Nov 18 07:03:22.461 INFO testing log-0.4.5 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 07:03:22.461 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 07:03:22.702 INFO [stdout] 667af492702e3801b66840decd49e50a39c159cd0862303be68eac9f577cff4d
Nov 18 07:03:22.703 INFO running `"docker" "start" "-a" "667af492702e3801b66840decd49e50a39c159cd0862303be68eac9f577cff4d"`
Nov 18 07:03:23.224 INFO [stderr] usermod: no changes
Nov 18 07:03:23.262 INFO [stderr]    Compiling log v0.4.5 (/source)
Nov 18 07:03:23.655 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.40s
Nov 18 07:03:23.659 INFO [stderr] su: No module specific data is present
Nov 18 07:03:23.962 INFO running `"docker" "inspect" "667af492702e3801b66840decd49e50a39c159cd0862303be68eac9f577cff4d"`
Nov 18 07:03:24.101 INFO running `"docker" "rm" "-f" "667af492702e3801b66840decd49e50a39c159cd0862303be68eac9f577cff4d"`
Nov 18 07:03:24.184 INFO [stdout] 667af492702e3801b66840decd49e50a39c159cd0862303be68eac9f577cff4d
Nov 18 07:03:24.188 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 07:03:24.379 INFO [stdout] dce3439e4abc8a3ac6da88b7b714aaa50f0313ecb5d3dbc46a84ed9198e79651
Nov 18 07:03:24.381 INFO running `"docker" "start" "-a" "dce3439e4abc8a3ac6da88b7b714aaa50f0313ecb5d3dbc46a84ed9198e79651"`
Nov 18 07:03:24.893 INFO [stderr] usermod: no changes
Nov 18 07:03:24.933 INFO [stderr]    Compiling log v0.4.5 (/source)
Nov 18 07:03:26.354 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.44s
Nov 18 07:03:26.358 INFO [stderr] su: No module specific data is present
Nov 18 07:03:26.708 INFO running `"docker" "inspect" "dce3439e4abc8a3ac6da88b7b714aaa50f0313ecb5d3dbc46a84ed9198e79651"`
Nov 18 07:03:26.853 INFO running `"docker" "rm" "-f" "dce3439e4abc8a3ac6da88b7b714aaa50f0313ecb5d3dbc46a84ed9198e79651"`
Nov 18 07:03:27.142 INFO [stdout] dce3439e4abc8a3ac6da88b7b714aaa50f0313ecb5d3dbc46a84ed9198e79651
Nov 18 07:03:27.146 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 07:03:27.552 INFO [stdout] 4f2381fff93c24eeb600c92773327fa7102ea1dad35d5f0dea8362282ff284a2
Nov 18 07:03:27.555 INFO running `"docker" "start" "-a" "4f2381fff93c24eeb600c92773327fa7102ea1dad35d5f0dea8362282ff284a2"`
Nov 18 07:03:28.525 INFO [stderr] usermod: no changes
Nov 18 07:03:28.591 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.04s
Nov 18 07:03:28.591 INFO [stderr]      Running /target/debug/deps/log-afc01e525675ebc0
Nov 18 07:03:28.595 INFO [stdout] 
Nov 18 07:03:28.595 INFO [stdout] running 13 tests
Nov 18 07:03:28.595 INFO [stdout] test tests::test_metadata_builder ... ok
Nov 18 07:03:28.595 INFO [stdout] test tests::test_levelfilter_from_str ... ok
Nov 18 07:03:28.595 INFO [stdout] test tests::test_levelfilter_show ... ok
Nov 18 07:03:28.595 INFO [stdout] test tests::test_level_show ... ok
Nov 18 07:03:28.595 INFO [stdout] test tests::test_level_from_str ... ok
Nov 18 07:03:28.595 INFO [stdout] test tests::test_cross_eq ... ok
Nov 18 07:03:28.595 INFO [stdout] test tests::test_record_builder ... ok
Nov 18 07:03:28.595 INFO [stdout] test tests::test_record_complete_builder ... ok
Nov 18 07:03:28.595 INFO [stdout] test tests::test_record_convenience_builder ... ok
Nov 18 07:03:28.595 INFO [stdout] test tests::test_metadata_convenience_builder ... ok
Nov 18 07:03:28.595 INFO [stdout] test tests::test_to_level ... ok
Nov 18 07:03:28.595 INFO [stdout] test tests::test_cross_cmp ... ok
Nov 18 07:03:28.595 INFO [stdout] test tests::test_to_level_filter ... ok
Nov 18 07:03:28.595 INFO [stdout] 
Nov 18 07:03:28.595 INFO [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:03:28.595 INFO [stdout] 
Nov 18 07:03:28.598 INFO [stderr]      Running /target/debug/deps/filters-9a520ca9be59f4d9
Nov 18 07:03:28.598 INFO [stderr]    Doc-tests log
Nov 18 07:03:28.807 INFO [stdout] 
Nov 18 07:03:28.807 INFO [stdout] running 17 tests
Nov 18 07:03:31.447 INFO [stdout] test src/lib.rs - Record (line 681) ... ok
Nov 18 07:03:32.343 INFO [stdout] test src/lib.rs - MetadataBuilder (line 970) ... ok
Nov 18 07:03:32.743 INFO [stdout] test src/lib.rs -  (line 184) ... ok
Nov 18 07:03:32.803 INFO [stdout] test src/lib.rs -  (line 51) ... ok
Nov 18 07:03:33.507 INFO [stdout] test src/lib.rs - RecordBuilder (line 777) ... ok
Nov 18 07:03:33.666 INFO [stdout] test src/lib.rs -  (line 153) ... ok
Nov 18 07:03:33.819 INFO [stdout] test src/lib.rs -  (line 118) ... ok
Nov 18 07:03:34.467 INFO [stdout] test src/lib.rs - Metadata (line 913) ... ok
Nov 18 07:03:36.384 INFO [stdout] test src/lib.rs - set_logger (line 1113) ... ok
Nov 18 07:03:36.991 INFO [stdout] test src/lib.rs - RecordBuilder (line 792) ... ok
Nov 18 07:03:37.535 INFO [stdout] test src/macros.rs - log_enabled (line 181) ... ok
Nov 18 07:03:38.099 INFO [stdout] test src/macros.rs - info (line 99) ... ok
Nov 18 07:03:38.135 INFO [stdout] test src/macros.rs - log (line 18) ... ok
Nov 18 07:03:38.454 INFO [stdout] test src/macros.rs - debug (line 125) ... ok
Nov 18 07:03:38.722 INFO [stdout] test src/macros.rs - error (line 51) ... ok
Nov 18 07:03:38.722 INFO [stdout] test src/macros.rs - trace (line 150) ... ok
Nov 18 07:03:39.186 INFO [stdout] test src/macros.rs - warn (line 75) ... ok
Nov 18 07:03:39.187 INFO [stdout] 
Nov 18 07:03:39.187 INFO [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:03:39.187 INFO [stdout] 
Nov 18 07:03:39.203 INFO [stderr] su: No module specific data is present
Nov 18 07:03:39.480 INFO running `"docker" "inspect" "4f2381fff93c24eeb600c92773327fa7102ea1dad35d5f0dea8362282ff284a2"`
Nov 18 07:03:39.592 INFO running `"docker" "rm" "-f" "4f2381fff93c24eeb600c92773327fa7102ea1dad35d5f0dea8362282ff284a2"`
Nov 18 07:03:39.715 INFO [stdout] 4f2381fff93c24eeb600c92773327fa7102ea1dad35d5f0dea8362282ff284a2