search

BelledonneCommunications / linphone-android

Linphone.org mirror for linphone-android (https://gitlab.linphone.org/BC/public/linphone-android)
https://linphone.org
GNU General Public License v3.0
1.12k stars 686 forks source link

How to reduce Video Delay Time #596

Closed fengqz627 closed 5 years ago

fengqz627 commented 5 years ago

Hi, When two devices are installed with linphone 4.0.1, in a call, when one side operates, the video delay on the other side is about 0.4 - 0.5 seconds. how to reduce video delay time? the best delay is less than 0.2 seconds. Where can I download the latest version of linpone apk? I want to test it.

thanks. Best Regards.

fengqz627 commented 5 years ago

I forgot to tell I used the H264 option。

Viish commented 5 years ago

Hi, Have you tried VP8 video codec ? The latest beta is available at: http://linphone.org/releases/android/linphone-latest-beta.apk

fengqz627 commented 5 years ago

Thank you very much for your reply. yes, I've tried, VP8 has a bigger delay time. What patches can be improved, or what parameters can be set to improve it?

thanks.

fengqz627 commented 5 years ago

There seems to be some problem with this APK, dialing dd@ip can not call each other.

Viish commented 5 years ago

Check if random port setting is ON in network preferences. If yes, disable it otherwise it won't work for direct calling.

fengqz627 commented 5 years ago

I've tested the latest APK and the video delay time is very high. Is there any way to improve it?

Viish commented 5 years ago

We don't have such delays, please attach your logs.

fengqz627 commented 5 years ago

A is caller, B is callee, B displays A's video, the delay time is about 0.4-0.5 second. this is A's logs (caller): 04-26 17:21:30.510 31038-5830/org.linphone I/mediastreamer: MSAudio MSTicker priority increased to maximum. 04-26 17:21:30.510 1677-1721/? E/Sayeye: failed to open /sys/devices/soc.0/1c2ac00.twi/i2c-0/0-0040/runtime_suspend, No such file or directory 04-26 17:21:30.510 1677-1721/? D/Sayeye: Scense (Normal) state set succeed 04-26 17:21:30.511 2209-2360/system_process I/DynamicPManagerService: Current Scense:normal 04-26 17:21:30.518 31038-31038/org.linphone I/belle-sip: bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x7f848b8540)] acquired [ref=0x75a] 04-26 17:21:30.518 31038-31038/org.linphone I/belle-sip: transaction [0x7f848b8540]: starting transaction background task with id=[75a]. 04-26 17:21:30.519 31038-31038/org.linphone I/belle-sip: Changing [client] [INVITE] transaction [0x7f848b8540], from state [INIT] to [CALLING] 04-26 17:21:30.520 31038-31038/org.linphone I/belle-sip: channel [0x7f83902000]: message sent to [UDP://192.192.255.214:5060], size: [1389] bytes INVITE sip:dd@192.192.255.214 SIP/2.0 Via: SIP/2.0/UDP 192.192.255.133:5060;branch=z9hG4bK.wk~kWmZRF;rport From: "Linphone Android" sip:linphone.android@192.192.255.133;tag=BEwl0BufV To: sip:dd@192.192.255.214 CSeq: 20 INVITE Call-ID: CzON8ONQEJ Max-Forwards: 70 Supported: replaces, outbound, gruu Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Content-Type: application/sdp Content-Length: 706 Contact: "Linphone Android" sip:linphone.android@192.192.255.133;transport=udp;+sip.instance="";+org.linphone.specs=groupchat User-Agent: LinphoneAndroid/4.0.1 (belle-sip/1.6.3)

                                                     v=0
                                                     o=linphone.android 679 747 IN IP4 192.192.255.133
                                                     s=Talk
                                                     c=IN IP4 192.192.255.133
                                                     t=0 0
                                                     a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
                                                     m=audio 7076 RTP/AVP 96 97 98 0 8 18 101 99 100
                                                     a=rtpmap:96 opus/48000/2
                                                     a=fmtp:96 useinbandfec=1
                                                     a=rtpmap:97 speex/16000
                                                     a=fmtp:97 vbr=on
                                                     a=rtpmap:98 speex/8000
                                                     a=fmtp:98 vbr=on
                                                     a=fmtp:18 annexb=yes
                                                     a=rtpmap:101 telephone-event/48000
                                                     a=rtpmap:99 telephone-event/16000
                                                     a=rtpmap:100 telephone-event/8000
                                                     a=rtcp-fb:* trr-int 5
                                                     a=rtcp-fb:* ccm tmmbr
                                                     m=video 9078 RTP/AVP 96
                                                     a=rtpmap:96 H264/90000
                                                     a=fmtp:96 profile-level-id=42801F
                                                     a=rtcp-fb:* trr-int 5
                                                     a=rtcp-fb:* ccm tmmbr
                                                     a=rtcp-fb:96 nack pli
                                                     a=rtcp-fb:96 ccm fir

04-26 17:21:30.521 31038-31038/org.linphone I/bctbx: CallSession [0x7f83a914f8] moving from state State::OutgoingInit to State::OutgoingProgress 04-26 17:21:30.521 31038-31038/org.linphone I/mediastreamer: New call state [OutgoingProgress] 04-26 17:21:30.523 2209-4785/system_process I/ActivityManager: START u0 {cmp=org.linphone/.call.CallOutgoingActivity} from uid 10047 on display 0 04-26 17:21:30.529 1677-1721/? D/Sayeye: current scense is (Normal) 04-26 17:21:30.529 1677-1721/? E/Sayeye: failed to open /sys/devices/soc.0/1c2ac00.twi/i2c-0/0-0040/runtime_suspend, No such file or directory 04-26 17:21:30.529 1677-1721/? D/Sayeye: Scense (Normal) state set succeed 04-26 17:21:30.530 2209-2360/system_process I/DynamicPManagerService: Current Scense:normal 04-26 17:21:30.536 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_state_changed] 04-26 17:21:30.539 31038-31038/org.linphone I/belle-sip: Garbage collecting unowned object of type belle_sdp_session_description_t 04-26 17:21:30.545 31038-31038/org.linphone I/liblinphone: Callbacks [0x7f8499f230] unregistered on core [0x7f9a9f6200] 04-26 17:21:30.546 31038-31038/org.linphone I/liblinphone: Callbacks [0x7f8499f1e0] unregistered on core [0x7f9a9f6200] 04-26 17:21:30.550 1677-1721/? D/Sayeye: current scense is (Normal) 04-26 17:21:30.551 1677-1721/? E/Sayeye: failed to open /sys/devices/soc.0/1c2ac00.twi/i2c-0/0-0040/runtime_suspend, No such file or directory 04-26 17:21:30.551 1677-1721/? D/Sayeye: Scense (Normal) state set succeed 04-26 17:21:30.551 2209-2360/system_process I/DynamicPManagerService: Current Scense:normal 04-26 17:21:30.587 31038-31038/org.linphone I/liblinphone: Core callbacks [0x7f849a09a0] registered on core [0x7f9a9f6200] 04-26 17:21:30.589 31038-31038/org.linphone I/liblinphone: Core callbacks [0x7f849a0950] registered on core [0x7f9a9f6200] 04-26 17:21:30.595 31038-31038/org.linphone I/belle-sip: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x200786] 04-26 17:21:30.595 31038-31038/org.linphone I/belle-sip: channel [0x7f83902000]: starting recv background task with id=[200786]. 04-26 17:21:30.596 31038-31038/org.linphone I/belle-sip: channel [0x7f83902000]: received [237] new bytes from [UDP://192.192.255.214:5060]: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.192.255.133:5060;branch=z9hG4bK.wk~kWmZRF;rport From: "Linphone Android" sip:linphone.android@192.192.255.133;tag=BEwl0BufV To: sip:dd@192.192.255.214 Call-ID: CzON8ONQEJ CSeq: 20 INVITE 04-26 17:21:30.601 31038-31038/org.linphone I/belle-sip: channel [0x7f83902000] [237] bytes parsed 04-26 17:21:30.601 31038-31038/org.linphone I/belle-sip: Found transaction matching response. 04-26 17:21:30.601 31038-31038/org.linphone I/belle-sip: Changing [client] [INVITE] transaction [0x7f848b8540], from state [CALLING] to [PROCEEDING] 04-26 17:21:30.601 31038-31038/org.linphone I/bctbx: op [0x7f839c9400] : set_or_update_dialog() current=[0x0] new=[0x0] 04-26 17:21:30.601 31038-31038/org.linphone I/bctbx: Op [0x7f839c9400] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 04-26 17:21:30.602 31038-31038/org.linphone I/belle-sip: channel [0x7f83902000]: ending recv background task with id=[200786]. 04-26 17:21:30.603 31038-31038/org.linphone I/belle-sip: bellesip_wake_lock_release(): Android wake lock released [ref=0x200786] 04-26 17:21:30.615 1698-2478/? V/audio_hw_primary: out_set_parameters: routing=1 04-26 17:21:30.615 1698-2478/? V/audio_hw_primary: out_set_parameters, line: 1562 04-26 17:21:30.615 1698-2478/? V/audio_hw_primary: >>>>>>>> adev->mode:3,adev->out_device is: 1, val is : 1 04-26 17:21:30.621 1668-1728/? I/[Gralloc]: ion_alloc from ion_client:19 via heap type DMA(mask:16) for 3942400 Bytes uncached buffer successfully, usage = 0x00000f02 04-26 17:21:30.641 2209-2378/system_process I/ActivityManager: Displayed org.linphone/.call.CallOutgoingActivity: +92ms (total +5m28s499ms) 04-26 17:21:30.658 1668-1726/? I/[Gralloc]: ion_alloc from ion_client:19 via heap type DMA(mask:16) for 3942400 Bytes uncached buffer successfully, usage = 0x00000f02 04-26 17:21:30.676 2209-2378/system_process I/ActivityManager: Timeline: Activity_windows_visible id: org.linphone time:2494468 04-26 17:21:31.031 31038-31038/org.linphone I/belle-sip: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x2007b6] 04-26 17:21:31.032 31038-31038/org.linphone I/belle-sip: channel [0x7f83902000]: starting recv background task with id=[2007b6]. 04-26 17:21:31.032 31038-31038/org.linphone I/belle-sip: channel [0x7f83902000]: received [342] new bytes from [UDP://192.192.255.214:5060]: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.192.255.133:5060;branch=z9hG4bK.wk~kWmZRF;rport From: "Linphone Android" sip:linphone.android@192.192.255.133;tag=BEwl0BufV To: sip:dd@192.192.255.214;tag=fdbxnMB Call-ID: CzON8ONQEJ CSeq: 20 INVITE User-Agent: LinphoneAndroid/4.0.1 (belle-sip/1.6.3) Supported: replaces, outbound, gruu 04-26 17:21:31.046 31038-31038/org.linphone I/belle-sip: channel [0x7f83902000] [342] bytes parsed 04-26 17:21:31.046 31038-31038/org.linphone I/belle-sip: Found transaction matching response. 04-26 17:21:31.047 31038-31038/org.linphone I/belle-sip: New client dialog [0x7f85d0be00] , local tag [BEwl0BufV], remote tag [fdbxnMB] 04-26 17:21:31.047 31038-31038/org.linphone I/belle-sip: Dialog [0x7f85d0be00]: now updated by transaction [0x7f848b8540]. 04-26 17:21:31.048 31038-31038/org.linphone I/bctbx: op [0x7f839c9400] : set_or_update_dialog() current=[0x0] new=[0x7f85d0be00] 04-26 17:21:31.049 31038-31038/org.linphone I/bctbx: Op [0x7f839c9400] receiving call response [180], dialog is [0x7f85d0be00] in state [BELLE_SIP_DIALOG_EARLY] 04-26 17:21:31.049 31038-31038/org.linphone I/mediastreamer: ms_filter_unlink: MSRtpRecv:0x7f83a84300,0-->MSVoidSink:0x7f8495f600,0 04-26 17:21:31.050 31038-31038/org.linphone I/mediastreamer: speex_lib_ctl init with neon ? 1 04-26 17:21:31.051 31038-31038/org.linphone I/mediastreamer: Configuring resampler input with rate=[8000], nchannels=[1] 04-26 17:21:31.051 31038-31038/org.linphone I/mediastreamer: MSFilePlayer[0x7f83a84300]: /data/user/0/org.linphone/files/share/sounds/linphone/ringback.wav opened: rate=8000,channel=1 04-26 17:21:31.051 31038-31038/org.linphone I/mediastreamer: configuring resampler output to rate=[44100], nchannels=[1] 04-26 17:21:31.052 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSFilePlayer:0x7f83a84300,0-->MSDtmfGen:0x7f8495f600,0 04-26 17:21:31.052 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSDtmfGen:0x7f8495f600,0-->MSResample:0x7f8497d080,0 04-26 17:21:31.053 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSResample:0x7f8497d080,0-->MSOpenSLESPlayer:0x7f8497d100,0 04-26 17:21:31.053 31038-31038/org.linphone I/mediastreamer: Initializing speex resampler in mode [voip] 04-26 17:21:31.053 31038-5860/org.linphone I/mediastreamer: Priority used: 99 04-26 17:21:31.054 31038-5860/org.linphone I/mediastreamer: Ring MSTicker priority increased to maximum. 04-26 17:21:31.068 1698-2478/? V/audio_hw_primary: out_set_parameters: routing=1 04-26 17:21:31.068 1698-2478/? V/audio_hw_primary: out_set_parameters, line: 1562 04-26 17:21:31.068 1698-2478/? V/audio_hw_primary: >>>>>>>> adev->mode:3,adev->out_device is: 1, val is : 1 04-26 17:21:31.070 31038-31038/org.linphone I/bctbx: Remote ringing... 04-26 17:21:31.070 31038-31038/org.linphone I/bctbx: CallSession [0x7f83a914f8] moving from state State::OutgoingProgress to State::OutgoingRinging 04-26 17:21:31.071 31038-31038/org.linphone I/mediastreamer: New call state [OutgoingRinging] 04-26 17:21:31.074 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_state_changed] 04-26 17:21:31.075 31038-31038/org.linphone I/belle-sip: channel [0x7f83902000]: ending recv background task with id=[2007b6]. 04-26 17:21:31.078 31038-31038/org.linphone I/belle-sip: bellesip_wake_lock_release(): Android wake lock released [ref=0x2007b6] 04-26 17:21:31.114 1698-2478/? D/AudioFlinger: mixer(0xf1f40000) throttle end: throttle time(12) 04-26 17:21:32.299 1698-2478/? D/AudioFlinger: mixer(0xf1f40000) throttle end: throttle time(2) 04-26 17:21:33.601 31038-31038/org.linphone I/belle-sip: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x2007b2] 04-26 17:21:33.602 31038-31038/org.linphone I/belle-sip: channel [0x7f83902000]: starting recv background task with id=[2007b2]. 04-26 17:21:33.602 31038-31038/org.linphone I/belle-sip: channel [0x7f83902000]: received [1368] new bytes from [UDP://192.192.255.214:5060]: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.192.255.133:5060;branch=z9hG4bK.wk~kWmZRF;rport From: "Linphone Android" sip:linphone.android@192.192.255.133;tag=BEwl0BufV To: sip:dd@192.192.255.214;tag=fdbxnMB Call-ID: CzON8ONQEJ CSeq: 20 INVITE User-Agent: LinphoneAndroid/4.0.1 (belle-sip/1.6.3) Supported: replaces, outbound, gruu Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Contact: "Linphone Android" sip:linphone.android@192.192.255.214;transport=udp;+sip.instance="";+org.linphone.specs=groupchat Content-Type: application/sdp Content-Length: 712

                                                     v=0
                                                     o=linphone.android 2075 21 IN IP4 192.192.255.214
                                                     s=Talk
                                                     c=IN IP4 192.192.255.214
                                                     t=0 0
                                                     a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
                                                     m=audio 7076 RTP/AVP 96 97 98 0 8 18 101 99 100
                                                     a=rtpmap:96 opus/48000/2
                                                     a=fmtp:96 useinbandfec=1
                                                     a=rtpmap:97 speex/16000
                                                     a=fmtp:97 vbr=on
                                                     a=rtpmap:98 speex/8000
                                                     a=fmtp:98 vbr=on
                                                     a=fmtp:18 annexb=yes
                                                     a=rtpmap:101 telephone-event/48000
                                                     a=rtpmap:99 telephone-event/16000
                                                     a=rtpmap:100 telephone-event/8000
                                                     a=rtcp-fb:* trr-int 5000
                                                     a=rtcp-fb:* ccm tmmbr
                                                     m=video 9078 RTP/AVP 96
                                                     a=rtpmap:96 H264/90000
                                                     a=fmtp:96 profile-level-id=42801F
                                                     a=rtcp-fb:* trr-int 5000
                                                     a=rtcp-fb:* ccm tmmbr
                                                     a=rtcp-fb:96 nack pli
                                                     a=rtcp-fb:96 ccm fir

04-26 17:21:33.625 31038-31038/org.linphone I/belle-sip: channel [0x7f83902000] [656] bytes parsed 04-26 17:21:33.625 31038-31038/org.linphone I/belle-sip: channel [0x7f83902000] read [712] bytes of body from [192.192.255.214:5060] 04-26 17:21:33.626 31038-31038/org.linphone I/belle-sip: Found transaction matching response. 04-26 17:21:33.626 31038-31038/org.linphone I/belle-sip: Changing [client] [INVITE] transaction [0x7f848b8540], from state [PROCEEDING] to [ACCEPTED] 04-26 17:21:33.627 31038-31038/org.linphone I/belle-sip: Dialog [0x7f85d0be00]: now updated by transaction [0x7f848b8540]. 04-26 17:21:33.633 31038-31038/org.linphone I/bctbx: op [0x7f839c9400] : set_or_update_dialog() current=[0x7f85d0be00] new=[0x7f85d0be00] 04-26 17:21:33.634 31038-31038/org.linphone I/bctbx: Op [0x7f839c9400] receiving call response [200], dialog is [0x7f85d0be00] in state [BELLE_SIP_DIALOG_CONFIRMED] 04-26 17:21:33.671 31038-31038/org.linphone I/liblinphone: Found payload opus/48000 fmtp=useinbandfec=1 04-26 17:21:33.671 31038-31038/org.linphone I/liblinphone: Found payload speex/16000 fmtp=vbr=on 04-26 17:21:33.671 31038-31038/org.linphone I/liblinphone: Found payload speex/8000 fmtp=vbr=on 04-26 17:21:33.672 31038-31038/org.linphone I/liblinphone: Found payload PCMU/8000 fmtp= 04-26 17:21:33.672 31038-31038/org.linphone I/liblinphone: Found payload PCMA/8000 fmtp= 04-26 17:21:33.672 31038-31038/org.linphone I/liblinphone: Found payload G729/8000 fmtp=annexb=yes 04-26 17:21:33.672 31038-31038/org.linphone I/liblinphone: Found payload telephone-event/48000 fmtp= 04-26 17:21:33.672 31038-31038/org.linphone I/liblinphone: Found payload telephone-event/16000 fmtp= 04-26 17:21:33.673 31038-31038/org.linphone I/liblinphone: Found payload telephone-event/8000 fmtp= 04-26 17:21:33.673 31038-31038/org.linphone I/liblinphone: Found payload H264/90000 fmtp=profile-level-id=42801F 04-26 17:21:33.674 31038-31038/org.linphone I/bctbx: Doing SDP offer/answer process of type outgoing 04-26 17:21:33.676 31038-31038/org.linphone I/liblinphone: Processing for stream 0 04-26 17:21:33.676 31038-31038/org.linphone I/liblinphone: Doing offer/answer processing with specific provider for codec [opus] 04-26 17:21:33.676 31038-31038/org.linphone I/liblinphone: Processing for stream 1 04-26 17:21:33.677 31038-31038/org.linphone I/bctbx: CallSession [0x7f83a914f8] moving from state State::OutgoingRinging to State::Connected 04-26 17:21:33.677 31038-31038/org.linphone I/mediastreamer: New call state [Connected] 04-26 17:21:33.681 31038-31038/org.linphone W/OrientationEventListener: Cannot detect sensors. Not enabled 04-26 17:21:33.683 2209-5282/system_process I/ActivityManager: START u0 {cmp=org.linphone/.call.CallActivity} from uid 10047 on display 0 04-26 17:21:33.691 1677-1721/? D/Sayeye: current scense is (Normal) 04-26 17:21:33.713 1677-1721/? E/Sayeye: failed to open /sys/devices/soc.0/1c2ac00.twi/i2c-0/0-0040/runtime_suspend, No such file or directory 04-26 17:21:33.713 1677-1721/? D/Sayeye: Scense (Normal) state set succeed 04-26 17:21:33.714 2209-2360/system_process I/DynamicPManagerService: Current Scense:normal 04-26 17:21:33.719 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_state_changed] 04-26 17:21:33.722 31038-31038/org.linphone I/mediastreamer: ms_filter_unlink: MSFilePlayer:0x7f83a84300,0-->MSDtmfGen:0x7f8495f600,0 04-26 17:21:33.722 31038-31038/org.linphone I/mediastreamer: ms_filter_unlink: MSDtmfGen:0x7f8495f600,0-->MSResample:0x7f8497d080,0 04-26 17:21:33.722 31038-31038/org.linphone I/mediastreamer: ms_filter_unlink: MSResample:0x7f8497d080,0-->MSOpenSLESPlayer:0x7f8497d100,0 04-26 17:21:33.724 31038-5860/org.linphone I/mediastreamer: Ring MSTicker thread exiting 04-26 17:21:33.725 31038-5860/org.linphone W/art: Native thread exiting without having called DetachCurrentThread (maybe it's going to use a pthread_key_create destructor?): Thread[17,tid=5860,Native,Thread*=0x7f849dd800,peer=0x130330a0,"Thread-245"] 04-26 17:21:33.725 31038-5860/org.linphone I/mediastreamer: Thread end, detaching jvm from current thread 04-26 17:21:33.729 31038-31038/org.linphone I/bctbx: startStreams() CallSession=[0x7f83a914f8] local upload_bandwidth=[0] kbit/s; local download_bandwidth=[0] kbit/s 04-26 17:21:33.729 31038-31038/org.linphone I/bctbx: Audio bandwidth for CallSession [0x7f83a914f8] is 50 04-26 17:21:33.730 31038-31038/org.linphone I/bctbx: CallSession [0x7f83a914f8] - setting up advanced rate control 04-26 17:21:33.734 31038-31038/org.linphone I/ortp: RtpSession [0x7f838eb000] sending to rtp [::ffff:192.192.255.214]:7076 rtcp [::ffff:192.192.255.214]:7077 04-26 17:21:33.734 31038-31038/org.linphone I/mediastreamer: Stun packet sent for session [0x7f838eb000] 04-26 17:21:33.735 31038-31038/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f838eb000] 04-26 17:21:33.735 31038-31038/org.linphone I/bctbx: Webrtc aecm does not support sampling rate 48000, using 16000 instead 04-26 17:21:33.735 31038-31038/org.linphone I/mediastreamer: Sampling rate forced to 16000Hz to allow the use of echo canceller 04-26 17:21:33.736 31038-31038/org.linphone I/mediastreamer: opus stereo support is deactivated because of incompatible features targeted for this AudioStream 04-26 17:21:33.736 31038-31038/org.linphone I/mediastreamer: speex_lib_ctl init with neon ? 1 04-26 17:21:33.736 31038-31038/org.linphone I/mediastreamer: speex_lib_ctl init with neon ? 1 04-26 17:21:33.736 31038-31038/org.linphone I/mediastreamer: Setting echo canceller delay with value provided by soundcard: 250 ms 04-26 17:21:33.737 31038-31038/org.linphone I/mediastreamer: target bitrate not set for stream [0x7f838c3b80] using payload's bitrate is 50000 04-26 17:21:33.737 31038-31038/org.linphone I/mediastreamer: Setting audio encoder network bitrate to [50000] on stream [0x7f838c3b80] 04-26 17:21:33.737 31038-31038/org.linphone I/mediastreamer: opus setbitrate to 50000 04-26 17:21:33.737 31038-31038/org.linphone I/mediastreamer: Decoder has FEC capabilities 04-26 17:21:33.741 31038-31038/org.linphone I/mediastreamer: This device (gemvary/QUAD-P3/tulip) says it has no built-in echo canceller. 04-26 17:21:33.742 31038-31038/org.linphone I/mediastreamer: No information available for [gemvary/QUAD-P3/tulip], 04-26 17:21:33.742 31038-31038/org.linphone I/mediastreamer: Sound device information for [gemvary/QUAD-P3/tulip] is: builtin=[no], delay=[250] ms 04-26 17:21:33.746 31038-31038/org.linphone I/mediastreamer: This device (gemvary/QUAD-P3/tulip) says it has no built-in echo canceller. 04-26 17:21:33.746 31038-31038/org.linphone I/mediastreamer: No information available for [gemvary/QUAD-P3/tulip], 04-26 17:21:33.747 31038-31038/org.linphone I/mediastreamer: Sound device information for [gemvary/QUAD-P3/tulip] is: builtin=[no], delay=[250] ms 04-26 17:21:33.747 31038-31038/org.linphone I/mediastreamer: MSVolume set gain to [0.000000 db], [1.000000] linear 04-26 17:21:33.751 31038-31038/org.linphone I/mediastreamer: This device (gemvary/QUAD-P3/tulip) says it has no built-in echo canceller. 04-26 17:21:33.751 31038-31038/org.linphone I/mediastreamer: No information available for [gemvary/QUAD-P3/tulip], 04-26 17:21:33.751 31038-31038/org.linphone I/mediastreamer: Sound device information for [gemvary/QUAD-P3/tulip] is: builtin=[no], delay=[250] ms 04-26 17:21:33.751 31038-31038/org.linphone I/mediastreamer: MSVolume set gain to [0.000000 db], [1.000000] linear 04-26 17:21:33.752 31038-31038/org.linphone I/mediastreamer: configuring MSOpenSLESRecorder:0x7f8497d100-->MSOpusEnc:0x7f83a84300 from rate [44100] to rate [16000] and from channel [1] to channel [1] 04-26 17:21:33.752 31038-31038/org.linphone I/mediastreamer: configuring MSOpusDec:0x7f8497d300-->MSOpenSLESPlayer:0x7f8495f600 from rate [16000] to rate [44100] and from channel [1] to channel [1] 04-26 17:21:33.752 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSOpenSLESRecorder:0x7f8497d100,0-->MSResample:0x7f8495ff00,0 04-26 17:21:33.752 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSResample:0x7f8495ff00,0-->MSEqualizer:0x7f8497d280,0 04-26 17:21:33.753 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSEqualizer:0x7f8497d280,0-->MSWebRTCAECM:0x7f8495f200,1 04-26 17:21:33.753 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSWebRTCAECM:0x7f8495f200,1-->MSVolume:0x7f8495fe00,0 04-26 17:21:33.753 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSVolume:0x7f8495fe00,0-->MSAudioMixer:0x7f8497d000,0 04-26 17:21:33.753 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSAudioMixer:0x7f8497d000,0-->MSOpusEnc:0x7f83a84300,0 04-26 17:21:33.753 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSOpusEnc:0x7f83a84300,0-->MSRtpSend:0x7f8495f180,0 04-26 17:21:33.753 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSRtpRecv:0x7f8497d080,0-->MSOpusDec:0x7f8497d300,0 04-26 17:21:33.754 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSOpusDec:0x7f8497d300,0-->MSAudioFlowControl:0x7f8497d500,0 04-26 17:21:33.754 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSAudioFlowControl:0x7f8497d500,0-->MSDtmfGen:0x7f8495fb00,0 04-26 17:21:33.754 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSDtmfGen:0x7f8495fb00,0-->MSVolume:0x7f8495fe80,0 04-26 17:21:33.754 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSVolume:0x7f8495fe80,0-->MSEqualizer:0x7f8497d380,0 04-26 17:21:33.754 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSEqualizer:0x7f8497d380,0-->MSAudioMixer:0x7f8497d980,0 04-26 17:21:33.755 31038-31038/org.linphone I/mediastreamer: speex_lib_ctl init with neon ? 1 04-26 17:21:33.755 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSFilePlayer:0x7f8497db00,0-->MSResample:0x7f8497db80,0 04-26 17:21:33.755 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSResample:0x7f8497db80,0-->MSAudioMixer:0x7f8497d980,1 04-26 17:21:33.755 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSAudioMixer:0x7f8497d980,0-->MSWebRTCAECM:0x7f8495f200,0 04-26 17:21:33.755 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSWebRTCAECM:0x7f8495f200,0-->MSResample:0x7f8495ff80,0 04-26 17:21:33.756 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSResample:0x7f8495ff80,0-->MSOpenSLESPlayer:0x7f8495f600,0 04-26 17:21:33.757 1698-31213/? V/audio_hw_primary: to malloc in-buffer: period_size: 1024, frame_size: 4 04-26 17:21:33.758 1698-6005/? I/AudioFlinger: AudioFlinger's thread 0xf13c0000 ready to run 04-26 17:21:33.766 1698-6005/? V/audio_hw_primary: in_set_parameters: input_source=7;routing=-2147483644 04-26 17:21:33.768 31038-31038/org.linphone I/mediastreamer: Initializing speex resampler in mode [voip] 04-26 17:21:33.769 31038-31038/org.linphone I/bctbx: Initializing WebRTC echo canceler with framesize=160, delay_ms=250, delay_samples=4000 04-26 17:21:33.770 31038-31038/org.linphone I/mediastreamer: Initializing speex resampler in mode [voip] 04-26 17:21:33.770 31038-31038/org.linphone I/mediastreamer: Initializing speex resampler in mode [voip] 04-26 17:21:33.774 31038-31038/org.linphone I/mediastreamer: MSOpusEnc: codec bitrate set to [34000] with ptime [20] 04-26 17:21:33.775 31038-31038/org.linphone I/mediastreamer: Setting opus codec bitrate to [34000] from network bitrate [50000] with ptime [20] 04-26 17:21:33.775 31038-31038/org.linphone I/mediastreamer: Filter MSRtpRecv is already being scheduled; nothing to do. 04-26 17:21:33.776 1698-6005/? V/audio_hw_primary: start_input_stream, line: 1810 04-26 17:21:33.776 1698-6005/? D/audio_hw_primary: >>>>>> select_device 04-26 17:21:33.777 1698-6005/? V/audio_hw_primary: active_output, LINE:1050,FUNC:select_device, adev->out_device:1 04-26 17:21:33.777 1698-6005/? V/audio_hw_primary: LINE:1056,FUNC:select_device, headset_on:0, headphone_on:0, speaker_on:0, earpiece_on:1, bt_on:0 04-26 17:21:33.777 1698-6005/? V/audio_hw_primary: LINE:1083,FUNC:select_device, output_device_id:6 04-26 17:21:33.778 1698-6005/? V/audio_hw_primary: select_device, line: 1104 04-26 17:21:33.778 1698-6005/? V/audio_hw_primary: record,LINE:1156,FUNC:select_device, adev->in_device:4,AUDIO_DEVICE_IN_ALL_SCO:80000008 04-26 17:21:33.778 1698-6005/? V/audio_hw_primary: fm record,****LINE:1181,FUNC:select_device,bt_on:0,headset_on:0,main_mic_on;4,adev->in_device:4,AUDIO_DEVICE_IN_ALL_SCO:80000008 04-26 17:21:33.778 1698-6005/? V/audio_hw_primary: select_device, line: 1201 04-26 17:21:33.781 31038-5830/org.linphone I/ortp: First estimation 04-26 17:21:33.781 31038-31038/org.linphone I/mediastreamer: This device (gemvary/QUAD-P3/tulip) says it has no built-in echo canceller. 04-26 17:21:33.782 31038-31038/org.linphone I/mediastreamer: No information available for [gemvary/QUAD-P3/tulip], 04-26 17:21:33.782 31038-5830/org.linphone I/mediastreamer: Stun packet sent for session [0x7f838eb000] 04-26 17:21:33.782 31038-31038/org.linphone I/mediastreamer: Sound device information for [gemvary/QUAD-P3/tulip] is: builtin=[no], delay=[250] ms 04-26 17:21:33.782 31038-31038/org.linphone I/mediastreamer: MSVolume set gain to [0.000000 db], [1.000000] linear 04-26 17:21:33.782 31038-5830/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f838eb000] 04-26 17:21:33.782 31038-31038/org.linphone I/mediastreamer: Setting video size 640x480 on stream [0x7f838cc000] 04-26 17:21:33.783 31038-31038/org.linphone I/bctbx: CallSession [0x7f83a914f8] - setting up advanced rate control 04-26 17:21:33.783 31038-31038/org.linphone I/bctbx: startVideoStream: device_rotation=0 04-26 17:21:33.785 31038-31038/org.linphone I/mediastreamer: Instanciating Android VIDEO capture MS filter 04-26 17:21:33.785 31038-31038/org.linphone I/mediastreamer: Creating AndroidReaderContext for Android VIDEO capture filter 04-26 17:21:33.785 31038-31038/org.linphone I/mediastreamer: Init of Android VIDEO capture filter (0x7f80383600) 04-26 17:21:33.786 31038-31038/org.linphone I/mediastreamer: getHelperClassGlobalRef (env: 0x7f9a79b180) 04-26 17:21:33.786 31038-31038/org.linphone I/mediastreamer: Android SDK version found is 23 04-26 17:21:33.790 31038-31038/org.linphone I/ortp: RtpSession [0x7f838eec00] sending to rtp [::ffff:192.192.255.214]:9078 rtcp [::ffff:192.192.255.214]:9079 04-26 17:21:33.790 31038-5830/org.linphone W/bctbx: Getting reference signal but no echo to synchronize on. 04-26 17:21:33.790 31038-31038/org.linphone I/ortp: Using permissive algorithm 04-26 17:21:33.790 31038-5830/org.linphone I/mediastreamer: MSAudioMixer [0x7f8497d000] is entering bypass mode. 04-26 17:21:33.790 31038-31038/org.linphone W/ortp: Fail to increase socket's recv buffer size with SO_RCVBUFFORCE: Operation not permitted. 04-26 17:21:33.791 31038-31038/org.linphone W/ortp: Fail to increase socket's send buffer size with SO_SNDBUFFORCE: Operation not permitted. 04-26 17:21:33.791 31038-31038/org.linphone W/ortp: Fail to increase socket's recv buffer size with SO_RCVBUFFORCE: Operation not permitted. 04-26 17:21:33.791 31038-31038/org.linphone I/mediastreamer: Stun packet sent for session [0x7f838eec00] 04-26 17:21:33.791 31038-31038/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f838eec00] 04-26 17:21:33.795 31038-6012/org.linphone I/OMXClient: Using client-side OMX mux. 04-26 17:21:33.799 1698-3513/? D/awplayer: : ----get_omx_component_factory_fn 04-26 17:21:33.800 1698-3513/? D/awplayer: : Calling process is: org.linphone 04-26 17:21:33.800 1698-3513/? D/awplayer: : omx_enc Create! 04-26 17:21:33.800 1698-3513/? D/awplayer: : COMPONENT_INIT, name = OMX.allwinner.video.encoder.avc 04-26 17:21:33.801 31038-5830/org.linphone W/bctbx: Getting reference signal but no echo to synchronize on. 04-26 17:21:33.803 31038-31038/org.linphone I/mediastreamer: Codec video/avc successfully created. 04-26 17:21:33.803 31038-31038/org.linphone I/mediastreamer: Using the default video configuration list 04-26 17:21:33.804 31038-31038/org.linphone I/mediastreamer: target and payload bitrates not set for stream [0x7f838cc000] using lowest configuration of preferred video size 640x480 04-26 17:21:33.804 31038-31038/org.linphone I/mediastreamer: Limiting bitrate of video encoder to 0 bits/s for stream [0x7f838cc000] 04-26 17:21:33.804 31038-31038/org.linphone I/mediastreamer: Video configuration set: bitrate=400000 bits/s, fps=15.000000, vsize=640x480 04-26 17:21:33.804 31038-31038/org.linphone I/mediastreamer: video_set_device_rotation : 0 04-26 17:21:33.805 31038-31038/org.linphone I/mediastreamer: Preview capture window set but camera not created yet; remembering it for later use 04-26 17:21:33.805 31038-31038/org.linphone I/mediastreamer: mediastreamer3 supported resolutions : 04-26 17:21:33.805 31038-31038/org.linphone I/mediastreamer: mediastreamer 1280x720 04-26 17:21:33.805 31038-31038/org.linphone I/mediastreamer: mediastreamer 800x600 04-26 17:21:33.806 31038-31038/org.linphone I/mediastreamer: mediastreamer 640x480 04-26 17:21:33.806 31038-31038/org.linphone I/mediastreamer: mediastreamerresolution selection done (640, 480, 0) 04-26 17:21:33.806 31038-31038/org.linphone I/mediastreamer: Camera selected resolution is: 640x480 (requested: 640x480) with downscaling?0 04-26 17:21:33.807 31038-31038/org.linphone I/mediastreamer: Output video size adjusted to match camera resolution (640x480) 04-26 17:21:33.807 31038-31038/org.linphone I/mediastreamer: Video configuration set: bitrate=400000 bits/s, fps=30.000000, vsize=640x480 04-26 17:21:33.807 31038-31038/org.linphone I/mediastreamer: Setting sent vsize=640x480, fps=30.000000 04-26 17:21:33.807 31038-31038/org.linphone I/mediastreamer: Video configuration set: bitrate=400000 bits/s, fps=30.000000, vsize=640x480 04-26 17:21:33.807 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSAndroidVideoCapture:0x7f84985180,0-->MSPixConv:0x7f84985580,0 04-26 17:21:33.808 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSPixConv:0x7f84985580,0-->MSTee:0x7f84985480,0 04-26 17:21:33.808 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSTee:0x7f84985480,0-->MSSizeConv:0x7f84985600,0 04-26 17:21:33.808 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSSizeConv:0x7f84985600,0-->MSMediaCodecH264Enc:0x7f84985200,0 04-26 17:21:33.808 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSMediaCodecH264Enc:0x7f84985200,0-->MSRtpSend:0x7f8495f300,0 04-26 17:21:33.808 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSTee:0x7f84985480,2-->MSJpegWriter:0x7f84985500,0 04-26 17:21:33.809 31038-31038/org.linphone I/mediastreamer: MSMediaCodecH264Dec initialization 04-26 17:21:33.809 31038-31038/org.linphone E/mediastreamer: no such method on filter MSMediaCodecH264Dec, fid=16389 method index=7 04-26 17:21:33.809 31038-31038/org.linphone I/mediastreamer: ogl_display_new : 0x7f838dea00 04-26 17:21:33.809 31038-31038/org.linphone I/mediastreamer: android_display_init 0x7f84985700 0x7f8446c690 04-26 17:21:33.810 31038-5830/org.linphone W/bctbx: Getting reference signal but no echo to synchronize on. 04-26 17:21:33.810 31038-31038/org.linphone I/mediastreamer: MSMediaCodecH264Dec: freeze on error enabled 04-26 17:21:33.811 31038-31038/org.linphone E/mediastreamer: no such method on filter MSAndroidOpenGLDisplay, fid=16387 method index=1 04-26 17:21:33.811 31038-31038/org.linphone E/mediastreamer: no such method on filter MSAndroidOpenGLDisplay, fid=16387 method index=0 04-26 17:21:33.811 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSRtpRecv:0x7f84985780,0-->MSTee:0x7f8495f380,0 04-26 17:21:33.811 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSTee:0x7f8495f380,1-->MSItcSink:0x7f8495f400,0 04-26 17:21:33.811 31038-31038/org.linphone I/mediastreamer: configure_itc(): format set to type=video;encoding=H264;vsize=0x0;fps=0.000000;fmtp='' 04-26 17:21:33.812 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSTee:0x7f8495f380,0-->MSMediaCodecH264Dec:0x7f84985680,0 04-26 17:21:33.812 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSMediaCodecH264Dec:0x7f84985680,0-->MSTee:0x7f84985880,0 04-26 17:21:33.812 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSTee:0x7f84985880,1-->MSJpegWriter:0x7f84985800,0 04-26 17:21:33.812 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSTee:0x7f84985880,0-->MSAndroidOpenGLDisplay:0x7f84985700,0 04-26 17:21:33.812 31038-31038/org.linphone I/mediastreamer: ms_filter_link: MSTee:0x7f84985480,1-->MSAndroidOpenGLDisplay:0x7f84985700,1 04-26 17:21:33.813 31038-31038/org.linphone I/mediastreamer: Preprocessing of Android VIDEO capture filter 04-26 17:21:33.813 31038-31038/org.linphone I/mediastreamer: Starting Android camera '0' (rotation:0) 04-26 17:21:33.815 1698-31621/? I/CameraService: CameraService::connect call (PID 31038 "org.linphone", camera ID 0) for HAL version default and Camera API version 1 04-26 17:21:33.815 31038-6017/org.linphone I/mediastreamer: MSVideo MSTicker priority left to normal. 04-26 17:21:33.815 1698-31621/? D/HALCameraFactory: There is 1 attached cameras and 0 removable cameras 04-26 17:21:33.816 1698-31621/? D/HALCameraFactory: Calling process is: org.linphone 04-26 17:21:33.816 1698-31621/? D/MediaPlayer: (f:setDataSource, l:164) set BDFolderPlay[0] 04-26 17:21:33.821 31038-5830/org.linphone W/bctbx: Getting reference signal but no echo to synchronize on. 04-26 17:21:33.830 1698-6018/? D/NuPlayerDriver: notifyListener_l(0xf22abde0), (1, 0, 0) 04-26 17:21:33.830 1698-31621/? D/MediaPlayer: (f:setDataSource, l:164) set BDFolderPlay[0] 04-26 17:21:33.831 31038-5830/org.linphone W/bctbx: Getting reference signal but no echo to synchronize on. 04-26 17:21:33.841 31038-5830/org.linphone W/bctbx: Getting reference signal but no echo to synchronize on. 04-26 17:21:33.843 1698-6021/? D/NuPlayerDriver: notifyListener_l(0xf22abe40), (1, 0, 0) 04-26 17:21:33.843 1698-31621/? D/MediaPlayer: (f:setDataSource, l:164) set BDFolderPlay[0] 04-26 17:21:33.844 1698-6025/? E/FileSource: Failed to open file '/system/media/audio/ui/VideoStop.ogg'. (No such file or directory) 04-26 17:21:33.844 1698-6025/? E/GenericSource: Failed to create data source! 04-26 17:21:33.844 1698-6024/? D/NuPlayerDriver: notifyListener_l(0xf22abea0), (100, 1, -2147483648) 04-26 17:21:33.844 1698-6024/? E/MediaPlayer: error (1, -2147483648) 04-26 17:21:33.846 1698-31621/? I/CameraClient: Opening camera 0 04-26 17:21:33.847 1698-31621/? D/HALCameraFactory: Calling process is: org.linphone 04-26 17:21:33.851 31038-5830/org.linphone W/bctbx: Getting reference signal but no echo to synchronize on. 04-26 17:21:33.860 31038-5830/org.linphone W/bctbx: Getting reference signal but no echo to synchronize on. 04-26 17:21:33.870 31038-5830/org.linphone W/bctbx: Getting reference signal but no echo to synchronize on. 04-26 17:21:33.881 31038-5830/org.linphone W/bctbx: Getting reference signal but no echo to synchronize on. 04-26 17:21:33.888 1698-6005/? D/audio_hw_primary: >>>>>> in_ajust_rate is : 44100 04-26 17:21:33.888 1698-6005/? V/audio_hw_primary: start_input_stream, line: 1827,adev->mode:3,adev->out_device:1 04-26 17:21:33.888 1698-6005/? V/audio_hw_primary: rate:44100, period_count:2, period_size:1024,channels:2 04-26 17:21:33.888 1698-6005/? V/audio_hw_primary: in_ajust_rate:44100 04-26 17:21:33.888 1698-6005/? V/audio_hw_primary: do not use in resampler 04-26 17:21:33.893 1698-31621/? D/HALCameraFactory: Calling process is: org.linphone 04-26 17:21:33.916 31038-5830/org.linphone W/bctbx: Getting reference signal but no echo to synchronize on. 04-26 17:21:33.919 31038-31038/org.linphone E/mediastreamer: SupportedPreviewSizesSupportedPreviewSizes : 1280x720 17 04-26 17:21:33.919 31038-31038/org.linphone E/mediastreamer: SupportedPreviewSizesSupportedPreviewSizes : 800x600 17 04-26 17:21:33.919 31038-31038/org.linphone E/mediastreamer: SupportedPreviewSizesSupportedPreviewSizes : 640x480 17 04-26 17:21:33.921 1698-1973/? E/CameraHardware: ERR(commandThread):Fail on mV4L2Camera->setBrightness(brightness(0)) 04-26 17:21:33.922 1698-1698/? D/HALCameraFactory: Calling process is: org.linphone 04-26 17:21:33.924 31038-31038/org.linphone W/mediastreamer: Camera preview orientation: 0 04-26 17:21:33.924 1698-2482/? D/CameraHardware: Starting camera, Size:1280x720 , picture format:yuv420sp 04-26 17:21:33.924 1698-2482/? D/V4L2CameraDevice: startDevice, wxh: 1280x720, fmt: 825382478 04-26 17:21:33.928 31038-5830/org.linphone W/bctbx: Getting reference signal but no echo to synchronize on. 04-26 17:21:33.928 31038-5830/org.linphone W/bctbx: Not enough ref samples, using zeroes 04-26 17:21:33.936 31038-31038/org.linphone D/Camera: app passed NULL surface 04-26 17:21:33.937 31038-5830/org.linphone I/bctbx: Samples are back. 04-26 17:21:33.938 31038-5830/org.linphone W/bctbx: Not enough ref samples, using zeroes 04-26 17:21:33.939 31038-31038/org.linphone I/mediastreamer: Preprocessing of Android VIDEO capture filter done 04-26 17:21:33.939 31038-31038/org.linphone I/mediastreamer: MSMediaCodecH264Enc: AMediaImage is available. 04-26 17:21:33.940 1698-3195/? D/awplayer: : COMPONENT_SET_PARAMETER: VideoEncodeCustomParamStoreMetaDataInBuffers 0 04-26 17:21:33.940 31038-6012/org.linphone I/ACodec: [OMX.allwinner.video.encoder.avc] using color format 0x15 in place of 0x7f420888 04-26 17:21:33.941 31038-6012/org.linphone D/ACodec: video_def->nFrameWidth: 640, video_def->nFrameHeight: 480 04-26 17:21:33.941 1698-2482/? D/awplayer: : init_input_port: stride = 640, width = 640, height = 480 04-26 17:21:33.942 1698-2688/? D/awplayer: : m_framerate: 30 04-26 17:21:33.944 31038-6012/org.linphone I/ACodec: setupVideoEncoder succeeded 04-26 17:21:33.944 1698-31621/? D/awplayer: : COMPONENT_SET_PARAMETER: VideoEncodeCustomParamEnableAndroidNativeBuffers,nPortIndex: 1,enable:0 04-26 17:21:33.947 31038-31038/org.linphone I/mediastreamer: MSMediaCodecH264Enc: encoder successfully configured. size=640x480, color-format=2135033992 04-26 17:21:33.948 1698-6013/? D/awplayer: : x set state command, cmd = 0, pCmdData = 2. 04-26 17:21:33.953 31038-31038/org.linphone I/mediastreamer: MSMediaCodecH264Enc: encoder successfully started 04-26 17:21:33.953 31038-31038/org.linphone I/mediastreamer: Filter MSRtpRecv is already being scheduled; nothing to do. 04-26 17:21:33.953 31038-31038/org.linphone I/bctbx: CallSession[0x7f83a914f8] : payload type 96 opus/48000 fmtp=useinbandfec=1 added to frozen list 04-26 17:21:33.954 31038-31038/org.linphone I/bctbx: CallSession[0x7f83a914f8] : payload type 97 speex/16000 fmtp=vbr=on added to frozen list 04-26 17:21:33.954 31038-31038/org.linphone I/bctbx: CallSession[0x7f83a914f8] : payload type 98 speex/8000 fmtp=vbr=on added to frozen list 04-26 17:21:33.954 31038-31038/org.linphone I/bctbx: CallSession[0x7f83a914f8] : payload type 0 PCMU/8000 fmtp= added to frozen list 04-26 17:21:33.954 31038-31038/org.linphone I/bctbx: CallSession[0x7f83a914f8] : payload type 8 PCMA/8000 fmtp= added to frozen list 04-26 17:21:33.954 31038-31038/org.linphone I/bctbx: CallSession[0x7f83a914f8] : payload type 18 G729/8000 fmtp=annexb=yes added to frozen list 04-26 17:21:33.955 31038-31038/org.linphone I/bctbx: CallSession[0x7f83a914f8] : payload type 101 telephone-event/48000 fmtp= added to frozen list 04-26 17:21:33.955 31038-31038/org.linphone I/bctbx: CallSession[0x7f83a914f8] : payload type 99 telephone-event/16000 fmtp= added to frozen list 04-26 17:21:33.955 31038-31038/org.linphone I/bctbx: CallSession[0x7f83a914f8] : payload type 100 telephone-event/8000 fmtp= added to frozen list 04-26 17:21:33.955 31038-31038/org.linphone I/bctbx: CallSession[0x7f83a914f8] : payload type 96 H264/90000 fmtp=profile-level-id=42801F added to frozen list 04-26 17:21:33.955 31038-31038/org.linphone I/bctbx: audio stream index found: 0, updating main audio stream index 04-26 17:21:33.956 31038-31038/org.linphone I/bctbx: video stream index found: 1, updating main video stream index 04-26 17:21:33.956 31038-31038/org.linphone I/bctbx: CallSession [0x7f83a914f8] moving from state State::Connected to State::StreamsRunning 04-26 17:21:33.956 31038-6017/org.linphone I/mediastreamer: Stun packet sent for session [0x7f838eec00] 04-26 17:21:33.957 31038-6017/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f838eec00] 04-26 17:21:33.957 31038-6017/org.linphone I/ortp: First estimation 04-26 17:21:33.959 1698-6013/? D/awplayer: : x set state command, cmd = 0, pCmdData = 3. 04-26 17:21:33.959 1698-6014/? D/awplayer: : profile-venc=66, profile-omx=1, frame_rate:30, bit_rate:360000, eColorFormat:00000015 04-26 17:21:33.959 1698-6014/? E/awplayer: : omx_venc base_config info: src_wxh:640x480, dis_wxh:640x480, stride:640  04-26 17:21:33.959 1698-6014/? D/awplayer: : (f:VideoEncInit, l:128) 04-26 17:21:33.959 1698-6014/? D/awplayer: : (f:VideoEncInit, l:144) 04-26 17:21:33.961 31038-31038/org.linphone I/mediastreamer: New call state [StreamsRunning] 04-26 17:21:33.963 31038-31038/org.linphone W/mediastreamer: [AudioManager] already in MODE_IN_COMMUNICATION, skipping... 04-26 17:21:33.964 1698-6014/? D/awplayer: : BitStreamCreate OK 04-26 17:21:33.968 31038-5830/org.linphone I/bctbx: Samples are back. 04-26 17:21:33.984 1698-2478/? D/AudioFlinger: mixer(0xf1f40000) throttle end: throttle time(33) 04-26 17:21:34.016 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_state_changed] 04-26 17:21:34.017 31038-31038/org.linphone I/belle-sip: Computing branch id z9hG4bK.F3vsuonfQ for message sent statelessly 04-26 17:21:34.021 31038-31038/org.linphone I/belle-sip: channel [0x7f83902000]: message sent to [UDP://192.192.255.214:5060], size: [363] bytes ACK sip:linphone.android@192.192.255.214;transport=udp SIP/2.0 Via: SIP/2.0/UDP 192.192.255.133:5060;rport;branch=z9hG4bK.F3vsuonfQ From: "Linphone Android" sip:linphone.android@192.192.255.133;tag=BEwl0BufV To: sip:dd@192.192.255.214;tag=fdbxnMB CSeq: 20 ACK Call-ID: CzON8ONQEJ Max-Forwards: 70 User-Agent: LinphoneAndroid/4.0.1 (belle-sip/1.6.3) 04-26 17:21:34.021 31038-31038/org.linphone I/belle-sip: channel [0x7f83902000]: ending recv background task with id=[2007b2]. 04-26 17:21:34.022 31038-31038/org.linphone I/belle-sip: bellesip_wake_lock_release(): Android wake lock released [ref=0x2007b2] 04-26 17:21:34.023 31038-31038/org.linphone I/belle-sip: Garbage collecting unowned object of type belle_sip_hop_t 04-26 17:21:34.026 31038-31038/org.linphone I/belle-sip: Garbage collecting unowned object of type belle_sdp_session_description_t 04-26 17:21:34.027 31038-31038/org.linphone I/liblinphone: Callbacks [0x7f849a0950] unregistered on core [0x7f9a9f6200] 04-26 17:21:34.028 31038-31038/org.linphone I/liblinphone: Callbacks [0x7f849a09a0] unregistered on core [0x7f9a9f6200] 04-26 17:21:34.042 1677-1721/? D/Sayeye: current scense is (Normal) 04-26 17:21:34.047 1677-1721/? E/Sayeye: failed to open /sys/devices/soc.0/1c2ac00.twi/i2c-0/0-0040/runtime_suspend, No such file or directory 04-26 17:21:34.047 1677-1721/? D/Sayeye: Scense (Normal) state set succeed 04-26 17:21:34.048 2209-2360/system_process I/DynamicPManagerService: Current Scense:normal 04-26 17:21:34.131 31038-31038/org.linphone I/liblinphone: Notifying all friends that we are [on-the-phone] 04-26 17:21:34.135 31038-31038/org.linphone W/mediastreamer: Routing audio to speaker, disabling bluetooth audio route 04-26 17:21:34.137 1698-2470/? V/audio_hw_primary: adev_set_voice_volume, volume: 0.000000 04-26 17:21:34.141 31038-31038/org.linphone I/mediastreamer: This device (gemvary/QUAD-P3/tulip) says it has no built-in echo canceller. 04-26 17:21:34.142 31038-31038/org.linphone I/mediastreamer: No information available for [gemvary/QUAD-P3/tulip], 04-26 17:21:34.142 31038-31038/org.linphone I/mediastreamer: Sound device information for [gemvary/QUAD-P3/tulip] is: builtin=[no], delay=[250] ms 04-26 17:21:34.147 31038-31038/org.linphone I/liblinphone: Core callbacks [0x7f849a0db0] registered on core [0x7f9a9f6200] 04-26 17:21:34.155 31038-31038/org.linphone I/liblinphone: Core callbacks [0x7f8499f1e0] registered on core [0x7f9a9f6200] 04-26 17:21:34.156 31038-31038/org.linphone E/mediastreamer: no such method on filter MSAndroidOpenGLDisplay, fid=16387 method index=3 04-26 17:21:34.156 31038-31038/org.linphone I/liblinphone: Java_org_linphone_core_CoreImpl_setNativeVideoWindowId(): NewGlobalRef(0x200712) 04-26 17:21:34.156 31038-31038/org.linphone I/mediastreamer: Sending opengles_display pointer (0x7f838dea00) 04-26 17:21:34.205 1668-1726/? I/[Gralloc]: ion_alloc from ion_client:19 via heap type DMA(mask:16) for 3942400 Bytes uncached buffer successfully, usage = 0x00000f02 04-26 17:21:34.219 31038-31038/org.linphone W/mediastreamer: Video display surface created 04-26 17:21:34.220 31038-6041/org.linphone W/mediastreamer: creating OpenGL ES 2.0 context 04-26 17:21:34.222 31038-31038/org.linphone I/mediastreamer: Video display surface is being changed. 04-26 17:21:34.222 31038-31038/org.linphone I/liblinphone: Java_org_linphone_core_CoreImpl_setNativeVideoWindowId(): NewGlobalRef(0x1006fa) 04-26 17:21:34.223 31038-31038/org.linphone I/mediastreamer: Clearing old opengles_display (0x7f838dea00) 04-26 17:21:34.223 31038-31038/org.linphone I/mediastreamer: uninit opengles_display (gl initialized:0) 04-26 17:21:34.223 31038-31038/org.linphone I/mediastreamer: ogl_display_new : 0x7f838dea00 04-26 17:21:34.224 31038-31038/org.linphone I/mediastreamer: Sending opengles_display pointer (0x7f838dea00) 04-26 17:21:34.224 31038-31038/org.linphone I/liblinphone: Java_org_linphone_core_CoreImpl_setNativeVideoWindowId(): DeleteGlobalRef(0x200712) 04-26 17:21:34.224 31038-31038/org.linphone W/mediastreamer: Video display surface changed 04-26 17:21:34.224 1668-1726/? I/[Gralloc]: ion_alloc from ion_client:19 via heap type DMA(mask:16) for 1971200 Bytes uncached buffer successfully, usage = 0x00000f02 04-26 17:21:34.229 31038-6041/org.linphone I/mediastreamer: init opengles_display (800 x 1232, gl initialized:0) 04-26 17:21:34.230 31038-6041/org.linphone I/mediastreamer: resize opengles_display (800 x 1232, gl initialized:0) 04-26 17:21:34.231 31038-6041/org.linphone I/mediastreamer: OpenGL program info: [NO INFORMATION] 04-26 17:21:34.238 31038-31038/org.linphone W/mediastreamer: Video preview surface created 04-26 17:21:34.238 31038-31038/org.linphone I/mediastreamer: Video preview surface is being changed. 04-26 17:21:34.238 31038-31038/org.linphone I/liblinphone: Java_org_linphone_core_CoreImpl_setNativePreviewWindowId(): NewGlobalRef(0x10070e) 04-26 17:21:34.239 31038-31038/org.linphone I/mediastreamer: Preview capture window changed (oldwin: 0x10049a newwin: 0x10070e rotation:0) 04-26 17:21:34.239 1698-1973/? W/CameraHardware: CMD_QUEUE_STOP_FACE_DETECTE failed, mFaceDetection not opened. 04-26 17:21:34.245 1698-1968/? W/V4L2CameraDevice: should stop capture now 04-26 17:21:34.248 1698-1969/? W/CameraClient: lockIfMessageWanted(16): dropped unwanted message 04-26 17:21:34.248 1698-31621/? D/V4L2CameraDevice: V4L2CameraDevice::stopDevice 04-26 17:21:34.250 1698-1973/? W/CameraHardware: CMD_QUEUE_STOP_FACE_DETECTE failed, mFaceDetection not opened. 04-26 17:21:34.286 1698-2478/? V/audio_hw_primary: out_set_parameters: routing=2 04-26 17:21:34.286 1698-2478/? V/audio_hw_primary: out_set_parameters, line: 1562 04-26 17:21:34.286 1698-2478/? V/audio_hw_primary: >>>>>>>> adev->mode:3,adev->out_device is: 1, val is : 2 04-26 17:21:34.286 1698-2478/? V/audio_hw_primary: out_set_parameters, line: 1570 04-26 17:21:34.286 1698-2478/? V/audio_hw_primary: out_set_parameters, line: 1586 04-26 17:21:34.286 1698-2478/? D/audio_hw_primary: >>>>>> select_device 04-26 17:21:34.288 1698-2478/? V/audio_hw_primary: active_output, LINE:1050,FUNC:select_device, adev->out_device:2 04-26 17:21:34.288 1698-2478/? V/audio_hw_primary: LINE:1056,FUNC:select_device, headset_on:0, headphone_on:0, speaker_on:2, earpiece_on:0, bt_on:0 04-26 17:21:34.288 1698-2478/? V/audio_hw_primary: LINE:1083,FUNC:select_device, output_device_id:6 04-26 17:21:34.288 1698-2478/? V/audio_hw_primary: select_device, line: 1104 04-26 17:21:34.288 1698-2478/? V/audio_hw_primary: record,LINE:1156,FUNC:select_device, adev->in_device:4,AUDIO_DEVICE_IN_ALL_SCO:80000008 04-26 17:21:34.288 1698-2478/? V/audio_hw_primary: fm record,LINE:1181,FUNC:select_device,bt_on:0,headset_on:0,main_mic_on;0,adev->in_device:4,AUDIO_DEVICE_IN_ALL_SCO:80000008 04-26 17:21:34.288 1698-2478/? V/audio_hw_primary: select_device, line: 1201 04-26 17:21:34.307 31038-5830/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f838eb000] 04-26 17:21:34.375 1698-6027/? D/CallbackNotifier: savePictureThread exit, line: 1515 04-26 17:21:34.375 1698-2687/? I/CameraFlashlight: Destroying camera 0 04-26 17:21:34.418 1698-2478/? D/audio_hw_primary: >>>>>> select_device 04-26 17:21:34.421 1698-2478/? V/audio_hw_primary: active_output, LINE:1050,FUNC:select_device, adev->out_device:2 04-26 17:21:34.421 1698-2478/? V/audio_hw_primary: LINE:1056,FUNC:select_device, headset_on:0, headphone_on:0, speaker_on:2, earpiece_on:0, bt_on:0 04-26 17:21:34.421 1698-2478/? V/audio_hw_primary: LINE:1083,FUNC:select_device, output_device_id:6 04-26 17:21:34.421 1698-2478/? V/audio_hw_primary: select_device, line: 1104 04-26 17:21:34.478 1698-2687/? D/HALCameraFactory: There is 1 attached cameras and 0 removable cameras 04-26 17:21:34.481 1698-2687/? I/CameraService: disconnect: Disconnected client for camera 0 for PID 31038 04-26 17:21:34.489 1698-3195/? D/NuPlayerDriver: reset(0xf22abde0) 04-26 17:21:34.489 1698-3195/? D/NuPlayerDriver: notifyListener_l(0xf22abde0), (8, 0, 0) 04-26 17:21:34.490 1698-2688/? I/CameraService: CameraService::connect call (PID 31038 "org.linphone", camera ID 0) for HAL version default and Camera API version 1 04-26 17:21:34.490 1698-6018/? D/NuPlayerDriver: notifyResetComplete(0xf22abde0) 04-26 17:21:34.490 1698-2688/? D/HALCameraFactory: There is 1 attached cameras and 0 removable cameras 04-26 17:21:34.491 1698-2688/? D/HALCameraFactory: Calling process is: org.linphone 04-26 17:21:34.528 1698-6005/? V/audio_hw_primary: start_input_stream, line: 1810 04-26 17:21:34.528 1698-6005/? D/audio_hw_primary: >>>>>> select_device 04-26 17:21:34.529 1698-2470/? V/audio_hw_primary: adev_set_voice_volume, volume: 0.800000 04-26 17:21:34.529 1698-3195/? D/NuPlayerDriver: reset(0xf22abe40) 04-26 17:21:34.529 1698-3195/? D/NuPlayerDriver: notifyListener_l(0xf22abe40), (8, 0, 0) 04-26 17:21:34.530 1698-6005/? V/audio_hw_primary: active_output, LINE:1050,FUNC:select_device, adev->out_device:2 04-26 17:21:34.530 1698-6005/? V/audio_hw_primary: LINE:1056,FUNC:select_device, headset_on:0, headphone_on:0, speaker_on:2, earpiece_on:0, bt_on:0 04-26 17:21:34.530 1698-6005/? V/audio_hw_primary: LINE:1083,FUNC:select_device, output_device_id:6 04-26 17:21:34.530 1698-6005/? V/audio_hw_primary: select_device, line: 1104 04-26 17:21:34.530 1698-6005/? V/audio_hw_primary: record,LINE:1156,FUNC:select_device, adev->in_device:4,AUDIO_DEVICE_IN_ALL_SCO:80000008 04-26 17:21:34.530 1698-6005/? V/audio_hw_primary: fm record,LINE:1181,FUNC:select_device,bt_on:0,headset_on:0,main_mic_on;0,adev->in_device:4,AUDIO_DEVICE_IN_ALL_SCO:80000008 04-26 17:21:34.530 1698-6005/? V/audio_hw_primary: select_device, line: 1201 04-26 17:21:34.530 1698-6021/? D/NuPlayerDriver: notifyResetComplete(0xf22abe40) 04-26 17:21:34.532 1698-3195/? D/NuPlayerDriver: reset(0xf22abea0) 04-26 17:21:34.532 1698-3195/? D/NuPlayerDriver: notifyListener_l(0xf22abea0), (8, 0, 0) 04-26 17:21:34.532 1698-6024/? D/NuPlayerDriver: notifyResetComplete(0xf22abea0) 04-26 17:21:34.533 1698-2688/? D/MediaPlayer: (f:setDataSource, l:164) set BDFolderPlay[0] 04-26 17:21:34.562 1698-6063/? D/NuPlayerDriver: notifyListener_l(0xf5e58e20), (1, 0, 0) 04-26 17:21:34.562 1698-2688/? D/MediaPlayer: (f:setDataSource, l:164) set BDFolderPlay[0] 04-26 17:21:34.567 31038-5830/org.linphone I/ortp: OrtpCongestionDetector: moving from state CongestionStateNormal to state CongestionStatePending 04-26 17:21:34.580 1698-6067/? D/NuPlayerDriver: notifyListener_l(0xf5e58a60), (1, 0, 0) 04-26 17:21:34.580 1698-2688/? D/MediaPlayer: (f:setDataSource, l:164) set BDFolderPlay[0] 04-26 17:21:34.581 1698-6071/? E/FileSource: Failed to open file '/system/media/audio/ui/VideoStop.ogg'. (No such file or directory) 04-26 17:21:34.581 1698-6071/? E/GenericSource: Failed to create data source! 04-26 17:21:34.581 1698-6070/? D/NuPlayerDriver: notifyListener_l(0xf22abb40), (100, 1, -2147483648) 04-26 17:21:34.581 1698-6070/? E/MediaPlayer: error (1, -2147483648) 04-26 17:21:34.585 1698-2688/? I/CameraClient: Opening camera 0 04-26 17:21:34.586 1698-2688/? D/HALCameraFactory: Calling process is: org.linphone 04-26 17:21:34.638 1698-6005/? D/audio_hw_primary: >>>>>> in_ajust_rate is : 44100 04-26 17:21:34.638 1698-6005/? V/audio_hw_primary: start_input_stream, line: 1827,adev->mode:3,adev->out_device:2 04-26 17:21:34.638 1698-6005/? V/audio_hw_primary: rate:44100, period_count:2, period_size:1024,channels:2 04-26 17:21:34.638 1698-6005/? V/audio_hw_primary: in_ajust_rate:44100 04-26 17:21:34.639 1698-6005/? V/audio_hw_primary: do not use in resampler 04-26 17:21:34.649 1698-2688/? D/HALCameraFactory: Calling process is: org.linphone 04-26 17:21:34.658 31038-31038/org.linphone E/mediastreamer: SupportedPreviewSizesSupportedPreviewSizes : 1280x720 17 04-26 17:21:34.659 31038-31038/org.linphone E/mediastreamer: SupportedPreviewSizesSupportedPreviewSizes : 800x600 17 04-26 17:21:34.659 31038-31038/org.linphone E/mediastreamer: SupportedPreviewSizesSupportedPreviewSizes : 640x480 17 04-26 17:21:34.661 1698-1973/? E/CameraHardware: ERR(commandThread):Fail on mV4L2Camera->setBrightness(brightness(0)) 04-26 17:21:34.665 1698-2687/? D/HALCameraFactory: Calling process is: org.linphone 04-26 17:21:34.666 31038-31038/org.linphone W/mediastreamer: Camera preview orientation: 0 04-26 17:21:34.667 1698-1698/? D/CameraHardware: Starting camera, Size:1280x720 , picture format:yuv420sp 04-26 17:21:34.667 1698-1698/? D/V4L2CameraDevice: startDevice, wxh: 1280x720, fmt: 825382478 04-26 17:21:34.674 31038-5830/org.linphone I/mediastreamer: Adjusting output timestamp by 16320 04-26 17:21:34.686 1698-6005/? V/audio_hw_primary: in_set_parameters: input_source=7;routing=-2147483520 04-26 17:21:34.687 31038-31038/org.linphone I/liblinphone: Java_org_linphone_core_CoreImpl_setNativePreviewWindowId(): DeleteGlobalRef(0x10049a) 04-26 17:21:34.688 31038-6017/org.linphone W/mediastreamer: MSVideo MSTicker: We are late of 432 miliseconds. 04-26 17:21:34.689 31038-6017/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f838eec00] 04-26 17:21:34.693 31038-31038/org.linphone W/mediastreamer: Video preview surface changed 04-26 17:21:34.700 1698-6005/? D/audio_hw_primary: >>>>>> select_device 04-26 17:21:34.701 31038-31038/org.linphone I/ortp: First estimation 04-26 17:21:34.701 31038-31038/org.linphone I/ortp: First estimation 04-26 17:21:34.702 31038-31038/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f83a914f8]: RTP audio=[d=24.87,u=25.17], video=[d=0.05,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.44], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:34.702 31038-31038/org.linphone I/bctbx: Thread processing load: audio=13.0369 video=4.07154 text=0 04-26 17:21:34.707 1698-6005/? V/audio_hw_primary: active_output, LINE:1050,FUNC:select_device, adev->out_device:2 04-26 17:21:34.707 1698-6005/? V/audio_hw_primary: LINE:1056,FUNC:select_device, headset_on:0, headphone_on:0, speaker_on:2, earpiece_on:0, bt_on:0 04-26 17:21:34.707 1698-6005/? V/audio_hw_primary: LINE:1083,FUNC:select_device, output_device_id:6 04-26 17:21:34.707 1698-6005/? V/audio_hw_primary: select_device, line: 1104 04-26 17:21:34.737 2209-2378/system_process I/ActivityManager: Displayed org.linphone/.call.CallActivity: +701ms 04-26 17:21:34.743 1668-3356/? I/[Gralloc]: ion_alloc from ion_client:19 via heap type DMA(mask:16) for 3942400 Bytes uncached buffer successfully, usage = 0x00000f02 04-26 17:21:34.752 2209-2378/system_process I/ActivityManager: Timeline: Activity_windows_visible id: org.linphone time:2498543 04-26 17:21:34.797 1677-1721/? D/Sayeye: current scense is (Normal) 04-26 17:21:34.806 1677-1721/? E/Sayeye: failed to open /sys/devices/soc.0/1c2ac00.twi/i2c-0/0-0040/runtime_suspend, No such file or directory 04-26 17:21:34.806 1677-1721/? D/Sayeye: Scense (Normal) state set succeed 04-26 17:21:34.807 31038-5830/org.linphone I/mediastreamer: MSAudioMixer [0x7f8497d980] is entering bypass mode. 04-26 17:21:34.807 2209-2360/system_process I/DynamicPManagerService: Current Scense:normal 04-26 17:21:34.807 31038-5830/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f838eb000] 04-26 17:21:34.808 1698-6005/? V/audio_hw_primary: start_input_stream, line: 1810 04-26 17:21:34.808 1698-6005/? D/audio_hw_primary: >>>>>> select_device 04-26 17:21:34.809 1698-6005/? V/audio_hw_primary: active_output, LINE:1050,FUNC:select_device, adev->out_device:2 04-26 17:21:34.809 1698-6005/? V/audio_hw_primary: LINE:1056,FUNC:select_device, headset_on:0, headphone_on:0, speaker_on:2, earpiece_on:0, bt_on:0 04-26 17:21:34.809 1698-6005/? V/audio_hw_primary: LINE:1083,FUNC:select_device, output_device_id:6 04-26 17:21:34.809 1698-6005/? V/audio_hw_primary: select_device, line: 1104 04-26 17:21:34.809 1698-6005/? V/audio_hw_primary: record,LINE:1156,FUNC:select_device, adev->in_device:80,AUDIO_DEVICE_IN_ALL_SCO:80000008 04-26 17:21:34.809 1698-6005/? V/audio_hw_primary: fm record,****LINE:1181,FUNC:select_device,bt_on:0,headset_on:0,main_mic_on;0,adev->in_device:80,AUDIO_DEVICE_IN_ALL_SCO:80000008 04-26 17:21:34.809 1698-6005/? V/audio_hw_primary: select_device, line: 1201 04-26 17:21:34.894 1698-1969/? D/PreviewWindow: onNextFrameAvailable: Adjusting preview windows 0xf22aba30 geometry to 1280x720 04-26 17:21:34.899 1668-1726/? I/[Gralloc]: ion_alloc from ion_client:19 via heap type DMA(mask:16) for 1382400 Bytes uncached buffer successfully, usage = 0x00000902 04-26 17:21:34.918 1698-6005/? D/audio_hw_primary: >>>>>> in_ajust_rate is : 44100 04-26 17:21:34.918 1698-6005/? V/audio_hw_primary: start_input_stream, line: 1827,adev->mode:3,adev->out_device:2 04-26 17:21:34.918 1698-6005/? V/audio_hw_primary: rate:44100, period_count:2, period_size:1024,channels:2 04-26 17:21:34.918 1698-6005/? V/audio_hw_primary: in_ajust_rate:44100 04-26 17:21:34.918 1698-6005/? V/audio_hw_primary: do not use in resampler 04-26 17:21:34.938 1698-2478/? D/AudioFlinger: mixer(0xf1f40000) throttle end: throttle time(22) 04-26 17:21:34.950 31038-6017/org.linphone I/mediastreamer: MSMediaCodecH264Enc: first frame to encode queued (size: 640x480) 04-26 17:21:34.957 31038-6017/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f838eec00] 04-26 17:21:34.961 31038-5830/org.linphone I/mediastreamer: Adjusting output timestamp by 10560 04-26 17:21:34.984 1698-2478/? D/AudioFlinger: mixer(0xf1f40000) throttle end: throttle time(33) 04-26 17:21:34.986 31038-6017/org.linphone I/mediastreamer: MSMediaCodecH264Enc: seeing SPS 04-26 17:21:34.987 31038-6017/org.linphone I/mediastreamer: MSMediaCodecH264Enc: seeing PPS 04-26 17:21:34.997 31038-6017/org.linphone W/mediastreamer: rfc3984_unpack: a frame with SPS+PPS but no IDR was output, starting at seq number 0 04-26 17:21:34.997 31038-6017/org.linphone I/mediastreamer: Receiving first SPS 04-26 17:21:34.997 31038-6017/org.linphone I/mediastreamer: Receiving first PPS 04-26 17:21:35.000 31038-6096/org.linphone I/OMXClient: Using client-side OMX mux. 04-26 17:21:35.004 1698-31621/? D/awplayer: : (f:omx_vdec, l:255) 04-26 17:21:35.004 1698-31621/? D/awplayer: : Calling process is: org.linphone 04-26 17:21:35.004 1698-31621/? D/awplayer: : ion open fd = 58 04-26 17:21:35.004 1698-31621/? D/awplayer: : (f:component_init, l:513) name = OMX.allwinner.video.decoder.avc 04-26 17:21:35.006 31038-6017/org.linphone I/mediastreamer: Codec video/avc successfully created. 04-26 17:21:35.008 31038-6096/org.linphone I/ACodec: [OMX.allwinner.video.decoder.avc] using color format 0x13 in place of 0x7f420888 04-26 17:21:35.009 1698-3195/? W/awplayer: : set_parameter, OMX_IndexParamPortDefinition, OutPortDef : change nBufferSize[38016] to [460800] to suit frame width[640] and height[480] 04-26 17:21:35.009 1698-3195/? D/awplayer: : (omx_vdec, f:set_parameter, l:1375) OMX_IndexParamPortDefinition, width = 640, height = 480, nPortIndex[1], nBufferCountActual[4], nBufferCountMin[4], nBufferSize[460800],AlloSize[0] 04-26 17:21:35.010 1698-31621/? W/awplayer: : +++++ get display crop: top[0],left[0],width[640],height[480] 04-26 17:21:35.011 1698-6097/? D/awplayer: : set state command, cmd = 0, cmddata = 2. 04-26 17:21:35.011 1698-6097/? D/awplayer: : (f:ComponentThread, l:3855) wait for OMX_VdrvCommand_PrepareVdecLib 04-26 17:21:35.011 1698-6098/? D/awplayer: : (f:ComponentVdrvThread, l:4516) vdrvThread receive cmd[0x0] 04-26 17:21:35.011 1698-6098/? D/awplayer: : (f:ComponentVdrvThread, l:4521)(OMX_VdrvCommand_PrepareVdecLib) 04-26 17:21:35.011 1698-6098/? D/awplayer: : set nDisplayHoldingFrameBufferNum : 0 04-26 17:21:35.011 1698-6098/? W/awplayer: : warning: the nDeInterlaceHoldingFrameBufferNum is 0 04-26 17:21:35.011 1698-6098/? W/awplayer: : warning: the nDisplayHoldingFrameBufferNum is 0 04-26 17:21:35.013 1698-6098/? D/awplayer: : Create decoder '115:h264' 04-26 17:21:35.015 1698-6097/? D/awplayer: : (f:ComponentThread, l:3857) wait for OMX_VdrvCommand_PrepareVdecLib done! 04-26 17:21:35.015 1698-6097/? D/awplayer: : bEnabled[1],[1],bPopulated[1],[0] 04-26 17:21:35.017 31038-6017/org.linphone E/mediastreamer: MSMediaCodecH264Dec: no buffer available for queuing this frame ! Decoder is too slow. 04-26 17:21:35.017 31038-6017/org.linphone I/mediastreamer: MSMediaCodecH264Dec: I-frame received 04-26 17:21:35.017 31038-6017/org.linphone E/mediastreamer: MSMediaCodecH264Dec: no buffer available for queuing this frame ! Decoder is too slow. 04-26 17:21:35.018 31038-6017/org.linphone I/mediastreamer: Request sending of PLI on videostream [0x7f838cc000] 04-26 17:21:35.025 1698-6097/? D/awplayer: : bEnabled[1],[1],bPopulated[1],[1] 04-26 17:21:35.031 31038-6017/org.linphone I/ortp: Sending RTCP SR compound message on session [0x7f838eec00]. 04-26 17:21:35.035 1698-6097/? D/awplayer: : set state command, cmd = 0, cmddata = 3. 04-26 17:21:35.073 31038-31038/org.linphone I/mediastreamer: MSVideo_stream_iterate[0x7f838cc000], local statistics available: Local current jitter buffer size: 0.0ms 04-26 17:21:35.074 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_stats_updated] 04-26 17:21:35.116 1668-2697/? I/[Gralloc]: ion_alloc from ion_client:19 via heap type DMA(mask:16) for 1382400 Bytes uncached buffer successfully, usage = 0x00000902 04-26 17:21:35.177 31038-31038/org.linphone I/mediastreamer: MSVideo stream [0x7f838cc000]: receiving RTCP SR 04-26 17:21:35.178 31038-31038/org.linphone I/mediastreamer: MSMediaCodecH264Enc: PLI requested 04-26 17:21:35.178 31038-31038/org.linphone I/mediastreamer: video_stream_process_rtcp stream [0x7f838cc000] PLI count 1 04-26 17:21:35.178 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_stats_updated] 04-26 17:21:35.283 31038-31038/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f83a914f8]: RTP audio=[d=36.36,u=25.17], video=[d=6.84,u=0.75,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.44], video=[d=2.65,u=1.12], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:35.284 31038-31038/org.linphone I/bctbx: Thread processing load: audio=22.944 video=6.83345 text=0 04-26 17:21:35.287 31038-6017/org.linphone I/mediastreamer: AMediaCodec_setParams() request-sync key transfered to Bundle with value 0 04-26 17:21:35.288 1698-6014/? D/awplayer: : (f:ComponentVencThread, l:3877) OMX_Venc_Cmd_RequestIDRFrame[0x6] 04-26 17:21:35.288 31038-6017/org.linphone E/mediastreamer: MSMediaCodecH264Enc: I-frame requested to MediaCodec 04-26 17:21:35.316 31038-6017/org.linphone I/mediastreamer: MSMediaCodecH264Enc: seeing IDR without prior SPS/PPS, so manually adding them. 04-26 17:21:35.336 31038-31038/org.linphone I/mediastreamer: MSVideo stream [0x7f838cc000]: receiving RTCP RR 04-26 17:21:35.336 31038-31038/org.linphone I/mediastreamer: MSMediaCodecH264Enc: PLI requested 04-26 17:21:35.336 31038-31038/org.linphone I/mediastreamer: video_stream_process_rtcp stream [0x7f838cc000] PLI count 2 04-26 17:21:35.336 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_stats_updated] 04-26 17:21:35.406 31038-5830/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f838eb000] 04-26 17:21:35.696 31038-5830/org.linphone I/mediastreamer: sound/wall clock skew is average=163.198 ms 04-26 17:21:36.008 31038-6017/org.linphone I/mediastreamer: MSMediaCodecH264Dec: I-frame received 04-26 17:21:36.008 31038-6017/org.linphone I/mediastreamer: Receiving first SPS 04-26 17:21:36.009 31038-6017/org.linphone I/mediastreamer: Receiving first PPS 04-26 17:21:36.009 31038-6017/org.linphone I/mediastreamer: MSMediaCodecH264Dec: fresh I-frame submitted to the decoder 04-26 17:21:36.016 1698-6098/? D/awplayer: : here3:hCtx->bProgressice=1 04-26 17:21:36.026 31038-5830/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f838eb000] 04-26 17:21:36.243 31038-31038/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f83a914f8]: RTP audio=[d=44.73,u=35.63], video=[d=9.04,u=139.75,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.66], video=[d=0.06,u=1.41], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:36.243 31038-31038/org.linphone I/bctbx: Thread processing load: audio=23.3166 video=7.76795 text=0 04-26 17:21:36.297 31038-6017/org.linphone I/mediastreamer: AMediaCodec_setParams() request-sync key transfered to Bundle with value 0 04-26 17:21:36.298 1698-6014/? D/awplayer: : (f:ComponentVencThread, l:3877) OMX_Venc_Cmd_RequestIDRFrame[0x6] 04-26 17:21:36.298 31038-6017/org.linphone E/mediastreamer: MSMediaCodecH264Enc: I-frame requested to MediaCodec 04-26 17:21:36.317 31038-6017/org.linphone I/mediastreamer: MSMediaCodecH264Enc: seeing IDR without prior SPS/PPS, so manually adding them. 04-26 17:21:36.607 31038-5830/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f838eb000] 04-26 17:21:36.708 31038-31038/org.linphone W/mediastreamer: Measured and target fps significantly different (9.871164<->30.000000), updating encoder. 04-26 17:21:36.708 31038-31038/org.linphone I/mediastreamer: Video configuration set: bitrate=400000 bits/s, fps=9.871164, vsize=640x480 04-26 17:21:36.709 31038-31038/org.linphone I/mediastreamer: AMediaCodec_setParams() video-bitrate key transfered to Bundle with value 400000 04-26 17:21:36.710 1698-6014/? D/awplayer: : pSelf->m_framerate: 30, bitrate: 400000 04-26 17:21:36.710 1698-6014/? D/awplayer: : setSuperFrameCfg, pSelf->m_framerate: 30, bitrate: 400000 04-26 17:21:36.779 31038-6004/org.linphone I/mediastreamer: sound/wall clock skew is average=313.696326 ms 04-26 17:21:37.011 1698-6098/? D/fbm.c: : FbmCreate, total fbm number: 5, decoder needed: 2, nWidth=640, nHeight=480 04-26 17:21:37.032 1698-3513/? W/awplayer: : +++++ get display crop: top[0],left[0],width[640],height[480] 04-26 17:21:37.049 31038-6017/org.linphone I/mediastreamer: First frame decoded 640x480 04-26 17:21:37.051 31038-31038/org.linphone I/bctbx: First video frame decoded successfully 04-26 17:21:37.053 31038-6041/org.linphone I/mediastreamer: allocate_gl_textures: allocated new textures[0] (1024 x 512) 04-26 17:21:37.057 1668-3356/? I/[Gralloc]: ion_alloc from ion_client:19 via heap type DMA(mask:16) for 1971200 Bytes uncached buffer successfully, usage = 0x00000f02 04-26 17:21:37.187 31038-5830/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f838eb000] 04-26 17:21:37.258 31038-31038/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f83a914f8]: RTP audio=[d=45.95,u=49.30], video=[d=0.43,u=304.53,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.67], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:37.258 31038-31038/org.linphone I/bctbx: Thread processing load: audio=24.1872 video=8.10141 text=0 04-26 17:21:37.297 31038-5830/org.linphone I/ortp: OrtpCongestionDetector: moving from state CongestionStatePending to state CongestionStateNormal 04-26 17:21:37.514 31038-31038/org.linphone I/belle-sip: channel [0x7f83902000]: keep alive sent to [UDP://192.192.255.214:5060] 04-26 17:21:37.717 31038-6017/org.linphone I/ortp: Sending RTCP SR compound message on session [0x7f838eec00]. 04-26 17:21:37.727 31038-5830/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f838eb000] 04-26 17:21:37.735 31038-31038/org.linphone I/mediastreamer: MSVideo_stream_iterate[0x7f838cc000], local statistics available: Local current jitter buffer size: 0.0ms 04-26 17:21:37.736 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_stats_updated] 04-26 17:21:38.243 31038-31038/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f83a914f8]: RTP audio=[d=43.37,u=50.89], video=[d=0.46,u=185.27,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.69], video=[d=0.00,u=2.14], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:38.243 31038-31038/org.linphone I/bctbx: Thread processing load: audio=23.706 video=9.07722 text=0 04-26 17:21:38.277 31038-5830/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f838eb000] 04-26 17:21:38.324 31038-31038/org.linphone I/mediastreamer: MSVideo stream [0x7f838cc000]: receiving RTCP SR 04-26 17:21:38.324 31038-31038/org.linphone I/mediastreamer: MSQualityIndicator[0x7f83a98440][video], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 3.9ms RT propagation : 13.1ms 04-26 17:21:38.324 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_stats_updated] 04-26 17:21:38.727 31038-31038/org.linphone W/mediastreamer: Measured and target fps significantly different (10.088417<->30.000000), updating encoder. 04-26 17:21:38.727 31038-31038/org.linphone I/mediastreamer: Video configuration set: bitrate=400000 bits/s, fps=10.088417, vsize=640x480 04-26 17:21:38.727 31038-31038/org.linphone I/mediastreamer: AMediaCodec_setParams() video-bitrate key transfered to Bundle with value 400000 04-26 17:21:38.729 1698-6014/? D/awplayer: : pSelf->m_framerate: 30, bitrate: 400000 04-26 17:21:38.729 1698-6014/? D/awplayer: : setSuperFrameCfg, pSelf->m_framerate: 30, bitrate: 400000 04-26 17:21:38.797 31038-5830/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f838eb000] 04-26 17:21:38.840 1698-2478/? D/AudioFlinger: mixer(0xf1f40000) throttle end: throttle time(28) 04-26 17:21:38.949 31038-31038/org.linphone I/mediastreamer: MSAudio stream [0x7f838c3b80]: receiving RTCP SR 04-26 17:21:38.949 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_stats_updated] 04-26 17:21:39.101 31038-6004/org.linphone I/mediastreamer: sound/wall clock skew is average=472.144622 ms 04-26 17:21:39.254 31038-31038/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f83a914f8]: RTP audio=[d=45.11,u=50.44], video=[d=0.46,u=105.88,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=2.10,u=0.74], video=[d=0.09,u=0.00], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:39.254 31038-31038/org.linphone I/bctbx: Thread processing load: audio=25.1097 video=10.6183 text=0 04-26 17:21:39.327 31038-5830/org.linphone I/ortp: Sending RTCP SR compound message on session [0x7f838eb000]. 04-26 17:21:39.334 31038-31038/org.linphone I/mediastreamer: MSAudio_stream_iterate[0x7f838c3b80], local statistics available: Local current jitter buffer size: 385.8ms 04-26 17:21:39.337 31038-5830/org.linphone I/ortp: jitter buffer stable: target-size: 315.791667 ms, effective-size: 385.829956 (min: 40 nom: 60, max: 500) 04-26 17:21:39.337 31038-5830/org.linphone I/ortp: jitter buffer rls stats: count=247, clockrate=48000, offset=-12993.8 clock_ratio=1.02396, capped_offset=-1277827146 capped_clock_ratio=1.023961, max_ts_deviation=15158.000000 prev_max_ts_deviation=12993.000000, deviation=3930, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.016215 04-26 17:21:39.338 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_stats_updated] 04-26 17:21:39.973 31038-6017/org.linphone I/ortp: jitter buffer stable: target-size: 59.999999 ms, effective-size: 0.000000 (min: 40 nom: 60, max: 500) 04-26 17:21:39.973 31038-6017/org.linphone I/ortp: jitter buffer rls stats: count=9, clockrate=90000, offset=-49.1038 clock_ratio=1.00168, capped_offset=940933390 capped_clock_ratio=1.001682, max_ts_deviation=900.000000 prev_max_ts_deviation=5400.000000, deviation=900, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.066523 04-26 17:21:40.058 31038-6017/org.linphone I/mediastreamer: Captured mean fps=9.491846, expected=30.000000 04-26 17:21:40.269 31038-31038/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f83a914f8]: RTP audio=[d=47.29,u=49.04], video=[d=0.43,u=79.13,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.01,u=2.05], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:40.270 31038-31038/org.linphone I/bctbx: Thread processing load: audio=20.845 video=8.41811 text=0 04-26 17:21:40.756 31038-31038/org.linphone W/mediastreamer: Measured and target fps significantly different (9.534134<->30.000000), updating encoder. 04-26 17:21:40.756 31038-31038/org.linphone I/mediastreamer: Video configuration set: bitrate=400000 bits/s, fps=9.534134, vsize=640x480 04-26 17:21:40.760 31038-31038/org.linphone I/mediastreamer: AMediaCodec_setParams() video-bitrate key transfered to Bundle with value 400000 04-26 17:21:40.761 1698-6014/? D/awplayer: : pSelf->m_framerate: 30, bitrate: 400000 04-26 17:21:40.761 1698-6014/? D/awplayer: : setSuperFrameCfg, pSelf->m_framerate: 30, bitrate: 400000 04-26 17:21:41.057 31038-5830/org.linphone I/mediastreamer: sound/wall clock skew is average=524.688 ms 04-26 17:21:41.255 31038-31038/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f83a914f8]: RTP audio=[d=45.29,u=49.72], video=[d=0.58,u=120.86,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:41.255 31038-31038/org.linphone I/bctbx: Thread processing load: audio=24.6727 video=9.68281 text=0 04-26 17:21:41.423 31038-6004/org.linphone I/mediastreamer: sound/wall clock skew is average=530.629869 ms 04-26 17:21:41.507 31038-6017/org.linphone I/ortp: Sending RTCP SR compound message on session [0x7f838eec00]. 04-26 17:21:41.516 31038-31038/org.linphone I/mediastreamer: MSVideo_stream_iterate[0x7f838cc000], local statistics available: Local current jitter buffer size: 0.0ms 04-26 17:21:41.517 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_stats_updated] 04-26 17:21:42.057 31038-6017/org.linphone I/mediastreamer: H264 decoder: FPS: 0.999920 04-26 17:21:42.273 31038-31038/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f83a914f8]: RTP audio=[d=45.06,u=49.79], video=[d=0.46,u=159.32,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=2.14], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:42.273 31038-31038/org.linphone I/bctbx: Thread processing load: audio=22.5382 video=10.5143 text=0 04-26 17:21:42.344 1668-1742/? D/hwcomposer: hwc request rotate context times[4] 04-26 17:21:42.344 1668-1742/? D/hwcomposer: alloc rotate_cache_t[1] 04-26 17:21:42.450 31038-31038/org.linphone I/mediastreamer: MSVideo stream [0x7f838cc000]: receiving RTCP SR 04-26 17:21:42.451 31038-31038/org.linphone I/mediastreamer: MSQualityIndicator[0x7f83a98440][video], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 3.0ms RT propagation : 16.4ms 04-26 17:21:42.452 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_stats_updated] 04-26 17:21:42.536 1668-1742/? D/hwcomposer: alloc rotate_cache_t[2] 04-26 17:21:42.765 31038-31038/org.linphone W/mediastreamer: Measured and target fps significantly different (10.872274<->30.000000), updating encoder. 04-26 17:21:42.765 31038-31038/org.linphone I/mediastreamer: Video configuration set: bitrate=400000 bits/s, fps=10.872274, vsize=640x480 04-26 17:21:42.765 31038-31038/org.linphone I/mediastreamer: AMediaCodec_setParams() video-bitrate key transfered to Bundle with value 400000 04-26 17:21:42.766 1698-6014/? D/awplayer: : pSelf->m_framerate: 30, bitrate: 400000 04-26 17:21:42.766 1698-6014/? D/awplayer: : setSuperFrameCfg, pSelf->m_framerate: 30, bitrate: 400000 04-26 17:21:43.248 31038-31038/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f83a914f8]: RTP audio=[d=48.22,u=49.63], video=[d=0.57,u=138.05,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.18,u=0.00], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:43.249 31038-31038/org.linphone I/bctbx: Thread processing load: audio=20.9752 video=10.6496 text=0 04-26 17:21:43.746 31038-6004/org.linphone I/mediastreamer: sound/wall clock skew is average=552.532783 ms 04-26 17:21:44.149 31038-31038/org.linphone I/mediastreamer: MSAudio stream [0x7f838c3b80]: receiving RTCP SR 04-26 17:21:44.150 31038-31038/org.linphone I/mediastreamer: MSQualityIndicator[0x7f83a97fe0][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 5.2ms RT propagation : 18.7ms 04-26 17:21:44.150 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_stats_updated] 04-26 17:21:44.244 31038-31038/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f83a914f8]: RTP audio=[d=46.75,u=51.42], video=[d=0.58,u=150.51,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=5.58,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:44.245 31038-31038/org.linphone I/bctbx: Thread processing load: audio=22.4681 video=14.6972 text=0 04-26 17:21:44.416 31038-5830/org.linphone I/ortp: jitter buffer stable: target-size: 295.791656 ms, effective-size: 385.829956 (min: 40 nom: 60, max: 500) 04-26 17:21:44.417 31038-5830/org.linphone I/ortp: jitter buffer rls stats: count=501, clockrate=48000, offset=-13511.8 clock_ratio=1.03259, capped_offset=-1277827664 capped_clock_ratio=1.032587, max_ts_deviation=3064.000000 prev_max_ts_deviation=1918.000000, deviation=2180, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.008001 04-26 17:21:44.487 31038-5830/org.linphone W/mediastreamer: Flow controlled bufferizer of max 250 ms was filled with at least 900 ms in the last 5000 ms, asking to drop 645 ms 04-26 17:21:44.490 31038-31038/org.linphone W/mediastreamer: Too much buffered audio signal, throwing out 645 ms 04-26 17:21:44.506 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:44.667 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:44.771 31038-31038/org.linphone W/mediastreamer: Measured and target fps significantly different (10.079627<->30.000000), updating encoder. 04-26 17:21:44.771 31038-31038/org.linphone I/mediastreamer: Video configuration set: bitrate=400000 bits/s, fps=10.079627, vsize=640x480 04-26 17:21:44.772 31038-31038/org.linphone I/mediastreamer: AMediaCodec_setParams() video-bitrate key transfered to Bundle with value 400000 04-26 17:21:44.773 1698-6014/? D/awplayer: : pSelf->m_framerate: 30, bitrate: 400000 04-26 17:21:44.773 1698-6014/? D/awplayer: : setSuperFrameCfg, pSelf->m_framerate: 30, bitrate: 400000 04-26 17:21:44.826 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:44.988 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:45.015 31038-6017/org.linphone I/ortp: Sending RTCP SR compound message on session [0x7f838eec00]. 04-26 17:21:45.041 31038-6017/org.linphone I/ortp: jitter buffer stable: target-size: 39.999999 ms, effective-size: 0.000000 (min: 40 nom: 60, max: 500) 04-26 17:21:45.041 31038-6017/org.linphone I/ortp: jitter buffer rls stats: count=14, clockrate=90000, offset=-36.1557 clock_ratio=1.00041, capped_offset=940933403 capped_clock_ratio=1.000412, max_ts_deviation=2672.000000 prev_max_ts_deviation=900.000000, deviation=894, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.060134 04-26 17:21:45.044 31038-31038/org.linphone I/mediastreamer: MSVideo_stream_iterate[0x7f838cc000], local statistics available: Local current jitter buffer size: 0.0ms 04-26 17:21:45.044 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_stats_updated] 04-26 17:21:45.147 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:45.207 31038-6017/org.linphone I/mediastreamer: Captured mean fps=9.011247, expected=30.000000 04-26 17:21:45.292 31038-31038/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f83a914f8]: RTP audio=[d=48.86,u=49.76], video=[d=0.51,u=140.41,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.03,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:45.292 31038-31038/org.linphone I/bctbx: Thread processing load: audio=19.1884 video=10.1225 text=0 04-26 17:21:45.297 1698-2478/? D/AudioFlinger: mixer(0xf1f40000) throttle end: throttle time(14) 04-26 17:21:45.307 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:45.457 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:45.617 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:45.767 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:45.927 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:46.069 31038-6004/org.linphone I/mediastreamer: sound/wall clock skew is average=561.097438 ms 04-26 17:21:46.086 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:46.097 31038-5830/org.linphone I/mediastreamer: sound/wall clock skew is average=561.146 ms 04-26 17:21:46.238 31038-5830/org.linphone I/ortp: Sending RTCP SR compound message on session [0x7f838eb000]. 04-26 17:21:46.247 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:46.257 31038-31038/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f83a914f8]: RTP audio=[d=46.24,u=50.57], video=[d=0.67,u=140.38,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=2.15], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:46.257 31038-31038/org.linphone I/bctbx: Thread processing load: audio=21.356 video=18.1698 text=0 04-26 17:21:46.257 31038-31038/org.linphone I/mediastreamer: MSAudio_stream_iterate[0x7f838c3b80], local statistics available: Local current jitter buffer size: 285.1ms 04-26 17:21:46.258 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_stats_updated] 04-26 17:21:46.282 31038-31038/org.linphone I/mediastreamer: MSVideo stream [0x7f838cc000]: receiving RTCP SR 04-26 17:21:46.283 31038-31038/org.linphone I/mediastreamer: MSQualityIndicator[0x7f83a98440][video], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 2.6ms RT propagation : 10.2ms 04-26 17:21:46.283 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_stats_updated] 04-26 17:21:46.407 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:46.557 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:46.717 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:46.797 31038-31038/org.linphone W/mediastreamer: Measured and target fps significantly different (10.325639<->30.000000), updating encoder. 04-26 17:21:46.797 31038-31038/org.linphone I/mediastreamer: Video configuration set: bitrate=400000 bits/s, fps=10.325639, vsize=640x480 04-26 17:21:46.798 31038-31038/org.linphone I/mediastreamer: AMediaCodec_setParams() video-bitrate key transfered to Bundle with value 400000 04-26 17:21:46.798 1698-6014/? D/awplayer: : pSelf->m_framerate: 30, bitrate: 400000 04-26 17:21:46.798 1698-6014/? D/awplayer: : setSuperFrameCfg, pSelf->m_framerate: 30, bitrate: 400000 04-26 17:21:46.877 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:47.037 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:47.102 31038-6017/org.linphone I/mediastreamer: H264 decoder: FPS: 0.995530 04-26 17:21:47.197 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:47.261 31038-31038/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f83a914f8]: RTP audio=[d=49.86,u=49.51], video=[d=0.60,u=140.42,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=2.15], video=[d=0.07,u=0.00], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:47.262 31038-31038/org.linphone I/bctbx: Thread processing load: audio=24.5351 video=10.9682 text=0 04-26 17:21:47.357 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:47.517 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:47.570 2209-2653/system_process E/ActivityManager: Prevent non-system code from sending protected broadcasts 04-26 17:21:47.678 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:47.837 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:47.998 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:48.157 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:48.240 31038-31038/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f83a914f8]: RTP audio=[d=50.55,u=50.13], video=[d=0.73,u=139.52,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:48.241 31038-31038/org.linphone I/bctbx: Thread processing load: audio=21.8038 video=9.65292 text=0 04-26 17:21:48.316 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:48.392 31038-6004/org.linphone I/mediastreamer: sound/wall clock skew is average=564.739036 ms 04-26 17:21:48.477 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:48.637 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:48.734 31038-31038/org.linphone I/mediastreamer: MSAudio stream [0x7f838c3b80]: receiving RTCP SR 04-26 17:21:48.735 31038-31038/org.linphone I/mediastreamer: MSQualityIndicator[0x7f83a97fe0][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 5.3ms RT propagation : 10.6ms 04-26 17:21:48.735 31038-31038/org.linphone I/liblinphone: Linphone core [0x7f9a9f6200] notified [call_stats_updated] 04-26 17:21:48.786 31038-5830/org.linphone W/mediastreamer: Too many samples to drop, dropping entire frame. 04-26 17:21:48.804 31038-31038/org.linphone W/mediastreamer: Measured and target fps significantly different (9.499858<->30.000000), updating encoder. 04-26 17:21:48.804 31038-31038/org.linphone I/mediastreamer: Video configuration set: bitrate=400000 bits/s, fps=9.499858, vsize=640x480 04-26 17:21:48.807 31038-31038/org.linphone I/mediastreamer: AMediaCodec_setParams() video-bitrate key transfered to Bundle with value 400000 04-26 17:21:48.807 1698-6014/? D/awplayer: : pSelf->m_framerate: 30, bitrate: 400000 04-26 17:21:48.807 1698-6014/? D/awplayer: : setSuperFrameCfg, pSelf->m_framerate: 30, bitrate: 400000

fengqz627 commented 5 years ago

this is B's logs(callee): 04-26 17:21:30.327 28301-28301/org.linphone I/belle-sip: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x1006a2] 04-26 17:21:30.328 28301-28301/org.linphone I/belle-sip: channel [0x7f5fd51000]: starting recv background task with id=[1006a2]. 04-26 17:21:30.329 28301-28301/org.linphone I/belle-sip: channel [0x7f5fd51000]: received [1389] new bytes from [UDP://::ffff:192.192.255.133:5060]: INVITE sip:dd@192.192.255.214 SIP/2.0 Via: SIP/2.0/UDP 192.192.255.133:5060;branch=z9hG4bK.wk~kWmZRF;rport From: "Linphone Android" sip:linphone.android@192.192.255.133;tag=BEwl0BufV To: sip:dd@192.192.255.214 CSeq: 20 INVITE Call-ID: CzON8ONQEJ Max-Forwards: 70 Supported: replaces, outbound, gruu Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Content-Type: application/sdp Content-Length: 706 Contact: "Linphone Android" sip:linphone.android@192.192.255.133;transport=udp;+sip.instance="";+org.linphone.specs=groupchat User-Agent: LinphoneAndroid/4.0.1 (belle-sip/1.6.3)

                                                     v=0
                                                     o=linphone.android 679 747 IN IP4 192.192.255.133
                                                     s=Talk
                                                     c=IN IP4 192.192.255.133
                                                     t=0 0
                                                     a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
                                                     m=audio 7076 RTP/AVP 96 97 98 0 8 18 101 99 100
                                                     a=rtpmap:96 opus/48000/2
                                                     a=fmtp:96 useinbandfec=1
                                                     a=rtpmap:97 speex/16000
                                                     a=fmtp:97 vbr=on
                                                     a=rtpmap:98 speex/8000
                                                     a=fmtp:98 vbr=on
                                                     a=fmtp:18 annexb=yes
                                                     a=rtpmap:101 telephone-event/48000
                                                     a=rtpmap:99 telephone-event/16000
                                                     a=rtpmap:100 telephone-event/8000
                                                     a=rtcp-fb:* trr-int 5
                                                     a=rtcp-fb:* ccm tmmbr
                                                     m=video 9078 RTP/AVP 96
                                                     a=rtpmap:96 H264/90000
                                                     a=fmtp:96 profile-level-id=42801F
                                                     a=rtcp-fb:* trr-int 5
                                                     a=rtcp-fb:* ccm tmmbr
                                                     a=rtcp-fb:96 nack pli
                                                     a=rtcp-fb:96 ccm fir

04-26 17:21:30.363 28301-28301/org.linphone I/belle-sip: channel [0x7f5fd51000] [683] bytes parsed 04-26 17:21:30.364 28301-28301/org.linphone I/belle-sip: channel [0x7f5fd51000] read [706] bytes of body from [::ffff:192.192.255.133:5060] 04-26 17:21:30.374 28301-28301/org.linphone I/belle-sip: bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x7f5fc9d8c0)] acquired [ref=0x2007aa] 04-26 17:21:30.374 28301-28301/org.linphone I/belle-sip: transaction [0x7f5fc9d8c0]: starting transaction background task with id=[2007aa]. 04-26 17:21:30.374 28301-28301/org.linphone I/belle-sip: Changing [server] [INVITE] transaction [0x7f5fc9d8c0], from state [INIT] to [PROCEEDING] 04-26 17:21:30.376 28301-28301/org.linphone I/belle-sip: channel [0x7f5fd51000]: message sent to [UDP://::ffff:192.192.255.133:5060], size: [237] bytes SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.192.255.133:5060;branch=z9hG4bK.wk~kWmZRF;rport From: "Linphone Android" sip:linphone.android@192.192.255.133;tag=BEwl0BufV To: sip:dd@192.192.255.214 Call-ID: CzON8ONQEJ CSeq: 20 INVITE 04-26 17:21:30.376 28301-28301/org.linphone I/belle-sip: New server dialog [0x7f5f13a000] , local tag [fdbxnMB], remote tag [BEwl0BufV] 04-26 17:21:30.376 28301-28301/org.linphone I/bctbx: op [0x7f5f0f5300] : set_or_update_dialog() current=[0x0] new=[0x7f5f13a000] 04-26 17:21:30.376 28301-28301/org.linphone I/bctbx: new incoming call from ["Linphone Android" sip:linphone.android@192.192.255.133] to [sip:dd@192.192.255.214] 04-26 17:21:30.393 28301-28301/org.linphone I/liblinphone: Found payload opus/48000 fmtp=useinbandfec=1 04-26 17:21:30.393 28301-28301/org.linphone I/liblinphone: Found payload speex/16000 fmtp=vbr=on 04-26 17:21:30.394 28301-28301/org.linphone I/liblinphone: Found payload speex/8000 fmtp=vbr=on 04-26 17:21:30.394 28301-28301/org.linphone I/liblinphone: Found payload PCMU/8000 fmtp= 04-26 17:21:30.394 28301-28301/org.linphone I/liblinphone: Found payload PCMA/8000 fmtp= 04-26 17:21:30.394 28301-28301/org.linphone I/liblinphone: Found payload G729/8000 fmtp=annexb=yes 04-26 17:21:30.395 28301-28301/org.linphone I/liblinphone: Found payload telephone-event/48000 fmtp= 04-26 17:21:30.395 28301-28301/org.linphone I/liblinphone: Found payload telephone-event/16000 fmtp= 04-26 17:21:30.395 28301-28301/org.linphone I/liblinphone: Found payload telephone-event/8000 fmtp= 04-26 17:21:30.395 28301-28301/org.linphone I/liblinphone: Found payload H264/90000 fmtp=profile-level-id=42801F 04-26 17:21:30.397 28301-28301/org.linphone I/bctbx: New MediaSession [0x7f5f0fd0b8] initialized (LinphoneCore version: 3.12.0-2618-g259a81cf8) 04-26 17:21:30.400 28301-28301/org.linphone I/bctbx: audio stream index found: 0, updating main audio stream index 04-26 17:21:30.401 28301-28301/org.linphone I/bctbx: video stream index found: 1, updating main video stream index 04-26 17:21:30.401 28301-28301/org.linphone I/bctbx: CallSession [0x7f5f0fd0b8], stream type [audio], multicast role is [inactive] 04-26 17:21:30.402 28301-28301/org.linphone I/ortp: RtpSession bound to [::0] ports [7076] [7077] 04-26 17:21:30.402 28301-28301/org.linphone I/ortp: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 04-26 17:21:30.406 28301-28301/org.linphone I/liblinphone: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80 04-26 17:21:30.406 28301-28301/org.linphone I/liblinphone: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32 04-26 17:21:30.406 28301-28301/org.linphone I/liblinphone: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80 04-26 17:21:30.406 28301-28301/org.linphone I/liblinphone: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32 04-26 17:21:30.407 28301-28301/org.linphone I/mediastreamer: Creating ZRTP engine on rtp session [0x7f5f0bcc00] ssrc 0x8c330e87 04-26 17:21:30.411 28301-28301/org.linphone I/mediastreamer: Setting DSCP to 46 for MSAudio stream. 04-26 17:21:30.411 28301-28301/org.linphone I/bctbx: Equalizer location: hp 04-26 17:21:30.414 28301-28301/org.linphone I/mediastreamer: cannot set noise gate mode to [0] because no volume send 04-26 17:21:30.415 28301-28301/org.linphone I/bctbx: CallSession [0x7f5f0fd0b8], stream type [video], multicast role is [inactive] 04-26 17:21:30.416 28301-28301/org.linphone I/ortp: RtpSession bound to [::0] ports [9078] [9079] 04-26 17:21:30.421 28301-28301/org.linphone I/mediastreamer: This device (gemvary/QUAD C6/tulip) says it has no built-in echo canceller. 04-26 17:21:30.421 28301-28301/org.linphone I/mediastreamer: No information available for [gemvary/QUAD C6/tulip], 04-26 17:21:30.421 28301-28301/org.linphone I/mediastreamer: Sound device information for [gemvary/QUAD C6/tulip] is: builtin=[no], delay=[250] ms 04-26 17:21:30.422 28301-28301/org.linphone I/ortp: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 04-26 17:21:30.422 28301-28301/org.linphone I/mediastreamer: Initializing multistream ZRTP context on rtp session [0x7f5f0ec800] ssrc 0x40563591 04-26 17:21:30.422 28301-28301/org.linphone I/mediastreamer: Setting DSCP to 0 for MSVideo stream. 04-26 17:21:30.423 28301-28301/org.linphone I/bctbx: CallSession [0x7f5f0fd0b8], stream type [text], multicast role is [inactive] 04-26 17:21:30.424 28301-28301/org.linphone I/ortp: RtpSession bound to [::0] ports [11078] [11079] 04-26 17:21:30.424 28301-28301/org.linphone I/ortp: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 04-26 17:21:30.427 28301-28301/org.linphone I/bctbx: Don't put text stream on local offer for CallSession [0x7f5f0fd0b8] 04-26 17:21:30.428 28301-28301/org.linphone I/bctbx: Doing SDP offer/answer process of type incoming 04-26 17:21:30.428 28301-28301/org.linphone I/liblinphone: Doing offer/answer processing with specific provider for codec [opus] 04-26 17:21:30.429 28301-28301/org.linphone I/liblinphone: Linphone core [0x7f759b6200] notified [call_created] 04-26 17:21:30.433 28301-28301/org.linphone I/belle-sip: bellesip_wake_lock_acquire(): Android wake lock [Liblinphone call notification] acquired [ref=0x1007b2] 04-26 17:21:30.433 28301-28301/org.linphone I/bctbx: Starting background task [1050546] with name: [Liblinphone call notification] and expiration of [30] 04-26 17:21:30.434 28301-28301/org.linphone I/bctbx: CallSession [0x7f5f0fd0b8] moving from state State::Idle to State::IncomingReceived 04-26 17:21:30.434 28301-28301/org.linphone I/mediastreamer: acquireWifiLock() 04-26 17:21:30.436 28301-28301/org.linphone I/mediastreamer: acquireMcastLock() 04-26 17:21:30.458 28301-28301/org.linphone I/mediastreamer: acquireCpuLock() 04-26 17:21:30.462 28301-28301/org.linphone I/mediastreamer: New call state [IncomingReceived] 04-26 17:21:30.468 28301-28301/org.linphone W/mediastreamer: Routing audio to speaker, disabling bluetooth audio route 04-26 17:21:30.635 28301-28301/org.linphone D/MediaPlayer: setSubtitleAnchor in MediaPlayer 04-26 17:21:30.803 28301-28301/org.linphone I/liblinphone: Linphone core [0x7f759b6200] notified [call_state_changed] 04-26 17:21:30.803 28301-28301/org.linphone I/bctbx: Ending background task [1050546] with name: [Liblinphone call notification] 04-26 17:21:30.806 28301-28301/org.linphone I/belle-sip: bellesip_wake_lock_release(): Android wake lock released [ref=0x1007b2] 04-26 17:21:30.807 28301-28301/org.linphone I/bctbx: Contact has not been fixed, stack will do 04-26 17:21:30.808 28301-28301/org.linphone I/belle-sip: channel [0x7f5fd51000]: message sent to [UDP://::ffff:192.192.255.133:5060], size: [342] bytes SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.192.255.133:5060;branch=z9hG4bK.wk~kWmZRF;rport From: "Linphone Android" sip:linphone.android@192.192.255.133;tag=BEwl0BufV To: sip:dd@192.192.255.214;tag=fdbxnMB Call-ID: CzON8ONQEJ CSeq: 20 INVITE User-Agent: LinphoneAndroid/4.0.1 (belle-sip/1.6.3) Supported: replaces, outbound, gruu 04-26 17:21:30.808 28301-28301/org.linphone I/belle-sip: Dialog [0x7f5f13a000]: now updated by transaction [0x7f5fc9d8c0]. 04-26 17:21:30.808 28301-28301/org.linphone I/belle-sip: channel [0x7f5fd51000]: ending recv background task with id=[1006a2]. 04-26 17:21:30.817 28301-28301/org.linphone I/belle-sip: bellesip_wake_lock_release(): Android wake lock released [ref=0x1006a2] 04-26 17:21:30.818 28301-28301/org.linphone I/belle-sip: Garbage collecting unowned object of type belle_sdp_session_description_t 04-26 17:21:30.821 28301-28301/org.linphone I/bctbx: Incoming call ringing for 0 seconds 04-26 17:21:30.842 28301-28301/org.linphone I/mediastreamer: [Permission] Read phone state permission is granted 04-26 17:21:30.843 28301-28301/org.linphone I/mediastreamer: [Permission] Read external storage for ring tone permission is granted 04-26 17:21:30.846 28301-28301/org.linphone I/liblinphone: Core callbacks [0x7f5f247760] registered on core [0x7f759b6200] 04-26 17:21:30.849 28301-28301/org.linphone I/mediastreamer: Presence list subscription is disabled 04-26 17:21:30.850 28301-28301/org.linphone I/liblinphone: Notifying all friends that we are [open] 04-26 17:21:30.860 28301-28301/org.linphone I/liblinphone: Core callbacks [0x7f5f247710] registered on core [0x7f759b6200] 04-26 17:21:30.864 28301-28301/org.linphone I/liblinphone: Callbacks [0x7f5f247710] unregistered on core [0x7f759b6200]a 04-26 17:21:30.868 28301-28301/org.linphone I/liblinphone: Callbacks [0x7f5f247760] unregistered on core [0x7f759b6200] 04-26 17:21:30.886 28301-28301/org.linphone I/mediastreamer: [ContactsManager] Step 0 for 0 contacts: 00:00 elapsed since starting 04-26 17:21:30.887 28301-28301/org.linphone E/ActivityThread: Failed to find provider info for com.android.contacts 04-26 17:21:30.888 28301-28301/org.linphone E/ActivityThread: Failed to find provider info for com.android.contacts 04-26 17:21:30.889 28301-28301/org.linphone I/mediastreamer: [ContactsManager] Step 1 for 0 contacts: 00:00 elapsed since starting 04-26 17:21:30.890 28301-28301/org.linphone E/ActivityThread: Failed to find provider info for com.android.contacts 04-26 17:21:30.890 28301-28301/org.linphone E/ActivityThread: Failed to find provider info for com.android.contacts 04-26 17:21:30.891 28301-28301/org.linphone I/mediastreamer: [ContactsManager] Step 2 for 0 contacts: 00:00 elapsed since starting 04-26 17:21:30.891 28301-28301/org.linphone I/mediastreamer: [ContactsManager] Step 3 for 0 contacts: 00:00 elapsed since starting 04-26 17:21:30.929 28301-28301/org.linphone I/liblinphone: Core callbacks [0x7f5f245460] registered on core [0x7f759b6200] 04-26 17:21:30.931 28301-28301/org.linphone I/liblinphone: Core callbacks [0x7f5f245500] registered on core [0x7f759b6200] 04-26 17:21:30.935 28301-28301/org.linphone I/liblinphone: Callbacks [0x7f5f245500] unregistered on core [0x7f759b6200] 04-26 17:21:30.935 28301-28301/org.linphone I/liblinphone: Callbacks [0x7f5f245460] unregistered on core [0x7f759b6200]va 04-26 17:21:31.044 28301-28301/org.linphone I/liblinphone: Core callbacks [0x7f5f245af0] registered on core [0x7f759b6200] 04-26 17:21:31.047 28301-28301/org.linphone I/liblinphone: Core callbacks [0x7f5f245b40] registered on core [0x7f759b6200] 04-26 17:21:31.051 28301-28301/org.linphone I/Linphone: There is already a log collection handler, keep it 04-26 17:21:31.051 28301-28301/org.linphone I/liblinphone: There is already a log collection handler, keep it 04-26 17:21:31.051 28301-28301/org.linphone I/liblinphone: There is already a log collection handler, keep it 04-26 17:21:31.052 28301-28301/org.linphone I/mediastreamer: [KeepAlive] Refresh registers 04-26 17:21:33.071 28301-28301/org.linphone I/bctbx: Incoming call ringing for 3 seconds 04-26 17:21:33.072 28301-28301/org.linphone I/Choreographer: Skipped 118 frames! The application may be doing too much work on its main thread. 04-26 17:21:33.107 28301-28301/org.linphone I/bctbx: Incoming call ringing for 3 seconds 04-26 17:21:33.111 28301-28301/org.linphone I/Linphone: There is already a log collection handler, keep it 04-26 17:21:33.111 28301-28301/org.linphone I/liblinphone: There is already a log collection handler, keep it 04-26 17:21:33.111 28301-28301/org.linphone I/liblinphone: There is already a log collection handler, keep it 04-26 17:21:33.122 28301-28301/org.linphone I/mediastreamer: [KeepAlive] Screen is on, enableava 04-26 17:21:33.374 28301-28301/org.linphone I/bctbx: Don't put text stream on local offer for CallSession [0x7f5f0fd0b8] 04-26 17:21:33.376 28301-28301/org.linphone I/bctbx: Configuring prefered card sampling rate to [48000] 04-26 17:21:33.376 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSRtpRecv:0x7f5fb8b700,0-->MSVoidSink:0x7f5f0f4100,0 04-26 17:21:33.377 28301-28301/org.linphone I/bctbx: Accepting server transaction [0x7f5fc9d8c0] on op [0x7f5f0f5300] 04-26 17:21:33.377 28301-28301/org.linphone I/bctbx: Doing SDP offer/answer process of type incoming 04-26 17:21:33.378 28301-28301/org.linphone I/liblinphone: Doing offer/answer processing with specific provider for codec [opus] 04-26 17:21:33.378 28301-23300/org.linphone I/mediastreamer: Priority used: 99 04-26 17:21:33.379 28301-23300/org.linphone I/mediastreamer: MSAudio MSTicker priority increased to maximum. 04-26 17:21:33.379 28301-28301/org.linphone I/belle-sip: channel [0x7f5fd51000]: message sent to [UDP://::ffff:192.192.255.133:5060], size: [1368] bytes SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.192.255.133:5060;branch=z9hG4bK.wk~kWmZRF;rport From: "Linphone Android" sip:linphone.android@192.192.255.133;tag=BEwl0BufV To: sip:dd@192.192.255.214;tag=fdbxnMB Call-ID: CzON8ONQEJ CSeq: 20 INVITE User-Agent: LinphoneAndroid/4.0.1 (belle-sip/1.6.3) Supported: replaces, outbound, gruu Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Contact: "Linphone Android" sip:linphone.android@192.192.255.214;transport=udp;+sip.instance="";+org.linphone.specs=groupchat Content-Type: application/sdp Content-Length: 712

                                                     v=0
                                                     o=linphone.android 2075 21 IN IP4 192.192.255.214
                                                     s=Talk
                                                     c=IN IP4 192.192.255.214
                                                     t=0 0
                                                     a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
                                                     m=audio 7076 RTP/AVP 96 97 98 0 8 18 101 99 100
                                                     a=rtpmap:96 opus/48000/2
                                                     a=fmtp:96 useinbandfec=1
                                                     a=rtpmap:97 speex/16000
                                                     a=fmtp:97 vbr=on
                                                     a=rtpmap:98 speex/8000
                                                     a=fmtp:98 vbr=on
                                                     a=fmtp:18 annexb=yes
                                                     a=rtpmap:101 telephone-event/48000
                                                     a=rtpmap:99 telephone-event/16000
                                                     a=rtpmap:100 telephone-event/8000
                                                     a=rtcp-fb:* trr-int 5000
                                                     a=rtcp-fb:* ccm tmmbr
                                                     m=video 9078 RTP/AVP 96
                                                     a=rtpmap:96 H264/90000
                                                     a=fmtp:96 profile-level-id=42801F
                                                     a=rtcp-fb:* trr-int 5000
                                                     a=rtcp-fb:* ccm tmmbr
                                                     a=rtcp-fb:96 nack pli
                                                     a=rtcp-fb:96 ccm fir

04-26 17:21:33.379 28301-28301/org.linphone I/belle-sip: Changing [server] [INVITE] transaction [0x7f5fc9d8c0], from state [PROCEEDING] to [ACCEPTED] 04-26 17:21:33.380 28301-28301/org.linphone I/belle-sip: Dialog [0x7f5f13a000]: now updated by transaction [0x7f5fc9d8c0]. 04-26 17:21:33.380 28301-28301/org.linphone I/bctbx: CallSession [0x7f5f0fd0b8] moving from state State::IncomingReceived to State::Connected 04-26 17:21:33.380 28301-28301/org.linphone I/mediastreamer: New call state [Connected] 04-26 17:21:33.393 28301-28301/org.linphone W/mediastreamer: Routing audio to speaker, disabling bluetooth audio route 04-26 17:21:33.431 28301-28301/org.linphone I/liblinphone: Linphone core [0x7f759b6200] notified [call_state_changed] 04-26 17:21:33.432 28301-28301/org.linphone I/bctbx: startStreams() CallSession=[0x7f5f0fd0b8] local upload_bandwidth=[0] kbit/s; local download_bandwidth=[0] kbit/s 04-26 17:21:33.432 28301-28301/org.linphone I/bctbx: Audio bandwidth for CallSession [0x7f5f0fd0b8] is 50 04-26 17:21:33.433 28301-28301/org.linphone I/bctbx: CallSession [0x7f5f0fd0b8] - setting up advanced rate control 04-26 17:21:33.435 28301-28301/org.linphone I/ortp: RtpSession [0x7f5f0bcc00] sending to rtp [::ffff:192.192.255.133]:7076 rtcp [::ffff:192.192.255.133]:7077 04-26 17:21:33.436 28301-28301/org.linphone I/mediastreamer: Stun packet sent for session [0x7f5f0bcc00] 04-26 17:21:33.436 28301-28301/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f5f0bcc00] 04-26 17:21:33.437 28301-28301/org.linphone I/mediastreamer: ms_filter_unlink: MSRtpRecv:0x7f5fb8b700,0-->MSVoidSink:0x7f5f0f4100,0 04-26 17:21:33.437 28301-28301/org.linphone I/bctbx: Webrtc aecm does not support sampling rate 48000, using 16000 instead 04-26 17:21:33.437 28301-28301/org.linphone I/mediastreamer: Sampling rate forced to 16000Hz to allow the use of echo canceller 04-26 17:21:33.437 28301-28301/org.linphone I/mediastreamer: opus stereo support is deactivated because of incompatible features targeted for this AudioStream 04-26 17:21:33.438 28301-28301/org.linphone I/mediastreamer: speex_lib_ctl init with neon ? 1 04-26 17:21:33.438 28301-28301/org.linphone I/mediastreamer: speex_lib_ctl init with neon ? 1 04-26 17:21:33.438 28301-28301/org.linphone I/mediastreamer: Setting echo canceller delay with value provided by soundcard: 250 ms 04-26 17:21:33.438 28301-28301/org.linphone I/mediastreamer: target bitrate not set for stream [0x7f5f042280] using payload's bitrate is 50000 04-26 17:21:33.438 28301-28301/org.linphone I/mediastreamer: Setting audio encoder network bitrate to [50000] on stream [0x7f5f042280] 04-26 17:21:33.439 28301-28301/org.linphone I/mediastreamer: opus setbitrate to 50000 04-26 17:21:33.439 28301-28301/org.linphone I/mediastreamer: Decoder has FEC capabilities 04-26 17:21:33.442 28301-28301/org.linphone I/mediastreamer: This device (gemvary/QUAD C6/tulip) says it has no built-in echo canceller. 04-26 17:21:33.443 28301-28301/org.linphone I/mediastreamer: No information available for [gemvary/QUAD C6/tulip], 04-26 17:21:33.443 28301-28301/org.linphone I/mediastreamer: Sound device information for [gemvary/QUAD C6/tulip] is: builtin=[no], delay=[250] ms 04-26 17:21:33.447 28301-28301/org.linphone I/mediastreamer: This device (gemvary/QUAD C6/tulip) says it has no built-in echo canceller. 04-26 17:21:33.447 28301-28301/org.linphone I/mediastreamer: No information available for [gemvary/QUAD C6/tulip], 04-26 17:21:33.447 28301-28301/org.linphone I/mediastreamer: Sound device information for [gemvary/QUAD C6/tulip] is: builtin=[no], delay=[250] ms 04-26 17:21:33.447 28301-28301/org.linphone I/mediastreamer: MSVolume set gain to [0.000000 db], [1.000000] linear 04-26 17:21:33.451 28301-28301/org.linphone I/mediastreamer: This device (gemvary/QUAD C6/tulip) says it has no built-in echo canceller. 04-26 17:21:33.451 28301-28301/org.linphone I/mediastreamer: No information available for [gemvary/QUAD C6/tulip], 04-26 17:21:33.451 28301-28301/org.linphone I/mediastreamer: Sound device information for [gemvary/QUAD C6/tulip] is: builtin=[no], delay=[250] ms 04-26 17:21:33.452 28301-28301/org.linphone I/mediastreamer: MSVolume set gain to [0.000000 db], [1.000000] linear 04-26 17:21:33.452 28301-28301/org.linphone I/mediastreamer: configuring MSOpenSLESRecorder:0x7f5fb8b700-->MSOpusEnc:0x7f5fb8bb80 from rate [44100] to rate [16000] and from channel [1] to channel [1] 04-26 17:21:33.452 28301-28301/org.linphone I/mediastreamer: configuring MSOpusDec:0x7f5fb8bc00-->MSOpenSLESPlayer:0x7f5f0f4100 from rate [16000] to rate [44100] and from channel [1] to channel [1] 04-26 17:21:33.452 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSOpenSLESRecorder:0x7f5fb8b700,0-->MSResample:0x7f5fb8bd80,0 04-26 17:21:33.452 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSResample:0x7f5fb8bd80,0-->MSEqualizer:0x7f5fc23080,0 04-26 17:21:33.453 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSEqualizer:0x7f5fc23080,0-->MSWebRTCAECM:0x7f5fb8b380,1 04-26 17:21:33.453 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSWebRTCAECM:0x7f5fb8b380,1-->MSVolume:0x7f5fb8bc80,0 04-26 17:21:33.453 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSVolume:0x7f5fb8bc80,0-->MSAudioMixer:0x7f5fc23000,0 04-26 17:21:33.453 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSAudioMixer:0x7f5fc23000,0-->MSOpusEnc:0x7f5fb8bb80,0 04-26 17:21:33.453 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSOpusEnc:0x7f5fb8bb80,0-->MSRtpSend:0x7f5fb8b300,0 04-26 17:21:33.454 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSRtpRecv:0x7f5f0f4080,0-->MSOpusDec:0x7f5fb8bc00,0 04-26 17:21:33.454 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSOpusDec:0x7f5fb8bc00,0-->MSAudioFlowControl:0x7f5fc23180,0 04-26 17:21:33.454 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSAudioFlowControl:0x7f5fc23180,0-->MSDtmfGen:0x7f5fb8bb00,0 04-26 17:21:33.454 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSDtmfGen:0x7f5fb8bb00,0-->MSVolume:0x7f5fb8bd00,0 04-26 17:21:33.454 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSVolume:0x7f5fb8bd00,0-->MSEqualizer:0x7f5fc23100,0 04-26 17:21:33.454 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSEqualizer:0x7f5fc23100,0-->MSAudioMixer:0x7f5fc23200,0 04-26 17:21:33.455 28301-28301/org.linphone I/mediastreamer: speex_lib_ctl init with neon ? 1 04-26 17:21:33.455 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSFilePlayer:0x7f5fc23280,0-->MSResample:0x7f5fc23300,0 04-26 17:21:33.455 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSResample:0x7f5fc23300,0-->MSAudioMixer:0x7f5fc23200,1 04-26 17:21:33.455 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSAudioMixer:0x7f5fc23200,0-->MSWebRTCAECM:0x7f5fb8b380,0 04-26 17:21:33.455 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSWebRTCAECM:0x7f5fb8b380,0-->MSResample:0x7f5fb8bf80,0 04-26 17:21:33.456 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSResample:0x7f5fb8bf80,0-->MSOpenSLESPlayer:0x7f5f0f4100,0 04-26 17:21:33.465 28301-28301/org.linphone I/mediastreamer: Initializing speex resampler in mode [voip] 04-26 17:21:33.465 28301-28301/org.linphone I/bctbx: Initializing WebRTC echo canceler with framesize=160, delay_ms=250, delay_samples=4000 04-26 17:21:33.466 28301-28301/org.linphone I/mediastreamer: Initializing speex resampler in mode [voip] 04-26 17:21:33.466 28301-28301/org.linphone I/mediastreamer: Initializing speex resampler in mode [voip] 04-26 17:21:33.582 28301-28301/org.linphone I/mediastreamer: MSOpusEnc: codec bitrate set to [34000] with ptime [20] 04-26 17:21:33.583 28301-28301/org.linphone I/mediastreamer: Setting opus codec bitrate to [34000] from network bitrate [50000] with ptime [20] 04-26 17:21:33.583 28301-28301/org.linphone I/mediastreamer: Filter MSRtpRecv is already being scheduled; nothing to do. 04-26 17:21:33.591 28301-28301/org.linphone I/mediastreamer: This device (gemvary/QUAD C6/tulip) says it has no built-in echo canceller. 04-26 17:21:33.591 28301-23300/org.linphone I/ortp: First estimation 04-26 17:21:33.591 28301-28301/org.linphone I/mediastreamer: No information available for [gemvary/QUAD C6/tulip], 04-26 17:21:33.592 28301-23300/org.linphone W/bctbx: Getting reference signal but no echo to synchronize on. 04-26 17:21:33.592 28301-28301/org.linphone I/mediastreamer: Sound device information for [gemvary/QUAD C6/tulip] is: builtin=[no], delay=[250] ms 04-26 17:21:33.592 28301-23300/org.linphone W/bctbx: Not enough ref samples, using zeroes 04-26 17:21:33.592 28301-28301/org.linphone I/mediastreamer: MSVolume set gain to [0.000000 db], [1.000000] linear 04-26 17:21:33.594 28301-23300/org.linphone I/mediastreamer: MSAudioMixer [0x7f5fc23000] is entering bypass mode. 04-26 17:21:33.595 28301-28301/org.linphone I/mediastreamer: Setting video size 640x480 on stream [0x7f60e93800] 04-26 17:21:33.596 28301-28301/org.linphone I/bctbx: CallSession [0x7f5f0fd0b8] - setting up advanced rate control 04-26 17:21:33.596 28301-28301/org.linphone I/bctbx: startVideoStream: device_rotation=0 04-26 17:21:33.601 28301-23300/org.linphone I/mediastreamer: Stun packet sent for session [0x7f5f0bcc00] 04-26 17:21:33.601 28301-23300/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f5f0bcc00] 04-26 17:21:33.604 28301-28301/org.linphone I/ortp: RtpSession [0x7f5f0ec800] sending to rtp [::ffff:192.192.255.133]:9078 rtcp [::ffff:192.192.255.133]:9079 04-26 17:21:33.605 28301-28301/org.linphone I/ortp: Using permissive algorithm 04-26 17:21:33.605 28301-28301/org.linphone W/ortp: Fail to increase socket's recv buffer size with SO_RCVBUFFORCE: Operation not permitted. 04-26 17:21:33.606 28301-28301/org.linphone W/ortp: Fail to increase socket's send buffer size with SO_SNDBUFFORCE: Operation not permitted. 04-26 17:21:33.606 28301-28301/org.linphone W/ortp: Fail to increase socket's recv buffer size with SO_RCVBUFFORCE: Operation not permitted. 04-26 17:21:33.607 28301-28301/org.linphone I/mediastreamer: Stun packet sent for session [0x7f5f0ec800] 04-26 17:21:33.608 28301-28301/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f5f0ec800] 04-26 17:21:33.613 28301-23300/org.linphone I/bctbx: Samples are back. 04-26 17:21:33.618 28301-23322/org.linphone I/OMXClient: Using client-side OMX mux. 04-26 17:21:33.629 28301-28301/org.linphone I/mediastreamer: Codec video/avc successfully created. 04-26 17:21:33.630 28301-28301/org.linphone I/mediastreamer: Using the default video configuration list 04-26 17:21:33.630 28301-28301/org.linphone I/mediastreamer: target and payload bitrates not set for stream [0x7f60e93800] using lowest configuration of preferred video size 640x480 04-26 17:21:33.631 28301-28301/org.linphone I/mediastreamer: Limiting bitrate of video encoder to 0 bits/s for stream [0x7f60e93800] 04-26 17:21:33.636 28301-28301/org.linphone I/mediastreamer: Video configuration set: bitrate=400000 bits/s, fps=15.000000, vsize=640x480 04-26 17:21:33.637 28301-28301/org.linphone E/mediastreamer: no such method on filter MSStaticImage, fid=16387 method index=4 04-26 17:21:33.638 28301-28301/org.linphone E/mediastreamer: Cannot load /data/user/0/org.linphone/files/share/images/nowebcamCIF.jpg 04-26 17:21:33.639 28301-28301/org.linphone I/mediastreamer: Output video size adjusted to match camera resolution (640x480) 04-26 17:21:33.640 28301-28301/org.linphone I/mediastreamer: Video configuration set: bitrate=400000 bits/s, fps=30.000000, vsize=640x480 04-26 17:21:33.640 28301-28301/org.linphone I/mediastreamer: Setting sent vsize=640x480, fps=30.000000 04-26 17:21:33.641 28301-28301/org.linphone I/mediastreamer: Video configuration set: bitrate=400000 bits/s, fps=30.000000, vsize=640x480 04-26 17:21:33.641 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSStaticImage:0x7f5fc23980,0-->MSPixConv:0x7f5fcc6c00,0 04-26 17:21:33.642 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSPixConv:0x7f5fcc6c00,0-->MSTee:0x7f5fcc6600,0 04-26 17:21:33.642 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSTee:0x7f5fcc6600,0-->MSSizeConv:0x7f5fcc6c80,0 04-26 17:21:33.642 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSSizeConv:0x7f5fcc6c80,0-->MSMediaCodecH264Enc:0x7f5fcc6100,0 04-26 17:21:33.643 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSMediaCodecH264Enc:0x7f5fcc6100,0-->MSRtpSend:0x7f5fb8b480,0 04-26 17:21:33.643 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSTee:0x7f5fcc6600,2-->MSJpegWriter:0x7f5fcc6680,0 04-26 17:21:33.644 28301-28301/org.linphone I/mediastreamer: MSMediaCodecH264Dec initialization 04-26 17:21:33.644 28301-28301/org.linphone E/mediastreamer: no such method on filter MSMediaCodecH264Dec, fid=16389 method index=7 04-26 17:21:33.645 28301-28301/org.linphone I/mediastreamer: ogl_display_new : 0x7f60e65a00 04-26 17:21:33.645 28301-28301/org.linphone I/mediastreamer: android_display_init 0x7f5fcc6d80 0x7f5ebf1830 04-26 17:21:33.645 28301-28301/org.linphone I/mediastreamer: MSMediaCodecH264Dec: freeze on error enabled 04-26 17:21:33.646 28301-28301/org.linphone E/mediastreamer: no such method on filter MSAndroidOpenGLDisplay, fid=16387 method index=1 04-26 17:21:33.646 28301-28301/org.linphone E/mediastreamer: no such method on filter MSAndroidOpenGLDisplay, fid=16387 method index=0 04-26 17:21:33.649 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSRtpRecv:0x7f5fcc6e00,0-->MSTee:0x7f5fb8b500,0 04-26 17:21:33.649 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSTee:0x7f5fb8b500,1-->MSItcSink:0x7f5fb8b580,0 04-26 17:21:33.649 28301-28301/org.linphone I/mediastreamer: configure_itc(): format set to type=video;encoding=H264;vsize=0x0;fps=0.000000;fmtp='' 04-26 17:21:33.650 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSTee:0x7f5fb8b500,0-->MSMediaCodecH264Dec:0x7f5fcc6d00,0 04-26 17:21:33.650 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSMediaCodecH264Dec:0x7f5fcc6d00,0-->MSTee:0x7f5fcc6f00,0 04-26 17:21:33.650 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSTee:0x7f5fcc6f00,1-->MSJpegWriter:0x7f5fcc6e80,0 04-26 17:21:33.650 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSTee:0x7f5fcc6f00,0-->MSAndroidOpenGLDisplay:0x7f5fcc6d80,0 04-26 17:21:33.650 28301-28301/org.linphone I/mediastreamer: ms_filter_link: MSTee:0x7f5fcc6600,1-->MSAndroidOpenGLDisplay:0x7f5fcc6d80,1 04-26 17:21:33.651 28301-28301/org.linphone I/mediastreamer: MSMediaCodecH264Enc: AMediaImage is available. 04-26 17:21:33.652 28301-23329/org.linphone I/mediastreamer: MSVideo MSTicker priority left to normal. 04-26 17:21:33.652 28301-23322/org.linphone I/ACodec: [OMX.allwinner.video.encoder.avc] using color format 0x15 in place of 0x7f420888 04-26 17:21:33.653 28301-23322/org.linphone D/ACodec: video_def->nFrameWidth: 640, video_def->nFrameHeight: 480 04-26 17:21:33.655 28301-23322/org.linphone I/ACodec: setupVideoEncoder succeeded 04-26 17:21:33.657 28301-28301/org.linphone I/mediastreamer: MSMediaCodecH264Enc: encoder successfully configured. size=640x480, color-format=2135033992 04-26 17:21:33.662 28301-28301/org.linphone I/mediastreamer: MSMediaCodecH264Enc: encoder successfully started 04-26 17:21:33.662 28301-28301/org.linphone I/mediastreamer: Filter MSRtpRecv is already being scheduled; nothing to do. 04-26 17:21:33.662 28301-28301/org.linphone I/bctbx: CallSession[0x7f5f0fd0b8] : payload type 96 opus/48000 fmtp=useinbandfec=1 added to frozen list 04-26 17:21:33.662 28301-28301/org.linphone I/bctbx: CallSession[0x7f5f0fd0b8] : payload type 97 speex/16000 fmtp=vbr=on added to frozen list 04-26 17:21:33.662 28301-28301/org.linphone I/bctbx: CallSession[0x7f5f0fd0b8] : payload type 98 speex/8000 fmtp=vbr=on added to frozen list 04-26 17:21:33.663 28301-28301/org.linphone I/bctbx: CallSession[0x7f5f0fd0b8] : payload type 0 PCMU/8000 fmtp= added to frozen list 04-26 17:21:33.663 28301-28301/org.linphone I/bctbx: CallSession[0x7f5f0fd0b8] : payload type 8 PCMA/8000 fmtp= added to frozen list 04-26 17:21:33.663 28301-28301/org.linphone I/bctbx: CallSession[0x7f5f0fd0b8] : payload type 18 G729/8000 fmtp=annexb=yes added to frozen list 04-26 17:21:33.663 28301-28301/org.linphone I/bctbx: CallSession[0x7f5f0fd0b8] : payload type 101 telephone-event/48000 fmtp= added to frozen list 04-26 17:21:33.664 28301-28301/org.linphone I/bctbx: CallSession[0x7f5f0fd0b8] : payload type 99 telephone-event/16000 fmtp= added to frozen list 04-26 17:21:33.664 28301-28301/org.linphone I/bctbx: CallSession[0x7f5f0fd0b8] : payload type 100 telephone-event/8000 fmtp= added to frozen list 04-26 17:21:33.664 28301-28301/org.linphone I/bctbx: CallSession[0x7f5f0fd0b8] : payload type 96 H264/90000 fmtp=profile-level-id=42801F added to frozen list 04-26 17:21:33.664 28301-28301/org.linphone I/bctbx: CallSession [0x7f5f0fd0b8] moving from state State::Connected to State::StreamsRunning 04-26 17:21:33.665 28301-28301/org.linphone I/mediastreamer: New call state [StreamsRunning] 04-26 17:21:33.666 28301-28301/org.linphone W/mediastreamer: [AudioManager] already in MODE_IN_COMMUNICATION, skipping... 04-26 17:21:33.666 28301-23329/org.linphone I/mediastreamer: Stun packet sent for session [0x7f5f0ec800] 04-26 17:21:33.668 28301-23329/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f5f0ec800] 04-26 17:21:33.668 28301-23329/org.linphone I/ortp: First estimation 04-26 17:21:33.733 28301-28301/org.linphone E/mediastreamer: CallIncommingActivity - onCreate - State.StreamsRunning - speaker = true 04-26 17:21:33.737 28301-28301/org.linphone I/liblinphone: Linphone core [0x7f759b6200] notified [call_state_changed] 04-26 17:21:33.737 28301-28301/org.linphone I/bctbx: CallSession accepted 04-26 17:21:33.738 28301-28301/org.linphone W/mediastreamer: Routing audio to earpiece, disabling bluetooth audio route 04-26 17:21:33.740 28301-28301/org.linphone W/OrientationEventListener: Cannot detect sensors. Not enabled 04-26 17:21:33.768 28301-28301/org.linphone W/MediaPlayer: mediaplayer went away with unhandled events 04-26 17:21:33.768 28301-28301/org.linphone I/belle-sip: Garbage collecting unowned object of type belle_sdp_session_description_t 04-26 17:21:33.769 28301-28301/org.linphone I/ortp: First estimation 04-26 17:21:33.769 28301-28301/org.linphone I/ortp: First estimation 04-26 17:21:33.769 28301-28301/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f5f0fd0b8]: RTP audio=[d=9.84,u=0.00], video=[d=2.39,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:33.770 28301-28301/org.linphone I/bctbx: Thread processing load: audio=21.3982 video=1.54343 text=0 04-26 17:21:33.774 28301-28301/org.linphone I/liblinphone: Callbacks [0x7f5f245b40] unregistered on core [0x7f759b6200] 04-26 17:21:33.775 28301-28301/org.linphone I/liblinphone: Callbacks [0x7f5f245af0] unregistered on core [0x7f759b6200] 04-26 17:21:33.810 28301-28301/org.linphone I/belle-sip: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x10077a] 04-26 17:21:33.810 28301-28301/org.linphone I/belle-sip: channel [0x7f5fd51000]: starting recv background task with id=[10077a]. 04-26 17:21:33.810 28301-28301/org.linphone I/belle-sip: channel [0x7f5fd51000]: received [363] new bytes from [UDP://::ffff:192.192.255.133:5060]: ACK sip:linphone.android@192.192.255.214;transport=udp SIP/2.0 Via: SIP/2.0/UDP 192.192.255.133:5060;rport;branch=z9hG4bK.F3vsuonfQ From: "Linphone Android" sip:linphone.android@192.192.255.133;tag=BEwl0BufV To: sip:dd@192.192.255.214;tag=fdbxnMB CSeq: 20 ACK Call-ID: CzON8ONQEJ Max-Forwards: 70 User-Agent: LinphoneAndroid/4.0.1 (belle-sip/1.6.3) 04-26 17:21:33.829 28301-28301/org.linphone I/belle-sip: channel [0x7f5fd51000] [363] bytes parsed 04-26 17:21:33.829 28301-28301/org.linphone I/belle-sip: Incoming INVITE has ACK, dialog is happy 04-26 17:21:33.830 28301-28301/org.linphone I/belle-sip: channel [0x7f5fd51000]: ending recv background task with id=[10077a]. 04-26 17:21:33.832 28301-28301/org.linphone I/belle-sip: bellesip_wake_lock_release(): Android wake lock released [ref=0x10077a] 04-26 17:21:34.093 28301-23300/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f5f0bcc00] 04-26 17:21:34.096 28301-28301/org.linphone E/mediastreamer: android.hardware.camera2.CameraAccessException: The system-wide limit for number of open cameras has been reached, and more camera devices cannot be opened until previous instances are closed. 04-26 17:21:34.108 28301-28301/org.linphone I/liblinphone: Notifying all friends that we are [on-the-phone] 04-26 17:21:34.111 28301-28301/org.linphone W/mediastreamer: Routing audio to speaker, disabling bluetooth audio route 04-26 17:21:34.118 28301-28301/org.linphone I/mediastreamer: This device (gemvary/QUAD C6/tulip) says it has no built-in echo canceller. 04-26 17:21:34.118 28301-28301/org.linphone I/mediastreamer: No information available for [gemvary/QUAD C6/tulip], 04-26 17:21:34.118 28301-28301/org.linphone I/mediastreamer: Sound device information for [gemvary/QUAD C6/tulip] is: builtin=[no], delay=[250] ms 04-26 17:21:34.123 28301-28301/org.linphone I/liblinphone: Core callbacks [0x7f5f247530] registered on core [0x7f759b6200] 04-26 17:21:34.128 28301-28301/org.linphone I/liblinphone: Core callbacks [0x7f5f247580] registered on core [0x7f759b6200] 04-26 17:21:34.129 28301-28301/org.linphone E/mediastreamer: no such method on filter MSAndroidOpenGLDisplay, fid=16387 method index=3 04-26 17:21:34.130 28301-28301/org.linphone I/liblinphone: Java_org_linphone_core_CoreImpl_setNativeVideoWindowId(): NewGlobalRef(0x20073a) 04-26 17:21:34.130 28301-28301/org.linphone I/mediastreamer: Sending opengles_display pointer (0x7f60e65a00) 04-26 17:21:34.162 28301-23329/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f5f0ec800] 04-26 17:21:34.215 28301-28301/org.linphone W/mediastreamer: Video display surface created 04-26 17:21:34.215 28301-28301/org.linphone I/mediastreamer: Video display surface is being changed. 04-26 17:21:34.216 28301-28301/org.linphone I/liblinphone: Java_org_linphone_core_CoreImpl_setNativeVideoWindowId(): NewGlobalRef(0x200716) 04-26 17:21:34.216 28301-28301/org.linphone I/mediastreamer: Clearing old opengles_display (0x7f60e65a00) 04-26 17:21:34.216 28301-28301/org.linphone I/mediastreamer: uninit opengles_display (gl initialized:0) 04-26 17:21:34.216 28301-28301/org.linphone I/mediastreamer: ogl_display_new : 0x7f60e65a00 04-26 17:21:34.216 28301-28301/org.linphone I/mediastreamer: Sending opengles_display pointer (0x7f60e65a00) 04-26 17:21:34.217 28301-28301/org.linphone I/liblinphone: Java_org_linphone_core_CoreImpl_setNativeVideoWindowId(): DeleteGlobalRef(0x20073a) 04-26 17:21:34.217 28301-28301/org.linphone W/mediastreamer: Video display surface changed 04-26 17:21:34.218 28301-23364/org.linphone W/mediastreamer: creating OpenGL ES 2.0 context 04-26 17:21:34.229 28301-23364/org.linphone I/mediastreamer: init opengles_display (1024 x 517, gl initialized:0) 04-26 17:21:34.230 28301-23364/org.linphone I/mediastreamer: resize opengles_display (1024 x 517, gl initialized:0) 04-26 17:21:34.230 28301-28301/org.linphone W/mediastreamer: Video preview surface created 04-26 17:21:34.230 28301-28301/org.linphone I/mediastreamer: Video preview surface is being changed. 04-26 17:21:34.231 28301-28301/org.linphone I/liblinphone: Java_org_linphone_core_CoreImpl_setNativePreviewWindowId(): NewGlobalRef(0x200736) 04-26 17:21:34.231 28301-28301/org.linphone E/mediastreamer: no such method on filter MSStaticImage, fid=16387 method index=4 04-26 17:21:34.231 28301-23364/org.linphone I/mediastreamer: OpenGL program info: [NO INFORMATION] 04-26 17:21:34.231 28301-28301/org.linphone I/liblinphone: Java_org_linphone_core_CoreImpl_setNativePreviewWindowId(): DeleteGlobalRef(0x762) 04-26 17:21:34.233 28301-28301/org.linphone W/mediastreamer: Video preview surface changed 04-26 17:21:34.456 28301-28301/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f5f0fd0b8]: RTP audio=[d=7.01,u=26.92], video=[d=0.07,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.23], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:34.457 28301-28301/org.linphone I/bctbx: Thread processing load: audio=17.1339 video=0.707042 text=0 04-26 17:21:34.596 28301-23300/org.linphone I/mediastreamer: MSAudioMixer [0x7f5fc23200] is entering bypass mode. 04-26 17:21:34.602 28301-23300/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f5f0bcc00] 04-26 17:21:34.662 28301-23329/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f5f0ec800] 04-26 17:21:34.678 28301-23329/org.linphone I/mediastreamer: MSMediaCodecH264Enc: first frame to encode queued (size: 640x480) 04-26 17:21:34.712 28301-23329/org.linphone I/mediastreamer: MSMediaCodecH264Enc: seeing SPS 04-26 17:21:34.713 28301-23329/org.linphone I/mediastreamer: MSMediaCodecH264Enc: seeing PPS 04-26 17:21:34.840 28301-28301/org.linphone I/mediastreamer: MSVideo stream [0x7f60e93800]: receiving RTCP SR 04-26 17:21:34.841 28301-28301/org.linphone I/mediastreamer: MSMediaCodecH264Enc: PLI requested 04-26 17:21:34.841 28301-28301/org.linphone I/mediastreamer: video_stream_process_rtcp stream [0x7f60e93800] PLI count 1 04-26 17:21:34.842 28301-28301/org.linphone I/liblinphone: Linphone core [0x7f759b6200] notified [call_stats_updated] 04-26 17:21:34.844 28301-23329/org.linphone W/mediastreamer: rfc3984_unpack: a frame with SPS+PPS but no IDR was output, starting at seq number 0 04-26 17:21:34.844 28301-23329/org.linphone I/mediastreamer: Receiving first SPS 04-26 17:21:34.845 28301-23329/org.linphone I/mediastreamer: Receiving first PPS 04-26 17:21:34.850 28301-23409/org.linphone I/OMXClient: Using client-side OMX mux. 04-26 17:21:34.861 28301-23329/org.linphone I/mediastreamer: Codec video/avc successfully created. 04-26 17:21:34.864 28301-23409/org.linphone I/ACodec: [OMX.allwinner.video.decoder.avc] using color format 0x13 in place of 0x7f420888 04-26 17:21:34.879 28301-23329/org.linphone E/mediastreamer: MSMediaCodecH264Dec: no buffer available for queuing this frame ! Decoder is too slow. 04-26 17:21:34.879 28301-23329/org.linphone I/mediastreamer: MSMediaCodecH264Dec: I-frame received 04-26 17:21:34.880 28301-23329/org.linphone E/mediastreamer: MSMediaCodecH264Dec: no buffer available for queuing this frame ! Decoder is too slow. 04-26 17:21:34.881 28301-23329/org.linphone I/mediastreamer: Request sending of PLI on videostream [0x7f60e93800] 04-26 17:21:34.922 28301-23329/org.linphone I/ortp: Sending RTCP SR compound message on session [0x7f5f0ec800]. 04-26 17:21:34.942 28301-28301/org.linphone I/mediastreamer: MSVideo_stream_iterate[0x7f60e93800], local statistics available: Local current jitter buffer size: 0.0ms 04-26 17:21:34.943 28301-28301/org.linphone I/liblinphone: Linphone core [0x7f759b6200] notified [call_stats_updated] 04-26 17:21:34.953 28301-23329/org.linphone I/mediastreamer: Request sending of PLI on videostream [0x7f60e93800] 04-26 17:21:35.032 28301-23329/org.linphone I/mediastreamer: Request sending of PLI on videostream [0x7f60e93800] 04-26 17:21:35.104 28301-28301/org.linphone I/mediastreamer: MSVideo_stream_iterate[0x7f60e93800], local statistics available: Local current jitter buffer size: 0.0ms 04-26 17:21:35.105 28301-28301/org.linphone I/liblinphone: Linphone core [0x7f759b6200] notified [call_stats_updated] 04-26 17:21:35.105 28301-23329/org.linphone I/ortp: Sending RTCP RR compound message on session [0x7f5f0ec800]. 04-26 17:21:35.172 28301-23329/org.linphone I/mediastreamer: MSMediaCodecH264Dec: I-frame received 04-26 17:21:35.172 28301-23329/org.linphone I/mediastreamer: Receiving first SPS 04-26 17:21:35.172 28301-23329/org.linphone I/mediastreamer: Receiving first PPS 04-26 17:21:35.173 28301-23329/org.linphone I/mediastreamer: MSMediaCodecH264Dec: fresh I-frame submitted to the decoder 04-26 17:21:35.222 28301-23300/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f5f0bcc00] 04-26 17:21:35.326 28301-23329/org.linphone I/mediastreamer: First frame decoded 640x480 04-26 17:21:35.329 28301-23364/org.linphone I/mediastreamer: allocate_gl_textures: allocated new textures[0] (1024 x 512) 04-26 17:21:35.349 28301-28301/org.linphone I/bctbx: First video frame decoded successfully 04-26 17:21:35.468 28301-28301/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f5f0fd0b8]: RTP audio=[d=50.30,u=26.92], video=[d=100.52,u=0.74,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.23], video=[d=0.18,u=1.10], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:35.468 28301-28301/org.linphone I/bctbx: Thread processing load: audio=42.5409 video=11.6074 text=0 04-26 17:21:35.688 28301-23329/org.linphone I/mediastreamer: AMediaCodec_setParams() request-sync key transfered to Bundle with value 0 04-26 17:21:35.690 28301-23329/org.linphone E/mediastreamer: MSMediaCodecH264Enc: I-frame requested to MediaCodec 04-26 17:21:35.726 28301-23329/org.linphone I/mediastreamer: MSMediaCodecH264Enc: seeing IDR without prior SPS/PPS, so manually adding them. 04-26 17:21:35.771 28301-28301/org.linphone W/mediastreamer: Measured and target fps significantly different (1.000000<->30.000000), updating encoder. 04-26 17:21:35.771 28301-28301/org.linphone I/mediastreamer: Video configuration set: bitrate=400000 bits/s, fps=1.000000, vsize=640x480 04-26 17:21:35.772 28301-28301/org.linphone I/mediastreamer: AMediaCodec_setParams() video-bitrate key transfered to Bundle with value 400000 04-26 17:21:35.822 28301-23300/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f5f0bcc00] 04-26 17:21:36.173 28301-23329/org.linphone I/mediastreamer: MSMediaCodecH264Dec: I-frame received 04-26 17:21:36.401 28301-23300/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f5f0bcc00] 04-26 17:21:36.456 28301-23307/org.linphone I/mediastreamer: sound/wall clock skew is average=310.936098 ms 04-26 17:21:36.461 28301-28301/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f5f0fd0b8]: RTP audio=[d=46.94,u=38.56], video=[d=359.09,u=8.37,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.65], video=[d=0.00,u=2.46], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:36.462 28301-28301/org.linphone I/bctbx: Thread processing load: audio=26.833 video=10.8134 text=0 04-26 17:21:36.982 28301-23300/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f5f0bcc00] 04-26 17:21:37.317 28301-28301/org.linphone I/belle-sip: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x2006f6] 04-26 17:21:37.318 28301-28301/org.linphone I/belle-sip: channel [0x7f5fd51000]: starting recv background task with id=[2006f6]. 04-26 17:21:37.318 28301-28301/org.linphone I/belle-sip: channel [0x7f5fd51000]: ending recv background task with id=[2006f6]. 04-26 17:21:37.320 28301-28301/org.linphone I/belle-sip: bellesip_wake_lock_release(): Android wake lock released [ref=0x2006f6] 04-26 17:21:37.458 28301-28301/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f5f0fd0b8]: RTP audio=[d=47.93,u=49.54], video=[d=191.09,u=8.35,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.67], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:37.459 28301-28301/org.linphone I/bctbx: Thread processing load: audio=37.2584 video=9.78289 text=0 04-26 17:21:37.522 28301-23300/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f5f0bcc00] 04-26 17:21:37.525 28301-28301/org.linphone I/mediastreamer: MSVideo stream [0x7f60e93800]: receiving RTCP SR 04-26 17:21:37.526 28301-28301/org.linphone I/liblinphone: Linphone core [0x7f759b6200] notified [call_stats_updated] 04-26 17:21:37.774 28301-28301/org.linphone W/mediastreamer: Measured and target fps significantly different (1.000000<->30.000000), updating encoder. 04-26 17:21:37.775 28301-28301/org.linphone I/mediastreamer: Video configuration set: bitrate=400000 bits/s, fps=1.000000, vsize=640x480 04-26 17:21:37.775 28301-28301/org.linphone I/mediastreamer: AMediaCodec_setParams() video-bitrate key transfered to Bundle with value 400000 04-26 17:21:38.072 28301-23300/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f5f0bcc00] 04-26 17:21:38.102 28301-23329/org.linphone I/ortp: Sending RTCP SR compound message on session [0x7f5f0ec800]. 04-26 17:21:38.132 28301-28301/org.linphone I/mediastreamer: MSVideo_stream_iterate[0x7f60e93800], local statistics available: Local current jitter buffer size: 0.0ms 04-26 17:21:38.132 28301-28301/org.linphone I/liblinphone: Linphone core [0x7f759b6200] notified [call_stats_updated] 04-26 17:21:38.455 28301-28301/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f5f0fd0b8]: RTP audio=[d=42.67,u=50.34], video=[d=115.95,u=0.39,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=0.70], video=[d=0.08,u=0.00], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:38.456 28301-28301/org.linphone I/bctbx: Thread processing load: audio=37.4522 video=13.789 text=0 04-26 17:21:38.613 28301-23300/org.linphone I/mediastreamer: Stun packet sent on rtcp for session [0x7f5f0bcc00] 04-26 17:21:38.712 28301-23300/org.linphone I/ortp: Sending RTCP SR compound message on session [0x7f5f0bcc00]. 04-26 17:21:38.720 28301-28301/org.linphone I/mediastreamer: MSAudio_stream_iterate[0x7f5f042280], local statistics available: Local current jitter buffer size: 82.2ms 04-26 17:21:38.720 28301-28301/org.linphone I/liblinphone: Linphone core [0x7f759b6200] notified [call_stats_updated] 04-26 17:21:38.778 28301-23307/org.linphone I/mediastreamer: sound/wall clock skew is average=478.364181 ms 04-26 17:21:38.852 28301-23300/org.linphone I/mediastreamer: sound/wall clock skew is average=481.234 ms 04-26 17:21:39.084 28301-23300/org.linphone W/mediastreamer: Flow controlled bufferizer of max 250 ms is filled with 1220 ms at the end of the 5000 ms interval, asking to drop 480 ms 04-26 17:21:39.089 28301-28301/org.linphone W/mediastreamer: Too much buffered audio signal, throwing out 480 ms 04-26 17:21:39.130 28301-28301/org.linphone I/mediastreamer: MSAudio stream [0x7f5f042280]: receiving RTCP SR 04-26 17:21:39.131 28301-28301/org.linphone I/liblinphone: Linphone core [0x7f759b6200] notified [call_stats_updated] 04-26 17:21:39.223 28301-23300/org.linphone I/ortp: jitter buffer stable: target-size: 74.874997 ms, effective-size: 82.171951 (min: 40 nom: 60, max: 500) 04-26 17:21:39.223 28301-23300/org.linphone I/ortp: jitter buffer rls stats: count=247, clockrate=48000, offset=-465.001 clock_ratio=1.11038, capped_offset=254057449 capped_clock_ratio=1.110377, max_ts_deviation=3594.000000 prev_max_ts_deviation=3269.000000, deviation=2598, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.016473 04-26 17:21:39.433 28301-23300/org.linphone I/ortp: OrtpCongestionDetector: moving from state CongestionStateNormal to state CongestionStatePending 04-26 17:21:39.471 28301-28301/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f5f0fd0b8]: RTP audio=[d=48.58,u=50.12], video=[d=30.22,u=0.39,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=1.71,u=2.82], video=[d=0.00,u=2.24], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:39.472 28301-28301/org.linphone I/bctbx: Thread processing load: audio=30.7535 video=8.12068 text=0 04-26 17:21:39.779 28301-28301/org.linphone W/mediastreamer: Measured and target fps significantly different (1.000000<->30.000000), updating encoder. 04-26 17:21:39.780 28301-28301/org.linphone I/mediastreamer: Video configuration set: bitrate=400000 bits/s, fps=1.000000, vsize=640x480 04-26 17:21:39.780 28301-28301/org.linphone I/mediastreamer: AMediaCodec_setParams() video-bitrate key transfered to Bundle with value 400000 04-26 17:21:39.893 28301-23329/org.linphone I/ortp: jitter buffer stable: target-size: 59.999999 ms, effective-size: 0.000000 (min: 40 nom: 60, max: 500) 04-26 17:21:39.894 28301-23329/org.linphone I/ortp: jitter buffer rls stats: count=114, clockrate=90000, offset=31.9959 clock_ratio=1.00027, capped_offset=-18343744 capped_clock_ratio=1.000273, max_ts_deviation=166.000000 prev_max_ts_deviation=5400.000000, deviation=166, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.022149 04-26 17:21:40.381 28301-23329/org.linphone I/mediastreamer: H264 decoder: FPS: 9.486427 04-26 17:21:40.461 28301-28301/org.linphone I/bctbx: Bandwidth usage for CallSession [0x7f5f0fd0b8]: RTP audio=[d=41.23,u=49.39], video=[d=86.17,u=0.39,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.01,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 04-26 17:21:40.461 28301-28301/org.linphone I/bctbx: Thread processing load: audio=30.4214 video=17.3231 text=0

fengqz627 commented 5 years ago

By the way, what I mean by delay is that when you operate on A, it takes 0.4-0.5 seconds for B to have a corresponding action.

Viish commented 5 years ago

Old issue, I'm closing it. Feel free to reopen if it still applies with version 4.2.

christf commented 2 years ago

This happens to me as well in 4.5. It seems to be cpu starvation here. Is there a way to influence settings so they are lighter on the CPU when encoding?