Dec 15 05:42:10.751 INFO testing log-once-0.2.0 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 05:42:10.751 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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:10.967 INFO [stdout] 56f3e64d3b34d96f67984eaf0c72581bba439ff63e21c8fc6659024bb4025a8b
Dec 15 05:42:10.969 INFO running `"docker" "start" "-a" "56f3e64d3b34d96f67984eaf0c72581bba439ff63e21c8fc6659024bb4025a8b"`
Dec 15 05:42:11.597 INFO [stderr] usermod: no changes
Dec 15 05:42:11.628 INFO [stderr]    Compiling log-once v0.2.0 (/source)
Dec 15 05:42:12.445 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.82s
Dec 15 05:42:12.445 INFO [stderr] su: No module specific data is present
Dec 15 05:42:12.787 INFO running `"docker" "inspect" "56f3e64d3b34d96f67984eaf0c72581bba439ff63e21c8fc6659024bb4025a8b"`
Dec 15 05:42:12.954 INFO running `"docker" "rm" "-f" "56f3e64d3b34d96f67984eaf0c72581bba439ff63e21c8fc6659024bb4025a8b"`
Dec 15 05:42:13.097 INFO [stdout] 56f3e64d3b34d96f67984eaf0c72581bba439ff63e21c8fc6659024bb4025a8b
Dec 15 05:42:13.099 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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:13.339 INFO [stdout] 61398cc3a1be476f92b87dec0c4b485812162f51a370c6f0b117d95e3326bce1
Dec 15 05:42:13.344 INFO running `"docker" "start" "-a" "61398cc3a1be476f92b87dec0c4b485812162f51a370c6f0b117d95e3326bce1"`
Dec 15 05:42:14.331 INFO [stderr] usermod: no changes
Dec 15 05:42:14.407 INFO [stderr]    Compiling log-once v0.2.0 (/source)
Dec 15 05:42:20.591 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.23s
Dec 15 05:42:20.599 INFO [stderr] su: No module specific data is present
Dec 15 05:42:20.883 INFO running `"docker" "inspect" "61398cc3a1be476f92b87dec0c4b485812162f51a370c6f0b117d95e3326bce1"`
Dec 15 05:42:20.973 INFO running `"docker" "rm" "-f" "61398cc3a1be476f92b87dec0c4b485812162f51a370c6f0b117d95e3326bce1"`
Dec 15 05:42:21.066 INFO [stdout] 61398cc3a1be476f92b87dec0c4b485812162f51a370c6f0b117d95e3326bce1
Dec 15 05:42:21.069 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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:21.311 INFO [stdout] fe895087e511234d7794e24f513e1bfb2d72240c694713cc9488863a2097be76
Dec 15 05:42:21.314 INFO running `"docker" "start" "-a" "fe895087e511234d7794e24f513e1bfb2d72240c694713cc9488863a2097be76"`
Dec 15 05:42:21.725 INFO [stderr] usermod: no changes
Dec 15 05:42:21.759 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Dec 15 05:42:21.760 INFO [stderr]      Running /target/debug/deps/log_once-f543e3ea28ed833f
Dec 15 05:42:21.764 INFO [stdout] 
Dec 15 05:42:21.764 INFO [stdout] running 1 test
Dec 15 05:42:21.764 INFO [stdout] test tests::called_once ... ok
Dec 15 05:42:21.764 INFO [stdout] 
Dec 15 05:42:21.764 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 05:42:21.764 INFO [stdout] 
Dec 15 05:42:21.765 INFO [stderr]      Running /target/debug/deps/debug-093b2bd7cad0c1b1
Dec 15 05:42:21.766 INFO [stdout] 
Dec 15 05:42:21.766 INFO [stdout] running 1 test
Dec 15 05:42:21.767 INFO [stdout] test debug ... ok
Dec 15 05:42:21.767 INFO [stdout] 
Dec 15 05:42:21.767 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 05:42:21.767 INFO [stdout] 
Dec 15 05:42:21.767 INFO [stderr]      Running /target/debug/deps/error-87c0fb37ca75faed
Dec 15 05:42:21.769 INFO [stdout] 
Dec 15 05:42:21.769 INFO [stdout] running 1 test
Dec 15 05:42:21.771 INFO [stdout] test error ... ok
Dec 15 05:42:21.771 INFO [stdout] 
Dec 15 05:42:21.771 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 05:42:21.771 INFO [stdout] 
Dec 15 05:42:21.771 INFO [stderr]      Running /target/debug/deps/info-4a2a6131e2afdf0d
Dec 15 05:42:21.772 INFO [stdout] 
Dec 15 05:42:21.772 INFO [stdout] running 1 test
Dec 15 05:42:21.773 INFO [stdout] test info ... ok
Dec 15 05:42:21.773 INFO [stdout] 
Dec 15 05:42:21.773 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 05:42:21.773 INFO [stdout] 
Dec 15 05:42:21.773 INFO [stderr]      Running /target/debug/deps/trace-64c5af300a087627
Dec 15 05:42:21.774 INFO [stdout] 
Dec 15 05:42:21.774 INFO [stdout] running 1 test
Dec 15 05:42:21.779 INFO [stdout] test trace ... ok
Dec 15 05:42:21.779 INFO [stdout] 
Dec 15 05:42:21.779 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 05:42:21.779 INFO [stdout] 
Dec 15 05:42:21.779 INFO [stderr]      Running /target/debug/deps/warn-a2bc1f026eb9b021
Dec 15 05:42:21.781 INFO [stdout] 
Dec 15 05:42:21.781 INFO [stdout] running 1 test
Dec 15 05:42:21.781 INFO [stdout] test warn ... ok
Dec 15 05:42:21.781 INFO [stdout] 
Dec 15 05:42:21.781 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 05:42:21.781 INFO [stdout] 
Dec 15 05:42:21.782 INFO [stderr]    Doc-tests log-once
Dec 15 05:42:22.780 INFO [stdout] 
Dec 15 05:42:22.780 INFO [stdout] running 1 test
Dec 15 05:42:24.200 INFO [stdout] test src/lib.rs -  (line 22) ... ok
Dec 15 05:42:24.200 INFO [stdout] 
Dec 15 05:42:24.200 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 05:42:24.200 INFO [stdout] 
Dec 15 05:42:24.203 INFO [stderr] su: No module specific data is present
Dec 15 05:42:24.800 INFO running `"docker" "inspect" "fe895087e511234d7794e24f513e1bfb2d72240c694713cc9488863a2097be76"`
Dec 15 05:42:24.940 INFO running `"docker" "rm" "-f" "fe895087e511234d7794e24f513e1bfb2d72240c694713cc9488863a2097be76"`
Dec 15 05:42:25.044 INFO [stdout] fe895087e511234d7794e24f513e1bfb2d72240c694713cc9488863a2097be76