In this case linphone doesn't like the ack it's received. 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] On Jan 25, 2017 10:38 AM, "John Statler" <[email protected]> wrote: > 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:[email protected]> > <sip:[email protected]>;tag=nWe8jVIdYDGAEbeq04078C > > To: <sip:[email protected]> <sip:[email protected]>; > 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:[email protected]:5061;transport=udp> > <sip:[email protected]: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:[email protected]:5061 SIP/2.0 > > Via: SIP/2.0/UDP 69.9.143.3:5060;branch=z9hG4bK97vILOxAHrCfV8JL040792 > > To: <sip:[email protected]> <sip:[email protected]>; > tag=e1U~tje > > From: "[*]CCCXXCC JOHN" <sip:[email protected]> > <sip:[email protected]>;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:[email protected]> > <sip:[email protected]>;tag=nWe8jVIdYDGAEbeq04078C > > To: <sip:[email protected]> <sip:[email protected]>; > 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:[email protected]> > <sip:[email protected]>;tag=nWe8jVIdYDGAEbeq04078C > > To: <sip:[email protected]> <sip:[email protected]>; > 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:[email protected]:5061;transport=udp> > <sip:[email protected]: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:[email protected]:5061 SIP/2.0 > > Via: SIP/2.0/UDP 69.9.143.3:5060;branch=z9hG4bK97vILOxAHrCfV8JL040792 > > To: <sip:[email protected]> <sip:[email protected]>; > tag=e1U~tje > > From: "[*]CCCXXCC JOHN" <sip:[email protected]> > <sip:[email protected]>;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:[email protected]> > <sip:[email protected]>;tag=nWe8jVIdYDGAEbeq04078C > > To: <sip:[email protected]> <sip:[email protected]>; > 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:[email protected]> <sip:[email protected]>; > tag=rSp1QG51J > > To: sip:[email protected] > > 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:[email protected]:5061;transport=udp> > <sip:[email protected]: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:[email protected] > > From: <sip:[email protected]> <sip:[email protected]>; > 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:[email protected]> <sip:[email protected]>; > tag=rSp1QG51J > > To: sip:[email protected] > > 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:[email protected]:5061;transport=udp> > <sip:[email protected]: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:[email protected] > > From: <sip:[email protected]> <sip:[email protected]>; > tag=rSp1QG51J > > Call-ID: 5dQ366BqGl > > CSeq: 25 REGISTER > > Contact: <sip:[email protected]:5061;transport=udp> > <sip:[email protected]: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:[email protected]] 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:[email protected]> > <sip:[email protected]>;tag=nWe8jVIdYDGAEbeq04078C > > To: <sip:[email protected]> <sip:[email protected]>; > 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:[email protected]:5061;transport=udp> > <sip:[email protected]: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:[email protected]:5061 SIP/2.0 > > Via: SIP/2.0/UDP 69.9.143.3:5060;branch=z9hG4bK97vILOxAHrCfV8JL040792 > > To: <sip:[email protected]> <sip:[email protected]>; > tag=e1U~tje > > From: "[*]CCCXXCC JOHN" <sip:[email protected]> > <sip:[email protected]>;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:[email protected]> > <sip:[email protected]>;tag=nWe8jVIdYDGAEbeq04078C > > To: <sip:[email protected]> <sip:[email protected]>; > 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:[email protected]> <sip:[email protected]>; > tag=e1U~tje > > To: "[*]CCCXXCC JOHN" <sip:[email protected]> > <sip:[email protected]>;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:[email protected]> > <sip:[email protected]>;tag=nWe8jVIdYDGAEbeq04078C > > From: <sip:[email protected]> <sip:[email protected]>; > 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 > > > -- > > _______________________________________________ > Linphone-users mailing list > [email protected] > https://lists.nongnu.org/mailman/listinfo/linphone-users > >
_______________________________________________ Linphone-users mailing list [email protected] https://lists.nongnu.org/mailman/listinfo/linphone-users
