Nov 17 19:45:10.759 INFO testing tvm-0.1.0 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 17 19:45:10.761 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:11.219 INFO [stdout] a8f615f19c03282b65b637a7f32648e5d763f15ba26cfd2c111e6fd251676709
Nov 17 19:45:11.221 INFO running `"docker" "start" "-a" "a8f615f19c03282b65b637a7f32648e5d763f15ba26cfd2c111e6fd251676709"`
Nov 17 19:45:12.588 INFO [stderr] usermod: no changes
Nov 17 19:45:12.727 INFO [stderr]    Compiling bounded-spsc-queue v0.4.0
Nov 17 19:45:12.727 INFO [stderr]    Compiling memchr v2.1.1
Nov 17 19:45:12.728 INFO [stderr]    Compiling num-complex v0.1.43
Nov 17 19:45:15.523 INFO [stderr]    Compiling ndarray v0.11.2
Nov 17 19:45:16.436 INFO [stderr]    Compiling nom v4.1.1
Nov 17 19:45:27.591 INFO [stderr]    Compiling tvm v0.1.0 (/source)
Nov 17 19:45:30.011 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:30.011 INFO [stderr]  --> src/lib.rs:5:3
Nov 17 19:45:30.011 INFO [stderr]   |
Nov 17 19:45:30.011 INFO [stderr] 5 |   extern_prelude,
Nov 17 19:45:30.011 INFO [stderr]   |   ^^^^^^^^^^^^^^
Nov 17 19:45:30.011 INFO [stderr]   |
Nov 17 19:45:30.011 INFO [stderr]   = note: #[warn(stable_features)] on by default
Nov 17 19:45:30.011 INFO [stderr] 
Nov 17 19:45:34.066 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 21.45s
Nov 17 19:45:34.076 INFO [stderr] su: No module specific data is present
Nov 17 19:45:34.814 INFO running `"docker" "inspect" "a8f615f19c03282b65b637a7f32648e5d763f15ba26cfd2c111e6fd251676709"`
Nov 17 19:45:35.108 INFO running `"docker" "rm" "-f" "a8f615f19c03282b65b637a7f32648e5d763f15ba26cfd2c111e6fd251676709"`
Nov 17 19:45:35.521 INFO [stdout] a8f615f19c03282b65b637a7f32648e5d763f15ba26cfd2c111e6fd251676709
Nov 17 19:45:35.524 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:36.065 INFO [stdout] d58dacdbe726fd53d495fa40dbf035140eeac2955ac64c07dafb7067d5e90120
Nov 17 19:45:36.069 INFO running `"docker" "start" "-a" "d58dacdbe726fd53d495fa40dbf035140eeac2955ac64c07dafb7067d5e90120"`
Nov 17 19:45:37.137 INFO [stderr] usermod: no changes
Nov 17 19:45:37.319 INFO [stderr]    Compiling tvm v0.1.0 (/source)
Nov 17 19:45:40.078 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.078 INFO [stderr]  --> src/lib.rs:5:3
Nov 17 19:45:40.078 INFO [stderr]   |
Nov 17 19:45:40.078 INFO [stderr] 5 |   extern_prelude,
Nov 17 19:45:40.078 INFO [stderr]   |   ^^^^^^^^^^^^^^
Nov 17 19:45:40.078 INFO [stderr]   |
Nov 17 19:45:40.078 INFO [stderr]   = note: #[warn(stable_features)] on by default
Nov 17 19:45:40.078 INFO [stderr] 
Nov 17 19:45:41.250 INFO [stderr] warning: unused import: `collections::HashMap`
Nov 17 19:45:41.250 INFO [stderr]  --> tests/test_graph_serde.rs:8:11
Nov 17 19:45:41.250 INFO [stderr]   |
Nov 17 19:45:41.250 INFO [stderr] 8 | use std::{collections::HashMap, convert::TryFrom, fs, io::Read};
Nov 17 19:45:41.250 INFO [stderr]   |           ^^^^^^^^^^^^^^^^^^^^
Nov 17 19:45:41.250 INFO [stderr]   |
Nov 17 19:45:41.250 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Nov 17 19:45:41.250 INFO [stderr] 
Nov 17 19:45:41.431 INFO [stderr] warning: unused variable: `params`
Nov 17 19:45:41.431 INFO [stderr]   --> tests/test_graph_serde.rs:19:7
Nov 17 19:45:41.431 INFO [stderr]    |
Nov 17 19:45:41.431 INFO [stderr] 19 |   let params = tvm::runtime::load_param_dict(&params_bytes);
Nov 17 19:45:41.431 INFO [stderr]    |       ^^^^^^ help: consider using `_params` instead
Nov 17 19:45:41.431 INFO [stderr]    |
Nov 17 19:45:41.431 INFO [stderr]    = note: #[warn(unused_variables)] on by default
Nov 17 19:45:41.431 INFO [stderr] 
Nov 17 19:45:41.459 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:41.459 INFO [stderr]  --> tests/test_graph_serde.rs:1:12
Nov 17 19:45:41.459 INFO [stderr]   |
Nov 17 19:45:41.459 INFO [stderr] 1 | #![feature(fs_read_write, try_from)]
Nov 17 19:45:41.459 INFO [stderr]   |            ^^^^^^^^^^^^^
Nov 17 19:45:41.459 INFO [stderr]   |
Nov 17 19:45:41.459 INFO [stderr]   = note: #[warn(stable_features)] on by default
Nov 17 19:45:41.459 INFO [stderr] 
Nov 17 19:45:42.595 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.41s
Nov 17 19:45:42.600 INFO [stderr] su: No module specific data is present
Nov 17 19:45:43.148 INFO running `"docker" "inspect" "d58dacdbe726fd53d495fa40dbf035140eeac2955ac64c07dafb7067d5e90120"`
Nov 17 19:45:43.449 INFO running `"docker" "rm" "-f" "d58dacdbe726fd53d495fa40dbf035140eeac2955ac64c07dafb7067d5e90120"`
Nov 17 19:45:43.779 INFO [stdout] d58dacdbe726fd53d495fa40dbf035140eeac2955ac64c07dafb7067d5e90120
Nov 17 19:45:43.793 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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.283 INFO [stdout] dbc4355c05ae7fc63bca0efa5801dfbe9d49a4f605bbdfc98f4e9dcf8b746c00
Nov 17 19:45:44.286 INFO running `"docker" "start" "-a" "dbc4355c05ae7fc63bca0efa5801dfbe9d49a4f605bbdfc98f4e9dcf8b746c00"`
Nov 17 19:45:45.339 INFO [stderr] usermod: no changes
Nov 17 19:45:45.535 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.16s
Nov 17 19:45:45.535 INFO [stderr]      Running /target/debug/deps/tvm-f5cd119f071c3773
Nov 17 19:45:45.539 INFO [stdout] 
Nov 17 19:45:45.539 INFO [stdout] running 3 tests
Nov 17 19:45:45.560 INFO [stdout] test runtime::threading::tests::test_max_concurrency ... ok
Nov 17 19:45:45.560 INFO [stdout] test runtime::graph::tests::test_str_to_type ... ok
Nov 17 19:45:45.743 INFO [stdout] test runtime::threading::tests::test_parallel_launch ... ok
Nov 17 19:45:45.743 INFO [stdout] 
Nov 17 19:45:45.743 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 19:45:45.743 INFO [stdout] 
Nov 17 19:45:45.751 INFO [stderr]      Running /target/debug/deps/test_graph_serde-083f7db5de3f4830
Nov 17 19:45:45.754 INFO [stdout] 
Nov 17 19:45:45.754 INFO [stdout] running 1 test
Nov 17 19:45:45.824 INFO [stdout] test test_load_graph ... FAILED
Nov 17 19:45:45.824 INFO [stdout] 
Nov 17 19:45:45.824 INFO [stdout] failures:
Nov 17 19:45:45.824 INFO [stdout] 
Nov 17 19:45:45.824 INFO [stdout] ---- test_load_graph stdout ----
Nov 17 19:45:45.824 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:45.824 INFO [stdout] stack backtrace:
Nov 17 19:45:45.824 INFO [stdout]    0:     0x563b5c03f853 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h26df0f6fdb27c709
Nov 17 19:45:45.824 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 17 19:45:45.824 INFO [stdout]    1:     0x563b5c03a0b8 - std::sys_common::backtrace::_print::h84c40fecda6d638e
Nov 17 19:45:45.824 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Nov 17 19:45:45.824 INFO [stdout]    2:     0x563b5c03e7c4 - std::panicking::default_hook::{{closure}}::h77e370c0567fc0a7
Nov 17 19:45:45.824 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Nov 17 19:45:45.824 INFO [stdout]                                at libstd/panicking.rs:211
Nov 17 19:45:45.824 INFO [stdout]    3:     0x563b5c03e4ac - std::panicking::default_hook::hb9a45c11a33bbcb2
Nov 17 19:45:45.824 INFO [stdout]                                at libstd/panicking.rs:221
Nov 17 19:45:45.824 INFO [stdout]    4:     0x563b5c03eed0 - std::panicking::rust_panic_with_hook::ha8d95048d940f754
Nov 17 19:45:45.824 INFO [stdout]                                at libstd/panicking.rs:476
Nov 17 19:45:45.824 INFO [stdout]    5:     0x563b5c03ea51 - std::panicking::continue_panic_fmt::hfc82a3747387e2c6
Nov 17 19:45:45.824 INFO [stdout]                                at libstd/panicking.rs:390
Nov 17 19:45:45.824 INFO [stdout]    6:     0x563b5c03e935 - rust_begin_unwind
Nov 17 19:45:45.824 INFO [stdout]                                at libstd/panicking.rs:325
Nov 17 19:45:45.824 INFO [stdout]    7:     0x563b5c05236c - core::panicking::panic_fmt::h0b2a5728bdf6d5e2
Nov 17 19:45:45.824 INFO [stdout]                                at libcore/panicking.rs:77
Nov 17 19:45:45.824 INFO [stdout]    8:     0x563b5bfc5570 - core::result::unwrap_failed::h68436c6327d678bc
Nov 17 19:45:45.824 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/macros.rs:26
Nov 17 19:45:45.824 INFO [stdout]    9:     0x563b5bf70f7f - <core::result::Result<T, E>>::unwrap::ha04535459c82eecd
Nov 17 19:45:45.824 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/result.rs:808
Nov 17 19:45:45.824 INFO [stdout]   10:     0x563b5bf6e7c1 - test_graph_serde::test_load_graph::h10f510e1b5c3279a
Nov 17 19:45:45.824 INFO [stdout]                                at tests/test_graph_serde.rs:15
Nov 17 19:45:45.824 INFO [stdout]   11:     0x563b5bf6e759 - test_graph_serde::test_load_graph::{{closure}}::hd36d852a4d02c007
Nov 17 19:45:45.824 INFO [stdout]                                at tests/test_graph_serde.rs:13
Nov 17 19:45:45.824 INFO [stdout]   12:     0x563b5bf6fdad - core::ops::function::FnOnce::call_once::hb2a7c27adefd170c
Nov 17 19:45:45.824 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/ops/function.rs:238
Nov 17 19:45:45.824 INFO [stdout]   13:     0x563b5bf7aa0e - <F as alloc::boxed::FnBox<A>>::call_box::hae1ea457a0ccd8d5
Nov 17 19:45:45.824 INFO [stdout]                                at libtest/lib.rs:1471
Nov 17 19:45:45.824 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/ops/function.rs:238
Nov 17 19:45:45.824 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:673
Nov 17 19:45:45.824 INFO [stdout]   14:     0x563b5c04d6b9 - __rust_maybe_catch_panic
Nov 17 19:45:45.824 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 19:45:45.824 INFO [stdout]   15:     0x563b5bf72863 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd0ded23d435d70d0
Nov 17 19:45:45.824 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:289
Nov 17 19:45:45.824 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:398
Nov 17 19:45:45.824 INFO [stdout]                                at libtest/lib.rs:1426
Nov 17 19:45:45.824 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/sys_common/backtrace.rs:136
Nov 17 19:45:45.824 INFO [stdout]   16:     0x563b5bf73704 - std::panicking::try::do_call::h031514fb401b7159
Nov 17 19:45:45.824 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/thread/mod.rs:477
Nov 17 19:45:45.824 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:319
Nov 17 19:45:45.824 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:310
Nov 17 19:45:45.824 INFO [stdout]   17:     0x563b5c04d6b9 - __rust_maybe_catch_panic
Nov 17 19:45:45.824 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 19:45:45.824 INFO [stdout]   18:     0x563b5bf7a8ec - <F as alloc::boxed::FnBox<A>>::call_box::h44700b7a2ec6fd33
Nov 17 19:45:45.824 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:289
Nov 17 19:45:45.824 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:398
Nov 17 19:45:45.824 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/thread/mod.rs:476
Nov 17 19:45:45.824 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:673
Nov 17 19:45:45.824 INFO [stdout]   19:     0x563b5c04159d - std::sys::unix::thread::Thread::new::thread_start::h8526625243d2f13b
Nov 17 19:45:45.824 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:683
Nov 17 19:45:45.824 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Nov 17 19:45:45.824 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Nov 17 19:45:45.824 INFO [stdout]   20:     0x7f9aa6eb46b9 - start_thread
Nov 17 19:45:45.824 INFO [stdout]   21:     0x7f9aa69d441c - clone
Nov 17 19:45:45.824 INFO [stdout]   22:                0x0 - <unknown>
Nov 17 19:45:45.824 INFO [stdout] 
Nov 17 19:45:45.824 INFO [stdout] 
Nov 17 19:45:45.824 INFO [stdout] failures:
Nov 17 19:45:45.824 INFO [stdout]     test_load_graph
Nov 17 19:45:45.824 INFO [stdout] 
Nov 17 19:45:45.825 INFO [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 19:45:45.825 INFO [stdout] 
Nov 17 19:45:45.835 INFO [stderr] error: test failed, to rerun pass '--test test_graph_serde'
Nov 17 19:45:45.835 INFO [stderr] su: No module specific data is present
Nov 17 19:45:46.999 INFO running `"docker" "inspect" "dbc4355c05ae7fc63bca0efa5801dfbe9d49a4f605bbdfc98f4e9dcf8b746c00"`
Nov 17 19:45:47.300 INFO running `"docker" "rm" "-f" "dbc4355c05ae7fc63bca0efa5801dfbe9d49a4f605bbdfc98f4e9dcf8b746c00"`
Nov 17 19:45:47.607 INFO [stdout] dbc4355c05ae7fc63bca0efa5801dfbe9d49a4f605bbdfc98f4e9dcf8b746c00