Dec 15 20:42:18.762 INFO testing brotli-3.1.5 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 15 20:42:18.762 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 15 20:42:18.966 INFO [stdout] 94af586f6a2cf4216374ed4c705140c33641bea198ab228f617a9a6ce0b55b95
Dec 15 20:42:18.969 INFO running `"docker" "start" "-a" "94af586f6a2cf4216374ed4c705140c33641bea198ab228f617a9a6ce0b55b95"`
Dec 15 20:42:19.424 INFO [stderr] usermod: no changes
Dec 15 20:42:19.460 INFO [stderr]    Compiling alloc-no-stdlib v2.0.1
Dec 15 20:42:19.657 INFO [stderr]    Compiling alloc-stdlib v0.2.1
Dec 15 20:42:20.307 INFO [stderr]    Compiling brotli-decompressor v2.1.1
Dec 15 20:42:26.044 INFO [stderr]    Compiling brotli v3.1.5 (/source)
Dec 15 20:42:53.631 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 34.18s
Dec 15 20:42:53.642 INFO [stderr] su: No module specific data is present
Dec 15 20:42:54.646 INFO running `"docker" "inspect" "94af586f6a2cf4216374ed4c705140c33641bea198ab228f617a9a6ce0b55b95"`
Dec 15 20:42:54.937 INFO running `"docker" "rm" "-f" "94af586f6a2cf4216374ed4c705140c33641bea198ab228f617a9a6ce0b55b95"`
Dec 15 20:42:55.207 INFO [stdout] 94af586f6a2cf4216374ed4c705140c33641bea198ab228f617a9a6ce0b55b95
Dec 15 20:42:55.209 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 15 20:42:55.731 INFO [stdout] cf791adfa6e3a0e930c8314dadb6eed640df9045342c94583c4297b65ed3822c
Dec 15 20:42:55.735 INFO running `"docker" "start" "-a" "cf791adfa6e3a0e930c8314dadb6eed640df9045342c94583c4297b65ed3822c"`
Dec 15 20:42:56.463 INFO [stderr] usermod: no changes
Dec 15 20:42:56.491 INFO [stderr]    Compiling brotli v3.1.5 (/source)
Dec 15 20:43:26.131 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 29.68s
Dec 15 20:43:26.134 INFO [stderr] su: No module specific data is present
Dec 15 20:43:26.501 INFO running `"docker" "inspect" "cf791adfa6e3a0e930c8314dadb6eed640df9045342c94583c4297b65ed3822c"`
Dec 15 20:43:26.616 INFO running `"docker" "rm" "-f" "cf791adfa6e3a0e930c8314dadb6eed640df9045342c94583c4297b65ed3822c"`
Dec 15 20:43:26.740 INFO [stdout] cf791adfa6e3a0e930c8314dadb6eed640df9045342c94583c4297b65ed3822c
Dec 15 20:43:26.743 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 15 20:43:27.045 INFO [stdout] 03faa80ddae3d1b8653e84cec9d127555ac6b2080c27b3413db043655c684a8b
Dec 15 20:43:27.047 INFO running `"docker" "start" "-a" "03faa80ddae3d1b8653e84cec9d127555ac6b2080c27b3413db043655c684a8b"`
Dec 15 20:43:27.428 INFO [stderr] usermod: no changes
Dec 15 20:43:27.465 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Dec 15 20:43:27.468 INFO [stderr]      Running /target/debug/deps/brotli-8f04916581b90f0e
Dec 15 20:43:27.470 INFO [stdout] 
Dec 15 20:43:27.470 INFO [stdout] running 34 tests
Dec 15 20:43:27.470 INFO [stdout] test concat::test::test_deserialization_any_written ... ok
Dec 15 20:43:27.470 INFO [stdout] test concat::test::test_serialization ... ok
Dec 15 20:43:27.470 INFO [stdout] test concat::test::test_cat_empty_stream ... ok
Dec 15 20:43:27.471 INFO [stdout] test enc::interface::test::test_u8_to_speed ... ok
Dec 15 20:43:27.471 INFO [stdout] test enc::command::test::test_command_return_distance_index_offset ... ok
Dec 15 20:43:27.471 INFO [stdout] test concat::test::test_deserialization ... ok
Dec 15 20:43:27.507 INFO [stdout] test enc::test::test_roundtrip_10x10y ... ok
Dec 15 20:43:27.539 INFO [stdout] test enc::test::test_roundtrip_64x ... ok
Dec 15 20:43:27.638 INFO [stdout] test enc::test::test_roundtrip_aaabaaaa ... ok
Dec 15 20:43:27.645 INFO [stdout] test enc::test::test_roundtrip_backward65536 ... ok
Dec 15 20:43:27.815 INFO [stdout] test enc::test::test_roundtrip_compressed ... ok
Dec 15 20:43:27.906 INFO [stdout] test enc::test::test_roundtrip_empty ... ok
Dec 15 20:43:28.001 INFO [stdout] test enc::test::test_roundtrip_first_2_bytes_alice ... ok
Dec 15 20:43:28.040 INFO [stdout] test enc::test::test_roundtrip_first_58_bytes_alice ... ok
Dec 15 20:43:28.195 INFO [stdout] test enc::test::test_roundtrip_monkey ... ok
Dec 15 20:43:28.219 INFO [stdout] test enc::test::test_roundtrip_compressed_repeated ... ok
Dec 15 20:43:28.411 INFO [stdout] test enc::test::test_roundtrip_quickfox ... ok
Dec 15 20:43:28.610 INFO [stdout] test enc::test::test_roundtrip_ukkonooa ... ok
Dec 15 20:43:28.662 INFO [stdout] test enc::test::test_roundtrip_x ... ok
Dec 15 20:43:28.667 INFO [stdout] test enc::util::test::fast_log2_works ... ok
Dec 15 20:43:28.667 INFO [stdout] test enc::util::test::log2floor_non_zero_works ... ok
Dec 15 20:43:28.711 INFO [stdout] test ffi::multicompress::test::test_compress_empty_multi_catable ... ok
Dec 15 20:43:28.775 INFO [stdout] test ffi::multicompress::test::test_compress_empty_multi_raw ... ok
Dec 15 20:43:28.803 INFO [stdout] test ffi::multicompress::test::test_compress_empty_multi_raw_one_thread ... ok
Dec 15 20:43:28.820 INFO [stdout] test ffi::multicompress::test::test_compress_empty_workpool ... ok
Dec 15 20:43:28.863 INFO [stdout] test ffi::multicompress::test::test_compress_null_multi_raw ... ok
Dec 15 20:43:28.991 INFO [stdout] test enc::test::test_roundtrip_quickfox_repeated ... ok
Dec 15 20:43:29.096 INFO [stdout] test ffi::multicompress::test::test_compress_workpool ... ok
Dec 15 20:43:29.099 INFO [stdout] test enc::test::test_roundtrip_asyoulik ... ok
Dec 15 20:43:29.107 INFO [stdout] test enc::backward_references::test::test_bulk_store_range_pow2 ... ok
Dec 15 20:43:29.223 INFO [stdout] test enc::static_dict::test::test_find_match_length ... ok
Dec 15 20:43:29.480 INFO [stdout] test enc::backward_references::test::test_bulk_store_range ... ok
Dec 15 20:43:30.083 INFO [stdout] test enc::backward_references::test::test_bulk_store_range_off_spec ... ok
Dec 15 20:43:30.471 INFO [stdout] test enc::test::test_roundtrip_asyoulik9_5 ... ok
Dec 15 20:43:30.471 INFO [stdout] 
Dec 15 20:43:30.471 INFO [stdout] test result: ok. 34 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 20:43:30.471 INFO [stdout] 
Dec 15 20:43:30.480 INFO [stderr]      Running /target/debug/deps/brotli-067dfd251a9bc2b6
Dec 15 20:43:30.482 INFO [stdout] 
Dec 15 20:43:30.482 INFO [stdout] running 91 tests
Dec 15 20:43:30.489 INFO [stderr] 1 Iterations; Time 0.000856371
Dec 15 20:43:30.493 INFO [stdout] test integration_tests::test_10x_10y ... ok
Dec 15 20:43:30.494 INFO [stdout] test integration_tests::test_10x_10y_byte_by_byte ... ok
Dec 15 20:43:30.494 INFO [stdout] test integration_tests::test_64x ... ok
Dec 15 20:43:30.504 INFO [stderr] 1 Iterations; Time 0.000841745
Dec 15 20:43:30.511 INFO [stdout] test integration_tests::test_10x_10y_one_out_byte ... ok
Dec 15 20:43:30.523 INFO [stdout] test integration_tests::test_backward65536 ... ok
Dec 15 20:43:30.612 INFO [stdout] test integration_tests::test_compressed_file ... ok
Dec 15 20:43:30.636 INFO [stdout] test integration_tests::test_empty ... ok
Dec 15 20:43:30.762 INFO [stdout] test integration_tests::test_empty0 ... ok
Dec 15 20:43:30.780 INFO [stdout] test integration_tests::test_empty1 ... ok
Dec 15 20:43:30.807 INFO [stdout] test integration_tests::test_empty10 ... ok
Dec 15 20:43:30.837 INFO [stdout] test integration_tests::test_empty11 ... ok
Dec 15 20:43:30.846 INFO [stdout] test integration_tests::test_empty12 ... ok
Dec 15 20:43:30.874 INFO [stdout] test integration_tests::test_empty13 ... ok
Dec 15 20:43:30.886 INFO [stdout] test integration_tests::test_empty14 ... ok
Dec 15 20:43:30.914 INFO [stdout] test integration_tests::test_as_you_like_it ... ok
Dec 15 20:43:30.915 INFO [stdout] test integration_tests::test_empty16 ... ok
Dec 15 20:43:30.930 INFO [stdout] test integration_tests::test_compressed_repeated ... ok
Dec 15 20:43:30.974 INFO [stdout] test integration_tests::test_alice29 ... ok
Dec 15 20:43:30.975 INFO [stdout] test integration_tests::test_empty15 ... ok
Dec 15 20:43:30.990 INFO [stdout] test integration_tests::test_empty3 ... ok
Dec 15 20:43:30.991 INFO [stdout] test integration_tests::test_empty2 ... ok
Dec 15 20:43:30.991 INFO [stdout] test integration_tests::test_empty4 ... ok
Dec 15 20:43:30.991 INFO [stdout] test integration_tests::test_empty6 ... ok
Dec 15 20:43:30.991 INFO [stdout] test integration_tests::test_empty5 ... ok
Dec 15 20:43:30.999 INFO [stdout] test integration_tests::test_empty8 ... ok
Dec 15 20:43:30.999 INFO [stdout] test integration_tests::test_empty7 ... ok
Dec 15 20:43:30.999 INFO [stdout] test integration_tests::test_empty9 ... ok
Dec 15 20:43:31.207 INFO [stdout] test integration_tests::test_negative_hypothesis ... ok
Dec 15 20:43:31.229 INFO [stdout] test integration_tests::test_empty18 ... ok
Dec 15 20:43:31.767 INFO [stdout] test integration_tests::test_empty17 ... ok
Dec 15 20:43:32.259 INFO [stdout] test integration_tests::test_alice1 ... ok
Dec 15 20:43:32.259 INFO [stderr] 1 Iterations; Time 1.620694704
Dec 15 20:43:32.459 INFO [stdout] test integration_tests::test_random_then_unicode_2 ... ok
Dec 15 20:43:35.051 INFO [stdout] test integration_tests::test_random_then_unicode_3 ... ok
Dec 15 20:43:35.479 INFO [stdout] test integration_tests::test_random_then_unicode_0 ... ok
Dec 15 20:43:35.703 INFO [stdout] test integration_tests::test_random_then_unicode_1 ... ok
Dec 15 20:43:35.723 INFO [stdout] test integration_tests::test_random_then_unicode_4 ... ok
Dec 15 20:43:37.651 INFO [stdout] test integration_tests::test_random_then_unicode_5 ... ok
Dec 15 20:43:40.019 INFO [stdout] test integration_tests::test_random_then_unicode_8 ... ok
Dec 15 20:43:40.023 INFO [stdout] test integration_tests::test_random_then_unicode_6 ... ok
Dec 15 20:43:40.027 INFO [stdout] test integration_tests::test_reader_64x ... ok
Dec 15 20:43:40.343 INFO [stdout] test integration_tests::test_1024k ... ok
Dec 15 20:43:42.231 INFO [stdout] test integration_tests::test_random_then_unicode_7 ... ok
Dec 15 20:43:43.343 INFO [stdout] test integration_tests::test_random_then_unicode_9 ... ok
Dec 15 20:43:44.055 INFO [stdout] test integration_tests::test_alice29_10 ... ok
Dec 15 20:43:44.063 INFO [stdout] test integration_tests::test_roundtrip_64x ... ok
Dec 15 20:43:44.659 INFO [stdout] test integration_tests::test_reader_quickfox_repeated ... ok
Dec 15 20:43:45.752 INFO [stdout] test integration_tests::test_reader_as_you_lik ... ok
Dec 15 20:43:47.751 INFO [stdout] test integration_tests::test_reader_alice ... ok
Dec 15 20:43:47.931 INFO [stdout] test integration_tests::test_ukkonooa ... ok
Dec 15 20:43:47.935 INFO [stdout] test integration_tests::test_writer_64x ... ok
Dec 15 20:43:51.443 INFO [stdout] test integration_tests::test_roundtrip_quickfox_repeated ... ok
Dec 15 20:43:53.868 INFO [stdout] test integration_tests::test_writer_alice ... ok
Dec 15 20:43:54.572 INFO [stdout] test integration_tests::test_alice29_11 ... ok
Dec 15 20:43:55.212 INFO [stdout] test integration_tests::test_writer_quickfox_repeated ... ok
Dec 15 20:43:55.886 INFO [stdout] test test_broccoli::test_append_then_cat_works ... ok
Dec 15 20:43:55.937 INFO [stdout] test integration_tests::test_writer_as_you_lik ... ok
Dec 15 20:43:56.022 INFO [stdout] test integration_tests::test_reader_random_then_unicode ... ok
Dec 15 20:43:56.024 INFO [stdout] test test_broccoli::test_append_then_empty_works ... ok
Dec 15 20:43:57.563 INFO [stdout] test test_broccoli::test_appendonly_twice_fails ... ok
Dec 15 20:43:57.635 INFO [stdout] test test_broccoli::test_one_byte_before_works ... ok
Dec 15 20:43:57.667 INFO [stdout] test test_broccoli::test_one_byte_works ... ok
Dec 15 20:43:57.771 INFO [stdout] test test_broccoli::test_two_byte_before_works ... ok
Dec 15 20:43:57.847 INFO [stdout] test test_broccoli::test_two_byte_works ... ok
Dec 15 20:43:57.935 INFO [stdout] test test_broccoli::test_empty_then_cat_works ... ok
Dec 15 20:44:00.060 INFO [stdout] test integration_tests::test_random_then_unicode_9_5 ... ok
Dec 15 20:44:02.655 INFO [stdout] test integration_tests::test_writer_random_then_unicode ... ok
Dec 15 20:44:03.927 INFO [stdout] test integration_tests::test_roundtrip_as_you_lik ... ok
Dec 15 20:44:03.956 INFO [stdout] test test_threading::multi_threaded_split_compression_test_0b1 ... ok
Dec 15 20:44:03.967 INFO [stdout] test test_threading::multi_threaded_split_compression_test_0b5 ... ok
Dec 15 20:44:04.077 INFO [stdout] test test_custom_dict::test_custom_wrong_dict_fails_but_doesnt_disrupt_compression_strategy ... ok
Dec 15 20:44:04.116 INFO [stdout] test test_threading::multi_threaded_split_compression_test_1b1 ... ok
Dec 15 20:44:04.127 INFO [stdout] test test_threading::multi_threaded_split_compression_test_1b5 ... ok
Dec 15 20:44:05.127 INFO [stdout] test integration_tests::test_random_then_unicode_9x5 ... ok
Dec 15 20:44:06.759 INFO [stdout] test test_custom_dict::test_custom_dict_for_multithreading ... ok
Dec 15 20:44:06.935 INFO [stdout] test test_threading::multi_threaded_split_compression_test_2 ... ok
Dec 15 20:44:07.427 INFO [stdout] test test_threading::multi_threaded_split_compression_test_1 ... ok
Dec 15 20:44:07.427 INFO [stdout] test test_threading::thread_spawn_per_job_split_compression_test_0b1 ... ok
Dec 15 20:44:07.628 INFO [stdout] test integration_tests::test_roundtrip_alice29 ... ok
Dec 15 20:44:07.658 INFO [stdout] test test_threading::thread_spawn_per_job_split_compression_test_0b3 ... ok
Dec 15 20:44:07.671 INFO [stdout] test test_threading::thread_spawn_per_job_split_compression_test_1b1 ... ok
Dec 15 20:44:07.735 INFO [stdout] test test_threading::thread_spawn_per_job_split_compression_test_1b3 ... ok
Dec 15 20:44:08.391 INFO [stdout] test test_threading::multi_threaded_split_compression_test_3 ... ok
Dec 15 20:44:08.399 INFO [stdout] test tests::test_10x_10y ... ok
Dec 15 20:44:09.323 INFO [stdout] test test_custom_dict::test_custom_dict ... ok
Dec 15 20:44:09.702 INFO [stdout] test test_threading::thread_spawn_per_job_split_compression_test_3 ... ok
Dec 15 20:44:10.038 INFO [stdout] test test_threading::thread_spawn_per_job_split_compression_test_1 ... ok
Dec 15 20:44:10.243 INFO [stdout] test test_threading::multi_threaded_split_compression_test_5 ... ok
Dec 15 20:44:10.592 INFO [stdout] test tests::test_alice ... ok
Dec 15 20:44:11.783 INFO [stdout] test test_custom_dict::test_custom_wrong_dict_fails ... ok
Dec 15 20:44:12.091 INFO [stdout] test test_broccoli::test_concat ... ok
Dec 15 20:44:13.031 INFO [stdout] test test_threading::multi_threaded_split_compression_test_4 ... ok
Dec 15 20:44:13.031 INFO [stdout] 
Dec 15 20:44:13.031 INFO [stdout] test result: ok. 91 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 20:44:13.031 INFO [stdout] 
Dec 15 20:44:13.046 INFO [stdout] 
Dec 15 20:44:13.046 INFO [stderr]      Running /target/debug/deps/catbrotli-ea7c675ae922dc47
Dec 15 20:44:13.046 INFO [stdout] running 0 tests
Dec 15 20:44:13.046 INFO [stderr]    Doc-tests brotli
Dec 15 20:44:13.046 INFO [stdout] 
Dec 15 20:44:13.046 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 20:44:13.046 INFO [stdout] 
Dec 15 20:44:15.499 INFO [stdout] 
Dec 15 20:44:15.499 INFO [stdout] running 0 tests
Dec 15 20:44:15.499 INFO [stdout] 
Dec 15 20:44:15.499 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 20:44:15.499 INFO [stdout] 
Dec 15 20:44:15.535 INFO [stderr] su: No module specific data is present
Dec 15 20:44:15.867 INFO running `"docker" "inspect" "03faa80ddae3d1b8653e84cec9d127555ac6b2080c27b3413db043655c684a8b"`
Dec 15 20:44:16.101 INFO running `"docker" "rm" "-f" "03faa80ddae3d1b8653e84cec9d127555ac6b2080c27b3413db043655c684a8b"`
Dec 15 20:44:16.395 INFO [stdout] 03faa80ddae3d1b8653e84cec9d127555ac6b2080c27b3413db043655c684a8b