Nov 16 17:59:26.873 INFO testing nnarain/silica against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 16 17:59:26.875 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 16 17:59:27.315 INFO [stdout] 6f9832a15b822aa706df6c39ba45704e9ff62e7ab38c8694d0796feb10cf1359
Nov 16 17:59:27.318 INFO running `"docker" "start" "-a" "6f9832a15b822aa706df6c39ba45704e9ff62e7ab38c8694d0796feb10cf1359"`
Nov 16 17:59:30.042 INFO [stderr] usermod: no changes
Nov 16 17:59:30.169 INFO [stderr]    Compiling nom v3.2.0
Nov 16 17:59:30.183 INFO [stderr]    Compiling docopt v0.8.1
Nov 16 17:59:39.379 INFO [stderr]    Compiling silica v0.1.0 (/source)
Nov 16 17:59:45.599 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 15.52s
Nov 16 17:59:45.603 INFO [stderr] su: No module specific data is present
Nov 16 17:59:46.341 INFO running `"docker" "inspect" "6f9832a15b822aa706df6c39ba45704e9ff62e7ab38c8694d0796feb10cf1359"`
Nov 16 17:59:46.609 INFO running `"docker" "rm" "-f" "6f9832a15b822aa706df6c39ba45704e9ff62e7ab38c8694d0796feb10cf1359"`
Nov 16 17:59:46.935 INFO [stdout] 6f9832a15b822aa706df6c39ba45704e9ff62e7ab38c8694d0796feb10cf1359
Nov 16 17:59:46.942 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 16 17:59:47.483 INFO [stdout] ca03c937e82c3dc6c05f3560d5fe3c45c98a17f44c6a89d096c94eb79ac6291f
Nov 16 17:59:47.495 INFO running `"docker" "start" "-a" "ca03c937e82c3dc6c05f3560d5fe3c45c98a17f44c6a89d096c94eb79ac6291f"`
Nov 16 17:59:48.564 INFO [stderr] usermod: no changes
Nov 16 17:59:48.710 INFO [stderr]    Compiling silica v0.1.0 (/source)
Nov 16 17:59:54.956 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.34s
Nov 16 17:59:54.962 INFO [stderr] su: No module specific data is present
Nov 16 17:59:55.976 INFO running `"docker" "inspect" "ca03c937e82c3dc6c05f3560d5fe3c45c98a17f44c6a89d096c94eb79ac6291f"`
Nov 16 17:59:56.249 INFO running `"docker" "rm" "-f" "ca03c937e82c3dc6c05f3560d5fe3c45c98a17f44c6a89d096c94eb79ac6291f"`
Nov 16 17:59:56.581 INFO [stdout] ca03c937e82c3dc6c05f3560d5fe3c45c98a17f44c6a89d096c94eb79ac6291f
Nov 16 17:59:56.587 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 16 17:59:56.945 INFO [stdout] c914e926a965cfeec837abed08fc306b2e1737f8863c218a9e1a12fc3a5bed07
Nov 16 17:59:56.950 INFO running `"docker" "start" "-a" "c914e926a965cfeec837abed08fc306b2e1737f8863c218a9e1a12fc3a5bed07"`
Nov 16 17:59:57.855 INFO [stderr] usermod: no changes
Nov 16 17:59:57.967 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.08s
Nov 16 17:59:57.967 INFO [stderr]      Running /target/debug/deps/silica-ae39e5915f4151f5
Nov 16 17:59:57.973 INFO [stdout] 
Nov 16 17:59:57.973 INFO [stdout] running 103 tests
Nov 16 17:59:57.979 INFO [stdout] test assembler::codegenerator::tests::test_jp_instruction ... ok
Nov 16 17:59:57.979 INFO [stdout] test assembler::codegenerator::tests::test_ld1 ... ok
Nov 16 17:59:57.979 INFO [stdout] test assembler::codegenerator::tests::test_drw ... ok
Nov 16 17:59:57.979 INFO [stdout] test assembler::codegenerator::tests::test_db_directive ... ok
Nov 16 17:59:57.979 INFO [stdout] test assembler::codegenerator::tests::test_and ... ok
Nov 16 17:59:57.979 INFO [stdout] test assembler::codegenerator::tests::test_ld10 ... ok
Nov 16 17:59:57.979 INFO [stdout] test assembler::codegenerator::tests::test_ld11 ... ok
Nov 16 17:59:57.979 INFO [stdout] test assembler::codegenerator::tests::test_add3 ... ok
Nov 16 17:59:57.979 INFO [stdout] test assembler::codegenerator::tests::test_ld2 ... ok
Nov 16 17:59:57.979 INFO [stdout] test assembler::codegenerator::tests::test_ld3 ... ok
Nov 16 17:59:57.979 INFO [stdout] test assembler::codegenerator::tests::test_ld4 ... ok
Nov 16 17:59:57.979 INFO [stdout] test assembler::codegenerator::tests::test_add1 ... ok
Nov 16 17:59:57.979 INFO [stdout] test assembler::codegenerator::tests::test_ld5 ... ok
Nov 16 17:59:57.979 INFO [stdout] test assembler::codegenerator::tests::test_ld6 ... ok
Nov 16 17:59:57.979 INFO [stdout] test assembler::codegenerator::tests::test_ld7 ... ok
Nov 16 17:59:57.979 INFO [stdout] test assembler::codegenerator::tests::test_rnd ... ok
Nov 16 17:59:57.995 INFO [stdout] test assembler::codegenerator::tests::test_ld9 ... ok
Nov 16 17:59:57.995 INFO [stdout] test assembler::codegenerator::tests::test_ld8 ... ok
Nov 16 17:59:57.996 INFO [stdout] test assembler::codegenerator::tests::test_ld_i_addr ... ok
Nov 16 17:59:57.996 INFO [stdout] test assembler::codegenerator::tests::test_or ... ok
Nov 16 17:59:57.996 INFO [stdout] test assembler::codegenerator::tests::test_sh1 ... ok
Nov 16 17:59:57.996 INFO [stdout] test assembler::codegenerator::tests::test_add2 ... ok
Nov 16 17:59:57.996 INFO [stdout] test assembler::codegenerator::tests::test_shr ... ok
Nov 16 17:59:57.997 INFO [stdout] test assembler::lexer::tests::test_label_operand ... ok
Nov 16 17:59:57.997 INFO [stdout] test assembler::lexer::tests::test_lex_blank_line ... ok
Nov 16 17:59:57.997 INFO [stdout] test assembler::codegenerator::tests::test_xor ... ok
Nov 16 17:59:57.997 INFO [stdout] test assembler::codegenerator::tests::test_subn ... ok
Nov 16 17:59:57.997 INFO [stdout] test assembler::lexer::tests::test_lex_column_sep_parse_all ... ok
Nov 16 17:59:57.998 INFO [stdout] test assembler::codegenerator::tests::test_sub ... ok
Nov 16 17:59:57.998 INFO [stdout] test assembler::codegenerator::tests::test_skp ... ok
Nov 16 17:59:57.998 INFO [stdout] test assembler::codegenerator::tests::test_sknp ... ok
Nov 16 17:59:57.998 INFO [stdout] test assembler::lexer::tests::test_lex_column_sep_parse_until ... ok
Nov 16 17:59:57.999 INFO [stdout] test assembler::lexer::tests::test_lex_comma ... ok
Nov 16 17:59:57.999 INFO [stdout] test assembler::lexer::tests::test_lex_comments ... ok
Nov 16 17:59:57.999 INFO [stdout] test assembler::lexer::tests::test_lex_decimal_literal ... ok
Nov 16 17:59:57.999 INFO [stdout] test assembler::lexer::tests::test_lex_directives ... ok
Nov 16 17:59:57.999 INFO [stdout] test assembler::lexer::tests::test_lex_hex_literal ... ok
Nov 16 17:59:58.000 INFO [stdout] test assembler::lexer::tests::test_lex_instruction1 ... ok
Nov 16 17:59:58.000 INFO [stdout] test assembler::lexer::tests::test_lex_instruction2 ... ok
Nov 16 17:59:58.000 INFO [stdout] test assembler::lexer::tests::test_lex_instruction3 ... ok
Nov 16 17:59:58.000 INFO [stdout] test assembler::lexer::tests::test_lex_instruction4 ... ok
Nov 16 17:59:58.000 INFO [stdout] test assembler::lexer::tests::test_lex_instruction5 ... ok
Nov 16 17:59:58.001 INFO [stdout] test assembler::lexer::tests::test_lex_instruction6 ... ok
Nov 16 17:59:58.001 INFO [stdout] test assembler::lexer::tests::test_lex_label ... ok
Nov 16 17:59:58.001 INFO [stdout] test assembler::lexer::tests::test_lex_line1_crlf ... ok
Nov 16 17:59:58.001 INFO [stdout] test assembler::lexer::tests::test_lex_line1_lf ... ok
Nov 16 17:59:58.001 INFO [stdout] test assembler::lexer::tests::test_lex_line3 ... ok
Nov 16 17:59:58.002 INFO [stdout] test assembler::lexer::tests::test_lex_line4 ... ok
Nov 16 17:59:58.002 INFO [stdout] test assembler::lexer::tests::test_lex_line5 ... ok
Nov 16 17:59:58.002 INFO [stdout] test assembler::lexer::tests::test_lex_line6 ... ok
Nov 16 17:59:58.002 INFO [stdout] test assembler::lexer::tests::test_lex_line7 ... ok
Nov 16 17:59:58.003 INFO [stdout] test assembler::lexer::tests::test_lex_line_ending_crlf ... ok
Nov 16 17:59:58.003 INFO [stdout] test assembler::lexer::tests::test_lex_line_ending_lf ... ok
Nov 16 17:59:58.003 INFO [stdout] test assembler::lexer::tests::test_lex_line_termination1 ... ok
Nov 16 17:59:58.003 INFO [stdout] test assembler::lexer::tests::test_lex_line_termination2 ... ok
Nov 16 17:59:58.004 INFO [stdout] test assembler::lexer::tests::test_lex_line_termination3 ... ok
Nov 16 17:59:58.004 INFO [stdout] test assembler::lexer::tests::test_lex_lines1 ... ok
Nov 16 17:59:58.004 INFO [stdout] test assembler::lexer::tests::test_lex_lines2 ... ok
Nov 16 17:59:58.004 INFO [stdout] test assembler::lexer::tests::test_lex_lines3 ... ok
Nov 16 17:59:58.005 INFO [stdout] test assembler::lexer::tests::test_lex_lines4 ... ok
Nov 16 17:59:58.005 INFO [stdout] test assembler::lexer::tests::test_lex_lines5 ... ok
Nov 16 17:59:58.005 INFO [stdout] test assembler::lexer::tests::test_lex_mnem ... ok
Nov 16 17:59:58.005 INFO [stdout] test assembler::lexer::tests::test_lex_numeric_literal_parse_decimal ... ok
Nov 16 17:59:58.006 INFO [stdout] test assembler::lexer::tests::test_lex_numeric_literal_parse_hex ... ok
Nov 16 17:59:58.006 INFO [stdout] test assembler::lexer::tests::test_lex_registers ... ok
Nov 16 17:59:58.006 INFO [stdout] test assembler::parser::tests::test_parse1 ... ok
Nov 16 17:59:58.006 INFO [stdout] test assembler::parser::tests::test_parse_directive1 ... ok
Nov 16 17:59:58.007 INFO [stdout] test assembler::parser::tests::test_parse2 ... ok
Nov 16 17:59:58.007 INFO [stdout] test assembler::parser::tests::test_parse_directive2 ... ok
Nov 16 17:59:58.007 INFO [stdout] test assembler::parser::tests::test_parse_instruction1 ... ok
Nov 16 17:59:58.008 INFO [stdout] test assembler::parser::tests::test_parse_instruction2 ... ok
Nov 16 17:59:58.008 INFO [stdout] test assembler::parser::tests::test_parse_instruction3 ... ok
Nov 16 17:59:58.008 INFO [stdout] test assembler::parser::tests::test_parse_instruction4 ... ok
Nov 16 17:59:58.008 INFO [stdout] test assembler::parser::tests::test_parse_instruction5 ... ok
Nov 16 17:59:58.009 INFO [stdout] test assembler::parser::tests::test_parse_label ... ok
Nov 16 17:59:58.009 INFO [stdout] test assembler::semantics::tests::test_check_add1 ... ok
Nov 16 17:59:58.009 INFO [stdout] test assembler::semantics::tests::test_check_add2 ... ok
Nov 16 17:59:58.009 INFO [stdout] test assembler::semantics::tests::test_check_and ... ok
Nov 16 17:59:58.010 INFO [stdout] test assembler::semantics::tests::test_check_directive ... ok
Nov 16 17:59:58.010 INFO [stdout] test assembler::semantics::tests::test_check_drw ... ok
Nov 16 17:59:58.010 INFO [stdout] test assembler::semantics::tests::test_check_instruction1 ... ok
Nov 16 17:59:58.010 INFO [stdout] test assembler::semantics::tests::test_check_jp1 ... ok
Nov 16 17:59:58.011 INFO [stdout] test assembler::semantics::tests::test_check_jr ... ok
Nov 16 17:59:58.011 INFO [stdout] test assembler::semantics::tests::test_check_label ... ok
Nov 16 17:59:58.011 INFO [stdout] test assembler::semantics::tests::test_check_ld1 ... ok
Nov 16 17:59:58.011 INFO [stdout] test assembler::semantics::tests::test_check_ld2 ... ok
Nov 16 17:59:58.011 INFO [stdout] test assembler::semantics::tests::test_check_or ... ok
Nov 16 17:59:58.014 INFO [stdout] test assembler::semantics::tests::test_check_se2 ... ok
Nov 16 17:59:58.014 INFO [stdout] test assembler::semantics::tests::test_check_se1 ... ok
Nov 16 17:59:58.014 INFO [stdout] test assembler::semantics::tests::test_check_shl ... ok
Nov 16 17:59:58.014 INFO [stdout] test assembler::semantics::tests::test_check_rnd ... ok
Nov 16 17:59:58.014 INFO [stdout] test assembler::semantics::tests::test_check_shr ... ok
Nov 16 17:59:58.014 INFO [stdout] test assembler::semantics::tests::test_check_skp ... ok
Nov 16 17:59:58.014 INFO [stdout] test assembler::semantics::tests::test_check_sknp ... ok
Nov 16 17:59:58.014 INFO [stdout] test assembler::semantics::tests::test_check_sne1 ... ok
Nov 16 17:59:58.014 INFO [stdout] test assembler::semantics::tests::test_check_sub ... ok
Nov 16 17:59:58.014 INFO [stdout] test assembler::semantics::tests::test_check_sne2 ... ok
Nov 16 17:59:58.014 INFO [stdout] test assembler::semantics::tests::test_check_subn ... ok
Nov 16 17:59:58.014 INFO [stdout] test assembler::semantics::tests::test_check_xor ... ok
Nov 16 17:59:58.095 INFO [stdout] test assembler::semantics::tests::test_check_directive_only ... ok
Nov 16 17:59:58.095 INFO [stdout] test assembler::semantics::tests::test_check_directive_invalid_operand ... ok
Nov 16 17:59:58.095 INFO [stdout] test assembler::semantics::tests::test_check_instruction2 ... ok
Nov 16 17:59:58.095 INFO [stdout] test assembler::semantics::tests::test_check_jp2 ... ok
Nov 16 17:59:58.096 INFO [stdout] 
Nov 16 17:59:58.096 INFO [stdout] test result: ok. 103 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 17:59:58.096 INFO [stdout] 
Nov 16 17:59:58.119 INFO [stdout] 
Nov 16 17:59:58.120 INFO [stderr]      Running /target/debug/deps/silica-88d09593cf87055f
Nov 16 17:59:58.121 INFO [stdout] running 0 tests
Nov 16 17:59:58.121 INFO [stderr]      Running /target/debug/deps/assembler_tests-40111089fd2304bd
Nov 16 17:59:58.121 INFO [stdout] 
Nov 16 17:59:58.121 INFO [stderr]    Doc-tests silica
Nov 16 17:59:58.122 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 17:59:58.122 INFO [stdout] 
Nov 16 17:59:58.122 INFO [stdout] 
Nov 16 17:59:58.122 INFO [stdout] running 0 tests
Nov 16 17:59:58.123 INFO [stdout] 
Nov 16 17:59:58.123 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 17:59:58.123 INFO [stdout] 
Nov 16 17:59:59.026 INFO [stdout] 
Nov 16 17:59:59.026 INFO [stdout] running 0 tests
Nov 16 17:59:59.026 INFO [stdout] 
Nov 16 17:59:59.026 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 17:59:59.026 INFO [stdout] 
Nov 16 17:59:59.048 INFO [stderr] su: No module specific data is present
Nov 16 17:59:59.699 INFO running `"docker" "inspect" "c914e926a965cfeec837abed08fc306b2e1737f8863c218a9e1a12fc3a5bed07"`
Nov 16 17:59:59.957 INFO running `"docker" "rm" "-f" "c914e926a965cfeec837abed08fc306b2e1737f8863c218a9e1a12fc3a5bed07"`
Nov 16 18:00:00.302 INFO [stdout] c914e926a965cfeec837abed08fc306b2e1737f8863c218a9e1a12fc3a5bed07