Dec 15 01:53:02.986 INFO testing pest_vm-2.0.0 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 01:53:02.986 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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:53:03.407 INFO [stdout] c176cf331d3ca3ee3a240c8be7c1f542caf0bdba9ada77bca147fa6b8df0ed1e
Dec 15 01:53:03.411 INFO running `"docker" "start" "-a" "c176cf331d3ca3ee3a240c8be7c1f542caf0bdba9ada77bca147fa6b8df0ed1e"`
Dec 15 01:53:04.455 INFO [stderr] usermod: no changes
Dec 15 01:53:04.543 INFO [stderr]    Compiling pest_vm v2.0.0 (/source)
Dec 15 01:53:06.700 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.22s
Dec 15 01:53:06.703 INFO [stderr] su: No module specific data is present
Dec 15 01:53:07.503 INFO running `"docker" "inspect" "c176cf331d3ca3ee3a240c8be7c1f542caf0bdba9ada77bca147fa6b8df0ed1e"`
Dec 15 01:53:07.802 INFO running `"docker" "rm" "-f" "c176cf331d3ca3ee3a240c8be7c1f542caf0bdba9ada77bca147fa6b8df0ed1e"`
Dec 15 01:53:08.107 INFO [stdout] c176cf331d3ca3ee3a240c8be7c1f542caf0bdba9ada77bca147fa6b8df0ed1e
Dec 15 01:53:08.119 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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:53:08.712 INFO [stdout] 718017613d803cc72ee0cee160721f652a1dca0126f7c72a8d9ed4a280e9f9a1
Dec 15 01:53:08.715 INFO running `"docker" "start" "-a" "718017613d803cc72ee0cee160721f652a1dca0126f7c72a8d9ed4a280e9f9a1"`
Dec 15 01:53:10.152 INFO [stderr] usermod: no changes
Dec 15 01:53:10.255 INFO [stderr]    Compiling pest_vm v2.0.0 (/source)
Dec 15 01:53:24.300 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.12s
Dec 15 01:53:24.304 INFO [stderr] su: No module specific data is present
Dec 15 01:53:25.440 INFO running `"docker" "inspect" "718017613d803cc72ee0cee160721f652a1dca0126f7c72a8d9ed4a280e9f9a1"`
Dec 15 01:53:25.750 INFO running `"docker" "rm" "-f" "718017613d803cc72ee0cee160721f652a1dca0126f7c72a8d9ed4a280e9f9a1"`
Dec 15 01:53:26.203 INFO [stdout] 718017613d803cc72ee0cee160721f652a1dca0126f7c72a8d9ed4a280e9f9a1
Dec 15 01:53:26.211 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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:53:26.677 INFO [stdout] 601bba439bbb1789f2b33a6b9079cd7ac56dd9c376ee30621b19ece2d817b112
Dec 15 01:53:26.687 INFO running `"docker" "start" "-a" "601bba439bbb1789f2b33a6b9079cd7ac56dd9c376ee30621b19ece2d817b112"`
Dec 15 01:53:27.900 INFO [stderr] usermod: no changes
Dec 15 01:53:28.016 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.08s
Dec 15 01:53:28.034 INFO [stderr]      Running /target/debug/deps/pest_vm-7a5aa9cc76f5aff3
Dec 15 01:53:28.034 INFO [stdout] 
Dec 15 01:53:28.034 INFO [stdout] running 0 tests
Dec 15 01:53:28.034 INFO [stdout] 
Dec 15 01:53:28.034 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:53:28.034 INFO [stdout] 
Dec 15 01:53:28.034 INFO [stderr]      Running /target/debug/deps/grammar-b68b410f11cb28f7
Dec 15 01:53:28.036 INFO [stdout] 
Dec 15 01:53:28.036 INFO [stdout] running 71 tests
Dec 15 01:53:28.063 INFO [stdout] test ascii_alpha_uppers ... ok
Dec 15 01:53:28.068 INFO [stdout] test ascii_alphanumerics ... ok
Dec 15 01:53:28.107 INFO [stdout] test ascii_alpha_lowers ... ok
Dec 15 01:53:28.116 INFO [stdout] test ascii_alphas ... ok
Dec 15 01:53:28.118 INFO [stdout] test ascii_nonzero_digits ... ok
Dec 15 01:53:28.122 INFO [stdout] test ascii_hex_digits ... ok
Dec 15 01:53:28.129 INFO [stdout] test ascii_bin_digits ... ok
Dec 15 01:53:28.135 INFO [stdout] test ascii_digits ... ok
Dec 15 01:53:28.149 INFO [stdout] test ascii_oct_digits ... ok
Dec 15 01:53:28.153 INFO [stdout] test asciis ... ok
Dec 15 01:53:28.167 INFO [stdout] test double_neg_pred ... ok
Dec 15 01:53:28.190 INFO [stdout] test checkpoint_restore ... ok
Dec 15 01:53:28.194 INFO [stdout] test choice_string ... ok
Dec 15 01:53:28.197 INFO [stdout] test newline ... ok
Dec 15 01:53:28.204 INFO [stdout] test insensitive ... ok
Dec 15 01:53:28.214 INFO [stdout] test ident ... ok
Dec 15 01:53:28.235 INFO [stdout] test choice_range ... ok
Dec 15 01:53:28.235 INFO [stdout] test optional_empty ... ok
Dec 15 01:53:28.236 INFO [stdout] test optional_string ... ok
Dec 15 01:53:28.237 INFO [stdout] test neg_pred ... ok
Dec 15 01:53:28.239 INFO [stdout] test peek ... ok
Dec 15 01:53:28.257 INFO [stdout] test pop ... ok
Dec 15 01:53:28.271 INFO [stdout] test repeat_atomic_strings ... ok
Dec 15 01:53:28.275 INFO [stdout] test peek_all ... ok
Dec 15 01:53:28.277 INFO [stdout] test pop_fail ... ok
Dec 15 01:53:28.278 INFO [stdout] test pop_all ... ok
Dec 15 01:53:28.282 INFO [stdout] test pos_pred ... ok
Dec 15 01:53:28.282 INFO [stdout] test repeat_atomic_empty ... ok
Dec 15 01:53:28.295 INFO [stdout] test range ... ok
Dec 15 01:53:28.335 INFO [stdout] test repeat_empty ... ok
Dec 15 01:53:28.342 INFO [stdout] test repeat_exact ... ok
Dec 15 01:53:28.347 INFO [stdout] test repeat_max_atomic_once ... ok
Dec 15 01:53:28.354 INFO [stdout] test repeat_comment ... ok
Dec 15 01:53:28.387 INFO [stdout] test repeat_max_once ... ok
Dec 15 01:53:28.387 INFO [stdout] test repeat_max_atomic_twice ... ok
Dec 15 01:53:28.439 INFO [stdout] test repeat_max_twice ... ok
Dec 15 01:53:28.439 INFO [stdout] test repeat_min_atomic_thrice ... ok
Dec 15 01:53:28.463 INFO [stdout] test repeat_min_atomic_twice ... ok
Dec 15 01:53:28.470 INFO [stdout] test repeat_min_max_atomic_thrice ... ok
Dec 15 01:53:28.483 INFO [stdout] test repeat_max_atomic_space ... ok
Dec 15 01:53:28.483 INFO [stdout] test repeat_max_thrice ... ok
Dec 15 01:53:28.483 INFO [stdout] test repeat_atomic_space ... ok
Dec 15 01:53:28.491 INFO [stdout] test repeat_max_atomic_thrice ... ok
Dec 15 01:53:28.491 INFO [stdout] test repeat_min_atomic_once ... ok
Dec 15 01:53:28.491 INFO [stdout] test repeat_min_max_thrice ... ok
Dec 15 01:53:28.491 INFO [stdout] test repeat_min_max_atomic_twice ... ok
Dec 15 01:53:28.491 INFO [stdout] test repeat_min_atomic_space ... ok
Dec 15 01:53:28.503 INFO [stdout] test repeat_min_max_atomic_space ... ok
Dec 15 01:53:28.507 INFO [stdout] test repeat_min_thrice ... ok
Dec 15 01:53:28.507 INFO [stdout] test repeat_min_max_twice ... ok
Dec 15 01:53:28.515 INFO [stdout] test repeat_min_once ... ok
Dec 15 01:53:28.515 INFO [stdout] test repeat_mutate_stack ... ok
Dec 15 01:53:28.515 INFO [stdout] test repeat_once_atomic_space ... ok
Dec 15 01:53:28.527 INFO [stdout] test repeat_once_atomic_empty ... ok
Dec 15 01:53:28.542 INFO [stdout] test repeat_min_twice ... ok
Dec 15 01:53:28.542 INFO [stdout] test repeat_once_atomic_strings ... ok
Dec 15 01:53:28.559 INFO [stdout] test sequence_atomic ... ok
Dec 15 01:53:28.583 INFO [stdout] test repeat_once_strings ... ok
Dec 15 01:53:28.583 INFO [stdout] test sequence ... ok
Dec 15 01:53:28.595 INFO [stdout] test sequence_atomic_compound ... ok
Dec 15 01:53:28.595 INFO [stdout] test repeat_once_empty ... ok
Dec 15 01:53:28.595 INFO [stdout] test repeat_strings ... ok
Dec 15 01:53:28.611 INFO [stdout] test sequence_compound ... ok
Dec 15 01:53:28.619 INFO [stdout] test sequence_atomic_space ... ok
Dec 15 01:53:28.623 INFO [stdout] test sequence_compound_nested ... ok
Dec 15 01:53:28.635 INFO [stdout] test soi_at_start ... ok
Dec 15 01:53:28.639 INFO [stdout] test sequence_compound_nested_space ... ok
Dec 15 01:53:28.643 INFO [stdout] test string ... ok
Dec 15 01:53:28.647 INFO [stdout] test shadowing ... ok
Dec 15 01:53:28.663 INFO [stdout] test unicode ... ok
Dec 15 01:53:28.663 INFO [stdout] test sequence_non_atomic ... ok
Dec 15 01:53:28.663 INFO [stdout] 
Dec 15 01:53:28.663 INFO [stdout] test result: ok. 71 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:53:28.663 INFO [stdout] 
Dec 15 01:53:28.679 INFO [stderr]      Running /target/debug/deps/lists-091888e4ffa55755
Dec 15 01:53:28.687 INFO [stdout] 
Dec 15 01:53:28.687 INFO [stdout] running 7 tests
Dec 15 01:53:28.695 INFO [stdout] test nested_items ... ok
Dec 15 01:53:28.695 INFO [stdout] test items ... ok
Dec 15 01:53:28.695 INFO [stdout] test nested_then_not ... ok
Dec 15 01:53:28.699 INFO [stdout] test nested_item ... ok
Dec 15 01:53:28.711 INFO [stdout] test item ... ok
Dec 15 01:53:28.711 INFO [stdout] test nested_two_levels ... ok
Dec 15 01:53:28.711 INFO [stdout] test children ... ok
Dec 15 01:53:28.711 INFO [stdout] 
Dec 15 01:53:28.711 INFO [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:53:28.711 INFO [stdout] 
Dec 15 01:53:28.719 INFO [stdout] 
Dec 15 01:53:28.719 INFO [stderr]      Running /target/debug/deps/reporting-f8177c5e855ab236
Dec 15 01:53:28.719 INFO [stdout] running 9 tests
Dec 15 01:53:28.719 INFO [stdout] test choices ... ok
Dec 15 01:53:28.739 INFO [stdout] test mixed_progress ... ok
Dec 15 01:53:28.739 INFO [stdout] test choices_a_progress ... ok
Dec 15 01:53:28.739 INFO [stdout] test mixed ... ok
Dec 15 01:53:28.743 INFO [stdout] test choices_b_progress ... ok
Dec 15 01:53:28.755 INFO [stdout] test negative ... ok
Dec 15 01:53:28.755 INFO [stderr]    Doc-tests pest_vm
Dec 15 01:53:28.755 INFO [stdout] test choices_no_progress ... ok
Dec 15 01:53:28.755 INFO [stdout] test nested ... ok
Dec 15 01:53:28.755 INFO [stdout] test negative_match ... ok
Dec 15 01:53:28.755 INFO [stdout] 
Dec 15 01:53:28.755 INFO [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:53:28.755 INFO [stdout] 
Dec 15 01:53:30.123 INFO [stdout] 
Dec 15 01:53:30.123 INFO [stdout] running 0 tests
Dec 15 01:53:30.123 INFO [stdout] 
Dec 15 01:53:30.123 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:53:30.123 INFO [stdout] 
Dec 15 01:53:30.133 INFO [stderr] su: No module specific data is present
Dec 15 01:53:31.228 INFO running `"docker" "inspect" "601bba439bbb1789f2b33a6b9079cd7ac56dd9c376ee30621b19ece2d817b112"`
Dec 15 01:53:31.631 INFO running `"docker" "rm" "-f" "601bba439bbb1789f2b33a6b9079cd7ac56dd9c376ee30621b19ece2d817b112"`
Dec 15 01:53:31.987 INFO [stdout] 601bba439bbb1789f2b33a6b9079cd7ac56dd9c376ee30621b19ece2d817b112