Dec 15 05:42:10.816 INFO testing log-once-0.2.0 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 15 05:42:10.816 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:11.020 INFO [stdout] eb9ba0c86b903fd6a62b79ca9190845043fcb71f9f9f21503f7cd22fa262d55c
Dec 15 05:42:11.025 INFO running `"docker" "start" "-a" "eb9ba0c86b903fd6a62b79ca9190845043fcb71f9f9f21503f7cd22fa262d55c"`
Dec 15 05:42:11.582 INFO [stderr] usermod: no changes
Dec 15 05:42:11.612 INFO [stderr]    Compiling log-once v0.2.0 (/source)
Dec 15 05:42:12.446 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.85s
Dec 15 05:42:12.448 INFO [stderr] su: No module specific data is present
Dec 15 05:42:12.833 INFO running `"docker" "inspect" "eb9ba0c86b903fd6a62b79ca9190845043fcb71f9f9f21503f7cd22fa262d55c"`
Dec 15 05:42:12.970 INFO running `"docker" "rm" "-f" "eb9ba0c86b903fd6a62b79ca9190845043fcb71f9f9f21503f7cd22fa262d55c"`
Dec 15 05:42:13.090 INFO [stdout] eb9ba0c86b903fd6a62b79ca9190845043fcb71f9f9f21503f7cd22fa262d55c
Dec 15 05:42:13.092 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:13.481 INFO [stdout] deb415dec3b06f8e8df352c080c81fa38c24d7e792dec30e14f6b524e10e0c61
Dec 15 05:42:13.487 INFO running `"docker" "start" "-a" "deb415dec3b06f8e8df352c080c81fa38c24d7e792dec30e14f6b524e10e0c61"`
Dec 15 05:42:14.767 INFO [stderr] usermod: no changes
Dec 15 05:42:14.883 INFO [stderr]    Compiling log-once v0.2.0 (/source)
Dec 15 05:42:20.656 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.86s
Dec 15 05:42:20.660 INFO [stderr] su: No module specific data is present
Dec 15 05:42:20.880 INFO running `"docker" "inspect" "deb415dec3b06f8e8df352c080c81fa38c24d7e792dec30e14f6b524e10e0c61"`
Dec 15 05:42:20.959 INFO running `"docker" "rm" "-f" "deb415dec3b06f8e8df352c080c81fa38c24d7e792dec30e14f6b524e10e0c61"`
Dec 15 05:42:21.065 INFO [stdout] deb415dec3b06f8e8df352c080c81fa38c24d7e792dec30e14f6b524e10e0c61
Dec 15 05:42:21.067 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:21.263 INFO [stdout] fd8ce0fd9e9f5ff264430a9a94dd564d1b7ac9e61e1cae6df2e930ec35f168c9
Dec 15 05:42:21.268 INFO running `"docker" "start" "-a" "fd8ce0fd9e9f5ff264430a9a94dd564d1b7ac9e61e1cae6df2e930ec35f168c9"`
Dec 15 05:42:21.662 INFO [stderr] usermod: no changes
Dec 15 05:42:21.699 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.01s
Dec 15 05:42:21.711 INFO [stderr]      Running /target/debug/deps/log_once-e333d0a3d013ff0e
Dec 15 05:42:21.713 INFO [stdout] 
Dec 15 05:42:21.713 INFO [stdout] running 1 test
Dec 15 05:42:21.713 INFO [stdout] test tests::called_once ... ok
Dec 15 05:42:21.713 INFO [stdout] 
Dec 15 05:42:21.713 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 05:42:21.713 INFO [stdout] 
Dec 15 05:42:21.713 INFO [stderr]      Running /target/debug/deps/debug-230695292aa026db
Dec 15 05:42:21.715 INFO [stdout] 
Dec 15 05:42:21.715 INFO [stdout] running 1 test
Dec 15 05:42:21.718 INFO [stdout] test debug ... ok
Dec 15 05:42:21.718 INFO [stdout] 
Dec 15 05:42:21.718 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 05:42:21.718 INFO [stdout] 
Dec 15 05:42:21.719 INFO [stderr]      Running /target/debug/deps/error-21ad2502b3186e1b
Dec 15 05:42:21.720 INFO [stdout] 
Dec 15 05:42:21.720 INFO [stdout] running 1 test
Dec 15 05:42:21.720 INFO [stdout] test error ... ok
Dec 15 05:42:21.720 INFO [stdout] 
Dec 15 05:42:21.720 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 05:42:21.720 INFO [stdout] 
Dec 15 05:42:21.721 INFO [stderr]      Running /target/debug/deps/info-bef5b6d52a2f0d7f
Dec 15 05:42:21.724 INFO [stdout] 
Dec 15 05:42:21.724 INFO [stdout] running 1 test
Dec 15 05:42:21.725 INFO [stderr]      Running /target/debug/deps/trace-5f39052988d8edef
Dec 15 05:42:21.726 INFO [stdout] test info ... ok
Dec 15 05:42:21.726 INFO [stdout] 
Dec 15 05:42:21.726 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 05:42:21.726 INFO [stdout] 
Dec 15 05:42:21.726 INFO [stdout] 
Dec 15 05:42:21.726 INFO [stdout] running 1 test
Dec 15 05:42:21.726 INFO [stdout] test trace ... ok
Dec 15 05:42:21.726 INFO [stdout] 
Dec 15 05:42:21.726 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 05:42:21.726 INFO [stdout] 
Dec 15 05:42:21.727 INFO [stderr]      Running /target/debug/deps/warn-d98ad71429394dcd
Dec 15 05:42:21.735 INFO [stdout] 
Dec 15 05:42:21.735 INFO [stdout] running 1 test
Dec 15 05:42:21.735 INFO [stdout] test warn ... ok
Dec 15 05:42:21.735 INFO [stdout] 
Dec 15 05:42:21.735 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 05:42:21.735 INFO [stdout] 
Dec 15 05:42:21.738 INFO [stderr]    Doc-tests log-once
Dec 15 05:42:22.640 INFO [stdout] 
Dec 15 05:42:22.640 INFO [stdout] running 1 test
Dec 15 05:42:23.735 INFO [stdout] test src/lib.rs -  (line 22) ... ok
Dec 15 05:42:23.735 INFO [stdout] 
Dec 15 05:42:23.735 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 05:42:23.735 INFO [stdout] 
Dec 15 05:42:23.760 INFO [stderr] su: No module specific data is present
Dec 15 05:42:24.629 INFO running `"docker" "inspect" "fd8ce0fd9e9f5ff264430a9a94dd564d1b7ac9e61e1cae6df2e930ec35f168c9"`
Dec 15 05:42:24.800 INFO running `"docker" "rm" "-f" "fd8ce0fd9e9f5ff264430a9a94dd564d1b7ac9e61e1cae6df2e930ec35f168c9"`
Dec 15 05:42:24.931 INFO [stdout] fd8ce0fd9e9f5ff264430a9a94dd564d1b7ac9e61e1cae6df2e930ec35f168c9