[INFO] cloning repository https://github.com/kent-tokyo/shogiesa
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/kent-tokyo/shogiesa" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkent-tokyo%2Fshogiesa", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkent-tokyo%2Fshogiesa'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 42bebf95c289dee2b9a9b8cc0bfc8b54fa0ef352
[INFO] testing kent-tokyo/shogiesa against 1.97.0-beta.6 for beta-1.98-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkent-tokyo%2Fshogiesa" "/workspace/builds/worker-4-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/kent-tokyo/shogiesa
[INFO] finished tweaking git repo https://github.com/kent-tokyo/shogiesa
[INFO] tweaked toml for git repo https://github.com/kent-tokyo/shogiesa written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/kent-tokyo/shogiesa on toolchain 1.97.0-beta.6
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.97.0-beta.6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/kent-tokyo/shogiesa 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.97.0-beta.6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded blake3 v1.8.5
[INFO] [stderr]   Downloaded csa v1.0.2
[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:3d5ced03c013a94a2f102a4510f48a6e9184255caf5fd8244f58017bde7f5210" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] a2aee8d26ebb95be08429d50d9b437dc6fbb0d94333235fbb25dc5ee37b223d0
[INFO] running `Command { std: "docker" "start" "a2aee8d26ebb95be08429d50d9b437dc6fbb0d94333235fbb25dc5ee37b223d0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a2aee8d26ebb95be08429d50d9b437dc6fbb0d94333235fbb25dc5ee37b223d0", 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" "a2aee8d26ebb95be08429d50d9b437dc6fbb0d94333235fbb25dc5ee37b223d0" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a2aee8d26ebb95be08429d50d9b437dc6fbb0d94333235fbb25dc5ee37b223d0", 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" "a2aee8d26ebb95be08429d50d9b437dc6fbb0d94333235fbb25dc5ee37b223d0" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling time-core v0.1.9
[INFO] [stderr]    Compiling num-conv v0.2.2
[INFO] [stderr]    Compiling syn v2.0.118
[INFO] [stderr]    Compiling blake3 v1.8.5
[INFO] [stderr]    Compiling minimal-lexical v0.2.1
[INFO] [stderr]    Compiling deranged v0.5.8
[INFO] [stderr]    Compiling cpufeatures v0.3.0
[INFO] [stderr]    Compiling arrayvec v0.7.8
[INFO] [stderr]    Compiling constant_time_eq v0.4.2
[INFO] [stderr]    Compiling regex-syntax v0.8.11
[INFO] [stderr]    Compiling log v0.4.33
[INFO] [stderr]    Compiling smallvec v1.15.2
[INFO] [stderr]    Compiling shogiesa-cli v0.7.0 (/opt/rustwide/workdir/crates/shogiesa-cli)
[INFO] [stderr]    Compiling nom v7.1.3
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling fake-usi-engine v0.7.0 (/opt/rustwide/workdir/crates/fake-usi-engine)
[INFO] [stderr]    Compiling time v0.3.51
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling csa v1.0.2
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling shogiesa-core v0.7.0 (/opt/rustwide/workdir/crates/shogiesa-core)
[INFO] [stderr]    Compiling stratifykit-core v0.7.0 (/opt/rustwide/workdir/crates/stratifykit-core)
[INFO] [stderr]    Compiling shogiesa-pack v0.7.0 (/opt/rustwide/workdir/crates/shogiesa-pack)
[INFO] [stderr]    Compiling shogiesa-usi v0.7.0 (/opt/rustwide/workdir/crates/shogiesa-usi)
[INFO] [stderr]    Compiling shogiesa-csa v0.7.0 (/opt/rustwide/workdir/crates/shogiesa-csa)
[INFO] [stderr]    Compiling shogiesa-kif v0.7.0 (/opt/rustwide/workdir/crates/shogiesa-kif)
[INFO] [stderr]    Compiling shogiesa-stratify v0.7.0 (/opt/rustwide/workdir/crates/shogiesa-stratify)
[INFO] [stderr]    Compiling shogiesa v0.7.0 (/opt/rustwide/workdir/crates/shogiesa)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 51.13s
[INFO] running `Command { std: "docker" "inspect" "a2aee8d26ebb95be08429d50d9b437dc6fbb0d94333235fbb25dc5ee37b223d0", 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" "a2aee8d26ebb95be08429d50d9b437dc6fbb0d94333235fbb25dc5ee37b223d0" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling regex v1.12.4
[INFO] [stderr]    Compiling zerocopy v0.8.52
[INFO] [stderr]    Compiling bstr v1.12.3
[INFO] [stderr]    Compiling zerocopy-derive v0.8.52
[INFO] [stderr]    Compiling either v1.16.0
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling alloca v0.4.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling shogiesa-core v0.7.0 (/opt/rustwide/workdir/crates/shogiesa-core)
[INFO] [stderr]    Compiling stratifykit-core v0.7.0 (/opt/rustwide/workdir/crates/stratifykit-core)
[INFO] [stderr]    Compiling float-cmp v0.10.0
[INFO] [stderr]    Compiling plotters v0.3.7
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling predicates v3.1.4
[INFO] [stderr]    Compiling assert_cmd v2.2.2
[INFO] [stderr]    Compiling shogiesa-csa v0.7.0 (/opt/rustwide/workdir/crates/shogiesa-csa)
[INFO] [stderr]    Compiling shogiesa-usi v0.7.0 (/opt/rustwide/workdir/crates/shogiesa-usi)
[INFO] [stderr]    Compiling shogiesa-kif v0.7.0 (/opt/rustwide/workdir/crates/shogiesa-kif)
[INFO] [stderr]    Compiling shogiesa-pack v0.7.0 (/opt/rustwide/workdir/crates/shogiesa-pack)
[INFO] [stderr]    Compiling shogiesa-stratify v0.7.0 (/opt/rustwide/workdir/crates/shogiesa-stratify)
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling criterion-plot v0.8.2
[INFO] [stderr]    Compiling rayon v1.12.0
[INFO] [stderr]    Compiling page_size v0.6.0
[INFO] [stderr]    Compiling shogiesa v0.7.0 (/opt/rustwide/workdir/crates/shogiesa)
[INFO] [stderr]    Compiling shogiesa-cli v0.7.0 (/opt/rustwide/workdir/crates/shogiesa-cli)
[INFO] [stderr]    Compiling fake-usi-engine v0.7.0 (/opt/rustwide/workdir/crates/fake-usi-engine)
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling criterion v0.8.2
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 05s
[INFO] running `Command { std: "docker" "inspect" "a2aee8d26ebb95be08429d50d9b437dc6fbb0d94333235fbb25dc5ee37b223d0", 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" "a2aee8d26ebb95be08429d50d9b437dc6fbb0d94333235fbb25dc5ee37b223d0" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.25s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/fake_usi_engine-7e1a71e389a9b054)
[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 unittests src/lib.rs (/opt/rustwide/target/debug/deps/shogiesa-0adec6e4e780f677)
[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 unittests src/main.rs (/opt/rustwide/target/debug/deps/shogiesa-1887a48be37e859b)
[INFO] [stdout] 
[INFO] [stdout] running 27 tests
[INFO] [stdout] test calibrate_helper_tests::dataset_diagnostics_skips_unlabeled_records ... ok
[INFO] [stdout] test calibrate_helper_tests::dataset_diagnostics_buckets_a_labeled_record ... ok
[INFO] [stdout] test calibrate_helper_tests::coverage_tally_records_kept_and_first_reason_only ... ok
[INFO] [stdout] test extract_zobrist_dedup_tests::two_distinct_unparseable_sfens_are_both_kept_and_counted_as_skipped ... ok
[INFO] [stdout] test extract_zobrist_dedup_tests::valid_duplicate_sfen_is_deduped_normally ... ok
[INFO] [stdout] test fingerprint_tests::assign_split_bucket_is_a_stable_golden_value ... ok
[INFO] [stdout] test fingerprint_tests::label_cache_key_is_a_64_char_hex_digest ... ok
[INFO] [stdout] test fingerprint_tests::engine_options_hash_is_a_stable_golden_value ... ok
[INFO] [stdout] test label_cache_correctness_tests::engine_fingerprint_falls_back_to_none_when_path_is_unreadable ... ok
[INFO] [stdout] test merge_observations_tests::engine_version_mismatch_is_not_a_collision ... ok
[INFO] [stdout] test label_cache_correctness_tests::engine_fingerprint_content_mode_differs_for_different_binaries ... ok
[INFO] [stdout] test merge_observations_tests::keep_both_on_collision_keeps_both ... ok
[INFO] [stdout] test label_cache_correctness_tests::label_cache_path_differs_when_engine_fingerprint_differs ... ok
[INFO] [stdout] test merge_observations_tests::no_collision_appends_both ... ok
[INFO] [stdout] test label_pipeline_tests::reorder_push_buffer_size_equals_arrivals_ahead_of_next_id ... ok
[INFO] [stdout] test merge_observations_tests::prefer_primary_on_collision_drops_secondary ... ok
[INFO] [stdout] test merge_observations_tests::multiple_simultaneous_collisions_all_resolved ... ok
[INFO] [stdout] test label_pipeline_tests::reorder_push_holds_back_out_of_order_arrivals ... ok
[INFO] [stdout] test merge_observations_tests::prefer_secondary_on_collision_replaces_primary ... ok
[INFO] [stdout] test label_pipeline_tests::reorder_push_restores_order_from_arbitrary_arrival_order ... ok
[INFO] [stdout] test merge_observations_tests::shallow_and_deep_same_engine_never_collide_under_any_policy ... ok
[INFO] [stdout] test label_cache_correctness_tests::engine_fingerprint_none_mode_produces_no_fingerprint ... ok
[INFO] [stdout] test tune_pareto_tests::pareto_frontier_excludes_a_strictly_dominated_point ... ok
[INFO] [stdout] test tune_pareto_tests::pick_balanced_range_normalizes_before_computing_distance ... ok
[INFO] [stdout] test resume_index_tests::index_maps_keys_to_the_offset_of_their_line_and_a_later_duplicate_wins ... ok
[INFO] [stdout] test tune_pareto_tests::pick_broad_and_strict_pick_opposite_ends ... ok
[INFO] [stdout] test label_cache_correctness_tests::atomic_cache_write_never_leaves_a_torn_file_under_concurrent_writers ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 27 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cli_test.rs (/opt/rustwide/target/debug/deps/cli_test-2dd08c0f9ca9ece4)
[INFO] [stdout] 
[INFO] [stdout] running 197 tests
[INFO] [stdout] test balance_bounded_heap_resolves_tie_contest_by_earliest_index ... ok
[INFO] [stdout] test audit_falls_back_to_achieved_depth_when_requested_depth_is_absent ... ok
[INFO] [stdout] test audit_bestmove_match_is_vacuous_when_student_resigns ... ok
[INFO] [stdout] test balance_bounded_heap_auto_target_matches_pre_refactor_golden_output ... ok
[INFO] [stdout] test balance_by_eval_bucket_normalizes_to_black_perspective ... ok
[INFO] [stdout] test audit_uses_a_short_mate_teacher_without_flagging_underreach ... ok
[INFO] [stdout] test audit_score_error_cp_normalizes_through_black_perspective ... ok
[INFO] [stdout] test balance_by_phase_defaults_to_min_bucket ... ok
[INFO] [stdout] test audit_groups_by_engine_not_across_engines ... ok
[INFO] [stdout] test balance_manifest_records_counts ... ok
[INFO] [stdout] test balance_target_override ... ok
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.14s
[INFO] [stdout] test cache_stats_reports_entry_count_and_engine_distribution ... ok
[INFO] [stdout] test cache_verify_and_stats_distinguish_v1_legacy_from_v2_entries ... ok
[INFO] [stdout] test cache_prune_legacy_only_removes_only_v1_entries ... ok
[INFO] [stdout] test cache_verify_detects_corruption_and_does_not_claim_schema_awareness ... ok
[INFO] [stdout] test calibrate_reports_dataset_wide_diagnostics_independent_of_sweep ... ok
[INFO] [stdout] test cache_prune_yes_older_than_days_removes_only_aged_entries ... ok
[INFO] [stdout] test calibrate_sweep_and_hold_flag_on_the_same_field_are_mutually_exclusive ... ok
[INFO] [stdout] test cache_prune_dry_run_deletes_nothing_by_default ... ok
[INFO] [stdout] test calibrate_sweep_policy_margin_produces_golden_csv_rows ... ok
[INFO] [stdout] test cache_write_path_produces_v2_envelope_not_bare_observation ... ok
[INFO] [stdout] test distribution_ply_histogram_flags_missing_bucket ... ok
[INFO] [stdout] test calibrate_sweep_score_swing_produces_golden_csv_rows ... ok
[INFO] [stdout] test distribution_flags_missing_eval_bucket_as_missing ... ok
[INFO] [stdout] test distribution_source_root_dominance_uses_root_id_not_path ... ok
[INFO] [stdout] test distribution_flags_under_and_over_represented_buckets ... ok
[INFO] [stdout] test extract_ply_filter_flag ... ok
[INFO] [stdout] test filter_allow_timeout_salvaged_mate_keeps_the_record ... ok
[INFO] [stdout] test filter_bestmove_agreement_excludes_resign_from_comparison ... ok
[INFO] [stdout] test filter_bestmove_agreement_passes ... ok
[INFO] [stdout] test calibrate_requires_at_least_one_sweep_flag ... ok
[INFO] [stdout] test filter_bestmove_disagreement_excluded ... ok
[INFO] [stdout] test extract_creates_jsonl ... ok
[INFO] [stdout] test filter_dry_run_reports_without_writing_output ... ok
[INFO] [stdout] test cache_prune_yes_corrupted_only_removes_only_corrupted_entries ... ok
[INFO] [stdout] test extract_dedup_zobrist_removes_duplicates ... ok
[INFO] [stdout] test cache_prune_requires_at_least_one_filter ... ok
[INFO] [stdout] test filter_eval_range ... ok
[INFO] [stdout] test filter_exclude_in_check ... ok
[INFO] [stdout] test filter_exclude_mate ... ok
[INFO] [stdout] test filter_exclude_timeout_salvaged ... ok
[INFO] [stdout] test distribution_shows_basic_sections ... ok
[INFO] [stdout] test filter_dry_run_with_manifest_writes_manifest_no_output_file ... ok
[INFO] [stdout] test filter_manifest_records_drop_reasons_and_config ... ok
[INFO] [stdout] test filter_explain_out_works_standalone_with_dry_run ... ok
[INFO] [stdout] test filter_exclude_capture ... ok
[INFO] [stdout] test filter_max_engine_score_swing_cp_excludes_large_cross_engine_swing ... ok
[INFO] [stdout] test filter_min_depth_reached_excludes_shallow_but_exempts_mate ... ok
[INFO] [stdout] test filter_end_to_end_with_label ... ok
[INFO] [stdout] test filter_no_observations_excluded ... ok
[INFO] [stdout] test filter_min_policy_margin_cp ... ok
[INFO] [stdout] test filter_require_engine_agreement_excludes_disagreement ... ok
[INFO] [stdout] test filter_require_engine_agreement_single_engine_passes ... ok
[INFO] [stdout] test filter_phase ... ok
[INFO] [stdout] test filter_explain_out_records_rejected_with_full_reasons ... ok
[INFO] [stdout] test filter_require_policy_margin_excludes_missing_margin ... ok
[INFO] [stdout] test filter_require_exact_score_excludes_non_exact ... ok
[INFO] [stdout] test filter_require_requested_depth_reached_rejects_salvaged_mate_underreach_by_default ... ok
[INFO] [stdout] test filter_requires_out_unless_dry_run ... ok
[INFO] [stdout] test from_match_extracts_positions_from_startpos_kifu ... ok
[INFO] [stdout] test from_match_handles_directory_of_kifu_files ... ok
[INFO] [stdout] test from_match_losing_side_engine1_keeps_only_engine2_win_games ... ok
[INFO] [stdout] test from_match_losing_side_engine2_keeps_only_engine1_win_games ... ok
[INFO] [stdout] test from_match_no_losing_side_keeps_all_games ... ok
[INFO] [stdout] test filter_score_swing_excluded ... ok
[INFO] [stdout] test from_match_position_sfen_nonstandard_start_continues_ply_from_move_count ... ok
[INFO] [stdout] test from_match_position_sfen_with_king_in_hand_is_skipped_not_crashed ... ok
[INFO] [stdout] test from_match_position_sfen_line_extracts_from_the_given_starting_position ... ok
[INFO] [stdout] test distribution_rejects_zero_ply_bucket_size ... ok
[INFO] [stdout] test from_match_output_feeds_into_label_and_filter ... ok
[INFO] [stdout] test label_adds_observations ... ok
[INFO] [stdout] test from_match_promotion_and_drop_tokens_apply_correctly ... ok
[INFO] [stdout] test filter_preset_conflicts_with_individual_flags ... ok
[INFO] [stdout] test filter_preset_loads_config_from_tune_output ... ok
[INFO] [stdout] test help_lists_manifest_and_dry_run_flags ... ok
[INFO] [stdout] test label_appends_to_existing_observations ... ok
[INFO] [stdout] test label_manifest_reports_resumed_count ... ok
[INFO] [stdout] test label_manifest_preserve_order_flag_is_reported_true_when_passed ... ok
[INFO] [stdout] test label_manifest_records_engine_and_depths ... ok
[INFO] [stdout] test label_manifest_cache_hit_rate_reflects_hits_and_misses ... ok
[INFO] [stdout] test label_manifest_reports_throughput_metrics ... ok
[INFO] [stdout] test filter_preset_unknown_label_errors ... ok
[INFO] [stdout] test from_match_no_txt_files_found_errors ... ok
[INFO] [stdout] test label_replace_existing_overwrites ... ok
[INFO] [stdout] test label_resume_from_missing_path_is_a_noop ... ok
[INFO] [stdout] test label_multipv_populates_margin ... ok
[INFO] [stdout] test label_resume_from_skips_already_covered_and_labels_the_rest ... ok
[INFO] [stdout] test label_resume_from_tolerates_an_unreadable_record_and_relabels_it ... ok
[INFO] [stdout] test label_skip_existing_no_duplicate_on_early_stop_divergence ... ok
[INFO] [stdout] test lineprior_export_creates_jsonl_from_csa ... ok
[INFO] [stdout] test lineprior_export_creates_jsonl_from_kif ... ok
[INFO] [stdout] test label_skip_and_replace_existing_conflict ... ok
[INFO] [stdout] test label_cache_dir_hits_on_second_run_and_output_matches ... ok
[INFO] [stdout] test lineprior_export_max_ply_does_not_drop_variation_moves ... ok
[INFO] [stdout] test lineprior_export_manifest_input_hash_stable_across_repeated_runs ... ok
[INFO] [stdout] test lineprior_export_manifest_shape_and_counts ... ok
[INFO] [stdout] test label_skip_existing_avoids_duplicate ... ok
[INFO] [stdout] test lineprior_export_sequence_id_stable_across_repeated_runs ... ok
[INFO] [stdout] test lineprior_export_rejects_invalid_state_format ... ok
[INFO] [stdout] test lineprior_export_max_ply_truncates_but_outcome_still_correct ... ok
[INFO] [stdout] test label_then_filter_keeps_record_when_one_engine_resigns ... ok
[INFO] [stdout] test make_gate_openings_count_zero_selects_nothing ... ok
[INFO] [stdout] test make_gate_openings_dedups_identical_starting_position ... ok
[INFO] [stdout] test make_gate_openings_count_larger_than_dataset_keeps_everything ... ok
[INFO] [stdout] test make_gate_openings_dedup_does_not_inflate_rank_for_other_records ... ok
[INFO] [stdout] test label_resume_from_same_as_out_is_rejected ... ok
[INFO] [stdout] test make_gate_openings_max_ply_filters_late_positions ... ok
[INFO] [stdout] test make_gate_openings_skips_invalid_sfen_without_crashing ... ok
[INFO] [stdout] test make_gate_openings_manifest_reports_root_diversity_stats ... ok
[INFO] [stdout] test make_gate_openings_min_ply_filters_early_positions ... ok
[INFO] [stdout] test make_gate_openings_writes_plain_sfen_lines_not_jsonl ... ok
[INFO] [stdout] test merge_observations_prefer_primary_drops_secondary_on_collision ... ok
[INFO] [stdout] test merge_observations_keep_both_keeps_all_colliding_observations ... ok
[INFO] [stdout] test merge_observations_prefer_secondary_replaces_primary_on_collision ... ok
[INFO] [stdout] test mine_losing_threshold ... ok
[INFO] [stdout] test mine_detects_blunder_and_window ... ok
[INFO] [stdout] test mine_no_blunder_empty_output ... ok
[INFO] [stdout] test pack_manifest_records_counts ... ok
[INFO] [stdout] test make_gate_openings_diversifies_across_roots ... ok
[INFO] [stdout] test report_bounded_streaming_matches_pre_refactor_golden_output ... ok
[INFO] [stdout] test report_eval_bucket_normalizes_to_black_perspective ... ok
[INFO] [stdout] test report_shows_special_bestmove_rate ... ok
[INFO] [stdout] test report_engine_disagreement_excludes_resign ... ok
[INFO] [stdout] test manifest_common_keys_present_across_commands ... ok
[INFO] [stdout] test report_shows_labeled_diagnostics ... ok
[INFO] [stdout] test report_hides_multipv_coverage_without_multipv ... ok
[INFO] [stdout] test report_shows_engine_disagreement ... ok
[INFO] [stdout] test sample_bounded_heap_matches_pre_refactor_golden_output ... ok
[INFO] [stdout] test report_shows_stats ... ok
[INFO] [stdout] test sample_bounded_heap_resolves_full_tie_contest_by_earliest_index ... ok
[INFO] [stdout] test sample_count_zero_selects_nothing ... ok
[INFO] [stdout] test sample_count_larger_than_dataset_keeps_everything ... ok
[INFO] [stdout] test sample_returns_exact_count ... ok
[INFO] [stdout] test sample_deterministic_with_seed ... ok
[INFO] [stdout] test schema_v1_minimal_round_trips ... ok
[INFO] [stdout] test schema_v2_policy_margin_round_trips ... ok
[INFO] [stdout] test sample_manifest_records_counts ... ok
[INFO] [stdout] test schema_v3_engine_stability_round_trips ... ok
[INFO] [stdout] test schema_v5_score_bound_round_trips ... ok
[INFO] [stdout] test schema_v4_candidates_round_trips ... ok
[INFO] [stdout] test select_coverage_prioritizes_thin_bucket ... ok
[INFO] [stdout] test select_coverage_bounded_heap_matches_pre_refactor_golden_output ... ok
[INFO] [stdout] test select_hard_breaks_ties_by_per_record_score_swing ... ok
[INFO] [stdout] test schema_v7_score_perspective_and_bestmove_kind_round_trips ... ok
[INFO] [stdout] test schema_v6_source_root_id_round_trips ... ok
[INFO] [stdout] test select_coverage_bounded_heap_resolves_full_tie_contest_by_earliest_index ... ok
[INFO] [stdout] test select_hard_grouped_streaming_matches_materialized_for_swing_tiebreak ... ok
[INFO] [stdout] test select_hard_grouped_streaming_ranks_across_group_boundaries ... ok
[INFO] [stdout] test same_input_file_produces_same_manifest_input_hash_across_commands ... ok
[INFO] [stdout] test select_uncertain_bounded_heap_resolves_full_tie_contest_by_earliest_index ... ok
[INFO] [stdout] test split_by_source_creates_one_file_per_game ... ok
[INFO] [stdout] test select_hard_grouped_streaming_matches_materialized_for_blunder_adjacency ... ok
[INFO] [stdout] test select_hard_prioritizes_blunder_adjacent_position ... ok
[INFO] [stdout] test select_uncertain_bounded_heap_matches_pre_refactor_golden_output ... ok
[INFO] [stdout] test split_by_source_survives_forced_eviction_with_more_sources_than_max_open_writers ... ok
[INFO] [stdout] test select_uncertain_ranks_worst_quality_first ... ok
[INFO] [stdout] test split_train_valid_test_no_leakage ... ok
[INFO] [stdout] test split_train_valid_test_root_id_overrides_unrelated_paths ... ok
[INFO] [stdout] test split_by_source_truncates_stale_file_on_first_touch_this_run ... ok
[INFO] [stdout] test stability_excludes_resign_from_bestmove_agreement ... ok
[INFO] [stdout] test stability_populates_swing_and_agreement ... ok
[INFO] [stdout] test split_train_valid_test_requires_all_three_paths ... ok
[INFO] [stdout] test select_uncertain_count_larger_than_dataset_keeps_everything ... ok
[INFO] [stdout] test stability_skips_unlabeled ... ok
[INFO] [stdout] test split_train_valid_test_keeps_variation_with_mainline ... ok
[INFO] [stdout] test stratify_drops_bucket_not_in_quota_distinctly_from_over_quota ... ok
[INFO] [stdout] test stratify_group_aware_quota_fill_diversifies_across_roots ... ok
[INFO] [stdout] test stability_detects_disagreement ... ok
[INFO] [stdout] test stratify_manifest_reports_root_diversity_stats ... ok
[INFO] [stdout] test split_train_valid_test_deterministic_with_seed ... ok
[INFO] [stdout] test stratify_quota_rejects_by_flag ... ok
[INFO] [stdout] test stratify_quota_keeps_up_to_each_bucket_quota ... ok
[INFO] [stdout] test stratify_preserves_original_file_order ... ok
[INFO] [stdout] test stratify_write_template_and_quota_conflict ... ok
[INFO] [stdout] test label_jobs_2_is_unordered_by_default_and_preserve_order_matches_input_order ... ok
[INFO] [stdout] test stratify_single_root_bucket_keeps_first_n_in_file_order ... ok
[INFO] [stdout] test stratify_write_template_writes_observed_bucket_counts ... ok
[INFO] [stdout] test stratify_requires_exactly_one_of_write_template_or_quota ... ok
[INFO] [stdout] test tune_csv_grid_has_expected_coverage_and_mismatch_per_threshold ... ok
[INFO] [stdout] test tune_preset_out_collapses_like_report_does ... ok
[INFO] [stdout] test tune_preset_out_empty_when_no_audit_pairs ... ok
[INFO] [stdout] test tune_report_collapses_candidates_when_frontier_has_one_point ... ok
[INFO] [stdout] test stratify_write_template_then_quota_unedited_keeps_everything ... ok
[INFO] [stdout] test tune_report_produces_three_distinct_pareto_candidates ... ok
[INFO] [stdout] test validate_broken_json_shows_warn_but_exits_0 ... ok
[INFO] [stdout] test validate_clean_data_exits_0 ... ok
[INFO] [stdout] test tune_grid_is_a_full_cartesian_product_of_both_swept_axes ... ok
[INFO] [stdout] test validate_strict_broken_json_exits_1 ... ok
[INFO] [stdout] test validate_strict_tag_mismatch_exits_1 ... ok
[INFO] [stdout] test version_flag_reports_crate_version ... ok
[INFO] [stdout] test tune_no_report_flag_writes_only_csv ... ok
[INFO] [stdout] test validate_strict_clean_data_exits_0 ... ok
[INFO] [stdout] test stratify_write_template_requires_by ... ok
[INFO] [stdout] test tune_preset_out_writes_three_candidates_with_full_configs ... ok
[INFO] [stdout] test tune_requires_at_least_one_sweep_flag ... ok
[INFO] [stdout] test stratify_quota_loader_error_paths ... ok
[INFO] [stdout] test label_manifest_reports_timeout_salvaged_count ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 197 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.05s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stderr]      Running tests/lineprior_dogfood_script_test.rs (/opt/rustwide/target/debug/deps/lineprior_dogfood_script_test-24ee9d6fd5af4626)
[INFO] [stderr] error: jq is required (used to read manifest/report JSON fields into report.md)
[INFO] [stderr] error: jq is required (used to read manifest/report JSON fields into report.md)
[INFO] [stderr] error: jq is required (used to read manifest/report JSON fields into report.md)
[INFO] [stdout] test lineprior_dogfood_script_strict_report_fields_fails_on_missing_metrics ... FAILED
[INFO] [stdout] test lineprior_dogfood_script_produces_report ... FAILED
[INFO] [stdout] test lineprior_dogfood_script_strict_report_fields_passes_with_complete_metrics ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- lineprior_dogfood_script_strict_report_fields_fails_on_missing_metrics stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'lineprior_dogfood_script_strict_report_fields_fails_on_missing_metrics' (2937) panicked at crates/shogiesa-cli/tests/lineprior_dogfood_script_test.rs:110:76:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5f20c9047bc8 - std[10d71ac2c42b111e]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5f20c9047bc8 - std[10d71ac2c42b111e]::backtrace_rs::backtrace::trace_unsynchronized::<std[10d71ac2c42b111e]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5f20c9047bc8 - std[10d71ac2c42b111e]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5f20c9047bc8 - <<std[10d71ac2c42b111e]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[8fb95b77d25ab6c8]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5f20c905c2ca - <core[8fb95b77d25ab6c8]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5f20c905c2ca - core[8fb95b77d25ab6c8]::fmt::write
[INFO] [stdout]    6:     0x5f20c904c532 - std[10d71ac2c42b111e]::io::default_write_fmt::<alloc[a7ee25936407c584]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x5f20c904c532 - <alloc[a7ee25936407c584]::vec::Vec<u8> as std[10d71ac2c42b111e]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5f20c90250f6 - <std[10d71ac2c42b111e]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5f20c90250f6 - std[10d71ac2c42b111e]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5f20c903f709 - std[10d71ac2c42b111e]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5f20c8fb763c - <alloc[a7ee25936407c584]::boxed::Box<dyn for<'a, 'b> core[8fb95b77d25ab6c8]::ops::function::Fn<(&'a std[10d71ac2c42b111e]::panic::PanicHookInfo<'b>,), Output = ()> + core[8fb95b77d25ab6c8]::marker::Sync + core[8fb95b77d25ab6c8]::marker::Send> as core[8fb95b77d25ab6c8]::ops::function::Fn<(&std[10d71ac2c42b111e]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/alloc/src/boxed.rs:2289:9
[INFO] [stdout]   12:     0x5f20c8fb763c - test[213991da798b1c03]::test_main_inner::<test[213991da798b1c03]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5f20c903f8c2 - <alloc[a7ee25936407c584]::boxed::Box<dyn for<'a, 'b> core[8fb95b77d25ab6c8]::ops::function::Fn<(&'a std[10d71ac2c42b111e]::panic::PanicHookInfo<'b>,), Output = ()> + core[8fb95b77d25ab6c8]::marker::Sync + core[8fb95b77d25ab6c8]::marker::Send> as core[8fb95b77d25ab6c8]::ops::function::Fn<(&std[10d71ac2c42b111e]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/alloc/src/boxed.rs:2289:9
[INFO] [stdout]   14:     0x5f20c903f8c2 - std[10d71ac2c42b111e]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5f20c90251b2 - std[10d71ac2c42b111e]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5f20c901a189 - std[10d71ac2c42b111e]::sys::backtrace::__rust_end_short_backtrace::<std[10d71ac2c42b111e]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5f20c9025ecd - __rustc[3be9ba60078d4c86]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5f20c905ca1c - core[8fb95b77d25ab6c8]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5f20c905c792 - core[8fb95b77d25ab6c8]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x5f20c8f91556 - <core[8fb95b77d25ab6c8]::result::Result<alloc[a7ee25936407c584]::string::String, std[10d71ac2c42b111e]::io::error::Error>>::unwrap
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/result.rs:1233:23
[INFO] [stdout]   21:     0x5f20c8f91556 - lineprior_dogfood_script_test[6ce3d251a1545050]::lineprior_dogfood_script_strict_report_fields_fails_on_missing_metrics
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/shogiesa-cli/tests/lineprior_dogfood_script_test.rs:110:76
[INFO] [stdout]   22:     0x5f20c8f8efb7 - lineprior_dogfood_script_test[6ce3d251a1545050]::lineprior_dogfood_script_strict_report_fields_fails_on_missing_metrics::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/shogiesa-cli/tests/lineprior_dogfood_script_test.rs:97:76
[INFO] [stdout]   23:     0x5f20c8f93d66 - <lineprior_dogfood_script_test[6ce3d251a1545050]::lineprior_dogfood_script_strict_report_fields_fails_on_missing_metrics::{closure#0} as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5f20c8faa71b - <fn() -> core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5f20c8faa71b - test[213991da798b1c03]::__rust_begin_short_backtrace::<core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>, fn() -> core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x5f20c8fb7f95 - test[213991da798b1c03]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x5f20c8fb7f95 - <core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x5f20c8fb7f95 - std[10d71ac2c42b111e]::panicking::catch_unwind::do_call::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}>, core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x5f20c8fb7f95 - std[10d71ac2c42b111e]::panicking::catch_unwind::<core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>, core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x5f20c8fb7f95 - std[10d71ac2c42b111e]::panic::catch_unwind::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}>, core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x5f20c8fb7f95 - test[213991da798b1c03]::run_test_in_process
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x5f20c8fb7f95 - test[213991da798b1c03]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x5f20c8fb18a4 - test[213991da798b1c03]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x5f20c8fb18a4 - std[10d71ac2c42b111e]::sys::backtrace::__rust_begin_short_backtrace::<test[213991da798b1c03]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x5f20c8fbb012 - std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked::<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x5f20c8fbb012 - <core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x5f20c8fbb012 - std[10d71ac2c42b111e]::panicking::catch_unwind::do_call::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x5f20c8fbb012 - std[10d71ac2c42b111e]::panicking::catch_unwind::<(), core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x5f20c8fbb012 - std[10d71ac2c42b111e]::panic::catch_unwind::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x5f20c8fbb012 - std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked::<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x5f20c8fbb012 - <std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1} as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x5f20c90471ef - <alloc[a7ee25936407c584]::boxed::Box<dyn core[8fb95b77d25ab6c8]::ops::function::FnOnce<(), Output = ()> + core[8fb95b77d25ab6c8]::marker::Send> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/alloc/src/boxed.rs:2275:9
[INFO] [stdout]   43:     0x5f20c90471ef - <std[10d71ac2c42b111e]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x72e6df6deaa4 - <unknown>
[INFO] [stdout]   45:     0x72e6df76ba64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- lineprior_dogfood_script_produces_report stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'lineprior_dogfood_script_produces_report' (2936) panicked at crates/shogiesa-cli/tests/lineprior_dogfood_script_test.rs:46:5:
[INFO] [stdout] lineprior_dogfood.sh exited non-zero
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5f20c9047bc8 - std[10d71ac2c42b111e]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5f20c9047bc8 - std[10d71ac2c42b111e]::backtrace_rs::backtrace::trace_unsynchronized::<std[10d71ac2c42b111e]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5f20c9047bc8 - std[10d71ac2c42b111e]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5f20c9047bc8 - <<std[10d71ac2c42b111e]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[8fb95b77d25ab6c8]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5f20c905c2ca - <core[8fb95b77d25ab6c8]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5f20c905c2ca - core[8fb95b77d25ab6c8]::fmt::write
[INFO] [stdout]    6:     0x5f20c904c532 - std[10d71ac2c42b111e]::io::default_write_fmt::<alloc[a7ee25936407c584]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x5f20c904c532 - <alloc[a7ee25936407c584]::vec::Vec<u8> as std[10d71ac2c42b111e]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5f20c90250f6 - <std[10d71ac2c42b111e]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5f20c90250f6 - std[10d71ac2c42b111e]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5f20c903f709 - std[10d71ac2c42b111e]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5f20c8fb763c - <alloc[a7ee25936407c584]::boxed::Box<dyn for<'a, 'b> core[8fb95b77d25ab6c8]::ops::function::Fn<(&'a std[10d71ac2c42b111e]::panic::PanicHookInfo<'b>,), Output = ()> + core[8fb95b77d25ab6c8]::marker::Sync + core[8fb95b77d25ab6c8]::marker::Send> as core[8fb95b77d25ab6c8]::ops::function::Fn<(&std[10d71ac2c42b111e]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/alloc/src/boxed.rs:2289:9
[INFO] [stdout]   12:     0x5f20c8fb763c - test[213991da798b1c03]::test_main_inner::<test[213991da798b1c03]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5f20c903f8c2 - <alloc[a7ee25936407c584]::boxed::Box<dyn for<'a, 'b> core[8fb95b77d25ab6c8]::ops::function::Fn<(&'a std[10d71ac2c42b111e]::panic::PanicHookInfo<'b>,), Output = ()> + core[8fb95b77d25ab6c8]::marker::Sync + core[8fb95b77d25ab6c8]::marker::Send> as core[8fb95b77d25ab6c8]::ops::function::Fn<(&std[10d71ac2c42b111e]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/alloc/src/boxed.rs:2289:9
[INFO] [stdout]   14:     0x5f20c903f8c2 - std[10d71ac2c42b111e]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5f20c90251e4 - std[10d71ac2c42b111e]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:691:13
[INFO] [stdout]   16:     0x5f20c901a189 - std[10d71ac2c42b111e]::sys::backtrace::__rust_end_short_backtrace::<std[10d71ac2c42b111e]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5f20c9025ecd - __rustc[3be9ba60078d4c86]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5f20c905ca1c - core[8fb95b77d25ab6c8]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5f20c8f90af2 - lineprior_dogfood_script_test[6ce3d251a1545050]::lineprior_dogfood_script_produces_report
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/shogiesa-cli/tests/lineprior_dogfood_script_test.rs:46:5
[INFO] [stdout]   20:     0x5f20c8f8ef87 - lineprior_dogfood_script_test[6ce3d251a1545050]::lineprior_dogfood_script_produces_report::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/shogiesa-cli/tests/lineprior_dogfood_script_test.rs:28:46
[INFO] [stdout]   21:     0x5f20c8f93d26 - <lineprior_dogfood_script_test[6ce3d251a1545050]::lineprior_dogfood_script_produces_report::{closure#0} as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x5f20c8faa71b - <fn() -> core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x5f20c8faa71b - test[213991da798b1c03]::__rust_begin_short_backtrace::<core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>, fn() -> core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:724:18
[INFO] [stdout]   24:     0x5f20c8fb7f95 - test[213991da798b1c03]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:747:74
[INFO] [stdout]   25:     0x5f20c8fb7f95 - <core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   26:     0x5f20c8fb7f95 - std[10d71ac2c42b111e]::panicking::catch_unwind::do_call::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}>, core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   27:     0x5f20c8fb7f95 - std[10d71ac2c42b111e]::panicking::catch_unwind::<core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>, core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   28:     0x5f20c8fb7f95 - std[10d71ac2c42b111e]::panic::catch_unwind::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}>, core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panic.rs:359:14
[INFO] [stdout]   29:     0x5f20c8fb7f95 - test[213991da798b1c03]::run_test_in_process
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:747:27
[INFO] [stdout]   30:     0x5f20c8fb7f95 - test[213991da798b1c03]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:668:43
[INFO] [stdout]   31:     0x5f20c8fb18a4 - test[213991da798b1c03]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:698:41
[INFO] [stdout]   32:     0x5f20c8fb18a4 - std[10d71ac2c42b111e]::sys::backtrace::__rust_begin_short_backtrace::<test[213991da798b1c03]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   33:     0x5f20c8fbb012 - std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked::<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   34:     0x5f20c8fbb012 - <core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   35:     0x5f20c8fbb012 - std[10d71ac2c42b111e]::panicking::catch_unwind::do_call::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   36:     0x5f20c8fbb012 - std[10d71ac2c42b111e]::panicking::catch_unwind::<(), core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   37:     0x5f20c8fbb012 - std[10d71ac2c42b111e]::panic::catch_unwind::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panic.rs:359:14
[INFO] [stdout]   38:     0x5f20c8fbb012 - std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked::<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   39:     0x5f20c8fbb012 - <std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1} as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x5f20c90471ef - <alloc[a7ee25936407c584]::boxed::Box<dyn core[8fb95b77d25ab6c8]::ops::function::FnOnce<(), Output = ()> + core[8fb95b77d25ab6c8]::marker::Send> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/alloc/src/boxed.rs:2275:9
[INFO] [stdout]   41:     0x5f20c90471ef - <std[10d71ac2c42b111e]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   42:     0x72e6df6deaa4 - <unknown>
[INFO] [stdout]   43:     0x72e6df76ba64 - clone
[INFO] [stdout]   44:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- lineprior_dogfood_script_strict_report_fields_passes_with_complete_metrics stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'lineprior_dogfood_script_strict_report_fields_passes_with_complete_metrics' (2938) panicked at crates/shogiesa-cli/tests/lineprior_dogfood_script_test.rs:92:5:
[INFO] [stdout] assertion failed: status.success()
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5f20c9047bc8 - std[10d71ac2c42b111e]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5f20c9047bc8 - std[10d71ac2c42b111e]::backtrace_rs::backtrace::trace_unsynchronized::<std[10d71ac2c42b111e]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5f20c9047bc8 - std[10d71ac2c42b111e]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5f20c9047bc8 - <<std[10d71ac2c42b111e]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[8fb95b77d25ab6c8]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5f20c905c2ca - <core[8fb95b77d25ab6c8]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5f20c905c2ca - core[8fb95b77d25ab6c8]::fmt::write
[INFO] [stdout]    6:     0x5f20c904c532 - std[10d71ac2c42b111e]::io::default_write_fmt::<alloc[a7ee25936407c584]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x5f20c904c532 - <alloc[a7ee25936407c584]::vec::Vec<u8> as std[10d71ac2c42b111e]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5f20c90250f6 - <std[10d71ac2c42b111e]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5f20c90250f6 - std[10d71ac2c42b111e]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5f20c903f709 - std[10d71ac2c42b111e]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5f20c8fb763c - <alloc[a7ee25936407c584]::boxed::Box<dyn for<'a, 'b> core[8fb95b77d25ab6c8]::ops::function::Fn<(&'a std[10d71ac2c42b111e]::panic::PanicHookInfo<'b>,), Output = ()> + core[8fb95b77d25ab6c8]::marker::Sync + core[8fb95b77d25ab6c8]::marker::Send> as core[8fb95b77d25ab6c8]::ops::function::Fn<(&std[10d71ac2c42b111e]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/alloc/src/boxed.rs:2289:9
[INFO] [stdout]   12:     0x5f20c8fb763c - test[213991da798b1c03]::test_main_inner::<test[213991da798b1c03]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5f20c903f8c2 - <alloc[a7ee25936407c584]::boxed::Box<dyn for<'a, 'b> core[8fb95b77d25ab6c8]::ops::function::Fn<(&'a std[10d71ac2c42b111e]::panic::PanicHookInfo<'b>,), Output = ()> + core[8fb95b77d25ab6c8]::marker::Sync + core[8fb95b77d25ab6c8]::marker::Send> as core[8fb95b77d25ab6c8]::ops::function::Fn<(&std[10d71ac2c42b111e]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/alloc/src/boxed.rs:2289:9
[INFO] [stdout]   14:     0x5f20c903f8c2 - std[10d71ac2c42b111e]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5f20c90251e4 - std[10d71ac2c42b111e]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:691:13
[INFO] [stdout]   16:     0x5f20c901a189 - std[10d71ac2c42b111e]::sys::backtrace::__rust_end_short_backtrace::<std[10d71ac2c42b111e]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5f20c9025ecd - __rustc[3be9ba60078d4c86]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5f20c905ca1c - core[8fb95b77d25ab6c8]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5f20c905c9e2 - core[8fb95b77d25ab6c8]::panicking::panic
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/panicking.rs:150:5
[INFO] [stdout]   20:     0x5f20c8f9189e - lineprior_dogfood_script_test[6ce3d251a1545050]::lineprior_dogfood_script_strict_report_fields_passes_with_complete_metrics
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/shogiesa-cli/tests/lineprior_dogfood_script_test.rs:92:5
[INFO] [stdout]   21:     0x5f20c8f8efe7 - lineprior_dogfood_script_test[6ce3d251a1545050]::lineprior_dogfood_script_strict_report_fields_passes_with_complete_metrics::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/shogiesa-cli/tests/lineprior_dogfood_script_test.rs:85:80
[INFO] [stdout]   22:     0x5f20c8f93da6 - <lineprior_dogfood_script_test[6ce3d251a1545050]::lineprior_dogfood_script_strict_report_fields_passes_with_complete_metrics::{closure#0} as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x5f20c8faa71b - <fn() -> core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5f20c8faa71b - test[213991da798b1c03]::__rust_begin_short_backtrace::<core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>, fn() -> core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:724:18
[INFO] [stdout]   25:     0x5f20c8fb7f95 - test[213991da798b1c03]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:747:74
[INFO] [stdout]   26:     0x5f20c8fb7f95 - <core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   27:     0x5f20c8fb7f95 - std[10d71ac2c42b111e]::panicking::catch_unwind::do_call::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}>, core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   28:     0x5f20c8fb7f95 - std[10d71ac2c42b111e]::panicking::catch_unwind::<core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>, core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   29:     0x5f20c8fb7f95 - std[10d71ac2c42b111e]::panic::catch_unwind::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}>, core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panic.rs:359:14
[INFO] [stdout]   30:     0x5f20c8fb7f95 - test[213991da798b1c03]::run_test_in_process
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:747:27
[INFO] [stdout]   31:     0x5f20c8fb7f95 - test[213991da798b1c03]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:668:43
[INFO] [stdout]   32:     0x5f20c8fb18a4 - test[213991da798b1c03]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:698:41
[INFO] [stdout]   33:     0x5f20c8fb18a4 - std[10d71ac2c42b111e]::sys::backtrace::__rust_begin_short_backtrace::<test[213991da798b1c03]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   34:     0x5f20c8fbb012 - std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked::<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   35:     0x5f20c8fbb012 - <core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   36:     0x5f20c8fbb012 - std[10d71ac2c42b111e]::panicking::catch_unwind::do_call::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   37:     0x5f20c8fbb012 - std[10d71ac2c42b111e]::panicking::catch_unwind::<(), core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   38:     0x5f20c8fbb012 - std[10d71ac2c42b111e]::panic::catch_unwind::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panic.rs:359:14
[INFO] [stdout]   39:     0x5f20c8fbb012 - std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked::<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   40:     0x5f20c8fbb012 - <std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1} as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x5f20c90471ef - <alloc[a7ee25936407c584]::boxed::Box<dyn core[8fb95b77d25ab6c8]::ops::function::FnOnce<(), Output = ()> + core[8fb95b77d25ab6c8]::marker::Send> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/alloc/src/boxed.rs:2275:9
[INFO] [stdout]   42:     0x5f20c90471ef - <std[10d71ac2c42b111e]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   43:     0x72e6df6deaa4 - <unknown>
[INFO] [stdout]   44:     0x72e6df76ba64 - clone
[INFO] [stdout]   45:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     lineprior_dogfood_script_produces_report
[INFO] [stdout]     lineprior_dogfood_script_strict_report_fields_fails_on_missing_metrics
[INFO] [stdout]     lineprior_dogfood_script_strict_report_fields_passes_with_complete_metrics
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `-p shogiesa-cli --test lineprior_dogfood_script_test`
[INFO] running `Command { std: "docker" "inspect" "a2aee8d26ebb95be08429d50d9b437dc6fbb0d94333235fbb25dc5ee37b223d0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a2aee8d26ebb95be08429d50d9b437dc6fbb0d94333235fbb25dc5ee37b223d0", kill_on_drop: false }`
[INFO] [stdout] a2aee8d26ebb95be08429d50d9b437dc6fbb0d94333235fbb25dc5ee37b223d0
