message: 224 interpreted as message: Flattened number is '224' message: No need to add a prefix message: Local interface to reach 87.98.157.38 is 10.101.1.69. message: Notifying all friends that we are in status 5 message: Partial MTU discovered : 1500 message: mtu to SIP-Proxy-Address is 1500 message: Discovered mtu is 1500, RTP payload max size is 1440 message: Call 0x9444400: moving from state LinphoneCallIdle to LinphoneCallOutgoingInit message: Contact has been fixed using proxy to warning: cannot set noise gate mode to [0] because no volume send message: allocating transaction ressource 26 1080035985 message: allocating ICT context message: eXosip option set: dns cache used:SIP-Proxy-Address -> 10.110.5.1 message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) INVITE sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 10.101.1.69:5060;rport;branch=z9hG4bK612987970 From: ;tag=665577470 To: Call-ID: 1080035985 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: 304 v=0 o=222 365 365 IN IP4 10.101.1.69 s=Talk c=IN IP4 10.101.1.69 t=0 0 m=audio 7078 RTP/AVP 112 111 110 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 message: cb_sndinvite (id=26) message: eXosip: timer sec:0 usec:509388! message: Call 0x9444400: moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress message: Received message: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.101.1.69:5060;branch=z9hG4bK612987970;received=10.101.1.69;rport=5060 From: ;tag=665577470 To: ;tag=as4e69c1be Call-ID: 1080035985 CSeq: 20 INVITE Server: FPBX-2.8.1(1.8.12.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="566463c1" Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:1080035985 message: Message received from: 10.110.5.1:5060 message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) ACK sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 10.101.1.69:5060;rport;branch=z9hG4bK612987970 From: ;tag=665577470 To: ;tag=as4e69c1be Call-ID: 1080035985 CSeq: 20 ACK Content-Length: 0 message: cb_rcv4xx (id=26) message: cb_sndack (id=26) message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 12 message: CALL_REQUESTFAILURE or GLOBALFAILURE or SERVERFAILURE message: auth_requested() for realm="asterisk", username=222 message: auth_requested(): authenticating realm="asterisk", username=222 message: Authentication info for username [222], id[222], realm ["asterisk"] added to eXosip message: Contact address updated to message: INFO: authinfo: "asterisk" "asterisk" message: allocating transaction ressource 27 1080035985 message: allocating ICT context message: eXosip option set: dns cache used:SIP-Proxy-Address -> 10.110.5.1 message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) INVITE sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 10.101.1.69:5060;rport;branch=z9hG4bK1689207158 From: ;tag=665577470 To: Call-ID: 1080035985 CSeq: 21 INVITE Contact: Authorization: Digest username="222", realm="asterisk", nonce="566463c1", uri="sip:address@hidden", response="2b3bc9e8893a2768428f8a497f095859", algorithm=MD5 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: 304 v=0 o=222 365 365 IN IP4 10.101.1.69 s=Talk c=IN IP4 10.101.1.69 t=0 0 m=audio 7078 RTP/AVP 112 111 110 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 message: cb_sndinvite (id=27) message: eXosip: timer sec:0 usec:509353! message: eXosip_default_action() done message: Received message: SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.101.1.69:5060;branch=z9hG4bK1689207158;received=10.101.1.69;rport=5060 From: ;tag=665577470 To: Call-ID: 1080035985 CSeq: 21 INVITE Server: FPBX-2.8.1(1.8.12.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:1080035985 message: Message received from: 10.110.5.1:5060 message: cb_rcv1xx (id=27) message: eXosip: Reseting timer to 10s before waking up! message: Received message: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.101.1.69:5060;branch=z9hG4bK1689207158;received=10.101.1.69;rport=5060 From: ;tag=665577470 To: ;tag=as5b4b717a Call-ID: 1080035985 CSeq: 21 INVITE Server: FPBX-2.8.1(1.8.12.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:1080035985 message: Message received from: 10.110.5.1:5060 message: cb_rcv1xx (id=27) message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 8 message: CALL_PROCEEDING message: Contact address updated to message: linphone process event get a message 9 message: CALL_RINGING message: Contact address updated to message: /usr/share/sounds/linphone/ringback.wav opened: rate=8000,channel=1 message: ms_filter_link: MSFilePlayer:0x9569cc8,0-->MSDtmfGen:0x9413408,0 message: ms_filter_link: MSDtmfGen:0x9413408,0-->MSAlsaWrite:0x95a5138,0 message: Remote ringing... message: Call 0x9444400: moving from state LinphoneCallOutgoingProgress to LinphoneCallOutgoingRinging message: alsa_open_w: opening default at 8000Hz, bits=16, stereo=0 message: Received message: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.101.1.69:5060;branch=z9hG4bK1689207158;received=10.101.1.69;rport=5060 From: ;tag=665577470 To: ;tag=as5b4b717a Call-ID: 1080035985 CSeq: 21 INVITE Server: FPBX-2.8.1(1.8.12.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:1080035985 message: Message received from: 10.110.5.1:5060 message: cb_rcv1xx (id=27) message: eXosip: Reseting timer to 10s before waking up! warning: alsa_set_params: The period size 256 is not supported by your hardware. ==> Using 170 instead. warning: alsa_set_params: periodsize:256 Using 170 warning: alsa_set_params: period:8 Using 8 message: alsa_open_w: Audio params set message: linphone process event get a message 9 message: CALL_RINGING message: Contact address updated to message: Received message: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.101.1.69:5060;branch=z9hG4bK1689207158;received=10.101.1.69;rport=5060 From: ;tag=665577470 To: ;tag=as5b4b717a Call-ID: 1080035985 CSeq: 21 INVITE Server: FPBX-2.8.1(1.8.12.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 255 v=0 o=root 506953323 506953323 IN IP4 10.110.5.1 s=Asterisk PBX 1.8.12.0 c=IN IP4 10.110.5.1 t=0 0 m=audio 11414 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:1080035985 message: Message received from: 10.110.5.1:5060 message: cb_rcv2xx (id=27) message: cb_nict_kill_transaction (id=27) message: keep alive: 3 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 10 message: CALL_ANSWERED message: Found payload PCMU/8000 fmtp= message: Found payload PCMA/8000 fmtp= message: Found payload telephone-event/8000 fmtp=0-16 message: Doing SDP offer/answer process message: Processing for stream 0 message: Adding speex/32000 for compatibility, just in case. message: Adding speex/16000 for compatibility, just in case. message: Adding speex/8000 for compatibility, just in case. message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) ACK sip:address@hidden:5060 SIP/2.0 Via: SIP/2.0/UDP 10.101.1.69:5060;rport;branch=z9hG4bK1699693685 From: ;tag=665577470 To: ;tag=as5b4b717a Call-ID: 1080035985 CSeq: 21 ACK Contact: Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 message: Call 0x9444400: moving from state LinphoneCallOutgoingRinging to LinphoneCallConnected message: ms_filter_unlink: MSFilePlayer:0x9569cc8,0-->MSDtmfGen:0x9413408,0 message: ms_filter_unlink: MSDtmfGen:0x9413408,0-->MSAlsaWrite:0x95a5138,0 message: Audio (ring) MSTicker thread exiting message: Audio bandwidth for this call is 80 message: Payload's bitrate is 80000 message: Setting audio encoder network bitrate to 80000 message: MSUlawEnc: got ptime=20 message: ms_filter_link: MSAlsaRead:0x9413408,0-->MSSpeexEC:0x95a9290,1 message: ms_filter_link: MSSpeexEC:0x95a9290,1-->MSVolume:0x94d7828,0 message: ms_filter_link: MSVolume:0x94d7828,0-->MSUlawEnc:0x9577400,0 message: ms_filter_link: MSUlawEnc:0x9577400,0-->MSRtpSend:0x944ba88,0 message: ms_filter_link: MSRtpRecv:0x940c788,0-->MSUlawDec:0x941fb88,0 message: ms_filter_link: MSUlawDec:0x941fb88,0-->MSDtmfGen:0x9569cc8,0 message: ms_filter_link: MSDtmfGen:0x9569cc8,0-->MSVolume:0x9567768,0 message: ms_filter_link: MSVolume:0x9567768,0-->MSEqualizer:0x95834b8,0 message: ms_filter_link: MSEqualizer:0x95834b8,0-->MSSpeexEC:0x95a9290,0 message: ms_filter_link: MSSpeexEC:0x95a9290,0-->MSAlsaWrite:0x95a5138,0 message: Initializing speex echo canceler with framesize=64, filterlength=2000, delay_samples=0 message: Filter MSRtpRecv is already being scheduled; nothing to do. message: Call 0x9444400: moving from state LinphoneCallConnected to LinphoneCallStreamsRunning message: Priority used: 99 message: Audio MSTicker priority set to SCHED_FIFO and value (99) message: alsa_open_r: opening default at 8000Hz, bits=16, stereo=0 warning: alsa_set_params: The period size 256 is not supported by your hardware. ==> Using 170 instead. warning: alsa_set_params: periodsize:256 Using 170 warning: alsa_set_params: period:8 Using 8 message: alsa_open_r: Audio params set message: ms_ticker_set_time_func: ticker updated. message: "asterisk"/222 authentication works. warning: Getting reference signal but no echo to synchronize on. warning: Not enough ref samples, using zeroes message: alsa_open_w: opening default at 8000Hz, bits=16, stereo=0 warning: alsa_set_params: The period size 256 is not supported by your hardware. ==> Using 170 instead. warning: alsa_set_params: periodsize:256 Using 170 warning: alsa_set_params: period:8 Using 8 message: alsa_open_w: Audio params set message: bandwidth usage: audio=[d=0.0,u=83.1] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=14.192363 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.2,u=137.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=22.526922 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: alsa: sound/wall clock skew is average=-810.463051 ms, instant=-1361 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 54 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 51 miliseconds. warning: Audio MSTicker: We are late of 68 miliseconds. warning: Audio MSTicker: We are late of 69 miliseconds. warning: Audio MSTicker: We are late of 70 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 69 miliseconds. warning: Audio MSTicker: We are late of 87 miliseconds. warning: Audio MSTicker: We are late of 89 miliseconds. warning: Audio MSTicker: We are late of 90 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes message: Adjusting output timestamp by -7120 warning: Audio MSTicker: We are late of 91 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 93 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 95 miliseconds. warning: Audio MSTicker: We are late of 113 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 116 miliseconds. warning: Audio MSTicker: We are late of 118 miliseconds. warning: Audio MSTicker: We are late of 119 miliseconds. warning: Audio MSTicker: We are late of 120 miliseconds. warning: Audio MSTicker: We are late of 121 miliseconds. warning: Audio MSTicker: We are late of 122 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 124 miliseconds. warning: Audio MSTicker: We are late of 142 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 147 miliseconds. warning: Audio MSTicker: We are late of 149 miliseconds. warning: Audio MSTicker: We are late of 151 miliseconds. warning: Audio MSTicker: We are late of 152 miliseconds. warning: Audio MSTicker: We are late of 154 miliseconds. warning: Audio MSTicker: We are late of 155 miliseconds. warning: Audio MSTicker: We are late of 156 miliseconds. warning: Audio MSTicker: We are late of 157 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 160 miliseconds. warning: Audio MSTicker: We are late of 180 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 184 miliseconds. warning: Audio MSTicker: We are late of 187 miliseconds. warning: Audio MSTicker: We are late of 189 miliseconds. warning: Audio MSTicker: We are late of 191 miliseconds. warning: Audio MSTicker: We are late of 193 miliseconds. warning: Audio MSTicker: We are late of 195 miliseconds. warning: Audio MSTicker: We are late of 197 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 200 miliseconds. warning: Audio MSTicker: We are late of 220 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 225 miliseconds. warning: Audio MSTicker: We are late of 229 miliseconds. warning: Audio MSTicker: We are late of 232 miliseconds. warning: Audio MSTicker: We are late of 235 miliseconds. warning: Audio MSTicker: We are late of 237 miliseconds. warning: Audio MSTicker: We are late of 240 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 244 miliseconds. warning: Audio MSTicker: We are late of 265 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 271 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 277 miliseconds. warning: Audio MSTicker: We are late of 299 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 306 miliseconds. warning: Audio MSTicker: We are late of 311 miliseconds. warning: Audio MSTicker: We are late of 315 miliseconds. warning: Audio MSTicker: We are late of 320 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 326 miliseconds. warning: Audio MSTicker: We are late of 348 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 358 miliseconds. warning: Audio MSTicker: We are late of 364 miliseconds. warning: Audio MSTicker: We are late of 369 miliseconds. warning: Audio MSTicker: We are late of 375 miliseconds. warning: Audio MSTicker: We are late of 380 miliseconds. warning: Audio MSTicker: We are late of 385 miliseconds. warning: Audio MSTicker: We are late of 390 miliseconds. warning: Audio MSTicker: We are late of 395 miliseconds. warning: Audio MSTicker: We are late of 399 miliseconds. warning: Audio MSTicker: We are late of 404 miliseconds. warning: Audio MSTicker: We are late of 408 miliseconds. warning: Audio MSTicker: We are late of 412 miliseconds. warning: Audio MSTicker: We are late of 416 miliseconds. warning: Audio MSTicker: We are late of 439 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 449 miliseconds. warning: Audio MSTicker: We are late of 456 miliseconds. warning: Audio MSTicker: We are late of 481 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 490 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 514 miliseconds. warning: Audio MSTicker: We are late of 526 miliseconds. warning: Audio MSTicker: We are late of 534 miliseconds. warning: Audio MSTicker: We are late of 544 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 568 miliseconds. warning: Audio MSTicker: We are late of 581 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 592 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 618 miliseconds. warning: Audio MSTicker: We are late of 631 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 644 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 670 miliseconds. warning: Audio MSTicker: We are late of 685 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 699 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 727 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 741 miliseconds. warning: Audio MSTicker: We are late of 753 miliseconds. warning: Audio MSTicker: We are late of 765 miliseconds. warning: Audio MSTicker: We are late of 776 miliseconds. warning: Audio MSTicker: We are late of 787 miliseconds. warning: Audio MSTicker: We are late of 799 miliseconds. warning: Audio MSTicker: We are late of 809 miliseconds. warning: Audio MSTicker: We are late of 820 miliseconds. warning: Audio MSTicker: We are late of 831 miliseconds. warning: Audio MSTicker: We are late of 841 miliseconds. warning: Audio MSTicker: We are late of 851 miliseconds. warning: Audio MSTicker: We are late of 861 miliseconds. warning: Audio MSTicker: We are late of 870 miliseconds. warning: Audio MSTicker: We are late of 880 miliseconds. warning: Audio MSTicker: We are late of 890 miliseconds. warning: Audio MSTicker: We are late of 899 miliseconds. warning: Audio MSTicker: We are late of 908 miliseconds. warning: Audio MSTicker: We are late of 917 miliseconds. warning: Audio MSTicker: We are late of 925 miliseconds. warning: Audio MSTicker: We are late of 933 miliseconds. warning: Audio MSTicker: We are late of 941 miliseconds. warning: Audio MSTicker: We are late of 949 miliseconds. warning: Audio MSTicker: We are late of 957 miliseconds. warning: Audio MSTicker: We are late of 965 miliseconds. warning: Not enough ref samples, using zeroes message: Adjusting output timestamp by -14480 warning: Audio MSTicker: We are late of 976 miliseconds. warning: Audio MSTicker: We are late of 1002 miliseconds. warning: Audio MSTicker: We are late of 1011 miliseconds. warning: Audio MSTicker: We are late of 1020 miliseconds. warning: Audio MSTicker: We are late of 1028 miliseconds. warning: Audio MSTicker: We are late of 1036 miliseconds. warning: Audio MSTicker: We are late of 1045 miliseconds. warning: Audio MSTicker: We are late of 1053 miliseconds. warning: Audio MSTicker: We are late of 1061 miliseconds. warning: Audio MSTicker: We are late of 1068 miliseconds. warning: Audio MSTicker: We are late of 1075 miliseconds. warning: Audio MSTicker: We are late of 1082 miliseconds. warning: Audio MSTicker: We are late of 1089 miliseconds. warning: Audio MSTicker: We are late of 1096 miliseconds. warning: Audio MSTicker: We are late of 1103 miliseconds. warning: Audio MSTicker: We are late of 1109 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 1136 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 1146 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 1157 miliseconds. warning: Audio MSTicker: We are late of 1184 miliseconds. warning: Audio MSTicker: We are late of 1193 miliseconds. warning: Audio MSTicker: We are late of 1202 miliseconds. warning: Audio MSTicker: We are late of 1210 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 1223 miliseconds. warning: Audio MSTicker: We are late of 1250 miliseconds. warning: Audio MSTicker: We are late of 1260 miliseconds. warning: Audio MSTicker: We are late of 1269 miliseconds. warning: Audio MSTicker: We are late of 1278 miliseconds. warning: Audio MSTicker: We are late of 1288 miliseconds. warning: Audio MSTicker: We are late of 1297 miliseconds. warning: Audio MSTicker: We are late of 1305 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 1318 miliseconds. warning: Audio MSTicker: We are late of 1345 miliseconds. warning: Audio MSTicker: We are late of 1355 miliseconds. warning: Audio MSTicker: We are late of 1365 miliseconds. warning: Audio MSTicker: We are late of 1375 miliseconds. warning: Audio MSTicker: We are late of 1385 miliseconds. warning: Audio MSTicker: We are late of 1394 miliseconds. warning: Audio MSTicker: We are late of 1403 miliseconds. warning: Audio MSTicker: We are late of 1411 miliseconds. warning: Audio MSTicker: We are late of 1420 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 1431 miliseconds. warning: Audio MSTicker: We are late of 1459 miliseconds. warning: Audio MSTicker: We are late of 1469 miliseconds. warning: Audio MSTicker: We are late of 1478 miliseconds. warning: Audio MSTicker: We are late of 1488 miliseconds. warning: Audio MSTicker: We are late of 1497 miliseconds. warning: Audio MSTicker: We are late of 1506 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 1519 miliseconds. warning: Audio MSTicker: We are late of 1546 miliseconds. warning: Audio MSTicker: We are late of 1556 miliseconds. warning: Audio MSTicker: We are late of 1566 miliseconds. warning: Audio MSTicker: We are late of 1575 miliseconds. warning: Audio MSTicker: We are late of 1585 miliseconds. warning: Audio MSTicker: We are late of 1594 miliseconds. warning: Audio MSTicker: We are late of 1603 miliseconds. warning: Audio MSTicker: We are late of 1612 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 1623 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 1650 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 1662 miliseconds. warning: Audio MSTicker: We are late of 1673 miliseconds. warning: Audio MSTicker: We are late of 1682 miliseconds. warning: Audio MSTicker: We are late of 1692 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 1703 miliseconds. warning: Audio MSTicker: We are late of 1730 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 1743 miliseconds. warning: Audio MSTicker: We are late of 1753 miliseconds. warning: Audio MSTicker: We are late of 1763 miliseconds. warning: Audio MSTicker: We are late of 1773 miliseconds. warning: Audio MSTicker: We are late of 1783 miliseconds. warning: Audio MSTicker: We are late of 1792 miliseconds. warning: Audio MSTicker: We are late of 1802 miliseconds. warning: Audio MSTicker: We are late of 1812 miliseconds. warning: Audio MSTicker: We are late of 1821 miliseconds. warning: Audio MSTicker: We are late of 1829 miliseconds. warning: Audio MSTicker: We are late of 1838 miliseconds. warning: Audio MSTicker: We are late of 1846 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 1874 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 1887 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 1898 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 1926 miliseconds. warning: Audio MSTicker: We are late of 1939 miliseconds. warning: Audio MSTicker: We are late of 1952 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 1981 miliseconds. warning: Audio MSTicker: We are late of 1995 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 2008 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 2040 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 2056 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 2090 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 2106 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 2136 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 2156 miliseconds. warning: Audio MSTicker: We are late of 2173 miliseconds. warning: Audio MSTicker: We are late of 2205 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 2226 miliseconds. warning: Audio MSTicker: We are late of 2244 miliseconds. warning: Audio MSTicker: We are late of 2279 miliseconds. warning: Audio MSTicker: We are late of 2297 miliseconds. warning: Audio MSTicker: We are late of 2314 miliseconds. warning: Audio MSTicker: We are late of 2331 miliseconds. warning: Audio MSTicker: We are late of 2347 miliseconds. warning: Audio MSTicker: We are late of 2363 miliseconds. warning: Audio MSTicker: We are late of 2379 miliseconds. warning: Audio MSTicker: We are late of 2395 miliseconds. warning: Audio MSTicker: We are late of 2410 miliseconds. warning: Audio MSTicker: We are late of 2425 miliseconds. warning: Audio MSTicker: We are late of 2440 miliseconds. warning: Audio MSTicker: We are late of 2456 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 2488 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 2507 miliseconds. warning: Audio MSTicker: We are late of 2525 miliseconds. warning: Audio MSTicker: We are late of 2558 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 2578 miliseconds. warning: Audio MSTicker: We are late of 2597 miliseconds. warning: Audio MSTicker: We are late of 2629 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 2651 miliseconds. warning: Audio MSTicker: We are late of 2671 miliseconds. warning: Audio MSTicker: We are late of 2706 miliseconds. warning: Audio MSTicker: We are late of 2726 miliseconds. warning: Audio MSTicker: We are late of 2744 miliseconds. warning: Audio MSTicker: We are late of 2761 miliseconds. warning: Audio MSTicker: We are late of 2779 miliseconds. warning: Audio MSTicker: We are late of 2796 miliseconds. warning: Audio MSTicker: We are late of 2813 miliseconds. warning: Audio MSTicker: We are late of 2830 miliseconds. warning: Audio MSTicker: We are late of 2846 miliseconds. warning: Audio MSTicker: We are late of 2862 miliseconds. warning: Audio MSTicker: We are late of 2878 miliseconds. warning: Audio MSTicker: We are late of 2894 miliseconds. warning: Audio MSTicker: We are late of 2909 miliseconds. warning: Audio MSTicker: We are late of 2925 miliseconds. warning: Audio MSTicker: We are late of 2940 miliseconds. warning: Audio MSTicker: We are late of 2955 miliseconds. warning: Audio MSTicker: We are late of 2969 miliseconds. warning: Audio MSTicker: We are late of 2983 miliseconds. warning: Audio MSTicker: We are late of 2997 miliseconds. warning: Audio MSTicker: We are late of 3011 miliseconds. warning: Audio MSTicker: We are late of 3024 miliseconds. warning: Audio MSTicker: We are late of 3037 miliseconds. warning: Audio MSTicker: We are late of 3050 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes message: Adjusting output timestamp by -20960 warning: Audio MSTicker: We are late of 3067 miliseconds. warning: Audio MSTicker: We are late of 3098 miliseconds. warning: Audio MSTicker: We are late of 3112 miliseconds. warning: Audio MSTicker: We are late of 3126 miliseconds. warning: Audio MSTicker: We are late of 3139 miliseconds. warning: Audio MSTicker: We are late of 3153 miliseconds. warning: Audio MSTicker: We are late of 3166 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 3183 miliseconds. warning: Audio MSTicker: We are late of 3214 miliseconds. warning: Audio MSTicker: We are late of 3228 miliseconds. warning: Audio MSTicker: We are late of 3243 miliseconds. warning: Audio MSTicker: We are late of 3256 miliseconds. warning: Audio MSTicker: We are late of 3269 miliseconds. warning: Audio MSTicker: We are late of 3283 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 3299 miliseconds. warning: Audio MSTicker: We are late of 3330 miliseconds. warning: Audio MSTicker: We are late of 3345 miliseconds. warning: Audio MSTicker: We are late of 3359 miliseconds. warning: Audio MSTicker: We are late of 3372 miliseconds. warning: Audio MSTicker: We are late of 3386 miliseconds. warning: Audio MSTicker: We are late of 3399 miliseconds. warning: Audio MSTicker: We are late of 3412 miliseconds. warning: Audio MSTicker: We are late of 3424 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 3441 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 3472 miliseconds. warning: Audio MSTicker: We are late of 3486 miliseconds. warning: Audio MSTicker: We are late of 3499 miliseconds. warning: Audio MSTicker: We are late of 3512 miliseconds. warning: Audio MSTicker: We are late of 3525 miliseconds. warning: Audio MSTicker: We are late of 3538 miliseconds. warning: Audio MSTicker: We are late of 3550 miliseconds. warning: Audio MSTicker: We are late of 3564 miliseconds. warning: Audio MSTicker: We are late of 3576 miliseconds. warning: Audio MSTicker: We are late of 3588 miliseconds. warning: Audio MSTicker: We are late of 3599 miliseconds. warning: Audio MSTicker: We are late of 3611 miliseconds. warning: Audio MSTicker: We are late of 3622 miliseconds. warning: Audio MSTicker: We are late of 3633 miliseconds. warning: Audio MSTicker: We are late of 3644 miliseconds. warning: Audio MSTicker: We are late of 3654 miliseconds. warning: Audio MSTicker: We are late of 3684 miliseconds. message: Samples are back. warning: Audio MSTicker: We are late of 3699 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 3715 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 3745 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 3762 miliseconds. warning: Audio MSTicker: We are late of 3794 miliseconds. warning: Audio MSTicker: We are late of 3809 miliseconds. warning: Audio MSTicker: We are late of 3823 miliseconds. warning: Audio MSTicker: We are late of 3837 miliseconds. warning: Audio MSTicker: We are late of 3850 miliseconds. warning: Audio MSTicker: We are late of 3863 miliseconds. warning: Audio MSTicker: We are late of 3876 miliseconds. warning: Audio MSTicker: We are late of 3889 miliseconds. warning: Audio MSTicker: We are late of 3901 miliseconds. warning: Audio MSTicker: We are late of 3914 miliseconds. warning: Audio MSTicker: We are late of 3926 miliseconds. warning: Audio MSTicker: We are late of 3937 miliseconds. warning: Audio MSTicker: We are late of 3949 miliseconds. warning: Audio MSTicker: We are late of 3961 miliseconds. warning: Audio MSTicker: We are late of 3973 miliseconds. warning: Audio MSTicker: We are late of 3983 miliseconds. warning: Audio MSTicker: We are late of 3994 miliseconds. warning: Audio MSTicker: We are late of 4005 miliseconds. warning: Audio MSTicker: We are late of 4015 miliseconds. warning: Audio MSTicker: We are late of 4025 miliseconds. warning: Audio MSTicker: We are late of 4035 miliseconds. warning: Audio MSTicker: We are late of 4045 miliseconds. warning: Audio MSTicker: We are late of 4054 miliseconds. warning: Audio MSTicker: We are late of 4063 miliseconds. warning: Audio MSTicker: We are late of 4073 miliseconds. warning: Audio MSTicker: We are late of 4081 miliseconds. warning: Audio MSTicker: We are late of 4090 miliseconds. warning: Audio MSTicker: We are late of 4100 miliseconds. warning: Audio MSTicker: We are late of 4108 miliseconds. warning: Audio MSTicker: We are late of 4116 miliseconds. message: Samples are back. message: Adjusting output timestamp by -4720 warning: Audio MSTicker: We are late of 4126 miliseconds. warning: Audio MSTicker: We are late of 4134 miliseconds. warning: Audio MSTicker: We are late of 4142 miliseconds. warning: Audio MSTicker: We are late of 4150 miliseconds. warning: Audio MSTicker: We are late of 4157 miliseconds. warning: Audio MSTicker: We are late of 4164 miliseconds. warning: Audio MSTicker: We are late of 4171 miliseconds. warning: Audio MSTicker: We are late of 4178 miliseconds. warning: Audio MSTicker: We are late of 4185 miliseconds. warning: Audio MSTicker: We are late of 4192 miliseconds. warning: Audio MSTicker: We are late of 4198 miliseconds. warning: Audio MSTicker: We are late of 4205 miliseconds. warning: Audio MSTicker: We are late of 4211 miliseconds. warning: Audio MSTicker: We are late of 4217 miliseconds. warning: Audio MSTicker: We are late of 4223 miliseconds. warning: Audio MSTicker: We are late of 4228 miliseconds. warning: Audio MSTicker: We are late of 4234 miliseconds. warning: Audio MSTicker: We are late of 4239 miliseconds. warning: Audio MSTicker: We are late of 4244 miliseconds. warning: Audio MSTicker: We are late of 4249 miliseconds. warning: Audio MSTicker: We are late of 4254 miliseconds. warning: Audio MSTicker: We are late of 4259 miliseconds. warning: Audio MSTicker: We are late of 4263 miliseconds. warning: Audio MSTicker: We are late of 4268 miliseconds. warning: Audio MSTicker: We are late of 4272 miliseconds. warning: Audio MSTicker: We are late of 4277 miliseconds. warning: Audio MSTicker: We are late of 4281 miliseconds. warning: Audio MSTicker: We are late of 4285 miliseconds. warning: Audio MSTicker: We are late of 4288 miliseconds. warning: Audio MSTicker: We are late of 4292 miliseconds. warning: Audio MSTicker: We are late of 4295 miliseconds. warning: Audio MSTicker: We are late of 4298 miliseconds. warning: Audio MSTicker: We are late of 4302 miliseconds. warning: Audio MSTicker: We are late of 4305 miliseconds. warning: Audio MSTicker: We are late of 4307 miliseconds. warning: Audio MSTicker: We are late of 4310 miliseconds. warning: Audio MSTicker: We are late of 4313 miliseconds. warning: Audio MSTicker: We are late of 4315 miliseconds. warning: Audio MSTicker: We are late of 4318 miliseconds. warning: Audio MSTicker: We are late of 4321 miliseconds. warning: Audio MSTicker: We are late of 4323 miliseconds. warning: Audio MSTicker: We are late of 4325 miliseconds. warning: Audio MSTicker: We are late of 4327 miliseconds. warning: Audio MSTicker: We are late of 4328 miliseconds. warning: Audio MSTicker: We are late of 4330 miliseconds. warning: Audio MSTicker: We are late of 4332 miliseconds. warning: Audio MSTicker: We are late of 4333 miliseconds. warning: Audio MSTicker: We are late of 4334 miliseconds. warning: Audio MSTicker: We are late of 4335 miliseconds. warning: Audio MSTicker: We are late of 4336 miliseconds. warning: Audio MSTicker: We are late of 4337 miliseconds. warning: Audio MSTicker: We are late of 4338 miliseconds. warning: Audio MSTicker: We are late of 4339 miliseconds. warning: Audio MSTicker: We are late of 4340 miliseconds. warning: Audio MSTicker: We are late of 4341 miliseconds. warning: Audio MSTicker: We are late of 4342 miliseconds. message: alsa: sound/wall clock skew is average=-9008.705621 ms, instant=-9841 ms warning: Not enough ref samples, using zeroes message: Adjusting output timestamp by 26000 warning: Audio MSTicker: We are late of 2602 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes message: Adjusting output timestamp by 1680 message: alsa: sound/wall clock skew is average=-9998.307130 ms, instant=-10017 ms message: bandwidth usage: audio=[d=80.7,u=588.8] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=0.410052 video=0.000000 message: Samples are back. message: Adjusting output timestamp by 19600 warning: Audio MSTicker: We are late of 130 miliseconds. warning: Not enough ref samples, using zeroes warning: Audio MSTicker: We are late of 137 miliseconds. message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=109.5,u=151.2] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=16.072254 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-11208.673239 ms, instant=-11644 ms message: bandwidth usage: audio=[d=81.7,u=208.4] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=4.820873 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: audio_stream_process_rtcp: interarrival jitter=137 , lost packets percentage since last report=0.000000, round trip time=0.002930 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=17.125000 ms, rt_prop=0.002930 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init message: alsa: sound/wall clock skew is average=-13167.703712 ms, instant=-13480 ms message: bandwidth usage: audio=[d=80.9,u=229.3] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=5.797703 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=80.3,u=120.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=11.023357 video=0.000000 message: alsa: sound/wall clock skew is average=-14287.136384 ms, instant=-14633 ms message: bandwidth usage: audio=[d=80.6,u=152.3] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=12.157302 video=0.000000 message: alsa: sound/wall clock skew is average=-15861.899675 ms, instant=-16149 ms message: bandwidth usage: audio=[d=80.6,u=230.0] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=6.151518 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.4,u=137.1] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=12.986802 video=0.000000 message: alsa: sound/wall clock skew is average=-17169.005319 ms, instant=-17314 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: audio_stream_process_rtcp: interarrival jitter=102 , lost packets percentage since last report=0.000000, round trip time=0.004578 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=12.750000 ms, rt_prop=0.004578 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.4,u=159.9] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=22.346340 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-18446.173347 ms, instant=-18620 ms error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=81.0,u=119.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=12.826643 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=80.2,u=79.1] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=14.630784 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-18915.890065 ms, instant=-19551 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=80.0,u=177.2] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=6.860232 video=0.000000 warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-20680.261719 ms, instant=-21039 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=80.7,u=229.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=5.674177 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: audio_stream_process_rtcp: interarrival jitter=95 , lost packets percentage since last report=0.000000, round trip time=0.007553 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=11.875000 ms, rt_prop=0.007553 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK3e176129;rport Max-Forwards: 70 From: "Unknown" ;tag=as3c8dd1c0 To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:02:20 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:31c0996026ed03a9127d6a6b1e1d450b message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 28 31c0996026ed03a9127d6a6b1e1d450b message: allocating NIST context message: cb_rcvunkrequest (id=28) message: eXosip: timer sec:10 usec:805729! message: eXosip: timer sec:10 usec:805655! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK3e176129;rport=5060 From: "Unknown" ;tag=as3c8dd1c0 To: ;tag=863304426 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=28) message: eXosip: timer sec:10 usec:794537! message: bandwidth usage: audio=[d=81.1,u=118.2] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=12.871133 video=0.000000 message: alsa: sound/wall clock skew is average=-22129.962066 ms, instant=-22347 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=81.3,u=204.3] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=11.183526 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-23679.267937 ms, instant=-23822 ms message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=81.0,u=217.0] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=6.888348 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-25830.999547 ms, instant=-26192 ms message: bandwidth usage: audio=[d=81.1,u=162.6] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=14.559032 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=80.4,u=133.8] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=25.563711 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: alsa: sound/wall clock skew is average=-26803.417446 ms, instant=-27134 ms error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: audio_stream_process_rtcp: interarrival jitter=117 , lost packets percentage since last report=0.000000, round trip time=0.003326 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=14.625000 ms, rt_prop=0.003326 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=81.0,u=217.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=7.075514 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-28740.896784 ms, instant=-29122 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=81.0,u=188.2] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=21.896889 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-30080.906201 ms, instant=-30219 ms error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=80.8,u=162.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=8.333558 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=80.7,u=104.2] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=11.346484 video=0.000000 message: alsa: sound/wall clock skew is average=-31161.568434 ms, instant=-31342 ms error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Adjusting output timestamp by -2320 message: bandwidth usage: audio=[d=80.7,u=148.1] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=12.267499 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-32357.153217 ms, instant=-32798 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: audio_stream_process_rtcp: interarrival jitter=95 , lost packets percentage since last report=0.000000, round trip time=0.002762 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=11.875000 ms, rt_prop=0.002762 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init message: bandwidth usage: audio=[d=80.7,u=148.1] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=9.749340 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: cb_nict_kill_transaction (id=26) message: free transaction ressource 26 1080035985 message: free ict ressource message: keep alive: 5 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: bandwidth usage: audio=[d=80.1,u=90.0] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=14.723976 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-33048.005917 ms, instant=-33429 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.4,u=138.1] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=25.575552 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.4,u=80.4] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=13.092844 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-33779.384109 ms, instant=-33908 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.6,u=135.9] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=9.477962 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-35020.314492 ms, instant=-35260 ms message: Samples are back. message: audio_stream_process_rtcp: interarrival jitter=133 , lost packets percentage since last report=0.000000, round trip time=0.007614 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=16.625000 ms, rt_prop=0.007614 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.3,u=174.2] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=14.089467 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=81.0,u=176.9] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=11.284466 video=0.000000 warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-36494.252712 ms, instant=-36601 ms message: Samples are back. message: bandwidth usage: audio=[d=80.4,u=124.3] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=10.684193 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=81.2,u=92.6] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=14.398452 video=0.000000 message: alsa: sound/wall clock skew is average=-37223.110153 ms, instant=-37226 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.3,u=135.1] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=11.766357 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-38224.152186 ms, instant=-38573 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: audio_stream_process_rtcp: interarrival jitter=116 , lost packets percentage since last report=0.000000, round trip time=0.003387 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=14.500000 ms, rt_prop=0.003387 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=80.7,u=157.0] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=6.550322 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:10 usec:999628! warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=81.1,u=136.6] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=13.102745 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-39437.211015 ms, instant=-39529 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.5,u=120.3] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=10.908762 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-40490.060624 ms, instant=-40832 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=81.1,u=191.9] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=4.934139 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: alsa: sound/wall clock skew is average=-42095.198289 ms, instant=-42495 ms message: bandwidth usage: audio=[d=80.9,u=173.8] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=6.803206 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: audio_stream_process_rtcp: interarrival jitter=118 , lost packets percentage since last report=0.000000, round trip time=0.005188 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=14.750000 ms, rt_prop=0.005188 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.4,u=163.6] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=8.649795 video=0.000000 warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-43433.418663 ms, instant=-43835 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.9,u=211.8] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=8.391427 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-45365.139185 ms, instant=-45707 ms error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=80.5,u=172.6] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=7.944222 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-46606.599760 ms, instant=-46720 ms message: bandwidth usage: audio=[d=80.0,u=142.3] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=25.860113 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=80.1,u=132.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=12.558177 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: alsa: sound/wall clock skew is average=-47557.105792 ms, instant=-47680 ms message: audio_stream_process_rtcp: interarrival jitter=80 , lost packets percentage since last report=0.000000, round trip time=0.005737 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=10.000000 ms, rt_prop=0.005737 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init message: bandwidth usage: audio=[d=81.3,u=107.5] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=13.545284 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: alsa: sound/wall clock skew is average=-48854.375307 ms, instant=-48993 ms message: bandwidth usage: audio=[d=80.8,u=173.3] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=11.270010 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: cb_nict_kill_transaction (id=28) message: free transaction ressource 28 31c0996026ed03a9127d6a6b1e1d450b message: free nist ressource message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: bandwidth usage: audio=[d=80.4,u=135.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=9.775938 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-49926.867196 ms, instant=-50347 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=80.2,u=175.2] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=11.180182 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-51109.809320 ms, instant=-51312 ms message: Samples are back. message: bandwidth usage: audio=[d=80.8,u=120.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=16.093180 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: audio_stream_process_rtcp: interarrival jitter=72 , lost packets percentage since last report=0.000000, round trip time=0.007584 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=9.000000 ms, rt_prop=0.007584 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=80.9,u=159.4] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=11.891361 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-52365.307707 ms, instant=-52445 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=81.0,u=134.4] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=9.251783 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. message: alsa: sound/wall clock skew is average=-53436.920989 ms, instant=-53757 ms warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=80.3,u=159.4] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=20.670626 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.0,u=231.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=27.649340 video=0.000000 message: alsa: sound/wall clock skew is average=-55379.185889 ms, instant=-55752 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=80.3,u=148.2] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=11.427203 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-56657.872365 ms, instant=-56906 ms message: audio_stream_process_rtcp: interarrival jitter=76 , lost packets percentage since last report=0.000000, round trip time=0.005569 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=9.500000 ms, rt_prop=0.005569 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init message: bandwidth usage: audio=[d=81.6,u=122.9] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=11.215270 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=81.2,u=121.0] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=10.190392 video=0.000000 message: alsa: sound/wall clock skew is average=-57442.694548 ms, instant=-57552 ms message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: bandwidth usage: audio=[d=80.4,u=121.6] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=9.072527 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-58640.539728 ms, instant=-59015 ms message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.8,u=194.4] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=6.307886 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=81.0,u=108.3] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=20.785824 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-59828.494800 ms, instant=-60183 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: audio_stream_process_rtcp: interarrival jitter=107 , lost packets percentage since last report=0.000000, round trip time=0.003723 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=13.375000 ms, rt_prop=0.003723 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=82.2,u=171.8] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=9.029724 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-61041.932073 ms, instant=-61432 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=81.8,u=147.2] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=8.583470 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-62314.367757 ms, instant=-62598 ms message: bandwidth usage: audio=[d=81.6,u=149.2] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=9.720471 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=81.4,u=185.0] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=14.992450 video=0.000000 message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: alsa: sound/wall clock skew is average=-63740.804586 ms, instant=-63878 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=80.2,u=77.5] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=15.291675 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: alsa: sound/wall clock skew is average=-64857.151529 ms, instant=-65017 ms message: audio_stream_process_rtcp: interarrival jitter=110 , lost packets percentage since last report=0.000000, round trip time=0.003357 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=13.750000 ms, rt_prop=0.003357 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=80.1,u=171.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=12.750214 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=81.1,u=117.8] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=10.767246 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-65459.138646 ms, instant=-65473 ms message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: bandwidth usage: audio=[d=81.7,u=95.9] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=17.666901 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=80.7,u=146.0] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=12.023686 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-66544.706953 ms, instant=-66785 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. message: bandwidth usage: audio=[d=80.4,u=145.1] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=9.645887 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: audio_stream_process_rtcp: interarrival jitter=78 , lost packets percentage since last report=0.000000, round trip time=0.003235 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=9.750000 ms, rt_prop=0.003235 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=80.9,u=123.3] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=22.089949 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-67534.248402 ms, instant=-67939 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=81.4,u=157.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=8.269965 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-68892.271951 ms, instant=-69400 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=80.2,u=199.2] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=6.523787 video=0.000000 warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: alsa: sound/wall clock skew is average=-70511.917421 ms, instant=-70853 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=81.0,u=155.5] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=6.189101 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.9,u=143.0] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=8.320465 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-71772.844672 ms, instant=-71878 ms error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: audio_stream_process_rtcp: interarrival jitter=80 , lost packets percentage since last report=0.000000, round trip time=0.002655 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=10.000000 ms, rt_prop=0.002655 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK1ff13c42;rport Max-Forwards: 70 From: "Unknown" ;tag=as4314851b To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:03:20 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:542a9c363f114c69746f97095aa37b82 message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 29 542a9c363f114c69746f97095aa37b82 message: allocating NIST context message: cb_rcvunkrequest (id=29) message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK1ff13c42;rport=5060 From: "Unknown" ;tag=as4314851b To: ;tag=1363537675 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=29) message: eXosip: Reseting timer to 10s before waking up! message: bandwidth usage: audio=[d=80.4,u=94.6] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=15.421319 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=80.8,u=135.5] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=14.029341 video=0.000000 message: alsa: sound/wall clock skew is average=-72493.115563 ms, instant=-73006 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.6,u=145.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=10.243692 video=0.000000 warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-73549.846423 ms, instant=-73639 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=81.7,u=189.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=18.013790 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-75105.972542 ms, instant=-75122 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=81.1,u=106.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=14.337469 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: audio_stream_process_rtcp: interarrival jitter=105 , lost packets percentage since last report=0.000000, round trip time=0.018799 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=13.125000 ms, rt_prop=0.018799 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=81.0,u=131.8] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=7.302617 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-76409.382649 ms, instant=-76952 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=81.1,u=206.0] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=8.783624 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-77802.304155 ms, instant=-78271 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=81.4,u=210.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=12.977962 video=0.000000 warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: alsa: sound/wall clock skew is average=-79909.054249 ms, instant=-80232 ms warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=81.6,u=231.6] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=9.814066 video=0.000000 warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=81.0,u=95.1] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=13.769724 video=0.000000 message: alsa: sound/wall clock skew is average=-80923.333973 ms, instant=-81043 ms error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: audio_stream_process_rtcp: interarrival jitter=125 , lost packets percentage since last report=0.000000, round trip time=0.006088 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=15.625000 ms, rt_prop=0.006088 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init message: bandwidth usage: audio=[d=80.9,u=133.6] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=14.659699 video=0.000000 message: keep alive: 8 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: alsa: sound/wall clock skew is average=-81924.341711 ms, instant=-82028 ms message: bandwidth usage: audio=[d=80.9,u=119.8] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=10.747621 video=0.000000 message: bandwidth usage: audio=[d=81.3,u=123.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=12.992333 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-82982.044298 ms, instant=-83367 ms warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. message: bandwidth usage: audio=[d=80.0,u=161.9] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=11.590937 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=80.7,u=149.4] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=24.856066 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-84113.750611 ms, instant=-84509 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. message: audio_stream_process_rtcp: interarrival jitter=113 , lost packets percentage since last report=0.000000, round trip time=0.002045 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=14.125000 ms, rt_prop=0.002045 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=81.5,u=149.9] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=12.181243 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-85100.014691 ms, instant=-85144 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=80.3,u=79.9] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=13.729620 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. message: bandwidth usage: audio=[d=80.6,u=116.3] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=7.512951 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-85784.611270 ms, instant=-85914 ms warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=79.8,u=122.5] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=12.421213 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=81.2,u=106.6] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=13.533303 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-86411.007269 ms, instant=-86429 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: audio_stream_process_rtcp: interarrival jitter=115 , lost packets percentage since last report=0.000000, round trip time=0.005127 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=14.375000 ms, rt_prop=0.005127 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init message: bandwidth usage: audio=[d=81.4,u=106.8] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=10.775491 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=79.8,u=151.6] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=13.002220 video=0.000000 message: alsa: sound/wall clock skew is average=-87523.646182 ms, instant=-87577 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:9 usec:996398! message: bandwidth usage: audio=[d=80.4,u=108.4] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=12.450276 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=81.5,u=161.5] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=19.529757 video=0.000000 message: alsa: sound/wall clock skew is average=-88329.478526 ms, instant=-88872 ms message: Samples are back. message: Adjusting output timestamp by -960 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.3,u=152.0] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=7.740053 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-89750.999194 ms, instant=-90052 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: audio_stream_process_rtcp: interarrival jitter=86 , lost packets percentage since last report=0.000000, round trip time=0.001770 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=10.750000 ms, rt_prop=0.001770 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init message: bandwidth usage: audio=[d=80.6,u=140.2] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=12.621130 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=81.3,u=92.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=12.823044 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-90474.513636 ms, instant=-90483 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=80.7,u=178.6] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=8.342285 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-91907.769877 ms, instant=-92126 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=81.0,u=182.5] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=20.644094 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-93506.443383 ms, instant=-93905 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=81.3,u=190.2] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=10.017625 video=0.000000 warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: audio_stream_process_rtcp: interarrival jitter=110 , lost packets percentage since last report=0.000000, round trip time=0.002563 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=13.750000 ms, rt_prop=0.002563 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init message: Samples are back. message: alsa: sound/wall clock skew is average=-94913.788672 ms, instant=-95055 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=80.7,u=147.2] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=11.054233 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=79.9,u=265.9] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=7.725287 video=0.000000 message: alsa: sound/wall clock skew is average=-96899.825609 ms, instant=-97260 ms warning: Not enough ref samples, using zeroes message: cb_nict_kill_transaction (id=29) message: free transaction ressource 29 542a9c363f114c69746f97095aa37b82 message: free nist ressource message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: bandwidth usage: audio=[d=81.3,u=106.1] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=12.195571 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: alsa: sound/wall clock skew is average=-98035.633662 ms, instant=-98256 ms message: bandwidth usage: audio=[d=81.3,u=162.3] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=9.456763 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=80.1,u=135.7] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=13.174983 video=0.000000 message: alsa: sound/wall clock skew is average=-99228.056535 ms, instant=-99264 ms error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: audio_stream_process_rtcp: interarrival jitter=115 , lost packets percentage since last report=0.000000, round trip time=0.001465 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=14.375000 ms, rt_prop=0.001465 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init message: bandwidth usage: audio=[d=81.2,u=122.3] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=10.651546 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: alsa: sound/wall clock skew is average=-100376.160862 ms, instant=-100541 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=80.8,u=185.3] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=18.083250 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=80.8,u=116.3] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=16.007101 video=0.000000 error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: alsa: sound/wall clock skew is average=-101437.908429 ms, instant=-101447 ms error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=81.4,u=113.6] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=7.841127 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-102380.317748 ms, instant=-102880 ms message: Adjusting output timestamp by -640 message: bandwidth usage: audio=[d=80.8,u=182.1] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=6.625379 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: audio_stream_process_rtcp: interarrival jitter=122 , lost packets percentage since last report=0.000000, round trip time=0.002838 seconds message: MSQosAnalyser: lost_percentage=0.000000, int_jitter=15.250000 ms, rt_prop=0.002838 sec message: MSQosAnalyser: everything is fine. message: MSBitrateController: current state is Init message: bandwidth usage: audio=[d=80.2,u=137.4] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=11.060729 video=0.000000 warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: alsa: sound/wall clock skew is average=-103741.181031 ms, instant=-103862 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: bandwidth usage: audio=[d=81.1,u=196.4] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=7.741048 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: alsa: sound/wall clock skew is average=-105383.581048 ms, instant=-105698 ms message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: bandwidth usage: audio=[d=81.5,u=178.2] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=9.542700 video=0.000000 warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. message: alsa: sound/wall clock skew is average=-107094.021696 ms, instant=-107392 ms warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: Samples are back. warning: Not enough ref samples, using zeroes message: bandwidth usage: audio=[d=81.6,u=162.1] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=10.559086 video=0.000000 message: Samples are back. warning: Not enough ref samples, using zeroes error: snd_pcm_avail_update: Broken pipe error: *** alsa_can_read fixup, trying to recover message: Recovery done message: bandwidth usage: audio=[d=81.3,u=122.4] video=[d=0.0,u=0.0] kbit/sec message: Thread processing load: audio=13.119033 video=0.000000 message: Received message: BYE sip:address@hidden SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK5b7f3feb;rport Max-Forwards: 70 From: ;tag=as5b4b717a To: ;tag=665577470 Call-ID: 1080035985 CSeq: 102 BYE User-Agent: FPBX-2.8.1(1.8.12.0) Proxy-Authorization: Digest username="222", realm="asterisk", algorithm=MD5, uri="sip:SIP-Proxy-Address", nonce="", response="ab8dbd93c31a76cf93824a16629a03e1" X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:1080035985 message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 30 1080035985 message: allocating NIST context message: cb_rcvunkrequest (id=30) message: cb_rcv? (id=30) message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK5b7f3feb;rport=5060 From: ;tag=as5b4b717a To: ;tag=665577470 Call-ID: 1080035985 CSeq: 102 BYE User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Content-Length: 0 message: cb_snd123456xx (id=30) message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 18 message: EXOSIP_CALL_MESSAGE_NEW message: linphone process event get a message 25 message: CALL_CLOSED or CANCELLED message: Current call terminated... message: ms_ticker_set_time_func: ticker updated. message: Filter MSRtpRecv is not scheduled; nothing to do. message: oRTP-stats: Audio session's RTP statistics : message: number of rtp packet sent=11372 message: number of rtp bytes sent=1955984 bytes message: number of rtp packet received=5939 message: number of rtp bytes received=1021508 bytes message: number of incoming rtp bytes successfully delivered to the application=734956 message: number of rtp packet lost=0 message: number of rtp packets received too late=192 message: number of bad formatted rtp packets=0 message: number of packet discarded because of queue overflow=1174 message: ms_filter_unlink: MSAlsaRead:0x9413408,0-->MSSpeexEC:0x95a9290,1 message: ms_filter_unlink: MSSpeexEC:0x95a9290,1-->MSVolume:0x94d7828,0 message: ms_filter_unlink: MSVolume:0x94d7828,0-->MSUlawEnc:0x9577400,0 message: ms_filter_unlink: MSUlawEnc:0x9577400,0-->MSRtpSend:0x944ba88,0 message: ms_filter_unlink: MSRtpRecv:0x940c788,0-->MSUlawDec:0x941fb88,0 message: ms_filter_unlink: MSUlawDec:0x941fb88,0-->MSDtmfGen:0x9569cc8,0 message: ms_filter_unlink: MSDtmfGen:0x9569cc8,0-->MSVolume:0x9567768,0 message: ms_filter_unlink: MSVolume:0x9567768,0-->MSEqualizer:0x95834b8,0 message: ms_filter_unlink: MSEqualizer:0x95834b8,0-->MSSpeexEC:0x95a9290,0 message: ms_filter_unlink: MSSpeexEC:0x95a9290,0-->MSAlsaWrite:0x95a5138,0 message: Audio MSTicker thread exiting message: Filter usage statistics: message: Name Count Time/tick (ms) CPU Usage message: MSSpeexEC 9400 2.5265 77.5765 message: MSAlsaRead 22743 0.142534 10.5882 message: MSAlsaWrite 6799 0.323142 7.17693 message: MSRtpRecv 22743 0.0228366 1.69642 message: MSRtpSend 22743 0.0189908 1.41074 message: MSUlawEnc 6202 0.0305246 0.618426 message: MSVolume 10475 0.014165 0.484673 message: MSUlawDec 4273 0.0145219 0.202719 message: MSDtmfGen 23340 0.00246502 0.187921 message: MSEqualizer 4273 0.00214714 0.0299731 message: MSFilePlayer 597 0.0141043 0.0275481 message: Call 0x9444400: moving from state LinphoneCallStreamsRunning to LinphoneCallEnd message: Resetting the current call message: Notifying all friends that we are in status 1 error: Failed to send notification. message: keep alive: 3 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK4cabdec3;rport Max-Forwards: 70 From: "Unknown" ;tag=as6d6e6d2f To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:04:20 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:5fbbf56045fa4a9a4c1eeaa0359e3fc7 message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 31 5fbbf56045fa4a9a4c1eeaa0359e3fc7 message: allocating NIST context message: cb_rcvunkrequest (id=31) message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK4cabdec3;rport=5060 From: "Unknown" ;tag=as6d6e6d2f To: ;tag=13982099 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=31) message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 5 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:6 usec:10000! message: cb_nict_kill_transaction (id=30) message: eXosip: eXosip_release_finished_calls remove a dialog (cid=11 did=-1) message: eXosip: remove a call message: free transaction ressource 27 1080035985 message: free ict ressource message: free transaction ressource 30 1080035985 message: free nist ressource message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 26 message: CALL_RELEASED message: Call 0x9444400: moving from state LinphoneCallEnd to LinphoneCallReleased message: Cleaning cid 11 message: keep alive: 7 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:4 usec:999395! message: cb_nict_kill_transaction (id=31) message: free transaction ressource 31 5fbbf56045fa4a9a4c1eeaa0359e3fc7 message: free nist ressource message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 5 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK5dc2085c;rport Max-Forwards: 70 From: "Unknown" ;tag=as14bf49f7 To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:05:20 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:4425a45c770e674377b5d8693e102bf7 message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 32 4425a45c770e674377b5d8693e102bf7 message: allocating NIST context message: cb_rcvunkrequest (id=32) message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK5dc2085c;rport=5060 From: "Unknown" ;tag=as14bf49f7 To: ;tag=1077466782 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=32) message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 9 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:9 usec:999485! message: cb_nict_kill_transaction (id=32) message: free transaction ressource 32 4425a45c770e674377b5d8693e102bf7 message: free nist ressource message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK78b3e470;rport Max-Forwards: 70 From: "Unknown" ;tag=as6d35c7fb To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:06:20 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:1e164343384b67dd3bb9a2845b9b0d59 message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 33 1e164343384b67dd3bb9a2845b9b0d59 message: allocating NIST context message: cb_rcvunkrequest (id=33) message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK78b3e470;rport=5060 From: "Unknown" ;tag=as6d35c7fb To: ;tag=2102937097 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=33) message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 9 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:9 usec:999612! message: cb_nict_kill_transaction (id=33) message: free transaction ressource 33 1e164343384b67dd3bb9a2845b9b0d59 message: free nist ressource message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK4ed1b66e;rport Max-Forwards: 70 From: "Unknown" ;tag=as0651f058 To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:07:20 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:63df9981377d37444516b1f465b414da message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 34 63df9981377d37444516b1f465b414da message: allocating NIST context message: cb_rcvunkrequest (id=34) message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK4ed1b66e;rport=5060 From: "Unknown" ;tag=as0651f058 To: ;tag=1435780816 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=34) message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 9 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:9 usec:999632! message: cb_nict_kill_transaction (id=34) message: free transaction ressource 34 63df9981377d37444516b1f465b414da message: free nist ressource message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: authinfo: No authentication found for 222 "asterisk" message: allocating transaction ressource 35 1632134826 message: allocating NICT context message: eXosip option set: dns cache used:SIP-Proxy-Address -> 10.110.5.1 message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) REGISTER sip:SIP-Proxy-Address SIP/2.0 Via: SIP/2.0/UDP 10.101.1.69:5060;rport;branch=z9hG4bK2037123242 From: ;tag=741217630 To: Call-ID: 1632134826 CSeq: 3 REGISTER Contact: Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Expires: 3600 Content-Length: 0 message: eXosip option set: dns cache updated:SIP-Proxy-Address -> 10.110.5.1 message: cb_sndregister (id=35) message: free transaction ressource 4 1632134826 message: free nict ressource message: eXosip: timer sec:0 usec:509856! message: Received message: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.101.1.69:5060;branch=z9hG4bK2037123242;received=10.101.1.69;rport=5060 From: ;tag=741217630 To: ;tag=as4ecee621 Call-ID: 1632134826 CSeq: 3 REGISTER Server: FPBX-2.8.1(1.8.12.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="601bdac2" Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:1632134826 message: Message received from: 10.110.5.1:5060 message: cb_rcv4xx (id=35) message: eXosip: timer sec:5 usec:10000! message: linphone process event get a message 2 message: REGISTRATION_FAILURE message: auth_requested() for realm="asterisk", username=222 message: auth_requested(): authenticating realm="asterisk", username=222 message: Authentication info for username [222], id[222], realm ["asterisk"] added to eXosip message: Contact address updated to message: INFO: authinfo: "asterisk" "asterisk" message: allocating transaction ressource 36 1632134826 message: allocating NICT context message: eXosip option set: dns cache used:SIP-Proxy-Address -> 10.110.5.1 message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) REGISTER sip:SIP-Proxy-Address SIP/2.0 Via: SIP/2.0/UDP 10.101.1.69:5060;rport;branch=z9hG4bK789980886 From: ;tag=741217630 To: Call-ID: 1632134826 CSeq: 4 REGISTER Contact: Authorization: Digest username="222", realm="asterisk", nonce="601bdac2", uri="sip:SIP-Proxy-Address", response="bd1de3174e555eb7b3d86bd29900f63a", algorithm=MD5 Max-Forwards: 70 User-Agent: Linphone/3.5.2 (eXosip2/3.6.0) Expires: 3600 Content-Length: 0 message: eXosip option set: dns cache updated:SIP-Proxy-Address -> 10.110.5.1 message: cb_sndregister (id=36) message: eXosip: timer sec:0 usec:509983! message: eXosip_default_action() done message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK5de2c57c;rport Max-Forwards: 70 From: "Unknown" ;tag=as1f21799a To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:08:20 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:75de6a5a0ae1b1826b2aeda964c6d4aa message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 37 75de6a5a0ae1b1826b2aeda964c6d4aa message: allocating NIST context message: cb_rcvunkrequest (id=37) message: eXosip: timer sec:0 usec:503554! message: Received message: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.101.1.69:5060;branch=z9hG4bK789980886;received=10.101.1.69;rport=5060 From: ;tag=741217630 To: ;tag=as4ecee621 Call-ID: 1632134826 CSeq: 4 REGISTER Server: FPBX-2.8.1(1.8.12.0) Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Expires: 3600 Contact: ;expires=3600 Date: Sun, 24 Nov 2013 07:08:20 GMT Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:1632134826 message: Message received from: 10.110.5.1:5060 message: cb_rcv2xx (id=36) message: eXosip: timer sec:4 usec:975577! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK5de2c57c;rport=5060 From: "Unknown" ;tag=as1f21799a To: ;tag=1157024786 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=37) message: eXosip: timer sec:4 usec:952776! message: linphone process event get a message 1 message: "asterisk"/222 authentication works. message: Register has up to date contact, doing nothing. message: cb_nict_kill_transaction (id=35) message: keep alive: 3 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:0 usec:29880! message: cb_nict_kill_transaction (id=36) message: eXosip: Reseting timer to 10s before waking up! message: free transaction ressource 35 1632134826 message: free nict ressource message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:5 usec:999730! message: cb_nict_kill_transaction (id=37) message: free transaction ressource 37 75de6a5a0ae1b1826b2aeda964c6d4aa message: free nist ressource message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 6 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK5eacc01f;rport Max-Forwards: 70 From: "Unknown" ;tag=as4346f99e To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:09:20 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:3481b9686dfd0ceb6c1adcc235b102f7 message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 38 3481b9686dfd0ceb6c1adcc235b102f7 message: allocating NIST context message: cb_rcvunkrequest (id=38) message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK5eacc01f;rport=5060 From: "Unknown" ;tag=as4346f99e To: ;tag=375830516 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=38) message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 9 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:9 usec:999425! message: cb_nict_kill_transaction (id=38) message: free transaction ressource 38 3481b9686dfd0ceb6c1adcc235b102f7 message: free nist ressource message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK30d55483;rport Max-Forwards: 70 From: "Unknown" ;tag=as7928bce7 To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:10:21 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:07e1ff4e0077df9e3d5bbefb6974aba5 message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 39 07e1ff4e0077df9e3d5bbefb6974aba5 message: allocating NIST context message: cb_rcvunkrequest (id=39) message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK30d55483;rport=5060 From: "Unknown" ;tag=as7928bce7 To: ;tag=424377100 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=39) message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 9 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:9 usec:999818! message: cb_nict_kill_transaction (id=39) message: free transaction ressource 39 07e1ff4e0077df9e3d5bbefb6974aba5 message: free nist ressource message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK497f082f;rport Max-Forwards: 70 From: "Unknown" ;tag=as546b6cbe To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:11:21 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:42a2fc4e6f199edd081b8e985bd9bebc message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 40 42a2fc4e6f199edd081b8e985bd9bebc message: allocating NIST context message: cb_rcvunkrequest (id=40) message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK497f082f;rport=5060 From: "Unknown" ;tag=as546b6cbe To: ;tag=533039565 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=40) message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 9 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:9 usec:999519! message: cb_nict_kill_transaction (id=40) message: free transaction ressource 40 42a2fc4e6f199edd081b8e985bd9bebc message: free nist ressource message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK44222329;rport Max-Forwards: 70 From: "Unknown" ;tag=as62e3b822 To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:12:21 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:1d18586a70b0ec571611615c27a2328e message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 41 1d18586a70b0ec571611615c27a2328e message: allocating NIST context message: cb_rcvunkrequest (id=41) message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK44222329;rport=5060 From: "Unknown" ;tag=as62e3b822 To: ;tag=1176676411 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=41) message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 9 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:9 usec:999352! message: cb_nict_kill_transaction (id=41) message: free transaction ressource 41 1d18586a70b0ec571611615c27a2328e message: free nist ressource message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK4c910b66;rport Max-Forwards: 70 From: "Unknown" ;tag=as13def05d To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:13:21 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:6b7009b843bab7f155bc4dc72db1b492 message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 42 6b7009b843bab7f155bc4dc72db1b492 message: allocating NIST context message: cb_rcvunkrequest (id=42) message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK4c910b66;rport=5060 From: "Unknown" ;tag=as13def05d To: ;tag=17480456 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=42) message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 9 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:9 usec:999406! message: cb_nict_kill_transaction (id=42) message: free transaction ressource 42 6b7009b843bab7f155bc4dc72db1b492 message: free nist ressource message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK2f257cde;rport Max-Forwards: 70 From: "Unknown" ;tag=as5feb2261 To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:14:21 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:67425fd2464f7a5e32146e266816eb3c message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 43 67425fd2464f7a5e32146e266816eb3c message: allocating NIST context message: cb_rcvunkrequest (id=43) message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK2f257cde;rport=5060 From: "Unknown" ;tag=as5feb2261 To: ;tag=2043391359 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=43) message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 9 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:9 usec:999631! message: cb_nict_kill_transaction (id=43) message: free transaction ressource 43 67425fd2464f7a5e32146e266816eb3c message: free nist ressource message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK2b1dab32;rport Max-Forwards: 70 From: "Unknown" ;tag=as54049bc1 To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:15:21 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:3b27073e6acd77f438ebdacf15116dee message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 44 3b27073e6acd77f438ebdacf15116dee message: allocating NIST context message: cb_rcvunkrequest (id=44) message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK2b1dab32;rport=5060 From: "Unknown" ;tag=as54049bc1 To: ;tag=1084680810 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=44) message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 9 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:9 usec:998849! message: cb_nict_kill_transaction (id=44) message: free transaction ressource 44 3b27073e6acd77f438ebdacf15116dee message: free nist ressource message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK2be6c38a;rport Max-Forwards: 70 From: "Unknown" ;tag=as4f8d2891 To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:16:21 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:47f00138097a60812e8afc2a1242a67c message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 45 47f00138097a60812e8afc2a1242a67c message: allocating NIST context message: cb_rcvunkrequest (id=45) message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK2be6c38a;rport=5060 From: "Unknown" ;tag=as4f8d2891 To: ;tag=1061879450 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=45) message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 9 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:9 usec:999512! message: cb_nict_kill_transaction (id=45) message: free transaction ressource 45 47f00138097a60812e8afc2a1242a67c message: free nist ressource message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK1a3a994f;rport Max-Forwards: 70 From: "Unknown" ;tag=as04ba007c To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:17:21 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:26e0a20d212cf5414d1609757553ce7e message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 46 26e0a20d212cf5414d1609757553ce7e message: allocating NIST context message: cb_rcvunkrequest (id=46) message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK1a3a994f;rport=5060 From: "Unknown" ;tag=as04ba007c To: ;tag=1055806266 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=46) message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 9 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:9 usec:998955! message: cb_nict_kill_transaction (id=46) message: free transaction ressource 46 26e0a20d212cf5414d1609757553ce7e message: free nist ressource message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: Received message: OPTIONS sip:address@hidden;line=a319343ec6c0c5b SIP/2.0 Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK2c761e6c;rport Max-Forwards: 70 From: "Unknown" ;tag=as3a9145ba To: Contact: Call-ID: address@hidden:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.1(1.8.12.0) Date: Sun, 24 Nov 2013 07:18:21 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 message: Message received from: 10.110.5.1:5060 message: Message received from: 10.110.5.1:5060 message: MESSAGE REC. CALLID:23f9428b1e35d1a9205c7190511c1d4e message: Message received from: 10.110.5.1:5060 message: This is a request message: allocating transaction ressource 47 23f9428b1e35d1a9205c7190511c1d4e message: allocating NIST context message: cb_rcvunkrequest (id=47) message: eXosip: Reseting timer to 10s before waking up! message: eXosip: Reseting timer to 10s before waking up! message: linphone process event get a message 27 message: in other_request message: DNS resolution with 10.110.5.1:5060 message: getaddrinfo returned the following addresses: message: 10.110.5.1 port 5060 message: Message sent: (to dest=10.110.5.1:5060) SIP/2.0 200 OK Via: SIP/2.0/UDP 10.110.5.1:5060;branch=z9hG4bK2c761e6c;rport=5060 From: "Unknown" ;tag=as3a9145ba To: ;tag=133303390 Call-ID: address@hidden:5060 CSeq: 102 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 message: cb_snd123456xx (id=47) message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 9 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up! message: keep alive: 1 message: eXosip: Keep Alive sent on UDP! message: eXosip: timer sec:9 usec:999417! message: cb_nict_kill_transaction (id=47) message: free transaction ressource 47 23f9428b1e35d1a9205c7190511c1d4e message: free nist ressource message: keep alive: 0 message: eXosip: Keep Alive sent on UDP! message: eXosip: Reseting timer to 10s before waking up!