Dec 13 23:48:50.533 INFO testing chklauser/rust-nvm against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 13 23:48:50.533 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 13 23:48:51.131 INFO [stdout] c6ff80fd8f4473e3e3cab0eaa841067776f6b7b18b123677f6f696a6d955b698
Dec 13 23:48:51.133 INFO running `"docker" "start" "-a" "c6ff80fd8f4473e3e3cab0eaa841067776f6b7b18b123677f6f696a6d955b698"`
Dec 13 23:48:53.202 INFO [stderr] usermod: no changes
Dec 13 23:48:53.375 INFO [stderr]    Compiling term_size v0.2.1
Dec 13 23:48:53.375 INFO [stderr]    Compiling combine v2.0.0
Dec 13 23:48:53.395 INFO [stderr]    Compiling env_logger v0.3.4
Dec 13 23:48:54.115 INFO [stderr]    Compiling clap v2.19.1
Dec 13 23:48:59.991 INFO [stderr]    Compiling combine-language v2.0.0
Dec 13 23:49:15.461 INFO [stderr]    Compiling nvm v0.1.0 (/source)
Dec 13 23:49:16.639 INFO [stderr] warning: unused macro definition
Dec 13 23:49:16.639 INFO [stderr]    --> src/vm/machine.rs:109:1
Dec 13 23:49:16.639 INFO [stderr]     |
Dec 13 23:49:16.639 INFO [stderr] 109 | / macro_rules! try_check{
Dec 13 23:49:16.639 INFO [stderr] 110 | |   ($e:expr) => (e)
Dec 13 23:49:16.639 INFO [stderr] 111 | | }
Dec 13 23:49:16.639 INFO [stderr]     | |_^
Dec 13 23:49:16.639 INFO [stderr]     |
Dec 13 23:49:16.639 INFO [stderr]     = note: #[warn(unused_macros)] on by default
Dec 13 23:49:16.639 INFO [stderr] 
Dec 13 23:49:25.453 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 32.22s
Dec 13 23:49:25.458 INFO [stderr] su: No module specific data is present
Dec 13 23:49:26.459 INFO running `"docker" "inspect" "c6ff80fd8f4473e3e3cab0eaa841067776f6b7b18b123677f6f696a6d955b698"`
Dec 13 23:49:26.713 INFO running `"docker" "rm" "-f" "c6ff80fd8f4473e3e3cab0eaa841067776f6b7b18b123677f6f696a6d955b698"`
Dec 13 23:49:27.009 INFO [stdout] c6ff80fd8f4473e3e3cab0eaa841067776f6b7b18b123677f6f696a6d955b698
Dec 13 23:49:27.015 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 13 23:49:27.426 INFO [stdout] 5bfb09bbb2144d3b54d61baf347396445d5d26f1ba9c1dfb10c6d54d429ea1f4
Dec 13 23:49:27.428 INFO running `"docker" "start" "-a" "5bfb09bbb2144d3b54d61baf347396445d5d26f1ba9c1dfb10c6d54d429ea1f4"`
Dec 13 23:49:29.415 INFO [stderr] usermod: no changes
Dec 13 23:49:29.535 INFO [stderr]    Compiling nvm v0.1.0 (/source)
Dec 13 23:49:30.857 INFO [stderr] warning: unused `#[macro_use]` import
Dec 13 23:49:30.857 INFO [stderr]  --> src/main.rs:8:1
Dec 13 23:49:30.857 INFO [stderr]   |
Dec 13 23:49:30.857 INFO [stderr] 8 | #[macro_use]
Dec 13 23:49:30.857 INFO [stderr]   | ^^^^^^^^^^^^
Dec 13 23:49:30.857 INFO [stderr]   |
Dec 13 23:49:30.857 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Dec 13 23:49:30.857 INFO [stderr] 
Dec 13 23:49:30.857 INFO [stderr] warning: unused macro definition
Dec 13 23:49:30.857 INFO [stderr]    --> src/vm/machine.rs:109:1
Dec 13 23:49:30.857 INFO [stderr]     |
Dec 13 23:49:30.857 INFO [stderr] 109 | / macro_rules! try_check{
Dec 13 23:49:30.857 INFO [stderr] 110 | |   ($e:expr) => (e)
Dec 13 23:49:30.857 INFO [stderr] 111 | | }
Dec 13 23:49:30.857 INFO [stderr]     | |_^
Dec 13 23:49:30.857 INFO [stderr]     |
Dec 13 23:49:30.857 INFO [stderr]     = note: #[warn(unused_macros)] on by default
Dec 13 23:49:30.857 INFO [stderr] 
Dec 13 23:49:39.466 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.03s
Dec 13 23:49:39.472 INFO [stderr] su: No module specific data is present
Dec 13 23:49:40.367 INFO running `"docker" "inspect" "5bfb09bbb2144d3b54d61baf347396445d5d26f1ba9c1dfb10c6d54d429ea1f4"`
Dec 13 23:49:40.672 INFO running `"docker" "rm" "-f" "5bfb09bbb2144d3b54d61baf347396445d5d26f1ba9c1dfb10c6d54d429ea1f4"`
Dec 13 23:49:41.009 INFO [stdout] 5bfb09bbb2144d3b54d61baf347396445d5d26f1ba9c1dfb10c6d54d429ea1f4
Dec 13 23:49:41.015 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 13 23:49:41.671 INFO [stdout] 5db5effb683ee278b28392c17db984dde00eaf283d0ba66caee1281bb24c7264
Dec 13 23:49:41.673 INFO running `"docker" "start" "-a" "5db5effb683ee278b28392c17db984dde00eaf283d0ba66caee1281bb24c7264"`
Dec 13 23:49:43.107 INFO [stderr] usermod: no changes
Dec 13 23:49:43.299 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.16s
Dec 13 23:49:43.306 INFO [stderr]      Running /target/debug/deps/nvm-7fdafd605b813be8
Dec 13 23:49:43.309 INFO [stdout] 
Dec 13 23:49:43.309 INFO [stdout] running 18 tests
Dec 13 23:49:43.309 INFO [stdout] test tests::miniprogram ... ok
Dec 13 23:49:43.312 INFO [stdout] test tests::if_else_if ... ok
Dec 13 23:49:43.312 INFO [stdout] test tests::if_else_test ... ok
Dec 13 23:49:43.327 INFO [stdout] test tests::ref_in_function ... ok
Dec 13 23:49:43.347 INFO [stdout] test tests::maxfn ... ok
Dec 13 23:49:43.347 INFO [stdout] test tests::ref_of_params_function ... ok
Dec 13 23:49:43.347 INFO [stdout] test tests::run_fib_iter8 ... ok
Dec 13 23:49:43.347 INFO [stdout] test tests::if_test ... ok
Dec 13 23:49:43.347 INFO [stdout] test tests::compile_fib_iter ... ok
Dec 13 23:49:43.347 INFO [stdout] test tests::allow_interpreter_line ... ok
Dec 13 23:49:43.367 INFO [stdout] test tests::sample ... ok
Dec 13 23:49:43.374 INFO [stdout] test tests::add ... ok
Dec 13 23:49:43.374 INFO [stdout] test tests::run_gen_fib4 ... ok
Dec 13 23:49:43.383 INFO [stdout] test tests::run_gen_fib8 ... ok
Dec 13 23:49:43.383 INFO [stdout] test tests::simple_call ... ok
Dec 13 23:49:43.383 INFO [stdout] test tests::simple_call_v ... ok
Dec 13 23:49:43.383 INFO [stdout] test tests::simple_function_call ... ok
Dec 13 23:49:43.399 INFO [stdout] test tests::readme_example ... ok
Dec 13 23:49:43.399 INFO [stdout] 
Dec 13 23:49:43.399 INFO [stdout] test result: ok. 18 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 23:49:43.399 INFO [stdout] 
Dec 13 23:49:43.421 INFO [stderr] su: No module specific data is present
Dec 13 23:49:44.155 INFO running `"docker" "inspect" "5db5effb683ee278b28392c17db984dde00eaf283d0ba66caee1281bb24c7264"`
Dec 13 23:49:44.407 INFO running `"docker" "rm" "-f" "5db5effb683ee278b28392c17db984dde00eaf283d0ba66caee1281bb24c7264"`
Dec 13 23:49:44.725 INFO [stdout] 5db5effb683ee278b28392c17db984dde00eaf283d0ba66caee1281bb24c7264