Nov 17 18:31:01.445 INFO testing wasm-logger-0.1.0 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 17 18:31:01.445 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 18:31:01.889 INFO [stdout] 9eee89078938cfca03fc4b9668e2921bc2978e1020a6e35af790672ef8ab2f92
Nov 17 18:31:01.891 INFO running `"docker" "start" "-a" "9eee89078938cfca03fc4b9668e2921bc2978e1020a6e35af790672ef8ab2f92"`
Nov 17 18:31:03.786 INFO [stderr] usermod: no changes
Nov 17 18:31:04.041 INFO [stderr]    Compiling wasm-bindgen-shared v0.2.28
Nov 17 18:31:04.050 INFO [stderr]    Compiling wasm-bindgen v0.2.28
Nov 17 18:31:04.051 INFO [stderr]    Compiling sourcefile v0.1.4
Nov 17 18:31:04.063 INFO [stderr]    Compiling syn v0.15.21
Nov 17 18:31:04.064 INFO [stderr]    Compiling weedle v0.8.0
Nov 17 18:31:04.064 INFO [stderr]    Compiling env_logger v0.6.0
Nov 17 18:31:35.649 INFO [stderr]    Compiling synstructure v0.10.1
Nov 17 18:31:35.649 INFO [stderr]    Compiling wasm-bindgen-backend v0.2.28
Nov 17 18:31:42.483 INFO [stderr]    Compiling failure_derive v0.1.3
Nov 17 18:31:50.667 INFO [stderr]    Compiling wasm-bindgen-macro-support v0.2.28
Nov 17 18:31:52.360 INFO [stderr]    Compiling failure v0.1.3
Nov 17 18:31:54.031 INFO [stderr]    Compiling wasm-bindgen-webidl v0.2.22
Nov 17 18:32:00.043 INFO [stderr]    Compiling wasm-bindgen-macro v0.2.28
Nov 17 18:32:09.379 INFO [stderr]    Compiling js-sys v0.3.5
Nov 17 18:32:10.124 INFO [stderr]    Compiling web-sys v0.3.5
Nov 17 18:32:23.799 INFO [stderr]    Compiling wasm-logger v0.1.0 (/source)
Nov 17 18:32:24.944 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 21s
Nov 17 18:32:24.960 INFO [stderr] su: No module specific data is present
Nov 17 18:32:25.709 INFO running `"docker" "inspect" "9eee89078938cfca03fc4b9668e2921bc2978e1020a6e35af790672ef8ab2f92"`
Nov 17 18:32:26.023 INFO running `"docker" "rm" "-f" "9eee89078938cfca03fc4b9668e2921bc2978e1020a6e35af790672ef8ab2f92"`
Nov 17 18:32:26.355 INFO [stdout] 9eee89078938cfca03fc4b9668e2921bc2978e1020a6e35af790672ef8ab2f92
Nov 17 18:32:26.358 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 18:32:27.039 INFO [stdout] c3df97c814257326617fd9ba6c1c391319e4849efdc482b5aae005b8e30d10cd
Nov 17 18:32:27.042 INFO running `"docker" "start" "-a" "c3df97c814257326617fd9ba6c1c391319e4849efdc482b5aae005b8e30d10cd"`
Nov 17 18:32:29.374 INFO [stderr] usermod: no changes
Nov 17 18:32:29.579 INFO [stderr]    Compiling wasm-logger v0.1.0 (/source)
Nov 17 18:32:30.871 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.46s
Nov 17 18:32:30.884 INFO [stderr] su: No module specific data is present
Nov 17 18:32:32.141 INFO running `"docker" "inspect" "c3df97c814257326617fd9ba6c1c391319e4849efdc482b5aae005b8e30d10cd"`
Nov 17 18:32:32.429 INFO running `"docker" "rm" "-f" "c3df97c814257326617fd9ba6c1c391319e4849efdc482b5aae005b8e30d10cd"`
Nov 17 18:32:32.707 INFO [stdout] c3df97c814257326617fd9ba6c1c391319e4849efdc482b5aae005b8e30d10cd
Nov 17 18:32:32.717 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 18:32:33.277 INFO [stdout] 8bd2b9fefec9629224a20a6f07d967b2df1fd4b2ff9232e49cccf6a9b8f6601e
Nov 17 18:32:33.282 INFO running `"docker" "start" "-a" "8bd2b9fefec9629224a20a6f07d967b2df1fd4b2ff9232e49cccf6a9b8f6601e"`
Nov 17 18:32:36.042 INFO [stderr] usermod: no changes
Nov 17 18:32:36.217 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.14s
Nov 17 18:32:36.221 INFO [stderr]      Running /target/debug/deps/wasm_logger-5ac77ea22694b26d
Nov 17 18:32:36.228 INFO [stdout] 
Nov 17 18:32:36.228 INFO [stdout] running 0 tests
Nov 17 18:32:36.228 INFO [stdout] 
Nov 17 18:32:36.229 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 18:32:36.229 INFO [stdout] 
Nov 17 18:32:36.229 INFO [stderr]    Doc-tests wasm-logger
Nov 17 18:32:37.380 INFO [stdout] 
Nov 17 18:32:37.380 INFO [stdout] running 2 tests
Nov 17 18:32:40.015 INFO [stdout] test src/lib.rs -  (line 5) ... FAILED
Nov 17 18:32:40.205 INFO [stdout] test src/lib.rs -  (line 12) ... ok
Nov 17 18:32:40.205 INFO [stdout] 
Nov 17 18:32:40.206 INFO [stdout] failures:
Nov 17 18:32:40.206 INFO [stdout] 
Nov 17 18:32:40.206 INFO [stdout] ---- src/lib.rs -  (line 5) stdout ----
Nov 17 18:32:40.207 INFO [stdout] error[E0468]: an `extern crate` loading macros must be at the crate root
Nov 17 18:32:40.207 INFO [stdout]  --> src/lib.rs:7:5
Nov 17 18:32:40.207 INFO [stdout]   |
Nov 17 18:32:40.208 INFO [stdout] 4 |     extern crate log;
Nov 17 18:32:40.208 INFO [stdout]   |     ^^^^^^^^^^^^^^^^^
Nov 17 18:32:40.208 INFO [stdout] 
Nov 17 18:32:40.209 INFO [stdout] thread 'src/lib.rs -  (line 5)' panicked at 'couldn't compile the test', librustdoc/test.rs:323:13
Nov 17 18:32:40.209 INFO [stdout] stack backtrace:
Nov 17 18:32:40.209 INFO [stdout]    0:     0x7f5260a7ebc3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h1a944b80a560daa8
Nov 17 18:32:40.210 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 17 18:32:40.210 INFO [stdout]    1:     0x7f5260a762a8 - std::sys_common::backtrace::_print::h18f735b4c3a49bdb
Nov 17 18:32:40.210 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Nov 17 18:32:40.211 INFO [stdout]    2:     0x7f5260a7b594 - std::panicking::default_hook::{{closure}}::h97ab3548a215c097
Nov 17 18:32:40.211 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Nov 17 18:32:40.211 INFO [stdout]                                at libstd/panicking.rs:211
Nov 17 18:32:40.212 INFO [stdout]    3:     0x7f5260a7b27c - std::panicking::default_hook::h98c2a01ef734e4b0
Nov 17 18:32:40.212 INFO [stdout]                                at libstd/panicking.rs:221
Nov 17 18:32:40.213 INFO [stdout]    4:     0x7f5262bd7d01 - rustc::util::common::panic_hook::hb5e29ad6fd71b050
Nov 17 18:32:40.213 INFO [stdout]    5:     0x7f5260a7bd79 - std::panicking::rust_panic_with_hook::hb4b3f28eb79ea752
Nov 17 18:32:40.213 INFO [stdout]                                at libstd/panicking.rs:480
Nov 17 18:32:40.214 INFO [stdout]    6:     0x56243a25d907 - std::panicking::begin_panic::hb09a382a0ab7f900
Nov 17 18:32:40.214 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:410
Nov 17 18:32:40.214 INFO [stdout]    7:     0x56243a421485 - rustdoc::test::run_test::h7e7f9ec8aeaa87f8
Nov 17 18:32:40.215 INFO [stdout]                                at librustdoc/test.rs:323
Nov 17 18:32:40.215 INFO [stdout]    8:     0x56243a2f4fae - <scoped_tls::ScopedKey<T>>::set::h908a96c8ec4ddccf
Nov 17 18:32:40.215 INFO [stdout]                                at librustdoc/test.rs:654
Nov 17 18:32:40.216 INFO [stdout]                                at /cargo/registry/src/github.com-1ecc6299db9ec823/scoped-tls-0.1.2/src/lib.rs:155
Nov 17 18:32:40.216 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libsyntax/lib.rs:124
Nov 17 18:32:40.216 INFO [stdout]                                at /cargo/registry/src/github.com-1ecc6299db9ec823/scoped-tls-0.1.2/src/lib.rs:155
Nov 17 18:32:40.217 INFO [stdout]    9:     0x56243a2d3871 - syntax::with_globals::h9807168bf3c0d85b
Nov 17 18:32:40.217 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libsyntax/lib.rs:123
Nov 17 18:32:40.217 INFO [stdout]   10:     0x56243a25db4b - std::panicking::try::do_call::h64a568dca1c842cf
Nov 17 18:32:40.217 INFO [stdout]                                at librustdoc/test.rs:651
Nov 17 18:32:40.218 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319
Nov 17 18:32:40.218 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310
Nov 17 18:32:40.219 INFO [stdout]   11:     0x7f5260a99049 - __rust_maybe_catch_panic
Nov 17 18:32:40.219 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 18:32:40.219 INFO [stdout]   12:     0x56243a2ed069 - rustc_driver::in_named_rustc_thread::hc08d76125a58a368
Nov 17 18:32:40.219 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 17 18:32:40.220 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 17 18:32:40.220 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/librustc_driver/lib.rs:1554
Nov 17 18:32:40.220 INFO [stdout]   13:     0x56243a3f5a80 - <F as alloc::boxed::FnBox<A>>::call_box::hf60ef6def5189442
Nov 17 18:32:40.221 INFO [stdout]                                at librustdoc/test.rs:651
Nov 17 18:32:40.221 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 17 18:32:40.221 INFO [stdout]   14:     0x7f52620b9b72 - <F as alloc::boxed::FnBox<A>>::call_box::hb3eb0389b4ad8337
Nov 17 18:32:40.222 INFO [stdout]                                at libtest/lib.rs:1464
Nov 17 18:32:40.222 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 17 18:32:40.222 INFO [stdout]   15:     0x7f5260a99049 - __rust_maybe_catch_panic
Nov 17 18:32:40.222 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 18:32:40.225 INFO [stdout]   16:     0x7f52620b1493 - std::sys_common::backtrace::__rust_begin_short_backtrace::h89ea8afe71e36f86
Nov 17 18:32:40.225 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 17 18:32:40.225 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 17 18:32:40.225 INFO [stdout]                                at libtest/lib.rs:1426
Nov 17 18:32:40.226 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/sys_common/backtrace.rs:136
Nov 17 18:32:40.226 INFO [stdout]   17:     0x7f52620b2344 - std::panicking::try::do_call::h4132042e0cbea3ae
Nov 17 18:32:40.226 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:477
Nov 17 18:32:40.227 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319
Nov 17 18:32:40.227 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310
Nov 17 18:32:40.227 INFO [stdout]   18:     0x7f5260a99049 - __rust_maybe_catch_panic
Nov 17 18:32:40.228 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 18:32:40.228 INFO [stdout]   19:     0x7f52620b9a4c - <F as alloc::boxed::FnBox<A>>::call_box::hab7f616f05880bf6
Nov 17 18:32:40.228 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 17 18:32:40.228 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 17 18:32:40.229 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:476
Nov 17 18:32:40.229 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 17 18:32:40.229 INFO [stdout]   20:     0x7f5260a8bb2d - std::sys::unix::thread::Thread::new::thread_start::h5f8d67158a1ca038
Nov 17 18:32:40.229 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:683
Nov 17 18:32:40.230 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Nov 17 18:32:40.230 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Nov 17 18:32:40.230 INFO [stdout]   21:     0x7f52607fe6b9 - start_thread
Nov 17 18:32:40.231 INFO [stdout]   22:     0x7f526011641c - clone
Nov 17 18:32:40.231 INFO [stdout]   23:                0x0 - <unknown>
Nov 17 18:32:40.231 INFO [stdout] query stack during panic:
Nov 17 18:32:40.231 INFO [stdout] end of query stack
Nov 17 18:32:40.232 INFO [stdout] 
Nov 17 18:32:40.232 INFO [stdout] 
Nov 17 18:32:40.232 INFO [stdout] failures:
Nov 17 18:32:40.232 INFO [stdout]     src/lib.rs -  (line 5)
Nov 17 18:32:40.233 INFO [stdout] 
Nov 17 18:32:40.233 INFO [stdout] test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 18:32:40.233 INFO [stdout] 
Nov 17 18:32:40.234 INFO [stderr] error: test failed, to rerun pass '--doc'
Nov 17 18:32:40.234 INFO [stderr] su: No module specific data is present
Nov 17 18:32:41.555 INFO running `"docker" "inspect" "8bd2b9fefec9629224a20a6f07d967b2df1fd4b2ff9232e49cccf6a9b8f6601e"`
Nov 17 18:32:41.896 INFO running `"docker" "rm" "-f" "8bd2b9fefec9629224a20a6f07d967b2df1fd4b2ff9232e49cccf6a9b8f6601e"`
Nov 17 18:32:42.243 INFO [stdout] 8bd2b9fefec9629224a20a6f07d967b2df1fd4b2ff9232e49cccf6a9b8f6601e