Dec 15 05:42:20.866 INFO testing log-0.4.6 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 05:42:20.867 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 05:42:21.087 INFO [stdout] a0d6632a58bba400ff5bd26658d1e3a32f830562e9e9a32f7a864b322d4d004f
Dec 15 05:42:21.089 INFO running `"docker" "start" "-a" "a0d6632a58bba400ff5bd26658d1e3a32f830562e9e9a32f7a864b322d4d004f"`
Dec 15 05:42:21.442 INFO [stderr] usermod: no changes
Dec 15 05:42:21.477 INFO [stderr]    Compiling log v0.4.6 (/source)
Dec 15 05:42:21.975 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.51s
Dec 15 05:42:21.983 INFO [stderr] su: No module specific data is present
Dec 15 05:42:22.516 INFO running `"docker" "inspect" "a0d6632a58bba400ff5bd26658d1e3a32f830562e9e9a32f7a864b322d4d004f"`
Dec 15 05:42:22.624 INFO running `"docker" "rm" "-f" "a0d6632a58bba400ff5bd26658d1e3a32f830562e9e9a32f7a864b322d4d004f"`
Dec 15 05:42:22.748 INFO [stdout] a0d6632a58bba400ff5bd26658d1e3a32f830562e9e9a32f7a864b322d4d004f
Dec 15 05:42:22.751 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 05:42:22.944 INFO [stdout] 5218ca5874b8f68dc9d0de2466c15fad282ff109678d062ecb1268be2ca3895f
Dec 15 05:42:22.945 INFO running `"docker" "start" "-a" "5218ca5874b8f68dc9d0de2466c15fad282ff109678d062ecb1268be2ca3895f"`
Dec 15 05:42:23.332 INFO [stderr] usermod: no changes
Dec 15 05:42:23.369 INFO [stderr]    Compiling log v0.4.6 (/source)
Dec 15 05:42:24.932 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.58s
Dec 15 05:42:24.936 INFO [stderr] su: No module specific data is present
Dec 15 05:42:25.258 INFO running `"docker" "inspect" "5218ca5874b8f68dc9d0de2466c15fad282ff109678d062ecb1268be2ca3895f"`
Dec 15 05:42:25.479 INFO running `"docker" "rm" "-f" "5218ca5874b8f68dc9d0de2466c15fad282ff109678d062ecb1268be2ca3895f"`
Dec 15 05:42:25.739 INFO [stdout] 5218ca5874b8f68dc9d0de2466c15fad282ff109678d062ecb1268be2ca3895f
Dec 15 05:42:25.739 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 05:42:26.071 INFO [stdout] c78cfa3af1c80bccb21a89ddaf2732f317025578e868ceaa8df282302e88179a
Dec 15 05:42:26.077 INFO running `"docker" "start" "-a" "c78cfa3af1c80bccb21a89ddaf2732f317025578e868ceaa8df282302e88179a"`
Dec 15 05:42:26.751 INFO [stderr] usermod: no changes
Dec 15 05:42:26.787 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Dec 15 05:42:26.800 INFO [stderr]      Running /target/debug/deps/log-72512af69d807d21
Dec 15 05:42:26.808 INFO [stdout] 
Dec 15 05:42:26.808 INFO [stdout] running 13 tests
Dec 15 05:42:26.835 INFO [stdout] test tests::test_level_from_str ... ok
Dec 15 05:42:26.835 INFO [stdout] test tests::test_level_show ... ok
Dec 15 05:42:26.871 INFO [stdout] test tests::test_cross_eq ... ok
Dec 15 05:42:26.871 INFO [stdout] test tests::test_levelfilter_show ... ok
Dec 15 05:42:26.871 INFO [stdout] test tests::test_levelfilter_from_str ... ok
Dec 15 05:42:26.871 INFO [stdout] test tests::test_record_builder ... ok
Dec 15 05:42:26.871 INFO [stdout] test tests::test_metadata_builder ... ok
Dec 15 05:42:26.871 INFO [stdout] test tests::test_metadata_convenience_builder ... ok
Dec 15 05:42:26.871 INFO [stdout] test tests::test_cross_cmp ... ok
Dec 15 05:42:26.871 INFO [stdout] test tests::test_to_level_filter ... ok
Dec 15 05:42:26.871 INFO [stdout] test tests::test_to_level ... ok
Dec 15 05:42:26.871 INFO [stdout] test tests::test_record_complete_builder ... ok
Dec 15 05:42:26.879 INFO [stdout] test tests::test_record_convenience_builder ... ok
Dec 15 05:42:26.879 INFO [stdout] 
Dec 15 05:42:26.879 INFO [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 05:42:26.879 INFO [stdout] 
Dec 15 05:42:26.879 INFO [stderr]      Running /target/debug/deps/filters-7190f28ebd856c18
Dec 15 05:42:26.883 INFO [stderr]    Doc-tests log
Dec 15 05:42:27.211 INFO [stdout] 
Dec 15 05:42:27.211 INFO [stdout] running 17 tests
Dec 15 05:42:31.715 INFO [stdout] test src/lib.rs -  (line 184) ... ok
Dec 15 05:42:32.081 INFO [stdout] test src/lib.rs -  (line 51) ... ok
Dec 15 05:42:32.241 INFO [stdout] test src/lib.rs - MetadataBuilder (line 970) ... ok
Dec 15 05:42:32.383 INFO [stdout] test src/lib.rs - RecordBuilder (line 777) ... ok
Dec 15 05:42:33.063 INFO [stdout] test src/lib.rs -  (line 153) ... ok
Dec 15 05:42:33.091 INFO [stdout] test src/lib.rs - Record (line 681) ... ok
Dec 15 05:42:33.403 INFO [stdout] test src/lib.rs - Metadata (line 913) ... ok
Dec 15 05:42:34.055 INFO [stdout] test src/lib.rs -  (line 118) ... ok
Dec 15 05:42:35.495 INFO [stdout] test src/lib.rs - set_logger (line 1113) ... ok
Dec 15 05:42:35.703 INFO [stdout] test src/lib.rs - RecordBuilder (line 792) ... ok
Dec 15 05:42:35.835 INFO [stdout] test src/macros.rs - error (line 51) ... ok
Dec 15 05:42:36.779 INFO [stdout] test src/macros.rs - debug (line 125) ... ok
Dec 15 05:42:37.035 INFO [stdout] test src/macros.rs - log_enabled (line 181) ... ok
Dec 15 05:42:37.408 INFO [stdout] test src/macros.rs - info (line 99) ... ok
Dec 15 05:42:37.587 INFO [stdout] test src/macros.rs - trace (line 150) ... ok
Dec 15 05:42:37.587 INFO [stdout] test src/macros.rs - log (line 18) ... ok
Dec 15 05:42:37.867 INFO [stdout] test src/macros.rs - warn (line 75) ... ok
Dec 15 05:42:37.867 INFO [stdout] 
Dec 15 05:42:37.867 INFO [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 05:42:37.867 INFO [stdout] 
Dec 15 05:42:37.881 INFO [stderr] su: No module specific data is present
Dec 15 05:42:38.216 INFO running `"docker" "inspect" "c78cfa3af1c80bccb21a89ddaf2732f317025578e868ceaa8df282302e88179a"`
Dec 15 05:42:38.339 INFO running `"docker" "rm" "-f" "c78cfa3af1c80bccb21a89ddaf2732f317025578e868ceaa8df282302e88179a"`
Dec 15 05:42:38.457 INFO [stdout] c78cfa3af1c80bccb21a89ddaf2732f317025578e868ceaa8df282302e88179a