Nov 17 21:48:13.208 INFO testing stderrlog-0.4.1 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 17 21:48:13.208 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 17 21:48:13.384 INFO [stdout] 6e8a2565b405fcd54967604122fe19399092865b7ab45bdd5b8813773cf2bea4
Nov 17 21:48:13.386 INFO running `"docker" "start" "-a" "6e8a2565b405fcd54967604122fe19399092865b7ab45bdd5b8813773cf2bea4"`
Nov 17 21:48:13.822 INFO [stderr] usermod: no changes
Nov 17 21:48:13.897 INFO [stderr]    Compiling stderrlog v0.4.1 (/source)
Nov 17 21:48:14.966 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.13s
Nov 17 21:48:14.972 INFO [stderr] su: No module specific data is present
Nov 17 21:48:15.246 INFO running `"docker" "inspect" "6e8a2565b405fcd54967604122fe19399092865b7ab45bdd5b8813773cf2bea4"`
Nov 17 21:48:15.361 INFO running `"docker" "rm" "-f" "6e8a2565b405fcd54967604122fe19399092865b7ab45bdd5b8813773cf2bea4"`
Nov 17 21:48:15.427 INFO [stdout] 6e8a2565b405fcd54967604122fe19399092865b7ab45bdd5b8813773cf2bea4
Nov 17 21:48:15.429 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 17 21:48:15.610 INFO [stdout] 1a9196b1ef4e8af809c2a580d1601356d63df17ce8a38a0f5dda91a50b8fe774
Nov 17 21:48:15.611 INFO running `"docker" "start" "-a" "1a9196b1ef4e8af809c2a580d1601356d63df17ce8a38a0f5dda91a50b8fe774"`
Nov 17 21:48:16.027 INFO [stderr] usermod: no changes
Nov 17 21:48:16.153 INFO [stderr]    Compiling clap v2.22.2
Nov 17 21:48:30.044 INFO [stderr]    Compiling structopt v0.2.13
Nov 17 21:48:30.759 INFO [stderr]    Compiling stderrlog v0.4.1 (/source)
Nov 17 21:48:35.243 INFO [stderr] warning: unused `#[macro_use]` import
Nov 17 21:48:35.244 INFO [stderr]   --> examples/structopt.rs:12:1
Nov 17 21:48:35.244 INFO [stderr]    |
Nov 17 21:48:35.244 INFO [stderr] 12 | #[macro_use]
Nov 17 21:48:35.245 INFO [stderr]    | ^^^^^^^^^^^^
Nov 17 21:48:35.245 INFO [stderr]    |
Nov 17 21:48:35.245 INFO [stderr]    = note: #[warn(unused_imports)] on by default
Nov 17 21:48:35.245 INFO [stderr] 
Nov 17 21:48:37.787 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 21.73s
Nov 17 21:48:37.792 INFO [stderr] su: No module specific data is present
Nov 17 21:48:38.208 INFO running `"docker" "inspect" "1a9196b1ef4e8af809c2a580d1601356d63df17ce8a38a0f5dda91a50b8fe774"`
Nov 17 21:48:38.508 INFO running `"docker" "rm" "-f" "1a9196b1ef4e8af809c2a580d1601356d63df17ce8a38a0f5dda91a50b8fe774"`
Nov 17 21:48:38.787 INFO [stdout] 1a9196b1ef4e8af809c2a580d1601356d63df17ce8a38a0f5dda91a50b8fe774
Nov 17 21:48:38.793 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 17 21:48:39.359 INFO [stdout] 63b9e1f3a81a0487a91af062fb47d8c7e5c63689225c2b968905b48e9657e22f
Nov 17 21:48:39.361 INFO running `"docker" "start" "-a" "63b9e1f3a81a0487a91af062fb47d8c7e5c63689225c2b968905b48e9657e22f"`
Nov 17 21:48:40.775 INFO [stderr] usermod: no changes
Nov 17 21:48:40.902 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.10s
Nov 17 21:48:40.915 INFO [stderr]      Running /target/debug/deps/stderrlog-0a56d03d7943cd95
Nov 17 21:48:40.916 INFO [stdout] 
Nov 17 21:48:40.916 INFO [stdout] running 2 tests
Nov 17 21:48:40.916 INFO [stdout] test tests::submodule ... ok
Nov 17 21:48:40.919 INFO [stdout] test tests::test_default_level ... ok
Nov 17 21:48:40.919 INFO [stdout] 
Nov 17 21:48:40.919 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 21:48:40.919 INFO [stdout] 
Nov 17 21:48:40.919 INFO [stderr]      Running /target/debug/deps/debug_level-522de1330de7a220
Nov 17 21:48:40.924 INFO [stdout] 
Nov 17 21:48:40.924 INFO [stdout] running 1 test
Nov 17 21:48:40.927 INFO [stderr] ERROR - error msg
Nov 17 21:48:40.927 INFO [stderr] WARN - warning msg
Nov 17 21:48:40.927 INFO [stderr] INFO - info msg
Nov 17 21:48:40.927 INFO [stderr] DEBUG - debug msg
Nov 17 21:48:40.927 INFO [stdout] test debug_level ... ok
Nov 17 21:48:40.927 INFO [stdout] 
Nov 17 21:48:40.927 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 21:48:40.927 INFO [stdout] 
Nov 17 21:48:40.931 INFO [stderr]      Running /target/debug/deps/error_level-a744b8d455574a9e
Nov 17 21:48:40.943 INFO [stdout] 
Nov 17 21:48:40.943 INFO [stdout] running 1 test
Nov 17 21:48:40.951 INFO [stderr] ERROR - error msg
Nov 17 21:48:40.951 INFO [stderr]      Running /target/debug/deps/info_level-aab34303d85de338
Nov 17 21:48:40.951 INFO [stdout] test error_level ... ok
Nov 17 21:48:40.951 INFO [stdout] 
Nov 17 21:48:40.951 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 21:48:40.951 INFO [stdout] 
Nov 17 21:48:40.959 INFO [stdout] 
Nov 17 21:48:40.959 INFO [stdout] running 1 test
Nov 17 21:48:40.959 INFO [stderr] ERROR - error msg
Nov 17 21:48:40.959 INFO [stderr] WARN - warning msg
Nov 17 21:48:40.959 INFO [stderr] INFO - info msg
Nov 17 21:48:40.971 INFO [stdout] test info_level ... ok
Nov 17 21:48:40.971 INFO [stdout] 
Nov 17 21:48:40.971 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 21:48:40.971 INFO [stdout] 
Nov 17 21:48:40.971 INFO [stdout] 
Nov 17 21:48:40.971 INFO [stdout] running 3 tests
Nov 17 21:48:40.971 INFO [stderr]      Running /target/debug/deps/module_inclusion-2fffe75e5f2fb9d6
Nov 17 21:48:40.978 INFO [stdout] test included::b::super_and_submodule_included ... ok
Nov 17 21:48:40.978 INFO [stdout] test included_not::including_module_with_substring_name ... ok
Nov 17 21:48:40.978 INFO [stdout] test included::b::sub_and_supermodule_included ... ok
Nov 17 21:48:40.978 INFO [stdout] 
Nov 17 21:48:40.978 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 21:48:40.978 INFO [stdout] 
Nov 17 21:48:40.978 INFO [stderr]      Running /target/debug/deps/quiet_trace_level-1c91e7299d133daa
Nov 17 21:48:40.983 INFO [stdout] 
Nov 17 21:48:40.983 INFO [stdout] running 1 test
Nov 17 21:48:40.991 INFO [stderr]      Running /target/debug/deps/trace_level-8cdc12ab358d76ff
Nov 17 21:48:40.991 INFO [stderr] ERROR - error msg
Nov 17 21:48:40.991 INFO [stderr] WARN - warning msg
Nov 17 21:48:40.991 INFO [stderr] INFO - info msg
Nov 17 21:48:40.991 INFO [stderr] DEBUG - debug msg
Nov 17 21:48:40.991 INFO [stderr] TRACE - trace msg
Nov 17 21:48:40.991 INFO [stderr]      Running /target/debug/deps/warn_level-df7e13d54e274498
Nov 17 21:48:40.991 INFO [stdout] test quiet_trace_level ... ok
Nov 17 21:48:40.991 INFO [stdout] 
Nov 17 21:48:40.991 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 21:48:40.991 INFO [stdout] 
Nov 17 21:48:40.991 INFO [stdout] 
Nov 17 21:48:40.991 INFO [stdout] running 1 test
Nov 17 21:48:40.991 INFO [stdout] test trace_level ... ok
Nov 17 21:48:40.991 INFO [stdout] 
Nov 17 21:48:40.991 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 21:48:40.991 INFO [stdout] 
Nov 17 21:48:40.991 INFO [stdout] 
Nov 17 21:48:40.991 INFO [stdout] running 1 test
Nov 17 21:48:41.003 INFO [stderr] ERROR - error msg
Nov 17 21:48:41.003 INFO [stderr] WARN - warning msg
Nov 17 21:48:41.003 INFO [stderr]    Doc-tests stderrlog
Nov 17 21:48:41.003 INFO [stdout] test warn_level ... ok
Nov 17 21:48:41.003 INFO [stdout] 
Nov 17 21:48:41.003 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 21:48:41.003 INFO [stdout] 
Nov 17 21:48:41.819 INFO [stdout] 
Nov 17 21:48:41.819 INFO [stdout] running 4 tests
Nov 17 21:48:45.104 INFO [stdout] test src/lib.rs -  (line 19) ... ok
Nov 17 21:48:46.138 INFO [stdout] test src/lib.rs -  (line 122) ... ok
Nov 17 21:48:46.872 INFO [stdout] test src/lib.rs -  (line 79) ... ok
Nov 17 21:48:46.884 INFO [stdout] test src/lib.rs -  (line 35) ... ok
Nov 17 21:48:46.884 INFO [stdout] 
Nov 17 21:48:46.884 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 21:48:46.884 INFO [stdout] 
Nov 17 21:48:46.893 INFO [stderr] su: No module specific data is present
Nov 17 21:48:47.257 INFO running `"docker" "inspect" "63b9e1f3a81a0487a91af062fb47d8c7e5c63689225c2b968905b48e9657e22f"`
Nov 17 21:48:47.409 INFO running `"docker" "rm" "-f" "63b9e1f3a81a0487a91af062fb47d8c7e5c63689225c2b968905b48e9657e22f"`
Nov 17 21:48:47.563 INFO [stdout] 63b9e1f3a81a0487a91af062fb47d8c7e5c63689225c2b968905b48e9657e22f