Dec 07 18:35:26.420 INFO testing divans-0.0.1 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 18:35:26.420 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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"`
Dec 07 18:35:26.648 INFO [stdout] 89796936abe1630c2ad8a4a394c733563d788abff187591471ecae00706e7fb6
Dec 07 18:35:26.650 INFO running `"docker" "start" "-a" "89796936abe1630c2ad8a4a394c733563d788abff187591471ecae00706e7fb6"`
Dec 07 18:35:27.764 INFO [stderr] usermod: no changes
Dec 07 18:35:27.821 INFO [stderr]    Compiling alloc-no-stdlib v1.3.0
Dec 07 18:35:27.824 INFO [stderr]    Compiling vergen v0.1.1
Dec 07 18:35:28.516 INFO [stderr]    Compiling brotli-decompressor v1.3.1
Dec 07 18:35:29.780 INFO [stderr]    Compiling divans v0.0.1 (/source)
Dec 07 18:35:32.792 INFO [stderr]    Compiling brotli v2.5.1
Dec 07 18:36:34.480 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 06s
Dec 07 18:36:34.485 INFO [stderr] su: No module specific data is present
Dec 07 18:36:34.962 INFO running `"docker" "inspect" "89796936abe1630c2ad8a4a394c733563d788abff187591471ecae00706e7fb6"`
Dec 07 18:36:35.162 INFO running `"docker" "rm" "-f" "89796936abe1630c2ad8a4a394c733563d788abff187591471ecae00706e7fb6"`
Dec 07 18:36:35.369 INFO [stdout] 89796936abe1630c2ad8a4a394c733563d788abff187591471ecae00706e7fb6
Dec 07 18:36:35.376 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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"`
Dec 07 18:36:35.820 INFO [stdout] 055b800e14805cc2f3b349e16723bd6146adc7cc7910cc7b36bc3cd7fe00eb51
Dec 07 18:36:35.828 INFO running `"docker" "start" "-a" "055b800e14805cc2f3b349e16723bd6146adc7cc7910cc7b36bc3cd7fe00eb51"`
Dec 07 18:36:36.350 INFO [stderr] usermod: no changes
Dec 07 18:36:36.413 INFO [stderr]    Compiling divans v0.0.1 (/source)
Dec 07 18:37:29.628 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 53.26s
Dec 07 18:37:29.634 INFO [stderr] su: No module specific data is present
Dec 07 18:37:29.922 INFO running `"docker" "inspect" "055b800e14805cc2f3b349e16723bd6146adc7cc7910cc7b36bc3cd7fe00eb51"`
Dec 07 18:37:30.019 INFO running `"docker" "rm" "-f" "055b800e14805cc2f3b349e16723bd6146adc7cc7910cc7b36bc3cd7fe00eb51"`
Dec 07 18:37:30.146 INFO [stdout] 055b800e14805cc2f3b349e16723bd6146adc7cc7910cc7b36bc3cd7fe00eb51
Dec 07 18:37:30.150 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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"`
Dec 07 18:37:30.340 INFO [stdout] da9057f61696d6b1e5517e7d64b28583c2038ea980c0c0117864bfcfdca9e092
Dec 07 18:37:30.342 INFO running `"docker" "start" "-a" "da9057f61696d6b1e5517e7d64b28583c2038ea980c0c0117864bfcfdca9e092"`
Dec 07 18:37:31.059 INFO [stderr] usermod: no changes
Dec 07 18:37:31.173 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Dec 07 18:37:31.186 INFO [stderr]      Running /target/debug/deps/divans-55d3a3256f94c1b5
Dec 07 18:37:31.197 INFO [stdout] 
Dec 07 18:37:31.197 INFO [stdout] running 63 tests
Dec 07 18:37:31.264 INFO [stdout] test alloc_util::test::test_non_reuse ... ok
Dec 07 18:37:31.265 INFO [stdout] test alloc_util::test::test_reuse ... ok
Dec 07 18:37:31.265 INFO [stdout] test arithmetic_coder::test::test_put_nibble ... ok
Dec 07 18:37:31.266 INFO [stdout] test arithmetic_coder::test::test_get_nibble ... ok
Dec 07 18:37:31.266 INFO [stdout] test cmd_to_raw::test::test_insert_huge ... ok
Dec 07 18:37:31.266 INFO [stdout] test cmd_to_raw::test::test_copy_near_overlap ... ok
Dec 07 18:37:31.266 INFO [stdout] test cmd_to_raw::test::test_copy_far ... ok
Dec 07 18:37:31.266 INFO [stdout] test cmd_to_raw::test::test_copy_big_overlap ... ok
Dec 07 18:37:31.267 INFO [stdout] test codec::crc32::test::test_crc32c_qbf_half ... ok
Dec 07 18:37:31.267 INFO [stdout] test codec::crc32::test::test_crc32c_qbf ... ok
Dec 07 18:37:31.267 INFO [stdout] test codec::crc32::test::test_crc32c_empty ... ok
Dec 07 18:37:31.267 INFO [stdout] test cmd_to_raw::test::test_ring_buffer_dict ... ok
Dec 07 18:37:31.267 INFO [stdout] test codec::crc32::test::test_crc32c_numeric_half ... ok
Dec 07 18:37:31.268 INFO [stdout] test cmd_to_raw::test::test_insert_medium ... ok
Dec 07 18:37:31.268 INFO [stdout] test priors::test::test_macro_count_expr ... ok
Dec 07 18:37:31.268 INFO [stdout] test priors::test::test_macro_sum_product_cdr ... ok
Dec 07 18:37:31.268 INFO [stdout] test priors::test::test_billing_types ... ok
Dec 07 18:37:31.268 INFO [stdout] test codec::crc32::test::test_crc32c_numeric ... ok
Dec 07 18:37:31.277 INFO [stdout] test priors::test::test_get_tuple ... ok
Dec 07 18:37:31.277 INFO [stdout] test priors::test::test_macro_product ... ok
Dec 07 18:37:31.278 INFO [stdout] test priors::test::test_num_dimensions ... ok
Dec 07 18:37:31.288 INFO [stdout] test priors::test::test_num_prior ... ok
Dec 07 18:37:31.289 INFO [stdout] test probability::blend_cdf::test::test_sym_to_start_and_freq ... ok
Dec 07 18:37:31.312 INFO [stdout] test priors::test::test_get ... ok
Dec 07 18:37:31.312 INFO [stdout] test probability::blend_cdf::test::test_blend_lut ... ok
Dec 07 18:37:31.624 INFO [stdout] test priors::test::test_get_bad_tuple_index ... ok
Dec 07 18:37:31.624 INFO [stdout] test priors::test::test_get_bad_tuple_dimensionality ... ok
Dec 07 18:37:31.628 INFO [stdout] test probability::frequentist_cdf::test::test_sym_to_start_and_freq ... ok
Dec 07 18:37:31.628 INFO [stdout] test probability::numeric::test::test_divide ... ok
Dec 07 18:37:31.656 INFO [stdout] test probability::interface::test::test_u8_to_speed ... ok
Dec 07 18:37:31.664 INFO [stdout] test probability::opt_frequentist_cdf::test::test_cdf_opt_eq_baseline ... ok
Dec 07 18:37:31.964 INFO [stdout] test probability::frequentist_cdf::test::test_nonzero_pdf ... ok
Dec 07 18:37:32.288 INFO [stdout] test probability::opt_frequentist_cdf::test::test_nonzero_pdf ... ok
Dec 07 18:37:32.292 INFO [stdout] test probability::opt_frequentist_cdf::test::test_sym_to_start_and_freq ... ok
Dec 07 18:37:34.076 INFO [stdout] test probability::frequentist_cdf::test::test_cdf_offset_to_sym_start_and_freq ... ok
Dec 07 18:37:35.558 INFO [stdout] test probability::opt_frequentist_cdf::test::test_cdf_offset_to_sym_start_and_freq ... ok
Dec 07 18:37:35.672 INFO [stdout] test probability::blend_cdf::test::test_nonzero_pdf ... ok
Dec 07 18:37:35.960 INFO [stdout] test probability::blend_cdf::test::test_cdf_offset_to_sym_start_and_freq ... ok
Dec 07 18:37:36.052 INFO [stderr] encoded size: 2928
Dec 07 18:37:36.053 INFO [stderr] effeciency: 1.00311013336588
Dec 07 18:37:36.058 INFO [stderr] done!
Dec 07 18:37:36.068 INFO [stdout] test test_ans::entropy16_big_trait_test ... ok
Dec 07 18:37:36.072 INFO [stderr] encoded size: 24
Dec 07 18:37:36.072 INFO [stderr] effeciency: 2.1054007256147593
Dec 07 18:37:36.072 INFO [stdout] test test_ans::entropy16_lite_trait_test ... ok
Dec 07 18:37:36.072 INFO [stderr] done!
Dec 07 18:37:36.125 INFO [stderr] encoded size: 2928
Dec 07 18:37:36.125 INFO [stderr] effeciency: 0.9933084853811626
Dec 07 18:37:36.129 INFO [stderr] done!
Dec 07 18:37:36.140 INFO [stdout] test test_ans::entropy16_trait_test ... ok
Dec 07 18:37:40.129 INFO [stdout] test probability::opt_frequentist_cdf::test::test_stationary_probability ... ok
Dec 07 18:37:40.836 INFO [stdout] test test_mux::test_balance_mux ... ok
Dec 07 18:37:43.052 INFO [stdout] test test_mux::test_3meg_mux ... ok
Dec 07 18:37:43.400 INFO [stdout] test reader::test::test_experimental_reader_compressor_on_alice_full ... ok
Dec 07 18:37:43.800 INFO [stdout] test test_mux::test_interleaved_mux ... ok
Dec 07 18:37:43.800 INFO [stdout] test test_mux::test_nano_mux ... ok
Dec 07 18:37:43.844 INFO [stdout] test test_mux::test_real_mux ... ok
Dec 07 18:37:43.960 INFO [stdout] test test_mux::test_med_mux ... ok
Dec 07 18:37:43.972 INFO [stdout] test test_mux::test_short_mux ... ok
Dec 07 18:37:44.004 INFO [stdout] test test_mux::test_tiny_mux ... ok
Dec 07 18:37:44.008 INFO [stdout] test test_mux::unit_test_decode_mux ... ok
Dec 07 18:37:44.064 INFO [stdout] test probability::frequentist_cdf::test::test_stationary_probability ... ok
Dec 07 18:37:44.184 INFO [stdout] test test_mux::test_sc3_mux ... ok
Dec 07 18:37:46.228 INFO [stdout] test test_mux::test_long_mux ... ok
Dec 07 18:37:49.997 INFO [stdout] test probability::blend_cdf::test::test_stationary_probability ... ok
Dec 07 18:38:09.313 INFO [stdout] test reader::test::test_hybrid_reader_compressor_on_unicode_full ... ok
Dec 07 18:38:09.889 INFO [stdout] test reader::test::test_hybrid_reader_compressor_on_alice_full ... ok
Dec 07 18:38:13.420 INFO [stdout] test writer::test::test_hybrid_writer_compressor_on_alice_full ... ok
Dec 07 18:38:14.564 INFO [stdout] test writer::test::test_experimental_writer_compressor_on_alice_full ... ok
Dec 07 18:38:16.056 INFO [stdout] test writer::test::test_hybrid_writer_compressor_on_unicode_full ... ok
Dec 07 18:38:16.059 INFO [stdout] test reader::test::test_hybrid_reader_compressor_on_alice_small_buffer ... ok
Dec 07 18:38:18.084 INFO [stdout] test writer::test::test_hybrid_writer_compressor_on_alice_small_buffer ... ok
Dec 07 18:38:18.084 INFO [stdout] 
Dec 07 18:38:18.084 INFO [stdout] test result: ok. 63 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 18:38:18.084 INFO [stdout] 
Dec 07 18:38:18.102 INFO [stderr]      Running /target/debug/deps/divans-843ec4c4bdb3ce9e
Dec 07 18:38:18.102 INFO [stdout] 
Dec 07 18:38:18.102 INFO [stdout] running 19 tests
Dec 07 18:38:18.640 INFO [stdout] test integration_test::test_e2e_64xp ... ok
Dec 07 18:38:18.948 INFO [stdout] test integration_test::test_e2e_32xx ... ok
Dec 07 18:38:19.360 INFO [stdout] test integration_test::test_alice29 ... ok
Dec 07 18:38:19.565 INFO [stdout] test integration_test::test_e2e_empty_br ... ok
Dec 07 18:38:19.698 INFO [stdout] test integration_test::test_asyoulik ... ok
Dec 07 18:38:19.856 INFO [stdout] test integration_test::test_e2e_empty ... ok
Dec 07 18:38:20.205 INFO [stdout] test integration_test::test_e2e_empty_br_tinybuf ... ok
Dec 07 18:38:20.430 INFO [stdout] test integration_test::test_e2e_empty_just_flush ... ok
Dec 07 18:38:20.564 INFO [stdout] test integration_test::test_e2e_262145_at ... ok
Dec 07 18:38:20.568 INFO [stdout] test integration_test::test_e2e_empty_tinybuf ... ok
Dec 07 18:38:20.601 INFO [stdout] test integration_test::test_ends_with_truncated_dictionary ... ok
Dec 07 18:38:21.045 INFO [stdout] test integration_test::test_e2e_ones_tinybuf ... ok
Dec 07 18:38:21.868 INFO [stdout] test benchmark::test_raw_adaptive_literal_stream ... ok
Dec 07 18:38:21.998 INFO [stdout] test integration_test::test_random_then_unicode ... ok
Dec 07 18:38:22.718 INFO [stdout] test benchmark::test_raw_literal_stream ... ok
Dec 07 18:38:38.808 INFO [stdout] test integration_test::test_e2e_smallbuf_without_priors ... ok
Dec 07 18:38:40.525 INFO [stdout] test integration_test::test_e2e_alice ... ok
Dec 07 18:38:40.640 INFO [stdout] test benchmark::test_raw_ir_literal_stream ... ok
Dec 07 18:38:41.155 INFO [stdout] test integration_test::test_e2e_tinybuf ... ok
Dec 07 18:38:41.156 INFO [stdout] 
Dec 07 18:38:41.156 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 18:38:41.156 INFO [stdout] 
Dec 07 18:38:41.163 INFO [stderr]    Doc-tests divans
Dec 07 18:38:42.880 INFO [stdout] 
Dec 07 18:38:42.880 INFO [stdout] running 0 tests
Dec 07 18:38:42.880 INFO [stdout] 
Dec 07 18:38:42.880 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 18:38:42.880 INFO [stdout] 
Dec 07 18:38:42.909 INFO [stderr] su: No module specific data is present
Dec 07 18:38:43.362 INFO running `"docker" "inspect" "da9057f61696d6b1e5517e7d64b28583c2038ea980c0c0117864bfcfdca9e092"`
Dec 07 18:38:43.571 INFO running `"docker" "rm" "-f" "da9057f61696d6b1e5517e7d64b28583c2038ea980c0c0117864bfcfdca9e092"`
Dec 07 18:38:43.737 INFO [stdout] da9057f61696d6b1e5517e7d64b28583c2038ea980c0c0117864bfcfdca9e092