Oct 11 10:13:27.638 INFO testing test-logger-0.1.0 against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling
Oct 11 10:13:27.638 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt build --frozen
Oct 11 10:13:27.638 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-0/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-0/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt build --frozen" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"`
Oct 11 10:13:27.913 INFO blam! 6a38ff85974e97775d4a23f32a7f2413b8e92120d6e5a4099e0c596e00410c18
Oct 11 10:13:27.917 INFO running `"docker" "start" "-a" "6a38ff85974e97775d4a23f32a7f2413b8e92120d6e5a4099e0c596e00410c18"`
Oct 11 10:13:29.320 INFO kablam! usermod: no changes
Oct 11 10:13:29.415 INFO kablam!    Compiling test-logger v0.1.0 (/source)
Oct 11 10:13:30.596 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 1.24s
Oct 11 10:13:30.596 INFO kablam! su: No module specific data is present
Oct 11 10:13:31.110 INFO running `"docker" "rm" "-f" "6a38ff85974e97775d4a23f32a7f2413b8e92120d6e5a4099e0c596e00410c18"`
Oct 11 10:13:31.272 INFO blam! 6a38ff85974e97775d4a23f32a7f2413b8e92120d6e5a4099e0c596e00410c18
Oct 11 10:13:31.276 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen --no-run
Oct 11 10:13:31.276 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-0/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-0/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen --no-run" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"`
Oct 11 10:13:31.606 INFO blam! f070504250e184f4db997c65e0a1029cc9383e5d08846e83aa427d3a3401b4f5
Oct 11 10:13:31.608 INFO running `"docker" "start" "-a" "f070504250e184f4db997c65e0a1029cc9383e5d08846e83aa427d3a3401b4f5"`
Oct 11 10:13:32.295 INFO kablam! usermod: no changes
Oct 11 10:13:32.384 INFO kablam!    Compiling test-logger v0.1.0 (/source)
Oct 11 10:13:35.274 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 2.96s
Oct 11 10:13:35.288 INFO kablam! su: No module specific data is present
Oct 11 10:13:35.932 INFO running `"docker" "rm" "-f" "f070504250e184f4db997c65e0a1029cc9383e5d08846e83aa427d3a3401b4f5"`
Oct 11 10:13:36.128 INFO blam! f070504250e184f4db997c65e0a1029cc9383e5d08846e83aa427d3a3401b4f5
Oct 11 10:13:36.133 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen
Oct 11 10:13:36.134 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-0/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-0/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"`
Oct 11 10:13:36.520 INFO blam! 2d7849d018abd6d8f239f754f790b4fcae165fd64462349fd188718c61c21245
Oct 11 10:13:36.523 INFO running `"docker" "start" "-a" "2d7849d018abd6d8f239f754f790b4fcae165fd64462349fd188718c61c21245"`
Oct 11 10:13:37.330 INFO kablam! usermod: no changes
Oct 11 10:13:37.424 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.05s
Oct 11 10:13:37.443 INFO kablam!      Running /target/debug/deps/test_logger-65b71c54c9c66f81
Oct 11 10:13:37.445 INFO blam! 
Oct 11 10:13:37.445 INFO blam! running 0 tests
Oct 11 10:13:37.445 INFO blam! 
Oct 11 10:13:37.445 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 11 10:13:37.445 INFO blam! 
Oct 11 10:13:37.445 INFO blam! 
Oct 11 10:13:37.445 INFO blam! running 1 test
Oct 11 10:13:37.445 INFO kablam!      Running /target/debug/deps/should_fail-64073f8d2d7eb855
Oct 11 10:13:37.445 INFO kablam! ERROR:should_fail: This output will be be printed by default if the test fails
Oct 11 10:13:37.479 INFO blam! test failing_test_has_log_output_printed_if_log_level_is_high_enough ... FAILED
Oct 11 10:13:37.479 INFO blam! 
Oct 11 10:13:37.479 INFO blam! failures:
Oct 11 10:13:37.479 INFO blam! 
Oct 11 10:13:37.479 INFO blam! ---- failing_test_has_log_output_printed_if_log_level_is_high_enough stdout ----
Oct 11 10:13:37.479 INFO blam! thread 'failing_test_has_log_output_printed_if_log_level_is_high_enough' panicked at 'assertion failed: `(left == right)`
Oct 11 10:13:37.479 INFO blam!   left: `100`,
Oct 11 10:13:37.479 INFO blam!  right: `99`', tests/should_fail.rs:14:5
Oct 11 10:13:37.479 INFO blam! stack backtrace:
Oct 11 10:13:37.479 INFO blam!    0:     0x56376bd6baee - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h52ace5b3d4e95617
Oct 11 10:13:37.479 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Oct 11 10:13:37.479 INFO blam!    1:     0x56376bd74996 - std::sys_common::backtrace::print::h6cfc8a66009c1cc9
Oct 11 10:13:37.479 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Oct 11 10:13:37.479 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Oct 11 10:13:37.479 INFO blam!    2:     0x56376bd6f6ed - std::panicking::default_hook::{{closure}}::h47bedf778bf88480
Oct 11 10:13:37.479 INFO blam!                                at libstd/panicking.rs:211
Oct 11 10:13:37.479 INFO blam!    3:     0x56376bd6f3fb - std::panicking::default_hook::h1ef46fa7cd894809
Oct 11 10:13:37.479 INFO blam!                                at libstd/panicking.rs:221
Oct 11 10:13:37.479 INFO blam!    4:     0x56376bd6fd9c - std::panicking::rust_panic_with_hook::h05510fde8841c579
Oct 11 10:13:37.479 INFO blam!                                at libstd/panicking.rs:476
Oct 11 10:13:37.479 INFO blam!    5:     0x56376bd6f959 - std::panicking::continue_panic_fmt::h6e11f50e47f94b00
Oct 11 10:13:37.479 INFO blam!                                at libstd/panicking.rs:390
Oct 11 10:13:37.479 INFO blam!    6:     0x56376bd6f8bd - std::panicking::begin_panic_fmt::h0063f49bf5a35411
Oct 11 10:13:37.479 INFO blam!                                at libstd/panicking.rs:345
Oct 11 10:13:37.479 INFO blam!    7:     0x56376bbfff77 - should_fail::failing_test_has_log_output_printed_if_log_level_is_high_enough::h74caf962c7375b57
Oct 11 10:13:37.479 INFO blam!                                at tests/should_fail.rs:14
Oct 11 10:13:37.479 INFO blam!    8:     0x56376bbffdf9 - should_fail::failing_test_has_log_output_printed_if_log_level_is_high_enough::{{closure}}::hf5fe268be635fc2e
Oct 11 10:13:37.479 INFO blam!                                at /source/<::test_logger::test macros>:4
Oct 11 10:13:37.479 INFO blam!    9:     0x56376bc0057d - core::ops::function::FnOnce::call_once::h173d61de356d1c7b
Oct 11 10:13:37.479 INFO blam!                                at libcore/ops/function.rs:238
Oct 11 10:13:37.479 INFO blam!   10:     0x56376bc01fee - <F as alloc::boxed::FnBox<A>>::call_box::h95b7cb918af67b5d
Oct 11 10:13:37.479 INFO blam!                                at libtest/lib.rs:1468
Oct 11 10:13:37.479 INFO blam!                                at libcore/ops/function.rs:238
Oct 11 10:13:37.479 INFO blam!                                at liballoc/boxed.rs:672
Oct 11 10:13:37.479 INFO blam!   11:     0x56376bd7fb69 - __rust_maybe_catch_panic
Oct 11 10:13:37.479 INFO blam!                                at libpanic_unwind/lib.rs:102
Oct 11 10:13:37.479 INFO blam!   12:     0x56376bc22b3d - std::sys_common::backtrace::__rust_begin_short_backtrace::he1120aab928e481f
Oct 11 10:13:37.479 INFO blam!                                at libstd/panicking.rs:289
Oct 11 10:13:37.479 INFO blam!                                at libstd/panic.rs:392
Oct 11 10:13:37.479 INFO blam!                                at libtest/lib.rs:1423
Oct 11 10:13:37.479 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Oct 11 10:13:37.479 INFO blam!   13:     0x56376bc23744 - std::panicking::try::do_call::ha990a345054c2d63
Oct 11 10:13:37.479 INFO blam!                                at libstd/thread/mod.rs:409
Oct 11 10:13:37.479 INFO blam!                                at libstd/panic.rs:313
Oct 11 10:13:37.479 INFO blam!                                at libstd/panicking.rs:310
Oct 11 10:13:37.479 INFO blam!   14:     0x56376bd7fb69 - __rust_maybe_catch_panic
Oct 11 10:13:37.479 INFO blam!                                at libpanic_unwind/lib.rs:102
Oct 11 10:13:37.479 INFO blam!   15:     0x56376bc10d06 - <F as alloc::boxed::FnBox<A>>::call_box::h96125cb29a9ba44b
Oct 11 10:13:37.479 INFO blam!                                at libstd/panicking.rs:289
Oct 11 10:13:37.479 INFO blam!                                at libstd/panic.rs:392
Oct 11 10:13:37.479 INFO blam!                                at libstd/thread/mod.rs:408
Oct 11 10:13:37.479 INFO blam!                                at liballoc/boxed.rs:672
Oct 11 10:13:37.479 INFO blam!   16:     0x56376bd6db2a - std::sys_common::thread::start_thread::h97c4c5d82028f6b1
Oct 11 10:13:37.479 INFO blam!                                at liballoc/boxed.rs:682
Oct 11 10:13:37.479 INFO blam!                                at libstd/sys_common/thread.rs:24
Oct 11 10:13:37.479 INFO blam!   17:     0x56376bd632b5 - std::sys::unix::thread::Thread::new::thread_start::he7d65efc4dd16886
Oct 11 10:13:37.479 INFO blam!                                at libstd/sys/unix/thread.rs:90
Oct 11 10:13:37.479 INFO blam!   18:     0x7f7bf89376b9 - start_thread
Oct 11 10:13:37.479 INFO blam!   19:     0x7f7bf845741c - clone
Oct 11 10:13:37.479 INFO blam!   20:                0x0 - <unknown>
Oct 11 10:13:37.479 INFO blam! 
Oct 11 10:13:37.480 INFO blam! 
Oct 11 10:13:37.480 INFO blam! failures:
Oct 11 10:13:37.480 INFO blam!     failing_test_has_log_output_printed_if_log_level_is_high_enough
Oct 11 10:13:37.480 INFO blam! 
Oct 11 10:13:37.480 INFO blam! test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Oct 11 10:13:37.480 INFO blam! 
Oct 11 10:13:37.485 INFO kablam! error: test failed, to rerun pass '--test should_fail'
Oct 11 10:13:37.486 INFO kablam! su: No module specific data is present
Oct 11 10:13:38.165 INFO running `"docker" "rm" "-f" "2d7849d018abd6d8f239f754f790b4fcae165fd64462349fd188718c61c21245"`
Oct 11 10:13:38.444 INFO blam! 2d7849d018abd6d8f239f754f790b4fcae165fd64462349fd188718c61c21245