Dec 13 15:38:11.588 INFO testing jfredett/synacor against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 13 15:38:11.588 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 15:38:12.177 INFO [stdout] eae79c7454df3cf8820afefe14d059d9223c03535c25a852704378230b72a722
Dec 13 15:38:12.179 INFO running `"docker" "start" "-a" "eae79c7454df3cf8820afefe14d059d9223c03535c25a852704378230b72a722"`
Dec 13 15:38:12.850 INFO [stderr] usermod: no changes
Dec 13 15:38:12.906 INFO [stderr]    Compiling synacor v0.1.0 (/source)
Dec 13 15:38:13.637 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 13 15:38:13.638 INFO [stderr]    --> src/vm.rs:186:9
Dec 13 15:38:13.638 INFO [stderr]     |
Dec 13 15:38:13.638 INFO [stderr] 186 |         stdin.read_exact(&mut buf);
Dec 13 15:38:13.638 INFO [stderr]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 15:38:13.638 INFO [stderr]     |
Dec 13 15:38:13.638 INFO [stderr]     = note: #[warn(unused_must_use)] on by default
Dec 13 15:38:13.638 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 13 15:38:13.638 INFO [stderr] 
Dec 13 15:38:16.231 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.36s
Dec 13 15:38:16.238 INFO [stderr] su: No module specific data is present
Dec 13 15:38:16.756 INFO running `"docker" "inspect" "eae79c7454df3cf8820afefe14d059d9223c03535c25a852704378230b72a722"`
Dec 13 15:38:16.900 INFO running `"docker" "rm" "-f" "eae79c7454df3cf8820afefe14d059d9223c03535c25a852704378230b72a722"`
Dec 13 15:38:17.042 INFO [stdout] eae79c7454df3cf8820afefe14d059d9223c03535c25a852704378230b72a722
Dec 13 15:38:17.045 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 15:38:17.255 INFO [stdout] 5dec555f83d7b162aa5d99da4a7f365bd971635cf4bc8b36ce0b6bacbbec3c6e
Dec 13 15:38:17.260 INFO running `"docker" "start" "-a" "5dec555f83d7b162aa5d99da4a7f365bd971635cf4bc8b36ce0b6bacbbec3c6e"`
Dec 13 15:38:17.652 INFO [stderr] usermod: no changes
Dec 13 15:38:17.715 INFO [stderr]    Compiling synacor v0.1.0 (/source)
Dec 13 15:38:21.059 INFO [stderr] warning: unused arithmetic operation that must be used
Dec 13 15:38:21.059 INFO [stderr]    --> src/u15.rs:163:30
Dec 13 15:38:21.059 INFO [stderr]     |
Dec 13 15:38:21.059 INFO [stderr] 163 |         fn sub_underflow() { u15(0) - u15(2); }
Dec 13 15:38:21.059 INFO [stderr]     |                              ^^^^^^^^^^^^^^^
Dec 13 15:38:21.059 INFO [stderr]     |
Dec 13 15:38:21.059 INFO [stderr]     = note: #[warn(unused_must_use)] on by default
Dec 13 15:38:21.059 INFO [stderr] 
Dec 13 15:38:21.059 INFO [stderr] warning: unused arithmetic operation that must be used
Dec 13 15:38:21.059 INFO [stderr]    --> src/u15.rs:167:28
Dec 13 15:38:21.059 INFO [stderr]     |
Dec 13 15:38:21.059 INFO [stderr] 167 |         fn div_by_zero() { u15(1) / u15(0); }
Dec 13 15:38:21.059 INFO [stderr]     |                            ^^^^^^^^^^^^^^^
Dec 13 15:38:21.059 INFO [stderr] 
Dec 13 15:38:21.059 INFO [stderr] warning: unused arithmetic operation that must be used
Dec 13 15:38:21.059 INFO [stderr]    --> src/u15.rs:223:26
Dec 13 15:38:21.059 INFO [stderr]     |
Dec 13 15:38:21.059 INFO [stderr] 223 |         fn sub_panic() { u15(16) - u15(17); }
Dec 13 15:38:21.059 INFO [stderr]     |                          ^^^^^^^^^^^^^^^^^
Dec 13 15:38:21.059 INFO [stderr] 
Dec 13 15:38:21.126 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 13 15:38:21.126 INFO [stderr]    --> src/vm.rs:186:9
Dec 13 15:38:21.126 INFO [stderr]     |
Dec 13 15:38:21.126 INFO [stderr] 186 |         stdin.read_exact(&mut buf);
Dec 13 15:38:21.126 INFO [stderr]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 15:38:21.126 INFO [stderr]     |
Dec 13 15:38:21.126 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 13 15:38:21.126 INFO [stderr] 
Dec 13 15:38:23.435 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.75s
Dec 13 15:38:23.451 INFO [stderr] su: No module specific data is present
Dec 13 15:38:23.902 INFO running `"docker" "inspect" "5dec555f83d7b162aa5d99da4a7f365bd971635cf4bc8b36ce0b6bacbbec3c6e"`
Dec 13 15:38:24.002 INFO running `"docker" "rm" "-f" "5dec555f83d7b162aa5d99da4a7f365bd971635cf4bc8b36ce0b6bacbbec3c6e"`
Dec 13 15:38:24.121 INFO [stdout] 5dec555f83d7b162aa5d99da4a7f365bd971635cf4bc8b36ce0b6bacbbec3c6e
Dec 13 15:38:24.124 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 15:38:24.308 INFO [stdout] ffcd2ca2266d2deaa26457af81817b37ef84dd546ce4bdc43f13b93246245757
Dec 13 15:38:24.375 INFO running `"docker" "start" "-a" "ffcd2ca2266d2deaa26457af81817b37ef84dd546ce4bdc43f13b93246245757"`
Dec 13 15:38:25.003 INFO [stderr] usermod: no changes
Dec 13 15:38:25.069 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Dec 13 15:38:25.070 INFO [stderr]      Running /target/debug/deps/synacor-fdd0020650c25f48
Dec 13 15:38:25.070 INFO [stdout] 
Dec 13 15:38:25.070 INFO [stdout] running 312 tests
Dec 13 15:38:25.070 INFO [stdout] test address::tests::next ... ok
Dec 13 15:38:25.070 INFO [stdout] test address::tests::invalid ... ok
Dec 13 15:38:25.070 INFO [stdout] test address::tests::register_some ... ok
Dec 13 15:38:25.070 INFO [stdout] test address::tests::register_none ... ok
Dec 13 15:38:25.070 INFO [stdout] test address::tests::valid ... ok
Dec 13 15:38:25.070 INFO [stdout] test argument::tests::new_lit ... ok
Dec 13 15:38:25.070 INFO [stdout] test argument::tests::new_reg ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::add::lit_lit ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::add::reg_lit ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::add::reg_reg ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::add::lit_reg ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::and::lit_lit ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::and::lit_reg ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::and::reg_reg ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::and::reg_lit ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::eq::lit_reg ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::eq::lit_lit ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::eq::reg_lit ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::gt::lit_lit ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::halt::test ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::gt::lit_reg ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::call::reg ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::call::lit ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::eq::reg_reg ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::jf::lit_lit ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::jf::lit_reg ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::in_val::reg ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::in_val::lit ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::gt::reg_lit ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::gt::reg_reg ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::jf::reg_lit ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::jt::lit_lit ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::jt::lit_reg ... ok
Dec 13 15:38:25.070 INFO [stdout] test instruction::tests::display::jt::reg_lit ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::jmp::reg ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::modulo::lit_reg ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::modulo::lit_lit ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::jf::reg_reg ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::jt::reg_reg ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::mult::reg_lit ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::mult::lit_lit ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::mult::lit_reg ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::noop::test ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::modulo::reg_reg ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::not::lit ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::mult::reg_reg ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::not::reg ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::jmp::lit ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::modulo::reg_lit ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::or::lit_lit ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::or::reg_lit ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::or::lit_reg ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::out::reg ... ok
Dec 13 15:38:25.123 INFO [stdout] test instruction::tests::display::pop::reg ... ok
Dec 13 15:38:25.124 INFO [stdout] test instruction::tests::display::out::lit ... ok
Dec 13 15:38:25.124 INFO [stdout] test instruction::tests::display::push::lit ... ok
Dec 13 15:38:25.124 INFO [stdout] test instruction::tests::display::push::reg ... ok
Dec 13 15:38:25.124 INFO [stdout] test instruction::tests::display::or::reg_reg ... ok
Dec 13 15:38:25.124 INFO [stdout] test instruction::tests::display::rmem::lit ... ok
Dec 13 15:38:25.124 INFO [stdout] test instruction::tests::display::rmem::reg ... ok
Dec 13 15:38:25.124 INFO [stdout] test instruction::tests::display::set::lit ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::display::set::reg ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::display::wmem::lit_reg ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::display::wmem::lit_lit ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::display::wmem::reg_lit ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::display::wmem::reg_reg ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::add::lit_lit ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::add::lit_reg ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::add::reg_lit ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::and::lit_reg ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::and::reg_lit ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::and::reg_reg ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::call::lit ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::call::reg ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::eq::lit_lit ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::eq::reg_lit ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::and::lit_lit ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::eq::lit_reg ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::eq::reg_reg ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::add::reg_reg ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::gt::lit_lit ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::display::ret::test ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::in_val::reg ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::in_val::lit ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::gt::reg_lit ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::halt::halt ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::gt::reg_reg ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::gt::lit_reg ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::jf::lit_lit ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::jf::lit_reg ... ok
Dec 13 15:38:25.198 INFO [stdout] test instruction::tests::from_u16_sequence::jt::lit_lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::jt::lit_reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::jmp::lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::jmp::reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::jf::reg_lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::jf::reg_reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::modulo::lit_reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::modulo::lit_lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::modulo::reg_lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::jt::reg_reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::jt::reg_lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::mult::reg_lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::modulo::reg_reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::not::reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::mult::lit_lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::mult::lit_reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::or::lit_lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::or::lit_reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::or::reg_lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::or::reg_reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::out::lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::out::reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::noop::noop ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::mult::reg_reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::not::lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::push::lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::push::reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::ret::ret ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::rmem::lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::rmem::reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::set::reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::set::lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::wmem::lit_reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::wmem::reg_lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::from_u16_sequence::wmem::reg_reg ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::to_u16_sequence::add::lit_lit ... ok
Dec 13 15:38:25.199 INFO [stdout] test instruction::tests::to_u16_sequence::add::lit_reg ... ok
Dec 13 15:38:25.319 INFO [stdout] test instruction::tests::to_u16_sequence::add::reg_lit ... ok
Dec 13 15:38:25.319 INFO [stdout] test instruction::tests::from_u16_sequence::pop::reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::from_u16_sequence::wmem::lit_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::add::reg_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::call::lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::call::reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::eq::lit_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::and::lit_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::eq::reg_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::eq::reg_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::eq::lit_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::and::lit_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::gt::lit_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::gt::reg_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::in_val::reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::and::reg_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::jf::lit_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::jf::lit_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::and::reg_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::gt::lit_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::gt::reg_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::halt::halt ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::in_val::lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::jf::reg_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::jmp::lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::jmp::reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::jt::lit_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::jt::lit_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::jt::reg_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::jt::reg_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::modulo::lit_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::jf::reg_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::mult::reg_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::mult::lit_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::mult::reg_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::modulo::reg_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::modulo::reg_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::mult::lit_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::or::reg_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::or::lit_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::not::reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::noop::noop ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::not::lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::modulo::lit_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::or::lit_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::ret::ret ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::push::reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::or::reg_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::out::lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::out::reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::pop::reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::push::lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::wmem::lit_lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::set::reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::set::lit ... ok
Dec 13 15:38:25.367 INFO [stdout] test instruction::tests::to_u16_sequence::wmem::reg_reg ... ok
Dec 13 15:38:25.367 INFO [stdout] test register::tests::new ... ok
Dec 13 15:38:25.368 INFO [stdout] test instruction::tests::to_u16_sequence::rmem::lit ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::add ... ok
Dec 13 15:38:25.368 INFO [stdout] test instruction::tests::to_u16_sequence::wmem::lit_reg ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::add_assign ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::bitand ... ok
Dec 13 15:38:25.368 INFO [stdout] test instruction::tests::to_u16_sequence::wmem::reg_lit ... ok
Dec 13 15:38:25.368 INFO [stdout] test instruction::tests::to_u16_sequence::rmem::reg ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::bitand_assign ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::bitxor_assign ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::bitor ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::div_assign ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::div ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::mul ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::div_non_even_assign ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::div_non_even ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::bitxor ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::rem_assign ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::rem ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::bitor_assign ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::rem_assign_nonzero_remainder ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::rem_non_even ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::mul_assign ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::not ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shl ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shl_i16 ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shl_i32 ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shl_u32 ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shl_u64 ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shl_u16 ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shl_i8 ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shl_i64 ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shl_u8 ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shr_i64 ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shr_i8 ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shr_u32 ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shr_u16 ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shr_i32 ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shr_u64 ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::sub_assign ... ok
Dec 13 15:38:25.368 INFO [stdout] test argument::tests::new_panic_on_out_of_range ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shr_u8 ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shr ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::modular_arithmetic::add_overflow ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::modular_arithmetic::max_val ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::sub ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::modular_arithmetic::min_val ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::shr_i16 ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::modular_arithmetic::mul_overflow ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::modular_arithmetic::not_is_15b ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::add::lit_lit_nowrap ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::add::lit_reg_nowrap ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::add::lit_reg_wrap ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::add::reg_lit_nowrap ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::add::reg_lit_wrap ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::add::lit_lit_wrap ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::add::reg_reg_wrap ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::sub_panic ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::and::lit_lit ... ok
Dec 13 15:38:25.368 INFO [stdout] test register::tests::new_panics_with_lit_value ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::modular_arithmetic::div_by_zero ... ok
Dec 13 15:38:25.368 INFO [stdout] test register::tests::new_panics_with_invalid_value ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::basics::sub_panic_assign ... ok
Dec 13 15:38:25.368 INFO [stdout] test u15::tests::modular_arithmetic::sub_underflow ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::add::reg_reg_nowrap ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::and::lit_reg ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::call::lit ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::eq::lit_reg ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::eq::reg_reg ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::and::reg_lit ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::eq::reg_lit ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::gt::lit_lit_true ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::eq::lit_lit ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::call::reg ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::and::reg_reg ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::jf::reg_reg ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::gt::lit_lit_false ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::jf::reg_lit ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::gt::reg_reg ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::jf::lit_reg ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::jf::lit_lit ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::gt::reg_lit ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::gt::lit_reg ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::jt::lit_lit ... ok
Dec 13 15:38:25.368 INFO [stdout] test vm::tests::instructions::jump::reg ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::modulo::reg_reg ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::mult::lit_lit_nowrap ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::mult::lit_lit_wrap ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::mult::lit_reg_nowrap ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::mult::lit_reg_wrap ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::mult::reg_lit_nowrap ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::mult::reg_lit_wrap ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::mult::reg_reg_nowrap ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::mult::reg_reg_wrap ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::not::lit ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::not::reg ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::or::lit_lit ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::or::lit_reg ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::or::reg_lit ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::jt::reg_reg ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::modulo::lit_lit ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::modulo::lit_reg ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::jump::lit ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::pop::happy ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::push::lit ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::modulo::reg_lit ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::jt::reg_lit ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::jt::lit_reg ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::or::reg_reg ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::pop::nonempty_remaining_stack ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::set::reg ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::push::reg ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::ret::lit ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::ret::reg ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::set::lit ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::rmem::reg ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::rmem::lit ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::pop::stack_underflow_error ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::wmem::lit_lit ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::wmem::reg_reg ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::instructions::wmem::lit_reg ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::step::current_instruction_happy ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::load_program::valid_program_load ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::step::advance ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::step::current_instruction_bad_opcode ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::step::read_memory_happy ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::step::read_memory_invalid ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::step::current_instruction_malformed ... ok
Dec 13 15:38:25.369 INFO [stdout] test vm::tests::step::step ... ok
Dec 13 15:38:25.369 INFO [stdout] 
Dec 13 15:38:25.369 INFO [stdout] test result: ok. 312 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 15:38:25.369 INFO [stdout] 
Dec 13 15:38:25.369 INFO [stdout] 
Dec 13 15:38:25.369 INFO [stdout] running 0 tests
Dec 13 15:38:25.369 INFO [stdout] 
Dec 13 15:38:25.369 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 15:38:25.369 INFO [stdout] 
Dec 13 15:38:25.369 INFO [stdout] 
Dec 13 15:38:25.369 INFO [stdout] running 0 tests
Dec 13 15:38:25.369 INFO [stdout] 
Dec 13 15:38:25.369 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 15:38:25.369 INFO [stdout] 
Dec 13 15:38:25.369 INFO [stdout] 
Dec 13 15:38:25.369 INFO [stdout] running 0 tests
Dec 13 15:38:25.369 INFO [stdout] 
Dec 13 15:38:25.369 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 15:38:25.369 INFO [stdout] 
Dec 13 15:38:25.369 INFO [stderr]      Running /target/debug/deps/syn_asm-31451e70a233d914
Dec 13 15:38:25.369 INFO [stderr]      Running /target/debug/deps/syn_dis-8ef4b722a841adf9
Dec 13 15:38:25.369 INFO [stderr]      Running /target/debug/deps/syn_vm-4d08f75dda9ebdaf
Dec 13 15:38:25.369 INFO [stderr]    Doc-tests synacor
Dec 13 15:38:25.726 INFO [stdout] 
Dec 13 15:38:25.726 INFO [stdout] running 0 tests
Dec 13 15:38:25.726 INFO [stdout] 
Dec 13 15:38:25.726 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 15:38:25.726 INFO [stdout] 
Dec 13 15:38:25.726 INFO [stderr] su: No module specific data is present
Dec 13 15:38:26.117 INFO running `"docker" "inspect" "ffcd2ca2266d2deaa26457af81817b37ef84dd546ce4bdc43f13b93246245757"`
Dec 13 15:38:26.212 INFO running `"docker" "rm" "-f" "ffcd2ca2266d2deaa26457af81817b37ef84dd546ce4bdc43f13b93246245757"`
Dec 13 15:38:26.331 INFO [stdout] ffcd2ca2266d2deaa26457af81817b37ef84dd546ce4bdc43f13b93246245757