./momo --resolution VGA --fixed-resolution --log-level 1 --port 8082 test [000:000] [2319] (device_info_linux.cc:45): NumberOfDevices [000:245] [2319] (device_info_linux.cc:70): GetDeviceName [000:245] [2319] (v4l2_video_capturer.cpp:58): GetDeviceName(0): device_name=C922 Pro Stream Webcam, unique_name=usb-0000:01:00.0-1.3 [000:245] [2319] (device_info_linux.cc:70): GetDeviceName [000:245] [2319] (v4l2_video_capturer.cpp:58): GetDeviceName(1): device_name=C922 Pro Stream Webcam, unique_name=usb-0000:01:00.0-1.3 [000:245] [2319] (device_info_linux.cc:70): GetDeviceName [000:246] [2319] (v4l2_video_capturer.cpp:58): GetDeviceName(2): device_name=bcm2835-codec-decode, unique_name=platform:bcm2835-codec [000:246] [2319] (device_info_linux.cc:70): GetDeviceName [000:246] [2319] (v4l2_video_capturer.cpp:58): GetDeviceName(3): device_name=bcm2835-codec-encode, unique_name=platform:bcm2835-codec [000:246] [2319] (device_info_linux.cc:70): GetDeviceName [000:247] [2319] (v4l2_video_capturer.cpp:58): GetDeviceName(4): device_name=bcm2835-codec-isp, unique_name=platform:bcm2835-codec [000:247] [2319] (device_info_linux.cc:70): GetDeviceName [000:259] [2319] (v4l2_video_capturer.cpp:218): Video Capture enumerats supported image formats: [000:259] [2319] (v4l2_video_capturer.cpp:220): { pixelformat = YUYV, description = 'be8c5ad4' } [000:259] [2319] (v4l2_video_capturer.cpp:220): { pixelformat = MJPG, description = 'be8c5ad4' } [000:259] [2319] (v4l2_video_capturer.cpp:236): We prefer format YUYV [000:412] [2319] (v4l2_video_capturer.cpp:66): Get Capture [000:423] [2319] (audio_device_impl.cc:76): Create [000:423] [2319] (audio_device_impl.cc:84): CreateForTest [000:430] [2319] (audio_device_buffer.cc:65): AudioDeviceBuffer::ctor [000:430] [2319] (audio_device_impl.cc:122): AudioDeviceModuleImpl [000:430] [2319] (audio_device_impl.cc:126): CheckPlatform [000:430] [2319] (audio_device_impl.cc:137): current platform is Linux [000:430] [2319] (audio_device_impl.cc:156): CreatePlatformSpecificObjects [000:430] [2319] (audio_device_impl.cc:946): PlatformAudioLayer [000:430] [2319] (audio_device_impl.cc:266): PulseAudio support is enabled. [000:430] [2319] (audio_mixer_manager_alsa_linux.cc:30): AudioMixerManagerLinuxALSA created [000:431] [2319] (audio_device_alsa_linux.cc:101): AudioDeviceLinuxALSA created [000:431] [2319] (audio_device_impl.cc:274): Linux ALSA APIs will be utilized. [000:431] [2319] (audio_device_impl.cc:311): AttachAudioBuffer [000:431] [2319] (audio_device_buffer.cc:181): SetRecordingSampleRate(0) [000:431] [2319] (audio_device_buffer.cc:187): SetPlayoutSampleRate(0) [000:431] [2319] (audio_device_buffer.cc:201): SetRecordingChannels(0) [000:431] [2319] (audio_device_buffer.cc:207): SetPlayoutChannels(0) [000:442] [2319] (audio_processing_impl.cc:358): Injected APM submodules: Echo control factory: 0 Echo detector: 0 Capture analyzer: 0 Capture post processor: 0 Render pre processor: 0 [000:457] [2319] (agc_manager_direct.cc:69): [agc] GetMinMicLevel [000:457] [2319] (agc_manager_direct.cc:73): [agc] Using default min mic level: 12 [000:464] [2319] (webrtc_voice_engine.cc:205): WebRtcVoiceEngine::WebRtcVoiceEngine [000:464] [2319] (webrtc_video_engine.cc:469): WebRtcVideoEngine::WebRtcVideoEngine() [000:474] [2322] (webrtc_voice_engine.cc:228): WebRtcVoiceEngine::Init [000:474] [2322] (webrtc_voice_engine.cc:236): Supported send codecs in order of preference: [000:483] [2322] (webrtc_voice_engine.cc:239): opus/48000/2 { minptime=10 useinbandfec=1 } (111) [000:483] [2322] (webrtc_voice_engine.cc:239): ISAC/16000/1 (103) [000:483] [2322] (webrtc_voice_engine.cc:239): G722/8000/1 (9) [000:483] [2322] (webrtc_voice_engine.cc:239): ILBC/8000/1 (102) [000:483] [2322] (webrtc_voice_engine.cc:239): PCMU/8000/1 (0) [000:483] [2322] (webrtc_voice_engine.cc:239): PCMA/8000/1 (8) [000:483] [2322] (webrtc_voice_engine.cc:239): CN/16000/1 (105) [000:483] [2322] (webrtc_voice_engine.cc:239): CN/8000/1 (13) [000:483] [2322] (webrtc_voice_engine.cc:239): telephone-event/48000/1 (110) [000:483] [2322] (webrtc_voice_engine.cc:239): telephone-event/16000/1 (113) [000:483] [2322] (webrtc_voice_engine.cc:239): telephone-event/8000/1 (126) [000:483] [2322] (webrtc_voice_engine.cc:242): Supported recv codecs in order of preference: [000:484] [2322] (webrtc_voice_engine.cc:245): opus/48000/2 { minptime=10 useinbandfec=1 } (111) [000:484] [2322] (webrtc_voice_engine.cc:245): ISAC/16000/1 (103) [000:484] [2322] (webrtc_voice_engine.cc:245): G722/8000/1 (9) [000:484] [2322] (webrtc_voice_engine.cc:245): ILBC/8000/1 (102) [000:484] [2322] (webrtc_voice_engine.cc:245): PCMU/8000/1 (0) [000:484] [2322] (webrtc_voice_engine.cc:245): PCMA/8000/1 (8) [000:484] [2322] (webrtc_voice_engine.cc:245): CN/16000/1 (105) [000:484] [2322] (webrtc_voice_engine.cc:245): CN/8000/1 (13) [000:484] [2322] (webrtc_voice_engine.cc:245): telephone-event/48000/1 (110) [000:484] [2322] (webrtc_voice_engine.cc:245): telephone-event/16000/1 (113) [000:484] [2322] (webrtc_voice_engine.cc:245): telephone-event/8000/1 (126) [000:484] [2322] (audio_device_impl.cc:331): Init [000:484] [2322] (audio_device_alsa_linux.cc:161): failed to open X display, typing detection will not work [000:484] [2322] (audio_device_impl.cc:675): SetPlayoutDevice(0) [000:528] [2322] (audio_device_impl.cc:364): InitSpeaker [000:545] [2322] (audio_mixer_manager_alsa_linux.cc:942): Could not find output volume in the mixer. [000:545] [2322] (adm_helpers.cc:48): Unable to access speaker. [000:545] [2322] (audio_device_impl.cc:579): StereoPlayoutIsAvailable [000:562] [2322] (audio_mixer_manager_alsa_linux.cc:942): Could not find output volume in the mixer. [000:562] [2322] (audio_device_alsa_linux.cc:764): InitSpeaker() failed ALSA lib pcm_dmix.c:1108:(snd_pcm_dmix_open) unable to open slave [000:589] [2322] (audio_device_alsa_linux.cc:801): unable to open playback device: No such file or directory (-2) [000:589] [2322] (audio_device_impl.cc:586): output: 0 [000:589] [2322] (audio_device_impl.cc:591): SetStereoPlayout(0) [000:589] [2322] (audio_device_buffer.cc:207): SetPlayoutChannels(1) [000:589] [2322] (audio_device_impl.cc:737): SetRecordingDevice(0) [000:627] [2322] (audio_device_impl.cc:370): InitMicrophone [000:644] [2322] (audio_device_impl.cc:533): StereoRecordingIsAvailable [000:742] [2322] (audio_device_buffer.cc:181): SetRecordingSampleRate(48000) [000:742] [2322] (audio_device_buffer.cc:201): SetRecordingChannels(2) [000:754] [2322] (audio_device_impl.cc:540): output: 1 [000:754] [2322] (audio_device_impl.cc:545): SetStereoRecording(1) [000:754] [2322] (audio_device_buffer.cc:201): SetRecordingChannels(2) [000:755] [2322] (audio_device_impl.cc:850): RegisterAudioCallback [000:755] [2322] (audio_device_buffer.cc:82): RegisterAudioCallback [000:755] [2322] (webrtc_voice_engine.cc:315): 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, } [000:755] [2322] (audio_device_impl.cc:866): BuiltInAECIsAvailable [000:755] [2322] (audio_device_generic.cc:18): BuiltInAECIsAvailable: Not supported on this platform [000:755] [2322] (audio_device_impl.cc:869): output: 0 [000:755] [2322] (audio_device_impl.cc:882): BuiltInAGCIsAvailable [000:755] [2322] (audio_device_generic.cc:28): BuiltInAGCIsAvailable: Not supported on this platform [000:755] [2322] (audio_device_impl.cc:885): output: 0 [000:755] [2322] (audio_device_impl.cc:898): BuiltInNSIsAvailable [000:755] [2322] (audio_device_generic.cc:38): BuiltInNSIsAvailable: Not supported on this platform [000:755] [2322] (audio_device_impl.cc:901): output: 0 [000:755] [2322] (webrtc_voice_engine.cc:431): Stereo swapping enabled? 0 [000:755] [2322] (webrtc_voice_engine.cc:436): NetEq capacity is 200 [000:755] [2322] (webrtc_voice_engine.cc:442): NetEq fast mode? 0 [000:755] [2322] (webrtc_voice_engine.cc:448): NetEq minimum delay is 0 [000:755] [2322] (webrtc_voice_engine.cc:454): NetEq handle reordered packets? 0 [000:756] [2322] (webrtc_voice_engine.cc:466): Experimental ns is enabled? 0 [000:756] [2322] (webrtc_voice_engine.cc:518): NS set to 1 [000:756] [2322] (webrtc_voice_engine.cc:522): Typing detection is enabled? 1 [000:756] [2322] (audio_processing_impl.cc:627): AudioProcessing::ApplyConfig: AudioProcessing::Config{ pipeline: {maximum_internal_processing_rate: 32000, multi_channel_render: 0, , multi_channel_capture: 0}, pre_amplifier: { enabled: 0, fixed_gain_factor: 1 }, high_pass_filter: { enabled: 1 }, echo_canceller: { enabled: 1, mobile_mode: 0, legacy_moderate_suppression_level: 0, use_legacy_aec: 0 }, noise_suppression: { enabled: 1, level: High }, voice_detection: { enabled: 1 }, gain_controller1: { enabled: 1, mode: AdaptiveAnalog, target_level_dbfs: 3, compression_gain_db: 9, enable_limiter: 1, analog_level_minimum: 0, analog_level_maximum: 255 }, gain_controller2: { enabled: 0, fixed_digital: { gain_db: 0 }, adaptive_digital: { enabled: 0, level_estimator: Rms, use_saturation_protector: 1, extra_saturation_margin_db: 2 } }, residual_echo_detector: { enabled: 1 }, level_estimation: { enabled: 0 } } [000:757] [2322] (render_delay_buffer.cc:362): Applying total delay of 5 blocks. [000:757] [2322] (matched_filter.cc:450): Filter 0: start: 0 ms, end: 128 ms. [000:757] [2322] (matched_filter.cc:450): Filter 1: start: 96 ms, end: 224 ms. [000:757] [2322] (matched_filter.cc:450): Filter 2: start: 192 ms, end: 320 ms. [000:757] [2322] (matched_filter.cc:450): Filter 3: start: 288 ms, end: 416 ms. [000:757] [2322] (matched_filter.cc:450): Filter 4: start: 384 ms, end: 512 ms. [000:759] [2322] (message_queue.cc:518): Message took 285ms to dispatch. Posted from: Init@../../../_source/raspbian-buster_armv7/webrtc/src/pc/channel_manager.cc:135 [000:759] [2323] (message_queue.cc:518): Message took 292ms to dispatch. Posted from: CreateModularPeerConnectionFactory@../../../_source/raspbian-buster_armv7/webrtc/src/pc/peer_connection_factory.cc:59 [000:759] [2319] (manager.cpp:151): RTCManager: AudioOptions {} [012:291] [2319] (p2p_websocket_session.cpp:15): P2PWebsocketSession [012:299] [2319] (p2p_websocket_session.cpp:33): run [012:299] [2319] (p2p_websocket_session.cpp:39): doAccept [012:300] [2319] (p2p_websocket_session.cpp:49): onAccept: system:0 [012:300] [2319] (websocket.cpp:49): doRead [017:834] [2319] (websocket.cpp:69): onRead: Success [017:834] [2319] (p2p_websocket_session.cpp:63): onRead: system:0 [017:834] [2319] (p2p_websocket_session.cpp:75): onRead: recv_string={"type":"offer","sdp":"v=0\r\no=- 6374940865554541006 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0 1 2\r\na=msid-semantic: WMS\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 114 115 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:jfho\r\na=ice-pwd:IwePzL5Vrr+2rszCCnRJKlAv\r\na=ice-options:trickle\r\na=fingerprint:sha-256 BA:34:33:D7:C7:42:E6:CA:75:C8:52:DB:F3:93:96:E9:73:BD:67:BA:6A:BB:DC:AC:7D:F2:34:72:62:B5:4B:22\r\na=setup:actpass\r\na=mid:0\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07\r\na=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:114 red/90000\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:116 ulpfec/90000\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:jfho\r\na=ice-pwd:IwePzL5Vrr+2rszCCnRJKlAv\r\na=ice-options:trickle\r\na=fingerprint:sha-256 BA:34:33:D7:C7:42:E6:CA:75:C8:52:DB:F3:93:96:E9:73:BD:67:BA:6A:BB:DC:AC:7D:F2:34:72:62:B5:4B:22\r\na=setup:actpass\r\na=mid:1\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\nm=application 9 UDP/DTLS/SCTP webrtc-datachannel\r\nc=IN IP4 0.0.0.0\r\na=ice-ufrag:jfho\r\na=ice-pwd:IwePzL5Vrr+2rszCCnRJKlAv\r\na=ice-options:trickle\r\na=fingerprint:sha-256 BA:34:33:D7:C7:42:E6:CA:75:C8:52:DB:F3:93:96:E9:73:BD:67:BA:6A:BB:DC:AC:7D:F2:34:72:62:B5:4B:22\r\na=setup:actpass\r\na=mid:2\r\na=sctp-port:5000\r\na=max-message-size:262144\r\n"} [017:836] [2322] (rtc_event_log_impl.cc:43): Creating legacy encoder for RTC event log. [017:884] [2322] (peer_connection_factory.cc:389): Using default network controller factory [017:884] [2322] (bitrate_prober.cc:69): Bandwidth probing enabled, set to inactive [017:885] [2322] (paced_sender.cc:167): ProcessThreadAttached 0xb3b3d930 [017:885] [2322] (cpu_info.cc:53): Available number of cores: 4 [017:885] [2322] (aimd_rate_control.cc:114): Using aimd rate control with back off factor 0.85 [017:885] [2322] (remote_bitrate_estimator_single_stream.cc:72): RemoteBitrateEstimatorSingleStream: Instantiating. [017:885] [2322] (remote_estimator_proxy.cc:50): Maximum interval between transport feedback RTCP messages (ms): 250 [017:891] [2323] (message_queue.cc:518): Message took 56ms to dispatch. Posted from: CreatePeerConnection@../../../_source/raspbian-buster_armv7/webrtc/src/api/peer_connection_factory_proxy.h:34 [017:891] [2321] (openssl_identity.cc:43): Making key pair [017:891] [2323] (peer_connection.cc:1618): Adding audio transceiver in response to a call to AddTrack. [017:894] [2323] (peer_connection.cc:1618): Adding video transceiver in response to a call to AddTrack. [017:894] [2319] (webrtc_sdp.cc:3310): Ignored line: a=rtcp:9 IN IP4 0.0.0.0 [017:895] [2319] (webrtc_sdp.cc:3310): Ignored line: a=rtcp:9 IN IP4 0.0.0.0 [017:896] [2321] (openssl_identity.cc:91): Returning key pair [017:896] [2321] (openssl_certificate.cc:59): Making certificate for WebRTC [017:909] [2321] (openssl_certificate.cc:109): Returning certificate [017:914] [2321] (p2p_transport_channel.cc:482): Set backup connection ping interval to 25000 milliseconds. [017:914] [2321] (p2p_transport_channel.cc:491): Set ICE receiving timeout to 2500 milliseconds [017:914] [2321] (p2p_transport_channel.cc:498): Set ping most likely connection to 0 [017:914] [2321] (p2p_transport_channel.cc:505): Set stable_writable_connection_ping_interval to 2500 [017:914] [2321] (p2p_transport_channel.cc:518): Set presume writable when fully relayed to 0 [017:914] [2321] (p2p_transport_channel.cc:536): Set regather_on_failed_networks_interval to 300000 [017:914] [2321] (p2p_transport_channel.cc:555): Set receiving_switching_delay to 1000 [017:914] [2321] (jsep_transport_controller.cc:1200): Creating DtlsSrtpTransport. [017:916] [2321] (dtls_srtp_transport.cc:62): Setting RTCP Transport on 0 transport 0 [017:916] [2321] (dtls_srtp_transport.cc:67): Setting RTP Transport on 0 transport b4802cd8 [017:919] [2321] (p2p_transport_channel.cc:482): Set backup connection ping interval to 25000 milliseconds. [017:919] [2321] (p2p_transport_channel.cc:491): Set ICE receiving timeout to 2500 milliseconds [017:919] [2321] (p2p_transport_channel.cc:498): Set ping most likely connection to 0 [017:919] [2321] (p2p_transport_channel.cc:505): Set stable_writable_connection_ping_interval to 2500 [017:919] [2321] (p2p_transport_channel.cc:518): Set presume writable when fully relayed to 0 [017:919] [2321] (p2p_transport_channel.cc:536): Set regather_on_failed_networks_interval to 300000 [017:919] [2321] (p2p_transport_channel.cc:555): Set receiving_switching_delay to 1000 [017:919] [2321] (jsep_transport_controller.cc:1200): Creating DtlsSrtpTransport. [017:919] [2321] (dtls_srtp_transport.cc:62): Setting RTCP Transport on 1 transport 0 [017:919] [2321] (dtls_srtp_transport.cc:67): Setting RTP Transport on 1 transport b48045f8 [017:919] [2321] (p2p_transport_channel.cc:482): Set backup connection ping interval to 25000 milliseconds. [017:919] [2321] (p2p_transport_channel.cc:491): Set ICE receiving timeout to 2500 milliseconds [017:919] [2321] (p2p_transport_channel.cc:498): Set ping most likely connection to 0 [017:919] [2321] (p2p_transport_channel.cc:505): Set stable_writable_connection_ping_interval to 2500 [017:919] [2321] (p2p_transport_channel.cc:518): Set presume writable when fully relayed to 0 [017:919] [2321] (p2p_transport_channel.cc:536): Set regather_on_failed_networks_interval to 300000 [017:919] [2321] (p2p_transport_channel.cc:555): Set receiving_switching_delay to 1000 [017:919] [2321] (jsep_transport_controller.cc:1200): Creating DtlsSrtpTransport. [017:919] [2321] (dtls_srtp_transport.cc:62): Setting RTCP Transport on 2 transport 0 [017:919] [2321] (dtls_srtp_transport.cc:67): Setting RTP Transport on 2 transport b4805e90 [017:920] [2321] (p2p_transport_channel.cc:427): Received remote ICE parameters: ufrag=jfho, renomination disabled [017:920] [2321] (p2p_transport_channel.cc:427): Received remote ICE parameters: ufrag=jfho, renomination disabled [017:920] [2321] (p2p_transport_channel.cc:427): Received remote ICE parameters: ufrag=jfho, renomination disabled [017:920] [2322] (webrtc_video_engine.cc:482): CreateMediaChannel. Options: VideoOptions {} [017:920] [2322] (channel.cc:146): Created channel for 0 [017:921] [2322] (call.cc:1047): UpdateAggregateNetworkState: aggregate_state=down [017:921] [2322] (rtp_transport_controller_send.cc:305): SignalNetworkState Down [017:922] [2322] (webrtc_voice_engine.cc:1461): 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, } [017:922] [2322] (webrtc_voice_engine.cc:315): 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, } [017:922] [2322] (webrtc_voice_engine.cc:436): NetEq capacity is 200 [017:922] [2322] (webrtc_voice_engine.cc:442): NetEq fast mode? 0 [017:922] [2322] (webrtc_voice_engine.cc:448): NetEq minimum delay is 0 [017:922] [2322] (webrtc_voice_engine.cc:454): NetEq handle reordered packets? 0 [017:922] [2322] (webrtc_voice_engine.cc:466): Experimental ns is enabled? 0 [017:922] [2322] (audio_processing_impl.cc:627): AudioProcessing::ApplyConfig: AudioProcessing::Config{ pipeline: {maximum_internal_processing_rate: 32000, multi_channel_render: 0, , multi_channel_capture: 0}, pre_amplifier: { enabled: 0, fixed_gain_factor: 1 }, high_pass_filter: { enabled: 1 }, echo_canceller: { enabled: 1, mobile_mode: 0, legacy_moderate_suppression_level: 0, use_legacy_aec: 0 }, noise_suppression: { enabled: 1, level: High }, voice_detection: { enabled: 1 }, gain_controller1: { enabled: 1, mode: AdaptiveAnalog, target_level_dbfs: 3, compression_gain_db: 9, enable_limiter: 1, analog_level_minimum: 0, analog_level_maximum: 255 }, gain_controller2: { enabled: 0, fixed_digital: { gain_db: 0 }, adaptive_digital: { enabled: 0, level_estimator: Rms, use_saturation_protector: 1, extra_saturation_margin_db: 2 } }, residual_echo_detector: { enabled: 1 }, level_estimation: { enabled: 0 } } [017:923] [2322] (webrtc_voice_engine.cc:1479): 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, } [017:923] [2322] (channel.cc:146): Created channel for 1 [017:924] [2322] (call.cc:1047): UpdateAggregateNetworkState: aggregate_state=down [017:924] [2322] (rtp_transport_controller_send.cc:305): SignalNetworkState Down [017:924] [2323] (peer_connection.cc:3601): Creating data channel, mid=2 [017:924] [2321] (peer_connection.cc:6704): Setting up data channel transport for mid=2 [017:924] [2323] (peer_connection.cc:4781): Session: 8988880037952262839 Old state: kStable New state: kHaveRemoteOffer [017:925] [2322] (channel.cc:880): Setting remote voice description [017:925] [2322] (webrtc_voice_engine.cc:1281): WebRtcVoiceMediaChannel::SetSendParameters: {codecs: [AudioCodec[111:opus: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}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 6}], extmap-allow-mixed: false, max_bandwidth_bps: -1, mid: 1, options: AudioOptions {}} [017:925] [2322] (webrtc_voice_engine.cc:1678): Recreate all the receive streams because the send codec has changed. [017:926] [2322] (webrtc_voice_engine.cc:2132): WebRtcVoiceMediaChannel::SetMaxSendBitrate. [017:926] [2322] (webrtc_voice_engine.cc:1461): Setting voice channel options: AudioOptions {} [017:926] [2322] (webrtc_voice_engine.cc:315): 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, } [017:926] [2322] (webrtc_voice_engine.cc:436): NetEq capacity is 200 [017:926] [2322] (webrtc_voice_engine.cc:442): NetEq fast mode? 0 [017:926] [2322] (webrtc_voice_engine.cc:448): NetEq minimum delay is 0 [017:926] [2322] (webrtc_voice_engine.cc:454): NetEq handle reordered packets? 0 [017:926] [2322] (webrtc_voice_engine.cc:466): Experimental ns is enabled? 0 [017:926] [2322] (audio_processing_impl.cc:627): AudioProcessing::ApplyConfig: AudioProcessing::Config{ pipeline: {maximum_internal_processing_rate: 32000, multi_channel_render: 0, , multi_channel_capture: 0}, pre_amplifier: { enabled: 0, fixed_gain_factor: 1 }, high_pass_filter: { enabled: 1 }, echo_canceller: { enabled: 1, mobile_mode: 0, legacy_moderate_suppression_level: 0, use_legacy_aec: 0 }, noise_suppression: { enabled: 1, level: High }, voice_detection: { enabled: 1 }, gain_controller1: { enabled: 1, mode: AdaptiveAnalog, target_level_dbfs: 3, compression_gain_db: 9, enable_limiter: 1, analog_level_minimum: 0, analog_level_maximum: 255 }, gain_controller2: { enabled: 0, fixed_digital: { gain_db: 0 }, adaptive_digital: { enabled: 0, level_estimator: Rms, use_saturation_protector: 1, extra_saturation_margin_db: 2 } }, residual_echo_detector: { enabled: 1 }, level_estimation: { enabled: 0 } } [017:927] [2322] (webrtc_voice_engine.cc:1479): 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, } [017:927] [2322] (channel.cc:817): Changing voice state, recv=0 send=0 [017:927] [2322] (channel.cc:1059): Setting remote video description [017:928] [2322] (webrtc_video_engine.cc:706): SetSendParameters: {codecs: [VideoCodec[96:VP8], VideoCodec[97:rtx], VideoCodec[114:red], VideoCodec[115:rtx], VideoCodec[116:ulpfec]], conference_mode: no, extensions: [{uri: urn:ietf:params:rtp-hdrext:toffset, id: 14}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 2}, {uri: urn:3gpp:video-orientation, id: 13}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 3}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 12}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 4}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 6}], extmap-allow-mixed: false, max_bandwidth_bps: -1, mid: 0} [017:928] [2322] (webrtc_video_engine.cc:651): WebRTC-FlexFEC-03 field trial is not enabled. [017:928] [2322] (webrtc_video_engine.cc:714): Negotiated codec: VideoCodec[96:VP8] [017:928] [2322] (webrtc_video_engine.cc:846): SetFeedbackOptions on all the receive streams because the send codec or RTCP mode has changed. [017:929] [2322] (channel.cc:963): Changing video state, send=0 [017:929] [2323] (peer_connection.cc:5927): Local and Remote descriptions must be applied to get the SSL Role of the SCTP transport. [017:929] [2319] (websocket.cpp:49): doRead [017:929] [2323] (observer.cpp:104): Set local description success! [017:929] [2323] (p2p_connection.cpp:54): onSetDescription SdpType: offer [017:930] [2323] (peer_connection.cc:5954): Local and Remote descriptions must be applied to get the SSL Role of the session. [017:930] [2323] (peer_connection.cc:5954): Local and Remote descriptions must be applied to get the SSL Role of the session. [017:930] [2323] (peer_connection.cc:5954): Local and Remote descriptions must be applied to get the SSL Role of the session. [017:931] [2323] (observer.cpp:90): Created session description : v=0 o=- 8988880037952262839 2 IN IP4 127.0.0.1 s=- t=0 0 a=group:BUNDLE 0 1 2 a=msid-semantic: WMS Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T m=video 9 UDP/TLS/RTP/SAVPF 96 97 114 115 116 c=IN IP4 0.0.0.0 a=rtcp:9 IN IP4 0.0.0.0 a=ice-ufrag:YBtH a=ice-pwd:obIYCSsyYarhj2HDTGeJbhiX a=ice-options:trickle a=fingerprint:sha-256 99:C6:6D:BA:A2:8A:A4:B3:19:6C:B5:D0:99:39:DF:8C:9B:93:8C:AF:88:BD:49:3C:21:D3:9E:9E:E8:6F:D1:B7 a=setup:active a=mid:0 a=extmap:14 urn:ietf:params:rtp-hdrext:toffset a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time a=extmap:13 urn:3gpp:video-orientation a=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay a=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type a=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing a=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07 a=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id a=sendonly a=msid:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T c64mYVHt2Pfc97Yy6NkgZG0I1YQg5daw a=rtcp-mux a=rtcp-rsize a=rtpmap:96 VP8/90000 a=rtcp-fb:96 goog-remb a=rtcp-fb:96 transport-cc a=rtcp-fb:96 ccm fir a=rtcp-fb:96 nack a=rtcp-fb:96 nack pli a=rtpmap:97 rtx/90000 a=fmtp:97 apt=96 a=rtpmap:114 red/90000 a=rtpmap:115 rtx/90000 a=fmtp:115 apt=114 a=rtpmap:116 ulpfec/90000 a=ssrc-group:FID 1366490776 211509755 a=ssrc:1366490776 cname:QVXt6J+XDjYF4pMe a=ssrc:1366490776 msid:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T c64mYVHt2Pfc97Yy6NkgZG0I1YQg5daw a=ssrc:1366490776 mslabel:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T a=ssrc:1366490776 label:c64mYVHt2Pfc97Yy6NkgZG0I1YQg5daw a=ssrc:211509755 cname:QVXt6J+XDjYF4pMe a=ssrc:211509755 msid:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T c64mYVHt2Pfc97Yy6NkgZG0I1YQg5daw a=ssrc:211509755 mslabel:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T a=ssrc:211509755 label:c64mYVHt2Pfc97Yy6NkgZG0I1YQg5daw m=audio 9 UDP/TLS/RTP/SAVPF 111 103 9 0 8 105 13 110 113 126 c=IN IP4 0.0.0.0 a=rtcp:9 IN IP4 0.0.0.0 a=ice-ufrag:YBtH a=ice-pwd:obIYCSsyYarhj2HDTGeJbhiX a=ice-options:trickle a=fingerprint:sha-256 99:C6:6D:BA:A2:8A:A4:B3:19:6C:B5:D0:99:39:DF:8C:9B:93:8C:AF:88:BD:49:3C:21:D3:9E:9E:E8:6F:D1:B7 a=setup:active a=mid:1 a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time a=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id a=sendonly a=msid:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T hw+kkc5DGSxYxwOiHmCFtbXRCFAAyhNy a=rtcp-mux a=rtpmap:111 opus/48000/2 a=rtcp-fb:111 transport-cc a=fmtp:111 minptime=10;useinbandfec=1 a=rtpmap:103 ISAC/16000 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:105 CN/16000 a=rtpmap:13 CN/8000 a=rtpmap:110 telephone-event/48000 a=rtpmap:113 telephone-event/16000 a=rtpmap:126 telephone-event/8000 a=ssrc:3064290279 cname:QVXt6J+XDjYF4pMe a=ssrc:3064290279 msid:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T hw+kkc5DGSxYxwOiHmCFtbXRCFAAyhNy a=ssrc:3064290279 mslabel:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T a=ssrc:3064290279 label:hw+kkc5DGSxYxwOiHmCFtbXRCFAAyhNy m=application 9 UDP/DTLS/SCTP webrtc-datachannel c=IN IP4 0.0.0.0 b=AS:30 a=ice-ufrag:YBtH a=ice-pwd:obIYCSsyYarhj2HDTGeJbhiX a=ice-options:trickle a=fingerprint:sha-256 99:C6:6D:BA:A2:8A:A4:B3:19:6C:B5:D0:99:39:DF:8C:9B:93:8C:AF:88:BD:49:3C:21:D3:9E:9E:E8:6F:D1:B7 a=setup:active a=mid:2 a=sctp-port:5000 a=max-message-size:262144 [017:933] [2319] (websocket.cpp:69): onRead: Success [017:939] [2319] (p2p_websocket_session.cpp:63): onRead: system:0 [017:939] [2319] (p2p_websocket_session.cpp:75): onRead: recv_string={"type":"candidate","ice":{"candidate":"candidate:4052721947 1 udp 2113937151 be4c7bf0-bade-4fa2-a468-9a51c4212eff.local 63674 typ host generation 0 ufrag jfho network-cost 999","sdpMid":"0","sdpMLineIndex":0}} [017:939] [2321] (dtls_srtp_transport.cc:62): Setting RTCP Transport on 0 transport 0 [017:939] [2321] (dtls_srtp_transport.cc:67): Setting RTP Transport on 0 transport b4802cd8 [017:939] [2321] (p2p_transport_channel.cc:416): Set ICE ufrag: YBtH pwd: obIYCSsyYarhj2HDTGeJbhiX on transport 0 [017:939] [2321] (dtls_transport.cc:367): DtlsTransport[0|1|__]: DTLS setup complete. [017:939] [2322] (call.cc:1047): UpdateAggregateNetworkState: aggregate_state=down [017:939] [2322] (rtp_transport_controller_send.cc:305): SignalNetworkState Down [017:940] [2323] (rtp_transceiver.cc:179): Changing transceiver (MID=1) current direction from to kSendOnly. [017:940] [2323] (rtp_transceiver.cc:179): Changing transceiver (MID=0) current direction from to kSendOnly. [017:940] [2322] (channel.cc:508): Channel enabled [017:940] [2322] (channel.cc:817): Changing voice state, recv=0 send=0 [017:941] [2322] (channel.cc:508): Channel enabled [017:941] [2322] (channel.cc:963): Changing video state, send=0 [017:941] [2323] (peer_connection.cc:4781): Session: 8988880037952262839 Old state: kHaveRemoteOffer New state: kStable [017:941] [2322] (channel.cc:825): Setting local voice description [017:941] [2322] (webrtc_voice_engine.cc:1326): WebRtcVoiceMediaChannel::SetRecvParameters: {codecs: [AudioCodec[111:opus:48000:0:2], AudioCodec[103:ISAC:16000:0:1], AudioCodec[9:G722:8000:0:1], AudioCodec[0:PCMU:8000:0:1], AudioCodec[8:PCMA:8000:0:1], AudioCodec[105:CN:16000:0:1], AudioCodec[13:CN:8000:0:1], AudioCodec[110:telephone-event:48000: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}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 6}]} [017:941] [2322] (webrtc_voice_engine.cc:1489): Setting receive voice codecs. [017:942] [2322] (webrtc_voice_engine.cc:1760): AddSendStream: {id:hw+kkc5DGSxYxwOiHmCFtbXRCFAAyhNy;ssrcs:[3064290279];ssrc_groups:;cname:QVXt6J+XDjYF4pMe;stream_ids:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T;} [017:948] [2322] (delay_manager.cc:74): Delay histogram config: quantile=1041529569 forget_factor=32745 start_forget_weight=2 [017:948] [2322] (decision_logic.cc:57): NetEq decision logic settings: estimate_dtx_delay=0 time_stretch_cn=0 target_level_window_ms=100 [017:948] [2322] (neteq_impl.cc:141): 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 [017:949] [2322] (audio_coding_module.cc:229): Created [017:949] [2322] (audio_send_stream.cc:161): AudioSendStream: 3064290279 [017:950] [2322] (audio_send_stream.cc:227): AudioSendStream::ConfigureStream: {rtp: {ssrc: 3064290279, extmap-allow-mixed: false, extensions: [{uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 3}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 2}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 4}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 6}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:ssrc-audio-level, id: 1}], c_name: QVXt6J+XDjYF4pMe}, rtcp_report_interval_ms: 5000, send_transport: (Transport), min_bitrate_bps: 32000, max_bitrate_bps: 32000, send_codec_spec: {nack_enabled: false, transport_cc_enabled: true, cng_payload_type: , payload_type: 111, format: {name: opus, clockrate_hz: 48000, num_channels: 2, parameters: {minptime: 10, useinbandfec: 1}}}} [017:958] [2322] (call.cc:1047): UpdateAggregateNetworkState: aggregate_state=down [017:958] [2322] (rtp_transport_controller_send.cc:305): SignalNetworkState Down [017:958] [2322] (channel.cc:636): Add send stream ssrc: 3064290279 [017:959] [2322] (channel.cc:817): Changing voice state, recv=0 send=0 [017:959] [2322] (channel.cc:976): Setting local video description [017:959] [2322] (webrtc_video_engine.cc:1017): SetRecvParameters: {codecs: [VideoCodec[96:VP8], VideoCodec[97:rtx], VideoCodec[114:red], VideoCodec[115:rtx], VideoCodec[116:ulpfec]], extensions: [{uri: urn:ietf:params:rtp-hdrext:toffset, id: 14}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 2}, {uri: urn:3gpp:video-orientation, id: 13}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 3}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 12}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 4}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 6}]} [017:960] [2322] (webrtc_video_engine.cc:1032): Changing recv codecs from {VideoCodec[96:VP8], VideoCodec[98:VP9], VideoCodec[100:H264], VideoCodec[102:H264], VideoCodec[104:H264], VideoCodec[106:H264]} to {VideoCodec[96:VP8]} [017:960] [2322] (webrtc_video_engine.cc:1133): AddSendStream: {id:c64mYVHt2Pfc97Yy6NkgZG0I1YQg5daw;ssrcs:[1366490776,211509755];ssrc_groups:{semantics:FID;ssrcs:[1366490776,211509755]};cname:QVXt6J+XDjYF4pMe;stream_ids:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T;} [017:961] [2322] (webrtc_video_engine.cc:1958): RecreateWebRtcStream (send) because of SetCodec. [017:963] [2322] (balanced_degradation_settings.cc:93): Unsupported size, value ignored. [017:964] [2322] (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 [017:964] [2322] (video_stream_encoder.cc:2536): Automatic animation detection experiment is disabled. [017:965] [2363] (video_send_stream_impl.cc:221): VideoSendStreamInternal: {encoder_settings: { experiment_cpu_load_estimator: off}}, rtp: {ssrcs: [1366490776], rtcp_mode: RtcpMode::kReducedSize, max_packet_size: 1200, extmap-allow-mixed: false, extensions: [{uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 3}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 12}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: urn:3gpp:video-orientation, id: 13}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 4}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 6}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 5}], lntf: {enabled: false}, nack: {rtp_history_ms: 1000}, ulpfec: {ulpfec_payload_type: 116, red_payload_type: 114, red_rtx_payload_type: 115}, payload_name: VP8, payload_type: 96, raw_payload: false, flexfec: {payload_type: -1, ssrc: 0, protected_media_ssrcs: []}, rtx: {ssrcs: [211509755], payload_type: 97}, c_name: QVXt6J+XDjYF4pMe}, rtcp_report_interval_ms: 1000, send_transport: (Transport), render_delay_ms: 0, target_delay_ms: 0, suspend_below_min_bitrate: off} [017:966] [2322] (call.cc:1047): UpdateAggregateNetworkState: aggregate_state=down [017:966] [2367] (video_stream_encoder.cc:673): ConfigureEncoder requested. [017:966] [2322] (rtp_transport_controller_send.cc:305): SignalNetworkState Down [017:966] [2322] (video_send_stream.cc:160): VideoSendStream::Stop [017:966] [2322] (webrtc_video_engine.cc:1180): SetLocalSsrc on all the receive streams because we added a send stream. [017:966] [2363] (video_send_stream_impl.cc:378): VideoSendStream::Stop [017:966] [2322] (channel.cc:636): Add send stream ssrc: 1366490776 [017:967] [2322] (video_send_stream.cc:160): VideoSendStream::Stop [017:967] [2322] (channel.cc:963): Changing video state, send=0 [017:967] [2363] (video_send_stream_impl.cc:378): VideoSendStream::Stop [017:968] [2322] (webrtc_voice_engine.cc:1461): Setting voice channel options: AudioOptions {} [017:968] [2322] (webrtc_voice_engine.cc:315): 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, } [017:968] [2322] (webrtc_voice_engine.cc:436): NetEq capacity is 200 [017:968] [2322] (webrtc_voice_engine.cc:442): NetEq fast mode? 0 [017:968] [2322] (webrtc_voice_engine.cc:448): NetEq minimum delay is 0 [017:968] [2322] (webrtc_voice_engine.cc:454): NetEq handle reordered packets? 0 [017:968] [2322] (webrtc_voice_engine.cc:466): Experimental ns is enabled? 0 [017:968] [2322] (audio_processing_impl.cc:627): AudioProcessing::ApplyConfig: AudioProcessing::Config{ pipeline: {maximum_internal_processing_rate: 32000, multi_channel_render: 0, , multi_channel_capture: 0}, pre_amplifier: { enabled: 0, fixed_gain_factor: 1 }, high_pass_filter: { enabled: 1 }, echo_canceller: { enabled: 1, mobile_mode: 0, legacy_moderate_suppression_level: 0, use_legacy_aec: 0 }, noise_suppression: { enabled: 1, level: High }, voice_detection: { enabled: 1 }, gain_controller1: { enabled: 1, mode: AdaptiveAnalog, target_level_dbfs: 3, compression_gain_db: 9, enable_limiter: 1, analog_level_minimum: 0, analog_level_maximum: 255 }, gain_controller2: { enabled: 0, fixed_digital: { gain_db: 0 }, adaptive_digital: { enabled: 0, level_estimator: Rms, use_saturation_protector: 1, extra_saturation_margin_db: 2 } }, residual_echo_detector: { enabled: 1 }, level_estimation: { enabled: 0 } } [017:969] [2322] (webrtc_voice_engine.cc:1479): 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, } [017:970] [2322] (webrtc_video_engine.cc:1092): SetVideoSend (ssrc= 1366490776, options: VideoOptions {noise reduction: false, is_screencast : true, }, source = (source)) [017:970] [2322] (webrtc_video_engine.cc:1958): RecreateWebRtcStream (send) because of SetCodec. [017:970] [2322] (video_send_stream.cc:160): VideoSendStream::Stop [017:971] [2363] (video_send_stream_impl.cc:378): VideoSendStream::Stop [017:971] [2363] (video_send_stream_impl.cc:378): VideoSendStream::Stop [017:971] [2363] (video_send_stream_impl.cc:302): ~VideoSendStreamInternal: {encoder_settings: { experiment_cpu_load_estimator: off}}, rtp: {ssrcs: [1366490776], rtcp_mode: RtcpMode::kReducedSize, max_packet_size: 1200, extmap-allow-mixed: false, extensions: [{uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 3}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 12}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: urn:3gpp:video-orientation, id: 13}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 4}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 6}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 5}], lntf: {enabled: false}, nack: {rtp_history_ms: 1000}, ulpfec: {ulpfec_payload_type: 116, red_payload_type: 114, red_rtx_payload_type: 115}, payload_name: VP8, payload_type: 96, raw_payload: false, flexfec: {payload_type: -1, ssrc: 0, protected_media_ssrcs: []}, rtx: {ssrcs: [211509755], payload_type: 97}, c_name: QVXt6J+XDjYF4pMe}, rtcp_report_interval_ms: 1000, send_transport: (Transport), render_delay_ms: 0, target_delay_ms: 0, suspend_below_min_bitrate: off} [017:971] [2322] (call.cc:1047): UpdateAggregateNetworkState: aggregate_state=down [017:971] [2322] (rtp_transport_controller_send.cc:305): SignalNetworkState Down [017:972] [2322] (send_statistics_proxy.cc:659): Frames encoded 0 WebRTC.Video.DroppedFrames.Capturer 0 WebRTC.Video.DroppedFrames.EncoderQueue 0 WebRTC.Video.DroppedFrames.Encoder 0 WebRTC.Video.DroppedFrames.Ratelimiter 0 [017:973] [2322] (balanced_degradation_settings.cc:93): Unsupported size, value ignored. [017:973] [2322] (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 [017:973] [2322] (video_stream_encoder.cc:2536): Automatic animation detection experiment is disabled. [017:973] [2363] (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 [017:974] [2363] (video_send_stream_impl.cc:221): VideoSendStreamInternal: {encoder_settings: { experiment_cpu_load_estimator: off}}, rtp: {ssrcs: [1366490776], rtcp_mode: RtcpMode::kReducedSize, max_packet_size: 1200, extmap-allow-mixed: false, extensions: [{uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 3}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 12}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: urn:3gpp:video-orientation, id: 13}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 4}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 6}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 5}], lntf: {enabled: false}, nack: {rtp_history_ms: 1000}, ulpfec: {ulpfec_payload_type: 116, red_payload_type: 114, red_rtx_payload_type: 115}, payload_name: VP8, payload_type: 96, raw_payload: false, flexfec: {payload_type: -1, ssrc: 0, protected_media_ssrcs: []}, rtx: {ssrcs: [211509755], payload_type: 97}, c_name: QVXt6J+XDjYF4pMe}, rtcp_report_interval_ms: 1000, send_transport: (Transport), render_delay_ms: 0, target_delay_ms: 0, suspend_below_min_bitrate: off} [017:974] [2363] (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 [017:974] [2322] (call.cc:1047): UpdateAggregateNetworkState: aggregate_state=down [017:974] [2368] (video_stream_encoder.cc:673): ConfigureEncoder requested. [017:975] [2322] (rtp_transport_controller_send.cc:305): SignalNetworkState Down [017:975] [2322] (video_send_stream.cc:160): VideoSendStream::Stop [017:975] [2363] (video_send_stream_impl.cc:378): VideoSendStream::Stop [017:975] [2368] (video_stream_encoder.cc:673): ConfigureEncoder requested. [017:976] [2321] (basic_port_allocator.cc:381): Start getting ports with turn_port_prune_policy 0 [017:977] [2321] (basic_port_allocator.cc:110): Filtered out ignored networks: [017:977] [2321] (basic_port_allocator.cc:112): Net[lo:0:0:0:x:x:x:x:x/128:Loopback:id=2] [017:977] [2321] (basic_port_allocator.cc:112): Net[lo:127.0.0.x/8:Loopback:id=1] [017:977] [2321] (basic_port_allocator.cc:884): Network manager has started [017:977] [2321] (basic_port_allocator.cc:110): Filtered out ignored networks: [017:977] [2321] (basic_port_allocator.cc:112): Net[lo:0:0:0:x:x:x:x:x/128:Loopback:id=2] [017:977] [2321] (basic_port_allocator.cc:112): Net[lo:127.0.0.x/8:Loopback:id=1] [017:977] [2321] (basic_port_allocator.cc:799): Allocate ports on 1 networks [017:977] [2321] (basic_port_allocator.cc:1376): Net[wlan0:192.168.11.x/24:Wifi:id=3]: Allocation Phase=Udp [017:977] [2321] (port.cc:185): Port[b48057a8::1:0:local:Net[wlan0:192.168.11.x/24:Wifi:id=3]]: Port created with network cost 10 [017:977] [2321] (basic_port_allocator.cc:1447): AllocationSequence: UDPPort will be handling the STUN candidate generation. [017:978] [2321] (basic_port_allocator.cc:908): Adding allocated port for 0 [017:978] [2321] (basic_port_allocator.cc:929): Port[b48057a8:0:1:0:local:Net[wlan0:192.168.11.x/24:Wifi:id=3]]: Added port to allocator [017:978] [2321] (basic_port_allocator.cc:947): Port[b48057a8:0:1:0:local:Net[wlan0:192.168.11.x/24:Wifi:id=3]]: Gathered candidate: Cand[:2536573974:1:udp:2122260223:192.168.11.x:47116:local::0:YBtH:obIYCSsyYarhj2HDTGeJbhiX:3:10:0] [017:978] [2321] (basic_port_allocator.cc:980): Port[b48057a8:0:1:0:local:Net[wlan0:192.168.11.x/24:Wifi:id=3]]: Port ready. [017:978] [2321] (physical_socket_server.cc:557): Socket::OPT_DSCP not supported. [017:978] [2321] (p2p_transport_channel.cc:830): Port[b48057a8:0:1:0:local:Net[wlan0:192.168.11.x/24:Wifi:id=3]]: SetOption(5, 0) failed: 0 [017:978] [2321] (stun_port.cc:439): Port[b48057a8:0:1:0:local:Net[wlan0:192.168.11.x/24:Wifi:id=3]]: Starting STUN host lookup for stun.l.google.com:19302 [017:978] [2323] (p2p_connection.cpp:46): onCreateDescription [017:979] [2323] (websocket.cpp:88): sendText [017:979] [2321] (p2p_transport_channel.cc:1098): Dropping ICE candidate with hostname address (no AsyncResolverFactory) [017:980] [2323] (peer_connection.cc:4685): Changing IceConnectionState 0 => 1 [017:980] [2323] (observer.cpp:104): Set local description success! [017:980] [2319] (websocket.cpp:49): doRead [017:980] [2323] (p2p_connection.cpp:54): onSetDescription SdpType: answer [017:980] [2319] (websocket.cpp:94): doSendText: {"sdp":"v=0\r\no=- 8988880037952262839 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0 1 2\r\na=msid-semantic: WMS Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 114 115 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:YBtH\r\na=ice-pwd:obIYCSsyYarhj2HDTGeJbhiX\r\na=ice-options:trickle\r\na=fingerprint:sha-256 99:C6:6D:BA:A2:8A:A4:B3:19:6C:B5:D0:99:39:DF:8C:9B:93:8C:AF:88:BD:49:3C:21:D3:9E:9E:E8:6F:D1:B7\r\na=setup:active\r\na=mid:0\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07\r\na=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=sendonly\r\na=msid:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T c64mYVHt2Pfc97Yy6NkgZG0I1YQg5daw\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:114 red/90000\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:116 ulpfec/90000\r\na=ssrc-group:FID 1366490776 211509755\r\na=ssrc:1366490776 cname:QVXt6J+XDjYF4pMe\r\na=ssrc:1366490776 msid:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T c64mYVHt2Pfc97Yy6NkgZG0I1YQg5daw\r\na=ssrc:1366490776 mslabel:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T\r\na=ssrc:1366490776 label:c64mYVHt2Pfc97Yy6NkgZG0I1YQg5daw\r\na=ssrc:211509755 cname:QVXt6J+XDjYF4pMe\r\na=ssrc:211509755 msid:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T c64mYVHt2Pfc97Yy6NkgZG0I1YQg5daw\r\na=ssrc:211509755 mslabel:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T\r\na=ssrc:211509755 label:c64mYVHt2Pfc97Yy6NkgZG0I1YQg5daw\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111 103 9 0 8 105 13 110 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:YBtH\r\na=ice-pwd:obIYCSsyYarhj2HDTGeJbhiX\r\na=ice-options:trickle\r\na=fingerprint:sha-256 99:C6:6D:BA:A2:8A:A4:B3:19:6C:B5:D0:99:39:DF:8C:9B:93:8C:AF:88:BD:49:3C:21:D3:9E:9E:E8:6F:D1:B7\r\na=setup:active\r\na=mid:1\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=sendonly\r\na=msid:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T hw+kkc5DGSxYxwOiHmCFtbXRCFAAyhNy\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\na=ssrc:3064290279 cname:QVXt6J+XDjYF4pMe\r\na=ssrc:3064290279 msid:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T hw+kkc5DGSxYxwOiHmCFtbXRCFAAyhNy\r\na=ssrc:3064290279 mslabel:Xi3/N7uIJSA3zXoRG4whFs3Mjx9ILA7T\r\na=ssrc:3064290279 label:hw+kkc5DGSxYxwOiHmCFtbXRCFAAyhNy\r\nm=application 9 UDP/DTLS/SCTP webrtc-datachannel\r\nc=IN IP4 0.0.0.0\r\nb=AS:30\r\na=ice-ufrag:YBtH\r\na=ice-pwd:obIYCSsyYarhj2HDTGeJbhiX\r\na=ice-options:trickle\r\na=fingerprint:sha-256 99:C6:6D:BA:A2:8A:A4:B3:19:6C:B5:D0:99:39:DF:8C:9B:93:8C:AF:88:BD:49:3C:21:D3:9E:9E:E8:6F:D1:B7\r\na=setup:active\r\na=mid:2\r\na=sctp-port:5000\r\na=max-message-size:262144\r\n","type":"answer"} [017:980] [2323] (p2p_connection.cpp:34): onIceCandidate [017:980] [2319] (websocket.cpp:117): doWrite [017:980] [2319] (websocket.cpp:69): onRead: Success [017:980] [2323] (websocket.cpp:88): sendText [017:981] [2319] (p2p_websocket_session.cpp:63): onRead: system:0 [017:981] [2319] (p2p_websocket_session.cpp:75): onRead: recv_string={"type":"candidate","ice":{"candidate":"candidate:4052721947 1 udp 2113937151 be4c7bf0-bade-4fa2-a468-9a51c4212eff.local 63676 typ host generation 0 ufrag jfho network-cost 999","sdpMid":"1","sdpMLineIndex":1}} [017:982] [2368] (video_stream_encoder.cc:1314): Video frame parameters changed: dimensions=640x480, texture=0. [017:982] [2321] (jsep_transport_controller.cc:342): Not adding candidate because the JsepTransport doesn't exist. Ignore it. [017:982] [2368] (video_stream_encoder.cc:866): ReconfigureEncoder: Simulcast streams: 0: 640x480 fps: 60 min_bps: 30 target_bps: 1700 max_bps: 1700 max_qp: 56 num_tl: 1 active: true [017:982] [2368] (video_stream_encoder.cc:259): Set max framerate: 60 [017:983] [2319] (websocket.cpp:49): doRead [017:983] [2319] (websocket.cpp:149): onWrite: Success [017:983] [2319] (websocket.cpp:94): doSendText: {"ice":{"candidate":"candidate:2536573974 1 udp 2122260223 192.168.11.54 47116 typ host generation 0 ufrag YBtH network-id 3 network-cost 10","sdpMLineIndex":0,"sdpMid":"0"},"type":"candidate"} [017:983] [2319] (websocket.cpp:117): doWrite [017:983] [2319] (websocket.cpp:69): onRead: Success [017:984] [2319] (p2p_websocket_session.cpp:63): onRead: system:0 [017:984] [2319] (p2p_websocket_session.cpp:75): onRead: recv_string={"type":"candidate","ice":{"candidate":"candidate:4052721947 1 udp 2113937151 be4c7bf0-bade-4fa2-a468-9a51c4212eff.local 63678 typ host generation 0 ufrag jfho network-cost 999","sdpMid":"2","sdpMLineIndex":2}} [017:984] [2321] (jsep_transport_controller.cc:342): Not adding candidate because the JsepTransport doesn't exist. Ignore it. [017:984] [2319] (websocket.cpp:49): doRead [017:984] [2319] (websocket.cpp:69): onRead: Success [017:984] [2319] (p2p_websocket_session.cpp:63): onRead: system:0 [017:984] [2319] (p2p_websocket_session.cpp:75): onRead: recv_string={"type":"candidate","ice":{"candidate":"candidate:842163049 1 udp 1677729535 152.165.112.242 63678 typ srflx raddr 0.0.0.0 rport 0 generation 0 ufrag jfho network-cost 999","sdpMid":"2","sdpMLineIndex":2}} [017:985] [2321] (jsep_transport_controller.cc:342): Not adding candidate because the JsepTransport doesn't exist. Ignore it. [017:985] [2319] (websocket.cpp:49): doRead [017:985] [2319] (websocket.cpp:149): onWrite: Success [017:985] [2319] (websocket.cpp:69): onRead: Success [017:985] [2319] (p2p_websocket_session.cpp:63): onRead: system:0 [017:985] [2319] (p2p_websocket_session.cpp:75): onRead: recv_string={"type":"candidate","ice":{"candidate":"candidate:842163049 1 udp 1677729535 152.165.112.242 63676 typ srflx raddr 0.0.0.0 rport 0 generation 0 ufrag jfho network-cost 999","sdpMid":"1","sdpMLineIndex":1}} [017:985] [2321] (jsep_transport_controller.cc:342): Not adding candidate because the JsepTransport doesn't exist. Ignore it. [017:986] [2319] (websocket.cpp:49): doRead [017:986] [2319] (websocket.cpp:69): onRead: Success [017:986] [2319] (p2p_websocket_session.cpp:63): onRead: system:0 [017:986] [2319] (p2p_websocket_session.cpp:75): onRead: recv_string={"type":"candidate","ice":{"candidate":"candidate:842163049 1 udp 1677729535 152.165.112.242 63674 typ srflx raddr 0.0.0.0 rport 0 generation 0 ufrag jfho network-cost 999","sdpMid":"0","sdpMLineIndex":0}} [017:986] [2321] (connection.cc:282): Conn[b4804978:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->VkRznTG/:1:1677729535:stun:udp:152.165.112.x:63674|C--W|-|0|0|7205793488602807806|-]: Connection created [017:986] [2321] (p2p_transport_channel.cc:1332): Channel[0|1|__]: Created connection with origin: 2, total: 1 [017:986] [2321] (p2p_transport_channel.cc:1755): Channel[0|1|__]: Transport channel state changed from 0 to 2 [017:986] [2321] (jsep_transport_controller.cc:1428): 0 Transport 1 state changed. Check if state is complete. [017:987] [2321] (jsep_transport_controller.cc:1428): 0 Transport 1 state changed. Check if state is complete. [017:987] [2321] (p2p_transport_channel.cc:1566): Channel[0|1|__]: Have a pingable connection for the first time; starting to ping. [017:987] [2323] (peer_connection.cc:4704): Changing standardized IceConnectionState 0 => 1 [017:987] [2323] (observer.cpp:21): OnStandardizedIceConnectionChange :1 [017:987] [2323] (p2p_connection.cpp:24): onIceConnectionStateChange rtc_state unknown -> checking [017:987] [2321] (connection.cc:1044): Conn[b4804978:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->VkRznTG/:1:1677729535:stun:udp:152.165.112.x:63674|C--W|-|0|0|7205793488602807806|-]: Sent STUN ping, id=2f73344849415469584b7059, use_candidate=0, nomination=0 [017:987] [2319] (websocket.cpp:49): doRead [018:028] [2321] (basic_port_allocator.cc:1376): Net[wlan0:192.168.11.x/24:Wifi:id=3]: Allocation Phase=Relay [018:036] [2321] (connection.cc:1044): Conn[b4804978:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->VkRznTG/:1:1677729535:stun:udp:152.165.112.x:63674|C--I|-|0|0|7205793488602807806|-]: Sent STUN ping, id=736e50534f7077792b324b30, use_candidate=0, nomination=0 [018:075] [2321] (thread.cc:298): Waiting for the thread to join, but blocking calls have been disallowed [018:078] [2321] (basic_port_allocator.cc:1376): Net[wlan0:192.168.11.x/24:Wifi:id=3]: Allocation Phase=Tcp [018:078] [2321] (port.cc:185): Port[b4818a08::1:0:local:Net[wlan0:192.168.11.x/24:Wifi:id=3]]: Port created with network cost 10 [018:078] [2321] (basic_port_allocator.cc:908): Adding allocated port for 0 [018:078] [2321] (basic_port_allocator.cc:929): Port[b4818a08:0:1:0:local:Net[wlan0:192.168.11.x/24:Wifi:id=3]]: Added port to allocator [018:079] [2321] (basic_port_allocator.cc:947): Port[b4818a08:0:1:0:local:Net[wlan0:192.168.11.x/24:Wifi:id=3]]: Gathered candidate: Cand[:3652382950:1:tcp:1518280447:192.168.11.x:39339:local::0:YBtH:obIYCSsyYarhj2HDTGeJbhiX:3:10:0] [018:079] [2321] (basic_port_allocator.cc:980): Port[b4818a08:0:1:0:local:Net[wlan0:192.168.11.x/24:Wifi:id=3]]: Port ready. [018:079] [2321] (physical_socket_server.cc:557): Socket::OPT_DSCP not supported. [018:079] [2321] (p2p_transport_channel.cc:830): Port[b4818a08:0:1:0:local:Net[wlan0:192.168.11.x/24:Wifi:id=3]]: SetOption(5, 0) failed: 0 [018:079] [2321] (basic_port_allocator.cc:1088): Port[b4818a08:0:1:0:local:Net[wlan0:192.168.11.x/24:Wifi:id=3]]: Port completed gathering candidates. [018:079] [2323] (p2p_connection.cpp:34): onIceCandidate [018:079] [2323] (websocket.cpp:88): sendText [018:079] [2319] (websocket.cpp:94): doSendText: {"ice":{"candidate":"candidate:3652382950 1 tcp 1518280447 192.168.11.54 39339 typ host tcptype passive generation 0 ufrag YBtH network-id 3 network-cost 10","sdpMLineIndex":0,"sdpMid":"0"},"type":"candidate"} [018:079] [2319] (websocket.cpp:117): doWrite [018:079] [2319] (websocket.cpp:149): onWrite: Success [018:084] [2321] (connection.cc:1044): Conn[b4804978:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->VkRznTG/:1:1677729535:stun:udp:152.165.112.x:63674|C--I|-|0|0|7205793488602807806|-]: Sent STUN ping, id=513368514d54434248354237, use_candidate=0, nomination=0 [018:100] [2321] (port.cc:395): Received STUN ping id=504d514c787a364553373976 from unknown address 192.168.11.x:63674 [018:100] [2321] (connection.cc:282): Conn[b4819b90:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->eOfoXkfN:1:1845501695:prflx:udp:192.168.11.x:63674|C--W|-|0|0|7926369428982087166|-]: Connection created [018:100] [2321] (p2p_transport_channel.cc:1026): Adding connection from peer reflexive candidate: Cand[:1650318279:1:udp:1845501695:192.168.11.x:63674:prflx::0:jfho:IwePzL5Vrr+2rszCCnRJKlAv:0:999:0] [018:100] [2321] (connection.cc:646): Conn[b4819b90:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->eOfoXkfN:1:1845501695:prflx:udp:192.168.11.x:63674|CR-W|-|0|0|7926369428982087166|-]: Sent STUN ping response, to=192.168.11.x:63674, id=504d514c787a364553373976 [018:101] [2321] (p2p_transport_channel.cc:1089): Not switching the selected connection on controlled side yet: Conn[b4819b90:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->eOfoXkfN:1:1845501695:prflx:udp:192.168.11.x:63674|CR-W|-|1|0|7926369428982087166|-] [018:101] [2321] (p2p_transport_channel.cc:1755): Channel[0|1|R_]: Transport channel state changed from 2 to 1 [018:101] [2321] (jsep_transport_controller.cc:1428): 0 Transport 1 state changed. Check if state is complete. [018:133] [2321] (basic_ice_controller.cc:229): Selecting connection for triggered check: Conn[b4819b90:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->eOfoXkfN:1:1845501695:prflx:udp:192.168.11.x:63674|CR-W|-|1|0|7926369428982087166|-] [018:133] [2321] (connection.cc:1044): Conn[b4819b90:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->eOfoXkfN:1:1845501695:prflx:udp:192.168.11.x:63674|CR-W|-|1|0|7926369428982087166|-]: Sent STUN ping, id=5a2b4b4e756a4b6d7a586646, use_candidate=0, nomination=0 [018:182] [2321] (connection.cc:1044): Conn[b4804978:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->VkRznTG/:1:1677729535:stun:udp:152.165.112.x:63674|C--I|-|0|0|7205793488602807806|-]: Sent STUN ping, id=304c697549747368316c2f48, use_candidate=0, nomination=0 [018:198] [2321] (basic_port_allocator.cc:947): Port[b48057a8:0:1:0:local:Net[wlan0:192.168.11.x/24:Wifi:id=3]]: Gathered candidate: Cand[:377031330:1:udp:1686052607:152.165.112.x:47116:stun:192.168.11.54:47116:YBtH:obIYCSsyYarhj2HDTGeJbhiX:3:10:0] [018:199] [2321] (basic_port_allocator.cc:1088): Port[b48057a8:0:1:0:local:Net[wlan0:192.168.11.x/24:Wifi:id=3]]: Port completed gathering candidates. [018:199] [2321] (basic_port_allocator.cc:1163): All candidates gathered for 0:1:0 [018:199] [2321] (p2p_transport_channel.cc:891): P2PTransportChannel: 0, component 1 gathering complete [018:199] [2321] (connection.cc:454): Conn[b4819b90:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->eOfoXkfN:1:1845501695:prflx:udp:192.168.11.x:63674|CR-I|-|1|0|7926369428982087166|-]: Received STUN ping, id=535a52756e706a2f752f7258 [018:199] [2321] (connection.cc:646): Conn[b4819b90:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->eOfoXkfN:1:1845501695:prflx:udp:192.168.11.x:63674|CR-I|-|1|0|7926369428982087166|-]: Sent STUN ping response, to=192.168.11.x:63674, id=535a52756e706a2f752f7258 [018:199] [2321] (connection.cc:454): Conn[b4819b90:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->eOfoXkfN:1:1845501695:prflx:udp:192.168.11.x:63674|CR-I|-|1|0|7926369428982087166|-]: Received STUN ping, id=2f754b337431666b38437058 [018:199] [2323] (p2p_connection.cpp:34): onIceCandidate [018:199] [2321] (connection.cc:646): Conn[b4819b90:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->eOfoXkfN:1:1845501695:prflx:udp:192.168.11.x:63674|CR-I|-|1|0|7926369428982087166|-]: Sent STUN ping response, to=192.168.11.x:63674, id=2f754b337431666b38437058 [018:200] [2323] (websocket.cpp:88): sendText [018:200] [2319] (websocket.cpp:94): doSendText: {"ice":{"candidate":"candidate:377031330 1 udp 1686052607 152.165.112.242 47116 typ srflx raddr 192.168.11.54 rport 47116 generation 0 ufrag YBtH network-id 3 network-cost 10","sdpMLineIndex":0,"sdpMid":"0"},"type":"candidate"} [018:200] [2319] (websocket.cpp:117): doWrite [018:200] [2319] (websocket.cpp:149): onWrite: Success [018:200] [2321] (connection.cc:984): Conn[b4819b90:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->eOfoXkfN:1:1845501695:prflx:udp:192.168.11.x:63674|CR-I|-|1|0|7926369428982087166|-]: Received STUN ping response, id=5a2b4b4e756a4b6d7a586646, code=0, rtt=67, pings_since_last_response=5a2b4b4e756a4b6d7a586646 [018:201] [2321] (p2p_transport_channel.cc:231): Switching selected connection due to: candidate pair state changed [018:201] [2321] (p2p_transport_channel.cc:1681): Channel[0|1|R_]: New selected connection: Conn[b4819b90:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->eOfoXkfN:1:1845501695:prflx:udp:192.168.11.x:63674|CRWS|S|1|0|7926369428982087166|67] [018:201] [2321] (channel.cc:353): Network route was changed. [018:201] [2321] (channel.cc:353): Network route was changed. [018:201] [2321] (connection.cc:662): Conn[b4804978:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->VkRznTG/:1:1677729535:stun:udp:152.165.112.x:63674|C--I|-|0|0|7205793488602807806|-]: Connection pruned [018:201] [2321] (dtls_transport.cc:800): DtlsTransport[0|1|__]: configuring DTLS handshake timeout 134 based on ICE RTT 67 [018:201] [2321] (openssl_stream_adapter.cc:784): BeginSSL with peer. [018:202] [2321] (dtls_transport.cc:705): DtlsTransport[0|1|__]: DtlsTransport: Started DTLS handshake [018:202] [2321] (srtp_transport.cc:365): The params in SRTP transport are reset. [018:202] [2321] (p2p_transport_channel.cc:1755): Channel[0|1|RW]: Transport channel state changed from 1 to 2 [018:202] [2321] (jsep_transport_controller.cc:1428): 0 Transport 1 state changed. Check if state is complete. [018:202] [2321] (jsep_transport_controller.cc:1428): 0 Transport 1 state changed. Check if state is complete. [018:202] [2323] (peer_connection.cc:4704): Changing standardized IceConnectionState 1 => 2 [018:202] [2323] (observer.cpp:21): OnStandardizedIceConnectionChange :2 [018:202] [2323] (p2p_connection.cpp:24): onIceConnectionStateChange rtc_state checking -> connected [018:208] [2321] (openssl_stream_adapter.cc:1056): Accepted peer certificate. [018:238] [2321] (dtls_transport.cc:635): DtlsTransport[0|1|__]: DTLS handshake complete. [018:238] [2321] (jsep_transport_controller.cc:1351): Transport 0 writability changed to 1. [018:238] [2321] (dtls_srtp_transport.cc:220): Extracting keys from transport: 0 [018:238] [2323] (peer_connection.cc:6306): Changing to ICE connected state because all transports are writable. [018:238] [2322] (call.cc:1047): UpdateAggregateNetworkState: aggregate_state=up [018:238] [2323] (peer_connection.cc:4685): Changing IceConnectionState 1 => 2 [018:238] [2322] (rtp_transport_controller_send.cc:305): SignalNetworkState Up [018:238] [2363] (rtp_transport_controller_send.cc:528): Creating fallback congestion controller [018:238] [2363] (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 [018:238] [2363] (trendline_estimator.cc:183): 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 [018:238] [2363] (aimd_rate_control.cc:114): Using aimd rate control with back off factor 0.85 [018:238] [2363] (delay_based_bwe.cc:97): Initialized DelayBasedBwe with field trial smoothing:0.1,fraction_large:1,large:0 bytes,small:0 bytes and alr limited backoff disabled [018:238] [2363] (delay_based_bwe.cc:304): BWE Setting start bitrate to: 300 kbps [018:239] [2363] (probe_controller.cc:271): Measured bitrate: 300000 Minimum to probe further: 1260000 [018:239] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (900000:1688:5) [018:239] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (1800000:3375:5) [018:239] [2363] (bitrate_allocator.cc:394): Current BWE 300000 [018:239] [2322] (call.cc:1047): UpdateAggregateNetworkState: aggregate_state=up [018:239] [2322] (rtp_transport_controller_send.cc:305): SignalNetworkState Up [018:258] [2321] (srtp_transport.cc:310): SRTP activated with negotiated parameters: send cipher_suite 1 recv cipher_suite 1 [018:258] [2321] (channel.cc:538): Channel writable (0) for the first time [018:258] [2321] (channel.cc:538): Channel writable (1) for the first time [018:258] [2321] (sctp_transport.cc:205): InitializeUsrSctp [018:262] [2322] (video_send_stream.cc:133): VideoSendStream::UpdateActiveSimulcastLayers [018:262] [2363] (video_send_stream_impl.cc:324): VideoSendStream::UpdateActiveSimulcastLayers [018:262] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 30 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 1700 kbps [018:263] [2368] (video_stream_encoder.cc:1916): Video suspend state changed to: not suspended [018:263] [2363] (rtcp_sender.cc:960): Emitting TargetBitrate XR for SSRC 1366490776 with new layers enabled/disabled: VideoBitrateAllocation [ [300000] ] [018:263] [2322] (channel.cc:963): Changing video state, send=1 [018:263] [2368] (video_stream_encoder.cc:1474): Encoder settings changed from EncoderInfo { ScalingSettings { min_pixels_per_frame = 57600 }, supports_native_handle = 0, implementation_name = 'unknown', has_trusted_rate_controller = 0, is_hardware_accelerated = 1, has_internal_source = 0, fps_allocation = [[ 1] ], resolution_bitrate_limits = [] , supports_simulcast = 0} to EncoderInfo { ScalingSettings { min_pixels_per_frame = 57600 }, supports_native_handle = 0, implementation_name = 'libvpx', has_trusted_rate_controller = 0, is_hardware_accelerated = 0, has_internal_source = 0, fps_allocation = [[ 1] ], resolution_bitrate_limits = [] , supports_simulcast = 1} [018:263] [2322] (webrtc_voice_engine.cc:315): 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, } [018:263] [2322] (webrtc_voice_engine.cc:436): NetEq capacity is 200 [018:263] [2322] (webrtc_voice_engine.cc:442): NetEq fast mode? 0 [018:263] [2322] (webrtc_voice_engine.cc:448): NetEq minimum delay is 0 [018:263] [2322] (webrtc_voice_engine.cc:454): NetEq handle reordered packets? 0 [018:263] [2322] (webrtc_voice_engine.cc:466): Experimental ns is enabled? 0 [018:263] [2322] (audio_processing_impl.cc:627): AudioProcessing::ApplyConfig: AudioProcessing::Config{ pipeline: {maximum_internal_processing_rate: 32000, multi_channel_render: 0, , multi_channel_capture: 0}, pre_amplifier: { enabled: 0, fixed_gain_factor: 1 }, high_pass_filter: { enabled: 1 }, echo_canceller: { enabled: 1, mobile_mode: 0, legacy_moderate_suppression_level: 0, use_legacy_aec: 0 }, noise_suppression: { enabled: 1, level: High }, voice_detection: { enabled: 1 }, gain_controller1: { enabled: 1, mode: AdaptiveAnalog, target_level_dbfs: 3, compression_gain_db: 9, enable_limiter: 1, analog_level_minimum: 0, analog_level_maximum: 255 }, gain_controller2: { enabled: 0, fixed_digital: { gain_db: 0 }, adaptive_digital: { enabled: 0, level_estimator: Rms, use_saturation_protector: 1, extra_saturation_margin_db: 2 } }, residual_echo_detector: { enabled: 1 }, level_estimation: { enabled: 0 } } [018:264] [2322] (audio_device_impl.cc:781): RecordingIsInitialized [018:264] [2322] (audio_device_impl.cc:843): Recording [018:264] [2322] (audio_device_impl.cc:762): InitRecording [018:264] [2322] (audio_device_impl.cc:781): RecordingIsInitialized [018:309] [2368] (rtp_sender_video.cc:692): Sent first RTP packet of the first video frame (pre-pacer) [018:309] [2368] (rtp_sender_video.cc:696): Sent last RTP packet of the first video frame (pre-pacer) [018:309] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [018:310] [2323] (data_channel.cc:703): Sent CONTROL message on channel 1 [018:337] [2322] (audio_device_buffer.cc:181): SetRecordingSampleRate(48000) [018:337] [2322] (audio_device_buffer.cc:201): SetRecordingChannels(2) [018:338] [2322] (audio_device_impl.cc:768): output: 0 [018:338] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 62 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 1732 kbps [018:338] [2322] (audio_device_impl.cc:843): Recording [018:338] [2322] (audio_device_impl.cc:762): InitRecording [018:338] [2322] (audio_device_impl.cc:781): RecordingIsInitialized [018:338] [2322] (audio_device_impl.cc:818): StartRecording [018:338] [2322] (audio_device_impl.cc:843): Recording [018:338] [2322] (audio_device_buffer.cc:118): StartRecording [018:338] [2322] (audio_device_impl.cc:825): output: 0 [018:338] [2322] (channel.cc:817): Changing voice state, recv=0 send=1 [018:338] [2322] (message_queue.cc:518): Message took 75ms to dispatch. Posted from: UpdateMediaSendRecvState@../../../_source/raspbian-buster_armv7/webrtc/src/pc/channel.cc:796 [018:497] [2363] (probe_bitrate_estimator.cc:136): Probing unsuccessful, receive/send ratio too high [cluster id: 1] [send: 2709 bytes / 24 ms = 903 kbps] [receive: 2694 bytes / 7 ms = 3078857 bps ] [ratio: 3078857 bps / 903 kbps = 3.40959 > kMaxValidRatio (2)] [018:497] [2363] (probe_bitrate_estimator.cc:136): Probing unsuccessful, receive/send ratio too high [cluster id: 1] [send: 3597 bytes / 31 ms = 928258 bps] [receive: 3600 bytes / 15 ms = 1920 kbps ] [ratio: 1920 kbps / 928258 bps = 2.06839 > kMaxValidRatio (2)] [018:497] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 2] [send: 2715 bytes / 13 ms = 1670769 bps ] [receive: 2850 bytes / 12 ms = 1900 kbps] [018:497] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 2] [send: 3756 bytes / 17 ms = 1767529 bps ] [receive: 3893 bytes / 17 ms = 1832 kbps] [018:497] [2363] (trendline_estimator.cc:183): 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 [018:497] [2363] (probe_controller.cc:271): Measured bitrate: 1767529 Minimum to probe further: 1260000 [018:497] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (3464000:6495:5) [020:419] [2373] (audio_device_buffer.cc:239): Size of recording buffer: 960 [020:425] [2373] (render_delay_buffer.cc:362): Applying total delay of 5 blocks. [020:426] [2373] (matched_filter.cc:450): Filter 0: start: 0 ms, end: 128 ms. [020:426] [2373] (matched_filter.cc:450): Filter 1: start: 96 ms, end: 224 ms. [020:426] [2373] (matched_filter.cc:450): Filter 2: start: 192 ms, end: 320 ms. [020:426] [2373] (matched_filter.cc:450): Filter 3: start: 288 ms, end: 416 ms. [020:426] [2373] (matched_filter.cc:450): Filter 4: start: 384 ms, end: 512 ms. [020:429] [2373] (render_delay_buffer.cc:330): Receiving a first externally reported audio buffer delay of 105 ms. [020:432] [2366] (rtp_sender_audio.cc:266): First audio RTP packet sent to pacer [020:508] [2363] (probe_bitrate_estimator.cc:136): Probing unsuccessful, receive/send ratio too high [cluster id: 3] [send: 4892 bytes / 11 ms = 3557818 bps] [receive: 4894 bytes / 1 ms = 39152 kbps ] [ratio: 39152 kbps / 3557818 bps = 11.0045 > kMaxValidRatio (2)] [020:508] [2363] (probe_bitrate_estimator.cc:136): Probing unsuccessful, receive/send ratio too high [cluster id: 3] [send: 5600 bytes / 13 ms = 3446153 bps] [receive: 5587 bytes / 1 ms = 44696 kbps ] [ratio: 44696 kbps / 3446153 bps = 12.9698 > kMaxValidRatio (2)] [020:508] [2363] (probe_bitrate_estimator.cc:136): Probing unsuccessful, receive/send ratio too high [cluster id: 3] [send: 6293 bytes / 14 ms = 3596 kbps] [receive: 6295 bytes / 3 ms = 16786666 bps ] [ratio: 16786666 bps / 3596 kbps = 4.66815 > kMaxValidRatio (2)] [020:509] [2363] (trendline_estimator.cc:183): 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 [024:263] [2363] (video_send_stream_impl.cc:400): SignalEncoderTimedOut, Encoder timed out. [024:263] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 32 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 32 kbps [024:532] [2365] (rtp_rtcp_impl.cc:136): Process: Timeout: No increase in RTCP RR extended highest sequence number. [024:690] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [024:690] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 62 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 1732 kbps [024:714] [2363] (bitrate_allocator.cc:394): Current BWE 1414023 [024:720] [2363] (trendline_estimator.cc:183): 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 [025:439] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (3056290:5731:5) [026:139] [2363] (probe_controller.cc:271): Measured bitrate: 1651397 Minimum to probe further: 2139403 [026:239] [2363] (probe_controller.cc:271): Measured bitrate: 1651397 Minimum to probe further: 2139403 [026:262] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [026:464] [2363] (probe_controller.cc:369): kWaitingForProbingResult: timeout [026:844] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 4] [send: 3851 bytes / 10 ms = 3080800 bps ] [receive: 3838 bytes / 10 ms = 3070400 bps] [026:845] [2363] (trendline_estimator.cc:183): 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 [026:897] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 4] [send: 4804 bytes / 12 ms = 3202666 bps ] [receive: 4807 bytes / 13 ms = 2958153 bps] [028:090] [2321] (connection.cc:1092): Connection deleted with number of pings sent: 4 [028:090] [2321] (p2p_transport_channel.cc:1943): Channel[0|1|RW]: Removed connection b4804978 (1 remaining) [030:263] [2363] (video_send_stream_impl.cc:400): SignalEncoderTimedOut, Encoder timed out. [030:263] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 32 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 32 kbps [030:439] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (64000:120:5) [030:540] [2363] (bitrate_allocator.cc:394): Current BWE 2958153 [030:573] [2365] (rtp_rtcp_impl.cc:136): Process: Timeout: No increase in RTCP RR extended highest sequence number. [031:095] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [031:096] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 62 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 1732 kbps [031:114] [2363] (trendline_estimator.cc:183): 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 [032:263] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [034:263] [2363] (video_send_stream_impl.cc:400): SignalEncoderTimedOut, Encoder timed out. [034:263] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 32 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 32 kbps [034:573] [2365] (rtp_rtcp_impl.cc:136): Process: Timeout: No increase in RTCP RR extended highest sequence number. [035:288] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [035:288] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 62 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 1732 kbps [035:328] [2363] (trendline_estimator.cc:183): 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 [035:439] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (3464000:6495:5) [036:262] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [036:314] [2363] (bitrate_allocator.cc:394): Current BWE 2556844 [038:263] [2363] (video_send_stream_impl.cc:400): SignalEncoderTimedOut, Encoder timed out. [038:263] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 32 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 32 kbps [038:338] [2324] (audio_device_buffer.cc:414): [REC : 10000msec, 48kHz] callbacks: 26, samples: 12480, rate: 1248, rate diff: 97%, level: 188 [039:303] [2365] (rtp_rtcp_impl.cc:136): Process: Timeout: No increase in RTCP RR extended highest sequence number. [039:521] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [039:521] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 62 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 1732 kbps [039:533] [2363] (trendline_estimator.cc:183): 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 [039:583] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 6] [send: 4855 bytes / 12 ms = 3236666 bps ] [receive: 4835 bytes / 12 ms = 3223333 bps] [039:583] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 6] [send: 5806 bytes / 13 ms = 3572923 bps ] [receive: 5788 bytes / 14 ms = 3307428 bps] [040:263] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [040:463] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (3464000:6495:5) [041:704] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 7] [send: 4778 bytes / 12 ms = 3185333 bps ] [receive: 4781 bytes / 11 ms = 3477090 bps] [041:704] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 7] [send: 5742 bytes / 14 ms = 3281142 bps ] [receive: 5744 bytes / 14 ms = 3282285 bps] [041:705] [2363] (trendline_estimator.cc:183): 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 [041:705] [2363] (bitrate_allocator.cc:394): Current BWE 3281142 [043:882] [2363] (trendline_estimator.cc:183): 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 [045:072] [2365] (rtp_rtcp_impl.cc:136): Process: Timeout: No increase in RTCP RR extended highest sequence number. [045:464] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (3464000:6495:5) [046:059] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 8] [send: 4302 bytes / 10 ms = 3441600 bps ] [receive: 4304 bytes / 10 ms = 3443200 bps] [046:059] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 8] [send: 5387 bytes / 13 ms = 3315076 bps ] [receive: 5389 bytes / 13 ms = 3316307 bps] [046:059] [2363] (trendline_estimator.cc:183): 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 [047:664] [2363] (bitrate_allocator.cc:394): Current BWE 3315076 [048:263] [2363] (video_send_stream_impl.cc:400): SignalEncoderTimedOut, Encoder timed out. [048:263] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 32 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 32 kbps [048:338] [2324] (audio_device_buffer.cc:414): [REC : 10000msec, 48kHz] callbacks: 48, samples: 23040, rate: 2304, rate diff: 95%, level: 268 [049:560] [2365] (rtp_rtcp_impl.cc:136): Process: Timeout: No increase in RTCP RR extended highest sequence number. [050:260] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [050:260] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 62 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 1732 kbps [050:263] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [050:315] [2363] (trendline_estimator.cc:183): 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 [050:464] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (3464000:6495:5) [052:439] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 9] [send: 4599 bytes / 11 ms = 3344727 bps ] [receive: 4602 bytes / 11 ms = 3346909 bps] [052:439] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 9] [send: 5527 bytes / 13 ms = 3401230 bps ] [receive: 5530 bytes / 13 ms = 3403076 bps] [052:439] [2363] (trendline_estimator.cc:183): 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 [054:582] [2363] (trendline_estimator.cc:183): 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 [055:414] [2363] (bitrate_allocator.cc:394): Current BWE 3401230 [055:464] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (3464000:6495:5) [056:762] [2363] (trendline_estimator.cc:183): 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 [056:813] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 10] [send: 4246 bytes / 10 ms = 3396800 bps ] [receive: 4232 bytes / 10 ms = 3385600 bps] [056:814] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 10] [send: 5301 bytes / 12 ms = 3534 kbps ] [receive: 5287 bytes / 11 ms = 3845090 bps] [056:814] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 10] [send: 6356 bytes / 15 ms = 3389866 bps ] [receive: 6342 bytes / 15 ms = 3382400 bps] [058:339] [2324] (audio_device_buffer.cc:414): [REC : 10001msec, 48kHz] callbacks: 48, samples: 23040, rate: 2304, rate diff: 95%, level: 332 [060:263] [2363] (video_send_stream_impl.cc:400): SignalEncoderTimedOut, Encoder timed out. [060:263] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 32 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 32 kbps [060:464] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (64000:120:5) [060:540] [2365] (rtp_rtcp_impl.cc:136): Process: Timeout: No increase in RTCP RR extended highest sequence number. [060:690] [2363] (bitrate_allocator.cc:394): Current BWE 3382400 [060:988] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [060:988] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 62 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 1732 kbps [061:028] [2363] (trendline_estimator.cc:183): 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 [062:263] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [064:263] [2363] (video_send_stream_impl.cc:400): SignalEncoderTimedOut, Encoder timed out. [064:263] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 32 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 32 kbps [064:469] [2365] (rtp_rtcp_impl.cc:136): Process: Timeout: No increase in RTCP RR extended highest sequence number. [065:224] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [065:224] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 62 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 1732 kbps [065:240] [2363] (trendline_estimator.cc:183): 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 [065:464] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (3464000:6495:5) [066:263] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [066:264] [2363] (bitrate_allocator.cc:394): Current BWE 3158266 [068:263] [2363] (video_send_stream_impl.cc:400): SignalEncoderTimedOut, Encoder timed out. [068:263] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 32 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 32 kbps [068:339] [2324] (audio_device_buffer.cc:414): [REC : 10000msec, 48kHz] callbacks: 25, samples: 12000, rate: 1200, rate diff: 98%, level: 0 [068:629] [2365] (rtp_rtcp_impl.cc:136): Process: Timeout: No increase in RTCP RR extended highest sequence number. [069:428] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [069:429] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 62 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 1732 kbps [069:446] [2363] (trendline_estimator.cc:183): 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 [069:496] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 12] [send: 4844 bytes / 11 ms = 3522909 bps ] [receive: 4825 bytes / 11 ms = 3509090 bps] [069:496] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 12] [send: 5793 bytes / 13 ms = 3564923 bps ] [receive: 5776 bytes / 14 ms = 3300571 bps] [070:262] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [070:464] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (3464000:6495:5) [071:689] [2363] (bitrate_allocator.cc:394): Current BWE 3300571 [072:263] [2363] (video_send_stream_impl.cc:400): SignalEncoderTimedOut, Encoder timed out. [072:263] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 32 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 32 kbps [072:572] [2365] (rtp_rtcp_impl.cc:136): Process: Timeout: No increase in RTCP RR extended highest sequence number. [073:661] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [073:661] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 62 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 1732 kbps [073:699] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 13] [send: 4736 bytes / 11 ms = 3444363 bps ] [receive: 4717 bytes / 14 ms = 2695428 bps] [073:699] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 13] [send: 5664 bytes / 13 ms = 3485538 bps ] [receive: 5647 bytes / 14 ms = 3226857 bps] [073:699] [2363] (trendline_estimator.cc:183): 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 [074:264] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [075:464] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (3464000:6495:5) [075:736] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 14] [send: 4619 bytes / 7 ms = 5278857 bps ] [receive: 4605 bytes / 7 ms = 5262857 bps] [075:737] [2363] (trendline_estimator.cc:183): 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 [075:785] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 14] [send: 5532 bytes / 10 ms = 4425600 bps ] [receive: 5534 bytes / 9 ms = 4919111 bps] [076:965] [2363] (bitrate_allocator.cc:394): Current BWE 4425600 [078:263] [2363] (video_send_stream_impl.cc:400): SignalEncoderTimedOut, Encoder timed out. [078:263] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 32 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 32 kbps [078:339] [2324] (audio_device_buffer.cc:414): [REC : 10000msec, 48kHz] callbacks: 26, samples: 12480, rate: 1248, rate diff: 97%, level: 1905 [079:384] [2365] (rtp_rtcp_impl.cc:136): Process: Timeout: No increase in RTCP RR extended highest sequence number. [079:925] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [079:925] [2368] (video_stream_encoder.cc:1160): Number of frames: captured 70, dropped (due to encoder blocked) 0, interval_ms 60000 [079:925] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 62 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 1732 kbps [079:987] [2363] (trendline_estimator.cc:183): 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 [080:263] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [080:334] [2321] (connection.cc:1036): Conn[b4819b90:0:Net[wlan0:192.168.11.x/24:Wifi:id=3]:JY+/dr1W:1:0:local:udp:192.168.11.x:47116->eOfoXkfN:1:1845501695:prflx:udp:192.168.11.x:63674|CRWS|S|1|0|7926369428982087166|32]: Timing-out STUN ping 78342f526443657447464a63 after 60002 ms [080:464] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (3464000:6495:5) [082:163] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 15] [send: 4436 bytes / 11 ms = 3226181 bps ] [receive: 4436 bytes / 11 ms = 3226181 bps] [082:163] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 15] [send: 5545 bytes / 13 ms = 3412307 bps ] [receive: 5545 bytes / 13 ms = 3412307 bps] [082:163] [2363] (trendline_estimator.cc:183): 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 [082:164] [2363] (bitrate_allocator.cc:394): Current BWE 3412307 [084:240] [2363] (trendline_estimator.cc:183): 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 [085:464] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (3464000:6495:5) [087:290] [2363] (bitrate_allocator.cc:394): Current BWE 3412307 [087:841] [2365] (rtp_rtcp_impl.cc:136): Process: Timeout: No increase in RTCP RR extended highest sequence number. [088:263] [2363] (video_send_stream_impl.cc:400): SignalEncoderTimedOut, Encoder timed out. [088:263] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 32 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 32 kbps [088:339] [2324] (audio_device_buffer.cc:414): [REC : 10000msec, 48kHz] callbacks: 38, samples: 18240, rate: 1824, rate diff: 96%, level: 0 [088:484] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [088:484] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 62 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 1732 kbps [088:502] [2363] (trendline_estimator.cc:183): 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 [088:553] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 16] [send: 4850 bytes / 11 ms = 3527272 bps ] [receive: 4830 bytes / 10 ms = 3864 kbps] [088:553] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 16] [send: 5800 bytes / 13 ms = 3569230 bps ] [receive: 5782 bytes / 13 ms = 3558153 bps] [090:264] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [090:488] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (3464000:6495:5) [090:634] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 17] [send: 4895 bytes / 12 ms = 3263333 bps ] [receive: 4882 bytes / 11 ms = 3550545 bps] [090:634] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 17] [send: 5864 bytes / 14 ms = 3350857 bps ] [receive: 5866 bytes / 13 ms = 3609846 bps] [090:634] [2363] (trendline_estimator.cc:183): 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 [093:714] [2363] (bitrate_allocator.cc:394): Current BWE 3350857 [094:211] [2365] (rtp_rtcp_impl.cc:136): Process: Timeout: No increase in RTCP RR extended highest sequence number. [094:263] [2363] (video_send_stream_impl.cc:400): SignalEncoderTimedOut, Encoder timed out. [094:263] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 32 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 32 kbps [094:759] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [094:759] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 62 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 1732 kbps [094:788] [2363] (trendline_estimator.cc:183): 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 [095:489] [2363] (bitrate_prober.cc:116): Probe cluster (bitrate:min bytes:min packets): (3464000:6495:5) [096:263] [2363] (video_send_stream_impl.cc:457): SignalEncoderActive, Encoder is active. [096:917] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 18] [send: 4999 bytes / 12 ms = 3332666 bps ] [receive: 5002 bytes / 11 ms = 3637818 bps] [096:917] [2363] (probe_bitrate_estimator.cc:153): Probing successful [cluster id: 18] [send: 6007 bytes / 15 ms = 3203733 bps ] [receive: 6010 bytes / 14 ms = 3434285 bps] [096:917] [2363] (trendline_estimator.cc:183): 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 [098:339] [2324] (audio_device_buffer.cc:414): [REC : 10000msec, 48kHz] callbacks: 29, samples: 13920, rate: 1392, rate diff: 97%, level: 1809 [099:063] [2363] (trendline_estimator.cc:183): 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 [100:342] [2321] (openssl_stream_adapter.cc:903): Cleanup [100:344] [2321] (dtls_transport.cc:656): DtlsTransport[0|1|_W]: DTLS transport closed by remote [100:344] [2321] (jsep_transport_controller.cc:1351): Transport 0 writability changed to 0. [100:344] [2323] (peer_connection.cc:4685): Changing IceConnectionState 2 => 5 [100:344] [2321] (channel.cc:551): Channel not writable (0) [100:345] [2319] (websocket.cpp:69): onRead: Success [100:345] [2319] (p2p_websocket_session.cpp:63): onRead: system:0 [100:345] [2319] (p2p_websocket_session.cpp:75): onRead: recv_string={"type":"close"} [100:345] [2321] (channel.cc:551): Channel not writable (1) [100:345] [2321] (srtp_transport.cc:365): The params in SRTP transport are reset. [100:346] [2322] (video_send_stream.cc:133): VideoSendStream::UpdateActiveSimulcastLayers [100:346] [2363] (video_send_stream_impl.cc:324): VideoSendStream::UpdateActiveSimulcastLayers [100:346] [2322] (channel.cc:963): Changing video state, send=1 [100:347] [2322] (channel.cc:817): Changing voice state, recv=0 send=1 [100:349] [2322] (audio_device_impl.cc:914): GetPlayoutUnderrunCount [100:349] [2322] (audio_device_generic.cc:48): GetPlayoutUnderrunCount: Not supported on this platform [100:349] [2322] (audio_device_impl.cc:917): output: -1 [100:349] [2322] (webrtc_video_engine.cc:2307): VideoSendStream stats: 755695, {input_fps: 0, encode_fps: 0, encode_ms: 0, encode_usage_perc: 0, target_bps: 1700000, media_bps: 0, suspended: false, bw_adapted_res: false, cpu_adapted_res: false, bw_adapted_fps: false, cpu_adapted_fps: false, #cpu_adaptations: 0, #quality_adaptations: 0} {ssrc: 1366490776, width: 640, height: 480, key: 14, delta: 66, total_bps: 0, retransmit_bps: 0, avg_delay_ms: 45, max_delay_ms: 53, cum_loss: 0, max_ext_seq: 14325, nack: 0, fir: 0, pli: 12} [100:349] [2322] (webrtc_video_engine.cc:1419): Call stats: 755695, {send_bw_bps: 3203733, recv_bw_bps: 0, max_pad_bps: 0, pacer_delay_ms: 0, rtt_ms: 4} [100:349] [2323] (peer_connection.cc:4781): Session: 8988880037952262839 Old state: kStable New state: kClosed [100:350] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 30 kbps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 1700 kbps [100:350] [2322] (audio_device_impl.cc:832): StopRecording [100:375] [2322] (audio_device_buffer.cc:154): StopRecording [100:375] [2322] (audio_device_buffer.cc:174): HISTOGRAM(WebRTC.Audio.RecordedOnlyZeros): 0 [100:375] [2322] (audio_device_buffer.cc:177): total recording time: 82037 [100:375] [2322] (audio_device_impl.cc:836): output: 0 [100:375] [2322] (webrtc_video_engine.cc:1092): SetVideoSend (ssrc= 1366490776, options: nullptr, source = nullptr) [100:376] [2322] (channel.cc:518): Channel disabled [100:376] [2322] (video_send_stream.cc:160): VideoSendStream::Stop [100:376] [2322] (channel.cc:963): Changing video state, send=0 [100:376] [2363] (video_send_stream_impl.cc:378): VideoSendStream::Stop [100:376] [2363] (rtp_rtcp_impl.cc:312): Failed to send RTCP BYE [100:376] [2363] (bitrate_allocator.cc:519): UpdateAllocationLimits : total_requested_min_bitrate: 0 bps, total_requested_padding_bitrate: 0 bps, total_requested_max_bitrate: 0 bps [100:377] [2322] (video_send_stream.cc:160): VideoSendStream::Stop [100:377] [2363] (video_send_stream_impl.cc:378): VideoSendStream::Stop [100:377] [2368] (video_stream_encoder.cc:1916): Video suspend state changed to: suspended [100:379] [2363] (video_send_stream_impl.cc:378): VideoSendStream::Stop [100:379] [2363] (video_send_stream_impl.cc:302): ~VideoSendStreamInternal: {encoder_settings: { experiment_cpu_load_estimator: off}}, rtp: {ssrcs: [1366490776], rtcp_mode: RtcpMode::kReducedSize, max_packet_size: 1200, extmap-allow-mixed: false, extensions: [{uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 3}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 12}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: urn:3gpp:video-orientation, id: 13}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 4}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 6}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 5}], lntf: {enabled: false}, nack: {rtp_history_ms: 1000}, ulpfec: {ulpfec_payload_type: 116, red_payload_type: 114, red_rtx_payload_type: 115}, payload_name: VP8, payload_type: 96, raw_payload: false, flexfec: {payload_type: -1, ssrc: 0, protected_media_ssrcs: []}, rtx: {ssrcs: [211509755], payload_type: 97}, c_name: QVXt6J+XDjYF4pMe}, rtcp_report_interval_ms: 1000, send_transport: (Transport), render_delay_ms: 0, target_delay_ms: 0, suspend_below_min_bitrate: off} [100:380] [2322] (call.cc:1047): UpdateAggregateNetworkState: aggregate_state=up [100:380] [2322] (rtp_transport_controller_send.cc:305): SignalNetworkState Up [100:380] [2322] (send_statistics_proxy.cc:659): WebRTC.Video.Screenshare.InputFramesPerSecond periodic_samples:41, {min:0, avg:1, max:6} WebRTC.Video.Screenshare.SentFramesPerSecond periodic_samples:41, {min:0, avg:1, max:3} WebRTC.Video.Screenshare.SentToInputFpsRatioPercent 100 WebRTC.Video.Screenshare.SentPacketsLostInPercent 0 WebRTC.Video.Screenshare.NumberOfPauseEvents 0 WebRTC.Video.Screenshare.PausedTimeInPercent 0 WebRTC.Video.Screenshare.BitrateSentInBps periodic_samples:41, {min:0, avg:43296, max:151632} WebRTC.Video.Screenshare.MediaBitrateSentInBps periodic_samples:41, {min:0, avg:38992, max:147136} WebRTC.Video.Screenshare.PaddingBitrateSentInBps periodic_samples:41, {min:0, avg:40, max:896} WebRTC.Video.Screenshare.RetransmittedBitrateSentInBps periodic_samples:41, {min:0, avg:0, max:0} WebRTC.Video.Screenshare.RtxBitrateSentInBps periodic_samples:41, {min:0, avg:3312, max:23440} WebRTC.Video.Screenshare.FecBitrateSentInBps periodic_samples:41, {min:0, avg:0, max:0} Frames encoded 80 WebRTC.Video.Screenshare.DroppedFrames.Capturer 0 WebRTC.Video.Screenshare.DroppedFrames.EncoderQueue 8 WebRTC.Video.Screenshare.DroppedFrames.Encoder 0 WebRTC.Video.Screenshare.DroppedFrames.Ratelimiter 0 [100:381] [2322] (channel.cc:160): Destroyed channel: 0 [100:381] [2322] (channel.cc:518): Channel disabled [100:381] [2322] (channel.cc:817): Changing voice state, recv=0 send=0 [100:382] [2322] (webrtc_voice_engine.cc:1799): RemoveSendStream: 3064290279 [100:382] [2322] (call.cc:1047): UpdateAggregateNetworkState: aggregate_state=down [100:382] [2322] (rtp_transport_controller_send.cc:305): SignalNetworkState Down [100:382] [2322] (audio_send_stream.cc:178): ~AudioSendStream: 3064290279 [100:382] [2363] (pacing_controller.cc:143): PacedSender paused. [100:382] [2363] (control_handler.cc:78): Bitrate estimate state changed, BWE: 3203733 bps. [100:383] [2322] (channel.cc:160): Destroyed channel: 1 [100:383] [2321] (peer_connection.cc:6722): Tearing down data channel transport for mid=2 [100:383] [2321] (sctp_transport.cc:248): UninitializeUsrSctp [100:389] [2321] (openssl_stream_adapter.cc:903): Cleanup [100:390] [2322] (call.cc:547): WebRTC.Call.EstimatedSendBitrateInKbps, periodic_samples:41, {min:1400, avg:3100, max:4425} [100:390] [2322] (call.cc:555): WebRTC.Call.PacerBitrateInKbps, periodic_samples:41, {min:1400, avg:3100, max:4425} [100:390] [2322] (paced_sender.cc:167): ProcessThreadAttached 0x0 [100:391] [2322] (paced_sender.cc:167): ProcessThreadAttached 0x0 [100:391] [2322] (send_delay_stats.cc:49): WebRTC.Video.SendDelayInMs, periodic_samples:27, {min:0, avg:1, max:7} [100:393] [2323] (peer_connection.cc:1079): Session: 8988880037952262839 is destroyed. [100:394] [2323] (dtmf_sender.cc:217): The Dtmf provider is deleted. Clear the sending queue. [100:394] [2319] (websocket.cpp:49): doRead