Dec 06 11:35:51.516 INFO testing Mistodon/melo against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 06 11:35:51.516 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 06 11:35:52.122 INFO [stdout] 0e6816e6dd61c4687561be9f05fe4265314ffc986976adee9960ff7c1d6f7586
Dec 06 11:35:52.124 INFO running `"docker" "start" "-a" "0e6816e6dd61c4687561be9f05fe4265314ffc986976adee9960ff7c1d6f7586"`
Dec 06 11:35:54.587 INFO [stderr] usermod: no changes
Dec 06 11:35:54.785 INFO [stderr]    Compiling uuid v0.1.18
Dec 06 11:35:54.792 INFO [stderr]    Compiling num-rational v0.1.41
Dec 06 11:35:54.796 INFO [stderr]    Compiling structopt-derive v0.1.6
Dec 06 11:35:54.797 INFO [stderr]    Compiling structopt v0.1.7
Dec 06 11:35:57.285 INFO [stderr]    Compiling mktemp v0.3.1
Dec 06 11:36:00.429 INFO [stderr]    Compiling num v0.1.41
Dec 06 11:36:01.038 INFO [stderr]    Compiling rimd v0.0.1
Dec 06 11:36:03.342 INFO [stderr]    Compiling melo v0.1.0 (/source)
Dec 06 11:36:14.389 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 19.78s
Dec 06 11:36:14.400 INFO [stderr] su: No module specific data is present
Dec 06 11:36:15.388 INFO running `"docker" "inspect" "0e6816e6dd61c4687561be9f05fe4265314ffc986976adee9960ff7c1d6f7586"`
Dec 06 11:36:15.734 INFO running `"docker" "rm" "-f" "0e6816e6dd61c4687561be9f05fe4265314ffc986976adee9960ff7c1d6f7586"`
Dec 06 11:36:16.047 INFO [stdout] 0e6816e6dd61c4687561be9f05fe4265314ffc986976adee9960ff7c1d6f7586
Dec 06 11:36:16.052 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 06 11:36:16.456 INFO [stdout] dd7aa5ffd2306201c9b0b5fd87166b13f4b457d067f959f87216e8463deb4e27
Dec 06 11:36:16.458 INFO running `"docker" "start" "-a" "dd7aa5ffd2306201c9b0b5fd87166b13f4b457d067f959f87216e8463deb4e27"`
Dec 06 11:36:17.583 INFO [stderr] usermod: no changes
Dec 06 11:36:17.748 INFO [stderr]    Compiling pretty_assertions v0.4.1
Dec 06 11:36:18.680 INFO [stderr]    Compiling melo v0.1.0 (/source)
Dec 06 11:36:31.920 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.30s
Dec 06 11:36:31.937 INFO [stderr] su: No module specific data is present
Dec 06 11:36:32.602 INFO running `"docker" "inspect" "dd7aa5ffd2306201c9b0b5fd87166b13f4b457d067f959f87216e8463deb4e27"`
Dec 06 11:36:32.754 INFO running `"docker" "rm" "-f" "dd7aa5ffd2306201c9b0b5fd87166b13f4b457d067f959f87216e8463deb4e27"`
Dec 06 11:36:32.968 INFO [stdout] dd7aa5ffd2306201c9b0b5fd87166b13f4b457d067f959f87216e8463deb4e27
Dec 06 11:36:32.970 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 06 11:36:33.460 INFO [stdout] 3871c2cafaf6315272a6efa7cfb3ec672e43bba32431a1d38bd1193501ca13ec
Dec 06 11:36:33.462 INFO running `"docker" "start" "-a" "3871c2cafaf6315272a6efa7cfb3ec672e43bba32431a1d38bd1193501ca13ec"`
Dec 06 11:36:34.733 INFO [stderr] usermod: no changes
Dec 06 11:36:34.916 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.15s
Dec 06 11:36:34.924 INFO [stderr]      Running /target/debug/deps/melo-55346ea9d99194a7
Dec 06 11:36:34.940 INFO [stdout] 
Dec 06 11:36:34.940 INFO [stdout] running 108 tests
Dec 06 11:36:35.007 INFO [stdout] test abc_generation::tests::large_tuplets_fail ... ok
Dec 06 11:36:35.026 INFO [stdout] test abc_generation::tests::rest_across_bars ... ok
Dec 06 11:36:35.027 INFO [stdout] test abc_generation::tests::note_tied_across_triplet_bars ... ok
Dec 06 11:36:35.048 INFO [stdout] test abc_generation::tests::long_notes_in_triplets ... ok
Dec 06 11:36:35.048 INFO [stdout] test abc_generation::tests::test_div_tuplet ... ok
Dec 06 11:36:35.048 INFO [stdout] test abc_generation::tests::note_tied_across_bar ... ok
Dec 06 11:36:35.061 INFO [stdout] test abc_generation::tests::notes_with_dots ... ok
Dec 06 11:36:35.070 INFO [stdout] test abc_generation::tests::test_eight_notes_in_sequence ... ok
Dec 06 11:36:35.072 INFO [stdout] test abc_generation::tests::note_tied_across_triplets ... ok
Dec 06 11:36:35.072 INFO [stdout] test abc_generation::tests::notes_with_lengths ... ok
Dec 06 11:36:35.073 INFO [stdout] test abc_generation::tests::test_four_notes_in_sequence ... ok
Dec 06 11:36:35.089 INFO [stdout] test abc_generation::tests::test_even_faster_triplets ... ok
Dec 06 11:36:35.096 INFO [stdout] test abc_generation::tests::rest_before_chord_not_duplicated ... ok
Dec 06 11:36:35.098 INFO [stdout] test abc_generation::tests::test_single_note ... ok
Dec 06 11:36:35.098 INFO [stdout] test abc_generation::tests::test_fast_triplets ... ok
Dec 06 11:36:35.099 INFO [stdout] test abc_generation::tests::test_quintuplet_in_4_4_time ... ok
Dec 06 11:36:35.105 INFO [stdout] test abc_generation::tests::test_four_notes_then_sixteen ... ok
Dec 06 11:36:35.107 INFO [stdout] test abc_generation::tests::test_sixteen_notes_in_sequence ... ok
Dec 06 11:36:35.109 INFO [stdout] test abc_generation::tests::test_notes_with_rests ... ok
Dec 06 11:36:35.111 INFO [stdout] test abc_generation::tests::test_triplet_in_4_4_time ... ok
Dec 06 11:36:35.120 INFO [stdout] test abc_generation::tests::test_three_note_bar_in_3_4_time ... ok
Dec 06 11:36:35.125 INFO [stdout] test abc_generation::tests::test_two_notes_in_sequence ... ok
Dec 06 11:36:35.128 INFO [stdout] test abc_generation::tests::triplet_chords ... ok
Dec 06 11:36:35.130 INFO [stdout] test abc_generation::tests::test_two_notes_at_once ... ok
Dec 06 11:36:35.130 INFO [stdout] test lexing::tests::comments_ignored ... ok
Dec 06 11:36:35.130 INFO [stdout] test lexing::tests::empty_file ... ok
Dec 06 11:36:35.130 INFO [stdout] test lexing::tests::invalid_tokens ... ok
Dec 06 11:36:35.131 INFO [stdout] test lexing::tests::lex_all_staves_key ... ok
Dec 06 11:36:35.132 INFO [stdout] test lexing::tests::insignificant_whitespace_ignored ... ok
Dec 06 11:36:35.133 INFO [stdout] test lexing::tests::invalid_tokens_in_stave ... ok
Dec 06 11:36:35.144 INFO [stdout] test abc_generation::tests::test_triplets_with_rests ... ok
Dec 06 11:36:35.144 INFO [stdout] test lexing::tests::lex_blank_lines ... ok
Dec 06 11:36:35.144 INFO [stdout] test lexing::tests::lex_empty_key ... ok
Dec 06 11:36:35.145 INFO [stdout] test lexing::tests::lex_field_in_block ... ok
Dec 06 11:36:35.146 INFO [stdout] test lexing::tests::lex_complex_notes ... ok
Dec 06 11:36:35.147 INFO [stdout] test lexing::tests::lex_name ... ok
Dec 06 11:36:35.147 INFO [stdout] test abc_generation::tests::test_triplets_in_3_4_time ... ok
Dec 06 11:36:35.148 INFO [stdout] test lexing::tests::lex_note ... ok
Dec 06 11:36:35.152 INFO [stdout] test abc_generation::tests::three_notes_at_once ... ok
Dec 06 11:36:35.152 INFO [stdout] test lexing::tests::lex_multiple_fields ... ok
Dec 06 11:36:35.160 INFO [stdout] test lexing::tests::lex_piece ... ok
Dec 06 11:36:35.162 INFO [stdout] test lexing::tests::lex_note_length ... ok
Dec 06 11:36:35.165 INFO [stdout] test lexing::tests::lex_numbers ... ok
Dec 06 11:36:35.165 INFO [stdout] test lexing::tests::lex_play_part ... ok
Dec 06 11:36:35.166 INFO [stdout] test lexing::tests::lex_name_with_spaces ... ok
Dec 06 11:36:35.166 INFO [stdout] test lexing::tests::lex_quoted_name ... ok
Dec 06 11:36:35.166 INFO [stdout] test lexing::tests::lex_part ... ok
Dec 06 11:36:35.168 INFO [stdout] test lexing::tests::lex_section ... ok
Dec 06 11:36:35.168 INFO [stdout] test lexing::tests::lex_play ... ok
Dec 06 11:36:35.184 INFO [stdout] test notes::tests::round_trip_sharp_conversions ... ok
Dec 06 11:36:35.184 INFO [stdout] test notes::tests::round_trip_flat_conversions ... ok
Dec 06 11:36:35.186 INFO [stdout] test lexing::tests::semicolon_can_break_stave_within_one_line ... ok
Dec 06 11:36:35.196 INFO [stdout] test notes::tests::test_note_to_midi ... ok
Dec 06 11:36:35.196 INFO [stdout] test notes::tests::test_note_to_abc ... ok
Dec 06 11:36:35.196 INFO [stdout] test notes::tests::test_lcm ... ok
Dec 06 11:36:35.209 INFO [stdout] test lexing::tests::lex_quoted_name_with_quotes_in_it ... ok
Dec 06 11:36:35.210 INFO [stdout] test lexing::tests::lex_symbols ... ok
Dec 06 11:36:35.225 INFO [stdout] test lexing::tests::lex_voice ... ok
Dec 06 11:36:35.225 INFO [stdout] test lexing::tests::lex_ridiculous_field_name ... ok
Dec 06 11:36:35.232 INFO [stdout] test lexing::tests::right_brace_can_break_stave_within_one_line ... ok
Dec 06 11:36:35.233 INFO [stdout] test parsing::tests::fail_when_hit_notes_are_encountered_in_incompatible_staves ... ok
Dec 06 11:36:35.234 INFO [stdout] test parsing::tests::fail_on_underflowed_length ... ok
Dec 06 11:36:35.241 INFO [stdout] test parsing::tests::fail_when_notes_after_repeat_sign ... ok
Dec 06 11:36:35.242 INFO [stdout] test parsing::tests::fail_when_notes_before_repeat_sign ... ok
Dec 06 11:36:35.243 INFO [stdout] test parsing::tests::parse_attributes_with_trailing_comma ... ok
Dec 06 11:36:35.243 INFO [stdout] test parsing::tests::fail_on_overflowed_length ... ok
Dec 06 11:36:35.244 INFO [stdout] test parsing::tests::fail_to_parse_invalid_attributes ... ok
Dec 06 11:36:35.253 INFO [stdout] test parsing::tests::fail_on_unexpected_length ... ok
Dec 06 11:36:35.260 INFO [stdout] test parsing::tests::parse_attributes_in_piece ... ok
Dec 06 11:36:35.277 INFO [stdout] test parsing::tests::parse_empty_play_node ... ok
Dec 06 11:36:35.277 INFO [stdout] test parsing::tests::parse_attributes_without_commas ... ok
Dec 06 11:36:35.278 INFO [stdout] test parsing::tests::parse_empty_file ... ok
Dec 06 11:36:35.292 INFO [stdout] test parsing::tests::parse_empty_voice ... ok
Dec 06 11:36:35.297 INFO [stdout] test parsing::tests::parse_multiple_concurrent_melody_lines ... ok
Dec 06 11:36:35.298 INFO [stdout] test parsing::tests::parse_empty_piece ... ok
Dec 06 11:36:35.313 INFO [stdout] test parsing::tests::parse_multiple_concurrent_melody_lines_broken_up_by_blank_line ... ok
Dec 06 11:36:35.313 INFO [stdout] test parsing::tests::parse_empty_voiceless_play_node ... ok
Dec 06 11:36:35.323 INFO [stdout] test parsing::tests::parse_play_node_with_melody_notes ... ok
Dec 06 11:36:35.328 INFO [stdout] test parsing::tests::fail_parsing_too_many_staves_after_blank_line ... ok
Dec 06 11:36:35.333 INFO [stdout] test parsing::tests::parse_play_node_with_two_staves ... ok
Dec 06 11:36:35.334 INFO [stdout] test parsing::tests::parse_note_with_length ... ok
Dec 06 11:36:35.353 INFO [stdout] test parsing::tests::parse_multiple_empty_pieces ... ok
Dec 06 11:36:35.354 INFO [stdout] test parsing::tests::parse_stave_split_over_multiple_lines ... ok
Dec 06 11:36:35.369 INFO [stdout] test parsing::tests::parse_repeat ... ok
Dec 06 11:36:35.384 INFO [stdout] test parsing::tests::parse_play_node_with_extra_barlines ... ok
Dec 06 11:36:35.385 INFO [stdout] test parsing::tests::parse_rest_with_length ... ok
Dec 06 11:36:35.386 INFO [stdout] test parsing::tests::parse_play_node_with_stave ... ok
Dec 06 11:36:35.397 INFO [stdout] test sequencing::tests::fail_first_bar_repeat ... ok
Dec 06 11:36:35.398 INFO [stdout] test parsing::tests::parse_valueless_attribute ... ok
Dec 06 11:36:35.399 INFO [stdout] test parsing::tests::parse_play_node_with_percussive_notes ... ok
Dec 06 11:36:35.400 INFO [stdout] test sequencing::tests::notes_can_be_tied_across_bars ... ok
Dec 06 11:36:35.403 INFO [stdout] test sequencing::tests::piece_with_attributes ... ok
Dec 06 11:36:35.417 INFO [stdout] test sequencing::tests::piece_with_empty_voice ... ok
Dec 06 11:36:35.418 INFO [stdout] test sequencing::tests::repeat_bars_twice ... ok
Dec 06 11:36:35.424 INFO [stdout] test sequencing::tests::repeat_bars ... ok
Dec 06 11:36:35.433 INFO [stdout] test sequencing::tests::fail_when_notes_moved_out_of_range ... ok
Dec 06 11:36:35.433 INFO [stdout] test sequencing::tests::dots_do_not_carry_across_staves ... ok
Dec 06 11:36:35.460 INFO [stdout] test sequencing::tests::sequence_empty_piece ... ok
Dec 06 11:36:35.460 INFO [stdout] test sequencing::tests::voice_with_leading_dots ... ok
Dec 06 11:36:35.487 INFO [stdout] test sequencing::tests::voice_with_dots ... ok
Dec 06 11:36:35.487 INFO [stdout] test sequencing::tests::voice_with_two_notes ... ok
Dec 06 11:36:35.492 INFO [stdout] test parsing::tests::parse_voice_attributes ... ok
Dec 06 11:36:35.494 INFO [stdout] test sequencing::tests::voice_with_single_note ... ok
Dec 06 11:36:35.496 INFO [stdout] test sequencing::tests::voice_with_mismatched_play ... ok
Dec 06 11:36:35.531 INFO [stdout] test sequencing::tests::threes_against_twos ... ok
Dec 06 11:36:35.544 INFO [stdout] test sequencing::tests::voice_with_two_staves ... ok
Dec 06 11:36:35.545 INFO [stdout] test sequencing::tests::voice_with_note_lengths ... ok
Dec 06 11:36:35.612 INFO [stdout] test parsing::tests::parse_empty_piece_with_trailing_tokens_fails ... ok
Dec 06 11:36:35.612 INFO [stdout] 
Dec 06 11:36:35.612 INFO [stdout] test result: ok. 108 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 11:36:35.612 INFO [stdout] 
Dec 06 11:36:35.615 INFO [stderr]      Running /target/debug/deps/melo-06bc5f06c5c9947a
Dec 06 11:36:35.618 INFO [stdout] 
Dec 06 11:36:35.618 INFO [stdout] running 0 tests
Dec 06 11:36:35.618 INFO [stdout] 
Dec 06 11:36:35.618 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 11:36:35.618 INFO [stdout] 
Dec 06 11:36:35.620 INFO [stderr]      Running /target/debug/deps/compile_pieces_to_abc_tests-4420500c5a65ce35
Dec 06 11:36:35.630 INFO [stdout] 
Dec 06 11:36:35.630 INFO [stdout] running 9 tests
Dec 06 11:36:35.778 INFO [stdout] test minimal_drums ... ok
Dec 06 11:36:35.792 INFO [stdout] test minimal_chords ... ok
Dec 06 11:36:35.792 INFO [stdout] test minimal_melody ... ok
Dec 06 11:36:35.819 INFO [stdout] test time_and_tempo ... ok
Dec 06 11:36:35.828 INFO [stdout] test multiple_instruments ... ok
Dec 06 11:36:35.836 INFO [stdout] test repeats ... ok
Dec 06 11:36:35.836 INFO [stdout] test polyrhythms ... ok
Dec 06 11:36:35.852 INFO [stdout] test too_many_staves ... ok
Dec 06 11:36:35.939 INFO [stdout] test rondo_alla_turca ... ok
Dec 06 11:36:35.939 INFO [stdout] 
Dec 06 11:36:35.939 INFO [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 11:36:35.939 INFO [stdout] 
Dec 06 11:36:35.940 INFO [stderr]      Running /target/debug/deps/compile_to_abc_tests-a8ed837eb4b3f615
Dec 06 11:36:35.943 INFO [stdout] 
Dec 06 11:36:35.943 INFO [stdout] running 13 tests
Dec 06 11:36:36.043 INFO [stdout] test not_sextuplets ... ok
Dec 06 11:36:36.056 INFO [stdout] test complex_melody ... ok
Dec 06 11:36:36.082 INFO [stdout] test triple_time_expansion ... ok
Dec 06 11:36:36.095 INFO [stdout] test octave_shift ... ok
Dec 06 11:36:36.108 INFO [stdout] test simple_drums ... ok
Dec 06 11:36:36.109 INFO [stdout] test longer_melody ... ok
Dec 06 11:36:36.114 INFO [stdout] test simple_melody ... ok
Dec 06 11:36:36.119 INFO [stdout] test voice_args ... ok
Dec 06 11:36:36.127 INFO [stdout] test triplets ... ok
Dec 06 11:36:36.129 INFO [stdout] test quintuplets ... ok
Dec 06 11:36:36.133 INFO [stdout] test fifths ... ok
Dec 06 11:36:36.134 INFO [stdout] test variable_drum_bars ... ok
Dec 06 11:36:36.134 INFO [stdout] test variable_stave_drum_bars ... ok
Dec 06 11:36:36.134 INFO [stdout] 
Dec 06 11:36:36.135 INFO [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 11:36:36.135 INFO [stdout] 
Dec 06 11:36:36.137 INFO [stderr]    Doc-tests melo
Dec 06 11:36:39.245 INFO [stdout] 
Dec 06 11:36:39.245 INFO [stdout] running 0 tests
Dec 06 11:36:39.245 INFO [stdout] 
Dec 06 11:36:39.245 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 11:36:39.245 INFO [stdout] 
Dec 06 11:36:39.254 INFO [stderr] su: No module specific data is present
Dec 06 11:36:40.281 INFO running `"docker" "inspect" "3871c2cafaf6315272a6efa7cfb3ec672e43bba32431a1d38bd1193501ca13ec"`
Dec 06 11:36:40.638 INFO running `"docker" "rm" "-f" "3871c2cafaf6315272a6efa7cfb3ec672e43bba32431a1d38bd1193501ca13ec"`
Dec 06 11:36:40.948 INFO [stdout] 3871c2cafaf6315272a6efa7cfb3ec672e43bba32431a1d38bd1193501ca13ec