Oct 11 10:59:21.225 INFO testing spy-0.1.0 against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling
Oct 11 10:59:21.225 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt build --frozen
Oct 11 10:59:21.225 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-2/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:59:21.396 INFO blam! a905141666d464a9d6073d7ecf745a7c7280047b470df7400a19800bd6d43055
Oct 11 10:59:21.399 INFO running `"docker" "start" "-a" "a905141666d464a9d6073d7ecf745a7c7280047b470df7400a19800bd6d43055"`
Oct 11 10:59:22.020 INFO kablam! usermod: no changes
Oct 11 10:59:22.054 INFO kablam!    Compiling spy v0.1.0 (/source)
Oct 11 10:59:22.800 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.75s
Oct 11 10:59:22.800 INFO kablam! su: No module specific data is present
Oct 11 10:59:23.119 INFO running `"docker" "rm" "-f" "a905141666d464a9d6073d7ecf745a7c7280047b470df7400a19800bd6d43055"`
Oct 11 10:59:23.368 INFO blam! a905141666d464a9d6073d7ecf745a7c7280047b470df7400a19800bd6d43055
Oct 11 10:59:23.376 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen --no-run
Oct 11 10:59:23.376 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-2/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:59:23.668 INFO blam! 4ef719555a38b6495d6264cbce02f8bbf177c57232322b1dbe7eed35fb5813fa
Oct 11 10:59:23.681 INFO running `"docker" "start" "-a" "4ef719555a38b6495d6264cbce02f8bbf177c57232322b1dbe7eed35fb5813fa"`
Oct 11 10:59:24.389 INFO kablam! usermod: no changes
Oct 11 10:59:24.412 INFO kablam!    Compiling spy v0.1.0 (/source)
Oct 11 10:59:26.342 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 1.93s
Oct 11 10:59:26.342 INFO kablam! su: No module specific data is present
Oct 11 10:59:26.742 INFO running `"docker" "rm" "-f" "4ef719555a38b6495d6264cbce02f8bbf177c57232322b1dbe7eed35fb5813fa"`
Oct 11 10:59:26.980 INFO blam! 4ef719555a38b6495d6264cbce02f8bbf177c57232322b1dbe7eed35fb5813fa
Oct 11 10:59:26.985 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen
Oct 11 10:59:26.985 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-2/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:59:27.258 INFO blam! bd6edd0724eb25002aa71fe2e41d59e880297cb5f72cb456c0645722b9a0761b
Oct 11 10:59:27.268 INFO running `"docker" "start" "-a" "bd6edd0724eb25002aa71fe2e41d59e880297cb5f72cb456c0645722b9a0761b"`
Oct 11 10:59:28.564 INFO kablam! usermod: no changes
Oct 11 10:59:28.623 INFO blam! 
Oct 11 10:59:28.623 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.01s
Oct 11 10:59:28.623 INFO blam! running 3 tests
Oct 11 10:59:28.623 INFO kablam!      Running /target/debug/deps/spy-4a7c3e56d5d0e93e
Oct 11 10:59:28.626 INFO blam! test tests::create_spy_test ... ok
Oct 11 10:59:28.626 INFO blam! test tests::create_spy_with_args_and_return_test ... ok
Oct 11 10:59:28.626 INFO blam! test tests::create_spy_with_args_test ... ok
Oct 11 10:59:28.626 INFO blam! 
Oct 11 10:59:28.626 INFO blam! test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 11 10:59:28.626 INFO blam! 
Oct 11 10:59:28.640 INFO kablam!    Doc-tests spy
Oct 11 10:59:30.021 INFO blam! 
Oct 11 10:59:30.021 INFO blam! running 2 tests
Oct 11 10:59:32.280 INFO blam! test src/lib.rs -  (line 5) ... ok
Oct 11 10:59:32.303 INFO blam! test src/lib.rs - spy (line 134) ... FAILED
Oct 11 10:59:32.303 INFO blam! 
Oct 11 10:59:32.303 INFO blam! failures:
Oct 11 10:59:32.303 INFO blam! 
Oct 11 10:59:32.303 INFO blam! ---- src/lib.rs - spy (line 134) stdout ----
Oct 11 10:59:32.303 INFO blam! error: cannot find macro `spy!` in this scope
Oct 11 10:59:32.303 INFO blam!   --> src/lib.rs:143:21
Oct 11 10:59:32.303 INFO blam!    |
Oct 11 10:59:32.303 INFO blam! 12 | let (spy_fn, spy) = spy!(|n| n % 2 == 0);
Oct 11 10:59:32.303 INFO blam!    |                     ^^^
Oct 11 10:59:32.303 INFO blam! 
Oct 11 10:59:32.303 INFO blam! error: cannot find macro `spy!` in this scope
Oct 11 10:59:32.303 INFO blam!  --> src/lib.rs:139:21
Oct 11 10:59:32.303 INFO blam!   |
Oct 11 10:59:32.303 INFO blam! 8 | let (spy_fn, spy) = spy!(|n|);
Oct 11 10:59:32.303 INFO blam!   |                     ^^^
Oct 11 10:59:32.303 INFO blam! 
Oct 11 10:59:32.303 INFO blam! error: cannot find macro `spy!` in this scope
Oct 11 10:59:32.303 INFO blam!  --> src/lib.rs:136:21
Oct 11 10:59:32.303 INFO blam!   |
Oct 11 10:59:32.303 INFO blam! 5 | let (spy_fn, spy) = spy!();
Oct 11 10:59:32.303 INFO blam!   |                     ^^^
Oct 11 10:59:32.303 INFO blam! 
Oct 11 10:59:32.303 INFO blam! thread 'src/lib.rs - spy (line 134)' panicked at 'couldn't compile the test', librustdoc/test.rs:333:13
Oct 11 10:59:32.303 INFO blam! stack backtrace:
Oct 11 10:59:32.303 INFO blam!    0:     0x7f6126a84dce - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h52ace5b3d4e95617
Oct 11 10:59:32.303 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Oct 11 10:59:32.303 INFO blam!    1:     0x7f6126a69e06 - std::sys_common::backtrace::print::h6cfc8a66009c1cc9
Oct 11 10:59:32.303 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Oct 11 10:59:32.303 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Oct 11 10:59:32.303 INFO blam!    2:     0x7f6126a8fd9d - std::panicking::default_hook::{{closure}}::h47bedf778bf88480
Oct 11 10:59:32.303 INFO blam!                                at libstd/panicking.rs:211
Oct 11 10:59:32.303 INFO blam!    3:     0x7f6126a8faab - std::panicking::default_hook::h1ef46fa7cd894809
Oct 11 10:59:32.304 INFO blam!                                at libstd/panicking.rs:221
Oct 11 10:59:32.304 INFO blam!    4:     0x7f6128d0b64e - rustc::util::common::panic_hook::h6c0fab3f49b67368
Oct 11 10:59:32.304 INFO blam!    5:     0x7f6126a90563 - std::panicking::rust_panic_with_hook::h05510fde8841c579
Oct 11 10:59:32.304 INFO blam!                                at libstd/panicking.rs:480
Oct 11 10:59:32.304 INFO blam!    6:     0x5572affd6796 - std::panicking::begin_panic::hb2f2557b5ad530d6
Oct 11 10:59:32.304 INFO blam!                                at libstd/panicking.rs:410
Oct 11 10:59:32.304 INFO blam!    7:     0x5572b0159cfb - rustdoc::test::run_test::h7f9ad0c8d380d83c
Oct 11 10:59:32.304 INFO blam!                                at librustdoc/test.rs:333
Oct 11 10:59:32.304 INFO blam!    8:     0x5572b0119ef7 - <scoped_tls::ScopedKey<T>>::set::hd7bc23bb100376f9
Oct 11 10:59:32.304 INFO blam!                                at librustdoc/test.rs:565
Oct 11 10:59:32.304 INFO blam!                                at /cargo/registry/src/github.com-1ecc6299db9ec823/scoped-tls-0.1.2/src/lib.rs:155
Oct 11 10:59:32.304 INFO blam!                                at libsyntax/lib.rs:106
Oct 11 10:59:32.304 INFO blam!                                at /cargo/registry/src/github.com-1ecc6299db9ec823/scoped-tls-0.1.2/src/lib.rs:155
Oct 11 10:59:32.304 INFO blam!    9:     0x5572aff31a8f - syntax::with_globals::h13b9cd4342f6776c
Oct 11 10:59:32.304 INFO blam!                                at libsyntax/lib.rs:105
Oct 11 10:59:32.304 INFO blam!   10:     0x5572affd694a - std::panicking::try::do_call::h251f77b3e67a3607
Oct 11 10:59:32.304 INFO blam!                                at librustdoc/test.rs:562
Oct 11 10:59:32.304 INFO blam!                                at libstd/panic.rs:313
Oct 11 10:59:32.304 INFO blam!                                at libstd/panicking.rs:310
Oct 11 10:59:32.304 INFO blam!   11:     0x7f6126a9dc79 - __rust_maybe_catch_panic
Oct 11 10:59:32.304 INFO blam!                                at libpanic_unwind/lib.rs:102
Oct 11 10:59:32.304 INFO blam!   12:     0x5572b002b7d3 - rustc_driver::in_named_rustc_thread::h5b9d8f6b223fabca
Oct 11 10:59:32.304 INFO blam!                                at libstd/panicking.rs:289
Oct 11 10:59:32.304 INFO blam!                                at libstd/panic.rs:392
Oct 11 10:59:32.304 INFO blam!                                at librustc_driver/lib.rs:1575
Oct 11 10:59:32.304 INFO blam!   13:     0x5572b012f9ec - <F as alloc::boxed::FnBox<A>>::call_box::h469711a6fc649ccc
Oct 11 10:59:32.304 INFO blam!                                at librustdoc/test.rs:562
Oct 11 10:59:32.304 INFO blam!                                at liballoc/boxed.rs:672
Oct 11 10:59:32.304 INFO blam!   14:     0x7f61280f2ad2 - <F as alloc::boxed::FnBox<A>>::call_box::h477ac8c82814e76d
Oct 11 10:59:32.304 INFO blam!                                at libtest/lib.rs:1461
Oct 11 10:59:32.304 INFO blam!                                at liballoc/boxed.rs:672
Oct 11 10:59:32.304 INFO blam!   15:     0x7f6126a9dc79 - __rust_maybe_catch_panic
Oct 11 10:59:32.304 INFO blam!                                at libpanic_unwind/lib.rs:102
Oct 11 10:59:32.304 INFO blam!   16:     0x7f6128119b5d - std::sys_common::backtrace::__rust_begin_short_backtrace::he1120aab928e481f
Oct 11 10:59:32.304 INFO blam!                                at libstd/panicking.rs:289
Oct 11 10:59:32.304 INFO blam!                                at libstd/panic.rs:392
Oct 11 10:59:32.304 INFO blam!                                at libtest/lib.rs:1423
Oct 11 10:59:32.304 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Oct 11 10:59:32.304 INFO blam!   17:     0x7f612811aac4 - std::panicking::try::do_call::ha990a345054c2d63
Oct 11 10:59:32.304 INFO blam!                                at libstd/thread/mod.rs:409
Oct 11 10:59:32.304 INFO blam!                                at libstd/panic.rs:313
Oct 11 10:59:32.304 INFO blam!                                at libstd/panicking.rs:310
Oct 11 10:59:32.304 INFO blam!   18:     0x7f6126a9dc79 - __rust_maybe_catch_panic
Oct 11 10:59:32.304 INFO blam!                                at libpanic_unwind/lib.rs:102
Oct 11 10:59:32.304 INFO blam!   19:     0x7f61281035b6 - <F as alloc::boxed::FnBox<A>>::call_box::h96125cb29a9ba44b
Oct 11 10:59:32.304 INFO blam!                                at libstd/panicking.rs:289
Oct 11 10:59:32.304 INFO blam!                                at libstd/panic.rs:392
Oct 11 10:59:32.304 INFO blam!                                at libstd/thread/mod.rs:408
Oct 11 10:59:32.304 INFO blam!                                at liballoc/boxed.rs:672
Oct 11 10:59:32.304 INFO blam!   20:     0x7f6126a8b93a - std::sys_common::thread::start_thread::h97c4c5d82028f6b1
Oct 11 10:59:32.304 INFO blam!                                at liballoc/boxed.rs:682
Oct 11 10:59:32.304 INFO blam!                                at libstd/sys_common/thread.rs:24
Oct 11 10:59:32.304 INFO blam!   21:     0x7f6126a60d35 - std::sys::unix::thread::Thread::new::thread_start::he7d65efc4dd16886
Oct 11 10:59:32.304 INFO blam!                                at libstd/sys/unix/thread.rs:90
Oct 11 10:59:32.304 INFO blam!   22:     0x7f61265eb6b9 - start_thread
Oct 11 10:59:32.304 INFO blam!   23:     0x7f612610b41c - clone
Oct 11 10:59:32.304 INFO blam!   24:                0x0 - <unknown>
Oct 11 10:59:32.304 INFO blam! query stack during panic:
Oct 11 10:59:32.304 INFO blam! end of query stack
Oct 11 10:59:32.304 INFO blam! 
Oct 11 10:59:32.304 INFO blam! 
Oct 11 10:59:32.304 INFO blam! failures:
Oct 11 10:59:32.304 INFO blam!     src/lib.rs - spy (line 134)
Oct 11 10:59:32.304 INFO blam! 
Oct 11 10:59:32.304 INFO blam! test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Oct 11 10:59:32.304 INFO blam! 
Oct 11 10:59:32.328 INFO kablam! error: test failed, to rerun pass '--doc'
Oct 11 10:59:32.328 INFO kablam! su: No module specific data is present
Oct 11 10:59:32.786 INFO running `"docker" "rm" "-f" "bd6edd0724eb25002aa71fe2e41d59e880297cb5f72cb456c0645722b9a0761b"`
Oct 11 10:59:33.008 INFO blam! bd6edd0724eb25002aa71fe2e41d59e880297cb5f72cb456c0645722b9a0761b