Nov 17 13:24:22.186 INFO testing Mistodon/melo against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 17 13:24:22.186 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 17 13:24:22.817 INFO [stdout] a8d62352d44967e0e78f49f8bbbe211b49aab4807a26815269d81aa12de017d3
Nov 17 13:24:22.820 INFO running `"docker" "start" "-a" "a8d62352d44967e0e78f49f8bbbe211b49aab4807a26815269d81aa12de017d3"`
Nov 17 13:24:24.919 INFO [stderr] usermod: no changes
Nov 17 13:24:25.091 INFO [stderr]    Compiling enum_primitive v0.1.1
Nov 17 13:24:25.091 INFO [stderr]    Compiling uuid v0.1.18
Nov 17 13:24:25.099 INFO [stderr]    Compiling structopt v0.1.7
Nov 17 13:24:25.844 INFO [stderr]    Compiling rimd v0.0.1
Nov 17 13:24:26.668 INFO [stderr]    Compiling mktemp v0.3.1
Nov 17 13:24:28.981 INFO [stderr]    Compiling melo v0.1.0 (/source)
Nov 17 13:24:37.019 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.08s
Nov 17 13:24:37.027 INFO [stderr] su: No module specific data is present
Nov 17 13:24:37.417 INFO running `"docker" "inspect" "a8d62352d44967e0e78f49f8bbbe211b49aab4807a26815269d81aa12de017d3"`
Nov 17 13:24:37.556 INFO running `"docker" "rm" "-f" "a8d62352d44967e0e78f49f8bbbe211b49aab4807a26815269d81aa12de017d3"`
Nov 17 13:24:37.827 INFO [stdout] a8d62352d44967e0e78f49f8bbbe211b49aab4807a26815269d81aa12de017d3
Nov 17 13:24:37.829 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 17 13:24:38.139 INFO [stdout] 5f0d36e8693f3cf70c8ee2c63b92cd26395fa72bad146208c37ff7a221a34588
Nov 17 13:24:38.141 INFO running `"docker" "start" "-a" "5f0d36e8693f3cf70c8ee2c63b92cd26395fa72bad146208c37ff7a221a34588"`
Nov 17 13:24:38.831 INFO [stderr] usermod: no changes
Nov 17 13:24:39.019 INFO [stderr]    Compiling pretty_assertions v0.4.1
Nov 17 13:24:39.763 INFO [stderr]    Compiling melo v0.1.0 (/source)
Nov 17 13:24:47.207 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 8.33s
Nov 17 13:24:47.218 INFO [stderr] su: No module specific data is present
Nov 17 13:24:48.022 INFO running `"docker" "inspect" "5f0d36e8693f3cf70c8ee2c63b92cd26395fa72bad146208c37ff7a221a34588"`
Nov 17 13:24:48.303 INFO running `"docker" "rm" "-f" "5f0d36e8693f3cf70c8ee2c63b92cd26395fa72bad146208c37ff7a221a34588"`
Nov 17 13:24:48.685 INFO [stdout] 5f0d36e8693f3cf70c8ee2c63b92cd26395fa72bad146208c37ff7a221a34588
Nov 17 13:24:48.690 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 17 13:24:49.163 INFO [stdout] ad64e82260c0ddc7800ab3f697f3fdf75ecf5ccd976fcc7c3c61d382bf4aa59b
Nov 17 13:24:49.171 INFO running `"docker" "start" "-a" "ad64e82260c0ddc7800ab3f697f3fdf75ecf5ccd976fcc7c3c61d382bf4aa59b"`
Nov 17 13:24:50.030 INFO [stderr] usermod: no changes
Nov 17 13:24:50.175 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.12s
Nov 17 13:24:50.189 INFO [stderr]      Running /target/debug/deps/melo-67b7ce7cd1ea5c04
Nov 17 13:24:50.199 INFO [stdout] 
Nov 17 13:24:50.199 INFO [stdout] running 108 tests
Nov 17 13:24:50.279 INFO [stdout] test abc_generation::tests::note_tied_across_bar ... ok
Nov 17 13:24:50.303 INFO [stdout] test abc_generation::tests::large_tuplets_fail ... ok
Nov 17 13:24:50.307 INFO [stdout] test abc_generation::tests::notes_with_lengths ... ok
Nov 17 13:24:50.309 INFO [stdout] test abc_generation::tests::long_notes_in_triplets ... ok
Nov 17 13:24:50.331 INFO [stdout] test abc_generation::tests::test_div_tuplet ... ok
Nov 17 13:24:50.343 INFO [stdout] test abc_generation::tests::test_eight_notes_in_sequence ... ok
Nov 17 13:24:50.344 INFO [stdout] test abc_generation::tests::notes_with_dots ... ok
Nov 17 13:24:50.344 INFO [stdout] test abc_generation::tests::note_tied_across_triplets ... ok
Nov 17 13:24:50.359 INFO [stdout] test abc_generation::tests::test_notes_with_rests ... ok
Nov 17 13:24:50.375 INFO [stdout] test abc_generation::tests::note_tied_across_triplet_bars ... ok
Nov 17 13:24:50.375 INFO [stdout] test abc_generation::tests::test_fast_triplets ... ok
Nov 17 13:24:50.379 INFO [stdout] test abc_generation::tests::rest_across_bars ... ok
Nov 17 13:24:50.399 INFO [stdout] test abc_generation::tests::test_even_faster_triplets ... ok
Nov 17 13:24:50.400 INFO [stdout] test abc_generation::tests::test_quintuplet_in_4_4_time ... ok
Nov 17 13:24:50.407 INFO [stdout] test abc_generation::tests::rest_before_chord_not_duplicated ... ok
Nov 17 13:24:50.407 INFO [stdout] test abc_generation::tests::test_four_notes_then_sixteen ... ok
Nov 17 13:24:50.415 INFO [stdout] test abc_generation::tests::test_single_note ... ok
Nov 17 13:24:50.415 INFO [stdout] test abc_generation::tests::test_four_notes_in_sequence ... ok
Nov 17 13:24:50.423 INFO [stdout] test abc_generation::tests::test_sixteen_notes_in_sequence ... ok
Nov 17 13:24:50.439 INFO [stdout] test abc_generation::tests::test_three_note_bar_in_3_4_time ... ok
Nov 17 13:24:50.439 INFO [stdout] test abc_generation::tests::test_triplets_in_3_4_time ... ok
Nov 17 13:24:50.440 INFO [stdout] test abc_generation::tests::test_triplet_in_4_4_time ... ok
Nov 17 13:24:50.456 INFO [stdout] test abc_generation::tests::three_notes_at_once ... ok
Nov 17 13:24:50.456 INFO [stdout] test lexing::tests::invalid_tokens ... ok
Nov 17 13:24:50.463 INFO [stdout] test abc_generation::tests::test_two_notes_in_sequence ... ok
Nov 17 13:24:50.463 INFO [stdout] test abc_generation::tests::test_two_notes_at_once ... ok
Nov 17 13:24:50.464 INFO [stdout] test abc_generation::tests::test_triplets_with_rests ... ok
Nov 17 13:24:50.475 INFO [stdout] test lexing::tests::insignificant_whitespace_ignored ... ok
Nov 17 13:24:50.487 INFO [stdout] test lexing::tests::empty_file ... ok
Nov 17 13:24:50.487 INFO [stdout] test lexing::tests::invalid_tokens_in_stave ... ok
Nov 17 13:24:50.487 INFO [stdout] test lexing::tests::comments_ignored ... ok
Nov 17 13:24:50.487 INFO [stdout] test lexing::tests::lex_all_staves_key ... ok
Nov 17 13:24:50.487 INFO [stdout] test lexing::tests::lex_blank_lines ... ok
Nov 17 13:24:50.487 INFO [stdout] test lexing::tests::lex_empty_key ... ok
Nov 17 13:24:50.503 INFO [stdout] test lexing::tests::lex_complex_notes ... ok
Nov 17 13:24:50.504 INFO [stdout] test lexing::tests::lex_note ... ok
Nov 17 13:24:50.504 INFO [stdout] test lexing::tests::lex_field_in_block ... ok
Nov 17 13:24:50.507 INFO [stdout] test lexing::tests::lex_note_length ... ok
Nov 17 13:24:50.507 INFO [stdout] test lexing::tests::lex_name_with_spaces ... ok
Nov 17 13:24:50.507 INFO [stdout] test lexing::tests::lex_part ... ok
Nov 17 13:24:50.508 INFO [stdout] test lexing::tests::lex_name ... ok
Nov 17 13:24:50.515 INFO [stdout] test lexing::tests::lex_multiple_fields ... ok
Nov 17 13:24:50.515 INFO [stdout] test abc_generation::tests::triplet_chords ... ok
Nov 17 13:24:50.523 INFO [stdout] test lexing::tests::lex_numbers ... ok
Nov 17 13:24:50.523 INFO [stdout] test lexing::tests::lex_piece ... ok
Nov 17 13:24:50.524 INFO [stdout] test lexing::tests::lex_play ... ok
Nov 17 13:24:50.524 INFO [stdout] test lexing::tests::lex_quoted_name ... ok
Nov 17 13:24:50.531 INFO [stdout] test lexing::tests::lex_quoted_name_with_quotes_in_it ... ok
Nov 17 13:24:50.531 INFO [stdout] test lexing::tests::lex_voice ... ok
Nov 17 13:24:50.531 INFO [stdout] test lexing::tests::lex_play_part ... ok
Nov 17 13:24:50.532 INFO [stdout] test notes::tests::round_trip_flat_conversions ... ok
Nov 17 13:24:50.532 INFO [stdout] test notes::tests::round_trip_sharp_conversions ... ok
Nov 17 13:24:50.539 INFO [stdout] test lexing::tests::lex_section ... ok
Nov 17 13:24:50.540 INFO [stdout] test lexing::tests::semicolon_can_break_stave_within_one_line ... ok
Nov 17 13:24:50.540 INFO [stdout] test notes::tests::test_lcm ... ok
Nov 17 13:24:50.540 INFO [stdout] test lexing::tests::right_brace_can_break_stave_within_one_line ... ok
Nov 17 13:24:50.540 INFO [stdout] test notes::tests::test_note_to_midi ... ok
Nov 17 13:24:50.547 INFO [stdout] test notes::tests::test_note_to_abc ... ok
Nov 17 13:24:50.547 INFO [stdout] test lexing::tests::lex_symbols ... ok
Nov 17 13:24:50.547 INFO [stdout] test parsing::tests::fail_on_underflowed_length ... ok
Nov 17 13:24:50.559 INFO [stdout] test lexing::tests::lex_ridiculous_field_name ... ok
Nov 17 13:24:50.567 INFO [stdout] test parsing::tests::fail_to_parse_invalid_attributes ... ok
Nov 17 13:24:50.568 INFO [stdout] test parsing::tests::fail_when_hit_notes_are_encountered_in_incompatible_staves ... ok
Nov 17 13:24:50.571 INFO [stdout] test parsing::tests::fail_when_notes_after_repeat_sign ... ok
Nov 17 13:24:50.571 INFO [stdout] test parsing::tests::parse_attributes_in_piece ... ok
Nov 17 13:24:50.572 INFO [stdout] test parsing::tests::fail_on_overflowed_length ... ok
Nov 17 13:24:50.572 INFO [stdout] test parsing::tests::fail_on_unexpected_length ... ok
Nov 17 13:24:50.579 INFO [stdout] test parsing::tests::fail_parsing_too_many_staves_after_blank_line ... ok
Nov 17 13:24:50.580 INFO [stdout] test parsing::tests::fail_when_notes_before_repeat_sign ... ok
Nov 17 13:24:50.580 INFO [stdout] test parsing::tests::parse_attributes_with_trailing_comma ... ok
Nov 17 13:24:50.580 INFO [stdout] test parsing::tests::parse_empty_play_node ... ok
Nov 17 13:24:50.581 INFO [stdout] test parsing::tests::parse_empty_piece ... ok
Nov 17 13:24:50.581 INFO [stdout] test parsing::tests::parse_empty_file ... ok
Nov 17 13:24:50.587 INFO [stdout] test parsing::tests::parse_empty_voiceless_play_node ... ok
Nov 17 13:24:50.587 INFO [stdout] test parsing::tests::parse_attributes_without_commas ... ok
Nov 17 13:24:50.588 INFO [stdout] test parsing::tests::parse_empty_voice ... ok
Nov 17 13:24:50.588 INFO [stdout] test parsing::tests::parse_play_node_with_percussive_notes ... ok
Nov 17 13:24:50.588 INFO [stdout] test parsing::tests::parse_multiple_concurrent_melody_lines ... ok
Nov 17 13:24:50.588 INFO [stdout] test parsing::tests::parse_multiple_empty_pieces ... ok
Nov 17 13:24:50.599 INFO [stdout] test parsing::tests::parse_play_node_with_two_staves ... ok
Nov 17 13:24:50.600 INFO [stdout] test parsing::tests::parse_play_node_with_stave ... ok
Nov 17 13:24:50.600 INFO [stdout] test parsing::tests::parse_note_with_length ... ok
Nov 17 13:24:50.600 INFO [stdout] test parsing::tests::parse_multiple_concurrent_melody_lines_broken_up_by_blank_line ... ok
Nov 17 13:24:50.603 INFO [stdout] test parsing::tests::parse_valueless_attribute ... ok
Nov 17 13:24:50.603 INFO [stdout] test parsing::tests::parse_stave_split_over_multiple_lines ... ok
Nov 17 13:24:50.604 INFO [stdout] test parsing::tests::parse_rest_with_length ... ok
Nov 17 13:24:50.607 INFO [stdout] test parsing::tests::parse_voice_attributes ... ok
Nov 17 13:24:50.607 INFO [stdout] test parsing::tests::parse_repeat ... ok
Nov 17 13:24:50.615 INFO [stdout] test parsing::tests::parse_play_node_with_melody_notes ... ok
Nov 17 13:24:50.615 INFO [stdout] test parsing::tests::parse_play_node_with_extra_barlines ... ok
Nov 17 13:24:50.639 INFO [stdout] test sequencing::tests::repeat_bars ... ok
Nov 17 13:24:50.639 INFO [stdout] test sequencing::tests::dots_do_not_carry_across_staves ... ok
Nov 17 13:24:50.639 INFO [stdout] test sequencing::tests::sequence_empty_piece ... ok
Nov 17 13:24:50.651 INFO [stdout] test sequencing::tests::threes_against_twos ... ok
Nov 17 13:24:50.651 INFO [stdout] test sequencing::tests::notes_can_be_tied_across_bars ... ok
Nov 17 13:24:50.655 INFO [stdout] test sequencing::tests::piece_with_empty_voice ... ok
Nov 17 13:24:50.655 INFO [stdout] test sequencing::tests::fail_when_notes_moved_out_of_range ... ok
Nov 17 13:24:50.656 INFO [stdout] test sequencing::tests::fail_first_bar_repeat ... ok
Nov 17 13:24:50.663 INFO [stdout] test sequencing::tests::piece_with_attributes ... ok
Nov 17 13:24:50.679 INFO [stdout] test sequencing::tests::repeat_bars_twice ... ok
Nov 17 13:24:50.683 INFO [stdout] test sequencing::tests::voice_with_leading_dots ... ok
Nov 17 13:24:50.684 INFO [stdout] test sequencing::tests::voice_with_single_note ... ok
Nov 17 13:24:50.687 INFO [stdout] test sequencing::tests::voice_with_two_notes ... ok
Nov 17 13:24:50.687 INFO [stdout] test sequencing::tests::voice_with_mismatched_play ... ok
Nov 17 13:24:50.691 INFO [stdout] test sequencing::tests::voice_with_dots ... ok
Nov 17 13:24:50.707 INFO [stdout] test sequencing::tests::voice_with_note_lengths ... ok
Nov 17 13:24:50.707 INFO [stdout] test sequencing::tests::voice_with_two_staves ... ok
Nov 17 13:24:50.779 INFO [stdout] test parsing::tests::parse_empty_piece_with_trailing_tokens_fails ... ok
Nov 17 13:24:50.779 INFO [stderr]      Running /target/debug/deps/melo-37bd70109434afa0
Nov 17 13:24:50.779 INFO [stdout] 
Nov 17 13:24:50.779 INFO [stdout] test result: ok. 108 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 13:24:50.779 INFO [stdout] 
Nov 17 13:24:50.785 INFO [stdout] 
Nov 17 13:24:50.785 INFO [stderr]      Running /target/debug/deps/compile_pieces_to_abc_tests-50af30589c9fd1e8
Nov 17 13:24:50.785 INFO [stdout] running 0 tests
Nov 17 13:24:50.785 INFO [stdout] 
Nov 17 13:24:50.785 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 13:24:50.785 INFO [stdout] 
Nov 17 13:24:50.791 INFO [stdout] 
Nov 17 13:24:50.791 INFO [stdout] running 9 tests
Nov 17 13:24:50.905 INFO [stdout] test minimal_melody ... ok
Nov 17 13:24:50.947 INFO [stdout] test minimal_drums ... ok
Nov 17 13:24:50.953 INFO [stdout] test minimal_chords ... ok
Nov 17 13:24:50.965 INFO [stdout] test time_and_tempo ... ok
Nov 17 13:24:50.971 INFO [stdout] test too_many_staves ... ok
Nov 17 13:24:50.975 INFO [stdout] test repeats ... ok
Nov 17 13:24:50.975 INFO [stdout] test polyrhythms ... ok
Nov 17 13:24:50.979 INFO [stdout] test multiple_instruments ... ok
Nov 17 13:24:51.061 INFO [stdout] test rondo_alla_turca ... ok
Nov 17 13:24:51.062 INFO [stdout] 
Nov 17 13:24:51.062 INFO [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 13:24:51.063 INFO [stdout] 
Nov 17 13:24:51.063 INFO [stderr]      Running /target/debug/deps/compile_to_abc_tests-a8f8a9f09ed871dc
Nov 17 13:24:51.065 INFO [stdout] 
Nov 17 13:24:51.065 INFO [stdout] running 13 tests
Nov 17 13:24:51.139 INFO [stdout] test complex_melody ... ok
Nov 17 13:24:51.147 INFO [stdout] test quintuplets ... ok
Nov 17 13:24:51.170 INFO [stdout] test simple_melody ... ok
Nov 17 13:24:51.183 INFO [stdout] test not_sextuplets ... ok
Nov 17 13:24:51.189 INFO [stdout] test triplets ... ok
Nov 17 13:24:51.189 INFO [stdout] test variable_drum_bars ... ok
Nov 17 13:24:51.189 INFO [stdout] test simple_drums ... ok
Nov 17 13:24:51.194 INFO [stdout] test fifths ... ok
Nov 17 13:24:51.194 INFO [stdout] test voice_args ... ok
Nov 17 13:24:51.195 INFO [stdout] test longer_melody ... ok
Nov 17 13:24:51.197 INFO [stdout] test variable_stave_drum_bars ... ok
Nov 17 13:24:51.198 INFO [stdout] test octave_shift ... ok
Nov 17 13:24:51.200 INFO [stdout] test triple_time_expansion ... ok
Nov 17 13:24:51.200 INFO [stdout] 
Nov 17 13:24:51.200 INFO [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 13:24:51.201 INFO [stdout] 
Nov 17 13:24:51.207 INFO [stderr]    Doc-tests melo
Nov 17 13:24:52.490 INFO [stdout] 
Nov 17 13:24:52.490 INFO [stdout] running 0 tests
Nov 17 13:24:52.490 INFO [stdout] 
Nov 17 13:24:52.490 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 13:24:52.490 INFO [stdout] 
Nov 17 13:24:52.503 INFO [stderr] su: No module specific data is present
Nov 17 13:24:53.357 INFO running `"docker" "inspect" "ad64e82260c0ddc7800ab3f697f3fdf75ecf5ccd976fcc7c3c61d382bf4aa59b"`
Nov 17 13:24:53.589 INFO running `"docker" "rm" "-f" "ad64e82260c0ddc7800ab3f697f3fdf75ecf5ccd976fcc7c3c61d382bf4aa59b"`
Nov 17 13:24:53.866 INFO [stdout] ad64e82260c0ddc7800ab3f697f3fdf75ecf5ccd976fcc7c3c61d382bf4aa59b