Dec 15 01:54:14.652 INFO testing pest-2.0.2 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 15 01:54:14.652 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:54:14.836 INFO [stdout] c79194224857f87b2ea30454bf40b6646656e769369df1af76b1e8f00ab694d3
Dec 15 01:54:14.838 INFO running `"docker" "start" "-a" "c79194224857f87b2ea30454bf40b6646656e769369df1af76b1e8f00ab694d3"`
Dec 15 01:54:15.303 INFO [stderr] usermod: no changes
Dec 15 01:54:15.350 INFO [stderr]    Compiling pest v2.0.2 (/source)
Dec 15 01:54:18.187 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.85s
Dec 15 01:54:18.190 INFO [stderr] su: No module specific data is present
Dec 15 01:54:18.548 INFO running `"docker" "inspect" "c79194224857f87b2ea30454bf40b6646656e769369df1af76b1e8f00ab694d3"`
Dec 15 01:54:18.644 INFO running `"docker" "rm" "-f" "c79194224857f87b2ea30454bf40b6646656e769369df1af76b1e8f00ab694d3"`
Dec 15 01:54:18.751 INFO [stdout] c79194224857f87b2ea30454bf40b6646656e769369df1af76b1e8f00ab694d3
Dec 15 01:54:18.757 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:54:18.963 INFO [stdout] 091463b8190c259ced09d51f745b5b8b1fd69a4e3a77cf1fad74558af7536fb6
Dec 15 01:54:18.964 INFO running `"docker" "start" "-a" "091463b8190c259ced09d51f745b5b8b1fd69a4e3a77cf1fad74558af7536fb6"`
Dec 15 01:54:19.695 INFO [stderr] usermod: no changes
Dec 15 01:54:19.756 INFO [stderr]    Compiling pest v2.0.2 (/source)
Dec 15 01:54:29.900 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.18s
Dec 15 01:54:29.903 INFO [stderr] su: No module specific data is present
Dec 15 01:54:30.243 INFO running `"docker" "inspect" "091463b8190c259ced09d51f745b5b8b1fd69a4e3a77cf1fad74558af7536fb6"`
Dec 15 01:54:30.387 INFO running `"docker" "rm" "-f" "091463b8190c259ced09d51f745b5b8b1fd69a4e3a77cf1fad74558af7536fb6"`
Dec 15 01:54:30.702 INFO [stdout] 091463b8190c259ced09d51f745b5b8b1fd69a4e3a77cf1fad74558af7536fb6
Dec 15 01:54:30.709 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:54:31.076 INFO [stdout] c184916d462e952041c014db838eb63133b40c2eeeccf156510cfd87ae850dfc
Dec 15 01:54:31.078 INFO running `"docker" "start" "-a" "c184916d462e952041c014db838eb63133b40c2eeeccf156510cfd87ae850dfc"`
Dec 15 01:54:32.267 INFO [stderr] usermod: no changes
Dec 15 01:54:32.354 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Dec 15 01:54:32.354 INFO [stderr]      Running /target/debug/deps/pest-0f2b53819eb45100
Dec 15 01:54:32.354 INFO [stdout] 
Dec 15 01:54:32.354 INFO [stdout] running 46 tests
Dec 15 01:54:32.423 INFO [stdout] test error::tests::display_custom_span_two_lines ... ok
Dec 15 01:54:32.466 INFO [stdout] test error::tests::display_custom_pos ... ok
Dec 15 01:54:32.466 INFO [stdout] test error::tests::display_custom_span_three_lines ... ok
Dec 15 01:54:32.466 INFO [stdout] test error::tests::display_custom_span_two_lines_inverted_cols ... ok
Dec 15 01:54:32.466 INFO [stdout] test error::tests::display_parsing_error_mixed ... ok
Dec 15 01:54:32.466 INFO [stdout] test error::tests::display_parsing_error_negatives ... ok
Dec 15 01:54:32.466 INFO [stdout] test error::tests::display_parsing_error_positives ... ok
Dec 15 01:54:32.466 INFO [stdout] test iterators::pairs::tests::concat ... ok
Dec 15 01:54:32.466 INFO [stdout] test iterators::pairs::tests::as_str ... ok
Dec 15 01:54:32.466 INFO [stdout] test iterators::pairs::tests::double_ended_iter_for_pairs ... ok
Dec 15 01:54:32.466 INFO [stdout] test iterators::pairs::tests::iter_for_pairs ... ok
Dec 15 01:54:32.466 INFO [stdout] test iterators::pairs::tests::pairs_display ... ok
Dec 15 01:54:32.466 INFO [stdout] test iterators::pairs::tests::pairs_debug ... ok
Dec 15 01:54:32.466 INFO [stdout] test iterators::tokens::tests::double_ended_iter_for_tokens ... ok
Dec 15 01:54:32.466 INFO [stdout] test error::tests::display_parsing_error_unknown ... ok
Dec 15 01:54:32.466 INFO [stdout] test macros::tests::fails_with ... ok
Dec 15 01:54:32.466 INFO [stdout] test iterators::pair::tests::pair_into_inner ... ok
Dec 15 01:54:32.466 INFO [stdout] test error::tests::error_with_path ... ok
Dec 15 01:54:32.466 INFO [stdout] test error::tests::mapped_parsing_error ... ok
Dec 15 01:54:32.466 INFO [stdout] test iterators::flat_pairs::tests::double_ended_iter_for_flat_pairs ... ok
Dec 15 01:54:32.466 INFO [stdout] test iterators::flat_pairs::tests::iter_for_flat_pairs ... ok
Dec 15 01:54:32.466 INFO [stdout] test macros::tests::parses_to ... ok
Dec 15 01:54:32.466 INFO [stdout] test position::tests::cmp ... ok
Dec 15 01:54:32.466 INFO [stdout] test position::tests::hash ... ok
Dec 15 01:54:32.466 INFO [stdout] test position::tests::empty ... ok
Dec 15 01:54:32.466 INFO [stdout] test position::tests::line_of ... ok
Dec 15 01:54:32.466 INFO [stdout] test position::tests::line_col ... ok
Dec 15 01:54:32.466 INFO [stdout] test position::tests::line_of_between_new_line ... ok
Dec 15 01:54:32.466 INFO [stdout] test position::tests::line_of_empty ... ok
Dec 15 01:54:32.466 INFO [stdout] test position::tests::line_of_new_line ... ok
Dec 15 01:54:32.466 INFO [stdout] test position::tests::match_insensitive ... ok
Dec 15 01:54:32.466 INFO [stdout] test position::tests::parts ... ok
Dec 15 01:54:32.466 INFO [stdout] test position::tests::skip ... ok
Dec 15 01:54:32.466 INFO [stdout] test position::tests::skip_empty ... ok
Dec 15 01:54:32.466 INFO [stdout] test position::tests::skip_until ... ok
Dec 15 01:54:32.466 INFO [stdout] test span::tests::split ... ok
Dec 15 01:54:32.466 INFO [stdout] test stack::test::iter ... ok
Dec 15 01:54:32.466 INFO [stdout] test stack::test::snapshot_with_empty ... ok
Dec 15 01:54:32.466 INFO [stdout] test stack::test::stack_ops ... ok
Dec 15 01:54:32.473 INFO [stdout] test position::tests::match_range ... ok
Dec 15 01:54:32.495 INFO [stdout] test macros::tests::empty ... ok
Dec 15 01:54:32.495 INFO [stdout] test macros::tests::missing_end ... ok
Dec 15 01:54:32.495 INFO [stdout] test macros::tests::wrong_positives ... ok
Dec 15 01:54:32.511 INFO [stdout] test macros::tests::wrong_pos ... ok
Dec 15 01:54:32.511 INFO [stdout] test macros::tests::wrong_negatives ... ok
Dec 15 01:54:32.513 INFO [stdout] test position::tests::cmp_panic ... ok
Dec 15 01:54:32.513 INFO [stdout] 
Dec 15 01:54:32.513 INFO [stdout] test result: ok. 46 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:54:32.513 INFO [stdout] 
Dec 15 01:54:32.514 INFO [stderr]      Running /target/debug/deps/calculator-f434bb4e67e3b9a5
Dec 15 01:54:32.515 INFO [stdout] 
Dec 15 01:54:32.516 INFO [stdout] running 4 tests
Dec 15 01:54:32.516 INFO [stdout] test prec_climb ... ok
Dec 15 01:54:32.516 INFO [stdout] test parens ... ok
Dec 15 01:54:32.516 INFO [stdout] test number ... ok
Dec 15 01:54:32.523 INFO [stdout] test expression ... ok
Dec 15 01:54:32.523 INFO [stdout] 
Dec 15 01:54:32.523 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:54:32.523 INFO [stdout] 
Dec 15 01:54:32.524 INFO [stderr]      Running /target/debug/deps/json-05058d15709beeef
Dec 15 01:54:32.539 INFO [stdout] 
Dec 15 01:54:32.539 INFO [stdout] running 11 tests
Dec 15 01:54:32.617 INFO [stdout] test array_empty ... ok
Dec 15 01:54:32.617 INFO [stderr]    Doc-tests pest
Dec 15 01:54:32.617 INFO [stdout] test ast ... ok
Dec 15 01:54:32.617 INFO [stdout] test bool ... ok
Dec 15 01:54:32.617 INFO [stdout] test null ... ok
Dec 15 01:54:32.617 INFO [stdout] test float_with_exp ... ok
Dec 15 01:54:32.617 INFO [stdout] test number_minus_zero ... ok
Dec 15 01:54:32.617 INFO [stdout] test float ... ok
Dec 15 01:54:32.617 INFO [stdout] test array ... ok
Dec 15 01:54:32.617 INFO [stdout] test number_zero ... ok
Dec 15 01:54:32.617 INFO [stdout] test object ... ok
Dec 15 01:54:32.617 INFO [stdout] test string_with_escapes ... ok
Dec 15 01:54:32.617 INFO [stdout] 
Dec 15 01:54:32.617 INFO [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 01:54:32.687 INFO [stdout] 
Dec 15 01:54:33.933 INFO [stdout] 
Dec 15 01:54:33.933 INFO [stdout] running 57 tests
Dec 15 01:54:38.864 INFO [stdout] test src/error.rs - error::Error<R>::new_from_pos (line 75) ... ok
Dec 15 01:54:42.075 INFO [stdout] test src/iterators/flat_pairs.rs - iterators::flat_pairs::FlatPairs<'i, R>::tokens (line 48) ... ok
Dec 15 01:54:43.708 INFO [stdout] test src/error.rs - error::Error<R>::new_from_span (line 113) ... ok
Dec 15 01:54:43.731 INFO [stdout] test src/iterators/pair.rs - iterators::pair::Pair<'i, R>::as_str (line 79) ... ok
Dec 15 01:54:44.631 INFO [stdout] test src/iterators/pair.rs - iterators::pair::Pair<'i, R>::as_span (line 136) ... ok
Dec 15 01:54:45.080 INFO [stdout] test src/error.rs - error::Error<R>::with_path (line 159) ... ok
Dec 15 01:54:45.451 INFO [stdout] test src/iterators/pair.rs - iterators::pair::Pair<'i, R>::as_rule (line 50) ... ok
Dec 15 01:54:45.470 INFO [stdout] test src/error.rs - error::Error<R>::renamed_rules (line 195) ... ok
Dec 15 01:54:45.470 INFO [stdout] test src/lib.rs -  (line 49) ... ignored
Dec 15 01:54:47.239 INFO [stdout] test src/iterators/pair.rs - iterators::pair::Pair<'i, R>::into_inner (line 166) ... ok
Dec 15 01:54:54.343 INFO [stdout] test src/iterators/pair.rs - iterators::pair::Pair<'i, R>::into_span (line 109) ... ok
Dec 15 01:54:56.171 INFO [stdout] test src/iterators/pairs.rs - iterators::pairs::Pairs<'i, R>::concat (line 89) ... ok
Dec 15 01:54:56.311 INFO [stdout] test src/iterators/pair.rs - iterators::pair::Pair<'i, R>::tokens (line 194) ... ok
Dec 15 01:54:56.475 INFO [stdout] test src/iterators/pairs.rs - iterators::pairs::Pairs<'i, R>::flatten (line 117) ... ok
Dec 15 01:54:56.563 INFO [stdout] test src/iterators/pairs.rs - iterators::pairs::Pairs<'i, R>::tokens (line 147) ... ok
Dec 15 01:54:56.776 INFO [stdout] test src/iterators/pairs.rs - iterators::pairs::Pairs<'i, R>::as_str (line 56) ... ok
Dec 15 01:54:56.917 INFO [stdout] test src/macros.rs - fails_with (line 261) ... ok
Dec 15 01:54:58.687 INFO [stdout] test src/macros.rs - parses_to (line 160) ... ok
Dec 15 01:55:00.621 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::match_char_by (line 448) ... ok
Dec 15 01:55:01.490 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::end_of_input (line 659) ... ok
Dec 15 01:55:02.411 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::atomic (line 758) ... ok
Dec 15 01:55:02.703 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::atomicity (line 144) ... ok
Dec 15 01:55:03.251 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::lookahead (line 691) ... ok
Dec 15 01:55:03.771 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::match_string (line 482) ... ok
Dec 15 01:55:03.831 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::match_insensitive (line 513) ... ok
Dec 15 01:55:03.843 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::match_range (line 544) ... ok
Dec 15 01:55:05.981 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::new (line 101) ... ok
Dec 15 01:55:06.756 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::repeat (line 368) ... ok
Dec 15 01:55:07.559 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::restore_on_err (line 1001) ... ok
Dec 15 01:55:07.781 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::skip (line 575) ... ok
Dec 15 01:55:08.131 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::optional (line 411) ... ok
Dec 15 01:55:09.239 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::position (line 123) ... ok
Dec 15 01:55:10.241 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::rule (line 167) ... ok
Dec 15 01:55:12.011 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::sequence (line 321) ... ok
Dec 15 01:55:13.650 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::skip_until (line 606) ... ok
Dec 15 01:55:14.839 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::stack_peek (line 847) ... ok
Dec 15 01:55:15.495 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::stack_drop (line 974) ... ok
Dec 15 01:55:15.495 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::stack_match_peek (line 902) ... ok
Dec 15 01:55:17.184 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::stack_pop (line 875) ... ok
Dec 15 01:55:17.219 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::stack_match_pop (line 935) ... ok
Dec 15 01:55:18.339 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::stack_push (line 811) ... ok
Dec 15 01:55:19.123 INFO [stdout] test src/parser_state.rs - parser_state::ParserState<'i, R>::start_of_input (line 629) ... ok
Dec 15 01:55:19.123 INFO [stdout] test src/prec_climber.rs - prec_climber::PrecClimber<R>::climb (line 154) ... ignored
Dec 15 01:55:19.967 INFO [stdout] test src/parser_state.rs - parser_state::state (line 62) ... ok
Dec 15 01:55:21.051 INFO [stdout] test src/position.rs - position::Position<'i>::pos (line 49) ... ok
Dec 15 01:55:21.773 INFO [stdout] test src/position.rs - position::Position<'i>::line_of (line 154) ... ok
Dec 15 01:55:22.079 INFO [stdout] test src/position.rs - position::Position<'i>::from_start (line 34) ... ok
Dec 15 01:55:22.487 INFO [stdout] test src/position.rs - position::Position<'i>::span (line 69) ... ok
Dec 15 01:55:23.362 INFO [stdout] test src/position.rs - position::Position<'i>::line_col (line 92) ... ok
Dec 15 01:55:25.315 INFO [stdout] test src/prec_climber.rs - prec_climber::Operator<R>::new (line 45) ... ok
Dec 15 01:55:25.359 INFO [stdout] test src/span.rs - span::Span<'i>::as_str (line 133) ... ok
Dec 15 01:55:25.359 INFO [stdout] test src/prec_climber.rs - prec_climber::PrecClimber<R>::new (line 100) ... ok
Dec 15 01:55:25.631 INFO [stdout] test src/span.rs - span::Span<'i>::end (line 55) ... ok
Dec 15 01:55:26.675 INFO [stdout] test src/span.rs - span::Span<'i>::end_pos (line 92) ... ok
Dec 15 01:55:27.295 INFO [stdout] test src/span.rs - span::Span<'i>::start (line 37) ... ok
Dec 15 01:55:27.295 INFO [stdout] test src/span.rs - span::Span<'i>::split (line 111) ... ok
Dec 15 01:55:27.371 INFO [stdout] test src/span.rs - span::Span<'i>::start_pos (line 73) ... ok
Dec 15 01:55:27.371 INFO [stdout] 
Dec 15 01:55:27.371 INFO [stdout] test result: ok. 55 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out
Dec 15 01:55:27.371 INFO [stdout] 
Dec 15 01:55:27.411 INFO [stderr] su: No module specific data is present
Dec 15 01:55:27.881 INFO running `"docker" "inspect" "c184916d462e952041c014db838eb63133b40c2eeeccf156510cfd87ae850dfc"`
Dec 15 01:55:28.021 INFO running `"docker" "rm" "-f" "c184916d462e952041c014db838eb63133b40c2eeeccf156510cfd87ae850dfc"`
Dec 15 01:55:28.328 INFO [stdout] c184916d462e952041c014db838eb63133b40c2eeeccf156510cfd87ae850dfc