Nov 18 16:03:01.627 INFO testing dipstick-0.6.11 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 16:03:01.629 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:02.209 INFO [stdout] 6949baa53994b9f0f40f1512dfbd8543a7ee7902aaf28f2c63078c2b21c5fdfd
Nov 18 16:03:02.212 INFO running `"docker" "start" "-a" "6949baa53994b9f0f40f1512dfbd8543a7ee7902aaf28f2c63078c2b21c5fdfd"`
Nov 18 16:03:04.055 INFO [stderr] usermod: no changes
Nov 18 16:03:04.155 INFO [stderr]    Compiling dipstick v0.6.11 (/source)
Nov 18 16:03:09.096 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.02s
Nov 18 16:03:09.103 INFO [stderr] su: No module specific data is present
Nov 18 16:03:10.752 INFO running `"docker" "inspect" "6949baa53994b9f0f40f1512dfbd8543a7ee7902aaf28f2c63078c2b21c5fdfd"`
Nov 18 16:03:11.161 INFO running `"docker" "rm" "-f" "6949baa53994b9f0f40f1512dfbd8543a7ee7902aaf28f2c63078c2b21c5fdfd"`
Nov 18 16:03:11.419 INFO [stdout] 6949baa53994b9f0f40f1512dfbd8543a7ee7902aaf28f2c63078c2b21c5fdfd
Nov 18 16:03:11.429 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:11.977 INFO [stdout] e4039a9884da7fa9c7cef905c94ce49513445d17ac08f4f43331eefedcf77912
Nov 18 16:03:11.978 INFO running `"docker" "start" "-a" "e4039a9884da7fa9c7cef905c94ce49513445d17ac08f4f43331eefedcf77912"`
Nov 18 16:03:13.215 INFO [stderr] usermod: no changes
Nov 18 16:03:13.395 INFO [stderr]    Compiling dipstick v0.6.11 (/source)
Nov 18 16:03:18.963 INFO [stderr] warning: unused `#[macro_use]` import
Nov 18 16:03:18.964 INFO [stderr]  --> examples/instruments.rs:4:1
Nov 18 16:03:18.964 INFO [stderr]   |
Nov 18 16:03:18.964 INFO [stderr] 4 | #[macro_use]
Nov 18 16:03:18.964 INFO [stderr]   | ^^^^^^^^^^^^
Nov 18 16:03:18.971 INFO [stderr]   |
Nov 18 16:03:18.971 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Nov 18 16:03:18.971 INFO [stderr] 
Nov 18 16:03:27.399 INFO [stderr] warning: unused `#[macro_use]` import
Nov 18 16:03:27.399 INFO [stderr]  --> examples/async_print.rs:3:1
Nov 18 16:03:27.399 INFO [stderr]   |
Nov 18 16:03:27.399 INFO [stderr] 3 | #[macro_use]
Nov 18 16:03:27.399 INFO [stderr]   | ^^^^^^^^^^^^
Nov 18 16:03:27.399 INFO [stderr]   |
Nov 18 16:03:27.399 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Nov 18 16:03:27.399 INFO [stderr] 
Nov 18 16:03:31.814 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.60s
Nov 18 16:03:31.843 INFO [stderr] su: No module specific data is present
Nov 18 16:03:32.539 INFO running `"docker" "inspect" "e4039a9884da7fa9c7cef905c94ce49513445d17ac08f4f43331eefedcf77912"`
Nov 18 16:03:32.940 INFO running `"docker" "rm" "-f" "e4039a9884da7fa9c7cef905c94ce49513445d17ac08f4f43331eefedcf77912"`
Nov 18 16:03:33.337 INFO [stdout] e4039a9884da7fa9c7cef905c94ce49513445d17ac08f4f43331eefedcf77912
Nov 18 16:03:33.346 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:33.781 INFO [stdout] 39929459350f3873bd0673c2c979eba6f5acdf5f6f9cd1dbc7df4e92241a050f
Nov 18 16:03:33.787 INFO running `"docker" "start" "-a" "39929459350f3873bd0673c2c979eba6f5acdf5f6f9cd1dbc7df4e92241a050f"`
Nov 18 16:03:35.321 INFO [stderr] usermod: no changes
Nov 18 16:03:35.404 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
Nov 18 16:03:35.408 INFO [stderr]      Running /target/debug/deps/dipstick-14c7769abe8c8810
Nov 18 16:03:35.410 INFO [stdout] 
Nov 18 16:03:35.410 INFO [stdout] running 6 tests
Nov 18 16:03:35.419 INFO [stdout] test macros::test_mod::call_macro_defined_metrics ... ok
Nov 18 16:03:35.419 INFO [stdout] test macros::test_app::call_macro_defined_metrics ... ok
Nov 18 16:03:35.419 INFO [stdout] test output::test::test_to_log ... ok
Nov 18 16:03:35.419 INFO [stdout] test lru_cache::tests::get_and_get_mut_promote ... ok
Nov 18 16:03:35.419 INFO [stdout] test output::test::sink_print ... ok
Nov 18 16:03:35.423 INFO [stdout] test output::test::test_to_void ... ok
Nov 18 16:03:35.423 INFO [stdout] 
Nov 18 16:03:35.423 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:03:35.423 INFO [stdout] 
Nov 18 16:03:35.431 INFO [stderr]      Running /target/debug/deps/skeptic-8a6e343b1c2b369a
Nov 18 16:03:35.435 INFO [stdout] 
Nov 18 16:03:35.435 INFO [stdout] running 0 tests
Nov 18 16:03:35.435 INFO [stdout] 
Nov 18 16:03:35.435 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:03:35.435 INFO [stdout] 
Nov 18 16:03:35.436 INFO [stderr]    Doc-tests dipstick
Nov 18 16:03:36.999 INFO [stdout] 
Nov 18 16:03:36.999 INFO [stdout] running 5 tests
Nov 18 16:03:45.679 INFO [stdout] test src/core.rs - core::ControlScopeFn<M>::flush (line 138) ... ok
Nov 18 16:03:46.329 INFO [stdout] test src/core.rs - core::ControlScopeFn<M>::write (line 126) ... ok
Nov 18 16:03:47.551 INFO [stdout] test src/core.rs - core::ControlScopeFn<M>::flush_on_drop (line 151) ... ok
Nov 18 16:03:47.675 INFO [stdout] test src/core.rs - core::ControlScopeFn<M>::new (line 110) ... ok
Nov 18 16:03:47.676 INFO [stdout] test src/core.rs - core::Chain<M>::open_scope (line 185) ... ok
Nov 18 16:03:47.676 INFO [stdout] 
Nov 18 16:03:47.676 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:03:47.676 INFO [stdout] 
Nov 18 16:03:47.710 INFO [stderr] su: No module specific data is present
Nov 18 16:03:48.840 INFO running `"docker" "inspect" "39929459350f3873bd0673c2c979eba6f5acdf5f6f9cd1dbc7df4e92241a050f"`
Nov 18 16:03:49.217 INFO running `"docker" "rm" "-f" "39929459350f3873bd0673c2c979eba6f5acdf5f6f9cd1dbc7df4e92241a050f"`
Nov 18 16:03:49.452 INFO [stdout] 39929459350f3873bd0673c2c979eba6f5acdf5f6f9cd1dbc7df4e92241a050f