Dec 14 14:15:07.592 INFO testing wasm-logger-0.1.0 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550 Dec 14 14:15:07.592 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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"` Dec 14 14:15:08.124 INFO [stdout] b978552fc386456eeb93545e1b0a6a2122c3c1dc28a16b977490ef6ae85415c1 Dec 14 14:15:08.127 INFO running `"docker" "start" "-a" "b978552fc386456eeb93545e1b0a6a2122c3c1dc28a16b977490ef6ae85415c1"` Dec 14 14:15:09.831 INFO [stderr] usermod: no changes Dec 14 14:15:10.019 INFO [stderr] Compiling wasm-bindgen-shared v0.2.29 Dec 14 14:15:10.034 INFO [stderr] Compiling wasm-bindgen v0.2.29 Dec 14 14:15:10.034 INFO [stderr] Compiling sourcefile v0.1.4 Dec 14 14:15:10.039 INFO [stderr] Compiling nom v4.1.1 Dec 14 14:15:13.721 INFO [stderr] Compiling wasm-bindgen-backend v0.2.29 Dec 14 14:15:14.819 INFO [stderr] Compiling weedle v0.8.0 Dec 14 14:15:24.947 INFO [stderr] Compiling wasm-bindgen-macro-support v0.2.29 Dec 14 14:15:30.635 INFO [stderr] Compiling wasm-bindgen-macro v0.2.29 Dec 14 14:15:33.371 INFO [stderr] Compiling wasm-bindgen-webidl v0.2.23 Dec 14 14:15:37.255 INFO [stderr] Compiling js-sys v0.3.6 Dec 14 14:15:47.179 INFO [stderr] Compiling web-sys v0.3.6 Dec 14 14:15:57.067 INFO [stderr] Compiling wasm-logger v0.1.0 (/source) Dec 14 14:15:58.089 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 48.23s Dec 14 14:15:58.107 INFO [stderr] su: No module specific data is present Dec 14 14:15:58.560 INFO running `"docker" "inspect" "b978552fc386456eeb93545e1b0a6a2122c3c1dc28a16b977490ef6ae85415c1"` Dec 14 14:15:58.739 INFO running `"docker" "rm" "-f" "b978552fc386456eeb93545e1b0a6a2122c3c1dc28a16b977490ef6ae85415c1"` Dec 14 14:15:58.948 INFO [stdout] b978552fc386456eeb93545e1b0a6a2122c3c1dc28a16b977490ef6ae85415c1 Dec 14 14:15:58.954 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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"` Dec 14 14:15:59.211 INFO [stdout] f93360555bc60c0fd8f414978fe4a1bec69ab9da46cf73fd7926aeb81b81da23 Dec 14 14:15:59.224 INFO running `"docker" "start" "-a" "f93360555bc60c0fd8f414978fe4a1bec69ab9da46cf73fd7926aeb81b81da23"` Dec 14 14:15:59.901 INFO [stderr] usermod: no changes Dec 14 14:16:00.047 INFO [stderr] Compiling wasm-logger v0.1.0 (/source) Dec 14 14:16:01.223 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1.29s Dec 14 14:16:01.230 INFO [stderr] su: No module specific data is present Dec 14 14:16:01.695 INFO running `"docker" "inspect" "f93360555bc60c0fd8f414978fe4a1bec69ab9da46cf73fd7926aeb81b81da23"` Dec 14 14:16:01.897 INFO running `"docker" "rm" "-f" "f93360555bc60c0fd8f414978fe4a1bec69ab9da46cf73fd7926aeb81b81da23"` Dec 14 14:16:02.089 INFO [stdout] f93360555bc60c0fd8f414978fe4a1bec69ab9da46cf73fd7926aeb81b81da23 Dec 14 14:16:02.092 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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"` Dec 14 14:16:02.341 INFO [stdout] 8b58310cf4dbf11013fdb3197ce7cf0e9e15efef493a403e940b8a341879ca61 Dec 14 14:16:02.346 INFO running `"docker" "start" "-a" "8b58310cf4dbf11013fdb3197ce7cf0e9e15efef493a403e940b8a341879ca61"` Dec 14 14:16:02.908 INFO [stderr] usermod: no changes Dec 14 14:16:03.039 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.10s Dec 14 14:16:03.043 INFO [stderr] Running /target/debug/deps/wasm_logger-bb3d6ccf0981aea4 Dec 14 14:16:03.047 INFO [stdout] Dec 14 14:16:03.047 INFO [stdout] running 0 tests Dec 14 14:16:03.047 INFO [stdout] Dec 14 14:16:03.047 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 14:16:03.047 INFO [stdout] Dec 14 14:16:03.047 INFO [stderr] Doc-tests wasm-logger Dec 14 14:16:04.300 INFO [stdout] Dec 14 14:16:04.300 INFO [stdout] running 2 tests Dec 14 14:16:06.163 INFO [stdout] test src/lib.rs - (line 5) ... FAILED Dec 14 14:16:06.237 INFO [stdout] test src/lib.rs - (line 12) ... ok Dec 14 14:16:06.237 INFO [stdout] Dec 14 14:16:06.237 INFO [stdout] failures: Dec 14 14:16:06.237 INFO [stdout] Dec 14 14:16:06.237 INFO [stdout] ---- src/lib.rs - (line 5) stdout ---- Dec 14 14:16:06.237 INFO [stdout] error[E0468]: an `extern crate` loading macros must be at the crate root Dec 14 14:16:06.237 INFO [stdout] --> src/lib.rs:7:5 Dec 14 14:16:06.237 INFO [stdout] | Dec 14 14:16:06.237 INFO [stdout] 4 | extern crate log; Dec 14 14:16:06.237 INFO [stdout] | ^^^^^^^^^^^^^^^^^ Dec 14 14:16:06.237 INFO [stdout] Dec 14 14:16:06.237 INFO [stdout] thread 'src/lib.rs - (line 5)' panicked at 'couldn't compile the test', src/librustdoc/test.rs:326:13 Dec 14 14:16:06.237 INFO [stdout] stack backtrace: Dec 14 14:16:06.237 INFO [stdout] 0: 0x7f7b3f3a8f43 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::heb4d4d98bcf46d90 Dec 14 14:16:06.237 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 14 14:16:06.237 INFO [stdout] 1: 0x7f7b3f39ffa8 - std::sys_common::backtrace::_print::h919420b22c2473e3 Dec 14 14:16:06.237 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 14 14:16:06.237 INFO [stdout] 2: 0x7f7b3f3a5222 - std::panicking::default_hook::{{closure}}::h22d4ac021acb88d5 Dec 14 14:16:06.237 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 14 14:16:06.237 INFO [stdout] at src/libstd/panicking.rs:210 Dec 14 14:16:06.237 INFO [stdout] 3: 0x7f7b3f3a4f17 - std::panicking::default_hook::h1134a4d34aab4428 Dec 14 14:16:06.237 INFO [stdout] at src/libstd/panicking.rs:218 Dec 14 14:16:06.237 INFO [stdout] 4: 0x7f7b415af681 - rustc::util::common::panic_hook::h5f1ef819f65e49ce Dec 14 14:16:06.237 INFO [stdout] 5: 0x7f7b3f3a5a09 - std::panicking::rust_panic_with_hook::h9c89557c251f2d42 Dec 14 14:16:06.237 INFO [stdout] at src/libstd/panicking.rs:491 Dec 14 14:16:06.237 INFO [stdout] 6: 0x55a4307adb24 - std::panicking::begin_panic::h626604038f71be4c Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:421 Dec 14 14:16:06.237 INFO [stdout] 7: 0x55a430923ab2 - ::add_test::{{closure}}::{{closure}}::{{closure}}::h03844441a87ed622 Dec 14 14:16:06.237 INFO [stdout] at src/librustdoc/test.rs:326 Dec 14 14:16:06.237 INFO [stdout] at src/librustdoc/test.rs:653 Dec 14 14:16:06.237 INFO [stdout] 8: 0x55a4308e0398 - >::set::ha4da892a47d9254a Dec 14 14:16:06.237 INFO [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/scoped-tls-0.1.2/src/lib.rs:155 Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libsyntax/lib.rs:123 Dec 14 14:16:06.237 INFO [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/scoped-tls-0.1.2/src/lib.rs:155 Dec 14 14:16:06.237 INFO [stdout] 9: 0x55a43081853f - syntax::with_globals::hd5f80a8e81630563 Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libsyntax/lib.rs:122 Dec 14 14:16:06.237 INFO [stdout] 10: 0x55a4307add24 - std::panicking::try::do_call::haa456735219e3377 Dec 14 14:16:06.237 INFO [stdout] at src/librustdoc/test.rs:650 Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319 Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306 Dec 14 14:16:06.237 INFO [stdout] 11: 0x7f7b3f3c2649 - __rust_maybe_catch_panic Dec 14 14:16:06.237 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 14 14:16:06.237 INFO [stdout] 12: 0x55a430802e49 - rustc_driver::in_named_rustc_thread::he2bcf78be8ab5538 Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/librustc_driver/lib.rs:1556 Dec 14 14:16:06.237 INFO [stdout] 13: 0x55a4308f9140 - >::call_box::h418f2961326e63a6 Dec 14 14:16:06.237 INFO [stdout] at src/librustdoc/test.rs:650 Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 14 14:16:06.237 INFO [stdout] 14: 0x7f7b40a11102 - >::call_box::hb274b5baa0755876 Dec 14 14:16:06.237 INFO [stdout] at src/libtest/lib.rs:1476 Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 14 14:16:06.237 INFO [stdout] 15: 0x7f7b3f3c2649 - __rust_maybe_catch_panic Dec 14 14:16:06.237 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 14 14:16:06.237 INFO [stdout] 16: 0x7f7b40a2f427 - test::run_test::run_test_inner::{{closure}}::h5f14673685eef7c8 Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 14 14:16:06.237 INFO [stdout] at src/libtest/lib.rs:1438 Dec 14 14:16:06.237 INFO [stdout] 17: 0x7f7b40a09334 - std::sys_common::backtrace::__rust_begin_short_backtrace::h96de92a7d062bb5f Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/sys_common/backtrace.rs:136 Dec 14 14:16:06.237 INFO [stdout] 18: 0x7f7b40a099a4 - std::panicking::try::do_call::h6a71e68ea4ad0d8a Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:479 Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319 Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306 Dec 14 14:16:06.237 INFO [stdout] 19: 0x7f7b3f3c2649 - __rust_maybe_catch_panic Dec 14 14:16:06.237 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 14 14:16:06.237 INFO [stdout] 20: 0x7f7b40a10e4c - >::call_box::h260fd1ad8e2854a6 Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 14 14:16:06.237 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 14 14:16:06.238 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:478 Dec 14 14:16:06.238 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 14 14:16:06.238 INFO [stdout] 21: 0x7f7b3f3b5aed - std::sys::unix::thread::Thread::new::thread_start::h08aa646226e66c99 Dec 14 14:16:06.238 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:683 Dec 14 14:16:06.238 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 14 14:16:06.238 INFO [stdout] at src/libstd/sys/unix/thread.rs:91 Dec 14 14:16:06.238 INFO [stdout] 22: 0x7f7b3f1296b9 - start_thread Dec 14 14:16:06.238 INFO [stdout] 23: 0x7f7b3ea4141c - clone Dec 14 14:16:06.238 INFO [stdout] 24: 0x0 - Dec 14 14:16:06.238 INFO [stdout] query stack during panic: Dec 14 14:16:06.238 INFO [stdout] end of query stack Dec 14 14:16:06.238 INFO [stdout] Dec 14 14:16:06.238 INFO [stdout] Dec 14 14:16:06.238 INFO [stdout] failures: Dec 14 14:16:06.238 INFO [stdout] src/lib.rs - (line 5) Dec 14 14:16:06.238 INFO [stdout] Dec 14 14:16:06.238 INFO [stdout] test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 14:16:06.238 INFO [stdout] Dec 14 14:16:06.263 INFO [stderr] error: test failed, to rerun pass '--doc' Dec 14 14:16:06.263 INFO [stderr] su: No module specific data is present Dec 14 14:16:06.581 INFO running `"docker" "inspect" "8b58310cf4dbf11013fdb3197ce7cf0e9e15efef493a403e940b8a341879ca61"` Dec 14 14:16:06.727 INFO running `"docker" "rm" "-f" "8b58310cf4dbf11013fdb3197ce7cf0e9e15efef493a403e940b8a341879ca61"` Dec 14 14:16:06.931 INFO [stdout] 8b58310cf4dbf11013fdb3197ce7cf0e9e15efef493a403e940b8a341879ca61