[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_libc_patch_0 [INFO] extracting crate rtpmidi 0.3.0 into /workspace/builds/worker-2-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-2-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 serial_test_derive v2.0.0 [INFO] [stderr] Downloaded local-ip-address v0.6.5 [INFO] [stderr] Downloaded serial_test v2.0.0 [INFO] [stderr] Downloaded if-addrs v0.13.4 [INFO] [stderr] Downloaded colog v1.3.0 [INFO] [stderr] Downloaded jiff-static v0.2.13 [INFO] [stderr] Downloaded mdns-sd v0.13.9 [INFO] [stderr] Downloaded portable-atomic v1.11.0 [INFO] [stderr] Downloaded jiff v0.2.13 [INFO] [stderr] Downloaded tokio v1.45.0 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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] 7c6c31039136c441904931766de5c95afd487e33256fa7641e6d08966b461b32 [INFO] running `Command { std: "docker" "start" "-a" "7c6c31039136c441904931766de5c95afd487e33256fa7641e6d08966b461b32", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "7c6c31039136c441904931766de5c95afd487e33256fa7641e6d08966b461b32", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7c6c31039136c441904931766de5c95afd487e33256fa7641e6d08966b461b32", kill_on_drop: false }` [INFO] [stdout] 7c6c31039136c441904931766de5c95afd487e33256fa7641e6d08966b461b32 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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] 7873cb591b589b542a71a813439698ae8460433089f40f58ab44c006e25ee368 [INFO] running `Command { std: "docker" "start" "-a" "7873cb591b589b542a71a813439698ae8460433089f40f58ab44c006e25ee368", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.172 [INFO] [stderr] Compiling zerocopy v0.8.25 [INFO] [stderr] Compiling getrandom v0.3.3 [INFO] [stderr] Compiling memchr v2.7.4 [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling log v0.4.27 [INFO] [stderr] Compiling slab v0.4.9 [INFO] [stderr] Compiling syn v2.0.101 [INFO] [stderr] Compiling mio v1.0.3 [INFO] [stderr] Compiling socket2 v0.5.9 [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 15.18s [INFO] running `Command { std: "docker" "inspect" "7873cb591b589b542a71a813439698ae8460433089f40f58ab44c006e25ee368", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7873cb591b589b542a71a813439698ae8460433089f40f58ab44c006e25ee368", kill_on_drop: false }` [INFO] [stdout] 7873cb591b589b542a71a813439698ae8460433089f40f58ab44c006e25ee368 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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] 631988b5000b32cf358a010deb10542d38650b6f6a2b14c25c7ba0b41abcc11d [INFO] running `Command { std: "docker" "start" "-a" "631988b5000b32cf358a010deb10542d38650b6f6a2b14c25c7ba0b41abcc11d", kill_on_drop: false }` [INFO] [stderr] Compiling regex-syntax v0.8.5 [INFO] [stderr] Compiling aho-corasick v1.1.3 [INFO] [stderr] Compiling parking_lot_core v0.9.10 [INFO] [stderr] Compiling smallvec v1.15.0 [INFO] [stderr] Compiling anstyle-query v1.1.2 [INFO] [stderr] Compiling lock_api v0.4.12 [INFO] [stderr] Compiling anstyle-parse v0.2.6 [INFO] [stderr] Compiling colorchoice v1.0.3 [INFO] [stderr] Compiling anstyle v1.0.10 [INFO] [stderr] Compiling jiff v0.2.13 [INFO] [stderr] Compiling colored v2.2.0 [INFO] [stderr] Compiling serial_test_derive v2.0.0 [INFO] [stderr] Compiling tokio v1.45.0 [INFO] [stderr] Compiling anstream v0.6.18 [INFO] [stderr] Compiling dashmap v5.5.3 [INFO] [stderr] Compiling parking_lot v0.12.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 14.82s [INFO] running `Command { std: "docker" "inspect" "631988b5000b32cf358a010deb10542d38650b6f6a2b14c25c7ba0b41abcc11d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "631988b5000b32cf358a010deb10542d38650b6f6a2b14c25c7ba0b41abcc11d", kill_on_drop: false }` [INFO] [stdout] 631988b5000b32cf358a010deb10542d38650b6f6a2b14c25c7ba0b41abcc11d [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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] 3b7c3ae52a5747526f5a36ef2cff9d46156826969446fde6defc8d69bd1b8e27 [INFO] running `Command { std: "docker" "start" "-a" "3b7c3ae52a5747526f5a36ef2cff9d46156826969446fde6defc8d69bd1b8e27", 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.10s [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_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::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::clock_sync_packet::tests::test_new ... 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_clock_sync_packet_2 ... 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_body ... 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_read_invitation ... ok [INFO] [stdout] test packets::control_packets::session_initiation_packet::tests::test_read_rejection ... ok [INFO] [stdout] test packets::control_packets::control_packet::tests::test_read_session_initiation_packet ... ok [INFO] [stdout] test packets::midi_packets::delta_time::tests::test_one_byte_delta_time ... ok [INFO] [stdout] test packets::control_packets::control_packet::tests::test_parse_too_short_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::session_initiation_packet::tests::test_read_termination ... 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_delta_time_zero ... ok [INFO] [stdout] test packets::control_packets::session_initiation_packet::tests::test_write ... ok [INFO] [stdout] test packets::midi_packets::delta_time::tests::test_two_byte_delta_time ... ok [INFO] [stdout] test packets::midi_packets::delta_time::tests::test_size_calculation ... ok [INFO] [stdout] test packets::control_packets::session_initiation_packet::tests::test_new_acknowledgment ... ok [INFO] [stdout] test packets::midi_packets::delta_time::tests::test_four_byte_delta_time ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_read_channel_pressure ... 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_new_invitation ... 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_pitch_bend ... 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_read_control_change ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_read_program_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_write_invalid ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_write_note_off ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_command_write_note_on ... 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_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_command_write_control_change ... ok [INFO] [stdout] test packets::midi_packets::midi_command::tests::test_midi_command_from_bytes_without_status_byte ... ok [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_midi_command_write ... 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_new_and_commands ... 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_command_list_header::tests::test_midi_command_list_header ... 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_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.03s [INFO] [stdout] [INFO] [stderr] Running tests/integration_test.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/integration_test-c8828973b5944b73) [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 Cleanup starting on Control Port 35434, MIDI Port 35435 [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] [*] mDNS advertising is disabled. To enable it, compile with the 'mdns' feature. [INFO] [stderr] [*] RTP MIDI session Session1 starting on Control Port 57112, MIDI Port 57113 [INFO] [stderr] [*] mDNS advertising is disabled. To enable it, compile with the 'mdns' feature. [INFO] [stderr] [*] RTP MIDI session Session2 starting on Control Port 51899, MIDI Port 51900 [INFO] [stdout] test test_stop_cleanup ... ok [INFO] [stderr] [*] Session1-Control: Sent session invitation to 127.0.0.1:51899 [INFO] [stderr] [*] Session2-Control: Accepted session initiation from 127.0.0.1:57112 [INFO] [stderr] [*] Session2-Control: Sent invitation response to 127.0.0.1:57112 [INFO] [stderr] [*] Session1-Control: Received session acknowledgment from 127.0.0.1:51899 for token 3726687293 [INFO] [stderr] [D] Session1-Control: Matched Acknowledgment from 127.0.0.1:51899 invitation. Sending MIDI port invitation. [INFO] [stderr] [*] Session1-Control: Sent MIDI port invitation to 127.0.0.1:51900 with token 3726687293 [INFO] [stderr] [*] Session2-MIDI: Received session invitation from 127.0.0.1:57113 [INFO] [stderr] [*] Session2-MIDI: Sent invitation response to 127.0.0.1:57113 [INFO] [stderr] [*] Session1-MIDI: Received session acknowledgment from 127.0.0.1:51900 for token 3726687293 [INFO] [stderr] [D] Session1-MIDI: Matched Acknowledgment from 127.0.0.1:51900 for MIDI port invitation. Sending Clock Sync. [INFO] [stderr] [*] Session1-MIDI: Sent clock sync to 127.0.0.1:51900 [INFO] [stderr] [D] Session2-MIDI: Received clock sync from 127.0.0.1:57113 [INFO] [stderr] [D] Session2-MIDI: Sent clock sync response to 127.0.0.1:57113 [INFO] [stderr] [D] Session1-MIDI: Received clock sync from 127.0.0.1:51900 [INFO] [stderr] [D] Session1-MIDI: Updated clock sync for existing participant 127.0.0.1:51900 [INFO] [stderr] [D] Session1-MIDI: Sent clock sync response to 127.0.0.1:51900 [INFO] [stderr] [D] Session2-MIDI: Received clock sync from 127.0.0.1:57113 [INFO] [stderr] [*] Session2-MIDI: Clock sync finalized with 127.0.0.1:57113 (latency estimate: 0.3ms) [INFO] [stderr] [*] Session1-MIDI: Sending MIDI packet batch to [Participant { ctrl_addr: 127.0.0.1:51899, initiator_token: Some(3726687293), last_clock_sync: Instant { tv_sec: 625, tv_nsec: 944530346 }, 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: 15050, [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:57112, initiator_token: Some(3726687293), last_clock_sync: Instant { tv_sec: 625, tv_nsec: 944030546 }, 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: 17060, [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] [*] mDNS advertising is disabled. To enable it, compile with the 'mdns' feature. [INFO] [stderr] [*] RTP MIDI session Cleanup starting on Control Port 59879, MIDI Port 59880 [INFO] [stderr] [*] RTP MIDI session Session1 starting on Control Port 49617, MIDI Port 49618 [INFO] [stderr] [D] listen_for_control: cancellation requested [INFO] [stderr] [*] mDNS advertising is disabled. To enable it, compile with the 'mdns' feature. [INFO] [stderr] [D] listen_for_midi: cancellation requested [INFO] [stderr] [*] RTP MIDI session Session2 starting on Control Port 45699, MIDI Port 45700 [INFO] [stderr] [D] start_host_clock_sync: cancellation requested [INFO] [stderr] [*] Session1-Control: Sent session invitation to 127.0.0.1:45699 [INFO] [stderr] [*] Session2-Control: Accepted session initiation from 127.0.0.1:49617 [INFO] [stderr] [*] Session2-Control: Sent invitation response to 127.0.0.1:49617 [INFO] [stderr] [*] Session1-Control: Received session acknowledgment from 127.0.0.1:45699 for token 2661009344 [INFO] [stderr] [D] Session1-Control: Matched Acknowledgment from 127.0.0.1:45699 invitation. Sending MIDI port invitation. [INFO] [stderr] [*] Session1-Control: Sent MIDI port invitation to 127.0.0.1:45700 with token 2661009344 [INFO] [stderr] [*] Session2-MIDI: Received session invitation from 127.0.0.1:49618 [INFO] [stderr] [*] Session2-MIDI: Sent invitation response to 127.0.0.1:49618 [INFO] [stderr] [*] Session1-MIDI: Received session acknowledgment from 127.0.0.1:45700 for token 2661009344 [INFO] [stderr] [D] Session1-MIDI: Matched Acknowledgment from 127.0.0.1:45700 for MIDI port invitation. Sending Clock Sync. [INFO] [stderr] [*] Session1-MIDI: Sent clock sync to 127.0.0.1:45700 [INFO] [stderr] [D] Session2-MIDI: Received clock sync from 127.0.0.1:49618 [INFO] [stderr] [D] Session2-MIDI: Sent clock sync response to 127.0.0.1:49618 [INFO] [stderr] [D] Session1-MIDI: Received clock sync from 127.0.0.1:45700 [INFO] [stderr] [D] Session1-MIDI: Updated clock sync for existing participant 127.0.0.1:45700 [INFO] [stderr] [D] Session1-MIDI: Sent clock sync response to 127.0.0.1:45700 [INFO] [stderr] [D] Session2-MIDI: Received clock sync from 127.0.0.1:49618 [INFO] [stderr] [*] Session2-MIDI: Clock sync finalized with 127.0.0.1:49618 (latency estimate: 0.3ms) [INFO] [stdout] test integration_test::test_stop_cleanup ... ok [INFO] [stderr] [*] Session1-MIDI: Sending MIDI packet batch to [Participant { ctrl_addr: 127.0.0.1:45699, initiator_token: Some(2661009344), last_clock_sync: Instant { tv_sec: 628, tv_nsec: 155519278 }, 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: 15035, [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:49617, initiator_token: Some(2661009344), last_clock_sync: Instant { tv_sec: 628, tv_nsec: 155024308 }, 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: 17049, [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" "3b7c3ae52a5747526f5a36ef2cff9d46156826969446fde6defc8d69bd1b8e27", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3b7c3ae52a5747526f5a36ef2cff9d46156826969446fde6defc8d69bd1b8e27", kill_on_drop: false }` [INFO] [stdout] 3b7c3ae52a5747526f5a36ef2cff9d46156826969446fde6defc8d69bd1b8e27