[INFO] cloning repository https://github.com/sjlynch/RETL
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/sjlynch/RETL" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsjlynch%2FRETL", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsjlynch%2FRETL'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 6d79cdf9c8c82b19cbd7639654f33d07831efbbe
[INFO] testing sjlynch/RETL against master#ec6f9a5b4413f74386267ef8efc93712c2ce6db6 for pr-155739
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsjlynch%2FRETL" "/workspace/builds/worker-3-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/sjlynch/RETL
[INFO] removed 0 missing tests
[INFO] finished tweaking git repo https://github.com/sjlynch/RETL
[INFO] tweaked toml for git repo https://github.com/sjlynch/RETL written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/sjlynch/RETL on toolchain ec6f9a5b4413f74386267ef8efc93712c2ce6db6
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/sjlynch/RETL 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" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded serial_test_derive v3.4.0
[INFO] [stderr]   Downloaded sdd v3.0.10
[INFO] [stderr]   Downloaded serial_test v3.4.0
[INFO] [stderr]   Downloaded wasm-bindgen-macro v0.2.121
[INFO] [stderr]   Downloaded scc v2.4.0
[INFO] [stderr]   Downloaded wasm-bindgen-shared v0.2.121
[INFO] [stderr]   Downloaded wasm-bindgen-macro-support v0.2.121
[INFO] [stderr]   Downloaded wasm-bindgen v0.2.121
[INFO] [stderr]   Downloaded cc v1.2.62
[INFO] [stderr]   Downloaded assert_cmd v2.2.1
[INFO] [stderr]   Downloaded js-sys v0.3.98
[INFO] [stderr]   Downloaded web-sys v0.3.98
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 45db7429fbcdb63e5871aa86c0e53ae53e0eb50f07ec7c0949a804f20057aed2
[INFO] running `Command { std: "docker" "start" "-a" "45db7429fbcdb63e5871aa86c0e53ae53e0eb50f07ec7c0949a804f20057aed2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "45db7429fbcdb63e5871aa86c0e53ae53e0eb50f07ec7c0949a804f20057aed2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "45db7429fbcdb63e5871aa86c0e53ae53e0eb50f07ec7c0949a804f20057aed2", kill_on_drop: false }`
[INFO] [stdout] 45db7429fbcdb63e5871aa86c0e53ae53e0eb50f07ec7c0949a804f20057aed2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b8f82a02d787ddbf3d25c3b439fb695ae0095716318cb2f3a25493078e41e84e
[INFO] running `Command { std: "docker" "start" "-a" "b8f82a02d787ddbf3d25c3b439fb695ae0095716318cb2f3a25493078e41e84e", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]    Compiling pkg-config v0.3.33
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling zstd-safe v7.2.4
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling unicode-width v0.2.2
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling jobserver v0.1.34
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling cc v1.2.62
[INFO] [stderr]    Compiling console v0.15.11
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling sysinfo v0.33.1
[INFO] [stderr]    Compiling indicatif v0.17.11
[INFO] [stderr]    Compiling zstd-sys v2.0.16+zstd.1.5.7
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling zstd v0.13.3
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling retl v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 08s
[INFO] running `Command { std: "docker" "inspect" "b8f82a02d787ddbf3d25c3b439fb695ae0095716318cb2f3a25493078e41e84e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b8f82a02d787ddbf3d25c3b439fb695ae0095716318cb2f3a25493078e41e84e", kill_on_drop: false }`
[INFO] [stdout] b8f82a02d787ddbf3d25c3b439fb695ae0095716318cb2f3a25493078e41e84e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9b380046c9398bb5c7297d2e734693f27fccbd35d62b8150e51ab6f706f2ef58
[INFO] running `Command { std: "docker" "start" "-a" "9b380046c9398bb5c7297d2e734693f27fccbd35d62b8150e51ab6f706f2ef58", kill_on_drop: false }`
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling zerocopy-derive v0.8.48
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling rayon v1.12.0
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling predicates-core v1.0.10
[INFO] [stderr]    Compiling fastrand v2.4.1
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling float-cmp v0.10.0
[INFO] [stderr]    Compiling itertools v0.10.5
[INFO] [stderr]    Compiling termtree v0.5.1
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling difflib v0.4.0
[INFO] [stderr]    Compiling sdd v3.0.10
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling assert_cmd v2.2.1
[INFO] [stderr]    Compiling normalize-line-endings v0.3.0
[INFO] [stderr]    Compiling predicates v3.1.4
[INFO] [stderr]    Compiling scc v2.4.0
[INFO] [stderr]    Compiling zerocopy v0.8.48
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling predicates-tree v1.0.13
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling criterion-plot v0.5.0
[INFO] [stderr]    Compiling plotters v0.3.7
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling sysinfo v0.33.1
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling rand v0.9.4
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling serial_test_derive v3.4.0
[INFO] [stderr]    Compiling bstr v1.12.1
[INFO] [stderr]    Compiling is-terminal v0.4.17
[INFO] [stderr]    Compiling unarray v0.1.4
[INFO] [stderr]    Compiling serial_test v3.4.0
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling retl v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling proptest v1.11.0
[INFO] [stderr]    Compiling criterion v0.5.1
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 28s
[INFO] running `Command { std: "docker" "inspect" "9b380046c9398bb5c7297d2e734693f27fccbd35d62b8150e51ab6f706f2ef58", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9b380046c9398bb5c7297d2e734693f27fccbd35d62b8150e51ab6f706f2ef58", kill_on_drop: false }`
[INFO] [stdout] 9b380046c9398bb5c7297d2e734693f27fccbd35d62b8150e51ab6f706f2ef58
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] d0d6c071b1ca44ced630773c3e28036635df3f8d2eefc67844bb4bf647043fc6
[INFO] running `Command { std: "docker" "start" "-a" "d0d6c071b1ca44ced630773c3e28036635df3f8d2eefc67844bb4bf647043fc6", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.40s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/retl-1c7999cbd8256b9a)
[INFO] [stdout] 
[INFO] [stdout] running 32 tests
[INFO] [stdout] test json_whitelist::tests::empty_whitelist_yields_empty_object ... ok
[INFO] [stdout] test json_whitelist::tests::fused_handles_negative_epoch ... ok
[INFO] [stdout] test json_whitelist::tests::fused_leaves_float_timestamp_alone ... ok
[INFO] [stdout] test json_whitelist::tests::fused_leaves_timestamp_key_untouched_when_not_whitelisted ... ok
[INFO] [stdout] test json_whitelist::tests::fused_malformed_returns_error_and_clears_buf ... ok
[INFO] [stdout] test json_whitelist::tests::fused_preserves_nested_value_bytes ... ok
[INFO] [stdout] test json_whitelist::tests::fused_leaves_non_integer_timestamp_values_alone ... ok
[INFO] [stdout] test json_whitelist::tests::fused_handles_unicode_escaped_timestamp_key ... ok
[INFO] [stdout] test json_whitelist::tests::fused_rewrites_timestamp_keys_only ... ok
[INFO] [stdout] test json_whitelist::tests::happy_path_flat_object ... ok
[INFO] [stdout] test json_whitelist::tests::handles_null_bool_number_values ... ok
[INFO] [stdout] test json_whitelist::tests::key_with_unicode_escape_decoded_for_match ... ok
[INFO] [stdout] test json_whitelist::tests::no_match_yields_empty_object ... ok
[INFO] [stdout] test json_whitelist::tests::string_value_emitted_verbatim_with_escapes ... ok
[INFO] [stdout] test json_whitelist::tests::string_with_escaped_quote_is_skipped_correctly ... ok
[INFO] [stdout] test json_whitelist::tests::tolerates_whitespace_in_input ... ok
[INFO] [stdout] test json_whitelist::tests::skips_nested_object_value ... ok
[INFO] [stdout] test json_whitelist::tests::non_object_top_level_errors ... ok
[INFO] [stdout] test json_whitelist::tests::fused_rewrites_all_three_timestamp_keys ... ok
[INFO] [stdout] test json_whitelist::tests::skips_nested_array_value ... ok
[INFO] [stdout] test json_whitelist::tests::malformed_returns_error_and_clears_buf ... ok
[INFO] [stdout] test username_stream::tests::step_bounds_retries_on_persistent_read_error ... ok
[INFO] [stdout] test username_stream::tests::step_yields_then_eofs ... ok
[INFO] [stdout] test username_stream::tests::streams_lines_in_order_across_files ... ok
[INFO] [stdout] test stitch::tests::stitches_compact_array ... ok
[INFO] [stdout] test username_stream::tests::try_next_surfaces_open_errors_and_caller_can_continue ... ok
[INFO] [stdout] test username_stream::tests::lossy_iterator_skips_files_that_fail_to_open ... ok
[INFO] [stdout] test username_stream::tests::try_next_eventually_drains_all_files_with_only_open_errors ... ok
[INFO] [stdout] test stitch::tests::stitches_pretty_array_one_per_line ... ok
[INFO] [stdout] test stitch::tests::corrupt_temp_part_surfaces_io_error ... ok
[INFO] [stdout] test zstd_jsonl::tests::healthy_file_does_not_trigger_on_skip ... ok
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/retl-b215e8797abc2314)
[INFO] [stdout] test zstd_jsonl::tests::bit_flipped_frame_fails_full_but_scan_skips ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 32 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/aggregate.rs (/opt/rustwide/target/debug/deps/aggregate-8f8128111ae71030)
[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] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test aggregate_over_jsonl_inputs ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/aggregate_merge_bench.rs (/opt/rustwide/target/debug/deps/aggregate_merge_bench-f08fd88868c832e6)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/atomic_replace_fallback.rs (/opt/rustwide/target/debug/deps/atomic_replace_fallback-eea73f12a202a77d)
[INFO] [stdout] test merge_parallel_beats_serial_on_many_shards ... ignored, timing-based regression bench; run with --ignored --nocapture
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cli_help.rs (/opt/rustwide/target/debug/deps/cli_help-622191bcc6ac4729)
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test count_help_advertises_modes ... ok
[INFO] [stdout] test aggregate_help_requires_inputs_and_out ... ok
[INFO] [stdout] test parents_help_advertises_required_flags ... ok
[INFO] [stdout] test integrity_help_advertises_modes_and_sample ... ok
[INFO] [stdout] test export_help_advertises_zst_level_and_resume ... ok
[INFO] [stdout] test export_help_advertises_format_and_out ... ok
[INFO] [stdout] test first_seen_help_advertises_out ... ok
[INFO] [stdout] test scan_help_advertises_common_flags ... ok
[INFO] [stdout] test version_flag_works ... ok
[INFO] [stdout] test root_help_lists_all_subcommands ... ok
[INFO] [stderr]      Running tests/concurrency.rs (/opt/rustwide/target/debug/deps/concurrency-ef1836c1f19fceca)
[INFO] [stdout] test unknown_subcommand_fails_clean ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.09s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test for_each_file_limited_with_limit_one_is_serial ... ok
[INFO] [stdout] test for_each_file_limited_runs_callback_once_per_file_at_concurrency ... ok
[INFO] [stdout] test for_each_file_limited_propagates_errors ... ok
[INFO] [stdout] test sharded_writer_concurrent_writes_dedup_to_expected_set ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.15s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/counts_and_first_seen.rs (/opt/rustwide/target/debug/deps/counts_and_first_seen-8c2264e8cc029178)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test count_by_month_keywords_and_url ... ok
[INFO] [stdout] test author_counts_and_first_seen_tsv ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.28s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/examples.rs (/opt/rustwide/target/debug/deps/examples-c6b3a041d119b724)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test extract_jsonl_scan_builder ... ok
[INFO] [stdout] test export_partitioned_jsonl ... ok
[INFO] [stdout] test usernames_basic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.19s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/examples_usernames.rs (/opt/rustwide/target/debug/deps/examples_usernames-c83ffcaeab7e5d2f)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test usernames_with_domain_filter_submissions_only ... ok
[INFO] [stdout] test usernames_authors_in_out_and_regex ... ok
[INFO] [stdout] test usernames_with_filters_keywords_url_score ... ok
[INFO] [stdout] test usernames_exclude_common_bots ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.26s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/exclude_bots_env.rs (/opt/rustwide/target/debug/deps/exclude_bots_env-229f1e5ed8daa082)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test etl_exclude_authors_env_var_excludes_listed_names ... ok
[INFO] [stdout] test etl_exclude_authors_env_combines_with_file_and_defaults ... ok
[INFO] [stdout] test etl_exclude_authors_file_env_var_reads_newline_separated_names ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.51s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/export_empty_match.rs (/opt/rustwide/target/debug/deps/export_empty_match-8ad75935f426a9e9)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test extract_to_json_array_with_unmatched_query_writes_empty_array ... ok
[INFO] [stdout] test export_partitioned_zst_unmatched_query_removes_output_files ... ok
[INFO] [stdout] test count_by_month_returns_empty_map_for_unmatched_query ... ok
[INFO] [stdout] test count_by_month_aggregates_across_multiple_months ... ok
[INFO] [stdout] test export_partitioned_jsonl_unmatched_query_removes_output_files ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.18s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/export_interruption.rs (/opt/rustwide/target/debug/deps/export_interruption-edc485e19ef8a3a8)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test interrupted_export_leaves_no_half_written_zst_at_destination ... ignored, Pre-#T1: export_partitioned writes directly to dest, so a mid-file decode error leaves a partial .zst at destination. Un-ignore once #T1's staging+atomic-rename fix lands.
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/extract_and_export.rs (/opt/rustwide/target/debug/deps/extract_and_export-204ce68b611a3f1c)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test extract_with_whitelist_and_human_timestamps ... ok
[INFO] [stdout] test export_partitioned_zst ... ok
[INFO] [stdout] test extract_with_human_timestamps_only ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/extract_json_array.rs (/opt/rustwide/target/debug/deps/extract_json_array-73da9276a1c9bdc5)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test extract_to_json_array_empty_when_no_files_writes_empty_array ... ok
[INFO] [stdout] test extract_to_json_array_pretty_parses_back_as_vec_and_has_indentation ... ok
[INFO] [stdout] test extract_to_json_array_compact_parses_back_as_vec ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/human_timestamps_edge_cases.rs (/opt/rustwide/target/debug/deps/human_timestamps_edge_cases-0c933c32664a1685)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test human_timestamps_handle_negative_epoch_created_utc ... ok
[INFO] [stdout] test human_timestamps_handle_whitespace_between_colon_and_integer ... ok
[INFO] [stdout] test non_target_keys_not_misidentified_when_string_contains_keyword_substring ... ok
[INFO] [stdout] test human_timestamps_convert_edited_when_integer ... ok
[INFO] [stdout] test human_timestamps_leave_retrieved_on_string_untouched ... ok
[INFO] [stdout] test human_timestamps_leave_edited_null_untouched ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integrity.rs (/opt/rustwide/target/debug/deps/integrity-b956c4fd5a94d825)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test integrity_check_detects_corrupt_month ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integrity_truncation.rs (/opt/rustwide/target/debug/deps/integrity_truncation-6f18d79f36e7f67d)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test full_integrity_detects_tail_truncation ... ok
[INFO] [stdout] test quick_integrity_misses_tail_truncation ... ok
[INFO] [stdout] test normal_scan_warns_and_skips_truncated_month_without_crashing ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.27s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/json_utils_test.rs (/opt/rustwide/target/debug/deps/json_utils_test-7957fcecbf1163a6)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test author_lower_returns_none_when_field_missing ... ok
[INFO] [stdout] test is_comment_record_does_not_inspect_field_values ... ok
[INFO] [stdout] test is_comment_record_requires_both_body_and_parent_id ... ok
[INFO] [stdout] test author_lower_returns_none_when_field_is_not_a_string ... ok
[INFO] [stdout] test author_lower_handles_already_lowercase_input ... ok
[INFO] [stdout] test subreddit_lower_returns_lowercase_when_present_as_string ... ok
[INFO] [stdout] test subreddit_lower_returns_none_when_missing_or_non_string ... ok
[INFO] [stdout] test author_lower_lowercases_non_ascii_when_possible ... ok
[INFO] [stdout] test author_lower_returns_lowercase_when_present_as_string ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/json_whitelist_proptest.rs (/opt/rustwide/target/debug/deps/json_whitelist_proptest-2bdfc99b6d8965ad)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test fused_matches_two_pass ... ok
[INFO] [stdout] test tokenizer_matches_slow_path ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/lambdas.rs (/opt/rustwide/target/debug/deps/lambdas-d78a5e79dc88f955)
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.62s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test partition_writers_lambda_routing_and_output ... ok
[INFO] [stderr]      Running tests/multi_month.rs (/opt/rustwide/target/debug/deps/multi_month-8ca856e8aa3561a3)
[INFO] [stdout] test usernames_lambda_callback ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test bounds_tuple_returns_none_unless_both_endpoints_set ... ok
[INFO] [stdout] test iter_year_months_crosses_year_boundary ... ok
[INFO] [stdout] test iter_year_months_inclusive_endpoints_and_inverted_range_is_empty ... ok
[INFO] [stdout] test within_bounds_uses_record_level_timestamp ... ok
[INFO] [stdout] test plan_files_silently_skips_missing_month ... ok
[INFO] [stdout] test plan_files_clamps_to_existing_when_start_unset ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/parents_pipeline.rs (/opt/rustwide/target/debug/deps/parents_pipeline-8090381bb7611239)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test spool_resolve_attach_parents_end_to_end ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.18s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/partition_writers_more.rs (/opt/rustwide/target/debug/deps/partition_writers_more-a82a40b70073f326)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test parts_zero_is_clamped_to_one ... ok
[INFO] [stdout] test finalize_returns_paths_in_partition_index_order ... ok
[INFO] [stdout] test flush_all_is_idempotent_and_safe_between_writes ... ok
[INFO] [stdout] test finalize_without_writes_emits_empty_per_partition_files ... ok
[INFO] [stdout] test shard_hash_distributes_large_key_universe_across_partitions ... ok
[INFO] [stdout] test concurrent_writes_via_rayon_scope_land_on_correct_partitions ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/properties.rs (/opt/rustwide/target/debug/deps/properties-8d999251819b780f)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test round_trip_export_matches_filter ... ok
[INFO] [stderr]      Running tests/real_data_smoke.rs (/opt/rustwide/target/debug/deps/real_data_smoke-9fb9fefa968117ea)
[INFO] [stdout] test dedupe_is_idempotent ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.48s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test real_corpus_path_planner_discovers_the_one_month ... ok
[INFO] [stdout] test real_corpus_quick_integrity_check_passes_for_one_month ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/replace_file_atomic.rs (/opt/rustwide/target/debug/deps/replace_file_atomic-778476a7e910b187)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test replace_overwrites_existing_dest ... ok
[INFO] [stdout] test replace_is_atomic_under_concurrent_reader ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.75s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/round_trip.rs (/opt/rustwide/target/debug/deps/round_trip-4047137c80bcf8e6)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stderr]      Running tests/score_and_keyword_filters.rs (/opt/rustwide/target/debug/deps/score_and_keyword_filters-193955f896d9f493)
[INFO] [stdout] test round_trip_jsonl_preserves_counts_and_ids ... ok
[INFO] [stdout] test round_trip_zst_with_whitelist_is_structural_subset ... ok
[INFO] [stdout] test round_trip_zst_preserves_counts_and_ids ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.09s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test keywords_any_matches_title_field_on_submissions ... ok
[INFO] [stdout] test min_score_is_inclusive_at_boundary ... ok
[INFO] [stdout] test contains_url_true_matches_https_uppercase_in_body ... ok
[INFO] [stdout] test max_score_is_inclusive_at_boundary ... ok
[INFO] [stdout] test min_and_max_score_combine_as_a_closed_interval ... ok
[INFO] [stdout] test domains_in_filter_rejects_comments_when_set ... ok
[INFO] [stdout] test keywords_any_is_case_insensitive ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stderr]      Running tests/spool_resume.rs (/opt/rustwide/target/debug/deps/spool_resume-c5467f1c5ba7ec26)
[INFO] [stdout] test resume_disabled_does_not_create_manifest ... ok
[INFO] [stdout] test resume_drops_stale_entry_when_output_size_changes ... ok
[INFO] [stdout] test resume_skips_already_completed_months_on_second_run ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.13s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/tokenizer_fallback.rs (/opt/rustwide/target/debug/deps/tokenizer_fallback-c9858ab4e1f34463)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test tokenizer_rejects_missing_value ... ok
[INFO] [stdout] test tokenizer_rejects_top_level_array_even_though_serde_accepts_it ... ok
[INFO] [stdout] test tokenizer_rejects_trailing_comma_object ... ok
[INFO] [stdout] test tokenizer_rejects_top_level_scalar_even_though_serde_accepts_it ... ok
[INFO] [stdout] test stream_job_handles_escaped_keys_via_canonical_decode ... ok
[INFO] [stdout] test stream_job_jsonl_export_matches_slow_path_projection_on_quirky_objects ... ok
[INFO] [stdout] test stream_job_zst_export_matches_slow_path_projection_on_quirky_objects ... ok
[INFO] [stdout] test stream_job_drops_tokenizer_rejected_non_objects_without_error ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/unit_modules.rs (/opt/rustwide/target/debug/deps/unit_modules-7da4115968d0e12a)
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test ndjson_reader_strips_crlf_and_handles_blank_lines ... ok
[INFO] [stdout] test ndjson_writer_finish_atomic_promotes_temp ... ok
[INFO] [stdout] test kv_shard_min_picks_smallest_value_per_key ... ok
[INFO] [stdout] test ndjson_writer_then_reader_roundtrip ... ok
[INFO] [stdout] test dedupe_single_run_promotes_directly_to_output ... ok
[INFO] [stdout] test kv_shard_sum_reduces_per_key_totals ... ok
[INFO] [stdout] test bucketing_bucketize_shard_splits_into_n_buckets ... ok
[INFO] [stdout] test bucketing_partition_stage1_routes_all_records_and_is_deterministic ... ok
[INFO] [stdout] test bucketing_process_bucket_streaming_drives_adaptive_flush_with_10k_records ... ok
[INFO] [stdout] test dedupe_build_and_merge_groups_by_key_in_sorted_order ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- dedupe_build_and_merge_groups_by_key_in_sorted_order stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'dedupe_build_and_merge_groups_by_key_in_sorted_order' (689) panicked at tests/unit_modules.rs:350:6:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: open /tmp/.tmpaKV5F5/runs/run_1022.ndjson
[INFO] [stdout] 
[INFO] [stdout] Caused by:
[INFO] [stdout]     Too many open files (os error 24)
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <std::io::error::Error as anyhow::context::ext::StdError>::ext_context::<alloc::string::String>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.102/src/backtrace.rs:10:14
[INFO] [stdout]    1: <core::result::Result<std::fs::File, std::io::error::Error> as anyhow::Context<std::fs::File, std::io::error::Error>>::with_context::<alloc::string::String, retl::dedupe::merge_runs_sorted<unit_modules::dedupe_build_and_merge_groups_by_key_in_sorted_order::{closure#0}>::{closure#1}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.102/src/context.rs:65:37
[INFO] [stdout]    2: retl::dedupe::merge_runs_sorted::<unit_modules::dedupe_build_and_merge_groups_by_key_in_sorted_order::{closure#0}>
[INFO] [stdout]              at ./src/dedupe.rs:263:46
[INFO] [stdout]    3: unit_modules::dedupe_build_and_merge_groups_by_key_in_sorted_order
[INFO] [stdout]              at ./tests/unit_modules.rs:342:5
[INFO] [stdout]    4: unit_modules::dedupe_build_and_merge_groups_by_key_in_sorted_order::{closure#0}
[INFO] [stdout]              at ./tests/unit_modules.rs:294:58
[INFO] [stdout]    5: <unit_modules::dedupe_build_and_merge_groups_by_key_in_sorted_order::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    6: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    7: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:724:18
[INFO] [stdout]    8: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:747:74
[INFO] [stdout]    9: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   10: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   11: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   12: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panic.rs:359:14
[INFO] [stdout]   13: test::run_test_in_process
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:747:27
[INFO] [stdout]   14: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:668:43
[INFO] [stdout]   15: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:698:41
[INFO] [stdout]   16: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   17: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   18: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   19: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   20: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   21: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panic.rs:359:14
[INFO] [stdout]   22: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   23: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   25: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   26: <unknown>
[INFO] [stdout]   27: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x632cc5926c6a - std[29689e6404d28ef9]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x632cc5926c6a - std[29689e6404d28ef9]::backtrace_rs::backtrace::trace_unsynchronized::<std[29689e6404d28ef9]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x632cc5926c6a - std[29689e6404d28ef9]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x632cc5926c6a - <<std[29689e6404d28ef9]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[e929cb53b82a81ca]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x632cc593e31a - <core[e929cb53b82a81ca]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x632cc593e31a - core[e929cb53b82a81ca]::fmt::write
[INFO] [stdout]    6:     0x632cc592c0f2 - std[29689e6404d28ef9]::io::default_write_fmt::<alloc[9d7caffeb3b5d2c6]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x632cc592c0f2 - <alloc[9d7caffeb3b5d2c6]::vec::Vec<u8> as std[29689e6404d28ef9]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x632cc590251f - <std[29689e6404d28ef9]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x632cc590251f - std[29689e6404d28ef9]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x632cc591d509 - std[29689e6404d28ef9]::panicking::default_hook
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x632cc571fd3c - <alloc[9d7caffeb3b5d2c6]::boxed::Box<dyn for<'a, 'b> core[e929cb53b82a81ca]::ops::function::Fn<(&'a std[29689e6404d28ef9]::panic::PanicHookInfo<'b>,), Output = ()> + core[e929cb53b82a81ca]::marker::Send + core[e929cb53b82a81ca]::marker::Sync> as core[e929cb53b82a81ca]::ops::function::Fn<(&std[29689e6404d28ef9]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x632cc571fd3c - test[a24b3028667022f7]::test_main_inner::<test[a24b3028667022f7]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x632cc591d782 - <alloc[9d7caffeb3b5d2c6]::boxed::Box<dyn for<'a, 'b> core[e929cb53b82a81ca]::ops::function::Fn<(&'a std[29689e6404d28ef9]::panic::PanicHookInfo<'b>,), Output = ()> + core[e929cb53b82a81ca]::marker::Send + core[e929cb53b82a81ca]::marker::Sync> as core[e929cb53b82a81ca]::ops::function::Fn<(&std[29689e6404d28ef9]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x632cc591d782 - std[29689e6404d28ef9]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x632cc59025d8 - std[29689e6404d28ef9]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x632cc58f70e9 - std[29689e6404d28ef9]::sys::backtrace::__rust_end_short_backtrace::<std[29689e6404d28ef9]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x632cc590349d - __rustc[3aed6af316653e63]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x632cc593eadc - core[e929cb53b82a81ca]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x632cc593e852 - core[e929cb53b82a81ca]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x632cc57043de - <core[e929cb53b82a81ca]::result::Result<(), anyhow[4b33063320ba2d1b]::Error>>::unwrap
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/result.rs:1233:23
[INFO] [stdout]   21:     0x632cc57043de - unit_modules[1c0088d1b2a6f68d]::dedupe_build_and_merge_groups_by_key_in_sorted_order
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/unit_modules.rs:350:6
[INFO] [stdout]   22:     0x632cc56fc7e7 - unit_modules[1c0088d1b2a6f68d]::dedupe_build_and_merge_groups_by_key_in_sorted_order::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/unit_modules.rs:294:58
[INFO] [stdout]   23:     0x632cc56dadb6 - <unit_modules[1c0088d1b2a6f68d]::dedupe_build_and_merge_groups_by_key_in_sorted_order::{closure#0} as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x632cc571301b - <fn() -> core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String> as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x632cc571301b - test[a24b3028667022f7]::__rust_begin_short_backtrace::<core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>, fn() -> core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x632cc572080b - test[a24b3028667022f7]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x632cc572080b - <core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<test[a24b3028667022f7]::run_test_in_process::{closure#0}> as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x632cc572080b - std[29689e6404d28ef9]::panicking::catch_unwind::do_call::<core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<test[a24b3028667022f7]::run_test_in_process::{closure#0}>, core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x632cc572080b - std[29689e6404d28ef9]::panicking::catch_unwind::<core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>, core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<test[a24b3028667022f7]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x632cc572080b - std[29689e6404d28ef9]::panic::catch_unwind::<core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<test[a24b3028667022f7]::run_test_in_process::{closure#0}>, core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x632cc572080b - test[a24b3028667022f7]::run_test_in_process
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x632cc572080b - test[a24b3028667022f7]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x632cc5719f24 - test[a24b3028667022f7]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x632cc5719f24 - std[29689e6404d28ef9]::sys::backtrace::__rust_begin_short_backtrace::<test[a24b3028667022f7]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x632cc5723412 - std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked::<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x632cc5723412 - <core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x632cc5723412 - std[29689e6404d28ef9]::panicking::catch_unwind::do_call::<core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x632cc5723412 - std[29689e6404d28ef9]::panicking::catch_unwind::<(), core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x632cc5723412 - std[29689e6404d28ef9]::panic::catch_unwind::<core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x632cc5723412 - std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked::<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x632cc5723412 - <std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1} as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x632cc592648f - <alloc[9d7caffeb3b5d2c6]::boxed::Box<dyn core[e929cb53b82a81ca]::ops::function::FnOnce<(), Output = ()> + core[e929cb53b82a81ca]::marker::Send> as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   43:     0x632cc592648f - <std[29689e6404d28ef9]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x7d7ac7a00aa4 - <unknown>
[INFO] [stdout]   45:     0x7d7ac7a8da64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     dedupe_build_and_merge_groups_by_key_in_sorted_order
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 9 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.40s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test unit_modules`
[INFO] running `Command { std: "docker" "inspect" "d0d6c071b1ca44ced630773c3e28036635df3f8d2eefc67844bb4bf647043fc6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d0d6c071b1ca44ced630773c3e28036635df3f8d2eefc67844bb4bf647043fc6", kill_on_drop: false }`
[INFO] [stdout] d0d6c071b1ca44ced630773c3e28036635df3f8d2eefc67844bb4bf647043fc6
