Nov 17 20:31:55.108 INFO testing tis-100-0.2.2 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 17 20:31:55.108 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 17 20:31:55.296 INFO [stdout] 0690c9b57f20cc050b486baa0b8baf928b85bbd7b3ca46873d299d5ac20881de
Nov 17 20:31:55.297 INFO running `"docker" "start" "-a" "0690c9b57f20cc050b486baa0b8baf928b85bbd7b3ca46873d299d5ac20881de"`
Nov 17 20:31:55.682 INFO [stderr] usermod: no changes
Nov 17 20:31:55.718 INFO [stderr]    Compiling lua52-sys v0.0.4
Nov 17 20:32:00.216 INFO [stderr]    Compiling hlua v0.1.9
Nov 17 20:32:01.559 INFO [stderr]    Compiling tis-100 v0.2.2 (/source)
Nov 17 20:32:07.119 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.41s
Nov 17 20:32:07.127 INFO [stderr] su: No module specific data is present
Nov 17 20:32:07.416 INFO running `"docker" "inspect" "0690c9b57f20cc050b486baa0b8baf928b85bbd7b3ca46873d299d5ac20881de"`
Nov 17 20:32:07.488 INFO running `"docker" "rm" "-f" "0690c9b57f20cc050b486baa0b8baf928b85bbd7b3ca46873d299d5ac20881de"`
Nov 17 20:32:07.599 INFO [stdout] 0690c9b57f20cc050b486baa0b8baf928b85bbd7b3ca46873d299d5ac20881de
Nov 17 20:32:07.601 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 17 20:32:07.814 INFO [stdout] 01fb1e4e1fcf80579e7a001ae7ee1a0b0c86ebab081968c6c711b6a4cf9d5f9c
Nov 17 20:32:07.816 INFO running `"docker" "start" "-a" "01fb1e4e1fcf80579e7a001ae7ee1a0b0c86ebab081968c6c711b6a4cf9d5f9c"`
Nov 17 20:32:08.734 INFO [stderr] usermod: no changes
Nov 17 20:32:08.825 INFO [stderr]    Compiling tis-100 v0.2.2 (/source)
Nov 17 20:32:11.276 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.51s
Nov 17 20:32:11.283 INFO [stderr] su: No module specific data is present
Nov 17 20:32:11.519 INFO running `"docker" "inspect" "01fb1e4e1fcf80579e7a001ae7ee1a0b0c86ebab081968c6c711b6a4cf9d5f9c"`
Nov 17 20:32:11.636 INFO running `"docker" "rm" "-f" "01fb1e4e1fcf80579e7a001ae7ee1a0b0c86ebab081968c6c711b6a4cf9d5f9c"`
Nov 17 20:32:11.719 INFO [stdout] 01fb1e4e1fcf80579e7a001ae7ee1a0b0c86ebab081968c6c711b6a4cf9d5f9c
Nov 17 20:32:11.726 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 17 20:32:12.005 INFO [stdout] 23902c7911549480121a460714dde17ef02b775e29e8705ac73f5b365a1fa60b
Nov 17 20:32:12.006 INFO running `"docker" "start" "-a" "23902c7911549480121a460714dde17ef02b775e29e8705ac73f5b365a1fa60b"`
Nov 17 20:32:12.589 INFO [stderr] usermod: no changes
Nov 17 20:32:12.632 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Nov 17 20:32:12.632 INFO [stderr]      Running /target/debug/deps/tis_100-6e2df7ac72622cc9
Nov 17 20:32:12.640 INFO [stderr]    Doc-tests tis_100
Nov 17 20:32:12.640 INFO [stdout] 
Nov 17 20:32:12.640 INFO [stdout] running 15 tests
Nov 17 20:32:12.640 INFO [stdout] test core::test_parse_io_register ... ok
Nov 17 20:32:12.640 INFO [stdout] test lex::test_is_comment_delimiter ... ok
Nov 17 20:32:12.640 INFO [stdout] test lex::test_is_label_delimiter ... ok
Nov 17 20:32:12.640 INFO [stdout] test core::test_parse_port ... ok
Nov 17 20:32:12.640 INFO [stdout] test core::test_parse_source ... ok
Nov 17 20:32:12.640 INFO [stdout] test lex::test_is_whitespace ... ok
Nov 17 20:32:12.640 INFO [stdout] test core::test_parse_register ... ok
Nov 17 20:32:12.640 INFO [stdout] test image::test_image_with_data ... ok
Nov 17 20:32:12.640 INFO [stdout] test image::test_color_from_isize ... ok
Nov 17 20:32:12.640 INFO [stdout] test node::exec::test_clamp_value ... ok
Nov 17 20:32:12.640 INFO [stdout] test parse::test_parse_opcode ... ok
Nov 17 20:32:12.640 INFO [stdout] test save::test_parse_save ... ok
Nov 17 20:32:12.640 INFO [stdout] test image::test_image_write ... ok
Nov 17 20:32:12.640 INFO [stdout] test lex::test_lex_program ... ok
Nov 17 20:32:12.640 INFO [stdout] test lex::test_lex_line ... ok
Nov 17 20:32:12.640 INFO [stdout] 
Nov 17 20:32:12.640 INFO [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 20:32:12.641 INFO [stdout] 
Nov 17 20:32:13.460 INFO [stdout] 
Nov 17 20:32:13.460 INFO [stdout] running 7 tests
Nov 17 20:32:18.215 INFO [stdout] test src/io.rs - io::IoBus (line 41) ... ok
Nov 17 20:32:18.659 INFO [stdout] test src/parse.rs - parse::parse_program (line 51) ... ok
Nov 17 20:32:18.947 INFO [stdout] test src/io.rs - io::IoBus (line 29) ... ok
Nov 17 20:32:19.151 INFO [stdout] test src/lib.rs -  (line 5) ... ok
Nov 17 20:32:19.231 INFO [stdout] test src/machine.rs - machine::Sandbox (line 29) ... ok
Nov 17 20:32:19.299 INFO [stdout] test src/core.rs - core::opposite_port (line 38) ... ok
Nov 17 20:32:19.318 INFO [stdout] test src/node/exec.rs - node::exec::BasicExecutionNode (line 32) ... ok
Nov 17 20:32:19.318 INFO [stdout] 
Nov 17 20:32:19.318 INFO [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 20:32:19.318 INFO [stdout] 
Nov 17 20:32:19.346 INFO [stderr] su: No module specific data is present
Nov 17 20:32:19.971 INFO running `"docker" "inspect" "23902c7911549480121a460714dde17ef02b775e29e8705ac73f5b365a1fa60b"`
Nov 17 20:32:20.241 INFO running `"docker" "rm" "-f" "23902c7911549480121a460714dde17ef02b775e29e8705ac73f5b365a1fa60b"`
Nov 17 20:32:20.515 INFO [stdout] 23902c7911549480121a460714dde17ef02b775e29e8705ac73f5b365a1fa60b