Dec 07 04:40:09.309 INFO testing pest_vm-2.0.0 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 04:40:09.309 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-0/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 07 04:40:09.984 INFO [stdout] d7d7a77abd24af570bf8e5176956fadc4ca8c322989efa09727a70125e231b7a
Dec 07 04:40:10.044 INFO running `"docker" "start" "-a" "d7d7a77abd24af570bf8e5176956fadc4ca8c322989efa09727a70125e231b7a"`
Dec 07 04:40:11.300 INFO [stderr] usermod: no changes
Dec 07 04:40:11.406 INFO [stderr]    Compiling pest_vm v2.0.0 (/source)
Dec 07 04:40:13.236 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.90s
Dec 07 04:40:13.239 INFO [stderr] su: No module specific data is present
Dec 07 04:40:14.038 INFO running `"docker" "inspect" "d7d7a77abd24af570bf8e5176956fadc4ca8c322989efa09727a70125e231b7a"`
Dec 07 04:40:14.222 INFO running `"docker" "rm" "-f" "d7d7a77abd24af570bf8e5176956fadc4ca8c322989efa09727a70125e231b7a"`
Dec 07 04:40:14.520 INFO [stdout] d7d7a77abd24af570bf8e5176956fadc4ca8c322989efa09727a70125e231b7a
Dec 07 04:40:14.522 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-0/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 07 04:40:14.810 INFO [stdout] c00b427c8606ca46857a08043861a1afd03467476b74eb161f599860f25f2eb0
Dec 07 04:40:14.816 INFO running `"docker" "start" "-a" "c00b427c8606ca46857a08043861a1afd03467476b74eb161f599860f25f2eb0"`
Dec 07 04:40:15.784 INFO [stderr] usermod: no changes
Dec 07 04:40:15.915 INFO [stderr]    Compiling pest_vm v2.0.0 (/source)
Dec 07 04:40:27.730 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.92s
Dec 07 04:40:27.734 INFO [stderr] su: No module specific data is present
Dec 07 04:40:28.998 INFO running `"docker" "inspect" "c00b427c8606ca46857a08043861a1afd03467476b74eb161f599860f25f2eb0"`
Dec 07 04:40:29.333 INFO running `"docker" "rm" "-f" "c00b427c8606ca46857a08043861a1afd03467476b74eb161f599860f25f2eb0"`
Dec 07 04:40:29.692 INFO [stdout] c00b427c8606ca46857a08043861a1afd03467476b74eb161f599860f25f2eb0
Dec 07 04:40:29.698 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-0/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 07 04:40:30.138 INFO [stdout] 3df3cda32428694c3efb8e327b645418e0b5382a4445e08231d9c8240249f87c
Dec 07 04:40:30.140 INFO running `"docker" "start" "-a" "3df3cda32428694c3efb8e327b645418e0b5382a4445e08231d9c8240249f87c"`
Dec 07 04:40:31.979 INFO [stderr] usermod: no changes
Dec 07 04:40:32.064 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.05s
Dec 07 04:40:32.078 INFO [stderr]      Running /target/debug/deps/pest_vm-398e629851c6307d
Dec 07 04:40:32.079 INFO [stdout] 
Dec 07 04:40:32.079 INFO [stdout] running 0 tests
Dec 07 04:40:32.079 INFO [stdout] 
Dec 07 04:40:32.079 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 04:40:32.079 INFO [stdout] 
Dec 07 04:40:32.079 INFO [stderr]      Running /target/debug/deps/grammar-582e279f47513662
Dec 07 04:40:32.081 INFO [stdout] 
Dec 07 04:40:32.081 INFO [stdout] running 71 tests
Dec 07 04:40:32.136 INFO [stdout] test ascii_alphanumerics ... ok
Dec 07 04:40:32.153 INFO [stdout] test ascii_bin_digits ... ok
Dec 07 04:40:32.153 INFO [stdout] test ascii_hex_digits ... ok
Dec 07 04:40:32.158 INFO [stdout] test ascii_alpha_lowers ... ok
Dec 07 04:40:32.183 INFO [stdout] test ascii_digits ... ok
Dec 07 04:40:32.204 INFO [stdout] test ascii_alphas ... ok
Dec 07 04:40:32.204 INFO [stdout] test ascii_nonzero_digits ... ok
Dec 07 04:40:32.300 INFO [stdout] test ascii_oct_digits ... ok
Dec 07 04:40:32.300 INFO [stdout] test ascii_alpha_uppers ... ok
Dec 07 04:40:32.300 INFO [stdout] test asciis ... ok
Dec 07 04:40:32.308 INFO [stdout] test ident ... ok
Dec 07 04:40:32.308 INFO [stdout] test choice_string ... ok
Dec 07 04:40:32.332 INFO [stdout] test insensitive ... ok
Dec 07 04:40:32.360 INFO [stdout] test checkpoint_restore ... ok
Dec 07 04:40:32.360 INFO [stdout] test choice_range ... ok
Dec 07 04:40:32.360 INFO [stdout] test double_neg_pred ... ok
Dec 07 04:40:32.380 INFO [stdout] test pop ... ok
Dec 07 04:40:32.380 INFO [stdout] test neg_pred ... ok
Dec 07 04:40:32.444 INFO [stdout] test newline ... ok
Dec 07 04:40:32.444 INFO [stdout] test optional_empty ... ok
Dec 07 04:40:32.444 INFO [stdout] test optional_string ... ok
Dec 07 04:40:32.444 INFO [stdout] test peek_all ... ok
Dec 07 04:40:32.444 INFO [stdout] test peek ... ok
Dec 07 04:40:32.528 INFO [stdout] test pop_fail ... ok
Dec 07 04:40:32.528 INFO [stdout] test pos_pred ... ok
Dec 07 04:40:32.532 INFO [stdout] test repeat_atomic_empty ... ok
Dec 07 04:40:32.556 INFO [stdout] test repeat_max_atomic_once ... ok
Dec 07 04:40:32.556 INFO [stdout] test pop_all ... ok
Dec 07 04:40:32.556 INFO [stdout] test range ... ok
Dec 07 04:40:32.556 INFO [stdout] test repeat_atomic_strings ... ok
Dec 07 04:40:32.556 INFO [stdout] test repeat_empty ... ok
Dec 07 04:40:32.556 INFO [stdout] test repeat_comment ... ok
Dec 07 04:40:32.576 INFO [stdout] test repeat_exact ... ok
Dec 07 04:40:32.654 INFO [stdout] test repeat_max_twice ... ok
Dec 07 04:40:32.664 INFO [stdout] test repeat_max_atomic_twice ... ok
Dec 07 04:40:32.665 INFO [stdout] test repeat_max_once ... ok
Dec 07 04:40:32.696 INFO [stdout] test repeat_min_atomic_twice ... ok
Dec 07 04:40:32.696 INFO [stdout] test repeat_min_atomic_thrice ... ok
Dec 07 04:40:32.737 INFO [stdout] test repeat_min_max_atomic_thrice ... ok
Dec 07 04:40:32.776 INFO [stdout] test repeat_min_max_atomic_twice ... ok
Dec 07 04:40:32.796 INFO [stdout] test repeat_min_max_thrice ... ok
Dec 07 04:40:32.844 INFO [stdout] test repeat_min_max_twice ... ok
Dec 07 04:40:32.857 INFO [stdout] test repeat_max_thrice ... ok
Dec 07 04:40:32.857 INFO [stdout] test repeat_max_atomic_thrice ... ok
Dec 07 04:40:32.857 INFO [stdout] test repeat_max_atomic_space ... ok
Dec 07 04:40:32.880 INFO [stdout] test repeat_atomic_space ... ok
Dec 07 04:40:32.916 INFO [stdout] test repeat_min_thrice ... ok
Dec 07 04:40:32.916 INFO [stdout] test repeat_min_twice ... ok
Dec 07 04:40:32.936 INFO [stdout] test repeat_min_atomic_once ... ok
Dec 07 04:40:32.941 INFO [stdout] test repeat_min_max_atomic_space ... ok
Dec 07 04:40:32.941 INFO [stdout] test repeat_min_once ... ok
Dec 07 04:40:32.941 INFO [stdout] test repeat_min_atomic_space ... ok
Dec 07 04:40:32.941 INFO [stdout] test repeat_once_atomic_empty ... ok
Dec 07 04:40:32.944 INFO [stdout] test repeat_mutate_stack ... ok
Dec 07 04:40:32.952 INFO [stdout] test repeat_once_atomic_space ... ok
Dec 07 04:40:32.964 INFO [stdout] test repeat_strings ... ok
Dec 07 04:40:32.968 INFO [stdout] test repeat_once_atomic_strings ... ok
Dec 07 04:40:32.968 INFO [stdout] test repeat_once_strings ... ok
Dec 07 04:40:32.976 INFO [stdout] test repeat_once_empty ... ok
Dec 07 04:40:32.996 INFO [stdout] test sequence_atomic_space ... ok
Dec 07 04:40:32.996 INFO [stdout] test sequence_compound ... ok
Dec 07 04:40:32.996 INFO [stdout] test sequence_atomic_compound ... ok
Dec 07 04:40:33.004 INFO [stdout] test sequence ... ok
Dec 07 04:40:33.004 INFO [stdout] test sequence_atomic ... ok
Dec 07 04:40:33.024 INFO [stdout] test sequence_compound_nested_space ... ok
Dec 07 04:40:33.024 INFO [stdout] test sequence_non_atomic ... ok
Dec 07 04:40:33.032 INFO [stdout] test shadowing ... ok
Dec 07 04:40:33.032 INFO [stdout] test string ... ok
Dec 07 04:40:33.052 INFO [stdout] test sequence_compound_nested ... ok
Dec 07 04:40:33.056 INFO [stdout] test unicode ... ok
Dec 07 04:40:33.084 INFO [stdout] test soi_at_start ... ok
Dec 07 04:40:33.084 INFO [stderr]      Running /target/debug/deps/lists-15a6624c8dbbbc01
Dec 07 04:40:33.084 INFO [stdout] 
Dec 07 04:40:33.084 INFO [stdout] test result: ok. 71 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 04:40:33.084 INFO [stdout] 
Dec 07 04:40:33.092 INFO [stdout] 
Dec 07 04:40:33.092 INFO [stdout] running 7 tests
Dec 07 04:40:33.100 INFO [stdout] test children ... ok
Dec 07 04:40:33.100 INFO [stdout] test nested_items ... ok
Dec 07 04:40:33.104 INFO [stdout] test items ... ok
Dec 07 04:40:33.108 INFO [stdout] test nested_item ... ok
Dec 07 04:40:33.108 INFO [stdout] test nested_two_levels ... ok
Dec 07 04:40:33.108 INFO [stdout] test nested_then_not ... ok
Dec 07 04:40:33.112 INFO [stdout] test item ... ok
Dec 07 04:40:33.112 INFO [stderr]      Running /target/debug/deps/reporting-b1cf23b36fbde177
Dec 07 04:40:33.112 INFO [stdout] 
Dec 07 04:40:33.112 INFO [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 04:40:33.112 INFO [stdout] 
Dec 07 04:40:33.120 INFO [stdout] 
Dec 07 04:40:33.120 INFO [stdout] running 9 tests
Dec 07 04:40:33.128 INFO [stdout] test choices ... ok
Dec 07 04:40:33.132 INFO [stdout] test mixed ... ok
Dec 07 04:40:33.132 INFO [stdout] test choices_no_progress ... ok
Dec 07 04:40:33.140 INFO [stdout] test choices_a_progress ... ok
Dec 07 04:40:33.140 INFO [stdout] test mixed_progress ... ok
Dec 07 04:40:33.140 INFO [stdout] test choices_b_progress ... ok
Dec 07 04:40:33.160 INFO [stdout] test nested ... ok
Dec 07 04:40:33.164 INFO [stdout] test negative_match ... ok
Dec 07 04:40:33.164 INFO [stderr]    Doc-tests pest_vm
Dec 07 04:40:33.164 INFO [stdout] test negative ... ok
Dec 07 04:40:33.164 INFO [stdout] 
Dec 07 04:40:33.164 INFO [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 04:40:33.164 INFO [stdout] 
Dec 07 04:40:33.944 INFO [stdout] 
Dec 07 04:40:33.944 INFO [stdout] running 0 tests
Dec 07 04:40:33.944 INFO [stdout] 
Dec 07 04:40:33.944 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 04:40:33.944 INFO [stdout] 
Dec 07 04:40:33.960 INFO [stderr] su: No module specific data is present
Dec 07 04:40:35.034 INFO running `"docker" "inspect" "3df3cda32428694c3efb8e327b645418e0b5382a4445e08231d9c8240249f87c"`
Dec 07 04:40:35.396 INFO running `"docker" "rm" "-f" "3df3cda32428694c3efb8e327b645418e0b5382a4445e08231d9c8240249f87c"`
Dec 07 04:40:35.652 INFO [stdout] 3df3cda32428694c3efb8e327b645418e0b5382a4445e08231d9c8240249f87c