[INFO] updating cached repository razielgn/nes [INFO] running `"git" "fetch" "--all"` [INFO] [stdout] Fetching origin [INFO] [stderr] From git://github.com/razielgn/nes [INFO] [stderr] * branch HEAD -> FETCH_HEAD [INFO] running `"git" "clone" "work/cache/sources/gh/razielgn/nes" "work/ex/beta-1.37-6/sources/1.36.0/gh/razielgn/nes"` [INFO] [stderr] Cloning into 'work/ex/beta-1.37-6/sources/1.36.0/gh/razielgn/nes'... [INFO] [stderr] done. [INFO] running `"git" "clone" "work/cache/sources/gh/razielgn/nes" "work/ex/beta-1.37-6/sources/beta-2019-07-23/gh/razielgn/nes"` [INFO] [stderr] Cloning into 'work/ex/beta-1.37-6/sources/beta-2019-07-23/gh/razielgn/nes'... [INFO] [stderr] done. [INFO] running `"git" "rev-parse" "HEAD"` [INFO] [stdout] 0d5f8c44fe99947ac6de7a3648d2a7d95c9a4750 [INFO] sha for GitHub repo razielgn/nes: 0d5f8c44fe99947ac6de7a3648d2a7d95c9a4750 [INFO] validating manifest of razielgn/nes on toolchain 1.36.0 [INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+1.36.0" "read-manifest" "--manifest-path" "Cargo.toml"` [INFO] validating manifest of razielgn/nes on toolchain beta-2019-07-23 [INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+beta-2019-07-23" "read-manifest" "--manifest-path" "Cargo.toml"` [INFO] started frobbing razielgn/nes [INFO] finished frobbing razielgn/nes [INFO] frobbed toml for razielgn/nes written to work/ex/beta-1.37-6/sources/1.36.0/gh/razielgn/nes/Cargo.toml [INFO] started frobbing razielgn/nes [INFO] finished frobbing razielgn/nes [INFO] frobbed toml for razielgn/nes written to work/ex/beta-1.37-6/sources/beta-2019-07-23/gh/razielgn/nes/Cargo.toml [INFO] crate razielgn/nes already has a lockfile, it will not be regenerated [INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+1.36.0" "fetch" "--locked" "--manifest-path" "Cargo.toml"` [INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+beta-2019-07-23" "fetch" "--locked" "--manifest-path" "Cargo.toml"` [INFO] testing razielgn/nes against 1.36.0 for beta-1.37-6 [INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.37-6/worker-1/1.36.0:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.37-6/sources/1.36.0/gh/razielgn/nes:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+1.36.0" "build" "--frozen"` [INFO] [stdout] da7915671fbd200de2ba29c2e3e1aae6f80430933ac5848de038698bd26f581e [INFO] running `"docker" "start" "-a" "da7915671fbd200de2ba29c2e3e1aae6f80430933ac5848de038698bd26f581e"` [INFO] [stderr] Compiling num-traits v0.2.6 [INFO] [stderr] Compiling libc v0.2.54 [INFO] [stderr] Compiling autocfg v0.1.2 [INFO] [stderr] Compiling num-integer v0.1.39 [INFO] [stderr] Compiling cfg-if v0.1.7 [INFO] [stderr] Compiling num-iter v0.1.37 [INFO] [stderr] Compiling rand_core v0.4.0 [INFO] [stderr] Compiling byteorder v1.3.1 [INFO] [stderr] Compiling memchr v2.2.0 [INFO] [stderr] Compiling version_check v0.1.5 [INFO] [stderr] Compiling sdl2 v0.32.2 [INFO] [stderr] Compiling unicode-width v0.1.5 [INFO] [stderr] Compiling vec_map v0.8.1 [INFO] [stderr] Compiling strsim v0.8.0 [INFO] [stderr] Compiling lazy_static v1.3.0 [INFO] [stderr] Compiling bitflags v1.0.4 [INFO] [stderr] Compiling ansi_term v0.11.0 [INFO] [stderr] Compiling sdl2-sys v0.32.6 [INFO] [stderr] Compiling log v0.4.6 [INFO] [stderr] Compiling rand_core v0.3.1 [INFO] [stderr] Compiling rand_jitter v0.1.4 [INFO] [stderr] Compiling textwrap v0.11.0 [INFO] [stderr] Compiling rand_isaac v0.1.1 [INFO] [stderr] Compiling rand_xorshift v0.1.1 [INFO] [stderr] Compiling rand_hc v0.1.0 [INFO] [stderr] Compiling rand_chacha v0.1.1 [INFO] [stderr] Compiling rand_pcg v0.1.2 [INFO] [stderr] Compiling rand v0.6.5 [INFO] [stderr] Compiling nom v4.2.3 [INFO] [stderr] Compiling dirs v1.0.5 [INFO] [stderr] Compiling time v0.1.42 [INFO] [stderr] Compiling rand_os v0.1.3 [INFO] [stderr] Compiling atty v0.2.11 [INFO] [stderr] Compiling clap v2.33.0 [INFO] [stderr] Compiling term v0.5.2 [INFO] [stderr] Compiling chrono v0.4.6 [INFO] [stderr] Compiling num v0.1.42 [INFO] [stderr] Compiling simplelog v0.5.3 [INFO] [stderr] Compiling nes v0.1.0 (/opt/crater/workdir) [INFO] [stderr] Finished dev [optimized + debuginfo] target(s) in 1m 28s [INFO] running `"docker" "inspect" "da7915671fbd200de2ba29c2e3e1aae6f80430933ac5848de038698bd26f581e"` [INFO] running `"docker" "rm" "-f" "da7915671fbd200de2ba29c2e3e1aae6f80430933ac5848de038698bd26f581e"` [INFO] [stdout] da7915671fbd200de2ba29c2e3e1aae6f80430933ac5848de038698bd26f581e [INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.37-6/worker-1/1.36.0:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.37-6/sources/1.36.0/gh/razielgn/nes:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+1.36.0" "test" "--frozen" "--no-run"` [INFO] [stdout] 00126746fe7296abb939799589e2e4b2b378dd509d7a4bd94771641121939469 [INFO] running `"docker" "start" "-a" "00126746fe7296abb939799589e2e4b2b378dd509d7a4bd94771641121939469"` [INFO] [stderr] Compiling arrayvec v0.4.10 [INFO] [stderr] Compiling proc-macro2 v0.4.30 [INFO] [stderr] Compiling nodrop v0.1.13 [INFO] [stderr] Compiling memoffset v0.2.1 [INFO] [stderr] Compiling unicode-xid v0.1.0 [INFO] [stderr] Compiling scopeguard v0.3.3 [INFO] [stderr] Compiling serde v1.0.91 [INFO] [stderr] Compiling ryu v0.2.8 [INFO] [stderr] Compiling cgmath v0.16.1 [INFO] [stderr] Compiling winapi v0.3.7 [INFO] [stderr] Compiling syn v0.15.34 [INFO] [stderr] Compiling rayon-core v1.4.1 [INFO] [stderr] Compiling either v1.5.2 [INFO] [stderr] Compiling approx v0.1.1 [INFO] [stderr] Compiling rayon v1.0.3 [INFO] [stderr] Compiling itoa v0.4.4 [INFO] [stderr] Compiling same-file v1.0.4 [INFO] [stderr] Compiling cast v0.2.2 [INFO] [stderr] Compiling rgb v0.8.13 [INFO] [stderr] Compiling difference v2.0.0 [INFO] [stderr] Compiling crossbeam-utils v0.2.2 [INFO] [stderr] Compiling itertools v0.8.0 [INFO] [stderr] Compiling walkdir v2.2.7 [INFO] [stderr] Compiling num-traits v0.1.43 [INFO] [stderr] Compiling rand v0.4.6 [INFO] [stderr] Compiling num_cpus v1.10.0 [INFO] [stderr] Compiling rand_xoshiro v0.1.0 [INFO] [stderr] Compiling csv-core v0.1.5 [INFO] [stderr] Compiling criterion-plot v0.3.1 [INFO] [stderr] Compiling pretty_assertions v0.6.1 [INFO] [stderr] Compiling crossbeam-epoch v0.3.1 [INFO] [stderr] Compiling crossbeam-deque v0.2.0 [INFO] [stderr] Compiling quote v0.6.12 [INFO] [stderr] Compiling winconsole v0.10.0 [INFO] [stderr] Compiling colored v1.8.0 [INFO] [stderr] Compiling serde_json v1.0.39 [INFO] [stderr] Compiling csv v1.0.7 [INFO] [stderr] Compiling tinytemplate v1.0.1 [INFO] [stderr] Compiling serde_derive v1.0.91 [INFO] [stderr] Compiling criterion v0.2.11 [INFO] [stderr] Compiling nes v0.1.0 (/opt/crater/workdir) [INFO] [stderr] Finished dev [optimized + debuginfo] target(s) in 4m 07s [INFO] running `"docker" "inspect" "00126746fe7296abb939799589e2e4b2b378dd509d7a4bd94771641121939469"` [INFO] running `"docker" "rm" "-f" "00126746fe7296abb939799589e2e4b2b378dd509d7a4bd94771641121939469"` [INFO] [stdout] 00126746fe7296abb939799589e2e4b2b378dd509d7a4bd94771641121939469 [INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.37-6/worker-1/1.36.0:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/beta-1.37-6/sources/1.36.0/gh/razielgn/nes:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+1.36.0" "test" "--frozen"` [INFO] [stdout] 56154782f5aa4c8bae0c1d8e13bd18f9e1c78c905a9d8e85c5e4285627efe132 [INFO] running `"docker" "start" "-a" "56154782f5aa4c8bae0c1d8e13bd18f9e1c78c905a9d8e85c5e4285627efe132"` [INFO] [stderr] Finished dev [optimized + debuginfo] target(s) in 0.17s [INFO] [stderr] Running /opt/crater/target/debug/deps/nes-45235d82861c02e0 [INFO] [stdout] [INFO] [stdout] running 50 tests [INFO] [stdout] test cpu::test::bpl_relative_branch ... ok [INFO] [stdout] test cpu::test::bit_ops_on_p ... ok [INFO] [stdout] test cpu::test::asl_accumulator ... ok [INFO] [stdout] test bits::test::bit_ops ... ok [INFO] [stdout] test cpu::test::bpl_relative_not_branch ... ok [INFO] [stdout] test cpu::test::lda_absolute_x_page_cross ... ok [INFO] [stdout] test cpu::test::lda_absolute_y ... ok [INFO] [stdout] test cpu::test::lda_immediate ... ok [INFO] [stdout] test cpu::test::lda_indexed_indirect ... ok [INFO] [stdout] test cpu::test::jmp_indirect ... ok [INFO] [stdout] test cpu::test::lda_indirect_indexed ... ok [INFO] [stdout] test cpu::test::lda_absolute ... ok [INFO] [stdout] test cpu::test::lda_absolute_x ... ok [INFO] [stdout] test cpu::test::lda_indirect_indexed_page_crossing ... ok [INFO] [stdout] test cpu::test::lda_absolute_y_page_cross ... ok [INFO] [stdout] test cpu::test::reset ... ok [INFO] [stdout] test cpu::test::rol_absolute_x_dummy_read ... ok [INFO] [stdout] test cpu::test::sta_absolute_y_dummy_read ... ok [INFO] [stdout] test cpu::test::sta_indirect_indexed_dummy_read ... ok [INFO] [stdout] test memory::test::ram_wraparound ... ok [INFO] [stdout] test cpu::test::ldx_zero_page_y ... ok [INFO] [stdout] test cpu::test::nop_implied ... ok [INFO] [stdout] test ppu::tests::control::background_pattern_table_addr ... ok [INFO] [stdout] test cpu::test::lda_indirect_indexed_page_wraparound ... ok [INFO] [stdout] test cpu::test::lda_zero_page ... ok [INFO] [stdout] test ppu::tests::control::sprite_pattern_table_addr ... ok [INFO] [stdout] test cpu::test::lda_zero_page_x ... ok [INFO] [stdout] test ppu::tests::control::sprite_height ... ok [INFO] [stdout] test ppu::tests::control::nmi_at_next_vblank ... ok [INFO] [stdout] test ppu::tests::mask::grayscale ... ok [INFO] [stdout] test ppu::tests::control::vram_addr_incr ... ok [INFO] [stdout] test ppu::tests::read_from_data_not_in_palettes_address_range_is_delayed_by_one_read ... ok [INFO] [stdout] test cpu::test::lda_indexed_indirect_page_wraparound ... ok [INFO] [stdout] test ppu::tests::read_from_data_increments_vram_addr ... ok [INFO] [stdout] test ppu::tests::open_bus ... ok [INFO] [stdout] test ppu::tests::scroll_and_addr_write_example ... ok [INFO] [stdout] test ppu::tests::read_immediately_from_data_in_palettes_address_range ... ok [INFO] [stdout] test ppu::tests::write_and_read_oam ... ok [INFO] [stdout] test ppu::tests::write_to_data_increments_vram_addr ... ok [INFO] [stdout] test ppu::tests::vblank_has_ended ... ok [INFO] [stdout] test ppu::tests::vblank_has_started_and_reading_status_resets_it ... ok [INFO] [stdout] test ppu::tests::reading_status_exactly_on_vblank_cycle_returns_false ... ok [INFO] [stdout] test rom::test::parse_mapper_0_with_chr ... ok [INFO] [stdout] test ppu::tests::vblank_start_with_nmi_at_next_vblank_pulls_nmi ... ok [INFO] [stdout] test rom::test::parse_mapper_1 ... ok [INFO] [stdout] test rom::test::parse_mapper_0_without_chr ... ok [INFO] [stdout] test ppu::tests::writing_to_control_on_vblank_pulls_pin_with_one_instr_delay ... ok [INFO] [stdout] test ppu::tests::writing_to_control_within_vblank_clears_nmi_pin ... ok [INFO] [stdout] test ppu::tests::all_frames_are_equal_when_bg_is_disabled ... ok [INFO] [stdout] test ppu::tests::odd_frames_are_shorter_by_one_cycle ... ok [INFO] [stderr] Running /opt/crater/target/debug/deps/blargg-dd6504042c96f7a6 [INFO] [stdout] [INFO] [stdout] test result: ok. 50 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 57 tests [INFO] [stdout] test cpu_exec_space::apu ... ignored [INFO] [stdout] test cpu_interrupts_v2::branch_delays_irq ... ignored [INFO] [stdout] test cpu_interrupts_v2::cli_latency ... ignored [INFO] [stdout] test cpu_interrupts_v2::irq_and_dma ... ignored [INFO] [stdout] test cpu_interrupts_v2::nmi_and_brk ... ignored [INFO] [stdout] test cpu_interrupts_v2::nmi_and_irq ... ignored [INFO] [stdout] test instr_misc::dummy_reads_apu ... ignored [INFO] [stdout] test instr_misc::abs_x_wrap ... ok [INFO] [stdout] test instr_misc::branch_wrap ... ok [INFO] [stdout] test instr_test_v5::basics ... ok [INFO] [stdout] test cpu_exec_space::ppuio ... ok [INFO] [stdout] test instr_test_v5::branches ... ok [INFO] [stdout] test instr_misc::dummy_reads ... ok [INFO] [stdout] test instr_test_v5::brk ... ok [INFO] [stdout] test instr_test_v5::absolute ... ok [INFO] [stdout] test instr_test_v5::immediate ... ok [INFO] [stdout] test instr_test_v5::jmp_jsr ... ok [INFO] [stdout] test instr_test_v5::implied ... ok [INFO] [stdout] test instr_test_v5::rti ... ok [INFO] [stdout] test instr_test_v5::rts ... ok [INFO] [stdout] test instr_test_v5::ind_x ... ok [INFO] [stdout] test instr_test_v5::stack ... ignored [INFO] [stdout] test cpu_reset::registers ... ok [INFO] [stdout] test instr_test_v5::special ... ok [INFO] [stdout] test instr_timing::branch_timing ... ignored [INFO] [stdout] test instr_timing::instr_timing ... ignored [INFO] [stdout] test cpu_reset::ram_after_reset ... ok [INFO] [stdout] test instr_test_v5::ind_y ... ok [INFO] [stdout] test oam_read ... ok [INFO] [stdout] test ppu_sprite_hit::alignment ... ok [INFO] [stdout] test instr_test_v5::zero_page ... ok [INFO] [stdout] test instr_test_v5::abs_xy ... ok [INFO] [stdout] test ppu_sprite_hit::basics ... ok [INFO] [stdout] test ppu_sprite_hit::edge_timing ... ignored [INFO] [stdout] test ppu_sprite_hit::double_height ... ok [INFO] [stdout] test ppu_sprite_hit::corners ... ok [INFO] [stdout] test ppu_sprite_hit::flip ... ok [INFO] [stdout] test ppu_sprite_hit::screen_bottom ... ignored [INFO] [stdout] test ppu_sprite_hit::timing_basics ... ignored [INFO] [stdout] test ppu_sprite_hit::timing_order ... ignored [INFO] [stdout] test ppu_sprite_hit::right_edge ... ok [INFO] [stdout] test ppu_sprite_hit::left_clip ... ok [INFO] [stdout] test ppu_vbl_nmi::nmi_control ... ok [INFO] [stdout] test ppu_vbl_nmi::even_odd_frames ... ok [INFO] [stdout] test ppu_vbl_nmi::even_odd_timing ... ok [INFO] [stdout] test ppu_vbl_nmi::nmi_timing ... ignored [INFO] [stdout] test instr_test_v5::zp_xy ... ok [INFO] [stdout] test ppu_open_bus ... ok [INFO] [stdout] test ppu_vbl_nmi::vbl_clear_time ... ignored [INFO] [stdout] test ppu_vbl_nmi::nmi_off_timing ... ok [INFO] [stdout] test ppu_vbl_nmi::nmi_on_timing ... ok [INFO] [stdout] test instr_test_v5::all_instrs ... test instr_test_v5::all_instrs has been running for over 60 seconds [INFO] [stdout] test ppu_vbl_nmi::vbl_basics ... ok [INFO] [stdout] test ppu_vbl_nmi::suppression ... ok [INFO] [stdout] test ppu_vbl_nmi::vbl_set_time ... ok [INFO] [stdout] test instr_test_v5::official_only ... test instr_test_v5::official_only has been running for over 60 seconds [INFO] [stdout] test oam_stress ... test oam_stress has been running for over 60 seconds [INFO] [stdout] test instr_test_v5::all_instrs ... ok [INFO] [stdout] test instr_test_v5::official_only ... ok [INFO] [stderr] Running /opt/crater/target/debug/deps/nestest-9ef44271e7aa12ac [INFO] [stdout] test oam_stress ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 41 passed; 0 failed; 16 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test nestest ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] running `"docker" "inspect" "56154782f5aa4c8bae0c1d8e13bd18f9e1c78c905a9d8e85c5e4285627efe132"` [INFO] running `"docker" "rm" "-f" "56154782f5aa4c8bae0c1d8e13bd18f9e1c78c905a9d8e85c5e4285627efe132"` [INFO] [stdout] 56154782f5aa4c8bae0c1d8e13bd18f9e1c78c905a9d8e85c5e4285627efe132