Nov 18 15:57:52.688 INFO testing divans-0.0.1 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 15:57:52.688 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 18 15:57:53.099 INFO [stdout] 896ce6bec8e5228bfeb988da62b1e3086f39612c94c6c45210ef979c923791ac
Nov 18 15:57:53.101 INFO running `"docker" "start" "-a" "896ce6bec8e5228bfeb988da62b1e3086f39612c94c6c45210ef979c923791ac"`
Nov 18 15:57:54.037 INFO [stderr] usermod: no changes
Nov 18 15:57:54.091 INFO [stderr]    Compiling alloc-no-stdlib v1.3.0
Nov 18 15:57:54.099 INFO [stderr]    Compiling vergen v0.1.1
Nov 18 15:57:54.627 INFO [stderr]    Compiling brotli-decompressor v1.3.1
Nov 18 15:57:55.412 INFO [stderr]    Compiling divans v0.0.1 (/source)
Nov 18 15:57:58.994 INFO [stderr]    Compiling brotli v2.5.1
Nov 18 15:58:59.048 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 04s
Nov 18 15:58:59.059 INFO [stderr] su: No module specific data is present
Nov 18 15:59:00.018 INFO running `"docker" "inspect" "896ce6bec8e5228bfeb988da62b1e3086f39612c94c6c45210ef979c923791ac"`
Nov 18 15:59:00.259 INFO running `"docker" "rm" "-f" "896ce6bec8e5228bfeb988da62b1e3086f39612c94c6c45210ef979c923791ac"`
Nov 18 15:59:00.494 INFO [stdout] 896ce6bec8e5228bfeb988da62b1e3086f39612c94c6c45210ef979c923791ac
Nov 18 15:59:00.501 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 18 15:59:00.986 INFO [stdout] 3a959c363e7be42227d4503b2c594b6b721daa4e0991fca64ac1cfb2d94ac3c1
Nov 18 15:59:00.993 INFO running `"docker" "start" "-a" "3a959c363e7be42227d4503b2c594b6b721daa4e0991fca64ac1cfb2d94ac3c1"`
Nov 18 15:59:01.691 INFO [stderr] usermod: no changes
Nov 18 15:59:01.739 INFO [stderr]    Compiling divans v0.0.1 (/source)
Nov 18 15:59:50.869 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 49.15s
Nov 18 15:59:50.870 INFO [stderr] su: No module specific data is present
Nov 18 15:59:51.351 INFO running `"docker" "inspect" "3a959c363e7be42227d4503b2c594b6b721daa4e0991fca64ac1cfb2d94ac3c1"`
Nov 18 15:59:51.454 INFO running `"docker" "rm" "-f" "3a959c363e7be42227d4503b2c594b6b721daa4e0991fca64ac1cfb2d94ac3c1"`
Nov 18 15:59:51.617 INFO [stdout] 3a959c363e7be42227d4503b2c594b6b721daa4e0991fca64ac1cfb2d94ac3c1
Nov 18 15:59:51.620 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 18 15:59:51.827 INFO [stdout] 71096e68d5fddc96677b853f5d30264d05fe8347189d9c5f19c4a8bb287496a0
Nov 18 15:59:51.835 INFO running `"docker" "start" "-a" "71096e68d5fddc96677b853f5d30264d05fe8347189d9c5f19c4a8bb287496a0"`
Nov 18 15:59:52.315 INFO [stderr] usermod: no changes
Nov 18 15:59:52.355 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Nov 18 15:59:52.361 INFO [stderr]      Running /target/debug/deps/divans-5b0acd096e632f0e
Nov 18 15:59:52.362 INFO [stdout] 
Nov 18 15:59:52.362 INFO [stdout] running 63 tests
Nov 18 15:59:52.363 INFO [stdout] test alloc_util::test::test_reuse ... ok
Nov 18 15:59:52.363 INFO [stdout] test alloc_util::test::test_non_reuse ... ok
Nov 18 15:59:52.363 INFO [stdout] test arithmetic_coder::test::test_get_nibble ... ok
Nov 18 15:59:52.400 INFO [stdout] test arithmetic_coder::test::test_put_nibble ... ok
Nov 18 15:59:52.400 INFO [stdout] test cmd_to_raw::test::test_copy_big_overlap ... ok
Nov 18 15:59:52.400 INFO [stdout] test codec::crc32::test::test_crc32c_empty ... ok
Nov 18 15:59:52.400 INFO [stdout] test cmd_to_raw::test::test_insert_medium ... ok
Nov 18 15:59:52.400 INFO [stdout] test cmd_to_raw::test::test_ring_buffer_dict ... ok
Nov 18 15:59:52.400 INFO [stdout] test cmd_to_raw::test::test_copy_far ... ok
Nov 18 15:59:52.400 INFO [stdout] test priors::test::test_billing_types ... ok
Nov 18 15:59:52.400 INFO [stdout] test codec::crc32::test::test_crc32c_numeric_half ... ok
Nov 18 15:59:52.400 INFO [stdout] test codec::crc32::test::test_crc32c_numeric ... ok
Nov 18 15:59:52.400 INFO [stdout] test priors::test::test_get ... ok
Nov 18 15:59:52.400 INFO [stdout] test priors::test::test_macro_count_expr ... ok
Nov 18 15:59:52.400 INFO [stdout] test priors::test::test_macro_product ... ok
Nov 18 15:59:52.400 INFO [stdout] test cmd_to_raw::test::test_insert_huge ... ok
Nov 18 15:59:52.400 INFO [stdout] test codec::crc32::test::test_crc32c_qbf ... ok
Nov 18 15:59:52.400 INFO [stdout] test priors::test::test_macro_sum_product_cdr ... ok
Nov 18 15:59:52.400 INFO [stdout] test priors::test::test_get_tuple ... ok
Nov 18 15:59:52.400 INFO [stdout] test codec::crc32::test::test_crc32c_qbf_half ... ok
Nov 18 15:59:52.400 INFO [stdout] test probability::blend_cdf::test::test_blend_lut ... ok
Nov 18 15:59:52.400 INFO [stdout] test priors::test::test_num_prior ... ok
Nov 18 15:59:52.400 INFO [stdout] test cmd_to_raw::test::test_copy_near_overlap ... ok
Nov 18 15:59:52.400 INFO [stdout] test probability::blend_cdf::test::test_sym_to_start_and_freq ... ok
Nov 18 15:59:52.400 INFO [stdout] test priors::test::test_num_dimensions ... ok
Nov 18 15:59:52.447 INFO [stdout] test priors::test::test_get_bad_tuple_index ... ok
Nov 18 15:59:52.447 INFO [stdout] test priors::test::test_get_bad_tuple_dimensionality ... ok
Nov 18 15:59:52.463 INFO [stdout] test probability::interface::test::test_u8_to_speed ... ok
Nov 18 15:59:52.463 INFO [stdout] test probability::frequentist_cdf::test::test_sym_to_start_and_freq ... ok
Nov 18 15:59:52.491 INFO [stdout] test probability::numeric::test::test_divide ... ok
Nov 18 15:59:52.499 INFO [stdout] test probability::opt_frequentist_cdf::test::test_cdf_opt_eq_baseline ... ok
Nov 18 15:59:52.975 INFO [stdout] test probability::frequentist_cdf::test::test_nonzero_pdf ... ok
Nov 18 15:59:53.223 INFO [stdout] test probability::opt_frequentist_cdf::test::test_nonzero_pdf ... ok
Nov 18 15:59:53.223 INFO [stdout] test probability::opt_frequentist_cdf::test::test_sym_to_start_and_freq ... ok
Nov 18 15:59:53.404 INFO [stdout] test probability::frequentist_cdf::test::test_cdf_offset_to_sym_start_and_freq ... ok
Nov 18 15:59:53.843 INFO [stdout] test probability::opt_frequentist_cdf::test::test_cdf_offset_to_sym_start_and_freq ... ok
Nov 18 15:59:56.079 INFO [stdout] test probability::blend_cdf::test::test_cdf_offset_to_sym_start_and_freq ... ok
Nov 18 15:59:57.275 INFO [stdout] test probability::frequentist_cdf::test::test_stationary_probability ... ok
Nov 18 15:59:57.300 INFO [stderr] encoded size: 2928
Nov 18 15:59:57.300 INFO [stderr] effeciency: 1.00311013336588
Nov 18 15:59:57.355 INFO [stderr] done!
Nov 18 15:59:57.355 INFO [stderr] encoded size: 24
Nov 18 15:59:57.355 INFO [stderr] effeciency: 2.1054007256147593
Nov 18 15:59:57.355 INFO [stderr] done!
Nov 18 15:59:57.355 INFO [stdout] test test_ans::entropy16_big_trait_test ... ok
Nov 18 15:59:57.355 INFO [stdout] test test_ans::entropy16_lite_trait_test ... ok
Nov 18 15:59:57.389 INFO [stderr] encoded size: 2928
Nov 18 15:59:57.389 INFO [stderr] effeciency: 0.9933084853811626
Nov 18 15:59:57.389 INFO [stderr] done!
Nov 18 15:59:57.396 INFO [stdout] test test_ans::entropy16_trait_test ... ok
Nov 18 15:59:58.570 INFO [stdout] test probability::blend_cdf::test::test_nonzero_pdf ... ok
Nov 18 15:59:58.948 INFO [stdout] test reader::test::test_experimental_reader_compressor_on_alice_full ... ok
Nov 18 15:59:59.199 INFO [stdout] test probability::blend_cdf::test::test_stationary_probability ... ok
Nov 18 16:00:00.571 INFO [stdout] test test_mux::test_balance_mux ... ok
Nov 18 16:00:00.798 INFO [stdout] test test_mux::test_interleaved_mux ... ok
Nov 18 16:00:00.814 INFO [stdout] test test_mux::test_nano_mux ... ok
Nov 18 16:00:00.847 INFO [stdout] test test_mux::test_real_mux ... ok
Nov 18 16:00:00.855 INFO [stdout] test test_mux::test_med_mux ... ok
Nov 18 16:00:00.859 INFO [stdout] test test_mux::test_short_mux ... ok
Nov 18 16:00:00.866 INFO [stdout] test test_mux::test_tiny_mux ... ok
Nov 18 16:00:00.866 INFO [stdout] test test_mux::unit_test_decode_mux ... ok
Nov 18 16:00:00.921 INFO [stdout] test probability::opt_frequentist_cdf::test::test_stationary_probability ... ok
Nov 18 16:00:01.111 INFO [stdout] test test_mux::test_sc3_mux ... ok
Nov 18 16:00:02.051 INFO [stdout] test test_mux::test_long_mux ... ok
Nov 18 16:00:02.051 INFO [stdout] test test_mux::test_3meg_mux ... ok
Nov 18 16:00:13.415 INFO [stdout] test reader::test::test_hybrid_reader_compressor_on_unicode_full ... ok
Nov 18 16:00:19.068 INFO [stdout] test writer::test::test_hybrid_writer_compressor_on_alice_full ... ok
Nov 18 16:00:21.195 INFO [stdout] test writer::test::test_experimental_writer_compressor_on_alice_full ... ok
Nov 18 16:00:21.419 INFO [stdout] test reader::test::test_hybrid_reader_compressor_on_alice_full ... ok
Nov 18 16:00:25.679 INFO [stdout] test reader::test::test_hybrid_reader_compressor_on_alice_small_buffer ... ok
Nov 18 16:00:27.631 INFO [stdout] test writer::test::test_hybrid_writer_compressor_on_alice_small_buffer ... ok
Nov 18 16:00:28.092 INFO [stdout] test writer::test::test_hybrid_writer_compressor_on_unicode_full ... ok
Nov 18 16:00:28.092 INFO [stdout] 
Nov 18 16:00:28.092 INFO [stdout] test result: ok. 63 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:00:28.092 INFO [stdout] 
Nov 18 16:00:28.092 INFO [stderr]      Running /target/debug/deps/divans-66e761e757b465bb
Nov 18 16:00:28.102 INFO [stdout] 
Nov 18 16:00:28.102 INFO [stdout] running 19 tests
Nov 18 16:00:28.411 INFO [stdout] test integration_test::test_e2e_64xp ... ok
Nov 18 16:00:28.662 INFO [stdout] test integration_test::test_e2e_32xx ... ok
Nov 18 16:00:28.747 INFO [stdout] test integration_test::test_alice29 ... ok
Nov 18 16:00:28.935 INFO [stdout] test integration_test::test_asyoulik ... ok
Nov 18 16:00:29.011 INFO [stdout] test integration_test::test_e2e_empty ... ok
Nov 18 16:00:29.135 INFO [stdout] test integration_test::test_e2e_empty_br ... ok
Nov 18 16:00:29.327 INFO [stdout] test integration_test::test_e2e_empty_tinybuf ... ok
Nov 18 16:00:29.390 INFO [stdout] test integration_test::test_e2e_empty_just_flush ... ok
Nov 18 16:00:29.444 INFO [stdout] test integration_test::test_e2e_empty_br_tinybuf ... ok
Nov 18 16:00:29.631 INFO [stdout] test integration_test::test_e2e_ones_tinybuf ... ok
Nov 18 16:00:29.631 INFO [stdout] test integration_test::test_ends_with_truncated_dictionary ... ok
Nov 18 16:00:29.674 INFO [stdout] test integration_test::test_e2e_262145_at ... ok
Nov 18 16:00:29.919 INFO [stdout] test benchmark::test_raw_adaptive_literal_stream ... ok
Nov 18 16:00:30.467 INFO [stdout] test integration_test::test_random_then_unicode ... ok
Nov 18 16:00:32.486 INFO [stdout] test benchmark::test_raw_literal_stream ... ok
Nov 18 16:00:47.303 INFO [stdout] test integration_test::test_e2e_smallbuf_without_priors ... ok
Nov 18 16:00:48.144 INFO [stdout] test integration_test::test_e2e_alice ... ok
Nov 18 16:00:49.699 INFO [stdout] test benchmark::test_raw_ir_literal_stream ... ok
Nov 18 16:00:50.034 INFO [stdout] test integration_test::test_e2e_tinybuf ... ok
Nov 18 16:00:50.037 INFO [stdout] 
Nov 18 16:00:50.038 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:00:50.038 INFO [stdout] 
Nov 18 16:00:50.041 INFO [stderr]    Doc-tests divans
Nov 18 16:00:52.155 INFO [stdout] 
Nov 18 16:00:52.155 INFO [stdout] running 0 tests
Nov 18 16:00:52.156 INFO [stdout] 
Nov 18 16:00:52.156 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:00:52.156 INFO [stdout] 
Nov 18 16:00:52.156 INFO [stderr] su: No module specific data is present
Nov 18 16:00:52.739 INFO running `"docker" "inspect" "71096e68d5fddc96677b853f5d30264d05fe8347189d9c5f19c4a8bb287496a0"`
Nov 18 16:00:53.025 INFO running `"docker" "rm" "-f" "71096e68d5fddc96677b853f5d30264d05fe8347189d9c5f19c4a8bb287496a0"`
Nov 18 16:00:53.307 INFO [stdout] 71096e68d5fddc96677b853f5d30264d05fe8347189d9c5f19c4a8bb287496a0