Dec 13 08:45:43.837 INFO testing nnarain/silica against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 13 08:45:43.837 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:44.331 INFO [stdout] 9c2dff3d1880f285c526f4894f56c699ca48b153fb777ddcc2faae9c782c0ea4
Dec 13 08:45:44.333 INFO running `"docker" "start" "-a" "9c2dff3d1880f285c526f4894f56c699ca48b153fb777ddcc2faae9c782c0ea4"`
Dec 13 08:45:46.071 INFO [stderr] usermod: no changes
Dec 13 08:45:46.191 INFO [stderr]    Compiling libc v0.2.29
Dec 13 08:45:47.433 INFO [stderr]    Compiling memchr v1.0.1
Dec 13 08:45:48.026 INFO [stderr]    Compiling aho-corasick v0.6.3
Dec 13 08:45:48.028 INFO [stderr]    Compiling nom v3.2.0
Dec 13 08:45:49.360 INFO [stderr]    Compiling regex v0.2.2
Dec 13 08:45:58.332 INFO [stderr]    Compiling docopt v0.8.1
Dec 13 08:46:03.003 INFO [stderr]    Compiling silica v0.1.0 (/source)
Dec 13 08:46:09.817 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 23.72s
Dec 13 08:46:09.827 INFO [stderr] su: No module specific data is present
Dec 13 08:46:10.508 INFO running `"docker" "inspect" "9c2dff3d1880f285c526f4894f56c699ca48b153fb777ddcc2faae9c782c0ea4"`
Dec 13 08:46:10.752 INFO running `"docker" "rm" "-f" "9c2dff3d1880f285c526f4894f56c699ca48b153fb777ddcc2faae9c782c0ea4"`
Dec 13 08:46:11.146 INFO [stdout] 9c2dff3d1880f285c526f4894f56c699ca48b153fb777ddcc2faae9c782c0ea4
Dec 13 08:46:11.149 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:11.661 INFO [stdout] 046589d23ac9685dbe6f264dab4e9331bba1340408fd2d0e72c0035694a99bac
Dec 13 08:46:11.663 INFO running `"docker" "start" "-a" "046589d23ac9685dbe6f264dab4e9331bba1340408fd2d0e72c0035694a99bac"`
Dec 13 08:46:13.075 INFO [stderr] usermod: no changes
Dec 13 08:46:13.175 INFO [stderr]    Compiling silica v0.1.0 (/source)
Dec 13 08:46:19.277 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.17s
Dec 13 08:46:19.283 INFO [stderr] su: No module specific data is present
Dec 13 08:46:19.985 INFO running `"docker" "inspect" "046589d23ac9685dbe6f264dab4e9331bba1340408fd2d0e72c0035694a99bac"`
Dec 13 08:46:20.275 INFO running `"docker" "rm" "-f" "046589d23ac9685dbe6f264dab4e9331bba1340408fd2d0e72c0035694a99bac"`
Dec 13 08:46:20.615 INFO [stdout] 046589d23ac9685dbe6f264dab4e9331bba1340408fd2d0e72c0035694a99bac
Dec 13 08:46:20.621 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:21.028 INFO [stdout] 670cd11a4a77a84c708b2ef6cb7451818669b0233e4b7a34bc313b4728e4047a
Dec 13 08:46:21.034 INFO running `"docker" "start" "-a" "670cd11a4a77a84c708b2ef6cb7451818669b0233e4b7a34bc313b4728e4047a"`
Dec 13 08:46:22.140 INFO [stderr] usermod: no changes
Dec 13 08:46:22.227 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
Dec 13 08:46:22.227 INFO [stderr]      Running /target/debug/deps/silica-bcb29a9c0cf38ef6
Dec 13 08:46:22.231 INFO [stdout] 
Dec 13 08:46:22.231 INFO [stdout] running 103 tests
Dec 13 08:46:22.232 INFO [stdout] test assembler::codegenerator::tests::test_add2 ... ok
Dec 13 08:46:22.233 INFO [stdout] test assembler::codegenerator::tests::test_add3 ... ok
Dec 13 08:46:22.233 INFO [stdout] test assembler::codegenerator::tests::test_and ... ok
Dec 13 08:46:22.233 INFO [stdout] test assembler::codegenerator::tests::test_drw ... ok
Dec 13 08:46:22.233 INFO [stdout] test assembler::codegenerator::tests::test_db_directive ... ok
Dec 13 08:46:22.233 INFO [stdout] test assembler::codegenerator::tests::test_ld11 ... ok
Dec 13 08:46:22.233 INFO [stdout] test assembler::codegenerator::tests::test_ld3 ... ok
Dec 13 08:46:22.233 INFO [stdout] test assembler::codegenerator::tests::test_ld4 ... ok
Dec 13 08:46:22.233 INFO [stdout] test assembler::codegenerator::tests::test_ld2 ... ok
Dec 13 08:46:22.233 INFO [stdout] test assembler::codegenerator::tests::test_ld6 ... ok
Dec 13 08:46:22.233 INFO [stdout] test assembler::codegenerator::tests::test_ld8 ... ok
Dec 13 08:46:22.234 INFO [stdout] test assembler::codegenerator::tests::test_ld7 ... ok
Dec 13 08:46:22.234 INFO [stdout] test assembler::codegenerator::tests::test_ld5 ... ok
Dec 13 08:46:22.234 INFO [stdout] test assembler::codegenerator::tests::test_or ... ok
Dec 13 08:46:22.234 INFO [stdout] test assembler::codegenerator::tests::test_rnd ... ok
Dec 13 08:46:22.234 INFO [stdout] test assembler::codegenerator::tests::test_ld_i_addr ... ok
Dec 13 08:46:22.242 INFO [stdout] test assembler::codegenerator::tests::test_add1 ... ok
Dec 13 08:46:22.248 INFO [stdout] test assembler::codegenerator::tests::test_ld1 ... ok
Dec 13 08:46:22.248 INFO [stdout] test assembler::codegenerator::tests::test_ld9 ... ok
Dec 13 08:46:22.248 INFO [stdout] test assembler::codegenerator::tests::test_shr ... ok
Dec 13 08:46:22.248 INFO [stdout] test assembler::codegenerator::tests::test_sknp ... ok
Dec 13 08:46:22.248 INFO [stdout] test assembler::codegenerator::tests::test_sh1 ... ok
Dec 13 08:46:22.248 INFO [stdout] test assembler::codegenerator::tests::test_jp_instruction ... ok
Dec 13 08:46:22.248 INFO [stdout] test assembler::codegenerator::tests::test_ld10 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::codegenerator::tests::test_skp ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::codegenerator::tests::test_sub ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::codegenerator::tests::test_subn ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::codegenerator::tests::test_xor ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_blank_line ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_column_sep_parse_until ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_label_operand ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_column_sep_parse_all ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_comma ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_decimal_literal ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_directives ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_hex_literal ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_instruction1 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_comments ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_instruction2 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_instruction3 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_instruction4 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_instruction5 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_instruction6 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_label ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_line1_crlf ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_line1_lf ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_line3 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_line4 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_line5 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_line6 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_line7 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_line_ending_crlf ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_line_ending_lf ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_line_termination1 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_line_termination2 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_line_termination3 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_lines1 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_lines2 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_lines3 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_lines4 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_lines5 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_mnem ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_numeric_literal_parse_decimal ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_numeric_literal_parse_hex ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::lexer::tests::test_lex_registers ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::parser::tests::test_parse_instruction1 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::parser::tests::test_parse_instruction3 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::parser::tests::test_parse_instruction4 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::parser::tests::test_parse1 ... ok
Dec 13 08:46:22.255 INFO [stdout] test assembler::parser::tests::test_parse2 ... ok
Dec 13 08:46:22.256 INFO [stdout] test assembler::parser::tests::test_parse_directive1 ... ok
Dec 13 08:46:22.256 INFO [stdout] test assembler::parser::tests::test_parse_directive2 ... ok
Dec 13 08:46:22.256 INFO [stdout] test assembler::parser::tests::test_parse_instruction5 ... ok
Dec 13 08:46:22.256 INFO [stdout] test assembler::parser::tests::test_parse_label ... ok
Dec 13 08:46:22.256 INFO [stdout] test assembler::semantics::tests::test_check_add1 ... ok
Dec 13 08:46:22.256 INFO [stdout] test assembler::semantics::tests::test_check_add2 ... ok
Dec 13 08:46:22.256 INFO [stdout] test assembler::semantics::tests::test_check_and ... ok
Dec 13 08:46:22.256 INFO [stdout] test assembler::semantics::tests::test_check_directive ... ok
Dec 13 08:46:22.263 INFO [stdout] test assembler::parser::tests::test_parse_instruction2 ... ok
Dec 13 08:46:22.263 INFO [stdout] test assembler::semantics::tests::test_check_drw ... ok
Dec 13 08:46:22.263 INFO [stdout] test assembler::semantics::tests::test_check_instruction1 ... ok
Dec 13 08:46:22.263 INFO [stdout] test assembler::semantics::tests::test_check_jp1 ... ok
Dec 13 08:46:22.263 INFO [stdout] test assembler::semantics::tests::test_check_ld1 ... ok
Dec 13 08:46:22.275 INFO [stdout] test assembler::semantics::tests::test_check_ld2 ... ok
Dec 13 08:46:22.275 INFO [stdout] test assembler::semantics::tests::test_check_jr ... ok
Dec 13 08:46:22.275 INFO [stdout] test assembler::semantics::tests::test_check_label ... ok
Dec 13 08:46:22.275 INFO [stdout] test assembler::semantics::tests::test_check_or ... ok
Dec 13 08:46:22.291 INFO [stdout] test assembler::semantics::tests::test_check_se2 ... ok
Dec 13 08:46:22.292 INFO [stdout] test assembler::semantics::tests::test_check_shl ... ok
Dec 13 08:46:22.292 INFO [stdout] test assembler::semantics::tests::test_check_se1 ... ok
Dec 13 08:46:22.292 INFO [stdout] test assembler::semantics::tests::test_check_rnd ... ok
Dec 13 08:46:22.292 INFO [stdout] test assembler::semantics::tests::test_check_sknp ... ok
Dec 13 08:46:22.292 INFO [stdout] test assembler::semantics::tests::test_check_shr ... ok
Dec 13 08:46:22.292 INFO [stdout] test assembler::semantics::tests::test_check_skp ... ok
Dec 13 08:46:22.292 INFO [stdout] test assembler::semantics::tests::test_check_sne1 ... ok
Dec 13 08:46:22.292 INFO [stdout] test assembler::semantics::tests::test_check_sne2 ... ok
Dec 13 08:46:22.292 INFO [stdout] test assembler::semantics::tests::test_check_xor ... ok
Dec 13 08:46:22.292 INFO [stdout] test assembler::semantics::tests::test_check_sub ... ok
Dec 13 08:46:22.292 INFO [stdout] test assembler::semantics::tests::test_check_subn ... ok
Dec 13 08:46:22.391 INFO [stdout] test assembler::semantics::tests::test_check_instruction2 ... ok
Dec 13 08:46:22.391 INFO [stdout] test assembler::semantics::tests::test_check_jp2 ... ok
Dec 13 08:46:22.391 INFO [stdout] test assembler::semantics::tests::test_check_directive_only ... ok
Dec 13 08:46:22.391 INFO [stdout] test assembler::semantics::tests::test_check_directive_invalid_operand ... ok
Dec 13 08:46:22.391 INFO [stdout] 
Dec 13 08:46:22.391 INFO [stdout] test result: ok. 103 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 08:46:22.391 INFO [stdout] 
Dec 13 08:46:22.399 INFO [stderr]      Running /target/debug/deps/silica-4e548909b9283658
Dec 13 08:46:22.399 INFO [stdout] 
Dec 13 08:46:22.399 INFO [stdout] running 0 tests
Dec 13 08:46:22.399 INFO [stdout] 
Dec 13 08:46:22.399 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 08:46:22.399 INFO [stdout] 
Dec 13 08:46:22.403 INFO [stderr]      Running /target/debug/deps/assembler_tests-414e2d9fce32a690
Dec 13 08:46:22.411 INFO [stdout] 
Dec 13 08:46:22.411 INFO [stdout] running 0 tests
Dec 13 08:46:22.411 INFO [stdout] 
Dec 13 08:46:22.411 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 08:46:22.411 INFO [stdout] 
Dec 13 08:46:22.411 INFO [stderr]    Doc-tests silica
Dec 13 08:46:23.463 INFO [stdout] 
Dec 13 08:46:23.463 INFO [stdout] running 0 tests
Dec 13 08:46:23.463 INFO [stdout] 
Dec 13 08:46:23.463 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 08:46:23.463 INFO [stdout] 
Dec 13 08:46:23.482 INFO [stderr] su: No module specific data is present
Dec 13 08:46:24.244 INFO running `"docker" "inspect" "670cd11a4a77a84c708b2ef6cb7451818669b0233e4b7a34bc313b4728e4047a"`
Dec 13 08:46:24.531 INFO running `"docker" "rm" "-f" "670cd11a4a77a84c708b2ef6cb7451818669b0233e4b7a34bc313b4728e4047a"`
Dec 13 08:46:24.799 INFO [stdout] 670cd11a4a77a84c708b2ef6cb7451818669b0233e4b7a34bc313b4728e4047a