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 688 forks source link

Questions about audio and video #1669

Closed fengqz627 closed 2 years ago

fengqz627 commented 2 years ago

Hi,

The device is integrated with linphone-android, I find a problem： After the device is restarted, the audio and video of the first call is not as stable as that of the subsequent call. In particular, the sound quality of the first call after the device is restarted is relatively poor. If you call again after hanging up, the sound quality of the call will be much better. What is the reason and can it be improved?

thanks. Best Regards.

Viish commented 2 years ago

Hi,

Please attach SDK logs. Do you reproduce with linphone-android?

fengqz627 commented 2 years ago

Hi， Viish, thanks. For the first call after the device is restarted, not only the sound quality is poor, but also the audio and video delay is large. For the second call after hanging up, the sound quality of the call is much better, and the audio and video delay is much smaller。 The attachment is the log of the first call.

fengqz627 commented 2 years ago

2022-05-12 17:38:43.961 216-303/? D/alsa_route: route_set_controls() set route 0 2022-05-12 17:38:43.961 216-303/? E/alsa_route: set_controls() Can not get ctl : DAC Playback Volume 2022-05-12 17:38:43.962 227-12097/? D/RockFFPlayer: Messagecomplete type [2] 2022-05-12 17:38:43.962 227-12097/? D/RockFFPlayer: run : Message 2 complete 2022-05-12 17:38:43.962 227-812/? D/RockFFPlayer: enqueueMessage:sync wait ok 2022-05-12 17:38:43.963 227-12100/? D/RockFFClock: MasterClock - sync pts -172828, mPauseSysClock -1 2022-05-12 17:38:43.989 11944-11944/com.gemvary.manager I/tag_linphone: linphone_core_set_playback_gain_db(): no active call. 2022-05-12 17:38:43.989 11944-11944/com.gemvary.manager I/tag_linphone: linphone_core_set_mic_gain_db(): no active call. 2022-05-12 17:38:43.990 11944-11944/com.gemvary.manager I/X-LOG: Thread: main ├ com.gemvary.dev.AudioParameterConfig.getOneToOneAPByServer(AudioParameterConfig.java:4031) ├ com.gemvary.call.sdk.base.MyLinphone.do_group_call(MyLinphone.java:1159) ├ com.gemvary.call.sdk.base.MyLinphone.start_call(MyLinphone.java:289) ├ com.gemvary.call.sdk.target.CallTargetLAN.call(CallTargetLAN.java:45) └ com.gemvary.call.sdk.base.CallManager.toCall(CallManager.java:410) getOneToOneAPByServer() PlaybackGain =1.0, micGain=-2.0, ng_thres=0.25, ng_floorgain=0.0 2022-05-12 17:38:43.993 11944-11944/com.gemvary.manager D/LPManager: the call role is manager 2022-05-12 17:38:43.996 11944-11944/com.gemvary.manager I/tag_linphone: New MediaSession [0x8ed3d78c] initialized (LinphoneCore version: 9869bb10b) 2022-05-12 17:38:43.996 11944-11944/com.gemvary.manager I/tag_linphone: CallSession [0x8ed3d78c], stream type [audio], multicast role is [inactive] 2022-05-12 17:38:43.996 11944-11944/com.gemvary.manager I/tag_linphone: If ipv6 is not enabled, Media-session.cpp 2022-05-12 17:38:43.996 11944-11944/com.gemvary.manager I/tag_linphone: If ipv6 is not enabled, Media-session.cpp 2022-05-12 17:38:43.997 11944-11944/com.gemvary.manager I/tag_linphone: RtpSession bound to [0.0.0.0] ports [7076] [7077] 2022-05-12 17:38:43.997 11944-11944/com.gemvary.manager I/tag_linphone: fqztemp 91 audiostream.c 2022-05-12 17:38:43.997 11944-11944/com.gemvary.manager I/tag_linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2022-05-12 17:38:43.997 11944-11944/com.gemvary.manager I/tag_linphone: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80 2022-05-12 17:38:43.997 11944-11944/com.gemvary.manager I/tag_linphone: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32 2022-05-12 17:38:43.997 11944-11944/com.gemvary.manager I/tag_linphone: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80 2022-05-12 17:38:43.997 11944-11944/com.gemvary.manager I/tag_linphone: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32 2022-05-12 17:38:43.997 11944-11944/com.gemvary.manager I/tag_linphone: Creating ZRTP engine on rtp session [0x8fb06800] ssrc 0x63449f7a 2022-05-12 17:38:43.999 11944-11944/com.gemvary.manager I/tag_linphone: Setting DSCP to 46 for MSAudio stream. 2022-05-12 17:38:43.999 11944-11944/com.gemvary.manager I/tag_linphone: Equalizer location: hp 2022-05-12 17:38:44.000 11944-11944/com.gemvary.manager I/tag_linphone: fqztemp use_ng=1 2022-05-12 17:38:44.000 11944-11944/com.gemvary.manager I/tag_linphone: cannot set noise gate mode to [1] because no volume send 2022-05-12 17:38:44.000 11944-11944/com.gemvary.manager I/tag_linphone: CallSession [0x8ed3d78c], stream type [video], multicast role is [inactive] 2022-05-12 17:38:44.000 11944-11944/com.gemvary.manager I/tag_linphone: If ipv6 is not enabled, Media-session.cpp 2022-05-12 17:38:44.000 11944-11944/com.gemvary.manager I/tag_linphone: If ipv6 is not enabled, Media-session.cpp 2022-05-12 17:38:44.000 11944-11944/com.gemvary.manager I/tag_linphone: RtpSession bound to [0.0.0.0] ports [9078] [9079] 2022-05-12 17:38:44.003 11944-11944/com.gemvary.manager I/tag_linphone: This device (rockchip/GRG-S5/rk3288) says it has no built-in echo canceller. 2022-05-12 17:38:44.003 11944-11944/com.gemvary.manager I/tag_linphone: No information available for [rockchip/GRG-S5/rk3288], 2022-05-12 17:38:44.003 11944-11944/com.gemvary.manager I/tag_linphone: Sound device information for [rockchip/GRG-S5/rk3288] is: builtin=[no], delay=[250] ms 2022-05-12 17:38:44.004 11944-11944/com.gemvary.manager I/tag_linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2022-05-12 17:38:44.004 11944-11944/com.gemvary.manager I/tag_linphone: Initializing multistream ZRTP context on rtp session [0x8fb05a00] ssrc 0x56a79347 2022-05-12 17:38:44.004 11944-11944/com.gemvary.manager I/tag_linphone: Setting DSCP to 0 for MSVideo stream. 2022-05-12 17:38:44.004 11944-11944/com.gemvary.manager I/tag_linphone: CallSession [0x8ed3d78c], stream type [text], multicast role is [inactive] 2022-05-12 17:38:44.004 11944-11944/com.gemvary.manager I/tag_linphone: If ipv6 is not enabled, Media-session.cpp 2022-05-12 17:38:44.004 11944-11944/com.gemvary.manager I/tag_linphone: If ipv6 is not enabled, Media-session.cpp 2022-05-12 17:38:44.004 11944-11944/com.gemvary.manager I/tag_linphone: RtpSession bound to [0.0.0.0] ports [11078] [11079] 2022-05-12 17:38:44.004 11944-11944/com.gemvary.manager I/tag_linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2022-05-12 17:38:44.005 11944-11944/com.gemvary.manager I/tag_linphone: Linphone core [0x93ff0d80] notified [call_created] 2022-05-12 17:38:44.005 11944-11944/com.gemvary.manager I/tag_linphone: CallSession [0x8ed3d78c] moving from state State::Idle to State::OutgoingInit 2022-05-12 17:38:44.005 11944-11944/com.gemvary.manager I/tag_linphone: acquireWifiLock() 2022-05-12 17:38:44.007 11944-11944/com.gemvary.manager I/tag_linphone: acquireMcastLock() 2022-05-12 17:38:44.008 11944-11944/com.gemvary.manager I/tag_linphone: acquireCpuLock() 2022-05-12 17:38:44.044 11944-11944/com.gemvary.manager D/MyLinphone: callStateChanged, callId: outdoor@192.192.255.103, state: OutgoingInit, message: Starting outgoing call 2022-05-12 17:38:44.045 11944-11944/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.logBoth(MyLinphone.java:820) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:843) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.inviteAddressWithParams(Native Method) └ org.linphone.core.CoreImpl.inviteAddressWithParams(Core.java:4577) callStateChanged, callId: outdoor@192.192.255.103, state: OutgoingInit, message: Starting outgoing call 2022-05-12 17:38:44.045 11944-11944/com.gemvary.manager D/CallTarget: after added, call id list is: [outdoor@192.192.255.103] 2022-05-12 17:38:44.046 11944-11944/com.gemvary.manager D/CallManager: Thread: main ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:785) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:775) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:769) ├ com.gemvary.call.sdk.base.CallManager.outgoingInit(CallManager.java:842) └ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:945)

callId: outdoor@192.192.255.103, state: OutgoingInit, reason: null, ExtraStatus{targetPending=false, transferType=UNKNOWN, cloudVoipNotConnected=false, unitNo='null', roomNo='null', preparationError=null, hangupReason=null}

2022-05-12 17:38:44.049 11944-11944/com.gemvary.manager I/tag_linphone: VideoSize= vga 2022-05-12 17:38:44.049 11944-11944/com.gemvary.manager I/tag_linphone: fps= 15 2022-05-12 17:38:44.049 11944-11944/com.gemvary.manager I/tag_linphone: Linphone core [0x93ff0d80] notified [call_state_changed] 2022-05-12 17:38:44.051 11944-11944/com.gemvary.manager I/tag_linphone: Don't put video stream on local offer for CallSession [0x8ed3d78c] 2022-05-12 17:38:44.051 11944-11944/com.gemvary.manager I/tag_linphone: Don't put text stream on local offer for CallSession [0x8ed3d78c] 2022-05-12 17:38:44.051 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSRtpRecv:0x93fe2b68,0-->MSVoidSink:0x93fe2ba0,0 2022-05-12 17:38:44.051 11944-11944/com.gemvary.manager I/tag_linphone: ms_devices_info_get_model getdevicemodel= [GRG-S5] 2022-05-12 17:38:44.052 11944-11944/com.gemvary.manager I/tag_linphone: Contact has not been fixed, stack will do 2022-05-12 17:38:44.053 11944-12102/com.gemvary.manager I/tag_linphone: Priority used: 99 2022-05-12 17:38:44.054 11944-12102/com.gemvary.manager I/tag_linphone: MSAudio MSTicker priority increased to maximum. 2022-05-12 17:38:44.054 11944-11944/com.gemvary.manager I/tag_linphone: ["{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42:5060] calling [sip:outdoor@192.192.255.103] on op [0x92c70580] 2022-05-12 17:38:44.054 11944-11944/com.gemvary.manager I/tag_linphone: get_jni_env(): thread successfuly attached 2022-05-12 17:38:44.055 11944-11944/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x8ed11f70)] acquired [ref=0x200b2a] 2022-05-12 17:38:44.055 11944-11944/com.gemvary.manager I/tag_linphone: transaction [0x8ed11f70]: starting transaction background task with id=[200b2a]. 2022-05-12 17:38:44.056 11944-11944/com.gemvary.manager I/tag_linphone: belle_sip_client_transaction_send_request(): waiting channel to be ready 2022-05-12 17:38:44.057 11944-11944/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip send channel] acquired [ref=0x100b32] 2022-05-12 17:38:44.057 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: starting send background task with id=[100b32]. 2022-05-12 17:38:44.057 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: starting resolution of 192.192.255.103 2022-05-12 17:38:44.057 11944-11944/com.gemvary.manager I/tag_linphone: channel 0x8e792840: state RES_IN_PROGRESS 2022-05-12 17:38:44.057 11944-11944/com.gemvary.manager I/tag_linphone: transaction [0x8ed11f70] channel state changed to [RES_IN_PROGRESS] 2022-05-12 17:38:44.057 11944-11944/com.gemvary.manager I/tag_linphone: channel 0x8e792840: state RES_DONE 2022-05-12 17:38:44.057 11944-11944/com.gemvary.manager I/tag_linphone: transaction [0x8ed11f70] channel state changed to [RES_DONE] 2022-05-12 17:38:44.057 11944-11944/com.gemvary.manager I/tag_linphone: channel 0x8e792840: state CONNECTING 2022-05-12 17:38:44.057 11944-11944/com.gemvary.manager I/tag_linphone: transaction [0x8ed11f70] channel state changed to [CONNECTING] 2022-05-12 17:38:44.057 11944-11944/com.gemvary.manager I/tag_linphone: Trying to connect to [UDP://192.192.255.103:5060] 2022-05-12 17:38:44.061 11944-11944/com.gemvary.manager I/tag_linphone: Channel has local address 192.192.255.42:5060 2022-05-12 17:38:44.061 11944-11944/com.gemvary.manager I/tag_linphone: channel 0x8e792840: state READY 2022-05-12 17:38:44.061 11944-11944/com.gemvary.manager I/tag_linphone: transaction [0x8ed11f70] channel state changed to [READY] 2022-05-12 17:38:44.061 11944-11944/com.gemvary.manager I/tag_linphone: Changing [client] [INVITE] transaction [0x8ed11f70], from state [INIT] to [CALLING] 2022-05-12 17:38:44.061 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: message sent to [UDP://192.192.255.103:5060], size: [1727] bytes 2022-05-12 17:38:44.061 11944-11944/com.gemvary.manager I/tag_linphone: INVITE sip:outdoor@192.192.255.103 SIP/2.0 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: Via: SIP/2.0/UDP 192.192.255.42:5060;branch=z9hG4bK.7muWKbfcZ;rport 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: From: "{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42;tag=K5XtRxNxO 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: To: sip:outdoor@192.192.255.103 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: CSeq: 20 INVITE 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: Call-ID: 7mAEt-n6Vy 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: Max-Forwards: 70 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: Supported: replaces, outbound, gruu 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: Content-Type: application/sdp 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: Content-Length: 530 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: Contact: "{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42;transport=udp;+sip.instance="";+org.linphone.specs=groupchat 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: User-Agent: LinphoneAndroid/6.1.20220512170321 (belle-sip/1.6.3) 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: v=0 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: o=linphone.android 281 1935 IN IP4 192.192.255.42 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: s=Talk 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: c=IN IP4 192.192.255.42 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: t=0 0 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: m=audio 7076 RTP/AVP 96 97 98 0 8 18 101 99 100 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: a=rtpmap:96 opus/48000/2 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: a=fmtp:96 useinbandfec=1 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: a=rtpmap:97 speex/16000 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: a=fmtp:97 vbr=on 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: a=rtpmap:98 speex/8000 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: a=fmtp:98 vbr=on 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: a=fmtp:18 annexb=yes 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: a=rtpmap:101 telephone-event/48000 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: a=rtpmap:99 telephone-event/16000 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: a=rtpmap:100 telephone-event/8000 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: a=rtcp-fb: trr-int 5 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: a=rtcp-fb: ccm tmmbr 2022-05-12 17:38:44.062 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: ending send background task with id=[100b32]. 2022-05-12 17:38:44.065 11944-11944/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x100b32] 2022-05-12 17:38:44.066 11944-11944/com.gemvary.manager I/tag_linphone: CallSession [0x8ed3d78c] moving from state State::OutgoingInit to State::OutgoingProgress 2022-05-12 17:38:44.067 11944-11944/com.gemvary.manager D/MyLinphone: callStateChanged, callId: outdoor@192.192.255.103, state: OutgoingProgress, message: Outgoing call in progress 2022-05-12 17:38:44.067 11944-11944/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.logBoth(MyLinphone.java:820) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:843) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.inviteAddressWithParams(Native Method) └ org.linphone.core.CoreImpl.inviteAddressWithParams(Core.java:4577) callStateChanged, callId: outdoor@192.192.255.103, state: OutgoingProgress, message: Outgoing call in progress 2022-05-12 17:38:44.067 11944-11944/com.gemvary.manager I/tag_linphone: Linphone core [0x93ff0d80] notified [call_state_changed] 2022-05-12 17:38:44.068 11944-11944/com.gemvary.manager D/EventBus: No subscribers registered for event class com.gemvary.manager.bean.CallStatusEvent 2022-05-12 17:38:44.068 11944-11944/com.gemvary.manager D/EventBus: No subscribers registered for event class org.greenrobot.eventbus.NoSubscriberEvent 2022-05-12 17:38:44.150 11944-12052/com.gemvary.manager D/mali_winsys: EGLint new_window_surface(egl_winsys_display , void , EGLSurface, EGLConfig, egl_winsys_surface *, egl_color_buffer_format , EGLBoolean) returns 0x3000 2022-05-12 17:38:44.157 11944-11944/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0xb1a] 2022-05-12 17:38:44.157 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: starting recv background task with id=[b1a]. 2022-05-12 17:38:44.157 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: received [538] new bytes from [UDP://192.192.255.103:5060]: 2022-05-12 17:38:44.157 11944-11944/com.gemvary.manager I/tag_linphone: SIP/2.0 100 Trying 2022-05-12 17:38:44.157 11944-11944/com.gemvary.manager I/tag_linphone: Via: SIP/2.0/UDP 192.192.255.42:5060;rport=5060;received=192.192.255.42;branch=z9hG4bK.7muWKbfcZ 2022-05-12 17:38:44.157 11944-11944/com.gemvary.manager I/tag_linphone: Call-ID: 7mAEt-n6Vy 2022-05-12 17:38:44.157 11944-11944/com.gemvary.manager I/tag_linphone: From: "{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42;tag=K5XtRxNxO 2022-05-12 17:38:44.157 11944-11944/com.gemvary.manager I/tag_linphone: To: sip:outdoor@192.192.255.103 2022-05-12 17:38:44.157 11944-11944/com.gemvary.manager I/tag_linphone: CSeq: 20 INVITE 2022-05-12 17:38:44.157 11944-11944/com.gemvary.manager I/tag_linphone: Content-Length: 0 2022-05-12 17:38:44.161 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840] [538] bytes parsed 2022-05-12 17:38:44.161 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: discovered public ip and port are [192.192.255.42:5060] 2022-05-12 17:38:44.161 11944-11944/com.gemvary.manager I/tag_linphone: Found transaction matching response. 2022-05-12 17:38:44.161 11944-11944/com.gemvary.manager I/tag_linphone: Changing [client] [INVITE] transaction [0x8ed11f70], from state [CALLING] to [PROCEEDING] 2022-05-12 17:38:44.161 11944-11944/com.gemvary.manager I/tag_linphone: op [0x92c70580] : set_or_update_dialog() current=[0x0] new=[0x0] 2022-05-12 17:38:44.161 11944-11944/com.gemvary.manager I/tag_linphone: Op [0x92c70580] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2022-05-12 17:38:44.161 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: ending recv background task with id=[b1a]. 2022-05-12 17:38:44.162 11944-11944/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0xb1a] 2022-05-12 17:38:44.162 11944-11944/com.gemvary.manager I/tag_linphone: Garbage collecting unowned object of type belle_sdp_session_description_t 2022-05-12 17:38:44.164 11944-11944/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x100b16] 2022-05-12 17:38:44.164 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: starting recv background task with id=[100b16]. 2022-05-12 17:38:44.164 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: received [711] new bytes from [UDP://192.192.255.103:5060]: 2022-05-12 17:38:44.164 11944-11944/com.gemvary.manager I/tag_linphone: SIP/2.0 180 Ringing 2022-05-12 17:38:44.164 11944-11944/com.gemvary.manager I/tag_linphone: Via: SIP/2.0/UDP 192.192.255.42:5060;rport=5060;received=192.192.255.42;branch=z9hG4bK.7muWKbfcZ 2022-05-12 17:38:44.164 11944-11944/com.gemvary.manager I/tag_linphone: Call-ID: 7mAEt-n6Vy 2022-05-12 17:38:44.164 11944-11944/com.gemvary.manager I/tag_linphone: From: "{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42;tag=K5XtRxNxO 2022-05-12 17:38:44.164 11944-11944/com.gemvary.manager I/tag_linphone: To: sip:outdoor@192.192.255.103;tag=nPS-AK8SZlI9iPui10Pnd1Sq7eNstEqr 2022-05-12 17:38:44.164 11944-11944/com.gemvary.manager I/tag_linphone: CSeq: 20 INVITE 2022-05-12 17:38:44.165 11944-11944/com.gemvary.manager I/tag_linphone: Contact: 2022-05-12 17:38:44.165 11944-11944/com.gemvary.manager I/tag_linphone: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS 2022-05-12 17:38:44.165 11944-11944/com.gemvary.manager I/tag_linphone: Content-Length: 0 2022-05-12 17:38:44.169 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840] [711] bytes parsed 2022-05-12 17:38:44.169 11944-11944/com.gemvary.manager I/tag_linphone: Found transaction matching response. 2022-05-12 17:38:44.169 11944-11944/com.gemvary.manager I/tag_linphone: New client dialog [0x8e82f1e0] , local tag [K5XtRxNxO], remote tag [nPS-AK8SZlI9iPui10Pnd1Sq7eNstEqr] 2022-05-12 17:38:44.169 11944-11944/com.gemvary.manager I/tag_linphone: Dialog [0x8e82f1e0]: now updated by transaction [0x8ed11f70]. 2022-05-12 17:38:44.170 11944-11944/com.gemvary.manager I/tag_linphone: op [0x92c70580] : set_or_update_dialog() current=[0x0] new=[0x8e82f1e0] 2022-05-12 17:38:44.170 11944-11944/com.gemvary.manager I/tag_linphone: Op [0x92c70580] receiving call response [180], dialog is [0x8e82f1e0] in state [BELLE_SIP_DIALOG_EARLY] 2022-05-12 17:38:44.170 11944-11944/com.gemvary.manager I/tag_linphone: Remote ringing... 2022-05-12 17:38:44.170 11944-11944/com.gemvary.manager I/tag_linphone: CallSession [0x8ed3d78c] moving from state State::OutgoingProgress to State::OutgoingRinging 2022-05-12 17:38:44.171 11944-11944/com.gemvary.manager D/MyLinphone: callStateChanged, callId: outdoor@192.192.255.103, state: OutgoingRinging, message: Remote ringing 2022-05-12 17:38:44.171 11944-11944/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.logBoth(MyLinphone.java:820) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:843) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) callStateChanged, callId: outdoor@192.192.255.103, state: OutgoingRinging, message: Remote ringing 2022-05-12 17:38:44.172 11944-11944/com.gemvary.manager D/CallManager: Thread: main ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:785) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:775) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:769) ├ com.gemvary.call.sdk.base.CallManager.outgoingRinging(CallManager.java:865) └ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:956)

callId: outdoor@192.192.255.103, state: OutgoingRinging, reason: null, ExtraStatus{targetPending=false, transferType=UNKNOWN, cloudVoipNotConnected=false, unitNo='null', roomNo='null', preparationError=null, hangupReason=null}

2022-05-12 17:38:44.172 11944-11944/com.gemvary.manager D/OutgoingActivity: Thread: main ├ com.gemvary.manager.activity.OutgoingActivity.ringing(OutgoingActivity.java:405) ├ com.gemvary.manager.activity.OutgoingActivity.lambda$onCreate$6$OutgoingActivity(OutgoingActivity.java:230) ├ com.gemvary.manager.activity.-$$Lambda$OutgoingActivity$wsNkDazuaV1TG9LxuYSo38yj7TI.onCallStateChanged(lambda) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:788) └ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:775)

to play ringing

2022-05-12 17:38:44.173 11944-11944/com.gemvary.manager I/tag_linphone: Linphone core [0x93ff0d80] notified [call_state_changed] 2022-05-12 17:38:44.173 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: ending recv background task with id=[100b16]. 2022-05-12 17:38:44.173 11944-11944/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x100b16] 2022-05-12 17:38:44.175 227-812/? E/RockFFPlayer: doGetTrackInfo: track size = 1 2022-05-12 17:38:44.221 227-812/? D/RockFFPlayer: reset: in 2022-05-12 17:38:44.221 227-812/? D/RockFFPlayerBase: dumpStatus(): Started 2022-05-12 17:38:44.221 227-812/? D/RockFFPlayer: reset: stop_l in 2022-05-12 17:38:44.221 227-812/? D/RockFFPlayer: stop_l: in

2022-05-12 17:38:51.961 227-12285/? E/RockFFPlayer: file_read: offset=33657, c->offset=5940, c->length=27717 2022-05-12 17:38:51.961 227-12285/? E/RockFFPlayer: file_read: offset=33657, c->offset=5940, c->length=27717 2022-05-12 17:38:51.962 227-12285/? E/RockFFPlayer: file_read: offset=33657, c->offset=5940, c->length=27717 2022-05-12 17:38:51.962 227-12285/? D/RockFFPlayer: url_feof=1,err=0 2022-05-12 17:38:51.962 227-12285/? I/RockFFPlayer: av_read_frame end of stream ret = -541478725 2022-05-12 17:38:52.594 11944-12102/com.gemvary.manager I/tag_linphone: First estimation 2022-05-12 17:38:52.608 11944-11944/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x100b52] 2022-05-12 17:38:52.608 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: starting recv background task with id=[100b52]. 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: received [1071] new bytes from [UDP://192.192.255.103:5060]: 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: SIP/2.0 200 OK 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: Via: SIP/2.0/UDP 192.192.255.42:5060;rport=5060;received=192.192.255.42;branch=z9hG4bK.7muWKbfcZ 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: Call-ID: 7mAEt-n6Vy 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: From: "{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42;tag=K5XtRxNxO 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: To: sip:outdoor@192.192.255.103;tag=nPS-AK8SZlI9iPui10Pnd1Sq7eNstEqr 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: CSeq: 20 INVITE 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: Contact: 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: Supported: replaces, 100rel, timer, norefersub 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: Content-Type: application/sdp 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: Content-Length: 283 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: v=0 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: o=- 3861365923 3861365924 IN IP4 192.192.255.103 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: s=pjmedia 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: b=AS:84 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: t=0 0 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: a=X-nat:0 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: m=audio 4032 RTP/AVP 0 100 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: c=IN IP4 192.192.255.103 2022-05-12 17:38:52.609 11944-11944/com.gemvary.manager I/tag_linphone: b=TIAS:64000 2022-05-12 17:38:52.610 11944-11944/com.gemvary.manager I/tag_linphone: a=rtcp:4033 IN IP4 192.192.255.103 2022-05-12 17:38:52.610 11944-11944/com.gemvary.manager I/tag_linphone: a=sendrecv 2022-05-12 17:38:52.610 11944-11944/com.gemvary.manager I/tag_linphone: a=rtpmap:0 PCMU/8000 2022-05-12 17:38:52.610 11944-11944/com.gemvary.manager I/tag_linphone: a=rtpmap:100 telephone-event/8000 2022-05-12 17:38:52.610 11944-11944/com.gemvary.manager I/tag_linphone: a=fmtp:100 0-16 2022-05-12 17:38:52.631 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840] [788] bytes parsed 2022-05-12 17:38:52.632 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840] read [283] bytes of body from [192.192.255.103:5060] 2022-05-12 17:38:52.632 11944-11944/com.gemvary.manager I/tag_linphone: Found transaction matching response. 2022-05-12 17:38:52.632 11944-11944/com.gemvary.manager I/tag_linphone: Changing [client] [INVITE] transaction [0x8ed11f70], from state [PROCEEDING] to [ACCEPTED] 2022-05-12 17:38:52.632 11944-11944/com.gemvary.manager I/tag_linphone: Dialog [0x8e82f1e0]: now updated by transaction [0x8ed11f70]. 2022-05-12 17:38:52.633 11944-11944/com.gemvary.manager I/tag_linphone: op [0x92c70580] : set_or_update_dialog() current=[0x8e82f1e0] new=[0x8e82f1e0] 2022-05-12 17:38:52.633 11944-11944/com.gemvary.manager I/tag_linphone: Op [0x92c70580] receiving call response [200], dialog is [0x8e82f1e0] in state [BELLE_SIP_DIALOG_CONFIRMED] 2022-05-12 17:38:52.635 11944-11944/com.gemvary.manager I/tag_linphone: Found payload PCMU/8000 fmtp= 2022-05-12 17:38:52.635 11944-11944/com.gemvary.manager I/tag_linphone: Found payload telephone-event/8000 fmtp=0-16 2022-05-12 17:38:52.636 11944-11944/com.gemvary.manager I/tag_linphone: Doing SDP offer/answer process of type outgoing 2022-05-12 17:38:52.636 11944-11944/com.gemvary.manager I/tag_linphone: Processing for stream 0 2022-05-12 17:38:52.636 11944-11944/com.gemvary.manager I/tag_linphone: Adding opus/48000 for compatibility, just in case. 2022-05-12 17:38:52.636 11944-11944/com.gemvary.manager I/tag_linphone: Adding speex/16000 for compatibility, just in case. 2022-05-12 17:38:52.636 11944-11944/com.gemvary.manager I/tag_linphone: Adding speex/8000 for compatibility, just in case. 2022-05-12 17:38:52.636 11944-11944/com.gemvary.manager I/tag_linphone: Adding PCMA/8000 for compatibility, just in case. 2022-05-12 17:38:52.636 11944-11944/com.gemvary.manager I/tag_linphone: Adding G729/8000 for compatibility, just in case. 2022-05-12 17:38:52.636 11944-11944/com.gemvary.manager I/tag_linphone: Adding telephone-event/48000 for compatibility, just in case. 2022-05-12 17:38:52.636 11944-11944/com.gemvary.manager I/tag_linphone: Adding telephone-event/16000 for compatibility, just in case. 2022-05-12 17:38:52.637 11944-11944/com.gemvary.manager I/tag_linphone: CallSession [0x8ed3d78c] moving from state State::OutgoingRinging to State::Connected 2022-05-12 17:38:52.638 11944-11944/com.gemvary.manager D/MyLinphone: callStateChanged, callId: outdoor@192.192.255.103, state: Connected, message: Connected 2022-05-12 17:38:52.638 11944-11944/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.logBoth(MyLinphone.java:820) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:843) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) callStateChanged, callId: outdoor@192.192.255.103, state: Connected, message: Connected 2022-05-12 17:38:52.639 11944-11944/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:859) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) ├ org.linphone.core.CoreImpl.iterate(Core.java:4591) └ org.linphone.LinphoneManager$2$1.run(LinphoneManager.java:876) call state connected, isCallOut = true 2022-05-12 17:38:52.640 11944-11944/com.gemvary.manager D/CallManager: Thread: main ├ com.gemvary.call.sdk.base.CallManager.connected(CallManager.java:875) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:862) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) call connected 2022-05-12 17:38:52.640 11944-11944/com.gemvary.manager I/MyZGCall: zg_info ZGEngine is success 2022-05-12 17:38:52.641 11944-11944/com.gemvary.manager D/CallManager: Thread: main ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:785) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:775) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:769) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:764) └ com.gemvary.call.sdk.base.CallManager.connected(CallManager.java:889)

callId: outdoor@192.192.255.103, state: Connected, reason: None, ExtraStatus{targetPending=false, transferType=UNKNOWN, cloudVoipNotConnected=false, unitNo='null', roomNo='null', preparationError=null, hangupReason=null}

2022-05-12 17:38:52.642 227-227/? D/RockFFPlayer: stop 2022-05-12 17:38:52.642 227-227/? D/RockFFPlayerBase: dumpStatus(): Started 2022-05-12 17:38:52.642 227-227/? D/RockFFPlayer: stop_l: in 2022-05-12 17:38:52.642 227-227/? D/RockFFPlayer: enter enqueueMessage 2022-05-12 17:38:52.642 227-227/? D/RockFFPlayer: enqueueMessage:1 2022-05-12 17:38:52.642 227-227/? D/RockFFPlayer: enqueueMessage:2 2022-05-12 17:38:52.642 227-227/? D/RockFFPlayer: enqueueMessage:3 2022-05-12 17:38:52.642 227-227/? D/RockFFPlayer: enqueueMessage:Message 1,set signal 2022-05-12 17:38:52.642 227-227/? D/RockFFPlayer: enqueueMessage:sync wait 2022-05-12 17:38:52.642 227-12284/? D/RockFFPlayer: deliverMessage 2022-05-12 17:38:52.642 227-12284/? D/RockFFPlayer: run : dequeueMessage Message 4 2022-05-12 17:38:52.642 227-12284/? D/RockFFPlayer: CMD_STOP 2022-05-12 17:38:52.642 227-12284/? D/RockFFPlayer: doStop: in 2022-05-12 17:38:52.642 227-12284/? D/RockFFPlayerBase: dumpStatus(): Started 2022-05-12 17:38:52.642 227-12284/? D/RockFFPlayerBase: dumpStatus(): Stopped 2022-05-12 17:38:52.642 227-12284/? D/RockFFPlayer: doRelease: in 2022-05-12 17:38:52.642 227-12284/? I/FFIDecoder: waiting on end of decoder thread 2022-05-12 17:38:52.642 227-12288/? I/AudioDecoder: decoding audio ended 2022-05-12 17:38:52.642 227-12284/? E/FFMpegThread: DecoderAudio,wait 2022-05-12 17:38:52.642 227-12288/? D/FFMpegThread: DecoderAudio:thread ended 2022-05-12 17:38:52.642 227-12284/? D/FFMpegThread: DecoderAudio:wait at pthread_join 2022-05-12 17:38:52.642 227-12284/? E/TraceThread: DecoderAudio(tid = 2753562912)--> [UID=5ba18de2] pthread_join begin 2022-05-12 17:38:52.643 227-12285/? I/RockFFPlayer: readthread end of playing 2022-05-12 17:38:52.643 227-12284/? E/TraceThread: DecoderAudio(tid = 2753562912)--> [UID=5ba18de2] pthread_join end 2022-05-12 17:38:52.643 227-12284/? E/FFMpegThread: DecoderAudio:wait ok mThreadStatus=-1 2022-05-12 17:38:52.643 227-12284/? E/RockFFPlayerBase: FFMPlayerBase::flushRenderQueue in 2022-05-12 17:38:52.643 227-12284/? E/RockFFPlayerBase: FFMPlayerBase::flushRenderQueue out 2022-05-12 17:38:52.643 227-12284/? D/RockFFPlayer: doRelease: ok 2022-05-12 17:38:52.643 227-12284/? D/RockFFPlayer: doStop: ok 2022-05-12 17:38:52.643 227-12284/? D/RockFFPlayer: run : dequeueMessage Message 4 OK 2022-05-12 17:38:52.643 227-12284/? D/RockFFPlayer: Messagecomplete type [4] 2022-05-12 17:38:52.644 227-227/? D/RockFFPlayer: enqueueMessage:sync wait ok 2022-05-12 17:38:52.644 227-227/? D/RockFFPlayer: stop_l: clear queue ok 2022-05-12 17:38:52.644 227-12284/? D/RockFFPlayer: run : Message 4 complete 2022-05-12 17:38:52.644 227-12289/? D/RockFFPlayer: CancelThread: in 2022-05-12 17:38:52.644 227-227/? D/RockFFPlayer: stop_l: create cancel thread ok 2022-05-12 17:38:52.644 227-227/? D/RockFFPlayer: stop_l: ok 2022-05-12 17:38:52.644 227-12289/? D/RockFFPlayer: processAsyncExit: wait message thread 2022-05-12 17:38:52.644 227-227/? D/RockFFPlayer: reset: in 2022-05-12 17:38:52.644 227-227/? D/RockFFPlayerBase: dumpStatus(): Stopped 2022-05-12 17:38:52.644 227-227/? D/RockFFPlayer: enter enqueueMessage 2022-05-12 17:38:52.644 227-227/? D/RockFFPlayer: enqueueMessage:1 2022-05-12 17:38:52.644 227-227/? D/RockFFPlayer: enqueueMessage:2 2022-05-12 17:38:52.644 227-227/? D/RockFFPlayer: enqueueMessage:3 2022-05-12 17:38:52.644 227-227/? D/RockFFPlayer: enqueueMessage:Message 1,set signal 2022-05-12 17:38:52.644 227-227/? D/RockFFPlayer: enqueueMessage:sync wait 2022-05-12 17:38:52.645 227-12284/? D/RockFFPlayer: deliverMessage 2022-05-12 17:38:52.645 227-12284/? D/RockFFPlayer: run : dequeueMessage Message 11 2022-05-12 17:38:52.645 227-12284/? D/RockFFPlayer: CMD_RESET 2022-05-12 17:38:52.645 227-12284/? D/RockFFPlayerBase: dumpStatus(): Idle 2022-05-12 17:38:52.645 227-12284/? D/RockFFPlayer: doReset ok 2022-05-12 17:38:52.645 227-12284/? D/RockFFPlayer: run : dequeueMessage Message 11 OK 2022-05-12 17:38:52.645 227-12284/? D/RockFFPlayer: Messagecomplete type [11] 2022-05-12 17:38:52.645 227-12284/? D/RockFFPlayer: run : Message 11 complete 2022-05-12 17:38:52.645 227-227/? D/RockFFPlayer: enqueueMessage:sync wait ok 2022-05-12 17:38:52.645 227-227/? D/RockFFPlayer: reset: do reset ok 2022-05-12 17:38:52.646 227-12284/? D/RockFFPlayer: signal thread out 2022-05-12 17:38:52.646 227-227/? D/RockFFPlayer: stop:wait is ok 2022-05-12 17:38:52.646 227-12284/? D/RockFFPlayer: sched_thread out 2022-05-12 17:38:52.647 227-227/? D/RockFFPlayer: stop:ok 2022-05-12 17:38:52.648 227-227/? D/RockFFPlayer: ~RkMessageQueue 2022-05-12 17:38:52.648 227-227/? D/RockFFPlayer: reset: reset ok 2022-05-12 17:38:52.648 227-812/? D/RockFFPlayer: reset: in 2022-05-12 17:38:52.648 227-812/? D/RockFFPlayerBase: dumpStatus(): Idle 2022-05-12 17:38:52.648 227-812/? D/RockFFPlayer: reset: in idle status, already reset 2022-05-12 17:38:52.648 227-812/? E/FFPlayer: ~FFPlayer 2022-05-12 17:38:52.648 227-812/? D/RockFFPlayer: reset: in 2022-05-12 17:38:52.648 227-812/? D/RockFFPlayerBase: dumpStatus(): Idle 2022-05-12 17:38:52.648 227-812/? D/RockFFPlayer: reset: in idle status, already reset 2022-05-12 17:38:52.648 227-12289/? D/FFMpegThread: DecoderAudio:~PlayerThread enter mThreadStatus=-1 2022-05-12 17:38:52.648 227-812/? D/RockFFPlayer: ~FFMPlayer in 2022-05-12 17:38:52.648 227-812/? D/RockFFPlayer: reset: in 2022-05-12 17:38:52.648 227-812/? D/RockFFPlayerBase: dumpStatus(): Idle 2022-05-12 17:38:52.648 227-812/? D/RockFFPlayer: reset: in idle status, already reset 2022-05-12 17:38:52.651 227-12289/? E/RockFFPlayerBase: closeContext : in 2022-05-12 17:38:52.651 227-12289/? E/RockFFPlayerBase: closeContext : out 2022-05-12 17:38:52.651 227-12289/? D/RockFFPlayer: CancelThread: ok 2022-05-12 17:38:52.652 227-812/? D/RockFFPlayer: ~FFMediaSource 2022-05-12 17:38:52.652 227-812/? D/RockFFPlayer: FFMediaSource context null. 2022-05-12 17:38:52.652 227-812/? E/TraceThread: VsyncReciver(tid = 2767210784)--> [UID=5ba18de2] pthread_join begin-->player = 0xa54bf800 2022-05-12 17:38:52.668 227-812/? E/TraceThread: VsyncReciver(tid = 2767210784)--> [UID=5ba18de2] pthread_join end-->player = 0xa54bf800 2022-05-12 17:38:52.668 227-812/? D/VsyncReciver: stop_l:vsyncThread down 2022-05-12 17:38:52.668 227-812/? E/TraceThread: RockFFPlayer(tid = 2770344224)--> [UID=5ba18de2] ~FFMPlayer Destruction; object=0xa54bf800 2022-05-12 17:38:52.668 227-812/? D/RockFFPlayer: ~FFMPlayer ok 2022-05-12 17:38:52.669 227-812/? D/RockFFPlayerBase: ~FFMPlayerBase(():mDequeueThread down 2022-05-12 17:38:52.669 227-812/? D/RockFFPlayerBase: ~RkMessageHandler 2022-05-12 17:38:52.670 11944-11944/com.gemvary.manager D/OutgoingActivity: Thread: main ├ com.gemvary.manager.activity.OutgoingActivity.stopRinging(OutgoingActivity.java:411) ├ com.gemvary.manager.activity.OutgoingActivity.lambda$onCreate$6$OutgoingActivity(OutgoingActivity.java:232) ├ com.gemvary.manager.activity.-$$Lambda$OutgoingActivity$wsNkDazuaV1TG9LxuYSo38yj7TI.onCallStateChanged(lambda) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:788) └ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:775)

is still singlePlaying? false

2022-05-12 17:38:52.672 11944-11944/com.gemvary.manager I/OutgoingActivity: Thread: main ├ com.gemvary.manager.activity.OutgoingActivity.setStreamVolume(OutgoingActivity.java:432) ├ com.gemvary.manager.activity.OutgoingActivity.lambda$onCreate$6$OutgoingActivity(OutgoingActivity.java:235) ├ com.gemvary.manager.activity.-$$Lambda$OutgoingActivity$wsNkDazuaV1TG9LxuYSo38yj7TI.onCallStateChanged(lambda) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:788) └ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:775) 4 2022-05-12 17:38:52.674 11944-12102/com.gemvary.manager I/tag_linphone: First estimation 2022-05-12 17:38:52.675 11944-11944/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:865) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) ├ org.linphone.core.CoreImpl.iterate(Core.java:4591) └ org.linphone.LinphoneManager$2$1.run(LinphoneManager.java:876) getCallAudioSwitch = false 2022-05-12 17:38:52.675 11944-11944/com.gemvary.manager D/MyLinphone: beingConnectedThenCancelOtherCalls 2022-05-12 17:38:52.676 11944-11944/com.gemvary.manager D/MyLinphone: number of calls in total: 1 2022-05-12 17:38:52.676 11944-11944/com.gemvary.manager D/MyLinphone: no other calls 2022-05-12 17:38:52.791 216-303/? D/AudioHardwareTiny: out_set_parameters: kvpairs = routing=2 2022-05-12 17:38:52.795 11944-11944/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:888) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) ├ org.linphone.core.CoreImpl.iterate(Core.java:4591) └ org.linphone.LinphoneManager$2$1.run(LinphoneManager.java:876) when connect,enableMic true 2022-05-12 17:38:52.804 11944-11944/com.gemvary.manager I/tag_linphone: This device (rockchip/GRG-S5/rk3288) says it has no built-in echo canceller. 2022-05-12 17:38:52.804 11944-11944/com.gemvary.manager I/tag_linphone: No information available for [rockchip/GRG-S5/rk3288], 2022-05-12 17:38:52.804 11944-11944/com.gemvary.manager I/tag_linphone: Sound device information for [rockchip/GRG-S5/rk3288] is: builtin=[no], delay=[250] ms 2022-05-12 17:38:52.805 11944-11944/com.gemvary.manager W/tag_linphone: Could not apply gain on sent RTP packets: gain control wasn't activated. Use audio_stream_enable_gain_control() before starting the stream. 2022-05-12 17:38:52.806 11944-11944/com.gemvary.manager I/tag_linphone: Linphone core [0x93ff0d80] notified [call_state_changed] 2022-05-12 17:38:52.806 11944-11944/com.gemvary.manager I/tag_linphone: startStreams() CallSession=[0x8ed3d78c] local upload_bandwidth=[0] kbit/s; local download_bandwidth=[0] kbit/s 2022-05-12 17:38:52.806 11944-11944/com.gemvary.manager I/tag_linphone: Audio bandwidth for CallSession [0x8ed3d78c] is 80 2022-05-12 17:38:52.806 11944-11944/com.gemvary.manager I/tag_linphone: RtpSession [0x8fb06800] sending to rtp 192.192.255.103:4032 rtcp 192.192.255.103:4033 2022-05-12 17:38:52.807 11944-11944/com.gemvary.manager I/tag_linphone: Stun packet sent for session [0x8fb06800] 2022-05-12 17:38:52.807 11944-11944/com.gemvary.manager I/tag_linphone: Stun packet sent on rtcp for session [0x8fb06800] 2022-05-12 17:38:52.807 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSRtpRecv:0x93fe2b68,0-->MSVoidSink:0x93fe2ba0,0 2022-05-12 17:38:52.807 11944-11944/com.gemvary.manager I/tag_linphone: fqztemp mode [0] 2022-05-12 17:38:52.808 11944-11944/com.gemvary.manager I/tag_linphone: fqztemp use_ng=1 2022-05-12 17:38:52.808 11944-11944/com.gemvary.manager I/tag_linphone: fqztemp 63 audiostream.c use_ng=1 2022-05-12 17:38:52.808 11944-11944/com.gemvary.manager I/tag_linphone: speex_lib_ctl init with neon ? 1 2022-05-12 17:38:52.808 11944-11944/com.gemvary.manager I/tag_linphone: speex_lib_ctl init with neon ? 1 2022-05-12 17:38:52.808 11944-11944/com.gemvary.manager I/tag_linphone: Setting echo canceller delay with value provided by soundcard: 250 ms 2022-05-12 17:38:52.808 11944-11944/com.gemvary.manager I/tag_linphone: target bitrate not set for stream [0x8ed8fc00] using payload's bitrate is 80000 2022-05-12 17:38:52.809 11944-11944/com.gemvary.manager I/tag_linphone: Setting audio encoder network bitrate to [80000] on stream [0x8ed8fc00] 2022-05-12 17:38:52.816 11944-11944/com.gemvary.manager I/tag_linphone: This device (rockchip/GRG-S5/rk3288) says it has no built-in echo canceller. 2022-05-12 17:38:52.817 11944-11944/com.gemvary.manager I/tag_linphone: No information available for [rockchip/GRG-S5/rk3288], 2022-05-12 17:38:52.817 11944-11944/com.gemvary.manager I/tag_linphone: Sound device information for [rockchip/GRG-S5/rk3288] is: builtin=[no], delay=[250] ms 2022-05-12 17:38:52.824 11944-11944/com.gemvary.manager I/tag_linphone: This device (rockchip/GRG-S5/rk3288) says it has no built-in echo canceller. 2022-05-12 17:38:52.824 11944-11944/com.gemvary.manager I/tag_linphone: No information available for [rockchip/GRG-S5/rk3288], 2022-05-12 17:38:52.824 11944-11944/com.gemvary.manager I/tag_linphone: Sound device information for [rockchip/GRG-S5/rk3288] is: builtin=[no], delay=[250] ms 2022-05-12 17:38:52.825 11944-11944/com.gemvary.manager I/tag_linphone: MSVolume set gain to [0.000000 db], [1.000000] linear 2022-05-12 17:38:52.832 11944-11944/com.gemvary.manager I/tag_linphone: This device (rockchip/GRG-S5/rk3288) says it has no built-in echo canceller. 2022-05-12 17:38:52.832 11944-11944/com.gemvary.manager I/tag_linphone: No information available for [rockchip/GRG-S5/rk3288], 2022-05-12 17:38:52.832 11944-11944/com.gemvary.manager I/tag_linphone: Sound device information for [rockchip/GRG-S5/rk3288] is: builtin=[no], delay=[250] ms 2022-05-12 17:38:52.832 11944-11944/com.gemvary.manager I/tag_linphone: MSVolume set gain to [0.000000 db], [1.000000] linear 2022-05-12 17:38:52.833 11944-11944/com.gemvary.manager I/tag_linphone: configuring MSOpenSLESRecorder:0x93fe2b68-->MSUlawEnc:0x93fe2cf0 from rate [44100] to rate [8000] and from channel [1] to channel [1] 2022-05-12 17:38:52.833 11944-11944/com.gemvary.manager I/tag_linphone: configuring MSUlawDec:0x93fe2d28-->MSOpenSLESPlayer:0x93fe2ba0 from rate [8000] to rate [44100] and from channel [1] to channel [1] 2022-05-12 17:38:52.833 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSOpenSLESRecorder:0x93fe2b68,0-->MSResample:0x93fe2dd0,0 2022-05-12 17:38:52.833 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSResample:0x93fe2dd0,0-->MSEqualizer:0x93fe2fc8,0 2022-05-12 17:38:52.833 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSEqualizer:0x93fe2fc8,0-->MSWebRTCAECM:0x93fe27e8,1 2022-05-12 17:38:52.833 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSWebRTCAECM:0x93fe27e8,1-->MSVolume:0x93fe2d60,0 2022-05-12 17:38:52.834 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSVolume:0x93fe2d60,0-->MSAudioMixer:0x93fe2f90,0 2022-05-12 17:38:52.834 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSAudioMixer:0x93fe2f90,0-->MSUlawEnc:0x93fe2cf0,0 2022-05-12 17:38:52.834 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSUlawEnc:0x93fe2cf0,0-->MSRtpSend:0x93fe2778,0 2022-05-12 17:38:52.834 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSRtpRecv:0x93fe2c80,0-->MSUlawDec:0x93fe2d28,0 2022-05-12 17:38:52.834 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSUlawDec:0x93fe2d28,0-->MSGenericPLC:0x97ddf2f8,0 2022-05-12 17:38:52.834 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSGenericPLC:0x97ddf2f8,0-->MSAudioFlowControl:0x97ddf368,0 2022-05-12 17:38:52.834 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSAudioFlowControl:0x97ddf368,0-->MSDtmfGen:0x93fe2cb8,0 2022-05-12 17:38:52.835 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSDtmfGen:0x93fe2cb8,0-->MSVolume:0x93fe2d98,0 2022-05-12 17:38:52.835 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSVolume:0x93fe2d98,0-->MSEqualizer:0x97ddf2c0,0 2022-05-12 17:38:52.835 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSEqualizer:0x97ddf2c0,0-->MSAudioMixer:0x97ddf3a0,0 2022-05-12 17:38:52.835 11944-11944/com.gemvary.manager I/tag_linphone: speex_lib_ctl init with neon ? 1 2022-05-12 17:38:52.836 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSFilePlayer:0x97ddf678,0-->MSResample:0x97ddf6e8,0 2022-05-12 17:38:52.836 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSResample:0x97ddf6e8,0-->MSAudioMixer:0x97ddf3a0,1 2022-05-12 17:38:52.836 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSAudioMixer:0x97ddf3a0,0-->MSWebRTCAECM:0x93fe27e8,0 2022-05-12 17:38:52.836 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSWebRTCAECM:0x93fe27e8,0-->MSResample:0x93fe2f58,0 2022-05-12 17:38:52.836 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_link: MSResample:0x93fe2f58,0-->MSOpenSLESPlayer:0x93fe2ba0,0 2022-05-12 17:38:52.837 11944-11944/com.gemvary.manager W/libOpenSLES: Conversion from OpenSL ES positional channel mask 0x4 to Android mask 0 loses channels 2022-05-12 17:38:52.837 11944-11944/com.gemvary.manager I/libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x4, using default mask 0x10 based on channel count of 1) 2022-05-12 17:38:52.839 216-6049/? E/AudioHardwareTiny: adev_open_input_stream:channel is not support 2022-05-12 17:38:52.839 216-6049/? D/AudioHardwareTiny: in->mSpeexFrameSize:128 2022-05-12 17:38:52.842 216-12292/? I/AudioFlinger: AudioFlinger's thread 0xb1903300 ready to run 2022-05-12 17:38:52.847 216-6049/? W/AudioFlinger: createRecordTrack_l(): mismatch between requested flags (00000005) and input flags (00000001) 2022-05-12 17:38:52.848 11944-11944/com.gemvary.manager W/AudioRecord: AUDIO_INPUT_FLAG_FAST denied by server; frameCount 0 2022-05-12 17:38:52.848 216-621/? E/AudioHardwareTiny: adev_open_input_stream:channel is not support 2022-05-12 17:38:52.848 216-621/? D/AudioHardwareTiny: in->mSpeexFrameSize:512 2022-05-12 17:38:52.853 216-12293/? I/AudioFlinger: AudioFlinger's thread 0xb16838c0 ready to run 2022-05-12 17:38:52.862 11944-11944/com.gemvary.manager I/tag_linphone: Initializing speex resampler in mode [voip] 2022-05-12 17:38:52.862 216-12293/? D/AudioHardwareTiny: Device : 0x0 2022-05-12 17:38:52.862 216-12293/? D/AudioHardwareTiny: SampleRate : 0 2022-05-12 17:38:52.862 216-12293/? D/AudioHardwareTiny: Channels : 4 2022-05-12 17:38:52.862 216-12293/? D/AudioHardwareTiny: Formate : 0 2022-05-12 17:38:52.862 216-12293/? D/AudioHardwareTiny: PreiodSize : 0 2022-05-12 17:38:52.862 216-12293/? E/AudioHardwareTiny: getInputRouteFromDevice:device:80000004 2022-05-12 17:38:52.862 216-12293/? D/alsa_route: route_info->sound_card 0, route_info->devices 0
2022-05-12 17:38:52.862 11944-11944/com.gemvary.manager I/tag_linphone: Initializing WebRTC echo canceler with framesize=80, delay_ms=250, delay_samples=2000 2022-05-12 17:38:52.863 216-12293/? D/alsa_route: route_set_controls() set route 21 2022-05-12 17:38:52.864 11944-11944/com.gemvary.manager I/tag_linphone: ms_devices_info_get_model getdevicemodel= [GRG-S5] 2022-05-12 17:38:52.864 11944-11944/com.gemvary.manager I/tag_linphone: Initializing speex resampler in mode [voip] 2022-05-12 17:38:52.864 11944-11944/com.gemvary.manager I/tag_linphone: Initializing speex resampler in mode [voip] 2022-05-12 17:38:52.865 11944-11944/com.gemvary.manager I/libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x4, using default mask 0x1 based on channel count of 1) 2022-05-12 17:38:52.866 216-6049/? W/AudioFlinger: createTrack_l(): mismatch between requested flags (00000104) and output flags (00000006) 2022-05-12 17:38:52.868 11944-11944/com.gemvary.manager D/AudioTrack: Client defaulted notificationFrames to 512 for frameCount 1024 2022-05-12 17:38:52.869 11944-11944/com.gemvary.manager I/tag_linphone: Filter MSRtpRecv is already being scheduled; nothing to do. 2022-05-12 17:38:52.874 11944-12102/com.gemvary.manager I/tag_linphone: MSAudioMixer [0x97ddf3a0] is entering bypass mode. 2022-05-12 17:38:52.874 11944-11944/com.gemvary.manager I/tag_linphone: This device (rockchip/GRG-S5/rk3288) says it has no built-in echo canceller. 2022-05-12 17:38:52.874 11944-11944/com.gemvary.manager I/tag_linphone: No information available for [rockchip/GRG-S5/rk3288], 2022-05-12 17:38:52.874 11944-11944/com.gemvary.manager I/tag_linphone: Sound device information for [rockchip/GRG-S5/rk3288] is: builtin=[no], delay=[250] ms 2022-05-12 17:38:52.874 11944-11944/com.gemvary.manager I/tag_linphone: MSVolume set gain to [-2.000000 db], [0.630957] linear 2022-05-12 17:38:52.875 11944-11944/com.gemvary.manager I/tag_linphone: MSVolume set gain to [1.000000 db], [1.258925] linear 2022-05-12 17:38:52.875 11944-11944/com.gemvary.manager I/tag_linphone: No valid video stream defined 2022-05-12 17:38:52.875 11944-11944/com.gemvary.manager I/tag_linphone: CallSession[0x8ed3d78c] : payload type 0 PCMU/8000 fmtp= added to frozen list 2022-05-12 17:38:52.875 11944-12102/com.gemvary.manager W/tag_linphone: Getting reference signal but no echo to synchronize on. 2022-05-12 17:38:52.876 11944-12102/com.gemvary.manager I/tag_linphone: Stun packet sent for session [0x8fb06800] 2022-05-12 17:38:52.876 11944-11944/com.gemvary.manager I/tag_linphone: CallSession[0x8ed3d78c] : payload type 100 telephone-event/8000 fmtp= added to frozen list 2022-05-12 17:38:52.876 11944-12102/com.gemvary.manager I/tag_linphone: Stun packet sent on rtcp for session [0x8fb06800] 2022-05-12 17:38:52.876 11944-11944/com.gemvary.manager I/tag_linphone: CallSession[0x8ed3d78c] : payload type 96 opus/48000 fmtp=useinbandfec=1 added to frozen list 2022-05-12 17:38:52.876 11944-11944/com.gemvary.manager I/tag_linphone: CallSession[0x8ed3d78c] : payload type 97 speex/16000 fmtp=vbr=on added to frozen list 2022-05-12 17:38:52.876 11944-11944/com.gemvary.manager I/tag_linphone: CallSession[0x8ed3d78c] : payload type 98 speex/8000 fmtp=vbr=on added to frozen list 2022-05-12 17:38:52.876 11944-11944/com.gemvary.manager I/tag_linphone: CallSession[0x8ed3d78c] : payload type 8 PCMA/8000 fmtp= added to frozen list 2022-05-12 17:38:52.876 11944-11944/com.gemvary.manager I/tag_linphone: CallSession[0x8ed3d78c] : payload type 18 G729/8000 fmtp=annexb=yes added to frozen list 2022-05-12 17:38:52.877 11944-11944/com.gemvary.manager I/tag_linphone: CallSession[0x8ed3d78c] : payload type 101 telephone-event/48000 fmtp= added to frozen list 2022-05-12 17:38:52.877 11944-11944/com.gemvary.manager I/tag_linphone: CallSession[0x8ed3d78c] : payload type 99 telephone-event/16000 fmtp= added to frozen list 2022-05-12 17:38:52.877 11944-11944/com.gemvary.manager I/tag_linphone: audio stream index found: 0, updating main audio stream index 2022-05-12 17:38:52.877 11944-11944/com.gemvary.manager I/tag_linphone: CallSession [0x8ed3d78c] moving from state State::Connected to State::StreamsRunning 2022-05-12 17:38:52.878 11944-11944/com.gemvary.manager D/MyLinphone: callStateChanged, callId: outdoor@192.192.255.103, state: StreamsRunning, message: Streams running 2022-05-12 17:38:52.879 11944-11944/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.logBoth(MyLinphone.java:820) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:843) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) callStateChanged, callId: outdoor@192.192.255.103, state: StreamsRunning, message: Streams running 2022-05-12 17:38:52.879 11944-11944/com.gemvary.manager I/tag_linphone: Linphone core [0x93ff0d80] notified [call_state_changed] 2022-05-12 17:38:52.880 11944-11944/com.gemvary.manager I/tag_linphone: Computing branch id z9hG4bK.g7inmLKwv for message sent statelessly 2022-05-12 17:38:52.880 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: message sent to [UDP://192.192.255.103:5060], size: [625] bytes 2022-05-12 17:38:52.881 11944-11944/com.gemvary.manager I/tag_linphone: ACK sip:192.192.255.103:5060 SIP/2.0 2022-05-12 17:38:52.881 11944-11944/com.gemvary.manager I/tag_linphone: Via: SIP/2.0/UDP 192.192.255.42:5060;rport;branch=z9hG4bK.g7inmLKwv 2022-05-12 17:38:52.881 11944-11944/com.gemvary.manager I/tag_linphone: From: "{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42;tag=K5XtRxNxO 2022-05-12 17:38:52.881 11944-11944/com.gemvary.manager I/tag_linphone: To: sip:outdoor@192.192.255.103;tag=nPS-AK8SZlI9iPui10Pnd1Sq7eNstEqr 2022-05-12 17:38:52.881 11944-11944/com.gemvary.manager I/tag_linphone: CSeq: 20 ACK 2022-05-12 17:38:52.881 11944-11944/com.gemvary.manager I/tag_linphone: Call-ID: 7mAEt-n6Vy 2022-05-12 17:38:52.881 11944-11944/com.gemvary.manager I/tag_linphone: Max-Forwards: 70 2022-05-12 17:38:52.881 11944-11944/com.gemvary.manager I/tag_linphone: User-Agent: LinphoneAndroid/6.1.20220512170321 (belle-sip/1.6.3) 2022-05-12 17:38:52.881 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: ending recv background task with id=[100b52]. 2022-05-12 17:38:52.882 11944-11944/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x100b52] 2022-05-12 17:38:52.882 11944-11944/com.gemvary.manager I/tag_linphone: Garbage collecting unowned object of type belle_sip_hop_t 2022-05-12 17:38:52.882 11944-11944/com.gemvary.manager I/tag_linphone: Garbage collecting unowned object of type belle_sdp_session_description_t 2022-05-12 17:38:52.882 11944-11944/com.gemvary.manager I/tag_linphone: MSAudio stream [0x8ed8fc00]: receiving RTCP RR 2022-05-12 17:38:52.883 11944-11944/com.gemvary.manager I/tag_linphone: MSAudio stream [0x8ed8fc00]: receiving RTCP RR 2022-05-12 17:38:52.884 11944-11944/com.gemvary.manager I/tag_linphone: Linphone core [0x93ff0d80] notified [call_stats_updated] 2022-05-12 17:38:52.884 11944-12102/com.gemvary.manager I/tag_linphone: MSAudioMixer [0x97ddf3a0] is leaving bypass mode. 2022-05-12 17:38:52.884 11944-12102/com.gemvary.manager W/tag_linphone: Getting reference signal but no echo to synchronize on. 2022-05-12 17:38:52.884 11944-11944/com.gemvary.manager I/tag_linphone: Linphone core [0x93ff0d80] notified [call_stats_updated] 2022-05-12 17:38:52.885 11944-12102/com.gemvary.manager I/tag_linphone: MSAudioMixer [0x93fe2f90] is entering bypass mode. 2022-05-12 17:38:52.895 11944-12102/com.gemvary.manager W/tag_linphone: Getting reference signal but no echo to synchronize on. 2022-05-12 17:38:52.895 11944-12102/com.gemvary.manager W/tag_linphone: Not enough ref samples, using zeroes 2022-05-12 17:38:52.922 11944-12102/com.gemvary.manager I/tag_linphone: Samples are back. 2022-05-12 17:38:53.111 11944-11944/com.gemvary.manager I/tag_linphone: MSAudio stream [0x8ed8fc00]: receiving RTCP SR 2022-05-12 17:38:53.111 11944-11944/com.gemvary.manager I/tag_linphone: Linphone core [0x93ff0d80] notified [call_stats_updated] 2022-05-12 17:38:53.453 11944-11944/com.gemvary.manager I/tag_linphone: Bandwidth usage for CallSession [0x8ed3d78c]: RTP audio=[d=79.07,u=55.46], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.76,u=3.14], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2022-05-12 17:38:53.453 11944-11944/com.gemvary.manager I/tag_linphone: Thread processing load: audio=10.5645 video=0 text=0 2022-05-12 17:38:53.872 11944-12102/com.gemvary.manager I/tag_linphone: MSAudioMixer [0x97ddf3a0] is entering bypass mode. 2022-05-12 17:38:54.030 11944-12290/com.gemvary.manager I/tag_linphone: sound/wall clock skew is average=-3.403503 ms 2022-05-12 17:38:54.051 11944-12102/com.gemvary.manager I/tag_linphone: sound/wall clock skew is average=-3.4035 ms 2022-05-12 17:38:54.459 11944-11944/com.gemvary.manager I/tag_linphone: Bandwidth usage for CallSession [0x8ed3d78c]: RTP audio=[d=77.94,u=79.96], video=[d=0.00,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 2022-05-12 17:38:54.459 11944-11944/com.gemvary.manager I/tag_linphone: Thread processing load: audio=16.0596 video=0 text=0 2022-05-12 17:38:54.545 11944-12102/com.gemvary.manager I/tag_linphone: Sending RTCP SR compound message on session [0x8fb06800]. 2022-05-12 17:38:54.561 11944-11944/com.gemvary.manager I/tag_linphone: MSAudio_stream_iterate[0x8ed8fc00], local statistics available: Local current jitter buffer size: 52.8ms 2022-05-12 17:38:54.562 11944-11944/com.gemvary.manager I/tag_linphone: Linphone core [0x93ff0d80] notified [call_stats_updated] 2022-05-12 17:38:55.190 11944-12290/com.gemvary.manager I/tag_linphone: sound/wall clock skew is average=-4.647869 ms 2022-05-12 17:38:55.450 11944-11944/com.gemvary.manager I/tag_linphone: Bandwidth usage for CallSession [0x8ed3d78c]: RTP audio=[d=72.69,u=81.42], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.37], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2022-05-12 17:38:55.450 11944-11944/com.gemvary.manager I/tag_linphone: Thread processing load: audio=10.4023 video=0 text=0 2022-05-12 17:38:56.351 11944-12290/com.gemvary.manager I/tag_linphone: sound/wall clock skew is average=-5.013655 ms 2022-05-12 17:38:56.461 11944-11944/com.gemvary.manager I/tag_linphone: Bandwidth usage for CallSession [0x8ed3d78c]: RTP audio=[d=76.90,u=79.92], video=[d=0.00,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 2022-05-12 17:38:56.461 11944-11944/com.gemvary.manager I/tag_linphone: Thread processing load: audio=10.0119 video=0 text=0 2022-05-12 17:38:57.051 11944-12102/com.gemvary.manager I/tag_linphone: Sending RTCP SR compound message on session [0x8fb06800]. 2022-05-12 17:38:57.066 11944-11944/com.gemvary.manager I/tag_linphone: MSAudio_stream_iterate[0x8ed8fc00], local statistics available: Local current jitter buffer size: 55.0ms 2022-05-12 17:38:57.066 11944-11944/com.gemvary.manager I/tag_linphone: Linphone core [0x93ff0d80] notified [call_stats_updated] 2022-05-12 17:38:57.449 11944-11944/com.gemvary.manager I/tag_linphone: Bandwidth usage for CallSession [0x8ed3d78c]: RTP audio=[d=82.31,u=78.48], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.38], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2022-05-12 17:38:57.449 11944-11944/com.gemvary.manager I/tag_linphone: Thread processing load: audio=15.1326 video=0 text=0 2022-05-12 17:38:57.512 11944-12290/com.gemvary.manager I/tag_linphone: sound/wall clock skew is average=-5.131918 ms 2022-05-12 17:38:57.611 11944-11944/com.gemvary.manager I/tag_linphone: MSAudio stream [0x8ed8fc00]: receiving RTCP SR 2022-05-12 17:38:57.611 11944-11944/com.gemvary.manager I/tag_linphone: MSQualityIndicator[0x90ccf2a0][audio], remote statistics available: Loss rate : 0.0% Inter-arrival jitter: 6.1ms RT propagation : 3.6ms 2022-05-12 17:38:57.612 11944-11944/com.gemvary.manager I/tag_linphone: Linphone core [0x93ff0d80] notified [call_stats_updated] 2022-05-12 17:38:57.682 11944-12102/com.gemvary.manager I/tag_linphone: jitter buffer stable: target-size: 59.999999 ms, effective-size: 55.040001 (min: 40 nom: 60, max: 500) 2022-05-12 17:38:57.682 11944-12102/com.gemvary.manager I/tag_linphone: jitter buffer rls stats: count=246, clockrate=8000, offset=4.02953 clock_ratio=0.999871, capped_offset=1924 capped_clock_ratio=0.999871, max_ts_deviation=79.000000 prev_max_ts_deviation=480.000000, deviation=79, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.011875 2022-05-12 17:38:58.460 11944-11944/com.gemvary.manager I/tag_linphone: Bandwidth usage for CallSession [0x8ed3d78c]: RTP audio=[d=77.61,u=79.97], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.05,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2022-05-12 17:38:58.461 11944-11944/com.gemvary.manager I/tag_linphone: Thread processing load: audio=15.4424 video=0 text=0 2022-05-12 17:38:58.673 11944-12290/com.gemvary.manager I/tag_linphone: sound/wall clock skew is average=-5.262506 ms 2022-05-12 17:38:59.051 11944-12102/com.gemvary.manager I/tag_linphone: sound/wall clock skew is average=-5.31692 ms 2022-05-12 17:38:59.444 11944-11944/com.gemvary.manager I/tag_linphone: Bandwidth usage for CallSession [0x8ed3d78c]: RTP audio=[d=84.17,u=79.97], video=[d=0.00,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 2022-05-12 17:38:59.444 11944-11944/com.gemvary.manager I/tag_linphone: Thread processing load: audio=14.6783 video=0 text=0 2022-05-12 17:38:59.689 11944-11944/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x200b3a] 2022-05-12 17:38:59.689 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: starting recv background task with id=[200b3a]. 2022-05-12 17:38:59.690 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: received [631] new bytes from [UDP://192.192.255.103:5060]: 2022-05-12 17:38:59.690 11944-11944/com.gemvary.manager I/tag_linphone: BYE sip:linphone.android@192.192.255.42;transport=udp SIP/2.0 2022-05-12 17:38:59.690 11944-11944/com.gemvary.manager I/tag_linphone: Via: SIP/2.0/UDP 192.192.255.103:5060;rport;branch=z9hG4bKPjjRqLxL7RwYjiafeeuoy0RNy6fih3gCq. 2022-05-12 17:38:59.690 11944-11944/com.gemvary.manager I/tag_linphone: Max-Forwards: 70 2022-05-12 17:38:59.690 11944-11944/com.gemvary.manager I/tag_linphone: From: sip:outdoor@192.192.255.103;tag=nPS-AK8SZlI9iPui10Pnd1Sq7eNstEqr 2022-05-12 17:38:59.690 11944-11944/com.gemvary.manager I/tag_linphone: To: "{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42;tag=K5XtRxNxO 2022-05-12 17:38:59.690 11944-11944/com.gemvary.manager I/tag_linphone: Call-ID: 7mAEt-n6Vy 2022-05-12 17:38:59.690 11944-11944/com.gemvary.manager I/tag_linphone: CSeq: 4684 BYE 2022-05-12 17:38:59.690 11944-11944/com.gemvary.manager I/tag_linphone: Content-Length: 0 2022-05-12 17:38:59.731 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840] [631] bytes parsed 2022-05-12 17:38:59.733 11944-11944/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x90610c20)] acquired [ref=0x200b42] 2022-05-12 17:38:59.733 11944-11944/com.gemvary.manager I/tag_linphone: transaction [0x90610c20]: starting transaction background task with id=[200b42]. 2022-05-12 17:38:59.733 11944-11944/com.gemvary.manager I/tag_linphone: Changing [server] [BYE] transaction [0x90610c20], from state [INIT] to [TRYING] 2022-05-12 17:38:59.733 11944-11944/com.gemvary.manager I/tag_linphone: Changing [server] [BYE] transaction [0x90610c20], from state [TRYING] to [COMPLETED] 2022-05-12 17:38:59.734 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: message sent to [UDP://192.192.255.103:5060], size: [649] bytes 2022-05-12 17:38:59.734 11944-11944/com.gemvary.manager I/tag_linphone: SIP/2.0 200 Ok 2022-05-12 17:38:59.734 11944-11944/com.gemvary.manager I/tag_linphone: Via: SIP/2.0/UDP 192.192.255.103:5060;rport;branch=z9hG4bKPjjRqLxL7RwYjiafeeuoy0RNy6fih3gCq. 2022-05-12 17:38:59.734 11944-11944/com.gemvary.manager I/tag_linphone: From: sip:outdoor@192.192.255.103;tag=nPS-AK8SZlI9iPui10Pnd1Sq7eNstEqr 2022-05-12 17:38:59.734 11944-11944/com.gemvary.manager I/tag_linphone: To: "{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42;tag=K5XtRxNxO 2022-05-12 17:38:59.734 11944-11944/com.gemvary.manager I/tag_linphone: Call-ID: 7mAEt-n6Vy 2022-05-12 17:38:59.734 11944-11944/com.gemvary.manager I/tag_linphone: CSeq: 4684 BYE 2022-05-12 17:38:59.734 11944-11944/com.gemvary.manager I/tag_linphone: User-Agent: LinphoneAndroid/6.1.20220512170321 (belle-sip/1.6.3) 2022-05-12 17:38:59.734 11944-11944/com.gemvary.manager I/tag_linphone: Supported: replaces, outbound, gruu 2022-05-12 17:38:59.734 11944-11944/com.gemvary.manager I/tag_linphone: Dialog [0x8e82f1e0]: now updated by transaction [0x90610c20]. 2022-05-12 17:38:59.734 11944-11944/com.gemvary.manager I/tag_linphone: Dialog [0x8e82f1e0] deleted (is_expired=0) 2022-05-12 17:38:59.735 11944-11944/com.gemvary.manager I/tag_linphone: Writing echo canceler state, 0 bytes 2022-05-12 17:38:59.735 11944-11944/com.gemvary.manager W/tag_linphone: .linphone.ecstate has not been created because there is no data to write 2022-05-12 17:38:59.742 216-12293/? D/alsa_route: route_set_controls() set route 25 2022-05-12 17:38:59.744 11944-11944/com.gemvary.manager I/tag_linphone: ms_ticker_set_time_func: ticker's time method updated. 2022-05-12 17:38:59.745 11944-11944/com.gemvary.manager I/tag_linphone: Filter MSRtpRecv is not scheduled; nothing to do. 2022-05-12 17:38:59.745 11944-11944/com.gemvary.manager I/tag_linphone: =========================================================== 2022-05-12 17:38:59.745 11944-11944/com.gemvary.manager I/tag_linphone: AUDIO SESSION'S RTP STATISTICS
2022-05-12 17:38:59.746 11944-11944/com.gemvary.manager I/tag_linphone: ----------------------------------------------------------- 2022-05-12 17:38:59.746 11944-11944/com.gemvary.manager I/tag_linphone: sent 342 packets 2022-05-12 17:38:59.746 11944-11944/com.gemvary.manager I/tag_linphone: 0 duplicated packets 2022-05-12 17:38:59.746 11944-11944/com.gemvary.manager I/tag_linphone: 58824 bytes
2022-05-12 17:38:59.747 11944-11944/com.gemvary.manager I/tag_linphone: received 352 packets 2022-05-12 17:38:59.747 11944-11944/com.gemvary.manager I/tag_linphone: 0 duplicated packets 2022-05-12 17:38:59.747 11944-11944/com.gemvary.manager I/tag_linphone: 60544 bytes
2022-05-12 17:38:59.747 11944-11944/com.gemvary.manager I/tag_linphone: incoming delivered to the app 58996 bytes
2022-05-12 17:38:59.747 11944-11944/com.gemvary.manager I/tag_linphone: incoming cumulative lost 1 packets 2022-05-12 17:38:59.747 11944-11944/com.gemvary.manager I/tag_linphone: incoming received too late 4 packets 2022-05-12 17:38:59.748 11944-11944/com.gemvary.manager I/tag_linphone: incoming bad formatted 0 packets 2022-05-12 17:38:59.748 11944-11944/com.gemvary.manager I/tag_linphone: incoming discarded (queue overflow) 0 packets 2022-05-12 17:38:59.748 11944-11944/com.gemvary.manager I/tag_linphone: sent rtcp 2 packets 2022-05-12 17:38:59.748 11944-11944/com.gemvary.manager I/tag_linphone: received rtcp 4 packets 2022-05-12 17:38:59.748 11944-11944/com.gemvary.manager I/tag_linphone: =========================================================== 2022-05-12 17:38:59.748 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSOpenSLESRecorder:0x93fe2b68,0-->MSResample:0x93fe2dd0,0 2022-05-12 17:38:59.748 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSResample:0x93fe2dd0,0-->MSEqualizer:0x93fe2fc8,0 2022-05-12 17:38:59.748 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSEqualizer:0x93fe2fc8,0-->MSWebRTCAECM:0x93fe27e8,1 2022-05-12 17:38:59.748 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSWebRTCAECM:0x93fe27e8,1-->MSVolume:0x93fe2d60,0 2022-05-12 17:38:59.749 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSVolume:0x93fe2d60,0-->MSAudioMixer:0x93fe2f90,0 2022-05-12 17:38:59.749 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSAudioMixer:0x93fe2f90,0-->MSUlawEnc:0x93fe2cf0,0 2022-05-12 17:38:59.749 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSUlawEnc:0x93fe2cf0,0-->MSRtpSend:0x93fe2778,0 2022-05-12 17:38:59.749 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSRtpRecv:0x93fe2c80,0-->MSUlawDec:0x93fe2d28,0 2022-05-12 17:38:59.749 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSUlawDec:0x93fe2d28,0-->MSGenericPLC:0x97ddf2f8,0 2022-05-12 17:38:59.749 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSGenericPLC:0x97ddf2f8,0-->MSAudioFlowControl:0x97ddf368,0 2022-05-12 17:38:59.749 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSAudioFlowControl:0x97ddf368,0-->MSDtmfGen:0x93fe2cb8,0 2022-05-12 17:38:59.749 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSDtmfGen:0x93fe2cb8,0-->MSVolume:0x93fe2d98,0 2022-05-12 17:38:59.749 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSVolume:0x93fe2d98,0-->MSEqualizer:0x97ddf2c0,0 2022-05-12 17:38:59.749 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSEqualizer:0x97ddf2c0,0-->MSAudioMixer:0x97ddf3a0,0 2022-05-12 17:38:59.750 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSFilePlayer:0x97ddf678,0-->MSResample:0x97ddf6e8,0 2022-05-12 17:38:59.750 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSResample:0x97ddf6e8,0-->MSAudioMixer:0x97ddf3a0,1 2022-05-12 17:38:59.750 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSAudioMixer:0x97ddf3a0,0-->MSWebRTCAECM:0x93fe27e8,0 2022-05-12 17:38:59.750 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSWebRTCAECM:0x93fe27e8,0-->MSResample:0x93fe2f58,0 2022-05-12 17:38:59.750 11944-11944/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSResample:0x93fe2f58,0-->MSOpenSLESPlayer:0x93fe2ba0,0 2022-05-12 17:38:59.750 11944-11944/com.gemvary.manager I/tag_linphone: =========================================================== 2022-05-12 17:38:59.750 11944-11944/com.gemvary.manager I/tag_linphone: FILTER USAGE STATISTICS
2022-05-12 17:38:59.750 11944-11944/com.gemvary.manager I/tag_linphone: Name Count Time/tick (ms) CPU Usage 2022-05-12 17:38:59.750 11944-11944/com.gemvary.manager I/tag_linphone: ----------------------------------------------------------- 2022-05-12 17:38:59.750 11944-11944/com.gemvary.manager I/tag_linphone: MSResample 590 0.806811 45.9672
2022-05-12 17:38:59.750 11944-11944/com.gemvary.manager I/tag_linphone: MSWebRTCAECM 511 0.611519 30.1834
2022-05-12 17:38:59.751 11944-11944/com.gemvary.manager I/tag_linphone: MSRtpRecv 1563 0.0798244 12.0354
2022-05-12 17:38:59.751 11944-11944/com.gemvary.manager I/tag_linphone: MSRtpSend 687 0.100946 6.69526
2022-05-12 17:38:59.751 11944-11944/com.gemvary.manager I/tag_linphone: MSAudioMixer 1374 0.00692661 0.918147
2022-05-12 17:38:59.751 11944-11944/com.gemvary.manager I/tag_linphone: MSVolume 643 0.0134774 0.836719
2022-05-12 17:38:59.751 11944-11944/com.gemvary.manager I/tag_linphone: MSGenericPLC 687 0.0114942 0.762349
2022-05-12 17:38:59.751 11944-11944/com.gemvary.manager I/tag_linphone: MSUlawEnc 295 0.0231066 0.659352
2022-05-12 17:38:59.751 11944-11944/com.gemvary.manager I/tag_linphone: MSUlawDec 339 0.0176467 0.578403
2022-05-12 17:38:59.751 11944-11944/com.gemvary.manager I/tag_linphone: MSOpenSLESRecorder 687 0.00840619 0.55754
2022-05-12 17:38:59.751 11944-11944/com.gemvary.manager I/tag_linphone: MSDtmfGen 687 0.0035424 0.23495
2022-05-12 17:38:59.751 11944-11944/com.gemvary.manager I/tag_linphone: MSFilePlayer 687 0.00260083 0.1725
2022-05-12 17:38:59.751 11944-11944/com.gemvary.manager I/tag_linphone: MSEqualizer 643 0.00253489 0.157374
2022-05-12 17:38:59.751 11944-11944/com.gemvary.manager I/tag_linphone: MSOpenSLESPlayer 295 0.00484695 0.138308
2022-05-12 17:38:59.751 11944-11944/com.gemvary.manager I/tag_linphone: MSAudioFlowControl 348 0.00303867 0.102235
2022-05-12 17:38:59.752 11944-11944/com.gemvary.manager I/tag_linphone: MSVoidSink 4 0.00175 0.000843523 2022-05-12 17:38:59.752 11944-11944/com.gemvary.manager I/tag_linphone: MSItcSink 0 0 0
2022-05-12 17:38:59.752 11944-11944/com.gemvary.manager I/tag_linphone: MSTee 0 0 0
2022-05-12 17:38:59.752 11944-11944/com.gemvary.manager I/tag_linphone: =========================================================== 2022-05-12 17:38:59.752 11944-11944/com.gemvary.manager I/tag_linphone: =========================================================== 2022-05-12 17:38:59.752 11944-11944/com.gemvary.manager I/tag_linphone: FILTER USAGE STATISTICS
2022-05-12 17:38:59.752 11944-11944/com.gemvary.manager I/tag_linphone: Name Count Time/tick (ms) CPU Usage 2022-05-12 17:38:59.752 11944-11944/com.gemvary.manager I/tag_linphone: ----------------------------------------------------------- 2022-05-12 17:38:59.752 11944-11944/com.gemvary.manager I/tag_linphone: MSResample 590 0.806811 45.9672
2022-05-12 17:38:59.753 11944-11944/com.gemvary.manager I/tag_linphone: MSWebRTCAECM 511 0.611519 30.1834
2022-05-12 17:38:59.753 11944-11944/com.gemvary.manager I/tag_linphone: MSRtpRecv 1563 0.0798244 12.0354
2022-05-12 17:38:59.753 11944-11944/com.gemvary.manager I/tag_linphone: MSRtpSend 687 0.100946 6.69526
2022-05-12 17:38:59.753 11944-11944/com.gemvary.manager I/tag_linphone: MSAudioMixer 1374 0.00692661 0.918147
2022-05-12 17:38:59.753 11944-11944/com.gemvary.manager I/tag_linphone: MSVolume 643 0.0134774 0.836719
2022-05-12 17:38:59.753 11944-11944/com.gemvary.manager I/tag_linphone: MSGenericPLC 687 0.0114942 0.762349
2022-05-12 17:38:59.753 11944-11944/com.gemvary.manager I/tag_linphone: MSUlawEnc 295 0.0231066 0.659352
2022-05-12 17:38:59.753 11944-11944/com.gemvary.manager I/tag_linphone: MSUlawDec 339 0.0176467 0.578403
2022-05-12 17:38:59.753 11944-11944/com.gemvary.manager I/tag_linphone: MSOpenSLESRecorder 687 0.00840619 0.55754
2022-05-12 17:38:59.753 11944-11944/com.gemvary.manager I/tag_linphone: MSDtmfGen 687 0.0035424 0.23495
2022-05-12 17:38:59.753 11944-11944/com.gemvary.manager I/tag_linphone: MSFilePlayer 687 0.00260083 0.1725
2022-05-12 17:38:59.754 11944-11944/com.gemvary.manager I/tag_linphone: MSEqualizer 643 0.00253489 0.157374
2022-05-12 17:38:59.754 11944-11944/com.gemvary.manager I/tag_linphone: MSOpenSLESPlayer 295 0.00484695 0.138308
2022-05-12 17:38:59.754 11944-11944/com.gemvary.manager I/tag_linphone: MSAudioFlowControl 348 0.00303867 0.102235
2022-05-12 17:38:59.754 11944-11944/com.gemvary.manager I/tag_linphone: MSVoidSink 4 0.00175 0.000843523 2022-05-12 17:38:59.754 11944-11944/com.gemvary.manager I/tag_linphone: MSItcSink 0 0 0
2022-05-12 17:38:59.754 11944-11944/com.gemvary.manager I/tag_linphone: MSTee 0 0 0
2022-05-12 17:38:59.754 11944-11944/com.gemvary.manager I/tag_linphone: =========================================================== 2022-05-12 17:38:59.754 11944-11944/com.gemvary.manager I/tag_linphone: CallSession [0x8ed3d78c] moving from state State::StreamsRunning to State::End 2022-05-12 17:38:59.754 11944-11944/com.gemvary.manager I/tag_linphone: Notifying soundcard that we don't need it anymore for calls 2022-05-12 17:38:59.755 11944-11944/com.gemvary.manager I/tag_linphone: Stopping ZRTP context on session [0x0] 2022-05-12 17:38:59.755 11944-11944/com.gemvary.manager I/tag_linphone: ZRTP context destroyed 2022-05-12 17:38:59.764 11944-12102/com.gemvary.manager I/tag_linphone: MSAudio MSTicker thread exiting 2022-05-12 17:38:59.765 11944-11944/com.gemvary.manager I/tag_linphone: Stopping ZRTP context on session [0x0] 2022-05-12 17:38:59.765 11944-11944/com.gemvary.manager I/tag_linphone: ZRTP context destroyed 2022-05-12 17:38:59.765 11944-11944/com.gemvary.manager I/tag_linphone: Linphone core [0x93ff0d80] notified [call_log_updated] 2022-05-12 17:38:59.765 11944-11944/com.gemvary.manager I/tag_linphone: Resetting the current call 2022-05-12 17:38:59.770 11944-11944/com.gemvary.manager D/MyLinphone: callStateChanged, callId: outdoor@192.192.255.103, state: End, message: Call ended 2022-05-12 17:38:59.770 11944-11944/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.logBoth(MyLinphone.java:820) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:843) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) callStateChanged, callId: outdoor@192.192.255.103, state: End, message: Call ended 2022-05-12 17:38:59.771 11944-11944/com.gemvary.manager D/MyLinphone: call state End, reason is: None 2022-05-12 17:38:59.771 11944-11944/com.gemvary.manager D/CallTarget: after removed, call id list is: [] 2022-05-12 17:38:59.772 11944-11944/com.gemvary.manager D/CallManager: Thread: main ├ com.gemvary.call.sdk.base.CallManager.callEnd(CallManager.java:1063) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:941) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) call id list empty now, reset current call target 2022-05-12 17:38:59.772 11944-11944/com.gemvary.manager D/CallManager: Thread: main ├ com.gemvary.call.sdk.base.CallManager.callEnd(CallManager.java:1068) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:941) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) no call target pending 2022-05-12 17:38:59.773 11944-11944/com.gemvary.manager D/CallManager: Thread: main ├ com.gemvary.call.sdk.base.CallManager.callEnd(CallManager.java:1072) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:941) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) isAnswered? true, errorOccurred? false 2022-05-12 17:38:59.773 11944-11944/com.gemvary.manager D/CallManager: Thread: main ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:785) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:775) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:769) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:764) └ com.gemvary.call.sdk.base.CallManager.notifyCallEnd(CallManager.java:1016)

callId: outdoor@192.192.255.103, state: End, reason: None, ExtraStatus{targetPending=false, transferType=UNKNOWN, cloudVoipNotConnected=false, unitNo='null', roomNo='null', preparationError=null, hangupReason=null}

2022-05-12 17:38:59.774 11944-11944/com.gemvary.manager D/OutgoingActivity: Thread: main ├ com.gemvary.manager.activity.OutgoingActivity.lambda$onCreate$6$OutgoingActivity(OutgoingActivity.java:286) ├ com.gemvary.manager.activity.-$$Lambda$OutgoingActivity$wsNkDazuaV1TG9LxuYSo38yj7TI.onCallStateChanged(lambda) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:788) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:775) └ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:769) no call target pending, close the outgoing ui 2022-05-12 17:38:59.775 11944-11944/com.gemvary.manager D/OutgoingActivity: Thread: main ├ com.gemvary.manager.activity.OutgoingActivity.stopRinging(OutgoingActivity.java:411) ├ com.gemvary.manager.activity.OutgoingActivity.closeWithDelay(OutgoingActivity.java:424) ├ com.gemvary.manager.activity.OutgoingActivity.lambda$onCreate$6$OutgoingActivity(OutgoingActivity.java:287) ├ com.gemvary.manager.activity.-$$Lambda$OutgoingActivity$wsNkDazuaV1TG9LxuYSo38yj7TI.onCallStateChanged(lambda) └ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:788)

is still singlePlaying? false

2022-05-12 17:38:59.776 11944-11944/com.gemvary.manager I/tag_linphone: Linphone core [0x93ff0d80] notified [call_state_changed] 2022-05-12 17:38:59.776 11944-11944/com.gemvary.manager I/tag_linphone: channel [0x8e792840]: ending recv background task with id=[200b3a]. 2022-05-12 17:38:59.779 11944-11944/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x200b3a] 2022-05-12 17:38:59.779 11944-11944/com.gemvary.manager I/tag_linphone: Dialog [0x8e82f1e0] terminated for op [0x92c70580] 2022-05-12 17:38:59.779 11944-11944/com.gemvary.manager I/tag_linphone: CallSession [0x8ed3d78c] moving from state State::End to State::Released 2022-05-12 17:38:59.779 11944-11944/com.gemvary.manager I/tag_linphone: releaseWifiLock() 2022-05-12 17:38:59.780 11944-11944/com.gemvary.manager I/tag_linphone: releaseMcastLock() 2022-05-12 17:38:59.781 11944-11944/com.gemvary.manager I/tag_linphone: releaseCpuLock() 2022-05-12 17:38:59.783 11944-11944/com.gemvary.manager D/MyLinphone: callStateChanged, callId: outdoor@192.192.255.103, state: Released, message: Call released 2022-05-12 17:38:59.783 11944-11944/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.logBoth(MyLinphone.java:820) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:843) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) callStateChanged, callId: outdoor@192.192.255.103, state: Released, message: Call released 2022-05-12 17:38:59.783 11944-11944/com.gemvary.manager I/tag_linphone: Linphone core [0x93ff0d80] notified [call_state_changed] 2022-05-12 17:38:59.784 11944-11944/com.gemvary.manager I/tag_linphone: op [0x92c70580] : set_or_update_dialog() current=[0x8e82f1e0] new=[0x0] 2022-05-12 17:39:01.790 11944-11944/com.gemvary.manager I/tag_linphone: Activity paused:com.gemvary.manager.activity.OutgoingActivity@5c29590 2022-05-12 17:39:01.790 11944-11944/com.gemvary.manager I/tag_linphone: runningActivities=0 2022-05-12 17:39:01.792 11944-11944/com.gemvary.manager D/OutgoingActivity: Thread: main ├ com.gemvary.manager.activity.OutgoingActivity.onPause(OutgoingActivity.java:598) ├ android.app.Activity.performPause(Activity.java:6849) ├ android.app.Instrumentation.callActivityOnPause(Instrumentation.java:1322) ├ android.app.ActivityThread.performPauseActivityIfNeeded(ActivityThread.java:3746) └ android.app.ActivityThread.performPauseActivity(ActivityThread.java:3723) onPause

Viish commented 2 years ago

Can you also attach the logs of the next call so I can compare? And please attach as a file instead of the whole logs as text.

fengqz627 commented 2 years ago

Here is second call log:

2022-05-12 18:02:12.286 12617-12617/com.gemvary.manager I/tag_linphone: linphone_core_set_playback_gain_db(): no active call. 2022-05-12 18:02:12.286 12617-12617/com.gemvary.manager I/tag_linphone: linphone_core_set_mic_gain_db(): no active call. 2022-05-12 18:02:12.287 12617-12617/com.gemvary.manager I/X-LOG: Thread: main ├ com.gemvary.dev.AudioParameterConfig.getOneToOneAPByServer(AudioParameterConfig.java:4031) ├ com.gemvary.call.sdk.base.MyLinphone.do_group_call(MyLinphone.java:1159) ├ com.gemvary.call.sdk.base.MyLinphone.start_call(MyLinphone.java:289) ├ com.gemvary.call.sdk.target.CallTargetLAN.call(CallTargetLAN.java:45) └ com.gemvary.call.sdk.base.CallManager.toCall(CallManager.java:410) getOneToOneAPByServer() PlaybackGain =1.0, micGain=-2.0, ng_thres=0.25, ng_floorgain=0.0 2022-05-12 18:02:12.290 12617-12617/com.gemvary.manager D/LPManager: the call role is manager 2022-05-12 18:02:12.290 12617-12617/com.gemvary.manager I/tag_linphone: New MediaSession [0x92c9aabc] initialized (LinphoneCore version: 9869bb10b) 2022-05-12 18:02:12.291 12617-12617/com.gemvary.manager I/tag_linphone: CallSession [0x92c9aabc], stream type [audio], multicast role is [inactive] 2022-05-12 18:02:12.291 12617-12617/com.gemvary.manager I/tag_linphone: If ipv6 is not enabled, Media-session.cpp 2022-05-12 18:02:12.291 12617-12617/com.gemvary.manager I/tag_linphone: If ipv6 is not enabled, Media-session.cpp 2022-05-12 18:02:12.291 12617-12617/com.gemvary.manager I/tag_linphone: RtpSession bound to [0.0.0.0] ports [7076] [7077] 2022-05-12 18:02:12.291 12617-12617/com.gemvary.manager I/tag_linphone: fqztemp 91 audiostream.c 2022-05-12 18:02:12.291 12617-12617/com.gemvary.manager I/tag_linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2022-05-12 18:02:12.291 12617-12617/com.gemvary.manager I/tag_linphone: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80 2022-05-12 18:02:12.291 12617-12617/com.gemvary.manager I/tag_linphone: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32 2022-05-12 18:02:12.291 12617-12617/com.gemvary.manager I/tag_linphone: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80 2022-05-12 18:02:12.292 12617-12617/com.gemvary.manager I/tag_linphone: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32 2022-05-12 18:02:12.292 12617-12617/com.gemvary.manager I/tag_linphone: Creating ZRTP engine on rtp session [0x92d73600] ssrc 0xa4e74855 2022-05-12 18:02:12.293 12617-12617/com.gemvary.manager I/tag_linphone: Setting DSCP to 46 for MSAudio stream. 2022-05-12 18:02:12.293 12617-12617/com.gemvary.manager I/tag_linphone: Equalizer location: hp 2022-05-12 18:02:12.294 12617-12617/com.gemvary.manager I/tag_linphone: fqztemp use_ng=1 2022-05-12 18:02:12.294 12617-12617/com.gemvary.manager I/tag_linphone: cannot set noise gate mode to [1] because no volume send 2022-05-12 18:02:12.294 12617-12617/com.gemvary.manager I/tag_linphone: CallSession [0x92c9aabc], stream type [video], multicast role is [inactive] 2022-05-12 18:02:12.294 12617-12617/com.gemvary.manager I/tag_linphone: If ipv6 is not enabled, Media-session.cpp 2022-05-12 18:02:12.294 12617-12617/com.gemvary.manager I/tag_linphone: If ipv6 is not enabled, Media-session.cpp 2022-05-12 18:02:12.295 12617-12617/com.gemvary.manager I/tag_linphone: RtpSession bound to [0.0.0.0] ports [9078] [9079] 2022-05-12 18:02:12.298 12617-12617/com.gemvary.manager I/tag_linphone: This device (rockchip/GRG-S5/rk3288) says it has no built-in echo canceller. 2022-05-12 18:02:12.298 12617-12617/com.gemvary.manager I/tag_linphone: No information available for [rockchip/GRG-S5/rk3288], 2022-05-12 18:02:12.298 12617-12617/com.gemvary.manager I/tag_linphone: Sound device information for [rockchip/GRG-S5/rk3288] is: builtin=[no], delay=[250] ms 2022-05-12 18:02:12.298 12617-12617/com.gemvary.manager I/tag_linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2022-05-12 18:02:12.299 12617-12617/com.gemvary.manager I/tag_linphone: Initializing multistream ZRTP context on rtp session [0x92d72800] ssrc 0x3c6b0c6d 2022-05-12 18:02:12.299 12617-12617/com.gemvary.manager I/tag_linphone: Setting DSCP to 0 for MSVideo stream. 2022-05-12 18:02:12.299 12617-12617/com.gemvary.manager I/tag_linphone: CallSession [0x92c9aabc], stream type [text], multicast role is [inactive] 2022-05-12 18:02:12.299 12617-12617/com.gemvary.manager I/tag_linphone: If ipv6 is not enabled, Media-session.cpp 2022-05-12 18:02:12.299 12617-12617/com.gemvary.manager I/tag_linphone: If ipv6 is not enabled, Media-session.cpp 2022-05-12 18:02:12.299 12617-12617/com.gemvary.manager I/tag_linphone: RtpSession bound to [0.0.0.0] ports [11078] [11079] 2022-05-12 18:02:12.299 12617-12617/com.gemvary.manager I/tag_linphone: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION 2022-05-12 18:02:12.300 12617-12617/com.gemvary.manager I/tag_linphone: Linphone core [0x93cd1780] notified [call_created] 2022-05-12 18:02:12.300 12617-12617/com.gemvary.manager I/tag_linphone: CallSession [0x92c9aabc] moving from state State::Idle to State::OutgoingInit 2022-05-12 18:02:12.300 12617-12617/com.gemvary.manager I/tag_linphone: acquireWifiLock() 2022-05-12 18:02:12.300 12617-12617/com.gemvary.manager I/tag_linphone: acquireMcastLock() 2022-05-12 18:02:12.301 12617-12617/com.gemvary.manager I/tag_linphone: acquireCpuLock() 2022-05-12 18:02:12.336 12617-12617/com.gemvary.manager D/MyLinphone: callStateChanged, callId: outdoor@192.192.255.103, state: OutgoingInit, message: Starting outgoing call 2022-05-12 18:02:12.336 12617-12617/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.logBoth(MyLinphone.java:820) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:843) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.inviteAddressWithParams(Native Method) └ org.linphone.core.CoreImpl.inviteAddressWithParams(Core.java:4577) callStateChanged, callId: outdoor@192.192.255.103, state: OutgoingInit, message: Starting outgoing call 2022-05-12 18:02:12.337 12617-12617/com.gemvary.manager D/CallTarget: after added, call id list is: [outdoor@192.192.255.103] 2022-05-12 18:02:12.337 12617-12617/com.gemvary.manager D/CallManager: Thread: main ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:785) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:775) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:769) ├ com.gemvary.call.sdk.base.CallManager.outgoingInit(CallManager.java:842) └ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:945)

callId: outdoor@192.192.255.103, state: OutgoingInit, reason: null, ExtraStatus{targetPending=false, transferType=UNKNOWN, cloudVoipNotConnected=false, unitNo='null', roomNo='null', preparationError=null, hangupReason=null}

2022-05-12 18:02:12.339 12617-12617/com.gemvary.manager I/tag_linphone: VideoSize= vga 2022-05-12 18:02:12.339 12617-12617/com.gemvary.manager I/tag_linphone: fps= 15 2022-05-12 18:02:12.339 12617-12617/com.gemvary.manager I/tag_linphone: Linphone core [0x93cd1780] notified [call_state_changed] 2022-05-12 18:02:12.341 12617-12617/com.gemvary.manager I/tag_linphone: Don't put video stream on local offer for CallSession [0x92c9aabc] 2022-05-12 18:02:12.341 12617-12617/com.gemvary.manager I/tag_linphone: Don't put text stream on local offer for CallSession [0x92c9aabc] 2022-05-12 18:02:12.341 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSRtpRecv:0x8f158458,0-->MSVoidSink:0x8f158490,0 2022-05-12 18:02:12.341 12617-12617/com.gemvary.manager I/tag_linphone: ms_devices_info_get_model getdevicemodel= [GRG-S5] 2022-05-12 18:02:12.342 12617-12617/com.gemvary.manager I/tag_linphone: Contact has not been fixed, stack will do 2022-05-12 18:02:12.343 12617-12617/com.gemvary.manager I/tag_linphone: ["{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42:5060] calling [sip:outdoor@192.192.255.103] on op [0x943ce700] 2022-05-12 18:02:12.344 12617-12617/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x92cbdc90)] acquired [ref=0x100aea] 2022-05-12 18:02:12.344 12617-13008/com.gemvary.manager I/tag_linphone: Priority used: 99 2022-05-12 18:02:12.344 12617-12617/com.gemvary.manager I/tag_linphone: transaction [0x92cbdc90]: starting transaction background task with id=[100aea]. 2022-05-12 18:02:12.344 12617-13008/com.gemvary.manager I/tag_linphone: MSAudio MSTicker priority increased to maximum. 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: Changing [client] [INVITE] transaction [0x92cbdc90], from state [INIT] to [CALLING] 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0]: message sent to [UDP://192.192.255.103:5060], size: [1728] bytes 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: INVITE sip:outdoor@192.192.255.103 SIP/2.0 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: Via: SIP/2.0/UDP 192.192.255.42:5060;branch=z9hG4bK.wm1Gaaw2D;rport 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: From: "{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42;tag=LNJh4lXl5 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: To: sip:outdoor@192.192.255.103 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: CSeq: 20 INVITE 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: Call-ID: o6taYiVcUo 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: Max-Forwards: 70 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: Supported: replaces, outbound, gruu 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: Content-Type: application/sdp 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: Content-Length: 531 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: Contact: "{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42;transport=udp;+sip.instance="";+org.linphone.specs=groupchat 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: User-Agent: LinphoneAndroid/6.1.20220512170321 (belle-sip/1.6.3) 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: v=0 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: o=linphone.android 3554 2147 IN IP4 192.192.255.42 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: s=Talk 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: c=IN IP4 192.192.255.42 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: t=0 0 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: m=audio 7076 RTP/AVP 96 97 98 0 8 18 101 99 100 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: a=rtpmap:96 opus/48000/2 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: a=fmtp:96 useinbandfec=1 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: a=rtpmap:97 speex/16000 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: a=fmtp:97 vbr=on 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: a=rtpmap:98 speex/8000 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: a=fmtp:98 vbr=on 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: a=fmtp:18 annexb=yes 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: a=rtpmap:101 telephone-event/48000 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: a=rtpmap:99 telephone-event/16000 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: a=rtpmap:100 telephone-event/8000 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: a=rtcp-fb: trr-int 5 2022-05-12 18:02:12.345 12617-12617/com.gemvary.manager I/tag_linphone: a=rtcp-fb: ccm tmmbr 2022-05-12 18:02:12.346 12617-12617/com.gemvary.manager I/tag_linphone: CallSession [0x92c9aabc] moving from state State::OutgoingInit to State::OutgoingProgress 2022-05-12 18:02:12.346 12617-12617/com.gemvary.manager D/MyLinphone: callStateChanged, callId: outdoor@192.192.255.103, state: OutgoingProgress, message: Outgoing call in progress 2022-05-12 18:02:12.347 12617-12617/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.logBoth(MyLinphone.java:820) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:843) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.inviteAddressWithParams(Native Method) └ org.linphone.core.CoreImpl.inviteAddressWithParams(Core.java:4577) callStateChanged, callId: outdoor@192.192.255.103, state: OutgoingProgress, message: Outgoing call in progress 2022-05-12 18:02:12.347 12617-12617/com.gemvary.manager I/tag_linphone: Linphone core [0x93cd1780] notified [call_state_changed] 2022-05-12 18:02:12.347 12617-12617/com.gemvary.manager D/EventBus: No subscribers registered for event class com.gemvary.manager.bean.CallStatusEvent 2022-05-12 18:02:12.347 12617-12617/com.gemvary.manager D/EventBus: No subscribers registered for event class org.greenrobot.eventbus.NoSubscriberEvent 2022-05-12 18:02:12.387 656-669/com.android.systemui W/art: Suspending all threads took: 6.662ms 2022-05-12 18:02:12.389 656-669/com.android.systemui I/art: Background partial concurrent mark sweep GC freed 4571(256KB) AllocSpace objects, 0(0B) LOS objects, 40% free, 8MB/14MB, paused 7.270ms total 48.403ms 2022-05-12 18:02:12.417 469-480/system_process I/art: Background partial concurrent mark sweep GC freed 3649(135KB) AllocSpace objects, 0(0B) LOS objects, 33% free, 5MB/7MB, paused 6.315ms total 76.723ms 2022-05-12 18:02:12.433 12617-12722/com.gemvary.manager D/mali_winsys: EGLint new_window_surface(egl_winsys_display , void , EGLSurface, EGLConfig, egl_winsys_surface *, egl_color_buffer_format , EGLBoolean) returns 0x3000 2022-05-12 18:02:12.434 12617-12617/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x200aba] 2022-05-12 18:02:12.434 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0]: starting recv background task with id=[200aba]. 2022-05-12 18:02:12.434 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0]: received [538] new bytes from [UDP://192.192.255.103:5060]: 2022-05-12 18:02:12.434 12617-12617/com.gemvary.manager I/tag_linphone: SIP/2.0 100 Trying 2022-05-12 18:02:12.434 12617-12617/com.gemvary.manager I/tag_linphone: Via: SIP/2.0/UDP 192.192.255.42:5060;rport=5060;received=192.192.255.42;branch=z9hG4bK.wm1Gaaw2D 2022-05-12 18:02:12.434 12617-12617/com.gemvary.manager I/tag_linphone: Call-ID: o6taYiVcUo 2022-05-12 18:02:12.434 12617-12617/com.gemvary.manager I/tag_linphone: From: "{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42;tag=LNJh4lXl5 2022-05-12 18:02:12.434 12617-12617/com.gemvary.manager I/tag_linphone: To: sip:outdoor@192.192.255.103 2022-05-12 18:02:12.434 12617-12617/com.gemvary.manager I/tag_linphone: CSeq: 20 INVITE 2022-05-12 18:02:12.434 12617-12617/com.gemvary.manager I/tag_linphone: Content-Length: 0 2022-05-12 18:02:12.437 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0] [538] bytes parsed 2022-05-12 18:02:12.438 12617-12617/com.gemvary.manager I/tag_linphone: Found transaction matching response. 2022-05-12 18:02:12.438 12617-12617/com.gemvary.manager I/tag_linphone: Changing [client] [INVITE] transaction [0x92cbdc90], from state [CALLING] to [PROCEEDING] 2022-05-12 18:02:12.438 12617-12617/com.gemvary.manager I/tag_linphone: op [0x943ce700] : set_or_update_dialog() current=[0x0] new=[0x0] 2022-05-12 18:02:12.438 12617-12617/com.gemvary.manager I/tag_linphone: Op [0x943ce700] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL] 2022-05-12 18:02:12.438 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0]: ending recv background task with id=[200aba]. 2022-05-12 18:02:12.438 12617-12617/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x200aba] 2022-05-12 18:02:12.439 12617-12617/com.gemvary.manager I/tag_linphone: Garbage collecting unowned object of type belle_sdp_session_description_t 2022-05-12 18:02:12.439 12617-12617/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0xab6] 2022-05-12 18:02:12.440 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0]: starting recv background task with id=[ab6]. 2022-05-12 18:02:12.440 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0]: received [711] new bytes from [UDP://192.192.255.103:5060]: 2022-05-12 18:02:12.440 12617-12617/com.gemvary.manager I/tag_linphone: SIP/2.0 180 Ringing 2022-05-12 18:02:12.440 12617-12617/com.gemvary.manager I/tag_linphone: Via: SIP/2.0/UDP 192.192.255.42:5060;rport=5060;received=192.192.255.42;branch=z9hG4bK.wm1Gaaw2D 2022-05-12 18:02:12.440 12617-12617/com.gemvary.manager I/tag_linphone: Call-ID: o6taYiVcUo 2022-05-12 18:02:12.440 12617-12617/com.gemvary.manager I/tag_linphone: From: "{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42;tag=LNJh4lXl5 2022-05-12 18:02:12.440 12617-12617/com.gemvary.manager I/tag_linphone: To: sip:outdoor@192.192.255.103;tag=IAH-RgNmQ--NwvhJT.00T7lICJKfTRr. 2022-05-12 18:02:12.440 12617-12617/com.gemvary.manager I/tag_linphone: CSeq: 20 INVITE 2022-05-12 18:02:12.440 12617-12617/com.gemvary.manager I/tag_linphone: Contact: 2022-05-12 18:02:12.440 12617-12617/com.gemvary.manager I/tag_linphone: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS 2022-05-12 18:02:12.440 12617-12617/com.gemvary.manager I/tag_linphone: Content-Length: 0 2022-05-12 18:02:12.444 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0] [711] bytes parsed 2022-05-12 18:02:12.444 12617-12617/com.gemvary.manager I/tag_linphone: Found transaction matching response. 2022-05-12 18:02:12.444 12617-12617/com.gemvary.manager I/tag_linphone: New client dialog [0x8de87180] , local tag [LNJh4lXl5], remote tag [IAH-RgNmQ--NwvhJT.00T7lICJKfTRr.] 2022-05-12 18:02:12.444 12617-12617/com.gemvary.manager I/tag_linphone: Dialog [0x8de87180]: now updated by transaction [0x92cbdc90]. 2022-05-12 18:02:12.445 12617-12617/com.gemvary.manager I/tag_linphone: op [0x943ce700] : set_or_update_dialog() current=[0x0] new=[0x8de87180] 2022-05-12 18:02:12.445 12617-12617/com.gemvary.manager I/tag_linphone: Op [0x943ce700] receiving call response [180], dialog is [0x8de87180] in state [BELLE_SIP_DIALOG_EARLY] 2022-05-12 18:02:12.445 12617-12617/com.gemvary.manager I/tag_linphone: Remote ringing... 2022-05-12 18:02:12.445 12617-12617/com.gemvary.manager I/tag_linphone: CallSession [0x92c9aabc] moving from state State::OutgoingProgress to State::OutgoingRinging 2022-05-12 18:02:12.446 12617-12617/com.gemvary.manager D/MyLinphone: callStateChanged, callId: outdoor@192.192.255.103, state: OutgoingRinging, message: Remote ringing 2022-05-12 18:02:12.446 12617-12617/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.logBoth(MyLinphone.java:820) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:843) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) callStateChanged, callId: outdoor@192.192.255.103, state: OutgoingRinging, message: Remote ringing 2022-05-12 18:02:12.447 12617-12617/com.gemvary.manager D/CallManager: Thread: main ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:785) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:775) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:769) ├ com.gemvary.call.sdk.base.CallManager.outgoingRinging(CallManager.java:865) └ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:956)

callId: outdoor@192.192.255.103, state: OutgoingRinging, reason: null, ExtraStatus{targetPending=false, transferType=UNKNOWN, cloudVoipNotConnected=false, unitNo='null', roomNo='null', preparationError=null, hangupReason=null}

2022-05-12 18:02:12.447 12617-12617/com.gemvary.manager D/OutgoingActivity: Thread: main ├ com.gemvary.manager.activity.OutgoingActivity.ringing(OutgoingActivity.java:405) ├ com.gemvary.manager.activity.OutgoingActivity.lambda$onCreate$6$OutgoingActivity(OutgoingActivity.java:230) ├ com.gemvary.manager.activity.-$$Lambda$OutgoingActivity$wsNkDazuaV1TG9LxuYSo38yj7TI.onCallStateChanged(lambda) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:788) └ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:775)

to play ringing

2022-05-12 18:02:12.448 12617-12617/com.gemvary.manager I/tag_linphone: Linphone core [0x93cd1780] notified [call_state_changed] 2022-05-12 18:02:12.448 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0]: ending recv background task with id=[ab6]. 2022-05-12 18:02:12.449 12617-12617/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0xab6] 2022-05-12 18:02:12.450 227-812/? E/RockFFPlayer: doGetTrackInfo: track size = 1 2022-05-12 18:02:12.482 469-567/system_process I/ActivityManager: Displayed com.gemvary.manager/.activity.OutgoingActivity: +390ms 2022-05-12 18:02:12.483 227-812/? D/RockFFPlayer: reset: in 2022-05-12 18:02:12.483 227-812/? D/RockFFPlayerBase: dumpStatus(): Started 2022-05-12 18:02:12.483 227-812/? D/RockFFPlayer: reset: stop_l in 2022-05-12 18:02:12.483 227-812/? D/RockFFPlayer: stop_l: in 2022-05-12 18:02:12.483 227-812/? D/RockFFPlayer: enter enqueueMessage

2022-05-12 18:02:21.462 227-843/? I/MediaPlayerService: processName cmdline:/proc/12617/cmdline 2022-05-12 18:02:21.463 227-812/? I/MediaPlayerFactory: createFFPlayer 2022-05-12 18:02:21.465 227-812/? E/FFPlayer: isBluray(), extension = .apk 2022-05-12 18:02:21.465 227-812/? E/FFPlayerHelper: isBDDirectory() ,path = /data/app/com.gemvary.manager-1/base.apk 2022-05-12 18:02:21.465 227-812/? E/FFPlayerHelper: isBDDirectory(): no BDMV 2022-05-12 18:02:21.465 12617-13008/com.gemvary.manager I/tag_linphone: First estimation 2022-05-12 18:02:21.465 227-812/? D/RkMemConfig: RkMemConfig init 2022-05-12 18:02:21.465 227-812/? E/RockFFPlayerBase: FFMPlayerBase:eDisplayIdMain 2022-05-12 18:02:21.466 227-812/? D/FFPlayerHelper: MediaServer is SP_FOREGROUND, tid=812 2022-05-12 18:02:21.466 12617-12617/com.gemvary.manager I/tag_linphone: MSAudio stream [0x8f455500]: receiving RTCP RR 2022-05-12 18:02:21.466 227-812/? D/RkExtensionPlayer: RkExtensionPlayer in 2022-05-12 18:02:21.466 227-812/? D/RkExtensionPlayer: checkDirectoryAccess: Directory[0] = /tmp/rk_ext_subtitle/, AccessPermission = -1 2022-05-12 18:02:21.466 12617-12617/com.gemvary.manager I/tag_linphone: MSAudio stream [0x8f455500]: receiving RTCP RR 2022-05-12 18:02:21.467 227-812/? D/RkExtensionPlayer: checkDirectoryAccess: Directory[1] = /data/rk_ext_subtitle/, AccessPermission = -1 2022-05-12 18:02:21.467 227-812/? D/RkExtensionPlayer: checkDirectoryAccess: Directory[2] = /data/media/0/rk_ext_subtitle/, AccessPermission = -1 2022-05-12 18:02:21.467 227-812/? E/RkExtensionPlayer: createDirectory(), path = /tmp/rk_ext_subtitle/ 2022-05-12 18:02:21.467 227-812/? E/RkExtensionPlayer: createDirectory(), create directory = /tmp/rk_ext_subtitle/ fail,reson = No such file or directory 2022-05-12 18:02:21.467 227-812/? E/RkExtensionPlayer: createDirectory(), path = /data/rk_ext_subtitle/ 2022-05-12 18:02:21.467 12617-12617/com.gemvary.manager I/tag_linphone: Linphone core [0x93cd1780] notified [call_stats_updated] 2022-05-12 18:02:21.467 227-812/? E/RkExtensionPlayer: createDirectory(), path = /data/rk_ext_subtitle/a6cd2840/ 2022-05-12 18:02:21.468 227-812/? D/RkExtensionPlayer: checkDirectoryAccess: Use [/data/rk_ext_subtitle/a6cd2840/] as Temp Subtile Directory 2022-05-12 18:02:21.468 12617-12617/com.gemvary.manager I/tag_linphone: Linphone core [0x93cd1780] notified [call_stats_updated] 2022-05-12 18:02:21.473 227-812/? E/TraceThread: TraceThread::randomUID() --> [UID=511ddca3] 2022-05-12 18:02:21.473 227-13189/? E/TraceThread: VsyncReciver(tid = 2767210784)--> [UID=511ddca3] VsyncReciver::startthread 2022-05-12 18:02:21.474 227-812/? E/TraceThread: RockFFPlayer(tid = 2770344224)--> [UID=511ddca3] FFMPlayer Construction; object=0xa5447000 2022-05-12 18:02:21.474 227-812/? D/RockFFPlayer: setDataSource fd = 12, offset =927256,length = 4449 2022-05-12 18:02:21.474 227-812/? D/RockFFPlayer: start:create thread 2022-05-12 18:02:21.475 227-13190/? D/RockFFPlayer: RkMessageQueue::run() 2022-05-12 18:02:21.475 227-812/? D/RockFFPlayer: start:create thread ok,mThreadStatus=0 2022-05-12 18:02:21.475 227-812/? D/RockFFPlayer: enter enqueueMessage 2022-05-12 18:02:21.475 227-812/? D/RockFFPlayer: enqueueMessage:1 2022-05-12 18:02:21.475 227-812/? D/RockFFPlayer: enqueueMessage:2 2022-05-12 18:02:21.475 227-812/? D/RockFFPlayer: enqueueMessage:3 2022-05-12 18:02:21.475 227-812/? D/RockFFPlayer: enqueueMessage:Message 1,set signal 2022-05-12 18:02:21.475 227-812/? D/RockFFPlayer: enqueueMessage:sync wait 2022-05-12 18:02:21.475 227-13190/? D/RockFFPlayer: deliverMessage 2022-05-12 18:02:21.475 227-13190/? D/RockFFPlayer: run : dequeueMessage Message 20 2022-05-12 18:02:21.476 227-13190/? D/RockFFPlayer: CMD_CURRENTSTATUS 2022-05-12 18:02:21.476 227-13190/? D/RockFFPlayerBase: dumpStatus(): Initialized 2022-05-12 18:02:21.476 227-13190/? D/RockFFPlayer: run : dequeueMessage Message 20 OK 2022-05-12 18:02:21.476 227-13190/? D/RockFFPlayer: Messagecomplete type [20] 2022-05-12 18:02:21.476 227-13190/? D/RockFFPlayer: run : Message 20 complete 2022-05-12 18:02:21.476 227-812/? D/RockFFPlayer: enqueueMessage:sync wait ok 2022-05-12 18:02:21.478 227-843/? D/RockFFPlayer: prepareAsync in 2022-05-12 18:02:21.478 227-843/? D/RockFFPlayerBase: dumpStatus(): Initialized 2022-05-12 18:02:21.478 227-843/? D/RockFFPlayerBase: dumpStatus(): Preparing 2022-05-12 18:02:21.479 227-13191/? I/FFPlayer_Version: ** 2022-05-12 18:02:21.479 227-13191/? I/FFPlayer_Version: starting main player: v2.1 2018-08-13-14:03:10.299ffd14.shi 2022-05-12 18:02:21.480 227-13191/? I/FFPlayer_Version: ffplayer running on: rk3288 2022-05-12 18:02:21.480 227-13191/? I/FFPlayer_Version: ** 2022-05-12 18:02:21.480 227-13191/? I/RockFFPlayer: setDataSource(url =file/fd::12,offset::927256,length::4449) 2022-05-12 18:02:21.480 227-13191/? I/RockFFPlayer: setDataSource(fileurl =/data/app/com.gemvary.manager-1/base.apk) 2022-05-12 18:02:21.480 227-843/? D/RockFFPlayer: prepareAsync ok 2022-05-12 18:02:21.480 227-13191/? E/RockFFPlayerBase: openContext : in 2022-05-12 18:02:21.480 227-13191/? E/RockFFPlayer: ffmpeg version v2.1: git-2018-08-08-f9dabc0 2022-05-12 18:02:21.481 227-13191/? E/RockFFPlayer: av_probe_input_buffer:avio_read 2022-05-12 18:02:21.481 227-13191/? I/RockFFPlayer: flv_probe get probe score: 0 2022-05-12 18:02:21.482 227-13191/? E/RockFFPlayer: Hery, probe size = 0 2022-05-12 18:02:21.483 227-13191/? E/RockFFPlayer: avformat_open_input:read_header 2022-05-12 18:02:21.483 227-13191/? E/RockFFPlayer: file_read: offset=66069129, c->offset=927256, c->length=4449 2022-05-12 18:02:21.483 227-13191/? E/RockFFPlayer: file_read: offset=66069129, c->offset=927256, c->length=4449 2022-05-12 18:02:21.483 227-13191/? E/RockFFPlayer: avformat_open_input:read_header ok 2022-05-12 18:02:21.483 227-13191/? D/RkExtensionPlayer: openContext in 2022-05-12 18:02:21.484 227-13191/? E/RkExtensionPlayer: autoDetect(): path = /data/app/com.gemvary.manager-1/base.apk 2022-05-12 18:02:21.484 227-13191/? E/FFPlayerHelper: isNetWorkUrl: url = /data/app/com.gemvary.manager-1/base.apk 2022-05-12 18:02:21.484 227-13191/? E/FFPlayerHelper: isBDDirectory() ,path = /data/app/com.gemvary.manager-1 2022-05-12 18:02:21.484 227-13191/? E/FFPlayerHelper: isBDDirectory(): no BDMV 2022-05-12 18:02:21.484 227-13191/? D/RkExtensionPlayer: openContext out,mTrackInfos size = 0 2022-05-12 18:02:21.484 227-13191/? E/RockFFPlayerBase: openContext : out,ret = 0 2022-05-12 18:02:21.484 227-13191/? D/RockFFPlayer: the source format name: ogg 2022-05-12 18:02:21.484 227-13191/? D/RockFFPlayer: find av stream info in 2022-05-12 18:02:21.488 12617-12617/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x100b2e] 2022-05-12 18:02:21.489 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0]: starting recv background task with id=[100b2e]. 2022-05-12 18:02:21.489 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0]: received [1071] new bytes from [UDP://192.192.255.103:5060]: 2022-05-12 18:02:21.489 12617-12617/com.gemvary.manager I/tag_linphone: SIP/2.0 200 OK 2022-05-12 18:02:21.489 12617-12617/com.gemvary.manager I/tag_linphone: Via: SIP/2.0/UDP 192.192.255.42:5060;rport=5060;received=192.192.255.42;branch=z9hG4bK.wm1Gaaw2D 2022-05-12 18:02:21.489 12617-12617/com.gemvary.manager I/tag_linphone: Call-ID: o6taYiVcUo 2022-05-12 18:02:21.489 12617-12617/com.gemvary.manager I/tag_linphone: From: "{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42;tag=LNJh4lXl5 2022-05-12 18:02:21.489 12617-12617/com.gemvary.manager I/tag_linphone: To: sip:outdoor@192.192.255.103;tag=IAH-RgNmQ--NwvhJT.00T7lICJKfTRr. 2022-05-12 18:02:21.489 12617-12617/com.gemvary.manager I/tag_linphone: CSeq: 20 INVITE 2022-05-12 18:02:21.489 12617-12617/com.gemvary.manager I/tag_linphone: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: Contact: 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: Supported: replaces, 100rel, timer, norefersub 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: Content-Type: application/sdp 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: Content-Length: 283 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: v=0 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: o=- 3861367332 3861367333 IN IP4 192.192.255.103 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: s=pjmedia 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: b=AS:84 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: t=0 0 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: a=X-nat:0 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: m=audio 4036 RTP/AVP 0 100 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: c=IN IP4 192.192.255.103 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: b=TIAS:64000 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: a=rtcp:4037 IN IP4 192.192.255.103 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: a=sendrecv 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: a=rtpmap:0 PCMU/8000 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: a=rtpmap:100 telephone-event/8000 2022-05-12 18:02:21.490 12617-12617/com.gemvary.manager I/tag_linphone: a=fmtp:100 0-16 2022-05-12 18:02:21.491 227-13191/? E/RockFFPlayer: All info may be found, format: ogg 2022-05-12 18:02:21.491 227-13191/? E/RockFFPlayer: All info found, format: ogg 2022-05-12 18:02:21.491 227-13191/? E/RockFFPlayer: read_size = 1 2022-05-12 18:02:21.491 227-13191/? E/RockFFPlayer: update_stream_timings: st[-1521917920].duration = 143781832 2022-05-12 18:02:21.492 227-13191/? E/RockFFPlayer: estimate_timings:0: start_time: 0.000 duration: 143.782,base_time.den=44100,base_time.num=1 2022-05-12 18:02:21.492 227-13191/? E/RockFFPlayer: stream: start_time: 0.000 duration: 3260.359 bitrate=159 kb/s 2022-05-12 18:02:21.492 227-13191/? E/RockFFPlayer: avformat_find_stream_info:find_stream_info_err:free info,ok 2022-05-12 18:02:21.492 227-13191/? D/RockFFPlayer: find av stream info ok 2022-05-12 18:02:21.492 227-13191/? D/RockFFPlayer: AudioStartTime=0,VideoStartTime=-1,tmpTime=-1 2022-05-12 18:02:21.492 227-13191/? E/RockFFPlayer: Hery, s->duration = 3260359002.000000 2022-05-12 18:02:21.492 227-13191/? I/RockFFPlayer: Input #0, ogg, from 'file/fd::12,offset::927256,length::4449': 2022-05-12 18:02:21.492 227-13191/? I/RockFFPlayer: Duration: 2022-05-12 18:02:21.492 227-13191/? I/RockFFPlayer: 00:54:20.36 2022-05-12 18:02:21.492 227-13191/? I/RockFFPlayer: , start: 2022-05-12 18:02:21.492 227-13191/? I/RockFFPlayer: 0.000000 2022-05-12 18:02:21.492 227-13191/? I/RockFFPlayer: , bitrate: 2022-05-12 18:02:21.492 227-13191/? I/RockFFPlayer: 159 kb/s 2022-05-12 18:02:21.492 227-13191/? I/RockFFPlayer: Stream #0:0 2022-05-12 18:02:21.492 227-13191/? I/RockFFPlayer: : Audio: vorbis, 44100 Hz, stereo, s16, 160 kb/s 2022-05-12 18:02:21.492 227-13191/? I/RockFFPlayer: Metadata: 2022-05-12 18:02:21.493 227-13191/? I/RockFFPlayer: TITLE : 2022-05-12 18:02:21.493 227-13191/? I/RockFFPlayer: Digital Phone 2022-05-12 18:02:21.493 227-13191/? D/RockFFPlayer: send do-prepare message in 2022-05-12 18:02:21.493 227-13191/? D/RockFFPlayer: enter enqueueMessage 2022-05-12 18:02:21.493 227-13191/? D/RockFFPlayer: enqueueMessage:1 2022-05-12 18:02:21.493 227-13191/? D/RockFFPlayer: enqueueMessage:2 2022-05-12 18:02:21.493 227-13191/? D/RockFFPlayer: enqueueMessage:3 2022-05-12 18:02:21.493 227-13191/? D/RockFFPlayer: enqueueMessage:Message 1,set signal 2022-05-12 18:02:21.493 227-13191/? D/RockFFPlayer: enqueueMessage:sync wait 2022-05-12 18:02:21.493 227-13190/? D/RockFFPlayer: deliverMessage 2022-05-12 18:02:21.493 227-13190/? D/RockFFPlayer: run : dequeueMessage Message 1 2022-05-12 18:02:21.493 227-13190/? D/RockFFPlayer: CMD_PREPARE 2022-05-12 18:02:21.493 227-13190/? I/RockFFPlayer: doPrepare in 2022-05-12 18:02:21.493 227-13190/? I/RockFFPlayer: prepareVideo 2022-05-12 18:02:21.493 227-13190/? E/RockFFPlayer: mVideoStreamIndex is -1 2022-05-12 18:02:21.493 227-13190/? I/RockFFPlayer: prepareAudio 2022-05-12 18:02:21.493 227-13190/? E/RockFFPlayer: audio stream codec_id = 86021 2022-05-12 18:02:21.494 227-13190/? D/RockFFPlayerBase: open MediaDecoder.cfg failed,so can't config anything 2022-05-12 18:02:21.494 227-13190/? D/RockFFPlayerBase: File not exists! 2022-05-12 18:02:21.494 227-13190/? D/RkBitstreamManager: isBypass: 2022-05-12 18:02:21.498 227-13190/? D/RockFFPlayer: initAudioPlayer 2022-05-12 18:02:21.499 227-13190/? D/RkBitstreamManager: isBypass: 2022-05-12 18:02:21.499 227-13190/? D/RockFFPlayer: AudioPlayer getOutputMode : 0, SPDIF mode : -1 2022-05-12 18:02:21.499 227-13190/? D/RockFFPlayerBase: setAudioOutputMode output_mode : 0 2022-05-12 18:02:21.499 227-13190/? D/RockFFPlayerBase: set SPDIF output mode : -1 2022-05-12 18:02:21.499 227-13190/? D/AudioPlayerApi: audio player mode in: 0 2022-05-12 18:02:21.499 227-13190/? D/AudioPlayerApi: audio player mode out: 0 2022-05-12 18:02:21.499 227-13190/? D/RockFFPlayer: initAudioPlayer out 2022-05-12 18:02:21.499 227-13190/? D/RkExtensionPlayer: prepareExtendSubtitle in 2022-05-12 18:02:21.500 227-13190/? E/RockFFPlayer: Failed to prepare external subtitle 2022-05-12 18:02:21.500 227-13190/? D/RkExtensionPlayer: ~RkExtensionPlayer in 2022-05-12 18:02:21.500 227-13190/? D/RkExtensionPlayer: RkExtensionPlayer::closeExtendSubDecoder in 2022-05-12 18:02:21.500 227-13190/? E/FFExtensionSource: ~FFExtensionSource in 2022-05-12 18:02:21.500 227-13190/? D/RockFFPlayer: FFMediaSource context null. 2022-05-12 18:02:21.500 227-13190/? E/FFExtensionSource: ~FFExtensionSource out 2022-05-12 18:02:21.500 227-13190/? D/RockFFPlayer: ~FFMediaSource 2022-05-12 18:02:21.500 227-13190/? D/RockFFPlayer: FFMediaSource context null. 2022-05-12 18:02:21.500 227-13190/? D/RkExtensionPlayer: closeExtendSubDecoder out 2022-05-12 18:02:21.500 227-13190/? E/RkExtensionPlayer: deleteAllFileInDirectory(),/tmp/rk_ext_subtitle/ is not exist 2022-05-12 18:02:21.501 227-13190/? E/RkExtensionPlayer: deleteAllFileInDirectory(),/data/media/0/rk_ext_subtitle/ is not exist 2022-05-12 18:02:21.501 227-13190/? I/RockFFPlayer: prepareSubtitle in 2022-05-12 18:02:21.501 227-13190/? E/RockFFPlayer: hasSubtitle return fail 2022-05-12 18:02:21.501 227-13190/? D/AudioDecoder: decodeaudio AudioOutputMode : 0, SPDIF_MODE : -1, PlayerMode : 0 2022-05-12 18:02:21.501 227-13190/? D/AudioDecoder: mStream->codec->channels = 2 mStream->codec->sample_rate = 44100 2022-05-12 18:02:21.501 227-13190/? D/AudioDecoder: mStream->codec->channels = 2 mStream->codec->sample_rate = 44100 2022-05-12 18:02:21.501 227-13190/? D/RockFFPlayer: createAudioDecoder: create audio decoder ok 2022-05-12 18:02:21.501 227-13190/? E/RockFFPlayer: createVideoDecoder: no video stream 2022-05-12 18:02:21.501 227-13190/? E/RockFFPlayer: createSubtitleDecoder: no subtitle stream 2022-05-12 18:02:21.501 227-13190/? E/TraceThread: TraceThread::dumpTrace() FileName:/data/trace_thread.log 2022-05-12 18:02:21.502 227-13190/? D/RockFFPlayerBase: dumpStatus(): Preapared 2022-05-12 18:02:21.502 227-13190/? D/RockFFPlayer: Sync master clock type - 1 2022-05-12 18:02:21.502 227-13190/? I/RockFFPlayer: doPrepareFinish ok 2022-05-12 18:02:21.502 227-13190/? D/RockFFPlayer: run : dequeueMessage Message 1 OK 2022-05-12 18:02:21.502 227-13190/? D/RockFFPlayer: Messagecomplete type [1] 2022-05-12 18:02:21.502 227-13190/? D/RockFFPlayer: run : Message 1 complete 2022-05-12 18:02:21.502 227-13191/? D/RockFFPlayer: enqueueMessage:sync wait ok 2022-05-12 18:02:21.502 227-13191/? D/RockFFPlayer: send do-prepare message ok 2022-05-12 18:02:21.502 227-13191/? I/RockFFPlayer: decodeMovie:getCurrentStatus() = 8 2022-05-12 18:02:21.502 227-13191/? D/FFPlayerHelper: MediaServer is SP_FOREGROUND, tid=13191 2022-05-12 18:02:21.502 12617-12749/com.gemvary.manager D/MediaPlayer: setSubtitleAnchor in MediaPlayer 2022-05-12 18:02:21.503 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0] [788] bytes parsed 2022-05-12 18:02:21.504 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0] read [283] bytes of body from [192.192.255.103:5060] 2022-05-12 18:02:21.504 12617-12617/com.gemvary.manager I/tag_linphone: Found transaction matching response. 2022-05-12 18:02:21.504 227-13191/? E/RockFFPlayer: file_read: offset=931705, c->offset=927256, c->length=4449 2022-05-12 18:02:21.504 227-13191/? E/RockFFPlayer: file_read: offset=931705, c->offset=927256, c->length=4449 2022-05-12 18:02:21.504 12617-12617/com.gemvary.manager I/tag_linphone: Changing [client] [INVITE] transaction [0x92cbdc90], from state [PROCEEDING] to [ACCEPTED] 2022-05-12 18:02:21.504 227-13191/? E/RockFFPlayer: file_read: offset=931705, c->offset=927256, c->length=4449 2022-05-12 18:02:21.504 227-13191/? D/RockFFPlayer: url_feof=1,err=0 2022-05-12 18:02:21.504 227-13191/? I/RockFFPlayer: av_read_frame end of stream ret = -541478725 2022-05-12 18:02:21.504 12617-12617/com.gemvary.manager I/tag_linphone: Dialog [0x8de87180]: now updated by transaction [0x92cbdc90]. 2022-05-12 18:02:21.505 227-843/? E/RockFFPlayer: doGetTrackInfo: track size = 1 2022-05-12 18:02:21.506 12617-12617/com.gemvary.manager I/tag_linphone: op [0x943ce700] : set_or_update_dialog() current=[0x8de87180] new=[0x8de87180] 2022-05-12 18:02:21.506 12617-12617/com.gemvary.manager I/tag_linphone: Op [0x943ce700] receiving call response [200], dialog is [0x8de87180] in state [BELLE_SIP_DIALOG_CONFIRMED] 2022-05-12 18:02:21.507 227-843/? D/RockFFPlayer: setLooping(): loop = 0 2022-05-12 18:02:21.508 227-843/? D/RockFFPlayer: setLooping(): loop = 0 2022-05-12 18:02:21.509 227-843/? D/RockFFPlayer: setLooping(): loop = 0 2022-05-12 18:02:21.509 227-843/? D/RockFFPlayer: start: in 2022-05-12 18:02:21.509 227-843/? D/RockFFPlayerBase: dumpStatus(): Preapared 2022-05-12 18:02:21.509 227-843/? D/RockFFPlayer: enter enqueueMessage 2022-05-12 18:02:21.509 227-843/? D/RockFFPlayer: enqueueMessage:1 2022-05-12 18:02:21.509 12617-12617/com.gemvary.manager I/tag_linphone: Found payload PCMU/8000 fmtp= 2022-05-12 18:02:21.509 227-843/? D/RockFFPlayer: enqueueMessage:2 2022-05-12 18:02:21.509 227-843/? D/RockFFPlayer: enqueueMessage:3 2022-05-12 18:02:21.509 227-843/? D/RockFFPlayer: enqueueMessage:Message 1,set signal 2022-05-12 18:02:21.509 12617-12617/com.gemvary.manager I/tag_linphone: Found payload telephone-event/8000 fmtp=0-16 2022-05-12 18:02:21.510 227-843/? D/RockFFPlayer: enqueueMessage:sync wait 2022-05-12 18:02:21.510 227-13190/? D/RockFFPlayer: deliverMessage 2022-05-12 18:02:21.510 227-13190/? D/RockFFPlayer: run : dequeueMessage Message 2 2022-05-12 18:02:21.510 227-13190/? D/RockFFPlayer: CMD_START 2022-05-12 18:02:21.510 227-13190/? D/RockFFPlayer: doStart()**** 2022-05-12 18:02:21.510 12617-12617/com.gemvary.manager I/tag_linphone: Doing SDP offer/answer process of type outgoing 2022-05-12 18:02:21.510 227-13190/? D/RockFFPlayerBase: dumpStatus(): Preapared 2022-05-12 18:02:21.510 227-13190/? D/RockFFPlayerBase: dumpStatus(): Started 2022-05-12 18:02:21.510 227-13190/? D/RockFFPlayerBase: allocDecoderBuffer*** 2022-05-12 18:02:21.510 227-13190/? D/RockFFPlayerBase: allocDecoderBuffer**mNativeWindow == NULL 2022-05-12 18:02:21.510 227-13190/? D/RkAudioPlayer: audio mode: 0 2022-05-12 18:02:21.510 12617-12617/com.gemvary.manager I/tag_linphone: Processing for stream 0 2022-05-12 18:02:21.510 227-13190/? D/RkAudioPlayer: mAudioSink->open in mSampleRate = 44100 channels = 2 2022-05-12 18:02:21.510 12617-12617/com.gemvary.manager I/tag_linphone: Adding opus/48000 for compatibility, just in case. 2022-05-12 18:02:21.510 12617-12617/com.gemvary.manager I/tag_linphone: Adding speex/16000 for compatibility, just in case. 2022-05-12 18:02:21.511 12617-12617/com.gemvary.manager I/tag_linphone: Adding speex/8000 for compatibility, just in case. 2022-05-12 18:02:21.511 12617-12617/com.gemvary.manager I/tag_linphone: Adding PCMA/8000 for compatibility, just in case. 2022-05-12 18:02:21.511 12617-12617/com.gemvary.manager I/tag_linphone: Adding G729/8000 for compatibility, just in case. 2022-05-12 18:02:21.511 12617-12617/com.gemvary.manager I/tag_linphone: Adding telephone-event/48000 for compatibility, just in case. 2022-05-12 18:02:21.511 12617-12617/com.gemvary.manager I/tag_linphone: Adding telephone-event/16000 for compatibility, just in case. 2022-05-12 18:02:21.511 12617-12617/com.gemvary.manager I/tag_linphone: CallSession [0x92c9aabc] moving from state State::OutgoingRinging to State::Connected 2022-05-12 18:02:21.513 12617-12617/com.gemvary.manager D/MyLinphone: callStateChanged, callId: outdoor@192.192.255.103, state: Connected, message: Connected 2022-05-12 18:02:21.513 227-13190/? D/AudioTrack: Client defaulted notificationFrames to 2565 for frameCount 5130 2022-05-12 18:02:21.513 12617-12617/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.logBoth(MyLinphone.java:820) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:843) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) callStateChanged, callId: outdoor@192.192.255.103, state: Connected, message: Connected 2022-05-12 18:02:21.514 12617-12617/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:859) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) ├ org.linphone.core.CoreImpl.iterate(Core.java:4591) └ org.linphone.LinphoneManager$2$1.run(LinphoneManager.java:876) call state connected, isCallOut = true 2022-05-12 18:02:21.515 227-13190/? D/FFMpegThread: startAsync:threadname=DecoderAudio 2022-05-12 18:02:21.515 12617-12617/com.gemvary.manager D/CallManager: Thread: main ├ com.gemvary.call.sdk.base.CallManager.connected(CallManager.java:875) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:862) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) call connected 2022-05-12 18:02:21.515 227-13194/? D/FFMpegThread: DecoderAudio:starting thread 2022-05-12 18:02:21.515 227-13194/? D/AudioDecoder: Prepare audio decoder in 2022-05-12 18:02:21.515 12617-12617/com.gemvary.manager I/MyZGCall: zg_info ZGEngine is success 2022-05-12 18:02:21.515 227-13190/? D/RockFFPlayer: No surface to delay start video decoder thread 2022-05-12 18:02:21.515 227-13190/? D/RockFFPlayer: doStart(): mSeekTimeUs = -1 2022-05-12 18:02:21.515 227-13190/? D/RockFFPlayer: doStart ok 2022-05-12 18:02:21.515 227-13190/? D/RockFFPlayer: run : dequeueMessage Message 2 OK 2022-05-12 18:02:21.515 227-13190/? D/RockFFPlayer: Messagecomplete type [2] 2022-05-12 18:02:21.516 227-13190/? D/RockFFPlayer: run : Message 2 complete 2022-05-12 18:02:21.516 227-843/? D/RockFFPlayer: enqueueMessage:sync wait ok 2022-05-12 18:02:21.516 12617-12617/com.gemvary.manager D/CallManager: Thread: main ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:785) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:775) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:769) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:764) └ com.gemvary.call.sdk.base.CallManager.connected(CallManager.java:889)

callId: outdoor@192.192.255.103, state: Connected, reason: None, ExtraStatus{targetPending=false, transferType=UNKNOWN, cloudVoipNotConnected=false, unitNo='null', roomNo='null', preparationError=null, hangupReason=null}

2022-05-12 18:02:21.516 227-13194/? D/AudioDecoder: Prepare audio decoder ok 2022-05-12 18:02:21.516 227-13194/? E/TraceThread: DecoderAudio(tid = 2753038624)--> [UID=511ddca3] DecoderAudio::decode 2022-05-12 18:02:21.517 227-13193/? D/RockFFClock: MasterClock - sync pts -215021, mPauseSysClock -1 2022-05-12 18:02:21.517 227-13193/? D/AudioSink: callbackwrapper: EVENT_UNDERRUN (discarded) 2022-05-12 18:02:21.517 227-843/? D/RockFFPlayer: stop 2022-05-12 18:02:21.517 227-843/? D/RockFFPlayerBase: dumpStatus(): Started 2022-05-12 18:02:21.517 227-843/? D/RockFFPlayer: stop_l: in 2022-05-12 18:02:21.517 227-843/? D/RockFFPlayer: enter enqueueMessage 2022-05-12 18:02:21.518 227-843/? D/RockFFPlayer: enqueueMessage:1 2022-05-12 18:02:21.518 227-843/? D/RockFFPlayer: enqueueMessage:2 2022-05-12 18:02:21.518 227-843/? D/RockFFPlayer: enqueueMessage:3 2022-05-12 18:02:21.518 227-843/? D/RockFFPlayer: enqueueMessage:Message 1,set signal 2022-05-12 18:02:21.518 227-843/? D/RockFFPlayer: enqueueMessage:sync wait 2022-05-12 18:02:21.518 227-13190/? D/RockFFPlayer: deliverMessage 2022-05-12 18:02:21.518 227-13190/? D/RockFFPlayer: run : dequeueMessage Message 4 2022-05-12 18:02:21.518 227-13190/? D/RockFFPlayer: CMD_STOP 2022-05-12 18:02:21.518 227-13190/? D/RockFFPlayer: doStop: in 2022-05-12 18:02:21.518 227-13190/? D/RockFFPlayerBase: dumpStatus(): Started 2022-05-12 18:02:21.518 227-13190/? D/RockFFPlayerBase: dumpStatus(): Stopped 2022-05-12 18:02:21.518 227-13190/? D/RockFFPlayer: doRelease: in 2022-05-12 18:02:21.518 227-13190/? I/FFIDecoder: waiting on end of decoder thread 2022-05-12 18:02:21.518 227-13190/? E/FFMpegThread: DecoderAudio,wait 2022-05-12 18:02:21.518 227-13190/? D/FFMpegThread: DecoderAudio:wait at pthread_join 2022-05-12 18:02:21.519 227-13190/? E/TraceThread: DecoderAudio(tid = 2753038624)--> [UID=511ddca3] pthread_join begin 2022-05-12 18:02:21.519 227-13191/? I/RockFFPlayer: readthread end of playing 2022-05-12 18:02:21.520 227-13194/? I/AudioDecoder: decoding audio ended 2022-05-12 18:02:21.520 227-13194/? D/FFMpegThread: DecoderAudio:thread ended 2022-05-12 18:02:21.520 227-13190/? E/TraceThread: DecoderAudio(tid = 2753038624)--> [UID=511ddca3] pthread_join end 2022-05-12 18:02:21.521 227-13190/? E/FFMpegThread: DecoderAudio:wait ok mThreadStatus=-1 2022-05-12 18:02:21.521 227-13190/? E/RockFFPlayerBase: FFMPlayerBase::flushRenderQueue in 2022-05-12 18:02:21.521 227-13190/? E/RockFFPlayerBase: FFMPlayerBase::flushRenderQueue out 2022-05-12 18:02:21.521 227-13190/? D/RockFFPlayer: doRelease: ok 2022-05-12 18:02:21.521 227-13190/? D/RockFFPlayer: doStop: ok 2022-05-12 18:02:21.521 227-13190/? D/RockFFPlayer: run : dequeueMessage Message 4 OK 2022-05-12 18:02:21.521 227-13190/? D/RockFFPlayer: Messagecomplete type [4] 2022-05-12 18:02:21.521 227-13190/? D/RockFFPlayer: run : Message 4 complete 2022-05-12 18:02:21.521 227-843/? D/RockFFPlayer: enqueueMessage:sync wait ok 2022-05-12 18:02:21.521 227-843/? D/RockFFPlayer: stop_l: clear queue ok 2022-05-12 18:02:21.521 227-843/? D/RockFFPlayer: stop_l: create cancel thread ok 2022-05-12 18:02:21.521 227-13195/? D/RockFFPlayer: CancelThread: in 2022-05-12 18:02:21.521 227-843/? D/RockFFPlayer: stop_l: ok 2022-05-12 18:02:21.521 227-13195/? D/RockFFPlayer: processAsyncExit: wait message thread 2022-05-12 18:02:21.522 227-843/? D/RockFFPlayer: reset: in 2022-05-12 18:02:21.522 227-843/? D/RockFFPlayerBase: dumpStatus(): Stopped 2022-05-12 18:02:21.522 227-843/? D/RockFFPlayer: enter enqueueMessage 2022-05-12 18:02:21.522 227-843/? D/RockFFPlayer: enqueueMessage:1 2022-05-12 18:02:21.522 227-843/? D/RockFFPlayer: enqueueMessage:2 2022-05-12 18:02:21.522 227-843/? D/RockFFPlayer: enqueueMessage:3 2022-05-12 18:02:21.522 227-843/? D/RockFFPlayer: enqueueMessage:Message 1,set signal 2022-05-12 18:02:21.522 227-843/? D/RockFFPlayer: enqueueMessage:sync wait 2022-05-12 18:02:21.522 227-13190/? D/RockFFPlayer: deliverMessage 2022-05-12 18:02:21.522 227-13190/? D/RockFFPlayer: run : dequeueMessage Message 11 2022-05-12 18:02:21.522 227-13190/? D/RockFFPlayer: CMD_RESET 2022-05-12 18:02:21.522 227-13190/? D/RockFFPlayerBase: dumpStatus(): Idle 2022-05-12 18:02:21.522 227-13190/? D/RockFFPlayer: doReset ok 2022-05-12 18:02:21.522 227-13190/? D/RockFFPlayer: run : dequeueMessage Message 11 OK 2022-05-12 18:02:21.523 227-13190/? D/RockFFPlayer: Messagecomplete type [11] 2022-05-12 18:02:21.523 227-843/? D/RockFFPlayer: enqueueMessage:sync wait ok 2022-05-12 18:02:21.523 227-13190/? D/RockFFPlayer: run : Message 11 complete 2022-05-12 18:02:21.523 227-843/? D/RockFFPlayer: reset: do reset ok 2022-05-12 18:02:21.523 227-13190/? D/RockFFPlayer: signal thread out 2022-05-12 18:02:21.523 227-13190/? D/RockFFPlayer: sched_thread out 2022-05-12 18:02:21.523 227-843/? D/RockFFPlayer: stop:wait is ok 2022-05-12 18:02:21.523 227-843/? D/RockFFPlayer: stop:ok 2022-05-12 18:02:21.523 227-843/? D/RockFFPlayer: ~RkMessageQueue 2022-05-12 18:02:21.523 227-843/? D/RockFFPlayer: reset: reset ok 2022-05-12 18:02:21.523 227-13195/? D/FFMpegThread: DecoderAudio:~PlayerThread enter mThreadStatus=-1 2022-05-12 18:02:21.524 227-812/? D/RockFFPlayer: reset: in 2022-05-12 18:02:21.524 227-812/? D/RockFFPlayerBase: dumpStatus(): Idle 2022-05-12 18:02:21.524 227-812/? D/RockFFPlayer: reset: in idle status, already reset 2022-05-12 18:02:21.524 227-812/? E/FFPlayer: ~FFPlayer 2022-05-12 18:02:21.524 227-812/? D/RockFFPlayer: reset: in 2022-05-12 18:02:21.524 227-812/? D/RockFFPlayerBase: dumpStatus(): Idle 2022-05-12 18:02:21.524 227-812/? D/RockFFPlayer: reset: in idle status, already reset 2022-05-12 18:02:21.524 227-812/? D/RockFFPlayer: ~FFMPlayer in 2022-05-12 18:02:21.524 227-812/? D/RockFFPlayer: reset: in 2022-05-12 18:02:21.524 227-812/? D/RockFFPlayerBase: dumpStatus(): Idle 2022-05-12 18:02:21.524 227-812/? D/RockFFPlayer: reset: in idle status, already reset 2022-05-12 18:02:21.529 227-13195/? E/RockFFPlayerBase: closeContext : in 2022-05-12 18:02:21.529 227-13195/? E/RockFFPlayerBase: closeContext : out 2022-05-12 18:02:21.529 227-13195/? D/RockFFPlayer: CancelThread: ok 2022-05-12 18:02:21.530 227-812/? D/RockFFPlayer: ~FFMediaSource 2022-05-12 18:02:21.530 227-812/? D/RockFFPlayer: FFMediaSource context null. 2022-05-12 18:02:21.530 227-812/? E/TraceThread: VsyncReciver(tid = 2767210784)--> [UID=511ddca3] pthread_join begin-->player = 0xa5447000 2022-05-12 18:02:21.544 227-812/? E/TraceThread: VsyncReciver(tid = 2767210784)--> [UID=511ddca3] pthread_join end-->player = 0xa5447000 2022-05-12 18:02:21.545 227-812/? D/VsyncReciver: stop_l:vsyncThread down 2022-05-12 18:02:21.545 227-812/? E/TraceThread: RockFFPlayer(tid = 2770344224)--> [UID=511ddca3] ~FFMPlayer Destruction; object=0xa5447000 2022-05-12 18:02:21.545 227-812/? D/RockFFPlayer: ~FFMPlayer ok 2022-05-12 18:02:21.545 227-812/? D/RockFFPlayerBase: ~FFMPlayerBase(():mDequeueThread down 2022-05-12 18:02:21.545 12617-13008/com.gemvary.manager I/tag_linphone: First estimation 2022-05-12 18:02:21.545 227-812/? D/RockFFPlayerBase: ~RkMessageHandler 2022-05-12 18:02:21.547 12617-12617/com.gemvary.manager D/OutgoingActivity: Thread: main ├ com.gemvary.manager.activity.OutgoingActivity.stopRinging(OutgoingActivity.java:411) ├ com.gemvary.manager.activity.OutgoingActivity.lambda$onCreate$6$OutgoingActivity(OutgoingActivity.java:232) ├ com.gemvary.manager.activity.-$$Lambda$OutgoingActivity$wsNkDazuaV1TG9LxuYSo38yj7TI.onCallStateChanged(lambda) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:788) └ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:775)

is still singlePlaying? false

2022-05-12 18:02:21.549 12617-12617/com.gemvary.manager I/OutgoingActivity: Thread: main ├ com.gemvary.manager.activity.OutgoingActivity.setStreamVolume(OutgoingActivity.java:432) ├ com.gemvary.manager.activity.OutgoingActivity.lambda$onCreate$6$OutgoingActivity(OutgoingActivity.java:235) ├ com.gemvary.manager.activity.-$$Lambda$OutgoingActivity$wsNkDazuaV1TG9LxuYSo38yj7TI.onCallStateChanged(lambda) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:788) └ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:775) 4 2022-05-12 18:02:21.553 12617-12617/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:865) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) ├ org.linphone.core.CoreImpl.iterate(Core.java:4591) └ org.linphone.LinphoneManager$2$1.run(LinphoneManager.java:876) getCallAudioSwitch = false 2022-05-12 18:02:21.553 12617-12617/com.gemvary.manager D/MyLinphone: beingConnectedThenCancelOtherCalls 2022-05-12 18:02:21.553 12617-12617/com.gemvary.manager D/MyLinphone: number of calls in total: 1 2022-05-12 18:02:21.553 12617-12617/com.gemvary.manager D/MyLinphone: no other calls 2022-05-12 18:02:21.668 216-303/? D/AudioHardwareTiny: out_set_parameters: kvpairs = routing=2 2022-05-12 18:02:21.672 12617-12617/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:888) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) ├ org.linphone.core.CoreImpl.iterate(Core.java:4591) └ org.linphone.LinphoneManager$2$1.run(LinphoneManager.java:876) when connect,enableMic true 2022-05-12 18:02:21.678 12617-12617/com.gemvary.manager I/tag_linphone: This device (rockchip/GRG-S5/rk3288) says it has no built-in echo canceller. 2022-05-12 18:02:21.678 12617-12617/com.gemvary.manager I/tag_linphone: No information available for [rockchip/GRG-S5/rk3288], 2022-05-12 18:02:21.679 12617-12617/com.gemvary.manager I/tag_linphone: Sound device information for [rockchip/GRG-S5/rk3288] is: builtin=[no], delay=[250] ms 2022-05-12 18:02:21.679 12617-12617/com.gemvary.manager W/tag_linphone: Could not apply gain on sent RTP packets: gain control wasn't activated. Use audio_stream_enable_gain_control() before starting the stream. 2022-05-12 18:02:21.679 12617-12617/com.gemvary.manager I/tag_linphone: Linphone core [0x93cd1780] notified [call_state_changed] 2022-05-12 18:02:21.679 12617-12617/com.gemvary.manager I/tag_linphone: startStreams() CallSession=[0x92c9aabc] local upload_bandwidth=[0] kbit/s; local download_bandwidth=[0] kbit/s 2022-05-12 18:02:21.679 12617-12617/com.gemvary.manager I/tag_linphone: Audio bandwidth for CallSession [0x92c9aabc] is 80 2022-05-12 18:02:21.680 12617-12617/com.gemvary.manager I/tag_linphone: RtpSession [0x92d73600] sending to rtp 192.192.255.103:4036 rtcp 192.192.255.103:4037 2022-05-12 18:02:21.680 12617-12617/com.gemvary.manager I/tag_linphone: Stun packet sent for session [0x92d73600] 2022-05-12 18:02:21.680 12617-12617/com.gemvary.manager I/tag_linphone: Stun packet sent on rtcp for session [0x92d73600] 2022-05-12 18:02:21.680 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSRtpRecv:0x8f158458,0-->MSVoidSink:0x8f158490,0 2022-05-12 18:02:21.680 12617-12617/com.gemvary.manager I/tag_linphone: fqztemp mode [0] 2022-05-12 18:02:21.681 12617-12617/com.gemvary.manager I/tag_linphone: fqztemp use_ng=1 2022-05-12 18:02:21.681 12617-12617/com.gemvary.manager I/tag_linphone: fqztemp 63 audiostream.c use_ng=1 2022-05-12 18:02:21.681 12617-12617/com.gemvary.manager I/tag_linphone: speex_lib_ctl init with neon ? 1 2022-05-12 18:02:21.681 12617-12617/com.gemvary.manager I/tag_linphone: speex_lib_ctl init with neon ? 1 2022-05-12 18:02:21.681 12617-12617/com.gemvary.manager I/tag_linphone: Setting echo canceller delay with value configured by application. 2022-05-12 18:02:21.681 12617-12617/com.gemvary.manager I/tag_linphone: target bitrate not set for stream [0x8f455500] using payload's bitrate is 80000 2022-05-12 18:02:21.681 12617-12617/com.gemvary.manager I/tag_linphone: Setting audio encoder network bitrate to [80000] on stream [0x8f455500] 2022-05-12 18:02:21.691 12617-12617/com.gemvary.manager I/tag_linphone: This device (rockchip/GRG-S5/rk3288) says it has no built-in echo canceller. 2022-05-12 18:02:21.692 12617-12617/com.gemvary.manager I/tag_linphone: No information available for [rockchip/GRG-S5/rk3288], 2022-05-12 18:02:21.692 12617-12617/com.gemvary.manager I/tag_linphone: Sound device information for [rockchip/GRG-S5/rk3288] is: builtin=[no], delay=[250] ms 2022-05-12 18:02:21.697 12617-12617/com.gemvary.manager I/tag_linphone: This device (rockchip/GRG-S5/rk3288) says it has no built-in echo canceller. 2022-05-12 18:02:21.698 12617-12617/com.gemvary.manager I/tag_linphone: No information available for [rockchip/GRG-S5/rk3288], 2022-05-12 18:02:21.698 12617-12617/com.gemvary.manager I/tag_linphone: Sound device information for [rockchip/GRG-S5/rk3288] is: builtin=[no], delay=[250] ms 2022-05-12 18:02:21.698 12617-12617/com.gemvary.manager I/tag_linphone: MSVolume set gain to [0.000000 db], [1.000000] linear 2022-05-12 18:02:21.705 12617-12617/com.gemvary.manager I/tag_linphone: This device (rockchip/GRG-S5/rk3288) says it has no built-in echo canceller. 2022-05-12 18:02:21.705 12617-12617/com.gemvary.manager I/tag_linphone: No information available for [rockchip/GRG-S5/rk3288], 2022-05-12 18:02:21.705 12617-12617/com.gemvary.manager I/tag_linphone: Sound device information for [rockchip/GRG-S5/rk3288] is: builtin=[no], delay=[250] ms 2022-05-12 18:02:21.705 12617-12617/com.gemvary.manager I/tag_linphone: MSVolume set gain to [0.000000 db], [1.000000] linear 2022-05-12 18:02:21.705 12617-12617/com.gemvary.manager I/tag_linphone: configuring MSOpenSLESRecorder:0x8f158458-->MSUlawEnc:0x8f158c70 from rate [44100] to rate [8000] and from channel [1] to channel [1] 2022-05-12 18:02:21.706 12617-12617/com.gemvary.manager I/tag_linphone: configuring MSUlawDec:0x8f158ca8-->MSOpenSLESPlayer:0x8f158490 from rate [8000] to rate [44100] and from channel [1] to channel [1] 2022-05-12 18:02:21.706 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSOpenSLESRecorder:0x8f158458,0-->MSResample:0x8f158d50,0 2022-05-12 18:02:21.706 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSResample:0x8f158d50,0-->MSEqualizer:0x8f158df8,0 2022-05-12 18:02:21.706 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSEqualizer:0x8f158df8,0-->MSWebRTCAECM:0x947af388,1 2022-05-12 18:02:21.706 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSWebRTCAECM:0x947af388,1-->MSVolume:0x8f158ce0,0 2022-05-12 18:02:21.706 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSVolume:0x8f158ce0,0-->MSAudioMixer:0x8f158dc0,0 2022-05-12 18:02:21.706 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSAudioMixer:0x8f158dc0,0-->MSUlawEnc:0x8f158c70,0 2022-05-12 18:02:21.707 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSUlawEnc:0x8f158c70,0-->MSRtpSend:0x947af3c0,0 2022-05-12 18:02:21.707 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSRtpRecv:0x8f158c00,0-->MSUlawDec:0x8f158ca8,0 2022-05-12 18:02:21.707 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSUlawDec:0x8f158ca8,0-->MSGenericPLC:0x8f158e68,0 2022-05-12 18:02:21.707 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSGenericPLC:0x8f158e68,0-->MSAudioFlowControl:0x8f158ed8,0 2022-05-12 18:02:21.707 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSAudioFlowControl:0x8f158ed8,0-->MSDtmfGen:0x8f158c38,0 2022-05-12 18:02:21.707 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSDtmfGen:0x8f158c38,0-->MSVolume:0x8f158d18,0 2022-05-12 18:02:21.707 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSVolume:0x8f158d18,0-->MSEqualizer:0x8f158e30,0 2022-05-12 18:02:21.708 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSEqualizer:0x8f158e30,0-->MSAudioMixer:0x8f158f10,0 2022-05-12 18:02:21.708 12617-12617/com.gemvary.manager I/tag_linphone: speex_lib_ctl init with neon ? 1 2022-05-12 18:02:21.708 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSFilePlayer:0x8f158f48,0-->MSResample:0x8f158fb8,0 2022-05-12 18:02:21.708 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSResample:0x8f158fb8,0-->MSAudioMixer:0x8f158f10,1 2022-05-12 18:02:21.708 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSAudioMixer:0x8f158f10,0-->MSWebRTCAECM:0x947af388,0 2022-05-12 18:02:21.708 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSWebRTCAECM:0x947af388,0-->MSResample:0x8f158d88,0 2022-05-12 18:02:21.708 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_link: MSResample:0x8f158d88,0-->MSOpenSLESPlayer:0x8f158490,0 2022-05-12 18:02:21.708 12617-12617/com.gemvary.manager W/libOpenSLES: Conversion from OpenSL ES positional channel mask 0x4 to Android mask 0 loses channels 2022-05-12 18:02:21.709 12617-12617/com.gemvary.manager I/libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x4, using default mask 0x10 based on channel count of 1) 2022-05-12 18:02:21.709 216-312/? E/AudioHardwareTiny: adev_open_input_stream:channel is not support 2022-05-12 18:02:21.710 216-312/? D/AudioHardwareTiny: in->mSpeexFrameSize:128 2022-05-12 18:02:21.711 216-13198/? I/AudioFlinger: AudioFlinger's thread 0xb18038c0 ready to run 2022-05-12 18:02:21.716 216-621/? W/AudioFlinger: createRecordTrack_l(): mismatch between requested flags (00000005) and input flags (00000001) 2022-05-12 18:02:21.717 12617-12617/com.gemvary.manager W/AudioRecord: AUDIO_INPUT_FLAG_FAST denied by server; frameCount 0 2022-05-12 18:02:21.718 216-7905/? E/AudioHardwareTiny: adev_open_input_stream:channel is not support 2022-05-12 18:02:21.718 216-7905/? D/AudioHardwareTiny: in->mSpeexFrameSize:512 2022-05-12 18:02:21.720 216-13199/? I/AudioFlinger: AudioFlinger's thread 0xb1983980 ready to run 2022-05-12 18:02:21.730 216-13199/? D/AudioHardwareTiny: Device : 0x1 2022-05-12 18:02:21.730 216-13199/? D/AudioHardwareTiny: SampleRate : 0 2022-05-12 18:02:21.730 12617-12617/com.gemvary.manager I/tag_linphone: Initializing speex resampler in mode [voip] 2022-05-12 18:02:21.730 216-13199/? D/AudioHardwareTiny: Channels : 4 2022-05-12 18:02:21.730 216-13199/? D/AudioHardwareTiny: Formate : 0 2022-05-12 18:02:21.730 216-13199/? D/AudioHardwareTiny: PreiodSize : 0 2022-05-12 18:02:21.730 216-13199/? E/AudioHardwareTiny: getInputRouteFromDevice:device:80000004 2022-05-12 18:02:21.730 216-13199/? D/alsa_route: route_info->sound_card 0, route_info->devices 0
2022-05-12 18:02:21.730 216-13199/? D/alsa_route: route_set_controls() set route 21 2022-05-12 18:02:21.731 12617-12617/com.gemvary.manager I/tag_linphone: Initializing WebRTC echo canceler with framesize=80, delay_ms=396, delay_samples=3168 2022-05-12 18:02:21.732 12617-12617/com.gemvary.manager I/tag_linphone: ms_devices_info_get_model getdevicemodel= [GRG-S5] 2022-05-12 18:02:21.732 12617-12617/com.gemvary.manager I/tag_linphone: Initializing speex resampler in mode [voip] 2022-05-12 18:02:21.732 12617-12617/com.gemvary.manager I/tag_linphone: Initializing speex resampler in mode [voip] 2022-05-12 18:02:21.733 12617-12617/com.gemvary.manager I/libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x4, using default mask 0x1 based on channel count of 1) 2022-05-12 18:02:21.733 216-312/? W/AudioFlinger: createTrack_l(): mismatch between requested flags (00000104) and output flags (00000006) 2022-05-12 18:02:21.734 12617-12617/com.gemvary.manager D/AudioTrack: Client defaulted notificationFrames to 512 for frameCount 1024 2022-05-12 18:02:21.736 12617-12617/com.gemvary.manager I/tag_linphone: Filter MSRtpRecv is already being scheduled; nothing to do. 2022-05-12 18:02:21.740 12617-12617/com.gemvary.manager I/tag_linphone: This device (rockchip/GRG-S5/rk3288) says it has no built-in echo canceller. 2022-05-12 18:02:21.741 12617-12617/com.gemvary.manager I/tag_linphone: No information available for [rockchip/GRG-S5/rk3288], 2022-05-12 18:02:21.741 12617-12617/com.gemvary.manager I/tag_linphone: Sound device information for [rockchip/GRG-S5/rk3288] is: builtin=[no], delay=[250] ms 2022-05-12 18:02:21.741 12617-12617/com.gemvary.manager I/tag_linphone: MSVolume set gain to [-2.000000 db], [0.630957] linear 2022-05-12 18:02:21.741 12617-12617/com.gemvary.manager I/tag_linphone: MSVolume set gain to [1.000000 db], [1.258925] linear 2022-05-12 18:02:21.741 12617-12617/com.gemvary.manager I/tag_linphone: No valid video stream defined 2022-05-12 18:02:21.742 12617-12617/com.gemvary.manager I/tag_linphone: CallSession[0x92c9aabc] : payload type 0 PCMU/8000 fmtp= added to frozen list 2022-05-12 18:02:21.742 12617-12617/com.gemvary.manager I/tag_linphone: CallSession[0x92c9aabc] : payload type 100 telephone-event/8000 fmtp= added to frozen list 2022-05-12 18:02:21.742 12617-12617/com.gemvary.manager I/tag_linphone: CallSession[0x92c9aabc] : payload type 96 opus/48000 fmtp=useinbandfec=1 added to frozen list 2022-05-12 18:02:21.742 12617-12617/com.gemvary.manager I/tag_linphone: CallSession[0x92c9aabc] : payload type 97 speex/16000 fmtp=vbr=on added to frozen list 2022-05-12 18:02:21.742 12617-12617/com.gemvary.manager I/tag_linphone: CallSession[0x92c9aabc] : payload type 98 speex/8000 fmtp=vbr=on added to frozen list 2022-05-12 18:02:21.742 12617-12617/com.gemvary.manager I/tag_linphone: CallSession[0x92c9aabc] : payload type 8 PCMA/8000 fmtp= added to frozen list 2022-05-12 18:02:21.742 12617-12617/com.gemvary.manager I/tag_linphone: CallSession[0x92c9aabc] : payload type 18 G729/8000 fmtp=annexb=yes added to frozen list 2022-05-12 18:02:21.743 12617-12617/com.gemvary.manager I/tag_linphone: CallSession[0x92c9aabc] : payload type 101 telephone-event/48000 fmtp= added to frozen list 2022-05-12 18:02:21.743 12617-12617/com.gemvary.manager I/tag_linphone: CallSession[0x92c9aabc] : payload type 99 telephone-event/16000 fmtp= added to frozen list 2022-05-12 18:02:21.743 12617-12617/com.gemvary.manager I/tag_linphone: audio stream index found: 0, updating main audio stream index 2022-05-12 18:02:21.743 12617-12617/com.gemvary.manager I/tag_linphone: CallSession [0x92c9aabc] moving from state State::Connected to State::StreamsRunning 2022-05-12 18:02:21.744 12617-12617/com.gemvary.manager D/MyLinphone: callStateChanged, callId: outdoor@192.192.255.103, state: StreamsRunning, message: Streams running 2022-05-12 18:02:21.745 12617-12617/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.logBoth(MyLinphone.java:820) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:843) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) callStateChanged, callId: outdoor@192.192.255.103, state: StreamsRunning, message: Streams running 2022-05-12 18:02:21.745 12617-12617/com.gemvary.manager I/tag_linphone: Linphone core [0x93cd1780] notified [call_state_changed] 2022-05-12 18:02:21.745 12617-12617/com.gemvary.manager I/tag_linphone: Computing branch id z9hG4bK.G6ck8EnMk for message sent statelessly 2022-05-12 18:02:21.745 12617-13008/com.gemvary.manager I/tag_linphone: MSAudioMixer [0x8f158f10] is entering bypass mode. 2022-05-12 18:02:21.746 12617-13008/com.gemvary.manager W/tag_linphone: Getting reference signal but no echo to synchronize on. 2022-05-12 18:02:21.746 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0]: message sent to [UDP://192.192.255.103:5060], size: [625] bytes 2022-05-12 18:02:21.746 12617-12617/com.gemvary.manager I/tag_linphone: ACK sip:192.192.255.103:5060 SIP/2.0 2022-05-12 18:02:21.746 12617-13008/com.gemvary.manager I/tag_linphone: Stun packet sent for session [0x92d73600] 2022-05-12 18:02:21.746 12617-12617/com.gemvary.manager I/tag_linphone: Via: SIP/2.0/UDP 192.192.255.42:5060;rport;branch=z9hG4bK.G6ck8EnMk 2022-05-12 18:02:21.746 12617-12617/com.gemvary.manager I/tag_linphone: From: "{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42;tag=LNJh4lXl5 2022-05-12 18:02:21.746 12617-12617/com.gemvary.manager I/tag_linphone: To: sip:outdoor@192.192.255.103;tag=IAH-RgNmQ--NwvhJT.00T7lICJKfTRr. 2022-05-12 18:02:21.746 12617-12617/com.gemvary.manager I/tag_linphone: CSeq: 20 ACK 2022-05-12 18:02:21.746 12617-12617/com.gemvary.manager I/tag_linphone: Call-ID: o6taYiVcUo 2022-05-12 18:02:21.746 12617-12617/com.gemvary.manager I/tag_linphone: Max-Forwards: 70 2022-05-12 18:02:21.746 12617-12617/com.gemvary.manager I/tag_linphone: User-Agent: LinphoneAndroid/6.1.20220512170321 (belle-sip/1.6.3) 2022-05-12 18:02:21.746 12617-13008/com.gemvary.manager I/tag_linphone: Stun packet sent on rtcp for session [0x92d73600] 2022-05-12 18:02:21.746 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0]: ending recv background task with id=[100b2e]. 2022-05-12 18:02:21.747 12617-12617/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x100b2e] 2022-05-12 18:02:21.747 12617-12617/com.gemvary.manager I/tag_linphone: Garbage collecting unowned object of type belle_sip_hop_t 2022-05-12 18:02:21.747 12617-12617/com.gemvary.manager I/tag_linphone: Garbage collecting unowned object of type belle_sdp_session_description_t 2022-05-12 18:02:21.755 12617-13008/com.gemvary.manager I/tag_linphone: MSAudioMixer [0x8f158f10] is leaving bypass mode. 2022-05-12 18:02:21.755 12617-13008/com.gemvary.manager W/tag_linphone: Getting reference signal but no echo to synchronize on. 2022-05-12 18:02:21.756 12617-13008/com.gemvary.manager I/tag_linphone: MSAudioMixer [0x8f158dc0] is entering bypass mode. 2022-05-12 18:02:21.769 12617-13008/com.gemvary.manager W/tag_linphone: Getting reference signal but no echo to synchronize on. 2022-05-12 18:02:21.769 12617-13008/com.gemvary.manager W/tag_linphone: Not enough ref samples, using zeroes 2022-05-12 18:02:21.790 12617-13008/com.gemvary.manager I/tag_linphone: Samples are back. 2022-05-12 18:02:21.829 12617-13008/com.gemvary.manager W/tag_linphone: Not enough ref samples, using zeroes 2022-05-12 18:02:21.860 12617-13008/com.gemvary.manager I/tag_linphone: Samples are back. 2022-05-12 18:02:21.970 12617-12617/com.gemvary.manager I/tag_linphone: Changing [server] [BYE] transaction [0x8de83000], from state [COMPLETED] to [TERMINATED] 2022-05-12 18:02:21.970 12617-12617/com.gemvary.manager I/tag_linphone: Server BYE transaction [0x8de83000] terminated 2022-05-12 18:02:21.971 12617-12617/com.gemvary.manager I/tag_linphone: Destroying op [0x943ce280] of type [SalOpCall] 2022-05-12 18:02:21.971 12617-12617/com.gemvary.manager I/tag_linphone: Transaction [0x8eb7aac0] deleted 2022-05-12 18:02:21.972 12617-12617/com.gemvary.manager I/tag_linphone: transaction [0x8de83000]: ending transaction background task with id=[100b5a]. 2022-05-12 18:02:21.975 12617-12617/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x100b5a] 2022-05-12 18:02:21.975 12617-12617/com.gemvary.manager I/tag_linphone: Transaction [0x8de83000] deleted 2022-05-12 18:02:21.976 12617-12617/com.gemvary.manager I/tag_linphone: MSAudio stream [0x8f455500]: receiving RTCP SR 2022-05-12 18:02:21.976 12617-12617/com.gemvary.manager I/tag_linphone: Linphone core [0x93cd1780] notified [call_stats_updated] 2022-05-12 18:02:22.349 12617-13008/com.gemvary.manager I/tag_linphone: sound/wall clock skew is average=-3.13177 ms 2022-05-12 18:02:22.415 12617-12617/com.gemvary.manager I/tag_linphone: Bandwidth usage for CallSession [0x92c9aabc]: RTP audio=[d=73.93,u=72.80], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.48,u=1.15], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2022-05-12 18:02:22.416 12617-12617/com.gemvary.manager I/tag_linphone: Thread processing load: audio=16.1295 video=0 text=0 2022-05-12 18:02:22.749 12617-13008/com.gemvary.manager I/tag_linphone: MSAudioMixer [0x8f158f10] is entering bypass mode. 2022-05-12 18:02:22.895 12617-13196/com.gemvary.manager I/tag_linphone: sound/wall clock skew is average=-4.664634 ms 2022-05-12 18:02:23.401 12617-12617/com.gemvary.manager I/tag_linphone: Bandwidth usage for CallSession [0x92c9aabc]: RTP audio=[d=80.70,u=80.08], video=[d=0.00,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 2022-05-12 18:02:23.402 12617-12617/com.gemvary.manager I/tag_linphone: Thread processing load: audio=9.1612 video=0 text=0 2022-05-12 18:02:23.729 12617-13008/com.gemvary.manager I/tag_linphone: Sending RTCP SR compound message on session [0x92d73600]. 2022-05-12 18:02:23.746 12617-12617/com.gemvary.manager I/tag_linphone: MSAudio_stream_iterate[0x8f455500], local statistics available: Local current jitter buffer size: 58.9ms 2022-05-12 18:02:23.747 12617-12617/com.gemvary.manager I/tag_linphone: Linphone core [0x93cd1780] notified [call_stats_updated] 2022-05-12 18:02:24.056 12617-13196/com.gemvary.manager I/tag_linphone: sound/wall clock skew is average=-6.635797 ms 2022-05-12 18:02:24.396 12617-12617/com.gemvary.manager I/tag_linphone: Bandwidth usage for CallSession [0x92c9aabc]: RTP audio=[d=64.29,u=79.95], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.00,u=1.38], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2022-05-12 18:02:24.396 12617-12617/com.gemvary.manager I/tag_linphone: Thread processing load: audio=18.8745 video=0 text=0 2022-05-12 18:02:25.217 12617-13196/com.gemvary.manager I/tag_linphone: sound/wall clock skew is average=-7.093694 ms 2022-05-12 18:02:25.407 12617-12617/com.gemvary.manager I/tag_linphone: Bandwidth usage for CallSession [0x92c9aabc]: RTP audio=[d=78.60,u=78.40], video=[d=0.00,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 2022-05-12 18:02:25.407 12617-12617/com.gemvary.manager I/tag_linphone: Thread processing load: audio=14.0694 video=0 text=0 2022-05-12 18:02:25.871 12617-12617/com.gemvary.manager I/tag_linphone: MSAudio stream [0x8f455500]: receiving RTCP SR 2022-05-12 18:02:25.872 12617-12617/com.gemvary.manager I/tag_linphone: MSQualityIndicator[0x93c8e3a0][audio], remote statistics available: Loss rate : 1.0% Inter-arrival jitter: 5.9ms RT propagation : 7.2ms 2022-05-12 18:02:25.872 12617-12617/com.gemvary.manager I/tag_linphone: Linphone core [0x93cd1780] notified [call_stats_updated] 2022-05-12 18:02:26.378 12617-13196/com.gemvary.manager I/tag_linphone: sound/wall clock skew is average=-7.367238 ms 2022-05-12 18:02:26.398 12617-12617/com.gemvary.manager I/tag_linphone: Bandwidth usage for CallSession [0x92c9aabc]: RTP audio=[d=74.40,u=80.03], video=[d=0.00,u=0.00,ed=0.00], text=[d=0.00,u=0.00] kbits/sec RTCP audio=[d=0.29,u=0.00], video=[d=0.00,u=0.00], text=[d=0.00,u=0.00] kbits/sec 2022-05-12 18:02:26.399 12617-12617/com.gemvary.manager I/tag_linphone: Thread processing load: audio=16.3501 video=0 text=0 2022-05-12 18:02:26.559 12617-13008/com.gemvary.manager I/tag_linphone: jitter buffer stable: target-size: 59.999999 ms, effective-size: 58.878506 (min: 40 nom: 60, max: 500) 2022-05-12 18:02:26.559 12617-13008/com.gemvary.manager I/tag_linphone: jitter buffer rls stats: count=246, clockrate=8000, offset=43.8758 clock_ratio=0.999735, capped_offset=1963 capped_clock_ratio=0.999735, max_ts_deviation=114.000000 prev_max_ts_deviation=480.000000, deviation=114, RLS VARIABLES: P[0][0]=0.000000, P[1][0]=-0.000000, P[0][1]=-0.000000, P[1][1]=0.011899 2022-05-12 18:02:26.704 12617-12617/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x100ab2] 2022-05-12 18:02:26.704 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0]: starting recv background task with id=[100ab2]. 2022-05-12 18:02:26.705 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0]: received [631] new bytes from [UDP://192.192.255.103:5060]: 2022-05-12 18:02:26.706 12617-12617/com.gemvary.manager I/tag_linphone: BYE sip:linphone.android@192.192.255.42;transport=udp SIP/2.0 2022-05-12 18:02:26.706 12617-12617/com.gemvary.manager I/tag_linphone: Via: SIP/2.0/UDP 192.192.255.103:5060;rport;branch=z9hG4bKPjMlQZM5Q212y06rPODv4tgF1AL.Asr.JS 2022-05-12 18:02:26.706 12617-12617/com.gemvary.manager I/tag_linphone: Max-Forwards: 70 2022-05-12 18:02:26.706 12617-12617/com.gemvary.manager I/tag_linphone: From: sip:outdoor@192.192.255.103;tag=IAH-RgNmQ--NwvhJT.00T7lICJKfTRr. 2022-05-12 18:02:26.706 12617-12617/com.gemvary.manager I/tag_linphone: To: "{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42;tag=LNJh4lXl5 2022-05-12 18:02:26.706 12617-12617/com.gemvary.manager I/tag_linphone: Call-ID: o6taYiVcUo 2022-05-12 18:02:26.706 12617-12617/com.gemvary.manager I/tag_linphone: CSeq: 4413 BYE 2022-05-12 18:02:26.707 12617-12617/com.gemvary.manager I/tag_linphone: Content-Length: 0 2022-05-12 18:02:26.736 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0] [631] bytes parsed 2022-05-12 18:02:26.738 12617-12617/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x8de84980)] acquired [ref=0x100a9e] 2022-05-12 18:02:26.738 12617-12617/com.gemvary.manager I/tag_linphone: transaction [0x8de84980]: starting transaction background task with id=[100a9e]. 2022-05-12 18:02:26.738 12617-12617/com.gemvary.manager I/tag_linphone: Changing [server] [BYE] transaction [0x8de84980], from state [INIT] to [TRYING] 2022-05-12 18:02:26.738 12617-12617/com.gemvary.manager I/tag_linphone: Changing [server] [BYE] transaction [0x8de84980], from state [TRYING] to [COMPLETED] 2022-05-12 18:02:26.739 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0]: message sent to [UDP://192.192.255.103:5060], size: [649] bytes 2022-05-12 18:02:26.739 12617-12617/com.gemvary.manager I/tag_linphone: SIP/2.0 200 Ok 2022-05-12 18:02:26.739 12617-12617/com.gemvary.manager I/tag_linphone: Via: SIP/2.0/UDP 192.192.255.103:5060;rport;branch=z9hG4bKPjMlQZM5Q212y06rPODv4tgF1AL.Asr.JS 2022-05-12 18:02:26.739 12617-12617/com.gemvary.manager I/tag_linphone: From: sip:outdoor@192.192.255.103;tag=IAH-RgNmQ--NwvhJT.00T7lICJKfTRr. 2022-05-12 18:02:26.739 12617-12617/com.gemvary.manager I/tag_linphone: To: "{\"devCode\":\"S500513dc8f2c9a7c82c\",\"devType\":1,\"extno\":\"\",\"floorNo\":\"\",\"note\":\"管理机（s3288ff）\",\"roomno\":\"\",\"sipType\":1,\"targetFloorNo\":\"01\",\"targetRoomNo\":\"01\",\"targetUnitNo\":\"020102\",\"unitno\":\"\",\"zoneid\":\"6666\"}" sip:linphone.android@192.192.255.42;tag=LNJh4lXl5 2022-05-12 18:02:26.739 12617-12617/com.gemvary.manager I/tag_linphone: Call-ID: o6taYiVcUo 2022-05-12 18:02:26.739 12617-12617/com.gemvary.manager I/tag_linphone: CSeq: 4413 BYE 2022-05-12 18:02:26.739 12617-12617/com.gemvary.manager I/tag_linphone: User-Agent: LinphoneAndroid/6.1.20220512170321 (belle-sip/1.6.3) 2022-05-12 18:02:26.739 12617-12617/com.gemvary.manager I/tag_linphone: Supported: replaces, outbound, gruu 2022-05-12 18:02:26.739 12617-12617/com.gemvary.manager I/tag_linphone: Dialog [0x8de87180]: now updated by transaction [0x8de84980]. 2022-05-12 18:02:26.739 12617-12617/com.gemvary.manager I/tag_linphone: Dialog [0x8de87180] deleted (is_expired=0) 2022-05-12 18:02:26.739 12617-12617/com.gemvary.manager I/tag_linphone: Writing echo canceler state, 0 bytes 2022-05-12 18:02:26.739 12617-12617/com.gemvary.manager W/tag_linphone: .linphone.ecstate has not been created because there is no data to write 2022-05-12 18:02:26.750 216-13199/? D/alsa_route: route_set_controls() set route 25 2022-05-12 18:02:26.752 12617-12617/com.gemvary.manager I/tag_linphone: ms_ticker_set_time_func: ticker's time method updated. 2022-05-12 18:02:26.755 12617-12617/com.gemvary.manager I/tag_linphone: Filter MSRtpRecv is not scheduled; nothing to do. 2022-05-12 18:02:26.755 12617-12617/com.gemvary.manager I/tag_linphone: =========================================================== 2022-05-12 18:02:26.756 12617-12617/com.gemvary.manager I/tag_linphone: AUDIO SESSION'S RTP STATISTICS
2022-05-12 18:02:26.756 12617-12617/com.gemvary.manager I/tag_linphone: ----------------------------------------------------------- 2022-05-12 18:02:26.756 12617-12617/com.gemvary.manager I/tag_linphone: sent 249 packets 2022-05-12 18:02:26.757 12617-12617/com.gemvary.manager I/tag_linphone: 0 duplicated packets 2022-05-12 18:02:26.757 12617-12617/com.gemvary.manager I/tag_linphone: 42828 bytes
2022-05-12 18:02:26.757 12617-12617/com.gemvary.manager I/tag_linphone: received 258 packets 2022-05-12 18:02:26.757 12617-12617/com.gemvary.manager I/tag_linphone: 0 duplicated packets 2022-05-12 18:02:26.757 12617-12617/com.gemvary.manager I/tag_linphone: 44376 bytes
2022-05-12 18:02:26.757 12617-12617/com.gemvary.manager I/tag_linphone: incoming delivered to the app 43000 bytes
2022-05-12 18:02:26.757 12617-12617/com.gemvary.manager I/tag_linphone: incoming cumulative lost 1 packets 2022-05-12 18:02:26.757 12617-12617/com.gemvary.manager I/tag_linphone: incoming received too late 4 packets 2022-05-12 18:02:26.757 12617-12617/com.gemvary.manager I/tag_linphone: incoming bad formatted 0 packets 2022-05-12 18:02:26.757 12617-12617/com.gemvary.manager I/tag_linphone: incoming discarded (queue overflow) 0 packets 2022-05-12 18:02:26.757 12617-12617/com.gemvary.manager I/tag_linphone: sent rtcp 1 packets 2022-05-12 18:02:26.757 12617-12617/com.gemvary.manager I/tag_linphone: received rtcp 4 packets 2022-05-12 18:02:26.758 12617-12617/com.gemvary.manager I/tag_linphone: =========================================================== 2022-05-12 18:02:26.758 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSOpenSLESRecorder:0x8f158458,0-->MSResample:0x8f158d50,0 2022-05-12 18:02:26.758 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSResample:0x8f158d50,0-->MSEqualizer:0x8f158df8,0 2022-05-12 18:02:26.758 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSEqualizer:0x8f158df8,0-->MSWebRTCAECM:0x947af388,1 2022-05-12 18:02:26.758 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSWebRTCAECM:0x947af388,1-->MSVolume:0x8f158ce0,0 2022-05-12 18:02:26.758 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSVolume:0x8f158ce0,0-->MSAudioMixer:0x8f158dc0,0 2022-05-12 18:02:26.758 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSAudioMixer:0x8f158dc0,0-->MSUlawEnc:0x8f158c70,0 2022-05-12 18:02:26.758 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSUlawEnc:0x8f158c70,0-->MSRtpSend:0x947af3c0,0 2022-05-12 18:02:26.759 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSRtpRecv:0x8f158c00,0-->MSUlawDec:0x8f158ca8,0 2022-05-12 18:02:26.759 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSUlawDec:0x8f158ca8,0-->MSGenericPLC:0x8f158e68,0 2022-05-12 18:02:26.759 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSGenericPLC:0x8f158e68,0-->MSAudioFlowControl:0x8f158ed8,0 2022-05-12 18:02:26.760 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSAudioFlowControl:0x8f158ed8,0-->MSDtmfGen:0x8f158c38,0 2022-05-12 18:02:26.760 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSDtmfGen:0x8f158c38,0-->MSVolume:0x8f158d18,0 2022-05-12 18:02:26.760 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSVolume:0x8f158d18,0-->MSEqualizer:0x8f158e30,0 2022-05-12 18:02:26.760 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSEqualizer:0x8f158e30,0-->MSAudioMixer:0x8f158f10,0 2022-05-12 18:02:26.760 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSFilePlayer:0x8f158f48,0-->MSResample:0x8f158fb8,0 2022-05-12 18:02:26.760 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSResample:0x8f158fb8,0-->MSAudioMixer:0x8f158f10,1 2022-05-12 18:02:26.760 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSAudioMixer:0x8f158f10,0-->MSWebRTCAECM:0x947af388,0 2022-05-12 18:02:26.760 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSWebRTCAECM:0x947af388,0-->MSResample:0x8f158d88,0 2022-05-12 18:02:26.760 12617-12617/com.gemvary.manager I/tag_linphone: ms_filter_unlink: MSResample:0x8f158d88,0-->MSOpenSLESPlayer:0x8f158490,0 2022-05-12 18:02:26.761 12617-12617/com.gemvary.manager I/tag_linphone: =========================================================== 2022-05-12 18:02:26.761 12617-12617/com.gemvary.manager I/tag_linphone: FILTER USAGE STATISTICS
2022-05-12 18:02:26.761 12617-12617/com.gemvary.manager I/tag_linphone: Name Count Time/tick (ms) CPU Usage 2022-05-12 18:02:26.761 12617-12617/com.gemvary.manager I/tag_linphone: ----------------------------------------------------------- 2022-05-12 18:02:26.761 12617-12617/com.gemvary.manager I/tag_linphone: MSResample 436 0.797465 45.0431
2022-05-12 18:02:26.761 12617-12617/com.gemvary.manager I/tag_linphone: MSWebRTCAECM 386 0.592345 29.6293
2022-05-12 18:02:26.761 12617-12617/com.gemvary.manager I/tag_linphone: MSRtpRecv 1434 0.0734116 13.6161
2022-05-12 18:02:26.761 12617-12617/com.gemvary.manager I/tag_linphone: MSRtpSend 500 0.0978056 6.33339
2022-05-12 18:02:26.761 12617-12617/com.gemvary.manager I/tag_linphone: MSAudioMixer 1000 0.0106122 1.37301
2022-05-12 18:02:26.761 12617-12617/com.gemvary.manager I/tag_linphone: MSVolume 472 0.0132052 0.807311
2022-05-12 18:02:26.761 12617-12617/com.gemvary.manager I/tag_linphone: MSUlawEnc 218 0.0232535 0.658213
2022-05-12 18:02:26.762 12617-12617/com.gemvary.manager I/tag_linphone: MSUlawDec 246 0.0198109 0.632464
2022-05-12 18:02:26.762 12617-12617/com.gemvary.manager I/tag_linphone: MSGenericPLC 500 0.00928386 0.601176
2022-05-12 18:02:26.762 12617-12617/com.gemvary.manager I/tag_linphone: MSOpenSLESRecorder 500 0.00714265 0.462522
2022-05-12 18:02:26.762 12617-12617/com.gemvary.manager I/tag_linphone: MSFilePlayer 500 0.0028276 0.183101
2022-05-12 18:02:26.762 12617-12617/com.gemvary.manager I/tag_linphone: MSDtmfGen 500 0.00272861 0.176691
2022-05-12 18:02:26.762 12617-12617/com.gemvary.manager I/tag_linphone: MSEqualizer 472 0.00288769 0.176542
2022-05-12 18:02:26.762 12617-12617/com.gemvary.manager I/tag_linphone: MSAudioFlowControl 254 0.00521455 0.171867
2022-05-12 18:02:26.762 12617-12617/com.gemvary.manager I/tag_linphone: MSOpenSLESPlayer 218 0.00473192 0.133942
2022-05-12 18:02:26.762 12617-12617/com.gemvary.manager I/tag_linphone: MSVoidSink 4 0.0021002 0.00135727 2022-05-12 18:02:26.762 12617-12617/com.gemvary.manager I/tag_linphone: MSItcSink 0 0 0
2022-05-12 18:02:26.762 12617-12617/com.gemvary.manager I/tag_linphone: MSTee 0 0 0
2022-05-12 18:02:26.762 12617-12617/com.gemvary.manager I/tag_linphone: =========================================================== 2022-05-12 18:02:26.763 12617-12617/com.gemvary.manager I/tag_linphone: =========================================================== 2022-05-12 18:02:26.763 12617-12617/com.gemvary.manager I/tag_linphone: FILTER USAGE STATISTICS
2022-05-12 18:02:26.763 12617-12617/com.gemvary.manager I/tag_linphone: Name Count Time/tick (ms) CPU Usage 2022-05-12 18:02:26.763 12617-12617/com.gemvary.manager I/tag_linphone: ----------------------------------------------------------- 2022-05-12 18:02:26.763 12617-12617/com.gemvary.manager I/tag_linphone: MSResample 436 0.797465 45.0431
2022-05-12 18:02:26.763 12617-12617/com.gemvary.manager I/tag_linphone: MSWebRTCAECM 386 0.592345 29.6293
2022-05-12 18:02:26.763 12617-12617/com.gemvary.manager I/tag_linphone: MSRtpRecv 1434 0.0734116 13.6161
2022-05-12 18:02:26.763 12617-12617/com.gemvary.manager I/tag_linphone: MSRtpSend 500 0.0978056 6.33339
2022-05-12 18:02:26.763 12617-12617/com.gemvary.manager I/tag_linphone: MSAudioMixer 1000 0.0106122 1.37301
2022-05-12 18:02:26.764 12617-12617/com.gemvary.manager I/tag_linphone: MSVolume 472 0.0132052 0.807311
2022-05-12 18:02:26.764 12617-12617/com.gemvary.manager I/tag_linphone: MSUlawEnc 218 0.0232535 0.658213
2022-05-12 18:02:26.764 12617-12617/com.gemvary.manager I/tag_linphone: MSUlawDec 246 0.0198109 0.632464
2022-05-12 18:02:26.764 12617-12617/com.gemvary.manager I/tag_linphone: MSGenericPLC 500 0.00928386 0.601176
2022-05-12 18:02:26.764 12617-12617/com.gemvary.manager I/tag_linphone: MSOpenSLESRecorder 500 0.00714265 0.462522
2022-05-12 18:02:26.764 12617-12617/com.gemvary.manager I/tag_linphone: MSFilePlayer 500 0.0028276 0.183101
2022-05-12 18:02:26.764 12617-12617/com.gemvary.manager I/tag_linphone: MSDtmfGen 500 0.00272861 0.176691
2022-05-12 18:02:26.764 12617-12617/com.gemvary.manager I/tag_linphone: MSEqualizer 472 0.00288769 0.176542
2022-05-12 18:02:26.764 12617-12617/com.gemvary.manager I/tag_linphone: MSAudioFlowControl 254 0.00521455 0.171867
2022-05-12 18:02:26.764 12617-12617/com.gemvary.manager I/tag_linphone: MSOpenSLESPlayer 218 0.00473192 0.133942
2022-05-12 18:02:26.764 12617-12617/com.gemvary.manager I/tag_linphone: MSVoidSink 4 0.0021002 0.00135727 2022-05-12 18:02:26.764 12617-12617/com.gemvary.manager I/tag_linphone: MSItcSink 0 0 0
2022-05-12 18:02:26.765 12617-12617/com.gemvary.manager I/tag_linphone: MSTee 0 0 0
2022-05-12 18:02:26.765 12617-12617/com.gemvary.manager I/tag_linphone: =========================================================== 2022-05-12 18:02:26.765 12617-12617/com.gemvary.manager I/tag_linphone: CallSession [0x92c9aabc] moving from state State::StreamsRunning to State::End 2022-05-12 18:02:26.765 12617-12617/com.gemvary.manager I/tag_linphone: Notifying soundcard that we don't need it anymore for calls 2022-05-12 18:02:26.765 12617-12617/com.gemvary.manager I/tag_linphone: Stopping ZRTP context on session [0x0] 2022-05-12 18:02:26.765 12617-12617/com.gemvary.manager I/tag_linphone: ZRTP context destroyed 2022-05-12 18:02:26.772 12617-13008/com.gemvary.manager I/tag_linphone: MSAudio MSTicker thread exiting 2022-05-12 18:02:26.772 12617-12617/com.gemvary.manager I/tag_linphone: Stopping ZRTP context on session [0x0] 2022-05-12 18:02:26.773 12617-12617/com.gemvary.manager I/tag_linphone: ZRTP context destroyed 2022-05-12 18:02:26.773 12617-12617/com.gemvary.manager I/tag_linphone: Linphone core [0x93cd1780] notified [call_log_updated] 2022-05-12 18:02:26.773 12617-12617/com.gemvary.manager I/tag_linphone: Resetting the current call 2022-05-12 18:02:26.777 12617-12617/com.gemvary.manager D/MyLinphone: callStateChanged, callId: outdoor@192.192.255.103, state: End, message: Call ended 2022-05-12 18:02:26.777 12617-12617/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.logBoth(MyLinphone.java:820) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:843) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) callStateChanged, callId: outdoor@192.192.255.103, state: End, message: Call ended 2022-05-12 18:02:26.779 12617-12617/com.gemvary.manager D/MyLinphone: call state End, reason is: None 2022-05-12 18:02:26.779 12617-12617/com.gemvary.manager D/CallTarget: after removed, call id list is: [] 2022-05-12 18:02:26.779 12617-12617/com.gemvary.manager D/CallManager: Thread: main ├ com.gemvary.call.sdk.base.CallManager.callEnd(CallManager.java:1063) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:941) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) call id list empty now, reset current call target 2022-05-12 18:02:26.780 12617-12617/com.gemvary.manager D/CallManager: Thread: main ├ com.gemvary.call.sdk.base.CallManager.callEnd(CallManager.java:1068) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:941) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) no call target pending 2022-05-12 18:02:26.780 12617-12617/com.gemvary.manager D/CallManager: Thread: main ├ com.gemvary.call.sdk.base.CallManager.callEnd(CallManager.java:1072) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:941) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) isAnswered? true, errorOccurred? false 2022-05-12 18:02:26.781 12617-12617/com.gemvary.manager D/CallManager: Thread: main ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:785) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:775) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:769) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:764) └ com.gemvary.call.sdk.base.CallManager.notifyCallEnd(CallManager.java:1016)

callId: outdoor@192.192.255.103, state: End, reason: None, ExtraStatus{targetPending=false, transferType=UNKNOWN, cloudVoipNotConnected=false, unitNo='null', roomNo='null', preparationError=null, hangupReason=null}

2022-05-12 18:02:26.782 12617-12617/com.gemvary.manager D/OutgoingActivity: Thread: main ├ com.gemvary.manager.activity.OutgoingActivity.lambda$onCreate$6$OutgoingActivity(OutgoingActivity.java:286) ├ com.gemvary.manager.activity.-$$Lambda$OutgoingActivity$wsNkDazuaV1TG9LxuYSo38yj7TI.onCallStateChanged(lambda) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:788) ├ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:775) └ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:769) no call target pending, close the outgoing ui 2022-05-12 18:02:26.782 12617-12617/com.gemvary.manager D/OutgoingActivity: Thread: main ├ com.gemvary.manager.activity.OutgoingActivity.stopRinging(OutgoingActivity.java:411) ├ com.gemvary.manager.activity.OutgoingActivity.closeWithDelay(OutgoingActivity.java:424) ├ com.gemvary.manager.activity.OutgoingActivity.lambda$onCreate$6$OutgoingActivity(OutgoingActivity.java:287) ├ com.gemvary.manager.activity.-$$Lambda$OutgoingActivity$wsNkDazuaV1TG9LxuYSo38yj7TI.onCallStateChanged(lambda) └ com.gemvary.call.sdk.base.CallManager.notifyCallStateListeners(CallManager.java:788)

is still singlePlaying? false

2022-05-12 18:02:26.783 12617-12617/com.gemvary.manager I/tag_linphone: Linphone core [0x93cd1780] notified [call_state_changed] 2022-05-12 18:02:26.783 12617-12617/com.gemvary.manager I/tag_linphone: channel [0x8ded5cc0]: ending recv background task with id=[100ab2]. 2022-05-12 18:02:26.784 12617-12617/com.gemvary.manager I/tag_linphone: bellesip_wake_lock_release(): Android wake lock released [ref=0x100ab2] 2022-05-12 18:02:26.785 12617-12617/com.gemvary.manager I/tag_linphone: Dialog [0x8de87180] terminated for op [0x943ce700] 2022-05-12 18:02:26.785 12617-12617/com.gemvary.manager I/tag_linphone: CallSession [0x92c9aabc] moving from state State::End to State::Released 2022-05-12 18:02:26.785 12617-12617/com.gemvary.manager I/tag_linphone: releaseWifiLock() 2022-05-12 18:02:26.786 12617-12617/com.gemvary.manager I/tag_linphone: releaseMcastLock() 2022-05-12 18:02:26.787 12617-12617/com.gemvary.manager I/tag_linphone: releaseCpuLock() 2022-05-12 18:02:26.788 12617-12617/com.gemvary.manager D/MyLinphone: callStateChanged, callId: outdoor@192.192.255.103, state: Released, message: Call released 2022-05-12 18:02:26.789 12617-12617/com.gemvary.manager D/MyLinphone: Thread: main ├ com.gemvary.call.sdk.base.MyLinphone.logBoth(MyLinphone.java:820) ├ com.gemvary.call.sdk.base.MyLinphone.callStateChanged(MyLinphone.java:843) ├ com.gemvary.call.sdk.base.MyLinphone.onCallStateChanged(MyLinphone.java:1430) ├ org.linphone.core.CoreImpl.iterate(Native Method) └ org.linphone.core.CoreImpl.iterate(Core.java:4591) callStateChanged, callId: outdoor@192.192.255.103, state: Released, message: Call released 2022-05-12 18:02:26.789 12617-12617/com.gemvary.manager I/tag_linphone: Linphone core [0x93cd1780] notified [call_state_changed] 2022-05-12 18:02:26.789 12617-12617/com.gemvary.manager I/tag_linphone: op [0x943ce700] : set_or_update_dialog() current=[0x8de87180] new=[0x0] 2022-05-12 18:02:28.807 12617-12617/com.gemvary.manager I/tag_linphone: Activity paused:com.gemvary.manager.activity.OutgoingActivity@3e4b03b 2022-05-12 18:02:28.807 12617-12617/com.gemvary.manager I/tag_linphone: runningActivities=0 2022-05-12 18:02:28.809 12617-12617/com.gemvary.manager D/OutgoingActivity: Thread: main ├ com.gemvary.manager.activity.OutgoingActivity.onPause(OutgoingActivity.java:598) ├ android.app.Activity.performPause(Activity.java:6849) ├ android.app.Instrumentation.callActivityOnPause(Instrumentation.java:1322) ├ android.app.ActivityThread.performPauseActivityIfNeeded(ActivityThread.java:3746) └ android.app.ActivityThread.performPauseActivity(ActivityThread.java:3723) onPause

fengqz627 commented 2 years ago

I try to attach file, but it never succeeded.

Viish commented 2 years ago

Do you reproduce the issue with linphone-android app from the PlayStore / FDroid?

fengqz627 commented 2 years ago

NO, I will try. thanks.