Nov 17 06:43:59.452 INFO testing chklauser/rust-nvm against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 17 06:43:59.452 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 06:43:59.947 INFO [stdout] 0306d28a2620e1ff7ed5e2750e530fe1cee7b54336dfb5d352dc124e6efdc86f
Nov 17 06:43:59.948 INFO running `"docker" "start" "-a" "0306d28a2620e1ff7ed5e2750e530fe1cee7b54336dfb5d352dc124e6efdc86f"`
Nov 17 06:44:01.180 INFO [stderr] usermod: no changes
Nov 17 06:44:01.322 INFO [stderr]    Compiling winapi-build v0.1.1
Nov 17 06:44:01.322 INFO [stderr]    Compiling libc v0.2.14
Nov 17 06:44:01.322 INFO [stderr]    Compiling regex-syntax v0.3.4
Nov 17 06:44:01.322 INFO [stderr]    Compiling combine v2.0.0
Nov 17 06:44:02.835 INFO [stderr]    Compiling kernel32-sys v0.2.2
Nov 17 06:44:03.519 INFO [stderr]    Compiling memchr v0.1.11
Nov 17 06:44:03.520 INFO [stderr]    Compiling term_size v0.2.1
Nov 17 06:44:04.699 INFO [stderr]    Compiling clap v2.19.1
Nov 17 06:44:05.033 INFO [stderr]    Compiling thread-id v2.0.0
Nov 17 06:44:05.399 INFO [stderr]    Compiling aho-corasick v0.5.2
Nov 17 06:44:06.307 INFO [stderr]    Compiling thread_local v0.2.6
Nov 17 06:44:10.708 INFO [stderr]    Compiling combine-language v2.0.0
Nov 17 06:44:12.767 INFO [stderr]    Compiling regex v0.1.73
Nov 17 06:44:27.178 INFO [stderr]    Compiling env_logger v0.3.4
Nov 17 06:44:38.600 INFO [stderr]    Compiling nvm v0.1.0 (/source)
Nov 17 06:44:39.620 INFO [stderr] warning: unused macro definition
Nov 17 06:44:39.620 INFO [stderr]    --> src/vm/machine.rs:109:1
Nov 17 06:44:39.621 INFO [stderr]     |
Nov 17 06:44:39.621 INFO [stderr] 109 | / macro_rules! try_check{
Nov 17 06:44:39.622 INFO [stderr] 110 | |   ($e:expr) => (e)
Nov 17 06:44:39.622 INFO [stderr] 111 | | }
Nov 17 06:44:39.622 INFO [stderr]     | |_^
Nov 17 06:44:39.622 INFO [stderr]     |
Nov 17 06:44:39.623 INFO [stderr]     = note: #[warn(unused_macros)] on by default
Nov 17 06:44:39.623 INFO [stderr] 
Nov 17 06:44:48.328 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 47.11s
Nov 17 06:44:48.335 INFO [stderr] su: No module specific data is present
Nov 17 06:44:49.621 INFO running `"docker" "inspect" "0306d28a2620e1ff7ed5e2750e530fe1cee7b54336dfb5d352dc124e6efdc86f"`
Nov 17 06:44:49.935 INFO running `"docker" "rm" "-f" "0306d28a2620e1ff7ed5e2750e530fe1cee7b54336dfb5d352dc124e6efdc86f"`
Nov 17 06:44:50.217 INFO [stdout] 0306d28a2620e1ff7ed5e2750e530fe1cee7b54336dfb5d352dc124e6efdc86f
Nov 17 06:44:50.229 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 06:44:50.612 INFO [stdout] 048d5b6d7f787acf72be76836a80a32336b89f283de9050b928182bee7b30ce4
Nov 17 06:44:50.614 INFO running `"docker" "start" "-a" "048d5b6d7f787acf72be76836a80a32336b89f283de9050b928182bee7b30ce4"`
Nov 17 06:44:52.870 INFO [stderr] usermod: no changes
Nov 17 06:44:53.043 INFO [stderr]    Compiling nvm v0.1.0 (/source)
Nov 17 06:44:54.092 INFO [stderr] warning: unused `#[macro_use]` import
Nov 17 06:44:54.092 INFO [stderr]  --> src/main.rs:8:1
Nov 17 06:44:54.092 INFO [stderr]   |
Nov 17 06:44:54.092 INFO [stderr] 8 | #[macro_use]
Nov 17 06:44:54.092 INFO [stderr]   | ^^^^^^^^^^^^
Nov 17 06:44:54.092 INFO [stderr]   |
Nov 17 06:44:54.094 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Nov 17 06:44:54.094 INFO [stderr] 
Nov 17 06:44:54.094 INFO [stderr] warning: unused macro definition
Nov 17 06:44:54.094 INFO [stderr]    --> src/vm/machine.rs:109:1
Nov 17 06:44:54.094 INFO [stderr]     |
Nov 17 06:44:54.094 INFO [stderr] 109 | / macro_rules! try_check{
Nov 17 06:44:54.094 INFO [stderr] 110 | |   ($e:expr) => (e)
Nov 17 06:44:54.094 INFO [stderr] 111 | | }
Nov 17 06:44:54.094 INFO [stderr]     | |_^
Nov 17 06:44:54.094 INFO [stderr]     |
Nov 17 06:44:54.094 INFO [stderr]     = note: #[warn(unused_macros)] on by default
Nov 17 06:44:54.094 INFO [stderr] 
Nov 17 06:45:03.199 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.28s
Nov 17 06:45:03.208 INFO [stderr] su: No module specific data is present
Nov 17 06:45:04.296 INFO running `"docker" "inspect" "048d5b6d7f787acf72be76836a80a32336b89f283de9050b928182bee7b30ce4"`
Nov 17 06:45:04.599 INFO running `"docker" "rm" "-f" "048d5b6d7f787acf72be76836a80a32336b89f283de9050b928182bee7b30ce4"`
Nov 17 06:45:04.851 INFO [stdout] 048d5b6d7f787acf72be76836a80a32336b89f283de9050b928182bee7b30ce4
Nov 17 06:45:04.858 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 06:45:05.327 INFO [stdout] 206a7f3605b8b7bf78095cd67895cd050dd8f45404c8b8ddb9f6b5215610e56a
Nov 17 06:45:05.329 INFO running `"docker" "start" "-a" "206a7f3605b8b7bf78095cd67895cd050dd8f45404c8b8ddb9f6b5215610e56a"`
Nov 17 06:45:06.682 INFO [stderr] usermod: no changes
Nov 17 06:45:06.840 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.11s
Nov 17 06:45:06.849 INFO [stderr]      Running /target/debug/deps/nvm-602ded7aa95146d3
Nov 17 06:45:06.851 INFO [stdout] 
Nov 17 06:45:06.851 INFO [stdout] running 18 tests
Nov 17 06:45:06.868 INFO [stdout] test tests::allow_interpreter_line ... ok
Nov 17 06:45:06.869 INFO [stdout] test tests::if_else_test ... ok
Nov 17 06:45:06.872 INFO [stdout] test tests::if_test ... ok
Nov 17 06:45:06.872 INFO [stdout] test tests::if_else_if ... ok
Nov 17 06:45:06.883 INFO [stdout] test tests::add ... ok
Nov 17 06:45:06.885 INFO [stdout] test tests::compile_fib_iter ... ok
Nov 17 06:45:06.893 INFO [stdout] test tests::run_gen_fib8 ... ok
Nov 17 06:45:06.899 INFO [stdout] test tests::readme_example ... ok
Nov 17 06:45:06.903 INFO [stdout] test tests::ref_of_params_function ... ok
Nov 17 06:45:06.919 INFO [stdout] test tests::run_gen_fib4 ... ok
Nov 17 06:45:06.919 INFO [stdout] test tests::run_fib_iter8 ... ok
Nov 17 06:45:06.923 INFO [stdout] test tests::miniprogram ... ok
Nov 17 06:45:06.923 INFO [stdout] test tests::simple_function_call ... ok
Nov 17 06:45:06.933 INFO [stdout] test tests::ref_in_function ... ok
Nov 17 06:45:06.934 INFO [stdout] test tests::sample ... ok
Nov 17 06:45:06.943 INFO [stdout] test tests::maxfn ... ok
Nov 17 06:45:06.944 INFO [stdout] test tests::simple_call ... ok
Nov 17 06:45:06.951 INFO [stdout] test tests::simple_call_v ... ok
Nov 17 06:45:06.951 INFO [stdout] 
Nov 17 06:45:06.951 INFO [stdout] test result: ok. 18 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 06:45:06.951 INFO [stdout] 
Nov 17 06:45:06.959 INFO [stderr] su: No module specific data is present
Nov 17 06:45:08.185 INFO running `"docker" "inspect" "206a7f3605b8b7bf78095cd67895cd050dd8f45404c8b8ddb9f6b5215610e56a"`
Nov 17 06:45:08.419 INFO running `"docker" "rm" "-f" "206a7f3605b8b7bf78095cd67895cd050dd8f45404c8b8ddb9f6b5215610e56a"`
Nov 17 06:45:08.719 INFO [stdout] 206a7f3605b8b7bf78095cd67895cd050dd8f45404c8b8ddb9f6b5215610e56a