Dec 05 10:46:23.929 INFO testing quelledanielle/rusty_chip against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 05 10:46:23.929 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 05 10:46:24.303 INFO [stdout] a0b199e1d58497f3a6a87a1ddd87447606c17dfe3b34e837d2238d0065dbe863
Dec 05 10:46:24.306 INFO running `"docker" "start" "-a" "a0b199e1d58497f3a6a87a1ddd87447606c17dfe3b34e837d2238d0065dbe863"`
Dec 05 10:46:25.535 INFO [stderr] usermod: no changes
Dec 05 10:46:25.600 INFO [stderr]    Compiling libc v0.2.37
Dec 05 10:46:27.038 INFO [stderr]    Compiling rand v0.4.2
Dec 05 10:46:29.224 INFO [stderr]    Compiling rand v0.3.22
Dec 05 10:46:29.457 INFO [stderr]    Compiling rusty_chip v0.1.0 (/source)
Dec 05 10:46:30.943 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.37s
Dec 05 10:46:30.946 INFO [stderr] su: No module specific data is present
Dec 05 10:46:31.334 INFO running `"docker" "inspect" "a0b199e1d58497f3a6a87a1ddd87447606c17dfe3b34e837d2238d0065dbe863"`
Dec 05 10:46:31.417 INFO running `"docker" "rm" "-f" "a0b199e1d58497f3a6a87a1ddd87447606c17dfe3b34e837d2238d0065dbe863"`
Dec 05 10:46:31.520 INFO [stdout] a0b199e1d58497f3a6a87a1ddd87447606c17dfe3b34e837d2238d0065dbe863
Dec 05 10:46:31.520 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 05 10:46:31.776 INFO [stdout] 091ee5a695d2e8e767fb2038edbf1abf71d4cc0661fbd9ef0326c8ec09d0bdea
Dec 05 10:46:31.778 INFO running `"docker" "start" "-a" "091ee5a695d2e8e767fb2038edbf1abf71d4cc0661fbd9ef0326c8ec09d0bdea"`
Dec 05 10:46:32.223 INFO [stderr] usermod: no changes
Dec 05 10:46:32.259 INFO [stderr]    Compiling rusty_chip v0.1.0 (/source)
Dec 05 10:46:34.349 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.11s
Dec 05 10:46:34.352 INFO [stderr] su: No module specific data is present
Dec 05 10:46:34.640 INFO running `"docker" "inspect" "091ee5a695d2e8e767fb2038edbf1abf71d4cc0661fbd9ef0326c8ec09d0bdea"`
Dec 05 10:46:34.735 INFO running `"docker" "rm" "-f" "091ee5a695d2e8e767fb2038edbf1abf71d4cc0661fbd9ef0326c8ec09d0bdea"`
Dec 05 10:46:34.867 INFO [stdout] 091ee5a695d2e8e767fb2038edbf1abf71d4cc0661fbd9ef0326c8ec09d0bdea
Dec 05 10:46:34.869 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 05 10:46:35.061 INFO [stdout] c6a35680a2b5a0c14650813a409bdb8a8e2580b772b791837c034497ffecd7ce
Dec 05 10:46:35.065 INFO running `"docker" "start" "-a" "c6a35680a2b5a0c14650813a409bdb8a8e2580b772b791837c034497ffecd7ce"`
Dec 05 10:46:35.481 INFO [stderr] usermod: no changes
Dec 05 10:46:35.522 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Dec 05 10:46:35.522 INFO [stdout] 
Dec 05 10:46:35.522 INFO [stderr]      Running /target/debug/deps/rusty_chip-e72afd62afe9faab
Dec 05 10:46:35.522 INFO [stdout] running 87 tests
Dec 05 10:46:35.523 INFO [stdout] test cpu::opcode::tests::mask_nnn ... ok
Dec 05 10:46:35.523 INFO [stdout] test cpu::opcode::tests::mask_k ... ok
Dec 05 10:46:35.523 INFO [stdout] test cpu::opcode::tests::mask_first_hex_digit ... ok
Dec 05 10:46:35.523 INFO [stdout] test cpu::tests::beep_while_sound_timer_active ... ok
Dec 05 10:46:35.523 INFO [stdout] test cpu::pointer::tests::set_current ... ok
Dec 05 10:46:35.524 INFO [stdout] test cpu::pointer::tests::move_forward_adds_2_to_current ... ok
Dec 05 10:46:35.524 INFO [stdout] test cpu::pointer::tests::move_backward_subtracts_2_from_current ... ok
Dec 05 10:46:35.524 INFO [stdout] test cpu::pointer::tests::current_defaults_to_range_start ... ok
Dec 05 10:46:35.524 INFO [stdout] test cpu::opcode::tests::mask_x ... ok
Dec 05 10:46:35.524 INFO [stdout] test cpu::tests::fetch_opcode_fetches_two_current_bytes ... ok
Dec 05 10:46:35.524 INFO [stdout] test cpu::opcode::tests::mask_kk ... ok
Dec 05 10:46:35.531 INFO [stdout] test cpu::tests::new_loads_font_to_memory ... ok
Dec 05 10:46:35.531 INFO [stdout] test cpu::tests::new_loads_rom_to_memory ... ok
Dec 05 10:46:35.531 INFO [stdout] test cpu::tests::operation_00e0_clear_display ... ok
Dec 05 10:46:35.531 INFO [stdout] test cpu::tests::operation_1nnn_jump_addr ... ok
Dec 05 10:46:35.532 INFO [stdout] test cpu::opcode::tests::mask_y ... ok
Dec 05 10:46:35.533 INFO [stdout] test cpu::tests::operation_0000_no_op ... ok
Dec 05 10:46:35.533 INFO [stdout] test cpu::tests::operation_3xkk_skip_equal_vx_byte ... ok
Dec 05 10:46:35.533 INFO [stdout] test cpu::tests::operation_00ee_return_from_subroutine ... ok
Dec 05 10:46:35.533 INFO [stdout] test cpu::tests::operation_3xkk_skip_equal_vx_byte_no_skip ... ok
Dec 05 10:46:35.533 INFO [stdout] test cpu::tests::operation_4xkk_skip_not_equal_vx_byte ... ok
Dec 05 10:46:35.533 INFO [stdout] test cpu::tests::operation_4xkk_skip_not_equal_vx_byte_no_skip ... ok
Dec 05 10:46:35.534 INFO [stdout] test cpu::tests::operation_6xkk_load_vx_byte ... ok
Dec 05 10:46:35.534 INFO [stdout] test cpu::tests::operation_7xkk_add_vx_byte ... ok
Dec 05 10:46:35.535 INFO [stdout] test cpu::tests::operation_8xy1_or_vx_vy_00 ... ok
Dec 05 10:46:35.535 INFO [stdout] test cpu::tests::operation_5xy0_skip_equal_vx_vy_no_skip ... ok
Dec 05 10:46:35.535 INFO [stdout] test cpu::tests::operation_2nnn_call_subroutine ... ok
Dec 05 10:46:35.535 INFO [stdout] test cpu::tests::operation_8xy0_load_vx_vy ... ok
Dec 05 10:46:35.535 INFO [stdout] test cpu::tests::operation_7xkk_add_vx_byte_wrap ... ok
Dec 05 10:46:35.535 INFO [stdout] test cpu::tests::operation_8xy1_or_vx_vy_01 ... ok
Dec 05 10:46:35.535 INFO [stdout] test cpu::tests::operation_5xy0_skip_equal_vx_vy ... ok
Dec 05 10:46:35.536 INFO [stdout] test cpu::tests::operation_8xy1_or_vx_vy_11 ... ok
Dec 05 10:46:35.537 INFO [stdout] test cpu::tests::operation_8xy3_xor_vx_vy_10 ... ok
Dec 05 10:46:35.537 INFO [stdout] test cpu::tests::operation_8xy2_and_vx_vy_01 ... ok
Dec 05 10:46:35.537 INFO [stdout] test cpu::tests::operation_8xy2_and_vx_vy_00 ... ok
Dec 05 10:46:35.537 INFO [stdout] test cpu::tests::operation_8xy3_xor_vx_vy_00 ... ok
Dec 05 10:46:35.538 INFO [stdout] test cpu::tests::operation_8xy2_and_vx_vy_10 ... ok
Dec 05 10:46:35.540 INFO [stdout] test cpu::tests::operation_8xy2_and_vx_vy_11 ... ok
Dec 05 10:46:35.540 INFO [stdout] test cpu::tests::operation_8xy3_xor_vx_vy_01 ... ok
Dec 05 10:46:35.540 INFO [stdout] test cpu::tests::operation_8xy4_add_vx_vy ... ok
Dec 05 10:46:35.540 INFO [stdout] test cpu::tests::operation_8xy5_sub_vx_vy ... ok
Dec 05 10:46:35.540 INFO [stdout] test cpu::tests::operation_8xy1_or_vx_vy_10 ... ok
Dec 05 10:46:35.540 INFO [stdout] test cpu::tests::operation_8xy4_add_vx_vy_carry ... ok
Dec 05 10:46:35.540 INFO [stdout] test cpu::tests::operation_8xy3_xor_vx_vy_11 ... ok
Dec 05 10:46:35.541 INFO [stdout] test cpu::tests::operation_9xy0_skip_not_equal_vx_vy ... ok
Dec 05 10:46:35.541 INFO [stdout] test cpu::tests::operation_8xye_shl_vx_vy_sig_bit ... ok
Dec 05 10:46:35.541 INFO [stdout] test cpu::tests::operation_8xy7_subn_vx_vy_carry ... ok
Dec 05 10:46:35.541 INFO [stdout] test cpu::tests::operation_8xye_shl_vx_vy ... ok
Dec 05 10:46:35.541 INFO [stdout] test cpu::tests::operation_bnnn_jump_v0_addr ... ok
Dec 05 10:46:35.542 INFO [stdout] test cpu::tests::operation_8xy7_subn_vx_vy ... ok
Dec 05 10:46:35.542 INFO [stdout] test cpu::tests::operation_annn_load_i_addr ... ok
Dec 05 10:46:35.542 INFO [stdout] test cpu::tests::operation_9xy0_skip_not_equal_vx_vy_no_skip ... ok
Dec 05 10:46:35.542 INFO [stdout] test cpu::tests::operation_8xy6_shr_vx_vy ... ok
Dec 05 10:46:35.542 INFO [stdout] test cpu::tests::operation_8xy6_shr_vx_vy_sig_bit ... ok
Dec 05 10:46:35.542 INFO [stdout] test cpu::tests::operation_8xy5_sub_vx_vy_carry ... ok
Dec 05 10:46:35.543 INFO [stdout] test cpu::tests::operation_cxkk_rand_vx_byte ... ok
Dec 05 10:46:35.545 INFO [stdout] test cpu::tests::operation_fx18_load_st_vx ... ok
Dec 05 10:46:35.545 INFO [stdout] test cpu::tests::operation_dxyn_draw_vx_vy_n_collision ... ok
Dec 05 10:46:35.545 INFO [stdout] test cpu::tests::operation_fx1e_add_i_vx ... ok
Dec 05 10:46:35.546 INFO [stdout] test cpu::tests::operation_dxyn_draw_vx_vy_n_with_vx_wrap ... ok
Dec 05 10:46:35.547 INFO [stdout] test cpu::tests::operation_fx07_load_vx_dt ... ok
Dec 05 10:46:35.547 INFO [stdout] test cpu::tests::operation_fx15_load_dt_vx ... ok
Dec 05 10:46:35.547 INFO [stdout] test cpu::tests::operation_dxyn_draw_vx_vy_n_with_vy_wrap ... ok
Dec 05 10:46:35.547 INFO [stdout] test cpu::timer::tests::set_current_value ... ok
Dec 05 10:46:35.548 INFO [stdout] test cpu::timer::tests::init_active ... ok
Dec 05 10:46:35.551 INFO [stdout] test cpu::tests::operation_dxyn_draw_vx_vy_n ... ok
Dec 05 10:46:35.551 INFO [stdout] test cpu::tests::operation_fx29_load_i_vx_font ... ok
Dec 05 10:46:35.551 INFO [stdout] test cpu::tests::operation_fx33_load_bcd_vx ... ok
Dec 05 10:46:35.551 INFO [stdout] test cpu::timer::tests::init_inactive ... ok
Dec 05 10:46:35.551 INFO [stdout] test memory::tests::load_data_with_len_less_than_range ... ok
Dec 05 10:46:35.551 INFO [stdout] test cpu::tests::operation_fx55_load_through_vx ... ok
Dec 05 10:46:35.551 INFO [stdout] test cpu::tests::operation_fx65_read_through_vx ... ok
Dec 05 10:46:35.551 INFO [stdout] test output::graphics::tests::clear_display ... ok
Dec 05 10:46:35.551 INFO [stdout] test output::graphics::tests::new_display ... ok
Dec 05 10:46:35.551 INFO [stdout] test output::graphics::tests::read_pixel_value_wrap ... ok
Dec 05 10:46:35.551 INFO [stdout] test output::graphics::tests::read_pixel_value ... ok
Dec 05 10:46:35.552 INFO [stdout] test cpu::timer::tests::tick_inactive_no_op ... ok
Dec 05 10:46:35.552 INFO [stdout] test memory::tests::load_data_with_len_equal_range ... ok
Dec 05 10:46:35.553 INFO [stdout] test output::graphics::tests::update_pixel_false_xor_true ... ok
Dec 05 10:46:35.553 INFO [stdout] test output::graphics::tests::update_pixel_true_xor_true ... ok
Dec 05 10:46:35.553 INFO [stdout] test output::graphics::tests::update_pixel_true_xor_false ... ok
Dec 05 10:46:35.553 INFO [stdout] test output::graphics::tests::update_pixel_false_xor_false ... ok
Dec 05 10:46:35.553 INFO [stdout] test output::graphics::tests::update_pixel_wrap ... ok
Dec 05 10:46:35.553 INFO [stdout] test output::graphics::tests::draw_resets_redraw_to_false ... ok
Dec 05 10:46:35.567 INFO [stdout] test cpu::timer::tests::tick_deactivates_at_zero ... ok
Dec 05 10:46:35.591 INFO [stdout] test memory::tests::load_data_with_len_greater_than_range_panics ... ok
Dec 05 10:46:36.550 INFO [stdout] test cpu::timer::tests::tick_active_decrements_current_at_rate ... ok
Dec 05 10:46:36.550 INFO [stdout] 
Dec 05 10:46:36.550 INFO [stdout] test result: ok. 87 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 10:46:36.550 INFO [stdout] 
Dec 05 10:46:36.552 INFO [stderr]      Running /target/debug/deps/rusty_chip-9ee3f895d54cd835
Dec 05 10:46:36.553 INFO [stdout] 
Dec 05 10:46:36.553 INFO [stdout] running 0 tests
Dec 05 10:46:36.553 INFO [stdout] 
Dec 05 10:46:36.553 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 10:46:36.553 INFO [stdout] 
Dec 05 10:46:36.554 INFO [stderr]    Doc-tests rusty_chip
Dec 05 10:46:36.854 INFO [stdout] 
Dec 05 10:46:36.854 INFO [stdout] running 0 tests
Dec 05 10:46:36.854 INFO [stdout] 
Dec 05 10:46:36.854 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 10:46:36.854 INFO [stdout] 
Dec 05 10:46:36.860 INFO [stderr] su: No module specific data is present
Dec 05 10:46:37.170 INFO running `"docker" "inspect" "c6a35680a2b5a0c14650813a409bdb8a8e2580b772b791837c034497ffecd7ce"`
Dec 05 10:46:37.266 INFO running `"docker" "rm" "-f" "c6a35680a2b5a0c14650813a409bdb8a8e2580b772b791837c034497ffecd7ce"`
Dec 05 10:46:37.372 INFO [stdout] c6a35680a2b5a0c14650813a409bdb8a8e2580b772b791837c034497ffecd7ce