[12896:12396:0120/132250.062:VERBOSE1:webrtc_event_log_manager.cc(96)] WebRTC remote-bound event logging enabled. [12896:2780:0120/132250.164:WARNING:external_pref_loader.cc(332)] You are using an old-style extension deployment method (external_extensions.json), which will soon be deprecated. (see http://developer.chrome.com/extensions/external_extensions.html) [4228:10912:0120/132250.475:INFO:cpu_info.cc(53)] Available number of cores: 8 [12084:9948:0120/132250.594:INFO:cpu_info.cc(53)] Available number of cores: 8 [2400:12748:0120/132251.241:INFO:cpu_info.cc(53)] Available number of cores: 8 [12712:8120:0120/132252.519:INFO:cpu_info.cc(53)] Available number of cores: 8 [12896:12396:0120/132254.122:INFO:CONSOLE(0)] "[DOM] Input elements should have autocomplete attributes (suggested: "new-password"): (More info: https://goo.gl/9p2vKq) %o", source: https://HIDDENURL.com/ (0) [12896:12784:0120/132256.259:ERROR:chrome_browser_main_extra_parts_metrics.cc(227)] START: ReportBluetoothAvailability(). If you don't see the END: message, this is crbug.com/1216328. [12896:12396:0120/132256.261:ERROR:device_event_log_impl.cc(214)] [13:22:56.261] USB: usb_device_handle_win.cc:1050 Failed to read descriptor from node connection: J�rjestelm��n liitetty laite ei toimi. (0x1F) [12896:12784:0120/132256.289:ERROR:chrome_browser_main_extra_parts_metrics.cc(230)] END: ReportBluetoothAvailability() [12896:12784:0120/132256.294:ERROR:chrome_browser_main_extra_parts_metrics.cc(235)] START: GetDefaultBrowser(). If you don't see the END: message, this is crbug.com/1216328. [12896:12396:0120/132256.295:ERROR:device_event_log_impl.cc(214)] [13:22:56.296] USB: usb_device_handle_win.cc:1050 Failed to read descriptor from node connection: J�rjestelm��n liitetty laite ei toimi. (0x1F) [12896:12784:0120/132256.300:ERROR:chrome_browser_main_extra_parts_metrics.cc(239)] END: GetDefaultBrowser() [2400:12748:0120/132257.258:VERBOSE1:webrtc_logging.cc(32)] UMCI::RequestUserMedia({request_id=0}, {audio constraints=}, {video constraints=}) [2400:12748:0120/132257.258:VERBOSE1:webrtc_logging.cc(32)] UMP::ProcessRequest({request_id=0}, {audio=1}, {video=0}) [2400:12748:0120/132257.258:VERBOSE1:webrtc_logging.cc(32)] UMP::SetupAudioInput({request_id=0}, {constraints=}) [2400:12748:0120/132257.258:VERBOSE1:webrtc_logging.cc(32)] UMP::SetupAudioInput({request_id=0}) => (Requesting device capabilities) [2400:12748:0120/132257.728:VERBOSE1:webrtc_logging.cc(32)] UMP::SelectAudioSettings({request_id=0}) [2400:12748:0120/132257.728:VERBOSE1:webrtc_logging.cc(32)] UMP::GenerateStreamForCurrentRequestInfo({request_id=0}, {audio.device_id=default}, {video.device_id=}) [2400:12748:0120/132257.837:VERBOSE1:webrtc_logging.cc(32)] UMP::OnStreamGenerated({request_id=0}, {label=YMaJsgGoIqgRHAIsdbhdxwcgIVpgPuVgjSXh}, {device=[id: default, name: Oletus - Headset Microphone (2–Jabra EVOLVE LINK MS) (0b0e:0305)]}) [2400:12748:0120/132257.837:VERBOSE1:webrtc_logging.cc(32)] UMP::StartTracks({request_id=0}, {label=YMaJsgGoIqgRHAIsdbhdxwcgIVpgPuVgjSXh}) [2400:12748:0120/132257.837:VERBOSE1:webrtc_logging.cc(32)] UMP::CreateAudioTracks({render_to_associated_sink=0}) [2400:12748:0120/132257.837:VERBOSE1:webrtc_logging.cc(32)] UMP::InitializeAudioSourceObject({session_id=9941ADFCB4B40AAB5271678FFCF8B741}) [2400:12748:0120/132257.837:VERBOSE1:webrtc_logging.cc(32)] MSS::MediaStreamSource({id=default}, {type=Audio}, {name=Oletus - Headset Microphone (2–Jabra EVOLVE LINK MS) (0b0e:0305)}, {remote=0}, {ready_state=Live}) [2400:12748:0120/132257.837:VERBOSE1:webrtc_logging.cc(32)] MSS::SetGroupId({group_id=bacb133d7f60d8a66aa9017346365c48257baf677df119dcf8fde9f9fcbab8f4}) [2400:12748:0120/132257.837:VERBOSE1:webrtc_logging.cc(32)] UMP::CreateAudioSource => (audiprocessing is required) [2400:12748:0120/132257.837:VERBOSE1:webrtc_logging.cc(32)] MSAS::MediaStreamAudioSource({is_local_source=local}, {disable_local_echo=true}) [this=0x7C260090B080] [2400:12748:0120/132257.837:VERBOSE1:webrtc_logging.cc(32)] PLAS::ProcessedLocalAudioSource({session_id=9941ADFCB4B40AAB5271678FFCF8B741}) [2400:12748:0120/132257.837:VERBOSE1:webrtc_logging.cc(32)] UMP::StartAudioTrack({track=[id: b6ad052b-9ca1-4ae2-9a77-1db3a2fd17ee, enabled: 1, muted: 0]}, {is_pending=1}) [2400:12748:0120/132257.837:VERBOSE1:webrtc_logging.cc(32)] UMP::StartAudioTrack(source: {session_id=9941ADFCB4B40AAB5271678FFCF8B741}, {is_local_source=1}, {device=[id: default, group_id: bacb133d7f60d8a66aa9017346365c48257baf677df119dcf8fde9f9fcbab8f4, name: Oletus - Headset Microphone (2–Jabra EVOLVE LINK MS) (0b0e:0305)]}) [2400:12748:0120/132257.837:VERBOSE1:webrtc_logging.cc(32)] MSAS::ConnectToTrack(track=[id: b6ad052b-9ca1-4ae2-9a77-1db3a2fd17ee, unique_id: 1, enabled: true, muted=false]) [this=0x7C260090B080] [2400:12748:0120/132257.838:VERBOSE1:webrtc_logging.cc(32)] PLAS::EnsureSourceIsStarted({session_id=9941ADFCB4B40AAB5271678FFCF8B741}, {channel_layout=2}, {sample_rate=48000}, {buffer_size=480}, {effects=0}) [2400:12748:0120/132257.838:VERBOSE1:webrtc_logging.cc(32)] PLAS::EnsureSourceIsStarted() => (audio_processing_properties=[aec: aec3, disable_hw_ns: false, goog_audio_mirroring: false, goog_auto_gain_control: true, goog_experimental_echo_cancellation: true, goog_noise_suppression: true, goog_experimental_noise_suppression: true, goog_highpass_filter: true, goog_experimental_agc: true, hybrid_agc: trueanalog_agc_clipping_control: false]) [session_id=9941ADFCB4B40AAB5271678FFCF8B741] [2400:12748:0120/132257.838:VERBOSE1:webrtc_logging.cc(32)] WRADI::WebRtcAudioDeviceImpl({id=13F27BB5004BE349A2E91887CC0BF104}) [2400:12748:0120/132257.838:VERBOSE1:webrtc_logging.cc(32)] Using APM in renderer process. [2400:12748:0120/132257.838:VERBOSE1:webrtc_logging.cc(32)] MSAP::MediaStreamAudioProcessor({use_capture_multi_channel_processing=false}) [this=0x7C2602538A80] [2400:12748:0120/132257.838:VERBOSE1:webrtc_logging.cc(32)] MSAP::InitializeAudioProcessingModule() [this=0x7C2602538A80] [2400:12748:0120/132257.838:INFO:audio_processing_impl.cc(282)] Injected APM submodules: Echo control factory: 1 Echo detector: 0 Capture analyzer: 0 Capture post processor: 0 Render pre processor: 0 [2400:12748:0120/132257.838:INFO:audio_processing_impl.cc(291)] Denormal disabler: supported [2400:12748:0120/132257.838:INFO:clipping_predictor.cc(357)] [agc] Clipping prediction disabled. [2400:12748:0120/132257.838:INFO:agc_manager_direct.cc(69)] [agc] GetMinMicLevel [2400:12748:0120/132257.838:INFO:agc_manager_direct.cc(73)] [agc] Using default min mic level: 12 [2400:12748:0120/132257.838:VERBOSE1:render_delay_buffer.cc(378)] Applying total delay of 5 blocks. [2400:12748:0120/132257.838:VERBOSE1:matched_filter.cc(461)] Filter 0: start: 0 ms, end: 128 ms. [2400:12748:0120/132257.838:VERBOSE1:matched_filter.cc(461)] Filter 1: start: 96 ms, end: 224 ms. [2400:12748:0120/132257.838:VERBOSE1:matched_filter.cc(461)] Filter 2: start: 192 ms, end: 320 ms. [2400:12748:0120/132257.838:VERBOSE1:matched_filter.cc(461)] Filter 3: start: 288 ms, end: 416 ms. [2400:12748:0120/132257.838:VERBOSE1:matched_filter.cc(461)] Filter 4: start: 384 ms, end: 512 ms. [2400:12748:0120/132257.839:INFO:transparent_mode.cc(239)] AEC3 Transparent Mode: Legacy [2400:12748:0120/132257.839:INFO:echo_canceller3.cc(750)] AEC3 created with sample rate: 16000 Hz, num render channels: 1, num capture channels: 1 [2400:12748:0120/132257.839:VERBOSE1:webrtc_logging.cc(32)] MSAP::InitializeCaptureFifo({input_format=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 48000, frames_per_buffer: 480, effects: 0, mic_positions: ]}) [this=0x7C2602538A80] [2400:12748:0120/132257.839:VERBOSE1:webrtc_logging.cc(32)] MSAP::InitializeCaptureFifo => (output_format=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 48000, frames_per_buffer: 480, effects: 0, mic_positions: ]) [this=0x7C2602538A80] [2400:12748:0120/132257.839:VERBOSE1:webrtc_logging.cc(32)] MSAP::InitializeCaptureFifo => (FIFO: processing_frames=480, output_channels=1) [this=0x7C2602538A80] [2400:12748:0120/132257.839:VERBOSE1:webrtc_logging.cc(32)] MSAS::SetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 48000, frames_per_buffer: 480, effects: 0, mic_positions: ]}, {old_params=[format: PCM_LINEAR, channel_layout: 0, channels: 0, sample_rate: 0, frames_per_buffer: 0, effects: 0, mic_positions: ]}) [this=0x7C260090B080] [2400:12748:0120/132257.839:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnSetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 48000, frames_per_buffer: 480, effects: 0, mic_positions: ]}) [this=0x7C260090B1B8] [2400:12748:0120/132257.839:VERBOSE1:webrtc_logging.cc(32)] PLAS::EnsureSourceIsStarted() => (WebRTC audio source starts: input_parameters=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 48000, frames_per_buffer: 480, effects: 0, mic_positions: ], output_parameters=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 48000, frames_per_buffer: 480, effects: 0, mic_positions: ]) [session_id=9941ADFCB4B40AAB5271678FFCF8B741] [2400:12748:0120/132257.839:VERBOSE1:webrtc_logging.cc(32)] WRADI::AddAudioCapturer() [id=13F27BB5004BE349A2E91887CC0BF104] [2400:12748:0120/132257.839:VERBOSE1:webrtc_logging.cc(32)] PLAS::SetOutputDeviceForAec({device_id=}) [session_id=9941ADFCB4B40AAB5271678FFCF8B741] [2400:12748:0120/132257.839:VERBOSE1:webrtc_logging.cc(32)] MSAS::CreateMediaStreamAudioTrack({id=b6ad052b-9ca1-4ae2-9a77-1db3a2fd17ee}, {is_local_source=local}) [this=0x7C260090B080] [2400:12748:0120/132257.839:VERBOSE1:webrtc_logging.cc(32)] MSAT::MediaStreamAudioTrack({is_local_track=true}) [this=0x7C26041790C0] [2400:12748:0120/132257.839:VERBOSE1:webrtc_logging.cc(32)] MSAT::SetEnabled({enabled=true}) [this=0x7C26041790C0] [2400:12748:0120/132257.839:VERBOSE1:webrtc_logging.cc(32)] MSAT::Start() [this=0x7C26041790C0] [2400:12748:0120/132257.839:VERBOSE1:webrtc_logging.cc(32)] MSAD::AddConsumer => (number of consumer: active=0, pending=1) [this=0x7C260090B1B8] [2400:12748:0120/132257.839:VERBOSE1:webrtc_logging.cc(32)] MSAS::ConnectToTrack => (added new MediaStreamAudioTrack as consumer, total number of consumers=1) [this=0x7C260090B080] [2400:12748:0120/132257.839:VERBOSE1:webrtc_logging.cc(32)] MSS::SetAudioProcessingProperties({echo_cancellation_mode=browser}, {auto_gain_control=1}, {noise_supression=1}) [2400:12748:0120/132257.839:VERBOSE1:webrtc_logging.cc(32)] UMP::UMP::CreateVideoTracks({request_id=0}) [2400:1192:0120/132258.016:VERBOSE1:webrtc_logging.cc(32)] PLAS::OnCaptureStarted() [session_id=9941ADFCB4B40AAB5271678FFCF8B741] [2400:12748:0120/132258.017:VERBOSE1:webrtc_logging.cc(32)] UMP::OnTrackStarted({session_id=9941ADFCB4B40AAB5271678FFCF8B741}, {result=OK}) [2400:12748:0120/132258.018:VERBOSE1:webrtc_logging.cc(32)] UMP::UMP::OnCreateNativeTracksCompleted({request_id = 0}, {label=YMaJsgGoIqgRHAIsdbhdxwcgIVpgPuVgjSXh}) [2400:12748:0120/132258.018:VERBOSE1:webrtc_logging.cc(32)] UMP::GetUserMediaRequestSucceeded({request_id=0}) [2400:12748:0120/132258.018:VERBOSE1:webrtc_logging.cc(32)] UMP::DelayedGetUserMediaRequestSucceeded({request_id=0}, {result=OK}) [2400:12748:0120/132258.018:VERBOSE1:webrtc_logging.cc(32)] MST::MediaStreamTrack() [kind: audio, id: b6ad052b-9ca1-4ae2-9a77-1db3a2fd17ee, label: Oletus - Headset Microphone (2–Jabra EVOLVE LINK MS) (0b0e:0305), enabled: true, muted: false, readyState: live, remote=false] [2400:12748:0120/132258.019:VERBOSE1:webrtc_logging.cc(32)] MST::stopTrack() [kind: audio, id: b6ad052b-9ca1-4ae2-9a77-1db3a2fd17ee, label: Oletus - Headset Microphone (2–Jabra EVOLVE LINK MS) (0b0e:0305), enabled: true, muted: false, readyState: live, remote=false] [2400:12748:0120/132258.020:VERBOSE1:webrtc_logging.cc(32)] MST::setReadyState({ready_state=ended}) [kind: audio, id: b6ad052b-9ca1-4ae2-9a77-1db3a2fd17ee, label: Oletus - Headset Microphone (2–Jabra EVOLVE LINK MS) (0b0e:0305), enabled: true, muted: false, readyState: ended, remote=false] [2400:12748:0120/132258.020:VERBOSE1:webrtc_logging.cc(32)] MSAT::StopAndNotify() [this=0x7C26041790C0] [2400:12748:0120/132258.020:VERBOSE1:webrtc_logging.cc(32)] MSAD::RemoveConsumer => (number of consumers: active=0, pending=0) [this=0x7C260090B1B8] [2400:12748:0120/132258.020:VERBOSE1:webrtc_logging.cc(32)] MSAS::StopAudioDeliveryTo => (removed MediaStreamAudioTrack as consumer, total number of consumers=0) [this=0x7C260090B080] [2400:12748:0120/132258.020:VERBOSE1:webrtc_logging.cc(32)] MSAS::StopAudioDeliveryTo => (last track removed, stopping source) [this=0x7C260090B080] [2400:12748:0120/132258.020:VERBOSE1:webrtc_logging.cc(32)] MSAS::DoStopSource() [this=0x7C260090B080] [2400:12748:0120/132258.020:VERBOSE1:webrtc_logging.cc(32)] WRADI::RemoveAudioCapturer() [id=13F27BB5004BE349A2E91887CC0BF104] [2400:12748:0120/132258.021:VERBOSE1:webrtc_logging.cc(32)] UMP::OnLocalSourceStopped({session_id=9941ADFCB4B40AAB5271678FFCF8B741}) [2400:12748:0120/132258.021:VERBOSE1:webrtc_logging.cc(32)] UMP::RemoveLocalSource({id=default}, {name=Oletus - Headset Microphone (2–Jabra EVOLVE LINK MS) (0b0e:0305)}, {group_id=bacb133d7f60d8a66aa9017346365c48257baf677df119dcf8fde9f9fcbab8f4}) [2400:12748:0120/132258.021:VERBOSE1:webrtc_logging.cc(32)] MSS::SetReadyState({id=default}, {ready_state=Ended}) [10800:9596:0120/132258.043:WARNING:input_sync_writer.cc(366)] AISW: No room in socket buffer.: Putki suljetaan. (0xE8) [2400:1940:0120/132310.675:INFO:webrtc_voice_engine.cc(321)] WebRtcVoiceEngine::WebRtcVoiceEngine [2400:1940:0120/132310.676:INFO:field_trial_parser.cc(90)] No field with key: 'EnabledLaunch' (found in trial: "EnabledLaunch") [2400:1940:0120/132310.676:INFO:field_trial_parser.cc(97)] Valid keys are: Enabled, [2400:4836:0120/132310.676:INFO:webrtc_voice_engine.cc(343)] WebRtcVoiceEngine::Init [2400:4836:0120/132310.676:VERBOSE1:webrtc_voice_engine.cc(351)] Supported send codecs in order of preference: [2400:4836:0120/132310.676:VERBOSE1:webrtc_voice_engine.cc(354)] opus/48000/2 { minptime=10 useinbandfec=1 } (111) [2400:4836:0120/132310.676:VERBOSE1:webrtc_voice_engine.cc(354)] red/48000/2 { =111/111 } (63) [2400:4836:0120/132310.676:VERBOSE1:webrtc_voice_engine.cc(354)] ISAC/16000/1 (103) [2400:4836:0120/132310.676:VERBOSE1:webrtc_voice_engine.cc(354)] ISAC/32000/1 (104) [2400:4836:0120/132310.676:VERBOSE1:webrtc_voice_engine.cc(354)] G722/8000/1 (9) [2400:4836:0120/132310.676:VERBOSE1:webrtc_voice_engine.cc(354)] PCMU/8000/1 (0) [2400:4836:0120/132310.676:VERBOSE1:webrtc_voice_engine.cc(354)] PCMA/8000/1 (8) [2400:4836:0120/132310.676:VERBOSE1:webrtc_voice_engine.cc(354)] CN/32000/1 (106) [2400:4836:0120/132310.676:VERBOSE1:webrtc_voice_engine.cc(354)] CN/16000/1 (105) [2400:4836:0120/132310.676:VERBOSE1:webrtc_voice_engine.cc(354)] CN/8000/1 (13) [2400:4836:0120/132310.676:VERBOSE1:webrtc_voice_engine.cc(354)] telephone-event/48000/1 (110) [2400:4836:0120/132310.676:VERBOSE1:webrtc_voice_engine.cc(354)] telephone-event/32000/1 (112) [2400:4836:0120/132310.676:VERBOSE1:webrtc_voice_engine.cc(354)] telephone-event/16000/1 (113) [2400:4836:0120/132310.676:VERBOSE1:webrtc_voice_engine.cc(354)] telephone-event/8000/1 (126) [2400:4836:0120/132310.676:VERBOSE1:webrtc_voice_engine.cc(357)] Supported recv codecs in order of preference: [2400:4836:0120/132310.677:VERBOSE1:webrtc_voice_engine.cc(360)] opus/48000/2 { minptime=10 useinbandfec=1 } (111) [2400:4836:0120/132310.677:VERBOSE1:webrtc_voice_engine.cc(360)] red/48000/2 { =111/111 } (63) [2400:4836:0120/132310.677:VERBOSE1:webrtc_voice_engine.cc(360)] ISAC/16000/1 (103) [2400:4836:0120/132310.677:VERBOSE1:webrtc_voice_engine.cc(360)] ISAC/32000/1 (104) [2400:4836:0120/132310.677:VERBOSE1:webrtc_voice_engine.cc(360)] G722/8000/1 (9) [2400:4836:0120/132310.677:VERBOSE1:webrtc_voice_engine.cc(360)] PCMU/8000/1 (0) [2400:4836:0120/132310.677:VERBOSE1:webrtc_voice_engine.cc(360)] PCMA/8000/1 (8) [2400:4836:0120/132310.677:VERBOSE1:webrtc_voice_engine.cc(360)] CN/32000/1 (106) [2400:4836:0120/132310.677:VERBOSE1:webrtc_voice_engine.cc(360)] CN/16000/1 (105) [2400:4836:0120/132310.677:VERBOSE1:webrtc_voice_engine.cc(360)] CN/8000/1 (13) [2400:4836:0120/132310.677:VERBOSE1:webrtc_voice_engine.cc(360)] telephone-event/48000/1 (110) [2400:4836:0120/132310.677:VERBOSE1:webrtc_voice_engine.cc(360)] telephone-event/32000/1 (112) [2400:4836:0120/132310.677:VERBOSE1:webrtc_voice_engine.cc(360)] telephone-event/16000/1 (113) [2400:4836:0120/132310.677:VERBOSE1:webrtc_voice_engine.cc(360)] telephone-event/8000/1 (126) [2400:4836:0120/132310.677:VERBOSE1:webrtc_logging.cc(32)] WRADI::RegisterAudioCallback() [id=13F27BB5004BE349A2E91887CC0BF104] [2400:4836:0120/132310.677:INFO:webrtc_voice_engine.cc(439)] WebRtcVoiceEngine::ApplyOptions: AudioOptions {aec: 1, agc: 1, ns: 1, hf: 1, swap: 0, audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, typing: 1, experimental_agc: 0, experimental_ns: 0, residual_echo_detector: 1, } [2400:4836:0120/132310.678:INFO:webrtc_voice_engine.cc(548)] Stereo swapping enabled? 0 [2400:4836:0120/132310.678:INFO:webrtc_voice_engine.cc(553)] NetEq capacity is 200 [2400:4836:0120/132310.678:INFO:webrtc_voice_engine.cc(559)] NetEq fast mode? 0 [2400:4836:0120/132310.678:INFO:webrtc_voice_engine.cc(565)] NetEq minimum delay is 0 [2400:4836:0120/132310.678:INFO:webrtc_voice_engine.cc(571)] NetEq handle reordered packets? 0 [2400:4836:0120/132310.678:INFO:webrtc_voice_engine.cc(579)] No audio processing module present. No software-provided effects (AEC, NS, AGC, ...) are activated [2400:4836:0120/132310.678:INFO:rtc_event_log_impl.cc(43)] Creating legacy encoder for RTC event log. [2400:4836:0120/132310.678:INFO:peer_connection_factory.cc(339)] Using default network controller factory [2400:4836:0120/132310.681:INFO:bitrate_prober.cc(72)] Bandwidth probing enabled, set to inactive [2400:4836:0120/132310.681:INFO:aimd_rate_control.cc(112)] Using aimd rate control with back off factor 0.85 [2400:4836:0120/132310.681:INFO:remote_bitrate_estimator_single_stream.cc(71)] RemoteBitrateEstimatorSingleStream: Instantiating. [2400:1672:0120/132310.681:VERBOSE1:pacing_controller.cc(230)] bwe:pacer_updated pacing_kbps=300 padding_budget_kbps=0 [2400:4836:0120/132310.681:INFO:remote_estimator_proxy.cc(47)] Maximum interval between transport feedback RTCP messages (ms): 250 [2400:1940:0120/132310.682:VERBOSE1:webrtc_session_description_factory.cc(184)] DTLS-SRTP enabled; sending DTLS identity request (key type: 1). [2400:5796:0120/132310.683:INFO:openssl_key_pair.cc(38)] Making key pair [2400:5796:0120/132310.683:INFO:openssl_key_pair.cc(91)] Returning key pair [2400:5796:0120/132310.683:INFO:boringssl_certificate.cc(187)] Making certificate for WebRTC [2400:5796:0120/132310.683:INFO:boringssl_certificate.cc(243)] Returning certificate [2400:1940:0120/132310.684:VERBOSE1:webrtc_session_description_factory.cc(490)] Setting new certificate. [2400:12748:0120/132310.687:VERBOSE1:webrtc_logging.cc(32)] UMCI::RequestUserMedia({request_id=1}, {audio constraints={echoCancellation: {exact: false}, deviceId: {ideal: ["9970d29b6dae05e3ed112ced3a6b9b6f444c2a5c26d20a381ace669087b8bd68"]}, autoGainControl: {exact: false}, noiseSuppression: {exact: false}}}, {video constraints=}) [2400:12748:0120/132310.687:VERBOSE1:webrtc_logging.cc(32)] UMP::ProcessRequest({request_id=1}, {audio=1}, {video=0}) [2400:12748:0120/132310.687:VERBOSE1:webrtc_logging.cc(32)] UMP::SetupAudioInput({request_id=1}, {constraints={echoCancellation: {exact: false}, deviceId: {ideal: ["9970d29b6dae05e3ed112ced3a6b9b6f444c2a5c26d20a381ace669087b8bd68"]}, autoGainControl: {exact: false}, noiseSuppression: {exact: false}}}) [2400:12748:0120/132310.687:VERBOSE1:webrtc_logging.cc(32)] UMP::SetupAudioInput({request_id=1}) => (Requesting device capabilities) [2400:12748:0120/132310.731:VERBOSE1:webrtc_logging.cc(32)] UMP::SelectAudioSettings({request_id=1}) [2400:12748:0120/132310.731:VERBOSE1:webrtc_logging.cc(32)] UMP::GenerateStreamForCurrentRequestInfo({request_id=1}, {audio.device_id=9970d29b6dae05e3ed112ced3a6b9b6f444c2a5c26d20a381ace669087b8bd68}, {video.device_id=}) [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] UMP::OnStreamGenerated({request_id=1}, {label=9wWXUKQlzdGOGDt06UjyOU7xSEhKIkxAKJb1}, {device=[id: 9970d29b6dae05e3ed112ced3a6b9b6f444c2a5c26d20a381ace669087b8bd68, name: Headset Microphone (2–Jabra EVOLVE LINK MS) (0b0e:0305)]}) [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] UMP::StartTracks({request_id=1}, {label=9wWXUKQlzdGOGDt06UjyOU7xSEhKIkxAKJb1}) [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] UMP::CreateAudioTracks({render_to_associated_sink=0}) [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] UMP::InitializeAudioSourceObject({session_id=EC451D84FD9C097639622DA8A9411FB6}) [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] MSS::MediaStreamSource({id=9970d29b6dae05e3ed112ced3a6b9b6f444c2a5c26d20a381ace669087b8bd68}, {type=Audio}, {name=Headset Microphone (2–Jabra EVOLVE LINK MS) (0b0e:0305)}, {remote=0}, {ready_state=Live}) [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] MSS::SetGroupId({group_id=bacb133d7f60d8a66aa9017346365c48257baf677df119dcf8fde9f9fcbab8f4}) [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] UMP::CreateAudioSource => (no audiprocessing is used) [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] MSAS::MediaStreamAudioSource({is_local_source=local}, {disable_local_echo=true}) [this=0x7C2602535E00] [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] MSAS::SetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 48000, frames_per_buffer: 480, effects: 0, mic_positions: ]}, {old_params=[format: PCM_LINEAR, channel_layout: 0, channels: 0, sample_rate: 0, frames_per_buffer: 0, effects: 0, mic_positions: ]}) [this=0x7C2602535E00] [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnSetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 48000, frames_per_buffer: 480, effects: 0, mic_positions: ]}) [this=0x7C2602535F38] [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] UMP::StartAudioTrack({track=[id: 5fa7a763-1e35-4963-af00-6a7b46935b44, enabled: 1, muted: 0]}, {is_pending=1}) [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] UMP::StartAudioTrack(source: {session_id=EC451D84FD9C097639622DA8A9411FB6}, {is_local_source=1}, {device=[id: 9970d29b6dae05e3ed112ced3a6b9b6f444c2a5c26d20a381ace669087b8bd68, group_id: bacb133d7f60d8a66aa9017346365c48257baf677df119dcf8fde9f9fcbab8f4, name: Headset Microphone (2–Jabra EVOLVE LINK MS) (0b0e:0305)]}) [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] MSAS::ConnectToTrack(track=[id: 5fa7a763-1e35-4963-af00-6a7b46935b44, unique_id: 2, enabled: true, muted=false]) [this=0x7C2602535E00] [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] LocalMediaStreamAudioSource::EnsureSourceIsStarted. channel_layout=2, sample_rate=48000, buffer_size=480, session_id=EC451D84FD9C097639622DA8A9411FB6, effects=0. [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] MSAS::CreateMediaStreamAudioTrack({id=5fa7a763-1e35-4963-af00-6a7b46935b44}, {is_local_source=local}) [this=0x7C2602535E00] [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] MSAT::MediaStreamAudioTrack({is_local_track=true}) [this=0x7C2602D54780] [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] MSAT::SetEnabled({enabled=true}) [this=0x7C2602D54780] [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] MSAT::Start() [this=0x7C2602D54780] [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] MSAD::AddConsumer => (number of consumer: active=0, pending=1) [this=0x7C2602535F38] [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] MSAS::ConnectToTrack => (added new MediaStreamAudioTrack as consumer, total number of consumers=1) [this=0x7C2602535E00] [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] MSS::SetAudioProcessingProperties({echo_cancellation_mode=disabled}, {auto_gain_control=0}, {noise_supression=0}) [2400:12748:0120/132310.743:VERBOSE1:webrtc_logging.cc(32)] UMP::UMP::CreateVideoTracks({request_id=1}) [2400:12748:0120/132310.767:VERBOSE1:webrtc_logging.cc(32)] UMP::OnTrackStarted({session_id=EC451D84FD9C097639622DA8A9411FB6}, {result=OK}) [2400:12748:0120/132310.768:VERBOSE1:webrtc_logging.cc(32)] UMP::UMP::OnCreateNativeTracksCompleted({request_id = 1}, {label=9wWXUKQlzdGOGDt06UjyOU7xSEhKIkxAKJb1}) [2400:12748:0120/132310.768:VERBOSE1:webrtc_logging.cc(32)] UMP::GetUserMediaRequestSucceeded({request_id=1}) [2400:12748:0120/132310.769:VERBOSE1:webrtc_logging.cc(32)] UMP::DelayedGetUserMediaRequestSucceeded({request_id=1}, {result=OK}) [2400:12748:0120/132310.769:VERBOSE1:webrtc_logging.cc(32)] MST::MediaStreamTrack() [kind: audio, id: 5fa7a763-1e35-4963-af00-6a7b46935b44, label: Headset Microphone (2–Jabra EVOLVE LINK MS) (0b0e:0305), enabled: true, muted: false, readyState: live, remote=false] [2400:12748:0120/132310.770:VERBOSE1:webrtc_logging.cc(32)] WRMSTA::InitializeLocalAudioTrack({id=5fa7a763-1e35-4963-af00-6a7b46935b44}) [2400:12748:0120/132310.770:VERBOSE1:webrtc_logging.cc(32)] WRAS::Adapter::Adapter({label=5fa7a763-1e35-4963-af00-6a7b46935b44}) [2400:12748:0120/132310.770:VERBOSE1:webrtc_logging.cc(32)] WRAS::WebRtcAudioSink({label=5fa7a763-1e35-4963-af00-6a7b46935b44}) [2400:12748:0120/132310.770:VERBOSE1:webrtc_logging.cc(32)] MSAT::AddSink() [this=0x7C2602D54780] [2400:12748:0120/132310.770:VERBOSE1:webrtc_logging.cc(32)] MSAD::AddConsumer => (number of consumer: active=0, pending=1) [this=0x7C2602D54798] [2400:12748:0120/132310.770:VERBOSE1:webrtc_logging.cc(32)] WRAS::OnEnabledChanged([label=5fa7a763-1e35-4963-af00-6a7b46935b44] {enabled=true}) [2400:1940:0120/132310.771:VERBOSE1:webrtc_logging.cc(32)] WRAS::Adapter::set_enabled([label=5fa7a763-1e35-4963-af00-6a7b46935b44] {enable=true}) [2400:1940:0120/132310.771:INFO:rtp_transmission_manager.cc(187)] Adding audio transceiver in response to a call to AddTrack. [2400:1940:0120/132310.771:VERBOSE1:webrtc_logging.cc(32)] WRAS::Adapter::AddSink({label=5fa7a763-1e35-4963-af00-6a7b46935b44}) [2400:1940:0120/132310.771:VERBOSE1:webrtc_logging.cc(32)] WRMSTA::InitializeRemoteAudioTrack([this=00007C2603F510C0]) [2400:1940:0120/132310.771:INFO:remote_audio_source.cc(115)] RAS::SetVolume({volume=0.00}) [2400:12748:0120/132310.771:VERBOSE1:webrtc_logging.cc(32)] MSS::MediaStreamSource({id=55119d03-6d83-4777-9c97-2baa1093e46b}, {type=Audio}, {name=55119d03-6d83-4777-9c97-2baa1093e46b}, {remote=1}, {ready_state=Live}) [2400:12748:0120/132310.772:VERBOSE1:webrtc_logging.cc(32)] MSAS::MediaStreamAudioSource({is_local_source=remote}, {disable_local_echo=false}) [this=0x7C2603DC7000] [2400:12748:0120/132310.772:VERBOSE1:webrtc_logging.cc(32)] PCRAS::PeerConnectionRemoteAudioSource([id=55119d03-6d83-4777-9c97-2baa1093e46b]) [2400:12748:0120/132310.772:VERBOSE1:webrtc_logging.cc(32)] MSAS::ConnectToTrack(track=[id: 55119d03-6d83-4777-9c97-2baa1093e46b, unique_id: 3, enabled: true, muted=false]) [this=0x7C2603DC7000] [2400:12748:0120/132310.772:VERBOSE1:webrtc_logging.cc(32)] PCRAS::EnsureSourceIsStarted([id=55119d03-6d83-4777-9c97-2baa1093e46b]) [2400:12748:0120/132310.772:VERBOSE1:webrtc_logging.cc(32)] MSAT::MediaStreamAudioTrack({is_local_track=false}) [this=0x7C2602CF6B80] [2400:12748:0120/132310.772:VERBOSE1:webrtc_logging.cc(32)] PCRAT::PeerConnectionRemoteAudioTrack({id=55119d03-6d83-4777-9c97-2baa1093e46b}) [2400:12748:0120/132310.772:VERBOSE1:webrtc_logging.cc(32)] PCRAT::SetEnabled([id=55119d03-6d83-4777-9c97-2baa1093e46b] {enabled=true}) [2400:12748:0120/132310.772:VERBOSE1:webrtc_logging.cc(32)] MSAT::SetEnabled({enabled=true}) [this=0x7C2602CF6B80] [2400:12748:0120/132310.772:VERBOSE1:webrtc_logging.cc(32)] MSAT::Start() [this=0x7C2602CF6B80] [2400:12748:0120/132310.772:VERBOSE1:webrtc_logging.cc(32)] MSAD::AddConsumer => (number of consumer: active=0, pending=1) [this=0x7C2603DC7138] [2400:12748:0120/132310.772:VERBOSE1:webrtc_logging.cc(32)] MSAS::ConnectToTrack => (added new MediaStreamAudioTrack as consumer, total number of consumers=1) [this=0x7C2603DC7000] [2400:12748:0120/132310.773:VERBOSE1:webrtc_logging.cc(32)] MST::MediaStreamTrack() [kind: audio, id: 55119d03-6d83-4777-9c97-2baa1093e46b, label: 55119d03-6d83-4777-9c97-2baa1093e46b, enabled: true, muted: false, readyState: live, remote=true] [2400:12748:0120/132310.773:VERBOSE1:webrtc_logging.cc(32)] MSS::SetReadyState({id=55119d03-6d83-4777-9c97-2baa1093e46b}, {ready_state=Muted}) [2400:12748:0120/132310.773:VERBOSE1:webrtc_logging.cc(32)] MST::setReadyState({ready_state=live}) [kind: audio, id: 55119d03-6d83-4777-9c97-2baa1093e46b, label: 55119d03-6d83-4777-9c97-2baa1093e46b, enabled: true, muted: false, readyState: live, remote=true] [2400:12748:0120/132310.773:VERBOSE1:webrtc_logging.cc(32)] MST::SourceChangedState() [kind: audio, id: 55119d03-6d83-4777-9c97-2baa1093e46b, label: 55119d03-6d83-4777-9c97-2baa1093e46b, enabled: true, muted: true, readyState: live, remote=true] [2400:1940:0120/132310.774:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 104 to 127 [2400:1940:0120/132310.774:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 106 to 125 [2400:1940:0120/132310.774:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 110 to 124 [2400:1940:0120/132310.774:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 112 to 123 [2400:1940:0120/132310.774:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 102 to 122 [2400:1940:0120/132310.774:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 103 to 121 [2400:1940:0120/132310.774:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 105 to 120 [2400:1940:0120/132310.774:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 111 to 119 [2400:1940:0120/132310.774:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 113 to 118 [2400:1940:0120/132310.774:WARNING:media_session.cc(936)] RED codec red is missing an associated payload type. [2400:5796:0120/132310.776:INFO:p2p_transport_channel.cc(586)] Set backup connection ping interval to 25000 milliseconds. [2400:5796:0120/132310.776:INFO:p2p_transport_channel.cc(595)] Set ICE receiving timeout to 2500 milliseconds [2400:5796:0120/132310.776:INFO:p2p_transport_channel.cc(602)] Set ping most likely connection to 0 [2400:5796:0120/132310.776:INFO:p2p_transport_channel.cc(609)] Set stable_writable_connection_ping_interval to 2500 [2400:5796:0120/132310.776:INFO:p2p_transport_channel.cc(622)] Set presume writable when fully relayed to 0 [2400:5796:0120/132310.776:INFO:p2p_transport_channel.cc(640)] Set regather_on_failed_networks_interval to 300000 [2400:5796:0120/132310.776:INFO:p2p_transport_channel.cc(647)] Set receiving_switching_delay to 1000 [2400:5796:0120/132310.776:INFO:jsep_transport_controller.cc(1058)] Creating DtlsSrtpTransport. [2400:5796:0120/132310.776:INFO:dtls_srtp_transport.cc(63)] Setting RTCP Transport on 0 transport 0000000000000000 [2400:5796:0120/132310.776:INFO:dtls_srtp_transport.cc(68)] Setting RTP Transport on 0 transport 00007C2603FAE300 [2400:5796:0120/132310.776:INFO:p2p_transport_channel.cc(520)] Set ICE ufrag: KfC0 pwd: QUoS0XKwhbv+8Y0OliGOsYvL on transport 0 [2400:4836:0120/132310.776:VERBOSE1:webrtc_voice_engine.cc(1349)] WebRtcVoiceMediaChannel::WebRtcVoiceMediaChannel [2400:4836:0120/132310.776:INFO:webrtc_voice_engine.cc(1558)] Setting voice channel options: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [2400:4836:0120/132310.776:INFO:webrtc_voice_engine.cc(439)] WebRtcVoiceEngine::ApplyOptions: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [2400:4836:0120/132310.776:INFO:webrtc_voice_engine.cc(553)] NetEq capacity is 200 [2400:4836:0120/132310.776:INFO:webrtc_voice_engine.cc(559)] NetEq fast mode? 0 [2400:4836:0120/132310.776:INFO:webrtc_voice_engine.cc(565)] NetEq minimum delay is 0 [2400:4836:0120/132310.776:INFO:webrtc_voice_engine.cc(571)] NetEq handle reordered packets? 0 [2400:4836:0120/132310.776:INFO:webrtc_voice_engine.cc(579)] No audio processing module present. No software-provided effects (AEC, NS, AGC, ...) are activated [2400:4836:0120/132310.776:INFO:webrtc_voice_engine.cc(1576)] Set voice channel options. Current options: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [2400:4836:0120/132310.777:INFO:channel.cc(137)] Created channel: {mid: 0, media_type: audio} [2400:5796:0120/132310.777:INFO:rtp_demuxer.cc(145)] Added sink = 00007C2603DD9048 for criteria {mid: 0, rsid: , ssrcs: [], payload_types = []} [2400:5796:0120/132310.777:VERBOSE1:webrtc_voice_engine.cc(2345)] OnReadyToSend: Not ready. [2400:4836:0120/132310.777:VERBOSE1:call.cc(1354)] UpdateAggregateNetworkState: aggregate_state remains at down [2400:4836:0120/132310.777:VERBOSE1:rtp_transport_controller_send.cc(370)] SignalNetworkState Down [2400:1940:0120/132310.777:INFO:sdp_offer_answer.cc(2457)] Session: 1807224220773941525 Old state: stable New state: have-local-offer [2400:4836:0120/132310.777:INFO:webrtc_voice_engine.cc(2047)] ResetUnsignaledRecvStream. [2400:4836:0120/132310.777:INFO:channel.cc(827)] Setting local voice description for {mid: 0, media_type: audio} [2400:4836:0120/132310.777:INFO:webrtc_voice_engine.cc(1418)] WebRtcVoiceMediaChannel::SetRecvParameters: {codecs: [AudioCodec[111:opus:48000:0:2], AudioCodec[63:red:48000:0:2], AudioCodec[103:ISAC:16000:0:1], AudioCodec[104:ISAC:32000:0:1], AudioCodec[9:G722:8000:0:1], AudioCodec[0:PCMU:8000:0:1], AudioCodec[8:PCMA:8000:0:1], AudioCodec[106:CN:32000:0:1], AudioCodec[105:CN:16000:0:1], AudioCodec[13:CN:8000:0:1], AudioCodec[110:telephone-event:48000:0:1], AudioCodec[112:telephone-event:32000:0:1], AudioCodec[113:telephone-event:16000:0:1], AudioCodec[126:telephone-event:8000:0:1]], extensions: [{uri: urn:ietf:params:rtp-hdrext:ssrc-audio-level, id: 1}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 2}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 4}]} [2400:4836:0120/132310.777:INFO:webrtc_voice_engine.cc(1586)] Setting receive voice codecs. [2400:4836:0120/132310.777:INFO:webrtc_voice_engine.cc(1914)] AddSendStream: {id:5fa7a763-1e35-4963-af00-6a7b46935b44;ssrcs:[3080516117];ssrc_groups:;cname:WY5Z0khDLJzWrt7u;stream_ids:9wWXUKQlzdGOGDt06UjyOU7xSEhKIkxAKJb1;} [2400:4836:0120/132310.777:INFO:delay_manager.cc(54)] Delay manager config: quantile=0.95 forget_factor=0.983 start_forget_weight=2 resample_interval_ms=500 max_history_ms=2000 use_reorder_optimizer=1 reorder_forget_factor=0.9993 ms_per_loss_percent=20 [2400:4836:0120/132310.778:INFO:decision_logic.cc(70)] NetEq decision logic settings: estimate_dtx_delay=1 time_stretch_cn=1 target_level_window_ms=100 [2400:4836:0120/132310.778:INFO:neteq_impl.cc(161)] NetEq config: sample_rate_hz=16000, enable_post_decode_vad=true, max_packets_in_buffer=200, min_delay_ms=0, enable_fast_accelerate=false, enable_muted_state=false, enable_rtx_handling=false, extra_output_delay_ms=0 [2400:4836:0120/132310.778:VERBOSE1:neteq_impl.cc(2120)] SetSampleRateAndChannels 16000 1 [2400:4836:0120/132310.778:VERBOSE1:neteq_impl.cc(503)] FlushBuffers [2400:4836:0120/132310.778:INFO:audio_coding_module.cc(223)] Created [2400:4836:0120/132310.778:INFO:audio_send_stream.cc(160)] AudioSendStream: 3080516117 [2400:4836:0120/132310.778:INFO:audio_send_stream.cc(228)] AudioSendStream::ConfigureStream: {rtp: {ssrc: 3080516117, extmap-allow-mixed: true, extensions: [], c_name: WY5Z0khDLJzWrt7u}, rtcp_report_interval_ms: 5000, send_transport: (Transport), min_bitrate_bps: -1, max_bitrate_bps: -1, has audio_network_adaptor_config: false, has_dscp: false, send_codec_spec: } [2400:4836:0120/132310.778:WARNING:audio_send_stream.cc(870)] Config is invalid: min_bitrate_bps=-1; max_bitrate_bps=-1; both expected greater or equal to 0 [2400:4836:0120/132310.778:VERBOSE1:call.cc(1354)] UpdateAggregateNetworkState: aggregate_state remains at down [2400:4836:0120/132310.778:VERBOSE1:rtp_transport_controller_send.cc(370)] SignalNetworkState Down [2400:4836:0120/132310.778:INFO:channel.cc(675)] Add send stream ssrc: 3080516117 into {mid: 0, media_type: audio} [2400:4836:0120/132310.778:INFO:channel.cc(818)] Changing voice state, recv=0 send=0 for {mid: 0, media_type: audio} [2400:1940:0120/132310.778:VERBOSE1:peer_connection.cc(2125)] Local and Remote descriptions must be applied to get the SSL Role of the SCTP transport. [2400:4836:0120/132310.778:INFO:webrtc_voice_engine.cc(1558)] Setting voice channel options: AudioOptions {} [2400:4836:0120/132310.778:INFO:webrtc_voice_engine.cc(439)] WebRtcVoiceEngine::ApplyOptions: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [2400:4836:0120/132310.778:INFO:webrtc_voice_engine.cc(553)] NetEq capacity is 200 [2400:4836:0120/132310.778:INFO:webrtc_voice_engine.cc(559)] NetEq fast mode? 0 [2400:4836:0120/132310.778:INFO:webrtc_voice_engine.cc(565)] NetEq minimum delay is 0 [2400:4836:0120/132310.778:INFO:webrtc_voice_engine.cc(571)] NetEq handle reordered packets? 0 [2400:4836:0120/132310.778:INFO:webrtc_voice_engine.cc(579)] No audio processing module present. No software-provided effects (AEC, NS, AGC, ...) are activated [2400:4836:0120/132310.778:INFO:webrtc_voice_engine.cc(1576)] Set voice channel options. Current options: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [2400:5796:0120/132310.778:INFO:basic_port_allocator.cc(388)] Start getting ports with turn_port_prune_policy 0 [2400:5796:0120/132310.778:INFO:basic_port_allocator.cc(874)] Network manager has started [2400:5796:0120/132310.778:INFO:basic_port_allocator.cc(785)] Allocate ports on 1 networks [2400:5796:0120/132310.778:INFO:basic_port_allocator.cc(1381)] Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]: Allocation Phase=Udp [2400:5796:0120/132310.778:INFO:port.cc(178)] Port[2662800::1:0:local:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]]: Port created with network cost 0 [2400:5796:0120/132310.778:INFO:basic_port_allocator.cc(1455)] AllocationSequence: UDPPort will be handling the STUN candidate generation. [2400:5796:0120/132310.778:INFO:basic_port_allocator.cc(897)] Adding allocated port for 0 [2400:5796:0120/132310.778:INFO:basic_port_allocator.cc(919)] Port[2662800:0:1:0:local:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]]: Added port to allocator [2400:5796:0120/132310.779:INFO:basic_port_allocator.cc(936)] Port[2662800:0:1:0:local:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]]: Gathered candidate: Cand[:1674736731:1:udp:2122260223:172.21.0.x:59742:local::0:KfC0:QUoS0XKwhbv+8Y0OliGOsYvL:1:0:0] [2400:5796:0120/132310.779:INFO:basic_port_allocator.cc(969)] Port[2662800:0:1:0:local:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]]: Port ready. [2400:5796:0120/132310.779:VERBOSE1:basic_ice_controller.cc(541)] Sorting 0 available connections [2400:5796:0120/132310.779:INFO:stun_port.cc(423)] Port[2662800:0:1:0:local:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]]: Starting STUN host lookup for stun-eu.connectel.io:19822 [2400:5796:0120/132310.785:VERBOSE1:stun_request.cc(269)] Sent STUN request 1; resend delay = 250 [2400:11224:0120/132310.794:VERBOSE1:webrtc_logging.cc(32)] MSAT::OnSetFormat({params: [format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 48000, frames_per_buffer: 480, effects: 0, mic_positions: ]}) [this=0x7C2602D54780] [2400:11224:0120/132310.794:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnSetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 48000, frames_per_buffer: 480, effects: 0, mic_positions: ]}) [this=0x7C2602D54798] [2400:11224:0120/132310.794:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnData => (number of active consumers=1) [this=0x7C2602535F38] [2400:11224:0120/132310.794:VERBOSE1:webrtc_logging.cc(32)] MSAT::OnData() => (audio track is alive)) [this=0x7C2602D54780] [2400:11224:0120/132310.794:VERBOSE1:webrtc_logging.cc(32)] WRAS::OnSetFormat([label=5fa7a763-1e35-4963-af00-6a7b46935b44] {params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 48000, frames_per_buffer: 480, effects: 0, mic_positions: ]}) [2400:11224:0120/132310.794:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnData => (number of active consumers=1) [this=0x7C2602D54798] [2400:5796:0120/132310.819:INFO:basic_port_allocator.cc(936)] Port[2662800:0:1:0:local:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]]: Gathered candidate: Cand[:2512522639:1:udp:1686052607:194.252.149.x:59742:stun:172.21.0.150:59742:KfC0:QUoS0XKwhbv+8Y0OliGOsYvL:1:0:0] [2400:5796:0120/132310.819:INFO:basic_port_allocator.cc(1080)] Port[2662800:0:1:0:local:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]]: Port completed gathering candidates. [2400:5796:0120/132310.829:INFO:basic_port_allocator.cc(1381)] Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]: Allocation Phase=Relay [2400:5796:0120/132310.829:VERBOSE1:basic_port_allocator.cc(1515)] AllocationSequence: Relay ports disabled, skipping. [2400:5796:0120/132310.881:INFO:basic_port_allocator.cc(1381)] Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]: Allocation Phase=Tcp [2400:5796:0120/132310.881:INFO:port.cc(178)] Port[41edb00::1:0:local:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]]: Port created with network cost 0 [2400:5796:0120/132310.881:INFO:basic_port_allocator.cc(897)] Adding allocated port for 0 [2400:5796:0120/132310.881:INFO:basic_port_allocator.cc(919)] Port[41edb00:0:1:0:local:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]]: Added port to allocator [2400:5796:0120/132310.881:INFO:tcp_port.cc(182)] Port[41edb00:0:1:0:local:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]]: Not listening due to firewall restrictions. [2400:5796:0120/132310.881:INFO:basic_port_allocator.cc(936)] Port[41edb00:0:1:0:local:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]]: Gathered candidate: Cand[:760318123:1:tcp:1518280447:172.21.0.x:9:local::0:KfC0:QUoS0XKwhbv+8Y0OliGOsYvL:1:0:0] [2400:5796:0120/132310.881:INFO:basic_port_allocator.cc(969)] Port[41edb00:0:1:0:local:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]]: Port ready. [2400:5796:0120/132310.881:VERBOSE1:basic_ice_controller.cc(541)] Sorting 0 available connections [2400:5796:0120/132310.881:INFO:basic_port_allocator.cc(1080)] Port[41edb00:0:1:0:local:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]]: Port completed gathering candidates. [2400:5796:0120/132310.881:INFO:basic_port_allocator.cc(1154)] All candidates gathered for 0:1:0 [2400:5796:0120/132310.881:INFO:p2p_transport_channel.cc(1010)] P2PTransportChannel: 0, component 1 gathering complete [2400:12748:0120/132311.235:VERBOSE1:webrtc_sdp.cc(3282)] Ignored line: a=connection:new [2400:1940:0120/132311.235:INFO:sdp_offer_answer.cc(3472)] SetRemoteDescription: Remote media section at i=0 is missing an a=mid line. Filling in the value '0' from the matching local media section. [2400:5796:0120/132311.235:INFO:dtls_srtp_transport.cc(63)] Setting RTCP Transport on 0 transport 0000000000000000 [2400:5796:0120/132311.235:INFO:dtls_srtp_transport.cc(68)] Setting RTP Transport on 0 transport 00007C2603FAE300 [2400:5796:0120/132311.235:INFO:p2p_transport_channel.cc(531)] Received remote ICE parameters: ufrag=21a2c3bd49d08e124ada32201107ca7c, renomination disabled [2400:5796:0120/132311.235:INFO:dtls_transport.cc(368)] DtlsTransport[0|1|__]: DTLS setup complete. [2400:5796:0120/132311.235:VERBOSE1:basic_ice_controller.cc(541)] Sorting 0 available connections [2400:1940:0120/132311.235:INFO:sdp_offer_answer.cc(2457)] Session: 1807224220773941525 Old state: have-local-offer New state: stable [2400:4836:0120/132311.235:INFO:channel.cc(513)] Channel enabled: {mid: 0, media_type: audio} [2400:4836:0120/132311.235:INFO:channel.cc(818)] Changing voice state, recv=1 send=0 for {mid: 0, media_type: audio} [2400:5796:0120/132311.235:INFO:rtp_demuxer.cc(241)] Removed sink = 00007C2603DD9048 bindings [2400:5796:0120/132311.236:INFO:rtp_demuxer.cc(145)] Added sink = 00007C2603DD9048 for criteria {mid: 0, rsid: , ssrcs: [], payload_types = [0, 8, 9, 13, 63, 103, 104, 105, 106, 110, 111, 112, 113, 126, ]} [2400:4836:0120/132311.236:INFO:channel.cc(887)] Setting remote voice description for {mid: 0, media_type: audio} [2400:4836:0120/132311.236:INFO:webrtc_voice_engine.cc(1372)] WebRtcVoiceMediaChannel::SetSendParameters: {codecs: [AudioCodec[0:PCMU:8000:0:1], AudioCodec[8:PCMA:8000:0:1], AudioCodec[126:telephone-event:8000:0:1]], extensions: [], extmap-allow-mixed: false, max_bandwidth_bps: -1, mid: 0, options: AudioOptions {}} [2400:4836:0120/132311.236:INFO:audio_send_stream.cc(228)] AudioSendStream::ConfigureStream: {rtp: {ssrc: 3080516117, extmap-allow-mixed: true, extensions: [], c_name: WY5Z0khDLJzWrt7u}, rtcp_report_interval_ms: 5000, send_transport: (Transport), min_bitrate_bps: 64000, max_bitrate_bps: 64000, has audio_network_adaptor_config: false, has_dscp: false, send_codec_spec: {nack_enabled: false, transport_cc_enabled: false, enable_non_sender_rtt: false, cng_payload_type: , red_payload_type: , payload_type: 0, format: {name: PCMU, clockrate_hz: 8000, num_channels: 1, parameters: {maxptime: 150}}}} [2400:4836:0120/132311.236:WARNING:audio_send_stream.cc(870)] Config is invalid: min_bitrate_bps=-1; max_bitrate_bps=-1; both expected greater or equal to 0 [2400:4836:0120/132311.236:INFO:audio_send_stream.cc(228)] AudioSendStream::ConfigureStream: {rtp: {ssrc: 3080516117, extmap-allow-mixed: false, extensions: [], c_name: WY5Z0khDLJzWrt7u}, rtcp_report_interval_ms: 5000, send_transport: (Transport), min_bitrate_bps: 64000, max_bitrate_bps: 64000, has audio_network_adaptor_config: false, has_dscp: false, send_codec_spec: {nack_enabled: false, transport_cc_enabled: false, enable_non_sender_rtt: false, cng_payload_type: , red_payload_type: , payload_type: 0, format: {name: PCMU, clockrate_hz: 8000, num_channels: 1, parameters: {maxptime: 150}}}} [2400:4836:0120/132311.236:INFO:audio_send_stream.cc(228)] AudioSendStream::ConfigureStream: {rtp: {ssrc: 3080516117, mid: 0, extmap-allow-mixed: false, extensions: [], c_name: WY5Z0khDLJzWrt7u}, rtcp_report_interval_ms: 5000, send_transport: (Transport), min_bitrate_bps: 64000, max_bitrate_bps: 64000, has audio_network_adaptor_config: false, has_dscp: false, send_codec_spec: {nack_enabled: false, transport_cc_enabled: false, enable_non_sender_rtt: false, cng_payload_type: , red_payload_type: , payload_type: 0, format: {name: PCMU, clockrate_hz: 8000, num_channels: 1, parameters: {maxptime: 150}}}} [2400:4836:0120/132311.236:INFO:webrtc_voice_engine.cc(2332)] WebRtcVoiceMediaChannel::SetMaxSendBitrate. [2400:4836:0120/132311.236:INFO:webrtc_voice_engine.cc(1558)] Setting voice channel options: AudioOptions {} [2400:4836:0120/132311.236:INFO:webrtc_voice_engine.cc(439)] WebRtcVoiceEngine::ApplyOptions: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [2400:4836:0120/132311.236:INFO:webrtc_voice_engine.cc(553)] NetEq capacity is 200 [2400:4836:0120/132311.236:INFO:webrtc_voice_engine.cc(559)] NetEq fast mode? 0 [2400:4836:0120/132311.236:INFO:webrtc_voice_engine.cc(565)] NetEq minimum delay is 0 [2400:4836:0120/132311.236:INFO:webrtc_voice_engine.cc(571)] NetEq handle reordered packets? 0 [2400:4836:0120/132311.236:INFO:webrtc_voice_engine.cc(579)] No audio processing module present. No software-provided effects (AEC, NS, AGC, ...) are activated [2400:4836:0120/132311.236:INFO:webrtc_voice_engine.cc(1576)] Set voice channel options. Current options: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [2400:4836:0120/132311.236:INFO:channel.cc(818)] Changing voice state, recv=1 send=0 for {mid: 0, media_type: audio} [2400:1940:0120/132311.236:INFO:peer_connection.cc(1814)] Changing IceConnectionState 0 => 1 [2400:1940:0120/132311.236:INFO:peer_connection.cc(2131)] Non-rejected SCTP m= section is needed to get the SSL Role of the SCTP transport. [2400:1940:0120/132311.236:INFO:sdp_offer_answer.cc(1693)] Processing the MSIDs for MID=0 (streams=[]). [2400:5796:0120/132311.236:INFO:connection.cc(312)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--W|-|0|0|9115038255648079870|-]: Connection created [2400:1940:0120/132311.236:INFO:sdp_offer_answer.cc(1703)] Processing the addition of a remote track for MID=0. [2400:1940:0120/132311.236:INFO:rtp_transceiver.cc(313)] Changing transceiver (MID=0) current direction from to kSendRecv. [2400:5796:0120/132311.236:INFO:p2p_transport_channel.cc(1469)] Channel[0|1|__]: Created connection with origin: 2, total: 1 [2400:5796:0120/132311.236:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--W|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12639079, ms since last received data=12639079, rtt=6000, pings_since_last_response= [2400:5796:0120/132311.236:VERBOSE1:basic_ice_controller.cc(541)] Sorting 1 available connections [2400:5796:0120/132311.236:VERBOSE1:basic_ice_controller.cc(544)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--W|-|0|0|9115038255648079870|-] [2400:5796:0120/132311.236:INFO:p2p_transport_channel.cc(1923)] Channel[0|1|__]: Transport channel state changed from 0 to 2 [2400:5796:0120/132311.236:INFO:jsep_transport_controller.cc(1211)] 0 Transport 1 state changed. Check if state is complete. [2400:5796:0120/132311.236:INFO:jsep_transport_controller.cc(1211)] 0 Transport 1 state changed. Check if state is complete. [2400:5796:0120/132311.236:INFO:p2p_transport_channel.cc(1703)] Channel[0|1|__]: Have a pingable connection for the first time; starting to ping. [2400:5796:0120/132311.236:INFO:connection.cc(312)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|C--W|-|0|0|7277816997780274687|-]: Connection created [2400:5796:0120/132311.236:INFO:p2p_transport_channel.cc(1469)] Channel[0|1|__]: Created connection with origin: 2, total: 2 [2400:5796:0120/132311.236:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--W|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12639079, ms since last received data=12639079, rtt=6000, pings_since_last_response= [2400:5796:0120/132311.236:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|C--W|-|0|0|7277816997780274687|-]: UpdateState(), ms since last received response=12639079, ms since last received data=12639079, rtt=6000, pings_since_last_response= [2400:5796:0120/132311.236:VERBOSE1:basic_ice_controller.cc(541)] Sorting 2 available connections [2400:5796:0120/132311.236:VERBOSE1:basic_ice_controller.cc(544)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--W|-|0|0|9115038255648079870|-] [2400:5796:0120/132311.236:VERBOSE1:basic_ice_controller.cc(544)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|C--W|-|0|0|7277816997780274687|-] [2400:5796:0120/132311.236:VERBOSE1:p2p_transport_channel.cc(470)] Channel[0|1|__]: Ice not completed yet for this channel as Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1] has more than 1 connection. [2400:5796:0120/132311.236:INFO:p2p_transport_channel.cc(1923)] Channel[0|1|__]: Transport channel state changed from 2 to 1 [2400:5796:0120/132311.236:INFO:jsep_transport_controller.cc(1211)] 0 Transport 1 state changed. Check if state is complete. [2400:5796:0120/132311.236:WARNING:peer_connection.cc(2611)] Candidate has an unknown component: Cand[:Hac1f1491:2:udp:2130706430:172.31.20.x:11401:local::0:21a2c3bd49d08e124ada32201107ca7c:5eb66a7f56b9e70b08ccf29b0af93191:0:0:0] for mid 0 [2400:5796:0120/132311.236:WARNING:peer_connection.cc(2611)] Candidate has an unknown component: Cand[:S37c1a91:2:udp:1694498814:3.124.26.x:11401:stun:172.31.20.145:11401:21a2c3bd49d08e124ada32201107ca7c:5eb66a7f56b9e70b08ccf29b0af93191:0:0:0] for mid 0 [2400:5796:0120/132311.236:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--W|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12639080, ms since last received data=12639080, rtt=6000, pings_since_last_response= [2400:4836:0120/132311.236:VERBOSE1:webrtc_voice_engine.cc(2515)] WebRtcVoiceMediaChannel::SetDefaultRawAudioSink: [2400:5796:0120/132311.236:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|C--W|-|0|0|7277816997780274687|-]: UpdateState(), ms since last received response=12639080, ms since last received data=12639080, rtt=6000, pings_since_last_response= [2400:5796:0120/132311.236:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--W|-|0|0|9115038255648079870|-]: Sending STUN ping, id=5742616e6c6f356f6d385766, nomination=0 [2400:5796:0120/132311.237:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--W|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=5742616e6c6f356f6d385766, use_candidate=1, nomination=0 [2400:1940:0120/132311.237:INFO:peer_connection.cc(1833)] Changing standardized IceConnectionState 0 => 1 [2400:12748:0120/132311.249:VERBOSE1:webrtc_logging.cc(32)] MSS::SetReadyState({id=55119d03-6d83-4777-9c97-2baa1093e46b}, {ready_state=Live}) [2400:12748:0120/132311.249:VERBOSE1:webrtc_logging.cc(32)] MST::setReadyState({ready_state=live}) [kind: audio, id: 55119d03-6d83-4777-9c97-2baa1093e46b, label: 55119d03-6d83-4777-9c97-2baa1093e46b, enabled: true, muted: true, readyState: live, remote=true] [2400:12748:0120/132311.249:VERBOSE1:webrtc_logging.cc(32)] MST::SourceChangedState() [kind: audio, id: 55119d03-6d83-4777-9c97-2baa1093e46b, label: 55119d03-6d83-4777-9c97-2baa1093e46b, enabled: true, muted: false, readyState: live, remote=true] [2400:12748:0120/132311.250:VERBOSE1:webrtc_logging.cc(32)] WMPMS::WebMediaPlayerMS({delegate_id=4}, {is_audio_element=true}, {sink_id=2059beba85111230b00bbbd90c794bf46ed848af279cf9885699642594f2db86}) [delegate_id=4] [2400:12748:0120/132311.250:VERBOSE1:webrtc_logging.cc(32)] WMPMS::SetVolume({volume=1.00}) [delegate_id=4] [2400:12748:0120/132311.250:VERBOSE1:webrtc_logging.cc(32)] WMPMS::Load({load_type=MediaStream}) [delegate_id=4] [2400:12748:0120/132311.250:VERBOSE1:webrtc_logging.cc(32)] WMPMS::SetNetworkState => (state=Empty) [delegate_id=4] [2400:12748:0120/132311.250:VERBOSE1:webrtc_logging.cc(32)] WMPMS::SetReadyState => (state=HaveNothing) [delegate_id=4] [2400:12748:0120/132311.250:VERBOSE1:webrtc_logging.cc(32)] WMPMS::Load => (stream_id=2f064db2-c035-46a1-8eee-80474b78593c) [delegate_id=4] [2400:12748:0120/132311.250:VERBOSE1:webrtc_logging.cc(32)] MSRF::GetAudioRenderer({web_stream_id=2f064db2-c035-46a1-8eee-80474b78593c}, {device_id=2059beba85111230b00bbbd90c794bf46ed848af279cf9885699642594f2db86}) [2400:12748:0120/132311.250:VERBOSE1:webrtc_logging.cc(32)] MSRF::GetAudioRenderer => (media stream is a remote WebRTC stream) [2400:12748:0120/132311.250:VERBOSE1:webrtc_logging.cc(32)] MSRF::GetAudioRenderer => (creating new WebRtcAudioRenderer for remote stream) [2400:12748:0120/132311.250:VERBOSE1:webrtc_logging.cc(32)] WRAR::WebRtcAudioRenderer({session_id=}, {device_id=2059beba85111230b00bbbd90c794bf46ed848af279cf9885699642594f2db86}) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132311.250:VERBOSE1:webrtc_logging.cc(32)] WRADI::SetAudioRenderer() [id=13F27BB5004BE349A2E91887CC0BF104] [2400:12748:0120/132311.250:VERBOSE1:webrtc_logging.cc(32)] WRAR::Initialize([state=UNINITIALIZED]) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WRAR::Initialize => (sink device_status=OK) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WRAR::PrepareSink() [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WRAR::PrepareSink => (hardware parameters=[format: PCM_LOW_LATENCY, channel_layout: 3, channels: 2, sample_rate: 48000, frames_per_buffer: 480, effects: 0, mic_positions: , hw_cap.min_frames_per_buffer: 480, hw_cap.max_frames_per_buffer: 480]) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WRAR::PrepareSink => (source_frames_per_buffer=480) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WRAR::PrepareSink => (sink_params=[format: PCM_LOW_LATENCY, channel_layout: 3, channels: 2, sample_rate: 48000, frames_per_buffer: 480, effects: 0, mic_positions: ]) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WRADI::SetOutputDeviceForAec({output_device_id=2059beba85111230b00bbbd90c794bf46ed848af279cf9885699642594f2db86}) [id=13F27BB5004BE349A2E91887CC0BF104] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WRAR::UpdateSourceVolume => (source volume changed to 0.00) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WRAR::Start([state=PAUSED]) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WMPMS::Load => (audio_track_id=55119d03-6d83-4777-9c97-2baa1093e46b) [delegate_id=4] [2400:1940:0120/132311.256:INFO:remote_audio_source.cc(115)] RAS::SetVolume({volume=0.00}) [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WMPMS::Load => (audio only mode) [delegate_id=4] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WMPMS::SetReadyState => (state=HaveNothing) [delegate_id=4] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WMPMS::SetVolume({volume=1.00}) [delegate_id=4] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WRAR::UpdateSourceVolume => (source volume changed to 0.00) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WMPMS::Play() [delegate_id=4] [2400:1940:0120/132311.256:INFO:remote_audio_source.cc(115)] RAS::SetVolume({volume=0.00}) [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WRAR::AddPlayingState => (number of playing audio sources=1) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WRAR::EnterPlayState([state=PAUSED]) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WRAR::EnterPlayState => (state=PLAYING) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WRAR::UpdateSourceVolume => (source volume changed to 1.00) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132311.256:VERBOSE1:webrtc_logging.cc(32)] WMPMS::SetReadyState => (state=HaveMetadata) [delegate_id=4] [2400:1940:0120/132311.256:INFO:remote_audio_source.cc(115)] RAS::SetVolume({volume=1.00}) [2400:5796:0120/132311.287:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12639130, ms since last received data=12639130, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 [2400:5796:0120/132311.287:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|C--W|-|0|0|7277816997780274687|-]: UpdateState(), ms since last received response=12639130, ms since last received data=12639130, rtt=6000, pings_since_last_response= [2400:5796:0120/132311.287:VERBOSE1:connection.cc(852)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|C--W|-|0|0|7277816997780274687|-]: Sending STUN ping, id=4852634851444e7353495452, nomination=0 [2400:5796:0120/132311.287:INFO:connection.cc(1165)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|C--W|-|0|0|7277816997780274687|-]: Sent STUN BINDING request, id=4852634851444e7353495452, use_candidate=1, nomination=0 [1992:3348:0120/132311.291:ERROR:socket_udp.cc(220)] Received unexpected data packet from HIDDEN_IP:11400 before STUN binding is finished. [1992:3348:0120/132311.310:ERROR:socket_udp.cc(220)] Received unexpected data packet from HIDDEN_IP:11400 before STUN binding is finished. [2400:5796:0120/132311.318:INFO:connection.cc(1082)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|C--I|-|0|0|7277816997780274687|-]: Received STUN BINDING response, id=4852634851444e7353495452, code=0, rtt=31, pings_since_last_response=4852634851444e7353495452 [2400:5796:0120/132311.318:VERBOSE1:connection.cc(388)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|C--I|-|0|0|7277816997780274687|-]: set_receiving to 1 [2400:5796:0120/132311.318:VERBOSE1:connection.cc(363)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWI|-|0|0|7277816997780274687|-]: set_write_state from: 2 to 0 [2400:5796:0120/132311.318:VERBOSE1:connection.cc(398)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|-|0|0|7277816997780274687|-]: set_state [2400:5796:0120/132311.318:INFO:connection.cc(1266)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|-|0|0|7277816997780274687|31]: Updating local candidate type to srflx. [2400:5796:0120/132311.318:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12639161, ms since last received data=12639161, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 [2400:5796:0120/132311.318:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|-|0|0|7241540809789538302|31]: UpdateState(), ms since last received response=0, ms since last received data=12639161, rtt=100, pings_since_last_response= [2400:5796:0120/132311.318:VERBOSE1:basic_ice_controller.cc(541)] Sorting 2 available connections [2400:5796:0120/132311.318:VERBOSE1:basic_ice_controller.cc(544)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|-|0|0|7241540809789538302|31] [2400:5796:0120/132311.318:VERBOSE1:basic_ice_controller.cc(544)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-] [2400:5796:0120/132311.318:INFO:p2p_transport_channel.cc(331)] Switching selected connection due to: candidate pair state changed [2400:5796:0120/132311.318:INFO:p2p_transport_channel.cc(1817)] Channel[0|1|__]: New selected connection: Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31] [2400:5796:0120/132311.318:INFO:channel.cc(355)] Network route changed for {mid: 0, media_type: audio} [2400:4836:0120/132311.319:INFO:rtp_transport_controller_send.cc(313)] Network route changed on transport 0: new_route = [ connected: 1 local: [ 1/1 Ethernet turn: 0 ] remote: [ 1/0 Ethernet turn: 0 ] packet_overhead_bytes: 28 ] [2400:5796:0120/132311.319:VERBOSE1:p2p_transport_channel.cc(2256)] Channel[0|1|__]: Changed writable_ to 1 [2400:5796:0120/132311.319:VERBOSE1:dtls_transport.cc(500)] DtlsTransport[0|1|__]: ice_transport writable state changed to 1 [2400:5796:0120/132311.319:INFO:dtls_transport.cc(824)] DtlsTransport[0|1|__]: configuring DTLS handshake timeout 62 based on ICE RTT 31 [2400:5796:0120/132311.319:INFO:dtls_transport.cc(728)] DtlsTransport[0|1|__]: DtlsTransport: Started DTLS handshake [2400:5796:0120/132311.319:VERBOSE1:dtls_transport.cc(805)] DtlsTransport[0|1|__]: set_dtls_state from:0 to 1 [2400:5796:0120/132311.319:INFO:srtp_transport.cc(360)] The params in SRTP transport are reset. [2400:5796:0120/132311.319:VERBOSE1:dtls_transport.cc(543)] DtlsTransport[0|1|__]: ice_transport receiving state changed to 1 [2400:5796:0120/132311.319:VERBOSE1:p2p_transport_channel.cc(470)] Channel[0|1|RW]: Ice not completed yet for this channel as Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1] has more than 1 connection. [2400:5796:0120/132311.319:INFO:jsep_transport_controller.cc(1211)] 0 Transport 1 state changed. Check if state is complete. [2400:1940:0120/132311.319:INFO:peer_connection.cc(1833)] Changing standardized IceConnectionState 1 => 2 [2400:5796:0120/132311.319:VERBOSE1:connection.cc(486)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: Received STUN BINDING request, id=1d640000c3070503f549e976 [2400:5796:0120/132311.319:VERBOSE1:connection.cc(723)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: Sent STUN BINDING response, to=3.124.26.x:11400, id=1d640000c3070503f549e976 [2400:5796:0120/132311.331:ERROR:dtls_transport.cc(610)] DtlsTransport[0|1|__]: Received non-DTLS packet before DTLS complete. [2400:5796:0120/132311.338:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: UpdateState(), ms since last received response=20, ms since last received data=7, rtt=100, pings_since_last_response= [2400:5796:0120/132311.338:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12639181, ms since last received data=12639181, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 [2400:5796:0120/132311.338:VERBOSE1:connection.cc(852)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: Sending STUN ping, id=473458736337546d6a457034, nomination=0 [2400:5796:0120/132311.338:VERBOSE1:connection.cc(1165)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: Sent STUN BINDING request, id=473458736337546d6a457034, use_candidate=1, nomination=0 [2400:5796:0120/132311.351:ERROR:dtls_transport.cc(610)] DtlsTransport[0|1|__]: Received non-DTLS packet before DTLS complete. [2400:5796:0120/132311.369:VERBOSE1:connection.cc(1082)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWI|S|0|0|7241540809789538302|31]: Received STUN BINDING response, id=473458736337546d6a457034, code=0, rtt=31, pings_since_last_response=473458736337546d6a457034 [2400:5796:0120/132311.369:VERBOSE1:connection.cc(398)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: set_state [2400:5796:0120/132311.370:ERROR:dtls_transport.cc(610)] DtlsTransport[0|1|__]: Received non-DTLS packet before DTLS complete. [2400:5796:0120/132311.388:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: UpdateState(), ms since last received response=19, ms since last received data=17, rtt=100, pings_since_last_response= [2400:5796:0120/132311.388:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12639231, ms since last received data=12639231, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 [2400:5796:0120/132311.388:VERBOSE1:connection.cc(852)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: Sending STUN ping, id=636271633930324b46313943, nomination=0 [2400:5796:0120/132311.388:VERBOSE1:connection.cc(1165)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: Sent STUN BINDING request, id=636271633930324b46313943, use_candidate=1, nomination=0 [2400:5796:0120/132311.391:ERROR:dtls_transport.cc(610)] DtlsTransport[0|1|__]: Received non-DTLS packet before DTLS complete. [2400:5796:0120/132311.393:INFO:dtls_transport.cc(656)] DtlsTransport[0|1|__]: DTLS handshake complete. [2400:5796:0120/132311.393:VERBOSE1:dtls_transport.cc(805)] DtlsTransport[0|1|__]: set_dtls_state from:1 to 2 [2400:5796:0120/132311.393:VERBOSE1:dtls_transport.cc(789)] DtlsTransport[0|1|__]: set_writable to: 1 [2400:5796:0120/132311.393:VERBOSE1:webrtc_voice_engine.cc(2345)] OnReadyToSend: Ready. [2400:5796:0120/132311.393:INFO:jsep_transport_controller.cc(1149)] Transport 0 writability changed to 1. [2400:4836:0120/132311.393:INFO:call.cc(1350)] UpdateAggregateNetworkState: aggregate_state change to up [2400:4836:0120/132311.393:VERBOSE1:rtp_transport_controller_send.cc(370)] SignalNetworkState Up [2400:8152:0120/132311.393:INFO:rtp_transport_controller_send.cc(617)] Creating fallback congestion controller [2400:5796:0120/132311.393:INFO:dtls_srtp_transport.cc(218)] Extracting keys from transport: 0 [2400:1940:0120/132311.393:INFO:peer_connection.cc(2284)] Changing to ICE connected state because all transports are writable. [2400:1940:0120/132311.393:INFO:peer_connection.cc(1814)] Changing IceConnectionState 1 => 2 [2400:8152:0120/132311.393:VERBOSE1:loss_based_bwe_v2.cc(113)] The configuration does not specify that the estimator should be enabled, disabling it. [2400:8152:0120/132311.393:INFO:field_trial_parser.cc(90)] No field with key: 'Enabled' (found in trial: "Enabled") [2400:8152:0120/132311.393:INFO:field_trial_parser.cc(97)] Valid keys are: Disabled, [2400:8152:0120/132311.393:INFO:alr_experiment.cc(79)] Using ALR experiment settings: pacing factor: 1, max pacer queue length: 2875, ALR bandwidth usage percent: 80, ALR start budget level percent: 40, ALR end budget level percent: -60, ALR experiment group ID: 3 [2400:8152:0120/132311.393:INFO:trendline_estimator.cc(185)] Using Trendline filter for delay change estimation with settings sort:false,cap:false,beginning_packets:7,end_packets:7,cap_uncertainty:0,window_size:20 and no network state predictor [2400:8152:0120/132311.393:INFO:trendline_estimator.cc(185)] Using Trendline filter for delay change estimation with settings sort:false,cap:false,beginning_packets:7,end_packets:7,cap_uncertainty:0,window_size:20 and no network state predictor [2400:8152:0120/132311.393:INFO:aimd_rate_control.cc(112)] Using aimd rate control with back off factor 0.85 [2400:8152:0120/132311.393:INFO:delay_based_bwe.cc(104)] Initialized DelayBasedBwe with separate audio overuse detectionenabled:false,packet_threshold:10,time_threshold:1 s and alr limited backoff disabled [2400:8152:0120/132311.393:INFO:delay_based_bwe.cc(357)] BWE Setting start bitrate to: 300 kbps [2400:8152:0120/132311.394:INFO:probe_controller.cc(280)] Measured bitrate: 300000 Minimum to probe further: 1260000 [2400:8152:0120/132311.394:VERBOSE1:goog_cc_network_control.cc(688)] bwe 12639237 pushback_target_bps=300000 estimate_bps=300000 [2400:8152:0120/132311.394:INFO:bitrate_allocator.cc(394)] Current BWE 300000 [2400:9620:0120/132311.394:VERBOSE1:pacing_controller.cc(230)] bwe:pacer_updated pacing_kbps=750 padding_budget_kbps=0 [2400:9620:0120/132311.394:INFO:bitrate_prober.cc(114)] Probe cluster (bitrate:min bytes:min packets): (900000:1688:5) [2400:9620:0120/132311.394:INFO:bitrate_prober.cc(114)] Probe cluster (bitrate:min bytes:min packets): (1800000:3375:5) [2400:5796:0120/132311.398:INFO:srtp_transport.cc(305)] SRTP activated with negotiated parameters: send cipher_suite 1 recv cipher_suite 1 [2400:5796:0120/132311.398:INFO:channel.cc(543)] Channel writable ({mid: 0, media_type: audio}) for the first time [2400:4836:0120/132311.398:INFO:webrtc_voice_engine.cc(439)] WebRtcVoiceEngine::ApplyOptions: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [2400:4836:0120/132311.398:INFO:webrtc_voice_engine.cc(553)] NetEq capacity is 200 [2400:4836:0120/132311.398:INFO:webrtc_voice_engine.cc(559)] NetEq fast mode? 0 [2400:4836:0120/132311.398:INFO:webrtc_voice_engine.cc(565)] NetEq minimum delay is 0 [2400:4836:0120/132311.398:INFO:webrtc_voice_engine.cc(571)] NetEq handle reordered packets? 0 [2400:4836:0120/132311.398:INFO:webrtc_voice_engine.cc(579)] No audio processing module present. No software-provided effects (AEC, NS, AGC, ...) are activated [2400:4836:0120/132311.398:VERBOSE1:webrtc_logging.cc(32)] WRADI::StartRecording() [id=13F27BB5004BE349A2E91887CC0BF104] [2400:4836:0120/132311.398:INFO:channel.cc(818)] Changing voice state, recv=1 send=1 for {mid: 0, media_type: audio} [2400:5796:0120/132311.411:WARNING:srtp_session.cc(169)] Failed to unprotect SRTP packet, err=7, previous failure count: 0 [2400:5796:0120/132311.411:ERROR:srtp_transport.cc(216)] Failed to unprotect RTP packet: size=172, seqnum=2727, SSRC=820004207, previous failure count: 0 [2400:9620:0120/132311.413:INFO:rtp_sender_audio.cc(318)] First audio RTP packet sent to pacer [2400:5796:0120/132311.419:VERBOSE1:connection.cc(1082)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWI|S|0|0|7241540809789538302|31]: Received STUN BINDING response, id=636271633930324b46313943, code=0, rtt=31, pings_since_last_response=636271633930324b46313943 [2400:5796:0120/132311.419:VERBOSE1:connection.cc(398)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: set_state [2400:5796:0120/132311.437:INFO:rtp_demuxer.cc(418)] Added sink = 00007C2603DD9048 binding with SSRC=820004207 [2400:5796:0120/132311.437:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: UpdateState(), ms since last received response=18, ms since last received data=0, rtt=100, pings_since_last_response= [2400:4836:0120/132311.437:ERROR:call.cc(1568)] receive_rtp_config_ lookup failed for ssrc 820004207 [2400:5796:0120/132311.437:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12639280, ms since last received data=12639280, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 [2400:4836:0120/132311.437:INFO:webrtc_voice_engine.cc(2239)] Creating unsignaled receive stream for SSRC=820004207 [2400:4836:0120/132311.437:INFO:webrtc_voice_engine.cc(1977)] AddRecvStream: {ssrcs:[820004207];ssrc_groups:;stream_ids:;} [2400:5796:0120/132311.437:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sending STUN ping, id=64524f66456246547833626f, nomination=0 [2400:4836:0120/132311.437:INFO:delay_manager.cc(54)] Delay manager config: quantile=0.95 forget_factor=0.983 start_forget_weight=2 resample_interval_ms=500 max_history_ms=2000 use_reorder_optimizer=1 reorder_forget_factor=0.9993 ms_per_loss_percent=20 [2400:5796:0120/132311.437:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=64524f66456246547833626f, use_candidate=1, nomination=0 [2400:4836:0120/132311.437:INFO:decision_logic.cc(70)] NetEq decision logic settings: estimate_dtx_delay=1 time_stretch_cn=1 target_level_window_ms=100 [2400:4836:0120/132311.437:INFO:neteq_impl.cc(161)] NetEq config: sample_rate_hz=16000, enable_post_decode_vad=true, max_packets_in_buffer=200, min_delay_ms=0, enable_fast_accelerate=false, enable_muted_state=true, enable_rtx_handling=false, extra_output_delay_ms=0 [2400:4836:0120/132311.437:VERBOSE1:neteq_impl.cc(2120)] SetSampleRateAndChannels 16000 1 [2400:4836:0120/132311.437:VERBOSE1:neteq_impl.cc(503)] FlushBuffers [2400:4836:0120/132311.438:INFO:audio_receive_stream.cc(120)] AudioReceiveStream: 820004207 [2400:4836:0120/132311.438:INFO:rtp_demuxer.cc(145)] Added sink = 00007C26041F1C00 for criteria {mid: , rsid: , ssrcs: [820004207, ], payload_types = []} [2400:4836:0120/132311.438:VERBOSE1:call.cc(1354)] UpdateAggregateNetworkState: aggregate_state remains at up [2400:4836:0120/132311.438:VERBOSE1:rtp_transport_controller_send.cc(370)] SignalNetworkState Up [2400:4836:0120/132311.438:INFO:webrtc_voice_engine.cc(2088)] WRVMC::SetOutputVolume({ssrc=820004207}, {volume=1.00}) [2400:4836:0120/132311.438:INFO:webrtc_voice_engine.cc(2098)] WRVMC::SetOutputVolume => (stream with SSRC 820004207 now uses volume 1.00) [2400:4836:0120/132311.438:INFO:webrtc_voice_engine.cc(2137)] SetBaseMinimumPlayoutDelayMs() to 0 for recv stream with ssrc 820004207 [2400:4836:0120/132311.438:VERBOSE1:webrtc_voice_engine.cc(2502)] WebRtcVoiceMediaChannel::SetRawAudioSink: ssrc:820004207 (ptr) [2400:4836:0120/132311.438:VERBOSE1:neteq_impl.cc(2120)] SetSampleRateAndChannels 8000 1 [2400:3924:0120/132311.446:VERBOSE1:webrtc_logging.cc(32)] MSAS::SetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 16000, frames_per_buffer: 160, effects: 0, mic_positions: ]}, {old_params=[format: PCM_LINEAR, channel_layout: 0, channels: 0, sample_rate: 0, frames_per_buffer: 0, effects: 0, mic_positions: ]}) [this=0x7C2603DC7000] [2400:3924:0120/132311.446:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnSetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 16000, frames_per_buffer: 160, effects: 0, mic_positions: ]}) [this=0x7C2603DC7138] [2400:3924:0120/132311.446:VERBOSE1:webrtc_logging.cc(32)] MSAT::OnSetFormat({params: [format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 16000, frames_per_buffer: 160, effects: 0, mic_positions: ]}) [this=0x7C2602CF6B80] [2400:3924:0120/132311.446:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnSetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 16000, frames_per_buffer: 160, effects: 0, mic_positions: ]}) [this=0x7C2602CF6B98] [2400:3924:0120/132311.446:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnData => (number of active consumers=1) [this=0x7C2603DC7138] [2400:3924:0120/132311.446:VERBOSE1:webrtc_logging.cc(32)] MSAT::OnData() => (audio track is alive)) [this=0x7C2602CF6B80] [2400:3924:0120/132311.457:VERBOSE1:webrtc_logging.cc(32)] MSAS::SetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 8000, frames_per_buffer: 80, effects: 0, mic_positions: ]}, {old_params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 16000, frames_per_buffer: 160, effects: 0, mic_positions: ]}) [this=0x7C2603DC7000] [2400:3924:0120/132311.457:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnSetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 8000, frames_per_buffer: 80, effects: 0, mic_positions: ]}) [this=0x7C2603DC7138] [2400:3924:0120/132311.457:VERBOSE1:webrtc_logging.cc(32)] MSAT::OnSetFormat({params: [format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 8000, frames_per_buffer: 80, effects: 0, mic_positions: ]}) [this=0x7C2602CF6B80] [2400:3924:0120/132311.457:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnSetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 8000, frames_per_buffer: 80, effects: 0, mic_positions: ]}) [this=0x7C2602CF6B98] [2400:3924:0120/132311.457:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnData => (number of active consumers=1) [this=0x7C2603DC7138] [2400:5796:0120/132311.486:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: UpdateState(), ms since last received response=67, ms since last received data=15, rtt=100, pings_since_last_response= [2400:5796:0120/132311.486:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12639329, ms since last received data=12639329, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f [2400:5796:0120/132311.486:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sending STUN ping, id=5659734542306c644b324939, nomination=0 [2400:5796:0120/132311.486:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=5659734542306c644b324939, use_candidate=1, nomination=0 [2400:5796:0120/132311.535:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: UpdateState(), ms since last received response=116, ms since last received data=4, rtt=100, pings_since_last_response= [2400:5796:0120/132311.535:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12639378, ms since last received data=12639378, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 [2400:5796:0120/132311.785:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: UpdateState(), ms since last received response=366, ms since last received data=14, rtt=100, pings_since_last_response= [2400:5796:0120/132311.785:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12639628, ms since last received data=12639628, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 [2400:5796:0120/132312.037:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: UpdateState(), ms since last received response=618, ms since last received data=6, rtt=100, pings_since_last_response= [2400:5796:0120/132312.037:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12639880, ms since last received data=12639880, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 [2400:5796:0120/132312.037:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sending STUN ping, id=77544956797a796a4f656d67, nomination=0 [2400:5796:0120/132312.037:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=77544956797a796a4f656d67, use_candidate=1, nomination=0 [2400:5796:0120/132312.287:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: UpdateState(), ms since last received response=868, ms since last received data=16, rtt=100, pings_since_last_response= [2400:5796:0120/132312.287:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12640130, ms since last received data=12640130, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 [2400:1672:0120/132312.396:INFO:probe_controller.cc(378)] kWaitingForProbingResult: timeout [2400:5796:0120/132312.538:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: UpdateState(), ms since last received response=1119, ms since last received data=7, rtt=100, pings_since_last_response= [2400:5796:0120/132312.538:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12640381, ms since last received data=12640381, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 [2400:5796:0120/132312.538:VERBOSE1:connection.cc(852)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: Sending STUN ping, id=4d3956764a364f56574d4230, nomination=0 [2400:5796:0120/132312.538:VERBOSE1:connection.cc(1165)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: Sent STUN BINDING request, id=4d3956764a364f56574d4230, use_candidate=1, nomination=0 [2400:5796:0120/132312.569:VERBOSE1:connection.cc(1082)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWI|S|0|0|7241540809789538302|31]: Received STUN BINDING response, id=4d3956764a364f56574d4230, code=0, rtt=31, pings_since_last_response=4d3956764a364f56574d4230 [2400:5796:0120/132312.569:VERBOSE1:connection.cc(398)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: set_state [2400:5796:0120/132312.789:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: UpdateState(), ms since last received response=220, ms since last received data=18, rtt=100, pings_since_last_response= [2400:5796:0120/132312.789:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12640632, ms since last received data=12640632, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 [2400:5796:0120/132313.040:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: UpdateState(), ms since last received response=471, ms since last received data=9, rtt=100, pings_since_last_response= [2400:5796:0120/132313.040:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12640883, ms since last received data=12640883, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 [2400:5796:0120/132313.040:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sending STUN ping, id=41687a4c49752b3032716152, nomination=0 [2400:5796:0120/132313.040:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=41687a4c49752b3032716152, use_candidate=1, nomination=0 [2400:5796:0120/132313.290:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: UpdateState(), ms since last received response=722, ms since last received data=0, rtt=100, pings_since_last_response= [2400:5796:0120/132313.290:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12641134, ms since last received data=12641134, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 [2400:5796:0120/132313.543:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: UpdateState(), ms since last received response=974, ms since last received data=12, rtt=100, pings_since_last_response= [2400:5796:0120/132313.543:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12641386, ms since last received data=12641386, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 [2400:5796:0120/132313.543:VERBOSE1:connection.cc(852)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: Sending STUN ping, id=4d7a7176396330634b4a4345, nomination=0 [2400:5796:0120/132313.543:VERBOSE1:connection.cc(1165)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: Sent STUN BINDING request, id=4d7a7176396330634b4a4345, use_candidate=1, nomination=0 [2400:5796:0120/132313.573:VERBOSE1:connection.cc(1082)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWI|S|0|0|7241540809789538302|31]: Received STUN BINDING response, id=4d7a7176396330634b4a4345, code=0, rtt=30, pings_since_last_response=4d7a7176396330634b4a4345 [2400:5796:0120/132313.573:VERBOSE1:connection.cc(398)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|31]: set_state [2400:5796:0120/132313.794:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=221, ms since last received data=3, rtt=100, pings_since_last_response= [2400:5796:0120/132313.794:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12641637, ms since last received data=12641637, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 [2400:5796:0120/132314.046:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=473, ms since last received data=15, rtt=100, pings_since_last_response= [2400:5796:0120/132314.046:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12641889, ms since last received data=12641889, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 [2400:5796:0120/132314.046:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sending STUN ping, id=734d6e793551335a3035756c, nomination=0 [2400:5796:0120/132314.046:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=734d6e793551335a3035756c, use_candidate=1, nomination=0 [2400:5796:0120/132314.298:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=725, ms since last received data=7, rtt=100, pings_since_last_response= [2400:5796:0120/132314.298:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12642141, ms since last received data=12642141, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 1 more [2400:5796:0120/132314.549:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=976, ms since last received data=18, rtt=100, pings_since_last_response= [2400:5796:0120/132314.549:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12642392, ms since last received data=12642392, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 1 more [2400:5796:0120/132314.549:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sending STUN ping, id=6d74522f4c43484a5050626e, nomination=0 [2400:5796:0120/132314.549:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=6d74522f4c43484a5050626e, use_candidate=1, nomination=0 [2400:5796:0120/132314.801:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1228, ms since last received data=10, rtt=100, pings_since_last_response= [2400:5796:0120/132314.801:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12642644, ms since last received data=12642644, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 2 more [2400:5796:0120/132315.053:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1480, ms since last received data=0, rtt=100, pings_since_last_response= [2400:5796:0120/132315.053:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12642896, ms since last received data=12642896, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 2 more [2400:5796:0120/132315.053:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sending STUN ping, id=475857574c4543564a79486c, nomination=0 [2400:5796:0120/132315.053:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=475857574c4543564a79486c, use_candidate=1, nomination=0 [2400:1940:0120/132315.243:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 104 to 127 [2400:1940:0120/132315.243:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 106 to 125 [2400:1940:0120/132315.243:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 110 to 124 [2400:1940:0120/132315.243:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 112 to 123 [2400:1940:0120/132315.243:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 102 to 122 [2400:1940:0120/132315.243:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 103 to 121 [2400:1940:0120/132315.243:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 105 to 120 [2400:1940:0120/132315.243:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 111 to 119 [2400:1940:0120/132315.243:WARNING:used_ids.h(55)] Duplicate id found. Reassigning from 113 to 118 [2400:1940:0120/132315.243:WARNING:media_session.cc(936)] RED codec red is missing an associated payload type. [2400:1940:0120/132315.244:INFO:stats_collector.cc(453)] Assuming SSRC=820004207 is an unsignalled receive stream corresponding to the RtpReceiver with track ID "55119d03-6d83-4777-9c97-2baa1093e46b". [2400:5796:0120/132315.244:INFO:p2p_transport_channel.cc(520)] Set ICE ufrag: KfC0 pwd: QUoS0XKwhbv+8Y0OliGOsYvL on transport 0 [2400:1940:0120/132315.244:INFO:sdp_offer_answer.cc(2457)] Session: 1807224220773941525 Old state: stable New state: have-local-offer [2400:4836:0120/132315.244:INFO:channel.cc(827)] Setting local voice description for {mid: 0, media_type: audio} [2400:4836:0120/132315.244:INFO:webrtc_voice_engine.cc(1418)] WebRtcVoiceMediaChannel::SetRecvParameters: {codecs: [AudioCodec[111:opus:48000:0:2], AudioCodec[63:red:48000:0:2], AudioCodec[103:ISAC:16000:0:1], AudioCodec[104:ISAC:32000:0:1], AudioCodec[9:G722:8000:0:1], AudioCodec[0:PCMU:8000:0:1], AudioCodec[8:PCMA:8000:0:1], AudioCodec[106:CN:32000:0:1], AudioCodec[105:CN:16000:0:1], AudioCodec[13:CN:8000:0:1], AudioCodec[110:telephone-event:48000:0:1], AudioCodec[112:telephone-event:32000:0:1], AudioCodec[113:telephone-event:16000:0:1], AudioCodec[126:telephone-event:8000:0:1]], extensions: [{uri: urn:ietf:params:rtp-hdrext:ssrc-audio-level, id: 1}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 2}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 4}]} [2400:4836:0120/132315.244:INFO:webrtc_voice_engine.cc(1586)] Setting receive voice codecs. [2400:4836:0120/132315.244:INFO:channel.cc(818)] Changing voice state, recv=1 send=1 for {mid: 0, media_type: audio} [2400:1940:0120/132315.244:INFO:peer_connection.cc(2131)] Non-rejected SCTP m= section is needed to get the SSL Role of the SCTP transport. [2400:5796:0120/132315.244:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1672, ms since last received data=14, rtt=100, pings_since_last_response= [2400:5796:0120/132315.244:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12643088, ms since last received data=12643088, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 3 more [2400:5796:0120/132315.244:VERBOSE1:basic_ice_controller.cc(541)] Sorting 2 available connections [2400:5796:0120/132315.245:VERBOSE1:basic_ice_controller.cc(544)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30] [2400:5796:0120/132315.245:VERBOSE1:basic_ice_controller.cc(544)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-] [2400:5796:0120/132315.245:VERBOSE1:p2p_transport_channel.cc(470)] Channel[0|1|RW]: Ice not completed yet for this channel as Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1] has more than 1 connection. [2400:5796:0120/132315.245:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1672, ms since last received data=14, rtt=100, pings_since_last_response= [2400:5796:0120/132315.245:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12643088, ms since last received data=12643088, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 3 more [2400:5796:0120/132315.245:VERBOSE1:basic_ice_controller.cc(541)] Sorting 2 available connections [2400:5796:0120/132315.245:VERBOSE1:basic_ice_controller.cc(544)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30] [2400:5796:0120/132315.245:VERBOSE1:basic_ice_controller.cc(544)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-] [2400:5796:0120/132315.245:VERBOSE1:p2p_transport_channel.cc(470)] Channel[0|1|RW]: Ice not completed yet for this channel as Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1] has more than 1 connection. [2400:5796:0120/132315.245:WARNING:peer_connection.cc(2611)] Candidate has an unknown component: Cand[:Hac1f1491:2:udp:2130706430:172.31.20.x:11401:local::0:21a2c3bd49d08e124ada32201107ca7c:5eb66a7f56b9e70b08ccf29b0af93191:0:0:0] for mid 0 [2400:5796:0120/132315.245:WARNING:peer_connection.cc(2611)] Candidate has an unknown component: Cand[:S37c1a91:2:udp:1694498814:3.124.26.x:11401:stun:172.31.20.145:11401:21a2c3bd49d08e124ada32201107ca7c:5eb66a7f56b9e70b08ccf29b0af93191:0:0:0] for mid 0 [2400:12748:0120/132315.245:VERBOSE1:webrtc_sdp.cc(3282)] Ignored line: a=connection:new [2400:1940:0120/132315.246:INFO:sdp_offer_answer.cc(3472)] SetRemoteDescription: Remote media section at i=0 is missing an a=mid line. Filling in the value '0' from the matching local media section. [2400:5796:0120/132315.246:INFO:dtls_srtp_transport.cc(63)] Setting RTCP Transport on 0 transport 0000000000000000 [2400:5796:0120/132315.246:INFO:dtls_srtp_transport.cc(68)] Setting RTP Transport on 0 transport 00007C2603FAE300 [2400:5796:0120/132315.246:INFO:p2p_transport_channel.cc(531)] Received remote ICE parameters: ufrag=21a2c3bd49d08e124ada32201107ca7c, renomination disabled [2400:5796:0120/132315.246:INFO:dtls_transport.cc(239)] DtlsTransport[0|1|_W]: Ignoring identical remote DTLS fingerprint [2400:5796:0120/132315.246:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1673, ms since last received data=15, rtt=100, pings_since_last_response= [2400:1940:0120/132315.246:INFO:sdp_offer_answer.cc(2457)] Session: 1807224220773941525 Old state: have-local-offer New state: stable [2400:5796:0120/132315.246:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12643089, ms since last received data=12643089, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 3 more [2400:5796:0120/132315.246:VERBOSE1:basic_ice_controller.cc(541)] Sorting 2 available connections [2400:5796:0120/132315.246:VERBOSE1:basic_ice_controller.cc(544)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30] [2400:5796:0120/132315.246:VERBOSE1:basic_ice_controller.cc(544)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-] [2400:4836:0120/132315.246:INFO:channel.cc(887)] Setting remote voice description for {mid: 0, media_type: audio} [2400:5796:0120/132315.246:VERBOSE1:p2p_transport_channel.cc(470)] Channel[0|1|RW]: Ice not completed yet for this channel as Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1] has more than 1 connection. [2400:4836:0120/132315.246:INFO:webrtc_voice_engine.cc(1372)] WebRtcVoiceMediaChannel::SetSendParameters: {codecs: [AudioCodec[0:PCMU:8000:0:1], AudioCodec[8:PCMA:8000:0:1], AudioCodec[126:telephone-event:8000:0:1]], extensions: [], extmap-allow-mixed: false, max_bandwidth_bps: -1, mid: 0, options: AudioOptions {}} [2400:4836:0120/132315.246:VERBOSE1:rtp_transport_controller_send.cc(459)] WebRTC.RtpTransportControllerSend.SetSdpBitrateParameters: nothing to update [2400:4836:0120/132315.246:INFO:audio_send_stream.cc(228)] AudioSendStream::ConfigureStream: {rtp: {ssrc: 3080516117, mid: 0, extmap-allow-mixed: false, extensions: [], c_name: WY5Z0khDLJzWrt7u}, rtcp_report_interval_ms: 5000, send_transport: (Transport), min_bitrate_bps: 64000, max_bitrate_bps: 64000, has audio_network_adaptor_config: false, has_dscp: false, send_codec_spec: {nack_enabled: false, transport_cc_enabled: false, enable_non_sender_rtt: false, cng_payload_type: , red_payload_type: , payload_type: 0, format: {name: PCMU, clockrate_hz: 8000, num_channels: 1, parameters: {maxptime: 150}}}} [2400:4836:0120/132315.246:INFO:webrtc_voice_engine.cc(2332)] WebRtcVoiceMediaChannel::SetMaxSendBitrate. [2400:4836:0120/132315.246:INFO:webrtc_voice_engine.cc(1558)] Setting voice channel options: AudioOptions {} [2400:4836:0120/132315.246:INFO:webrtc_voice_engine.cc(439)] WebRtcVoiceEngine::ApplyOptions: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [2400:4836:0120/132315.246:INFO:webrtc_voice_engine.cc(553)] NetEq capacity is 200 [2400:4836:0120/132315.246:INFO:webrtc_voice_engine.cc(559)] NetEq fast mode? 0 [2400:4836:0120/132315.246:INFO:webrtc_voice_engine.cc(565)] NetEq minimum delay is 0 [2400:4836:0120/132315.246:INFO:webrtc_voice_engine.cc(571)] NetEq handle reordered packets? 0 [2400:4836:0120/132315.246:INFO:webrtc_voice_engine.cc(579)] No audio processing module present. No software-provided effects (AEC, NS, AGC, ...) are activated [2400:4836:0120/132315.246:INFO:webrtc_voice_engine.cc(1576)] Set voice channel options. Current options: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [2400:4836:0120/132315.246:INFO:channel.cc(818)] Changing voice state, recv=1 send=1 for {mid: 0, media_type: audio} [2400:1940:0120/132315.246:INFO:peer_connection.cc(2131)] Non-rejected SCTP m= section is needed to get the SSL Role of the SCTP transport. [2400:5796:0120/132315.246:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1674, ms since last received data=16, rtt=100, pings_since_last_response= [2400:1940:0120/132315.246:INFO:sdp_offer_answer.cc(1693)] Processing the MSIDs for MID=0 (streams=[]). [2400:5796:0120/132315.246:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12643090, ms since last received data=12643090, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 3 more [2400:1940:0120/132315.246:INFO:rtp_transceiver.cc(313)] Changing transceiver (MID=0) current direction from kSendRecv to kSendRecv. [2400:5796:0120/132315.246:VERBOSE1:basic_ice_controller.cc(541)] Sorting 2 available connections [2400:5796:0120/132315.246:VERBOSE1:basic_ice_controller.cc(544)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30] [2400:5796:0120/132315.246:VERBOSE1:basic_ice_controller.cc(544)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-] [2400:5796:0120/132315.246:VERBOSE1:p2p_transport_channel.cc(470)] Channel[0|1|RW]: Ice not completed yet for this channel as Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1] has more than 1 connection. [2400:5796:0120/132315.246:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1674, ms since last received data=16, rtt=100, pings_since_last_response= [2400:5796:0120/132315.246:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12643090, ms since last received data=12643090, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 3 more [2400:5796:0120/132315.246:VERBOSE1:basic_ice_controller.cc(541)] Sorting 2 available connections [2400:5796:0120/132315.246:VERBOSE1:basic_ice_controller.cc(544)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30] [2400:5796:0120/132315.246:VERBOSE1:basic_ice_controller.cc(544)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-] [2400:5796:0120/132315.246:VERBOSE1:p2p_transport_channel.cc(470)] Channel[0|1|RW]: Ice not completed yet for this channel as Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1] has more than 1 connection. [2400:5796:0120/132315.246:WARNING:peer_connection.cc(2611)] Candidate has an unknown component: Cand[:Hac1f1491:2:udp:2130706430:172.31.20.x:11401:local::0:21a2c3bd49d08e124ada32201107ca7c:5eb66a7f56b9e70b08ccf29b0af93191:0:0:0] for mid 0 [2400:5796:0120/132315.246:WARNING:peer_connection.cc(2611)] Candidate has an unknown component: Cand[:S37c1a91:2:udp:1694498814:3.124.26.x:11401:stun:172.31.20.145:11401:21a2c3bd49d08e124ada32201107ca7c:5eb66a7f56b9e70b08ccf29b0af93191:0:0:0] for mid 0 [2400:5796:0120/132315.249:INFO:rtp_demuxer.cc(418)] Added sink = 00007C2603DD9048 binding with SSRC=190465548 [2400:4836:0120/132315.249:ERROR:call.cc(1568)] receive_rtp_config_ lookup failed for ssrc 190465548 [2400:4836:0120/132315.249:INFO:webrtc_voice_engine.cc(2239)] Creating unsignaled receive stream for SSRC=190465548 [2400:4836:0120/132315.249:INFO:webrtc_voice_engine.cc(1977)] AddRecvStream: {ssrcs:[190465548];ssrc_groups:;stream_ids:;} [2400:4836:0120/132315.249:INFO:delay_manager.cc(54)] Delay manager config: quantile=0.95 forget_factor=0.983 start_forget_weight=2 resample_interval_ms=500 max_history_ms=2000 use_reorder_optimizer=1 reorder_forget_factor=0.9993 ms_per_loss_percent=20 [2400:4836:0120/132315.249:INFO:decision_logic.cc(70)] NetEq decision logic settings: estimate_dtx_delay=1 time_stretch_cn=1 target_level_window_ms=100 [2400:4836:0120/132315.249:INFO:neteq_impl.cc(161)] NetEq config: sample_rate_hz=16000, enable_post_decode_vad=true, max_packets_in_buffer=200, min_delay_ms=0, enable_fast_accelerate=false, enable_muted_state=true, enable_rtx_handling=false, extra_output_delay_ms=0 [2400:4836:0120/132315.249:VERBOSE1:neteq_impl.cc(2120)] SetSampleRateAndChannels 16000 1 [2400:4836:0120/132315.249:VERBOSE1:neteq_impl.cc(503)] FlushBuffers [2400:4836:0120/132315.249:INFO:audio_receive_stream.cc(120)] AudioReceiveStream: 190465548 [2400:4836:0120/132315.249:INFO:rtp_demuxer.cc(145)] Added sink = 00007C26041EEA00 for criteria {mid: , rsid: , ssrcs: [190465548, ], payload_types = []} [2400:4836:0120/132315.249:VERBOSE1:call.cc(1354)] UpdateAggregateNetworkState: aggregate_state remains at up [2400:4836:0120/132315.249:VERBOSE1:rtp_transport_controller_send.cc(370)] SignalNetworkState Up [2400:4836:0120/132315.249:INFO:webrtc_voice_engine.cc(2088)] WRVMC::SetOutputVolume({ssrc=190465548}, {volume=1.00}) [2400:4836:0120/132315.249:INFO:webrtc_voice_engine.cc(2098)] WRVMC::SetOutputVolume => (stream with SSRC 190465548 now uses volume 1.00) [2400:4836:0120/132315.249:INFO:webrtc_voice_engine.cc(2137)] SetBaseMinimumPlayoutDelayMs() to 0 for recv stream with ssrc 190465548 [2400:4836:0120/132315.249:VERBOSE1:webrtc_voice_engine.cc(2502)] WebRtcVoiceMediaChannel::SetRawAudioSink: ssrc:190465548 (ptr) [2400:4836:0120/132315.250:VERBOSE1:neteq_impl.cc(2120)] SetSampleRateAndChannels 8000 1 [2400:3924:0120/132315.256:VERBOSE1:webrtc_logging.cc(32)] MSAS::SetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 16000, frames_per_buffer: 160, effects: 0, mic_positions: ]}, {old_params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 8000, frames_per_buffer: 80, effects: 0, mic_positions: ]}) [this=0x7C2603DC7000] [2400:3924:0120/132315.256:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnSetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 16000, frames_per_buffer: 160, effects: 0, mic_positions: ]}) [this=0x7C2603DC7138] [2400:3924:0120/132315.256:VERBOSE1:webrtc_logging.cc(32)] MSAT::OnSetFormat({params: [format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 16000, frames_per_buffer: 160, effects: 0, mic_positions: ]}) [this=0x7C2602CF6B80] [2400:3924:0120/132315.256:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnSetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 16000, frames_per_buffer: 160, effects: 0, mic_positions: ]}) [this=0x7C2602CF6B98] [2400:3924:0120/132315.256:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnData => (number of active consumers=1) [this=0x7C2603DC7138] [2400:3924:0120/132315.266:VERBOSE1:webrtc_logging.cc(32)] MSAS::SetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 8000, frames_per_buffer: 80, effects: 0, mic_positions: ]}, {old_params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 16000, frames_per_buffer: 160, effects: 0, mic_positions: ]}) [this=0x7C2603DC7000] [2400:3924:0120/132315.266:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnSetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 8000, frames_per_buffer: 80, effects: 0, mic_positions: ]}) [this=0x7C2603DC7138] [2400:3924:0120/132315.266:VERBOSE1:webrtc_logging.cc(32)] MSAT::OnSetFormat({params: [format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 8000, frames_per_buffer: 80, effects: 0, mic_positions: ]}) [this=0x7C2602CF6B80] [2400:3924:0120/132315.266:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnSetFormat({params=[format: PCM_LOW_LATENCY, channel_layout: 2, channels: 1, sample_rate: 8000, frames_per_buffer: 80, effects: 0, mic_positions: ]}) [this=0x7C2602CF6B98] [2400:3924:0120/132315.266:VERBOSE1:webrtc_logging.cc(32)] MSAD::OnData => (number of active consumers=1) [this=0x7C2603DC7138] [2400:5796:0120/132315.305:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1732, ms since last received data=14, rtt=100, pings_since_last_response= [2400:5796:0120/132315.305:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12643148, ms since last received data=12643148, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 3 more [2400:5796:0120/132315.556:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1983, ms since last received data=7, rtt=100, pings_since_last_response= [2400:5796:0120/132315.556:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12643399, ms since last received data=12643399, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 3 more [2400:5796:0120/132315.556:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sending STUN ping, id=5a6171427436656575483675, nomination=0 [2400:5796:0120/132315.556:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=5a6171427436656575483675, use_candidate=1, nomination=0 [2400:5796:0120/132315.807:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=2234, ms since last received data=18, rtt=100, pings_since_last_response= [2400:5796:0120/132315.807:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12643650, ms since last received data=12643650, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 4 more [2400:5796:0120/132316.058:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=2485, ms since last received data=9, rtt=100, pings_since_last_response= [2400:5796:0120/132316.058:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12643901, ms since last received data=12643901, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 4 more [2400:5796:0120/132316.058:VERBOSE1:connection.cc(852)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: Sending STUN ping, id=6a6c6f365a79686b49414436, nomination=0 [2400:5796:0120/132316.058:VERBOSE1:connection.cc(1165)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: Sent STUN BINDING request, id=6a6c6f365a79686b49414436, use_candidate=1, nomination=0 [2400:5796:0120/132316.088:VERBOSE1:connection.cc(1082)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWI|S|0|0|7241540809789538302|30]: Received STUN BINDING response, id=6a6c6f365a79686b49414436, code=0, rtt=31, pings_since_last_response=6a6c6f365a79686b49414436 [2400:5796:0120/132316.088:VERBOSE1:connection.cc(398)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: set_state [2400:12748:0120/132316.257:VERBOSE1:webrtc_logging.cc(32)] WRAR::CheckAlive => (stream is alive) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:5796:0120/132316.308:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=220, ms since last received data=0, rtt=100, pings_since_last_response= [2400:5796:0120/132316.308:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12644152, ms since last received data=12644152, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 4 more [2400:5796:0120/132316.560:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=471, ms since last received data=11, rtt=100, pings_since_last_response= [2400:5796:0120/132316.560:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12644403, ms since last received data=12644403, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 4 more [2400:5796:0120/132316.560:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sending STUN ping, id=5836546b7631534c51503047, nomination=0 [2400:5796:0120/132316.560:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=5836546b7631534c51503047, use_candidate=1, nomination=0 [2400:5796:0120/132316.811:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=722, ms since last received data=2, rtt=100, pings_since_last_response= [2400:5796:0120/132316.811:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12644654, ms since last received data=12644654, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 5 more [2400:5796:0120/132317.062:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=973, ms since last received data=13, rtt=100, pings_since_last_response= [2400:5796:0120/132317.062:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12644905, ms since last received data=12644905, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 5 more [2400:5796:0120/132317.062:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sending STUN ping, id=454a653371584e4b57644f73, nomination=0 [2400:5796:0120/132317.062:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=454a653371584e4b57644f73, use_candidate=1, nomination=0 [2400:5796:0120/132317.313:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1223, ms since last received data=3, rtt=100, pings_since_last_response= [2400:5796:0120/132317.313:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12645155, ms since last received data=12645155, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 6 more [2400:5796:0120/132317.564:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1475, ms since last received data=15, rtt=100, pings_since_last_response= [2400:5796:0120/132317.564:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12645407, ms since last received data=12645407, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 6 more [2400:5796:0120/132317.564:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sending STUN ping, id=59503964514b7470556c4873, nomination=0 [2400:5796:0120/132317.564:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=59503964514b7470556c4873, use_candidate=1, nomination=0 [2400:5796:0120/132317.814:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1725, ms since last received data=5, rtt=100, pings_since_last_response= [2400:5796:0120/132317.814:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12645657, ms since last received data=12645657, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 7 more [2400:5796:0120/132318.065:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1976, ms since last received data=15, rtt=100, pings_since_last_response= [2400:5796:0120/132318.065:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12645908, ms since last received data=12645908, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 7 more [2400:5796:0120/132318.065:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sending STUN ping, id=6f304e483646415030625742, nomination=0 [2400:5796:0120/132318.065:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=6f304e483646415030625742, use_candidate=1, nomination=0 [2400:5796:0120/132318.317:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=2228, ms since last received data=8, rtt=100, pings_since_last_response= [2400:5796:0120/132318.317:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12646160, ms since last received data=12646160, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 8 more [2400:5796:0120/132318.568:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=2479, ms since last received data=19, rtt=100, pings_since_last_response= [2400:5796:0120/132318.568:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12646411, ms since last received data=12646411, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 8 more [2400:5796:0120/132318.568:VERBOSE1:connection.cc(852)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: Sending STUN ping, id=795739617947467639557565, nomination=0 [2400:5796:0120/132318.568:VERBOSE1:connection.cc(1165)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: Sent STUN BINDING request, id=795739617947467639557565, use_candidate=1, nomination=0 [2400:5796:0120/132318.598:VERBOSE1:connection.cc(1082)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWI|S|0|0|7241540809789538302|30]: Received STUN BINDING response, id=795739617947467639557565, code=0, rtt=31, pings_since_last_response=795739617947467639557565 [2400:5796:0120/132318.598:VERBOSE1:connection.cc(398)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: set_state [2400:5796:0120/132318.820:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=221, ms since last received data=11, rtt=100, pings_since_last_response= [2400:5796:0120/132318.820:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12646663, ms since last received data=12646663, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 8 more [2400:5796:0120/132319.072:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=473, ms since last received data=3, rtt=100, pings_since_last_response= [2400:5796:0120/132319.072:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12646915, ms since last received data=12646915, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 8 more [2400:5796:0120/132319.072:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sending STUN ping, id=77417a514c31436d2f483379, nomination=0 [2400:5796:0120/132319.072:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=77417a514c31436d2f483379, use_candidate=1, nomination=0 [2400:5796:0120/132319.323:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=724, ms since last received data=14, rtt=100, pings_since_last_response= [2400:5796:0120/132319.323:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12647166, ms since last received data=12647166, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 9 more [2400:5796:0120/132319.573:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=975, ms since last received data=152, rtt=100, pings_since_last_response= [2400:5796:0120/132319.573:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12647417, ms since last received data=12647417, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 9 more [2400:5796:0120/132319.573:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sending STUN ping, id=474e394859667079494e712b, nomination=0 [2400:5796:0120/132319.573:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=474e394859667079494e712b, use_candidate=1, nomination=0 [2400:5796:0120/132319.824:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1225, ms since last received data=402, rtt=100, pings_since_last_response= [2400:5796:0120/132319.824:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12647667, ms since last received data=12647667, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 10 more [2400:5796:0120/132320.075:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1476, ms since last received data=653, rtt=100, pings_since_last_response= [2400:5796:0120/132320.075:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12647918, ms since last received data=12647918, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 10 more [2400:5796:0120/132320.075:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sending STUN ping, id=6e6f6c65446b2b474e612b69, nomination=0 [2400:5796:0120/132320.075:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=6e6f6c65446b2b474e612b69, use_candidate=1, nomination=0 [2400:5796:0120/132320.327:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1728, ms since last received data=905, rtt=100, pings_since_last_response= [2400:5796:0120/132320.327:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12648170, ms since last received data=12648170, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 11 more [2400:5796:0120/132320.578:VERBOSE1:connection.cc(790)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: UpdateState(), ms since last received response=1979, ms since last received data=1156, rtt=100, pings_since_last_response= [2400:5796:0120/132320.578:VERBOSE1:connection.cc(790)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: UpdateState(), ms since last received response=12648421, ms since last received data=12648421, rtt=6000, pings_since_last_response=5742616e6c6f356f6d385766 64524f66456246547833626f 5659734542306c644b324939 77544956797a796a4f656d67 41687a4c49752b3032716152 ... 11 more [2400:5796:0120/132320.578:VERBOSE1:connection.cc(852)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sending STUN ping, id=505a68564773717977573079, nomination=0 [2400:5796:0120/132320.578:INFO:connection.cc(1165)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Sent STUN BINDING request, id=505a68564773717977573079, use_candidate=1, nomination=0 [2400:1940:0120/132320.587:INFO:stats_collector.cc(453)] Assuming SSRC=190465548 is an unsignalled receive stream corresponding to the RtpReceiver with track ID "55119d03-6d83-4777-9c97-2baa1093e46b". [2400:1940:0120/132320.587:INFO:sdp_offer_answer.cc(2457)] Session: 1807224220773941525 Old state: stable New state: closed [2400:1940:0120/132320.587:VERBOSE1:webrtc_logging.cc(32)] WRAS::Adapter::RemoveSink([label=5fa7a763-1e35-4963-af00-6a7b46935b44]) [2400:4836:0120/132320.587:VERBOSE1:webrtc_logging.cc(32)] WRADI::StopRecording() [id=13F27BB5004BE349A2E91887CC0BF104] [2400:4836:0120/132320.587:INFO:webrtc_voice_engine.cc(2114)] SetDefaultOutputVolume() to 0 for recv stream with ssrc 820004207 [2400:4836:0120/132320.587:INFO:webrtc_voice_engine.cc(2114)] SetDefaultOutputVolume() to 0 for recv stream with ssrc 190465548 [2400:4836:0120/132320.587:INFO:channel.cc(522)] Channel disabled: {mid: 0, media_type: audio} [2400:4836:0120/132320.587:INFO:channel.cc(818)] Changing voice state, recv=0 send=0 for {mid: 0, media_type: audio} [2400:5796:0120/132320.587:INFO:rtp_demuxer.cc(241)] Removed sink = 00007C2603DD9048 bindings [2400:4836:0120/132320.587:VERBOSE1:webrtc_voice_engine.cc(1356)] WebRtcVoiceMediaChannel::~WebRtcVoiceMediaChannel [2400:4836:0120/132320.587:INFO:webrtc_voice_engine.cc(1951)] RemoveSendStream: 3080516117 [2400:4836:0120/132320.587:VERBOSE1:call.cc(1354)] UpdateAggregateNetworkState: aggregate_state remains at up [2400:4836:0120/132320.587:VERBOSE1:rtp_transport_controller_send.cc(370)] SignalNetworkState Up [2400:4836:0120/132320.587:INFO:audio_send_stream.cc(177)] ~AudioSendStream: 3080516117 [2400:4836:0120/132320.587:INFO:webrtc_voice_engine.cc(2028)] RemoveRecvStream: 190465548 [2400:4836:0120/132320.587:INFO:rtp_demuxer.cc(241)] Removed sink = 00007C26041EEA00 bindings [2400:4836:0120/132320.587:VERBOSE1:call.cc(1354)] UpdateAggregateNetworkState: aggregate_state remains at up [2400:4836:0120/132320.587:VERBOSE1:rtp_transport_controller_send.cc(370)] SignalNetworkState Up [2400:4836:0120/132320.587:INFO:audio_receive_stream.cc(149)] ~AudioReceiveStream: 190465548 [2400:4836:0120/132320.588:INFO:webrtc_voice_engine.cc(2028)] RemoveRecvStream: 820004207 [2400:4836:0120/132320.588:INFO:rtp_demuxer.cc(241)] Removed sink = 00007C26041F1C00 bindings [2400:4836:0120/132320.588:INFO:call.cc(1350)] UpdateAggregateNetworkState: aggregate_state change to down [2400:4836:0120/132320.588:VERBOSE1:rtp_transport_controller_send.cc(370)] SignalNetworkState Down [2400:4836:0120/132320.588:INFO:audio_receive_stream.cc(149)] ~AudioReceiveStream: 820004207 [2400:12244:0120/132320.588:INFO:control_handler.cc(78)] Bitrate estimate state changed, BWE: 300 kbps. [2400:8152:0120/132320.588:INFO:pacing_controller.cc(159)] PacedSender paused. [2400:5796:0120/132320.588:VERBOSE1:connection.cc(753)] Conn[22dd200:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:VTukI0E6:1:0:stun:udp:194.252.149.x:59742->kvaY3RL5:1:1694498815:stun:udp:3.124.26.x:11400|CRWS|S|0|0|7241540809789538302|30]: Connection destroyed [2400:5796:0120/132320.588:VERBOSE1:connection.cc(753)] Conn[22d1400:0:Net[{453CB6D2-0BAA-4220-A74A-42B6D1F325F7}:172.21.0.x/24:Ethernet:id=1]:+rDRPQBM:1:0:local:udp:172.21.0.x:59742->GVPf5GHo:1:2130706431:local:udp:172.31.20.x:11400|C--I|-|0|0|9115038255648079870|-]: Connection destroyed [2400:12748:0120/132320.589:VERBOSE1:webrtc_logging.cc(32)] MSS::SetReadyState({id=55119d03-6d83-4777-9c97-2baa1093e46b}, {ready_state=Muted}) [2400:12748:0120/132320.589:VERBOSE1:webrtc_logging.cc(32)] MST::setReadyState({ready_state=live}) [kind: audio, id: 55119d03-6d83-4777-9c97-2baa1093e46b, label: 55119d03-6d83-4777-9c97-2baa1093e46b, enabled: true, muted: false, readyState: live, remote=true] [2400:12748:0120/132320.589:VERBOSE1:webrtc_logging.cc(32)] MST::SourceChangedState() [kind: audio, id: 55119d03-6d83-4777-9c97-2baa1093e46b, label: 55119d03-6d83-4777-9c97-2baa1093e46b, enabled: true, muted: true, readyState: live, remote=true] [2400:12748:0120/132320.589:VERBOSE1:webrtc_logging.cc(32)] MST::stopTrack() [kind: audio, id: 5fa7a763-1e35-4963-af00-6a7b46935b44, label: Headset Microphone (2–Jabra EVOLVE LINK MS) (0b0e:0305), enabled: true, muted: false, readyState: live, remote=false] [2400:12748:0120/132320.589:VERBOSE1:webrtc_logging.cc(32)] MST::setReadyState({ready_state=ended}) [kind: audio, id: 5fa7a763-1e35-4963-af00-6a7b46935b44, label: Headset Microphone (2–Jabra EVOLVE LINK MS) (0b0e:0305), enabled: true, muted: false, readyState: ended, remote=false] [2400:12748:0120/132320.589:VERBOSE1:webrtc_logging.cc(32)] MSAT::StopAndNotify() [this=0x7C2602D54780] [2400:12748:0120/132320.589:VERBOSE1:webrtc_logging.cc(32)] MSAD::RemoveConsumer => (number of consumers: active=0, pending=0) [this=0x7C2602535F38] [2400:12748:0120/132320.589:VERBOSE1:webrtc_logging.cc(32)] MSAS::StopAudioDeliveryTo => (removed MediaStreamAudioTrack as consumer, total number of consumers=0) [this=0x7C2602535E00] [2400:12748:0120/132320.589:VERBOSE1:webrtc_logging.cc(32)] MSAS::StopAudioDeliveryTo => (last track removed, stopping source) [this=0x7C2602535E00] [2400:12748:0120/132320.589:VERBOSE1:webrtc_logging.cc(32)] MSAS::DoStopSource() [this=0x7C2602535E00] [2400:12748:0120/132320.589:VERBOSE1:webrtc_logging.cc(32)] UMP::OnLocalSourceStopped({session_id=EC451D84FD9C097639622DA8A9411FB6}) [2400:12748:0120/132320.589:VERBOSE1:webrtc_logging.cc(32)] UMP::RemoveLocalSource({id=9970d29b6dae05e3ed112ced3a6b9b6f444c2a5c26d20a381ace669087b8bd68}, {name=Headset Microphone (2–Jabra EVOLVE LINK MS) (0b0e:0305)}, {group_id=bacb133d7f60d8a66aa9017346365c48257baf677df119dcf8fde9f9fcbab8f4}) [2400:12748:0120/132320.589:VERBOSE1:webrtc_logging.cc(32)] MSS::SetReadyState({id=9970d29b6dae05e3ed112ced3a6b9b6f444c2a5c26d20a381ace669087b8bd68}, {ready_state=Ended}) [2400:12748:0120/132320.589:VERBOSE1:webrtc_logging.cc(32)] MSAD::RemoveConsumer => (number of consumers: active=0, pending=0) [this=0x7C2602D54798] [2400:12748:0120/132320.615:VERBOSE1:webrtc_logging.cc(32)] MSS::SetReadyState({id=55119d03-6d83-4777-9c97-2baa1093e46b}, {ready_state=Ended}) [2400:12748:0120/132320.615:VERBOSE1:webrtc_logging.cc(32)] MST::setReadyState({ready_state=ended}) [kind: audio, id: 55119d03-6d83-4777-9c97-2baa1093e46b, label: 55119d03-6d83-4777-9c97-2baa1093e46b, enabled: true, muted: true, readyState: ended, remote=true] [2400:12748:0120/132320.615:VERBOSE1:webrtc_logging.cc(32)] WMPMS::ActiveStateChanged({is_active=false}) [delegate_id=4] [2400:12748:0120/132320.615:VERBOSE1:webrtc_logging.cc(32)] WMPMS::SetNetworkState => (state=Loading) [delegate_id=4] [2400:12748:0120/132320.615:VERBOSE1:webrtc_logging.cc(32)] WRAR::EnterPauseState([state=PLAYING]) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132320.615:VERBOSE1:webrtc_logging.cc(32)] WRAR::EnterPauseState => (state=PAUSED) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132320.615:VERBOSE1:webrtc_logging.cc(32)] WRAR::UpdateSourceVolume => (source volume changed to 0.00) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132320.615:VERBOSE1:webrtc_logging.cc(32)] WRAR::Stop([state=PAUSED]) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132320.615:VERBOSE1:webrtc_logging.cc(32)] WRAR::~AudioStreamTracker => (media stream duration=9 seconds) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:1940:0120/132320.615:INFO:remote_audio_source.cc(115)] RAS::SetVolume({volume=0.00}) [2400:12748:0120/132320.615:VERBOSE1:webrtc_logging.cc(32)] WRAR::Stop => (max_render_time=0.549 ms) [label=2f064db2-c035-46a1-8eee-80474b78593c] [2400:12748:0120/132320.616:VERBOSE1:webrtc_logging.cc(32)] MST::SourceChangedState() [kind: audio, id: 55119d03-6d83-4777-9c97-2baa1093e46b, label: 55119d03-6d83-4777-9c97-2baa1093e46b, enabled: true, muted: true, readyState: ended, remote=true] [2400:12748:0120/132323.607:VERBOSE1:webrtc_logging.cc(32)] WMPMS::~WebMediaPlayerMS() [delegate_id=4] [delegate_id=4] [1992:12768:0120/132323.736:WARNING:sqlite_persistent_store_backend_base.cc(171)] Failed to post task from FlushAndNotifyInBackground@net/extras/sqlite/sqlite_persistent_store_backend_base.cc:219 to client_task_runner_. [12896:12396:0120/132323.858:WARNING:pref_notifier_impl.cc(40)] Pref observer for media_router.cast_allow_all_ips found at shutdown.