[INFO] fetching crate rtpmidi 0.3.0... [INFO] testing rtpmidi-0.3.0 against try#8de4c7234dd9b97c9d76b58671343fdbbc9a433e+target=x86_64-unknown-linux-musl for musl_upgrade_1_2_5_with_getrandom_patch_0 [INFO] extracting crate rtpmidi 0.3.0 into /workspace/builds/worker-3-tc1/source [INFO] started tweaking crates.io crate rtpmidi 0.3.0 [INFO] removed 0 missing examples [INFO] removed 0 missing tests [INFO] finished tweaking crates.io crate rtpmidi 0.3.0 [INFO] tweaked toml for crates.io crate rtpmidi 0.3.0 written to /workspace/builds/worker-3-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate rtpmidi 0.3.0 on toolchain 8de4c7234dd9b97c9d76b58671343fdbbc9a433e [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate crates.io crate rtpmidi 0.3.0 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" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Updating crates.io index [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded colog v1.3.0 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 116b2e127c8d5c9f2f3eef36e535020a747a30c121b71673f520604efd122503 [INFO] running `Command { std: "docker" "start" "-a" "116b2e127c8d5c9f2f3eef36e535020a747a30c121b71673f520604efd122503", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "116b2e127c8d5c9f2f3eef36e535020a747a30c121b71673f520604efd122503", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "116b2e127c8d5c9f2f3eef36e535020a747a30c121b71673f520604efd122503", kill_on_drop: false }` [INFO] [stdout] 116b2e127c8d5c9f2f3eef36e535020a747a30c121b71673f520604efd122503 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "build" "--frozen" "--message-format=json" "--target" "x86_64-unknown-linux-musl", kill_on_drop: false }` [INFO] [stdout] b14e1eb653d4ef40e1edc6e2f06d6f43d769b22fe780e288a3ec97eab92bf670 [INFO] running `Command { std: "docker" "start" "-a" "b14e1eb653d4ef40e1edc6e2f06d6f43d769b22fe780e288a3ec97eab92bf670", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.95 [INFO] [stderr] Compiling unicode-ident v1.0.18 [INFO] [stderr] Compiling libc v0.2.172 [INFO] [stderr] Compiling autocfg v1.4.0 [INFO] [stderr] Compiling futures-core v0.3.31 [INFO] [stderr] Compiling futures-sink v0.3.31 [INFO] [stderr] Compiling getrandom v0.3.3 [INFO] [stderr] Compiling pin-project-lite v0.2.16 [INFO] [stderr] Compiling zerocopy v0.8.25 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling futures-task v0.3.31 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling memchr v2.7.4 [INFO] [stderr] Compiling futures-io v0.3.31 [INFO] [stderr] Compiling bytes v1.10.1 [INFO] [stderr] Compiling log v0.4.27 [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling byteorder v1.5.0 [INFO] [stderr] Compiling slab v0.4.9 [INFO] [stderr] Compiling quote v1.0.40 [INFO] [stderr] Compiling syn v2.0.101 [INFO] [stderr] Compiling socket2 v0.5.9 [INFO] [stderr] Compiling mio v1.0.3 [INFO] [stderr] Compiling rand_core v0.9.3 [INFO] [stderr] Compiling ppv-lite86 v0.2.21 [INFO] [stderr] Compiling rand_chacha v0.9.0 [INFO] [stderr] Compiling rand v0.9.1 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling tokio-macros v2.5.0 [INFO] [stderr] Compiling tokio v1.45.0 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling tokio-util v0.7.15 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling rtpmidi v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 17.30s [INFO] running `Command { std: "docker" "inspect" "b14e1eb653d4ef40e1edc6e2f06d6f43d769b22fe780e288a3ec97eab92bf670", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b14e1eb653d4ef40e1edc6e2f06d6f43d769b22fe780e288a3ec97eab92bf670", kill_on_drop: false }` [INFO] [stdout] b14e1eb653d4ef40e1edc6e2f06d6f43d769b22fe780e288a3ec97eab92bf670 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "test" "--frozen" "--no-run" "--message-format=json" "--target" "x86_64-unknown-linux-musl", kill_on_drop: false }` [INFO] [stdout] 766e8ac8514a7082c626a2c0479e130d5a010a52c3e78627208df4521f99016a [INFO] running `Command { std: "docker" "start" "-a" "766e8ac8514a7082c626a2c0479e130d5a010a52c3e78627208df4521f99016a", kill_on_drop: false }` [INFO] [stderr] Compiling regex-syntax v0.8.5 [INFO] [stderr] Compiling parking_lot_core v0.9.10 [INFO] [stderr] Compiling aho-corasick v1.1.3 [INFO] [stderr] Compiling lock_api v0.4.12 [INFO] [stderr] Compiling log v0.4.27 [INFO] [stderr] Compiling utf8parse v0.2.2 [INFO] [stderr] Compiling scopeguard v1.2.0 [INFO] [stderr] Compiling smallvec v1.15.0 [INFO] [stderr] Compiling anstyle-query v1.1.2 [INFO] [stderr] Compiling is_terminal_polyfill v1.70.1 [INFO] [stderr] Compiling colorchoice v1.0.3 [INFO] [stderr] Compiling anstyle v1.0.10 [INFO] [stderr] Compiling lazy_static v1.5.0 [INFO] [stderr] Compiling jiff v0.2.13 [INFO] [stderr] Compiling tokio v1.45.0 [INFO] [stderr] Compiling once_cell v1.21.3 [INFO] [stderr] Compiling anstyle-parse v0.2.6 [INFO] [stderr] Compiling hashbrown v0.14.5 [INFO] [stderr] Compiling colored v2.2.0 [INFO] [stderr] Compiling serial_test_derive v2.0.0 [INFO] [stderr] Compiling anstream v0.6.18 [INFO] [stderr] Compiling parking_lot v0.12.3 [INFO] [stderr] Compiling dashmap v5.5.3 [INFO] [stderr] Compiling serial_test v2.0.0 [INFO] [stderr] Compiling regex-automata v0.4.9 [INFO] [stderr] Compiling tokio-util v0.7.15 [INFO] [stderr] Compiling rtpmidi v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Compiling regex v1.11.1 [INFO] [stderr] Compiling env_filter v0.1.3 [INFO] [stderr] Compiling env_logger v0.11.8 [INFO] [stderr] Compiling colog v1.3.0 [INFO] [stdout] warning: unused import: `rtpmidi::sessions::rtp_midi_session::RtpMidiSession` [INFO] [stdout] --> examples/invite_to_session.rs:1:5 [INFO] [stdout] | [INFO] [stdout] 1 | use rtpmidi::sessions::rtp_midi_session::RtpMidiSession; [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_imports)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused import: `std::sync::Arc` [INFO] [stdout] --> examples/invite_to_session.rs:2:5 [INFO] [stdout] | [INFO] [stdout] 2 | use std::sync::Arc; [INFO] [stdout] | ^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 16.93s [INFO] running `Command { std: "docker" "inspect" "766e8ac8514a7082c626a2c0479e130d5a010a52c3e78627208df4521f99016a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "766e8ac8514a7082c626a2c0479e130d5a010a52c3e78627208df4521f99016a", kill_on_drop: false }` [INFO] [stdout] 766e8ac8514a7082c626a2c0479e130d5a010a52c3e78627208df4521f99016a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "test" "--frozen" "--target" "x86_64-unknown-linux-musl", kill_on_drop: false }` [INFO] [stdout] cff6f7e9e2e71f7c179974dab6c7790437d39f9ae0277dacfa2dcc30e66c6697 [INFO] running `Command { std: "docker" "start" "-a" "cff6f7e9e2e71f7c179974dab6c7790437d39f9ae0277dacfa2dcc30e66c6697", kill_on_drop: false }` [INFO] [stderr] warning: unused import: `rtpmidi::sessions::rtp_midi_session::RtpMidiSession` [INFO] [stderr] --> examples/invite_to_session.rs:1:5 [INFO] [stderr] | [INFO] [stderr] 1 | use rtpmidi::sessions::rtp_midi_session::RtpMidiSession; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(unused_imports)]` on by default [INFO] [stderr] [INFO] [stderr] warning: unused import: `std::sync::Arc` [INFO] [stderr] --> examples/invite_to_session.rs:2:5 [INFO] [stderr] | [INFO] [stderr] 2 | use std::sync::Arc; [INFO] [stderr] | ^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: `rtpmidi` (example "invite_to_session") generated 2 warnings (run `cargo fix --example "invite_to_session"` to apply 2 suggestions) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.13s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/rtpmidi-d539dbd3cccacf59) [INFO] [stdout] [INFO] [stdout] running 59 tests [INFO] [stdout] test packets::control_packets::clock_sync_packet::tests::test_new ... ok [INFO] [stdout] test packets::control_packets::clock_sync_packet::tests::test_read_control_packet_2 ... ok [INFO] [stdout] test packets::control_packets::clock_sync_packet::tests::test_write_control_packet ... ok [INFO] [stdout] test packets::control_packets::clock_sync_packet::tests::test_read_control_packet_0 ... ok [INFO] [stdout] test packets::control_packets::control_packet::tests::test_is_control_packet ... ok [INFO] [stdout] test packets::control_packets::control_packet::tests::test_parse_invalid_control_packet ... ok [INFO] [stdout] test packets::control_packets::control_packet::tests::test_parse_too_short_control_packet ... ok [INFO] [stdout] test packets::control_packets::control_packet::tests::test_read_clock_sync_packet_2 ... ok [INFO] [stdout] test packets::control_packets::control_packet::tests::test_parse_unknown_control_packet ... ok [INFO] [stdout] test packets::control_packets::control_packet::tests::test_read_session_initiation_packet ... ok [INFO] [stdout] test packets::control_packets::control_packet::tests::test_write_header ... ok [INFO] [stdout] test packets::control_packets::session_initiation_packet::tests::test_read_invalid ... ok [INFO] [stdout] test packets::control_packets::session_initiation_packet::tests::test_new_acknowledgment ... ok [INFO] [stdout] test packets::control_packets::session_initiation_packet::tests::test_new_invitation ... ok [INFO] [stdout] test packets::control_packets::session_initiation_packet::tests::test_read_acknowledgement ... ok [INFO] [stdout] test packets::control_packets::session_initiation_packet::tests::test_read_invitation ... ok [INFO] [stdout] test packets::control_packets::session_initiation_packet::tests::test_read_body ... ok [INFO] [stdout] test packets::control_packets::session_initiation_packet::tests::test_read_rejection ... ok [INFO] [stdout] test packets::control_packets::session_initiation_packet::tests::test_read_termination ... ok [INFO] [stdout] test packets::control_packets::session_initiation_packet::tests::test_write ... ok [INFO] [stdout] test packets::midi_packets::delta_time::tests::test_delta_time_zero ... ok [INFO] [stdout] test packets::midi_packets::delta_time::tests::test_four_byte_delta_time ... ok [INFO] [stdout] test packets::midi_packets::delta_time::tests::test_three_byte_delta_time ... ok [INFO] [stdout] test packets::midi_packets::delta_time::tests::test_two_byte_delta_time ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_read_channel_pressure ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_read_invalid ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_read_polyphonic_key_pressure ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_write_control_change ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_write_invalid ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_write_pitch_bend ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_write_polyphonic_key_pressure ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_read_pitch_bend ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_read_control_change ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_read_without_running_status ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_write_channel_pressure ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_read_program_change ... ok [INFO] [stdout] test packets::midi_packets::delta_time::tests::test_one_byte_delta_time ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_write_note_off ... ok [INFO] [stdout] test packets::midi_packets::delta_time::tests::test_size_calculation ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_write_program_change ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_midi_command ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_midi_command_from_bytes_without_status_byte ... ok [INFO] [stderr] Running tests/integration_test.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/integration_test-c8828973b5944b73) [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_midi_command_read_with_status_byte ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_write_note_on ... ok [INFO] [stdout] test packets::midi_packets::midi_command_list_body::tests::test_new_and_commands ... ok [INFO] [stdout] test packets::midi_packets::midi_command_list_header::tests::test_midi_command_list_header ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_serialize_and_deserialize ... ok [INFO] [stdout] test packets::midi_packets::midi_command_list_body::tests::test_size_and_write_read_roundtrip ... ok [INFO] [stdout] test packets::midi_packets::midi_packet::tests::test_midi_packet ... ok [INFO] [stdout] test packets::midi_packets::midi_timed_command::tests::test_timed_command ... ok [INFO] [stdout] test packets::midi_packets::midi_timed_command::tests::test_timed_command_from_bytes_without_delta_time ... ok [INFO] [stdout] test packets::midi_packets::midi_timed_command::tests::test_timed_command_read_with_delta_time ... ok [INFO] [stdout] test packets::midi_packets::midi_timed_command::tests::test_timed_command_serialize_and_deserialize ... ok [INFO] [stdout] test packets::midi_packets::midi_timed_command::tests::test_timed_command_write ... ok [INFO] [stdout] test packets::midi_packets::midi_timed_command::tests::test_timed_command_write_with_zero_delta_time ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_midi_command_write ... ok [INFO] [stdout] test packets::midi_packets::midi_timed_command::tests::test_timed_command_write_without_delta_time ... ok [INFO] [stdout] test packets::packet::tests::test_parse_control_packet ... ok [INFO] [stdout] test packets::packet::tests::test_parse_midi_packet ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 59 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stderr] [*] mDNS advertising is disabled. To enable it, compile with the 'mdns' feature. [INFO] [stderr] [*] RTP MIDI session Session1 starting on Control Port 55035, MIDI Port 55036 [INFO] [stderr] [*] mDNS advertising is disabled. To enable it, compile with the 'mdns' feature. [INFO] [stderr] [*] mDNS advertising is disabled. To enable it, compile with the 'mdns' feature. [INFO] [stderr] [*] RTP MIDI session Cleanup starting on Control Port 43646, MIDI Port 43647 [INFO] [stderr] [D] listen_for_control: cancellation requested [INFO] [stderr] [D] listen_for_midi: cancellation requested [INFO] [stderr] [D] start_host_clock_sync: cancellation requested [INFO] [stderr] [*] RTP MIDI session Session2 starting on Control Port 42079, MIDI Port 42080 [INFO] [stderr] [*] Session1-Control: Sent session invitation to 127.0.0.1:42079 [INFO] [stdout] test test_stop_cleanup ... ok [INFO] [stderr] [*] Session2-Control: Accepted session initiation from 127.0.0.1:55035 [INFO] [stderr] [*] Session2-Control: Sent invitation response to 127.0.0.1:55035 [INFO] [stderr] [*] Session1-Control: Received session acknowledgment from 127.0.0.1:42079 for token 3157227161 [INFO] [stderr] [D] Session1-Control: Matched Acknowledgment from 127.0.0.1:42079 invitation. Sending MIDI port invitation. [INFO] [stderr] [*] Session1-Control: Sent MIDI port invitation to 127.0.0.1:42080 with token 3157227161 [INFO] [stderr] [*] Session2-MIDI: Received session invitation from 127.0.0.1:55036 [INFO] [stderr] [*] Session2-MIDI: Sent invitation response to 127.0.0.1:55036 [INFO] [stderr] [*] Session1-MIDI: Received session acknowledgment from 127.0.0.1:42080 for token 3157227161 [INFO] [stderr] [D] Session1-MIDI: Matched Acknowledgment from 127.0.0.1:42080 for MIDI port invitation. Sending Clock Sync. [INFO] [stderr] [*] Session1-MIDI: Sent clock sync to 127.0.0.1:42080 [INFO] [stderr] [D] Session2-MIDI: Received clock sync from 127.0.0.1:55036 [INFO] [stderr] [D] Session2-MIDI: Sent clock sync response to 127.0.0.1:55036 [INFO] [stderr] [D] Session1-MIDI: Received clock sync from 127.0.0.1:42080 [INFO] [stderr] [D] Session1-MIDI: Updated clock sync for existing participant 127.0.0.1:42080 [INFO] [stderr] [D] Session1-MIDI: Sent clock sync response to 127.0.0.1:42080 [INFO] [stderr] [D] Session2-MIDI: Received clock sync from 127.0.0.1:55036 [INFO] [stderr] [*] Session2-MIDI: Clock sync finalized with 127.0.0.1:55036 (latency estimate: 1ms) [INFO] [stderr] [*] Session1-MIDI: Sending MIDI packet batch to [Participant { ctrl_addr: 127.0.0.1:42079, initiator_token: Some(3157227161), last_clock_sync: Instant { tv_sec: 6594, tv_nsec: 267469221 }, name: "Session2", invited_by_us: true }] [INFO] [stderr] [D] Session2-MIDI: Parsed MIDI packet: MidiPacket { [INFO] [stderr] | header: MidiPacketHeader { [INFO] [stderr] | flags: version: 2, p: false, x: false, cc: 0, m: false, pt: 97, [INFO] [stderr] | sequence_number: 0, [INFO] [stderr] | timestamp: 15060, [INFO] [stderr] | ssrc: 286331153, [INFO] [stderr] | }, [INFO] [stderr] | command_list: MidiCommandListBody { [INFO] [stderr] | commands: [ [INFO] [stderr] | TimedCommand { [INFO] [stderr] | delta_time: None, [INFO] [stderr] | command: NoteOn { [INFO] [stderr] | channel: 1, [INFO] [stderr] | key: 60, [INFO] [stderr] | velocity: 100, [INFO] [stderr] | }, [INFO] [stderr] | }, [INFO] [stderr] | ], [INFO] [stderr] | }, [INFO] [stderr] | } [INFO] [stderr] [*] Session2-MIDI: Sending MIDI packet batch to [Participant { ctrl_addr: 127.0.0.1:55035, initiator_token: Some(3157227161), last_clock_sync: Instant { tv_sec: 6594, tv_nsec: 266127311 }, name: "Session2", invited_by_us: false }] [INFO] [stderr] [D] Session1-MIDI: Parsed MIDI packet: MidiPacket { [INFO] [stderr] | header: MidiPacketHeader { [INFO] [stderr] | flags: version: 2, p: false, x: false, cc: 0, m: false, pt: 97, [INFO] [stderr] | sequence_number: 1, [INFO] [stderr] | timestamp: 17068, [INFO] [stderr] | ssrc: 572662306, [INFO] [stderr] | }, [INFO] [stderr] | command_list: MidiCommandListBody { [INFO] [stderr] | commands: [ [INFO] [stderr] | TimedCommand { [INFO] [stderr] | delta_time: None, [INFO] [stderr] | command: NoteOff { [INFO] [stderr] | channel: 1, [INFO] [stderr] | key: 60, [INFO] [stderr] | velocity: 0, [INFO] [stderr] | }, [INFO] [stderr] | }, [INFO] [stderr] | ], [INFO] [stderr] | }, [INFO] [stderr] | } [INFO] [stdout] test test_two_session_inter_communication ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.21s [INFO] [stdout] [INFO] [stderr] Running tests/mod.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/mod-82fb96c1468db870) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stderr] [*] mDNS advertising is disabled. To enable it, compile with the 'mdns' feature. [INFO] [stderr] [*] RTP MIDI session Session1 starting on Control Port 33255, MIDI Port 33256 [INFO] [stderr] [*] mDNS advertising is disabled. To enable it, compile with the 'mdns' feature. [INFO] [stderr] [*] RTP MIDI session Session2 starting on Control Port 52585, MIDI Port 52586 [INFO] [stdout] test integration_test::test_stop_cleanup ... ok [INFO] [stderr] [*] Session1-Control: Sent session invitation to 127.0.0.1:52585 [INFO] [stderr] [*] Session2-Control: Accepted session initiation from 127.0.0.1:33255 [INFO] [stderr] [*] Session2-Control: Sent invitation response to 127.0.0.1:33255 [INFO] [stderr] [*] Session1-Control: Received session acknowledgment from 127.0.0.1:52585 for token 1149431512 [INFO] [stderr] [D] Session1-Control: Matched Acknowledgment from 127.0.0.1:52585 invitation. Sending MIDI port invitation. [INFO] [stderr] [*] Session1-Control: Sent MIDI port invitation to 127.0.0.1:52586 with token 1149431512 [INFO] [stderr] [*] Session2-MIDI: Received session invitation from 127.0.0.1:33256 [INFO] [stderr] [*] Session2-MIDI: Sent invitation response to 127.0.0.1:33256 [INFO] [stderr] [*] Session1-MIDI: Received session acknowledgment from 127.0.0.1:52586 for token 1149431512 [INFO] [stderr] [D] Session1-MIDI: Matched Acknowledgment from 127.0.0.1:52586 for MIDI port invitation. Sending Clock Sync. [INFO] [stderr] [*] Session1-MIDI: Sent clock sync to 127.0.0.1:52586 [INFO] [stderr] [D] Session2-MIDI: Received clock sync from 127.0.0.1:33256 [INFO] [stderr] [D] Session2-MIDI: Sent clock sync response to 127.0.0.1:33256 [INFO] [stderr] [D] Session1-MIDI: Received clock sync from 127.0.0.1:52586 [INFO] [stderr] [D] Session1-MIDI: Updated clock sync for existing participant 127.0.0.1:52586 [INFO] [stderr] [D] Session1-MIDI: Sent clock sync response to 127.0.0.1:52586 [INFO] [stderr] [D] Session2-MIDI: Received clock sync from 127.0.0.1:33256 [INFO] [stderr] [*] Session2-MIDI: Clock sync finalized with 127.0.0.1:33256 (latency estimate: 0.3ms) [INFO] [stderr] [*] Session1-MIDI: Sending MIDI packet batch to [Participant { ctrl_addr: 127.0.0.1:52585, initiator_token: Some(1149431512), last_clock_sync: Instant { tv_sec: 6596, tv_nsec: 479189135 }, name: "Session2", invited_by_us: true }] [INFO] [stderr] [D] Session2-MIDI: Parsed MIDI packet: MidiPacket { [INFO] [stderr] | header: MidiPacketHeader { [INFO] [stderr] | flags: version: 2, p: false, x: false, cc: 0, m: false, pt: 97, [INFO] [stderr] | sequence_number: 0, [INFO] [stderr] | timestamp: 15040, [INFO] [stderr] | ssrc: 286331153, [INFO] [stderr] | }, [INFO] [stderr] | command_list: MidiCommandListBody { [INFO] [stderr] | commands: [ [INFO] [stderr] | TimedCommand { [INFO] [stderr] | delta_time: None, [INFO] [stderr] | command: NoteOn { [INFO] [stderr] | channel: 1, [INFO] [stderr] | key: 60, [INFO] [stderr] | velocity: 100, [INFO] [stderr] | }, [INFO] [stderr] | }, [INFO] [stderr] | ], [INFO] [stderr] | }, [INFO] [stderr] | } [INFO] [stderr] [*] Session2-MIDI: Sending MIDI packet batch to [Participant { ctrl_addr: 127.0.0.1:33255, initiator_token: Some(1149431512), last_clock_sync: Instant { tv_sec: 6596, tv_nsec: 478780824 }, name: "Session2", invited_by_us: false }] [INFO] [stderr] [D] Session1-MIDI: Parsed MIDI packet: MidiPacket { [INFO] [stderr] | header: MidiPacketHeader { [INFO] [stderr] | flags: version: 2, p: false, x: false, cc: 0, m: false, pt: 97, [INFO] [stderr] | sequence_number: 1, [INFO] [stderr] | timestamp: 17047, [INFO] [stderr] | ssrc: 572662306, [INFO] [stderr] | }, [INFO] [stderr] | command_list: MidiCommandListBody { [INFO] [stderr] | commands: [ [INFO] [stderr] | TimedCommand { [INFO] [stderr] | delta_time: None, [INFO] [stderr] | command: NoteOff { [INFO] [stderr] | channel: 1, [INFO] [stderr] | key: 60, [INFO] [stderr] | velocity: 0, [INFO] [stderr] | }, [INFO] [stderr] | }, [INFO] [stderr] | ], [INFO] [stderr] | }, [INFO] [stderr] | } [INFO] [stdout] test integration_test::test_two_session_inter_communication ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.21s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "cff6f7e9e2e71f7c179974dab6c7790437d39f9ae0277dacfa2dcc30e66c6697", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "cff6f7e9e2e71f7c179974dab6c7790437d39f9ae0277dacfa2dcc30e66c6697", kill_on_drop: false }` [INFO] [stdout] cff6f7e9e2e71f7c179974dab6c7790437d39f9ae0277dacfa2dcc30e66c6697