[INFO] fetching crate mos6502 0.9.0...
[INFO] testing mos6502-0.9.0 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-2
[INFO] extracting crate mos6502 0.9.0 into /workspace/builds/worker-1-tc1/source
[INFO] started tweaking crates.io crate mos6502 0.9.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate mos6502 0.9.0
[INFO] tweaked toml for crates.io crate mos6502 0.9.0 written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate mos6502 0.9.0 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate mos6502 0.9.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 26504b795f4fa6e70aefa38851fc2f2a36ad33fa6fddb7865a31dc829c69c053
[INFO] running `Command { std: "docker" "start" "-a" "26504b795f4fa6e70aefa38851fc2f2a36ad33fa6fddb7865a31dc829c69c053", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "26504b795f4fa6e70aefa38851fc2f2a36ad33fa6fddb7865a31dc829c69c053", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "26504b795f4fa6e70aefa38851fc2f2a36ad33fa6fddb7865a31dc829c69c053", kill_on_drop: false }`
[INFO] [stdout] 26504b795f4fa6e70aefa38851fc2f2a36ad33fa6fddb7865a31dc829c69c053
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8ab36222a3df1b75120d1ee7eccb1ea1105f8d368e4944192e91ba820d39d800
[INFO] running `Command { std: "docker" "start" "-a" "8ab36222a3df1b75120d1ee7eccb1ea1105f8d368e4944192e91ba820d39d800", kill_on_drop: false }`
[INFO] [stderr]    Compiling mos6502 v0.9.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.44s
[INFO] running `Command { std: "docker" "inspect" "8ab36222a3df1b75120d1ee7eccb1ea1105f8d368e4944192e91ba820d39d800", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8ab36222a3df1b75120d1ee7eccb1ea1105f8d368e4944192e91ba820d39d800", kill_on_drop: false }`
[INFO] [stdout] 8ab36222a3df1b75120d1ee7eccb1ea1105f8d368e4944192e91ba820d39d800
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] dd27e2820bc00fd25f5412fa44723a72b4f54d8a20543668a88c569ff1894c73
[INFO] running `Command { std: "docker" "start" "-a" "dd27e2820bc00fd25f5412fa44723a72b4f54d8a20543668a88c569ff1894c73", kill_on_drop: false }`
[INFO] [stderr]    Compiling mos6502 v0.9.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.29s
[INFO] running `Command { std: "docker" "inspect" "dd27e2820bc00fd25f5412fa44723a72b4f54d8a20543668a88c569ff1894c73", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "dd27e2820bc00fd25f5412fa44723a72b4f54d8a20543668a88c569ff1894c73", kill_on_drop: false }`
[INFO] [stdout] dd27e2820bc00fd25f5412fa44723a72b4f54d8a20543668a88c569ff1894c73
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 0fea5b63bf02c549cbf03ec6c28252000dc0b1553901f0a1cf5b38532ddae737
[INFO] running `Command { std: "docker" "start" "-a" "0fea5b63bf02c549cbf03ec6c28252000dc0b1553901f0a1cf5b38532ddae737", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/mos6502-1ff8aad44352ec78)
[INFO] [stdout] 
[INFO] [stdout] running 88 tests
[INFO] [stdout] test cpu::cycle_timing_tests::test_basic_cycle_counting ... ok
[INFO] [stdout] test cpu::cycle_timing_tests::test_decimal_mode_penalty_65c02 ... ok
[INFO] [stdout] test cpu::cycle_timing_tests::test_branch_cycle_timing ... ok
[INFO] [stdout] test cpu::cycle_timing_tests::test_irq_handling ... ok
[INFO] [stdout] test cpu::cycle_timing_tests::test_page_crossing_edge_cases ... ok
[INFO] [stdout] test cpu::cycle_timing_tests::test_wai_waits_for_interrupt ... ok
[INFO] [stdout] test cpu::cycle_timing_tests::test_page_crossing_detection ... ok
[INFO] [stdout] test cpu::cycle_timing_tests::test_various_instruction_cycles ... ok
[INFO] [stdout] test cpu::cycle_timing_tests::test_indirect_indexed_page_crossing ... ok
[INFO] [stdout] test cpu::cycle_timing_tests::test_no_decimal_penalty_on_nmos ... ok
[INFO] [stdout] test cpu::tests::adc_function_nmos6502_binary_carry ... ok
[INFO] [stdout] test cpu::tests::adc_function_nmos6502_binary_overflow ... ok
[INFO] [stdout] test cpu::tests::adc_function_ricoh2a03_decimal_calls_binary ... ok
[INFO] [stdout] test cpu::tests::add_with_carry_test ... ok
[INFO] [stdout] test cpu::tests::alr_test ... ok
[INFO] [stdout] test cpu::tests::and_test ... ok
[INFO] [stdout] test cpu::tests::bbr_backward_branch ... ok
[INFO] [stdout] test cpu::tests::arr_test ... ok
[INFO] [stdout] test cpu::tests::bbs_branches_when_bit_is_set ... ok
[INFO] [stdout] test cpu::tests::branch_if_carry_clear_test ... ok
[INFO] [stdout] test cpu::tests::branch_if_carry_set_test ... ok
[INFO] [stdout] test cpu::tests::anc_test ... ok
[INFO] [stdout] test cpu::tests::bbr_branches_when_bit_is_clear ... ok
[INFO] [stdout] test cpu::tests::branch_if_equal_test ... ok
[INFO] [stdout] test cpu::tests::branch_if_minus_test ... ok
[INFO] [stdout] test cpu::tests::adc_function_nmos6502_decimal_basic ... ok
[INFO] [stdout] test cpu::tests::brk_pushes_bits_4_and_5_set ... ok
[INFO] [stdout] test cpu::tests::branch_if_positive_test ... ok
[INFO] [stdout] test cpu::tests::cmos6502_adc_decimal_mode ... ok
[INFO] [stdout] test cpu::tests::branch_if_overflow_clear_test ... ok
[INFO] [stdout] test cpu::tests::branch_if_overflow_set_test ... ok
[INFO] [stdout] test cpu::tests::compare_with_x_register_test ... ok
[INFO] [stdout] test cpu::tests::cmos_jmp_absx_indirect ... ok
[INFO] [stdout] test cpu::tests::cmos_bit_absx ... ok
[INFO] [stdout] test cpu::tests::cmos_bit_zpx ... ok
[INFO] [stdout] test cpu::tests::cmos_wai ... ok
[INFO] [stdout] test cpu::tests::cmos_stp ... ok
[INFO] [stdout] test cpu::tests::compare_with_a_register_test ... ok
[INFO] [stdout] test cpu::cycle_timing_tests::test_cycles_accumulate ... ok
[INFO] [stdout] test cpu::tests::dcp_test ... ok
[INFO] [stdout] test cpu::tests::dec_x_test ... ok
[INFO] [stdout] test cpu::tests::dec_accumulator_test ... ok
[INFO] [stdout] test cpu::tests::decrement_y_test ... ok
[INFO] [stdout] test cpu::tests::get_flag ... ok
[INFO] [stdout] test cpu::tests::decrement_memory_test ... ok
[INFO] [stdout] test cpu::tests::decrement_x_test ... ok
[INFO] [stdout] test cpu::tests::decimal_subtract_test ... ok
[INFO] [stdout] test cpu::tests::inc_accumulator_test ... ok
[INFO] [stdout] test cpu::cycle_timing_tests::test_nmi_handling ... ok
[INFO] [stdout] test cpu::tests::adc_function_nmos6502_binary_basic ... ok
[INFO] [stdout] test cpu::tests::las_test ... ok
[INFO] [stdout] test cpu::tests::jump_test ... ok
[INFO] [stdout] test cpu::tests::decimal_add_test ... ok
[INFO] [stdout] test cpu::tests::isc_test ... ok
[INFO] [stdout] test cpu::tests::dont_panic_for_overflow ... ok
[INFO] [stdout] test cpu::tests::lax_test ... ok
[INFO] [stdout] test cpu::tests::jam_test ... ok
[INFO] [stdout] test cpu::tests::logical_shift_right_test ... ok
[INFO] [stdout] test cpu::tests::nmos6502_adc_decimal_mode ... ok
[INFO] [stdout] test cpu::tests::sax_test ... ok
[INFO] [stdout] test cpu::tests::ricoh2a03_ignores_decimal_mode ... ok
[INFO] [stdout] test cpu::tests::revision_a_adc_same_as_nmos ... ok
[INFO] [stdout] test cpu::tests::slo_test ... ok
[INFO] [stdout] test cpu::tests::sbx_test ... ok
[INFO] [stdout] test cpu::tests::rra_test ... ok
[INFO] [stdout] test cpu::tests::nop_variants_test ... ok
[INFO] [stdout] test cpu::tests::php_sets_bits_4_and_5 ... ok
[INFO] [stdout] test cpu::tests::pla_negative_flag_from_pulled_value ... ok
[INFO] [stdout] test cpu::tests::sre_test ... ok
[INFO] [stdout] test cpu::tests::plx_ply_negative_flag_from_pulled_value ... ok
[INFO] [stdout] test cpu::tests::reset_sequence_behavior ... ok
[INFO] [stdout] test cpu::tests::stack_underflow ... ok
[INFO] [stdout] test cpu::tests::rla_test ... ok
[INFO] [stdout] test cpu::tests::solid65_adc_immediate ... ok
[INFO] [stdout] test cpu::tests::rmb_clears_individual_bits ... ok
[INFO] [stdout] test cpu::tests::subtract_with_carry_comprehensive_test ... ok
[INFO] [stdout] test cpu::tests::trb_resets_bits_in_memory ... ok
[INFO] [stdout] test cpu::tests::tsb_sets_bits_in_memory ... ok
[INFO] [stdout] test cpu::tests::smb_sets_individual_bits ... ok
[INFO] [stdout] test cpu::tests::unset_flag ... ok
[INFO] [stdout] test cpu::tests::usbc_test ... ok
[INFO] [stdout] test cpu::tests::xaa_test ... ok
[INFO] [stdout] test cpu::tests::set_flag ... ok
[INFO] [stdout] test cpu::tests::branch_across_end_of_address_space ... ok
[INFO] [stdout] test cpu::tests::compare_with_y_register_test ... ok
[INFO] [stdout] test memory::tests::test_memory_overflow_panic - should panic ... ok
[INFO] [stdout] test cpu::tests::exclusive_or_test ... ok
[INFO] [stdout] test cpu::tests::inclusive_or_test ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 88 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stderr]      Running tests/functional_test.rs (/opt/rustwide/target/debug/deps/functional_test-47a20b89e11f88b6)
[INFO] [stdout] test klaus2m5_functional_test_nmos ... ok
[INFO] [stdout] test klaus2m5_65c02_extended_opcodes_test ... ok
[INFO] [stdout] test klaus2m5_functional_test_cmos ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 11.38s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/interrupt_test.rs (/opt/rustwide/target/debug/deps/interrupt_test-8ea4589771087376)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test klaus2m5_interrupt_test ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/timing_test.rs (/opt/rustwide/target/debug/deps/timing_test-ae01db632d38951c)
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests mos6502
[INFO] [stdout] running 1 test
[INFO] [stdout] test timing_test_6502 ... ignored
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test src/memory.rs - memory::Bus (line 72) ... ok
[INFO] [stdout] test src/cpu.rs - cpu (line 74) ... ok
[INFO] [stdout] test src/cpu.rs - cpu::CPU<M,V>::value_is_negative (line 1215) ... ok
[INFO] [stdout] test src/cpu.rs - cpu (line 138) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.80s; merged doctests compilation took 0.79s
[INFO] running `Command { std: "docker" "inspect" "0fea5b63bf02c549cbf03ec6c28252000dc0b1553901f0a1cf5b38532ddae737", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0fea5b63bf02c549cbf03ec6c28252000dc0b1553901f0a1cf5b38532ddae737", kill_on_drop: false }`
[INFO] [stdout] 0fea5b63bf02c549cbf03ec6c28252000dc0b1553901f0a1cf5b38532ddae737