[INFO] fetching crate rvoip-rtp-core 0.1.26... [INFO] testing rvoip-rtp-core-0.1.26 against try#b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b for pr-145330-1 [INFO] extracting crate rvoip-rtp-core 0.1.26 into /workspace/builds/worker-0-tc2/source [INFO] started tweaking crates.io crate rvoip-rtp-core 0.1.26 [INFO] removed 0 missing examples [INFO] finished tweaking crates.io crate rvoip-rtp-core 0.1.26 [INFO] tweaked toml for crates.io crate rvoip-rtp-core 0.1.26 written to /workspace/builds/worker-0-tc2/source/Cargo.toml [INFO] validating manifest of crates.io crate rvoip-rtp-core 0.1.26 on toolchain b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate crates.io crate rvoip-rtp-core 0.1.26 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" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 6bd0a6a02b9e494bc15ac881e2ad6f9508d1ad184286636514a783911cff9df4 [INFO] running `Command { std: "docker" "start" "-a" "6bd0a6a02b9e494bc15ac881e2ad6f9508d1ad184286636514a783911cff9df4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "6bd0a6a02b9e494bc15ac881e2ad6f9508d1ad184286636514a783911cff9df4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6bd0a6a02b9e494bc15ac881e2ad6f9508d1ad184286636514a783911cff9df4", kill_on_drop: false }` [INFO] [stdout] 6bd0a6a02b9e494bc15ac881e2ad6f9508d1ad184286636514a783911cff9df4 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 9f868f4e244c30b5188d16cfe5f544f298531dc89ef4c20e5ec8d25ac3a32408 [INFO] running `Command { std: "docker" "start" "-a" "9f868f4e244c30b5188d16cfe5f544f298531dc89ef4c20e5ec8d25ac3a32408", 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.174 [INFO] [stderr] Compiling cfg-if v1.0.1 [INFO] [stderr] Compiling version_check v0.9.5 [INFO] [stderr] Compiling typenum v1.18.0 [INFO] [stderr] Compiling autocfg v1.5.0 [INFO] [stderr] Compiling subtle v2.6.1 [INFO] [stderr] Compiling const-oid v0.9.6 [INFO] [stderr] Compiling base64ct v1.8.0 [INFO] [stderr] Compiling libm v0.2.15 [INFO] [stderr] Compiling shlex v1.3.0 [INFO] [stderr] Compiling generic-array v0.14.7 [INFO] [stderr] Compiling num-traits v0.2.19 [INFO] [stderr] Compiling pem-rfc7468 v0.7.0 [INFO] [stderr] Compiling cc v1.2.27 [INFO] [stderr] Compiling cpufeatures v0.2.17 [INFO] [stderr] Compiling syn v1.0.109 [INFO] [stderr] Compiling memchr v2.7.5 [INFO] [stderr] Compiling powerfmt v0.2.0 [INFO] [stderr] Compiling num-conv v0.1.0 [INFO] [stderr] Compiling quote v1.0.40 [INFO] [stderr] Compiling zerocopy v0.8.26 [INFO] [stderr] Compiling time-core v0.1.4 [INFO] [stderr] Compiling deranged v0.4.0 [INFO] [stderr] Compiling syn v2.0.104 [INFO] [stderr] Compiling time-macros v0.2.22 [INFO] [stderr] Compiling thiserror v1.0.69 [INFO] [stderr] Compiling smallvec v1.15.1 [INFO] [stderr] Compiling spin v0.9.8 [INFO] [stderr] Compiling itoa v1.0.15 [INFO] [stderr] Compiling semver v1.0.26 [INFO] [stderr] Compiling lazy_static v1.5.0 [INFO] [stderr] Compiling ring v0.17.14 [INFO] [stderr] Compiling base16ct v0.2.0 [INFO] [stderr] Compiling pin-project-lite v0.2.16 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling minimal-lexical v0.2.1 [INFO] [stderr] Compiling unicode-xid v0.2.6 [INFO] [stderr] Compiling num-integer v0.1.46 [INFO] [stderr] Compiling time v0.3.41 [INFO] [stderr] Compiling once_cell v1.21.3 [INFO] [stderr] Compiling nom v7.1.3 [INFO] [stderr] Compiling ff v0.13.1 [INFO] [stderr] Compiling group v0.13.0 [INFO] [stderr] Compiling lock_api v0.4.13 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling parking_lot_core v0.9.11 [INFO] [stderr] Compiling rustc_version v0.4.1 [INFO] [stderr] Compiling memoffset v0.7.1 [INFO] [stderr] Compiling scopeguard v1.2.0 [INFO] [stderr] Compiling untrusted v0.9.0 [INFO] [stderr] Compiling log v0.4.27 [INFO] [stderr] Compiling curve25519-dalek v4.1.3 [INFO] [stderr] Compiling ring v0.16.20 [INFO] [stderr] Compiling futures-sink v0.3.31 [INFO] [stderr] Compiling serde v1.0.219 [INFO] [stderr] Compiling oid-registry v0.6.1 [INFO] [stderr] Compiling futures-core v0.3.31 [INFO] [stderr] Compiling opaque-debug v0.3.1 [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling ppv-lite86 v0.2.21 [INFO] [stderr] Compiling parking_lot v0.12.4 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling rusticata-macros v4.1.0 [INFO] [stderr] Compiling num-bigint v0.4.6 [INFO] [stderr] Compiling tracing-core v0.1.34 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling synstructure v0.12.6 [INFO] [stderr] Compiling signal-hook-registry v1.4.5 [INFO] [stderr] Compiling mio v1.0.4 [INFO] [stderr] Compiling socket2 v0.5.10 [INFO] [stderr] Compiling memoffset v0.9.1 [INFO] [stderr] Compiling futures-task v0.3.31 [INFO] [stderr] Compiling base64 v0.22.1 [INFO] [stderr] Compiling slab v0.4.10 [INFO] [stderr] Compiling byteorder v1.5.0 [INFO] [stderr] Compiling bytes v1.10.1 [INFO] [stderr] Compiling untrusted v0.7.1 [INFO] [stderr] Compiling spin v0.5.2 [INFO] [stderr] Compiling futures-io v0.3.31 [INFO] [stderr] Compiling num-bigint-dig v0.8.4 [INFO] [stderr] Compiling rustls v0.21.12 [INFO] [stderr] Compiling getrandom v0.3.3 [INFO] [stderr] Compiling bitflags v1.3.2 [INFO] [stderr] Compiling nix v0.26.4 [INFO] [stderr] Compiling pem v3.0.5 [INFO] [stderr] Compiling rustls-webpki v0.101.7 [INFO] [stderr] Compiling sct v0.7.1 [INFO] [stderr] Compiling yasna v0.5.2 [INFO] [stderr] Compiling num-iter v0.1.45 [INFO] [stderr] Compiling asn1-rs-impl v0.1.0 [INFO] [stderr] Compiling asn1-rs-derive v0.4.0 [INFO] [stderr] Compiling zeroize_derive v1.4.2 [INFO] [stderr] Compiling thiserror-impl v1.0.69 [INFO] [stderr] Compiling zeroize v1.8.1 [INFO] [stderr] Compiling displaydoc v0.2.5 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling curve25519-dalek-derive v0.1.1 [INFO] [stderr] Compiling der v0.7.10 [INFO] [stderr] Compiling tokio-macros v2.5.0 [INFO] [stderr] Compiling serde_derive v1.0.219 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling crypto-common v0.1.6 [INFO] [stderr] Compiling block-buffer v0.10.4 [INFO] [stderr] Compiling digest v0.10.7 [INFO] [stderr] Compiling block-padding v0.3.3 [INFO] [stderr] Compiling inout v0.1.4 [INFO] [stderr] Compiling crypto-bigint v0.5.5 [INFO] [stderr] Compiling hmac v0.12.1 [INFO] [stderr] Compiling cipher v0.4.4 [INFO] [stderr] Compiling hkdf v0.12.4 [INFO] [stderr] Compiling signature v2.2.0 [INFO] [stderr] Compiling spki v0.7.3 [INFO] [stderr] Compiling rfc6979 v0.4.0 [INFO] [stderr] Compiling asn1-rs v0.5.2 [INFO] [stderr] Compiling pkcs8 v0.10.2 [INFO] [stderr] Compiling sec1 v0.7.3 [INFO] [stderr] Compiling universal-hash v0.5.1 [INFO] [stderr] Compiling polyval v0.6.2 [INFO] [stderr] Compiling ctr v0.9.2 [INFO] [stderr] Compiling sha2 v0.10.9 [INFO] [stderr] Compiling aead v0.5.2 [INFO] [stderr] Compiling ghash v0.5.1 [INFO] [stderr] Compiling aes v0.8.4 [INFO] [stderr] Compiling tokio v1.45.1 [INFO] [stderr] Compiling der-parser v8.2.0 [INFO] [stderr] Compiling elliptic-curve v0.13.8 [INFO] [stderr] Compiling async-trait v0.1.88 [INFO] [stderr] Compiling ipnet v2.11.0 [INFO] [stderr] Compiling ecdsa v0.16.9 [INFO] [stderr] Compiling primeorder v0.13.6 [INFO] [stderr] Compiling overload v0.1.1 [INFO] [stderr] Compiling winapi v0.3.9 [INFO] [stderr] Compiling data-encoding v2.9.0 [INFO] [stderr] Compiling p256 v0.13.2 [INFO] [stderr] Compiling p384 v0.13.1 [INFO] [stderr] Compiling nu-ansi-term v0.46.0 [INFO] [stderr] Compiling x509-parser v0.15.1 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling aes-gcm v0.10.3 [INFO] [stderr] Compiling ccm v0.5.0 [INFO] [stderr] Compiling x25519-dalek v2.0.1 [INFO] [stderr] Compiling pkcs1 v0.7.5 [INFO] [stderr] Compiling cbc v0.1.2 [INFO] [stderr] Compiling sha1 v0.10.6 [INFO] [stderr] Compiling rcgen v0.11.3 [INFO] [stderr] Compiling tracing-attributes v0.1.30 [INFO] [stderr] Compiling tracing-log v0.2.0 [INFO] [stderr] Compiling sharded-slab v0.1.7 [INFO] [stderr] Compiling thread_local v1.1.9 [INFO] [stderr] Compiling base64 v0.21.7 [INFO] [stderr] Compiling bitflags v2.9.1 [INFO] [stderr] Compiling match_cfg v0.1.0 [INFO] [stderr] Compiling hostname v0.3.1 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling nix v0.27.1 [INFO] [stderr] Compiling rustls-pemfile v1.0.4 [INFO] [stderr] Compiling tracing-subscriber v0.3.19 [INFO] [stderr] Compiling rsa v0.9.8 [INFO] [stderr] Compiling uuid v1.17.0 [INFO] [stderr] Compiling bincode v1.3.3 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling base64 v0.13.1 [INFO] [stderr] Compiling webrtc-util v0.8.1 [INFO] [stderr] Compiling webrtc-dtls v0.9.0 [INFO] [stderr] Compiling rvoip-rtp-core v0.1.26 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 43s [INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: num-bigint-dig v0.8.4 [INFO] [stderr] note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 1` [INFO] running `Command { std: "docker" "inspect" "9f868f4e244c30b5188d16cfe5f544f298531dc89ef4c20e5ec8d25ac3a32408", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "9f868f4e244c30b5188d16cfe5f544f298531dc89ef4c20e5ec8d25ac3a32408", kill_on_drop: false }` [INFO] [stdout] 9f868f4e244c30b5188d16cfe5f544f298531dc89ef4c20e5ec8d25ac3a32408 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 7df88fdf33ee95ee2fe34c836e3f5ce584f6d8baf265788936dbd41b490db7ec [INFO] running `Command { std: "docker" "start" "-a" "7df88fdf33ee95ee2fe34c836e3f5ce584f6d8baf265788936dbd41b490db7ec", kill_on_drop: false }` [INFO] [stderr] Compiling futures-core v0.3.31 [INFO] [stderr] Compiling tokio v1.45.1 [INFO] [stderr] Compiling async-stream-impl v0.3.6 [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling async-stream v0.3.6 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling webrtc-util v0.8.1 [INFO] [stderr] Compiling tokio-stream v0.1.17 [INFO] [stderr] Compiling tokio-test v0.4.4 [INFO] [stderr] Compiling webrtc-dtls v0.9.0 [INFO] [stderr] Compiling rvoip-rtp-core v0.1.26 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 2m 25s [INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: num-bigint-dig v0.8.4 [INFO] [stderr] note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 1` [INFO] running `Command { std: "docker" "inspect" "7df88fdf33ee95ee2fe34c836e3f5ce584f6d8baf265788936dbd41b490db7ec", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7df88fdf33ee95ee2fe34c836e3f5ce584f6d8baf265788936dbd41b490db7ec", kill_on_drop: false }` [INFO] [stdout] 7df88fdf33ee95ee2fe34c836e3f5ce584f6d8baf265788936dbd41b490db7ec [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 5a602421bae81c7f6600ef7679ccfc4768c41aede829c242a81eb1925cc40d72 [INFO] running `Command { std: "docker" "start" "-a" "5a602421bae81c7f6600ef7679ccfc4768c41aede829c242a81eb1925cc40d72", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.31s [INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: num-bigint-dig v0.8.4 [INFO] [stderr] note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 1` [INFO] [stdout] [INFO] [stdout] running 208 tests [INFO] [stdout] test api::common::extension::tests::test_audio_level_data ... ok [INFO] [stdout] test api::common::extension::tests::test_webrtc_extension_mappings ... ok [INFO] [stdout] test api::common::security_manager::tests::test_create_method_config ... ok [INFO] [stdout] test api::common::security_manager::tests::test_custom_method_preference ... ok [INFO] [stdout] test api::common::security_manager::tests::test_method_detection ... ok [INFO] [stdout] test api::common::security_manager::tests::test_negotiation_strategy_enum ... ok [INFO] [stdout] test api::common::security_manager::tests::test_create_security_manager ... ok [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rvoip_rtp_core-7e272b9733c60995) [INFO] [stdout] test api::common::security_manager::tests::test_initialize_manager ... ok [INFO] [stdout] test api::common::security_manager::tests::test_security_capabilities_struct ... ok [INFO] [stdout] test api::common::security_manager::tests::test_security_capabilities ... ok [INFO] [stdout] test api::common::unified_security::tests::test_create_mikey_context ... ok [INFO] [stdout] test api::common::unified_security::tests::test_create_psk_context ... ok [INFO] [stdout] test api::common::unified_security::tests::test_create_sdes_context ... ok [INFO] [stdout] test api::common::unified_security::tests::test_create_zrtp_context ... ok [INFO] [stdout] test api::common::unified_security::tests::test_invalid_psk_key ... ok [INFO] [stdout] test api::common::unified_security::tests::test_key_exchange_method_properties ... ok [INFO] [stdout] test api::common::unified_security::tests::test_method_config_creation ... ok [INFO] [stdout] test api::common::security_manager::tests::test_auto_negotiate_no_methods ... ok [INFO] [stdout] test api::common::unified_security::tests::test_multi_method_config ... ok [INFO] [stdout] test api::common::extension::tests::test_transport_cc_data ... ok [INFO] [stdout] test api::common::unified_security::tests::test_sdes_initialization_placeholder ... ok [INFO] [stdout] test api::common::unified_security::tests::test_psk_initialization ... ok [INFO] [stdout] test api::common::unified_security::tests::test_mikey_initialization_placeholder ... ok [INFO] [stdout] test api::common::unified_security::tests::test_security_config_creation ... ok [INFO] [stdout] test api::common::unified_security::tests::test_security_mode_conversions ... ok [INFO] [stdout] test api::common::unified_security::tests::test_sip_scenario_configs ... ok [INFO] [stdout] test api::common::unified_security::tests::test_zrtp_initialization_success ... ok [INFO] [stdout] test buffer::jitter::tests::test_in_order_packets ... ok [INFO] [stdout] test buffer::jitter::tests::test_packet_loss ... ok [INFO] [stdout] test buffer::jitter::tests::test_out_of_order_packets ... ok [INFO] [stdout] test buffer::jitter::tests::test_sequence_wraparound ... ok [INFO] [stdout] test api::common::extension::tests::test_video_orientation_data ... ok [INFO] [stdout] test buffer::tests::test_memory_limits ... ok [INFO] [stdout] test buffer::transmit::tests::test_basic_queuing ... ok [INFO] [stdout] test api::common::security_manager::tests::test_manager_initialization_warnings ... ok [INFO] [stdout] test api::common::security_manager::tests::test_security_context_type_variants ... ok [INFO] [stdout] test api::common::security_manager::tests::test_method_name_mapping ... ok [INFO] [stdout] test api::common::security_manager::tests::test_psk_negotiation ... ok [INFO] [stdout] test dtls::crypto::cipher::tests::test_mac_algorithm_properties ... ok [INFO] [stdout] test buffer::transmit::tests::test_buffer_overflow ... ok [INFO] [stdout] test error::tests::test_error_display ... ok [INFO] [stdout] test dtls::crypto::cipher::tests::test_cipher_type_properties ... ok [INFO] [stdout] test dtls::crypto::cipher::tests::test_cipher_suite_properties ... ok [INFO] [stdout] test packet::extension::tests::test_extension_format_detection ... ok [INFO] [stdout] test packet::extension::tests::test_one_byte_extensions ... ok [INFO] [stdout] test packet::extension::tests::test_invalid_extensions ... ok [INFO] [stdout] test packet::extension::tests::test_one_byte_serialization_alignment ... ok [INFO] [stdout] test packet::extension::tests::test_remove_extension ... ok [INFO] [stdout] test packet::extension::tests::test_legacy_extensions ... ok [INFO] [stdout] test packet::extension::tests::test_two_byte_extensions ... ok [INFO] [stdout] test packet::extension::tests::test_two_byte_serialization_alignment ... ok [INFO] [stdout] test packet::header::tests::test_header_create ... ok [INFO] [stdout] test packet::header::tests::test_header_size ... ok [INFO] [stdout] test packet::header::tests::test_parse_without_consuming ... ok [INFO] [stdout] test packet::rtcp::app::tests::test_app_creation ... ok [INFO] [stdout] test packet::rtcp::app::tests::test_serialize_parse ... ok [INFO] [stdout] 2025-10-02T05:35:23.658825Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x90 (V=2, P=false, X=true, CC=0) [INFO] [stdout] 2025-10-02T05:35:23.658880Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse with 20 bytes available [INFO] [stdout] 2025-10-02T05:35:23.658897Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x90 [INFO] [stdout] 2025-10-02T05:35:23.658904Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:23.658912Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=true, cc=0 [INFO] [stdout] 2025-10-02T05:35:23.658919Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x60 [INFO] [stdout] 2025-10-02T05:35:23.658926Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 96 [INFO] [stdout] 2025-10-02T05:35:23.658944Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 1000 [INFO] [stdout] 2025-10-02T05:35:23.658952Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 12345 [INFO] [stdout] 2025-10-02T05:35:23.658959Z DEBUG rvoip_rtp_core::packet::header: SSRC: 2882400001 [INFO] [stdout] 2025-10-02T05:35:23.658966Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:23.658973Z DEBUG rvoip_rtp_core::packet::header: Parsing extension header [INFO] [stdout] 2025-10-02T05:35:23.658980Z DEBUG rvoip_rtp_core::packet::header: Extension profile ID: 0xbede, length: 1 words [INFO] [stdout] 2025-10-02T05:35:23.658987Z DEBUG rvoip_rtp_core::packet::header: Extension length in bytes: 4 [INFO] [stdout] 2025-10-02T05:35:23.658997Z DEBUG rvoip_rtp_core::packet::header: Read 4 bytes of extension data [INFO] [stdout] 2025-10-02T05:35:23.659006Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully [INFO] [stdout] test buffer::transmit::tests::test_congestion_control ... ok [INFO] [stdout] 2025-10-02T05:35:23.659164Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:23.659187Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse with 12 bytes available [INFO] [stdout] 2025-10-02T05:35:23.659198Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x80 [INFO] [stdout] test packet::header::tests::test_header_with_extension ... 2025-10-02T05:35:23.659204Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] ok [INFO] [stdout] 2025-10-02T05:35:23.659210Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=false, cc=0 [INFO] [stdout] 2025-10-02T05:35:23.659216Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x60 [INFO] [stdout] 2025-10-02T05:35:23.659222Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 96 [INFO] [stdout] 2025-10-02T05:35:23.659228Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 1000 [INFO] [stdout] test packet::rtcp::app::tests::test_set_data ... 2025-10-02T05:35:23.659234Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 12345 [INFO] [stdout] ok [INFO] [stdout] 2025-10-02T05:35:23.659240Z DEBUG rvoip_rtp_core::packet::header: SSRC: 2882400001 [INFO] [stdout] 2025-10-02T05:35:23.659245Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:23.659253Z DEBUG rvoip_rtp_core::packet::header: No extension header [INFO] [stdout] 2025-10-02T05:35:23.659259Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully [INFO] [stdout] test packet::header::tests::test_header_serialize_parse ... ok [INFO] [stdout] test packet::rtcp::bye::tests::test_bye_creation ... ok [INFO] [stdout] test packet::rtcp::app::tests::test_size_calculation ... ok [INFO] [stdout] test packet::rtcp::bye::tests::test_serialize_parse ... ok [INFO] [stdout] test packet::rtcp::bye::tests::test_set_reason ... ok [INFO] [stdout] test packet::rtcp::bye::tests::test_add_source ... ok [INFO] [stdout] test packet::rtcp::bye::tests::test_size_calculation ... ok [INFO] [stdout] test packet::rtcp::compound::tests::test_compound_packet_validation ... ok [INFO] [stdout] test packet::rtcp::ntp::tests::test_ntp_timestamp_conversion ... ok [INFO] [stdout] test packet::rtcp::ntp::tests::test_ntp_timestamp_creation ... ok [INFO] [stdout] test packet::rtcp::compound::tests::test_compound_packet_serialize_parse ... ok [INFO] [stdout] test packet::rtcp::ntp::tests::test_ntp_timestamp_from_duration ... ok [INFO] [stdout] test packet::rtcp::ntp::tests::test_ntp_timestamp_to_duration ... ok [INFO] [stdout] test packet::rtcp::receiver_report::tests::test_add_report_block ... ok [INFO] [stdout] test packet::rtcp::receiver_report::tests::test_receiver_report_creation ... ok [INFO] [stdout] test packet::rtcp::receiver_report::tests::test_serialize_parse ... ok [INFO] [stdout] test packet::rtcp::report_block::tests::test_packet_loss_calculation ... ok [INFO] [stdout] test packet::rtcp::report_block::tests::test_report_block_serialize_parse ... ok [INFO] [stdout] test packet::rtcp::receiver_report::tests::test_size_calculation ... ok [INFO] [stdout] test packet::rtcp::report_block::tests::test_report_block_creation ... ok [INFO] [stdout] test packet::rtcp::sdes::tests::test_sdes_chunk ... ok [INFO] [stdout] test packet::rtcp::sdes::tests::test_sdes_packet ... ok [INFO] [stdout] test packet::rtcp::sdes::tests::test_sdes_item_creation ... ok [INFO] [stdout] test packet::rtcp::sender_report::tests::test_add_report_block ... ok [INFO] [stdout] test packet::rtcp::app::tests::test_app_with_data ... ok [INFO] [stdout] test packet::rtcp::sender_report::tests::test_serialize_parse ... ok [INFO] [stdout] test packet::rtcp::sender_report::tests::test_sender_report_creation ... ok [INFO] [stdout] test packet::rtcp::xr::tests::test_voip_metrics_block ... ok [INFO] [stdout] test packet::rtcp::sender_report::tests::test_size_calculation ... ok [INFO] [stdout] test packet::rtcp::tests::test_rtcp_packet_type_conversion ... ok [INFO] [stdout] test packet::rtcp::xr::tests::test_receiver_reference_time_block ... ok [INFO] [stdout] 2025-10-02T05:35:23.661320Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:23.661345Z DEBUG rvoip_rtp_core::packet::rtp: Parsing RTP packet from 29 bytes [INFO] [stdout] 2025-10-02T05:35:23.661364Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse_without_consuming with 29 bytes [INFO] [stdout] 2025-10-02T05:35:23.661376Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x80 [INFO] [stdout] 2025-10-02T05:35:23.661384Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:23.661391Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=false, cc=0 [INFO] [stdout] 2025-10-02T05:35:23.661399Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x60 [INFO] [stdout] 2025-10-02T05:35:23.661405Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 96 [INFO] [stdout] 2025-10-02T05:35:23.661412Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 1000 [INFO] [stdout] 2025-10-02T05:35:23.661419Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 12345 [INFO] [stdout] 2025-10-02T05:35:23.661425Z DEBUG rvoip_rtp_core::packet::header: SSRC: 2882400001 [INFO] [stdout] 2025-10-02T05:35:23.661433Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:23.661440Z DEBUG rvoip_rtp_core::packet::header: No extension header [INFO] [stdout] 2025-10-02T05:35:23.661447Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully, consumed 12 bytes [INFO] [stdout] 2025-10-02T05:35:23.661455Z DEBUG rvoip_rtp_core::packet::rtp: Parsed header of size 12 [INFO] [stdout] 2025-10-02T05:35:23.661463Z DEBUG rvoip_rtp_core::packet::rtp: Extracted payload of size 17 [INFO] [stdout] test packet::rtcp::xr::tests::test_xr_packet ... ok [INFO] [stdout] test packet::rtp::tests::test_debug_format ... ok [INFO] [stdout] test packet::rtp::tests::test_serialize_parse_roundtrip ... ok [INFO] [stdout] test packet::rtp::tests::test_new_with_payload ... ok [INFO] [stdout] test packet::rtp::tests::test_size ... ok [INFO] [stdout] 2025-10-02T05:35:23.661694Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:23.661714Z DEBUG rvoip_rtp_core::packet::rtp: Parsing RTP packet from 29 bytes [INFO] [stdout] 2025-10-02T05:35:23.661738Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse_without_consuming with 29 bytes [INFO] [stdout] 2025-10-02T05:35:23.661748Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x80 [INFO] [stdout] 2025-10-02T05:35:23.661754Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:23.661759Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=false, cc=0 [INFO] [stdout] 2025-10-02T05:35:23.661764Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x60 [INFO] [stdout] 2025-10-02T05:35:23.661770Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 96 [INFO] [stdout] 2025-10-02T05:35:23.661775Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 1000 [INFO] [stdout] 2025-10-02T05:35:23.661781Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 12345 [INFO] [stdout] 2025-10-02T05:35:23.661786Z DEBUG rvoip_rtp_core::packet::header: SSRC: 2882400001 [INFO] [stdout] 2025-10-02T05:35:23.661791Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:23.661797Z DEBUG rvoip_rtp_core::packet::header: No extension header [INFO] [stdout] 2025-10-02T05:35:23.661802Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully, consumed 12 bytes [INFO] [stdout] 2025-10-02T05:35:23.661807Z DEBUG rvoip_rtp_core::packet::rtp: Parsed header of size 12 [INFO] [stdout] 2025-10-02T05:35:23.661814Z DEBUG rvoip_rtp_core::packet::rtp: Extracted payload of size 17 [INFO] [stdout] test payload::g711::tests::test_g711u_payload_format ... ok [INFO] [stdout] test payload::g722::tests::test_g722_payload_format ... ok [INFO] [stdout] test packet::tests::test_packet_serialize_parse_roundtrip ... ok [INFO] [stdout] test packet::tests::test_rtp_packet_creation ... ok [INFO] [stdout] test payload::g711::tests::test_g711a_payload_format ... ok [INFO] [stdout] test payload::opus::tests::test_opus_bandwidth_modes ... ok [INFO] [stdout] test payload::registry::tests::test_media_frame_type_fallback ... ok [INFO] [stdout] test payload::opus::tests::test_opus_payload_format ... ok [INFO] [stdout] test payload::registry::tests::test_dynamic_types ... ok [INFO] [stdout] test payload::registry::tests::test_rfc3551_audio_types ... ok [INFO] [stdout] test payload::vp9::tests::test_vp9_descriptor_parsing ... ok [INFO] [stdout] test payload::vp8::tests::test_vp8_descriptor_parsing ... ok [INFO] [stdout] test payload::vp8::tests::test_vp8_payload_format ... ok [INFO] [stdout] test payload::registry::tests::test_rfc3551_video_types ... ok [INFO] [stdout] test payload::vp9::tests::test_vp9_payload_format ... ok [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_crypto_suites ... ok [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_init ... ok [INFO] [stdout] test packet::rtcp::app::tests::test_name_string ... ok [INFO] [stdout] test api::server::transport::tests::tests::test_server_lifecycle ... ok [INFO] [stdout] test buffer::pool::tests::test_buffer_pool ... ok [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_status_check ... ok [INFO] [stdout] test security::sdes::tests::sdes_tests::test_sdes_crypto_attribute_parsing ... ok [INFO] [stdout] test security::sdes::tests::sdes_tests::test_sdes_error_handling ... ok [INFO] [stdout] test security::sdes::tests::sdes_tests::test_sdes_multiple_crypto_suites ... ok [INFO] [stdout] test security::sdes::tests::sdes_tests::test_sdes_offer_answer_exchange ... ok [INFO] [stdout] test security::zrtp::tests::zrtp_tests::test_zrtp_basic_init ... ok [INFO] [stdout] test security::zrtp::tests::zrtp_tests::test_zrtp_config ... ok [INFO] [stdout] test security::zrtp::tests::zrtp_tests::test_zrtp_hash_functions ... ok [INFO] [stdout] test security::zrtp::tests::zrtp_tests::test_zrtp_packet_formats ... ok [INFO] [stdout] test security::zrtp::tests::zrtp_tests::test_zrtp_sas_deterministic ... ok [INFO] [stdout] test security::zrtp::tests::zrtp_tests::test_zrtp_sas_different_types ... ok [INFO] [stdout] test security::zrtp::tests::zrtp_tests::test_zrtp_sas_generation ... ok [INFO] [stdout] test security::zrtp::tests::zrtp_tests::test_zrtp_sas_verification ... ok [INFO] [stdout] 2025-10-02T05:35:24.031682Z DEBUG rvoip_rtp_core::session::scheduling: Set packet interval to 20ms (160 samples per packet) [INFO] [stdout] 2025-10-02T05:35:24.031776Z DEBUG rvoip_rtp_core::session::scheduling: Started RTP scheduler [INFO] [stdout] 2025-10-02T05:35:24.035684Z DEBUG rvoip_rtp_core::session::scheduling: Scheduled packet with seq=1000, ts=0 for Instant { tv_sec: 9632, tv_nsec: 369126251 } [INFO] [stdout] 2025-10-02T05:35:24.055814Z DEBUG rvoip_rtp_core::session::scheduling: Stopped RTP scheduler [INFO] [stdout] test session::scheduling::tests::test_scheduler_basic ... ok [INFO] [stdout] 2025-10-02T05:35:24.058859Z DEBUG rvoip_rtp_core::session::scheduling: Set packet interval to 20ms (160 samples per packet) [INFO] [stdout] 2025-10-02T05:35:24.058931Z DEBUG rvoip_rtp_core::session::scheduling: Started RTP scheduler [INFO] [stdout] 2025-10-02T05:35:24.058955Z DEBUG rvoip_rtp_core::session::scheduling: Scheduled packet with seq=1000, ts=0 for Instant { tv_sec: 9632, tv_nsec: 396283749 } [INFO] [stdout] 2025-10-02T05:35:24.058975Z DEBUG rvoip_rtp_core::session::scheduling: Scheduled packet with seq=1001, ts=160 for Instant { tv_sec: 9632, tv_nsec: 396283749 } [INFO] [stdout] 2025-10-02T05:35:24.058983Z DEBUG rvoip_rtp_core::session::scheduling: Scheduled packet with seq=1002, ts=320 for Instant { tv_sec: 9632, tv_nsec: 396283749 } [INFO] [stdout] 2025-10-02T05:35:24.079796Z DEBUG rvoip_rtp_core::session::scheduling: Stopped RTP scheduler [INFO] [stdout] test session::scheduling::tests::test_scheduler_timestamp_increment ... ok [INFO] [stdout] test session::stream::tests::test_is_sequence_newer ... ok [INFO] [stdout] 2025-10-02T05:35:24.086827Z DEBUG rvoip_rtp_core::session::stream: Initialized RTP stream with seq=1000 [INFO] [stdout] 2025-10-02T05:35:24.086882Z DEBUG rvoip_rtp_core::session::stream: Detected sequence gap: expected=1002, got=1005, lost=3 [INFO] [stdout] 2025-10-02T05:35:24.086891Z DEBUG rvoip_rtp_core::session::stream: Out of order packet: seq=1003, highest=1005 [INFO] [stdout] test session::stream::tests::test_sequence_tracking ... ok [INFO] [stdout] 2025-10-02T05:35:24.094714Z DEBUG rvoip_rtp_core::session::stream: Initialized RTP stream with seq=65530 [INFO] [stdout] 2025-10-02T05:35:24.094781Z DEBUG rvoip_rtp_core::session::stream: Detected sequence wraparound: 65535 -> 0 [INFO] [stdout] test session::stream::tests::test_sequence_wraparound ... ok [INFO] [stdout] test srtp::auth::tests::test_hmac_authentication ... ok [INFO] [stdout] test srtp::auth::tests::test_null_authentication ... ok [INFO] [stdout] test srtp::auth::tests::test_real_replay_protection ... ok [INFO] [stdout] test srtp::auth::tests::test_real_replay_protection_basic ... ok [INFO] [stdout] test srtp::auth::tests::test_replay_protection ... ok [INFO] [stdout] test srtp::crypto::tests::test_aes_cm_encryption ... ok [INFO] [stdout] 2025-10-02T05:35:24.137221Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:24.137787Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:24.140695Z DEBUG rvoip_rtp_core::packet::rtp: Parsing RTP packet from 79 bytes [INFO] [stdout] 2025-10-02T05:35:24.140726Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse_without_consuming with 79 bytes [INFO] [stdout] 2025-10-02T05:35:24.140736Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x80 [INFO] [stdout] 2025-10-02T05:35:24.140743Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:24.140749Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=false, cc=0 [INFO] [stdout] 2025-10-02T05:35:24.140754Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x60 [INFO] [stdout] 2025-10-02T05:35:24.140760Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 96 [INFO] [stdout] 2025-10-02T05:35:24.140766Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 1000 [INFO] [stdout] 2025-10-02T05:35:24.140772Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 12345 [INFO] [stdout] 2025-10-02T05:35:24.140778Z DEBUG rvoip_rtp_core::packet::header: SSRC: 2882400001 [INFO] [stdout] 2025-10-02T05:35:24.140784Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:24.140790Z DEBUG rvoip_rtp_core::packet::header: No extension header [INFO] [stdout] 2025-10-02T05:35:24.140796Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully, consumed 12 bytes [INFO] [stdout] 2025-10-02T05:35:24.140802Z DEBUG rvoip_rtp_core::packet::rtp: Parsed header of size 12 [INFO] [stdout] 2025-10-02T05:35:24.140809Z DEBUG rvoip_rtp_core::packet::rtp: Extracted payload of size 67 [INFO] [stdout] 2025-10-02T05:35:24.140886Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:24.140917Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:24.140941Z DEBUG rvoip_rtp_core::packet::rtp: Parsing RTP packet from 79 bytes [INFO] [stdout] 2025-10-02T05:35:24.140947Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse_without_consuming with 79 bytes [INFO] [stdout] 2025-10-02T05:35:24.140953Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x80 [INFO] [stdout] 2025-10-02T05:35:24.140958Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:24.140963Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=false, cc=0 [INFO] [stdout] 2025-10-02T05:35:24.140969Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x60 [INFO] [stdout] 2025-10-02T05:35:24.140974Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 96 [INFO] [stdout] 2025-10-02T05:35:24.140980Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 1000 [INFO] [stdout] 2025-10-02T05:35:24.140985Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 12345 [INFO] [stdout] 2025-10-02T05:35:24.140990Z DEBUG rvoip_rtp_core::packet::header: SSRC: 2882400001 [INFO] [stdout] 2025-10-02T05:35:24.140995Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:24.141001Z DEBUG rvoip_rtp_core::packet::header: No extension header [INFO] [stdout] 2025-10-02T05:35:24.141006Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully, consumed 12 bytes [INFO] [stdout] 2025-10-02T05:35:24.141012Z DEBUG rvoip_rtp_core::packet::rtp: Parsed header of size 12 [INFO] [stdout] 2025-10-02T05:35:24.141018Z DEBUG rvoip_rtp_core::packet::rtp: Extracted payload of size 67 [INFO] [stdout] test srtp::crypto::tests::test_complete_srtp_process ... ok [INFO] [stdout] test srtp::crypto::tests::test_hmac_sha1 ... ok [INFO] [stdout] test srtp::crypto::tests::test_null_encryption ... ok [INFO] [stdout] test srtp::crypto::tests::test_srtp_key_from_base64 ... ok [INFO] [stdout] 2025-10-02T05:35:24.151244Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:24.151297Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:24.151325Z DEBUG rvoip_rtp_core::packet::rtp: Parsing RTP packet from 26 bytes [INFO] [stdout] 2025-10-02T05:35:24.151339Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse_without_consuming with 26 bytes [INFO] [stdout] 2025-10-02T05:35:24.151345Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x80 [INFO] [stdout] 2025-10-02T05:35:24.151351Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:24.151357Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=false, cc=0 [INFO] [stdout] 2025-10-02T05:35:24.151363Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x60 [INFO] [stdout] 2025-10-02T05:35:24.151369Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 96 [INFO] [stdout] 2025-10-02T05:35:24.151375Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 1000 [INFO] [stdout] 2025-10-02T05:35:24.151381Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 12345 [INFO] [stdout] 2025-10-02T05:35:24.151387Z DEBUG rvoip_rtp_core::packet::header: SSRC: 2882400001 [INFO] [stdout] 2025-10-02T05:35:24.151394Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:24.151401Z DEBUG rvoip_rtp_core::packet::header: No extension header [INFO] [stdout] 2025-10-02T05:35:24.151407Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully, consumed 12 bytes [INFO] [stdout] 2025-10-02T05:35:24.151413Z DEBUG rvoip_rtp_core::packet::rtp: Parsed header of size 12 [INFO] [stdout] 2025-10-02T05:35:24.151420Z DEBUG rvoip_rtp_core::packet::rtp: Extracted payload of size 14 [INFO] [stdout] test srtp::crypto::tests::test_tamper_detection ... ok [INFO] [stdout] 2025-10-02T05:35:24.160880Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:24.160965Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:24.161002Z DEBUG rvoip_rtp_core::packet::rtp: Parsing RTP packet from 42 bytes [INFO] [stdout] 2025-10-02T05:35:24.161024Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse_without_consuming with 42 bytes [INFO] [stdout] 2025-10-02T05:35:24.161033Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x80 [INFO] [stdout] 2025-10-02T05:35:24.161040Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:24.161046Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=false, cc=0 [INFO] [stdout] 2025-10-02T05:35:24.161051Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x60 [INFO] [stdout] 2025-10-02T05:35:24.161057Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 96 [INFO] [stdout] 2025-10-02T05:35:24.161063Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 1000 [INFO] [stdout] 2025-10-02T05:35:24.161069Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 12345 [INFO] [stdout] 2025-10-02T05:35:24.161075Z DEBUG rvoip_rtp_core::packet::header: SSRC: 2882400001 [INFO] [stdout] 2025-10-02T05:35:24.161081Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:24.161087Z DEBUG rvoip_rtp_core::packet::header: No extension header [INFO] [stdout] 2025-10-02T05:35:24.161092Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully, consumed 12 bytes [INFO] [stdout] 2025-10-02T05:35:24.161099Z DEBUG rvoip_rtp_core::packet::rtp: Parsed header of size 12 [INFO] [stdout] 2025-10-02T05:35:24.161105Z DEBUG rvoip_rtp_core::packet::rtp: Extracted payload of size 30 [INFO] [stdout] test srtp::integration_tests::test_srtp_with_mikey_key_exchange ... ok [INFO] [stdout] 2025-10-02T05:35:24.161469Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:24.161510Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:24.161539Z DEBUG rvoip_rtp_core::packet::rtp: Parsing RTP packet from 35 bytes [INFO] [stdout] 2025-10-02T05:35:24.161561Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse_without_consuming with 35 bytes [INFO] [stdout] 2025-10-02T05:35:24.161571Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x80 [INFO] [stdout] 2025-10-02T05:35:24.161578Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:24.161583Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=false, cc=0 [INFO] [stdout] 2025-10-02T05:35:24.161589Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x60 [INFO] [stdout] 2025-10-02T05:35:24.161595Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 96 [INFO] [stdout] 2025-10-02T05:35:24.161602Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 1000 [INFO] [stdout] 2025-10-02T05:35:24.161608Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 12345 [INFO] [stdout] 2025-10-02T05:35:24.161613Z DEBUG rvoip_rtp_core::packet::header: SSRC: 2882400001 [INFO] [stdout] 2025-10-02T05:35:24.161620Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:24.161626Z DEBUG rvoip_rtp_core::packet::header: No extension header [INFO] [stdout] 2025-10-02T05:35:24.161647Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully, consumed 12 bytes [INFO] [stdout] 2025-10-02T05:35:24.161654Z DEBUG rvoip_rtp_core::packet::rtp: Parsed header of size 12 [INFO] [stdout] 2025-10-02T05:35:24.161661Z DEBUG rvoip_rtp_core::packet::rtp: Extracted payload of size 23 [INFO] [stdout] test srtp::integration_tests::test_srtp_with_sdes_key_exchange ... ok [INFO] [stdout] 2025-10-02T05:35:24.166846Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:24.166915Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:24.167260Z DEBUG rvoip_rtp_core::packet::rtp: Parsing RTP packet from 41 bytes [INFO] [stdout] 2025-10-02T05:35:24.167289Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse_without_consuming with 41 bytes [INFO] [stdout] 2025-10-02T05:35:24.167301Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x80 [INFO] [stdout] 2025-10-02T05:35:24.167311Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:24.167317Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=false, cc=0 [INFO] [stdout] 2025-10-02T05:35:24.167607Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x60 [INFO] [stdout] 2025-10-02T05:35:24.167625Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 96 [INFO] [stdout] 2025-10-02T05:35:24.167838Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 1000 [INFO] [stdout] 2025-10-02T05:35:24.168082Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 12345 [INFO] [stdout] 2025-10-02T05:35:24.168242Z DEBUG rvoip_rtp_core::packet::header: SSRC: 2882400001 [INFO] [stdout] 2025-10-02T05:35:24.168334Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:24.168469Z DEBUG rvoip_rtp_core::packet::header: No extension header [INFO] [stdout] 2025-10-02T05:35:24.168573Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully, consumed 12 bytes [INFO] [stdout] 2025-10-02T05:35:24.168585Z DEBUG rvoip_rtp_core::packet::rtp: Parsed header of size 12 [INFO] [stdout] 2025-10-02T05:35:24.168594Z DEBUG rvoip_rtp_core::packet::rtp: Extracted payload of size 29 [INFO] [stdout] test srtp::integration_tests::test_srtp_with_zrtp_key_exchange ... ok [INFO] [stdout] test srtp::key_derivation::tests::test_create_srtp_iv ... ok [INFO] [stdout] test srtp::key_derivation::tests::test_kdf_with_different_output_sizes ... ok [INFO] [stdout] test srtp::key_derivation::tests::test_key_rotation_frequency ... ok [INFO] [stdout] test srtp::key_derivation::tests::test_srtp_kdf ... ok [INFO] [stdout] test srtp::tests::test_srtp_context_creation ... ok [INFO] [stdout] test stats::jitter::tests::test_jitter_estimation ... ok [INFO] [stdout] test stats::jitter::tests::test_timestamp_delta ... ok [INFO] [stdout] test stats::loss::tests::test_burst_detection ... ok [INFO] [stdout] test stats::loss::tests::test_duplicate_packets ... ok [INFO] [stdout] test stats::loss::tests::test_packet_loss ... ok [INFO] [stdout] test stats::loss::tests::test_reordered_packets ... ok [INFO] [stdout] test stats::loss::tests::test_sequence_wraparound ... ok [INFO] [stdout] test stats::loss::tests::test_sequential_packets ... ok [INFO] [stdout] test stats::reports::tests::test_rtcp_interval ... ok [INFO] [stdout] test stats::reports::tests::test_sdes_generation ... ok [INFO] [stdout] test stats::reports::tests::test_sender_report_generation ... ok [INFO] [stdout] test stats::rtt::tests::test_rtt_calculation ... ok [INFO] [stdout] test stats::rtt::tests::test_rtt_initial_state ... ok [INFO] [stdout] test stats::rtt::tests::test_rtt_tracking ... ok [INFO] [stdout] test stats::tests::test_sequence_comparison ... ok [INFO] [stdout] test stats::tests::test_stats_manager ... ok [INFO] [stdout] test sync::clock::tests::test_media_clock_conversion ... ok [INFO] [stdout] test sync::clock::tests::test_sync_offset_calculation ... ok [INFO] [stdout] test sync::mapping::tests::test_stream_mapping ... ok [INFO] [stdout] test sync::mapping::tests::test_timestamp_mapper ... ok [INFO] [stdout] 2025-10-02T05:35:24.817909Z DEBUG rvoip_rtp_core::tests: Test packet data: [80 00 fd 70 00 00 00 00 00 00 00 00 54 65 73 74] [INFO] [stdout] 2025-10-02T05:35:24.817954Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse with 16 bytes available [INFO] [stdout] 2025-10-02T05:35:24.817965Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x80 [INFO] [stdout] 2025-10-02T05:35:24.817970Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:24.817976Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=false, cc=0 [INFO] [stdout] 2025-10-02T05:35:24.817982Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x00 [INFO] [stdout] 2025-10-02T05:35:24.817988Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 0 [INFO] [stdout] 2025-10-02T05:35:24.817995Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 64880 [INFO] [stdout] 2025-10-02T05:35:24.818002Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 0 [INFO] [stdout] 2025-10-02T05:35:24.818008Z DEBUG rvoip_rtp_core::packet::header: SSRC: 0 [INFO] [stdout] 2025-10-02T05:35:24.818014Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:24.818020Z DEBUG rvoip_rtp_core::packet::header: No extension header [INFO] [stdout] 2025-10-02T05:35:24.818025Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully [INFO] [stdout] 2025-10-02T05:35:24.818033Z DEBUG rvoip_rtp_core::tests: RTP header parse succeeded, remaining bytes: 4 [INFO] [stdout] 2025-10-02T05:35:24.818039Z DEBUG rvoip_rtp_core::packet::rtp: Parsing RTP packet from 16 bytes [INFO] [stdout] 2025-10-02T05:35:24.818045Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse_without_consuming with 16 bytes [INFO] [stdout] 2025-10-02T05:35:24.818054Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x80 [INFO] [stdout] 2025-10-02T05:35:24.818059Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:24.818064Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=false, cc=0 [INFO] [stdout] 2025-10-02T05:35:24.818069Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x00 [INFO] [stdout] 2025-10-02T05:35:24.818075Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 0 [INFO] [stdout] 2025-10-02T05:35:24.818080Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 64880 [INFO] [stdout] 2025-10-02T05:35:24.818086Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 0 [INFO] [stdout] 2025-10-02T05:35:24.818091Z DEBUG rvoip_rtp_core::packet::header: SSRC: 0 [INFO] [stdout] 2025-10-02T05:35:24.818098Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:24.818103Z DEBUG rvoip_rtp_core::packet::header: No extension header [INFO] [stdout] 2025-10-02T05:35:24.818110Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully, consumed 12 bytes [INFO] [stdout] 2025-10-02T05:35:24.818117Z DEBUG rvoip_rtp_core::packet::rtp: Parsed header of size 12 [INFO] [stdout] 2025-10-02T05:35:24.818124Z DEBUG rvoip_rtp_core::packet::rtp: Extracted payload of size 4 [INFO] [stdout] 2025-10-02T05:35:24.818132Z DEBUG rvoip_rtp_core::tests: RTP packet parse succeeded [INFO] [stdout] test tests::test_parse_real_world_packet ... ok [INFO] [stdout] 2025-10-02T05:35:24.818285Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x90 (V=2, P=false, X=true, CC=0) [INFO] [stdout] 2025-10-02T05:35:24.818306Z DEBUG rvoip_rtp_core::packet::rtp: Parsing RTP packet from 44 bytes [INFO] [stdout] 2025-10-02T05:35:24.818319Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse_without_consuming with 44 bytes [INFO] [stdout] 2025-10-02T05:35:24.818327Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x90 [INFO] [stdout] 2025-10-02T05:35:24.818332Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:24.818338Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=true, cc=0 [INFO] [stdout] 2025-10-02T05:35:24.818344Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x60 [INFO] [stdout] 2025-10-02T05:35:24.818348Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 96 [INFO] [stdout] 2025-10-02T05:35:24.818354Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 1000 [INFO] [stdout] 2025-10-02T05:35:24.818360Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 12345 [INFO] [stdout] 2025-10-02T05:35:24.818365Z DEBUG rvoip_rtp_core::packet::header: SSRC: 2882400001 [INFO] [stdout] 2025-10-02T05:35:24.818371Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:24.818381Z DEBUG rvoip_rtp_core::packet::header: Parsing extension header [INFO] [stdout] 2025-10-02T05:35:24.818389Z DEBUG rvoip_rtp_core::packet::header: Extension profile ID: 0x1234, length: 4 words [INFO] [stdout] 2025-10-02T05:35:24.818396Z DEBUG rvoip_rtp_core::packet::header: Extension length in bytes: 16 [INFO] [stdout] 2025-10-02T05:35:24.818403Z DEBUG rvoip_rtp_core::packet::header: Read 16 bytes of extension data [INFO] [stdout] 2025-10-02T05:35:24.818412Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully, consumed 32 bytes [INFO] [stdout] 2025-10-02T05:35:24.818418Z DEBUG rvoip_rtp_core::packet::rtp: Parsed header of size 32 [INFO] [stdout] 2025-10-02T05:35:24.818424Z DEBUG rvoip_rtp_core::packet::rtp: Extracted payload of size 12 [INFO] [stdout] test tests::test_parse_rtp_packet_with_extension ... ok [INFO] [stdout] 2025-10-02T05:35:24.818697Z DEBUG rvoip_rtp_core::tests: Original header: PT=96 [INFO] [stdout] 2025-10-02T05:35:24.818713Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:24.818728Z DEBUG rvoip_rtp_core::tests: Serialized header bytes: [80 60 03 e8 12 34 56 78 ab cd ef 01] [INFO] [stdout] 2025-10-02T05:35:24.818754Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse with 12 bytes available [INFO] [stdout] 2025-10-02T05:35:24.818770Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x80 [INFO] [stdout] 2025-10-02T05:35:24.818776Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:24.818782Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=false, cc=0 [INFO] [stdout] 2025-10-02T05:35:24.818788Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x60 [INFO] [stdout] 2025-10-02T05:35:24.818798Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 96 [INFO] [stdout] 2025-10-02T05:35:24.818804Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 1000 [INFO] [stdout] 2025-10-02T05:35:24.818809Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 305419896 [INFO] [stdout] 2025-10-02T05:35:24.818815Z DEBUG rvoip_rtp_core::packet::header: SSRC: 2882400001 [INFO] [stdout] 2025-10-02T05:35:24.818830Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:24.818836Z DEBUG rvoip_rtp_core::packet::header: No extension header [INFO] [stdout] 2025-10-02T05:35:24.818848Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully [INFO] [stdout] 2025-10-02T05:35:24.818857Z DEBUG rvoip_rtp_core::tests: Parsed header: PT=96 [INFO] [stdout] test tests::test_rtp_header_serialize_parse ... ok [INFO] [stdout] 2025-10-02T05:35:24.823688Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x82 (V=2, P=false, X=false, CC=2) [INFO] [stdout] 2025-10-02T05:35:24.824038Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse with 20 bytes available [INFO] [stdout] 2025-10-02T05:35:24.824122Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x82 [INFO] [stdout] 2025-10-02T05:35:24.824315Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:24.824655Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=false, cc=2 [INFO] [stdout] 2025-10-02T05:35:24.824671Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x60 [INFO] [stdout] 2025-10-02T05:35:24.824678Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 96 [INFO] [stdout] 2025-10-02T05:35:24.824683Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 1000 [INFO] [stdout] 2025-10-02T05:35:24.824689Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 305419896 [INFO] [stdout] 2025-10-02T05:35:24.824695Z DEBUG rvoip_rtp_core::packet::header: SSRC: 2882400001 [INFO] [stdout] 2025-10-02T05:35:24.824701Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 2 entries [INFO] [stdout] 2025-10-02T05:35:24.824713Z DEBUG rvoip_rtp_core::packet::header: CSRC 0: 0x11111111 [INFO] [stdout] 2025-10-02T05:35:24.824720Z DEBUG rvoip_rtp_core::packet::header: CSRC 1: 0x22222222 [INFO] [stdout] 2025-10-02T05:35:24.824725Z DEBUG rvoip_rtp_core::packet::header: No extension header [INFO] [stdout] 2025-10-02T05:35:24.824731Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully [INFO] [stdout] test tests::test_rtp_header_with_csrc ... ok [INFO] [stdout] 2025-10-02T05:35:24.832884Z DEBUG rvoip_rtp_core::tests: Original header with extension: ext=true, format=Some(Legacy), data_len=Some(14) [INFO] [stdout] 2025-10-02T05:35:24.832918Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x90 (V=2, P=false, X=true, CC=0) [INFO] [stdout] 2025-10-02T05:35:24.832951Z DEBUG rvoip_rtp_core::tests: Serialized extension header (size=32): [90 60 03 e8 12 34 56 78 ab cd ef 01 12 34 00 04 65 78 74 65 6e 73 69 6f 6e 20 64 61 74 61 00 00] [INFO] [stdout] 2025-10-02T05:35:24.832961Z DEBUG rvoip_rtp_core::tests: First byte: 0x90, extension bit set: true [INFO] [stdout] 2025-10-02T05:35:24.832968Z DEBUG rvoip_rtp_core::tests: Manual parse of first byte 0x90: V=2, P=false, X=true, CC=0 [INFO] [stdout] 2025-10-02T05:35:24.832977Z DEBUG rvoip_rtp_core::tests: Buffer size for parsing: 32 [INFO] [stdout] 2025-10-02T05:35:24.832991Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse with 32 bytes available [INFO] [stdout] 2025-10-02T05:35:24.832998Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x90 [INFO] [stdout] 2025-10-02T05:35:24.833003Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:24.833009Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=true, cc=0 [INFO] [stdout] 2025-10-02T05:35:24.833014Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x60 [INFO] [stdout] 2025-10-02T05:35:24.833020Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 96 [INFO] [stdout] 2025-10-02T05:35:24.833026Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 1000 [INFO] [stdout] 2025-10-02T05:35:24.833032Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 305419896 [INFO] [stdout] 2025-10-02T05:35:24.833038Z DEBUG rvoip_rtp_core::packet::header: SSRC: 2882400001 [INFO] [stdout] 2025-10-02T05:35:24.833043Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:24.833049Z DEBUG rvoip_rtp_core::packet::header: Parsing extension header [INFO] [stdout] 2025-10-02T05:35:24.833055Z DEBUG rvoip_rtp_core::packet::header: Extension profile ID: 0x1234, length: 4 words [INFO] [stdout] 2025-10-02T05:35:24.833061Z DEBUG rvoip_rtp_core::packet::header: Extension length in bytes: 16 [INFO] [stdout] 2025-10-02T05:35:24.833070Z DEBUG rvoip_rtp_core::packet::header: Read 16 bytes of extension data [INFO] [stdout] 2025-10-02T05:35:24.833080Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully [INFO] [stdout] 2025-10-02T05:35:24.833089Z DEBUG rvoip_rtp_core::tests: Remaining bytes after parse: 0 [INFO] [stdout] test tests::test_rtp_header_with_extension ... ok [INFO] [stdout] 2025-10-02T05:35:24.855723Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:24.855777Z DEBUG rvoip_rtp_core::packet::rtp: Parsing RTP packet from 29 bytes [INFO] [stdout] 2025-10-02T05:35:24.855797Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse_without_consuming with 29 bytes [INFO] [stdout] 2025-10-02T05:35:24.855807Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x80 [INFO] [stdout] 2025-10-02T05:35:24.855813Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:24.855819Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=false, cc=0 [INFO] [stdout] 2025-10-02T05:35:24.855825Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x60 [INFO] [stdout] 2025-10-02T05:35:24.855831Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 96 [INFO] [stdout] 2025-10-02T05:35:24.855836Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 1000 [INFO] [stdout] 2025-10-02T05:35:24.855842Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 305419896 [INFO] [stdout] 2025-10-02T05:35:24.855848Z DEBUG rvoip_rtp_core::packet::header: SSRC: 2882400001 [INFO] [stdout] 2025-10-02T05:35:24.855854Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:24.855860Z DEBUG rvoip_rtp_core::packet::header: No extension header [INFO] [stdout] 2025-10-02T05:35:24.855866Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully, consumed 12 bytes [INFO] [stdout] 2025-10-02T05:35:24.855873Z DEBUG rvoip_rtp_core::packet::rtp: Parsed header of size 12 [INFO] [stdout] 2025-10-02T05:35:24.855879Z DEBUG rvoip_rtp_core::packet::rtp: Extracted payload of size 17 [INFO] [stdout] test tests::test_rtp_packet_serialize_parse ... ok [INFO] [stdout] 2025-10-02T05:35:24.856058Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x90 (V=2, P=false, X=true, CC=0) [INFO] [stdout] test tests::test_serialize_rtp_packet_with_extension ... ok [INFO] [stdout] test time::tests::test_timestamp_conversion ... ok [INFO] [stdout] test time::tests::test_timestamp_diff ... ok [INFO] [stdout] 2025-10-02T05:35:24.860958Z DEBUG rvoip_rtp_core::transport::udp: Bound RTP socket to 0.0.0.0:33534 [INFO] [stdout] 2025-10-02T05:35:24.862854Z DEBUG rvoip_rtp_core::transport::udp: Using RTCP-MUX - no separate RTCP socket [INFO] [stdout] 2025-10-02T05:35:24.863240Z  INFO rvoip_rtp_core::transport::udp: Started UDP transport receiver tasks [INFO] [stdout] 2025-10-02T05:35:24.867771Z DEBUG rvoip_rtp_core::session::scheduling: Set packet interval to 20ms (160 samples per packet) [INFO] [stdout] 2025-10-02T05:35:24.881716Z DEBUG rvoip_rtp_core::session::scheduling: Started RTP scheduler [INFO] [stdout] 2025-10-02T05:35:24.881789Z  INFO rvoip_rtp_core::session: Started RTP session with SSRC=9b2046b6 [INFO] [stdout] 2025-10-02T05:35:24.881836Z DEBUG rvoip_rtp_core::session::scheduling: Scheduled packet with seq=50531, ts=3691999493 for Instant { tv_sec: 9633, tv_nsec: 219032281 } [INFO] [stdout] 2025-10-02T05:35:24.881855Z DEBUG rvoip_rtp_core::session::scheduling: Stopped RTP scheduler [INFO] [stdout] 2025-10-02T05:35:24.881881Z  INFO rvoip_rtp_core::session: Closed RTP session with SSRC=9b2046b6 [INFO] [stdout] test traits::media_transport::tests::test_rtp_media_transport ... ok [INFO] [stdout] 2025-10-02T05:35:24.883277Z  INFO rvoip_rtp_core::transport::allocator: Created global port allocator with range 16384-32767 [INFO] [stdout] 2025-10-02T05:35:24.883502Z DEBUG rvoip_rtp_core::transport::allocator: Successfully validated port 26819 (bound to 127.0.0.1:26819) [INFO] [stdout] test transport::allocator::tests::test_global_allocator ... ok [INFO] [stdout] 2025-10-02T05:35:25.148869Z DEBUG rvoip_rtp_core::transport::allocator: Successfully validated port 19059 (bound to 0.0.0.0:19059) [INFO] [stdout] 2025-10-02T05:35:25.400476Z DEBUG rvoip_rtp_core::transport::allocator: Released port 19059 on 0.0.0.0 [INFO] [stdout] test transport::allocator::tests::test_muxed_port_allocation ... ok [INFO] [stdout] 2025-10-02T05:35:25.479085Z DEBUG rvoip_rtp_core::transport::allocator: Successfully validated port 29674 (bound to 127.0.0.1:29674) [INFO] [stdout] 2025-10-02T05:35:25.735731Z DEBUG rvoip_rtp_core::transport::allocator: Released port 29674 on 127.0.0.1 [INFO] [stdout] test transport::allocator::tests::test_port_allocation ... ok [INFO] [stdout] test transport::allocator::tests::test_port_allocator_creation ... ok [INFO] [stdout] test transport::allocator::tests::test_port_allocator_with_custom_config ... ok [INFO] [stdout] 2025-10-02T05:35:25.754929Z DEBUG rvoip_rtp_core::transport::allocator: Released port 24714 on 127.0.0.1 [INFO] [stdout] 2025-10-02T05:35:25.755106Z DEBUG rvoip_rtp_core::transport::allocator: Released port 24715 on 127.0.0.1 [INFO] [stdout] test transport::allocator::tests::test_port_pair_allocation ... ok [INFO] [stdout] 2025-10-02T05:35:25.760742Z DEBUG rvoip_rtp_core::transport::allocator: Successfully validated port 28490 (bound to 0.0.0.0:28490) [INFO] [stdout] 2025-10-02T05:35:26.022863Z DEBUG rvoip_rtp_core::transport::allocator: Successfully validated port 23156 (bound to 0.0.0.0:23156) [INFO] [stdout] 2025-10-02T05:35:26.281820Z DEBUG rvoip_rtp_core::transport::allocator: Released port 28490 on 0.0.0.0 [INFO] [stdout] 2025-10-02T05:35:26.281920Z DEBUG rvoip_rtp_core::transport::allocator: Released port 23156 on 0.0.0.0 [INFO] [stdout] test transport::allocator::tests::test_separate_port_allocation ... ok [INFO] [stdout] 2025-10-02T05:35:26.297184Z DEBUG rvoip_rtp_core::transport::udp: Bound RTP socket to 127.0.0.1:46316 [INFO] [stdout] 2025-10-02T05:35:26.297219Z DEBUG rvoip_rtp_core::transport::udp: Using RTCP-MUX - no separate RTCP socket [INFO] [stdout] 2025-10-02T05:35:26.297282Z  INFO rvoip_rtp_core::transport::udp: Started UDP transport receiver tasks [INFO] [stdout] test transport::udp::tests::test_muxed_socket_bind_conflicts ... ok [INFO] [stdout] 2025-10-02T05:35:26.301100Z DEBUG rvoip_rtp_core::transport::udp: Bound RTP socket to 127.0.0.1:36584 [INFO] [stdout] 2025-10-02T05:35:26.308673Z DEBUG rvoip_rtp_core::transport::udp: Using RTCP-MUX - no separate RTCP socket [INFO] [stdout] 2025-10-02T05:35:26.309024Z  INFO rvoip_rtp_core::transport::udp: Started UDP transport receiver tasks [INFO] [stdout] test transport::udp::tests::test_rtcp_mux_socket_creation ... ok [INFO] [stdout] 2025-10-02T05:35:26.311943Z DEBUG rvoip_rtp_core::transport::udp: Identified RTCP packet: version=2, PT=200 [INFO] [stdout] 2025-10-02T05:35:26.311974Z DEBUG rvoip_rtp_core::transport::udp: Identified RTCP packet: version=2, PT=201 [INFO] [stdout] 2025-10-02T05:35:26.311981Z DEBUG rvoip_rtp_core::transport::udp: Identified RTCP packet: version=2, PT=202 [INFO] [stdout] 2025-10-02T05:35:26.311988Z DEBUG rvoip_rtp_core::transport::udp: Identified RTCP packet: version=2, PT=203 [INFO] [stdout] 2025-10-02T05:35:26.311993Z DEBUG rvoip_rtp_core::transport::udp: Identified RTCP packet: version=2, PT=204 [INFO] [stdout] 2025-10-02T05:35:26.312002Z DEBUG rvoip_rtp_core::transport::udp: Identified as RTP packet: version=2, PT=0 [INFO] [stdout] 2025-10-02T05:35:26.312015Z DEBUG rvoip_rtp_core::transport::udp: Identified as RTP packet: version=2, PT=0 [INFO] [stdout] 2025-10-02T05:35:26.312021Z DEBUG rvoip_rtp_core::transport::udp: Identified as RTP packet: version=2, PT=96 [INFO] [stdout] test transport::udp::tests::test_rtcp_packet_detection ... ok [INFO] [stdout] 2025-10-02T05:35:26.316874Z DEBUG rvoip_rtp_core::transport::udp: Bound RTP socket to 127.0.0.1:50289 [INFO] [stdout] 2025-10-02T05:35:26.316935Z DEBUG rvoip_rtp_core::transport::udp: Bound RTCP socket to 127.0.0.1:45524 [INFO] [stdout] 2025-10-02T05:35:26.317016Z  INFO rvoip_rtp_core::transport::udp: Started UDP transport receiver tasks [INFO] [stdout] test transport::udp::tests::test_separate_rtcp_socket_creation ... ok [INFO] [stdout] 2025-10-02T05:35:26.319939Z DEBUG rvoip_rtp_core::transport::udp: Bound RTP socket to 127.0.0.1:46573 [INFO] [stdout] 2025-10-02T05:35:26.319979Z DEBUG rvoip_rtp_core::transport::udp: Bound RTCP socket to 127.0.0.1:45487 [INFO] [stdout] 2025-10-02T05:35:26.320039Z  INFO rvoip_rtp_core::transport::udp: Started UDP transport receiver tasks [INFO] [stdout] test transport::udp::tests::test_separate_socket_bind_conflicts ... ok [INFO] [stdout] 2025-10-02T05:35:26.321481Z DEBUG rvoip_rtp_core::transport::udp: Bound RTP socket to 127.0.0.1:49254 [INFO] [stdout] 2025-10-02T05:35:26.321536Z DEBUG rvoip_rtp_core::transport::udp: Bound RTCP socket to 127.0.0.1:43900 [INFO] [stdout] 2025-10-02T05:35:26.321605Z  INFO rvoip_rtp_core::transport::udp: Started UDP transport receiver tasks [INFO] [stdout] test transport::udp::tests::test_udp_transport_creation ... ok [INFO] [stdout] 2025-10-02T05:35:26.322066Z DEBUG rvoip_rtp_core::transport::udp: Bound RTP socket to 127.0.0.1:60315 [INFO] [stdout] 2025-10-02T05:35:26.322090Z DEBUG rvoip_rtp_core::transport::udp: Using RTCP-MUX - no separate RTCP socket [INFO] [stdout] 2025-10-02T05:35:26.322131Z  INFO rvoip_rtp_core::transport::udp: Started UDP transport receiver tasks [INFO] [stdout] 2025-10-02T05:35:26.322175Z DEBUG rvoip_rtp_core::transport::udp: Bound RTP socket to 127.0.0.1:43490 [INFO] [stdout] 2025-10-02T05:35:26.322184Z DEBUG rvoip_rtp_core::transport::udp: Using RTCP-MUX - no separate RTCP socket [INFO] [stdout] 2025-10-02T05:35:26.322215Z  INFO rvoip_rtp_core::transport::udp: Started UDP transport receiver tasks [INFO] [stdout] 2025-10-02T05:35:26.322236Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:26.322275Z DEBUG rvoip_rtp_core::transport::udp: UDP receive loop started on Ok(127.0.0.1:60315) [INFO] [stdout] 2025-10-02T05:35:26.322300Z DEBUG rvoip_rtp_core::transport::udp: UDP receive loop started on Ok(127.0.0.1:43490) [INFO] [stdout] 2025-10-02T05:35:26.322362Z DEBUG rvoip_rtp_core::transport::udp: UDP send_to sent 24 bytes to 127.0.0.1:43490 [INFO] [stdout] 2025-10-02T05:35:26.322412Z  INFO rvoip_rtp_core::transport::udp: 🔵 UDP recv_from returned 24 bytes from 127.0.0.1:60315 [INFO] [stdout] 2025-10-02T05:35:26.322421Z DEBUG rvoip_rtp_core::transport::udp: Identified as RTP packet: version=2, PT=96 [INFO] [stdout] 2025-10-02T05:35:26.322428Z DEBUG rvoip_rtp_core::packet::rtp: Parsing RTP packet from 24 bytes [INFO] [stdout] 2025-10-02T05:35:26.322435Z DEBUG rvoip_rtp_core::packet::header: Starting RTP header parse_without_consuming with 24 bytes [INFO] [stdout] 2025-10-02T05:35:26.322441Z DEBUG rvoip_rtp_core::packet::header: First byte: 0x80 [INFO] [stdout] 2025-10-02T05:35:26.322447Z DEBUG rvoip_rtp_core::packet::header: Version: 2 [INFO] [stdout] 2025-10-02T05:35:26.322453Z DEBUG rvoip_rtp_core::packet::header: Flags: padding=false, extension=false, cc=0 [INFO] [stdout] 2025-10-02T05:35:26.322464Z DEBUG rvoip_rtp_core::packet::header: Second byte: 0x60 [INFO] [stdout] 2025-10-02T05:35:26.322470Z DEBUG rvoip_rtp_core::packet::header: Marker: false, payload_type: 96 [INFO] [stdout] 2025-10-02T05:35:26.322476Z DEBUG rvoip_rtp_core::packet::header: Sequence number: 1000 [INFO] [stdout] 2025-10-02T05:35:26.322481Z DEBUG rvoip_rtp_core::packet::header: Timestamp: 12345 [INFO] [stdout] 2025-10-02T05:35:26.322486Z DEBUG rvoip_rtp_core::packet::header: SSRC: 2882400001 [INFO] [stdout] 2025-10-02T05:35:26.322492Z DEBUG rvoip_rtp_core::packet::header: Parsing CSRC list with 0 entries [INFO] [stdout] 2025-10-02T05:35:26.322497Z DEBUG rvoip_rtp_core::packet::header: No extension header [INFO] [stdout] 2025-10-02T05:35:26.322503Z DEBUG rvoip_rtp_core::packet::header: RTP header parsing completed successfully, consumed 12 bytes [INFO] [stdout] 2025-10-02T05:35:26.322509Z DEBUG rvoip_rtp_core::packet::rtp: Parsed header of size 12 [INFO] [stdout] 2025-10-02T05:35:26.322516Z DEBUG rvoip_rtp_core::packet::rtp: Extracted payload of size 12 [INFO] [stdout] 2025-10-02T05:35:26.322525Z DEBUG rvoip_rtp_core::transport::udp: Transport received packet with SSRC=abcdef01, seq=1000, ts=12345 [INFO] [stdout] 2025-10-02T05:35:26.322533Z DEBUG rvoip_rtp_core::transport::udp: SSRC demultiplexing: Forwarding packet with SSRC=abcdef01, seq=1000, payload size=12 bytes [INFO] [stdout] test transport::udp::tests::test_udp_transport_event_subscription ... ok [INFO] [stdout] 2025-10-02T05:35:26.433021Z DEBUG rvoip_rtp_core::transport::udp: Bound RTP socket to 127.0.0.1:59333 [INFO] [stdout] 2025-10-02T05:35:26.433060Z DEBUG rvoip_rtp_core::transport::udp: Using RTCP-MUX - no separate RTCP socket [INFO] [stdout] 2025-10-02T05:35:26.433117Z  INFO rvoip_rtp_core::transport::udp: Started UDP transport receiver tasks [INFO] [stdout] 2025-10-02T05:35:26.433156Z DEBUG rvoip_rtp_core::transport::udp: Bound RTP socket to 127.0.0.1:49325 [INFO] [stdout] 2025-10-02T05:35:26.433163Z DEBUG rvoip_rtp_core::transport::udp: Using RTCP-MUX - no separate RTCP socket [INFO] [stdout] 2025-10-02T05:35:26.433189Z  INFO rvoip_rtp_core::transport::udp: Started UDP transport receiver tasks [INFO] [stdout] 2025-10-02T05:35:26.433204Z DEBUG rvoip_rtp_core::packet::header: Serializing first byte: 0x80 (V=2, P=false, X=false, CC=0) [INFO] [stdout] 2025-10-02T05:35:26.433241Z DEBUG rvoip_rtp_core::transport::udp: UDP receive loop started on Ok(127.0.0.1:59333) [INFO] [stdout] 2025-10-02T05:35:26.433257Z DEBUG rvoip_rtp_core::transport::udp: UDP receive loop started on Ok(127.0.0.1:49325) [INFO] [stdout] 2025-10-02T05:35:26.433318Z DEBUG rvoip_rtp_core::transport::udp: UDP send_to sent 24 bytes to 127.0.0.1:49325 [INFO] [stdout] test transport::udp::tests::test_udp_transport_packet_send ... ok [INFO] [stdout] 2025-10-02T05:35:26.436027Z DEBUG rvoip_rtp_core::transport::udp: Bound RTP socket to 127.0.0.1:38989 [INFO] [stdout] 2025-10-02T05:35:26.436080Z DEBUG rvoip_rtp_core::transport::udp: Using RTCP-MUX - no separate RTCP socket [INFO] [stdout] 2025-10-02T05:35:26.436139Z  INFO rvoip_rtp_core::transport::udp: Started UDP transport receiver tasks [INFO] [stdout] test transport::udp::tests::test_udp_transport_with_rtcp_mux ... ok [INFO] [stdout] test transport::validation::tests::test_platform_detection ... ok [INFO] [stdout] test transport::validation::tests::test_socket_strategy ... ok [INFO] [stdout] test transport::validation::tests::test_socket_validation ... ok [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_certificate_validation has been running for over 60 seconds [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_pke_ca_generation has been running for over 60 seconds [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_pke_certificate_generation has been running for over 60 seconds [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_pke_certificate_signing has been running for over 60 seconds [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_pke_init has been running for over 60 seconds [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_pke_unified_security_integration has been running for over 60 seconds [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_pke_vs_psk_mode has been running for over 60 seconds [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_certificate_validation ... ok [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_pke_vs_psk_mode ... ok [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_pke_certificate_generation ... ok [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_pke_unified_security_integration ... ok [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_pke_init ... ok [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_pke_certificate_signing ... ok [INFO] [stdout] test security::mikey::tests::mikey_tests::test_mikey_pke_ca_generation ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 208 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 196.62s [INFO] [stdout] [INFO] [stderr] Doc-tests rvoip_rtp_core [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" "5a602421bae81c7f6600ef7679ccfc4768c41aede829c242a81eb1925cc40d72", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5a602421bae81c7f6600ef7679ccfc4768c41aede829c242a81eb1925cc40d72", kill_on_drop: false }` [INFO] [stdout] 5a602421bae81c7f6600ef7679ccfc4768c41aede829c242a81eb1925cc40d72