Dec 13 11:37:38.546 INFO testing marosluuce/rchip8 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 13 11:37:38.546 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 11:37:39.024 INFO [stdout] 285306d0cd5649596a58539adb444fb2e8dd8bd2d64b17e354d3f0237363f435
Dec 13 11:37:39.026 INFO running `"docker" "start" "-a" "285306d0cd5649596a58539adb444fb2e8dd8bd2d64b17e354d3f0237363f435"`
Dec 13 11:37:40.551 INFO [stderr] usermod: no changes
Dec 13 11:37:40.624 INFO [stderr]    Compiling rchip8 v0.1.0 (/source)
Dec 13 11:37:42.040 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.48s
Dec 13 11:37:42.047 INFO [stderr] su: No module specific data is present
Dec 13 11:37:42.453 INFO running `"docker" "inspect" "285306d0cd5649596a58539adb444fb2e8dd8bd2d64b17e354d3f0237363f435"`
Dec 13 11:37:42.638 INFO running `"docker" "rm" "-f" "285306d0cd5649596a58539adb444fb2e8dd8bd2d64b17e354d3f0237363f435"`
Dec 13 11:37:42.782 INFO [stdout] 285306d0cd5649596a58539adb444fb2e8dd8bd2d64b17e354d3f0237363f435
Dec 13 11:37:42.784 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 11:37:43.011 INFO [stdout] 9e9bb026400220b830baa006035cb0b79766964f7795899fc3a4dd607f944108
Dec 13 11:37:43.012 INFO running `"docker" "start" "-a" "9e9bb026400220b830baa006035cb0b79766964f7795899fc3a4dd607f944108"`
Dec 13 11:37:43.586 INFO [stderr] usermod: no changes
Dec 13 11:37:43.628 INFO [stderr]    Compiling rchip8 v0.1.0 (/source)
Dec 13 11:37:45.440 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.83s
Dec 13 11:37:45.445 INFO [stderr] su: No module specific data is present
Dec 13 11:37:45.840 INFO running `"docker" "inspect" "9e9bb026400220b830baa006035cb0b79766964f7795899fc3a4dd607f944108"`
Dec 13 11:37:45.925 INFO running `"docker" "rm" "-f" "9e9bb026400220b830baa006035cb0b79766964f7795899fc3a4dd607f944108"`
Dec 13 11:37:46.012 INFO [stdout] 9e9bb026400220b830baa006035cb0b79766964f7795899fc3a4dd607f944108
Dec 13 11:37:46.013 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 11:37:46.164 INFO [stdout] 12e8446462664ff9e8ce3a1f124f2e106b3333418ad6294796d0e5102c152d0a
Dec 13 11:37:46.168 INFO running `"docker" "start" "-a" "12e8446462664ff9e8ce3a1f124f2e106b3333418ad6294796d0e5102c152d0a"`
Dec 13 11:37:46.529 INFO [stderr] usermod: no changes
Dec 13 11:37:46.562 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.01s
Dec 13 11:37:46.563 INFO [stderr]      Running /target/debug/deps/rchip8-98bddb239a84c664
Dec 13 11:37:46.567 INFO [stdout] 
Dec 13 11:37:46.567 INFO [stdout] running 0 tests
Dec 13 11:37:46.567 INFO [stdout] 
Dec 13 11:37:46.567 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 11:37:46.567 INFO [stdout] 
Dec 13 11:37:46.567 INFO [stderr]      Running /target/debug/deps/rchip8-28c5a7633e071b0e
Dec 13 11:37:46.572 INFO [stdout] 
Dec 13 11:37:46.572 INFO [stdout] running 0 tests
Dec 13 11:37:46.572 INFO [stdout] 
Dec 13 11:37:46.572 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 11:37:46.572 INFO [stdout] 
Dec 13 11:37:46.573 INFO [stderr]      Running /target/debug/deps/cpu_test-512b5fef920b3dd1
Dec 13 11:37:46.580 INFO [stdout] 
Dec 13 11:37:46.580 INFO [stdout] running 3 tests
Dec 13 11:37:46.580 INFO [stdout] test tests::executes_set_i ... ok
Dec 13 11:37:46.580 INFO [stdout] test tests::executes_jump_absolute ... ok
Dec 13 11:37:46.587 INFO [stderr]      Running /target/debug/deps/instruction_test-4fba03776fdfe5ff
Dec 13 11:37:46.587 INFO [stdout] test tests::skips_when_register_equals_value ... ok
Dec 13 11:37:46.587 INFO [stdout] 
Dec 13 11:37:46.587 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 11:37:46.587 INFO [stdout] 
Dec 13 11:37:46.589 INFO [stdout] 
Dec 13 11:37:46.589 INFO [stdout] running 34 tests
Dec 13 11:37:46.589 INFO [stdout] test tests::decodes_clear_screen ... ok
Dec 13 11:37:46.589 INFO [stdout] test tests::decodes_and ... ok
Dec 13 11:37:46.589 INFO [stdout] test tests::decodes_add_to_instruction_point ... ok
Dec 13 11:37:46.589 INFO [stdout] test tests::decodes_jump_absolute ... ok
Dec 13 11:37:46.589 INFO [stdout] test tests::decodes_add_absolute ... ok
Dec 13 11:37:46.589 INFO [stdout] test tests::decodes_jump_stack ... ok
Dec 13 11:37:46.589 INFO [stdout] test tests::decodes_jump_offset ... ok
Dec 13 11:37:46.589 INFO [stdout] test tests::decodes_load_sprite_location ... ok
Dec 13 11:37:46.590 INFO [stdout] test tests::decodes_move ... ok
Dec 13 11:37:46.590 INFO [stdout] test tests::decodes_load_absolute ... ok
Dec 13 11:37:46.590 INFO [stdout] test tests::decodes_move_delay ... ok
Dec 13 11:37:46.590 INFO [stdout] test tests::decodes_or ... ok
Dec 13 11:37:46.590 INFO [stdout] test tests::decodes_add ... ok
Dec 13 11:37:46.590 INFO [stdout] test tests::decodes_rand ... ok
Dec 13 11:37:46.590 INFO [stdout] test tests::decodes_set_sound_timer ... ok
Dec 13 11:37:46.590 INFO [stdout] test tests::decodes_shift_left ... ok
Dec 13 11:37:46.590 INFO [stdout] test tests::decodes_shift_right ... ok
Dec 13 11:37:46.590 INFO [stdout] test tests::decodes_skip_equal_absolute ... ok
Dec 13 11:37:46.591 INFO [stdout] test tests::decodes_return_from_subroutine ... ok
Dec 13 11:37:46.591 INFO [stdout] test tests::decodes_skip_equal ... ok
Dec 13 11:37:46.591 INFO [stdout] test tests::decodes_load_into_registers ... ok
Dec 13 11:37:46.591 INFO [stdout] test tests::decodes_set_delay_timer ... ok
Dec 13 11:37:46.591 INFO [stdout] test tests::decodes_set_i ... ok
Dec 13 11:37:46.592 INFO [stdout] test tests::decodes_sprite ... ok
Dec 13 11:37:46.592 INFO [stdout] test tests::decodes_skip_not_equal_absolute ... ok
Dec 13 11:37:46.592 INFO [stdout] test tests::decodes_skip_no_key ... ok
Dec 13 11:37:46.592 INFO [stdout] test tests::decodes_skip_key ... ok
Dec 13 11:37:46.592 INFO [stdout] test tests::decodes_store_bcd ... ok
Dec 13 11:37:46.592 INFO [stdout] test tests::decodes_store_registers ... ok
Dec 13 11:37:46.595 INFO [stdout] test tests::decodes_subtract ... ok
Dec 13 11:37:46.595 INFO [stdout] test tests::decodes_skip_not_equal ... ok
Dec 13 11:37:46.595 INFO [stdout] test tests::decodes_subtract_backwards ... ok
Dec 13 11:37:46.595 INFO [stdout] test tests::decodes_wait_delay ... ok
Dec 13 11:37:46.595 INFO [stdout] test tests::decodes_xor ... ok
Dec 13 11:37:46.595 INFO [stdout] 
Dec 13 11:37:46.595 INFO [stdout] test result: ok. 34 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 11:37:46.595 INFO [stdout] 
Dec 13 11:37:46.596 INFO [stderr]      Running /target/debug/deps/ram_test-1fe3cbe67aace2b5
Dec 13 11:37:46.650 INFO [stdout] 
Dec 13 11:37:46.651 INFO [stdout] running 2 tests
Dec 13 11:37:46.651 INFO [stdout] test tests::creates_ram ... ok
Dec 13 11:37:46.651 INFO [stdout] test tests::sets_an_address ... ok
Dec 13 11:37:46.651 INFO [stdout] 
Dec 13 11:37:46.651 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 11:37:46.651 INFO [stdout] 
Dec 13 11:37:46.651 INFO [stderr]    Doc-tests rchip8
Dec 13 11:37:46.932 INFO [stdout] 
Dec 13 11:37:46.932 INFO [stdout] running 0 tests
Dec 13 11:37:46.932 INFO [stdout] 
Dec 13 11:37:46.932 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 11:37:46.932 INFO [stdout] 
Dec 13 11:37:46.939 INFO [stderr] su: No module specific data is present
Dec 13 11:37:47.284 INFO running `"docker" "inspect" "12e8446462664ff9e8ce3a1f124f2e106b3333418ad6294796d0e5102c152d0a"`
Dec 13 11:37:47.368 INFO running `"docker" "rm" "-f" "12e8446462664ff9e8ce3a1f124f2e106b3333418ad6294796d0e5102c152d0a"`
Dec 13 11:37:47.439 INFO [stdout] 12e8446462664ff9e8ce3a1f124f2e106b3333418ad6294796d0e5102c152d0a