Nov 17 19:53:44.615 INFO testing tsc-time-0.1.0 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 17 19:53:44.616 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 19:53:44.791 INFO [stdout] ac49ed3c80d941246b03ab32e5dae14d305ba607b4287a196c899fa795cbc5a0
Nov 17 19:53:44.792 INFO running `"docker" "start" "-a" "ac49ed3c80d941246b03ab32e5dae14d305ba607b4287a196c899fa795cbc5a0"`
Nov 17 19:53:45.243 INFO [stderr] usermod: no changes
Nov 17 19:53:45.270 INFO [stderr]    Compiling tsc-time v0.1.0 (/source)
Nov 17 19:53:45.702 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.44s
Nov 17 19:53:45.704 INFO [stderr] su: No module specific data is present
Nov 17 19:53:46.009 INFO running `"docker" "inspect" "ac49ed3c80d941246b03ab32e5dae14d305ba607b4287a196c899fa795cbc5a0"`
Nov 17 19:53:46.111 INFO running `"docker" "rm" "-f" "ac49ed3c80d941246b03ab32e5dae14d305ba607b4287a196c899fa795cbc5a0"`
Nov 17 19:53:46.218 INFO [stdout] ac49ed3c80d941246b03ab32e5dae14d305ba607b4287a196c899fa795cbc5a0
Nov 17 19:53:46.218 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 19:53:46.494 INFO [stdout] 654102fa15b8a284263bea3da67ed7dc7970dc27912aedf6546a120eeab6c4ce
Nov 17 19:53:46.496 INFO running `"docker" "start" "-a" "654102fa15b8a284263bea3da67ed7dc7970dc27912aedf6546a120eeab6c4ce"`
Nov 17 19:53:46.964 INFO [stderr] usermod: no changes
Nov 17 19:53:46.993 INFO [stderr]    Compiling tsc-time v0.1.0 (/source)
Nov 17 19:53:47.867 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.87s
Nov 17 19:53:47.867 INFO [stderr] su: No module specific data is present
Nov 17 19:53:48.387 INFO running `"docker" "inspect" "654102fa15b8a284263bea3da67ed7dc7970dc27912aedf6546a120eeab6c4ce"`
Nov 17 19:53:48.693 INFO running `"docker" "rm" "-f" "654102fa15b8a284263bea3da67ed7dc7970dc27912aedf6546a120eeab6c4ce"`
Nov 17 19:53:48.913 INFO [stdout] 654102fa15b8a284263bea3da67ed7dc7970dc27912aedf6546a120eeab6c4ce
Nov 17 19:53:48.915 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 19:53:49.283 INFO [stdout] c5ceeb9bf5708ed08af0b124f39677f1e5d2435357e26eeb5c4b5857906f2f67
Nov 17 19:53:49.284 INFO running `"docker" "start" "-a" "c5ceeb9bf5708ed08af0b124f39677f1e5d2435357e26eeb5c4b5857906f2f67"`
Nov 17 19:53:50.127 INFO [stderr] usermod: no changes
Nov 17 19:53:50.191 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Nov 17 19:53:50.191 INFO [stderr]      Running /target/debug/deps/tsc_time-ebe263ab04333065
Nov 17 19:53:50.203 INFO [stdout] 
Nov 17 19:53:50.203 INFO [stdout] running 3 tests
Nov 17 19:53:50.203 INFO [stdout] test tests::print_span_overhead ... ok
Nov 17 19:53:50.204 INFO [stdout] test tests::invariant_tsc ... ok
Nov 17 19:53:50.204 INFO [stdout] test tests::start_stop ... ok
Nov 17 19:53:50.204 INFO [stdout] 
Nov 17 19:53:50.204 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 19:53:50.204 INFO [stdout] 
Nov 17 19:53:50.204 INFO [stderr]    Doc-tests tsc-time
Nov 17 19:53:50.879 INFO [stdout] 
Nov 17 19:53:50.879 INFO [stdout] running 1 test
Nov 17 19:53:52.015 INFO [stdout] test src/lib.rs -  (line 8) ... FAILED
Nov 17 19:53:52.016 INFO [stdout] 
Nov 17 19:53:52.016 INFO [stdout] failures:
Nov 17 19:53:52.016 INFO [stdout] 
Nov 17 19:53:52.016 INFO [stdout] ---- src/lib.rs -  (line 8) stdout ----
Nov 17 19:53:52.016 INFO [stdout] error[E0432]: unresolved import `tsc`
Nov 17 19:53:52.016 INFO [stdout]  --> src/lib.rs:12:5
Nov 17 19:53:52.016 INFO [stdout]   |
Nov 17 19:53:52.016 INFO [stdout] 6 | use tsc::*;
Nov 17 19:53:52.016 INFO [stdout]   |     ^^^ Could not find `tsc` in `{{root}}`
Nov 17 19:53:52.016 INFO [stdout] 
Nov 17 19:53:52.016 INFO [stdout] error[E0433]: failed to resolve. Use of undeclared type or module `Duration`
Nov 17 19:53:52.016 INFO [stdout]   --> src/lib.rs:25:5
Nov 17 19:53:52.016 INFO [stdout]    |
Nov 17 19:53:52.016 INFO [stdout] 19 |     Duration::span(|| black_box(fibonacci(black_box(8))));
Nov 17 19:53:52.016 INFO [stdout]    |     ^^^^^^^^ Use of undeclared type or module `Duration`
Nov 17 19:53:52.016 INFO [stdout] 
Nov 17 19:53:52.016 INFO [stdout] error[E0425]: cannot find function `has_invariant_tsc` in this scope
Nov 17 19:53:52.016 INFO [stdout]   --> src/lib.rs:22:9
Nov 17 19:53:52.016 INFO [stdout]    |
Nov 17 19:53:52.016 INFO [stdout] 16 | assert!(has_invariant_tsc(), "The TSC is not invariant!");
Nov 17 19:53:52.016 INFO [stdout]    |         ^^^^^^^^^^^^^^^^^ not found in this scope
Nov 17 19:53:52.017 INFO [stdout] help: possible candidate is found in another module, you can import it into scope
Nov 17 19:53:52.017 INFO [stdout]    |
Nov 17 19:53:52.017 INFO [stdout] 4  | use tsc_time::has_invariant_tsc;
Nov 17 19:53:52.017 INFO [stdout]    |
Nov 17 19:53:52.017 INFO [stdout] 
Nov 17 19:53:52.017 INFO [stdout] thread 'src/lib.rs -  (line 8)' panicked at 'couldn't compile the test', librustdoc/test.rs:323:13
Nov 17 19:53:52.017 INFO [stdout] stack backtrace:
Nov 17 19:53:52.017 INFO [stdout]    0:     0x7f15fc45ebc3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h1a944b80a560daa8
Nov 17 19:53:52.017 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 17 19:53:52.017 INFO [stdout]    1:     0x7f15fc4562a8 - std::sys_common::backtrace::_print::h18f735b4c3a49bdb
Nov 17 19:53:52.017 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Nov 17 19:53:52.017 INFO [stdout]    2:     0x7f15fc45b594 - std::panicking::default_hook::{{closure}}::h97ab3548a215c097
Nov 17 19:53:52.017 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Nov 17 19:53:52.017 INFO [stdout]                                at libstd/panicking.rs:211
Nov 17 19:53:52.017 INFO [stdout]    3:     0x7f15fc45b27c - std::panicking::default_hook::h98c2a01ef734e4b0
Nov 17 19:53:52.017 INFO [stdout]                                at libstd/panicking.rs:221
Nov 17 19:53:52.017 INFO [stdout]    4:     0x7f15fe5b7d01 - rustc::util::common::panic_hook::hb5e29ad6fd71b050
Nov 17 19:53:52.017 INFO [stdout]    5:     0x7f15fc45bd79 - std::panicking::rust_panic_with_hook::hb4b3f28eb79ea752
Nov 17 19:53:52.017 INFO [stdout]                                at libstd/panicking.rs:480
Nov 17 19:53:52.017 INFO [stdout]    6:     0x555df2eed907 - std::panicking::begin_panic::hb09a382a0ab7f900
Nov 17 19:53:52.017 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:410
Nov 17 19:53:52.017 INFO [stdout]    7:     0x555df30b1485 - rustdoc::test::run_test::h7e7f9ec8aeaa87f8
Nov 17 19:53:52.017 INFO [stdout]                                at librustdoc/test.rs:323
Nov 17 19:53:52.017 INFO [stdout]    8:     0x555df2f84fae - <scoped_tls::ScopedKey<T>>::set::h908a96c8ec4ddccf
Nov 17 19:53:52.018 INFO [stdout]                                at librustdoc/test.rs:654
Nov 17 19:53:52.018 INFO [stdout]                                at /cargo/registry/src/github.com-1ecc6299db9ec823/scoped-tls-0.1.2/src/lib.rs:155
Nov 17 19:53:52.018 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libsyntax/lib.rs:124
Nov 17 19:53:52.018 INFO [stdout]                                at /cargo/registry/src/github.com-1ecc6299db9ec823/scoped-tls-0.1.2/src/lib.rs:155
Nov 17 19:53:52.018 INFO [stdout]    9:     0x555df2f63871 - syntax::with_globals::h9807168bf3c0d85b
Nov 17 19:53:52.018 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libsyntax/lib.rs:123
Nov 17 19:53:52.018 INFO [stdout]   10:     0x555df2eedb4b - std::panicking::try::do_call::h64a568dca1c842cf
Nov 17 19:53:52.018 INFO [stdout]                                at librustdoc/test.rs:651
Nov 17 19:53:52.018 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319
Nov 17 19:53:52.018 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310
Nov 17 19:53:52.018 INFO [stdout]   11:     0x7f15fc479049 - __rust_maybe_catch_panic
Nov 17 19:53:52.018 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 19:53:52.018 INFO [stdout]   12:     0x555df2f7d069 - rustc_driver::in_named_rustc_thread::hc08d76125a58a368
Nov 17 19:53:52.018 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 17 19:53:52.018 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 17 19:53:52.018 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/librustc_driver/lib.rs:1554
Nov 17 19:53:52.018 INFO [stdout]   13:     0x555df3085a80 - <F as alloc::boxed::FnBox<A>>::call_box::hf60ef6def5189442
Nov 17 19:53:52.018 INFO [stdout]                                at librustdoc/test.rs:651
Nov 17 19:53:52.018 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 17 19:53:52.018 INFO [stdout]   14:     0x7f15fda99b72 - <F as alloc::boxed::FnBox<A>>::call_box::hb3eb0389b4ad8337
Nov 17 19:53:52.018 INFO [stdout]                                at libtest/lib.rs:1464
Nov 17 19:53:52.018 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 17 19:53:52.019 INFO [stdout]   15:     0x7f15fc479049 - __rust_maybe_catch_panic
Nov 17 19:53:52.019 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 19:53:52.019 INFO [stdout]   16:     0x7f15fda91493 - std::sys_common::backtrace::__rust_begin_short_backtrace::h89ea8afe71e36f86
Nov 17 19:53:52.019 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 17 19:53:52.019 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 17 19:53:52.019 INFO [stdout]                                at libtest/lib.rs:1426
Nov 17 19:53:52.019 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/sys_common/backtrace.rs:136
Nov 17 19:53:52.019 INFO [stdout]   17:     0x7f15fda92344 - std::panicking::try::do_call::h4132042e0cbea3ae
Nov 17 19:53:52.019 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:477
Nov 17 19:53:52.019 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319
Nov 17 19:53:52.019 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310
Nov 17 19:53:52.019 INFO [stdout]   18:     0x7f15fc479049 - __rust_maybe_catch_panic
Nov 17 19:53:52.019 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 19:53:52.019 INFO [stdout]   19:     0x7f15fda99a4c - <F as alloc::boxed::FnBox<A>>::call_box::hab7f616f05880bf6
Nov 17 19:53:52.019 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 17 19:53:52.019 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 17 19:53:52.019 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:476
Nov 17 19:53:52.019 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 17 19:53:52.019 INFO [stdout]   20:     0x7f15fc46bb2d - std::sys::unix::thread::Thread::new::thread_start::h5f8d67158a1ca038
Nov 17 19:53:52.019 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:683
Nov 17 19:53:52.019 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Nov 17 19:53:52.019 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Nov 17 19:53:52.019 INFO [stdout]   21:     0x7f15fc1de6b9 - start_thread
Nov 17 19:53:52.019 INFO [stdout]   22:     0x7f15fbaf641c - clone
Nov 17 19:53:52.020 INFO [stdout]   23:                0x0 - <unknown>
Nov 17 19:53:52.020 INFO [stdout] query stack during panic:
Nov 17 19:53:52.020 INFO [stdout] end of query stack
Nov 17 19:53:52.020 INFO [stdout] 
Nov 17 19:53:52.020 INFO [stdout] 
Nov 17 19:53:52.020 INFO [stdout] failures:
Nov 17 19:53:52.020 INFO [stdout]     src/lib.rs -  (line 8)
Nov 17 19:53:52.020 INFO [stdout] 
Nov 17 19:53:52.020 INFO [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 19:53:52.020 INFO [stdout] 
Nov 17 19:53:52.035 INFO [stderr] error: test failed, to rerun pass '--doc'
Nov 17 19:53:52.035 INFO [stderr] su: No module specific data is present
Nov 17 19:53:52.309 INFO running `"docker" "inspect" "c5ceeb9bf5708ed08af0b124f39677f1e5d2435357e26eeb5c4b5857906f2f67"`
Nov 17 19:53:52.397 INFO running `"docker" "rm" "-f" "c5ceeb9bf5708ed08af0b124f39677f1e5d2435357e26eeb5c4b5857906f2f67"`
Nov 17 19:53:52.481 INFO [stdout] c5ceeb9bf5708ed08af0b124f39677f1e5d2435357e26eeb5c4b5857906f2f67