-------- [20121219-07:04:54] [message] oRTP-0.20.0 initialized. [20121219-07:04:54] [message] assigning PCMU/8000 payload type number 0 [20121219-07:04:54] [message] assigning GSM/8000 payload type number 3 [20121219-07:04:54] [message] assigning PCMA/8000 payload type number 8 [20121219-07:04:54] [message] assigning speex/8000 payload type number 110 [20121219-07:04:54] [message] assigning speex/16000 payload type number 111 [20121219-07:04:54] [message] assigning speex/32000 payload type number 112 [20121219-07:04:54] [message] assigning telephone-event/8000 payload type number 101 [20121219-07:04:54] [message] assigning G722/8000 payload type number 9 [20121219-07:04:54] [message] assigning H263/90000 payload type number 34 [20121219-07:04:54] [message] assigning theora/90000 payload type number 97 [20121219-07:04:54] [message] assigning H263-1998/90000 payload type number 98 [20121219-07:04:54] [message] assigning MP4V-ES/90000 payload type number 99 [20121219-07:04:54] [message] assigning H264/90000 payload type number 102 [20121219-07:04:54] [message] assigning VP8/90000 payload type number 103 [20121219-07:04:54] [message] assigning x-snow/90000 payload type number 96 [20121219-07:04:54] [message] assigning iLBC/8000 payload type number 100 [20121219-07:04:54] [message] assigning AMR/8000 payload type number 104 [20121219-07:04:54] [message] assigning AMR-WB/16000 payload type number 105 [20121219-07:04:54] [message] assigning 1015/8000 payload type number 106 [20121219-07:04:54] [message] assigning G726-16/8000 payload type number 107 [20121219-07:04:54] [message] assigning G726-24/8000 payload type number 108 [20121219-07:04:54] [message] assigning G726-32/8000 payload type number 109 [20121219-07:04:54] [message] assigning G726-40/8000 payload type number 113 [20121219-07:04:54] [message] assigning AAL2-G726-16/8000 payload type number 114 [20121219-07:04:54] [message] assigning AAL2-G726-24/8000 payload type number 115 [20121219-07:04:54] [message] assigning AAL2-G726-32/8000 payload type number 116 [20121219-07:04:54] [message] assigning AAL2-G726-40/8000 payload type number 117 [20121219-07:04:54] [message] assigning SILK/8000 payload type number 118 [20121219-07:04:54] [message] assigning SILK/12000 payload type number 119 [20121219-07:04:54] [message] assigning SILK/16000 payload type number 120 [20121219-07:04:54] [message] assigning SILK/24000 payload type number 121 [20121219-07:04:54] [message] assigning G729/8000 payload type number 18 [20121219-07:04:54] [message] Mediastreamer2 2.8.2 (git: 2.8.2) starting. [20121219-07:04:54] [message] Registering all soundcard handlers [20121219-07:04:54] [message] Card 'ALSA: default device' added [20121219-07:04:54] [warning] Could not attach mixer to card: Invalid argument [20121219-07:04:54] [message] Card 'ALSA: HDA Intel' added [20121219-07:04:54] [warning] Could not attach mixer to card: Invalid argument [20121219-07:04:54] [warning] Strange, sound card HDA NVidia does not seems to be capable of anything, retrying with plughw... [20121219-07:04:54] [warning] Strange, sound card HDA NVidia seems totally unusable. [20121219-07:04:54] [message] Card 'ALSA: HDA NVidia' added [20121219-07:04:54] [message] Card 'PulseAudio: default' added [20121219-07:04:54] [message] New PulseAudio context state: PA_CONTEXT_CONNECTING [20121219-07:04:54] [message] New PulseAudio context state: PA_CONTEXT_FAILED [20121219-07:04:54] [message] Registering all webcam handlers [20121219-07:04:54] [message] Webcam V4L2: /dev/video0 added [20121219-07:04:54] [message] Webcam StaticImage: Static picture added [20121219-07:04:54] [message] Loading ms plugins fron [/usr/lib/mediastreamer/plugins] [20121219-07:04:54] [message] Loading plugin /usr/lib/mediastreamer/plugins/libmsx264.so.0.0.0... [20121219-07:04:54] [message] ms264-1.4.1 plugin registered. [20121219-07:04:54] [message] Plugin loaded (/usr/lib/mediastreamer/plugins/libmsx264.so.0.0.0) [20121219-07:04:54] [message] Loading plugin /usr/lib/mediastreamer/plugins/libmsbcg729.so.0.0.0... [20121219-07:04:54] [message] libmsbcg729 1.0.0 plugin loaded [20121219-07:04:54] [message] Plugin loaded (/usr/lib/mediastreamer/plugins/libmsbcg729.so.0.0.0) [20121219-07:04:54] [message] ms_init() done [20121219-07:04:54] [message] Cannot open directory /usr/lib/liblinphone/plugins: No such file or directory [20121219-07:04:54] [message] linphone_core_set_playback_gain_db(): no active call. [20121219-07:04:54] [message] Adding new codec L16/44100 with fmtp [20121219-07:04:54] [message] getaddrinfo returned the following addresses: [20121219-07:04:54] [message] 0.0.0.0 port 5060 [20121219-07:04:54] [message] eXosip: Reseting timer to 10s before waking up! [20121219-07:04:54] [message] New local ip address is 192.168.0.101 [20121219-07:04:54] [message] Network state is now [UP] [20121219-07:04:54] [message] getaddrinfo returned the following addresses: [20121219-07:04:54] [message] 0.0.0.0 port 5060 [20121219-07:04:54] [message] Local interface to reach 87.98.157.38 is 192.168.0.101. [20121219-07:04:54] [message] allocating transaction ressource 1 422128585 [20121219-07:04:54] [message] allocating NICT context [20121219-07:04:54] [message] eXosip_dnsutils_naptr_lookup: About to ask for 'sip.linphone.org NAPTR' [20121219-07:04:54] [error] eXosip_dnsutils_naptr_lookup: res_query failed ('sip.linphone.org NAPTR') [20121219-07:04:54] [message] eXosip: Reseting timer to 10s before waking up! [20121219-07:04:54] [message] DNS resolution with sip.linphone.org:5060 [20121219-07:04:54] [message] getaddrinfo returned the following addresses: [20121219-07:04:54] [message] 37.59.129.73 port 5060 [20121219-07:04:54] [message] Message sent: (to dest=37.59.129.73:5060) REGISTER sip:sip.linphone.org SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK1524262573 From: ;tag=216868713 To: Call-ID: 422128585 CSeq: 1 REGISTER Contact: Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Expires: 3600 Content-Length: 0 [20121219-07:04:54] [message] eXosip option set: dns cache added:sip.linphone.org -> 37.59.129.73 [20121219-07:04:54] [message] cb_sndregister (id=1) [20121219-07:04:54] [message] eXosip: timer sec:0 usec:509979! [20121219-07:04:54] [message] Received message: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK1524262573;received=1.2.3.4 From: ;tag=216868713 To: ;tag=DrZa8D8S94pKN Call-ID: 422128585 CSeq: 1 REGISTER Contact: Server: Flexisip/0.5.0 (sofia-sip-nta/2.0) WWW-Authenticate: Digest realm="sip.linphone.org", nonce="VOZ71AAAAADZ9yN9AADMobiT7toAAAAA", opaque="+GNywA==", algorithm=MD5, qop="auth" Content-Length: 0 [20121219-07:04:54] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:54] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:54] [message] MESSAGE REC. CALLID:422128585 [20121219-07:04:54] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:54] [message] cb_rcv4xx (id=1) [20121219-07:04:54] [message] eXosip: timer sec:5 usec:10000! [20121219-07:04:54] [message] linphone process event get a message 2 [20121219-07:04:54] [message] REGISTRATION_FAILURE [20121219-07:04:54] [message] auth_requested() for realm="sip.linphone.org", username=squirtle [20121219-07:04:54] [message] auth_requested(): authenticating realm="sip.linphone.org", username=squirtle [20121219-07:04:54] [message] Authentication info for username [squirtle], id[squirtle], realm ["sip.linphone.org"] added to eXosip [20121219-07:04:54] [message] Contact address updated to [20121219-07:04:54] [message] INFO: authinfo: "sip.linphone.org" "sip.linphone.org" [20121219-07:04:54] [message] allocating transaction ressource 2 422128585 [20121219-07:04:54] [message] allocating NICT context [20121219-07:04:54] [message] eXosip_default_action() done [20121219-07:04:54] [message] eXosip option set: dns cache used:sip.linphone.org -> 37.59.129.73 [20121219-07:04:54] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:04:54] [message] getaddrinfo returned the following addresses: [20121219-07:04:54] [message] 37.59.129.73 port 5060 [20121219-07:04:54] [message] Message sent: (to dest=37.59.129.73:5060) REGISTER sip:sip.linphone.org SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK648105715 From: ;tag=216868713 To: Call-ID: 422128585 CSeq: 2 REGISTER Contact: Authorization: Digest username="squirtle", realm="sip.linphone.org", nonce="VOZ71AAAAADZ9yN9AADMobiT7toAAAAA", uri="sip:sip.linphone.org", response="ee9859829399f12a6388c13da7ca798c", algorithm=MD5, cnonce="0a4f113b", opaque="+GNywA==", qop=auth, nc=00000001 Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Expires: 3600 Content-Length: 0 [20121219-07:04:54] [message] eXosip option set: dns cache updated:sip.linphone.org -> 37.59.129.73 [20121219-07:04:54] [message] cb_sndregister (id=2) [20121219-07:04:54] [message] eXosip: timer sec:0 usec:509987! [20121219-07:04:54] [message] Received message: SIP/2.0 200 Registration successful Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK648105715;received=1.2.3.4 From: ;tag=216868713 To: ;tag=FajvB4903p3rc Call-ID: 422128585 CSeq: 2 REGISTER Contact: ;expires=3600 Server: Flexisip/0.5.0 (sofia-sip-nta/2.0) Content-Length: 0 [20121219-07:04:54] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:54] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:54] [message] MESSAGE REC. CALLID:422128585 [20121219-07:04:54] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:54] [message] cb_rcv2xx (id=2) [20121219-07:04:54] [message] eXosip: timer sec:4 usec:983284! [20121219-07:04:54] [message] linphone process event get a message 1 [20121219-07:04:54] [message] "sip.linphone.org"/squirtle authentication works. [20121219-07:04:54] [message] contact do not match, need to update the register ( with 1.2.3.4:5060;transport=udp) [20121219-07:04:54] [message] authinfo: No authentication found for squirtle "sip.linphone.org" [20121219-07:04:54] [message] allocating transaction ressource 3 422128585 [20121219-07:04:54] [message] allocating NICT context [20121219-07:04:54] [message] Resending new register with updated contact [20121219-07:04:54] [message] eXosip option set: dns cache used:sip.linphone.org -> 37.59.129.73 [20121219-07:04:54] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:04:54] [message] getaddrinfo returned the following addresses: [20121219-07:04:54] [message] 37.59.129.73 port 5060 [20121219-07:04:54] [message] Message sent: (to dest=37.59.129.73:5060) REGISTER sip:sip.linphone.org SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK2143474083 From: ;tag=216868713 To: Call-ID: 422128585 CSeq: 3 REGISTER Contact: Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Expires: 3600 Content-Length: 0 [20121219-07:04:54] [message] eXosip option set: dns cache updated:sip.linphone.org -> 37.59.129.73 [20121219-07:04:54] [message] cb_sndregister (id=3) [20121219-07:04:54] [message] eXosip: timer sec:0 usec:509987! [20121219-07:04:54] [message] Received message: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK2143474083;received=1.2.3.4 From: ;tag=216868713 To: ;tag=gKBNDZt40ZSBr Call-ID: 422128585 CSeq: 3 REGISTER Contact: Server: Flexisip/0.5.0 (sofia-sip-nta/2.0) WWW-Authenticate: Digest realm="sip.linphone.org", nonce="VOZ71AAAAACvb8s5AAAuueLucJEAAAAA", opaque="+GNywA==", algorithm=MD5, qop="auth" Content-Length: 0 [20121219-07:04:54] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:54] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:54] [message] MESSAGE REC. CALLID:422128585 [20121219-07:04:54] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:54] [message] cb_rcv4xx (id=3) [20121219-07:04:54] [message] eXosip: timer sec:4 usec:956858! [20121219-07:04:54] [message] linphone process event get a message 2 [20121219-07:04:54] [message] REGISTRATION_FAILURE [20121219-07:04:54] [message] auth_requested() for realm="sip.linphone.org", username=squirtle [20121219-07:04:54] [message] auth_requested(): authenticating realm="sip.linphone.org", username=squirtle [20121219-07:04:54] [message] Authentication info for username [squirtle], id[squirtle], realm ["sip.linphone.org"] added to eXosip [20121219-07:04:54] [message] Contact address updated to [20121219-07:04:54] [message] INFO: authinfo: "sip.linphone.org" "sip.linphone.org" [20121219-07:04:54] [message] allocating transaction ressource 4 422128585 [20121219-07:04:54] [message] allocating NICT context [20121219-07:04:54] [message] eXosip_default_action() done [20121219-07:04:54] [message] eXosip option set: dns cache used:sip.linphone.org -> 37.59.129.73 [20121219-07:04:54] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:04:54] [message] getaddrinfo returned the following addresses: [20121219-07:04:54] [message] 37.59.129.73 port 5060 [20121219-07:04:54] [message] Message sent: (to dest=37.59.129.73:5060) REGISTER sip:sip.linphone.org SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK1258488464 From: ;tag=216868713 To: Call-ID: 422128585 CSeq: 4 REGISTER Contact: Authorization: Digest username="squirtle", realm="sip.linphone.org", nonce="VOZ71AAAAACvb8s5AAAuueLucJEAAAAA", uri="sip:sip.linphone.org", response="513a4fa82e19b1614a1f9c2bcad3d432", algorithm=MD5, cnonce="0a4f113b", opaque="+GNywA==", qop=auth, nc=00000001 Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Expires: 3600 Content-Length: 0 [20121219-07:04:54] [message] eXosip option set: dns cache updated:sip.linphone.org -> 37.59.129.73 [20121219-07:04:54] [message] cb_sndregister (id=4) [20121219-07:04:54] [message] eXosip: timer sec:0 usec:509988! [20121219-07:04:54] [message] Received message: SIP/2.0 200 Registration successful Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK1258488464;received=1.2.3.4 From: ;tag=216868713 To: ;tag=Hv4DFtB8X8FyK Call-ID: 422128585 CSeq: 4 REGISTER Contact: ;expires=3600 Server: Flexisip/0.5.0 (sofia-sip-nta/2.0) Content-Length: 0 [20121219-07:04:54] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:54] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:54] [message] MESSAGE REC. CALLID:422128585 [20121219-07:04:54] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:54] [message] cb_rcv2xx (id=4) [20121219-07:04:54] [message] eXosip: timer sec:4 usec:924270! [20121219-07:04:54] [message] linphone process event get a message 1 [20121219-07:04:54] [message] "sip.linphone.org"/squirtle authentication works. [20121219-07:04:54] [message] Register has up to date contact, doing nothing. [20121219-07:04:58] [message] Sending a new SUBSCRIBE [20121219-07:04:58] [message] Contact for subscribe has been fixed using proxy to [20121219-07:04:58] [message] allocating transaction ressource 5 2036121733 [20121219-07:04:58] [message] allocating NICT context [20121219-07:04:58] [message] linphone_friend_apply() done. [20121219-07:04:58] [message] eXosip option set: dns cache used:sip.linphone.org -> 37.59.129.73 [20121219-07:04:58] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:04:58] [message] getaddrinfo returned the following addresses: [20121219-07:04:58] [message] 37.59.129.73 port 5060 [20121219-07:04:58] [message] Message sent: (to dest=37.59.129.73:5060) SUBSCRIBE sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK627146988 From: ;tag=446971923 To: Call-ID: 2036121733 CSeq: 20 SUBSCRIBE Contact: Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Expires: 600 Event: presence Content-Length: 0 [20121219-07:04:58] [message] cb_sndsubscribe (id=5) [20121219-07:04:58] [message] eXosip: timer sec:0 usec:509992! [20121219-07:04:58] [message] Received message: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK627146988;received=1.2.3.4 From: ;tag=446971923 To: ;tag=1cStBBS0FQm9c Call-ID: 2036121733 CSeq: 20 SUBSCRIBE Contact: Server: Flexisip/0.5.0 (sofia-sip-nta/2.0) Proxy-Authenticate: Digest realm="sip.linphone.org", nonce="V+Z71AAAAAB0crNAAAAgsTDQZ+oAAAAA", opaque="+GNywA==", algorithm=MD5, qop="auth" Content-Length: 0 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] MESSAGE REC. CALLID:2036121733 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] cb_rcv4xx (id=5) [20121219-07:04:58] [message] eXosip: timer sec:1 usec:495371! [20121219-07:04:58] [message] linphone process event get a message 40 [20121219-07:04:58] [message] auth_requested() for realm="sip.linphone.org", username=squirtle [20121219-07:04:58] [message] auth_requested(): authenticating realm="sip.linphone.org", username=squirtle [20121219-07:04:58] [message] Authentication info for username [squirtle], id[squirtle], realm ["sip.linphone.org"] added to eXosip [20121219-07:04:58] [message] Contact address updated to [20121219-07:04:58] [message] INFO: authinfo: "sip.linphone.org" "sip.linphone.org" [20121219-07:04:58] [message] authinfo: squirtle [20121219-07:04:58] [message] allocating transaction ressource 6 2036121733 [20121219-07:04:58] [message] allocating NICT context [20121219-07:04:58] [message] eXosip_default_action() done [20121219-07:04:58] [message] eXosip option set: dns cache used:sip.linphone.org -> 37.59.129.73 [20121219-07:04:58] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:04:58] [message] getaddrinfo returned the following addresses: [20121219-07:04:58] [message] 37.59.129.73 port 5060 [20121219-07:04:58] [message] Message sent: (to dest=37.59.129.73:5060) SUBSCRIBE sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK862950742 From: ;tag=446971923 To: Call-ID: 2036121733 CSeq: 21 SUBSCRIBE Contact: Proxy-Authorization: Digest username="squirtle", realm="sip.linphone.org", nonce="V+Z71AAAAAB0crNAAAAgsTDQZ+oAAAAA", uri="sip:address@hidden", response="5114503cccce9c8ba421d684ea66d260", algorithm=MD5, cnonce="0a4f113b", opaque="+GNywA==", qop=auth, nc=00000001 Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Expires: 600 Event: presence Content-Length: 0 [20121219-07:04:58] [message] cb_sndsubscribe (id=6) [20121219-07:04:58] [message] eXosip: timer sec:0 usec:509993! [20121219-07:04:58] [message] Received message: SIP/2.0 101 Dialog Establishement Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK862950742;received=1.2.3.4 Record-Route: From: ;tag=446971923 To: ;tag=41 Call-ID: 2036121733 CSeq: 21 SUBSCRIBE Contact: User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Event: presence Content-Length: 0 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] MESSAGE REC. CALLID:2036121733 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] cb_rcv1xx (id=6) [20121219-07:04:58] [message] eXosip: timer sec:0 usec:328386! [20121219-07:04:58] [message] Received message: SIP/2.0 202 Accepted subscription Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK862950742;received=1.2.3.4 Record-Route: From: ;tag=446971923 To: ;tag=41 Call-ID: 2036121733 CSeq: 21 SUBSCRIBE Contact: Expires: 600 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Event: presence Content-Length: 0 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] MESSAGE REC. CALLID:2036121733 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] cb_rcv2xx (id=6) [20121219-07:04:58] [message] eXosip: timer sec:1 usec:271511! [20121219-07:04:58] [message] linphone process event get a message 37 [20121219-07:04:58] [message] Unhandled exosip event ! 37 [20121219-07:04:58] [message] linphone process event get a message 38 [20121219-07:04:58] [message] EXOSIP_SUBSCRIPTION_ANSWERED, ev->sid=1, ev->did=2 [20121219-07:04:58] [message] Received message: NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.6c355gmZD9Fp2DN6S5tg9jU0BB Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK5678;received=9.8.7.6 Max-Forwards: 70 From: ;tag=41 To: ;tag=446971923 Call-ID: 2036121733 CSeq: 3 NOTIFY Contact: User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subscription-State: active;expires=600 Content-Type: application/pidf+xml Content-Length: 360 open sip:address@hidden [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] MESSAGE REC. CALLID:2036121733 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] This is a request [20121219-07:04:58] [message] allocating transaction ressource 7 2036121733 [20121219-07:04:58] [message] allocating NIST context [20121219-07:04:58] [message] cb_rcvunkrequest (id=7) [20121219-07:04:58] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:04:58] [message] getaddrinfo returned the following addresses: [20121219-07:04:58] [message] 37.59.129.73 port 5060 [20121219-07:04:58] [message] Message sent: (to dest=37.59.129.73:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.6c355gmZD9Fp2DN6S5tg9jU0BB Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK5678;received=9.8.7.6 From: ;tag=41 To: ;tag=446971923 Call-ID: 2036121733 CSeq: 3 NOTIFY User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:04:58] [message] cb_snd123456xx (id=7) [20121219-07:04:58] [message] eXosip: timer sec:1 usec:58359! [20121219-07:04:58] [message] eXosip: timer sec:1 usec:58322! [20121219-07:04:58] [message] linphone process event get a message 43 [20121219-07:04:58] [message] CALL_SUBSCRIPTION_NOTIFY [20121219-07:04:58] [message] Receiving notify with sid=1,nid=0 [20121219-07:04:58] [message] We are notified that ;tag=41 has online status 1 [20121219-07:04:58] [message] Received message: SUBSCRIBE sip:address@hidden:5060;line=de07b2535c9193a SIP/2.0 Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.vv03gKgHyjg51ZNrXa8H3a2HcB Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK17252;received=9.8.7.6 Record-Route: Max-Forwards: 70 From: ;tag=31787 To: Call-ID: 21292 CSeq: 21 SUBSCRIBE Contact: Expires: 600 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Event: presence Content-Length: 0 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] MESSAGE REC. CALLID:21292 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] This is a request [20121219-07:04:58] [message] allocating transaction ressource 8 21292 [20121219-07:04:58] [message] allocating NIST context [20121219-07:04:58] [message] cb_rcvunkrequest (id=8) [20121219-07:04:58] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:04:58] [message] getaddrinfo returned the following addresses: [20121219-07:04:58] [message] 37.59.129.73 port 5060 [20121219-07:04:58] [message] Message sent: (to dest=37.59.129.73:5060) SIP/2.0 101 Dialog Establishement Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.vv03gKgHyjg51ZNrXa8H3a2HcB Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK17252;received=9.8.7.6 Record-Route: From: ;tag=31787 To: ;tag=161942888 Call-ID: 21292 CSeq: 21 SUBSCRIBE Contact: Event: presence User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:04:58] [message] cb_snd123456xx (id=8) [20121219-07:04:58] [message] eXosip: timer sec:1 usec:46450! [20121219-07:04:58] [message] eXosip: timer sec:1 usec:46414! [20121219-07:04:58] [message] linphone process event get a message 45 [20121219-07:04:58] [message] CALL_IN_SUBSCRIPTION_NEW [20121219-07:04:58] [message] Receiving new subscription from ;tag=31787. [20121219-07:04:58] [message] Contact for next subscribe answer has been fixed using proxy to [20121219-07:04:58] [message] Want to notify , insub=0x134b8b0 [20121219-07:04:58] [message] allocating transaction ressource 9 21292 [20121219-07:04:58] [message] allocating NICT context [20121219-07:04:58] [message] linphone_friend_apply() done. [20121219-07:04:58] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:04:58] [message] getaddrinfo returned the following addresses: [20121219-07:04:58] [message] 37.59.129.73 port 5060 [20121219-07:04:58] [message] Message sent: (to dest=37.59.129.73:5060) SIP/2.0 202 Accepted subscription Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.vv03gKgHyjg51ZNrXa8H3a2HcB Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK17252;received=9.8.7.6 Record-Route: From: ;tag=31787 To: ;tag=161942888 Call-ID: 21292 CSeq: 21 SUBSCRIBE Contact: Event: presence User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Expires: 600 Content-Length: 0 [20121219-07:04:58] [message] cb_snd123456xx (id=8) [20121219-07:04:58] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:04:58] [message] getaddrinfo returned the following addresses: [20121219-07:04:58] [message] 37.59.129.73 port 5060 [20121219-07:04:58] [message] Message sent: (to dest=37.59.129.73:5060) NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK245276670 Route: From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 2 NOTIFY Contact: Content-Type: application/pidf+xml Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subscription-State: active;expires=600 Content-Length: 368 open sip:address@hidden [20121219-07:04:58] [message] cb_sndnotify (id=9) [20121219-07:04:58] [message] eXosip: timer sec:0 usec:509968! [20121219-07:04:58] [message] eXosip: timer sec:0 usec:509920! [20121219-07:04:58] [message] Received message: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK245276670;received=1.2.3.4 From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 2 NOTIFY Contact: Server: Flexisip/0.5.0 (sofia-sip-nta/2.0) Proxy-Authenticate: Digest realm="sip.linphone.org", nonce="WOZ71AAAAADMUVEzAADzadyEUykAAAAA", opaque="+GNywA==", algorithm=MD5, qop="auth" Content-Length: 0 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] MESSAGE REC. CALLID:21292 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] cb_rcv4xx (id=9) [20121219-07:04:58] [message] eXosip: timer sec:1 usec:10000! [20121219-07:04:58] [message] linphone process event get a message 51 [20121219-07:04:58] [message] auth_requested() for realm="sip.linphone.org", username=squirtle [20121219-07:04:58] [message] auth_requested(): authenticating realm="sip.linphone.org", username=squirtle [20121219-07:04:58] [message] Authentication info for username [squirtle], id[squirtle], realm ["sip.linphone.org"] added to eXosip [20121219-07:04:58] [message] Contact address updated to [20121219-07:04:58] [message] INFO: authinfo: "sip.linphone.org" "sip.linphone.org" [20121219-07:04:58] [message] authinfo: squirtle [20121219-07:04:58] [message] allocating transaction ressource 10 21292 [20121219-07:04:58] [message] allocating NICT context [20121219-07:04:58] [message] eXosip_default_action() done [20121219-07:04:58] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:04:58] [message] getaddrinfo returned the following addresses: [20121219-07:04:58] [message] 37.59.129.73 port 5060 [20121219-07:04:58] [message] Message sent: (to dest=37.59.129.73:5060) NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK1615186332 Route: From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 3 NOTIFY Contact: Proxy-Authorization: Digest username="squirtle", realm="sip.linphone.org", nonce="WOZ71AAAAADMUVEzAADzadyEUykAAAAA", uri="sip:address@hidden", response="779e4849f966da227ccc92cd7d68d652", algorithm=MD5, cnonce="0a4f113b", opaque="+GNywA==", qop=auth, nc=00000001 Content-Type: application/pidf+xml Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subscription-State: active;expires=600 Content-Length: 368 open sip:address@hidden [20121219-07:04:58] [message] cb_sndnotify (id=10) [20121219-07:04:58] [message] eXosip: timer sec:0 usec:509990! [20121219-07:04:58] [message] Received message: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK1615186332;received=1.2.3.4 From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 3 NOTIFY User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] MESSAGE REC. CALLID:21292 [20121219-07:04:58] [message] Message received from: 37.59.129.73:5060 [20121219-07:04:58] [message] cb_rcv2xx (id=10) [20121219-07:04:58] [message] eXosip: timer sec:0 usec:806308! [20121219-07:04:58] [message] linphone process event get a message 49 [20121219-07:04:58] [message] Unhandled exosip event ! 49 [20121219-07:04:59] [message] cb_nict_kill_transaction (id=1) [20121219-07:04:59] [message] eXosip: timer sec:0 usec:25677! [20121219-07:04:59] [message] cb_nict_kill_transaction (id=2) [20121219-07:04:59] [message] eXosip: timer sec:0 usec:26272! [20121219-07:04:59] [message] cb_nict_kill_transaction (id=3) [20121219-07:04:59] [message] eXosip: timer sec:0 usec:32381! [20121219-07:04:59] [message] cb_nict_kill_transaction (id=4) [20121219-07:04:59] [message] eXosip: timer sec:3 usec:428692! [20121219-07:05:03] [message] cb_nict_kill_transaction (id=5) [20121219-07:05:03] [message] eXosip: timer sec:0 usec:221147! [20121219-07:05:03] [message] cb_nict_kill_transaction (id=6) [20121219-07:05:03] [message] eXosip: timer sec:0 usec:262602! [20121219-07:05:03] [message] cb_nict_kill_transaction (id=9) [20121219-07:05:03] [message] eXosip: timer sec:0 usec:201792! [20121219-07:05:03] [message] cb_nict_kill_transaction (id=10) [20121219-07:05:03] [message] eXosip: Reseting timer to 10s before waking up! [20121219-07:05:04] [message] Received message: NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.5yj4ajj7XSyQS9yaX3ZKKajjpe Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK19047;received=9.8.7.6 Max-Forwards: 70 From: ;tag=41 To: ;tag=446971923 Call-ID: 2036121733 CSeq: 5 NOTIFY Contact: User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subscription-State: active;expires=595 Content-Type: application/pidf+xml Content-Length: 454 open sip:address@hidden [20121219-07:05:04] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:04] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:04] [message] MESSAGE REC. CALLID:2036121733 [20121219-07:05:04] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:04] [message] This is a request [20121219-07:05:04] [message] allocating transaction ressource 11 2036121733 [20121219-07:05:04] [message] allocating NIST context [20121219-07:05:04] [message] cb_rcvunkrequest (id=11) [20121219-07:05:04] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:04] [message] getaddrinfo returned the following addresses: [20121219-07:05:04] [message] 37.59.129.73 port 5060 [20121219-07:05:04] [message] Message sent: (to dest=37.59.129.73:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.5yj4ajj7XSyQS9yaX3ZKKajjpe Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK19047;received=9.8.7.6 From: ;tag=41 To: ;tag=446971923 Call-ID: 2036121733 CSeq: 5 NOTIFY User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:04] [message] cb_snd123456xx (id=11) [20121219-07:05:04] [message] free transaction ressource 3 422128585 [20121219-07:05:04] [message] free nict ressource [20121219-07:05:04] [message] free transaction ressource 2 422128585 [20121219-07:05:04] [message] free nict ressource [20121219-07:05:04] [message] free transaction ressource 1 422128585 [20121219-07:05:04] [message] free nict ressource [20121219-07:05:04] [message] eXosip: Reseting timer to 10s before waking up! [20121219-07:05:04] [message] eXosip: Reseting timer to 10s before waking up! [20121219-07:05:04] [message] linphone process event get a message 43 [20121219-07:05:04] [message] CALL_SUBSCRIPTION_NOTIFY [20121219-07:05:04] [message] Receiving notify with sid=1,nid=0 [20121219-07:05:04] [message] We are notified that ;tag=41 has online status 5 [20121219-07:05:04] [message] Received message: INVITE sip:address@hidden:5060;line=de07b2535c9193a SIP/2.0 Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.pvyye3vBSBc9Dc70eNpgUFFjtD Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK14293;received=9.8.7.6 Record-Route: Max-Forwards: 70 From: ;tag=22983 To: Call-ID: 28648 CSeq: 21 INVITE Contact: Subject: Phone call User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Content-Type: application/sdp Content-Length: 501 v=0 o=charmander 3594 3594 IN IP4 9.8.7.6 s=Talk c=IN IP4 37.59.129.73 t=0 0 a=nortpproxy:yes m=audio 33520 RTP/AVP 112 111 110 3 0 8 101 a=rtpmap:112 speex/32000 a=fmtp:112 vbr=on a=rtpmap:111 speex/16000 a=fmtp:111 vbr=on a=rtpmap:110 speex/8000 a=fmtp:110 vbr=on a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 m=video 11058 RTP/AVP 103 99 98 a=rtpmap:103 VP8/90000 a=rtpmap:99 MP4V-ES/90000 a=fmtp:99 profile-level-id=3 a=rtpmap:98 H263-1998/90000 a=fmtp:98 CIF=1;QCIF=1 [20121219-07:05:04] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:04] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:04] [message] MESSAGE REC. CALLID:28648 [20121219-07:05:04] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:04] [message] This is a request [20121219-07:05:04] [message] allocating transaction ressource 12 28648 [20121219-07:05:04] [message] allocating IST context [20121219-07:05:04] [message] cb_rcvinvite (id=12) [20121219-07:05:04] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:04] [message] getaddrinfo returned the following addresses: [20121219-07:05:04] [message] 37.59.129.73 port 5060 [20121219-07:05:04] [message] Message sent: (to dest=37.59.129.73:5060) SIP/2.0 100 Trying Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.pvyye3vBSBc9Dc70eNpgUFFjtD Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK14293;received=9.8.7.6 From: ;tag=22983 To: Call-ID: 28648 CSeq: 21 INVITE User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:04] [message] cb_snd123456xx (id=12) [20121219-07:05:04] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:04] [message] getaddrinfo returned the following addresses: [20121219-07:05:04] [message] 37.59.129.73 port 5060 [20121219-07:05:04] [message] Message sent: (to dest=37.59.129.73:5060) SIP/2.0 101 Dialog Establishement Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.pvyye3vBSBc9Dc70eNpgUFFjtD Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK14293;received=9.8.7.6 Record-Route: From: ;tag=22983 To: ;tag=1268709216 Call-ID: 28648 CSeq: 21 INVITE Contact: User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:04] [message] cb_snd123456xx (id=12) [20121219-07:05:04] [message] keep alive: 0 [20121219-07:05:04] [message] eXosip: Keep Alive sent on UDP! [20121219-07:05:04] [message] eXosip: Reseting timer to 10s before waking up! [20121219-07:05:04] [message] eXosip: Reseting timer to 10s before waking up! [20121219-07:05:04] [message] linphone process event get a message 5 [20121219-07:05:04] [message] CALL_NEW [20121219-07:05:04] [warning] extract_received_rport(): no rport and no received parameters. [20121219-07:05:04] [message] Found payload speex/32000 fmtp=vbr=on [20121219-07:05:04] [message] Found payload speex/16000 fmtp=vbr=on [20121219-07:05:04] [message] Found payload speex/8000 fmtp=vbr=on [20121219-07:05:04] [message] Found payload GSM/8000 fmtp= [20121219-07:05:04] [message] Found payload PCMU/8000 fmtp= [20121219-07:05:04] [message] Found payload PCMA/8000 fmtp= [20121219-07:05:04] [message] Found payload telephone-event/8000 fmtp=0-11 [20121219-07:05:04] [message] Found payload VP8/90000 fmtp= [20121219-07:05:04] [message] Found payload MP4V-ES/90000 fmtp=profile-level-id=3 [20121219-07:05:04] [message] Found payload H263-1998/90000 fmtp=CIF=1;QCIF=1 [20121219-07:05:04] [warning] searching for already_a_call_with_remote_address. [20121219-07:05:04] [message] allocating transaction ressource 13 1593394462 [20121219-07:05:04] [message] allocating NICT context [20121219-07:05:04] [message] Local interface to reach 87.98.157.38 is 192.168.0.101. [20121219-07:05:04] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:04] [message] getaddrinfo returned the following addresses: [20121219-07:05:04] [message] Notifying all friends that we are in status 5 [20121219-07:05:04] [message] Want to notify , insub=0x134b8b0 [20121219-07:05:04] [message] 37.59.129.73 port 5060 [20121219-07:05:04] [message] Message sent: (to dest=37.59.129.73:5060) OPTIONS sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK869303881 From: ;tag=758109914 To: Call-ID: 1593394462 CSeq: 20 OPTIONS Accept: application/sdp Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:04] [message] cb_sndoptions (id=13) [20121219-07:05:04] [message] eXosip: timer sec:0 usec:509980! [20121219-07:05:04] [message] allocating transaction ressource 14 21292 [20121219-07:05:04] [message] allocating NICT context [20121219-07:05:04] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:04] [message] getaddrinfo returned the following addresses: [20121219-07:05:04] [message] 37.59.129.73 port 5060 [20121219-07:05:04] [message] Message sent: (to dest=37.59.129.73:5060) NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK1712616743 Route: From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 4 NOTIFY Contact: Content-Type: application/pidf+xml Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subscription-State: active;expires=594 Content-Length: 462 open sip:address@hidden [20121219-07:05:04] [message] cb_sndnotify (id=14) [20121219-07:05:04] [message] eXosip: timer sec:0 usec:509529! [20121219-07:05:04] [message] Received message: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK869303881;received=1.2.3.4 From: ;tag=758109914 To: ;tag=S5QDpmDNaDBaK Call-ID: 1593394462 CSeq: 20 OPTIONS Server: Flexisip/0.5.0 (sofia-sip-nta/2.0) Proxy-Authenticate: Digest realm="sip.linphone.org", nonce="XuZ71AAAAADU7yoLAAA8uT4+K6UAAAAA", opaque="+GNywA==", algorithm=MD5, qop="auth" Content-Length: 0 [20121219-07:05:04] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:04] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:04] [message] MESSAGE REC. CALLID:1593394462 [20121219-07:05:04] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:04] [message] cb_rcv4xx (id=13) [20121219-07:05:04] [message] eXosip: timer sec:0 usec:501609! [20121219-07:05:04] [message] Received message: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK1712616743;received=1.2.3.4 From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 4 NOTIFY Contact: Server: Flexisip/0.5.0 (sofia-sip-nta/2.0) Proxy-Authenticate: Digest realm="sip.linphone.org", nonce="XuZ71AAAAAC/q37pAABEW0I+1lIAAAAA", opaque="+GNywA==", algorithm=MD5, qop="auth" Content-Length: 0 [20121219-07:05:04] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:04] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:04] [message] MESSAGE REC. CALLID:21292 [20121219-07:05:04] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:04] [message] cb_rcv4xx (id=14) [20121219-07:05:04] [message] eXosip: timer sec:5 usec:10000! [20121219-07:05:04] [message] Sending stun requests... [20121219-07:05:04] [message] STUN test result: local audio port maps to 1.2.3.4:7078 [20121219-07:05:04] [message] NAT is symmetric for audio port [20121219-07:05:05] [message] Partial MTU discovered : 1500 [20121219-07:05:05] [message] mtu to sip.linphone.org is 1500 [20121219-07:05:05] [message] Discovered mtu is 1500, RTP payload max size is 1440 [20121219-07:05:05] [message] Doing SDP offer/answer process [20121219-07:05:05] [message] Processing for stream 0 [20121219-07:05:05] [message] remote proto: AVP => 0x1360518 [20121219-07:05:05] [message] Processing for stream 1 [20121219-07:05:05] [message] remote proto: AVP => (nil) [20121219-07:05:05] [message] retry with AVP => (nil) [20121219-07:05:05] [message] Starting local ring... [20121219-07:05:05] [message] /usr/share/sounds/linphone/rings/toy-mono.wav opened: rate=44100,channel=1 [20121219-07:05:05] [message] ms_filter_link: MSFilePlayer:0x1362db0,0-->MSDtmfGen:0x1362e30,0 [20121219-07:05:05] [message] ms_filter_link: MSDtmfGen:0x1362e30,0-->MSAlsaWrite:0x1362eb0,0 [20121219-07:05:05] [message] Call 0x135f960: moving from state LinphoneCallIdle to LinphoneCallIncomingReceived [20121219-07:05:05] [message] alsa_open_w: opening default at 44100Hz, bits=16, stereo=0 [20121219-07:05:05] [warning] alsa_set_params: The period size 1280 is not supported by your hardware. ==> Using 940 instead. [20121219-07:05:05] [warning] alsa_set_params: periodsize:1280 Using 940 [20121219-07:05:05] [warning] alsa_set_params: period:8 Using 8 [20121219-07:05:05] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:05] [message] getaddrinfo returned the following addresses: [20121219-07:05:05] [message] 37.59.129.73 port 5060 [20121219-07:05:05] [message] Message sent: (to dest=37.59.129.73:5060) SIP/2.0 180 Ringing Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.pvyye3vBSBc9Dc70eNpgUFFjtD Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK14293;received=9.8.7.6 Record-Route: From: ;tag=22983 To: ;tag=1268709216 Call-ID: 28648 CSeq: 21 INVITE Contact: User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:05] [message] cb_snd123456xx (id=12) [20121219-07:05:05] [message] eXosip: timer sec:4 usec:281516! [20121219-07:05:05] [message] linphone process event get a message 31 [20121219-07:05:05] [message] auth_requested() for realm="sip.linphone.org", username=squirtle [20121219-07:05:05] [message] auth_requested(): authenticating realm="sip.linphone.org", username=squirtle [20121219-07:05:05] [message] Authentication info for username [squirtle], id[squirtle], realm ["sip.linphone.org"] added to eXosip [20121219-07:05:05] [message] Contact address updated to [20121219-07:05:05] [message] INFO: authinfo: "sip.linphone.org" "sip.linphone.org" [20121219-07:05:05] [message] authinfo: squirtle [20121219-07:05:05] [message] allocating transaction ressource 15 1593394462 [20121219-07:05:05] [message] allocating NICT context [20121219-07:05:05] [message] eXosip_default_action() done [20121219-07:05:05] [message] linphone process event get a message 51 [20121219-07:05:05] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:05] [message] getaddrinfo returned the following addresses: [20121219-07:05:05] [message] 37.59.129.73 port 5060 [20121219-07:05:05] [message] auth_requested() for realm="sip.linphone.org", username=squirtle [20121219-07:05:05] [message] auth_requested(): authenticating realm="sip.linphone.org", username=squirtle [20121219-07:05:05] [message] Message sent: (to dest=37.59.129.73:5060) OPTIONS sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK2025251153 From: ;tag=758109914 To: Call-ID: 1593394462 CSeq: 21 OPTIONS Proxy-Authorization: Digest username="squirtle", realm="sip.linphone.org", nonce="XuZ71AAAAADU7yoLAAA8uT4+K6UAAAAA", uri="sip:address@hidden", response="cff566f16eff50c1c908f1640272083b", algorithm=MD5, cnonce="0a4f113b", opaque="+GNywA==", qop=auth, nc=00000001 Accept: application/sdp Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:05] [message] cb_sndoptions (id=15) [20121219-07:05:05] [message] Authentication info for username [squirtle], id[squirtle], realm ["sip.linphone.org"] added to eXosip [20121219-07:05:05] [message] eXosip: timer sec:0 usec:509987! [20121219-07:05:05] [message] Contact address updated to [20121219-07:05:05] [message] INFO: authinfo: "sip.linphone.org" "sip.linphone.org" [20121219-07:05:05] [message] authinfo: squirtle [20121219-07:05:05] [message] allocating transaction ressource 16 21292 [20121219-07:05:05] [message] allocating NICT context [20121219-07:05:05] [message] eXosip_default_action() done [20121219-07:05:05] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:05] [message] getaddrinfo returned the following addresses: [20121219-07:05:05] [message] 37.59.129.73 port 5060 [20121219-07:05:05] [message] Message sent: (to dest=37.59.129.73:5060) NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK104432148 Route: From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 5 NOTIFY Contact: Proxy-Authorization: Digest username="squirtle", realm="sip.linphone.org", nonce="XuZ71AAAAAC/q37pAABEW0I+1lIAAAAA", uri="sip:address@hidden", response="37b24242421bb38181263298d3439a13", algorithm=MD5, cnonce="0a4f113b", opaque="+GNywA==", qop=auth, nc=00000001 Content-Type: application/pidf+xml Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subscription-State: active;expires=594 Content-Length: 462 open sip:address@hidden [20121219-07:05:05] [message] cb_sndnotify (id=16) [20121219-07:05:05] [message] eXosip: timer sec:0 usec:509471! [20121219-07:05:05] [message] alsa_open_w: Audio params set [20121219-07:05:05] [message] Received message: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK104432148;received=1.2.3.4 From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 5 NOTIFY User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:05] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:05] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:05] [message] MESSAGE REC. CALLID:21292 [20121219-07:05:05] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:05] [message] cb_rcv2xx (id=16) [20121219-07:05:05] [message] eXosip: timer sec:0 usec:328950! [20121219-07:05:05] [message] linphone process event get a message 49 [20121219-07:05:05] [message] Unhandled exosip event ! 49 [20121219-07:05:05] [message] Received message: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK2025251153;received=1.2.3.4 From: ;tag=758109914 To: ;tag=5002 Call-ID: 1593394462 CSeq: 21 OPTIONS User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, SUBSCRIBE, NOTIFY, INFO Content-Length: 0 [20121219-07:05:05] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:05] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:05] [message] MESSAGE REC. CALLID:1593394462 [20121219-07:05:05] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:05] [message] cb_rcv2xx (id=15) [20121219-07:05:05] [message] eXosip: timer sec:4 usec:63409! [20121219-07:05:05] [message] linphone process event get a message 29 [20121219-07:05:05] [message] Contact address updated to [20121219-07:05:05] [message] ping reply ! [20121219-07:05:06] [message] stop ringing [20121219-07:05:06] [message] ms_filter_unlink: MSFilePlayer:0x1362db0,0-->MSDtmfGen:0x1362e30,0 [20121219-07:05:06] [message] ms_filter_unlink: MSDtmfGen:0x1362e30,0-->MSAlsaWrite:0x1362eb0,0 [20121219-07:05:06] [message] Audio (ring) MSTicker thread exiting [20121219-07:05:06] [message] ring stopped [20121219-07:05:06] [message] Contact has been fixed using OPTIONS to [20121219-07:05:06] [warning] cannot set noise gate mode to [0] because no volume send [20121219-07:05:06] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:06] [message] getaddrinfo returned the following addresses: [20121219-07:05:06] [message] Call 0x135f960: moving from state LinphoneCallIncomingReceived to LinphoneCallConnected [20121219-07:05:06] [message] 37.59.129.73 port 5060 [20121219-07:05:06] [message] Message sent: (to dest=37.59.129.73:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.pvyye3vBSBc9Dc70eNpgUFFjtD Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK14293;received=9.8.7.6 Record-Route: From: ;tag=22983 To: ;tag=1268709216 Call-ID: 28648 CSeq: 21 INVITE Contact: Content-Type: application/sdp User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 355 v=0 o=squirtle 118 118 IN IP4 1.2.3.4 s=Talk c=IN IP4 1.2.3.4 t=0 0 m=audio 7078 RTP/AVP 112 111 110 3 0 8 101 a=rtpmap:112 speex/32000 a=fmtp:112 vbr=on a=rtpmap:111 speex/16000 a=fmtp:111 vbr=on a=rtpmap:110 speex/8000 a=fmtp:110 vbr=on a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 m=video 0 RTP/AVP 0 a=inactive [20121219-07:05:06] [message] cb_snd123456xx (id=12) [20121219-07:05:06] [message] cb_nict_kill_transaction (id=12) [20121219-07:05:06] [message] eXosip: timer sec:2 usec:663072! [20121219-07:05:06] [message] Audio bandwidth for this call is 44 [20121219-07:05:06] [message] speex_lib_ctl does not support SPEEX_LIB_CPU_FEATURE_NEON [20121219-07:05:06] [message] Payload's bitrate is 44000 [20121219-07:05:06] [message] Setting audio encoder network bitrate to 44000 [20121219-07:05:06] [message] ms_filter_link: MSAlsaRead:0x13294f0,0-->MSSpeexEC:0x135f1b0,1 [20121219-07:05:06] [message] ms_filter_link: MSSpeexEC:0x135f1b0,1-->MSVolume:0x1394800,0 [20121219-07:05:06] [message] ms_filter_link: MSVolume:0x1394800,0-->MSSpeexEnc:0x13296e0,0 [20121219-07:05:06] [message] ms_filter_link: MSSpeexEnc:0x13296e0,0-->MSRtpSend:0x13672c0,0 [20121219-07:05:06] [message] ms_filter_link: MSRtpRecv:0x1362d30,0-->MSSpeexDec:0x1367f10,0 [20121219-07:05:06] [message] ms_filter_link: MSSpeexDec:0x1367f10,0-->MSDtmfGen:0x13628b0,0 [20121219-07:05:06] [message] ms_filter_link: MSDtmfGen:0x13628b0,0-->MSVolume:0x13634f0,0 [20121219-07:05:06] [message] ms_filter_link: MSVolume:0x13634f0,0-->MSEqualizer:0x1363770,0 [20121219-07:05:06] [message] ms_filter_link: MSEqualizer:0x1363770,0-->MSSpeexEC:0x135f1b0,0 [20121219-07:05:06] [message] ms_filter_link: MSSpeexEC:0x135f1b0,0-->MSAlsaWrite:0x137f890,0 [20121219-07:05:06] [message] Initializing speex echo canceler with framesize=64, filterlength=8000, delay_samples=0 [20121219-07:05:06] [message] Priority used: 99 [20121219-07:05:06] [message] Audio MSTicker priority set to SCHED_FIFO and value (99) [20121219-07:05:06] [message] Setting maxbitrate=28000 to speex encoder. [20121219-07:05:06] [message] Using bitrate 25600 for speex encoder, ip bitrate is 41600 [20121219-07:05:06] [message] Filter MSRtpRecv is already being scheduled; nothing to do. [20121219-07:05:06] [message] Call 0x135f960: moving from state LinphoneCallConnected to LinphoneCallStreamsRunning [20121219-07:05:06] [message] alsa_open_r: opening default at 32000Hz, bits=16, stereo=0 [20121219-07:05:06] [warning] alsa_set_params: The period size 1024 is not supported by your hardware. ==> Using 682 instead. [20121219-07:05:06] [warning] alsa_set_params: periodsize:1024 Using 682 [20121219-07:05:06] [warning] alsa_set_params: period:8 Using 8 [20121219-07:05:06] [message] alsa_open_r: Audio params set [20121219-07:05:06] [message] ms_ticker_set_time_func: ticker updated. [20121219-07:05:06] [message] call answered. [20121219-07:05:06] [warning] Getting reference signal but no echo to synchronize on. [20121219-07:05:06] [warning] Not enough ref samples, using zeroes [20121219-07:05:06] [message] alsa_open_w: opening default at 32000Hz, bits=16, stereo=0 [20121219-07:05:06] [warning] alsa_set_params: The period size 1024 is not supported by your hardware. ==> Using 682 instead. [20121219-07:05:06] [warning] alsa_set_params: periodsize:1024 Using 682 [20121219-07:05:06] [warning] alsa_set_params: period:8 Using 8 [20121219-07:05:06] [message] alsa_open_w: Audio params set [20121219-07:05:07] [message] bandwidth usage: audio=[d=0.0,u=75.5] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:07] [message] Thread processing load: audio=13.453513 video=0.000000 [20121219-07:05:07] [message] Received message: ACK sip:address@hidden;verified SIP/2.0 Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.pvyye3vBSBc9Dc70eNpgUFFjtD Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK31267;received=9.8.7.6 Max-Forwards: 70 From: ;tag=22983 To: ;tag=1268709216 Call-ID: 28648 CSeq: 21 ACK Contact: User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Proxy-Authorization: Digest username="charmander", realm="sip.linphone.org", nonce="XuZ71AAAAAATvC9wAABmTz1V9OcAAAAA", uri="sip:address@hidden", response="ddfa85b28f9bebeb2f356f4de8d5946a", algorithm=MD5, cnonce="0a4f113b", opaque="+GNywA==", qop=auth, nc=00000001 Content-Length: 0 [20121219-07:05:07] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:07] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:07] [message] MESSAGE REC. CALLID:28648 [20121219-07:05:07] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:07] [message] This is a request [20121219-07:05:07] [message] eXosip: timer sec:2 usec:463716! [20121219-07:05:07] [message] linphone process event get a message 15 [20121219-07:05:07] [message] CALL_ACK [20121219-07:05:08] [message] bandwidth usage: audio=[d=0.0,u=26.2] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:08] [message] Thread processing load: audio=19.990202 video=0.000000 [20121219-07:05:09] [message] bandwidth usage: audio=[d=0.0,u=36.8] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:09] [message] Thread processing load: audio=26.366598 video=0.000000 [20121219-07:05:09] [message] alsa: sound/wall clock skew is average=-7.636232 ms, instant=-9 ms [20121219-07:05:09] [message] ms_quality_indicator_update_local(): no packet received since last call [20121219-07:05:09] [message] cb_nict_kill_transaction (id=13) [20121219-07:05:09] [message] cb_nict_kill_transaction (id=14) [20121219-07:05:09] [message] free transaction ressource 5 2036121733 [20121219-07:05:09] [message] free nict ressource [20121219-07:05:09] [message] eXosip: timer sec:0 usec:907419! [20121219-07:05:10] [message] bandwidth usage: audio=[d=0.0,u=38.6] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:10] [message] Thread processing load: audio=25.851635 video=0.000000 [20121219-07:05:10] [message] cb_nict_kill_transaction (id=16) [20121219-07:05:10] [message] eXosip: timer sec:0 usec:35391! [20121219-07:05:10] [message] cb_nict_kill_transaction (id=15) [20121219-07:05:10] [message] eXosip: Reseting timer to 10s before waking up! [20121219-07:05:11] [message] bandwidth usage: audio=[d=0.0,u=37.6] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:11] [message] Thread processing load: audio=21.874466 video=0.000000 [20121219-07:05:11] [message] alsa: sound/wall clock skew is average=-7.499531 ms, instant=-5 ms [20121219-07:05:11] [message] ms_quality_indicator_update_local(): no packet received since last call [20121219-07:05:12] [message] bandwidth usage: audio=[d=0.0,u=32.4] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:12] [message] Thread processing load: audio=22.137684 video=0.000000 [20121219-07:05:12] [message] allocating transaction ressource 17 28648 [20121219-07:05:12] [message] allocating NICT context [20121219-07:05:12] [message] ms_ticker_set_time_func: ticker updated. [20121219-07:05:12] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:12] [message] getaddrinfo returned the following addresses: [20121219-07:05:12] [message] 37.59.129.73 port 5060 [20121219-07:05:12] [message] Message sent: (to dest=37.59.129.73:5060) BYE sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK1546242442 Route: From: ;tag=1268709216 To: ;tag=22983 Call-ID: 28648 CSeq: 2 BYE Contact: Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:12] [message] cb_sndbye (id=17) [20121219-07:05:12] [message] eXosip: timer sec:0 usec:509980! [20121219-07:05:12] [message] Filter MSRtpRecv is not scheduled; nothing to do. [20121219-07:05:12] [message] oRTP-stats: Audio session's RTP statistics : [20121219-07:05:12] [message] number of rtp packet sent=286 [20121219-07:05:12] [message] number of rtp bytes sent=16204 bytes [20121219-07:05:12] [message] number of rtp packet received=0 [20121219-07:05:12] [message] number of rtp bytes received=0 bytes [20121219-07:05:12] [message] number of incoming rtp bytes successfully delivered to the application=0 [20121219-07:05:12] [message] number of rtp packet lost=0 [20121219-07:05:12] [message] number of rtp packets received too late=0 [20121219-07:05:12] [message] number of bad formatted rtp packets=0 [20121219-07:05:12] [message] number of packet discarded because of queue overflow=0 [20121219-07:05:12] [message] ms_filter_unlink: MSAlsaRead:0x13294f0,0-->MSSpeexEC:0x135f1b0,1 [20121219-07:05:12] [message] ms_filter_unlink: MSSpeexEC:0x135f1b0,1-->MSVolume:0x1394800,0 [20121219-07:05:12] [message] ms_filter_unlink: MSVolume:0x1394800,0-->MSSpeexEnc:0x13296e0,0 [20121219-07:05:12] [message] ms_filter_unlink: MSSpeexEnc:0x13296e0,0-->MSRtpSend:0x13672c0,0 [20121219-07:05:12] [message] ms_filter_unlink: MSRtpRecv:0x1362d30,0-->MSSpeexDec:0x1367f10,0 [20121219-07:05:12] [message] ms_filter_unlink: MSSpeexDec:0x1367f10,0-->MSDtmfGen:0x13628b0,0 [20121219-07:05:12] [message] ms_filter_unlink: MSDtmfGen:0x13628b0,0-->MSVolume:0x13634f0,0 [20121219-07:05:12] [message] ms_filter_unlink: MSVolume:0x13634f0,0-->MSEqualizer:0x1363770,0 [20121219-07:05:12] [message] ms_filter_unlink: MSEqualizer:0x1363770,0-->MSSpeexEC:0x135f1b0,0 [20121219-07:05:12] [message] ms_filter_unlink: MSSpeexEC:0x135f1b0,0-->MSAlsaWrite:0x137f890,0 [20121219-07:05:12] [message] Audio MSTicker thread exiting [20121219-07:05:12] [message] Filter usage statistics: [20121219-07:05:12] [message] Name Count Time/tick (ms) CPU Usage [20121219-07:05:12] [message] MSSpeexEC 269 4.15034 82.1291 [20121219-07:05:12] [message] MSSpeexEnc 269 0.522942 10.3483 [20121219-07:05:12] [message] MSAlsaWrite 269 0.210767 4.17076 [20121219-07:05:12] [message] MSAlsaRead 577 0.0529086 2.24132 [20121219-07:05:12] [message] MSRtpSend 577 0.0163681 0.693386 [20121219-07:05:12] [message] MSRtpRecv 577 0.00606909 0.2571 [20121219-07:05:12] [message] MSVolume 269 0.00622433 0.12317 [20121219-07:05:12] [message] MSDtmfGen 577 0.000495588 0.0209942 [20121219-07:05:12] [message] MSSpeexDec 577 0.000374164 0.0158504 [20121219-07:05:12] [message] MSEqualizer 0 0 0 [20121219-07:05:12] [message] Call 0x135f960: moving from state LinphoneCallStreamsRunning to LinphoneCallEnd [20121219-07:05:12] [message] Resetting the current call [20121219-07:05:12] [message] Notifying all friends that we are in status 1 [20121219-07:05:12] [message] Want to notify , insub=0x134b8b0 [20121219-07:05:12] [message] allocating transaction ressource 18 21292 [20121219-07:05:12] [message] allocating NICT context [20121219-07:05:12] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:12] [message] getaddrinfo returned the following addresses: [20121219-07:05:12] [message] 37.59.129.73 port 5060 [20121219-07:05:12] [message] Message sent: (to dest=37.59.129.73:5060) NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK1515442160 Route: From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 6 NOTIFY Contact: Content-Type: application/pidf+xml Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subscription-State: active;expires=586 Content-Length: 368 open sip:address@hidden [20121219-07:05:12] [message] cb_sndnotify (id=18) [20121219-07:05:12] [message] eXosip: timer sec:0 usec:500614! [20121219-07:05:12] [message] Received message: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK1515442160;received=1.2.3.4 From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 6 NOTIFY Contact: Server: Flexisip/0.5.0 (sofia-sip-nta/2.0) Proxy-Authenticate: Digest realm="sip.linphone.org", nonce="ZuZ71AAAAAC21JgLAACGJL46cwcAAAAA", opaque="+GNywA==", algorithm=MD5, qop="auth" Content-Length: 0 [20121219-07:05:12] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:12] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:12] [message] MESSAGE REC. CALLID:21292 [20121219-07:05:12] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:12] [message] cb_rcv4xx (id=18) [20121219-07:05:12] [message] eXosip: timer sec:0 usec:491465! [20121219-07:05:12] [message] linphone process event get a message 51 [20121219-07:05:12] [message] auth_requested() for realm="sip.linphone.org", username=squirtle [20121219-07:05:12] [message] auth_requested(): authenticating realm="sip.linphone.org", username=squirtle [20121219-07:05:12] [message] Authentication info for username [squirtle], id[squirtle], realm ["sip.linphone.org"] added to eXosip [20121219-07:05:12] [message] Contact address updated to [20121219-07:05:12] [message] INFO: authinfo: "sip.linphone.org" "sip.linphone.org" [20121219-07:05:12] [message] authinfo: squirtle [20121219-07:05:12] [message] allocating transaction ressource 19 21292 [20121219-07:05:12] [message] allocating NICT context [20121219-07:05:12] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:12] [message] getaddrinfo returned the following addresses: [20121219-07:05:12] [message] 37.59.129.73 port 5060 [20121219-07:05:12] [message] Message sent: (to dest=37.59.129.73:5060) NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK136735490 Route: From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 7 NOTIFY Contact: Proxy-Authorization: Digest username="squirtle", realm="sip.linphone.org", nonce="ZuZ71AAAAAC21JgLAACGJL46cwcAAAAA", uri="sip:address@hidden", response="7d833518a201dddfd449ef6039ae7f7d", algorithm=MD5, cnonce="0a4f113b", opaque="+GNywA==", qop=auth, nc=00000001 Content-Type: application/pidf+xml Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subscription-State: active;expires=586 Content-Length: 368 open sip:address@hidden [20121219-07:05:12] [message] cb_sndnotify (id=19) [20121219-07:05:12] [message] eXosip: timer sec:0 usec:484408! [20121219-07:05:12] [message] eXosip_default_action() done [20121219-07:05:12] [message] Received message: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK1546242442;received=1.2.3.4 From: ;tag=1268709216 To: ;tag=22983 Call-ID: 28648 CSeq: 2 BYE User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:12] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:12] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:12] [message] MESSAGE REC. CALLID:28648 [20121219-07:05:12] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:12] [message] cb_rcv2xx (id=17) [20121219-07:05:12] [message] eXosip: timer sec:0 usec:348247! [20121219-07:05:12] [message] Received message: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK136735490;received=1.2.3.4 From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 7 NOTIFY User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:12] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:12] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:12] [message] MESSAGE REC. CALLID:21292 [20121219-07:05:12] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:12] [message] cb_rcv2xx (id=19) [20121219-07:05:12] [message] eXosip: timer sec:4 usec:821765! [20121219-07:05:12] [message] linphone process event get a message 20 [20121219-07:05:12] [message] Unhandled exosip event ! 20 [20121219-07:05:12] [message] linphone process event get a message 49 [20121219-07:05:12] [message] Unhandled exosip event ! 49 [20121219-07:05:13] [message] Received message: NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.22H75v092gDyy1j1p30jyBQ4Fe Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK19441;received=9.8.7.6 Max-Forwards: 70 From: ;tag=41 To: ;tag=446971923 Call-ID: 2036121733 CSeq: 7 NOTIFY Contact: User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subscription-State: active;expires=586 Content-Type: application/pidf+xml Content-Length: 360 open sip:address@hidden [20121219-07:05:13] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:13] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:13] [message] MESSAGE REC. CALLID:2036121733 [20121219-07:05:13] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:13] [message] This is a request [20121219-07:05:13] [message] allocating transaction ressource 20 2036121733 [20121219-07:05:13] [message] allocating NIST context [20121219-07:05:13] [message] cb_rcvunkrequest (id=20) [20121219-07:05:13] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:13] [message] getaddrinfo returned the following addresses: [20121219-07:05:13] [message] 37.59.129.73 port 5060 [20121219-07:05:13] [message] Message sent: (to dest=37.59.129.73:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.22H75v092gDyy1j1p30jyBQ4Fe Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK19441;received=9.8.7.6 From: ;tag=41 To: ;tag=446971923 Call-ID: 2036121733 CSeq: 7 NOTIFY User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:13] [message] cb_snd123456xx (id=20) [20121219-07:05:13] [message] eXosip: timer sec:4 usec:627632! [20121219-07:05:13] [message] eXosip: timer sec:4 usec:627569! [20121219-07:05:13] [message] linphone process event get a message 43 [20121219-07:05:13] [message] CALL_SUBSCRIPTION_NOTIFY [20121219-07:05:13] [message] Receiving notify with sid=1,nid=0 [20121219-07:05:13] [message] We are notified that ;tag=41 has online status 1 [20121219-07:05:15] [message] Local interface to reach 87.98.157.38 is 192.168.0.101. [20121219-07:05:15] [message] Notifying all friends that we are in status 5 [20121219-07:05:15] [message] Want to notify , insub=0x134b8b0 [20121219-07:05:15] [message] allocating transaction ressource 21 21292 [20121219-07:05:15] [message] allocating NICT context [20121219-07:05:15] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:15] [message] getaddrinfo returned the following addresses: [20121219-07:05:15] [message] 37.59.129.73 port 5060 [20121219-07:05:15] [message] Message sent: (to dest=37.59.129.73:5060) NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK327686565 Route: From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 8 NOTIFY Contact: Content-Type: application/pidf+xml Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subscription-State: active;expires=583 Content-Length: 462 open sip:address@hidden [20121219-07:05:15] [message] cb_sndnotify (id=21) [20121219-07:05:15] [message] free transaction ressource 15 1593394462 [20121219-07:05:15] [message] free nict ressource [20121219-07:05:15] [message] free transaction ressource 13 1593394462 [20121219-07:05:15] [message] free nict ressource [20121219-07:05:15] [message] keep alive: 1 [20121219-07:05:15] [message] eXosip: Keep Alive sent on UDP! [20121219-07:05:15] [message] eXosip: timer sec:0 usec:509847! [20121219-07:05:15] [message] Received message: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK327686565;received=1.2.3.4 From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 8 NOTIFY Contact: Server: Flexisip/0.5.0 (sofia-sip-nta/2.0) Proxy-Authenticate: Digest realm="sip.linphone.org", nonce="aeZ71AAAAACQGy00AAAgMPqGBFUAAAAA", opaque="+GNywA==", algorithm=MD5, qop="auth" Content-Length: 0 [20121219-07:05:15] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:15] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:15] [message] MESSAGE REC. CALLID:21292 [20121219-07:05:15] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:15] [message] cb_rcv4xx (id=21) [20121219-07:05:15] [message] eXosip: timer sec:2 usec:257869! [20121219-07:05:15] [message] Sending stun requests... [20121219-07:05:15] [message] STUN test result: local audio port maps to 1.2.3.4:7078 [20121219-07:05:15] [message] NAT is symmetric for audio port [20121219-07:05:16] [message] Partial MTU discovered : 1500 [20121219-07:05:16] [message] mtu to sip.linphone.org is 1500 [20121219-07:05:16] [message] Discovered mtu is 1500, RTP payload max size is 1440 [20121219-07:05:16] [message] Call 0x13a76e0: moving from state LinphoneCallIdle to LinphoneCallOutgoingInit [20121219-07:05:16] [message] Contact has been fixed using proxy to [20121219-07:05:16] [warning] cannot set noise gate mode to [0] because no volume send [20121219-07:05:16] [message] allocating transaction ressource 22 150493449 [20121219-07:05:16] [message] allocating ICT context [20121219-07:05:16] [message] eXosip option set: dns cache used:sip.linphone.org -> 37.59.129.73 [20121219-07:05:16] [message] Call 0x13a76e0: moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress [20121219-07:05:16] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:16] [message] getaddrinfo returned the following addresses: [20121219-07:05:16] [message] 37.59.129.73 port 5060 [20121219-07:05:16] [message] Message sent: (to dest=37.59.129.73:5060) INVITE sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK94330657 From: ;tag=1466034646 To: Call-ID: 150493449 CSeq: 20 INVITE Contact: Content-Type: application/sdp Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subject: Phone call Content-Length: 322 v=0 o=squirtle 537 537 IN IP4 1.2.3.4 s=Talk c=IN IP4 1.2.3.4 t=0 0 m=audio 7078 RTP/AVP 112 111 110 3 0 8 101 a=rtpmap:112 speex/32000 a=fmtp:112 vbr=on a=rtpmap:111 speex/16000 a=fmtp:111 vbr=on a=rtpmap:110 speex/8000 a=fmtp:110 vbr=on a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 [20121219-07:05:16] [message] cb_sndinvite (id=22) [20121219-07:05:16] [message] eXosip: timer sec:0 usec:509488! [20121219-07:05:16] [message] linphone process event get a message 51 [20121219-07:05:16] [message] auth_requested() for realm="sip.linphone.org", username=squirtle [20121219-07:05:16] [message] auth_requested(): authenticating realm="sip.linphone.org", username=squirtle [20121219-07:05:16] [message] Authentication info for username [squirtle], id[squirtle], realm ["sip.linphone.org"] added to eXosip [20121219-07:05:16] [message] Contact address updated to [20121219-07:05:16] [message] INFO: authinfo: "sip.linphone.org" "sip.linphone.org" [20121219-07:05:16] [message] authinfo: squirtle [20121219-07:05:16] [message] allocating transaction ressource 23 21292 [20121219-07:05:16] [message] allocating NICT context [20121219-07:05:16] [message] eXosip_default_action() done [20121219-07:05:16] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:16] [message] getaddrinfo returned the following addresses: [20121219-07:05:16] [message] 37.59.129.73 port 5060 [20121219-07:05:16] [message] Message sent: (to dest=37.59.129.73:5060) NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK464793077 Route: From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 9 NOTIFY Contact: Proxy-Authorization: Digest username="squirtle", realm="sip.linphone.org", nonce="aeZ71AAAAACQGy00AAAgMPqGBFUAAAAA", uri="sip:address@hidden", response="48a0c4bfb57b95416eac4f7d44543ae2", algorithm=MD5, cnonce="0a4f113b", opaque="+GNywA==", qop=auth, nc=00000001 Content-Type: application/pidf+xml Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subscription-State: active;expires=583 Content-Length: 462 open sip:address@hidden [20121219-07:05:16] [message] cb_sndnotify (id=23) [20121219-07:05:16] [message] eXosip: timer sec:0 usec:508014! [20121219-07:05:16] [message] Received message: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK94330657;received=1.2.3.4 From: ;tag=1466034646 To: ;tag=74217gmQ56B6Q Call-ID: 150493449 CSeq: 20 INVITE Contact: Server: Flexisip/0.5.0 (sofia-sip-nta/2.0) Proxy-Authenticate: Digest realm="sip.linphone.org", nonce="auZ71AAAAAB714ASAABZ6BwMJrYAAAAA", opaque="+GNywA==", algorithm=MD5, qop="auth" Content-Length: 0 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] MESSAGE REC. CALLID:150493449 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:16] [message] getaddrinfo returned the following addresses: [20121219-07:05:16] [message] 37.59.129.73 port 5060 [20121219-07:05:16] [message] Message sent: (to dest=37.59.129.73:5060) ACK sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK94330657 From: ;tag=1466034646 To: ;tag=74217gmQ56B6Q Call-ID: 150493449 CSeq: 20 ACK Content-Length: 0 [20121219-07:05:16] [message] cb_rcv4xx (id=22) [20121219-07:05:16] [message] cb_sndack (id=22) [20121219-07:05:16] [message] eXosip: timer sec:0 usec:501960! [20121219-07:05:16] [message] linphone process event get a message 12 [20121219-07:05:16] [message] CALL_REQUESTFAILURE or GLOBALFAILURE or SERVERFAILURE [20121219-07:05:16] [message] auth_requested() for realm="sip.linphone.org", username=squirtle [20121219-07:05:16] [message] auth_requested(): authenticating realm="sip.linphone.org", username=squirtle [20121219-07:05:16] [message] Authentication info for username [squirtle], id[squirtle], realm ["sip.linphone.org"] added to eXosip [20121219-07:05:16] [message] Contact address updated to [20121219-07:05:16] [message] INFO: authinfo: "sip.linphone.org" "sip.linphone.org" [20121219-07:05:16] [message] authinfo: squirtle [20121219-07:05:16] [message] allocating transaction ressource 24 150493449 [20121219-07:05:16] [message] allocating ICT context [20121219-07:05:16] [message] eXosip_default_action() done [20121219-07:05:16] [message] eXosip option set: dns cache used:sip.linphone.org -> 37.59.129.73 [20121219-07:05:16] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:16] [message] getaddrinfo returned the following addresses: [20121219-07:05:16] [message] 37.59.129.73 port 5060 [20121219-07:05:16] [message] Message sent: (to dest=37.59.129.73:5060) INVITE sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK1558456714 From: ;tag=1466034646 To: Call-ID: 150493449 CSeq: 21 INVITE Contact: Proxy-Authorization: Digest username="squirtle", realm="sip.linphone.org", nonce="auZ71AAAAAB714ASAABZ6BwMJrYAAAAA", uri="sip:address@hidden", response="de6d47e48344640410a1475dd9f68e13", algorithm=MD5, cnonce="0a4f113b", opaque="+GNywA==", qop=auth, nc=00000001 Content-Type: application/sdp Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subject: Phone call Content-Length: 322 v=0 o=squirtle 537 537 IN IP4 1.2.3.4 s=Talk c=IN IP4 1.2.3.4 t=0 0 m=audio 7078 RTP/AVP 112 111 110 3 0 8 101 a=rtpmap:112 speex/32000 a=fmtp:112 vbr=on a=rtpmap:111 speex/16000 a=fmtp:111 vbr=on a=rtpmap:110 speex/8000 a=fmtp:110 vbr=on a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 [20121219-07:05:16] [message] cb_sndinvite (id=24) [20121219-07:05:16] [message] eXosip: timer sec:0 usec:475823! [20121219-07:05:16] [message] Received message: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK1558456714;received=1.2.3.4 From: ;tag=1466034646 To: Call-ID: 150493449 CSeq: 21 INVITE Content-Length: 0 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] MESSAGE REC. CALLID:150493449 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] cb_rcv1xx (id=24) [20121219-07:05:16] [message] eXosip: timer sec:0 usec:460039! [20121219-07:05:16] [message] linphone process event get a message 8 [20121219-07:05:16] [message] CALL_PROCEEDING [20121219-07:05:16] [message] Contact address updated to [20121219-07:05:16] [message] Received message: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK464793077;received=1.2.3.4 From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 9 NOTIFY User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] MESSAGE REC. CALLID:21292 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] cb_rcv2xx (id=23) [20121219-07:05:16] [message] eXosip: timer sec:1 usec:370705! [20121219-07:05:16] [message] linphone process event get a message 49 [20121219-07:05:16] [message] Unhandled exosip event ! 49 [20121219-07:05:16] [message] Received message: SIP/2.0 101 Dialog Establishement Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK1558456714;received=1.2.3.4 Record-Route: From: ;tag=1466034646 To: ;tag=18467 Call-ID: 150493449 CSeq: 21 INVITE Contact: User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] MESSAGE REC. CALLID:150493449 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] cb_rcv1xx (id=24) [20121219-07:05:16] [message] eXosip: timer sec:1 usec:334371! [20121219-07:05:16] [message] linphone process event get a message 8 [20121219-07:05:16] [message] CALL_PROCEEDING [20121219-07:05:16] [message] Contact address updated to [20121219-07:05:16] [message] Received message: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK1558456714;received=1.2.3.4 Record-Route: From: ;tag=1466034646 To: ;tag=18467 Call-ID: 150493449 CSeq: 21 INVITE Contact: User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] MESSAGE REC. CALLID:150493449 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] cb_rcv1xx (id=24) [20121219-07:05:16] [message] eXosip: timer sec:0 usec:884997! [20121219-07:05:16] [message] linphone process event get a message 9 [20121219-07:05:16] [message] CALL_RINGING [20121219-07:05:16] [message] Contact address updated to [20121219-07:05:16] [message] Received message: NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.756cF2pKa2t8DjUpBFB7S9QFvB Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK32031;received=9.8.7.6 Max-Forwards: 70 From: ;tag=41 To: ;tag=446971923 Call-ID: 2036121733 CSeq: 9 NOTIFY Contact: User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subscription-State: active;expires=582 Content-Type: application/pidf+xml Content-Length: 454 open sip:address@hidden [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] MESSAGE REC. CALLID:2036121733 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] This is a request [20121219-07:05:16] [message] allocating transaction ressource 25 2036121733 [20121219-07:05:16] [message] allocating NIST context [20121219-07:05:16] [message] cb_rcvunkrequest (id=25) [20121219-07:05:16] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:16] [message] getaddrinfo returned the following addresses: [20121219-07:05:16] [message] 37.59.129.73 port 5060 [20121219-07:05:16] [message] Message sent: (to dest=37.59.129.73:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.756cF2pKa2t8DjUpBFB7S9QFvB Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK32031;received=9.8.7.6 From: ;tag=41 To: ;tag=446971923 Call-ID: 2036121733 CSeq: 9 NOTIFY User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:16] [message] cb_snd123456xx (id=25) [20121219-07:05:16] [message] eXosip: timer sec:0 usec:873427! [20121219-07:05:16] [message] Received message: OPTIONS sip:address@hidden:5060;line=de07b2535c9193a SIP/2.0 Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.vNHvUjtDHNa4mBNBpUtjH1365B Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK25279;received=9.8.7.6 Max-Forwards: 70 From: ;tag=7561 To: Call-ID: 24168 CSeq: 21 OPTIONS User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Accept: application/sdp Content-Length: 0 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] MESSAGE REC. CALLID:24168 [20121219-07:05:16] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:16] [message] This is a request [20121219-07:05:16] [message] allocating transaction ressource 26 24168 [20121219-07:05:16] [message] allocating NIST context [20121219-07:05:16] [message] cb_rcvunkrequest (id=26) [20121219-07:05:16] [message] eXosip: timer sec:0 usec:873092! [20121219-07:05:16] [message] eXosip: timer sec:0 usec:873049! [20121219-07:05:16] [message] /usr/share/sounds/linphone/ringback.wav opened: rate=8000,channel=1 [20121219-07:05:16] [message] ms_filter_link: MSFilePlayer:0x1371b60,0-->MSDtmfGen:0x13b1190,0 [20121219-07:05:16] [message] ms_filter_link: MSDtmfGen:0x13b1190,0-->MSAlsaWrite:0x13be450,0 [20121219-07:05:16] [message] Remote ringing... [20121219-07:05:16] [message] Call 0x13a76e0: moving from state LinphoneCallOutgoingProgress to LinphoneCallOutgoingRinging [20121219-07:05:16] [message] linphone process event get a message 43 [20121219-07:05:16] [message] CALL_SUBSCRIPTION_NOTIFY [20121219-07:05:16] [message] Receiving notify with sid=1,nid=0 [20121219-07:05:16] [message] We are notified that ;tag=41 has online status 5 [20121219-07:05:16] [message] linphone process event get a message 27 [20121219-07:05:16] [message] in other_request [20121219-07:05:16] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:16] [message] getaddrinfo returned the following addresses: [20121219-07:05:16] [message] 37.59.129.73 port 5060 [20121219-07:05:16] [message] Message sent: (to dest=37.59.129.73:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.vNHvUjtDHNa4mBNBpUtjH1365B Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK25279;received=9.8.7.6 From: ;tag=7561 To: ;tag=1481655969 Call-ID: 24168 CSeq: 21 OPTIONS Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, SUBSCRIBE, NOTIFY, INFO Accept: application/sdp User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:16] [message] cb_snd123456xx (id=26) [20121219-07:05:16] [message] eXosip: timer sec:0 usec:855470! [20121219-07:05:16] [message] alsa_open_w: opening default at 8000Hz, bits=16, stereo=0 [20121219-07:05:16] [warning] alsa_set_params: The period size 256 is not supported by your hardware. ==> Using 170 instead. [20121219-07:05:16] [warning] alsa_set_params: periodsize:256 Using 170 [20121219-07:05:16] [warning] alsa_set_params: period:8 Using 8 [20121219-07:05:16] [message] alsa_open_w: Audio params set [20121219-07:05:17] [message] cb_nict_kill_transaction (id=18) [20121219-07:05:17] [message] eXosip: timer sec:0 usec:167740! [20121219-07:05:17] [message] cb_nict_kill_transaction (id=17) [20121219-07:05:17] [message] eXosip: eXosip_release_finished_calls remove a dialog (cid=5 did=-1) [20121219-07:05:17] [message] eXosip: remove a call [20121219-07:05:17] [message] free transaction ressource 12 28648 [20121219-07:05:17] [message] free ist ressource [20121219-07:05:17] [message] eXosip: timer sec:0 usec:18846! [20121219-07:05:17] [message] eXosip: timer sec:0 usec:18777! [20121219-07:05:17] [message] cb_nict_kill_transaction (id=19) [20121219-07:05:17] [message] eXosip: timer sec:2 usec:563740! [20121219-07:05:17] [message] linphone process event get a message 26 [20121219-07:05:17] [message] CALL_RELEASED [20121219-07:05:17] [message] Call 0x135f960: moving from state LinphoneCallEnd to LinphoneCallReleased [20121219-07:05:17] [message] Cleaning cid 5 [20121219-07:05:19] [message] Received message: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK1558456714;received=1.2.3.4 Record-Route: From: ;tag=1466034646 To: ;tag=18467 Call-ID: 150493449 CSeq: 21 INVITE Contact: User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Type: application/sdp Content-Length: 314 v=0 o=charmander 116 116 IN IP4 9.8.7.6 s=Talk c=IN IP4 37.59.129.73 t=0 0 m=audio 18228 RTP/AVP 112 111 110 3 0 8 101 a=rtpmap:112 speex/32000 a=fmtp:112 vbr=on a=rtpmap:111 speex/16000 a=fmtp:111 vbr=on a=rtpmap:110 speex/8000 a=fmtp:110 vbr=on a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 [20121219-07:05:19] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:19] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:19] [message] MESSAGE REC. CALLID:150493449 [20121219-07:05:19] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:19] [message] cb_rcv2xx (id=24) [20121219-07:05:19] [message] cb_nict_kill_transaction (id=24) [20121219-07:05:19] [message] eXosip: timer sec:0 usec:553543! [20121219-07:05:19] [message] linphone process event get a message 10 [20121219-07:05:19] [message] CALL_ANSWERED [20121219-07:05:19] [message] Found payload speex/32000 fmtp=vbr=on [20121219-07:05:19] [message] Found payload speex/16000 fmtp=vbr=on [20121219-07:05:19] [message] Found payload speex/8000 fmtp=vbr=on [20121219-07:05:19] [message] Found payload GSM/8000 fmtp= [20121219-07:05:19] [message] Found payload PCMU/8000 fmtp= [20121219-07:05:19] [message] Found payload PCMA/8000 fmtp= [20121219-07:05:19] [message] Found payload telephone-event/8000 fmtp=0-11 [20121219-07:05:19] [message] Doing SDP offer/answer process [20121219-07:05:19] [message] Processing for stream 0 [20121219-07:05:19] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:19] [message] getaddrinfo returned the following addresses: [20121219-07:05:19] [message] 37.59.129.73 port 5060 [20121219-07:05:19] [message] Message sent: (to dest=37.59.129.73:5060) ACK sip:address@hidden;verified SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK181424819 Route: From: ;tag=1466034646 To: ;tag=18467 Call-ID: 150493449 CSeq: 21 ACK Contact: Proxy-Authorization: Digest username="squirtle", realm="sip.linphone.org", nonce="auZ71AAAAAB714ASAABZ6BwMJrYAAAAA", uri="sip:address@hidden", response="de6d47e48344640410a1475dd9f68e13", algorithm=MD5, cnonce="0a4f113b", opaque="+GNywA==", qop=auth, nc=00000001 Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:19] [message] Call 0x13a76e0: moving from state LinphoneCallOutgoingRinging to LinphoneCallConnected [20121219-07:05:19] [message] ms_filter_unlink: MSFilePlayer:0x1371b60,0-->MSDtmfGen:0x13b1190,0 [20121219-07:05:19] [message] ms_filter_unlink: MSDtmfGen:0x13b1190,0-->MSAlsaWrite:0x13be450,0 [20121219-07:05:19] [message] Audio (ring) MSTicker thread exiting [20121219-07:05:19] [message] Audio bandwidth for this call is 44 [20121219-07:05:19] [message] speex_lib_ctl does not support SPEEX_LIB_CPU_FEATURE_NEON [20121219-07:05:19] [message] Payload's bitrate is 44000 [20121219-07:05:19] [message] Setting audio encoder network bitrate to 44000 [20121219-07:05:19] [message] ms_filter_link: MSAlsaRead:0x13a0210,0-->MSSpeexEC:0x135fc00,1 [20121219-07:05:19] [message] ms_filter_link: MSSpeexEC:0x135fc00,1-->MSVolume:0x13a1e60,0 [20121219-07:05:19] [message] ms_filter_link: MSVolume:0x13a1e60,0-->MSSpeexEnc:0x13be510,0 [20121219-07:05:19] [message] ms_filter_link: MSSpeexEnc:0x13be510,0-->MSRtpSend:0x1258250,0 [20121219-07:05:19] [message] ms_filter_link: MSRtpRecv:0x13be450,0-->MSSpeexDec:0x135f9a0,0 [20121219-07:05:19] [message] ms_filter_link: MSSpeexDec:0x135f9a0,0-->MSDtmfGen:0x13a0170,0 [20121219-07:05:19] [message] ms_filter_link: MSDtmfGen:0x13a0170,0-->MSVolume:0x1360970,0 [20121219-07:05:19] [message] ms_filter_link: MSVolume:0x1360970,0-->MSEqualizer:0x13a0510,0 [20121219-07:05:19] [message] ms_filter_link: MSEqualizer:0x13a0510,0-->MSSpeexEC:0x135fc00,0 [20121219-07:05:19] [message] ms_filter_link: MSSpeexEC:0x135fc00,0-->MSAlsaWrite:0x13b1170,0 [20121219-07:05:19] [message] Initializing speex echo canceler with framesize=64, filterlength=8000, delay_samples=0 [20121219-07:05:19] [message] Priority used: 99 [20121219-07:05:19] [message] Audio MSTicker priority set to SCHED_FIFO and value (99) [20121219-07:05:19] [message] Setting maxbitrate=28000 to speex encoder. [20121219-07:05:19] [message] Using bitrate 25600 for speex encoder, ip bitrate is 41600 [20121219-07:05:19] [message] Filter MSRtpRecv is already being scheduled; nothing to do. [20121219-07:05:19] [message] Call 0x13a76e0: moving from state LinphoneCallConnected to LinphoneCallStreamsRunning [20121219-07:05:19] [message] "sip.linphone.org"/squirtle authentication works. [20121219-07:05:19] [message] alsa_open_r: opening default at 32000Hz, bits=16, stereo=0 [20121219-07:05:19] [warning] alsa_set_params: The period size 1024 is not supported by your hardware. ==> Using 682 instead. [20121219-07:05:19] [warning] alsa_set_params: periodsize:1024 Using 682 [20121219-07:05:19] [warning] alsa_set_params: period:8 Using 8 [20121219-07:05:19] [message] alsa_open_r: Audio params set [20121219-07:05:19] [message] ms_ticker_set_time_func: ticker updated. [20121219-07:05:20] [warning] Getting reference signal but no echo to synchronize on. [20121219-07:05:20] [warning] Not enough ref samples, using zeroes [20121219-07:05:20] [message] alsa_open_w: opening default at 32000Hz, bits=16, stereo=0 [20121219-07:05:20] [warning] alsa_set_params: The period size 1024 is not supported by your hardware. ==> Using 682 instead. [20121219-07:05:20] [warning] alsa_set_params: periodsize:1024 Using 682 [20121219-07:05:20] [warning] alsa_set_params: period:8 Using 8 [20121219-07:05:20] [message] alsa_open_w: Audio params set [20121219-07:05:20] [message] bandwidth usage: audio=[d=0.0,u=0.0] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:20] [message] Thread processing load: audio=8.442306 video=0.000000 [20121219-07:05:20] [message] cb_nict_kill_transaction (id=21) [20121219-07:05:20] [message] eXosip: timer sec:0 usec:886505! [20121219-07:05:21] [message] bandwidth usage: audio=[d=0.0,u=36.6] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:21] [message] Thread processing load: audio=22.974968 video=0.000000 [20121219-07:05:21] [message] cb_nict_kill_transaction (id=23) [20121219-07:05:21] [message] eXosip: timer sec:9 usec:101605! [20121219-07:05:21] [message] alsa: sound/wall clock skew is average=-8.085087 ms, instant=4 ms [20121219-07:05:22] [message] bandwidth usage: audio=[d=0.0,u=32.0] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:22] [message] Thread processing load: audio=22.808672 video=0.000000 [20121219-07:05:22] [message] ms_quality_indicator_update_local(): no packet received since last call [20121219-07:05:23] [message] bandwidth usage: audio=[d=0.0,u=44.1] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:23] [message] Thread processing load: audio=21.732666 video=0.000000 [20121219-07:05:24] [message] bandwidth usage: audio=[d=0.0,u=44.4] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:24] [message] Thread processing load: audio=23.394373 video=0.000000 [20121219-07:05:24] [message] alsa: sound/wall clock skew is average=-8.723544 ms, instant=-12 ms [20121219-07:05:24] [message] ms_quality_indicator_update_local(): no packet received since last call [20121219-07:05:25] [message] bandwidth usage: audio=[d=0.0,u=46.8] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:25] [message] Thread processing load: audio=28.004496 video=0.000000 [20121219-07:05:26] [message] bandwidth usage: audio=[d=0.0,u=35.6] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:26] [message] Thread processing load: audio=25.684992 video=0.000000 [20121219-07:05:26] [message] alsa: sound/wall clock skew is average=-3.191998 ms, instant=-11 ms [20121219-07:05:27] [message] bandwidth usage: audio=[d=0.0,u=38.9] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:27] [message] Thread processing load: audio=25.219864 video=0.000000 [20121219-07:05:27] [message] ms_quality_indicator_update_local(): no packet received since last call [20121219-07:05:28] [message] bandwidth usage: audio=[d=0.0,u=37.3] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:28] [message] Thread processing load: audio=22.564213 video=0.000000 [20121219-07:05:29] [message] bandwidth usage: audio=[d=0.0,u=36.7] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:29] [message] Thread processing load: audio=30.934225 video=0.000000 [20121219-07:05:29] [message] alsa: sound/wall clock skew is average=-3.382716 ms, instant=-1 ms [20121219-07:05:30] [message] bandwidth usage: audio=[d=0.0,u=36.4] video=[d=0.0,u=0.0] kbit/sec [20121219-07:05:30] [message] Thread processing load: audio=26.742828 video=0.000000 [20121219-07:05:30] [message] ms_quality_indicator_update_local(): no packet received since last call [20121219-07:05:30] [message] cb_nict_kill_transaction (id=7) [20121219-07:05:30] [message] free transaction ressource 17 28648 [20121219-07:05:30] [message] free nict ressource [20121219-07:05:30] [message] eXosip: releaase non-INVITE server transaction (did=2) [20121219-07:05:30] [message] eXosip: release non INVITE client transaction (did=4) [20121219-07:05:30] [message] keep alive: 5 [20121219-07:05:30] [message] eXosip: Keep Alive sent on UDP! [20121219-07:05:30] [message] eXosip: timer sec:0 usec:32796! [20121219-07:05:30] [message] cb_nict_kill_transaction (id=8) [20121219-07:05:30] [message] free transaction ressource 10 21292 [20121219-07:05:30] [message] free nict ressource [20121219-07:05:30] [message] free transaction ressource 7 2036121733 [20121219-07:05:30] [message] free nist ressource [20121219-07:05:30] [message] eXosip: release non INVITE client transaction (did=4) [20121219-07:05:30] [message] eXosip: timer sec:5 usec:864041! [20121219-07:05:30] [message] Authentication info for username [squirtle], id[squirtle], realm ["sip.linphone.org"] added to eXosip [20121219-07:05:30] [message] INFO: authinfo: "sip.linphone.org" "sip.linphone.org" [20121219-07:05:30] [message] allocating transaction ressource 27 150493449 [20121219-07:05:30] [message] allocating NICT context [20121219-07:05:30] [message] ms_ticker_set_time_func: ticker updated. [20121219-07:05:30] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:30] [message] getaddrinfo returned the following addresses: [20121219-07:05:30] [message] 37.59.129.73 port 5060 [20121219-07:05:30] [message] Message sent: (to dest=37.59.129.73:5060) BYE sip:address@hidden;verified SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK2147062262 Route: From: ;tag=1466034646 To: ;tag=18467 Call-ID: 150493449 CSeq: 22 BYE Contact: Proxy-Authorization: Digest username="squirtle", realm="sip.linphone.org", nonce="auZ71AAAAAB714ASAABZ6BwMJrYAAAAA", uri="sip:address@hidden;verified", response="5cae371d162ca7a257f41d86c5d99df6", algorithm=MD5, cnonce="0a4f113b", opaque="+GNywA==", qop=auth, nc=00000002 Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:30] [message] cb_sndbye (id=27) [20121219-07:05:30] [message] free transaction ressource 9 21292 [20121219-07:05:30] [message] free nict ressource [20121219-07:05:30] [message] Filter MSRtpRecv is not scheduled; nothing to do. [20121219-07:05:30] [message] oRTP-stats: Audio session's RTP statistics : [20121219-07:05:30] [message] number of rtp packet sent=547 [20121219-07:05:30] [message] number of rtp bytes sent=36619 bytes [20121219-07:05:30] [message] eXosip: timer sec:0 usec:509875! [20121219-07:05:30] [message] number of rtp packet received=0 [20121219-07:05:30] [message] number of rtp bytes received=0 bytes [20121219-07:05:30] [message] number of incoming rtp bytes successfully delivered to the application=0 [20121219-07:05:30] [message] number of rtp packet lost=0 [20121219-07:05:30] [message] number of rtp packets received too late=0 [20121219-07:05:30] [message] number of bad formatted rtp packets=0 [20121219-07:05:30] [message] number of packet discarded because of queue overflow=0 [20121219-07:05:30] [message] ms_filter_unlink: MSAlsaRead:0x13a0210,0-->MSSpeexEC:0x135fc00,1 [20121219-07:05:30] [message] ms_filter_unlink: MSSpeexEC:0x135fc00,1-->MSVolume:0x13a1e60,0 [20121219-07:05:30] [message] ms_filter_unlink: MSVolume:0x13a1e60,0-->MSSpeexEnc:0x13be510,0 [20121219-07:05:30] [message] ms_filter_unlink: MSSpeexEnc:0x13be510,0-->MSRtpSend:0x1258250,0 [20121219-07:05:30] [message] ms_filter_unlink: MSRtpRecv:0x13be450,0-->MSSpeexDec:0x135f9a0,0 [20121219-07:05:30] [message] ms_filter_unlink: MSSpeexDec:0x135f9a0,0-->MSDtmfGen:0x13a0170,0 [20121219-07:05:30] [message] ms_filter_unlink: MSDtmfGen:0x13a0170,0-->MSVolume:0x1360970,0 [20121219-07:05:30] [message] ms_filter_unlink: MSVolume:0x1360970,0-->MSEqualizer:0x13a0510,0 [20121219-07:05:30] [message] ms_filter_unlink: MSEqualizer:0x13a0510,0-->MSSpeexEC:0x135fc00,0 [20121219-07:05:30] [message] ms_filter_unlink: MSSpeexEC:0x135fc00,0-->MSAlsaWrite:0x13b1170,0 [20121219-07:05:30] [message] Audio MSTicker thread exiting [20121219-07:05:30] [message] Filter usage statistics: [20121219-07:05:30] [message] Name Count Time/tick (ms) CPU Usage [20121219-07:05:30] [message] MSSpeexEC 514 4.36598 80.1886 [20121219-07:05:30] [message] MSSpeexEnc 514 0.633437 11.6341 [20121219-07:05:30] [message] MSAlsaWrite 821 0.17079 5.00676 [20121219-07:05:30] [message] MSAlsaRead 1099 0.0525486 2.06147 [20121219-07:05:30] [message] MSRtpSend 1099 0.0167543 0.657268 [20121219-07:05:30] [message] MSRtpRecv 1099 0.00590126 0.231505 [20121219-07:05:30] [message] MSVolume 514 0.00670843 0.123212 [20121219-07:05:30] [message] MSFilePlayer 307 0.00505029 0.0554741 [20121219-07:05:30] [message] MSDtmfGen 1406 0.000526771 0.0264326 [20121219-07:05:30] [message] MSSpeexDec 1099 0.000387316 0.0151943 [20121219-07:05:30] [message] MSEqualizer 0 0 0 [20121219-07:05:30] [message] Call 0x13a76e0: moving from state LinphoneCallStreamsRunning to LinphoneCallEnd [20121219-07:05:30] [message] Resetting the current call [20121219-07:05:30] [message] Notifying all friends that we are in status 1 [20121219-07:05:30] [message] Want to notify , insub=0x134b8b0 [20121219-07:05:30] [message] allocating transaction ressource 28 21292 [20121219-07:05:30] [message] allocating NICT context [20121219-07:05:30] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:30] [message] getaddrinfo returned the following addresses: [20121219-07:05:30] [message] 37.59.129.73 port 5060 [20121219-07:05:30] [message] Message sent: (to dest=37.59.129.73:5060) NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK650419465 Route: From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 10 NOTIFY Contact: Content-Type: application/pidf+xml Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subscription-State: active;expires=568 Content-Length: 368 open sip:address@hidden [20121219-07:05:30] [message] cb_sndnotify (id=28) [20121219-07:05:30] [message] eXosip: timer sec:0 usec:506484! [20121219-07:05:30] [message] Received message: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK650419465;received=1.2.3.4 From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 10 NOTIFY Contact: Server: Flexisip/0.5.0 (sofia-sip-nta/2.0) Proxy-Authenticate: Digest realm="sip.linphone.org", nonce="eOZ71AAAAAAA1VeuAAC3O86pBu4AAAAA", opaque="+GNywA==", algorithm=MD5, qop="auth" Content-Length: 0 [20121219-07:05:30] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:30] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:30] [message] MESSAGE REC. CALLID:21292 [20121219-07:05:30] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:30] [message] cb_rcv4xx (id=28) [20121219-07:05:30] [message] eXosip: timer sec:0 usec:497698! [20121219-07:05:30] [message] linphone process event get a message 51 [20121219-07:05:30] [message] auth_requested() for realm="sip.linphone.org", username=squirtle [20121219-07:05:30] [message] auth_requested(): authenticating realm="sip.linphone.org", username=squirtle [20121219-07:05:30] [message] Authentication info for username [squirtle], id[squirtle], realm ["sip.linphone.org"] added to eXosip [20121219-07:05:30] [message] Contact address updated to [20121219-07:05:30] [message] INFO: authinfo: "sip.linphone.org" "sip.linphone.org" [20121219-07:05:30] [message] authinfo: squirtle [20121219-07:05:30] [message] allocating transaction ressource 29 21292 [20121219-07:05:30] [message] allocating NICT context [20121219-07:05:30] [message] eXosip_default_action() done [20121219-07:05:30] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:30] [message] getaddrinfo returned the following addresses: [20121219-07:05:30] [message] 37.59.129.73 port 5060 [20121219-07:05:30] [message] Message sent: (to dest=37.59.129.73:5060) NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK178404421 Route: From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 11 NOTIFY Contact: Proxy-Authorization: Digest username="squirtle", realm="sip.linphone.org", nonce="eOZ71AAAAAAA1VeuAAC3O86pBu4AAAAA", uri="sip:address@hidden", response="e901fe728f447164580af1a1ee57a165", algorithm=MD5, cnonce="0a4f113b", opaque="+GNywA==", qop=auth, nc=00000001 Content-Type: application/pidf+xml Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subscription-State: active;expires=568 Content-Length: 368 open sip:address@hidden [20121219-07:05:30] [message] cb_sndnotify (id=29) [20121219-07:05:30] [message] eXosip: timer sec:0 usec:491234! [20121219-07:05:31] [message] Received message: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK2147062262;received=1.2.3.4 From: ;tag=1466034646 To: ;tag=18467 Call-ID: 150493449 CSeq: 22 BYE User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:31] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:31] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:31] [message] MESSAGE REC. CALLID:150493449 [20121219-07:05:31] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:31] [message] cb_rcv2xx (id=27) [20121219-07:05:31] [message] eXosip: timer sec:0 usec:352568! [20121219-07:05:31] [message] linphone process event get a message 20 [20121219-07:05:31] [message] Unhandled exosip event ! 20 [20121219-07:05:31] [message] Received message: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK178404421;received=1.2.3.4 From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 11 NOTIFY User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:31] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:31] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:31] [message] MESSAGE REC. CALLID:21292 [20121219-07:05:31] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:31] [message] cb_rcv2xx (id=29) [20121219-07:05:31] [message] eXosip: timer sec:4 usec:823765! [20121219-07:05:31] [message] linphone process event get a message 49 [20121219-07:05:31] [message] Unhandled exosip event ! 49 [20121219-07:05:31] [message] Received message: NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.0pHBZH5S1FN3XS3575vraDDcac Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK20269;received=9.8.7.6 Max-Forwards: 70 From: ;tag=41 To: ;tag=446971923 Call-ID: 2036121733 CSeq: 11 NOTIFY Contact: User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subscription-State: active;expires=568 Content-Type: application/pidf+xml Content-Length: 360 open sip:address@hidden [20121219-07:05:31] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:31] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:31] [message] MESSAGE REC. CALLID:2036121733 [20121219-07:05:31] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:31] [message] This is a request [20121219-07:05:31] [message] allocating transaction ressource 30 2036121733 [20121219-07:05:31] [message] allocating NIST context [20121219-07:05:31] [message] cb_rcvunkrequest (id=30) [20121219-07:05:31] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:31] [message] getaddrinfo returned the following addresses: [20121219-07:05:31] [message] 37.59.129.73 port 5060 [20121219-07:05:31] [message] Message sent: (to dest=37.59.129.73:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 37.59.129.73;branch=z9hG4bK.0pHBZH5S1FN3XS3575vraDDcac Via: SIP/2.0/UDP 192.168.0.127:5060;rport=5060;branch=z9hG4bK20269;received=9.8.7.6 From: ;tag=41 To: ;tag=446971923 Call-ID: 2036121733 CSeq: 11 NOTIFY User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 [20121219-07:05:31] [message] cb_snd123456xx (id=30) [20121219-07:05:31] [message] eXosip: timer sec:4 usec:578568! [20121219-07:05:31] [message] eXosip: timer sec:4 usec:578512! [20121219-07:05:31] [message] linphone process event get a message 43 [20121219-07:05:31] [message] CALL_SUBSCRIPTION_NOTIFY [20121219-07:05:31] [message] Receiving notify with sid=1,nid=0 [20121219-07:05:31] [message] We are notified that ;tag=41 has online status 1 [20121219-07:05:32] [message] authinfo: No authentication found for squirtle "sip.linphone.org" [20121219-07:05:32] [message] allocating transaction ressource 31 2036121733 [20121219-07:05:32] [message] allocating NICT context [20121219-07:05:32] [message] allocating transaction ressource 32 21292 [20121219-07:05:32] [message] allocating NICT context [20121219-07:05:32] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:32] [message] getaddrinfo returned the following addresses: [20121219-07:05:32] [message] 37.59.129.73 port 5060 [20121219-07:05:32] [message] Message sent: (to dest=37.59.129.73:5060) SUBSCRIBE sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK1906719873 Route: From: ;tag=446971923 To: ;tag=41 Call-ID: 2036121733 CSeq: 22 SUBSCRIBE Contact: Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Expires: 0 Content-Length: 0 [20121219-07:05:32] [message] cb_sndsubscribe (id=31) [20121219-07:05:32] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:32] [message] getaddrinfo returned the following addresses: [20121219-07:05:32] [message] 37.59.129.73 port 5060 [20121219-07:05:32] [message] Message sent: (to dest=37.59.129.73:5060) NOTIFY sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK2107279210 Route: From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 12 NOTIFY Contact: Contact: Content-Type: application/pidf+xml Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Subscription-State: terminated;reason=deactivated Content-Length: 371 closed sip:address@hidden [20121219-07:05:32] [message] cb_sndnotify (id=32) [20121219-07:05:32] [message] eXosip: timer sec:0 usec:509822! [20121219-07:05:32] [message] authinfo: No authentication found for squirtle "sip.linphone.org" [20121219-07:05:32] [message] allocating transaction ressource 33 422128585 [20121219-07:05:32] [message] allocating NICT context [20121219-07:05:32] [message] eXosip option set: dns cache used:sip.linphone.org -> 37.59.129.73 [20121219-07:05:32] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:32] [message] getaddrinfo returned the following addresses: [20121219-07:05:32] [message] 37.59.129.73 port 5060 [20121219-07:05:32] [message] Message sent: (to dest=37.59.129.73:5060) REGISTER sip:sip.linphone.org SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK338366717 From: ;tag=216868713 To: Call-ID: 422128585 CSeq: 5 REGISTER Contact: Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Expires: 0 Content-Length: 0 [20121219-07:05:32] [message] eXosip option set: dns cache updated:sip.linphone.org -> 37.59.129.73 [20121219-07:05:32] [message] cb_sndregister (id=33) [20121219-07:05:32] [message] free transaction ressource 4 422128585 [20121219-07:05:32] [message] free nict ressource [20121219-07:05:32] [message] eXosip: timer sec:0 usec:509404! [20121219-07:05:32] [message] eXosip: timer sec:0 usec:509349! [20121219-07:05:32] [message] Received message: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK1906719873;received=1.2.3.4 From: ;tag=446971923 To: ;tag=41 Call-ID: 2036121733 CSeq: 22 SUBSCRIBE Contact: Server: Flexisip/0.5.0 (sofia-sip-nta/2.0) Proxy-Authenticate: Digest realm="sip.linphone.org", nonce="euZ71AAAAAAuLJ1dAAB4itZZoRcAAAAA", opaque="+GNywA==", algorithm=MD5, qop="auth" Content-Length: 0 [20121219-07:05:32] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:32] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:32] [message] MESSAGE REC. CALLID:2036121733 [20121219-07:05:32] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:32] [message] cb_rcv4xx (id=31) [20121219-07:05:32] [message] eXosip: timer sec:0 usec:501450! [20121219-07:05:32] [message] Received message: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK2107279210;received=1.2.3.4 From: ;tag=161942888 To: ;tag=31787 Call-ID: 21292 CSeq: 12 NOTIFY Contact: Contact: Server: Flexisip/0.5.0 (sofia-sip-nta/2.0) Proxy-Authenticate: Digest realm="sip.linphone.org", nonce="euZ71AAAAAAZ6PA7AACVF9onLagAAAAA", opaque="+GNywA==", algorithm=MD5, qop="auth" Content-Length: 0 [20121219-07:05:32] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:32] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:32] [message] MESSAGE REC. CALLID:21292 [20121219-07:05:32] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:32] [message] cb_rcv4xx (id=32) [20121219-07:05:32] [message] eXosip: timer sec:0 usec:498256! [20121219-07:05:32] [message] Received message: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK338366717;received=1.2.3.4 From: ;tag=216868713 To: ;tag=r02SQ08mUrrgj Call-ID: 422128585 CSeq: 5 REGISTER Contact: Server: Flexisip/0.5.0 (sofia-sip-nta/2.0) WWW-Authenticate: Digest realm="sip.linphone.org", nonce="euZ71AAAAAAEpEQaAADW8zsi5BEAAAAA", opaque="+GNywA==", algorithm=MD5, qop="auth" Content-Length: 0 [20121219-07:05:32] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:32] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:32] [message] MESSAGE REC. CALLID:422128585 [20121219-07:05:32] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:32] [message] cb_rcv4xx (id=33) [20121219-07:05:32] [message] eXosip: timer sec:3 usec:21109! [20121219-07:05:33] [message] linphone process event get a message 40 [20121219-07:05:33] [message] No op for sid 1 [20121219-07:05:33] [warning] No operation associated with this authentication ! [20121219-07:05:33] [message] linphone process event get a message 51 [20121219-07:05:33] [warning] No operation associated with this authentication ! [20121219-07:05:33] [message] linphone process event get a message 2 [20121219-07:05:33] [message] REGISTRATION_FAILURE [20121219-07:05:33] [message] auth_requested() for realm="sip.linphone.org", username=squirtle [20121219-07:05:33] [message] auth_requested(): authenticating realm="sip.linphone.org", username=squirtle [20121219-07:05:33] [message] Authentication info for username [squirtle], id[squirtle], realm ["sip.linphone.org"] added to eXosip [20121219-07:05:33] [message] Contact address updated to [20121219-07:05:33] [message] INFO: authinfo: "sip.linphone.org" "sip.linphone.org" [20121219-07:05:33] [message] allocating transaction ressource 34 422128585 [20121219-07:05:33] [message] allocating NICT context [20121219-07:05:33] [message] eXosip_default_action() done [20121219-07:05:33] [message] eXosip option set: dns cache used:sip.linphone.org -> 37.59.129.73 [20121219-07:05:33] [message] DNS resolution with 37.59.129.73:5060 [20121219-07:05:33] [message] getaddrinfo returned the following addresses: [20121219-07:05:33] [message] 37.59.129.73 port 5060 [20121219-07:05:33] [message] Message sent: (to dest=37.59.129.73:5060) REGISTER sip:sip.linphone.org SIP/2.0 Via: SIP/2.0/UDP 192.168.0.101:5060;rport;branch=z9hG4bK1902682355 From: ;tag=216868713 To: Call-ID: 422128585 CSeq: 6 REGISTER Contact: Authorization: Digest username="squirtle", realm="sip.linphone.org", nonce="euZ71AAAAAAEpEQaAADW8zsi5BEAAAAA", uri="sip:sip.linphone.org", response="0cca9aae6eb0192a05dcb641c7d6f559", algorithm=MD5, cnonce="0a4f113b", opaque="+GNywA==", qop=auth, nc=00000001 Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Expires: 0 Content-Length: 0 [20121219-07:05:33] [message] eXosip option set: dns cache updated:sip.linphone.org -> 37.59.129.73 [20121219-07:05:33] [message] cb_sndregister (id=34) [20121219-07:05:33] [message] eXosip: timer sec:0 usec:509980! [20121219-07:05:33] [message] Received message: SIP/2.0 200 Registration successful Via: SIP/2.0/UDP 192.168.0.101:5060;rport=5060;branch=z9hG4bK1902682355;received=1.2.3.4 From: ;tag=216868713 To: ;tag=S9UjSUSrr1e3D Call-ID: 422128585 CSeq: 6 REGISTER Server: Flexisip/0.5.0 (sofia-sip-nta/2.0) Content-Length: 0 [20121219-07:05:33] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:33] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:33] [message] MESSAGE REC. CALLID:422128585 [20121219-07:05:33] [message] Message received from: 37.59.129.73:5060 [20121219-07:05:33] [message] cb_rcv2xx (id=34) [20121219-07:05:33] [message] Release a terminated registration with 2xx [20121219-07:05:33] [message] Release a non-terminated transaction [20121219-07:05:33] [message] eXosip: timer sec:2 usec:921666! [20121219-07:05:33] [message] linphone process event get a message 1 [20121219-07:05:33] [message] "sip.linphone.org"/squirtle authentication works. [20121219-07:05:34] [message] Release a terminated transaction [20121219-07:05:34] [message] free transaction ressource 8 21292 [20121219-07:05:34] [message] free nist ressource [20121219-07:05:34] [message] Release a terminated transaction [20121219-07:05:34] [message] free transaction ressource 14 21292 [20121219-07:05:34] [message] free nict ressource [20121219-07:05:34] [message] Release a terminated transaction [20121219-07:05:34] [message] free transaction ressource 16 21292 [20121219-07:05:34] [message] free nict ressource [20121219-07:05:34] [message] Release a terminated transaction [20121219-07:05:34] [message] free transaction ressource 18 21292 [20121219-07:05:34] [message] free nict ressource [20121219-07:05:34] [message] Release a terminated transaction [20121219-07:05:34] [message] free transaction ressource 19 21292 [20121219-07:05:34] [message] free nict ressource [20121219-07:05:34] [message] Release a terminated transaction [20121219-07:05:34] [message] free transaction ressource 21 21292 [20121219-07:05:34] [message] free nict ressource [20121219-07:05:34] [message] Release a terminated transaction [20121219-07:05:34] [message] free transaction ressource 23 21292 [20121219-07:05:34] [message] free nict ressource [20121219-07:05:34] [message] free transaction ressource 28 21292 [20121219-07:05:34] [message] free nict ressource [20121219-07:05:34] [message] free transaction ressource 29 21292 [20121219-07:05:34] [message] free nict ressource [20121219-07:05:34] [message] free transaction ressource 32 21292 [20121219-07:05:34] [message] free nict ressource [20121219-07:05:34] [message] Release a terminated transaction [20121219-07:05:34] [message] free transaction ressource 6 2036121733 [20121219-07:05:34] [message] free nict ressource [20121219-07:05:34] [message] free transaction ressource 31 2036121733 [20121219-07:05:34] [message] free nict ressource [20121219-07:05:34] [message] free transaction ressource 11 2036121733 [20121219-07:05:34] [message] free nist ressource [20121219-07:05:34] [message] free transaction ressource 20 2036121733 [20121219-07:05:34] [message] free nist ressource [20121219-07:05:34] [message] free transaction ressource 25 2036121733 [20121219-07:05:34] [message] free nist ressource [20121219-07:05:34] [message] free transaction ressource 30 2036121733 [20121219-07:05:34] [message] free nist ressource [20121219-07:05:34] [message] Release a terminated transaction [20121219-07:05:34] [message] free transaction ressource 24 150493449 [20121219-07:05:34] [message] free ict ressource [20121219-07:05:34] [message] free transaction ressource 27 150493449 [20121219-07:05:34] [message] free nict ressource [20121219-07:05:34] [message] free transaction ressource 34 422128585 [20121219-07:05:34] [message] free nict ressource [20121219-07:05:34] [message] free transaction ressource 33 422128585 [20121219-07:05:34] [message] free nict ressource [20121219-07:05:34] [message] free transaction ressource 26 24168 [20121219-07:05:34] [message] free nist ressource [20121219-07:05:34] [message] free transaction ressource 22 150493449 [20121219-07:05:34] [message] free ict ressource --------