Nov 17 19:45:11.494 INFO testing tvm-0.1.0 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 17 19:45:11.495 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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:45:12.033 INFO [stdout] 457129d2fb640cff6a489679aa9b74e6849013a907094fe53ccbf23cdd9978c9
Nov 17 19:45:12.036 INFO running `"docker" "start" "-a" "457129d2fb640cff6a489679aa9b74e6849013a907094fe53ccbf23cdd9978c9"`
Nov 17 19:45:13.577 INFO [stderr] usermod: no changes
Nov 17 19:45:13.743 INFO [stderr]    Compiling bounded-spsc-queue v0.4.0
Nov 17 19:45:13.743 INFO [stderr]    Compiling memchr v2.1.1
Nov 17 19:45:13.743 INFO [stderr]    Compiling ndarray v0.11.2
Nov 17 19:45:17.043 INFO [stderr]    Compiling nom v4.1.1
Nov 17 19:45:27.655 INFO [stderr]    Compiling tvm v0.1.0 (/source)
Nov 17 19:45:29.935 INFO [stderr] warning: the feature `extern_prelude` has been stable since 1.30.0 and no longer requires an attribute to enable
Nov 17 19:45:29.935 INFO [stderr]  --> src/lib.rs:5:3
Nov 17 19:45:29.935 INFO [stderr]   |
Nov 17 19:45:29.935 INFO [stderr] 5 |   extern_prelude,
Nov 17 19:45:29.935 INFO [stderr]   |   ^^^^^^^^^^^^^^
Nov 17 19:45:29.935 INFO [stderr]   |
Nov 17 19:45:29.935 INFO [stderr]   = note: #[warn(stable_features)] on by default
Nov 17 19:45:29.935 INFO [stderr] 
Nov 17 19:45:33.847 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 20.23s
Nov 17 19:45:33.855 INFO [stderr] su: No module specific data is present
Nov 17 19:45:34.429 INFO running `"docker" "inspect" "457129d2fb640cff6a489679aa9b74e6849013a907094fe53ccbf23cdd9978c9"`
Nov 17 19:45:34.709 INFO running `"docker" "rm" "-f" "457129d2fb640cff6a489679aa9b74e6849013a907094fe53ccbf23cdd9978c9"`
Nov 17 19:45:35.006 INFO [stdout] 457129d2fb640cff6a489679aa9b74e6849013a907094fe53ccbf23cdd9978c9
Nov 17 19:45:35.009 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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:45:35.539 INFO [stdout] 8db4565c77da0776ae558742ac8f93cfed39667658c0c4008a98fa42d3b14394
Nov 17 19:45:35.541 INFO running `"docker" "start" "-a" "8db4565c77da0776ae558742ac8f93cfed39667658c0c4008a98fa42d3b14394"`
Nov 17 19:45:36.619 INFO [stderr] usermod: no changes
Nov 17 19:45:36.772 INFO [stderr]    Compiling tvm v0.1.0 (/source)
Nov 17 19:45:39.779 INFO [stderr] warning: unused import: `collections::HashMap`
Nov 17 19:45:39.779 INFO [stderr]  --> tests/test_graph_serde.rs:8:11
Nov 17 19:45:39.779 INFO [stderr]   |
Nov 17 19:45:39.779 INFO [stderr] 8 | use std::{collections::HashMap, convert::TryFrom, fs, io::Read};
Nov 17 19:45:39.779 INFO [stderr]   |           ^^^^^^^^^^^^^^^^^^^^
Nov 17 19:45:39.779 INFO [stderr]   |
Nov 17 19:45:39.779 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Nov 17 19:45:39.779 INFO [stderr] 
Nov 17 19:45:39.839 INFO [stderr] warning: unused variable: `params`
Nov 17 19:45:39.839 INFO [stderr]   --> tests/test_graph_serde.rs:19:7
Nov 17 19:45:39.839 INFO [stderr]    |
Nov 17 19:45:39.839 INFO [stderr] 19 |   let params = tvm::runtime::load_param_dict(&params_bytes);
Nov 17 19:45:39.839 INFO [stderr]    |       ^^^^^^ help: consider using `_params` instead
Nov 17 19:45:39.839 INFO [stderr]    |
Nov 17 19:45:39.839 INFO [stderr]    = note: #[warn(unused_variables)] on by default
Nov 17 19:45:39.839 INFO [stderr] 
Nov 17 19:45:39.855 INFO [stderr] warning: the feature `fs_read_write` has been stable since 1.26.0 and no longer requires an attribute to enable
Nov 17 19:45:39.855 INFO [stderr]  --> tests/test_graph_serde.rs:1:12
Nov 17 19:45:39.855 INFO [stderr]   |
Nov 17 19:45:39.855 INFO [stderr] 1 | #![feature(fs_read_write, try_from)]
Nov 17 19:45:39.855 INFO [stderr]   |            ^^^^^^^^^^^^^
Nov 17 19:45:39.855 INFO [stderr]   |
Nov 17 19:45:39.855 INFO [stderr]   = note: #[warn(stable_features)] on by default
Nov 17 19:45:39.855 INFO [stderr] 
Nov 17 19:45:40.615 INFO [stderr] warning: the feature `extern_prelude` has been stable since 1.30.0 and no longer requires an attribute to enable
Nov 17 19:45:40.615 INFO [stderr]  --> src/lib.rs:5:3
Nov 17 19:45:40.615 INFO [stderr]   |
Nov 17 19:45:40.615 INFO [stderr] 5 |   extern_prelude,
Nov 17 19:45:40.615 INFO [stderr]   |   ^^^^^^^^^^^^^^
Nov 17 19:45:40.615 INFO [stderr]   |
Nov 17 19:45:40.615 INFO [stderr]   = note: #[warn(stable_features)] on by default
Nov 17 19:45:40.615 INFO [stderr] 
Nov 17 19:45:42.504 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.85s
Nov 17 19:45:42.511 INFO [stderr] su: No module specific data is present
Nov 17 19:45:43.009 INFO running `"docker" "inspect" "8db4565c77da0776ae558742ac8f93cfed39667658c0c4008a98fa42d3b14394"`
Nov 17 19:45:43.351 INFO running `"docker" "rm" "-f" "8db4565c77da0776ae558742ac8f93cfed39667658c0c4008a98fa42d3b14394"`
Nov 17 19:45:43.723 INFO [stdout] 8db4565c77da0776ae558742ac8f93cfed39667658c0c4008a98fa42d3b14394
Nov 17 19:45:43.727 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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:45:44.225 INFO [stdout] a9cf35b8293bfae6b69a5a837a9cef76c130979e5f1e7db269d2b4f705300be1
Nov 17 19:45:44.227 INFO running `"docker" "start" "-a" "a9cf35b8293bfae6b69a5a837a9cef76c130979e5f1e7db269d2b4f705300be1"`
Nov 17 19:45:45.909 INFO [stderr] usermod: no changes
Nov 17 19:45:45.999 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.06s
Nov 17 19:45:45.999 INFO [stderr]      Running /target/debug/deps/tvm-4af7a95382945685
Nov 17 19:45:46.007 INFO [stdout] 
Nov 17 19:45:46.007 INFO [stdout] running 3 tests
Nov 17 19:45:46.007 INFO [stdout] test runtime::graph::tests::test_str_to_type ... ok
Nov 17 19:45:46.007 INFO [stdout] test runtime::threading::tests::test_max_concurrency ... ok
Nov 17 19:45:46.367 INFO [stdout] test runtime::threading::tests::test_parallel_launch ... ok
Nov 17 19:45:46.367 INFO [stdout] 
Nov 17 19:45:46.367 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 19:45:46.367 INFO [stdout] 
Nov 17 19:45:46.387 INFO [stderr]      Running /target/debug/deps/test_graph_serde-e722bf1598d964d5
Nov 17 19:45:46.387 INFO [stdout] 
Nov 17 19:45:46.387 INFO [stdout] running 1 test
Nov 17 19:45:46.511 INFO [stderr] error: test failed, to rerun pass '--test test_graph_serde'
Nov 17 19:45:46.511 INFO [stdout] test test_load_graph ... FAILED
Nov 17 19:45:46.511 INFO [stdout] 
Nov 17 19:45:46.511 INFO [stdout] failures:
Nov 17 19:45:46.512 INFO [stdout] 
Nov 17 19:45:46.512 INFO [stdout] ---- test_load_graph stdout ----
Nov 17 19:45:46.512 INFO [stdout] thread 'test_load_graph' panicked at 'called `Result::unwrap()` on an `Err` value: Os { code: 2, kind: NotFound, message: "No such file or directory" }', libcore/result.rs:1009:5
Nov 17 19:45:46.512 INFO [stdout] stack backtrace:
Nov 17 19:45:46.512 INFO [stdout]    0:     0x5596035e7843 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h1a944b80a560daa8
Nov 17 19:45:46.512 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 17 19:45:46.512 INFO [stdout]    1:     0x5596035e1f68 - std::sys_common::backtrace::_print::h18f735b4c3a49bdb
Nov 17 19:45:46.512 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Nov 17 19:45:46.512 INFO [stdout]    2:     0x5596035e67b4 - std::panicking::default_hook::{{closure}}::h97ab3548a215c097
Nov 17 19:45:46.512 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Nov 17 19:45:46.512 INFO [stdout]                                at libstd/panicking.rs:211
Nov 17 19:45:46.512 INFO [stdout]    3:     0x5596035e649c - std::panicking::default_hook::h98c2a01ef734e4b0
Nov 17 19:45:46.512 INFO [stdout]                                at libstd/panicking.rs:221
Nov 17 19:45:46.512 INFO [stdout]    4:     0x5596035e6ec0 - std::panicking::rust_panic_with_hook::hb4b3f28eb79ea752
Nov 17 19:45:46.512 INFO [stdout]                                at libstd/panicking.rs:476
Nov 17 19:45:46.512 INFO [stdout]    5:     0x5596035e6a41 - std::panicking::continue_panic_fmt::h61fd5a7b1a268752
Nov 17 19:45:46.512 INFO [stdout]                                at libstd/panicking.rs:390
Nov 17 19:45:46.512 INFO [stdout]    6:     0x5596035e6925 - rust_begin_unwind
Nov 17 19:45:46.512 INFO [stdout]                                at libstd/panicking.rs:325
Nov 17 19:45:46.512 INFO [stdout]    7:     0x5596035fa28c - core::panicking::panic_fmt::h400423a63e8ecedb
Nov 17 19:45:46.512 INFO [stdout]                                at libcore/panicking.rs:77
Nov 17 19:45:46.512 INFO [stdout]    8:     0x55960356e630 - core::result::unwrap_failed::he8144a30aac24b75
Nov 17 19:45:46.512 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/macros.rs:26
Nov 17 19:45:46.512 INFO [stdout]    9:     0x55960351a07f - <core::result::Result<T, E>>::unwrap::hf5915c287498d73e
Nov 17 19:45:46.512 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/result.rs:808
Nov 17 19:45:46.512 INFO [stdout]   10:     0x559603517721 - test_graph_serde::test_load_graph::h26883b71e780de53
Nov 17 19:45:46.512 INFO [stdout]                                at tests/test_graph_serde.rs:15
Nov 17 19:45:46.512 INFO [stdout]   11:     0x5596035176b9 - test_graph_serde::test_load_graph::{{closure}}::h45b43f7997012466
Nov 17 19:45:46.512 INFO [stdout]                                at tests/test_graph_serde.rs:13
Nov 17 19:45:46.512 INFO [stdout]   12:     0x559603518ccd - core::ops::function::FnOnce::call_once::h968cbae45a7d2982
Nov 17 19:45:46.512 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238
Nov 17 19:45:46.512 INFO [stdout]   13:     0x55960352375e - <F as alloc::boxed::FnBox<A>>::call_box::h9dcb7fc9b8bb4240
Nov 17 19:45:46.512 INFO [stdout]                                at libtest/lib.rs:1471
Nov 17 19:45:46.512 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238
Nov 17 19:45:46.512 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 17 19:45:46.512 INFO [stdout]   14:     0x5596035f5599 - __rust_maybe_catch_panic
Nov 17 19:45:46.512 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 19:45:46.512 INFO [stdout]   15:     0x55960351b7c3 - std::sys_common::backtrace::__rust_begin_short_backtrace::h89ea8afe71e36f86
Nov 17 19:45:46.512 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 17 19:45:46.512 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 17 19:45:46.512 INFO [stdout]                                at libtest/lib.rs:1426
Nov 17 19:45:46.512 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/sys_common/backtrace.rs:136
Nov 17 19:45:46.512 INFO [stdout]   16:     0x55960351c674 - std::panicking::try::do_call::h4132042e0cbea3ae
Nov 17 19:45:46.512 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:477
Nov 17 19:45:46.512 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319
Nov 17 19:45:46.512 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310
Nov 17 19:45:46.512 INFO [stdout]   17:     0x5596035f5599 - __rust_maybe_catch_panic
Nov 17 19:45:46.512 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 19:45:46.512 INFO [stdout]   18:     0x55960352388c - <F as alloc::boxed::FnBox<A>>::call_box::hab7f616f05880bf6
Nov 17 19:45:46.512 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 17 19:45:46.512 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 17 19:45:46.512 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:476
Nov 17 19:45:46.512 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 17 19:45:46.512 INFO [stdout]   19:     0x5596035e947d - std::sys::unix::thread::Thread::new::thread_start::h5f8d67158a1ca038
Nov 17 19:45:46.512 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:683
Nov 17 19:45:46.512 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Nov 17 19:45:46.512 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Nov 17 19:45:46.512 INFO [stdout]   20:     0x7f8bca3526b9 - start_thread
Nov 17 19:45:46.512 INFO [stdout]   21:     0x7f8bc9e7241c - clone
Nov 17 19:45:46.512 INFO [stdout]   22:                0x0 - <unknown>
Nov 17 19:45:46.512 INFO [stdout] 
Nov 17 19:45:46.512 INFO [stdout] 
Nov 17 19:45:46.512 INFO [stdout] failures:
Nov 17 19:45:46.512 INFO [stdout]     test_load_graph
Nov 17 19:45:46.512 INFO [stdout] 
Nov 17 19:45:46.512 INFO [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 19:45:46.512 INFO [stdout] 
Nov 17 19:45:46.529 INFO [stderr] su: No module specific data is present
Nov 17 19:45:47.300 INFO running `"docker" "inspect" "a9cf35b8293bfae6b69a5a837a9cef76c130979e5f1e7db269d2b4f705300be1"`
Nov 17 19:45:47.656 INFO running `"docker" "rm" "-f" "a9cf35b8293bfae6b69a5a837a9cef76c130979e5f1e7db269d2b4f705300be1"`
Nov 17 19:45:47.908 INFO [stdout] a9cf35b8293bfae6b69a5a837a9cef76c130979e5f1e7db269d2b4f705300be1