Dec 15 01:53:03.896 INFO testing pest_vm-2.0.0 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 15 01:53:03.896 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 15 01:53:04.408 INFO [stdout] 5b0a48ccdfb12b53b1f58b990c7619556af481db362bc53fcd2634eb89e5efd4
Dec 15 01:53:04.419 INFO running `"docker" "start" "-a" "5b0a48ccdfb12b53b1f58b990c7619556af481db362bc53fcd2634eb89e5efd4"`
Dec 15 01:53:06.213 INFO [stderr] usermod: no changes
Dec 15 01:53:06.351 INFO [stderr]    Compiling pest_vm v2.0.0 (/source)
Dec 15 01:53:08.487 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.25s
Dec 15 01:53:08.488 INFO [stderr] su: No module specific data is present
Dec 15 01:53:09.391 INFO running `"docker" "inspect" "5b0a48ccdfb12b53b1f58b990c7619556af481db362bc53fcd2634eb89e5efd4"`
Dec 15 01:53:09.769 INFO running `"docker" "rm" "-f" "5b0a48ccdfb12b53b1f58b990c7619556af481db362bc53fcd2634eb89e5efd4"`
Dec 15 01:53:10.107 INFO [stdout] 5b0a48ccdfb12b53b1f58b990c7619556af481db362bc53fcd2634eb89e5efd4
Dec 15 01:53:10.109 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 15 01:53:10.586 INFO [stdout] 153844cfaa798a84cb5dc64efbcae2d6ed07a6b17f1556842085b198537a7063
Dec 15 01:53:10.591 INFO running `"docker" "start" "-a" "153844cfaa798a84cb5dc64efbcae2d6ed07a6b17f1556842085b198537a7063"`
Dec 15 01:53:12.290 INFO [stderr] usermod: no changes
Dec 15 01:53:12.423 INFO [stderr]    Compiling pest_vm v2.0.0 (/source)
Dec 15 01:53:25.407 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.09s
Dec 15 01:53:25.410 INFO [stderr] su: No module specific data is present
Dec 15 01:53:26.571 INFO running `"docker" "inspect" "153844cfaa798a84cb5dc64efbcae2d6ed07a6b17f1556842085b198537a7063"`
Dec 15 01:53:26.985 INFO running `"docker" "rm" "-f" "153844cfaa798a84cb5dc64efbcae2d6ed07a6b17f1556842085b198537a7063"`
Dec 15 01:53:27.315 INFO [stdout] 153844cfaa798a84cb5dc64efbcae2d6ed07a6b17f1556842085b198537a7063
Dec 15 01:53:27.320 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 15 01:53:27.808 INFO [stdout] e13fbb7a17c2956d62435bb2fc1b9c3ad15459ebf7f66e2daf6142d62a78a758
Dec 15 01:53:27.810 INFO running `"docker" "start" "-a" "e13fbb7a17c2956d62435bb2fc1b9c3ad15459ebf7f66e2daf6142d62a78a758"`
Dec 15 01:53:30.315 INFO [stderr] usermod: no changes
Dec 15 01:53:30.419 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.08s
Dec 15 01:53:30.439 INFO [stderr]      Running /target/debug/deps/pest_vm-c76158a0b2c81e95
Dec 15 01:53:30.441 INFO [stderr]      Running /target/debug/deps/grammar-f78cd9c874625de0
Dec 15 01:53:30.441 INFO [stdout] 
Dec 15 01:53:30.441 INFO [stdout] running 0 tests
Dec 15 01:53:30.441 INFO [stdout] 
Dec 15 01:53:30.441 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:53:30.441 INFO [stdout] 
Dec 15 01:53:30.451 INFO [stdout] 
Dec 15 01:53:30.451 INFO [stdout] running 71 tests
Dec 15 01:53:30.471 INFO [stdout] test ascii_alpha_lowers ... ok
Dec 15 01:53:30.507 INFO [stdout] test ascii_digits ... ok
Dec 15 01:53:30.508 INFO [stdout] test ascii_bin_digits ... ok
Dec 15 01:53:30.515 INFO [stdout] test ascii_alphas ... ok
Dec 15 01:53:30.517 INFO [stdout] test ascii_alphanumerics ... ok
Dec 15 01:53:30.531 INFO [stdout] test ascii_nonzero_digits ... ok
Dec 15 01:53:30.543 INFO [stdout] test asciis ... ok
Dec 15 01:53:30.544 INFO [stdout] test ascii_alpha_uppers ... ok
Dec 15 01:53:30.567 INFO [stdout] test ascii_oct_digits ... ok
Dec 15 01:53:30.567 INFO [stdout] test ascii_hex_digits ... ok
Dec 15 01:53:30.599 INFO [stdout] test choice_range ... ok
Dec 15 01:53:30.599 INFO [stdout] test checkpoint_restore ... ok
Dec 15 01:53:30.607 INFO [stdout] test insensitive ... ok
Dec 15 01:53:30.607 INFO [stdout] test choice_string ... ok
Dec 15 01:53:30.619 INFO [stdout] test neg_pred ... ok
Dec 15 01:53:30.619 INFO [stdout] test newline ... ok
Dec 15 01:53:30.619 INFO [stdout] test double_neg_pred ... ok
Dec 15 01:53:30.643 INFO [stdout] test optional_empty ... ok
Dec 15 01:53:30.643 INFO [stdout] test ident ... ok
Dec 15 01:53:30.655 INFO [stdout] test optional_string ... ok
Dec 15 01:53:30.667 INFO [stdout] test peek ... ok
Dec 15 01:53:30.671 INFO [stdout] test pop_all ... ok
Dec 15 01:53:30.675 INFO [stdout] test pop ... ok
Dec 15 01:53:30.679 INFO [stdout] test peek_all ... ok
Dec 15 01:53:30.687 INFO [stdout] test pop_fail ... ok
Dec 15 01:53:30.691 INFO [stdout] test pos_pred ... ok
Dec 15 01:53:30.695 INFO [stdout] test range ... ok
Dec 15 01:53:30.707 INFO [stdout] test repeat_atomic_empty ... ok
Dec 15 01:53:30.719 INFO [stdout] test repeat_atomic_strings ... ok
Dec 15 01:53:30.751 INFO [stdout] test repeat_empty ... ok
Dec 15 01:53:30.759 INFO [stdout] test repeat_exact ... ok
Dec 15 01:53:30.767 INFO [stdout] test repeat_comment ... ok
Dec 15 01:53:30.767 INFO [stdout] test repeat_max_atomic_once ... ok
Dec 15 01:53:30.787 INFO [stdout] test repeat_max_atomic_twice ... ok
Dec 15 01:53:30.803 INFO [stdout] test repeat_max_once ... ok
Dec 15 01:53:30.807 INFO [stdout] test repeat_max_twice ... ok
Dec 15 01:53:30.837 INFO [stdout] test repeat_min_atomic_thrice ... ok
Dec 15 01:53:30.847 INFO [stdout] test repeat_min_atomic_twice ... ok
Dec 15 01:53:30.867 INFO [stdout] test repeat_min_max_atomic_thrice ... ok
Dec 15 01:53:30.883 INFO [stdout] test repeat_min_max_atomic_twice ... ok
Dec 15 01:53:30.898 INFO [stdout] test repeat_min_max_thrice ... ok
Dec 15 01:53:30.932 INFO [stdout] test repeat_max_atomic_thrice ... ok
Dec 15 01:53:30.932 INFO [stdout] test repeat_min_max_twice ... ok
Dec 15 01:53:30.932 INFO [stdout] test repeat_max_thrice ... ok
Dec 15 01:53:30.932 INFO [stdout] test repeat_max_atomic_space ... ok
Dec 15 01:53:30.932 INFO [stdout] test repeat_atomic_space ... ok
Dec 15 01:53:30.955 INFO [stdout] test repeat_min_thrice ... ok
Dec 15 01:53:30.971 INFO [stdout] test repeat_min_twice ... ok
Dec 15 01:53:30.995 INFO [stdout] test repeat_min_atomic_space ... ok
Dec 15 01:53:30.999 INFO [stdout] test repeat_min_max_atomic_space ... ok
Dec 15 01:53:30.999 INFO [stdout] test repeat_min_once ... ok
Dec 15 01:53:30.999 INFO [stdout] test repeat_min_atomic_once ... ok
Dec 15 01:53:30.999 INFO [stdout] test repeat_once_atomic_empty ... ok
Dec 15 01:53:31.003 INFO [stdout] test repeat_once_atomic_space ... ok
Dec 15 01:53:31.055 INFO [stdout] test repeat_mutate_stack ... ok
Dec 15 01:53:31.055 INFO [stdout] test repeat_once_atomic_strings ... ok
Dec 15 01:53:31.055 INFO [stdout] test repeat_once_empty ... ok
Dec 15 01:53:31.055 INFO [stdout] test repeat_strings ... ok
Dec 15 01:53:31.055 INFO [stdout] test repeat_once_strings ... ok
Dec 15 01:53:31.074 INFO [stdout] test sequence ... ok
Dec 15 01:53:31.074 INFO [stdout] test sequence_atomic_space ... ok
Dec 15 01:53:31.075 INFO [stdout] test sequence_atomic ... ok
Dec 15 01:53:31.083 INFO [stdout] test sequence_atomic_compound ... ok
Dec 15 01:53:31.083 INFO [stdout] test sequence_compound ... ok
Dec 15 01:53:31.083 INFO [stdout] test sequence_compound_nested ... ok
Dec 15 01:53:31.085 INFO [stdout] test sequence_non_atomic ... ok
Dec 15 01:53:31.106 INFO [stdout] test shadowing ... ok
Dec 15 01:53:31.112 INFO [stdout] test sequence_compound_nested_space ... ok
Dec 15 01:53:31.127 INFO [stdout] test string ... ok
Dec 15 01:53:31.131 INFO [stdout] test soi_at_start ... ok
Dec 15 01:53:31.135 INFO [stdout] test unicode ... ok
Dec 15 01:53:31.135 INFO [stdout] 
Dec 15 01:53:31.135 INFO [stdout] test result: ok. 71 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:53:31.135 INFO [stdout] 
Dec 15 01:53:31.137 INFO [stderr]      Running /target/debug/deps/lists-5f3af7696839534f
Dec 15 01:53:31.144 INFO [stdout] 
Dec 15 01:53:31.144 INFO [stdout] running 7 tests
Dec 15 01:53:31.161 INFO [stdout] test children ... ok
Dec 15 01:53:31.163 INFO [stdout] test nested_item ... ok
Dec 15 01:53:31.163 INFO [stdout] test nested_items ... ok
Dec 15 01:53:31.175 INFO [stdout] test item ... ok
Dec 15 01:53:31.175 INFO [stdout] test nested_then_not ... ok
Dec 15 01:53:31.175 INFO [stdout] test nested_two_levels ... ok
Dec 15 01:53:31.175 INFO [stdout] test items ... ok
Dec 15 01:53:31.175 INFO [stdout] 
Dec 15 01:53:31.175 INFO [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:53:31.175 INFO [stdout] 
Dec 15 01:53:31.176 INFO [stderr]      Running /target/debug/deps/reporting-6a16ef035bf327c2
Dec 15 01:53:31.177 INFO [stdout] 
Dec 15 01:53:31.178 INFO [stdout] running 9 tests
Dec 15 01:53:31.214 INFO [stdout] test choices_a_progress ... ok
Dec 15 01:53:31.223 INFO [stdout] test choices_no_progress ... ok
Dec 15 01:53:31.224 INFO [stdout] test mixed ... ok
Dec 15 01:53:31.225 INFO [stdout] test choices ... ok
Dec 15 01:53:31.227 INFO [stdout] test mixed_progress ... ok
Dec 15 01:53:31.231 INFO [stdout] test choices_b_progress ... ok
Dec 15 01:53:31.240 INFO [stdout] test negative_match ... ok
Dec 15 01:53:31.242 INFO [stdout] test nested ... ok
Dec 15 01:53:31.243 INFO [stderr]    Doc-tests pest_vm
Dec 15 01:53:31.243 INFO [stdout] test negative ... ok
Dec 15 01:53:31.243 INFO [stdout] 
Dec 15 01:53:31.243 INFO [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:53:31.243 INFO [stdout] 
Dec 15 01:53:32.610 INFO [stdout] 
Dec 15 01:53:32.610 INFO [stdout] running 0 tests
Dec 15 01:53:32.610 INFO [stdout] 
Dec 15 01:53:32.610 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:53:32.610 INFO [stdout] 
Dec 15 01:53:32.618 INFO [stderr] su: No module specific data is present
Dec 15 01:53:33.535 INFO running `"docker" "inspect" "e13fbb7a17c2956d62435bb2fc1b9c3ad15459ebf7f66e2daf6142d62a78a758"`
Dec 15 01:53:33.923 INFO running `"docker" "rm" "-f" "e13fbb7a17c2956d62435bb2fc1b9c3ad15459ebf7f66e2daf6142d62a78a758"`
Dec 15 01:53:34.263 INFO [stdout] e13fbb7a17c2956d62435bb2fc1b9c3ad15459ebf7f66e2daf6142d62a78a758