Dec 05 05:11:27.960 INFO testing taylorcoreyd/chip8 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 05 05:11:27.960 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 05 05:11:28.402 INFO [stdout] db9e28143a294ded9e00a7f820d250251990a32140161c564880ff37271089e2
Dec 05 05:11:28.404 INFO running `"docker" "start" "-a" "db9e28143a294ded9e00a7f820d250251990a32140161c564880ff37271089e2"`
Dec 05 05:11:29.796 INFO [stderr] usermod: no changes
Dec 05 05:11:30.166 INFO [stderr]    Compiling num-traits v0.2.4
Dec 05 05:11:30.166 INFO [stderr]    Compiling num-integer v0.1.38
Dec 05 05:11:30.166 INFO [stderr]    Compiling serde v1.0.66
Dec 05 05:11:30.172 INFO [stderr]    Compiling phf_shared v0.7.22
Dec 05 05:11:30.172 INFO [stderr]    Compiling rand v0.4.2
Dec 05 05:11:30.172 INFO [stderr]    Compiling num_cpus v1.8.0
Dec 05 05:11:30.172 INFO [stderr]    Compiling log v0.4.2
Dec 05 05:11:30.172 INFO [stderr]    Compiling itertools v0.5.10
Dec 05 05:11:34.000 INFO [stderr]    Compiling xml-rs v0.7.0
Dec 05 05:11:34.000 INFO [stderr]    Compiling x11-dl v2.18.0
Dec 05 05:11:34.024 INFO [stderr]    Compiling syn v0.10.8
Dec 05 05:11:34.064 INFO [stderr]    Compiling vecmath v0.3.1
Dec 05 05:11:34.100 INFO [stderr]    Compiling draw_state v0.7.1
Dec 05 05:11:34.285 INFO [stderr]    Compiling deflate v0.7.18
Dec 05 05:11:37.417 INFO [stderr]    Compiling stb_truetype v0.2.2
Dec 05 05:11:38.332 INFO [stderr]    Compiling rand v0.5.1
Dec 05 05:11:42.885 INFO [stderr]    Compiling crossbeam-epoch v0.3.1
Dec 05 05:11:42.904 INFO [stderr]    Compiling osmesa-sys v0.1.2
Dec 05 05:11:42.908 INFO [stderr]    Compiling phf v0.7.22
Dec 05 05:11:42.937 INFO [stderr]    Compiling log v0.3.9
Dec 05 05:11:45.892 INFO [stderr]    Compiling piston2d-graphics v0.21.1
Dec 05 05:11:45.898 INFO [stderr]    Compiling rusttype v0.2.3
Dec 05 05:11:48.341 INFO [stderr]    Compiling syn v0.14.2
Dec 05 05:11:48.424 INFO [stderr]    Compiling phf_generator v0.7.22
Dec 05 05:11:48.485 INFO [stderr]    Compiling rand v0.3.22
Dec 05 05:11:49.036 INFO [stderr]    Compiling gl_generator v0.5.5
Dec 05 05:12:08.928 INFO [stderr]    Compiling crossbeam-deque v0.2.0
Dec 05 05:12:09.651 INFO [stderr]    Compiling fs2 v0.2.5
Dec 05 05:12:09.808 INFO [stderr]    Compiling gl_generator v0.7.0
Dec 05 05:12:09.830 INFO [stderr]    Compiling gl_generator v0.6.1
Dec 05 05:12:10.448 INFO [stderr]    Compiling phf_codegen v0.7.22
Dec 05 05:12:11.162 INFO [stderr]    Compiling tempfile v2.2.0
Dec 05 05:12:11.604 INFO [stderr]    Compiling num-traits v0.1.43
Dec 05 05:12:12.329 INFO [stderr]    Compiling glutin v0.9.2
Dec 05 05:12:30.160 INFO [stderr]    Compiling derivative v1.0.0
Dec 05 05:12:30.164 INFO [stderr]    Compiling memmap v0.4.0
Dec 05 05:12:30.306 INFO [stderr]    Compiling rayon-core v1.4.0
Dec 05 05:12:30.318 INFO [stderr]    Compiling target_build_utils v0.3.1
Dec 05 05:12:30.321 INFO [stderr]    Compiling serde_json v0.9.10
Dec 05 05:12:31.102 INFO [stderr]    Compiling enum_primitive v0.1.1
Dec 05 05:12:31.840 INFO [stderr]    Compiling num-iter v0.1.37
Dec 05 05:12:32.169 INFO [stderr]    Compiling num-rational v0.1.42
Dec 05 05:12:33.288 INFO [stderr]    Compiling gfx_gl v0.4.0
Dec 05 05:12:33.356 INFO [stderr]    Compiling serde_derive v1.0.66
Dec 05 05:12:48.240 INFO [stderr]    Compiling gl v0.6.5
Dec 05 05:12:48.756 INFO [stderr]    Compiling png v0.9.0
Dec 05 05:12:48.884 INFO [stderr]    Compiling rayon v1.0.1
Dec 05 05:13:05.292 INFO [stderr]    Compiling gfx_core v0.7.2
Dec 05 05:13:11.176 INFO [stderr]    Compiling jpeg-decoder v0.1.15
Dec 05 05:13:34.948 INFO [stderr]    Compiling image v0.15.0
Dec 05 05:13:40.740 INFO [stderr]    Compiling pistoncore-input v0.19.0
Dec 05 05:13:40.757 INFO [stderr]    Compiling pistoncore-input v0.21.0
Dec 05 05:13:40.916 INFO [stderr]    Compiling libloading v0.3.4
Dec 05 05:13:49.390 INFO [stderr]    Compiling dlib v0.3.1
Dec 05 05:13:50.218 INFO [stderr]    Compiling wayland-sys v0.9.10
Dec 05 05:13:55.314 INFO [stderr]    Compiling wayland-client v0.9.10
Dec 05 05:13:57.973 INFO [stderr]    Compiling pistoncore-window v0.32.0
Dec 05 05:14:00.770 INFO [stderr]    Compiling pistoncore-window v0.28.0
Dec 05 05:14:01.346 INFO [stderr]    Compiling pistoncore-event_loop v0.37.0
Dec 05 05:14:03.142 INFO [stderr]    Compiling pistoncore-event_loop v0.33.0
Dec 05 05:14:03.474 INFO [stderr]    Compiling piston v0.37.0
Dec 05 05:14:07.524 INFO [stderr]    Compiling piston v0.33.0
Dec 05 05:14:07.764 INFO [stderr]    Compiling wayland-protocols v0.9.10
Dec 05 05:14:11.576 INFO [stderr]    Compiling wayland-kbd v0.9.1
Dec 05 05:14:21.176 INFO [stderr]    Compiling wayland-window v0.7.0
Dec 05 05:14:22.981 INFO [stderr]    Compiling winit v0.7.6
Dec 05 05:14:37.180 INFO [stderr]    Compiling pistoncore-glutin_window v0.39.1
Dec 05 05:14:43.928 INFO [stderr]    Compiling gfx v0.16.3
Dec 05 05:14:43.928 INFO [stderr]    Compiling gfx_device_gl v0.14.6
Dec 05 05:14:53.336 INFO [stderr]    Compiling piston-gfx_texture v0.26.0
Dec 05 05:14:54.593 INFO [stderr]    Compiling piston2d-gfx_graphics v0.43.0
Dec 05 05:14:57.677 INFO [stderr]    Compiling piston_window v0.70.0
Dec 05 05:14:58.957 INFO [stderr]    Compiling chip8 v0.1.0 (/source)
Dec 05 05:15:06.773 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3m 36s
Dec 05 05:15:06.792 INFO [stderr] su: No module specific data is present
Dec 05 05:15:07.636 INFO running `"docker" "inspect" "db9e28143a294ded9e00a7f820d250251990a32140161c564880ff37271089e2"`
Dec 05 05:15:08.008 INFO running `"docker" "rm" "-f" "db9e28143a294ded9e00a7f820d250251990a32140161c564880ff37271089e2"`
Dec 05 05:15:08.300 INFO [stdout] db9e28143a294ded9e00a7f820d250251990a32140161c564880ff37271089e2
Dec 05 05:15:08.308 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 05 05:15:08.660 INFO [stdout] 79646c38c441f46f18398f2bdd5586b82ebceda46e77f8da2efc2a2e0da41544
Dec 05 05:15:08.663 INFO running `"docker" "start" "-a" "79646c38c441f46f18398f2bdd5586b82ebceda46e77f8da2efc2a2e0da41544"`
Dec 05 05:15:10.056 INFO [stderr] usermod: no changes
Dec 05 05:15:10.360 INFO [stderr]    Compiling chip8 v0.1.0 (/source)
Dec 05 05:15:14.332 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.24s
Dec 05 05:15:14.356 INFO [stderr] su: No module specific data is present
Dec 05 05:15:15.114 INFO running `"docker" "inspect" "79646c38c441f46f18398f2bdd5586b82ebceda46e77f8da2efc2a2e0da41544"`
Dec 05 05:15:15.476 INFO running `"docker" "rm" "-f" "79646c38c441f46f18398f2bdd5586b82ebceda46e77f8da2efc2a2e0da41544"`
Dec 05 05:15:15.700 INFO [stdout] 79646c38c441f46f18398f2bdd5586b82ebceda46e77f8da2efc2a2e0da41544
Dec 05 05:15:15.708 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 05 05:15:16.110 INFO [stdout] ab626572b810189f7d1bb4faf5a6ff1399b648a3ca37a23ed7592548efd1eb6b
Dec 05 05:15:16.120 INFO running `"docker" "start" "-a" "ab626572b810189f7d1bb4faf5a6ff1399b648a3ca37a23ed7592548efd1eb6b"`
Dec 05 05:15:17.296 INFO [stderr] usermod: no changes
Dec 05 05:15:17.506 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.19s
Dec 05 05:15:17.535 INFO [stderr]      Running /target/debug/deps/chip8-8053f35d3c833a1d
Dec 05 05:15:17.551 INFO [stdout] 
Dec 05 05:15:17.551 INFO [stdout] running 53 tests
Dec 05 05:15:17.584 INFO [stdout] test cpu::tests::test_add_i_vx ... ok
Dec 05 05:15:17.584 INFO [stdout] test cpu::tests::test_add_vx_vy_overflow ... ok
Dec 05 05:15:17.584 INFO [stdout] test cpu::tests::test_and ... ok
Dec 05 05:15:17.584 INFO [stdout] test cpu::tests::test_add_vx_byte_overflow ... ok
Dec 05 05:15:17.596 INFO [stdout] test cpu::tests::test_execute_call ... ok
Dec 05 05:15:17.600 INFO [stdout] test cpu::tests::test_execute_jp ... ok
Dec 05 05:15:17.600 INFO [stdout] test cpu::tests::test_add_vx_vy ... ok
Dec 05 05:15:17.600 INFO [stdout] test cpu::tests::test_add_vx_byte ... ok
Dec 05 05:15:17.600 INFO [stdout] test cpu::tests::test_execute_ret ... ok
Dec 05 05:15:17.600 INFO [stdout] test cpu::tests::test_drw_vx_vy_n_erases ... ok
Dec 05 05:15:17.600 INFO [stdout] test cpu::tests::test_drw_vx_vy_n_unsets_flip ... ok
Dec 05 05:15:17.600 INFO [stdout] test cpu::tests::test_drw_vx_vy_n_sets_flip ... ok
Dec 05 05:15:17.600 INFO [stdout] test cpu::tests::test_ld_vx_i ... ok
Dec 05 05:15:17.600 INFO [stdout] test cpu::tests::test_ld_vx_k ... ok
Dec 05 05:15:17.600 INFO [stdout] test cpu::tests::test_ld_vx_vy ... ok
Dec 05 05:15:17.600 INFO [stdout] test cpu::tests::test_loading_bytes_from_vector ... ok
Dec 05 05:15:17.600 INFO [stdout] test cpu::tests::test_op_cls ... ok
Dec 05 05:15:17.600 INFO [stdout] test cpu::tests::test_op_jp ... ok
Dec 05 05:15:17.600 INFO [stdout] test cpu::tests::test_op_jp_v0_addr ... ok
Dec 05 05:15:17.600 INFO [stdout] test cpu::tests::test_or ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_rnd_vx_byte_masks_binary ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_ld_b_vx ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_ld_dt_vx ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_ld_i_vx ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_ld_vx_byte ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_ld_i_addr ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_fetching_opcode ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_se_if_true ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_se_if_false ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_se_vs_if_false ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_se_vs_if_true ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_shl_carry_0 ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_shl_carry_1 ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_shl_shift_x ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_shl_shift_y_to_x ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_shr_carry_0 ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_shr_carry_1 ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_sknp_vx_if_not_pressed ... ok
Dec 05 05:15:17.613 INFO [stdout] test cpu::tests::test_skp_vx_if_pressed ... ok
Dec 05 05:15:17.614 INFO [stdout] test cpu::tests::test_sknp_vx_if_pressed ... ok
Dec 05 05:15:17.614 INFO [stdout] test cpu::tests::test_skp_vx_if_not_pressed ... ok
Dec 05 05:15:17.614 INFO [stdout] test cpu::tests::test_sne_if_false ... ok
Dec 05 05:15:17.614 INFO [stdout] test cpu::tests::test_sne_if_true ... ok
Dec 05 05:15:17.614 INFO [stdout] test cpu::tests::test_sne_vx_vy_if_equal ... ok
Dec 05 05:15:17.614 INFO [stdout] test cpu::tests::test_sub_vx_vy ... ok
Dec 05 05:15:17.614 INFO [stdout] test cpu::tests::test_sne_vx_vy_if_not_equal ... ok
Dec 05 05:15:17.614 INFO [stdout] test cpu::tests::test_sub_vx_vy_overflow ... ok
Dec 05 05:15:17.614 INFO [stdout] test cpu::tests::test_subn_vx_vy ... ok
Dec 05 05:15:17.614 INFO [stdout] test cpu::tests::test_subn_vx_vy_overflow ... ok
Dec 05 05:15:17.620 INFO [stdout] test cpu::tests::test_xor ... ok
Dec 05 05:15:17.628 INFO [stdout] test cpu::tests::test_shr_shift_y_to_x ... ok
Dec 05 05:15:17.628 INFO [stdout] test cpu::tests::test_shr_shift_x ... ok
Dec 05 05:15:17.637 INFO [stdout] test cpu::tests::test_timers ... ok
Dec 05 05:15:17.637 INFO [stdout] 
Dec 05 05:15:17.637 INFO [stdout] test result: ok. 53 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:15:17.637 INFO [stdout] 
Dec 05 05:15:17.656 INFO [stderr] su: No module specific data is present
Dec 05 05:15:18.323 INFO running `"docker" "inspect" "ab626572b810189f7d1bb4faf5a6ff1399b648a3ca37a23ed7592548efd1eb6b"`
Dec 05 05:15:18.648 INFO running `"docker" "rm" "-f" "ab626572b810189f7d1bb4faf5a6ff1399b648a3ca37a23ed7592548efd1eb6b"`
Dec 05 05:15:18.912 INFO [stdout] ab626572b810189f7d1bb4faf5a6ff1399b648a3ca37a23ed7592548efd1eb6b