Dec 13 20:57:25.423 INFO testing drewchandler/nes-rs against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 13 20:57:25.423 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 20:57:25.640 INFO [stdout] 98a9644101c41d3060b4ef643e4350d300c6e2895ec715a01a0b0b294ebe971c
Dec 13 20:57:25.644 INFO running `"docker" "start" "-a" "98a9644101c41d3060b4ef643e4350d300c6e2895ec715a01a0b0b294ebe971c"`
Dec 13 20:57:26.452 INFO [stderr] usermod: no changes
Dec 13 20:57:26.562 INFO [stderr]    Compiling gcc v0.3.41
Dec 13 20:57:26.572 INFO [stderr]    Compiling dylib v0.0.1
Dec 13 20:57:26.572 INFO [stderr]    Compiling time v0.1.36
Dec 13 20:57:27.303 INFO [stderr]    Compiling x11-dl v2.2.1
Dec 13 20:57:29.300 INFO [stderr]    Compiling minifb v0.9.0
Dec 13 20:57:37.395 INFO [stderr]    Compiling nes-rs v0.1.0 (/source)
Dec 13 20:57:38.224 INFO [stderr] warning: unreachable pattern
Dec 13 20:57:38.224 INFO [stderr]  --> src/ppu/vram.rs:9:9
Dec 13 20:57:38.224 INFO [stderr]   |
Dec 13 20:57:38.224 INFO [stderr] 9 |         _ => panic!("UNIMPLEMENTED ADDR {:x}", addr),
Dec 13 20:57:38.224 INFO [stderr]   |         ^
Dec 13 20:57:38.224 INFO [stderr]   |
Dec 13 20:57:38.224 INFO [stderr]   = note: #[warn(unreachable_patterns)] on by default
Dec 13 20:57:38.224 INFO [stderr] 
Dec 13 20:57:38.308 INFO [stderr] warning: method is never used: `interrupt_disable`
Dec 13 20:57:38.309 INFO [stderr]    --> src/cpu/mod.rs:271:5
Dec 13 20:57:38.309 INFO [stderr]     |
Dec 13 20:57:38.309 INFO [stderr] 271 |     fn interrupt_disable(&self) -> bool {
Dec 13 20:57:38.309 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 20:57:38.309 INFO [stderr]     |
Dec 13 20:57:38.309 INFO [stderr]     = note: #[warn(dead_code)] on by default
Dec 13 20:57:38.309 INFO [stderr] 
Dec 13 20:57:38.309 INFO [stderr] warning: method is never used: `decimal_mode`
Dec 13 20:57:38.309 INFO [stderr]    --> src/cpu/mod.rs:283:5
Dec 13 20:57:38.309 INFO [stderr]     |
Dec 13 20:57:38.309 INFO [stderr] 283 |     fn decimal_mode(&self) -> bool {
Dec 13 20:57:38.309 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 20:57:38.309 INFO [stderr] 
Dec 13 20:57:38.309 INFO [stderr] warning: method is never used: `break_command`
Dec 13 20:57:38.309 INFO [stderr]    --> src/cpu/mod.rs:295:5
Dec 13 20:57:38.309 INFO [stderr]     |
Dec 13 20:57:38.309 INFO [stderr] 295 |     fn break_command(&self) -> bool {
Dec 13 20:57:38.309 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 20:57:38.309 INFO [stderr] 
Dec 13 20:57:38.822 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.34s
Dec 13 20:57:38.828 INFO [stderr] su: No module specific data is present
Dec 13 20:57:39.112 INFO running `"docker" "inspect" "98a9644101c41d3060b4ef643e4350d300c6e2895ec715a01a0b0b294ebe971c"`
Dec 13 20:57:39.231 INFO running `"docker" "rm" "-f" "98a9644101c41d3060b4ef643e4350d300c6e2895ec715a01a0b0b294ebe971c"`
Dec 13 20:57:39.366 INFO [stdout] 98a9644101c41d3060b4ef643e4350d300c6e2895ec715a01a0b0b294ebe971c
Dec 13 20:57:39.368 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 20:57:39.567 INFO [stdout] 883e15dd5f5aabf1a56e7a9ebf50cbe60cb7c13d2e275da3bc7f7a156365f256
Dec 13 20:57:39.569 INFO running `"docker" "start" "-a" "883e15dd5f5aabf1a56e7a9ebf50cbe60cb7c13d2e275da3bc7f7a156365f256"`
Dec 13 20:57:39.981 INFO [stderr] usermod: no changes
Dec 13 20:57:40.016 INFO [stderr]    Compiling nes-rs v0.1.0 (/source)
Dec 13 20:57:42.251 INFO [stderr] warning: unreachable pattern
Dec 13 20:57:42.251 INFO [stderr]  --> src/ppu/vram.rs:9:9
Dec 13 20:57:42.251 INFO [stderr]   |
Dec 13 20:57:42.251 INFO [stderr] 9 |         _ => panic!("UNIMPLEMENTED ADDR {:x}", addr),
Dec 13 20:57:42.251 INFO [stderr]   |         ^
Dec 13 20:57:42.251 INFO [stderr]   |
Dec 13 20:57:42.251 INFO [stderr]   = note: #[warn(unreachable_patterns)] on by default
Dec 13 20:57:42.251 INFO [stderr] 
Dec 13 20:57:43.159 INFO [stderr] warning: method is never used: `interrupt_disable`
Dec 13 20:57:43.159 INFO [stderr]    --> src/cpu/mod.rs:271:5
Dec 13 20:57:43.159 INFO [stderr]     |
Dec 13 20:57:43.159 INFO [stderr] 271 |     fn interrupt_disable(&self) -> bool {
Dec 13 20:57:43.159 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 20:57:43.159 INFO [stderr]     |
Dec 13 20:57:43.159 INFO [stderr]     = note: #[warn(dead_code)] on by default
Dec 13 20:57:43.159 INFO [stderr] 
Dec 13 20:57:43.159 INFO [stderr] warning: method is never used: `decimal_mode`
Dec 13 20:57:43.159 INFO [stderr]    --> src/cpu/mod.rs:283:5
Dec 13 20:57:43.159 INFO [stderr]     |
Dec 13 20:57:43.159 INFO [stderr] 283 |     fn decimal_mode(&self) -> bool {
Dec 13 20:57:43.159 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 20:57:43.159 INFO [stderr] 
Dec 13 20:57:43.159 INFO [stderr] warning: method is never used: `break_command`
Dec 13 20:57:43.159 INFO [stderr]    --> src/cpu/mod.rs:295:5
Dec 13 20:57:43.159 INFO [stderr]     |
Dec 13 20:57:43.159 INFO [stderr] 295 |     fn break_command(&self) -> bool {
Dec 13 20:57:43.159 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 20:57:43.159 INFO [stderr] 
Dec 13 20:57:46.775 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.77s
Dec 13 20:57:46.775 INFO [stderr] su: No module specific data is present
Dec 13 20:57:47.496 INFO running `"docker" "inspect" "883e15dd5f5aabf1a56e7a9ebf50cbe60cb7c13d2e275da3bc7f7a156365f256"`
Dec 13 20:57:47.807 INFO running `"docker" "rm" "-f" "883e15dd5f5aabf1a56e7a9ebf50cbe60cb7c13d2e275da3bc7f7a156365f256"`
Dec 13 20:57:48.215 INFO [stdout] 883e15dd5f5aabf1a56e7a9ebf50cbe60cb7c13d2e275da3bc7f7a156365f256
Dec 13 20:57:48.219 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 20:57:48.679 INFO [stdout] 0f7a28e060586e156b27c767732b7ea6f7ec3d00bce1d68fb9f67e452b265ecc
Dec 13 20:57:48.684 INFO running `"docker" "start" "-a" "0f7a28e060586e156b27c767732b7ea6f7ec3d00bce1d68fb9f67e452b265ecc"`
Dec 13 20:57:49.779 INFO [stderr] usermod: no changes
Dec 13 20:57:49.883 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.05s
Dec 13 20:57:49.895 INFO [stdout] 
Dec 13 20:57:49.895 INFO [stdout] running 60 tests
Dec 13 20:57:49.895 INFO [stderr]      Running /target/debug/deps/nes_rs-0ffd7597c6e8ed2d
Dec 13 20:57:49.899 INFO [stdout] test cpu::tests::test_beq ... ok
Dec 13 20:57:49.899 INFO [stdout] test cpu::tests::test_and ... ok
Dec 13 20:57:49.900 INFO [stdout] test cpu::tests::test_adc ... ok
Dec 13 20:57:49.900 INFO [stdout] test cpu::tests::test_bcs ... ok
Dec 13 20:57:49.900 INFO [stdout] test cpu::tests::test_asl ... ok
Dec 13 20:57:49.911 INFO [stdout] test cpu::tests::test_bit ... ok
Dec 13 20:57:49.911 INFO [stdout] test cpu::tests::test_bcc ... ok
Dec 13 20:57:49.911 INFO [stdout] test cpu::tests::test_bne ... ok
Dec 13 20:57:49.911 INFO [stdout] test cpu::tests::test_bmi ... ok
Dec 13 20:57:49.919 INFO [stdout] test cpu::tests::test_brk ... ok
Dec 13 20:57:49.919 INFO [stdout] test cpu::tests::test_bpl ... ok
Dec 13 20:57:49.919 INFO [stdout] test cpu::tests::test_bvc ... ok
Dec 13 20:57:49.919 INFO [stdout] test cpu::tests::test_cld ... ok
Dec 13 20:57:49.919 INFO [stdout] test cpu::tests::test_bvs ... ok
Dec 13 20:57:49.947 INFO [stdout] test cpu::tests::test_clc ... ok
Dec 13 20:57:49.947 INFO [stdout] test cpu::tests::test_dec ... ok
Dec 13 20:57:49.947 INFO [stdout] test cpu::tests::test_cpy ... ok
Dec 13 20:57:49.947 INFO [stdout] test cpu::tests::test_cpx ... ok
Dec 13 20:57:49.947 INFO [stdout] test cpu::tests::test_cmp ... ok
Dec 13 20:57:49.947 INFO [stdout] test cpu::tests::test_dex ... ok
Dec 13 20:57:49.947 INFO [stdout] test cpu::tests::test_cli ... ok
Dec 13 20:57:49.947 INFO [stdout] test cpu::tests::test_clv ... ok
Dec 13 20:57:49.947 INFO [stdout] test cpu::tests::test_dey ... ok
Dec 13 20:57:49.947 INFO [stdout] test cpu::tests::test_inc ... ok
Dec 13 20:57:49.947 INFO [stdout] test cpu::tests::test_iny ... ok
Dec 13 20:57:49.947 INFO [stdout] test cpu::tests::test_lda ... ok
Dec 13 20:57:49.947 INFO [stdout] test cpu::tests::test_nop ... ok
Dec 13 20:57:49.957 INFO [stdout] test cpu::tests::test_jsr ... ok
Dec 13 20:57:49.957 INFO [stdout] test cpu::tests::test_inx ... ok
Dec 13 20:57:49.963 INFO [stdout] test cpu::tests::test_eor ... ok
Dec 13 20:57:49.963 INFO [stdout] test cpu::tests::test_lsr ... ok
Dec 13 20:57:49.963 INFO [stdout] test cpu::tests::test_ldy ... ok
Dec 13 20:57:49.963 INFO [stdout] test cpu::tests::test_ldx ... ok
Dec 13 20:57:49.963 INFO [stdout] test cpu::tests::test_jmp ... ok
Dec 13 20:57:49.963 INFO [stdout] test cpu::tests::test_ora ... ok
Dec 13 20:57:49.963 INFO [stdout] test cpu::tests::test_pha ... ok
Dec 13 20:57:49.963 INFO [stdout] test cpu::tests::test_pla ... ok
Dec 13 20:57:49.963 INFO [stdout] test cpu::tests::test_rol ... ok
Dec 13 20:57:49.967 INFO [stdout] test cpu::tests::test_ror ... ok
Dec 13 20:57:49.967 INFO [stdout] test cpu::tests::test_rti ... ok
Dec 13 20:57:49.967 INFO [stdout] test cpu::tests::test_php ... ok
Dec 13 20:57:49.967 INFO [stdout] test cpu::tests::test_sed ... ok
Dec 13 20:57:49.967 INFO [stdout] test cpu::tests::test_sec ... ok
Dec 13 20:57:49.967 INFO [stdout] test cpu::tests::test_rts ... ok
Dec 13 20:57:49.967 INFO [stdout] test cpu::tests::test_sbc ... ok
Dec 13 20:57:49.967 INFO [stdout] test cpu::tests::test_sta ... ok
Dec 13 20:57:49.967 INFO [stdout] test cpu::tests::test_txa ... ok
Dec 13 20:57:49.967 INFO [stdout] test cpu::tests::test_plp ... ok
Dec 13 20:57:49.967 INFO [stdout] test cpu::tests::test_tsx ... ok
Dec 13 20:57:49.967 INFO [stdout] test cpu::tests::test_tax ... ok
Dec 13 20:57:49.967 INFO [stdout] test cpu::tests::test_sty ... ok
Dec 13 20:57:49.971 INFO [stdout] test cpu::tests::test_tay ... ok
Dec 13 20:57:49.971 INFO [stdout] test cpu::tests::test_stx ... ok
Dec 13 20:57:49.975 INFO [stdout] test ppu::tests::test_writing_to_spr_ram ... ok
Dec 13 20:57:49.975 INFO [stdout] test ppu::tests::test_write_scroll ... ok
Dec 13 20:57:49.975 INFO [stdout] test ppu::tests::test_write_ctrl ... ok
Dec 13 20:57:49.975 INFO [stdout] test cpu::tests::test_tya ... ok
Dec 13 20:57:49.975 INFO [stdout] test cpu::tests::test_sei ... ok
Dec 13 20:57:49.975 INFO [stdout] test cpu::tests::test_txs ... ok
Dec 13 20:57:49.976 INFO [stdout] test ppu::tests::test_writing_to_vram ... ok
Dec 13 20:57:49.976 INFO [stdout] 
Dec 13 20:57:49.976 INFO [stdout] test result: ok. 60 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 20:57:49.976 INFO [stdout] 
Dec 13 20:57:49.987 INFO [stderr] su: No module specific data is present
Dec 13 20:57:50.584 INFO running `"docker" "inspect" "0f7a28e060586e156b27c767732b7ea6f7ec3d00bce1d68fb9f67e452b265ecc"`
Dec 13 20:57:50.819 INFO running `"docker" "rm" "-f" "0f7a28e060586e156b27c767732b7ea6f7ec3d00bce1d68fb9f67e452b265ecc"`
Dec 13 20:57:50.953 INFO [stdout] 0f7a28e060586e156b27c767732b7ea6f7ec3d00bce1d68fb9f67e452b265ecc