Nov 16 23:46:49.874 INFO testing jfredett/synacor against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 16 23:46:49.876 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 16 23:46:50.175 INFO [stdout] c4d38b965777c0655b56096295d38739f8533f0a7be88fa5f72c025724c7e8bc
Nov 16 23:46:50.176 INFO running `"docker" "start" "-a" "c4d38b965777c0655b56096295d38739f8533f0a7be88fa5f72c025724c7e8bc"`
Nov 16 23:46:50.927 INFO [stderr] usermod: no changes
Nov 16 23:46:50.984 INFO [stderr]    Compiling synacor v0.1.0 (/source)
Nov 16 23:46:51.663 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 16 23:46:51.664 INFO [stderr]    --> src/vm.rs:186:9
Nov 16 23:46:51.664 INFO [stderr]     |
Nov 16 23:46:51.664 INFO [stderr] 186 |         stdin.read_exact(&mut buf);
Nov 16 23:46:51.664 INFO [stderr]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 16 23:46:51.664 INFO [stderr]     |
Nov 16 23:46:51.671 INFO [stderr]     = note: #[warn(unused_must_use)] on by default
Nov 16 23:46:51.671 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Nov 16 23:46:51.671 INFO [stderr] 
Nov 16 23:46:55.054 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.11s
Nov 16 23:46:55.070 INFO [stderr] su: No module specific data is present
Nov 16 23:46:55.812 INFO running `"docker" "inspect" "c4d38b965777c0655b56096295d38739f8533f0a7be88fa5f72c025724c7e8bc"`
Nov 16 23:46:56.175 INFO running `"docker" "rm" "-f" "c4d38b965777c0655b56096295d38739f8533f0a7be88fa5f72c025724c7e8bc"`
Nov 16 23:46:56.479 INFO [stdout] c4d38b965777c0655b56096295d38739f8533f0a7be88fa5f72c025724c7e8bc
Nov 16 23:46:56.488 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 16 23:46:57.011 INFO [stdout] a2bfb6cabfbc3ae075781dfb4c257ec3c5f71736d9a3d799b02f4cdcf6103937
Nov 16 23:46:57.013 INFO running `"docker" "start" "-a" "a2bfb6cabfbc3ae075781dfb4c257ec3c5f71736d9a3d799b02f4cdcf6103937"`
Nov 16 23:46:57.904 INFO [stderr] usermod: no changes
Nov 16 23:46:58.039 INFO [stderr]    Compiling synacor v0.1.0 (/source)
Nov 16 23:47:01.362 INFO [stderr] warning: unused arithmetic operation that must be used
Nov 16 23:47:01.363 INFO [stderr]    --> src/u15.rs:163:30
Nov 16 23:47:01.363 INFO [stderr]     |
Nov 16 23:47:01.364 INFO [stderr] 163 |         fn sub_underflow() { u15(0) - u15(2); }
Nov 16 23:47:01.364 INFO [stderr]     |                              ^^^^^^^^^^^^^^^
Nov 16 23:47:01.364 INFO [stderr]     |
Nov 16 23:47:01.364 INFO [stderr]     = note: #[warn(unused_must_use)] on by default
Nov 16 23:47:01.365 INFO [stderr] 
Nov 16 23:47:01.365 INFO [stderr] warning: unused arithmetic operation that must be used
Nov 16 23:47:01.366 INFO [stderr]    --> src/u15.rs:167:28
Nov 16 23:47:01.366 INFO [stderr]     |
Nov 16 23:47:01.366 INFO [stderr] 167 |         fn div_by_zero() { u15(1) / u15(0); }
Nov 16 23:47:01.367 INFO [stderr]     |                            ^^^^^^^^^^^^^^^
Nov 16 23:47:01.368 INFO [stderr] 
Nov 16 23:47:01.368 INFO [stderr] warning: unused arithmetic operation that must be used
Nov 16 23:47:01.368 INFO [stderr]    --> src/u15.rs:223:26
Nov 16 23:47:01.368 INFO [stderr]     |
Nov 16 23:47:01.368 INFO [stderr] 223 |         fn sub_panic() { u15(16) - u15(17); }
Nov 16 23:47:01.368 INFO [stderr]     |                          ^^^^^^^^^^^^^^^^^
Nov 16 23:47:01.368 INFO [stderr] 
Nov 16 23:47:01.423 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 16 23:47:01.424 INFO [stderr]    --> src/vm.rs:186:9
Nov 16 23:47:01.424 INFO [stderr]     |
Nov 16 23:47:01.424 INFO [stderr] 186 |         stdin.read_exact(&mut buf);
Nov 16 23:47:01.424 INFO [stderr]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 16 23:47:01.425 INFO [stderr]     |
Nov 16 23:47:01.425 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Nov 16 23:47:01.425 INFO [stderr] 
Nov 16 23:47:02.885 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.92s
Nov 16 23:47:02.890 INFO [stderr] su: No module specific data is present
Nov 16 23:47:03.183 INFO running `"docker" "inspect" "a2bfb6cabfbc3ae075781dfb4c257ec3c5f71736d9a3d799b02f4cdcf6103937"`
Nov 16 23:47:03.353 INFO running `"docker" "rm" "-f" "a2bfb6cabfbc3ae075781dfb4c257ec3c5f71736d9a3d799b02f4cdcf6103937"`
Nov 16 23:47:03.575 INFO [stdout] a2bfb6cabfbc3ae075781dfb4c257ec3c5f71736d9a3d799b02f4cdcf6103937
Nov 16 23:47:03.577 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 16 23:47:03.978 INFO [stdout] 9397b6c9a3f4c3d1bdb09b981c53747488339afc51068aa3d41757d8f0c62682
Nov 16 23:47:03.979 INFO running `"docker" "start" "-a" "9397b6c9a3f4c3d1bdb09b981c53747488339afc51068aa3d41757d8f0c62682"`
Nov 16 23:47:04.691 INFO [stderr] usermod: no changes
Nov 16 23:47:04.743 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Nov 16 23:47:04.744 INFO [stderr]      Running /target/debug/deps/synacor-6f70e7ccac1a908e
Nov 16 23:47:04.750 INFO [stdout] 
Nov 16 23:47:04.751 INFO [stdout] running 312 tests
Nov 16 23:47:04.751 INFO [stdout] test argument::tests::new_reg ... ok
Nov 16 23:47:04.751 INFO [stdout] test instruction::tests::display::add::lit_lit ... ok
Nov 16 23:47:04.751 INFO [stdout] test address::tests::next ... ok
Nov 16 23:47:04.751 INFO [stdout] test address::tests::register_none ... ok
Nov 16 23:47:04.751 INFO [stdout] test address::tests::register_some ... ok
Nov 16 23:47:04.752 INFO [stdout] test address::tests::invalid ... ok
Nov 16 23:47:04.752 INFO [stdout] test instruction::tests::display::add::lit_reg ... ok
Nov 16 23:47:04.752 INFO [stdout] test instruction::tests::display::add::reg_reg ... ok
Nov 16 23:47:04.752 INFO [stdout] test instruction::tests::display::and::lit_lit ... ok
Nov 16 23:47:04.752 INFO [stdout] test instruction::tests::display::add::reg_lit ... ok
Nov 16 23:47:04.752 INFO [stdout] test address::tests::valid ... ok
Nov 16 23:47:04.752 INFO [stdout] test argument::tests::new_lit ... ok
Nov 16 23:47:04.752 INFO [stdout] test instruction::tests::display::and::lit_reg ... ok
Nov 16 23:47:04.752 INFO [stdout] test instruction::tests::display::call::lit ... ok
Nov 16 23:47:04.752 INFO [stdout] test instruction::tests::display::and::reg_lit ... ok
Nov 16 23:47:04.752 INFO [stdout] test instruction::tests::display::and::reg_reg ... ok
Nov 16 23:47:04.752 INFO [stdout] test instruction::tests::display::call::reg ... ok
Nov 16 23:47:04.752 INFO [stdout] test instruction::tests::display::eq::lit_lit ... ok
Nov 16 23:47:04.752 INFO [stdout] test instruction::tests::display::eq::lit_reg ... ok
Nov 16 23:47:04.759 INFO [stdout] test instruction::tests::display::eq::reg_lit ... ok
Nov 16 23:47:04.759 INFO [stdout] test instruction::tests::display::gt::reg_lit ... ok
Nov 16 23:47:04.759 INFO [stdout] test instruction::tests::display::halt::test ... ok
Nov 16 23:47:04.759 INFO [stdout] test instruction::tests::display::gt::lit_lit ... ok
Nov 16 23:47:04.759 INFO [stdout] test instruction::tests::display::in_val::reg ... ok
Nov 16 23:47:04.767 INFO [stdout] test instruction::tests::display::eq::reg_reg ... ok
Nov 16 23:47:04.767 INFO [stdout] test instruction::tests::display::gt::reg_reg ... ok
Nov 16 23:47:04.767 INFO [stdout] test instruction::tests::display::gt::lit_reg ... ok
Nov 16 23:47:04.767 INFO [stdout] test instruction::tests::display::jf::reg_reg ... ok
Nov 16 23:47:04.771 INFO [stdout] test instruction::tests::display::jt::lit_lit ... ok
Nov 16 23:47:04.771 INFO [stdout] test instruction::tests::display::jmp::lit ... ok
Nov 16 23:47:04.771 INFO [stdout] test instruction::tests::display::in_val::lit ... ok
Nov 16 23:47:04.771 INFO [stdout] test instruction::tests::display::jf::lit_lit ... ok
Nov 16 23:47:04.771 INFO [stdout] test instruction::tests::display::jt::reg_reg ... ok
Nov 16 23:47:04.771 INFO [stdout] test instruction::tests::display::modulo::lit_lit ... ok
Nov 16 23:47:04.771 INFO [stdout] test instruction::tests::display::jt::reg_lit ... ok
Nov 16 23:47:04.771 INFO [stdout] test instruction::tests::display::modulo::lit_reg ... ok
Nov 16 23:47:04.771 INFO [stdout] test instruction::tests::display::jf::lit_reg ... ok
Nov 16 23:47:04.771 INFO [stdout] test instruction::tests::display::jf::reg_lit ... ok
Nov 16 23:47:04.773 INFO [stdout] test instruction::tests::display::jt::lit_reg ... ok
Nov 16 23:47:04.773 INFO [stdout] test instruction::tests::display::modulo::reg_reg ... ok
Nov 16 23:47:04.773 INFO [stdout] test instruction::tests::display::modulo::reg_lit ... ok
Nov 16 23:47:04.773 INFO [stdout] test instruction::tests::display::mult::lit_lit ... ok
Nov 16 23:47:04.774 INFO [stdout] test instruction::tests::display::mult::lit_reg ... ok
Nov 16 23:47:04.774 INFO [stdout] test instruction::tests::display::mult::reg_reg ... ok
Nov 16 23:47:04.774 INFO [stdout] test instruction::tests::display::noop::test ... ok
Nov 16 23:47:04.774 INFO [stdout] test instruction::tests::display::not::lit ... ok
Nov 16 23:47:04.774 INFO [stdout] test instruction::tests::display::jmp::reg ... ok
Nov 16 23:47:04.775 INFO [stdout] test instruction::tests::display::mult::reg_lit ... ok
Nov 16 23:47:04.775 INFO [stdout] test instruction::tests::display::out::reg ... ok
Nov 16 23:47:04.775 INFO [stdout] test instruction::tests::display::pop::reg ... ok
Nov 16 23:47:04.775 INFO [stdout] test instruction::tests::display::push::lit ... ok
Nov 16 23:47:04.776 INFO [stdout] test instruction::tests::display::or::lit_reg ... ok
Nov 16 23:47:04.776 INFO [stdout] test instruction::tests::display::or::reg_lit ... ok
Nov 16 23:47:04.776 INFO [stdout] test instruction::tests::display::or::reg_reg ... ok
Nov 16 23:47:04.776 INFO [stdout] test instruction::tests::display::out::lit ... ok
Nov 16 23:47:04.776 INFO [stdout] test instruction::tests::display::or::lit_lit ... ok
Nov 16 23:47:04.776 INFO [stdout] test instruction::tests::display::rmem::reg ... ok
Nov 16 23:47:04.776 INFO [stdout] test instruction::tests::display::rmem::lit ... ok
Nov 16 23:47:04.776 INFO [stdout] test instruction::tests::display::set::lit ... ok
Nov 16 23:47:04.776 INFO [stdout] test instruction::tests::display::ret::test ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::display::wmem::lit_lit ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::display::wmem::lit_reg ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::display::wmem::reg_lit ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::display::push::reg ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::display::wmem::reg_reg ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::from_u16_sequence::add::lit_lit ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::from_u16_sequence::add::reg_reg ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::from_u16_sequence::add::reg_lit ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::from_u16_sequence::add::lit_reg ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::from_u16_sequence::and::lit_reg ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::from_u16_sequence::and::lit_lit ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::from_u16_sequence::and::reg_reg ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::from_u16_sequence::and::reg_lit ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::display::set::reg ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::from_u16_sequence::eq::lit_lit ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::from_u16_sequence::eq::lit_reg ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::from_u16_sequence::eq::reg_lit ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::from_u16_sequence::gt::lit_reg ... ok
Nov 16 23:47:04.778 INFO [stdout] test instruction::tests::from_u16_sequence::eq::reg_reg ... ok
Nov 16 23:47:04.780 INFO [stdout] test instruction::tests::from_u16_sequence::gt::lit_lit ... ok
Nov 16 23:47:04.780 INFO [stdout] test instruction::tests::from_u16_sequence::gt::reg_reg ... ok
Nov 16 23:47:04.780 INFO [stdout] test instruction::tests::from_u16_sequence::halt::halt ... ok
Nov 16 23:47:04.780 INFO [stdout] test instruction::tests::from_u16_sequence::in_val::lit ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::call::lit ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::jf::lit_lit ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::in_val::reg ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::jf::lit_reg ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::jf::reg_lit ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::jmp::reg ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::jt::lit_lit ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::jt::lit_reg ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::jt::reg_lit ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::jf::reg_reg ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::jt::reg_reg ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::modulo::lit_lit ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::modulo::lit_reg ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::modulo::reg_lit ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::mult::lit_lit ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::mult::lit_reg ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::mult::reg_lit ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::mult::reg_reg ... ok
Nov 16 23:47:04.781 INFO [stdout] test instruction::tests::from_u16_sequence::noop::noop ... ok
Nov 16 23:47:04.783 INFO [stdout] test instruction::tests::from_u16_sequence::modulo::reg_reg ... ok
Nov 16 23:47:04.784 INFO [stdout] test instruction::tests::from_u16_sequence::or::lit_lit ... ok
Nov 16 23:47:04.785 INFO [stdout] test instruction::tests::from_u16_sequence::not::reg ... ok
Nov 16 23:47:04.785 INFO [stdout] test instruction::tests::from_u16_sequence::not::lit ... ok
Nov 16 23:47:04.785 INFO [stdout] test instruction::tests::from_u16_sequence::or::reg_reg ... ok
Nov 16 23:47:04.785 INFO [stdout] test instruction::tests::from_u16_sequence::or::reg_lit ... ok
Nov 16 23:47:04.785 INFO [stdout] test instruction::tests::from_u16_sequence::or::lit_reg ... ok
Nov 16 23:47:04.785 INFO [stdout] test instruction::tests::from_u16_sequence::out::lit ... ok
Nov 16 23:47:04.785 INFO [stdout] test instruction::tests::from_u16_sequence::out::reg ... ok
Nov 16 23:47:04.785 INFO [stdout] test instruction::tests::from_u16_sequence::pop::reg ... ok
Nov 16 23:47:04.785 INFO [stdout] test instruction::tests::from_u16_sequence::push::reg ... ok
Nov 16 23:47:04.785 INFO [stdout] test instruction::tests::from_u16_sequence::push::lit ... ok
Nov 16 23:47:04.785 INFO [stdout] test instruction::tests::from_u16_sequence::rmem::lit ... ok
Nov 16 23:47:04.785 INFO [stdout] test instruction::tests::from_u16_sequence::rmem::reg ... ok
Nov 16 23:47:04.785 INFO [stdout] test instruction::tests::from_u16_sequence::set::reg ... ok
Nov 16 23:47:04.786 INFO [stdout] test instruction::tests::from_u16_sequence::ret::ret ... ok
Nov 16 23:47:04.787 INFO [stdout] test instruction::tests::from_u16_sequence::wmem::lit_reg ... ok
Nov 16 23:47:04.787 INFO [stdout] test instruction::tests::from_u16_sequence::wmem::reg_lit ... ok
Nov 16 23:47:04.787 INFO [stdout] test instruction::tests::from_u16_sequence::wmem::lit_lit ... ok
Nov 16 23:47:04.788 INFO [stdout] test instruction::tests::from_u16_sequence::set::lit ... ok
Nov 16 23:47:04.788 INFO [stdout] test instruction::tests::from_u16_sequence::gt::reg_lit ... ok
Nov 16 23:47:04.788 INFO [stdout] test instruction::tests::from_u16_sequence::call::reg ... ok
Nov 16 23:47:04.788 INFO [stdout] test instruction::tests::to_u16_sequence::add::lit_lit ... ok
Nov 16 23:47:04.788 INFO [stdout] test instruction::tests::from_u16_sequence::jmp::lit ... ok
Nov 16 23:47:04.788 INFO [stdout] test instruction::tests::from_u16_sequence::wmem::reg_reg ... ok
Nov 16 23:47:04.788 INFO [stdout] test instruction::tests::display::not::reg ... ok
Nov 16 23:47:04.792 INFO [stdout] test instruction::tests::to_u16_sequence::add::lit_reg ... ok
Nov 16 23:47:04.792 INFO [stdout] test instruction::tests::to_u16_sequence::and::reg_lit ... ok
Nov 16 23:47:04.792 INFO [stdout] test instruction::tests::to_u16_sequence::add::reg_reg ... ok
Nov 16 23:47:04.792 INFO [stdout] test instruction::tests::to_u16_sequence::and::lit_lit ... ok
Nov 16 23:47:04.792 INFO [stdout] test instruction::tests::to_u16_sequence::and::lit_reg ... ok
Nov 16 23:47:04.792 INFO [stdout] test instruction::tests::to_u16_sequence::add::reg_lit ... ok
Nov 16 23:47:04.792 INFO [stdout] test instruction::tests::to_u16_sequence::and::reg_reg ... ok
Nov 16 23:47:04.792 INFO [stdout] test instruction::tests::to_u16_sequence::call::lit ... ok
Nov 16 23:47:04.792 INFO [stdout] test instruction::tests::to_u16_sequence::call::reg ... ok
Nov 16 23:47:04.792 INFO [stdout] test instruction::tests::to_u16_sequence::eq::lit_lit ... ok
Nov 16 23:47:04.792 INFO [stdout] test instruction::tests::to_u16_sequence::eq::reg_reg ... ok
Nov 16 23:47:04.795 INFO [stdout] test instruction::tests::to_u16_sequence::gt::lit_lit ... ok
Nov 16 23:47:04.795 INFO [stdout] test instruction::tests::to_u16_sequence::gt::reg_reg ... ok
Nov 16 23:47:04.795 INFO [stdout] test instruction::tests::to_u16_sequence::eq::reg_lit ... ok
Nov 16 23:47:04.795 INFO [stdout] test instruction::tests::to_u16_sequence::eq::lit_reg ... ok
Nov 16 23:47:04.795 INFO [stdout] test instruction::tests::to_u16_sequence::gt::reg_lit ... ok
Nov 16 23:47:04.795 INFO [stdout] test instruction::tests::to_u16_sequence::gt::lit_reg ... ok
Nov 16 23:47:04.795 INFO [stdout] test instruction::tests::to_u16_sequence::halt::halt ... ok
Nov 16 23:47:04.796 INFO [stdout] test instruction::tests::to_u16_sequence::in_val::lit ... ok
Nov 16 23:47:04.796 INFO [stdout] test instruction::tests::to_u16_sequence::jf::lit_lit ... ok
Nov 16 23:47:04.796 INFO [stdout] test instruction::tests::to_u16_sequence::jmp::reg ... ok
Nov 16 23:47:04.796 INFO [stdout] test instruction::tests::to_u16_sequence::jt::lit_lit ... ok
Nov 16 23:47:04.796 INFO [stdout] test instruction::tests::to_u16_sequence::jf::reg_lit ... ok
Nov 16 23:47:04.796 INFO [stdout] test instruction::tests::to_u16_sequence::in_val::reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::jt::lit_reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::jf::reg_reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::modulo::reg_lit ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::jmp::lit ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::jt::reg_reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::jf::lit_reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::modulo::reg_reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::modulo::lit_reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::jt::reg_lit ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::modulo::lit_lit ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::mult::lit_lit ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::noop::noop ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::mult::reg_reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::mult::reg_lit ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::mult::lit_reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::not::reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::or::lit_lit ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::or::reg_lit ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::or::reg_reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::out::lit ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::not::lit ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::or::lit_reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::ret::ret ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::rmem::reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::set::lit ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::rmem::lit ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::push::reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::push::lit ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::pop::reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::set::reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::wmem::lit_lit ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::out::reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::wmem::lit_reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::wmem::reg_reg ... ok
Nov 16 23:47:04.799 INFO [stdout] test instruction::tests::to_u16_sequence::wmem::reg_lit ... ok
Nov 16 23:47:04.799 INFO [stdout] test register::tests::new ... ok
Nov 16 23:47:04.799 INFO [stdout] test u15::tests::basics::bitand_assign ... ok
Nov 16 23:47:04.799 INFO [stdout] test u15::tests::basics::add ... ok
Nov 16 23:47:04.799 INFO [stdout] test u15::tests::basics::bitor ... ok
Nov 16 23:47:04.799 INFO [stdout] test u15::tests::basics::bitand ... ok
Nov 16 23:47:04.799 INFO [stdout] test u15::tests::basics::bitor_assign ... ok
Nov 16 23:47:04.799 INFO [stdout] test u15::tests::basics::bitxor ... ok
Nov 16 23:47:04.799 INFO [stdout] test u15::tests::basics::bitxor_assign ... ok
Nov 16 23:47:04.799 INFO [stdout] test u15::tests::basics::div ... ok
Nov 16 23:47:04.799 INFO [stdout] test u15::tests::basics::div_assign ... ok
Nov 16 23:47:04.799 INFO [stdout] test u15::tests::basics::div_non_even_assign ... ok
Nov 16 23:47:04.799 INFO [stdout] test u15::tests::basics::div_non_even ... ok
Nov 16 23:47:04.799 INFO [stdout] test u15::tests::basics::mul_assign ... ok
Nov 16 23:47:04.799 INFO [stdout] test u15::tests::basics::mul ... ok
Nov 16 23:47:04.799 INFO [stdout] test u15::tests::basics::not ... ok
Nov 16 23:47:04.799 INFO [stdout] test u15::tests::basics::rem ... ok
Nov 16 23:47:04.800 INFO [stdout] test u15::tests::basics::rem_assign ... ok
Nov 16 23:47:04.800 INFO [stdout] test u15::tests::basics::rem_assign_nonzero_remainder ... ok
Nov 16 23:47:04.800 INFO [stdout] test u15::tests::basics::rem_non_even ... ok
Nov 16 23:47:04.800 INFO [stdout] test u15::tests::basics::shl_i16 ... ok
Nov 16 23:47:04.800 INFO [stdout] test u15::tests::basics::shl ... ok
Nov 16 23:47:04.800 INFO [stdout] test u15::tests::basics::shl_i8 ... ok
Nov 16 23:47:04.800 INFO [stdout] test u15::tests::basics::shl_i32 ... ok
Nov 16 23:47:04.800 INFO [stdout] test u15::tests::basics::add_assign ... ok
Nov 16 23:47:04.800 INFO [stdout] test u15::tests::basics::shl_i64 ... ok
Nov 16 23:47:04.800 INFO [stdout] test u15::tests::basics::shl_u16 ... ok
Nov 16 23:47:04.800 INFO [stdout] test u15::tests::basics::shr ... ok
Nov 16 23:47:04.800 INFO [stdout] test u15::tests::basics::shl_u8 ... ok
Nov 16 23:47:04.800 INFO [stdout] test u15::tests::basics::shl_u64 ... ok
Nov 16 23:47:04.800 INFO [stdout] test u15::tests::basics::shl_u32 ... ok
Nov 16 23:47:04.803 INFO [stdout] test u15::tests::basics::shr_i8 ... ok
Nov 16 23:47:04.803 INFO [stdout] test u15::tests::basics::shr_i64 ... ok
Nov 16 23:47:04.803 INFO [stdout] test u15::tests::basics::shr_i16 ... ok
Nov 16 23:47:04.803 INFO [stdout] test u15::tests::basics::shr_i32 ... ok
Nov 16 23:47:04.807 INFO [stdout] test u15::tests::basics::shr_u64 ... ok
Nov 16 23:47:04.807 INFO [stdout] test u15::tests::basics::sub ... ok
Nov 16 23:47:04.807 INFO [stdout] test u15::tests::basics::shr_u8 ... ok
Nov 16 23:47:04.811 INFO [stdout] test u15::tests::basics::shr_u32 ... ok
Nov 16 23:47:04.811 INFO [stdout] test u15::tests::basics::sub_assign ... ok
Nov 16 23:47:04.811 INFO [stdout] test u15::tests::basics::shr_u16 ... ok
Nov 16 23:47:04.814 INFO [stdout] test u15::tests::modular_arithmetic::add_overflow ... ok
Nov 16 23:47:04.814 INFO [stdout] test u15::tests::modular_arithmetic::min_val ... ok
Nov 16 23:47:04.814 INFO [stdout] test u15::tests::modular_arithmetic::mul_overflow ... ok
Nov 16 23:47:04.814 INFO [stdout] test u15::tests::modular_arithmetic::max_val ... ok
Nov 16 23:47:04.815 INFO [stdout] test u15::tests::modular_arithmetic::not_is_15b ... ok
Nov 16 23:47:04.816 INFO [stdout] test vm::tests::instructions::add::lit_lit_nowrap ... ok
Nov 16 23:47:04.817 INFO [stdout] test vm::tests::instructions::add::lit_lit_wrap ... ok
Nov 16 23:47:04.817 INFO [stdout] test vm::tests::instructions::add::lit_reg_nowrap ... ok
Nov 16 23:47:04.817 INFO [stdout] test vm::tests::instructions::add::lit_reg_wrap ... ok
Nov 16 23:47:04.818 INFO [stdout] test argument::tests::new_panic_on_out_of_range ... ok
Nov 16 23:47:04.818 INFO [stdout] test register::tests::new_panics_with_invalid_value ... ok
Nov 16 23:47:04.825 INFO [stdout] test register::tests::new_panics_with_lit_value ... ok
Nov 16 23:47:04.825 INFO [stdout] test vm::tests::instructions::add::reg_reg_nowrap ... ok
Nov 16 23:47:04.825 INFO [stdout] test vm::tests::instructions::add::reg_lit_wrap ... ok
Nov 16 23:47:04.825 INFO [stdout] test vm::tests::instructions::add::reg_lit_nowrap ... ok
Nov 16 23:47:04.826 INFO [stdout] test vm::tests::instructions::and::reg_lit ... ok
Nov 16 23:47:04.826 INFO [stdout] test u15::tests::basics::sub_panic ... ok
Nov 16 23:47:04.826 INFO [stdout] test u15::tests::basics::sub_panic_assign ... ok
Nov 16 23:47:04.826 INFO [stdout] test vm::tests::instructions::and::lit_reg ... ok
Nov 16 23:47:04.827 INFO [stdout] test vm::tests::instructions::call::lit ... ok
Nov 16 23:47:04.827 INFO [stdout] test vm::tests::instructions::add::reg_reg_wrap ... ok
Nov 16 23:47:04.827 INFO [stdout] test vm::tests::instructions::and::lit_lit ... ok
Nov 16 23:47:04.828 INFO [stdout] test vm::tests::instructions::and::reg_reg ... ok
Nov 16 23:47:04.828 INFO [stdout] test vm::tests::instructions::eq::reg_lit ... ok
Nov 16 23:47:04.828 INFO [stdout] test vm::tests::instructions::eq::lit_lit ... ok
Nov 16 23:47:04.828 INFO [stdout] test vm::tests::instructions::gt::lit_lit_false ... ok
Nov 16 23:47:04.829 INFO [stdout] test vm::tests::instructions::gt::lit_lit_true ... ok
Nov 16 23:47:04.829 INFO [stdout] test vm::tests::instructions::gt::lit_reg ... ok
Nov 16 23:47:04.829 INFO [stdout] test vm::tests::instructions::eq::reg_reg ... ok
Nov 16 23:47:04.829 INFO [stdout] test vm::tests::instructions::call::reg ... ok
Nov 16 23:47:04.830 INFO [stdout] test vm::tests::instructions::eq::lit_reg ... ok
Nov 16 23:47:04.830 INFO [stdout] test vm::tests::instructions::gt::reg_reg ... ok
Nov 16 23:47:04.830 INFO [stdout] test vm::tests::instructions::jf::reg_lit ... ok
Nov 16 23:47:04.830 INFO [stdout] test vm::tests::instructions::jf::reg_reg ... ok
Nov 16 23:47:04.830 INFO [stdout] test u15::tests::modular_arithmetic::div_by_zero ... ok
Nov 16 23:47:04.830 INFO [stdout] test u15::tests::modular_arithmetic::sub_underflow ... ok
Nov 16 23:47:04.830 INFO [stdout] test vm::tests::instructions::jf::lit_reg ... ok
Nov 16 23:47:04.839 INFO [stdout] test vm::tests::instructions::jf::lit_lit ... ok
Nov 16 23:47:04.839 INFO [stdout] test vm::tests::instructions::jt::reg_lit ... ok
Nov 16 23:47:04.839 INFO [stdout] test vm::tests::instructions::jt::lit_reg ... ok
Nov 16 23:47:04.839 INFO [stdout] test vm::tests::instructions::jt::lit_lit ... ok
Nov 16 23:47:04.840 INFO [stdout] test vm::tests::instructions::jt::reg_reg ... ok
Nov 16 23:47:04.840 INFO [stdout] test vm::tests::instructions::modulo::reg_lit ... ok
Nov 16 23:47:04.840 INFO [stdout] test vm::tests::instructions::gt::reg_lit ... ok
Nov 16 23:47:04.840 INFO [stdout] test vm::tests::instructions::modulo::lit_lit ... ok
Nov 16 23:47:04.841 INFO [stdout] test vm::tests::instructions::mult::lit_reg_nowrap ... ok
Nov 16 23:47:04.841 INFO [stdout] test vm::tests::instructions::jump::reg ... ok
Nov 16 23:47:04.841 INFO [stdout] test vm::tests::instructions::jump::lit ... ok
Nov 16 23:47:04.841 INFO [stdout] test vm::tests::instructions::mult::reg_lit_wrap ... ok
Nov 16 23:47:04.841 INFO [stdout] test vm::tests::instructions::modulo::reg_reg ... ok
Nov 16 23:47:04.842 INFO [stdout] test vm::tests::instructions::mult::reg_lit_nowrap ... ok
Nov 16 23:47:04.843 INFO [stdout] test vm::tests::instructions::not::lit ... ok
Nov 16 23:47:04.843 INFO [stdout] test vm::tests::instructions::mult::lit_reg_wrap ... ok
Nov 16 23:47:04.844 INFO [stdout] test vm::tests::instructions::modulo::lit_reg ... ok
Nov 16 23:47:04.844 INFO [stdout] test vm::tests::instructions::mult::reg_reg_nowrap ... ok
Nov 16 23:47:04.844 INFO [stdout] test vm::tests::instructions::mult::lit_lit_wrap ... ok
Nov 16 23:47:04.844 INFO [stdout] test vm::tests::instructions::not::reg ... ok
Nov 16 23:47:04.844 INFO [stdout] test vm::tests::instructions::mult::lit_lit_nowrap ... ok
Nov 16 23:47:04.844 INFO [stdout] test vm::tests::instructions::mult::reg_reg_wrap ... ok
Nov 16 23:47:04.845 INFO [stdout] test vm::tests::instructions::or::lit_lit ... ok
Nov 16 23:47:04.845 INFO [stdout] test vm::tests::instructions::or::reg_reg ... ok
Nov 16 23:47:04.845 INFO [stdout] test vm::tests::instructions::or::lit_reg ... ok
Nov 16 23:47:04.845 INFO [stdout] test vm::tests::instructions::or::reg_lit ... ok
Nov 16 23:47:04.845 INFO [stdout] test vm::tests::instructions::pop::happy ... ok
Nov 16 23:47:04.846 INFO [stdout] test vm::tests::instructions::push::reg ... ok
Nov 16 23:47:04.846 INFO [stdout] test vm::tests::instructions::pop::nonempty_remaining_stack ... ok
Nov 16 23:47:04.846 INFO [stdout] test vm::tests::instructions::ret::reg ... ok
Nov 16 23:47:04.846 INFO [stdout] test vm::tests::instructions::rmem::lit ... ok
Nov 16 23:47:04.846 INFO [stdout] test vm::tests::instructions::set::reg ... ok
Nov 16 23:47:04.847 INFO [stdout] test vm::tests::instructions::wmem::reg_reg ... ok
Nov 16 23:47:04.847 INFO [stdout] test vm::tests::instructions::ret::lit ... ok
Nov 16 23:47:04.849 INFO [stdout] test vm::tests::instructions::push::lit ... ok
Nov 16 23:47:04.850 INFO [stdout] test vm::tests::instructions::pop::stack_underflow_error ... ok
Nov 16 23:47:04.850 INFO [stdout] test vm::tests::instructions::set::lit ... ok
Nov 16 23:47:04.850 INFO [stdout] test vm::tests::instructions::wmem::lit_lit ... ok
Nov 16 23:47:04.850 INFO [stdout] test vm::tests::instructions::wmem::lit_reg ... ok
Nov 16 23:47:04.850 INFO [stdout] test vm::tests::instructions::rmem::reg ... ok
Nov 16 23:47:04.850 INFO [stdout] test vm::tests::load_program::valid_program_load ... ok
Nov 16 23:47:04.850 INFO [stdout] test vm::tests::step::current_instruction_happy ... ok
Nov 16 23:47:04.850 INFO [stdout] test vm::tests::step::current_instruction_bad_opcode ... ok
Nov 16 23:47:04.850 INFO [stdout] test vm::tests::step::read_memory_invalid ... ok
Nov 16 23:47:04.851 INFO [stdout] test vm::tests::step::read_memory_happy ... ok
Nov 16 23:47:04.852 INFO [stdout] test vm::tests::step::advance ... ok
Nov 16 23:47:04.852 INFO [stdout] test vm::tests::step::step ... ok
Nov 16 23:47:04.852 INFO [stdout] test vm::tests::step::current_instruction_malformed ... ok
Nov 16 23:47:04.852 INFO [stdout] 
Nov 16 23:47:04.852 INFO [stdout] test result: ok. 312 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 23:47:04.852 INFO [stdout] 
Nov 16 23:47:04.853 INFO [stderr]      Running /target/debug/deps/syn_asm-966a916e7a586acf
Nov 16 23:47:04.856 INFO [stdout] 
Nov 16 23:47:04.856 INFO [stdout] running 0 tests
Nov 16 23:47:04.856 INFO [stdout] 
Nov 16 23:47:04.856 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 23:47:04.856 INFO [stdout] 
Nov 16 23:47:04.856 INFO [stderr]      Running /target/debug/deps/syn_dis-c8c44561d8523934
Nov 16 23:47:04.857 INFO [stdout] 
Nov 16 23:47:04.857 INFO [stdout] running 0 tests
Nov 16 23:47:04.857 INFO [stdout] 
Nov 16 23:47:04.857 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 23:47:04.857 INFO [stdout] 
Nov 16 23:47:04.858 INFO [stderr]      Running /target/debug/deps/syn_vm-f01bd4abe966a4b3
Nov 16 23:47:04.860 INFO [stdout] 
Nov 16 23:47:04.860 INFO [stdout] running 0 tests
Nov 16 23:47:04.860 INFO [stdout] 
Nov 16 23:47:04.860 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 23:47:04.860 INFO [stdout] 
Nov 16 23:47:04.860 INFO [stderr]    Doc-tests synacor
Nov 16 23:47:05.176 INFO [stdout] 
Nov 16 23:47:05.176 INFO [stdout] running 0 tests
Nov 16 23:47:05.176 INFO [stdout] 
Nov 16 23:47:05.176 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 23:47:05.176 INFO [stdout] 
Nov 16 23:47:05.186 INFO [stderr] su: No module specific data is present
Nov 16 23:47:05.496 INFO running `"docker" "inspect" "9397b6c9a3f4c3d1bdb09b981c53747488339afc51068aa3d41757d8f0c62682"`
Nov 16 23:47:05.590 INFO running `"docker" "rm" "-f" "9397b6c9a3f4c3d1bdb09b981c53747488339afc51068aa3d41757d8f0c62682"`
Nov 16 23:47:05.675 INFO [stdout] 9397b6c9a3f4c3d1bdb09b981c53747488339afc51068aa3d41757d8f0c62682