[INFO] cloning repository https://github.com/h-o-soft/mmlsid
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/h-o-soft/mmlsid" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fh-o-soft%2Fmmlsid", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fh-o-soft%2Fmmlsid'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 6d61d6cab8a610f30d45229522845f4ce7ac1857
[INFO] testing h-o-soft/mmlsid against 1.99.0-beta.8 for beta-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fh-o-soft%2Fmmlsid" "/workspace/builds/worker-3-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/h-o-soft/mmlsid
[INFO] finished tweaking git repo https://github.com/h-o-soft/mmlsid
[INFO] tweaked toml for git repo https://github.com/h-o-soft/mmlsid written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/h-o-soft/mmlsid on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/h-o-soft/mmlsid already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 0418e5793cd40a4b148471931e0a5c40a94a9e788024f7608f6ad114121b72ae
[INFO] running `Command { std: "docker" "start" "0418e5793cd40a4b148471931e0a5c40a94a9e788024f7608f6ad114121b72ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "0418e5793cd40a4b148471931e0a5c40a94a9e788024f7608f6ad114121b72ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "0418e5793cd40a4b148471931e0a5c40a94a9e788024f7608f6ad114121b72ae" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "0418e5793cd40a4b148471931e0a5c40a94a9e788024f7608f6ad114121b72ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "0418e5793cd40a4b148471931e0a5c40a94a9e788024f7608f6ad114121b72ae" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling mmlsid v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 2.57s
[INFO] running `Command { std: "docker" "inspect" "0418e5793cd40a4b148471931e0a5c40a94a9e788024f7608f6ad114121b72ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "0418e5793cd40a4b148471931e0a5c40a94a9e788024f7608f6ad114121b72ae" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling mmlsid v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 3.55s
[INFO] running `Command { std: "docker" "inspect" "0418e5793cd40a4b148471931e0a5c40a94a9e788024f7608f6ad114121b72ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "0418e5793cd40a4b148471931e0a5c40a94a9e788024f7608f6ad114121b72ae" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/mmlsid-cdeb0437ae7b6535)
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test compile::alloc::tests::local_labels_are_scoped_per_definition ... ok
[INFO] [stdout] test compile::alloc::tests::places_tables_in_order_and_labels_start ... ok
[INFO] [stdout] test compile::alloc::tests::speedtable_entries_are_shared ... ok
[INFO] [stdout] test compile::pattern::tests::shares_identical_patterns ... ok
[INFO] [stdout] test compile::quantize::tests::converts_dotted_lengths ... ok
[INFO] [stdout] test compile::pattern::tests::shares_patterns_across_calls ... ok
[INFO] [stdout] test compile::quantize::tests::suggests_a_value_that_keeps_the_current_grid ... ok
[INFO] [stdout] test compile::quantize::tests::does_not_suggest_values_beyond_u32 ... ok
[INFO] [stdout] test compile::alloc::tests::resolves_jump_to_local_label ... ok
[INFO] [stdout] test compile::alloc::tests::stop_becomes_position_zero ... ok
[INFO] [stdout] test compile::quantize::tests::rejects_lengths_off_the_grid ... ok
[INFO] [stdout] test compile::alloc::tests::rejects_table_overflow ... ok
[INFO] [stdout] test compile::pattern::tests::gives_empty_channel_a_pattern ... ok
[INFO] [stdout] test compile::pattern::tests::splits_into_chunks_of_pattern_length ... ok
[INFO] [stdout] test compile::quantize::tests::converts_plain_lengths ... ok
[INFO] [stdout] test compile::quantize::tests::suggestion_is_always_above_current ... ok
[INFO] [stdout] test compile::alloc::tests::rejects_unknown_and_duplicate_labels ... ok
[INFO] [stdout] test compile::quantize::tests::suggests_rows_per_beat_for_triplets ... ok
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/mmlsid-2b8e4334617ac56e)
[INFO] [stdout] test compile::quantize::tests::rejects_too_many_dots ... ok
[INFO] [stdout] test compile::quantize::tests::survives_extreme_inputs ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/book.rs (/opt/rustwide/target/debug/deps/book-c9dc8a678fc9ea31)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test example_songs_compile_and_validate ... ok
[INFO] [stdout] test every_example_in_the_book_compiles ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cli.rs (/opt/rustwide/target/debug/deps/cli-d0c4125985f0cdfb)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test compile_error_exits_with_one ... ok
[INFO] [stdout] test detects_the_same_file_through_a_different_path ... ok
[INFO] [stdout] /tmp/mmlsid-cli-broken-pipe/song.sng (286 バイト)
[INFO] [stdout] /tmp/mmlsid-cli-atomic/song.sng (286 バイト)
[INFO] [stdout] test reports_a_missing_file ... ok
[INFO] [stdout] test version_prints_the_crate_version ... ok
[INFO] [stdout] test leaves_no_temporary_file_behind ... ok
[INFO] [stdout] test help_succeeds_on_stdout ... ok
[INFO] [stdout] /tmp/mmlsid-cli-output-option/named.sng (286 バイト)
[INFO] [stdout] test honours_the_output_option ... ok
[INFO] [stdout] /tmp/mmlsid-cli-from-text/song.sng (286 バイト)
[INFO] [stdout] test refuses_to_overwrite_its_own_input ... ok
[INFO] [stdout] /tmp/mmlsid-cli-roundtrip/song.sng (286 バイト)
[INFO] [stdout] test survives_a_closed_pipe ... ok
[INFO] [stdout] test bad_usage_exits_with_two ... ok
[INFO] [stdout] test text_form_can_be_piped_back ... ok
[INFO] [stdout] test compiles_and_checks_a_song ... ok
[INFO] [stderr]      Running tests/commands.rs (/opt/rustwide/target/debug/deps/commands-c9d48d3c16a70748)
[INFO] [stdout] test does_not_wait_for_stdin ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 46 tests
[INFO] [stdout] test diverging_channels_fall_back_to_conservative_check ... ok
[INFO] [stdout] test all_commands_compile_to_valid_sng ... ok
[INFO] [stdout] test filter_control_packs_resonance_and_mask ... ok
[INFO] [stdout] test funktempo_uses_speedtable ... ok
[INFO] [stdout] test channel_names_do_not_swallow_following_notes ... ok
[INFO] [stdout] test aligned_channels_use_precise_check ... ok
[INFO] [stdout] test command_attaches_to_the_next_row ... ok
[INFO] [stdout] test per_channel_funktempo_recall ... ok
[INFO] [stdout] test funktempo_zero_passes_validation ... ok
[INFO] [stdout] test gate_timer_checked_against_funktempo ... ok
[INFO] [stdout] test filter_control_accepts_channel_names ... ok
[INFO] [stdout] test gate_timer_checked_against_tempo_at_that_point ... ok
[INFO] [stdout] test rejects_illegal_wavetable_commands ... ok
[INFO] [stdout] test portamento_uses_speedtable ... ok
[INFO] [stdout] test rejects_unknown_commands ... ok
[INFO] [stdout] test rejects_do_nothing_with_data ... ok
[INFO] [stdout] test implicit_instrument_one_is_checked ... ok
[INFO] [stdout] test rejects_command_pointing_outside_speedtable ... ok
[INFO] [stdout] test rejects_illegal_wavetable_command_written_as_raw ... ok
[INFO] [stdout] test speedtable_entries_are_shared_across_command_kinds ... ok
[INFO] [stdout] test inline_channel_names_must_be_uppercase ... ok
[INFO] [stdout] test table_pointer_commands_accept_raw_positions ... ok
[INFO] [stdout] test rejects_wavetable_command_pointing_outside_table ... ok
[INFO] [stdout] test rejects_out_of_range_values ... ok
[INFO] [stdout] test tempo_command_global_and_per_channel ... ok
[INFO] [stdout] test tempo_recall_uses_default_funktempo ... ok
[INFO] [stdout] test table_pointer_commands_check_kind ... ok
[INFO] [stdout] test tone_portamento_with_speed ... ok
[INFO] [stdout] test tempo_zero_and_one_recall_funktempo ... ok
[INFO] [stdout] test raw_command_escape_hatch ... ok
[INFO] [stdout] test rejects_command_in_non_wavetable ... ok
[INFO] [stdout] test register_commands ... ok
[INFO] [stdout] test rejects_bad_channel_names ... ok
[INFO] [stdout] test rejects_pointer_at_jump_row ... ok
[INFO] [stdout] test rejects_raw_command_out_of_range ... ok
[INFO] [stdout] test rejects_tempo_two ... ok
[INFO] [stdout] test table_pointer_commands_resolve_labels ... ok
[INFO] [stdout] test tempo_carried_over_the_loop_is_checked ... ok
[INFO] [stdout] test tempo_reset_at_loop_start_is_safe ... ok
[INFO] [stdout] test rejects_command_pointing_at_jump_row ... ok
[INFO] [stdout] test tone_portamento_zero_passes_validation ... ok
[INFO] [stdout] test tone_portamento_zero_is_tie_note ... ok
[INFO] [stdout] test volume_and_timing_mark_share_a_command ... ok
[INFO] [stdout] test untouched_channel_keeps_instrument_one ... ok
[INFO] [stdout] test wavetable_can_run_commands ... ok
[INFO] [stdout] test wavetable_command_by_number ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 46 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/corpus.rs (/opt/rustwide/target/debug/deps/corpus-61634ebd13f5684f)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test corpus_lowlevel_text_roundtrip ... ok
[INFO] [stdout] test corpus_passes_validation ... ok
[INFO] [stdout] test corpus_preserve_roundtrip_is_byte_exact ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/lowlevel.rs (/opt/rustwide/target/debug/deps/lowlevel-26c1a174cce8b2f2)
[INFO] [stdout] 
[INFO] [stdout] running 22 tests
[INFO] [stdout] test handles_empty_tables_and_orderlists ... ok
[INFO] [stdout] test falls_back_to_raw_for_non_ascii ... ok
[INFO] [stdout] test preserves_end_marker_inside_orderlist ... ok
[INFO] [stdout] test rejects_pattern_without_end_row ... ok
[INFO] [stdout] test rejects_unterminated_string ... ok
[INFO] [stdout] test ignores_comments_and_whitespace ... ok
[INFO] [stdout] test rejects_duplicate_channel ... ok
[INFO] [stdout] test rejects_value_too_large_for_byte ... ok
[INFO] [stdout] test escapes_quotes_and_backslashes ... ok
[INFO] [stdout] test rejects_non_sequential_numbering ... ok
[INFO] [stdout] test parses_minimal_notation ... ok
[INFO] [stdout] test rejects_unknown_section ... ok
[INFO] [stdout] test rejects_missing_channel ... ok
[INFO] [stdout] test accepts_decimal_and_hex_numbers ... ok
[INFO] [stdout] test rejects_duplicate_table_even_when_first_is_empty ... ok
[INFO] [stdout] test falls_back_to_raw_for_dirty_padding ... ok
[INFO] [stdout] test printing_is_idempotent ... ok
[INFO] [stdout] test reports_bad_escape_at_escape_position ... ok
[INFO] [stdout] test reports_lexer_error_position ... ok
[INFO] [stdout] test reports_missing_element_at_closing_brace ... ok
[INFO] [stdout] test reports_parser_error_at_offending_token ... ok
[INFO] [stdout] test text_roundtrip_preserves_song ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/macros.rs (/opt/rustwide/target/debug/deps/macros-e869d6c9aed5ea74)
[INFO] [stdout] 
[INFO] [stdout] running 21 tests
[INFO] [stdout] test macros_can_nest ... ok
[INFO] [stdout] test error_shows_the_whole_call_path ... ok
[INFO] [stdout] test errors_inside_a_macro_say_so ... ok
[INFO] [stdout] test expansion_failures_keep_the_call_path ... ok
[INFO] [stdout] test error_points_at_the_failing_call ... ok
[INFO] [stdout] test names_are_case_sensitive ... ok
[INFO] [stdout] test rejects_duplicate_definition ... ok
[INFO] [stdout] test rejects_direct_and_indirect_cycles ... ok
[INFO] [stdout] test repeat_inside_a_macro_is_fine ... ok
[INFO] [stdout] test expansion_matches_writing_it_out ... ok
[INFO] [stdout] test rejects_order_directives_in_macros ... ok
[INFO] [stdout] test rejects_undefined_macro ... ok
[INFO] [stdout] test rejects_bad_macro_names ... ok
[INFO] [stdout] test deferred_diagnostics_keep_the_call_path ... ok
[INFO] [stdout] test pending_command_crosses_the_macro_boundary ... ok
[INFO] [stdout] test rejects_too_deep_nesting ... ok
[INFO] [stdout] test forward_reference_is_allowed ... ok
[INFO] [stdout] test repeat_must_close_inside_the_macro ... ok
[INFO] [stdout] test reusable_across_channels_and_subtunes ... ok
[INFO] [stdout] test state_changes_leak_out_of_the_macro ... ok
[INFO] [stdout] test rejects_explosive_expansion ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.24s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/mml.rs (/opt/rustwide/target/debug/deps/mml-1bba796bb8364462)
[INFO] [stdout] 
[INFO] [stdout] running 44 tests
[INFO] [stdout] test default_instrument_can_actually_sound ... ok
[INFO] [stdout] test accepts_repeated_accidentals ... ok
[INFO] [stdout] test instrument_is_written_once ... ok
[INFO] [stdout] test dotted_length_is_one_and_a_half ... ok
[INFO] [stdout] test dot_alone_extends_default_length ... ok
[INFO] [stdout] test does_not_suggest_unreachable_rows_per_beat ... ok
[INFO] [stdout] test explicit_key_off_and_on ... ok
[INFO] [stdout] test length_becomes_rows ... ok
[INFO] [stdout] test empty_channels_do_not_need_padding ... ok
[INFO] [stdout] test extreme_values_do_not_panic ... ok
[INFO] [stdout] test header_strings_are_stored ... ok
[INFO] [stdout] test default_gate_timer_fits_lowest_tempo ... ok
[INFO] [stdout] test compiles_to_writable_sng ... ok
[INFO] [stdout] test multiple_channel_prefix ... ok
[INFO] [stdout] test independent_loop_allows_different_lengths ... ok
[INFO] [stdout] test ignores_comments_and_blank_lines ... ok
[INFO] [stdout] test rejects_mismatched_channel_lengths ... ok
[INFO] [stdout] test note_values_follow_octave ... ok
[INFO] [stdout] test rejects_note_out_of_sid_range ... ok
[INFO] [stdout] test octave_shifts ... ok
[INFO] [stdout] test octave_reverse_swaps_direction ... ok
[INFO] [stdout] test pad_fills_with_silence ... ok
[INFO] [stdout] test rejects_length_off_the_grid_with_hint ... ok
[INFO] [stdout] test rejects_channel_too_long ... ok
[INFO] [stdout] test shares_identical_patterns_and_packs_repeats ... ok
[INFO] [stdout] test rejects_numbers_too_large ... ok
[INFO] [stdout] test suggests_alternatives_for_other_dialects ... ok
[INFO] [stdout] test suggests_a_rows_per_beat_that_keeps_the_current_grid ... ok
[INFO] [stdout] test splits_by_pattern_length ... ok
[INFO] [stdout] test tempo_is_emitted_as_command ... ok
[INFO] [stdout] test tempo_is_omitted_when_not_specified ... ok
[INFO] [stdout] test suggested_rows_per_beat_works ... ok
[INFO] [stdout] test rejects_too_many_accidentals ... ok
[INFO] [stdout] test rest_silences_the_note ... ok
[INFO] [stdout] test rejects_unknown_header ... ok
[INFO] [stdout] test rejects_tempo_out_of_range ... ok
[INFO] [stdout] test rejects_out_of_range_octave ... ok
[INFO] [stdout] test tempo_six_is_still_emitted ... ok
[INFO] [stdout] test rejects_unknown_character ... ok
[INFO] [stdout] test tie_extends_without_key_off ... ok
[INFO] [stdout] test accidentals_shift_pitch ... ok
[INFO] [stdout] test reports_error_position ... ok
[INFO] [stdout] test extreme_rows_per_beat_errors_instead_of_panicking ... ok
[INFO] [stdout] test still_rejects_mismatch_between_written_channels ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 44 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/orderlist.rs (/opt/rustwide/target/debug/deps/orderlist-593eacab2fc1b658)
[INFO] [stdout] 
[INFO] [stdout] running 38 tests
[INFO] [stdout] test end_of_song_is_recognised_with_a_short_last_pattern ... ok
[INFO] [stdout] test boundary_uses_each_channels_own_rows ... ok
[INFO] [stdout] test compiles_to_valid_sng ... ok
[INFO] [stdout] test loop_point_sets_restart ... ok
[INFO] [stdout] test nested_repeats ... ok
[INFO] [stdout] test rejects_song_with_no_rows ... ok
[INFO] [stdout] test long_repeats_are_split ... ok
[INFO] [stdout] test rejects_subtune_with_only_directives ... ok
[INFO] [stdout] test loop_after_repeat_finds_its_position ... ok
[INFO] [stdout] test loop_point_inside_a_channel_line ... ok
[INFO] [stdout] test loop_before_transpose_restarts_at_the_transpose ... ok
[INFO] [stdout] test rejects_trailing_empty_subtune ... ok
[INFO] [stdout] test rejects_transpose_at_the_end ... ok
[INFO] [stdout] test rejects_transpose_out_of_range ... ok
[INFO] [stdout] test rejects_unbalanced_brackets ... ok
[INFO] [stdout] test rejects_two_loop_points ... ok
[INFO] [stdout] test rejects_transpose_off_pattern_boundary ... ok
[INFO] [stdout] test rejects_two_transposes_at_the_same_place ... ok
[INFO] [stdout] test rejects_unknown_directive ... ok
[INFO] [stdout] test rejects_loop_off_pattern_boundary ... ok
[INFO] [stdout] test loop_makes_gate_timer_check_conservative ... ok
[INFO] [stdout] test rejects_non_sequential_subtunes ... ok
[INFO] [stdout] test loop_before_repeat_points_at_the_repeat ... ok
[INFO] [stdout] test subtunes_are_separate ... ok
[INFO] [stdout] test subtunes_have_their_own_restart ... ok
[INFO] [stdout] test transpose_down ... ok
[INFO] [stdout] test repeat_brackets_expand ... ok
[INFO] [stdout] test repeat_defaults_to_twice ... ok
[INFO] [stdout] test repeated_patterns_are_packed ... ok
[INFO] [stdout] test restart_defaults_to_start ... ok
[INFO] [stdout] test restart_can_differ_per_channel ... ok
[INFO] [stdout] test transpose_applies_only_to_named_channels ... ok
[INFO] [stdout] test transpose_becomes_an_order_command ... ok
[INFO] [stdout] test transpose_before_repeat_keeps_order ... ok
[INFO] [stdout] test subtunes_share_patterns ... ok
[INFO] [stdout] test transpose_after_repeat_is_kept ... ok
[INFO] [stdout] test transpose_before_loop_restarts_after_it ... ok
[INFO] [stderr]      Running tests/readme.rs (/opt/rustwide/target/debug/deps/readme-e821364d4416f2b7)
[INFO] [stdout] test rejects_empty_subtune ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 38 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test readme_definition_examples_compile ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/sng_codec.rs (/opt/rustwide/target/debug/deps/sng_codec-42986aeb1e589a28)
[INFO] [stdout] 
[INFO] [stdout] running 43 tests
[INFO] [stdout] test active_instruments_includes_untouched_channels ... ok
[INFO] [stdout] test order_item_patterns_and_end ... ok
[INFO] [stdout] test minimal_sng_has_expected_size ... ok
[INFO] [stdout] test order_item_repeat_counts ... ok
[INFO] [stdout] test canonical_rejects_reference_to_unsaved_instrument ... ok
[INFO] [stdout] test gate_timer_must_be_less_than_tempo ... ok
[INFO] [stdout] test instrument_zero_means_no_change ... ok
[INFO] [stdout] test gate_timer_ignores_feature_flags ... ok
[INFO] [stdout] test playback_rejects_command_before_end_marker ... ok
[INFO] [stdout] test order_item_transpose_values ... ok
[INFO] [stdout] test playback_accepts_restart_at_repeat ... ok
[INFO] [stdout] test playback_accepts_restart_skipping_transpose ... ok
[INFO] [stdout] test playback_rejects_repeat_before_transpose ... ok
[INFO] [stdout] test preserve_roundtrip_is_byte_exact ... ok
[INFO] [stdout] test rejects_old_versions_with_specific_error ... ok
[INFO] [stdout] test playback_rejects_restart_out_of_range ... ok
[INFO] [stdout] test playback_rejects_pointer_at_jump_row ... ok
[INFO] [stdout] test played_instruments_follows_the_orderlist ... ok
[INFO] [stdout] test playback_rejects_empty_subtunes_and_patterns ... ok
[INFO] [stdout] test playback_rejects_undefined_pattern_reference ... ok
[INFO] [stdout] test playback_rejects_instrument_table_pointer_out_of_range ... ok
[INFO] [stdout] test playback_rejects_pattern_table_pointer_out_of_range ... ok
[INFO] [stdout] test played_instruments_needs_a_note ... ok
[INFO] [stdout] test played_instruments_skips_tone_portamento_rows ... ok
[INFO] [stdout] test rejects_pattern_with_too_many_rows ... ok
[INFO] [stdout] test rejects_pattern_without_end_row ... ok
[INFO] [stdout] test rejects_too_many_patterns ... ok
[INFO] [stdout] test rejects_zero_length_orderlist ... ok
[INFO] [stdout] test structure_allows_undefined_pattern_reference ... ok
[INFO] [stdout] test playback_accepts_transpose_then_repeat_then_pattern ... ok
[INFO] [stdout] test preserves_trailing_zero_rows_and_end_row_bytes ... ok
[INFO] [stdout] test table_pointer_zero_is_allowed ... ok
[INFO] [stdout] test unplayed_instrument_may_point_at_jump_row ... ok
[INFO] [stdout] test reads_minimal_sng ... ok
[INFO] [stdout] test played_instruments_ignores_unreferenced_patterns ... ok
[INFO] [stdout] test rejects_bad_magic ... ok
[INFO] [stdout] test structure_rejects_empty_instruments ... ok
[INFO] [stdout] test rejects_trailing_data ... ok
[INFO] [stdout] test rejects_truncated_file ... ok
[INFO] [stdout] test playback_rejects_invalid_command ... ok
[INFO] [stdout] test rejects_orderlist_without_end_marker ... ok
[INFO] [stdout] test rejects_too_many_subtunes ... ok
[INFO] [stdout] test startup_tempo_from_instrument_63 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 43 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/stats.rs (/opt/rustwide/target/debug/deps/stats-8feecd8225f9ef57)
[INFO] [stdout] 
[INFO] [stdout] running 21 tests
[INFO] [stdout] test declared_tempo_drives_the_bpm ... ok
[INFO] [stdout] test no_note_when_tempo_is_constant ... ok
[INFO] [stdout] test funktempo_recall_does_not_depend_on_scan_order ... ok
[INFO] [stdout] test bpm_follows_rows_per_beat ... ok
[INFO] [stdout] test lists_tempos_in_use ... ok
[INFO] [stdout] test counts_what_is_used ... ok
[INFO] [stdout] test patterns_are_shared_across_channels ... ok
[INFO] [stdout] test reports_funktempo ... ok
[INFO] [stdout] test initial_tempo_is_not_taken_from_a_later_change ... ok
[INFO] [stdout] test shows_usage_against_the_limits ... ok
[INFO] [stdout] test estimates_bpm ... ok
[INFO] [stdout] test reports_pattern_sharing ... ok
[INFO] [stdout] test saved_items_match_the_song ... ok
[INFO] [stdout] test no_sharing_note_when_nothing_is_shared ... ok
[INFO] [stdout] test tempo_returning_to_the_original_is_kept ... ok
[INFO] [stdout] test uses_the_last_rows_per_beat ... ok
[INFO] [stdout] test reports_per_channel_tempo ... ok
[INFO] [stdout] test says_when_nothing_is_packed ... ok
[INFO] [stdout] test reports_each_subtune ... ok
[INFO] [stdout] test counts_transpose_separately ... ok
[INFO] [stderr]      Running tests/tables.rs (/opt/rustwide/target/debug/deps/tables-d5218f8d84d6a05a)
[INFO] [stdout] test reports_repeat_packing ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 55 tests
[INFO] [stdout] test channel_vibrato_becomes_command ... ok
[INFO] [stdout] test channels_by_name_match_the_mask ... ok
[INFO] [stdout] test comment_after_a_closing_brace ... ok
[INFO] [stdout] test instrument_can_use_raw_pointers ... ok
[INFO] [stdout] test explicit_wave_zero_is_allowed ... ok
[INFO] [stdout] test instrument_switching ... ok
[INFO] [stdout] test compiles_to_valid_sng ... ok
[INFO] [stdout] test local_labels_resolve_within_the_definition ... ok
[INFO] [stdout] test instrument_block_layout_does_not_matter ... ok
[INFO] [stdout] test instrument_defaults_are_playable ... ok
[INFO] [stdout] test no_default_instrument_when_defined ... ok
[INFO] [stdout] test jump_can_target_a_table_name ... ok
[INFO] [stdout] test noise_combines_with_control_bits ... ok
[INFO] [stdout] test raw_value_bypasses_noise_check ... ok
[INFO] [stdout] test internal_default_name_collision_is_reported ... ok
[INFO] [stdout] test instrument_fields_are_applied ... ok
[INFO] [stdout] test filter_passbands_combine ... ok
[INFO] [stdout] test delay_entries ... ok
[INFO] [stdout] test modulation_notation_is_table_specific ... ok
[INFO] [stdout] test default_wavetable_is_added_even_with_other_tables ... ok
[INFO] [stdout] test rejects_jump_to_other_table_kind ... ok
[INFO] [stdout] test rejects_instrument_number_out_of_range ... ok
[INFO] [stdout] test rejects_instrument_without_wave ... ok
[INFO] [stdout] test rejects_label_for_speedtable ... ok
[INFO] [stdout] test rejects_extra_tokens_after_stop_and_jump ... ok
[INFO] [stdout] test rejects_noise_combined_with_oscillator ... ok
[INFO] [stdout] test local_labels_do_not_collide_across_definitions ... ok
[INFO] [stdout] test instrument_numbers_may_skip ... ok
[INFO] [stdout] test rejects_label_of_wrong_table_kind ... ok
[INFO] [stdout] test rejects_out_of_range_modulation_values ... ok
[INFO] [stdout] test params_requires_each_field_exactly_once ... ok
[INFO] [stdout] test rejects_unknown_label ... ok
[INFO] [stdout] test stop_is_jump_to_zero ... ok
[INFO] [stdout] test rejects_use_of_undefined_instrument ... ok
[INFO] [stdout] test sweep_accepts_signed_and_raw_speed ... ok
[INFO] [stdout] test rejects_duplicate_and_unknown_instrument_fields ... ok
[INFO] [stdout] test rejects_duplicate_table_name ... ok
[INFO] [stdout] test symbolic_and_raw_can_be_mixed ... ok
[INFO] [stdout] test symbols_are_wavetable_only ... ok
[INFO] [stdout] test rejects_bad_delay_and_symbols ... ok
[INFO] [stdout] test tables_are_placed_in_order ... ok
[INFO] [stdout] test rejects_table_overflow ... ok
[INFO] [stdout] test default_instrument_when_nothing_defined ... ok
[INFO] [stdout] test tempo_collision_detects_do_nothing_command ... ok
[INFO] [stdout] test rejects_table_without_name ... ok
[INFO] [stdout] test rejects_tempo_colliding_with_command ... ok
[INFO] [stdout] test pulse_set_splits_the_width ... ok
[INFO] [stdout] test waveform_symbols ... ok
[INFO] [stdout] test tables_written_by_hand_convert_to_symbols ... ok
[INFO] [stdout] test tempo_collision_names_the_occupying_command ... ok
[INFO] [stdout] test vibrato_populates_speedtable ... ok
[INFO] [stdout] test wave_right_symbols ... ok
[INFO] [stdout] test rejects_unclosed_block ... ok
[INFO] [stderr]    Doc-tests mmlsid
[INFO] [stdout] test rejects_implicit_instrument_one_when_undefined ... ok
[INFO] [stdout] test symbolic_and_raw_produce_the_same_bytes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 55 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "0418e5793cd40a4b148471931e0a5c40a94a9e788024f7608f6ad114121b72ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0418e5793cd40a4b148471931e0a5c40a94a9e788024f7608f6ad114121b72ae", kill_on_drop: false }`
[INFO] [stdout] 0418e5793cd40a4b148471931e0a5c40a94a9e788024f7608f6ad114121b72ae