linphone-users
[Top][All Lists]
Advanced

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

[Linphone-users] Hang up at about 30 seconds - incoming calls, with log


From: John Statler
Subject: [Linphone-users] Hang up at about 30 seconds - incoming calls, with log
Date: Wed, 25 Jan 2017 05:15:38 -0800
User-agent: Mozilla/5.0 (Windows NT 6.1; rv:45.0) Gecko/20100101 Thunderbird/45.6.0

Thank you all for working on this project. I love the interface better than any I've seen so far. I just wish it would work for me.

Incoming calls like from a cell phone are hung up after about 30 seconds.

I have tested this in two locations with two different routers. Same response in both locations.

Great outgoing calls like to a cell phone. Great calling from one Linphone location to another Linphone location. But when a cell phone calls the Linphone, Linphone hangs up after 30 seconds. This is at both locations.

I hate to do this but the following log is from just before the hangup. The quality drops to bad, then a few seconds later the call is hung up.

Here's the log. I hope you can help determine the problem.

Thanks in advance for any help you can provide. john.

=================================

message: 2017-01-25 04:54:31:373 Bandwidth usage for call [0491AA28]:

RTP audio=[d= 80.0,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

message: 2017-01-25 04:54:31:373 Thread processing load: audio=2.495591 video=0.000000 text=0.000000

message: 2017-01-25 04:54:32:393 Bandwidth usage for call [0491AA28]:

RTP audio=[d= 80.0,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

message: 2017-01-25 04:54:32:393 Thread processing load: audio=1.525873 video=0.000000 text=0.000000

message: 2017-01-25 04:54:32:555 Sending RTCP SR compound message on session [0491B198].

message: 2017-01-25 04:54:32:573 MSAudio_stream_iterate[033D38D0], local statistics available:

Local current jitter buffer size: 79.9ms

message: 2017-01-25 04:54:32:963 MSAudio stream [033D38D0]: receiving RTCP SR

message: 2017-01-25 04:54:32:963 MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=7.375000 ms, rt_prop=20.233978 sec

message: 2017-01-25 04:54:32:963 MSSimpleQosAnalyzer: everything is fine.

message: 2017-01-25 04:54:32:963 MSBitrateController: current state is Init

message: 2017-01-25 04:54:32:963 MSQualityIndicator[005CCF28][audio], remote statistics available:

Loss rate : 0.0%

Inter-arrival jitter: 7.4ms

RT propagation : 20234.0ms

message: 2017-01-25 04:54:32:963 MSAudio stream [033D38D0]: receiving RTCP SR

message: 2017-01-25 04:54:32:993 Dialog sending retransmission of 200Ok

message: 2017-01-25 04:54:32:993 channel [034002A0]: message sent to [UDP://MMMMMM.infopbx.net:5060], size: [792] bytes

SIP/2.0 200 Ok

Via: SIP/2.0/UDP 69.9.143.3:5060;branch=z9hG4bKnWe8jVIdYDGAEbeq04078C

From: "[*]CCCXXCC JOHN" <sip:address@hidden>;tag=nWe8jVIdYDGAEbeq04078C

To: <sip:address@hidden>;tag=e1U~tje

Call-ID: 20170125125329046443-4cc874112ce44e0e67e90933c07d6e61

CSeq: 201 INVITE

User-Agent: Linphone/3.10.2 (belle-sip/1.5.0)

Supported: replaces, outbound

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE

Contact: <sip:address@hidden:5061;transport=udp>;+sip.instance="<urn:uuid:aaf127dc-af0d-4a78-a1d5-4a5f3b9234c6>"

Content-Type: application/sdp

Content-Length: 146


v=0

o=1209 2258 3245 IN IP4 192.168.1.119

s=Talk

c=IN IP4 192.168.1.119

t=0 0

m=audio 7082 RTP/AVP 0 101

a=rtpmap:101 telephone-event/8000


message: 2017-01-25 04:54:33:113 channel [034002A0]: received [348] new bytes from [UDP://MMMMMM.infopbx.net:5060]:

ACK sip:address@hidden:5061 SIP/2.0

Via: SIP/2.0/UDP 69.9.143.3:5060;branch=z9hG4bK97vILOxAHrCfV8JL040792

To: <sip:address@hidden>;tag=e1U~tje

From: "[*]CCCXXCC JOHN" <sip:address@hidden>;tag=nWe8jVIdYDGAEbeq04078C

Call-ID: 20170125125329046443-4cc874112ce44e0e67e90933c07d6e61

CSeq: 201 ACK

Content-Length: 0



message: 2017-01-25 04:54:33:115 channel [034002A0] [348] bytes parsed

error: 2017-01-25 04:54:33:115 Missing mandatory header [Max-Forwards] for message [ACK]

message: 2017-01-25 04:54:33:116 channel [034002A0]: message sent to [UDP://MMMMMM.infopbx.net:5060], size: [314] bytes

SIP/2.0 400 Bad request

Via: SIP/2.0/UDP 69.9.143.3:5060;branch=z9hG4bK97vILOxAHrCfV8JL040792

From: "[*]CCCXXCC JOHN" <sip:address@hidden>;tag=nWe8jVIdYDGAEbeq04078C

To: <sip:address@hidden>;tag=e1U~tje

Call-ID: 20170125125329046443-4cc874112ce44e0e67e90933c07d6e61

CSeq: 201 ACK



message: 2017-01-25 04:54:33:383 Bandwidth usage for call [0491AA28]:

RTP audio=[d= 80.0,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

message: 2017-01-25 04:54:33:383 Thread processing load: audio=2.572473 video=0.000000 text=0.000000

message: 2017-01-25 04:54:33:503 channel [034002A0]: keep alive sent to [UDP://MMMMMM.infopbx.net:5060]

message: 2017-01-25 04:54:34:373 Bandwidth usage for call [0491AA28]:

RTP audio=[d= 80.0,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

RTCP audio=[d= 1.9,u= 1.3], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

message: 2017-01-25 04:54:34:373 Thread processing load: audio=1.611532 video=0.000000 text=0.000000

message: 2017-01-25 04:54:35:393 Bandwidth usage for call [0491AA28]:

RTP audio=[d= 80.0,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

message: 2017-01-25 04:54:35:393 Thread processing load: audio=1.376328 video=0.000000 text=0.000000

message: 2017-01-25 04:54:36:383 Bandwidth usage for call [0491AA28]:

RTP audio=[d= 80.0,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

message: 2017-01-25 04:54:36:383 Thread processing load: audio=3.070410 video=0.000000 text=0.000000

message: 2017-01-25 04:54:36:983 Dialog sending retransmission of 200Ok

message: 2017-01-25 04:54:36:983 channel [034002A0]: message sent to [UDP://MMMMMM.infopbx.net:5060], size: [792] bytes

SIP/2.0 200 Ok

Via: SIP/2.0/UDP 69.9.143.3:5060;branch=z9hG4bKnWe8jVIdYDGAEbeq04078C

From: "[*]CCCXXCC JOHN" <sip:address@hidden>;tag=nWe8jVIdYDGAEbeq04078C

To: <sip:address@hidden>;tag=e1U~tje

Call-ID: 20170125125329046443-4cc874112ce44e0e67e90933c07d6e61

CSeq: 201 INVITE

User-Agent: Linphone/3.10.2 (belle-sip/1.5.0)

Supported: replaces, outbound

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE

Contact: <sip:address@hidden:5061;transport=udp>;+sip.instance="<urn:uuid:aaf127dc-af0d-4a78-a1d5-4a5f3b9234c6>"

Content-Type: application/sdp

Content-Length: 146


v=0

o=1209 2258 3245 IN IP4 192.168.1.119

s=Talk

c=IN IP4 192.168.1.119

t=0 0

m=audio 7082 RTP/AVP 0 101

a=rtpmap:101 telephone-event/8000


message: 2017-01-25 04:54:37:073 channel [034002A0]: received [348] new bytes from [UDP://MMMMMM.infopbx.net:5060]:

ACK sip:address@hidden:5061 SIP/2.0

Via: SIP/2.0/UDP 69.9.143.3:5060;branch=z9hG4bK97vILOxAHrCfV8JL040792

To: <sip:address@hidden>;tag=e1U~tje

From: "[*]CCCXXCC JOHN" <sip:address@hidden>;tag=nWe8jVIdYDGAEbeq04078C

Call-ID: 20170125125329046443-4cc874112ce44e0e67e90933c07d6e61

CSeq: 201 ACK

Content-Length: 0



message: 2017-01-25 04:54:37:075 channel [034002A0] [348] bytes parsed

error: 2017-01-25 04:54:37:075 Missing mandatory header [Max-Forwards] for message [ACK]

message: 2017-01-25 04:54:37:075 channel [034002A0]: message sent to [UDP://MMMMMM.infopbx.net:5060], size: [314] bytes

SIP/2.0 400 Bad request

Via: SIP/2.0/UDP 69.9.143.3:5060;branch=z9hG4bK97vILOxAHrCfV8JL040792

From: "[*]CCCXXCC JOHN" <sip:address@hidden>;tag=nWe8jVIdYDGAEbeq04078C

To: <sip:address@hidden>;tag=e1U~tje

Call-ID: 20170125125329046443-4cc874112ce44e0e67e90933c07d6e61

CSeq: 201 ACK



message: 2017-01-25 04:54:37:373 Bandwidth usage for call [0491AA28]:

RTP audio=[d= 80.0,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

message: 2017-01-25 04:54:37:373 Thread processing load: audio=1.332124 video=0.000000 text=0.000000

message: 2017-01-25 04:54:37:974 MSAudio stream [033D38D0]: receiving RTCP SR

message: 2017-01-25 04:54:37:974 MSSimpleQosAnalyzer: lost_percentage=0.000000, int_jitter=9.750000 ms, rt_prop=15.233978 sec

message: 2017-01-25 04:54:37:974 MSSimpleQosAnalyzer: everything is fine.

message: 2017-01-25 04:54:37:974 MSBitrateController: current state is Init

message: 2017-01-25 04:54:37:974 MSQualityIndicator[005CCF28][audio], remote statistics available:

Loss rate : 0.0%

Inter-arrival jitter: 9.8ms

RT propagation : 15234.0ms

message: 2017-01-25 04:54:37:974 MSAudio stream [033D38D0]: receiving RTCP SR

message: 2017-01-25 04:54:38:285 Sending RTCP SR compound message on session [0491B198].

message: 2017-01-25 04:54:38:304 MSAudio_stream_iterate[033D38D0], local statistics available:

Local current jitter buffer size: 79.9ms

message: 2017-01-25 04:54:38:364 Bandwidth usage for call [0491AA28]:

RTP audio=[d= 80.0,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

message: 2017-01-25 04:54:38:364 Thread processing load: audio=3.862629 video=0.000000 text=0.000000

message: 2017-01-25 04:54:39:384 Bandwidth usage for call [0491AA28]:

RTP audio=[d= 80.0,u= 79.9], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

RTCP audio=[d= 1.9,u= 1.3], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

message: 2017-01-25 04:54:39:384 Thread processing load: audio=1.889869 video=0.000000 text=0.000000

message: 2017-01-25 04:54:40:374 Bandwidth usage for call [0491AA28]:

RTP audio=[d= 80.0,u= 80.1], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

message: 2017-01-25 04:54:40:374 Thread processing load: audio=3.472746 video=0.000000 text=0.000000

message: 2017-01-25 04:54:40:464 linphone_core_find_auth_info(): returning auth info username=1209, realm=MMMMMM.infopbx.net

message: 2017-01-25 04:54:40:464 Auth info found for [1209] realm [MMMMMM.infopbx.net]

message: 2017-01-25 04:54:40:464 Transaction [005CD108] deleted

message: 2017-01-25 04:54:40:464 Changing [client] [REGISTER] transaction [005CCFE8], from state [INIT] to [TRYING]

message: 2017-01-25 04:54:40:464 channel [034002A0]: message sent to [UDP://MMMMMM.infopbx.net:5060], size: [776] bytes

REGISTER sip:MMMMMM.infopbx.net SIP/2.0

Via: SIP/2.0/UDP 192.168.1.119:5061;branch=z9hG4bK.f6fVLoPdo;rport

From: <sip:address@hidden>;tag=rSp1QG51J

To: sip:address@hidden

CSeq: 24 REGISTER

Call-ID: 5dQ366BqGl

Max-Forwards: 70

Supported: replaces, outbound

Accept: application/sdp

Accept: text/plain

Accept: application/vnd.gsma.rcs-ft-http+xml

Contact: <sip:address@hidden:5061;transport=udp>;+sip.instance="<urn:uuid:aaf127dc-af0d-4a78-a1d5-4a5f3b9234c6>"

Expires: 3600

User-Agent: Linphone/3.10.2 (belle-sip/1.5.0)

Proxy-Authorization: Digest realm="MMMMMM.infopbx.net", nonce="81f165ac845816c1a5fe0cfc7e0a540f", username="1209", uri="sip:MMMMMM.infopbx.net", response="222ccda25ef940c2242ef726049139ae"



message: 2017-01-25 04:54:40:524 channel [034002A0]: received [393] new bytes from [UDP://MMMMMM.infopbx.net:5060]:

SIP/2.0 407 Proxy Authentication Required

Proxy-Authenticate: Digest realm="MMMMMM.infopbx.net", nonce="7fb6ac920be0344953d4d85dae765bfc"

Via: SIP/2.0/UDP 192.168.1.119:5061;branch=z9hG4bK.f6fVLoPdo;rport;received=77.77.77.66

To: sip:address@hidden

From: <sip:address@hidden>;tag=rSp1QG51J

Call-ID: 5dQ366BqGl

CSeq: 24 REGISTER

Content-Length: 0



message: 2017-01-25 04:54:40:527 channel [034002A0] [393] bytes parsed

message: 2017-01-25 04:54:40:527 Found transaction matching response.

message: 2017-01-25 04:54:40:527 Changing [client] [REGISTER] transaction [005CCFE8], from state [TRYING] to [COMPLETED]

message: 2017-01-25 04:54:40:527 linphone_core_find_auth_info(): returning auth info username=1209, realm=MMMMMM.infopbx.net

message: 2017-01-25 04:54:40:527 Auth info found for [1209] realm [MMMMMM.infopbx.net]

message: 2017-01-25 04:54:40:527 Changing [client] [REGISTER] transaction [005CD168], from state [INIT] to [TRYING]

message: 2017-01-25 04:54:40:527 channel [034002A0]: message sent to [UDP://MMMMMM.infopbx.net:5060], size: [776] bytes

REGISTER sip:MMMMMM.infopbx.net SIP/2.0

Via: SIP/2.0/UDP 192.168.1.119:5061;branch=z9hG4bK.HNl54LKGz;rport

From: <sip:address@hidden>;tag=rSp1QG51J

To: sip:address@hidden

CSeq: 25 REGISTER

Call-ID: 5dQ366BqGl

Max-Forwards: 70

Supported: replaces, outbound

Accept: application/sdp

Accept: text/plain

Accept: application/vnd.gsma.rcs-ft-http+xml

Contact: <sip:address@hidden:5061;transport=udp>;+sip.instance="<urn:uuid:aaf127dc-af0d-4a78-a1d5-4a5f3b9234c6>"

Expires: 3600

User-Agent: Linphone/3.10.2 (belle-sip/1.5.0)

Proxy-Authorization: Digest realm="MMMMMM.infopbx.net", nonce="7fb6ac920be0344953d4d85dae765bfc", username="1209", uri="sip:MMMMMM.infopbx.net", response="f922c22eead1673452cbd036d035d20d"



message: 2017-01-25 04:54:40:644 channel [034002A0]: received [482] new bytes from [UDP://MMMMMM.infopbx.net:5060]:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 192.168.1.119:5061;branch=z9hG4bK.HNl54LKGz;rport;received=77.77.77.66

To: sip:address@hidden

From: <sip:address@hidden>;tag=rSp1QG51J

Call-ID: 5dQ366BqGl

CSeq: 25 REGISTER

Contact: <sip:address@hidden:5061;transport=udp>;+sip.instance="<urn:uuid:aaf127dc-af0d-4a78-a1d5-4a5f3b9234c6>"

Expires: 30

Server: NetSapiens SiPBx 1-1225g2

Allow: INVITE,ACK,CANCEL,BYE,OPTIONS,REFER,SUBSCRIBE

Content-Length: 0



message: 2017-01-25 04:54:40:647 channel [034002A0] [482] bytes parsed

message: 2017-01-25 04:54:40:647 Found transaction matching response.

message: 2017-01-25 04:54:40:647 Changing [client] [REGISTER] transaction [005CD168], from state [TRYING] to [COMPLETED]

message: 2017-01-25 04:54:40:647 Refresher [0055A960]: has no contact for request [0338F338].

message: 2017-01-25 04:54:40:647 Refresher: scheduling next timer in 27000 ms

message: 2017-01-25 04:54:40:647 Register refresher [200] reason [OK] for proxy [<sip:MMMMMM.infopbx.net;transport=udp>]

message: 2017-01-25 04:54:40:647 Proxy config [0057B590] for identity [sip:address@hidden] moving from state [LinphoneRegistrationOk] to [LinphoneRegistrationOk] on core [0056F560]

message: 2017-01-25 04:54:40:649 Linphone core [0056F560] notifying [registration_state_changed]

message: 2017-01-25 04:54:40:974 Dialog sending retransmission of 200Ok

message: 2017-01-25 04:54:40:974 channel [034002A0]: message sent to [UDP://MMMMMM.infopbx.net:5060], size: [792] bytes

SIP/2.0 200 Ok

Via: SIP/2.0/UDP 69.9.143.3:5060;branch=z9hG4bKnWe8jVIdYDGAEbeq04078C

From: "[*]CCCXXCC JOHN" <sip:address@hidden>;tag=nWe8jVIdYDGAEbeq04078C

To: <sip:address@hidden>;tag=e1U~tje

Call-ID: 20170125125329046443-4cc874112ce44e0e67e90933c07d6e61

CSeq: 201 INVITE

User-Agent: Linphone/3.10.2 (belle-sip/1.5.0)

Supported: replaces, outbound

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE

Contact: <sip:address@hidden:5061;transport=udp>;+sip.instance="<urn:uuid:aaf127dc-af0d-4a78-a1d5-4a5f3b9234c6>"

Content-Type: application/sdp

Content-Length: 146


v=0

o=1209 2258 3245 IN IP4 192.168.1.119

s=Talk

c=IN IP4 192.168.1.119

t=0 0

m=audio 7082 RTP/AVP 0 101

a=rtpmap:101 telephone-event/8000


message: 2017-01-25 04:54:41:094 channel [034002A0]: received [348] new bytes from [UDP://MMMMMM.infopbx.net:5060]:

ACK sip:address@hidden:5061 SIP/2.0

Via: SIP/2.0/UDP 69.9.143.3:5060;branch=z9hG4bK97vILOxAHrCfV8JL040792

To: <sip:address@hidden>;tag=e1U~tje

From: "[*]CCCXXCC JOHN" <sip:address@hidden>;tag=nWe8jVIdYDGAEbeq04078C

Call-ID: 20170125125329046443-4cc874112ce44e0e67e90933c07d6e61

CSeq: 201 ACK

Content-Length: 0



message: 2017-01-25 04:54:41:096 channel [034002A0] [348] bytes parsed

error: 2017-01-25 04:54:41:096 Missing mandatory header [Max-Forwards] for message [ACK]

message: 2017-01-25 04:54:41:097 channel [034002A0]: message sent to [UDP://MMMMMM.infopbx.net:5060], size: [314] bytes

SIP/2.0 400 Bad request

Via: SIP/2.0/UDP 69.9.143.3:5060;branch=z9hG4bK97vILOxAHrCfV8JL040792

From: "[*]CCCXXCC JOHN" <sip:address@hidden>;tag=nWe8jVIdYDGAEbeq04078C

To: <sip:address@hidden>;tag=e1U~tje

Call-ID: 20170125125329046443-4cc874112ce44e0e67e90933c07d6e61

CSeq: 201 ACK



message: 2017-01-25 04:54:41:364 Bandwidth usage for call [0491AA28]:

RTP audio=[d= 80.0,u= 80.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

RTCP audio=[d= 0.0,u= 0.0], video=[d= 0.0,u= 0.0], text=[d= 0.0,u= 0.0] kbits/sec

message: 2017-01-25 04:54:41:364 Thread processing load: audio=3.268273 video=0.000000 text=0.000000

error: 2017-01-25 04:54:41:484 Dialog [00536298] was not ACK'd within T1*64 seconds, it is going to be terminated.

message: 2017-01-25 04:54:41:484 Dialog [00536298]: now updated by transaction [005CD108].

message: 2017-01-25 04:54:41:484 Changing [client] [BYE] transaction [005CD108], from state [INIT] to [TRYING]

message: 2017-01-25 04:54:41:484 channel [034002A0]: message sent to [UDP://MMMMMM.infopbx.net:5060], size: [351] bytes

BYE sip:69.9.143.3:5060;transport=udp SIP/2.0

Via: SIP/2.0/UDP 192.168.1.119:5061;branch=z9hG4bK.idik2SmSb;rport

From: <sip:address@hidden>;tag=e1U~tje

To: "[*]CCCXXCC JOHN" <sip:address@hidden>;tag=nWe8jVIdYDGAEbeq04078C

CSeq: 111 BYE

Call-ID: 20170125125329046443-4cc874112ce44e0e67e90933c07d6e61

Max-Forwards: 70



message: 2017-01-25 04:54:41:484 Changing [server] [INVITE] transaction [005CC928], from state [ACCEPTED] to [TERMINATED]

message: 2017-01-25 04:54:41:484 Server INVITE transaction [005CC928] terminated

message: 2017-01-25 04:54:41:484 Transaction [005CC928] deleted

message: 2017-01-25 04:54:41:575 channel [034002A0]: received [341] new bytes from [UDP://MMMMMM.infopbx.net:5060]:

SIP/2.0 200 OK

Via: SIP/2.0/UDP 192.168.1.119:5061;branch=z9hG4bK.idik2SmSb;rport;received=77.77.77.66

To: "[*]CCCXXCCJOHN"<sip:address@hidden>;tag=nWe8jVIdYDGAEbeq04078C

From: <sip:address@hidden>;tag=e1U~tje

Call-ID: 20170125125329046443-4cc874112ce44e0e67e90933c07d6e61

CSeq: 111 BYE

Content-Length: 0



message: 2017-01-25 04:54:41:577 channel [034002A0] [341] bytes parsed

message: 2017-01-25 04:54:41:577 Found transaction matching response.

message: 2017-01-25 04:54:41:577 Changing [client] [BYE] transaction [005CD108], from state [TRYING] to [COMPLETED]

message: 2017-01-25 04:54:41:577 Dialog [00536298]: now updated by transaction [005CD108].

message: 2017-01-25 04:54:41:577 Dialog [00536298] deleted (is_expired=0)

message: 2017-01-25 04:54:41:605 Dialog [00536298] terminated for op [03351470]

message: 2017-01-25 04:54:41:605 Current call terminated...

message: 2017-01-25 04:54:41:605 Writing echo canceler state, 21872 bytes

message: 2017-01-25 04:54:41:607 Filter MSRtpRecv is not scheduled; nothing to do.

message: 2017-01-25 04:54:41:607 ===========================================================

message: 2017-01-25 04:54:41:608 AUDIO SESSION'S RTP STATISTICS

message: 2017-01-25 04:54:41:608 -----------------------------------------------------------

message: 2017-01-25 04:54:41:608 sent 1604 packets

message: 2017-01-25 04:54:41:608 0 duplicated packets

message: 2017-01-25 04:54:41:608 275888 bytes

message: 2017-01-25 04:54:41:608 received 1602 packets

message: 2017-01-25 04:54:41:608 2 duplicated packets

message: 2017-01-25 04:54:41:608 274908 bytes

message: 2017-01-25 04:54:41:608 incoming delivered to the app 274194 bytes

message: 2017-01-25 04:54:41:608 incoming cumulative lost 1 packets

message: 2017-01-25 04:54:41:608 incoming received too late 0 packets

message: 2017-01-25 04:54:41:608 incoming bad formatted 0 packets

message: 2017-01-25 04:54:41:608 incoming discarded (queue overflow) 0 packets

message: 2017-01-25 04:54:41:608 sent rtcp 9 packets

message: 2017-01-25 04:54:41:608 received rtcp 9 packets

message: 2017-01-25 04:54:41:608 ===========================================================

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSWASAPIRead:005C7790,0-->MSResample:0333A9B0,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSResample:0333A9B0,0-->MSEqualizer:03354060,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSEqualizer:03354060,0-->MSSpeexEC:0059C7B8,1

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSSpeexEC:0059C7B8,1-->MSVolume:0333A930,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSVolume:0333A930,0-->MSAudioMixer:0333AA30,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSAudioMixer:0333AA30,0-->MSUlawEnc:0333A870,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSUlawEnc:0333A870,0-->MSRtpSend:0059CAF8,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSRtpRecv:005C7810,0-->MSUlawDec:0333A8F0,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSUlawDec:0333A8F0,0-->MSGenericPLC:03354120,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSGenericPLC:03354120,0-->MSDtmfGen:005C77D0,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSDtmfGen:005C77D0,0-->MSVolume:0333A970,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSVolume:0333A970,0-->MSTee:0333AAF0,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSTee:0333AAF0,0-->MSEqualizer:033540A0,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSEqualizer:033540A0,0-->MSAudioMixer:033541A0,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSFilePlayer:033541E0,0-->MSResample:03354220,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSResample:03354220,0-->MSAudioMixer:033541A0,1

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSAudioMixer:033541A0,0-->MSSpeexEC:0059C7B8,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSSpeexEC:0059C7B8,0-->MSResample:0333A9F0,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSResample:0333A9F0,0-->MSWASAPIWrite:0333A8B0,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSAudioMixer:0333AAB0,1-->MSResample:0059CD78,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSResample:0059CD78,0-->MSOpusEnc:0059CD38,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSOpusEnc:0059CD38,0-->MSMKVRecorder:0333AB30,1

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSItcSource:0333AB70,0-->MSMKVRecorder:0333AB30,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSAudioMixer:0333AA30,1-->MSAudioMixer:0333AAB0,0

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSTee:0333AAF0,1-->MSAudioMixer:0333AAB0,1

message: 2017-01-25 04:54:41:608 ms_filter_unlink: MSAudioMixer:0333AAB0,0-->MSFileRec:0333AA70,0

message: 2017-01-25 04:54:41:608 ===========================================================

message: 2017-01-25 04:54:41:608 FILTER USAGE STATISTICS

message: 2017-01-25 04:54:41:608 Name Count Time/tick (ms) CPU Usage

message: 2017-01-25 04:54:41:608 -----------------------------------------------------------

message: 2017-01-25 04:54:41:608 MSRtpSend 3210 0.159452 59.4657

message: 2017-01-25 04:54:41:608 MSResample 6678 0.0227579 17.6539

message: 2017-01-25 04:54:41:608 MSSpeexEC 3203 0.0330836 12.3113

message: 2017-01-25 04:54:41:608 MSRtpRecv 3210 0.0115229 4.29733

message: 2017-01-25 04:54:41:608 MSOpusEnc 99 0.23 2.67131

message: 2017-01-25 04:54:41:608 MSAudioMixer 9630 0.00124598 1.39373

message: 2017-01-25 04:54:41:608 MSUlawDec 1594 0.00250784 0.464576

message: 2017-01-25 04:54:41:608 MSWASAPIWrite 3382 0.00118238 0.464576

message: 2017-01-25 04:54:41:608 MSGenericPLC 3210 0.000934288 0.348432

message: 2017-01-25 04:54:41:608 MSFilePlayer 3210 0.000622859 0.232288

message: 2017-01-25 04:54:41:608 MSEqualizer 4791 0.000417362 0.232288

message: 2017-01-25 04:54:41:608 MSFileRec 99 0.01 0.116144

message: 2017-01-25 04:54:41:608 MSUlawEnc 3197 0.000312695 0.116144

message: 2017-01-25 04:54:41:608 MSDtmfGen 3579 0.00027933 0.116144

message: 2017-01-25 04:54:41:608 MSTee 1594 0.000626959 0.116144

message: 2017-01-25 04:54:41:608 MSItcSource 3210 0 0

message: 2017-01-25 04:54:41:608 MSMKVRecorder 49 0 0

message: 2017-01-25 04:54:41:608 MSVolume 4791 0 0

message: 2017-01-25 04:54:41:608 MSWASAPIRead 3210 0 0

message: 2017-01-25 04:54:41:608 MSOpusDec 369 0 0

message: 2017-01-25 04:54:41:608 MSMKVPlayer 369 0 0

message: 2017-01-25 04:54:41:608 MSItcSink 0 0 0

message: 2017-01-25 04:54:41:608 ===========================================================

message: 2017-01-25 04:54:41:610 MKVRecorder: destroyed

message: 2017-01-25 04:54:41:611 ===========================================================

message: 2017-01-25 04:54:41:611 FILTER USAGE STATISTICS

message: 2017-01-25 04:54:41:611 Name Count Time/tick (ms) CPU Usage

message: 2017-01-25 04:54:41:611 -----------------------------------------------------------

message: 2017-01-25 04:54:41:611 MSRtpSend 3210 0.159452 59.4657

message: 2017-01-25 04:54:41:611 MSResample 6678 0.0227579 17.6539

message: 2017-01-25 04:54:41:611 MSSpeexEC 3203 0.0330836 12.3113

message: 2017-01-25 04:54:41:611 MSRtpRecv 3210 0.0115229 4.29733

message: 2017-01-25 04:54:41:611 MSOpusEnc 99 0.23 2.67131

message: 2017-01-25 04:54:41:611 MSAudioMixer 9630 0.00124598 1.39373

message: 2017-01-25 04:54:41:611 MSUlawDec 1594 0.00250784 0.464576

message: 2017-01-25 04:54:41:611 MSWASAPIWrite 3382 0.00118238 0.464576

message: 2017-01-25 04:54:41:611 MSGenericPLC 3210 0.000934288 0.348432

message: 2017-01-25 04:54:41:611 MSFilePlayer 3210 0.000622859 0.232288

message: 2017-01-25 04:54:41:611 MSEqualizer 4791 0.000417362 0.232288

message: 2017-01-25 04:54:41:611 MSFileRec 99 0.01 0.116144

message: 2017-01-25 04:54:41:611 MSUlawEnc 3197 0.000312695 0.116144

message: 2017-01-25 04:54:41:611 MSDtmfGen 3579 0.00027933 0.116144

message: 2017-01-25 04:54:41:611 MSTee 1594 0.000626959 0.116144

message: 2017-01-25 04:54:41:611 MSItcSource 3210 0 0

message: 2017-01-25 04:54:41:611 MSMKVRecorder 49 0 0

message: 2017-01-25 04:54:41:611 MSVolume 4791 0 0

message: 2017-01-25 04:54:41:611 MSWASAPIRead 3210 0 0

message: 2017-01-25 04:54:41:611 MSOpusDec 369 0 0

message: 2017-01-25 04:54:41:611 MSMKVPlayer 369 0 0

message: 2017-01-25 04:54:41:611 MSItcSink 0 0 0

message: 2017-01-25 04:54:41:611 ===========================================================

message: 2017-01-25 04:54:41:611 Call 0491AA28: moving from state LinphoneCallStreamsRunning to LinphoneCallEnd

message: 2017-01-25 04:54:41:611 check OS support for qwave.lib

message: 2017-01-25 04:54:41:611 Stopping ZRTP context on session [00000000]

message: 2017-01-25 04:54:41:611 ZRTP context destroyed

message: 2017-01-25 04:54:41:616 MSAudio MSTicker thread exiting

message: 2017-01-25 04:54:41:616 Stopping ZRTP context on session [00000000]

message: 2017-01-25 04:54:41:616 ZRTP context destroyed

message: 2017-01-25 04:54:41:618 linphone_core_get_call_history(): completed in 0 ms

message: 2017-01-25 04:54:41:628 Linphone core [0056F560] notifying [call_log_updated]

message: 2017-01-25 04:54:41:628 Resetting the current call

message: 2017-01-25 04:54:41:628 StatusIcon: blinking set to FALSE

message: 2017-01-25 04:54:41:628 Linphone core [0056F560] notifying [call_state_changed]

message: 2017-01-25 04:54:41:638 Call 0491AA28: moving from state LinphoneCallEnd to LinphoneCallReleased

message: 2017-01-25 04:54:41:638 Linphone core [0056F560] notifying [call_state_changed]

message: 2017-01-25 04:54:41:638 op [03351470] : set_or_update_dialog() current=[00536298] new=[00000000]

message: 2017-01-25 04:54:41:638 Destroying op [03351470] of type [SalOpCall]

message: 2017-01-25 04:54:43:528 channel [034002A0]: keep alive sent to [UDP://MMMMMM.infopbx.net:5060]

message: 2017-01-25 04:54:44:008 Call [0491AA28] freed.

message: 2017-01-25 04:54:45:538 Changing [client] [REGISTER] transaction [005CCFE8], from state [COMPLETED] to [TERMINATED]

message: 2017-01-25 04:54:45:538 Client internal REGISTER transaction [005CCFE8] terminated

message: 2017-01-25 04:54:45:538 Transaction [005CCFE8] deleted

message: 2017-01-25 04:54:45:658 Changing [client] [REGISTER] transaction [005CD168], from state [COMPLETED] to [TERMINATED]

message: 2017-01-25 04:54:45:658 Client internal REGISTER transaction [005CD168] terminated

message: 2017-01-25 04:54:46:588 Changing [client] [BYE] transaction [005CD108], from state [COMPLETED] to [TERMINATED]

message: 2017-01-25 04:54:46:588 Client internal BYE transaction [005CD108] terminated

message: 2017-01-25 04:54:46:588 Transaction [005CD108] deleted

=============================
Thanks again. John


--

reply via email to

[Prev in Thread] Current Thread [Next in Thread]