Dec 15 01:56:52.317 INFO testing peresil-0.4.0-alpha.0 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 01:56:52.317 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 15 01:56:52.707 INFO [stdout] a7f9666d20f41a2f94f58c0d69eb260e42f972dd37708ae843bb5b483369f074
Dec 15 01:56:52.709 INFO running `"docker" "start" "-a" "a7f9666d20f41a2f94f58c0d69eb260e42f972dd37708ae843bb5b483369f074"`
Dec 15 01:56:53.798 INFO [stderr] usermod: no changes
Dec 15 01:56:53.865 INFO [stderr]    Compiling peresil v0.4.0-alpha.0 (/source)
Dec 15 01:56:54.819 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.92s
Dec 15 01:56:54.819 INFO [stderr] su: No module specific data is present
Dec 15 01:56:55.359 INFO running `"docker" "inspect" "a7f9666d20f41a2f94f58c0d69eb260e42f972dd37708ae843bb5b483369f074"`
Dec 15 01:56:55.675 INFO running `"docker" "rm" "-f" "a7f9666d20f41a2f94f58c0d69eb260e42f972dd37708ae843bb5b483369f074"`
Dec 15 01:56:55.934 INFO [stdout] a7f9666d20f41a2f94f58c0d69eb260e42f972dd37708ae843bb5b483369f074
Dec 15 01:56:55.937 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 15 01:56:56.377 INFO [stdout] db24c7132a46d6f378865874292c5d0800d69652769b7dbf1be12855e0a408f0
Dec 15 01:56:56.383 INFO running `"docker" "start" "-a" "db24c7132a46d6f378865874292c5d0800d69652769b7dbf1be12855e0a408f0"`
Dec 15 01:56:57.626 INFO [stderr] usermod: no changes
Dec 15 01:56:57.672 INFO [stderr]    Compiling peresil v0.4.0-alpha.0 (/source)
Dec 15 01:57:00.932 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.29s
Dec 15 01:57:00.939 INFO [stderr] su: No module specific data is present
Dec 15 01:57:01.751 INFO running `"docker" "inspect" "db24c7132a46d6f378865874292c5d0800d69652769b7dbf1be12855e0a408f0"`
Dec 15 01:57:02.004 INFO running `"docker" "rm" "-f" "db24c7132a46d6f378865874292c5d0800d69652769b7dbf1be12855e0a408f0"`
Dec 15 01:57:02.355 INFO [stdout] db24c7132a46d6f378865874292c5d0800d69652769b7dbf1be12855e0a408f0
Dec 15 01:57:02.360 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 15 01:57:02.787 INFO [stdout] eeae9a4e8ffb8644b5dd5b17babd25ff8d7acc571df0d4dcfb63f22016bfdc4a
Dec 15 01:57:02.787 INFO running `"docker" "start" "-a" "eeae9a4e8ffb8644b5dd5b17babd25ff8d7acc571df0d4dcfb63f22016bfdc4a"`
Dec 15 01:57:03.770 INFO [stderr] usermod: no changes
Dec 15 01:57:03.832 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Dec 15 01:57:03.832 INFO [stderr]      Running /target/debug/deps/peresil-d299ac2c54f14d74
Dec 15 01:57:03.839 INFO [stdout] 
Dec 15 01:57:03.839 INFO [stdout] running 26 tests
Dec 15 01:57:03.843 INFO [stdout] test test::child_parse_succeeds ... ok
Dec 15 01:57:03.843 INFO [stdout] test test::alternate_stops_parsing_after_unrecoverable_failure ... ok
Dec 15 01:57:03.843 INFO [stdout] test test::alternate_with_children_parses ... ok
Dec 15 01:57:03.843 INFO [stdout] test test::child_parse_fails_parent_step ... ok
Dec 15 01:57:03.843 INFO [stdout] test test::optional_missing ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::first_error_is_better ... ok
Dec 15 01:57:03.858 INFO [stderr]      Running /target/debug/deps/calculator-02386e15206715fc
Dec 15 01:57:03.858 INFO [stdout] test test::one_error ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::one_success ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::child_parse_fails_child_step ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::second_error_is_better ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::optional_present ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::optional_with_unrecoverable ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::optional_with_recoverable ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::sequential_success ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::string_consume_identifier ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::string_alternate ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::string_sequential ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::string_to ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::string_zero_or_more ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::string_consume_literal ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::success_after_failure ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::success_before_failure ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::two_error_at_same_point ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::zero_or_more ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::zero_or_more_fails_on_unrecoverable_failure ... ok
Dec 15 01:57:03.858 INFO [stdout] test test::zero_or_more_failure_returns_to_beginning_of_line ... ok
Dec 15 01:57:03.858 INFO [stdout] 
Dec 15 01:57:03.858 INFO [stdout] test result: ok. 26 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:57:03.858 INFO [stdout] 
Dec 15 01:57:03.858 INFO [stdout] 
Dec 15 01:57:03.858 INFO [stdout] running 15 tests
Dec 15 01:57:03.858 INFO [stdout] test failure_invalid_number ... ok
Dec 15 01:57:03.858 INFO [stdout] test failure_invalid_number_in_other_position ... ok
Dec 15 01:57:03.858 INFO [stdout] test division_divides ... ok
Dec 15 01:57:03.858 INFO [stdout] test divide_two_numbers ... ok
Dec 15 01:57:03.858 INFO [stdout] test all_operators_together ... ok
Dec 15 01:57:03.858 INFO [stdout] test addition_adds ... ok
Dec 15 01:57:03.858 INFO [stdout] test add_two_numbers ... ok
Dec 15 01:57:03.858 INFO [stdout] test failure_not_a_number ... ok
Dec 15 01:57:03.858 INFO [stdout] test add_three_numbers ... ok
Dec 15 01:57:03.868 INFO [stdout] test multiplication_multiplies ... ok
Dec 15 01:57:03.868 INFO [stderr]      Running /target/debug/deps/ingredient_list-ebee68020433aafa
Dec 15 01:57:03.868 INFO [stdout] test multiply_three_numbers ... ok
Dec 15 01:57:03.868 INFO [stdout] test multiply_two_numbers ... ok
Dec 15 01:57:03.868 INFO [stdout] test single_number ... ok
Dec 15 01:57:03.868 INFO [stdout] test subtract_two_numbers ... ok
Dec 15 01:57:03.868 INFO [stdout] test subtraction_subtracts ... ok
Dec 15 01:57:03.868 INFO [stdout] 
Dec 15 01:57:03.868 INFO [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:57:03.868 INFO [stdout] 
Dec 15 01:57:03.868 INFO [stdout] 
Dec 15 01:57:03.868 INFO [stdout] running 8 tests
Dec 15 01:57:03.868 INFO [stdout] test ounces ... ok
Dec 15 01:57:03.868 INFO [stdout] test tablespoons ... ok
Dec 15 01:57:03.868 INFO [stdout] test failure_unknown_unit ... ok
Dec 15 01:57:03.868 INFO [stdout] test failure_invalid_size ... ok
Dec 15 01:57:03.868 INFO [stdout] test failure_expected_whitespace_after_unit ... ok
Dec 15 01:57:03.868 INFO [stdout] test failure_expected_whitespace_after_size ... ok
Dec 15 01:57:03.868 INFO [stdout] test cups ... ok
Dec 15 01:57:03.868 INFO [stdout] test failure_expected_name ... ok
Dec 15 01:57:03.868 INFO [stdout] 
Dec 15 01:57:03.868 INFO [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:57:03.868 INFO [stdout] 
Dec 15 01:57:03.872 INFO [stderr]    Doc-tests peresil
Dec 15 01:57:04.811 INFO [stdout] 
Dec 15 01:57:04.811 INFO [stdout] running 1 test
Dec 15 01:57:06.435 INFO [stdout] test src/lib.rs -  (line 5) ... ok
Dec 15 01:57:06.435 INFO [stdout] 
Dec 15 01:57:06.435 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:57:06.435 INFO [stdout] 
Dec 15 01:57:06.447 INFO [stderr] su: No module specific data is present
Dec 15 01:57:06.835 INFO running `"docker" "inspect" "eeae9a4e8ffb8644b5dd5b17babd25ff8d7acc571df0d4dcfb63f22016bfdc4a"`
Dec 15 01:57:07.143 INFO running `"docker" "rm" "-f" "eeae9a4e8ffb8644b5dd5b17babd25ff8d7acc571df0d4dcfb63f22016bfdc4a"`
Dec 15 01:57:07.391 INFO [stdout] eeae9a4e8ffb8644b5dd5b17babd25ff8d7acc571df0d4dcfb63f22016bfdc4a