Dec 15 15:06:09.208 INFO testing fern-0.5.7 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 15:06:09.208 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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"`
Dec 15 15:06:09.474 INFO [stdout] cffd1096044d6298a8e6d1d49808eb809b6c655c981c2284fdaf9f7d4a6bd3ed
Dec 15 15:06:09.476 INFO running `"docker" "start" "-a" "cffd1096044d6298a8e6d1d49808eb809b6c655c981c2284fdaf9f7d4a6bd3ed"`
Dec 15 15:06:09.931 INFO [stderr] usermod: no changes
Dec 15 15:06:09.982 INFO [stderr]    Compiling fern v0.5.7 (/source)
Dec 15 15:06:11.995 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.04s
Dec 15 15:06:12.007 INFO [stderr] su: No module specific data is present
Dec 15 15:06:12.375 INFO running `"docker" "inspect" "cffd1096044d6298a8e6d1d49808eb809b6c655c981c2284fdaf9f7d4a6bd3ed"`
Dec 15 15:06:12.439 INFO running `"docker" "rm" "-f" "cffd1096044d6298a8e6d1d49808eb809b6c655c981c2284fdaf9f7d4a6bd3ed"`
Dec 15 15:06:12.519 INFO [stdout] cffd1096044d6298a8e6d1d49808eb809b6c655c981c2284fdaf9f7d4a6bd3ed
Dec 15 15:06:12.521 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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"`
Dec 15 15:06:12.752 INFO [stdout] 2e51fae329e4f8aad986607fb7f25739fbd06604f3863fa7c44d6ebed0923cc3
Dec 15 15:06:12.756 INFO running `"docker" "start" "-a" "2e51fae329e4f8aad986607fb7f25739fbd06604f3863fa7c44d6ebed0923cc3"`
Dec 15 15:06:13.231 INFO [stderr] usermod: no changes
Dec 15 15:06:13.325 INFO [stderr]    Compiling fern v0.5.7 (/source)
Dec 15 15:06:23.613 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.35s
Dec 15 15:06:23.621 INFO [stderr] su: No module specific data is present
Dec 15 15:06:23.996 INFO running `"docker" "inspect" "2e51fae329e4f8aad986607fb7f25739fbd06604f3863fa7c44d6ebed0923cc3"`
Dec 15 15:06:24.205 INFO running `"docker" "rm" "-f" "2e51fae329e4f8aad986607fb7f25739fbd06604f3863fa7c44d6ebed0923cc3"`
Dec 15 15:06:24.471 INFO [stdout] 2e51fae329e4f8aad986607fb7f25739fbd06604f3863fa7c44d6ebed0923cc3
Dec 15 15:06:24.475 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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"`
Dec 15 15:06:24.852 INFO [stdout] 28a0ad7b4819f88ad5de6f155cca07f1e4302d423f9b146e1f03662a527eb84b
Dec 15 15:06:24.854 INFO running `"docker" "start" "-a" "28a0ad7b4819f88ad5de6f155cca07f1e4302d423f9b146e1f03662a527eb84b"`
Dec 15 15:06:26.239 INFO [stderr] usermod: no changes
Dec 15 15:06:26.333 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.06s
Dec 15 15:06:26.337 INFO [stderr]      Running /target/debug/deps/fern-073ab5a411bcca81
Dec 15 15:06:26.349 INFO [stdout] 
Dec 15 15:06:26.349 INFO [stdout] running 7 tests
Dec 15 15:06:26.349 INFO [stdout] test log_impl::test::test_level_config_find_exact_many ... ok
Dec 15 15:06:26.349 INFO [stdout] test log_impl::test::test_level_config_similar_names_are_not_same ... ok
Dec 15 15:06:26.349 INFO [stdout] test log_impl::test::test_level_config_hierarchy_correct ... ok
Dec 15 15:06:26.355 INFO [stdout] test log_impl::test::test_level_config_single_colon_is_not_double_colon ... ok
Dec 15 15:06:26.355 INFO [stdout] test log_impl::test::test_level_config_simple_hierarchy ... ok
Dec 15 15:06:26.355 INFO [stdout] test log_impl::test::test_level_config_find_exact_minimal ... ok
Dec 15 15:06:26.355 INFO [stdout] test log_impl::test::test_level_config_all_chars ... ok
Dec 15 15:06:26.355 INFO [stdout] 
Dec 15 15:06:26.355 INFO [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 15:06:26.355 INFO [stdout] 
Dec 15 15:06:26.356 INFO [stderr]      Running /target/debug/deps/channel_logging-3125311c158eb8cc
Dec 15 15:06:26.364 INFO [stdout] 
Dec 15 15:06:26.364 INFO [stdout] running 1 test
Dec 15 15:06:26.364 INFO [stdout] test test_channel_logging ... ok
Dec 15 15:06:26.364 INFO [stdout] 
Dec 15 15:06:26.364 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 15:06:26.365 INFO [stdout] 
Dec 15 15:06:26.367 INFO [stderr]      Running /target/debug/deps/file_logging-ee96bb939e836884
Dec 15 15:06:26.372 INFO [stdout] 
Dec 15 15:06:26.372 INFO [stdout] running 2 tests
Dec 15 15:06:26.373 INFO [stdout] [INFO] Test information message
Dec 15 15:06:26.373 INFO [stdout] [WARN] Test warning message
Dec 15 15:06:26.373 INFO [stdout] [ERROR] Test error message
Dec 15 15:06:26.373 INFO [stdout] test test_basic_logging_file_logging ... ok
Dec 15 15:06:26.374 INFO [stdout] test test_custom_line_separators ... ok
Dec 15 15:06:26.374 INFO [stdout] 
Dec 15 15:06:26.374 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 15:06:26.374 INFO [stdout] 
Dec 15 15:06:26.374 INFO [stderr]      Running /target/debug/deps/global_logging-c96112c5482ca944
Dec 15 15:06:26.391 INFO [stdout] 
Dec 15 15:06:26.391 INFO [stdout] running 1 test
Dec 15 15:06:26.399 INFO [stdout] test test_global_logger ... ok
Dec 15 15:06:26.399 INFO [stdout] 
Dec 15 15:06:26.399 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 15:06:26.399 INFO [stdout] 
Dec 15 15:06:26.401 INFO [stderr]      Running /target/debug/deps/meta_logging-cb39e33703093925
Dec 15 15:06:26.404 INFO [stdout] 
Dec 15 15:06:26.404 INFO [stdout] running 0 tests
Dec 15 15:06:26.404 INFO [stdout] 
Dec 15 15:06:26.404 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 15:06:26.404 INFO [stdout] 
Dec 15 15:06:26.405 INFO [stderr]      Running /target/debug/deps/panic_logging-cc7abe8ac2379ade
Dec 15 15:06:26.408 INFO [stdout] 
Dec 15 15:06:26.408 INFO [stdout] running 3 tests
Dec 15 15:06:26.409 INFO [stdout] this should not panic
Dec 15 15:06:26.409 INFO [stdout] test double_chained_with_panics_no_info_panic ... ok
Dec 15 15:06:26.456 INFO [stdout] test test_panic_panics ... ok
Dec 15 15:06:26.457 INFO [stdout] test double_chained_with_panics_yes_error_panic ... ok
Dec 15 15:06:26.457 INFO [stdout] 
Dec 15 15:06:26.457 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 15:06:26.457 INFO [stdout] 
Dec 15 15:06:26.460 INFO [stderr]      Running /target/debug/deps/support-5d07c3efab83d1d7
Dec 15 15:06:26.462 INFO [stdout] 
Dec 15 15:06:26.462 INFO [stdout] running 0 tests
Dec 15 15:06:26.462 INFO [stdout] 
Dec 15 15:06:26.462 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 15:06:26.462 INFO [stdout] 
Dec 15 15:06:26.463 INFO [stderr]      Running /target/debug/deps/write_logging-b6cc4a7f7c120bf4
Dec 15 15:06:26.472 INFO [stdout] 
Dec 15 15:06:26.472 INFO [stdout] running 1 test
Dec 15 15:06:26.472 INFO [stdout] [INFO] Test information message
Dec 15 15:06:26.472 INFO [stdout] test test_raw_write_logging ... ok
Dec 15 15:06:26.472 INFO [stdout] 
Dec 15 15:06:26.472 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 15:06:26.472 INFO [stdout] 
Dec 15 15:06:26.473 INFO [stderr]    Doc-tests fern
Dec 15 15:06:27.651 INFO [stdout] 
Dec 15 15:06:27.651 INFO [stdout] running 30 tests
Dec 15 15:06:30.637 INFO [stdout] test src/builders.rs - builders::Dispatch::into_shared (line 371) ... ok
Dec 15 15:06:31.547 INFO [stdout] test src/builders.rs - builders::Dispatch::level (line 212) ... ok
Dec 15 15:06:32.555 INFO [stdout] test src/builders.rs - builders::Dispatch::into_log (line 559) ... ok
Dec 15 15:06:33.323 INFO [stdout] test src/builders.rs - builders::Dispatch::format (line 153) ... ok
Dec 15 15:06:34.174 INFO [stdout] test src/builders.rs - builders::Dispatch::filter (line 328) ... ok
Dec 15 15:06:34.738 INFO [stdout] test src/builders.rs - builders::Dispatch (line 32) ... ok
Dec 15 15:06:34.879 INFO [stdout] test src/builders.rs - builders::Dispatch::level_for (line 247) ... ok
Dec 15 15:06:36.347 INFO [stdout] test src/builders.rs - builders::Dispatch::level_for (line 264) ... ok
Dec 15 15:06:37.115 INFO [stdout] test src/builders.rs - builders::Dispatch::chain (line 187) ... ok
Dec 15 15:06:37.859 INFO [stdout] test src/builders.rs - builders::Output::call (line 1059) ... ok
Dec 15 15:06:38.222 INFO [stdout] test src/builders.rs - builders::Output::file (line 861) ... ok
Dec 15 15:06:39.960 INFO [stdout] test src/builders.rs - builders::Output::file (line 885) ... ok
Dec 15 15:06:40.118 INFO [stdout] test src/builders.rs - builders::Output::file (line 872) ... ok
Dec 15 15:06:41.591 INFO [stdout] test src/builders.rs - builders::Output::stderr (line 989) ... ok
Dec 15 15:06:41.599 INFO [stdout] test src/builders.rs - builders::Output::stderr (line 981) ... ok
Dec 15 15:06:42.831 INFO [stdout] test src/builders.rs - builders::Output::sender (line 1010) ... ok
Dec 15 15:06:44.097 INFO [stdout] test src/builders.rs - builders::Output::stdout (line 954) ... ok
Dec 15 15:06:44.539 INFO [stdout] test src/builders.rs - builders::Output::stdout (line 962) ... ok
Dec 15 15:06:44.735 INFO [stdout] test src/builders.rs - builders::Output::writer (line 911) ... ok
Dec 15 15:06:45.416 INFO [stdout] test src/builders.rs - builders::Output::writer (line 928) ... ok
Dec 15 15:06:46.139 INFO [stdout] test src/builders.rs - builders::Panic (line 687) ... ok
Dec 15 15:06:47.407 INFO [stdout] test src/lib.rs -  (line 153) ... ok
Dec 15 15:06:47.463 INFO [stdout] test src/builders.rs - builders::Panic (line 665) ... ok
Dec 15 15:06:48.087 INFO [stdout] test src/lib.rs -  (line 17) ... ok
Dec 15 15:06:48.475 INFO [stdout] test src/lib.rs - log_file (line 273) ... ok
Dec 15 15:06:51.075 INFO [stdout] test src/log_impl.rs - log_impl::FormatCallback<'a>::finish (line 319) ... ok
Dec 15 15:06:52.119 INFO [stdout] test src/meta.rs - meta (line 6) ... ok
Dec 15 15:06:52.123 INFO [stdout] test src/log_impl.rs - log_impl::FormatCallback (line 40) ... ok
Dec 15 15:06:52.335 INFO [stdout] test src/meta.rs - meta (line 50) ... ok
Dec 15 15:06:52.473 INFO [stdout] test src/lib.rs -  (line 32) ... ok
Dec 15 15:06:52.474 INFO [stdout] 
Dec 15 15:06:52.474 INFO [stdout] test result: ok. 30 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 15:06:52.474 INFO [stdout] 
Dec 15 15:06:52.513 INFO [stderr] su: No module specific data is present
Dec 15 15:06:53.635 INFO running `"docker" "inspect" "28a0ad7b4819f88ad5de6f155cca07f1e4302d423f9b146e1f03662a527eb84b"`
Dec 15 15:06:53.967 INFO running `"docker" "rm" "-f" "28a0ad7b4819f88ad5de6f155cca07f1e4302d423f9b146e1f03662a527eb84b"`
Dec 15 15:06:54.311 INFO [stdout] 28a0ad7b4819f88ad5de6f155cca07f1e4302d423f9b146e1f03662a527eb84b