Dec 07 08:11:03.155 INFO testing log-once-0.2.0 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 08:11:03.155 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-7/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 07 08:11:03.327 INFO [stdout] 4c2c9a88d27d32e310d77637c069858c201bd68891db51b430a17d8c53b86b74
Dec 07 08:11:03.329 INFO running `"docker" "start" "-a" "4c2c9a88d27d32e310d77637c069858c201bd68891db51b430a17d8c53b86b74"`
Dec 07 08:11:03.868 INFO [stderr] usermod: no changes
Dec 07 08:11:03.920 INFO [stderr]    Compiling log-once v0.2.0 (/source)
Dec 07 08:11:04.596 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.71s
Dec 07 08:11:04.599 INFO [stderr] su: No module specific data is present
Dec 07 08:11:04.922 INFO running `"docker" "inspect" "4c2c9a88d27d32e310d77637c069858c201bd68891db51b430a17d8c53b86b74"`
Dec 07 08:11:05.041 INFO running `"docker" "rm" "-f" "4c2c9a88d27d32e310d77637c069858c201bd68891db51b430a17d8c53b86b74"`
Dec 07 08:11:05.172 INFO [stdout] 4c2c9a88d27d32e310d77637c069858c201bd68891db51b430a17d8c53b86b74
Dec 07 08:11:05.174 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-7/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 07 08:11:05.374 INFO [stdout] f5ea237ff6b88e08f8e16b56f04e3710894f4373761415a106a5c08dd79b8620
Dec 07 08:11:05.374 INFO running `"docker" "start" "-a" "f5ea237ff6b88e08f8e16b56f04e3710894f4373761415a106a5c08dd79b8620"`
Dec 07 08:11:05.937 INFO [stderr] usermod: no changes
Dec 07 08:11:06.008 INFO [stderr]    Compiling log-once v0.2.0 (/source)
Dec 07 08:11:10.804 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.85s
Dec 07 08:11:10.808 INFO [stderr] su: No module specific data is present
Dec 07 08:11:11.086 INFO running `"docker" "inspect" "f5ea237ff6b88e08f8e16b56f04e3710894f4373761415a106a5c08dd79b8620"`
Dec 07 08:11:11.230 INFO running `"docker" "rm" "-f" "f5ea237ff6b88e08f8e16b56f04e3710894f4373761415a106a5c08dd79b8620"`
Dec 07 08:11:11.408 INFO [stdout] f5ea237ff6b88e08f8e16b56f04e3710894f4373761415a106a5c08dd79b8620
Dec 07 08:11:11.414 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-7/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 07 08:11:11.719 INFO [stdout] 19d32ffb2c2ca4d47205b50807e44dac8b7c4b509de61e70da66adc25fcd56fc
Dec 07 08:11:11.724 INFO running `"docker" "start" "-a" "19d32ffb2c2ca4d47205b50807e44dac8b7c4b509de61e70da66adc25fcd56fc"`
Dec 07 08:11:12.213 INFO [stderr] usermod: no changes
Dec 07 08:11:12.242 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.01s
Dec 07 08:11:12.243 INFO [stderr]      Running /target/debug/deps/log_once-2d80906f4f539a07
Dec 07 08:11:12.246 INFO [stdout] 
Dec 07 08:11:12.246 INFO [stdout] running 1 test
Dec 07 08:11:12.248 INFO [stdout] test tests::called_once ... ok
Dec 07 08:11:12.248 INFO [stdout] 
Dec 07 08:11:12.248 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 08:11:12.248 INFO [stdout] 
Dec 07 08:11:12.251 INFO [stderr]      Running /target/debug/deps/debug-271b27d6d2d68bc4
Dec 07 08:11:12.257 INFO [stdout] 
Dec 07 08:11:12.257 INFO [stdout] running 1 test
Dec 07 08:11:12.260 INFO [stdout] test debug ... ok
Dec 07 08:11:12.260 INFO [stdout] 
Dec 07 08:11:12.260 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 08:11:12.260 INFO [stdout] 
Dec 07 08:11:12.261 INFO [stderr]      Running /target/debug/deps/error-74576a5bf40f9e04
Dec 07 08:11:12.265 INFO [stdout] 
Dec 07 08:11:12.265 INFO [stdout] running 1 test
Dec 07 08:11:12.266 INFO [stdout] test error ... ok
Dec 07 08:11:12.266 INFO [stdout] 
Dec 07 08:11:12.266 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 08:11:12.266 INFO [stdout] 
Dec 07 08:11:12.266 INFO [stderr]      Running /target/debug/deps/info-faf6ca557234368b
Dec 07 08:11:12.267 INFO [stdout] 
Dec 07 08:11:12.267 INFO [stdout] running 1 test
Dec 07 08:11:12.267 INFO [stdout] test info ... ok
Dec 07 08:11:12.267 INFO [stdout] 
Dec 07 08:11:12.267 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 08:11:12.267 INFO [stdout] 
Dec 07 08:11:12.268 INFO [stderr]      Running /target/debug/deps/trace-4d14450e19612093
Dec 07 08:11:12.270 INFO [stdout] 
Dec 07 08:11:12.270 INFO [stdout] running 1 test
Dec 07 08:11:12.272 INFO [stdout] test trace ... ok
Dec 07 08:11:12.272 INFO [stderr]      Running /target/debug/deps/warn-bc2df2413b4531ac
Dec 07 08:11:12.272 INFO [stdout] 
Dec 07 08:11:12.272 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 08:11:12.272 INFO [stdout] 
Dec 07 08:11:12.278 INFO [stdout] 
Dec 07 08:11:12.278 INFO [stdout] running 1 test
Dec 07 08:11:12.278 INFO [stdout] test warn ... ok
Dec 07 08:11:12.278 INFO [stdout] 
Dec 07 08:11:12.278 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 08:11:12.278 INFO [stdout] 
Dec 07 08:11:12.278 INFO [stderr]    Doc-tests log-once
Dec 07 08:11:13.168 INFO [stdout] 
Dec 07 08:11:13.168 INFO [stdout] running 1 test
Dec 07 08:11:14.418 INFO [stdout] test src/lib.rs -  (line 22) ... ok
Dec 07 08:11:14.418 INFO [stdout] 
Dec 07 08:11:14.418 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 08:11:14.418 INFO [stdout] 
Dec 07 08:11:14.436 INFO [stderr] su: No module specific data is present
Dec 07 08:11:14.903 INFO running `"docker" "inspect" "19d32ffb2c2ca4d47205b50807e44dac8b7c4b509de61e70da66adc25fcd56fc"`
Dec 07 08:11:15.062 INFO running `"docker" "rm" "-f" "19d32ffb2c2ca4d47205b50807e44dac8b7c4b509de61e70da66adc25fcd56fc"`
Dec 07 08:11:15.170 INFO [stdout] 19d32ffb2c2ca4d47205b50807e44dac8b7c4b509de61e70da66adc25fcd56fc