Dec 07 04:41:13.422 INFO testing pest-2.0.2 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 04:41:13.422 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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:41:13.638 INFO [stdout] 31eb05c50499ea9e74b49cd589b135db45bba7dc5d4a153dbe52e74bd09645dd
Dec 07 04:41:13.642 INFO running `"docker" "start" "-a" "31eb05c50499ea9e74b49cd589b135db45bba7dc5d4a153dbe52e74bd09645dd"`
Dec 07 04:41:14.090 INFO [stderr] usermod: no changes
Dec 07 04:41:14.136 INFO [stderr]    Compiling pest v2.0.2 (/source)
Dec 07 04:41:16.903 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.79s
Dec 07 04:41:16.905 INFO [stderr] su: No module specific data is present
Dec 07 04:41:17.193 INFO running `"docker" "inspect" "31eb05c50499ea9e74b49cd589b135db45bba7dc5d4a153dbe52e74bd09645dd"`
Dec 07 04:41:17.257 INFO running `"docker" "rm" "-f" "31eb05c50499ea9e74b49cd589b135db45bba7dc5d4a153dbe52e74bd09645dd"`
Dec 07 04:41:17.347 INFO [stdout] 31eb05c50499ea9e74b49cd589b135db45bba7dc5d4a153dbe52e74bd09645dd
Dec 07 04:41:17.349 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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:41:17.600 INFO [stdout] ee40483e3b666d261ca06d9bbf08f6ab80e59c72238e3dbd90357211f0922ddd
Dec 07 04:41:17.600 INFO running `"docker" "start" "-a" "ee40483e3b666d261ca06d9bbf08f6ab80e59c72238e3dbd90357211f0922ddd"`
Dec 07 04:41:18.272 INFO [stderr] usermod: no changes
Dec 07 04:41:18.324 INFO [stderr]    Compiling pest v2.0.2 (/source)
Dec 07 04:41:27.878 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.58s
Dec 07 04:41:27.881 INFO [stderr] su: No module specific data is present
Dec 07 04:41:28.404 INFO running `"docker" "inspect" "ee40483e3b666d261ca06d9bbf08f6ab80e59c72238e3dbd90357211f0922ddd"`
Dec 07 04:41:28.473 INFO running `"docker" "rm" "-f" "ee40483e3b666d261ca06d9bbf08f6ab80e59c72238e3dbd90357211f0922ddd"`
Dec 07 04:41:28.565 INFO [stdout] ee40483e3b666d261ca06d9bbf08f6ab80e59c72238e3dbd90357211f0922ddd
Dec 07 04:41:28.570 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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:41:28.787 INFO [stdout] 48c867d89b3417d53688998a9fdfaaeb62d0d6278ee3f250f6c1b8ea428358cb
Dec 07 04:41:28.789 INFO running `"docker" "start" "-a" "48c867d89b3417d53688998a9fdfaaeb62d0d6278ee3f250f6c1b8ea428358cb"`
Dec 07 04:41:29.216 INFO [stderr] usermod: no changes
Dec 07 04:41:29.243 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.01s
Dec 07 04:41:29.244 INFO [stderr]      Running /target/debug/deps/pest-51ffbd7db486e44f
Dec 07 04:41:29.246 INFO [stdout] 
Dec 07 04:41:29.246 INFO [stdout] running 46 tests
Dec 07 04:41:29.247 INFO [stdout] test error::tests::display_custom_pos ... ok
Dec 07 04:41:29.247 INFO [stdout] test error::tests::display_custom_span_three_lines ... ok
Dec 07 04:41:29.247 INFO [stdout] test error::tests::display_custom_span_two_lines ... ok
Dec 07 04:41:29.247 INFO [stdout] test error::tests::display_custom_span_two_lines_inverted_cols ... ok
Dec 07 04:41:29.247 INFO [stdout] test error::tests::display_parsing_error_positives ... ok
Dec 07 04:41:29.247 INFO [stdout] test error::tests::display_parsing_error_negatives ... ok
Dec 07 04:41:29.247 INFO [stdout] test error::tests::display_parsing_error_unknown ... ok
Dec 07 04:41:29.247 INFO [stdout] test error::tests::error_with_path ... ok
Dec 07 04:41:29.247 INFO [stdout] test error::tests::mapped_parsing_error ... ok
Dec 07 04:41:29.247 INFO [stdout] test iterators::pair::tests::pair_into_inner ... ok
Dec 07 04:41:29.248 INFO [stdout] test iterators::flat_pairs::tests::double_ended_iter_for_flat_pairs ... ok
Dec 07 04:41:29.248 INFO [stdout] test iterators::pairs::tests::double_ended_iter_for_pairs ... ok
Dec 07 04:41:29.248 INFO [stdout] test iterators::flat_pairs::tests::iter_for_flat_pairs ... ok
Dec 07 04:41:29.248 INFO [stdout] test iterators::pairs::tests::concat ... ok
Dec 07 04:41:29.248 INFO [stdout] test iterators::pairs::tests::iter_for_pairs ... ok
Dec 07 04:41:29.248 INFO [stdout] test iterators::pairs::tests::as_str ... ok
Dec 07 04:41:29.249 INFO [stdout] test iterators::pairs::tests::pairs_display ... ok
Dec 07 04:41:29.252 INFO [stdout] test iterators::pairs::tests::pairs_debug ... ok
Dec 07 04:41:29.252 INFO [stdout] test macros::tests::fails_with ... ok
Dec 07 04:41:29.252 INFO [stdout] test iterators::tokens::tests::double_ended_iter_for_tokens ... ok
Dec 07 04:41:29.252 INFO [stdout] test macros::tests::parses_to ... ok
Dec 07 04:41:29.252 INFO [stdout] test error::tests::display_parsing_error_mixed ... ok
Dec 07 04:41:29.252 INFO [stdout] test position::tests::cmp ... ok
Dec 07 04:41:29.253 INFO [stdout] test position::tests::hash ... ok
Dec 07 04:41:29.253 INFO [stdout] test position::tests::line_col ... ok
Dec 07 04:41:29.253 INFO [stdout] test position::tests::line_of ... ok
Dec 07 04:41:29.256 INFO [stdout] test position::tests::empty ... ok
Dec 07 04:41:29.256 INFO [stdout] test position::tests::line_of_between_new_line ... ok
Dec 07 04:41:29.256 INFO [stdout] test position::tests::line_of_empty ... ok
Dec 07 04:41:29.256 INFO [stdout] test position::tests::match_insensitive ... ok
Dec 07 04:41:29.257 INFO [stdout] test position::tests::match_range ... ok
Dec 07 04:41:29.257 INFO [stdout] test position::tests::parts ... ok
Dec 07 04:41:29.257 INFO [stdout] test position::tests::skip ... ok
Dec 07 04:41:29.257 INFO [stdout] test position::tests::skip_empty ... ok
Dec 07 04:41:29.257 INFO [stdout] test position::tests::skip_until ... ok
Dec 07 04:41:29.257 INFO [stdout] test span::tests::split ... ok
Dec 07 04:41:29.263 INFO [stdout] test position::tests::line_of_new_line ... ok
Dec 07 04:41:29.263 INFO [stdout] test stack::test::snapshot_with_empty ... ok
Dec 07 04:41:29.263 INFO [stdout] test stack::test::stack_ops ... ok
Dec 07 04:41:29.263 INFO [stdout] test stack::test::iter ... ok
Dec 07 04:41:29.296 INFO [stdout] test macros::tests::missing_end ... ok
Dec 07 04:41:29.296 INFO [stdout] test macros::tests::empty ... ok
Dec 07 04:41:29.296 INFO [stdout] test macros::tests::wrong_negatives ... ok
Dec 07 04:41:29.296 INFO [stdout] test macros::tests::wrong_positives ... ok
Dec 07 04:41:29.296 INFO [stdout] test macros::tests::wrong_pos ... ok
Dec 07 04:41:29.308 INFO [stdout] test position::tests::cmp_panic ... ok
Dec 07 04:41:29.308 INFO [stderr]      Running /target/debug/deps/calculator-51ab755a041d37c9
Dec 07 04:41:29.308 INFO [stdout] 
Dec 07 04:41:29.308 INFO [stdout] test result: ok. 46 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 04:41:29.308 INFO [stdout] 
Dec 07 04:41:29.312 INFO [stdout] 
Dec 07 04:41:29.312 INFO [stdout] running 4 tests
Dec 07 04:41:29.328 INFO [stdout] test parens ... ok
Dec 07 04:41:29.328 INFO [stdout] test prec_climb ... ok
Dec 07 04:41:29.336 INFO [stdout] test number ... ok
Dec 07 04:41:29.336 INFO [stdout] test expression ... ok
Dec 07 04:41:29.336 INFO [stdout] 
Dec 07 04:41:29.336 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 04:41:29.336 INFO [stdout] 
Dec 07 04:41:29.337 INFO [stderr]      Running /target/debug/deps/json-263b82b5fe481f0e
Dec 07 04:41:29.347 INFO [stdout] 
Dec 07 04:41:29.347 INFO [stdout] running 11 tests
Dec 07 04:41:29.348 INFO [stdout] test ast ... ok
Dec 07 04:41:29.348 INFO [stdout] test array_empty ... ok
Dec 07 04:41:29.348 INFO [stdout] test float ... ok
Dec 07 04:41:29.349 INFO [stdout] test bool ... ok
Dec 07 04:41:29.349 INFO [stdout] test float_with_exp ... ok
Dec 07 04:41:29.349 INFO [stdout] test number_minus_zero ... ok
Dec 07 04:41:29.349 INFO [stdout] test null ... ok
Dec 07 04:41:29.359 INFO [stdout] test string_with_escapes ... ok
Dec 07 04:41:29.370 INFO [stdout] test array ... ok
Dec 07 04:41:29.370 INFO [stdout] test object ... ok
Dec 07 04:41:29.370 INFO [stdout] test number_zero ... ok
Dec 07 04:41:29.370 INFO [stdout] 
Dec 07 04:41:29.370 INFO [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 04:41:29.370 INFO [stdout] 
Dec 07 04:41:29.371 INFO [stderr]    Doc-tests pest
Dec 07 04:41:30.336 INFO [stdout] 
Dec 07 04:41:30.336 INFO [stdout] running 57 tests
Dec 07 04:41:33.381 INFO [stdout] test src/error.rs - error::Error<R>::new_from_span (line 113) ... ok
Dec 07 04:41:34.252 INFO [stdout] test src/error.rs - error::Error<R>::new_from_pos (line 75) ... ok
Dec 07 04:41:35.669 INFO [stdout] test src/error.rs - error::Error<R>::with_path (line 159) ... ok
Dec 07 04:41:36.533 INFO [stdout] test src/iterators/pair.rs - iterators::pair::Pair<'i, R>::as_str (line 79) ... ok
Dec 07 04:41:37.840 INFO [stdout] test src/iterators/pair.rs - iterators::pair::Pair<'i, R>::as_span (line 136) ... ok
Dec 07 04:41:38.113 INFO [stdout] test src/error.rs - error::Error<R>::renamed_rules (line 195) ... ok
Dec 07 04:41:38.544 INFO [stdout] test src/iterators/flat_pairs.rs - iterators::flat_pairs::FlatPairs<'i, R>::tokens (line 48) ... ok
Dec 07 04:41:39.080 INFO [stdout] test src/iterators/pair.rs - iterators::pair::Pair<'i, R>::as_rule (line 50) ... ok
Dec 07 04:41:39.080 INFO [stdout] test src/lib.rs -  (line 49) ... ignored
Dec 07 04:41:43.913 INFO [stdout] test src/iterators/pair.rs - iterators::pair::Pair<'i, R>::into_inner (line 166) ... ok
Dec 07 04:41:44.376 INFO [stdout] test src/iterators/pairs.rs - iterators::pairs::Pairs<'i, R>::as_str (line 56) ... ok
Dec 07 04:41:45.385 INFO [stdout] test src/iterators/pair.rs - iterators::pair::Pair<'i, R>::into_span (line 109) ... ok
Dec 07 04:41:47.313 INFO [stdout] test src/iterators/pairs.rs - iterators::pairs::Pairs<'i, R>::concat (line 89) ... ok
Dec 07 04:41:47.548 INFO [stdout] test src/iterators/pair.rs - iterators::pair::Pair<'i, R>::tokens (line 194) ... ok
Dec 07 04:41:48.380 INFO [stdout] test src/iterators/pairs.rs - iterators::pairs::Pairs<'i, R>::tokens (line 147) ... ok
Dec 07 04:41:49.649 INFO [stdout] test src/iterators/pairs.rs - iterators::pairs::Pairs<'i, R>::flatten (line 117) ... ok
Dec 07 04:41:50.393 INFO [stdout] test src/macros.rs - fails_with (line 261) ... ok
Dec 07 04:41:53.944 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::atomicity (line 144) ... ok
Dec 07 04:41:55.636 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::end_of_input (line 659) ... ok
Dec 07 04:41:55.637 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::atomic (line 758) ... ok
Dec 07 04:41:56.444 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::lookahead (line 691) ... ok
Dec 07 04:41:56.920 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::match_char_by (line 448) ... ok
Dec 07 04:41:57.080 INFO [stdout] test src/macros.rs - parses_to (line 160) ... ok
Dec 07 04:41:57.453 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::match_insensitive (line 513) ... ok
Dec 07 04:41:58.596 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::match_range (line 544) ... ok
Dec 07 04:42:00.024 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::match_string (line 482) ... ok
Dec 07 04:42:01.232 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::new (line 101) ... ok
Dec 07 04:42:01.544 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::repeat (line 368) ... ok
Dec 07 04:42:01.640 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::position (line 123) ... ok
Dec 07 04:42:02.133 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::restore_on_err (line 1001) ... ok
Dec 07 04:42:02.332 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::optional (line 411) ... ok
Dec 07 04:42:03.536 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::sequence (line 321) ... ok
Dec 07 04:42:03.540 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::rule (line 167) ... ok
Dec 07 04:42:05.193 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::skip (line 575) ... ok
Dec 07 04:42:05.388 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::stack_match_peek (line 902) ... ok
Dec 07 04:42:06.825 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::skip_until (line 606) ... ok
Dec 07 04:42:06.988 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::stack_drop (line 974) ... ok
Dec 07 04:42:07.920 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::stack_match_pop (line 935) ... ok
Dec 07 04:42:08.390 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::stack_peek (line 847) ... ok
Dec 07 04:42:09.520 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::stack_push (line 811) ... ok
Dec 07 04:42:09.827 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::start_of_input (line 629) ... ok
Dec 07 04:42:09.948 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::stack_pop (line 875) ... ok
Dec 07 04:42:09.948 INFO [stdout] test src/prec_climber.rs - prec_climber::PrecClimber<R>::climb (line 154) ... ignored
Dec 07 04:42:12.006 INFO [stdout] test src/position.rs - position::Position<'i>::from_start (line 34) ... ok
Dec 07 04:42:12.320 INFO [stdout] test src/parser_state.rs - parser_state::state (line 62) ... ok
Dec 07 04:42:13.800 INFO [stdout] test src/position.rs - position::Position<'i>::pos (line 49) ... ok
Dec 07 04:42:13.838 INFO [stdout] test src/position.rs - position::Position<'i>::line_col (line 92) ... ok
Dec 07 04:42:13.880 INFO [stdout] test src/position.rs - position::Position<'i>::span (line 69) ... ok
Dec 07 04:42:14.033 INFO [stdout] test src/prec_climber.rs - prec_climber::PrecClimber<R>::new (line 100) ... ok
Dec 07 04:42:14.388 INFO [stdout] test src/position.rs - position::Position<'i>::line_of (line 154) ... ok
Dec 07 04:42:14.531 INFO [stdout] test src/prec_climber.rs - prec_climber::Operator<R>::new (line 45) ... ok
Dec 07 04:42:15.440 INFO [stdout] test src/span.rs - span::Span<'i>::end (line 55) ... ok
Dec 07 04:42:16.009 INFO [stdout] test src/span.rs - span::Span<'i>::start (line 37) ... ok
Dec 07 04:42:16.010 INFO [stdout] test src/span.rs - span::Span<'i>::split (line 111) ... ok
Dec 07 04:42:16.092 INFO [stdout] test src/span.rs - span::Span<'i>::end_pos (line 92) ... ok
Dec 07 04:42:16.092 INFO [stdout] test src/span.rs - span::Span<'i>::as_str (line 133) ... ok
Dec 07 04:42:16.122 INFO [stdout] test src/span.rs - span::Span<'i>::start_pos (line 73) ... ok
Dec 07 04:42:16.122 INFO [stdout] 
Dec 07 04:42:16.122 INFO [stdout] test result: ok. 55 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out
Dec 07 04:42:16.122 INFO [stdout] 
Dec 07 04:42:16.167 INFO [stderr] su: No module specific data is present
Dec 07 04:42:16.708 INFO running `"docker" "inspect" "48c867d89b3417d53688998a9fdfaaeb62d0d6278ee3f250f6c1b8ea428358cb"`
Dec 07 04:42:16.865 INFO running `"docker" "rm" "-f" "48c867d89b3417d53688998a9fdfaaeb62d0d6278ee3f250f6c1b8ea428358cb"`
Dec 07 04:42:17.107 INFO [stdout] 48c867d89b3417d53688998a9fdfaaeb62d0d6278ee3f250f6c1b8ea428358cb