Nov 18 19:13:17.878 INFO testing brotli-2.5.1 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 19:13:17.879 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 19:13:18.078 INFO [stdout] 417160e10eb13062fd27d35d32fa3a49b0e40dca1bbd4bbe197b1a02ca94be85
Nov 18 19:13:18.081 INFO running `"docker" "start" "-a" "417160e10eb13062fd27d35d32fa3a49b0e40dca1bbd4bbe197b1a02ca94be85"`
Nov 18 19:13:18.704 INFO [stderr] usermod: no changes
Nov 18 19:13:18.803 INFO [stderr]    Compiling brotli v2.5.1 (/source)
Nov 18 19:13:37.851 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 19.10s
Nov 18 19:13:37.854 INFO [stderr] su: No module specific data is present
Nov 18 19:13:38.197 INFO running `"docker" "inspect" "417160e10eb13062fd27d35d32fa3a49b0e40dca1bbd4bbe197b1a02ca94be85"`
Nov 18 19:13:38.269 INFO running `"docker" "rm" "-f" "417160e10eb13062fd27d35d32fa3a49b0e40dca1bbd4bbe197b1a02ca94be85"`
Nov 18 19:13:38.391 INFO [stdout] 417160e10eb13062fd27d35d32fa3a49b0e40dca1bbd4bbe197b1a02ca94be85
Nov 18 19:13:38.394 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 19:13:38.635 INFO [stdout] 3c910268a3f2fb0b7d0eb3b913e5049c97aff60306ea73229a0de66d9de05b6c
Nov 18 19:13:38.641 INFO running `"docker" "start" "-a" "3c910268a3f2fb0b7d0eb3b913e5049c97aff60306ea73229a0de66d9de05b6c"`
Nov 18 19:13:39.871 INFO [stderr] usermod: no changes
Nov 18 19:13:39.938 INFO [stderr]    Compiling brotli v2.5.1 (/source)
Nov 18 19:14:10.693 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 30.79s
Nov 18 19:14:10.699 INFO [stderr] su: No module specific data is present
Nov 18 19:14:11.052 INFO running `"docker" "inspect" "3c910268a3f2fb0b7d0eb3b913e5049c97aff60306ea73229a0de66d9de05b6c"`
Nov 18 19:14:11.209 INFO running `"docker" "rm" "-f" "3c910268a3f2fb0b7d0eb3b913e5049c97aff60306ea73229a0de66d9de05b6c"`
Nov 18 19:14:11.450 INFO [stdout] 3c910268a3f2fb0b7d0eb3b913e5049c97aff60306ea73229a0de66d9de05b6c
Nov 18 19:14:11.460 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 19:14:11.771 INFO [stdout] 97779e1cace873bc4ddb8142a4bca6cd7b1e45ed8cb1850c0e203c143d36337f
Nov 18 19:14:11.775 INFO running `"docker" "start" "-a" "97779e1cace873bc4ddb8142a4bca6cd7b1e45ed8cb1850c0e203c143d36337f"`
Nov 18 19:14:12.536 INFO [stderr] usermod: no changes
Nov 18 19:14:12.611 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.04s
Nov 18 19:14:12.623 INFO [stderr]      Running /target/debug/deps/brotli-5b37d96006284401
Nov 18 19:14:12.628 INFO [stdout] 
Nov 18 19:14:12.628 INFO [stdout] running 19 tests
Nov 18 19:14:12.651 INFO [stdout] test enc::interface::test::test_u8_to_speed ... ok
Nov 18 19:14:12.651 INFO [stdout] test enc::command::test::test_command_return_distance_index_offset ... ok
Nov 18 19:14:12.685 INFO [stdout] test enc::test::test_roundtrip_10x10y ... ok
Nov 18 19:14:12.685 INFO [stdout] test enc::test::test_roundtrip_64x ... ok
Nov 18 19:14:12.747 INFO [stdout] test enc::test::test_roundtrip_empty ... ok
Nov 18 19:14:12.963 INFO [stdout] test enc::test::test_roundtrip_monkey ... ok
Nov 18 19:14:12.995 INFO [stdout] test enc::test::test_roundtrip_compressed ... ok
Nov 18 19:14:13.051 INFO [stdout] test enc::test::test_roundtrip_quickfox ... ok
Nov 18 19:14:13.066 INFO [stdout] test enc::test::test_roundtrip_ukkonooa ... ok
Nov 18 19:14:13.211 INFO [stdout] test enc::test::test_roundtrip_x ... ok
Nov 18 19:14:13.211 INFO [stdout] test enc::util::test::fast_log2_works ... ok
Nov 18 19:14:13.211 INFO [stdout] test enc::util::test::log2floor_non_zero_works ... ok
Nov 18 19:14:13.463 INFO [stdout] test enc::test::test_roundtrip_backward65536 ... ok
Nov 18 19:14:13.467 INFO [stdout] test enc::test::test_roundtrip_aaabaaaa ... ok
Nov 18 19:14:13.667 INFO [stdout] test enc::test::test_roundtrip_compressed_repeated ... ok
Nov 18 19:14:13.863 INFO [stdout] test enc::test::test_roundtrip_quickfox_repeated ... ok
Nov 18 19:14:14.135 INFO [stdout] test enc::test::test_roundtrip_asyoulik ... ok
Nov 18 19:14:14.815 INFO [stdout] test enc::static_dict::test::test_find_match_length ... ok
Nov 18 19:14:15.397 INFO [stdout] test enc::test::test_roundtrip_asyoulik9_5 ... ok
Nov 18 19:14:15.397 INFO [stdout] 
Nov 18 19:14:15.397 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:14:15.398 INFO [stdout] 
Nov 18 19:14:15.402 INFO [stdout] 
Nov 18 19:14:15.403 INFO [stderr]      Running /target/debug/deps/brotli-253041ce3b9d9887
Nov 18 19:14:15.403 INFO [stdout] running 62 tests
Nov 18 19:14:15.435 INFO [stdout] test integration_tests::test_10x_10y ... ok
Nov 18 19:14:15.463 INFO [stdout] test integration_tests::test_64x ... ok
Nov 18 19:14:15.463 INFO [stderr] 1 Iterations; Time 0.027976360
Nov 18 19:14:15.463 INFO [stdout] test integration_tests::test_10x_10y_one_out_byte ... ok
Nov 18 19:14:15.468 INFO [stdout] test integration_tests::test_10x_10y_byte_by_byte ... ok
Nov 18 19:14:15.469 INFO [stderr] 1 Iterations; Time 0.038778122
Nov 18 19:14:15.595 INFO [stdout] test integration_tests::test_backward65536 ... ok
Nov 18 19:14:15.607 INFO [stdout] test integration_tests::test_compressed_file ... ok
Nov 18 19:14:15.615 INFO [stdout] test integration_tests::test_empty ... ok
Nov 18 19:14:15.616 INFO [stdout] test integration_tests::test_empty0 ... ok
Nov 18 19:14:15.619 INFO [stdout] test integration_tests::test_empty1 ... ok
Nov 18 19:14:15.619 INFO [stdout] test integration_tests::test_empty10 ... ok
Nov 18 19:14:15.620 INFO [stdout] test integration_tests::test_empty11 ... ok
Nov 18 19:14:15.620 INFO [stdout] test integration_tests::test_empty12 ... ok
Nov 18 19:14:15.620 INFO [stdout] test integration_tests::test_empty13 ... ok
Nov 18 19:14:15.620 INFO [stdout] test integration_tests::test_empty14 ... ok
Nov 18 19:14:15.620 INFO [stdout] test integration_tests::test_empty15 ... ok
Nov 18 19:14:15.620 INFO [stdout] test integration_tests::test_empty16 ... ok
Nov 18 19:14:15.796 INFO [stdout] test integration_tests::test_compressed_repeated ... ok
Nov 18 19:14:15.847 INFO [stdout] test integration_tests::test_alice29 ... ok
Nov 18 19:14:15.852 INFO [stdout] test integration_tests::test_empty2 ... ok
Nov 18 19:14:15.852 INFO [stdout] test integration_tests::test_empty3 ... ok
Nov 18 19:14:15.853 INFO [stdout] test integration_tests::test_empty4 ... ok
Nov 18 19:14:15.861 INFO [stdout] test integration_tests::test_empty5 ... ok
Nov 18 19:14:15.861 INFO [stdout] test integration_tests::test_empty6 ... ok
Nov 18 19:14:15.879 INFO [stdout] test integration_tests::test_empty7 ... ok
Nov 18 19:14:15.879 INFO [stdout] test integration_tests::test_empty8 ... ok
Nov 18 19:14:15.895 INFO [stdout] test integration_tests::test_empty9 ... ok
Nov 18 19:14:15.911 INFO [stdout] test integration_tests::test_as_you_like_it ... ok
Nov 18 19:14:16.098 INFO [stdout] test integration_tests::test_empty17 ... ok
Nov 18 19:14:16.204 INFO [stdout] test integration_tests::test_empty18 ... ok
Nov 18 19:14:16.219 INFO [stdout] test integration_tests::test_negative_hypothesis ... ok
Nov 18 19:14:16.495 INFO [stderr] 1 Iterations; Time 0.944501161
Nov 18 19:14:16.499 INFO [stdout] test integration_tests::test_alice1 ... ok
Nov 18 19:14:17.074 INFO [stdout] test integration_tests::test_random_then_unicode_2 ... ok
Nov 18 19:14:17.383 INFO [stdout] test integration_tests::test_random_then_unicode_3 ... ok
Nov 18 19:14:18.123 INFO [stdout] test integration_tests::test_random_then_unicode_0 ... ok
Nov 18 19:14:18.267 INFO [stdout] test integration_tests::test_random_then_unicode_1 ... ok
Nov 18 19:14:18.575 INFO [stdout] test integration_tests::test_random_then_unicode_4 ... ok
Nov 18 19:14:19.990 INFO [stdout] test integration_tests::test_random_then_unicode_5 ... ok
Nov 18 19:14:20.163 INFO [stdout] test integration_tests::test_1024k ... ok
Nov 18 19:14:21.927 INFO [stdout] test integration_tests::test_random_then_unicode_7 ... ok
Nov 18 19:14:21.931 INFO [stdout] test integration_tests::test_reader_64x ... ok
Nov 18 19:14:22.167 INFO [stdout] test integration_tests::test_random_then_unicode_6 ... ok
Nov 18 19:14:23.187 INFO [stdout] test integration_tests::test_random_then_unicode_8 ... ok
Nov 18 19:14:24.587 INFO [stdout] test integration_tests::test_random_then_unicode_9 ... ok
Nov 18 19:14:25.691 INFO [stdout] test integration_tests::test_reader_quickfox_repeated ... ok
Nov 18 19:14:25.707 INFO [stdout] test integration_tests::test_roundtrip_64x ... ok
Nov 18 19:14:28.135 INFO [stdout] test integration_tests::test_reader_as_you_lik ... ok
Nov 18 19:14:28.159 INFO [stdout] test integration_tests::test_reader_alice ... ok
Nov 18 19:14:32.787 INFO [stdout] test integration_tests::test_alice29_10 ... ok
Nov 18 19:14:32.803 INFO [stdout] test integration_tests::test_writer_64x ... ok
Nov 18 19:14:33.903 INFO [stdout] test integration_tests::test_roundtrip_quickfox_repeated ... ok
Nov 18 19:14:37.935 INFO [stdout] test integration_tests::test_reader_random_then_unicode ... ok
Nov 18 19:14:38.422 INFO [stdout] test integration_tests::test_writer_as_you_lik ... ok
Nov 18 19:14:38.701 INFO [stdout] test integration_tests::test_writer_quickfox_repeated ... ok
Nov 18 19:14:38.701 INFO [stdout] test tests::test_10x_10y ... ok
Nov 18 19:14:40.528 INFO [stdout] test integration_tests::test_writer_alice ... ok
Nov 18 19:14:40.556 INFO [stdout] test tests::test_alice ... ok
Nov 18 19:14:40.734 INFO [stdout] test integration_tests::test_random_then_unicode_9_5x ... ok
Nov 18 19:14:42.103 INFO [stdout] test integration_tests::test_random_then_unicode_9_5 ... ok
Nov 18 19:14:43.548 INFO [stdout] test integration_tests::test_roundtrip_as_you_lik ... ok
Nov 18 19:14:44.646 INFO [stdout] test integration_tests::test_writer_random_then_unicode ... ok
Nov 18 19:14:44.929 INFO [stdout] test integration_tests::test_alice29_11 ... ok
Nov 18 19:14:45.864 INFO [stdout] test integration_tests::test_roundtrip_alice29 ... ok
Nov 18 19:14:45.864 INFO [stdout] 
Nov 18 19:14:45.864 INFO [stdout] test result: ok. 62 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:14:45.864 INFO [stdout] 
Nov 18 19:14:45.873 INFO [stderr]    Doc-tests brotli
Nov 18 19:14:48.952 INFO [stdout] 
Nov 18 19:14:48.952 INFO [stdout] running 0 tests
Nov 18 19:14:48.952 INFO [stdout] 
Nov 18 19:14:48.952 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:14:48.952 INFO [stdout] 
Nov 18 19:14:49.025 INFO [stderr] su: No module specific data is present
Nov 18 19:14:49.340 INFO running `"docker" "inspect" "97779e1cace873bc4ddb8142a4bca6cd7b1e45ed8cb1850c0e203c143d36337f"`
Nov 18 19:14:49.473 INFO running `"docker" "rm" "-f" "97779e1cace873bc4ddb8142a4bca6cd7b1e45ed8cb1850c0e203c143d36337f"`
Nov 18 19:14:49.627 INFO [stdout] 97779e1cace873bc4ddb8142a4bca6cd7b1e45ed8cb1850c0e203c143d36337f