[INFO] fetching crate kvc 1.1.3...
[INFO] testing kvc-1.1.3 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-1
[INFO] extracting crate kvc 1.1.3 into /workspace/builds/worker-5-tc1/source
[INFO] started tweaking crates.io crate kvc 1.1.3
[INFO] finished tweaking crates.io crate kvc 1.1.3
[INFO] tweaked toml for crates.io crate kvc 1.1.3 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate kvc 1.1.3 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate kvc 1.1.3 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded regex v1.8.4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] b26c8ff5edf660ce854eabb038edfb74d87ed89ad746d9b89817dfa7ba6cb9e0
[INFO] running `Command { std: "docker" "start" "-a" "b26c8ff5edf660ce854eabb038edfb74d87ed89ad746d9b89817dfa7ba6cb9e0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b26c8ff5edf660ce854eabb038edfb74d87ed89ad746d9b89817dfa7ba6cb9e0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b26c8ff5edf660ce854eabb038edfb74d87ed89ad746d9b89817dfa7ba6cb9e0", kill_on_drop: false }`
[INFO] [stdout] b26c8ff5edf660ce854eabb038edfb74d87ed89ad746d9b89817dfa7ba6cb9e0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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" "RUSTDOCFLAGS=--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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] fdaef406d51523c13b1bd4b841ee19f00be29f18a5cfabce012632365589418b
[INFO] running `Command { std: "docker" "start" "-a" "fdaef406d51523c13b1bd4b841ee19f00be29f18a5cfabce012632365589418b", kill_on_drop: false }`
[INFO] [stderr]    Compiling memchr v2.5.0
[INFO] [stderr]    Compiling libc v0.2.146
[INFO] [stderr]    Compiling unicode-width v0.1.10
[INFO] [stderr]    Compiling regex-syntax v0.7.2
[INFO] [stderr]    Compiling textwrap v0.11.0
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling clap v2.34.0
[INFO] [stderr]    Compiling aho-corasick v1.0.2
[INFO] [stderr]    Compiling regex v1.8.4
[INFO] [stderr]    Compiling kvc v1.1.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 8.68s
[INFO] running `Command { std: "docker" "inspect" "fdaef406d51523c13b1bd4b841ee19f00be29f18a5cfabce012632365589418b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fdaef406d51523c13b1bd4b841ee19f00be29f18a5cfabce012632365589418b", kill_on_drop: false }`
[INFO] [stdout] fdaef406d51523c13b1bd4b841ee19f00be29f18a5cfabce012632365589418b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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" "RUSTDOCFLAGS=--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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a1e9bb90770dcff21b27d17bd0bacf0e4674173f6a9c15d83d8f388aa4a3a7a4
[INFO] running `Command { std: "docker" "start" "-a" "a1e9bb90770dcff21b27d17bd0bacf0e4674173f6a9c15d83d8f388aa4a3a7a4", kill_on_drop: false }`
[INFO] [stderr]    Compiling kvc v1.1.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.12s
[INFO] running `Command { std: "docker" "inspect" "a1e9bb90770dcff21b27d17bd0bacf0e4674173f6a9c15d83d8f388aa4a3a7a4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a1e9bb90770dcff21b27d17bd0bacf0e4674173f6a9c15d83d8f388aa4a3a7a4", kill_on_drop: false }`
[INFO] [stdout] a1e9bb90770dcff21b27d17bd0bacf0e4674173f6a9c15d83d8f388aa4a3a7a4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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" "RUSTDOCFLAGS=--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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 846cbade889c0988b7929a3c5718a169a57d55fa3546228eab711d7e54c37429
[INFO] running `Command { std: "docker" "start" "-a" "846cbade889c0988b7929a3c5718a169a57d55fa3546228eab711d7e54c37429", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.06s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/kvc-6459d53e7a33f496)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test tests::keywords_are_returned ... ok
[INFO] [stdout] test tests::line_counts_tokens ... ok
[INFO] [stdout] test tests::line_gets_date ... ok
[INFO] [stdout] test tests::date_matches_only_date ... ok
[INFO] [stdout] test tests::table_size ... ok
[INFO] [stdout] test tests::line_ignores_comments ... ok
[INFO] [stdout] test tests::line_accepts_keywords ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/kvc-df.rs (/opt/rustwide/target/debug/deps/kvc_df-fc47497b27ffd2c2)
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/kvc-stream.rs (/opt/rustwide/target/debug/deps/kvc_stream-afd236c65e547a05)
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests kvc
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test src/lib.rs - version (line 9) ... ok
[INFO] [stdout] test src/lib.rs - get_reserved_matchers (line 34) ... ok
[INFO] [stdout] test src/lib.rs - get_reserved_matchers (line 42) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.45s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "846cbade889c0988b7929a3c5718a169a57d55fa3546228eab711d7e54c37429", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "846cbade889c0988b7929a3c5718a169a57d55fa3546228eab711d7e54c37429", kill_on_drop: false }`
[INFO] [stdout] 846cbade889c0988b7929a3c5718a169a57d55fa3546228eab711d7e54c37429