Nov 18 02:58:00.213 INFO testing pvm-0.1.0 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 02:58:00.215 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 18 02:58:00.435 INFO [stdout] 53f14667e1fdd4eea962bba904ea8e5eb810ca7d278b9472e9463f7915617b85
Nov 18 02:58:00.439 INFO running `"docker" "start" "-a" "53f14667e1fdd4eea962bba904ea8e5eb810ca7d278b9472e9463f7915617b85"`
Nov 18 02:58:01.068 INFO [stderr] usermod: no changes
Nov 18 02:58:01.109 INFO [stderr]    Compiling pvm v0.1.0 (/source)
Nov 18 02:58:02.706 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.62s
Nov 18 02:58:02.710 INFO [stderr] su: No module specific data is present
Nov 18 02:58:03.025 INFO running `"docker" "inspect" "53f14667e1fdd4eea962bba904ea8e5eb810ca7d278b9472e9463f7915617b85"`
Nov 18 02:58:03.134 INFO running `"docker" "rm" "-f" "53f14667e1fdd4eea962bba904ea8e5eb810ca7d278b9472e9463f7915617b85"`
Nov 18 02:58:03.263 INFO [stdout] 53f14667e1fdd4eea962bba904ea8e5eb810ca7d278b9472e9463f7915617b85
Nov 18 02:58:03.265 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 18 02:58:03.470 INFO [stdout] 9c18301616a9edd0826c0ab39e5f11e73606929f25e3f3bfe34ea30f5089c3f4
Nov 18 02:58:03.473 INFO running `"docker" "start" "-a" "9c18301616a9edd0826c0ab39e5f11e73606929f25e3f3bfe34ea30f5089c3f4"`
Nov 18 02:58:03.931 INFO [stderr] usermod: no changes
Nov 18 02:58:03.987 INFO [stderr]    Compiling pvm v0.1.0 (/source)
Nov 18 02:58:04.763 INFO [stderr] warning: unused import: `std::io`
Nov 18 02:58:04.764 INFO [stderr]  --> tests/calculator1.rs:5:5
Nov 18 02:58:04.764 INFO [stderr]   |
Nov 18 02:58:04.764 INFO [stderr] 5 | use std::io;
Nov 18 02:58:04.764 INFO [stderr]   |     ^^^^^^^
Nov 18 02:58:04.765 INFO [stderr]   |
Nov 18 02:58:04.765 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Nov 18 02:58:04.765 INFO [stderr] 
Nov 18 02:58:07.286 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.33s
Nov 18 02:58:07.290 INFO [stderr] su: No module specific data is present
Nov 18 02:58:07.682 INFO running `"docker" "inspect" "9c18301616a9edd0826c0ab39e5f11e73606929f25e3f3bfe34ea30f5089c3f4"`
Nov 18 02:58:07.891 INFO running `"docker" "rm" "-f" "9c18301616a9edd0826c0ab39e5f11e73606929f25e3f3bfe34ea30f5089c3f4"`
Nov 18 02:58:08.216 INFO [stdout] 9c18301616a9edd0826c0ab39e5f11e73606929f25e3f3bfe34ea30f5089c3f4
Nov 18 02:58:08.228 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 18 02:58:08.683 INFO [stdout] e390dc8cacc358b4f29ed0bb4397bc998811d51004c27bd7ee727b8b8d873b2e
Nov 18 02:58:08.685 INFO running `"docker" "start" "-a" "e390dc8cacc358b4f29ed0bb4397bc998811d51004c27bd7ee727b8b8d873b2e"`
Nov 18 02:58:09.293 INFO [stderr] usermod: no changes
Nov 18 02:58:09.359 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Nov 18 02:58:09.360 INFO [stderr]      Running /target/debug/deps/pvm-6d3e6f5e248f61f6
Nov 18 02:58:09.369 INFO [stdout] 
Nov 18 02:58:09.369 INFO [stdout] running 34 tests
Nov 18 02:58:09.378 INFO [stdout] test ast::tests::simple_iteration_grammar ... ok
Nov 18 02:58:09.386 INFO [stdout] test ast::tests::simple_char_grammar_rules ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::ambersand_predicate ... ok
Nov 18 02:58:09.387 INFO [stdout] test ast::tests::simple_lookahead_grammar ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::optional_predicate ... ok
Nov 18 02:58:09.387 INFO [stdout] test ast::tests::simple_optional_subparser ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::not_predicate ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::char_range_links ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::direct_left_recursion ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::direct_left_recursion_with_tail ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::self_reference_impossible_parser ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::simple_char_choice ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::simple_subparser ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::simple_char_range ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::simple_char_sequence ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::simple_token_stream_result ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::skip_parser ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::skip_parser_with_toggle ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::three_subparser ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::zero_or_more_chars ... ok
Nov 18 02:58:09.387 INFO [stdout] test parser::tests::direct_left_recursion ... ok
Nov 18 02:58:09.387 INFO [stdout] test machine::tests::simple_char ... ok
Nov 18 02:58:09.390 INFO [stdout] test parser::tests::simple_char_grammar ... ok
Nov 18 02:58:09.391 INFO [stdout] test parser::tests::indirect_left_recursion ... ok
Nov 18 02:58:09.391 INFO [stdout] test parser::tests::parenthetical_and_lookahead ... ok
Nov 18 02:58:09.394 INFO [stdout] test parser::tests::simple_choice_grammar ... ok
Nov 18 02:58:09.395 INFO [stdout] test tests::add_multiply_variable_expression ... ok
Nov 18 02:58:09.395 INFO [stdout] test machine::tests::partial_commit_zero_or_more ... ok
Nov 18 02:58:09.395 INFO [stdout] test parser::tests::tokenizer ... ok
Nov 18 02:58:09.395 INFO [stdout] test machine::tests::one_or_more_chars ... ok
Nov 18 02:58:09.395 INFO [stdout] test parser::tests::simple_suffix_grammar ... ok
Nov 18 02:58:09.395 INFO [stdout] test parser::tests::simple_prefix_grammar ... ok
Nov 18 02:58:09.407 INFO [stdout] test parser::tests::simple_parentheticals ... ok
Nov 18 02:58:09.411 INFO [stdout] test parser::tests::dogfood ... ok
Nov 18 02:58:09.411 INFO [stdout] 
Nov 18 02:58:09.411 INFO [stdout] test result: ok. 34 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 02:58:09.411 INFO [stdout] 
Nov 18 02:58:09.415 INFO [stdout] 
Nov 18 02:58:09.415 INFO [stderr]      Running /target/debug/deps/calculator1-4862c964a9e76494
Nov 18 02:58:09.415 INFO [stdout] running 3 tests
Nov 18 02:58:09.421 INFO [stdout] test constants ... ok
Nov 18 02:58:09.423 INFO [stdout] test simple_expressions ... ok
Nov 18 02:58:20.529 INFO [stdout] test random_expressions ... ok
Nov 18 02:58:20.529 INFO [stdout] 
Nov 18 02:58:20.530 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 02:58:20.530 INFO [stdout] 
Nov 18 02:58:20.531 INFO [stderr]      Running /target/debug/deps/simple-5f231f77e6f77d47
Nov 18 02:58:20.533 INFO [stdout] 
Nov 18 02:58:20.533 INFO [stdout] running 1 test
Nov 18 02:58:20.551 INFO [stdout] test simple ... ok
Nov 18 02:58:20.551 INFO [stdout] 
Nov 18 02:58:20.552 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 02:58:20.552 INFO [stdout] 
Nov 18 02:58:20.553 INFO [stderr]    Doc-tests pvm
Nov 18 02:58:21.311 INFO [stdout] 
Nov 18 02:58:21.311 INFO [stdout] running 0 tests
Nov 18 02:58:21.311 INFO [stdout] 
Nov 18 02:58:21.311 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 02:58:21.311 INFO [stdout] 
Nov 18 02:58:21.314 INFO [stderr] su: No module specific data is present
Nov 18 02:58:21.956 INFO running `"docker" "inspect" "e390dc8cacc358b4f29ed0bb4397bc998811d51004c27bd7ee727b8b8d873b2e"`
Nov 18 02:58:22.229 INFO running `"docker" "rm" "-f" "e390dc8cacc358b4f29ed0bb4397bc998811d51004c27bd7ee727b8b8d873b2e"`
Nov 18 02:58:22.531 INFO [stdout] e390dc8cacc358b4f29ed0bb4397bc998811d51004c27bd7ee727b8b8d873b2e