[INFO] fetching crate crsp-base 0.2.0...
[INFO] testing crsp-base-0.2.0 against master#0d13f6afeba4935499abe0c9a07426c94492c94e for pr-96025
[INFO] extracting crate crsp-base 0.2.0 into /workspace/builds/worker-83/source
[INFO] validating manifest of crates.io crate crsp-base 0.2.0 on toolchain 0d13f6afeba4935499abe0c9a07426c94492c94e
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+0d13f6afeba4935499abe0c9a07426c94492c94e" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate crsp-base 0.2.0
[INFO] finished tweaking crates.io crate crsp-base 0.2.0
[INFO] tweaked toml for crates.io crate crsp-base 0.2.0 written to /workspace/builds/worker-83/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+0d13f6afeba4935499abe0c9a07426c94492c94e" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+0d13f6afeba4935499abe0c9a07426c94492c94e" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded spin_sleep v1.1.1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-83/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-83/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+0d13f6afeba4935499abe0c9a07426c94492c94e" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] e5b51bf1ba945efb85e9b9f38f4cdbe0a9b5ea4397451b46885dbcab416cd678
[INFO] running `Command { std: "docker" "start" "-a" "e5b51bf1ba945efb85e9b9f38f4cdbe0a9b5ea4397451b46885dbcab416cd678", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e5b51bf1ba945efb85e9b9f38f4cdbe0a9b5ea4397451b46885dbcab416cd678", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e5b51bf1ba945efb85e9b9f38f4cdbe0a9b5ea4397451b46885dbcab416cd678", kill_on_drop: false }`
[INFO] [stdout] e5b51bf1ba945efb85e9b9f38f4cdbe0a9b5ea4397451b46885dbcab416cd678
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-83/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-83/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" "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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+0d13f6afeba4935499abe0c9a07426c94492c94e" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7a7d231ba9461dd32345e03bd09f150498a2fafb53a09258db63118759d5ce40
[INFO] running `Command { std: "docker" "start" "-a" "7a7d231ba9461dd32345e03bd09f150498a2fafb53a09258db63118759d5ce40", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.37
[INFO] [stderr]    Compiling unicode-xid v0.2.2
[INFO] [stderr]    Compiling syn v1.0.91
[INFO] [stderr]    Compiling libc v0.2.124
[INFO] [stderr]    Compiling cfg-if v1.0.0
[INFO] [stderr]    Compiling serde v1.0.136
[INFO] [stderr]    Compiling autocfg v1.1.0
[INFO] [stderr]    Compiling scopeguard v1.1.0
[INFO] [stderr]    Compiling futures-core v0.3.21
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling ppv-lite86 v0.2.16
[INFO] [stderr]    Compiling pin-project-lite v0.2.8
[INFO] [stderr]    Compiling convert_case v0.4.0
[INFO] [stderr]    Compiling futures-sink v0.3.21
[INFO] [stderr]    Compiling spin_sleep v1.1.1
[INFO] [stderr]    Compiling static_assertions v1.1.0
[INFO] [stderr]    Compiling tracing-core v0.1.26
[INFO] [stderr]    Compiling lock_api v0.4.7
[INFO] [stderr]    Compiling spin v0.9.3
[INFO] [stderr]    Compiling quote v1.0.18
[INFO] [stderr]    Compiling getrandom v0.2.6
[INFO] [stderr]    Compiling rand_core v0.6.3
[INFO] [stderr]    Compiling nanorand v0.7.0
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling toml v0.5.9
[INFO] [stderr]    Compiling thiserror-impl v1.0.30
[INFO] [stderr]    Compiling pin-project-internal v1.0.10
[INFO] [stderr]    Compiling tracing-attributes v0.1.20
[INFO] [stderr]    Compiling derive_more v0.99.17
[INFO] [stderr]    Compiling thiserror v1.0.30
[INFO] [stderr]    Compiling pin-project v1.0.10
[INFO] [stderr]    Compiling tracing v0.1.34
[INFO] [stderr]    Compiling proc-macro-crate v1.1.3
[INFO] [stderr]    Compiling flume v0.10.12
[INFO] [stderr]    Compiling num_enum_derive v0.5.7
[INFO] [stderr]    Compiling num_enum v0.5.7
[INFO] [stderr]    Compiling crsp-base v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 51.90s
[INFO] running `Command { std: "docker" "inspect" "7a7d231ba9461dd32345e03bd09f150498a2fafb53a09258db63118759d5ce40", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7a7d231ba9461dd32345e03bd09f150498a2fafb53a09258db63118759d5ce40", kill_on_drop: false }`
[INFO] [stdout] 7a7d231ba9461dd32345e03bd09f150498a2fafb53a09258db63118759d5ce40
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-83/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-83/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" "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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+0d13f6afeba4935499abe0c9a07426c94492c94e" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7d2a21a7eee77af994e1935a05e337782250f9da67577246ec8a841e84cd10f9
[INFO] running `Command { std: "docker" "start" "-a" "7d2a21a7eee77af994e1935a05e337782250f9da67577246ec8a841e84cd10f9", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling crsp-base v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 8.34s
[INFO] [stderr]   Executable unittests src/lib.rs (/opt/rustwide/target/debug/deps/crsp_base-cf7f9c48b16e848f)
[INFO] running `Command { std: "docker" "inspect" "7d2a21a7eee77af994e1935a05e337782250f9da67577246ec8a841e84cd10f9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7d2a21a7eee77af994e1935a05e337782250f9da67577246ec8a841e84cd10f9", kill_on_drop: false }`
[INFO] [stdout] 7d2a21a7eee77af994e1935a05e337782250f9da67577246ec8a841e84cd10f9
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-83/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-83/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" "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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+0d13f6afeba4935499abe0c9a07426c94492c94e" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 1bac0f25d229e90fc45629756be901025c9aeb6130079e549c1a7c8e4647ba34
[INFO] running `Command { std: "docker" "start" "-a" "1bac0f25d229e90fc45629756be901025c9aeb6130079e549c1a7c8e4647ba34", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.12s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/crsp_base-cf7f9c48b16e848f)
[INFO] [stdout] 
[INFO] [stdout] running 70 tests
[INFO] [stdout] test instruction::test::instruction_try_from_u8x2::case_err ... ok
[INFO] [stdout] test instruction::test::u8x2_from_instruction ... ok
[INFO] [stdout] test processor::test::step::instr_add_assign_const::case_no_carry ... ok
[INFO] [stdout] test instruction::test::instruction_try_from_u8x2::case_ok ... ok
[INFO] [stdout] test processor::test::step::instr_add_assign_const::case_carry ... ok
[INFO] [stdout] test processor::test::step::instr_assign_const ... ok
[INFO] [stdout] test processor::test::step::instr_add_assign_to_i::case_carry ... ok
[INFO] [stdout] test processor::test::step::instr_assign_addr_to_i ... ok
[INFO] [stdout] test processor::test::step::instr_add_assign_to_i::case_no_carry ... ok
[INFO] [stdout] test processor::test::step::instr_assign_delay_timer_val ... ok
[INFO] [stdout] test processor::test::step::instr_assign ... ok
[INFO] [stdout] test processor::test::step::instr_assign_hex_char_sprite_addr_to_i::case_err ... ok
[INFO] [stdout] test processor::test::step::instr_assign_hex_char_sprite_addr_to_i::case_ok ... ok
[INFO] [stdout] test processor::test::step::instr_assign_to_sound_timer ... ok
[INFO] [stdout] test processor::test::step::instr_assign_to_delay_timer ... ok
[INFO] [stdout] test processor::test::step::instr_clear_screen ... ok
[INFO] [stdout] test processor::test::step::instr_call_machine_subroutine::case_err ... ok
[INFO] [stdout] test processor::test::step::instr_draw_sprite::case_clip_x_wrap_y ... ok
[INFO] [stdout] test processor::test::step::instr_call_subroutine::case_err ... ok
[INFO] [stdout] test processor::test::step::instr_call_machine_subroutine::case_skip ... ok
[INFO] [stdout] test processor::test::step::instr_call_subroutine::case_ok ... ok
[INFO] [stdout] test processor::test::step::instr_draw_sprite::case_clip_xy ... ok
[INFO] [stdout] test processor::test::step::instr_draw_sprite::case_out_of_bounds ... ok
[INFO] [stdout] test processor::test::step::instr_jump_offset ... ok
[INFO] [stdout] test processor::test::step::instr_draw_sprite::case_wrap_xy ... ok
[INFO] [stdout] test processor::test::step::instr_jump ... ok
[INFO] [stdout] test processor::test::step::instr_load_register_values::case_ok ... ok
[INFO] [stdout] test processor::test::step::instr_load_register_values::case_err ... ok
[INFO] [stdout] test processor::test::step::instr_draw_sprite::case_wrap_x_clip_y ... ok
[INFO] [stdout] test processor::test::step::instr_return::case_err ... ok
[INFO] [stdout] test processor::test::step::instr_return::case_ok ... ok
[INFO] [stdout] test processor::test::step::instr_store_bcd::case_err ... ok
[INFO] [stdout] test processor::test::step::instr_store_register_values::case_err ... ok
[INFO] [stdout] test processor::test::step::instr_unknown::case_err ... ok
[INFO] [stdout] test processor::test::step::instr_store_bcd::case_ok ... ok
[INFO] [stdout] test processor::test::step::instr_store_register_values::case_ok ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::add::case_target_is_vf ... ok
[INFO] [stdout] test processor::test::step::instr_unknown::case_skip ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::add::case_no_carry ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::add::case_carry ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::rev_sub::case_borrow ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::shr::case_old_lsb_set ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::and ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::rev_sub::case_target_is_vf ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::xor ... ok
[INFO] [stdout] test processor::test::step::instrs_skip_if::eq::case_eq ... ok
[INFO] [stdout] test processor::test::step::instrs_skip_if_key::not_pressed::case_not_pressed ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::sub::case_no_borrow ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::rev_sub::case_no_borrow ... ok
[INFO] [stdout] test processor::test::step::instrs_skip_if::eq_const::case_eq ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::or ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::shl::case_target_is_vf ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::shl::case_old_msb_set ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::shl::case_old_msb_unset ... ok
[INFO] [stdout] test processor::test::step::instrs_skip_if_key::not_pressed::case_not_a_valid_key ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::shr::case_target_is_vf ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::shr::case_old_lsb_unset ... ok
[INFO] [stdout] test processor::test::step::instrs_skip_if_key::not_pressed::case_pressed ... ok
[INFO] [stdout] test processor::test::step::instrs_skip_if::neq_const::case_neq ... ok
[INFO] [stdout] test processor::test::step::instrs_skip_if_key::pressed::case_pressed ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::sub::case_target_is_vf ... ok
[INFO] [stdout] test processor::test::step::instrs_op_assign::sub::case_borrow ... ok
[INFO] [stdout] test processor::test::step::instrs_skip_if::neq::case_eq ... ok
[INFO] [stdout] test processor::test::step::oob_program_counter ... ok
[INFO] [stdout] test processor::test::step::instrs_skip_if_key::pressed::case_not_a_valid_key ... ok
[INFO] [stdout] test processor::test::step::instrs_skip_if::eq_const::case_neq ... ok
[INFO] [stdout] test processor::test::step::instrs_skip_if::neq::case_neq ... ok
[INFO] [stdout] test processor::test::step::instrs_skip_if_key::pressed::case_not_pressed ... ok
[INFO] [stdout] test processor::test::step::instrs_skip_if::neq_const::case_eq ... ok
[INFO] [stdout] test processor::test::step::instrs_skip_if::eq::case_neq ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 70 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.25s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests crsp-base
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "1bac0f25d229e90fc45629756be901025c9aeb6130079e549c1a7c8e4647ba34", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1bac0f25d229e90fc45629756be901025c9aeb6130079e549c1a7c8e4647ba34", kill_on_drop: false }`
[INFO] [stdout] 1bac0f25d229e90fc45629756be901025c9aeb6130079e549c1a7c8e4647ba34