[INFO] fetching crate nkgrep 0.2.0...
[INFO] testing nkgrep-0.2.0 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate nkgrep 0.2.0 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate nkgrep 0.2.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate nkgrep 0.2.0
[INFO] tweaked toml for crates.io crate nkgrep 0.2.0 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate nkgrep 0.2.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 nkgrep 0.2.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-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:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] de95d50fe874925eba9c85a4c522a4b116ba20d294189333827757d2db101753
[INFO] running `Command { std: "docker" "start" "de95d50fe874925eba9c85a4c522a4b116ba20d294189333827757d2db101753", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "de95d50fe874925eba9c85a4c522a4b116ba20d294189333827757d2db101753", 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" "de95d50fe874925eba9c85a4c522a4b116ba20d294189333827757d2db101753" "/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" "de95d50fe874925eba9c85a4c522a4b116ba20d294189333827757d2db101753", 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" "de95d50fe874925eba9c85a4c522a4b116ba20d294189333827757d2db101753" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling unicode-ident v1.0.24
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling rustversion v1.0.23
[INFO] [stderr]    Compiling crossbeam-utils v0.8.23
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.21
[INFO] [stderr]    Compiling regex-syntax v0.8.11
[INFO] [stderr]    Compiling crossbeam-deque v0.8.8
[INFO] [stderr]    Compiling libc v0.2.189
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling log v0.4.34
[INFO] [stderr]    Compiling simdutf8 v0.1.5
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling zmij v1.0.21
[INFO] [stderr]    Compiling core_detect v1.0.0
[INFO] [stderr]    Compiling rayon-core v1.13.0
[INFO] [stderr]    Compiling multiversion_no_op v1.0.0
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling aho-corasick v1.1.5
[INFO] [stderr]    Compiling encoding_rs v0.8.41
[INFO] [stderr]    Compiling grep-matcher v0.1.9
[INFO] [stderr]    Compiling same-file v1.0.6
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling syn v3.0.5
[INFO] [stderr]    Compiling walkdir v2.5.0
[INFO] [stderr]    Compiling itoa v1.0.18
[INFO] [stderr]    Compiling either v1.18.0
[INFO] [stderr]    Compiling memmap2 v0.9.11
[INFO] [stderr]    Compiling rayon v1.12.0
[INFO] [stderr]    Compiling regex-automata v0.4.18
[INFO] [stderr]    Compiling multiversion-macros v0.9.0
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling multiversion v0.9.0
[INFO] [stderr]    Compiling bstr v1.13.1
[INFO] [stderr]    Compiling globset v0.4.20
[INFO] [stderr]    Compiling grep-regex v0.1.14
[INFO] [stderr]    Compiling ignore v0.4.33
[INFO] [stderr]    Compiling encoding_rs_io v0.1.8
[INFO] [stderr]    Compiling grep-searcher v0.1.17
[INFO] [stderr]    Compiling nkgrep v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 1m 27s
[INFO] running `Command { std: "docker" "inspect" "de95d50fe874925eba9c85a4c522a4b116ba20d294189333827757d2db101753", 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" "de95d50fe874925eba9c85a4c522a4b116ba20d294189333827757d2db101753" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling nkgrep v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 48.29s
[INFO] running `Command { std: "docker" "inspect" "de95d50fe874925eba9c85a4c522a4b116ba20d294189333827757d2db101753", 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" "de95d50fe874925eba9c85a4c522a4b116ba20d294189333827757d2db101753" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.09s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/build/nkgrep/4e13f35c5251fa2c/out/nkg-4e13f35c5251fa2c)
[INFO] [stdout] 
[INFO] [stdout] running 53 tests
[INFO] [stdout] test color_tests::colored_row_delegates_framing ... ok
[INFO] [stdout] test color_tests::span_wrap_matches_rg_bytes ... ok
[INFO] [stdout] test color_tests::multi_span_all_wrapped ... ok
[INFO] [stdout] test color_tests::parse_when_values ... ok
[INFO] [stdout] test color_tests::serve_colored_mirrors_plain_shape ... ok
[INFO] [stdout] test color_tests::no_match_borrows_back_verbatim ... ok
[INFO] [stdout] test context_tests::groups_merge_overlap_and_adjacency ... ok
[INFO] [stdout] test color_tests::zero_width_spans_skipped ... ok
[INFO] [stdout] test context_tests::attach_remap_is_byte_identical ... ok
[INFO] [stdout] test context_tests::served_context_renders_rg_shape ... ok
[INFO] [stdout] test emit_tests::plan_reads_bytes_not_rows ... ok
[INFO] [stdout] test emit_tests::ctx_chunking_never_changes_a_byte ... ok
[INFO] [stdout] test color_tests::enabled_explicit_wins ... ok
[INFO] [stdout] test context_tests::groups_clamp_top_and_split ... ok
[INFO] [stdout] test emit_tests::top_truncation_sizes_from_the_rows_that_print ... ok
[INFO] [stdout] test escape_tests::fuzz_vs_serde_corpus_bytes ... ok
[INFO] [stdout] test gram_hash_tests::gram_hash_spreads_the_top_control_bits ... ok
[INFO] [stdout] test escape_tests::fuzz_vs_serde_full_matrix ... ok
[INFO] [stdout] test literal_tests::cached_verify_matches_plain ... ok
[INFO] [stdout] test git_skip_tests::git_explicit_operand_still_searches ... ok
[INFO] [stdout] test git_skip_tests::git_four_contexts_match_rg ... ok
[INFO] [stdout] test literal_tests::escaped_pipe_stays_one_branch ... ok
[INFO] [stdout] test literal_tests::class_pipe_does_not_split ... ok
[INFO] [stdout] test literal_tests::escaped_pipe_branch_still_indexes ... ok
[INFO] [stdout] test literal_tests::matchflags_combine_scopes_groups ... ok
[INFO] [stdout] test literal_tests::matchflags_grams_multi ... ok
[INFO] [stdout] test literal_tests::group_alternation_splits ... ok
[INFO] [stdout] test literal_tests::group_and_repeat_falls_back ... ok
[INFO] [stdout] test literal_tests::matchflags_pattern_lines ... ok
[INFO] [stdout] test literal_tests::no_literal_falls_back ... ok
[INFO] [stdout] test literal_tests::matchflags_matcher_word_fixed ... ok
[INFO] [stdout] test literal_tests::matchflags_invert_and_max_count ... ok
[INFO] [stdout] test literal_tests::plain_queries_unchanged ... ok
[INFO] [stdout] test literal_tests::pack_bijective_and_ordered ... ok
[INFO] [stdout] test prefilter::tests::intersect_all_smallest_first_exact ... ok
[INFO] [stdout] test literal_tests::top_zero_indexed_matches_scan_empty ... ok
[INFO] [stdout] test prefilter::tests::merge_matches_hashset_model ... ok
[INFO] [stdout] test serve_wire_tests::context_mode_collects_hits_and_carried_rows ... ok
[INFO] [stdout] test serve_wire_tests::ctx_marker_in_match_text_counts_as_a_hit ... ok
[INFO] [stdout] test serve_wire_tests::emit_batch_failure_latches_broken ... ok
[INFO] [stdout] test serve_wire_tests::scan_survives_adversarial_match_text ... ok
[INFO] [stdout] test serve_wire_tests::truncated_and_foreign_rows_degrade_not_panic ... ok
[INFO] [stdout] test serve_wire_tests::unescape_is_the_inverse_of_the_wire_escaper ... ok
[INFO] [stdout] test serve_wire_tests::json_mode_passes_the_wire_through_verbatim ... ok
[INFO] [stdout] test varint_tests::varint_rejects_run_past_end ... ok
[INFO] [stdout] test varint_tests::varint_rejects_wider_than_u32 ... ok
[INFO] [stdout] test varint_tests::varint_round_trips ... ok
[INFO] [stdout] test varint_tests::varint_would_not_silently_decode_to_an_in_range_id ... ok
[INFO] [stdout] test serve_wire_tests::emit_batches_break_only_between_rows ... ok
[INFO] [stdout] test emit_tests::few_huge_rows_take_the_parallel_path ... ok
[INFO] [stdout] test emit_tests::a_chunk_reserves_the_bytes_it_renders ... ok
[INFO] [stdout] test emit_tests::chunking_never_changes_a_byte ... ok
[INFO] [stdout] test emit_tests::chunking_never_changes_a_byte_on_huge_rows ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 53 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.66s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/oracle.rs (/opt/rustwide/target/release/build/nkgrep/f0e91dabe54594b4/out/oracle-f0e91dabe54594b4)
[INFO] [stdout] 
[INFO] [stdout] running 32 tests
[INFO] [stderr] nkg: indexed 12 files in 25 ms -> /tmp/nkg-filecount-887-dribble-0/t.idx.bin
[INFO] [stderr] nkg: indexed 100 files in 26 ms -> /tmp/nkg-oracle-887-corrupt-bin-4/t.idx.bin
[INFO] [stderr] nkg: indexed 100 files in 21 ms -> /tmp/nkg-oracle-887-corrupt-bounds-3/t.idx.bin
[INFO] [stderr] nkg: indexed 102 files in 33 ms -> /tmp/nkg-oracle-887-crlf-2/t.idx.json
[INFO] [stderr] nkg: indexed 100 files in 11 ms -> /tmp/nkg-oracle-887-aggs-1/t.idx.json
[INFO] [stderr] nkg: serving 12 files on 127.0.0.1:46151
[INFO] [stderr] nkg: indexed 40 files in 87 ms -> /tmp/nkg-filecount-887-done-1/t.idx.bin
[INFO] [stdout] test corrupt_binary_id_exits_2 ... ok
[INFO] [stdout] test crlf_line_keeps_carriage_return ... ok
[INFO] [stderr] nkg: indexed 101 files in 102 ms -> /tmp/nkg-oracle-887-corrupt-blen-0/t.idx.bin
[INFO] [stderr] nkg: indexed 6 files in 28 ms -> /tmp/nkg-score-887-byte-0/t.idx.bin
[INFO] [stderr] nkg: indexed 100 files in 54 ms -> /tmp/nkg-oracle-887-corrupt-idx-5/t.idx.json
[INFO] [stdout] test corrupt_header_bounds_exit_2_at_load ... ok
[INFO] [stderr] nkg: serving 40 files on 127.0.0.1:34411
[INFO] [stderr] nkg: indexed 100 files in 13 ms -> /tmp/nkg-oracle-887-daemon-sub-6/t.idx.json
[INFO] [stderr] nkg: serving 6 files on 127.0.0.1:41725
[INFO] [stderr] nkg: serving 100 files on 127.0.0.1:34925
[INFO] [stdout] test corrupt_binary_blen_exits_2 ... ok
[INFO] [stderr] nkg: indexed 101 files in 19 ms -> /tmp/nkg-oracle-887-corrupt-idx-5/dup.idx.json
[INFO] [stdout] test corrupt_index_exits_2 ... ok
[INFO] [stdout] test daemon_output_is_byte_identical_to_cold ... ok
[INFO] [stdout] test dashdash_ends_flag_scan ... ok
[INFO] [stderr] nkg: indexed 102 files in 11 ms -> /tmp/nkg-oracle-887-flags-10/t.idx.json
[INFO] [stderr] nkg: indexed 100 files in 145 ms -> /tmp/nkg-oracle-887-daemon-io-err-7/d.idx.json
[INFO] [stdout] test daemon_subtree_equals_scan ... ok
[INFO] [stderr] nkg: indexed 100 files in 57 ms -> /tmp/nkg-oracle-887-hostile-paths-11/t.idx.json
[INFO] [stderr] nkg: indexed 100 files in 60 ms -> /tmp/nkg-oracle-887-daemon-latch-scope-8/d.idx.json
[INFO] [stdout] test aggregates_equal_scan ... ok
[INFO] [stdout] test daemon_verify_io_error_exits_2 ... ok
[INFO] [stdout] test daemon_done_line_is_last_and_midstream_disconnect_is_survivable ... ok
[INFO] [stdout] test daemon_verify_latch_is_request_scoped ... ok
[INFO] [stderr] nkg: indexed 3 files in 26 ms -> /tmp/nkg-filecount-887-phaseprobe-2/t.idx.bin
[INFO] [stderr] nkg: indexed 100 files in 28 ms -> /tmp/nkg-oracle-887-invert-l-json-13/t.idx.json
[INFO] [stderr] nkg: indexed 100 files in 35 ms -> /tmp/nkg-oracle-887-subtree-14/t.idx.json
[INFO] [stdout] test phase_probe_never_reaches_the_wire ... ok
[INFO] [stderr] nkg: indexed 100 files in 98 ms -> /tmp/nkg-oracle-887-base-12/t.idx.json
[INFO] [stderr] nkg: indexed 101 files in 42 ms -> /tmp/nkg-oracle-887-loud-io-15/o.idx.json
[INFO] [stdout] test loud_io_errors ... FAILED
[INFO] [stderr] nkg: indexed 6 files in 5 ms -> /tmp/nkg-oracle-quant-887/q.idx.bin
[INFO] [stderr] nkg: indexed 6 files in 6 ms -> /tmp/nkg-score-887-location-1/t.idx.bin
[INFO] [stdout] test hostile_stored_paths_exit_2 ... ok
[INFO] [stderr] nkg: indexed 100 files in 9 ms -> /tmp/nkg-oracle-887-rebuild-self-17/nkg.idx.json
[INFO] [stdout] test piped_stdin_with_port_is_usage_error ... ok
[INFO] [stderr] nkg: serving 6 files on 127.0.0.1:40683
[INFO] [stderr] nkg: indexed 100 files in 18 ms -> /tmp/nkg-oracle-887-rebuild-self-17/nkg.idx.json
[INFO] [stderr] nkg: indexed 100 files in 29 ms -> /tmp/nkg-oracle-887-invert-l-bin-18/t.idx.bin
[INFO] [stdout] test rebuild_in_corpus_excludes_itself ... ok
[INFO] [stderr] nkg: indexed 6 files in 5 ms -> /tmp/nkg-score-887-location-1/x/y/z/t.idx.bin
[INFO] [stdout] test invert_files_lists_files_the_capped_searcher_never_reached ... ok
[INFO] [stderr] nkg: serving 6 files on 127.0.0.1:39763
[INFO] [stdout] test scores_do_not_depend_on_corpus_location ... ok
[INFO] [stderr] nkg: indexed 7 files in 4 ms -> /tmp/nkg-filecount-887-distinct-3/t.idx.bin
[INFO] [stderr] nkg: indexed 1 files in 2 ms -> /tmp/nkg-filecount-887-barekey-4/t.idx.bin
[INFO] [stderr] nkg: serving 1 files on 127.0.0.1:45109
[INFO] [stderr] nkg: indexed 1 files in 5 ms -> /tmp/nkg-filecount-887-inject-5/t.idx.bin
[INFO] [stderr] nkg: serving 7 files on 127.0.0.1:33221
[INFO] [stderr] nkg: serving 1 files on 127.0.0.1:40335
[INFO] [stderr] nkg: indexed 4 files in 11 ms -> /tmp/nkg-filecount-887-matrix-6/t.idx.bin
[INFO] [stdout] test serve_file_count_deduplicates_and_survives_escaped_paths ... ok
[INFO] [stderr] nkg: indexed 100 files in 13 ms -> /tmp/nkg-oracle-887-topk-20/t.idx.json
[INFO] [stdout] test serve_file_count_ignores_injected_path_prefix_in_match_text ... ok
[INFO] [stderr] nkg: serving 4 files on 127.0.0.1:34553
[INFO] [stdout] test serve_file_count_ignores_bare_path_key_in_match_text ... ok
[INFO] [stdout] test stale_binary_format_is_named ... ok
[INFO] [stdout] test quantifiers_and_literal_punctuation_stay_indexed_equal_scan ... ok
[INFO] [stderr] nkg: indexed 101 files in 86 ms -> /tmp/nkg-oracle-887-base-12/t.idx.bin
[INFO] [stderr] nkg: serving 4 files on 127.0.0.1:37157
[INFO] [stderr] nkg: indexed 102 files in 23 ms -> /tmp/nkg-oracle-887-topk-io-21/t.idx.json
[INFO] [stdout] test top_k_reports_errors_only_for_files_it_opens ... ok
[INFO] [stderr] nkg: serving 4 files on 127.0.0.1:41673
[INFO] [stdout] test top_k_equals_scan ... ok
[INFO] [stderr] nkg: serving 4 files on 127.0.0.1:37557
[INFO] [stdout] test indexed_subtree_equals_scan ... ok
[INFO] [stdout] test indexed_equals_scan ... ok
[INFO] [stderr] nkg: serving 4 files on 127.0.0.1:34605
[INFO] [stderr] nkg: indexed 4 files in 181 ms -> /tmp/nkg-filecount-887-longline-7/t.idx.bin
[INFO] [stderr] nkg: serving 4 files on 127.0.0.1:33539
[INFO] [stdout] test flag_matrix_equals_scan ... ok
[INFO] [stderr] nkg: serving 4 files on 127.0.0.1:42187
[INFO] [stdout] test very_long_lines_preserve_indexed_equals_scan ... ok
[INFO] [stderr] nkg: serving 4 files on 127.0.0.1:43995
[INFO] [stdout] test serve_file_count_matches_cold_across_the_flag_matrix ... ok
[INFO] [stderr] nkg: serving 4 files on 127.0.0.1:44415
[INFO] [stdout] test daemon_batched_response_reassembles_under_max_segmentation ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- loud_io_errors stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'loud_io_errors' (1288) panicked at tests/oracle.rs:488:5:
[INFO] [stdout] assertion `left == right` failed: denied-dir build must exit 2
[INFO] [stdout]   left: Some(0)
[INFO] [stdout]  right: Some(2)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x588f80237b07 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x588f8021c02a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x588f802370bb - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x588f80236b9f - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x588f802367ca - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x588f80236305 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    6:     0x588f8025d0d1 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    7:     0x588f8025d089 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    8:     0x588f8025d58c - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]    9:     0x588f8021bebb - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   10:     0x588f8021be38 - core[a373ad73a504971]::panicking::assert_failed_inner
[INFO] [stdout]   11:     0x588f801df661 - core[a373ad73a504971]::panicking::assert_failed::<core[a373ad73a504971]::option::Option<i32>, core[a373ad73a504971]::option::Option<i32>>
[INFO] [stdout]   12:     0x588f801f616c - <oracle[e6657350ba89bc1d]::loud_io_errors::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x588f8027adeb - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   14:     0x588f80276d91 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x588f8027657c - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x588f80295cdc - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   17:     0x588f8025f004 - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7b60ba89bdfa - <unknown>
[INFO] [stdout]   19:     0x7b60ba92f3d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     loud_io_errors
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 31 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.15s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test oracle`
[INFO] running `Command { std: "docker" "inspect" "de95d50fe874925eba9c85a4c522a4b116ba20d294189333827757d2db101753", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "de95d50fe874925eba9c85a4c522a4b116ba20d294189333827757d2db101753", kill_on_drop: false }`
[INFO] [stdout] de95d50fe874925eba9c85a4c522a4b116ba20d294189333827757d2db101753
