[INFO] cloning repository https://github.com/geoffreycopin/chip8
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/geoffreycopin/chip8" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fgeoffreycopin%2Fchip8", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fgeoffreycopin%2Fchip8'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] b873ea95101fd02a25461da205de3ba9d3ac79ec
[INFO] testing geoffreycopin/chip8 against try#722e1797249a965b6335aebd65d777f917e498f1 for pr-91031
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fgeoffreycopin%2Fchip8" "/workspace/builds/worker-59/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-59/source'...
[INFO] [stderr] done.
[INFO] [stderr] Updating files:  47% (259/547)
Updating files:  48% (263/547)
Updating files:  49% (269/547)
Updating files:  49% (272/547)
Updating files:  50% (274/547)
Updating files:  51% (279/547)
Updating files:  51% (282/547)
Updating files:  52% (285/547)
Updating files:  52% (286/547)
Updating files:  53% (290/547)
Updating files:  54% (296/547)
Updating files:  55% (301/547)
Updating files:  56% (307/547)
Updating files:  57% (312/547)
Updating files:  58% (318/547)
Updating files:  59% (323/547)
Updating files:  60% (329/547)
Updating files:  61% (334/547)
Updating files:  62% (340/547)
Updating files:  63% (345/547)
Updating files:  64% (351/547)
Updating files:  65% (356/547)
Updating files:  66% (362/547)
Updating files:  67% (367/547)
Updating files:  68% (372/547)
Updating files:  69% (378/547)
Updating files:  70% (383/547)
Updating files:  71% (389/547)
Updating files:  72% (394/547)
Updating files:  73% (400/547)
Updating files:  74% (405/547)
Updating files:  75% (411/547)
Updating files:  76% (416/547)
Updating files:  77% (422/547)
Updating files:  78% (427/547)
Updating files:  79% (433/547)
Updating files:  80% (438/547)
Updating files:  81% (444/547)
Updating files:  82% (449/547)
Updating files:  83% (455/547)
Updating files:  84% (460/547)
Updating files:  85% (465/547)
Updating files:  86% (471/547)
Updating files:  87% (476/547)
Updating files:  88% (482/547)
Updating files:  89% (487/547)
Updating files:  90% (493/547)
Updating files:  91% (498/547)
Updating files:  92% (504/547)
Updating files:  93% (509/547)
Updating files:  94% (515/547)
Updating files:  95% (520/547)
Updating files:  96% (526/547)
Updating files:  97% (531/547)
Updating files:  98% (537/547)
Updating files:  99% (542/547)
Updating files: 100% (547/547)
Updating files: 100% (547/547), done.
[INFO] validating manifest of git repo https://github.com/geoffreycopin/chip8 on toolchain 722e1797249a965b6335aebd65d777f917e498f1
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/geoffreycopin/chip8
[INFO] finished tweaking git repo https://github.com/geoffreycopin/chip8
[INFO] tweaked toml for git repo https://github.com/geoffreycopin/chip8 written to /workspace/builds/worker-59/source/Cargo.toml
[INFO] crate git repo https://github.com/geoffreycopin/chip8 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "fetch" "--manifest-path" "Cargo.toml", 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]  Downloading crates ...
[INFO] [stderr]   Downloaded rand v0.5.3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-59/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-59/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 7990aefc1d55e648d9fd6c348396b8cec21de5196c6654289b1b9728cd1141a5
[INFO] running `Command { std: "docker" "start" "-a" "7990aefc1d55e648d9fd6c348396b8cec21de5196c6654289b1b9728cd1141a5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7990aefc1d55e648d9fd6c348396b8cec21de5196c6654289b1b9728cd1141a5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7990aefc1d55e648d9fd6c348396b8cec21de5196c6654289b1b9728cd1141a5", kill_on_drop: false }`
[INFO] [stdout] 7990aefc1d55e648d9fd6c348396b8cec21de5196c6654289b1b9728cd1141a5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-59/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-59/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 412b61db6fc0a37c0ba1b4906d808168914df762bc8850f864fc5c8413a0a10a
[INFO] running `Command { std: "docker" "start" "-a" "412b61db6fc0a37c0ba1b4906d808168914df762bc8850f864fc5c8413a0a10a", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling rand_core v0.2.1
[INFO] [stderr]    Compiling rand v0.5.3
[INFO] [stderr]    Compiling chip8_emulator v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.62s
[INFO] running `Command { std: "docker" "inspect" "412b61db6fc0a37c0ba1b4906d808168914df762bc8850f864fc5c8413a0a10a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "412b61db6fc0a37c0ba1b4906d808168914df762bc8850f864fc5c8413a0a10a", kill_on_drop: false }`
[INFO] [stdout] 412b61db6fc0a37c0ba1b4906d808168914df762bc8850f864fc5c8413a0a10a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-59/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-59/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b0d755793e6eebfae93aaa153e23f47631fe5c4fc00e7ed2f3f3551356413981
[INFO] running `Command { std: "docker" "start" "-a" "b0d755793e6eebfae93aaa153e23f47631fe5c4fc00e7ed2f3f3551356413981", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling chip8_emulator v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 3.75s
[INFO] running `Command { std: "docker" "inspect" "b0d755793e6eebfae93aaa153e23f47631fe5c4fc00e7ed2f3f3551356413981", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b0d755793e6eebfae93aaa153e23f47631fe5c4fc00e7ed2f3f3551356413981", kill_on_drop: false }`
[INFO] [stdout] b0d755793e6eebfae93aaa153e23f47631fe5c4fc00e7ed2f3f3551356413981
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-59/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-59/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] ba9c84aeb2d68a585b18d5fb44f497fd4443e13233977794a381c1974801babe
[INFO] running `Command { std: "docker" "start" "-a" "ba9c84aeb2d68a585b18d5fb44f497fd4443e13233977794a381c1974801babe", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/chip8_emulator-f84cf28328e32519)
[INFO] [stdout] 
[INFO] [stdout] running 96 tests
[INFO] [stdout] test cpu::test::add_to_i ... ok
[INFO] [stdout] test cpu::test::add ... ok
[INFO] [stdout] test cpu::test::add_reg_overflow ... ok
[INFO] [stdout] test cpu::test::and ... ok
[INFO] [stdout] test cpu::test::call ... ok
[INFO] [stdout] test cpu::test::add_reg ... ok
[INFO] [stdout] test cpu::test::jp_reg_i ... ok
[INFO] [stdout] test cpu::test::ld ... ok
[INFO] [stdout] test cpu::test::ld_dt ... ok
[INFO] [stdout] test cpu::test::ld_bcd ... ok
[INFO] [stdout] test cpu::test::ld_kb ... ok
[INFO] [stdout] test cpu::test::load_registers ... ok
[INFO] [stdout] test cpu::test::jp ... ok
[INFO] [stdout] test cpu::test::or ... ok
[INFO] [stdout] test cpu::test::load_reg ... ok
[INFO] [stdout] test cpu::test::load_chr_sprite_addr ... ok
[INFO] [stdout] test cpu::test::se_not_equals ... ok
[INFO] [stdout] test cpu::test::new ... ok
[INFO] [stdout] test cpu::test::ret ... ok
[INFO] [stdout] test cpu::test::se ... ok
[INFO] [stdout] test cpu::test::load_program ... ok
[INFO] [stdout] test cpu::test::ldi ... ok
[INFO] [stdout] test cpu::test::read_memory ... ok
[INFO] [stdout] test cpu::test::se_reg_not_equals ... ok
[INFO] [stdout] test cpu::test::set_dt ... ok
[INFO] [stdout] test cpu::test::se_reg ... ok
[INFO] [stdout] test cpu::test::read_opcode ... ok
[INFO] [stdout] test cpu::test::call_invalid_address - should panic ... ok
[INFO] [stdout] test cpu::test::add_to_i_overflow - should panic ... ok
[INFO] [stdout] test cpu::test::call_invalid_pc - should panic ... ok
[INFO] [stdout] test cpu::test::shl_carry ... ok
[INFO] [stdout] test cpu::test::shr_carry ... ok
[INFO] [stdout] test cpu::test::load_registers_overflow - should panic ... ok
[INFO] [stdout] test cpu::test::set_set ... ok
[INFO] [stdout] test cpu::test::load_chr_sprite_addr_non_existing - should panic ... ok
[INFO] [stdout] test cpu::test::sne_reg_equals ... ok
[INFO] [stdout] test cpu::test::read_memory_overflow - should panic ... ok
[INFO] [stdout] test cpu::test::sne_equals ... ok
[INFO] [stdout] test cpu::test::sne_reg ... ok
[INFO] [stdout] test cpu::test::sub ... ok
[INFO] [stdout] test cpu::test::shl ... ok
[INFO] [stdout] test cpu::test::sknp ... ok
[INFO] [stdout] test cpu::test::sne ... ok
[INFO] [stdout] test cpu::test::sub_overflow ... ok
[INFO] [stdout] test cpu::test::subn_overflow ... ok
[INFO] [stdout] test cpu::test::ret_when_sp_is_0 - should panic ... ok
[INFO] [stdout] test cpu::test::subn ... ok
[INFO] [stdout] test cpu::test::skp_not_pressed ... ok
[INFO] [stdout] test cpu::test::skp ... ok
[INFO] [stdout] test cpu::test::shr ... ok
[INFO] [stdout] test keypad::test::key_down ... ok
[INFO] [stdout] test keypad::test::invalid_maping_code ... ok
[INFO] [stdout] test cpu::test::xor ... ok
[INFO] [stdout] test cpu::test::skp_pressed ... ok
[INFO] [stdout] test cpu::test::update_timers ... ok
[INFO] [stdout] test cpu::test::update_timers_when_zero ... ok
[INFO] [stdout] test keypad::test::key_up ... ok
[INFO] [stdout] test opcodes::test::add ... ok
[INFO] [stdout] test opcodes::test::add_to_i ... ok
[INFO] [stdout] test opcodes::test::jp ... ok
[INFO] [stdout] test opcodes::test::cls ... ok
[INFO] [stdout] test opcodes::test::ld ... ok
[INFO] [stdout] test opcodes::test::add_reg ... ok
[INFO] [stdout] test opcodes::test::ld_bcd ... ok
[INFO] [stdout] test opcodes::test::ld_chr ... ok
[INFO] [stdout] test opcodes::test::call ... ok
[INFO] [stdout] test opcodes::test::and ... ok
[INFO] [stdout] test opcodes::test::drw ... ok
[INFO] [stdout] test opcodes::test::ld_kb ... ok
[INFO] [stdout] test opcodes::test::ld_reg ... ok
[INFO] [stdout] test opcodes::test::or ... ok
[INFO] [stdout] test opcodes::test::set_st ... ok
[INFO] [stdout] test opcodes::test::jp_reg_i ... ok
[INFO] [stdout] test opcodes::test::ldi ... ok
[INFO] [stdout] test opcodes::test::ld_dt ... ok
[INFO] [stdout] test opcodes::test::rd_mem ... ok
[INFO] [stdout] test opcodes::test::rand ... ok
[INFO] [stdout] test opcodes::test::shl ... ok
[INFO] [stdout] test opcodes::test::set_dt ... ok
[INFO] [stdout] test opcodes::test::ret ... ok
[INFO] [stdout] test opcodes::test::se ... ok
[INFO] [stdout] test opcodes::test::se_reg ... ok
[INFO] [stdout] test opcodes::test::shr ... ok
[INFO] [stdout] test opcodes::test::skp ... ok
[INFO] [stdout] test opcodes::test::ld_regs ... ok
[INFO] [stdout] test opcodes::test::sne_reg ... ok
[INFO] [stdout] test opcodes::test::sknp ... ok
[INFO] [stdout] test opcodes::test::xor ... ok
[INFO] [stdout] test opcodes::test::sne ... ok
[INFO] [stdout] test opcodes::test::sub ... ok
[INFO] [stdout] test screen::test::turn_on_collision ... ok
[INFO] [stdout] test opcodes::test::subn ... ok
[INFO] [stdout] test screen::test::new ... ok
[INFO] [stdout] test screen::test::clear ... ok
[INFO] [stdout] test screen::test::turn_on ... ok
[INFO] [stdout] test screen::test::turn_off ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 96 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.62s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "ba9c84aeb2d68a585b18d5fb44f497fd4443e13233977794a381c1974801babe", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ba9c84aeb2d68a585b18d5fb44f497fd4443e13233977794a381c1974801babe", kill_on_drop: false }`
[INFO] [stdout] ba9c84aeb2d68a585b18d5fb44f497fd4443e13233977794a381c1974801babe