[INFO] fetching crate nes_rust 0.1.2...
[INFO] testing nes_rust-0.1.2 against master#432e145bd5a974c5b6f4dd9b352891bd7502b69d for pr-87041
[INFO] extracting crate nes_rust 0.1.2 into /workspace/builds/worker-1/source
[INFO] validating manifest of crates.io crate nes_rust 0.1.2 on toolchain 432e145bd5a974c5b6f4dd9b352891bd7502b69d
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate nes_rust 0.1.2
[INFO] finished tweaking crates.io crate nes_rust 0.1.2
[INFO] tweaked toml for crates.io crate nes_rust 0.1.2 written to /workspace/builds/worker-1/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] e2ea6cb7e669f60e6f97704ed2a8257ca9a5212481f05c1b7682d55c2bef2c46
[INFO] running `Command { std: "docker" "start" "-a" "e2ea6cb7e669f60e6f97704ed2a8257ca9a5212481f05c1b7682d55c2bef2c46", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e2ea6cb7e669f60e6f97704ed2a8257ca9a5212481f05c1b7682d55c2bef2c46", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e2ea6cb7e669f60e6f97704ed2a8257ca9a5212481f05c1b7682d55c2bef2c46", kill_on_drop: false }`
[INFO] [stdout] e2ea6cb7e669f60e6f97704ed2a8257ca9a5212481f05c1b7682d55c2bef2c46
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 4c71e09bdeae88f85ac1e37cfe497ca677d64288983b00244626def6ee816b36
[INFO] running `Command { std: "docker" "start" "-a" "4c71e09bdeae88f85ac1e37cfe497ca677d64288983b00244626def6ee816b36", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling nes_rust v0.1.2 (/opt/rustwide/workdir)
[INFO] [stdout] warning: field is never read: `enabled`
[INFO] [stdout]    --> src/apu.rs:885:2
[INFO] [stdout]     |
[INFO] [stdout] 885 |     enabled: bool,
[INFO] [stdout]     |     ^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 1 warning emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.06s
[INFO] running `Command { std: "docker" "inspect" "4c71e09bdeae88f85ac1e37cfe497ca677d64288983b00244626def6ee816b36", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4c71e09bdeae88f85ac1e37cfe497ca677d64288983b00244626def6ee816b36", kill_on_drop: false }`
[INFO] [stdout] 4c71e09bdeae88f85ac1e37cfe497ca677d64288983b00244626def6ee816b36
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a085e62d5bc851494903b291555adfafd2a21d14d9960b227e2f2d7ff6ef3382
[INFO] running `Command { std: "docker" "start" "-a" "a085e62d5bc851494903b291555adfafd2a21d14d9960b227e2f2d7ff6ef3382", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling nes_rust v0.1.2 (/opt/rustwide/workdir)
[INFO] [stdout] warning: field is never read: `enabled`
[INFO] [stdout]    --> src/apu.rs:885:2
[INFO] [stdout]     |
[INFO] [stdout] 885 |     enabled: bool,
[INFO] [stdout]     |     ^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 1 warning emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused variable: `r`
[INFO] [stdout]    --> src/rom.rs:200:7
[INFO] [stdout]     |
[INFO] [stdout] 200 |         let r = Rom::new(vec![0; 17]);
[INFO] [stdout]     |             ^ help: if this is intentional, prefix it with an underscore: `_r`
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_variables)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: field is never read: `enabled`
[INFO] [stdout]    --> src/apu.rs:885:2
[INFO] [stdout]     |
[INFO] [stdout] 885 |     enabled: bool,
[INFO] [stdout]     |     ^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 2 warnings emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 1.64s
[INFO] running `Command { std: "docker" "inspect" "a085e62d5bc851494903b291555adfafd2a21d14d9960b227e2f2d7ff6ef3382", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a085e62d5bc851494903b291555adfafd2a21d14d9960b227e2f2d7ff6ef3382", kill_on_drop: false }`
[INFO] [stdout] a085e62d5bc851494903b291555adfafd2a21d14d9960b227e2f2d7ff6ef3382
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 2995cc0a89b8993aa02bf29011ac23e729773bef236a1a6f52c2f1f5cde9d43e
[INFO] running `Command { std: "docker" "start" "-a" "2995cc0a89b8993aa02bf29011ac23e729773bef236a1a6f52c2f1f5cde9d43e", kill_on_drop: false }`
[INFO] [stderr] warning: field is never read: `enabled`
[INFO] [stderr]    --> src/apu.rs:885:2
[INFO] [stderr]     |
[INFO] [stderr] 885 |     enabled: bool,
[INFO] [stderr]     |     ^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(dead_code)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: 1 warning emitted
[INFO] [stderr] 
[INFO] [stderr] warning: unused variable: `r`
[INFO] [stderr]    --> src/rom.rs:200:7
[INFO] [stderr]     |
[INFO] [stderr] 200 |         let r = Rom::new(vec![0; 17]);
[INFO] [stderr]     |             ^ help: if this is intentional, prefix it with an underscore: `_r`
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(unused_variables)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: field is never read: `enabled`
[INFO] [stderr]    --> src/apu.rs:885:2
[INFO] [stderr]     |
[INFO] [stderr] 885 |     enabled: bool,
[INFO] [stderr]     |     ^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(dead_code)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: 2 warnings emitted
[INFO] [stderr] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.01s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/nes_rust-4aba9444c0868e00)
[INFO] [stdout] 
[INFO] [stdout] running 52 tests
[INFO] [stdout] test mapper::tests_nrom_mapper::initialize ... ok
[INFO] [stdout] test mapper::tests_nrom_mapper::map_with_program_bank_num_1 ... ok
[INFO] [stdout] test memory::tests_memory::capacity ... ok
[INFO] [stdout] test memory::tests_memory::clear ... ok
[INFO] [stdout] test register::tests_register_u16::clear ... ok
[INFO] [stdout] test register::tests_register_u16::add ... ok
[INFO] [stdout] test register::tests_register_u16::clear_bit ... ok
[INFO] [stdout] test register::tests_register_u16::dump ... ok
[INFO] [stdout] test register::tests_register_u16::get_width ... ok
[INFO] [stdout] test register::tests_register_u16::decrement_by_2 ... ok
[INFO] [stdout] test register::tests_register_u16::increment ... ok
[INFO] [stdout] test register::tests_register_u16::increment_by_2 ... ok
[INFO] [stdout] test register::tests_register_u16::initial_value ... ok
[INFO] [stdout] test register::tests_register_u16::is_bit_set ... ok
[INFO] [stdout] test register::tests_register_u16::load ... ok
[INFO] [stdout] test register::tests_register_u16::load_bit ... ok
[INFO] [stdout] test register::tests_register_u16::load_bits ... ok
[INFO] [stdout] test register::tests_register_u16::set_bit ... ok
[INFO] [stdout] test register::tests_register_u16::store_bits ... ok
[INFO] [stdout] test register::tests_register_u16::sub ... ok
[INFO] [stdout] test register::tests_register_u8::add ... ok
[INFO] [stdout] test register::tests_register_u8::clear ... ok
[INFO] [stdout] test register::tests_register_u8::clear_bit ... ok
[INFO] [stdout] test register::tests_register_u16::shift ... ok
[INFO] [stdout] test memory::tests_memory::initialize ... ok
[INFO] [stdout] test register::tests_register_u8::decrement ... ok
[INFO] [stdout] test memory::tests_memory::store_and_load ... ok
[INFO] [stdout] test register::tests_register_u8::load ... ok
[INFO] [stdout] test register::tests_register_u8::dump ... ok
[INFO] [stdout] test register::tests_register_u8::load_bit ... ok
[INFO] [stdout] test register::tests_register_u8::get_width ... ok
[INFO] [stdout] test register::tests_register_u8::load_bits ... ok
[INFO] [stdout] test register::tests_register_u8::set_bit ... ok
[INFO] [stdout] test register::tests_register_u8::store_bit ... ok
[INFO] [stdout] test register::tests_register_u8::store_bits ... ok
[INFO] [stdout] test register::tests_register_u8::decrement_by_2 ... ok
[INFO] [stdout] test register::tests_register_u8::sub ... ok
[INFO] [stdout] test register::tests_register_u8::is_bit_set ... ok
[INFO] [stdout] test rom::tests_rom::store ... ok
[INFO] [stdout] test register::tests_register_u8::initial_value ... ok
[INFO] [stdout] test register::tests_register_u8::increment ... ok
[INFO] [stdout] test register::tests_register_u16::store_bit ... ok
[INFO] [stdout] test register::tests_register_u16::store ... ok
[INFO] [stdout] test register::tests_register_u8::shift ... ok
[INFO] [stdout] test rom::tests_rom::valid ... ok
[INFO] [stdout] test rom::tests_rom::initialize ... ok
[INFO] [stdout] test rom::tests_rom::load ... ok
[INFO] [stderr]    Doc-tests nes_rust
[INFO] [stdout] test register::tests_register_u8::store ... ok
[INFO] [stdout] test register::tests_register_u8::increment_by_2 ... ok
[INFO] [stdout] test register::tests_register_u16::decrement ... ok
[INFO] [stdout] test mapper::tests_nrom_mapper::map_with_program_bank_num_2 ... ok
[INFO] [stdout] test mapper::tests_nrom_mapper::map_for_chr_rom ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 52 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test src/lib.rs - Nes (line 26) ... 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] running `Command { std: "docker" "inspect" "2995cc0a89b8993aa02bf29011ac23e729773bef236a1a6f52c2f1f5cde9d43e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2995cc0a89b8993aa02bf29011ac23e729773bef236a1a6f52c2f1f5cde9d43e", kill_on_drop: false }`
[INFO] [stdout] 2995cc0a89b8993aa02bf29011ac23e729773bef236a1a6f52c2f1f5cde9d43e