[INFO] fetching crate grep-printer 0.1.6...
[INFO] testing grep-printer-0.1.6 against master#432e145bd5a974c5b6f4dd9b352891bd7502b69d for pr-87041
[INFO] extracting crate grep-printer 0.1.6 into /workspace/builds/worker-9/source
[INFO] validating manifest of crates.io crate grep-printer 0.1.6 on toolchain 432e145bd5a974c5b6f4dd9b352891bd7502b69d
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate grep-printer 0.1.6
[INFO] finished tweaking crates.io crate grep-printer 0.1.6
[INFO] tweaked toml for crates.io crate grep-printer 0.1.6 written to /workspace/builds/worker-9/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded grep-searcher v0.1.8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 7fdf9ba2b8b8c50618a78daf755a3084bf29abeeeb66bb7f4702d8a60e11cbdf
[INFO] running `Command { std: "docker" "start" "-a" "7fdf9ba2b8b8c50618a78daf755a3084bf29abeeeb66bb7f4702d8a60e11cbdf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7fdf9ba2b8b8c50618a78daf755a3084bf29abeeeb66bb7f4702d8a60e11cbdf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7fdf9ba2b8b8c50618a78daf755a3084bf29abeeeb66bb7f4702d8a60e11cbdf", kill_on_drop: false }`
[INFO] [stdout] 7fdf9ba2b8b8c50618a78daf755a3084bf29abeeeb66bb7f4702d8a60e11cbdf
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] edf05390b8c85d72c440550b3f0f13c14475501f3d5a22c249064c94c77b3fad
[INFO] running `Command { std: "docker" "start" "-a" "edf05390b8c85d72c440550b3f0f13c14475501f3d5a22c249064c94c77b3fad", kill_on_drop: false }`
[INFO] [stderr]    Compiling memchr v2.4.0
[INFO] [stderr]    Compiling log v0.4.14
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling bytecount v0.6.2
[INFO] [stderr]    Compiling encoding_rs v0.8.28
[INFO] [stderr]    Compiling memmap2 v0.3.0
[INFO] [stderr]    Compiling serde v1.0.126
[INFO] [stderr]    Compiling grep-matcher v0.1.5
[INFO] [stderr]    Compiling bstr v0.2.16
[INFO] [stderr]    Compiling encoding_rs_io v0.1.7
[INFO] [stderr]    Compiling grep-searcher v0.1.8
[INFO] [stderr]    Compiling serde_json v1.0.64
[INFO] [stderr]    Compiling grep-printer v0.1.6 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 8.42s
[INFO] running `Command { std: "docker" "inspect" "edf05390b8c85d72c440550b3f0f13c14475501f3d5a22c249064c94c77b3fad", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "edf05390b8c85d72c440550b3f0f13c14475501f3d5a22c249064c94c77b3fad", kill_on_drop: false }`
[INFO] [stdout] edf05390b8c85d72c440550b3f0f13c14475501f3d5a22c249064c94c77b3fad
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 10d8f7fcb2d6d1e55b486734545267084ab548a0ae658f20f3313954337d0295
[INFO] running `Command { std: "docker" "start" "-a" "10d8f7fcb2d6d1e55b486734545267084ab548a0ae658f20f3313954337d0295", kill_on_drop: false }`
[INFO] [stderr]    Compiling regex-syntax v0.6.25
[INFO] [stderr]    Compiling once_cell v1.8.0
[INFO] [stderr]    Compiling aho-corasick v0.7.18
[INFO] [stderr]    Compiling thread_local v1.1.3
[INFO] [stderr]    Compiling regex v1.5.4
[INFO] [stderr]    Compiling grep-regex v0.1.9
[INFO] [stderr]    Compiling grep-printer v0.1.6 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 10.57s
[INFO] running `Command { std: "docker" "inspect" "10d8f7fcb2d6d1e55b486734545267084ab548a0ae658f20f3313954337d0295", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "10d8f7fcb2d6d1e55b486734545267084ab548a0ae658f20f3313954337d0295", kill_on_drop: false }`
[INFO] [stdout] 10d8f7fcb2d6d1e55b486734545267084ab548a0ae658f20f3313954337d0295
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] de7984afc07e78b50318ad643d8135b2e911251f85e931ef4aaba060fb2efcb3
[INFO] running `Command { std: "docker" "start" "-a" "de7984afc07e78b50318ad643d8135b2e911251f85e931ef4aaba060fb2efcb3", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.02s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/grep_printer-0afc2849274c261f)
[INFO] [stdout] 
[INFO] [stdout] running 94 tests
[INFO] [stdout] test json::tests::max_matches_after_context ... ok
[INFO] [stdout] test json::tests::always_begin_end_no_match ... ok
[INFO] [stdout] test standard::tests::byte_offset ... ok
[INFO] [stdout] test json::tests::max_matches ... ok
[INFO] [stdout] test json::tests::binary_detection ... ok
[INFO] [stdout] test standard::tests::context_break_multiple_no_heading ... ok
[INFO] [stdout] test standard::tests::byte_offset_multi_line ... ok
[INFO] [stdout] test standard::tests::column_number ... ok
[INFO] [stdout] test standard::tests::context_break_multiple_heading ... ok
[INFO] [stdout] test json::tests::missing_crlf ... ok
[INFO] [stdout] test standard::tests::column_number_multi_line ... ok
[INFO] [stdout] test standard::tests::heading_multiple ... ok
[INFO] [stdout] test json::tests::no_match ... ok
[INFO] [stdout] test standard::tests::invert_multi_line ... ok
[INFO] [stdout] test standard::tests::heading ... ok
[INFO] [stdout] test standard::tests::invert_context_only_matching_multi_line ... ok
[INFO] [stdout] test standard::tests::invert_context_only_matching ... ok
[INFO] [stdout] test standard::tests::max_columns_with_count_preview_two_matches ... ok
[INFO] [stdout] test standard::tests::invert_context ... ok
[INFO] [stdout] test standard::tests::no_heading ... ok
[INFO] [stdout] test standard::tests::max_columns_with_count_preview_no_match ... ok
[INFO] [stdout] test standard::tests::max_matches_multi_line1 ... ok
[INFO] [stdout] test standard::tests::line_number_multi_line ... ok
[INFO] [stdout] test standard::tests::only_matching_max_columns_multi_line2 ... ok
[INFO] [stdout] test standard::tests::only_matching_max_columns_multi_line1 ... ok
[INFO] [stdout] test standard::tests::only_matching_max_columns_preview ... ok
[INFO] [stdout] test standard::tests::max_matches_context ... ok
[INFO] [stdout] test standard::tests::only_matching_max_columns ... ok
[INFO] [stdout] test standard::tests::per_match ... ok
[INFO] [stdout] test standard::tests::only_matching ... ok
[INFO] [stdout] test standard::tests::only_matching_max_columns_preview_multi_line1 ... ok
[INFO] [stdout] test standard::tests::invert ... ok
[INFO] [stdout] test standard::tests::max_columns ... ok
[INFO] [stdout] test standard::tests::context_break ... ok
[INFO] [stdout] test standard::tests::max_columns_multi_line ... ok
[INFO] [stdout] test standard::tests::max_columns_with_count_preview_one_match ... ok
[INFO] [stdout] test standard::tests::max_matches ... ok
[INFO] [stdout] test standard::tests::max_columns_with_count ... ok
[INFO] [stdout] test standard::tests::max_matches_multi_line2 ... ok
[INFO] [stdout] test standard::tests::invert_context_multi_line ... ok
[INFO] [stdout] test standard::tests::path ... ok
[INFO] [stdout] test standard::tests::no_heading_multiple ... ok
[INFO] [stdout] test standard::tests::max_columns_preview ... ok
[INFO] [stdout] test standard::tests::per_match_multi_line2 ... ok
[INFO] [stdout] test standard::tests::replacement_multi_line_diff_line_term ... ok
[INFO] [stdout] test standard::tests::line_number ... ok
[INFO] [stdout] test standard::tests::replacement_max_columns_preview2 ... ok
[INFO] [stdout] test standard::tests::only_matching_max_columns_preview_multi_line2 ... ok
[INFO] [stdout] test standard::tests::path_terminator ... ok
[INFO] [stdout] test standard::tests::only_matching_multi_line1 ... ok
[INFO] [stdout] test standard::tests::per_match_multi_line3_only_first_line ... ok
[INFO] [stdout] test standard::tests::regression_search_empty_with_crlf ... ok
[INFO] [stdout] test standard::tests::max_columns_multi_line_preview ... ok
[INFO] [stdout] test standard::tests::per_match_multi_line2_only_first_line ... ok
[INFO] [stdout] test standard::tests::regression_after_context_with_match ... ok
[INFO] [stdout] test standard::tests::replacement_multi_line ... ok
[INFO] [stdout] test standard::tests::per_match_multi_line1_only_first_line ... ok
[INFO] [stdout] test standard::tests::per_match_multi_line1 ... ok
[INFO] [stdout] test standard::tests::replacement_multi_line_combine_lines ... ok
[INFO] [stdout] test standard::tests::reports_binary ... ok
[INFO] [stdout] test standard::tests::reports_stats ... ok
[INFO] [stdout] test standard::tests::reports_match ... ok
[INFO] [stdout] test standard::tests::per_match_multi_line3 ... ok
[INFO] [stdout] test standard::tests::separator_field ... ok
[INFO] [stdout] test standard::tests::reports_stats_multiple ... ok
[INFO] [stdout] test summary::tests::path_with_match_not_found ... ok
[INFO] [stdout] test standard::tests::trim_ascii ... ok
[INFO] [stdout] test summary::tests::count_no_path_even_with_path ... ok
[INFO] [stdout] test standard::tests::trim_ascii_multi_line ... ok
[INFO] [stdout] test standard::tests::trim_ascii_with_line_term ... ok
[INFO] [stdout] test standard::tests::only_matching_multi_line2 ... ok
[INFO] [stdout] test standard::tests::separator_path ... ok
[INFO] [stdout] test summary::tests::path_without_match_error ... ok
[INFO] [stdout] test summary::tests::path_with_match_found ... ok
[INFO] [stdout] test summary::tests::path_without_match_found ... ok
[INFO] [stdout] test summary::tests::count_path ... ok
[INFO] [stdout] test summary::tests::count_matches ... ok
[INFO] [stdout] test summary::tests::path_with_match_error ... ok
[INFO] [stdout] test summary::tests::count_path_separator ... ok
[INFO] [stdout] test summary::tests::count_path_with_zero ... ok
[INFO] [stdout] test summary::tests::count_no_path ... ok
[INFO] [stdout] test summary::tests::count_path_field_separator ... ok
[INFO] [stdout] test summary::tests::count_path_without_zero ... ok
[INFO] [stdout] test summary::tests::count_max_matches ... ok
[INFO] [stdout] test summary::tests::count_path_terminator ... ok
[INFO] [stdout] test summary::tests::quiet_with_stats ... ok
[INFO] [stdout] test summary::tests::quiet ... ok
[INFO] [stdout] test summary::tests::path_without_match_not_found ... ok
[INFO] [stdout] test standard::tests::replacement_max_columns ... ok
[INFO] [stdout] test standard::tests::replacement_per_match ... ok
[INFO] [stdout] test standard::tests::replacement ... ok
[INFO] [stdout] test standard::tests::replacement_only_matching ... ok
[INFO] [stdout] test standard::tests::replacement_max_columns_preview1 ... ok
[INFO] [stdout] test standard::tests::replacement_passthru ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 94 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.15s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests grep-printer
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/color.rs - color::UserColorSpec (line 149) ... ok
[INFO] [stdout] test src/lib.rs - (line 28) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.15s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "de7984afc07e78b50318ad643d8135b2e911251f85e931ef4aaba060fb2efcb3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "de7984afc07e78b50318ad643d8135b2e911251f85e931ef4aaba060fb2efcb3", kill_on_drop: false }`
[INFO] [stdout] de7984afc07e78b50318ad643d8135b2e911251f85e931ef4aaba060fb2efcb3