linphone-developers
[Top][All Lists]
Advanced

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

Re: [Linphone-developers] Incoming calls hangs at 36 seconds


From: Russell Treleaven
Subject: Re: [Linphone-developers] Incoming calls hangs at 36 seconds
Date: Tue, 27 Nov 2018 19:49:40 -0500

Hi Sandra,

a normal inbound call establishment looks like this.
<-invite
->100 trying
->180 ringing
->200 ok
<-ack

In your case you are sending 200 ok repeatedly because you are not getting the ack from your peer.
I have not looked very deeply to see why(my eyes are sore), but the usual suspects are.

1. a malfunctioning sip alg(application layer gateway).
2. misconfigured natting firewall between you and the peer.

Has your internet connection changed to coincide with this issue?




On Tue, Nov 27, 2018 at 7:27 PM Sandra & Alejandro <address@hidden> wrote:
Hi,

I have been using linphone with my Voip SIP provider in the last 3 months on my laptop - ubuntu. Very happy so far.

Since recently, all the incoming calls I am receiving last exactly 36 seconds. No problem with outgoing calls. I have tried different ports but it is same problem. Also I tried different wifi connections and without and without VPN, but still. Here is the log for one call I just did from an android device to show you. I reset it so it wasn't too long.

Also, I have read in all the logs something that drops my attention but not sure if it means what I think (message: Local interface to reach 87.98.157.38 is 172.111.232.50.) I don't recognize this ip address 87.98.157.38 should I be worried about it?

Thanks for your help...

Sandra

message: Local interface to reach 87.98.157.38 is 172.111.232.50.
message: Message received from: 208.65.240.44:5060
message: Received message len=1162 from 208.65.240.44:5060:
INVITE sip:address@hidden:6060;transport=UDP SIP/2.0
Record-Route: <sip:address@hidden;lr=on>
Via: SIP/2.0/UDP 208.65.240.44;branch=z9hG4bKcd6a.b971729.0
Via: SIP/2.0/UDP 208.65.240.165:5060;branch=z9hG4bK-524287-1---4cd5c2665335d939;rport=5060
Via: SIP/2.0/UDP 208.65.240.165:5061;branch=z9hG4bK-xpg7va4qeh4a4vbf;rport=5061
Max-Forwards: 69
Record-Route: <sip:208.65.240.165:5060;lr;transport=UDP>
Contact: "Anonymous"<sip:208.65.240.165:5061>
To: <sip:address@hidden>
From: 16478470071 <sip:address@hidden>;tag=oyx76x6fsv33y456.o
Call-ID: fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
CSeq: 811 INVITE
Expires: 300
Content-Disposition: session
Content-Type: application/sdp
User-Agent: Sippy
h323-conf-id: 2174924046-1930174979-3411759228-1710557178
Portasip-3264-action: offer 1
cisco-GUID: 2174924046-1930174979-3411759228-1710557178
Content-Length: 241

v=0
o=Sippy 3770111738669013094 0 IN IP4 208.65.240.165
s=F
t=0 0
m=audio 51124 RTP/AVP 0 98 101 13
c=IN IP4 208.65.240.142
a=rtpmap:98 SILK/8000
a=fmtp:98 useinbandfec=1; usedtx=0
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16

message: MESSAGE REC. CALLID:fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
message: no transaction for message
message: allocating transaction resource 5 fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
message: allocating IST context
message: cb_rcvinvite (id=5)
message: DNS resolution with 208.65.240.44:5060
message: getaddrinfo returned: 208.65.240.44 port 5060
message: Message sent: (to dest=208.65.240.44:5060)
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 208.65.240.44;branch=z9hG4bKcd6a.b971729.0
Via: SIP/2.0/UDP 208.65.240.165:5060;branch=z9hG4bK-524287-1---4cd5c2665335d939;rport=5060
Via: SIP/2.0/UDP 208.65.240.165:5061;branch=z9hG4bK-xpg7va4qeh4a4vbf;rport=5061
From: 16478470071 <sip:address@hidden>;tag=oyx76x6fsv33y456.o
To: <sip:address@hidden>
Call-ID: fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
CSeq: 811 INVITE
User-Agent: Linphone/3.6.1 (eXosip2/4.1.0)
Content-Length: 0


message: cb_snd123456xx (id=5)
message: cb_snd123456xx (id=5)
message: eXosip: Reseting timer to 10s before waking up!
message: eXosip: Reseting timer to 10s before waking up!
message: linphone process event get a message 2

message: CALL_NEW

warning: extract_received_rport(): no rport and no received parameters.
message: Found payload PCMU/8000 fmtp=
message: Found payload SILK/8000 fmtp=useinbandfec=1; usedtx=0
message: Found payload telephone-event/8000 fmtp=0-16
error: payload number 13 has no rtpmap and is unknown in AV Profile, ignored.
warning:  searching for already_a_call_with_remote_address.
message: Local interface to reach 87.98.157.38 is 172.111.232.50.
message: Notifying all friends that we are in status 5
message: Doing SDP offer/answer process of type incoming
message: No match for SILK/8000
message: Starting local ring...
message: /home/sandra/Música/Amanecer.wav opened: rate=44100,channel=2
message: ms_filter_link: MSFilePlayer:0x563d72b86350,0-->MSDtmfGen:0x563d729d0920,0
message: ms_filter_link: MSDtmfGen:0x563d729d0920,0-->MSAlsaWrite:0x563d72abde00,0
message: Call 0x563d729b1480: moving from state LinphoneCallIdle to LinphoneCallIncomingReceived
message: Priority used: 99
message: Ring MSTicker setpriority() failed: Permiso denegado, nevermind.
message: alsa_open_w: opening default at 44100Hz, bits=16, stereo=1
warning: alsa_set_params: periodsize:1280 Using 1280
warning: alsa_set_params: period:8 Using 8
message: incoming call ringing for 0 seconds
message: DNS resolution with 208.65.240.44:5060
message: getaddrinfo returned: 208.65.240.44 port 5060
message: Message sent: (to dest=208.65.240.44:5060)
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 208.65.240.44;branch=z9hG4bKcd6a.b971729.0
Via: SIP/2.0/UDP 208.65.240.165:5060;branch=z9hG4bK-524287-1---4cd5c2665335d939;rport=5060
Via: SIP/2.0/UDP 208.65.240.165:5061;branch=z9hG4bK-xpg7va4qeh4a4vbf;rport=5061
Record-Route: <sip:address@hidden;lr=on>
Record-Route: <sip:208.65.240.165:5060;lr;transport=UDP>
From: 16478470071 <sip:address@hidden>;tag=oyx76x6fsv33y456.o
To: <sip:address@hidden>;tag=1320630202
Call-ID: fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
CSeq: 811 INVITE
Contact: <sip:address@hidden:5079>
User-Agent: Linphone/3.6.1 (eXosip2/4.1.0)
Content-Length: 0


message: cb_snd123456xx (id=5)
message: eXosip: Reseting timer to 10s before waking up!
message: incoming call ringing for 0 seconds
message: incoming call ringing for 0 seconds
message: incoming call ringing for 0 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: alsa_open_w: Audio params set
warning: Ring MSTicker: We are late of 200 miliseconds.
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: Dummy SIP message received
message: eXosip: Reseting timer to 10s before waking up!
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 1 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 2 seconds
message: incoming call ringing for 3 seconds
message: stop ringing
message: ms_filter_unlink: MSFilePlayer:0x563d72b86350,0-->MSDtmfGen:0x563d729d0920,0
message: ms_filter_unlink: MSDtmfGen:0x563d729d0920,0-->MSAlsaWrite:0x563d72abde00,0
message: Ring MSTicker thread exiting
message: ring stopped
message: Contact has been fixed using local ip to sip:address@hidden:5079
message: Setting DSCP to 46 for audio stream.
warning: cannot set noise gate mode to [0] because no volume send
message: Doing SDP offer/answer process of type incoming
message: No match for SILK/8000
message: Call 0x563d729b1480: moving from state LinphoneCallIncomingReceived to LinphoneCallConnected
message: DNS resolution with 208.65.240.44:5060
message: getaddrinfo returned: 208.65.240.44 port 5060
message: Message sent: (to dest=208.65.240.44:5060)
SIP/2.0 200 OK
Via: SIP/2.0/UDP 208.65.240.44;branch=z9hG4bKcd6a.b971729.0
Via: SIP/2.0/UDP 208.65.240.165:5060;branch=z9hG4bK-524287-1---4cd5c2665335d939;rport=5060
Via: SIP/2.0/UDP 208.65.240.165:5061;branch=z9hG4bK-xpg7va4qeh4a4vbf;rport=5061
Record-Route: <sip:address@hidden;lr=on>
Record-Route: <sip:208.65.240.165:5060;lr;transport=UDP>
From: 16478470071 <sip:address@hidden>;tag=oyx76x6fsv33y456.o
To: <sip:address@hidden>;tag=1320630202
Call-ID: fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
CSeq: 811 INVITE
Contact: <sip:address@hidden:5079>
Content-Type: application/sdp
User-Agent: Linphone/3.6.1 (eXosip2/4.1.0)
Content-Length:   193

v=0
o=16476274775 2249 180 IN IP4 172.111.232.50
s=Talk
c=IN IP4 172.111.232.50
t=0 0
m=audio 7078 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11

message: cb_snd123456xx (id=5)
message: cb_nict_kill_transaction (id=5)
message: eXosip: Reseting timer to 10s before waking up!
message: Audio bandwidth for this call is 80
message: Payload's bitrate is 80000
message: Setting audio encoder network bitrate to 80000
warning: MS_DECODER_HAVE_PLC function not implemented by the decoder: enable default plc
message: ms_filter_link: MSAlsaRead:0x563d72ba4d80,0-->MSSpeexEC:0x563d72bb1280,1
message: ms_filter_link: MSSpeexEC:0x563d72bb1280,1-->MSVolume:0x563d72b3b600,0
message: ms_filter_link: MSVolume:0x563d72b3b600,0-->MSTee:0x563d72b71b70,0
message: ms_filter_link: MSTee:0x563d72b71b70,0-->MSUlawEnc:0x563d72b82360,0
message: ms_filter_link: MSUlawEnc:0x563d72b82360,0-->MSRtpSend:0x563d72b322d0,0
message: ms_filter_link: MSRtpRecv:0x563d72ba7b80,0-->MSUlawDec:0x563d72b4d660,0
message: ms_filter_link: MSUlawDec:0x563d72b4d660,0-->MSGenericPLC:0x563d72b9de80,0
message: ms_filter_link: MSGenericPLC:0x563d72b9de80,0-->MSDtmfGen:0x563d72baae00,0
message: ms_filter_link: MSDtmfGen:0x563d72baae00,0-->MSVolume:0x563d72b3b6e0,0
message: ms_filter_link: MSVolume:0x563d72b3b6e0,0-->MSTee:0x563d72b71a70,0
message: ms_filter_link: MSTee:0x563d72b71a70,0-->MSEqualizer:0x563d72b9dcb0,0
message: ms_filter_link: MSEqualizer:0x563d72b9dcb0,0-->MSSpeexEC:0x563d72bb1280,0
message: ms_filter_link: MSSpeexEC:0x563d72bb1280,0-->MSAlsaWrite:0x563d72ba7b00,0
message: ms_filter_link: MSTee:0x563d72b71b70,1-->MSAudioMixer:0x563d72b3b8a0,0
message: ms_filter_link: MSTee:0x563d72b71a70,1-->MSAudioMixer:0x563d72b3b8a0,1
message: ms_filter_link: MSAudioMixer:0x563d72b3b8a0,0-->MSFileRec:0x563d72b3b7a0,0
message: Initializing speex echo canceler with framesize=64, filterlength=2000, delay_samples=0
message: Priority used: 99
message: Audio MSTicker setpriority() failed: Permiso denegado, nevermind.
message: Filter MSRtpRecv is already being scheduled; nothing to do.
message: MSVolume set gain to [0,000000 db], [1,000000] linear
message: Call 0x563d729b1480: moving from state LinphoneCallConnected to LinphoneCallStreamsRunning
message: call answered.
message: alsa_open_r: opening default at 8000Hz, bits=16, stereo=0
warning: alsa_set_params: periodsize:256 Using 256
warning: alsa_set_params: period:8 Using 8
message: ms_quality_indicator_update_local(): no packet received since last call
message: alsa_open_r: Audio params set
message: ms_ticker_set_time_func: ticker's time method updated.
warning: Getting reference signal but no echo to synchronize on.
warning: Not enough ref samples, using zeroes
message: alsa_open_w: opening default at 8000Hz, bits=16, stereo=0
warning: alsa_set_params: periodsize:256 Using 256
warning: alsa_set_params: period:8 Using 8
message: alsa_open_w: Audio params set
warning: Audio MSTicker: We are late of 207 miliseconds.
message: Samples are back.
warning: Not enough ref samples, using zeroes
message: Samples are back.
warning: Not enough ref samples, using zeroes
message: Samples are back.
message: bandwidth usage: audio=[d=73,9,u=77,5] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,438117 video=0,000000
message: sound/wall clock skew is average=4,270140 ms
message: DNS resolution with 208.65.240.44:5060
message: getaddrinfo returned: 208.65.240.44 port 5060
message: Message sent: (to dest=208.65.240.44:5060)
SIP/2.0 200 OK
Via: SIP/2.0/UDP 208.65.240.44;branch=z9hG4bKcd6a.b971729.0
Via: SIP/2.0/UDP 208.65.240.165:5060;branch=z9hG4bK-524287-1---4cd5c2665335d939;rport=5060
Via: SIP/2.0/UDP 208.65.240.165:5061;branch=z9hG4bK-xpg7va4qeh4a4vbf;rport=5061
Record-Route: <sip:address@hidden;lr=on>
Record-Route: <sip:208.65.240.165:5060;lr;transport=UDP>
From: 16478470071 <sip:address@hidden>;tag=oyx76x6fsv33y456.o
To: <sip:address@hidden>;tag=1320630202
Call-ID: fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
CSeq: 811 INVITE
Contact: <sip:address@hidden:5079>
Content-Type: application/sdp
User-Agent: Linphone/3.6.1 (eXosip2/4.1.0)
Content-Length:   193

v=0
o=16476274775 2249 180 IN IP4 172.111.232.50
s=Talk
c=IN IP4 172.111.232.50
t=0 0
m=audio 7078 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11

message: Local interface to reach 87.98.157.38 is 172.111.232.50.
message: bandwidth usage: audio=[d=81,2,u=81,2] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,431896 video=0,000000
message: audio_stream_iterate(): local statistics available
Local's current jitter buffer size:50,126583 ms
message: bandwidth usage: audio=[d=80,2,u=81,0] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,575084 video=0,000000
message: sound/wall clock skew is average=0,130514 ms
message: DNS resolution with 208.65.240.44:5060
message: getaddrinfo returned: 208.65.240.44 port 5060
message: Message sent: (to dest=208.65.240.44:5060)
SIP/2.0 200 OK
Via: SIP/2.0/UDP 208.65.240.44;branch=z9hG4bKcd6a.b971729.0
Via: SIP/2.0/UDP 208.65.240.165:5060;branch=z9hG4bK-524287-1---4cd5c2665335d939;rport=5060
Via: SIP/2.0/UDP 208.65.240.165:5061;branch=z9hG4bK-xpg7va4qeh4a4vbf;rport=5061
Record-Route: <sip:address@hidden;lr=on>
Record-Route: <sip:208.65.240.165:5060;lr;transport=UDP>
From: 16478470071 <sip:address@hidden>;tag=oyx76x6fsv33y456.o
To: <sip:address@hidden>;tag=1320630202
Call-ID: fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
CSeq: 811 INVITE
Contact: <sip:address@hidden:5079>
Content-Type: application/sdp
User-Agent: Linphone/3.6.1 (eXosip2/4.1.0)
Content-Length:   193

v=0
o=16476274775 2249 180 IN IP4 172.111.232.50
s=Talk
c=IN IP4 172.111.232.50
t=0 0
m=audio 7078 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11

message: bandwidth usage: audio=[d=84,1,u=80,9] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,860671 video=0,000000
message: sound/wall clock skew is average=-1,288491 ms
message: audio_stream_iterate(): local statistics available
Local's current jitter buffer size:61,111111 ms
message: bandwidth usage: audio=[d=81,3,u=80,5] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,874395 video=0,000000
message: bandwidth usage: audio=[d=75,3,u=80,2] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=2,062121 video=0,000000
message: sound/wall clock skew is average=-2,062723 ms
message: DNS resolution with 208.65.240.44:5060
message: getaddrinfo returned: 208.65.240.44 port 5060
message: Message sent: (to dest=208.65.240.44:5060)
SIP/2.0 200 OK
Via: SIP/2.0/UDP 208.65.240.44;branch=z9hG4bKcd6a.b971729.0
Via: SIP/2.0/UDP 208.65.240.165:5060;branch=z9hG4bK-524287-1---4cd5c2665335d939;rport=5060
Via: SIP/2.0/UDP 208.65.240.165:5061;branch=z9hG4bK-xpg7va4qeh4a4vbf;rport=5061
Record-Route: <sip:address@hidden;lr=on>
Record-Route: <sip:208.65.240.165:5060;lr;transport=UDP>
From: 16478470071 <sip:address@hidden>;tag=oyx76x6fsv33y456.o
To: <sip:address@hidden>;tag=1320630202
Call-ID: fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
CSeq: 811 INVITE
Contact: <sip:address@hidden:5079>
Content-Type: application/sdp
User-Agent: Linphone/3.6.1 (eXosip2/4.1.0)
Content-Length:   193

v=0
o=16476274775 2249 180 IN IP4 172.111.232.50
s=Talk
c=IN IP4 172.111.232.50
t=0 0
m=audio 7078 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11

message: Local interface to reach 87.98.157.38 is 172.111.232.50.
message: bandwidth usage: audio=[d=86,0,u=82,0] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,765259 video=0,000000
message: audio_stream_iterate(): local statistics available
Local's current jitter buffer size:62,880001 ms
message: bandwidth usage: audio=[d=81,6,u=79,9] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,420226 video=0,000000
message: sound/wall clock skew is average=-2,058033 ms
message: bandwidth usage: audio=[d=77,3,u=81,3] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,332809 video=0,000000
message: sound/wall clock skew is average=-2,124961 ms
message: audio_stream_iterate(): local statistics available
Local's current jitter buffer size:63,064518 ms
message: bandwidth usage: audio=[d=80,2,u=80,2] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,259475 video=0,000000
message: eXosip: Keep Alive sent on UDP!
message: eXosip: Reseting timer to 10s before waking up!
message: bandwidth usage: audio=[d=81,8,u=81,8] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,506426 video=0,000000
message: sound/wall clock skew is average=-2,384111 ms
message: DNS resolution with 208.65.240.44:5060
message: getaddrinfo returned: 208.65.240.44 port 5060
message: Message sent: (to dest=208.65.240.44:5060)
SIP/2.0 200 OK
Via: SIP/2.0/UDP 208.65.240.44;branch=z9hG4bKcd6a.b971729.0
Via: SIP/2.0/UDP 208.65.240.165:5060;branch=z9hG4bK-524287-1---4cd5c2665335d939;rport=5060
Via: SIP/2.0/UDP 208.65.240.165:5061;branch=z9hG4bK-xpg7va4qeh4a4vbf;rport=5061
Record-Route: <sip:address@hidden;lr=on>
Record-Route: <sip:208.65.240.165:5060;lr;transport=UDP>
From: 16478470071 <sip:address@hidden>;tag=oyx76x6fsv33y456.o
To: <sip:address@hidden>;tag=1320630202
Call-ID: fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
CSeq: 811 INVITE
Contact: <sip:address@hidden:5079>
Content-Type: application/sdp
User-Agent: Linphone/3.6.1 (eXosip2/4.1.0)
Content-Length:   193

v=0
o=16476274775 2249 180 IN IP4 172.111.232.50
s=Talk
c=IN IP4 172.111.232.50
t=0 0
m=audio 7078 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11

message: Local interface to reach 87.98.157.38 is 172.111.232.50.
message: bandwidth usage: audio=[d=76,1,u=80,2] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,293112 video=0,000000
message: audio_stream_iterate(): local statistics available
Local's current jitter buffer size:62,741936 ms
message: sound/wall clock skew is average=-2,518447 ms
message: bandwidth usage: audio=[d=84,9,u=79,2] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,154256 video=0,000000
message: bandwidth usage: audio=[d=84,2,u=81,7] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,245711 video=0,000000
message: sound/wall clock skew is average=-2,242216 ms
message: audio_stream_iterate(): local statistics available
Local's current jitter buffer size:71,746033 ms
message: bandwidth usage: audio=[d=80,8,u=81,7] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,243107 video=0,000000
message: bandwidth usage: audio=[d=81,5,u=80,6] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,161732 video=0,000000
message: sound/wall clock skew is average=-2,266131 ms
message: DNS resolution with 208.65.240.44:5060
message: getaddrinfo returned: 208.65.240.44 port 5060
message: Message sent: (to dest=208.65.240.44:5060)
SIP/2.0 200 OK
Via: SIP/2.0/UDP 208.65.240.44;branch=z9hG4bKcd6a.b971729.0
Via: SIP/2.0/UDP 208.65.240.165:5060;branch=z9hG4bK-524287-1---4cd5c2665335d939;rport=5060
Via: SIP/2.0/UDP 208.65.240.165:5061;branch=z9hG4bK-xpg7va4qeh4a4vbf;rport=5061
Record-Route: <sip:address@hidden;lr=on>
Record-Route: <sip:208.65.240.165:5060;lr;transport=UDP>
From: 16478470071 <sip:address@hidden>;tag=oyx76x6fsv33y456.o
To: <sip:address@hidden>;tag=1320630202
Call-ID: fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
CSeq: 811 INVITE
Contact: <sip:address@hidden:5079>
Content-Type: application/sdp
User-Agent: Linphone/3.6.1 (eXosip2/4.1.0)
Content-Length:   193

v=0
o=16476274775 2249 180 IN IP4 172.111.232.50
s=Talk
c=IN IP4 172.111.232.50
t=0 0
m=audio 7078 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11

message: Local interface to reach 87.98.157.38 is 172.111.232.50.
message: bandwidth usage: audio=[d=84,1,u=80,0] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,322813 video=0,000000
message: sound/wall clock skew is average=-2,044124 ms
message: bandwidth usage: audio=[d=77,2,u=81,2] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,330556 video=0,000000
message: bandwidth usage: audio=[d=82,3,u=81,5] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,486779 video=0,000000
message: sound/wall clock skew is average=-1,807964 ms
message: audio_stream_iterate(): local statistics available
Local's current jitter buffer size:64,065041 ms
message: bandwidth usage: audio=[d=82,6,u=80,2] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,260637 video=0,000000
message: eXosip: Keep Alive sent on UDP!
message: eXosip: Reseting timer to 10s before waking up!
message: sound/wall clock skew is average=-1,962342 ms
message: bandwidth usage: audio=[d=80,6,u=80,6] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,309183 video=0,000000
message: DNS resolution with 208.65.240.44:5060
message: getaddrinfo returned: 208.65.240.44 port 5060
message: Message sent: (to dest=208.65.240.44:5060)
SIP/2.0 200 OK
Via: SIP/2.0/UDP 208.65.240.44;branch=z9hG4bKcd6a.b971729.0
Via: SIP/2.0/UDP 208.65.240.165:5060;branch=z9hG4bK-524287-1---4cd5c2665335d939;rport=5060
Via: SIP/2.0/UDP 208.65.240.165:5061;branch=z9hG4bK-xpg7va4qeh4a4vbf;rport=5061
Record-Route: <sip:address@hidden;lr=on>
Record-Route: <sip:208.65.240.165:5060;lr;transport=UDP>
From: 16478470071 <sip:address@hidden>;tag=oyx76x6fsv33y456.o
To: <sip:address@hidden>;tag=1320630202
Call-ID: fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
CSeq: 811 INVITE
Contact: <sip:address@hidden:5079>
Content-Type: application/sdp
User-Agent: Linphone/3.6.1 (eXosip2/4.1.0)
Content-Length:   193

v=0
o=16476274775 2249 180 IN IP4 172.111.232.50
s=Talk
c=IN IP4 172.111.232.50
t=0 0
m=audio 7078 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11

message: Local interface to reach 87.98.157.38 is 172.111.232.50.
message: bandwidth usage: audio=[d=79,5,u=80,3] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,410197 video=0,000000
message: sound/wall clock skew is average=-1,554156 ms
message: bandwidth usage: audio=[d=81,7,u=80,1] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,130269 video=0,000000
message: bandwidth usage: audio=[d=84,0,u=79,9] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,483904 video=0,000000
message: sound/wall clock skew is average=-1,947770 ms
message: audio_stream_iterate(): local statistics available
Local's current jitter buffer size:71,825394 ms
message: bandwidth usage: audio=[d=83,1,u=81,5] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,326625 video=0,000000
message: sound/wall clock skew is average=-2,033160 ms
message: bandwidth usage: audio=[d=82,7,u=79,3] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,383419 video=0,000000
message: DNS resolution with 208.65.240.44:5060
message: getaddrinfo returned: 208.65.240.44 port 5060
message: Message sent: (to dest=208.65.240.44:5060)
SIP/2.0 200 OK
Via: SIP/2.0/UDP 208.65.240.44;branch=z9hG4bKcd6a.b971729.0
Via: SIP/2.0/UDP 208.65.240.165:5060;branch=z9hG4bK-524287-1---4cd5c2665335d939;rport=5060
Via: SIP/2.0/UDP 208.65.240.165:5061;branch=z9hG4bK-xpg7va4qeh4a4vbf;rport=5061
Record-Route: <sip:address@hidden;lr=on>
Record-Route: <sip:208.65.240.165:5060;lr;transport=UDP>
From: 16478470071 <sip:address@hidden>;tag=oyx76x6fsv33y456.o
To: <sip:address@hidden>;tag=1320630202
Call-ID: fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
CSeq: 811 INVITE
Contact: <sip:address@hidden:5079>
Content-Type: application/sdp
User-Agent: Linphone/3.6.1 (eXosip2/4.1.0)
Content-Length:   193

v=0
o=16476274775 2249 180 IN IP4 172.111.232.50
s=Talk
c=IN IP4 172.111.232.50
t=0 0
m=audio 7078 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11

message: Local interface to reach 87.98.157.38 is 172.111.232.50.
message: bandwidth usage: audio=[d=83,6,u=81,8] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,599098 video=0,000000
message: sound/wall clock skew is average=-2,151923 ms
message: bandwidth usage: audio=[d=76,3,u=79,5] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,552235 video=0,000000
message: Dummy SIP message received
message: eXosip: Reseting timer to 10s before waking up!
message: sound/wall clock skew is average=-2,206743 ms
message: bandwidth usage: audio=[d=82,3,u=82,3] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,670358 video=0,000000
message: audio_stream_iterate(): local statistics available
Local's current jitter buffer size:65,120003 ms
message: bandwidth usage: audio=[d=81,0,u=78,6] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,382884 video=0,000000
message: sound/wall clock skew is average=-2,109143 ms
message: bandwidth usage: audio=[d=82,4,u=82,4] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,547263 video=0,000000
message: DNS resolution with 208.65.240.44:5060
message: getaddrinfo returned: 208.65.240.44 port 5060
message: Message sent: (to dest=208.65.240.44:5060)
SIP/2.0 200 OK
Via: SIP/2.0/UDP 208.65.240.44;branch=z9hG4bKcd6a.b971729.0
Via: SIP/2.0/UDP 208.65.240.165:5060;branch=z9hG4bK-524287-1---4cd5c2665335d939;rport=5060
Via: SIP/2.0/UDP 208.65.240.165:5061;branch=z9hG4bK-xpg7va4qeh4a4vbf;rport=5061
Record-Route: <sip:address@hidden;lr=on>
Record-Route: <sip:208.65.240.165:5060;lr;transport=UDP>
From: 16478470071 <sip:address@hidden>;tag=oyx76x6fsv33y456.o
To: <sip:address@hidden>;tag=1320630202
Call-ID: fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
CSeq: 811 INVITE
Contact: <sip:address@hidden:5079>
Content-Type: application/sdp
User-Agent: Linphone/3.6.1 (eXosip2/4.1.0)
Content-Length:   193

v=0
o=16476274775 2249 180 IN IP4 172.111.232.50
s=Talk
c=IN IP4 172.111.232.50
t=0 0
m=audio 7078 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11

message: Local interface to reach 87.98.157.38 is 172.111.232.50.
message: bandwidth usage: audio=[d=83,2,u=80,7] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,568030 video=0,000000
message: sound/wall clock skew is average=-2,096884 ms
message: bandwidth usage: audio=[d=80,2,u=80,9] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,607370 video=0,000000
message: sound/wall clock skew is average=-2,138889 ms
message: bandwidth usage: audio=[d=81,2,u=80,3] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,478364 video=0,000000
message: audio_stream_iterate(): local statistics available
Local's current jitter buffer size:69,758064 ms
message: bandwidth usage: audio=[d=81,2,u=82,1] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,389956 video=0,000000
message: sound/wall clock skew is average=-2,277470 ms
message: bandwidth usage: audio=[d=80,9,u=78,5] video=[d=0,0,u=0,0] kbit/sec
message: Thread processing load: audio=1,284254 video=0,000000
message: DNS resolution with 208.65.240.44:5060
message: getaddrinfo returned: 208.65.240.44 port 5060
message: Message sent: (to dest=208.65.240.44:5060)
SIP/2.0 200 OK
Via: SIP/2.0/UDP 208.65.240.44;branch=z9hG4bKcd6a.b971729.0
Via: SIP/2.0/UDP 208.65.240.165:5060;branch=z9hG4bK-524287-1---4cd5c2665335d939;rport=5060
Via: SIP/2.0/UDP 208.65.240.165:5061;branch=z9hG4bK-xpg7va4qeh4a4vbf;rport=5061
Record-Route: <sip:address@hidden;lr=on>
Record-Route: <sip:208.65.240.165:5060;lr;transport=UDP>
From: 16478470071 <sip:address@hidden>;tag=oyx76x6fsv33y456.o
To: <sip:address@hidden>;tag=1320630202
Call-ID: fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
CSeq: 811 INVITE
Contact: <sip:address@hidden:5079>
Content-Type: application/sdp
User-Agent: Linphone/3.6.1 (eXosip2/4.1.0)
Content-Length:   193

v=0
o=16476274775 2249 180 IN IP4 172.111.232.50
s=Talk
c=IN IP4 172.111.232.50
t=0 0
m=audio 7078 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11

error: eXosip: no ACK received during 20s: dropping call
message: allocating transaction resource 6 fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
message: allocating NICT context
message: DNS resolution with 208.65.240.44:5060
message: getaddrinfo returned: 208.65.240.44 port 5060
message: Message sent: (to dest=208.65.240.44:5060)
BYE sip:208.65.240.165:5061 SIP/2.0
Via: SIP/2.0/UDP 172.111.232.50:5079;rport;branch=z9hG4bK194298910
Route: <sip:address@hidden;lr=on>
Route: <sip:208.65.240.165:5060;lr;transport=UDP>
From: <sip:address@hidden>;tag=1320630202
To: 16478470071 <sip:address@hidden>;tag=oyx76x6fsv33y456.o
Call-ID: fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
CSeq: 2 BYE
Contact: <sip:address@hidden:5079>
Max-Forwards: 70
User-Agent: Linphone/3.6.1 (eXosip2/4.1.0)
Content-Length: 0


message: cb_sndbye (id=6)
message: eXosip: Keep Alive sent on UDP!
message: linphone process event get a message 21

message: CALL_CLOSED or CANCELLED

message: Current call terminated...
message: ms_ticker_set_time_func: ticker's time method updated.
message: Filter MSRtpRecv is not scheduled; nothing to do.
message: ===========================================================
message:              AUDIO SESSION'S RTP STATISTICS               
message: -----------------------------------------------------------
message: sent                                          1839 packets
message:                                             316308 bytes 
message: received                                      1797 packets
message:                                             309084 bytes 
message: incoming delivered to the app               308396 bytes 
message: lost                                             0 packets
message: received too late                                0 packets
message: bad formatted                                    0 packets
message: discarded (queue overflow)                       0 packets
message: ===========================================================
message: ms_filter_unlink: MSAlsaRead:0x563d72ba4d80,0-->MSSpeexEC:0x563d72bb1280,1
message: ms_filter_unlink: MSSpeexEC:0x563d72bb1280,1-->MSVolume:0x563d72b3b600,0
message: ms_filter_unlink: MSVolume:0x563d72b3b600,0-->MSTee:0x563d72b71b70,0
message: ms_filter_unlink: MSTee:0x563d72b71b70,0-->MSUlawEnc:0x563d72b82360,0
message: ms_filter_unlink: MSUlawEnc:0x563d72b82360,0-->MSRtpSend:0x563d72b322d0,0
message: ms_filter_unlink: MSRtpRecv:0x563d72ba7b80,0-->MSUlawDec:0x563d72b4d660,0
message: ms_filter_unlink: MSUlawDec:0x563d72b4d660,0-->MSGenericPLC:0x563d72b9de80,0
message: ms_filter_unlink: MSGenericPLC:0x563d72b9de80,0-->MSDtmfGen:0x563d72baae00,0
message: ms_filter_unlink: MSDtmfGen:0x563d72baae00,0-->MSVolume:0x563d72b3b6e0,0
message: ms_filter_unlink: MSVolume:0x563d72b3b6e0,0-->MSTee:0x563d72b71a70,0
message: ms_filter_unlink: MSTee:0x563d72b71a70,0-->MSEqualizer:0x563d72b9dcb0,0
message: ms_filter_unlink: MSEqualizer:0x563d72b9dcb0,0-->MSSpeexEC:0x563d72bb1280,0
message: ms_filter_unlink: MSSpeexEC:0x563d72bb1280,0-->MSAlsaWrite:0x563d72ba7b00,0
message: ms_filter_unlink: MSTee:0x563d72b71b70,1-->MSAudioMixer:0x563d72b3b8a0,0
message: ms_filter_unlink: MSTee:0x563d72b71a70,1-->MSAudioMixer:0x563d72b3b8a0,1
message: ms_filter_unlink: MSAudioMixer:0x563d72b3b8a0,0-->MSFileRec:0x563d72b3b7a0,0
message: Audio MSTicker thread exiting
message: ===========================================================
message:                   FILTER USAGE STATISTICS                 
message: Name                Count     Time/tick (ms)      CPU Usage
message: -----------------------------------------------------------
message: MSSpeexEC           2977      0,0951988           37,9927  
message: MSAlsaWrite         2284      0,119116            36,4755  
message: MSAlsaRead          3680      0,0278456           13,7362  
message: MSRtpSend           3680      0,0106156           5,23667  
message: MSRtpRecv           3680      0,00745414          3,67712  
message: MSUlawEnc           2284      0,00165207          0,505895 
message: MSAudioMixer        3680      0,00101722          0,501794 
message: MSVolume            4098      0,000872846         0,479468 
message: MSUlawDec           1793      0,00145413          0,3496   
message: MSTee               4098      0,000612138         0,336257 
message: MSDtmfGen           3680      0,000462739         0,228268 
message: MSGenericPLC        3680      0,000450754         0,222356 
message: MSFileRec           3680      0,000368426         0,181744 
message: MSEqualizer         1814      0,000314153         0,076412 
message: ===========================================================
message: Call 0x563d729b1480: moving from state LinphoneCallStreamsRunning to LinphoneCallEnd
message: Resetting the current call
message: Notifying all friends that we are in status 1
message: Message received from: 208.65.240.44:5060
message: Received message len=355 from 208.65.240.44:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.111.232.50:5079;rport=5079;branch=z9hG4bK194298910
Record-Route: <sip:208.65.240.44;lr>
To: 16478470071 <sip:address@hidden>;tag=oyx76x6fsv33y456.o
From: <sip:address@hidden>;tag=1320630202
Call-ID: fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
CSeq: 2 BYE
Server: Sippy
Content-Length: 0


message: MESSAGE REC. CALLID:fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
message: cb_rcv2xx (id=6)
message: linphone process event get a message 16

message: EXOSIP_CALL_MESSAGE_ANSWERED
message: Local interface to reach 87.98.157.38 is 172.111.232.50.
message: cb_nict_kill_transaction (id=6)
message: eXosip: _eXosip_release_finished_calls remove a dialog (cid=5 did=-1)
message: eXosip: remove a call
message: free transaction resource 5 fe4b4c21-6d40-1237-129a-000c29499bbd~o~o
message: free ist resource
message: eXosip: Reseting timer to 10s before waking up!
message: eXosip: Reseting timer to 10s before waking up!
message: linphone process event get a message 22

message: CALL_RELEASED

message: Call 0x563d729b1480: moving from state LinphoneCallEnd to LinphoneCallReleased
message: Cleaning cid 5
message: Local interface to reach 87.98.157.38 is 172.111.232.50.
message: Local interface to reach 87.98.157.38 is 172.111.232.50.





_______________________________________________
Linphone-developers mailing list
address@hidden
https://lists.nongnu.org/mailman/listinfo/linphone-developers


--
Sincerely,

Russell Treleaven
sip:address@hidden;transport=tcp


reply via email to

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