Dec 02 05:16:13.745 INFO testing Mistodon/melo against 1.30.1 for beta-1.31-5
Dec 02 05:16:13.745 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-4/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/beta-1.31-5/1.30.1:/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 +1.30.1 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 05:16:14.338 INFO [stdout] 2acca0e56a6fbbf6e596243a36301170707876b162c04c1db39fe9821ec51b13
Dec 02 05:16:14.340 INFO running `"docker" "start" "-a" "2acca0e56a6fbbf6e596243a36301170707876b162c04c1db39fe9821ec51b13"`
Dec 02 05:16:15.874 INFO [stderr] usermod: no changes
Dec 02 05:16:16.082 INFO [stderr]    Compiling uuid v0.1.18
Dec 02 05:16:16.087 INFO [stderr]    Compiling rimd v0.0.1
Dec 02 05:16:18.022 INFO [stderr]    Compiling mktemp v0.3.1
Dec 02 05:16:19.999 INFO [stderr]    Compiling melo v0.1.0 (/source)
Dec 02 05:16:32.206 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 16.29s
Dec 02 05:16:32.222 INFO [stderr] su: No module specific data is present
Dec 02 05:16:34.000 INFO running `"docker" "inspect" "2acca0e56a6fbbf6e596243a36301170707876b162c04c1db39fe9821ec51b13"`
Dec 02 05:16:34.373 INFO running `"docker" "rm" "-f" "2acca0e56a6fbbf6e596243a36301170707876b162c04c1db39fe9821ec51b13"`
Dec 02 05:16:34.827 INFO [stdout] 2acca0e56a6fbbf6e596243a36301170707876b162c04c1db39fe9821ec51b13
Dec 02 05:16:34.832 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-4/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 05:16:35.335 INFO [stdout] 97c8ff6f0fb71a6d87db4e318d4f0b2f5d2dc2f11ad91bd7f47644f2772d6994
Dec 02 05:16:35.339 INFO running `"docker" "start" "-a" "97c8ff6f0fb71a6d87db4e318d4f0b2f5d2dc2f11ad91bd7f47644f2772d6994"`
Dec 02 05:16:36.628 INFO [stderr] usermod: no changes
Dec 02 05:16:36.791 INFO [stderr]    Compiling pretty_assertions v0.4.1
Dec 02 05:16:37.955 INFO [stderr]    Compiling melo v0.1.0 (/source)
Dec 02 05:16:55.072 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.41s
Dec 02 05:16:55.082 INFO [stderr] su: No module specific data is present
Dec 02 05:16:57.091 INFO running `"docker" "inspect" "97c8ff6f0fb71a6d87db4e318d4f0b2f5d2dc2f11ad91bd7f47644f2772d6994"`
Dec 02 05:16:57.359 INFO running `"docker" "rm" "-f" "97c8ff6f0fb71a6d87db4e318d4f0b2f5d2dc2f11ad91bd7f47644f2772d6994"`
Dec 02 05:16:57.689 INFO [stdout] 97c8ff6f0fb71a6d87db4e318d4f0b2f5d2dc2f11ad91bd7f47644f2772d6994
Dec 02 05:16:57.699 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-4/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 05:16:58.093 INFO [stdout] 3dab8b02c8d5fc64d90e2071aa28cdae767f3fb42caad535156b0ca0be5a0c74
Dec 02 05:16:58.093 INFO running `"docker" "start" "-a" "3dab8b02c8d5fc64d90e2071aa28cdae767f3fb42caad535156b0ca0be5a0c74"`
Dec 02 05:17:00.390 INFO [stderr] usermod: no changes
Dec 02 05:17:00.596 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.18s
Dec 02 05:17:00.609 INFO [stderr]      Running /target/debug/deps/melo-a481ae8b86f6961c
Dec 02 05:17:00.617 INFO [stdout] 
Dec 02 05:17:00.617 INFO [stdout] running 108 tests
Dec 02 05:17:00.698 INFO [stdout] test abc_generation::tests::large_tuplets_fail ... ok
Dec 02 05:17:00.701 INFO [stdout] test abc_generation::tests::rest_before_chord_not_duplicated ... ok
Dec 02 05:17:00.719 INFO [stdout] test abc_generation::tests::notes_with_lengths ... ok
Dec 02 05:17:00.719 INFO [stdout] test abc_generation::tests::long_notes_in_triplets ... ok
Dec 02 05:17:00.719 INFO [stdout] test abc_generation::tests::note_tied_across_triplets ... ok
Dec 02 05:17:00.719 INFO [stdout] test abc_generation::tests::test_div_tuplet ... ok
Dec 02 05:17:00.719 INFO [stdout] test abc_generation::tests::notes_with_dots ... ok
Dec 02 05:17:00.719 INFO [stdout] test abc_generation::tests::note_tied_across_triplet_bars ... ok
Dec 02 05:17:00.747 INFO [stdout] test abc_generation::tests::rest_across_bars ... ok
Dec 02 05:17:00.751 INFO [stdout] test abc_generation::tests::note_tied_across_bar ... ok
Dec 02 05:17:00.759 INFO [stdout] test abc_generation::tests::test_notes_with_rests ... ok
Dec 02 05:17:00.775 INFO [stdout] test abc_generation::tests::test_eight_notes_in_sequence ... ok
Dec 02 05:17:00.783 INFO [stdout] test abc_generation::tests::test_even_faster_triplets ... ok
Dec 02 05:17:00.802 INFO [stdout] test abc_generation::tests::test_sixteen_notes_in_sequence ... ok
Dec 02 05:17:00.802 INFO [stdout] test abc_generation::tests::test_single_note ... ok
Dec 02 05:17:00.807 INFO [stdout] test abc_generation::tests::test_four_notes_then_sixteen ... ok
Dec 02 05:17:00.829 INFO [stdout] test abc_generation::tests::test_fast_triplets ... ok
Dec 02 05:17:00.840 INFO [stdout] test abc_generation::tests::test_three_note_bar_in_3_4_time ... ok
Dec 02 05:17:00.841 INFO [stdout] test abc_generation::tests::test_four_notes_in_sequence ... ok
Dec 02 05:17:00.856 INFO [stdout] test abc_generation::tests::test_two_notes_in_sequence ... ok
Dec 02 05:17:00.865 INFO [stdout] test lexing::tests::comments_ignored ... ok
Dec 02 05:17:00.868 INFO [stdout] test abc_generation::tests::test_triplets_with_rests ... ok
Dec 02 05:17:00.868 INFO [stdout] test abc_generation::tests::test_triplets_in_3_4_time ... ok
Dec 02 05:17:00.879 INFO [stdout] test abc_generation::tests::test_quintuplet_in_4_4_time ... ok
Dec 02 05:17:00.884 INFO [stdout] test abc_generation::tests::test_triplet_in_4_4_time ... ok
Dec 02 05:17:00.900 INFO [stdout] test lexing::tests::lex_all_staves_key ... ok
Dec 02 05:17:00.901 INFO [stdout] test abc_generation::tests::three_notes_at_once ... ok
Dec 02 05:17:00.911 INFO [stdout] test lexing::tests::invalid_tokens ... ok
Dec 02 05:17:00.912 INFO [stdout] test lexing::tests::invalid_tokens_in_stave ... ok
Dec 02 05:17:00.921 INFO [stdout] test lexing::tests::empty_file ... ok
Dec 02 05:17:00.921 INFO [stdout] test lexing::tests::lex_blank_lines ... ok
Dec 02 05:17:00.921 INFO [stdout] test lexing::tests::lex_field_in_block ... ok
Dec 02 05:17:00.922 INFO [stdout] test lexing::tests::lex_empty_key ... ok
Dec 02 05:17:00.931 INFO [stdout] test lexing::tests::insignificant_whitespace_ignored ... ok
Dec 02 05:17:00.933 INFO [stdout] test lexing::tests::lex_name ... ok
Dec 02 05:17:00.939 INFO [stdout] test abc_generation::tests::triplet_chords ... ok
Dec 02 05:17:00.945 INFO [stdout] test lexing::tests::lex_multiple_fields ... ok
Dec 02 05:17:00.947 INFO [stdout] test lexing::tests::lex_complex_notes ... ok
Dec 02 05:17:00.967 INFO [stdout] test abc_generation::tests::test_two_notes_at_once ... ok
Dec 02 05:17:00.967 INFO [stdout] test lexing::tests::lex_part ... ok
Dec 02 05:17:00.967 INFO [stdout] test lexing::tests::lex_name_with_spaces ... ok
Dec 02 05:17:00.967 INFO [stdout] test lexing::tests::lex_note ... ok
Dec 02 05:17:00.967 INFO [stdout] test lexing::tests::lex_numbers ... ok
Dec 02 05:17:00.973 INFO [stdout] test lexing::tests::lex_quoted_name ... ok
Dec 02 05:17:00.975 INFO [stdout] test lexing::tests::lex_note_length ... ok
Dec 02 05:17:00.976 INFO [stdout] test lexing::tests::lex_piece ... ok
Dec 02 05:17:00.988 INFO [stdout] test lexing::tests::lex_play_part ... ok
Dec 02 05:17:00.999 INFO [stdout] test lexing::tests::lex_play ... ok
Dec 02 05:17:01.000 INFO [stdout] test lexing::tests::lex_section ... ok
Dec 02 05:17:01.001 INFO [stdout] test lexing::tests::semicolon_can_break_stave_within_one_line ... ok
Dec 02 05:17:01.002 INFO [stdout] test lexing::tests::lex_ridiculous_field_name ... ok
Dec 02 05:17:01.005 INFO [stdout] test notes::tests::test_lcm ... ok
Dec 02 05:17:01.005 INFO [stdout] test notes::tests::round_trip_sharp_conversions ... ok
Dec 02 05:17:01.005 INFO [stdout] test notes::tests::round_trip_flat_conversions ... ok
Dec 02 05:17:01.015 INFO [stdout] test notes::tests::test_note_to_abc ... ok
Dec 02 05:17:01.015 INFO [stdout] test notes::tests::test_note_to_midi ... ok
Dec 02 05:17:01.015 INFO [stdout] test parsing::tests::fail_on_overflowed_length ... ok
Dec 02 05:17:01.016 INFO [stdout] test lexing::tests::lex_quoted_name_with_quotes_in_it ... ok
Dec 02 05:17:01.024 INFO [stdout] test parsing::tests::fail_on_underflowed_length ... ok
Dec 02 05:17:01.024 INFO [stdout] test parsing::tests::fail_on_unexpected_length ... ok
Dec 02 05:17:01.037 INFO [stdout] test parsing::tests::fail_when_hit_notes_are_encountered_in_incompatible_staves ... ok
Dec 02 05:17:01.037 INFO [stdout] test parsing::tests::fail_when_notes_before_repeat_sign ... ok
Dec 02 05:17:01.043 INFO [stdout] test lexing::tests::lex_voice ... ok
Dec 02 05:17:01.049 INFO [stdout] test lexing::tests::right_brace_can_break_stave_within_one_line ... ok
Dec 02 05:17:01.050 INFO [stdout] test lexing::tests::lex_symbols ... ok
Dec 02 05:17:01.051 INFO [stdout] test parsing::tests::fail_parsing_too_many_staves_after_blank_line ... ok
Dec 02 05:17:01.063 INFO [stdout] test parsing::tests::parse_attributes_without_commas ... ok
Dec 02 05:17:01.063 INFO [stdout] test parsing::tests::parse_empty_piece ... ok
Dec 02 05:17:01.072 INFO [stdout] test parsing::tests::parse_empty_play_node ... ok
Dec 02 05:17:01.072 INFO [stdout] test parsing::tests::fail_when_notes_after_repeat_sign ... ok
Dec 02 05:17:01.073 INFO [stdout] test parsing::tests::fail_to_parse_invalid_attributes ... ok
Dec 02 05:17:01.073 INFO [stdout] test parsing::tests::parse_empty_file ... ok
Dec 02 05:17:01.074 INFO [stdout] test parsing::tests::parse_attributes_with_trailing_comma ... ok
Dec 02 05:17:01.075 INFO [stdout] test parsing::tests::parse_multiple_empty_pieces ... ok
Dec 02 05:17:01.080 INFO [stdout] test parsing::tests::parse_play_node_with_extra_barlines ... ok
Dec 02 05:17:01.087 INFO [stdout] test parsing::tests::parse_empty_voiceless_play_node ... ok
Dec 02 05:17:01.095 INFO [stdout] test parsing::tests::parse_note_with_length ... ok
Dec 02 05:17:01.095 INFO [stdout] test parsing::tests::parse_play_node_with_percussive_notes ... ok
Dec 02 05:17:01.099 INFO [stdout] test parsing::tests::parse_play_node_with_two_staves ... ok
Dec 02 05:17:01.115 INFO [stdout] test parsing::tests::parse_attributes_in_piece ... ok
Dec 02 05:17:01.115 INFO [stdout] test parsing::tests::parse_empty_voice ... ok
Dec 02 05:17:01.115 INFO [stdout] test parsing::tests::parse_multiple_concurrent_melody_lines ... ok
Dec 02 05:17:01.115 INFO [stdout] test parsing::tests::parse_play_node_with_stave ... ok
Dec 02 05:17:01.115 INFO [stdout] test parsing::tests::parse_play_node_with_melody_notes ... ok
Dec 02 05:17:01.115 INFO [stdout] test parsing::tests::parse_multiple_concurrent_melody_lines_broken_up_by_blank_line ... ok
Dec 02 05:17:01.116 INFO [stdout] test parsing::tests::parse_stave_split_over_multiple_lines ... ok
Dec 02 05:17:01.118 INFO [stdout] test sequencing::tests::fail_first_bar_repeat ... ok
Dec 02 05:17:01.120 INFO [stdout] test parsing::tests::parse_valueless_attribute ... ok
Dec 02 05:17:01.120 INFO [stdout] test parsing::tests::parse_rest_with_length ... ok
Dec 02 05:17:01.121 INFO [stdout] test sequencing::tests::piece_with_empty_voice ... ok
Dec 02 05:17:01.121 INFO [stdout] test parsing::tests::parse_voice_attributes ... ok
Dec 02 05:17:01.127 INFO [stdout] test parsing::tests::parse_repeat ... ok
Dec 02 05:17:01.133 INFO [stdout] test sequencing::tests::notes_can_be_tied_across_bars ... ok
Dec 02 05:17:01.136 INFO [stdout] test sequencing::tests::threes_against_twos ... ok
Dec 02 05:17:01.138 INFO [stdout] test sequencing::tests::piece_with_attributes ... ok
Dec 02 05:17:01.140 INFO [stdout] test sequencing::tests::dots_do_not_carry_across_staves ... ok
Dec 02 05:17:01.159 INFO [stdout] test sequencing::tests::voice_with_leading_dots ... ok
Dec 02 05:17:01.159 INFO [stdout] test sequencing::tests::repeat_bars_twice ... ok
Dec 02 05:17:01.159 INFO [stdout] test sequencing::tests::repeat_bars ... ok
Dec 02 05:17:01.159 INFO [stdout] test sequencing::tests::fail_when_notes_moved_out_of_range ... ok
Dec 02 05:17:01.175 INFO [stdout] test sequencing::tests::sequence_empty_piece ... ok
Dec 02 05:17:01.180 INFO [stdout] test sequencing::tests::voice_with_note_lengths ... ok
Dec 02 05:17:01.183 INFO [stdout] test sequencing::tests::voice_with_single_note ... ok
Dec 02 05:17:01.184 INFO [stdout] test sequencing::tests::voice_with_dots ... ok
Dec 02 05:17:01.193 INFO [stdout] test sequencing::tests::voice_with_mismatched_play ... ok
Dec 02 05:17:01.207 INFO [stdout] test sequencing::tests::voice_with_two_notes ... ok
Dec 02 05:17:01.216 INFO [stdout] test sequencing::tests::voice_with_two_staves ... ok
Dec 02 05:17:01.229 INFO [stdout] test parsing::tests::parse_empty_piece_with_trailing_tokens_fails ... ok
Dec 02 05:17:01.229 INFO [stdout] 
Dec 02 05:17:01.229 INFO [stdout] test result: ok. 108 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 05:17:01.229 INFO [stdout] 
Dec 02 05:17:01.231 INFO [stderr]      Running /target/debug/deps/melo-3b3727f7e12bd2e1
Dec 02 05:17:01.234 INFO [stdout] 
Dec 02 05:17:01.234 INFO [stdout] running 0 tests
Dec 02 05:17:01.234 INFO [stdout] 
Dec 02 05:17:01.234 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 05:17:01.234 INFO [stdout] 
Dec 02 05:17:01.234 INFO [stderr]      Running /target/debug/deps/compile_pieces_to_abc_tests-b9891ae791c2dbd3
Dec 02 05:17:01.240 INFO [stdout] 
Dec 02 05:17:01.240 INFO [stdout] running 9 tests
Dec 02 05:17:01.351 INFO [stdout] test minimal_chords ... ok
Dec 02 05:17:01.351 INFO [stdout] test minimal_melody ... ok
Dec 02 05:17:01.395 INFO [stdout] test minimal_drums ... ok
Dec 02 05:17:01.418 INFO [stdout] test repeats ... ok
Dec 02 05:17:01.418 INFO [stdout] test polyrhythms ... ok
Dec 02 05:17:01.419 INFO [stdout] test too_many_staves ... ok
Dec 02 05:17:01.439 INFO [stdout] test time_and_tempo ... ok
Dec 02 05:17:01.454 INFO [stdout] test multiple_instruments ... ok
Dec 02 05:17:01.582 INFO [stdout] test rondo_alla_turca ... ok
Dec 02 05:17:01.582 INFO [stdout] 
Dec 02 05:17:01.582 INFO [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 05:17:01.582 INFO [stdout] 
Dec 02 05:17:01.583 INFO [stderr]      Running /target/debug/deps/compile_to_abc_tests-5f05dd0d80ade5a8
Dec 02 05:17:01.585 INFO [stdout] 
Dec 02 05:17:01.585 INFO [stdout] running 13 tests
Dec 02 05:17:01.771 INFO [stdout] test not_sextuplets ... ok
Dec 02 05:17:01.771 INFO [stdout] test complex_melody ... ok
Dec 02 05:17:01.771 INFO [stdout] test fifths ... ok
Dec 02 05:17:01.772 INFO [stdout] test longer_melody ... ok
Dec 02 05:17:01.779 INFO [stdout] test octave_shift ... ok
Dec 02 05:17:01.779 INFO [stdout] test simple_melody ... ok
Dec 02 05:17:01.779 INFO [stdout] test quintuplets ... ok
Dec 02 05:17:01.811 INFO [stdout] test variable_stave_drum_bars ... ok
Dec 02 05:17:01.811 INFO [stdout] test variable_drum_bars ... ok
Dec 02 05:17:01.811 INFO [stdout] test triplets ... ok
Dec 02 05:17:01.812 INFO [stdout] test triple_time_expansion ... ok
Dec 02 05:17:01.812 INFO [stdout] test simple_drums ... ok
Dec 02 05:17:01.818 INFO [stdout] test voice_args ... ok
Dec 02 05:17:01.818 INFO [stdout] 
Dec 02 05:17:01.818 INFO [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 05:17:01.818 INFO [stdout] 
Dec 02 05:17:01.820 INFO [stderr]    Doc-tests melo
Dec 02 05:17:05.351 INFO [stdout] 
Dec 02 05:17:05.351 INFO [stdout] running 0 tests
Dec 02 05:17:05.351 INFO [stdout] 
Dec 02 05:17:05.351 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 05:17:05.351 INFO [stdout] 
Dec 02 05:17:05.371 INFO [stderr] su: No module specific data is present
Dec 02 05:17:06.360 INFO running `"docker" "inspect" "3dab8b02c8d5fc64d90e2071aa28cdae767f3fb42caad535156b0ca0be5a0c74"`
Dec 02 05:17:06.653 INFO running `"docker" "rm" "-f" "3dab8b02c8d5fc64d90e2071aa28cdae767f3fb42caad535156b0ca0be5a0c74"`
Dec 02 05:17:06.983 INFO [stdout] 3dab8b02c8d5fc64d90e2071aa28cdae767f3fb42caad535156b0ca0be5a0c74
