[INFO] crate emit 0.10.0 is already in cache
[INFO] extracting crate emit 0.10.0 into work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/reg/emit/0.10.0
[INFO] extracting crate emit 0.10.0 into work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/emit/0.10.0
[INFO] validating manifest of emit-0.10.0 on toolchain master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] validating manifest of emit-0.10.0 on toolchain try#7fea0229842e79db1159ea58ad9a653b288efee9
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] started frobbing emit-0.10.0
[INFO] finished frobbing emit-0.10.0
[INFO] frobbed toml for emit-0.10.0 written to work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/reg/emit/0.10.0/Cargo.toml
[INFO] started frobbing emit-0.10.0
[INFO] finished frobbing emit-0.10.0
[INFO] frobbed toml for emit-0.10.0 written to work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/emit/0.10.0/Cargo.toml
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] testing emit-0.10.0 against try#7fea0229842e79db1159ea58ad9a653b288efee9 for pr-57967
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-5/try#7fea0229842e79db1159ea58ad9a653b288efee9:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/emit/0.10.0:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "build" "--frozen"`
[INFO] [stdout] fd37359cc6063c4caa7bd20835d8661be11118ced83211f230bfc7e35d2568e7
[INFO] running `"docker" "start" "-a" "fd37359cc6063c4caa7bd20835d8661be11118ced83211f230bfc7e35d2568e7"`
[INFO] [stderr]    Compiling emit v0.10.0 (/opt/crater/workdir)
[INFO] [stderr] warning: use of deprecated item 'std::sync::atomic::ATOMIC_USIZE_INIT': the `new` function is now preferred
[INFO] [stderr]  --> src/pipeline/ambient.rs:7:44
[INFO] [stderr]   |
[INFO] [stderr] 7 | static PIPELINE_REF: atomic::AtomicUsize = atomic::ATOMIC_USIZE_INIT;
[INFO] [stderr]   |                                            ^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: #[warn(deprecated)] on by default
[INFO] [stderr] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.76s
[INFO] running `"docker" "inspect" "fd37359cc6063c4caa7bd20835d8661be11118ced83211f230bfc7e35d2568e7"`
[INFO] running `"docker" "rm" "-f" "fd37359cc6063c4caa7bd20835d8661be11118ced83211f230bfc7e35d2568e7"`
[INFO] [stdout] fd37359cc6063c4caa7bd20835d8661be11118ced83211f230bfc7e35d2568e7
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-5/try#7fea0229842e79db1159ea58ad9a653b288efee9:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/emit/0.10.0:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "test" "--frozen" "--no-run"`
[INFO] [stdout] 523e503cba6b2979973dbfc998de299b7b5ee61e069b944c6dfa1cb3b3dd2565
[INFO] running `"docker" "start" "-a" "523e503cba6b2979973dbfc998de299b7b5ee61e069b944c6dfa1cb3b3dd2565"`
[INFO] [stderr]    Compiling emit v0.10.0 (/opt/crater/workdir)
[INFO] [stderr] warning: use of deprecated item 'std::sync::atomic::ATOMIC_USIZE_INIT': the `new` function is now preferred
[INFO] [stderr]  --> src/pipeline/ambient.rs:7:44
[INFO] [stderr]   |
[INFO] [stderr] 7 | static PIPELINE_REF: atomic::AtomicUsize = atomic::ATOMIC_USIZE_INIT;
[INFO] [stderr]   |                                            ^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: #[warn(deprecated)] on by default
[INFO] [stderr] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.43s
[INFO] running `"docker" "inspect" "523e503cba6b2979973dbfc998de299b7b5ee61e069b944c6dfa1cb3b3dd2565"`
[INFO] running `"docker" "rm" "-f" "523e503cba6b2979973dbfc998de299b7b5ee61e069b944c6dfa1cb3b3dd2565"`
[INFO] [stdout] 523e503cba6b2979973dbfc998de299b7b5ee61e069b944c6dfa1cb3b3dd2565
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-5/try#7fea0229842e79db1159ea58ad9a653b288efee9:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/emit/0.10.0:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "test" "--frozen"`
[INFO] [stdout] 3436632b8748fe9c465944d4302baa0cd27808a09e772fa2936a7c84ca0d0fc3
[INFO] running `"docker" "start" "-a" "3436632b8748fe9c465944d4302baa0cd27808a09e772fa2936a7c84ca0d0fc3"`
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.05s
[INFO] [stderr]      Running /opt/crater/target/debug/deps/emit-67b1d79dd09451ac
[INFO] [stdout] 
[INFO] [stdout] running 19 tests
[INFO] [stdout] test pipeline::reference::tests::warn_is_enabled_at_info ... ok
[INFO] [stdout] test pipeline::reference::tests::debug_is_disabled_at_info ... ok
[INFO] [stdout] test pipeline::reference::tests::info_is_enabled_at_info ... ok
[INFO] [stdout] test templates::repl::tests::leading_values_are_replaced ... ok
[INFO] [stdout] test templates::repl::tests::duplicate_values_are_replaced ... ok
[INFO] [stdout] test templates::repl::tests::malformed_labels_are_not_replaced ... ok
[INFO] [stdout] test formatters::json::tests::json_is_produced ... ok
[INFO] [stdout] test templates::repl::tests::missing_values_are_replaced_as_blank ... ok
[INFO] [stdout] test templates::tests::additional_holes_are_indexed ... ok
[INFO] [stdout] test templates::repl::tests::values_are_replaced ... ok
[INFO] [stdout] test templates::tests::additional_names_are_ignored ... ok
[INFO] [stdout] test templates::tests::leading_holes_are_handled ... ok
[INFO] [stdout] test templates::tests::templates_with_parameters_are_built ... ok
[INFO] [stdout] test templates::repl::tests::trailing_values_are_replaced ... ok
[INFO] [stdout] test templates::tests::templates_without_parameters_are_built ... ok
[INFO] [stdout] test templates::tests::trailing_holes_are_handled ... ok
[INFO] [stdout] test tests::template_and_properties_are_captured ... ok
[INFO] [stdout] 2019-02-01T15:14:38Z INFO Hello, crates-build-env at 2139 in office!
[INFO] [stdout] 
[INFO] [stdout] {"@t":"2019-02-01T15:14:38.333Z","@mt":"Hello, {name} at {time} in {room}!","app":"Test","name":"crates-build-env","room":"office","target":"emit::tests","time":2139}
[INFO] [stdout] emit 2019-02-01T15:14:38.333255974Z INFO Hello, {name} at {time} in {room}!
[INFO] [stdout]   app: "Test"
[INFO] [stdout]   name: "crates-build-env"
[INFO] [stdout]   room: "office"
[INFO] [stdout]   target: "emit::tests"
[INFO] [stdout]   time: 2139
[INFO] [stdout] 
[INFO] [stdout] test tests::pipeline_example ... ok
[INFO] [stdout] test tests::unparameterized_templates_are_captured ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests emit
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test src/lib.rs -  (line 11) ... ignored
[INFO] [stdout] test src/lib.rs -  (line 20) ... ignored
[INFO] [stdout] test src/lib.rs - debug (line 253) ... ignored
[INFO] [stdout] test src/lib.rs - emit (line 161) ... ignored
[INFO] [stdout] test src/lib.rs - emit (line 168) ... ignored
[INFO] [stdout] test src/lib.rs - error (line 193) ... ignored
[INFO] [stdout] test src/lib.rs - info (line 233) ... ignored
[INFO] [stdout] test src/lib.rs - trace (line 273) ... ignored
[INFO] [stdout] test src/lib.rs - warn (line 213) ... ignored
[INFO] [stdout] test src/lib.rs -  (line 47) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 9 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] running `"docker" "inspect" "3436632b8748fe9c465944d4302baa0cd27808a09e772fa2936a7c84ca0d0fc3"`
[INFO] running `"docker" "rm" "-f" "3436632b8748fe9c465944d4302baa0cd27808a09e772fa2936a7c84ca0d0fc3"`
[INFO] [stdout] 3436632b8748fe9c465944d4302baa0cd27808a09e772fa2936a7c84ca0d0fc3