[Linphone-developers] Early Media not delivering

Hi, hope to get some help here, I already posted in Users Section but I think this is more dev-related.

I’m doing some tests with the sample app and fail to see early media video before the call gets answered.

I’m doing the call without registration to a device which wants Early Media.

Can someone send me a hint how to get early media video h264 enabled?

Here is some log output, if more is needed please let me know.

Thanks in advance

Flo

2019-11-12 09:52:03.511 7345-7345/org.linphone.sample I/chatty: uid=10065(org.linphone.sample) expire 6 lines

2019-11-12 09:52:33.529 7345-7345/org.linphone.sample I/Linphone Sample: channel [0x8121f8c0]: keep alive sent to [UDP://10.100.3.111:5060]

2019-11-12 09:52:40.774 7345-7345/org.linphone.sample I/Linphone Sample: New MediaSession [0x830f698c] initialized (LinphoneCore version: 3.12.0-3579-g37ec0fabd)

2019-11-12 09:52:40.786 7345-7345/org.linphone.sample I/Linphone Sample: CallSession [0x830f698c], stream type [audio], multicast role is [inactive]

2019-11-12 09:52:40.788 7345-7345/org.linphone.sample I/Linphone Sample: RtpSession bound to [::0] ports [4000] [4001]

2019-11-12 09:52:40.788 7345-7345/org.linphone.sample I/Linphone Sample: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION

2019-11-12 09:52:40.790 7345-7345/org.linphone.sample I/Linphone Sample: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_80

2019-11-12 09:52:40.791 7345-7345/org.linphone.sample I/Linphone Sample: Configured srtp crypto suite: AES_CM_128_HMAC_SHA1_32

2019-11-12 09:52:40.791 7345-7345/org.linphone.sample I/Linphone Sample: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_80

2019-11-12 09:52:40.791 7345-7345/org.linphone.sample I/Linphone Sample: Configured srtp crypto suite: AES_256_CM_HMAC_SHA1_32

2019-11-12 09:52:40.791 7345-7345/org.linphone.sample I/Linphone Sample: Creating ZRTP engine on rtp session [0x9af36600] ssrc 0x33ff663a

2019-11-12 09:52:40.793 7345-7345/org.linphone.sample I/Linphone Sample: Setting DSCP to 46 for MSAudio stream.

2019-11-12 09:52:40.793 7345-7345/org.linphone.sample I/Linphone Sample: Equalizer location: hp

2019-11-12 09:52:40.798 7345-7345/org.linphone.sample I/Linphone Sample: cannot set noise gate mode to [0] because no volume send

2019-11-12 09:52:40.801 7345-7345/org.linphone.sample I/Linphone Sample: CallSession [0x830f698c], stream type [video], multicast role is [inactive]

2019-11-12 09:52:40.802 7345-7345/org.linphone.sample I/Linphone Sample: RtpSession bound to [::0] ports [4010] [4011]

2019-11-12 09:52:40.809 7345-7345/org.linphone.sample I/Linphone Sample: This device (freescale/VAR_SOM_MX6/imx6) says it has no built-in echo canceller.

2019-11-12 09:52:40.810 7345-7345/org.linphone.sample I/Linphone Sample: No information available for [freescale/VAR_SOM_MX6/imx6],

2019-11-12 09:52:40.810 7345-7345/org.linphone.sample I/Linphone Sample: Using android >= 8 sound device descriptor.

2019-11-12 09:52:40.810 7345-7345/org.linphone.sample I/Linphone Sample: Sound device information for [freescale/VAR_SOM_MX6/imx6] is: builtin=[yes], delay=[0] ms

2019-11-12 09:52:40.811 7345-7345/org.linphone.sample I/Linphone Sample: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION

2019-11-12 09:52:40.811 7345-7345/org.linphone.sample I/Linphone Sample: Initializing multistream ZRTP context on rtp session [0x9af37400] ssrc 0x875fe094

2019-11-12 09:52:40.811 7345-7345/org.linphone.sample I/Linphone Sample: Setting DSCP to 0 for MSVideo stream.

2019-11-12 09:52:40.812 7345-7345/org.linphone.sample I/Linphone Sample: CallSession [0x830f698c], stream type [text], multicast role is [inactive]

2019-11-12 09:52:40.812 7345-7345/org.linphone.sample I/Linphone Sample: RtpSession bound to [::0] ports [11078] [11079]

2019-11-12 09:52:40.813 7345-7345/org.linphone.sample I/Linphone Sample: rtp_session_enable_network_simulation:DISABLING NETWORK SIMULATION

2019-11-12 09:52:40.813 7345-7345/org.linphone.sample I/Linphone Sample: Linphone core [0x9a9de500] notified [call_created]

2019-11-12 09:52:40.814 7345-7345/org.linphone.sample I/Linphone Sample: CallSession [0x830f698c] moving from state State::Idle to State::OutgoingInit

2019-11-12 09:52:40.815 7345-7345/org.linphone.sample I/Linphone Sample: [Platform Helper] acquireWifiLock()

2019-11-12 09:52:40.817 7345-7345/org.linphone.sample I/Linphone Sample: [Platform Helper] acquireMcastLock()

2019-11-12 09:52:40.820 7345-7345/org.linphone.sample I/Linphone Sample: [Platform Helper] acquireCpuLock()

2019-11-12 09:52:40.844 7345-7345/org.linphone.sample I/Linphone Sample: HEY, CallState is OutgoingInit

2019-11-12 09:52:40.845 7345-7345/org.linphone.sample I/Linphone Sample: Linphone core [0x9a9de500] notified [call_state_changed]

2019-11-12 09:52:40.849 7345-7345/org.linphone.sample I/Linphone Sample: Don't put text stream on local offer for CallSession [0x830f698c]

2019-11-12 09:52:40.849 7345-7345/org.linphone.sample I/Linphone Sample: ms_filter_link: MSRtpRecv:0x9af6c7f8,0-->MSVoidSink:0x9af6c8a0,0

2019-11-12 09:52:40.850 7345-7453/org.linphone.sample I/Linphone Sample: Priority used: 99

2019-11-12 09:52:40.851 7345-7453/org.linphone.sample I/Linphone Sample: MSAudio MSTicker priority increased to maximum.

2019-11-12 09:52:40.853 7345-7345/org.linphone.sample I/Linphone Sample: Contact has not been fixed, stack will do

2019-11-12 09:52:40.856 7345-7345/org.linphone.sample I/Linphone Sample: ["SIP Unit" <sip:sipunit@10.100.3.163:5060>] calling [sip:10.100.3.111] on op [0xa4cc0ec0]

2019-11-12 09:52:40.860 7345-7345/org.linphone.sample I/Linphone Sample: bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0xa4cb7190)] acquired [ref=0x2b02]

2019-11-12 09:52:40.861 7345-7345/org.linphone.sample I/Linphone Sample: transaction [0xa4cb7190]: starting transaction background task with id=[2b02].

2019-11-12 09:52:40.861 7345-7345/org.linphone.sample I/Linphone Sample: Changing [client] [INVITE] transaction [0xa4cb7190], from state [INIT] to [CALLING]

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: channel [0x8121f8c0]: message sent to [UDP://10.100.3.111:5060], size: [1405] bytes

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: INVITE sip:10.100.3.111 SIP/2.0

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: Via: SIP/2.0/UDP 10.100.3.163:5060;branch=z9hG4bK.ttDYOuLbv;rport

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: From: "SIP Unit" <sip:sipunit@10.100.3.163>;tag=XitOykd5K

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: To: sip:10.100.3.111

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: CSeq: 20 INVITE

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: Call-ID: PL1a~vys7U

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: Max-Forwards: 70

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: Supported: replaces, outbound, gruu

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: Content-Type: application/sdp

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: Content-Length: 821

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: Contact: "SIP Unit" <sip:sipunit@10.100.3.163;transport=udp>;+sip.instance="<urn:uuid:d4e06272-5065-008f-a116-1915910512dc>"

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: User-Agent: Unknown (belle-sip/1.7.0)

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: v=0

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: o=sipunit 2281 3983 IN IP4 10.100.3.163

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: s=Talk

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: c=IN IP4 10.100.3.163

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: t=0 0

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: m=audio 4000 RTP/AVP 0 96 97 98 99 8 101 100 102

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: a=rtpmap:96 opus/48000/2

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: a=fmtp:96 useinbandfec=1

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: a=rtpmap:97 PCMU/8000/2

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: a=rtpmap:98 speex/16000

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: a=fmtp:98 vbr=on

2019-11-12 09:52:40.862 7345-7345/org.linphone.sample I/Linphone Sample: a=rtpmap:99 speex/8000

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=fmtp:99 vbr=on

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=rtpmap:101 telephone-event/8000

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=rtpmap:100 telephone-event/48000

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=rtpmap:102 telephone-event/16000

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=rtcp-fb:* trr-int 5000

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=rtcp-fb:* ccm tmmbr

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: m=video 4010 RTP/AVP 96 97

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=rtpmap:96 H264/90000

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=fmtp:96 profile-level-id=42801F

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=rtpmap:97 VP8/90000

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=rtcp-fb:* trr-int 5000

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=rtcp-fb:* ccm tmmbr

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=rtcp-fb:96 nack pli

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=rtcp-fb:96 ccm fir

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=rtcp-fb:97 nack pli

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=rtcp-fb:97 nack sli

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=rtcp-fb:97 ack rpsi

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: a=rtcp-fb:97 ccm fir

2019-11-12 09:52:40.863 7345-7345/org.linphone.sample I/Linphone Sample: CallSession [0x830f698c] moving from state State::OutgoingInit to State::OutgoingProgress

2019-11-12 09:52:40.880 7345-7345/org.linphone.sample I/Linphone Sample: HEY, CallState is OutgoingProgress

2019-11-12 09:52:40.880 7345-7345/org.linphone.sample I/Linphone Sample: Linphone core [0x9a9de500] notified [call_state_changed]

2019-11-12 09:52:40.881 7345-7345/org.linphone.sample I/Linphone Sample: using Video Device Camera2Device1Facingfront

2019-11-12 09:52:40.899 7345-7345/org.linphone.sample I/Linphone Sample: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x2a72]

2019-11-12 09:52:40.902 7345-7345/org.linphone.sample I/Linphone Sample: channel [0x8121f8c0]: starting recv background task with id=[2a72].

2019-11-12 09:52:40.902 7345-7345/org.linphone.sample I/Linphone Sample: channel [0x8121f8c0]: received [260] new bytes from [UDP://10.100.3.111:5060]:

SIP/2.0 100 Trying

Via: SIP/2.0/UDP 10.100.3.163:5060;branch=z9hG4bK.ttDYOuLbv;rport=5060

From: "SIP Unit" <sip:sipunit@10.100.3.163>;tag=XitOykd5K

To: <sip:10.100.3.111>

Call-ID: PL1a~vys7U

CSeq: 20 INVITE

User-Agent: eXosip/4.0.0

Content-Length: 0

2019-11-12 09:52:40.909 7345-7345/org.linphone.sample I/Linphone Sample: channel [0x8121f8c0] [260] bytes parsed

2019-11-12 09:52:40.909 7345-7345/org.linphone.sample I/Linphone Sample: Found transaction matching response.

2019-11-12 09:52:40.909 7345-7345/org.linphone.sample I/Linphone Sample: Changing [client] [INVITE] transaction [0xa4cb7190], from state [CALLING] to [PROCEEDING]

2019-11-12 09:52:40.909 7345-7345/org.linphone.sample I/Linphone Sample: op [0xa4cc0ec0] : set_or_update_dialog() current=[0x0] new=[0x0]

2019-11-12 09:52:40.910 7345-7345/org.linphone.sample I/Linphone Sample: Op [0xa4cc0ec0] receiving call response [100], dialog is [0x0] in state [BELLE_SIP_DIALOG_NULL]

2019-11-12 09:52:40.911 7345-7345/org.linphone.sample I/Linphone Sample: channel [0x8121f8c0]: ending recv background task with id=[2a72].

2019-11-12 09:52:40.914 7345-7345/org.linphone.sample I/Linphone Sample: bellesip_wake_lock_release(): Android wake lock released [ref=0x2a72]

2019-11-12 09:52:40.918 7345-7345/org.linphone.sample I/Linphone Sample: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x2a6a]

2019-11-12 09:52:40.918 7345-7345/org.linphone.sample I/Linphone Sample: channel [0x8121f8c0]: starting recv background task with id=[2a6a].

2019-11-12 09:52:40.918 7345-7345/org.linphone.sample I/Linphone Sample: channel [0x8121f8c0]: received [309] new bytes from [UDP://10.100.3.111:5060]:

SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 10.100.3.163:5060;branch=z9hG4bK.ttDYOuLbv;rport=5060

From: "SIP Unit" <sip:sipunit@10.100.3.163>;tag=XitOykd5K

To: <sip:10.100.3.111>;tag=663482644

Call-ID: PL1a~vys7U

CSeq: 20 INVITE

Contact: <sip:10.100.3.111:5060>

User-Agent: eXosip/4.0.0

Content-Length: 0

2019-11-12 09:52:40.924 7345-7345/org.linphone.sample I/Linphone Sample: channel [0x8121f8c0] [309] bytes parsed

2019-11-12 09:52:40.924 7345-7345/org.linphone.sample I/Linphone Sample: Found transaction matching response.

2019-11-12 09:52:40.925 7345-7345/org.linphone.sample I/Linphone Sample: New client dialog [0x9d306a20] , local tag [XitOykd5K], remote tag [663482644]

2019-11-12 09:52:40.925 7345-7345/org.linphone.sample I/Linphone Sample: Dialog [0x9d306a20]: now updated by transaction [0xa4cb7190].

2019-11-12 09:52:40.926 7345-7345/org.linphone.sample I/Linphone Sample: op [0xa4cc0ec0] : set_or_update_dialog() current=[0x0] new=[0x9d306a20]

2019-11-12 09:52:40.926 7345-7345/org.linphone.sample I/Linphone Sample: Op [0xa4cc0ec0] receiving call response [180], dialog is [0x9d306a20] in state [BELLE_SIP_DIALOG_EARLY]

2019-11-12 09:52:40.926 7345-7345/org.linphone.sample I/Linphone Sample: ms_filter_unlink: MSRtpRecv:0x9af6c7f8,0-->MSVoidSink:0x9af6c8a0,0

2019-11-12 09:52:40.927 7345-7345/org.linphone.sample I/Linphone Sample: speex_lib_ctl init with neon ? 1

2019-11-12 09:52:40.927 7345-7345/org.linphone.sample I/Linphone Sample: Configuring resampler input with rate=[8000], nchannels=[1]

2019-11-12 09:52:40.927 7345-7345/org.linphone.sample I/Linphone Sample: MSFilePlayer[0x9af6c7f8]: /data/user/0/org.linphone.sample/files/share/sounds/linphone/ringback.wav opened: rate=8000,channel=1

2019-11-12 09:52:40.927 7345-7345/org.linphone.sample I/Linphone Sample: configuring resampler output to rate=[44100], nchannels=[1]

2019-11-12 09:52:40.928 7345-7345/org.linphone.sample I/Linphone Sample: ms_filter_link: MSFilePlayer:0x9af6c7f8,0-->MSDtmfGen:0x9af6d240,0

2019-11-12 09:52:40.928 7345-7345/org.linphone.sample I/Linphone Sample: ms_filter_link: MSDtmfGen:0x9af6d240,0-->MSResample:0x9af6d208,0

2019-11-12 09:52:40.928 7345-7455/org.linphone.sample I/Linphone Sample: Priority used: 99

2019-11-12 09:52:40.928 7345-7345/org.linphone.sample I/Linphone Sample: ms_filter_link: MSResample:0x9af6d208,0-->MSOpenSLESPlayer:0x9af6d0f0,0

2019-11-12 09:52:40.928 7345-7455/org.linphone.sample I/Linphone Sample: Ring MSTicker priority increased to maximum.

2019-11-12 09:52:40.928 7345-7345/org.linphone.sample I/Linphone Sample: Initializing speex resampler in mode [voip]

2019-11-12 09:52:40.930 7345-7345/org.linphone.sample I/libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x4, using default mask 0x1 based on channel count of 1)

2019-11-12 09:52:40.932 7345-7345/org.linphone.sample I/AudioTrack: AUDIO_OUTPUT_FLAG_FAST successful; frameCount 384 -> 384

2019-11-12 09:52:40.932 7345-7345/org.linphone.sample D/AudioTrack: Client defaulted notificationFrames to 192 for frameCount 384

2019-11-12 09:52:40.935 7345-7345/org.linphone.sample I/Linphone Sample: Remote ringing...

2019-11-12 09:52:40.935 7345-7345/org.linphone.sample I/Linphone Sample: CallSession [0x830f698c] moving from state State::OutgoingProgress to State::OutgoingRinging

2019-11-12 09:52:40.951 7345-7345/org.linphone.sample I/Linphone Sample: HEY, CallState is OutgoingRinging

2019-11-12 09:52:40.952 7345-7345/org.linphone.sample I/Linphone Sample: Linphone core [0x9a9de500] notified [call_state_changed]

2019-11-12 09:52:40.952 7345-7345/org.linphone.sample I/Linphone Sample: channel [0x8121f8c0]: ending recv background task with id=[2a6a].

2019-11-12 09:52:40.954 7345-7345/org.linphone.sample I/Linphone Sample: bellesip_wake_lock_release(): Android wake lock released [ref=0x2a6a]

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x2a3a]

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: channel [0x8121f8c0]: starting recv background task with id=[2a3a].

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: channel [0x8121f8c0]: received [581] new bytes from [UDP://10.100.3.111:5060]:

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: SIP/2.0 183 Session Progress

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: Via: SIP/2.0/UDP 10.100.3.163:5060;branch=z9hG4bK.ttDYOuLbv;rport=5060

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: From: "SIP Unit" <sip:sipunit@10.100.3.163>;tag=XitOykd5K

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: To: <sip:10.100.3.111>;tag=663482644

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: Call-ID: PL1a~vys7U

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: CSeq: 20 INVITE

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: Contact: <sip:10.100.3.111:5060>

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: Content-Type: application/sdp

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: User-Agent: eXosip/4.0.0

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: Content-Length: 228

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: v=0

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: o=g1 1573548762 1573548762 IN IP4 10.100.3.111

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: s=-

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: c=IN IP4 10.100.3.111

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: t=0 0

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: m=video 11500 RTP/AVP 103

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: a=rtpmap:103 H264/90000

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: a=fmtp:103 packetization-mode=1;sprop-parameter-sets=Z0IAHqtAUB7I,aM44gA==

2019-11-12 09:52:40.958 7345-7345/org.linphone.sample I/Linphone Sample: a=recvonly

2019-11-12 09:52:40.968 7345-7345/org.linphone.sample I/Linphone Sample: channel [0x8121f8c0] [353] bytes parsed

2019-11-12 09:52:40.968 7345-7345/org.linphone.sample I/Linphone Sample: channel [0x8121f8c0] read [228] bytes of body from [10.100.3.111:5060]

2019-11-12 09:52:40.969 7345-7345/org.linphone.sample I/Linphone Sample: Found transaction matching response.

2019-11-12 09:52:40.969 7345-7345/org.linphone.sample I/Linphone Sample: Dialog [0x9d306a20]: now updated by transaction [0xa4cb7190].

2019-11-12 09:52:40.973 7345-7345/org.linphone.sample I/Linphone Sample: op [0xa4cc0ec0] : set_or_update_dialog() current=[0x9d306a20] new=[0x9d306a20]

2019-11-12 09:52:40.973 7345-7345/org.linphone.sample I/Linphone Sample: Op [0xa4cc0ec0] receiving call response [183], dialog is [0x9d306a20] in state [BELLE_SIP_DIALOG_EARLY]

2019-11-12 09:52:40.977 7345-7345/org.linphone.sample I/Linphone Sample: Found payload H264/90000 fmtp=packetization-mode=1;sprop-parameter-sets=Z0IAHqtAUB7I,aM44gA==

2019-11-12 09:52:40.977 7345-7345/org.linphone.sample I/Linphone Sample: Doing SDP offer/answer process of type outgoing

2019-11-12 09:52:40.978 7345-7345/org.linphone.sample I/Linphone Sample: Processing for stream 0

2019-11-12 09:52:40.978 7345-7345/org.linphone.sample W/Linphone Sample: No matching stream for 0

2019-11-12 09:52:40.978 7345-7345/org.linphone.sample I/Linphone Sample: Processing for stream 1

2019-11-12 09:52:40.978 7345-7345/org.linphone.sample W/Linphone Sample: No matching stream for 1

2019-11-12 09:52:40.979 7345-7345/org.linphone.sample I/Linphone Sample: CallSession [0x830f698c] moving from state State::OutgoingRinging to State::OutgoingEarlyMedia

2019-11-12 09:52:41.003 7345-7345/org.linphone.sample I/Linphone Sample: HEY, outgoing early media would be fine, early media is enabled? true, SendRecv

2019-11-12 09:52:41.004 7345-7345/org.linphone.sample I/Linphone Sample: Linphone core [0x9a9de500] notified [call_state_changed]

2019-11-12 09:52:41.004 7345-7345/org.linphone.sample D/AudioTrack: stop() called with 5376 frames delivered

2019-11-12 09:52:41.005 7345-7345/org.linphone.sample I/Linphone Sample: ms_filter_unlink: MSFilePlayer:0x9af6c7f8,0-->MSDtmfGen:0x9af6d240,0

2019-11-12 09:52:41.006 7345-7345/org.linphone.sample I/Linphone Sample: ms_filter_unlink: MSDtmfGen:0x9af6d240,0-->MSResample:0x9af6d208,0

2019-11-12 09:52:41.006 7345-7345/org.linphone.sample I/Linphone Sample: ms_filter_unlink: MSResample:0x9af6d208,0-->MSOpenSLESPlayer:0x9af6d0f0,0

2019-11-12 09:52:41.008 7345-7455/org.linphone.sample I/Linphone Sample: Ring MSTicker thread exiting

2019-11-12 09:52:41.009 7345-7345/org.linphone.sample I/Linphone Sample: Doing early media...

2019-11-12 09:52:41.009 7345-7345/org.linphone.sample I/Linphone Sample: startStreams() CallSession=[0x830f698c] local upload_bandwidth=[0] kbit/s; local download_bandwidth=[0] kbit/s

2019-11-12 09:52:41.009 7345-7345/org.linphone.sample I/Linphone Sample: [Camera2 Capture] Filter postprocess

2019-11-12 09:52:41.009 7345-7345/org.linphone.sample I/Linphone Sample: ms_filter_unlink: MSAndroidCamera2Capture:0x9af6d828,0-->MSPixConv:0x9af6ddd8,0

2019-11-12 09:52:41.009 7345-7345/org.linphone.sample I/Linphone Sample: ms_filter_unlink: MSPixConv:0x9af6ddd8,0-->MSTee:0x9af6d128,0

2019-11-12 09:52:41.009 7345-7345/org.linphone.sample I/Linphone Sample: ms_filter_unlink: MSTee:0x9af6d128,1-->MSVoidSink:0x9af682d8,0

2019-11-12 09:52:41.010 7345-7345/org.linphone.sample I/Linphone Sample: ms_filter_unlink: MSTee:0x9af6d128,2-->MSJpegWriter:0x9af6d0b8,0

2019-11-12 09:52:41.011 7345-7431/org.linphone.sample I/Linphone Sample: Video MSTicker thread exiting

2019-11-12 09:52:41.012 7345-7345/org.linphone.sample I/Linphone Sample: [Camera2 Capture] Filter uninit

2019-11-12 09:52:41.012 7345-7345/org.linphone.sample I/Linphone Sample: No valid video stream defined

2019-11-12 09:52:41.012 7345-7345/org.linphone.sample I/Linphone Sample: speex_lib_ctl init with neon ? 1

2019-11-12 09:52:41.013 7345-7345/org.linphone.sample I/Linphone Sample: Configuring resampler input with rate=[8000], nchannels=[1]

2019-11-12 09:52:41.013 7345-7345/org.linphone.sample I/Linphone Sample: MSFilePlayer[0x9af6d128]: /data/user/0/org.linphone.sample/files/share/sounds/linphone/ringback.wav opened: rate=8000,channel=1

2019-11-12 09:52:41.013 7345-7345/org.linphone.sample I/Linphone Sample: configuring resampler output to rate=[44100], nchannels=[1]

2019-11-12 09:52:41.013 7345-7345/org.linphone.sample I/Linphone Sample: ms_filter_link: MSFilePlayer:0x9af6d128,0-->MSDtmfGen:0x9af6ddd8,0

2019-11-12 09:52:41.013 7345-7345/org.linphone.sample I/Linphone Sample: ms_filter_link: MSDtmfGen:0x9af6ddd8,0-->MSResample:0x9af6d0b8,0

2019-11-12 09:52:41.013 7345-7345/org.linphone.sample I/Linphone Sample: ms_filter_link: MSResample:0x9af6d0b8,0-->MSOpenSLESPlayer:0x9af682d8,0

2019-11-12 09:52:41.014 7345-7345/org.linphone.sample I/Linphone Sample: Initializing speex resampler in mode [voip]

2019-11-12 09:52:41.014 7345-7458/org.linphone.sample I/Linphone Sample: Priority used: 99

2019-11-12 09:52:41.014 7345-7458/org.linphone.sample I/Linphone Sample: Ring MSTicker priority increased to maximum.

2019-11-12 09:52:41.015 7345-7345/org.linphone.sample I/libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x4, using default mask 0x1 based on channel count of 1)

2019-11-12 09:52:41.016 7345-7345/org.linphone.sample I/AudioTrack: AUDIO_OUTPUT_FLAG_FAST successful; frameCount 384 -> 384

2019-11-12 09:52:41.016 7345-7345/org.linphone.sample D/AudioTrack: Client defaulted notificationFrames to 192 for frameCount 384

2019-11-12 09:52:41.019 7345-7345/org.linphone.sample I/Linphone Sample: channel [0x8121f8c0]: ending recv background task with id=[2a3a].

2019-11-12 09:52:41.025 7345-7345/org.linphone.sample I/Linphone Sample: bellesip_wake_lock_release(): Android wake lock released [ref=0x2a3a]

2019-11-12 09:52:41.025 7345-7345/org.linphone.sample I/Linphone Sample: Garbage collecting unowned object of type belle_sdp_session_description_t

2019-11-12 09:52:41.528 7345-7345/org.linphone.sample I/Linphone Sample: Bandwidth usage for CallSession [0x830f698c]: