Dec 15 09:37:01.809 INFO testing grep-searcher-0.1.1 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 15 09:37:01.809 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 09:37:02.017 INFO [stdout] 6cab9bb4d5062f49e6e910d0cf844e169beda101604e825c37d1bb8dded02cb7
Dec 15 09:37:02.019 INFO running `"docker" "start" "-a" "6cab9bb4d5062f49e6e910d0cf844e169beda101604e825c37d1bb8dded02cb7"`
Dec 15 09:37:02.595 INFO [stderr] usermod: no changes
Dec 15 09:37:02.719 INFO [stderr]    Compiling encoding_rs_io v0.1.3
Dec 15 09:37:02.727 INFO [stderr]    Compiling grep-matcher v0.1.1
Dec 15 09:37:04.059 INFO [stderr]    Compiling grep-searcher v0.1.1 (/source)
Dec 15 09:37:05.787 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.15s
Dec 15 09:37:05.787 INFO [stderr] su: No module specific data is present
Dec 15 09:37:06.551 INFO running `"docker" "inspect" "6cab9bb4d5062f49e6e910d0cf844e169beda101604e825c37d1bb8dded02cb7"`
Dec 15 09:37:06.979 INFO running `"docker" "rm" "-f" "6cab9bb4d5062f49e6e910d0cf844e169beda101604e825c37d1bb8dded02cb7"`
Dec 15 09:37:07.335 INFO [stdout] 6cab9bb4d5062f49e6e910d0cf844e169beda101604e825c37d1bb8dded02cb7
Dec 15 09:37:07.343 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 09:37:07.726 INFO [stdout] e7b5b8c54702621ba4a41c60022a7736d7c032ab372006e4f8200c4425a29bd9
Dec 15 09:37:07.729 INFO running `"docker" "start" "-a" "e7b5b8c54702621ba4a41c60022a7736d7c032ab372006e4f8200c4425a29bd9"`
Dec 15 09:37:09.271 INFO [stderr] usermod: no changes
Dec 15 09:37:09.399 INFO [stderr]    Compiling grep-regex v0.1.1
Dec 15 09:37:11.773 INFO [stderr]    Compiling grep-searcher v0.1.1 (/source)
Dec 15 09:37:18.859 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.56s
Dec 15 09:37:18.871 INFO [stderr] su: No module specific data is present
Dec 15 09:37:19.559 INFO running `"docker" "inspect" "e7b5b8c54702621ba4a41c60022a7736d7c032ab372006e4f8200c4425a29bd9"`
Dec 15 09:37:19.913 INFO running `"docker" "rm" "-f" "e7b5b8c54702621ba4a41c60022a7736d7c032ab372006e4f8200c4425a29bd9"`
Dec 15 09:37:20.198 INFO [stdout] e7b5b8c54702621ba4a41c60022a7736d7c032ab372006e4f8200c4425a29bd9
Dec 15 09:37:20.205 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 09:37:20.671 INFO [stdout] a3b975ef774a029c2276569294418fa434cc41c19149f247ecb41976895bb574
Dec 15 09:37:20.672 INFO running `"docker" "start" "-a" "a3b975ef774a029c2276569294418fa434cc41c19149f247ecb41976895bb574"`
Dec 15 09:37:21.288 INFO [stderr] usermod: no changes
Dec 15 09:37:21.347 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.04s
Dec 15 09:37:21.349 INFO [stderr]      Running /target/debug/deps/grep_searcher-b669de4a355d6731
Dec 15 09:37:21.351 INFO [stdout] 
Dec 15 09:37:21.351 INFO [stdout] running 75 tests
Dec 15 09:37:21.351 INFO [stdout] test line_buffer::tests::buffer_binary_convert4 ... ok
Dec 15 09:37:21.352 INFO [stdout] test line_buffer::tests::buffer_basics1 ... ok
Dec 15 09:37:21.352 INFO [stdout] test line_buffer::tests::buffer_basics3 ... ok
Dec 15 09:37:21.352 INFO [stdout] test line_buffer::tests::buffer_basics4 ... ok
Dec 15 09:37:21.352 INFO [stdout] test line_buffer::tests::buffer_basics2 ... ok
Dec 15 09:37:21.352 INFO [stdout] test line_buffer::tests::buffer_binary_convert1 ... ok
Dec 15 09:37:21.352 INFO [stdout] test line_buffer::tests::buffer_binary_convert3 ... ok
Dec 15 09:37:21.352 INFO [stdout] test line_buffer::tests::buffer_binary_convert2 ... ok
Dec 15 09:37:21.352 INFO [stdout] test line_buffer::tests::buffer_binary_quit1 ... ok
Dec 15 09:37:21.352 INFO [stdout] test line_buffer::tests::buffer_binary_none ... ok
Dec 15 09:37:21.352 INFO [stdout] test line_buffer::tests::buffer_empty ... ok
Dec 15 09:37:21.353 INFO [stdout] test line_buffer::tests::buffer_binary_quit2 ... ok
Dec 15 09:37:21.353 INFO [stdout] test line_buffer::tests::buffer_limited_capacity1 ... ok
Dec 15 09:37:21.353 INFO [stdout] test line_buffer::tests::buffer_limited_capacity2 ... ok
Dec 15 09:37:21.353 INFO [stdout] test line_buffer::tests::buffer_limited_capacity3 ... ok
Dec 15 09:37:21.353 INFO [stdout] test line_buffer::tests::buffer_small_capacity ... ok
Dec 15 09:37:21.353 INFO [stdout] test line_buffer::tests::buffer_zero_capacity ... ok
Dec 15 09:37:21.353 INFO [stdout] test line_buffer::tests::replace ... ok
Dec 15 09:37:21.353 INFO [stdout] test lines::tests::line_count ... ok
Dec 15 09:37:21.353 INFO [stdout] test lines::tests::line_locate_weird ... ok
Dec 15 09:37:21.353 INFO [stdout] test lines::tests::preceding_lines_doc ... ok
Dec 15 09:37:21.354 INFO [stdout] test line_buffer::tests::buffer_binary_quit5 ... ok
Dec 15 09:37:21.354 INFO [stdout] test lines::tests::preceding_lines_empty1 ... ok
Dec 15 09:37:21.354 INFO [stdout] test line_buffer::tests::buffer_binary_quit4 ... ok
Dec 15 09:37:21.354 INFO [stdout] test line_buffer::tests::buffer_binary_quit3 ... ok
Dec 15 09:37:21.354 INFO [stdout] test lines::tests::line_locate ... ok
Dec 15 09:37:21.354 INFO [stdout] test lines::tests::preceding_lines_short ... ok
Dec 15 09:37:21.354 INFO [stdout] test lines::tests::preceding_lines_empty2 ... ok
Dec 15 09:37:21.354 INFO [stdout] test lines::tests::line_iter_empty ... ok
Dec 15 09:37:21.354 INFO [stdout] test lines::tests::preceding_lines_sherlock ... ok
Dec 15 09:37:21.358 INFO [stdout] test lines::tests::line_iter ... ok
Dec 15 09:37:21.358 INFO [stdout] test searcher::glue::tests::basic3 ... ok
Dec 15 09:37:21.358 INFO [stdout] test searcher::glue::tests::basic2 ... ok
Dec 15 09:37:21.361 INFO [stdout] test searcher::glue::tests::basic1 ... ok
Dec 15 09:37:21.363 INFO [stdout] test searcher::glue::tests::basic4 ... ok
Dec 15 09:37:21.411 INFO [stdout] test searcher::glue::tests::context_code1 ... ok
Dec 15 09:37:21.411 INFO [stdout] test searcher::glue::tests::binary2 ... ok
Dec 15 09:37:21.411 INFO [stdout] test searcher::glue::tests::binary1 ... ok
Dec 15 09:37:21.412 INFO [stdout] test searcher::glue::tests::context_code2 ... ok
Dec 15 09:37:21.412 INFO [stdout] test searcher::glue::tests::context_sherlock1 ... ok
Dec 15 09:37:21.415 INFO [stdout] test searcher::glue::tests::big_error_one_line ... ok
Dec 15 09:37:21.416 INFO [stdout] test searcher::glue::tests::big_error_multi_line ... ok
Dec 15 09:37:21.427 INFO [stdout] test searcher::glue::tests::context_sherlock3 ... ok
Dec 15 09:37:21.435 INFO [stdout] test searcher::glue::tests::context_code3 ... ok
Dec 15 09:37:21.437 INFO [stdout] test searcher::glue::tests::context_sherlock2 ... ok
Dec 15 09:37:21.496 INFO [stdout] test searcher::glue::tests::context_sherlock6 ... ok
Dec 15 09:37:21.508 INFO [stdout] test searcher::glue::tests::empty_line1 ... ok
Dec 15 09:37:21.521 INFO [stdout] test searcher::glue::tests::context_sherlock_invert2 ... ok
Dec 15 09:37:21.521 INFO [stdout] test searcher::glue::tests::context_sherlock_invert1 ... ok
Dec 15 09:37:21.522 INFO [stdout] test searcher::glue::tests::context_sherlock4 ... ok
Dec 15 09:37:21.524 INFO [stdout] test searcher::glue::tests::empty_line2 ... ok
Dec 15 09:37:21.538 INFO [stdout] test searcher::glue::tests::empty_line5 ... ok
Dec 15 09:37:21.555 INFO [stdout] test searcher::glue::tests::empty_line6 ... ok
Dec 15 09:37:21.555 INFO [stdout] test searcher::glue::tests::invert1 ... ok
Dec 15 09:37:21.555 INFO [stdout] test searcher::glue::tests::empty_line4 ... ok
Dec 15 09:37:21.605 INFO [stdout] test searcher::glue::tests::empty_line3 ... ok
Dec 15 09:37:21.605 INFO [stdout] test searcher::glue::tests::line_number_invert1 ... ok
Dec 15 09:37:21.605 INFO [stdout] test searcher::glue::tests::multi_line_overlap1 ... ok
Dec 15 09:37:21.605 INFO [stdout] test searcher::glue::tests::passthru_sherlock1 ... ok
Dec 15 09:37:21.605 INFO [stdout] test searcher::glue::tests::context_sherlock5 ... ok
Dec 15 09:37:21.605 INFO [stdout] test searcher::tests::config_error_heap_limit ... ok
Dec 15 09:37:21.605 INFO [stdout] test searcher::glue::tests::passthru_sherlock_invert1 ... ok
Dec 15 09:37:21.605 INFO [stdout] test searcher::glue::tests::scratch ... ok
Dec 15 09:37:21.605 INFO [stdout] test testutil::tests::empty_line2 ... ok
Dec 15 09:37:21.605 INFO [stdout] test testutil::tests::empty_line1 ... ok
Dec 15 09:37:21.605 INFO [stdout] test searcher::tests::config_error_line_terminator ... ok
Dec 15 09:37:21.605 INFO [stdout] test testutil::tests::empty_line4 ... ok
Dec 15 09:37:21.605 INFO [stdout] test testutil::tests::empty_line3 ... ok
Dec 15 09:37:21.605 INFO [stdout] test searcher::glue::tests::binary3 ... ok
Dec 15 09:37:21.605 INFO [stdout] test testutil::tests::empty_line5 ... ok
Dec 15 09:37:21.605 INFO [stdout] test testutil::tests::empty_line6 ... ok
Dec 15 09:37:21.605 INFO [stdout] test searcher::glue::tests::line_number1 ... ok
Dec 15 09:37:21.612 INFO [stdout] test searcher::glue::tests::multi_line_overlap2 ... ok
Dec 15 09:37:21.642 INFO [stdout] test searcher::glue::tests::binary4 ... ok
Dec 15 09:37:21.912 INFO [stdout] test searcher::glue::tests::big1 ... ok
Dec 15 09:37:21.912 INFO [stdout] 
Dec 15 09:37:21.912 INFO [stdout] test result: ok. 75 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 09:37:21.912 INFO [stdout] 
Dec 15 09:37:21.913 INFO [stderr]    Doc-tests grep-searcher
Dec 15 09:37:23.318 INFO [stdout] 
Dec 15 09:37:23.318 INFO [stdout] running 1 test
Dec 15 09:37:27.515 INFO [stdout] test src/lib.rs -  (line 49) ... ok
Dec 15 09:37:27.515 INFO [stdout] 
Dec 15 09:37:27.515 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 09:37:27.515 INFO [stdout] 
Dec 15 09:37:27.536 INFO [stderr] su: No module specific data is present
Dec 15 09:37:28.390 INFO running `"docker" "inspect" "a3b975ef774a029c2276569294418fa434cc41c19149f247ecb41976895bb574"`
Dec 15 09:37:28.617 INFO running `"docker" "rm" "-f" "a3b975ef774a029c2276569294418fa434cc41c19149f247ecb41976895bb574"`
Dec 15 09:37:28.851 INFO [stdout] a3b975ef774a029c2276569294418fa434cc41c19149f247ecb41976895bb574