Dec 13 08:45:42.307 INFO testing nnarain/silica against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 13 08:45:42.307 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 13 08:45:42.900 INFO [stdout] cc4b43165694b9253d32f1f65d9299c4c2bf7f45366e9ba1ec863b0e000b8c6e
Dec 13 08:45:42.904 INFO running `"docker" "start" "-a" "cc4b43165694b9253d32f1f65d9299c4c2bf7f45366e9ba1ec863b0e000b8c6e"`
Dec 13 08:45:43.823 INFO [stderr] usermod: no changes
Dec 13 08:45:43.935 INFO [stderr]    Compiling libc v0.2.29
Dec 13 08:45:45.215 INFO [stderr]    Compiling memchr v1.0.1
Dec 13 08:45:45.755 INFO [stderr]    Compiling aho-corasick v0.6.3
Dec 13 08:45:45.756 INFO [stderr]    Compiling nom v3.2.0
Dec 13 08:45:47.027 INFO [stderr]    Compiling regex v0.2.2
Dec 13 08:45:56.403 INFO [stderr]    Compiling docopt v0.8.1
Dec 13 08:46:01.372 INFO [stderr]    Compiling silica v0.1.0 (/source)
Dec 13 08:46:08.376 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 24.52s
Dec 13 08:46:08.381 INFO [stderr] su: No module specific data is present
Dec 13 08:46:08.999 INFO running `"docker" "inspect" "cc4b43165694b9253d32f1f65d9299c4c2bf7f45366e9ba1ec863b0e000b8c6e"`
Dec 13 08:46:09.289 INFO running `"docker" "rm" "-f" "cc4b43165694b9253d32f1f65d9299c4c2bf7f45366e9ba1ec863b0e000b8c6e"`
Dec 13 08:46:09.598 INFO [stdout] cc4b43165694b9253d32f1f65d9299c4c2bf7f45366e9ba1ec863b0e000b8c6e
Dec 13 08:46:09.611 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 13 08:46:10.097 INFO [stdout] f5cb5f3892a87246eff118faae27ca7c0a9d1f5644cee11746a4151a1cbed999
Dec 13 08:46:10.103 INFO running `"docker" "start" "-a" "f5cb5f3892a87246eff118faae27ca7c0a9d1f5644cee11746a4151a1cbed999"`
Dec 13 08:46:11.457 INFO [stderr] usermod: no changes
Dec 13 08:46:11.613 INFO [stderr]    Compiling silica v0.1.0 (/source)
Dec 13 08:46:17.928 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.44s
Dec 13 08:46:17.933 INFO [stderr] su: No module specific data is present
Dec 13 08:46:18.675 INFO running `"docker" "inspect" "f5cb5f3892a87246eff118faae27ca7c0a9d1f5644cee11746a4151a1cbed999"`
Dec 13 08:46:18.995 INFO running `"docker" "rm" "-f" "f5cb5f3892a87246eff118faae27ca7c0a9d1f5644cee11746a4151a1cbed999"`
Dec 13 08:46:19.199 INFO [stdout] f5cb5f3892a87246eff118faae27ca7c0a9d1f5644cee11746a4151a1cbed999
Dec 13 08:46:19.201 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 13 08:46:19.699 INFO [stdout] 021400533d8e473a70b61f30b388a943680286d2b2ba3a126aa90dd0f4e1464f
Dec 13 08:46:19.705 INFO running `"docker" "start" "-a" "021400533d8e473a70b61f30b388a943680286d2b2ba3a126aa90dd0f4e1464f"`
Dec 13 08:46:20.711 INFO [stderr] usermod: no changes
Dec 13 08:46:20.845 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Dec 13 08:46:20.848 INFO [stderr]      Running /target/debug/deps/silica-a7f4edc71c376e13
Dec 13 08:46:20.871 INFO [stdout] 
Dec 13 08:46:20.871 INFO [stdout] running 103 tests
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_add3 ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_add2 ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_add1 ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_ld10 ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_ld1 ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_and ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_jp_instruction ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_ld11 ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_db_directive ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_drw ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_ld7 ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_ld8 ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_ld9 ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_ld_i_addr ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_or ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_rnd ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_sh1 ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_shr ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_sknp ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_skp ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_sub ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_subn ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_xor ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::lexer::tests::test_label_operand ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::lexer::tests::test_lex_blank_line ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::lexer::tests::test_lex_column_sep_parse_all ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::lexer::tests::test_lex_column_sep_parse_until ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::lexer::tests::test_lex_comma ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::lexer::tests::test_lex_comments ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::lexer::tests::test_lex_decimal_literal ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::lexer::tests::test_lex_directives ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::lexer::tests::test_lex_hex_literal ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::lexer::tests::test_lex_instruction1 ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::lexer::tests::test_lex_instruction2 ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::lexer::tests::test_lex_instruction3 ... ok
Dec 13 08:46:20.883 INFO [stdout] test assembler::codegenerator::tests::test_ld2 ... ok
Dec 13 08:46:20.895 INFO [stdout] test assembler::lexer::tests::test_lex_label ... ok
Dec 13 08:46:20.895 INFO [stdout] test assembler::codegenerator::tests::test_ld5 ... ok
Dec 13 08:46:20.895 INFO [stdout] test assembler::codegenerator::tests::test_ld4 ... ok
Dec 13 08:46:20.895 INFO [stdout] test assembler::codegenerator::tests::test_ld6 ... ok
Dec 13 08:46:20.895 INFO [stdout] test assembler::lexer::tests::test_lex_instruction6 ... ok
Dec 13 08:46:20.895 INFO [stdout] test assembler::lexer::tests::test_lex_instruction4 ... ok
Dec 13 08:46:20.895 INFO [stdout] test assembler::codegenerator::tests::test_ld3 ... ok
Dec 13 08:46:20.895 INFO [stdout] test assembler::lexer::tests::test_lex_line1_crlf ... ok
Dec 13 08:46:20.895 INFO [stdout] test assembler::lexer::tests::test_lex_line1_lf ... ok
Dec 13 08:46:20.895 INFO [stdout] test assembler::lexer::tests::test_lex_line4 ... ok
Dec 13 08:46:20.895 INFO [stdout] test assembler::lexer::tests::test_lex_line3 ... ok
Dec 13 08:46:20.895 INFO [stdout] test assembler::lexer::tests::test_lex_line7 ... ok
Dec 13 08:46:20.895 INFO [stdout] test assembler::lexer::tests::test_lex_line5 ... ok
Dec 13 08:46:20.895 INFO [stdout] test assembler::lexer::tests::test_lex_line6 ... ok
Dec 13 08:46:20.895 INFO [stdout] test assembler::lexer::tests::test_lex_instruction5 ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::lexer::tests::test_lex_lines2 ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::lexer::tests::test_lex_lines3 ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::lexer::tests::test_lex_lines5 ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::lexer::tests::test_lex_lines4 ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::lexer::tests::test_lex_mnem ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::lexer::tests::test_lex_numeric_literal_parse_decimal ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::lexer::tests::test_lex_registers ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::lexer::tests::test_lex_numeric_literal_parse_hex ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::parser::tests::test_parse2 ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::parser::tests::test_parse1 ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::parser::tests::test_parse_directive2 ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::parser::tests::test_parse_directive1 ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::parser::tests::test_parse_instruction2 ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::parser::tests::test_parse_instruction1 ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::parser::tests::test_parse_instruction3 ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::parser::tests::test_parse_instruction4 ... ok
Dec 13 08:46:20.897 INFO [stdout] test assembler::parser::tests::test_parse_label ... ok
Dec 13 08:46:20.903 INFO [stdout] test assembler::parser::tests::test_parse_instruction5 ... ok
Dec 13 08:46:20.903 INFO [stdout] test assembler::semantics::tests::test_check_add1 ... ok
Dec 13 08:46:20.903 INFO [stdout] test assembler::semantics::tests::test_check_add2 ... ok
Dec 13 08:46:20.903 INFO [stdout] test assembler::semantics::tests::test_check_and ... ok
Dec 13 08:46:20.903 INFO [stdout] test assembler::semantics::tests::test_check_directive ... ok
Dec 13 08:46:20.915 INFO [stdout] test assembler::lexer::tests::test_lex_lines1 ... ok
Dec 13 08:46:20.915 INFO [stdout] test assembler::lexer::tests::test_lex_line_termination2 ... ok
Dec 13 08:46:20.915 INFO [stdout] test assembler::lexer::tests::test_lex_line_termination3 ... ok
Dec 13 08:46:20.915 INFO [stdout] test assembler::lexer::tests::test_lex_line_termination1 ... ok
Dec 13 08:46:20.915 INFO [stdout] test assembler::lexer::tests::test_lex_line_ending_lf ... ok
Dec 13 08:46:20.915 INFO [stdout] test assembler::lexer::tests::test_lex_line_ending_crlf ... ok
Dec 13 08:46:20.915 INFO [stdout] test assembler::semantics::tests::test_check_instruction1 ... ok
Dec 13 08:46:20.915 INFO [stdout] test assembler::semantics::tests::test_check_jp1 ... ok
Dec 13 08:46:20.915 INFO [stdout] test assembler::semantics::tests::test_check_drw ... ok
Dec 13 08:46:20.915 INFO [stdout] test assembler::semantics::tests::test_check_ld1 ... ok
Dec 13 08:46:20.915 INFO [stdout] test assembler::semantics::tests::test_check_or ... ok
Dec 13 08:46:20.915 INFO [stdout] test assembler::semantics::tests::test_check_rnd ... ok
Dec 13 08:46:20.923 INFO [stdout] test assembler::semantics::tests::test_check_ld2 ... ok
Dec 13 08:46:20.923 INFO [stdout] test assembler::semantics::tests::test_check_se2 ... ok
Dec 13 08:46:20.923 INFO [stdout] test assembler::semantics::tests::test_check_shl ... ok
Dec 13 08:46:20.923 INFO [stdout] test assembler::semantics::tests::test_check_shr ... ok
Dec 13 08:46:20.923 INFO [stdout] test assembler::semantics::tests::test_check_sknp ... ok
Dec 13 08:46:20.943 INFO [stdout] test assembler::semantics::tests::test_check_se1 ... ok
Dec 13 08:46:20.943 INFO [stdout] test assembler::semantics::tests::test_check_jr ... ok
Dec 13 08:46:20.943 INFO [stdout] test assembler::semantics::tests::test_check_label ... ok
Dec 13 08:46:20.959 INFO [stdout] test assembler::semantics::tests::test_check_sne2 ... ok
Dec 13 08:46:20.959 INFO [stdout] test assembler::semantics::tests::test_check_sub ... ok
Dec 13 08:46:20.959 INFO [stdout] test assembler::semantics::tests::test_check_sne1 ... ok
Dec 13 08:46:20.959 INFO [stdout] test assembler::semantics::tests::test_check_skp ... ok
Dec 13 08:46:20.959 INFO [stdout] test assembler::semantics::tests::test_check_xor ... ok
Dec 13 08:46:20.967 INFO [stdout] test assembler::semantics::tests::test_check_subn ... ok
Dec 13 08:46:21.035 INFO [stdout] test assembler::semantics::tests::test_check_directive_only ... ok
Dec 13 08:46:21.035 INFO [stdout] test assembler::semantics::tests::test_check_directive_invalid_operand ... ok
Dec 13 08:46:21.049 INFO [stdout] test assembler::semantics::tests::test_check_instruction2 ... ok
Dec 13 08:46:21.049 INFO [stderr]      Running /target/debug/deps/silica-d1a9e552d05d9c57
Dec 13 08:46:21.049 INFO [stdout] test assembler::semantics::tests::test_check_jp2 ... ok
Dec 13 08:46:21.049 INFO [stdout] 
Dec 13 08:46:21.049 INFO [stdout] test result: ok. 103 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 08:46:21.049 INFO [stdout] 
Dec 13 08:46:21.055 INFO [stdout] 
Dec 13 08:46:21.055 INFO [stdout] running 0 tests
Dec 13 08:46:21.055 INFO [stdout] 
Dec 13 08:46:21.055 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 08:46:21.055 INFO [stdout] 
Dec 13 08:46:21.059 INFO [stderr]      Running /target/debug/deps/assembler_tests-c82f3b49ab10340f
Dec 13 08:46:21.063 INFO [stderr]    Doc-tests silica
Dec 13 08:46:21.063 INFO [stdout] 
Dec 13 08:46:21.063 INFO [stdout] running 0 tests
Dec 13 08:46:21.063 INFO [stdout] 
Dec 13 08:46:21.063 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 08:46:21.063 INFO [stdout] 
Dec 13 08:46:22.096 INFO [stdout] 
Dec 13 08:46:22.096 INFO [stdout] running 0 tests
Dec 13 08:46:22.096 INFO [stdout] 
Dec 13 08:46:22.096 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 08:46:22.096 INFO [stdout] 
Dec 13 08:46:22.100 INFO [stderr] su: No module specific data is present
Dec 13 08:46:22.710 INFO running `"docker" "inspect" "021400533d8e473a70b61f30b388a943680286d2b2ba3a126aa90dd0f4e1464f"`
Dec 13 08:46:22.935 INFO running `"docker" "rm" "-f" "021400533d8e473a70b61f30b388a943680286d2b2ba3a126aa90dd0f4e1464f"`
Dec 13 08:46:23.321 INFO [stdout] 021400533d8e473a70b61f30b388a943680286d2b2ba3a126aa90dd0f4e1464f