Nov 18 16:03:04.116 INFO testing dipstick-0.6.11 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 16:03:04.118 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 16:03:04.687 INFO [stdout] 32aeeb1f5464168d753f549c168c12fb17f3fd011b2cdfa275a0728e4c8c54cb
Nov 18 16:03:04.690 INFO running `"docker" "start" "-a" "32aeeb1f5464168d753f549c168c12fb17f3fd011b2cdfa275a0728e4c8c54cb"`
Nov 18 16:03:07.196 INFO [stderr] usermod: no changes
Nov 18 16:03:07.363 INFO [stderr]    Compiling dipstick v0.6.11 (/source)
Nov 18 16:03:11.779 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.56s
Nov 18 16:03:11.784 INFO [stderr] su: No module specific data is present
Nov 18 16:03:12.858 INFO running `"docker" "inspect" "32aeeb1f5464168d753f549c168c12fb17f3fd011b2cdfa275a0728e4c8c54cb"`
Nov 18 16:03:13.215 INFO running `"docker" "rm" "-f" "32aeeb1f5464168d753f549c168c12fb17f3fd011b2cdfa275a0728e4c8c54cb"`
Nov 18 16:03:13.531 INFO [stdout] 32aeeb1f5464168d753f549c168c12fb17f3fd011b2cdfa275a0728e4c8c54cb
Nov 18 16:03:13.535 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 16:03:14.015 INFO [stdout] 5d03478130ad638887293e936820a1d669b4470cc3c1f8cc2e95e62b70f29ca5
Nov 18 16:03:14.017 INFO running `"docker" "start" "-a" "5d03478130ad638887293e936820a1d669b4470cc3c1f8cc2e95e62b70f29ca5"`
Nov 18 16:03:15.366 INFO [stderr] usermod: no changes
Nov 18 16:03:15.535 INFO [stderr]    Compiling dipstick v0.6.11 (/source)
Nov 18 16:03:22.359 INFO [stderr] warning: unused `#[macro_use]` import
Nov 18 16:03:22.359 INFO [stderr]  --> examples/async_print.rs:3:1
Nov 18 16:03:22.359 INFO [stderr]   |
Nov 18 16:03:22.359 INFO [stderr] 3 | #[macro_use]
Nov 18 16:03:22.359 INFO [stderr]   | ^^^^^^^^^^^^
Nov 18 16:03:22.359 INFO [stderr]   |
Nov 18 16:03:22.359 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Nov 18 16:03:22.359 INFO [stderr] 
Nov 18 16:03:32.067 INFO [stderr] warning: unused `#[macro_use]` import
Nov 18 16:03:32.067 INFO [stderr]  --> examples/instruments.rs:4:1
Nov 18 16:03:32.067 INFO [stderr]   |
Nov 18 16:03:32.067 INFO [stderr] 4 | #[macro_use]
Nov 18 16:03:32.067 INFO [stderr]   | ^^^^^^^^^^^^
Nov 18 16:03:32.067 INFO [stderr]   |
Nov 18 16:03:32.067 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Nov 18 16:03:32.067 INFO [stderr] 
Nov 18 16:03:34.538 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 19.13s
Nov 18 16:03:34.545 INFO [stderr] su: No module specific data is present
Nov 18 16:03:35.548 INFO running `"docker" "inspect" "5d03478130ad638887293e936820a1d669b4470cc3c1f8cc2e95e62b70f29ca5"`
Nov 18 16:03:35.903 INFO running `"docker" "rm" "-f" "5d03478130ad638887293e936820a1d669b4470cc3c1f8cc2e95e62b70f29ca5"`
Nov 18 16:03:36.140 INFO [stdout] 5d03478130ad638887293e936820a1d669b4470cc3c1f8cc2e95e62b70f29ca5
Nov 18 16:03:36.146 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 16:03:36.579 INFO [stdout] 59fa344c9445f969d9f3f8ea0fc7460eb2581612fb18138cfa1ca6cda462b929
Nov 18 16:03:36.581 INFO running `"docker" "start" "-a" "59fa344c9445f969d9f3f8ea0fc7460eb2581612fb18138cfa1ca6cda462b929"`
Nov 18 16:03:37.541 INFO [stderr] usermod: no changes
Nov 18 16:03:37.707 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.14s
Nov 18 16:03:37.723 INFO [stderr]      Running /target/debug/deps/dipstick-c896564b5e99c989
Nov 18 16:03:37.723 INFO [stdout] 
Nov 18 16:03:37.723 INFO [stdout] running 6 tests
Nov 18 16:03:37.723 INFO [stdout] test lru_cache::tests::get_and_get_mut_promote ... ok
Nov 18 16:03:37.723 INFO [stdout] test macros::test_app::call_macro_defined_metrics ... ok
Nov 18 16:03:37.723 INFO [stdout] test macros::test_mod::call_macro_defined_metrics ... ok
Nov 18 16:03:37.723 INFO [stdout] test output::test::test_to_log ... ok
Nov 18 16:03:37.723 INFO [stdout] test output::test::test_to_void ... ok
Nov 18 16:03:37.723 INFO [stdout] test output::test::sink_print ... ok
Nov 18 16:03:37.723 INFO [stdout] 
Nov 18 16:03:37.723 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:03:37.723 INFO [stdout] 
Nov 18 16:03:37.735 INFO [stderr]      Running /target/debug/deps/skeptic-572a089fe80d6b19
Nov 18 16:03:37.735 INFO [stdout] 
Nov 18 16:03:37.735 INFO [stdout] running 0 tests
Nov 18 16:03:37.735 INFO [stdout] 
Nov 18 16:03:37.735 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:03:37.735 INFO [stdout] 
Nov 18 16:03:37.747 INFO [stderr]    Doc-tests dipstick
Nov 18 16:03:39.299 INFO [stdout] 
Nov 18 16:03:39.299 INFO [stdout] running 5 tests
Nov 18 16:03:49.243 INFO [stdout] test src/core.rs - core::ControlScopeFn<M>::flush_on_drop (line 151) ... ok
Nov 18 16:03:49.245 INFO [stdout] test src/core.rs - core::ControlScopeFn<M>::new (line 110) ... ok
Nov 18 16:03:49.803 INFO [stdout] test src/core.rs - core::ControlScopeFn<M>::write (line 126) ... ok
Nov 18 16:03:49.815 INFO [stdout] test src/core.rs - core::ControlScopeFn<M>::flush (line 138) ... ok
Nov 18 16:03:49.983 INFO [stdout] test src/core.rs - core::Chain<M>::open_scope (line 185) ... ok
Nov 18 16:03:49.983 INFO [stdout] 
Nov 18 16:03:49.983 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:03:49.983 INFO [stdout] 
Nov 18 16:03:50.000 INFO [stderr] su: No module specific data is present
Nov 18 16:03:50.791 INFO running `"docker" "inspect" "59fa344c9445f969d9f3f8ea0fc7460eb2581612fb18138cfa1ca6cda462b929"`
Nov 18 16:03:51.052 INFO running `"docker" "rm" "-f" "59fa344c9445f969d9f3f8ea0fc7460eb2581612fb18138cfa1ca6cda462b929"`
Nov 18 16:03:51.407 INFO [stdout] 59fa344c9445f969d9f3f8ea0fc7460eb2581612fb18138cfa1ca6cda462b929