[Blink] Constantly rings busy when calling from standard cell phone

Adrian Georgescu ag at ag-projects.com
Fri Aug 2 09:07:22 CEST 2013


I see that you have enabled the answering machine. Turn off the answering machine if you want to answer the call yourself.

Adrian

On Aug 2, 2013, at 1:48 AM, Michael Reilly, Palm Database Solutions wrote:

> From my cell phone, I call 619-500-1500 (a VOIPO cloud number forwarded to palmdbs at sip2sip.info)
> 
> Sometimes call immediately hangs up (Blink never indicates incoming call).  The rest of the time, Blink show that a call is incoming, but after one ring in my cell phone, I start hearing the busy signal.  The Blink app still shows the call incoming, with the countdown to voicemail running, as if the call is still live.
> 
> Please advise how I can receive calls from traditional phones using your app.  My computer will be down for a moment while I travel, and no other SIP service will be running.  I will be back online in about 2 hours and computer should remain on through the night (if you need App up to test).
> 
> Logs below:
> 
> ACTIVITY:
> 2013-08-01 16:11:36   [Session 3 with +18582165356 at 192.168.47.68] Invite from: "REILLY MICHAEL" <sip:+18582165356 at 192.168.47.68;isup-oli=62> with audio
> 2013-08-01 16:11:36   Using input/output audio devices: Built-in Microph/Built-in Output
> 2013-08-01 16:11:46   [Session 3 with +18582165356 at 192.168.47.68] Sending session to answering machine
> 2013-08-01 16:11:46   Unmute output of Answering Machine
> 2013-08-01 16:11:46   [Session 3 with +18582165356 at 192.168.47.68] Session will start
> 2013-08-01 16:11:46   [Session 3 with +18582165356 at 192.168.47.68] Audio stream started
> 2013-08-01 16:11:51   [Session 3 with +18582165356 at 192.168.47.68] Session ended
> 2013-08-01 16:11:51   [Session 3 with +18582165356 at 192.168.47.68] Audio stream ended
> 2013-08-01 16:13:13   palmdbs at sip2sip.info is subscribed to my availability for palmdbs at sip2sip.info
> 2013-08-01 16:13:13   Account('palmdbs at sip2sip.info') registered contact "sip:58427360 at 192.168.5.136:55693;transport=tls" at 81.23.228.129:443;transport=tls for 300 seconds
> 2013-08-01 16:17:43   palmdbs at sip2sip.info is subscribed to my availability for palmdbs at sip2sip.info
> 2013-08-01 16:17:44   Account('palmdbs at sip2sip.info') registered contact "sip:58427360 at 192.168.5.136:55693;transport=tls" at 81.23.228.129:443;transport=tls for 300 seconds
> 2013-08-01 16:22:13   palmdbs at sip2sip.info is subscribed to my availability for palmdbs at sip2sip.info
> 2013-08-01 16:22:15   Account('palmdbs at sip2sip.info') registered contact "sip:58427360 at 192.168.5.136:55693;transport=tls" at 85.17.186.7:443;transport=tls for 300 seconds
> 2013-08-01 16:26:43   palmdbs at sip2sip.info is subscribed to my availability for palmdbs at sip2sip.info
> 2013-08-01 16:26:45   Account('palmdbs at sip2sip.info') registered contact "sip:58427360 at 192.168.5.136:55693;transport=tls" at 85.17.186.7:443;transport=tls for 300 seconds
> 2013-08-01 16:31:14   palmdbs at sip2sip.info is subscribed to my availability for palmdbs at sip2sip.info
> 2013-08-01 16:31:17   Account('palmdbs at sip2sip.info') registered contact "sip:58427360 at 192.168.5.136:55693;transport=tls" at 81.23.228.129:443;transport=tls for 300 seconds
> 2013-08-01 16:35:44   palmdbs at sip2sip.info is subscribed to my availability for palmdbs at sip2sip.info
> 2013-08-01 16:35:47   Account('palmdbs at sip2sip.info') registered contact "sip:58427360 at 192.168.5.136:55693;transport=tls" at 85.17.186.7:443;transport=tls for 300 seconds
> 2013-08-01 16:40:14   palmdbs at sip2sip.info is subscribed to my availability for palmdbs at sip2sip.info
> 2013-08-01 16:40:18   Account('palmdbs at sip2sip.info') registered contact "sip:58427360 at 192.168.5.136:55693;transport=tls" at 85.17.186.7:443;transport=tls for 300 seconds
> 2013-08-01 16:44:44   palmdbs at sip2sip.info is subscribed to my availability for palmdbs at sip2sip.info
> 2013-08-01 16:44:49   Account('palmdbs at sip2sip.info') registered contact "sip:58427360 at 192.168.5.136:55693;transport=tls" at 81.23.228.129:443;transport=tls for 300 seconds
> 
> 
> SIP:
> RECEIVED: Packet 1, +0:00:00
> 2013-08-01 16:11:36.306853: 81.23.228.129:443 -(SIP over tls)-> 192.168.5.136:55700
> INVITE sip:58427360 at 192.168.5.136:55693;transport=tls SIP/2.0
> Record-Route: <sip:81.23.228.129:443;transport=tls;r2=on;lr;ftag=gK055ceb23;did=b03.32864935>
> Record-Route: <sip:81.23.228.129;r2=on;lr;ftag=gK055ceb23;did=b03.32864935>
> Record-Route: <sip:85.17.186.7;lr;ftag=gK055ceb23;did=b03.7bd28974>
> Record-Route: <sip:67.228.182.4;lr=on;ftag=gK055ceb23;did=b03.ce559b12>
> Record-Route: <sip:67.231.4.195;lr=on;ftag=gK055ceb23>
> Record-Route: <sip:67.231.4.93;lr=on;ftag=gK055ceb23>
> Allow: INVITE,ACK,CANCEL,BYE
> Via: SIP/2.0/TLS 81.23.228.129:443;branch=z9hG4bKf219.5b31c8c1.0
> Via: SIP/2.0/UDP 85.17.186.7:5060;branch=z9hG4bKf219.92301f06.0
> Via: SIP/2.0/UDP 81.23.228.129:5060;branch=z9hG4bKf219.4b31c8c1.0
> Via: SIP/2.0/UDP 67.228.182.4;branch=z9hG4bKf219.0aaf3035.0
> Via: SIP/2.0/UDP 67.231.4.195;rport=5060;branch=z9hG4bKf219.a68321b2.0
> Via: SIP/2.0/UDP 67.231.4.93;branch=z9hG4bKf219.ccb87536.0
> Via: SIP/2.0/UDP 192.168.47.68:5060;branch=z9hG4bK05Bfc7e7f7351077649
> From: "REILLY MICHAEL" <sip:+18582165356 at 192.168.47.68;isup-oli=62>;tag=gK055ceb23
> To: <sip:+16195001500 at 67.231.4.93>
> Call-ID: 1157963900_72362068 at 192.168.47.68
> CSeq: 17400 INVITE
> Max-Forwards: 88
> Contact: <sip:+18582165356 at 192.168.47.68:5060>
> Content-Length: 263
> Content-Type: application/sdp
> 
> v=0
> o=Sonus_UAC 28370 10295 IN IP4 192.168.47.68
> s=- SIP
> c=IN IP4 85.17.186.6
> t=0 0
> m=audio 53828 RTP/AVP 0 18 101
> a=rtpmap:0 PCMU/8000
> a=rtpmap:18 G729/8000
> a=fmtp:18 annexb=no
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
> a=sendrecv
> a=ptime:20
> 
> SENDING: Packet 2, +0:00:00.155457
> 2013-08-01 16:11:36.462310: 192.168.5.136:55700 -(SIP over tls)-> 81.23.228.129:443
> SIP/2.0 100 Trying
> Via: SIP/2.0/TLS 81.23.228.129:443;received=81.23.228.129;branch=z9hG4bKf219.5b31c8c1.0
> Via: SIP/2.0/UDP 85.17.186.7:5060;branch=z9hG4bKf219.92301f06.0
> Via: SIP/2.0/UDP 81.23.228.129:5060;branch=z9hG4bKf219.4b31c8c1.0
> Via: SIP/2.0/UDP 67.228.182.4;branch=z9hG4bKf219.0aaf3035.0
> Via: SIP/2.0/UDP 67.231.4.195;rport=5060;branch=z9hG4bKf219.a68321b2.0
> Via: SIP/2.0/UDP 67.231.4.93;branch=z9hG4bKf219.ccb87536.0
> Via: SIP/2.0/UDP 192.168.47.68:5060;branch=z9hG4bK05Bfc7e7f7351077649
> Record-Route: <sip:81.23.228.129:443;transport=tls;lr;r2=on;ftag=gK055ceb23;did=b03.32864935>
> Record-Route: <sip:81.23.228.129;lr;r2=on;ftag=gK055ceb23;did=b03.32864935>
> Record-Route: <sip:85.17.186.7;lr;ftag=gK055ceb23;did=b03.7bd28974>
> Record-Route: <sip:67.228.182.4;lr;ftag=gK055ceb23;did=b03.ce559b12>
> Record-Route: <sip:67.231.4.195;lr;ftag=gK055ceb23>
> Record-Route: <sip:67.231.4.93;lr;ftag=gK055ceb23>
> Call-ID: 1157963900_72362068 at 192.168.47.68
> From: "REILLY MICHAEL" <sip:+18582165356 at 192.168.47.68;isup-oli=62>;tag=gK055ceb23
> To: <sip:+16195001500 at 67.231.4.93>
> CSeq: 17400 INVITE
> Server: Blink Pro 2.3.1 (MacOSX)
> Content-Length:  0
> 
> SENDING: Packet 3, +0:00:00.171979
> 2013-08-01 16:11:36.478832: 192.168.5.136:55700 -(SIP over tls)-> 81.23.228.129:443
> SIP/2.0 180 Ringing
> Via: SIP/2.0/TLS 81.23.228.129:443;received=81.23.228.129;branch=z9hG4bKf219.5b31c8c1.0
> Via: SIP/2.0/UDP 85.17.186.7:5060;branch=z9hG4bKf219.92301f06.0
> Via: SIP/2.0/UDP 81.23.228.129:5060;branch=z9hG4bKf219.4b31c8c1.0
> Via: SIP/2.0/UDP 67.228.182.4;branch=z9hG4bKf219.0aaf3035.0
> Via: SIP/2.0/UDP 67.231.4.195;rport=5060;branch=z9hG4bKf219.a68321b2.0
> Via: SIP/2.0/UDP 67.231.4.93;branch=z9hG4bKf219.ccb87536.0
> Via: SIP/2.0/UDP 192.168.47.68:5060;branch=z9hG4bK05Bfc7e7f7351077649
> Record-Route: <sip:81.23.228.129:443;transport=tls;lr;r2=on;ftag=gK055ceb23;did=b03.32864935>
> Record-Route: <sip:81.23.228.129;lr;r2=on;ftag=gK055ceb23;did=b03.32864935>
> Record-Route: <sip:85.17.186.7;lr;ftag=gK055ceb23;did=b03.7bd28974>
> Record-Route: <sip:67.228.182.4;lr;ftag=gK055ceb23;did=b03.ce559b12>
> Record-Route: <sip:67.231.4.195;lr;ftag=gK055ceb23>
> Record-Route: <sip:67.231.4.93;lr;ftag=gK055ceb23>
> Call-ID: 1157963900_72362068 at 192.168.47.68
> From: "REILLY MICHAEL" <sip:+18582165356 at 192.168.47.68;isup-oli=62>;tag=gK055ceb23
> To: <sip:+16195001500 at 67.231.4.93>;tag=oqa.jAHj-NIX0QIOdEUD9WRxVgs9-8ch
> CSeq: 17400 INVITE
> Server: Blink Pro 2.3.1 (MacOSX)
> Contact: <sip:58427360 at 192.168.5.136:55693;transport=tls>
> Allow: SUBSCRIBE, NOTIFY, PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE, REFER
> Content-Length:  0
> 
> SENDING: Packet 4, +0:00:10.443092
> 2013-08-01 16:11:46.749945: 192.168.5.136:55700 -(SIP over tls)-> 81.23.228.129:443
> SIP/2.0 200 OK
> Via: SIP/2.0/TLS 81.23.228.129:443;received=81.23.228.129;branch=z9hG4bKf219.5b31c8c1.0
> Via: SIP/2.0/UDP 85.17.186.7:5060;branch=z9hG4bKf219.92301f06.0
> Via: SIP/2.0/UDP 81.23.228.129:5060;branch=z9hG4bKf219.4b31c8c1.0
> Via: SIP/2.0/UDP 67.228.182.4;branch=z9hG4bKf219.0aaf3035.0
> Via: SIP/2.0/UDP 67.231.4.195;rport=5060;branch=z9hG4bKf219.a68321b2.0
> Via: SIP/2.0/UDP 67.231.4.93;branch=z9hG4bKf219.ccb87536.0
> Via: SIP/2.0/UDP 192.168.47.68:5060;branch=z9hG4bK05Bfc7e7f7351077649
> Record-Route: <sip:81.23.228.129:443;transport=tls;lr;r2=on;ftag=gK055ceb23;did=b03.32864935>
> Record-Route: <sip:81.23.228.129;lr;r2=on;ftag=gK055ceb23;did=b03.32864935>
> Record-Route: <sip:85.17.186.7;lr;ftag=gK055ceb23;did=b03.7bd28974>
> Record-Route: <sip:67.228.182.4;lr;ftag=gK055ceb23;did=b03.ce559b12>
> Record-Route: <sip:67.231.4.195;lr;ftag=gK055ceb23>
> Record-Route: <sip:67.231.4.93;lr;ftag=gK055ceb23>
> Call-ID: 1157963900_72362068 at 192.168.47.68
> From: "REILLY MICHAEL" <sip:+18582165356 at 192.168.47.68;isup-oli=62>;tag=gK055ceb23
> To: <sip:+16195001500 at 67.231.4.93>;tag=oqa.jAHj-NIX0QIOdEUD9WRxVgs9-8ch
> CSeq: 17400 INVITE
> Server: Blink Pro 2.3.1 (MacOSX)
> Allow: SUBSCRIBE, NOTIFY, PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE, REFER
> Contact: <sip:58427360 at 192.168.5.136:55693;transport=tls>
> Supported: 100rel, replaces, norefersub, gruu
> Content-Type: application/sdp
> Content-Length:   241
> 
> v=0
> o=- 3584387506 3584387507 IN IP4 192.168.5.136
> s=Blink Pro 2.3.1 (MacOSX)
> c=IN IP4 192.168.5.136
> t=0 0
> m=audio 50004 RTP/AVP 0 101
> a=rtcp:50005
> a=rtpmap:0 PCMU/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
> a=sendrecv
> 
> SENDING: Packet 5, +0:00:10.941918
> 2013-08-01 16:11:47.248771: 192.168.5.136:55700 -(SIP over tls)-> 81.23.228.129:443
> SIP/2.0 200 OK
> Via: SIP/2.0/TLS 81.23.228.129:443;received=81.23.228.129;branch=z9hG4bKf219.5b31c8c1.0
> Via: SIP/2.0/UDP 85.17.186.7:5060;branch=z9hG4bKf219.92301f06.0
> Via: SIP/2.0/UDP 81.23.228.129:5060;branch=z9hG4bKf219.4b31c8c1.0
> Via: SIP/2.0/UDP 67.228.182.4;branch=z9hG4bKf219.0aaf3035.0
> Via: SIP/2.0/UDP 67.231.4.195;rport=5060;branch=z9hG4bKf219.a68321b2.0
> Via: SIP/2.0/UDP 67.231.4.93;branch=z9hG4bKf219.ccb87536.0
> Via: SIP/2.0/UDP 192.168.47.68:5060;branch=z9hG4bK05Bfc7e7f7351077649
> Record-Route: <sip:81.23.228.129:443;transport=tls;lr;r2=on;ftag=gK055ceb23;did=b03.32864935>
> Record-Route: <sip:81.23.228.129;lr;r2=on;ftag=gK055ceb23;did=b03.32864935>
> Record-Route: <sip:85.17.186.7;lr;ftag=gK055ceb23;did=b03.7bd28974>
> Record-Route: <sip:67.228.182.4;lr;ftag=gK055ceb23;did=b03.ce559b12>
> Record-Route: <sip:67.231.4.195;lr;ftag=gK055ceb23>
> Record-Route: <sip:67.231.4.93;lr;ftag=gK055ceb23>
> Call-ID: 1157963900_72362068 at 192.168.47.68
> From: "REILLY MICHAEL" <sip:+18582165356 at 192.168.47.68;isup-oli=62>;tag=gK055ceb23
> To: <sip:+16195001500 at 67.231.4.93>;tag=oqa.jAHj-NIX0QIOdEUD9WRxVgs9-8ch
> CSeq: 17400 INVITE
> Server: Blink Pro 2.3.1 (MacOSX)
> Allow: SUBSCRIBE, NOTIFY, PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE, REFER
> Contact: <sip:58427360 at 192.168.5.136:55693;transport=tls>
> Supported: 100rel, replaces, norefersub, gruu
> Content-Type: application/sdp
> Content-Length:   241
> 
> v=0
> o=- 3584387506 3584387507 IN IP4 192.168.5.136
> s=Blink Pro 2.3.1 (MacOSX)
> c=IN IP4 192.168.5.136
> t=0 0
> m=audio 50004 RTP/AVP 0 101
> a=rtcp:50005
> a=rtpmap:0 PCMU/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
> a=sendrecv
> 
> SENDING: Packet 6, +0:00:11.943389
> 2013-08-01 16:11:48.250242: 192.168.5.136:55700 -(SIP over tls)-> 81.23.228.129:443
> SIP/2.0 200 OK
> Via: SIP/2.0/TLS 81.23.228.129:443;received=81.23.228.129;branch=z9hG4bKf219.5b31c8c1.0
> Via: SIP/2.0/UDP 85.17.186.7:5060;branch=z9hG4bKf219.92301f06.0
> Via: SIP/2.0/UDP 81.23.228.129:5060;branch=z9hG4bKf219.4b31c8c1.0
> Via: SIP/2.0/UDP 67.228.182.4;branch=z9hG4bKf219.0aaf3035.0
> Via: SIP/2.0/UDP 67.231.4.195;rport=5060;branch=z9hG4bKf219.a68321b2.0
> Via: SIP/2.0/UDP 67.231.4.93;branch=z9hG4bKf219.ccb87536.0
> Via: SIP/2.0/UDP 192.168.47.68:5060;branch=z9hG4bK05Bfc7e7f7351077649
> Record-Route: <sip:81.23.228.129:443;transport=tls;lr;r2=on;ftag=gK055ceb23;did=b03.32864935>
> Record-Route: <sip:81.23.228.129;lr;r2=on;ftag=gK055ceb23;did=b03.32864935>
> Record-Route: <sip:85.17.186.7;lr;ftag=gK055ceb23;did=b03.7bd28974>
> Record-Route: <sip:67.228.182.4;lr;ftag=gK055ceb23;did=b03.ce559b12>
> Record-Route: <sip:67.231.4.195;lr;ftag=gK055ceb23>
> Record-Route: <sip:67.231.4.93;lr;ftag=gK055ceb23>
> Call-ID: 1157963900_72362068 at 192.168.47.68
> From: "REILLY MICHAEL" <sip:+18582165356 at 192.168.47.68;isup-oli=62>;tag=gK055ceb23
> To: <sip:+16195001500 at 67.231.4.93>;tag=oqa.jAHj-NIX0QIOdEUD9WRxVgs9-8ch
> CSeq: 17400 INVITE
> Server: Blink Pro 2.3.1 (MacOSX)
> Allow: SUBSCRIBE, NOTIFY, PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE, REFER
> Contact: <sip:58427360 at 192.168.5.136:55693;transport=tls>
> Supported: 100rel, replaces, norefersub, gruu
> Content-Type: application/sdp
> Content-Length:   241
> 
> v=0
> o=- 3584387506 3584387507 IN IP4 192.168.5.136
> s=Blink Pro 2.3.1 (MacOSX)
> c=IN IP4 192.168.5.136
> t=0 0
> m=audio 50004 RTP/AVP 0 101
> a=rtcp:50005
> a=rtpmap:0 PCMU/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
> a=sendrecv
> 
> SENDING: Packet 7, +0:00:13.944684
> 2013-08-01 16:11:50.251537: 192.168.5.136:55700 -(SIP over tls)-> 81.23.228.129:443
> SIP/2.0 200 OK
> Via: SIP/2.0/TLS 81.23.228.129:443;received=81.23.228.129;branch=z9hG4bKf219.5b31c8c1.0
> Via: SIP/2.0/UDP 85.17.186.7:5060;branch=z9hG4bKf219.92301f06.0
> Via: SIP/2.0/UDP 81.23.228.129:5060;branch=z9hG4bKf219.4b31c8c1.0
> Via: SIP/2.0/UDP 67.228.182.4;branch=z9hG4bKf219.0aaf3035.0
> Via: SIP/2.0/UDP 67.231.4.195;rport=5060;branch=z9hG4bKf219.a68321b2.0
> Via: SIP/2.0/UDP 67.231.4.93;branch=z9hG4bKf219.ccb87536.0
> Via: SIP/2.0/UDP 192.168.47.68:5060;branch=z9hG4bK05Bfc7e7f7351077649
> Record-Route: <sip:81.23.228.129:443;transport=tls;lr;r2=on;ftag=gK055ceb23;did=b03.32864935>
> Record-Route: <sip:81.23.228.129;lr;r2=on;ftag=gK055ceb23;did=b03.32864935>
> Record-Route: <sip:85.17.186.7;lr;ftag=gK055ceb23;did=b03.7bd28974>
> Record-Route: <sip:67.228.182.4;lr;ftag=gK055ceb23;did=b03.ce559b12>
> Record-Route: <sip:67.231.4.195;lr;ftag=gK055ceb23>
> Record-Route: <sip:67.231.4.93;lr;ftag=gK055ceb23>
> Call-ID: 1157963900_72362068 at 192.168.47.68
> From: "REILLY MICHAEL" <sip:+18582165356 at 192.168.47.68;isup-oli=62>;tag=gK055ceb23
> To: <sip:+16195001500 at 67.231.4.93>;tag=oqa.jAHj-NIX0QIOdEUD9WRxVgs9-8ch
> CSeq: 17400 INVITE
> Server: Blink Pro 2.3.1 (MacOSX)
> Allow: SUBSCRIBE, NOTIFY, PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE, REFER
> Contact: <sip:58427360 at 192.168.5.136:55693;transport=tls>
> Supported: 100rel, replaces, norefersub, gruu
> Content-Type: application/sdp
> Content-Length:   241
> 
> v=0
> o=- 3584387506 3584387507 IN IP4 192.168.5.136
> s=Blink Pro 2.3.1 (MacOSX)
> c=IN IP4 192.168.5.136
> t=0 0
> m=audio 50004 RTP/AVP 0 101
> a=rtcp:50005
> a=rtpmap:0 PCMU/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
> a=sendrecv
> 
> SENDING: Packet 8, +0:00:14.354288
> 2013-08-01 16:11:50.661141: 192.168.5.136:55700 -(SIP over tls)-> 81.23.228.129:443
> BYE sip:+18582165356 at 192.168.47.68:5060 SIP/2.0
> Via: SIP/2.0/tls 192.168.5.136:55700;rport;branch=z9hG4bKPjqdXGr-SURDNy2Hzl2wW-bDDdqQ1WImzH
> Max-Forwards: 70
> From: <sip:+16195001500 at 67.231.4.93>;tag=oqa.jAHj-NIX0QIOdEUD9WRxVgs9-8ch
> To: "REILLY MICHAEL" <sip:+18582165356 at 192.168.47.68;isup-oli=62>;tag=gK055ceb23
> Call-ID: 1157963900_72362068 at 192.168.47.68
> CSeq: 30264 BYE
> Route: <sip:81.23.228.129:443;transport=tls;lr;r2=on;ftag=gK055ceb23;did=b03.32864935>
> Route: <sip:81.23.228.129;lr;r2=on;ftag=gK055ceb23;did=b03.32864935>
> Route: <sip:85.17.186.7;lr;ftag=gK055ceb23;did=b03.7bd28974>
> Route: <sip:67.228.182.4;lr;ftag=gK055ceb23;did=b03.ce559b12>
> Route: <sip:67.231.4.195;lr;ftag=gK055ceb23>
> Route: <sip:67.231.4.93;lr;ftag=gK055ceb23>
> User-Agent: Blink Pro 2.3.1 (MacOSX)
> Content-Length:  0
> 
> SENDING: Packet 9, +0:00:15.327115
> 2013-08-01 16:11:51.633968: 192.168.5.136:55700 -(SIP over tls)-> 81.23.228.129:443
> BYE sip:+18582165356 at 192.168.47.68:5060 SIP/2.0
> Via: SIP/2.0/tls 192.168.5.136:55700;rport;branch=z9hG4bKPjVDpfJCsAdEZwVLYUVtRrygs9eDrCUng3
> Max-Forwards: 70
> From: <sip:+16195001500 at 67.231.4.93>;tag=oqa.jAHj-NIX0QIOdEUD9WRxVgs9-8ch
> To: "REILLY MICHAEL" <sip:+18582165356 at 192.168.47.68;isup-oli=62>;tag=gK055ceb23
> Call-ID: 1157963900_72362068 at 192.168.47.68
> CSeq: 30265 BYE
> Route: <sip:81.23.228.129:443;transport=tls;lr;r2=on;ftag=gK055ceb23;did=b03.32864935>
> Route: <sip:81.23.228.129;lr;r2=on;ftag=gK055ceb23;did=b03.32864935>
> Route: <sip:85.17.186.7;lr;ftag=gK055ceb23;did=b03.7bd28974>
> Route: <sip:67.228.182.4;lr;ftag=gK055ceb23;did=b03.ce559b12>
> Route: <sip:67.231.4.195;lr;ftag=gK055ceb23>
> Route: <sip:67.231.4.93;lr;ftag=gK055ceb23>
> User-Agent: Blink Pro 2.3.1 (MacOSX)
> Content-Length:  0
> 
> 2013-08-01 16:11:51.722141: DNS lookup NAPTR sip2sip.info succeeded, ttl=3599: 5 100 "s" "SIPS+D2T" "" _sips._tcp.sip2sip.info., 30 100 "s" "SIP+D2U" "" _sip._udp.sip2sip.info., 10 100 "s" "SIP+D2T" "" _sip._tcp.sip2sip.info.
> 2013-08-01 16:11:51.723677: DNS lookup SRV _sips._tcp.sip2sip.info. succeeded, ttl=3600: 100 100 443 proxy.sipthor.net.
> 2013-08-01 16:11:51.989350: DNS lookup A proxy.sipthor.net. succeeded, ttl=60: 85.17.186.7, 81.23.228.129
> 2013-08-01 16:11:51.989945: DNS lookup A proxy.sipthor.net. succeeded, ttl=60: 85.17.186.7, 81.23.228.129
> 2013-08-01 16:11:51.990922: DNS lookup SRV _sip._tcp.sip2sip.info. succeeded, ttl=3600: 100 100 5060 proxy.sipthor.net.
> 2013-08-01 16:11:56.525084: DNS lookup NAPTR sip2sip.info succeeded, ttl=3599: 5 100 "s" "SIPS+D2T" "" _sips._tcp.sip2sip.info., 30 100 "s" "SIP+D2U" "" _sip._udp.sip2sip.info., 10 100 "s" "SIP+D2T" "" _sip._tcp.sip2sip.info.
> 2013-08-01 16:11:56.525731: DNS lookup SRV _sips._tcp.sip2sip.info. succeeded, ttl=3600: 100 100 443 proxy.sipthor.net.
> 2013-08-01 16:11:56.526704: DNS lookup A proxy.sipthor.net. succeeded, ttl=60: 85.17.186.7, 81.23.228.129
> 2013-08-01 16:11:56.527983: DNS lookup A proxy.sipthor.net. succeeded, ttl=60: 85.17.186.7, 81.23.228.129
> 2013-08-01 16:11:56.528730: DNS lookup SRV _sip._tcp.sip2sip.info. succeeded, ttl=3600: 100 100 5060 proxy.sipthor.net.
> RECEIVED: Packet 22, +0:00:29.292458
> 2013-08-01 16:12:05.599311: 81.23.228.129:443 -(SIP over tls)-> 192.168.5.136:55700
> SIP/2.0 408 Request Timeout
> Via: SIP/2.0/tls 192.168.5.136:55700;received=64.134.231.245;rport=55700;branch=z9hG4bKPjqdXGr-SURDNy2Hzl2wW-bDDdqQ1WImzH
> From: <sip:+16195001500 at 67.231.4.93>;tag=oqa.jAHj-NIX0QIOdEUD9WRxVgs9-8ch
> To: "REILLY MICHAEL" <sip:+18582165356 at 192.168.47.68;isup-oli=62>;tag=gK055ceb23
> Call-ID: 1157963900_72362068 at 192.168.47.68
> CSeq: 30264 BYE
> Server: SIP Thor on OpenSIPS XS 1.9.0
> Content-Length: 0
> 
> RECEIVED: Packet 23, +0:00:30.513333
> 2013-08-01 16:12:06.820186: 81.23.228.129:443 -(SIP over tls)-> 192.168.5.136:55700
> SIP/2.0 408 Request Timeout
> Via: SIP/2.0/tls 192.168.5.136:55700;received=64.134.231.245;rport=55700;branch=z9hG4bKPjVDpfJCsAdEZwVLYUVtRrygs9eDrCUng3
> From: <sip:+16195001500 at 67.231.4.93>;tag=oqa.jAHj-NIX0QIOdEUD9WRxVgs9-8ch
> To: "REILLY MICHAEL" <sip:+18582165356 at 192.168.47.68;isup-oli=62>;tag=gK055ceb23
> Call-ID: 1157963900_72362068 at 192.168.47.68
> CSeq: 30265 BYE
> Server: SIP Thor on OpenSIPS XS 1.9.0
> Content-Length: 0
> 
> 2013-08-01 16:13:12.993626: DNS lookup NAPTR sip2sip.info succeeded, ttl=3599: 5 100 "s" "SIPS+D2T" "" _sips._tcp.sip2sip.info., 30 100 "s" "SIP+D2U" "" _sip._udp.sip2sip.info., 10 100 "s" "SIP+D2T" "" _sip._tcp.sip2sip.info.
> 2013-08-01 16:13:12.995081: DNS lookup SRV _sips._tcp.sip2sip.info. succeeded, ttl=3600: 100 100 443 proxy.sipthor.net.
> 2013-08-01 16:13:13.169544: DNS lookup A proxy.sipthor.net. succeeded, ttl=60: 81.23.228.129, 85.17.186.7
> 2013-08-01 16:13:13.170158: DNS lookup A proxy.sipthor.net. succeeded, ttl=60: 81.23.228.129, 85.17.186.7
> 2013-08-01 16:13:13.171308: DNS lookup SRV _sip._tcp.sip2sip.info. succeeded, ttl=3600: 100 100 5060 proxy.sipthor.net.
> 
> SENDING: Packet 37, +0:01:36.885336
> 2013-08-01 16:13:13.192189: 192.168.5.136:55700 -(SIP over tls)-> 81.23.228.129:443
> REGISTER sip:sip2sip.info SIP/2.0
> Via: SIP/2.0/tls 192.168.5.136:55700;rport;branch=z9hG4bKPjCj4zSLnvYQzU1MjVOHDMX71ft-pyKFLl
> Max-Forwards: 70
> From: "Palm Database Solutions" <sip:palmdbs at sip2sip.info>;tag=vOhAwies8AIdVj6iVKg.qcOpPpwxNOLI
> To: "Palm Database Solutions" <sip:palmdbs at sip2sip.info>
> Contact: <sip:58427360 at 192.168.5.136:55693;transport=tls>;+sip.instance="<urn:uuid:5b2bdcbb-1a3f-469a-b4c4-dedf7c8147bd>"
> Call-ID: B-e95c-rXWapsOddusuVE2wOkuweXfza
> CSeq: 5 REGISTER
> Expires: 300
> Supported: gruu
> User-Agent: Blink Pro 2.3.1 (MacOSX)
> Content-Length:  0
> 
> RECEIVED: Packet 42, +0:01:37.103031
> 2013-08-01 16:13:13.409884: 81.23.228.129:443 -(SIP over tls)-> 192.168.5.136:55700
> SIP/2.0 401 Unauthorized
> Via: SIP/2.0/tls 192.168.5.136:55700;received=64.134.231.245;rport=55700;branch=z9hG4bKPjCj4zSLnvYQzU1MjVOHDMX71ft-pyKFLl
> From: "Palm Database Solutions" <sip:palmdbs at sip2sip.info>;tag=vOhAwies8AIdVj6iVKg.qcOpPpwxNOLI
> To: "Palm Database Solutions" <sip:palmdbs at sip2sip.info>;tag=ac7e0189ab09b4fde10c77c8597b662a.6afd
> Call-ID: B-e95c-rXWapsOddusuVE2wOkuweXfza
> CSeq: 5 REGISTER
> WWW-Authenticate: Digest realm="sip2sip.info", nonce="51faeb2415c089f11b20c903c8b157ac95f8afaa"
> Server: SIP Thor on OpenSIPS XS 1.9.0
> Content-Length: 0
> 
> SENDING: Packet 43, +0:01:37.109462
> 2013-08-01 16:13:13.416315: 192.168.5.136:55700 -(SIP over tls)-> 81.23.228.129:443
> REGISTER sip:sip2sip.info SIP/2.0
> Via: SIP/2.0/tls 192.168.5.136:55700;rport;branch=z9hG4bKPj2JG8QjShJHBjnnHGrBMHwb7pQ-qJjKYa
> Max-Forwards: 70
> From: "Palm Database Solutions" <sip:palmdbs at sip2sip.info>;tag=vOhAwies8AIdVj6iVKg.qcOpPpwxNOLI
> To: "Palm Database Solutions" <sip:palmdbs at sip2sip.info>
> Contact: <sip:58427360 at 192.168.5.136:55693;transport=tls>;+sip.instance="<urn:uuid:5b2bdcbb-1a3f-469a-b4c4-dedf7c8147bd>"
> Call-ID: B-e95c-rXWapsOddusuVE2wOkuweXfza
> CSeq: 6 REGISTER
> Expires: 300
> Supported: gruu
> User-Agent: Blink Pro 2.3.1 (MacOSX)
> Authorization: Digest username="palmdbs", realm="sip2sip.info", nonce="51faeb2415c089f11b20c903c8b157ac95f8afaa", uri="sip:sip2sip.info", response="32802a277e6520a81a40697245cdeef1"
> Content-Length:  0
> 
> RECEIVED: Packet 49, +0:01:37.313287
> 2013-08-01 16:13:13.620140: 81.23.228.129:443 -(SIP over tls)-> 192.168.5.136:55700
> SIP/2.0 200 OK
> Via: SIP/2.0/tls 192.168.5.136:55700;received=64.134.231.245;rport=55700;branch=z9hG4bKPj2JG8QjShJHBjnnHGrBMHwb7pQ-qJjKYa
> From: "Palm Database Solutions" <sip:palmdbs at sip2sip.info>;tag=vOhAwies8AIdVj6iVKg.qcOpPpwxNOLI
> To: "Palm Database Solutions" <sip:palmdbs at sip2sip.info>;tag=ac7e0189ab09b4fde10c77c8597b662a.8e5e
> Call-ID: B-e95c-rXWapsOddusuVE2wOkuweXfza
> CSeq: 6 REGISTER
> Contact: <sip:58427360 at 192.168.5.136:55693;transport=tls>;expires=300;received="sip:81.23.228.129:5060;target=%73%69%70:%36%34.%31%33%34.%32%33%31.%32%34%35:%35%35%37%30%30%3b%74%72%61%6e%73%70%6f%72%74%3d%74%6c%73"
> Server: SIP Thor on OpenSIPS XS 1.9.0
> Content-Length: 0
> 
> 2013-08-01 16:16:27.091259: DNS lookup NAPTR sip2sip.info succeeded, ttl=3599: 5 100 "s" "SIPS+D2T" "" _sips._tcp.sip2sip.info., 30 100 "s" "SIP+D2U" "" _sip._udp.sip2sip.info., 10 100 "s" "SIP+D2T" "" _sip._tcp.sip2sip.info.
> 2013-08-01 16:16:27.091945: DNS lookup SRV _sips._tcp.sip2sip.info. succeeded, ttl=3600: 100 100 443 proxy.sipthor.net.
> 2013-08-01 16:16:27.284416: DNS lookup A proxy.sipthor.net. succeeded, ttl=60: 85.17.186.7, 81.23.228.129
> 2013-08-01 16:16:27.285234: DNS lookup A proxy.sipthor.net. succeeded, ttl=60: 85.17.186.7, 81.23.228.129
> 2013-08-01 16:16:27.286382: DNS lookup SRV _sip._tcp.sip2sip.info. succeeded, ttl=3600: 100 100 5060 proxy.sipthor.net.
> 
> 
> ENGINE:
> 2013-08-01 16:11:33.739253 (5)  tlsc0x9d24e14: Sending 4 byte(s) keep-alive to 85.17.186.7:443
> 2013-08-01 16:11:36.305881 (5) sip_endpoint.c: Processing incoming message: Request msg INVITE/cseq=17400 (rdata0x9e2cd2c)
> 2013-08-01 16:11:36.308187 (5)   tsx0x2d8a264: Transaction created for Request msg INVITE/cseq=17400 (rdata0x9e2cd2c)
> 2013-08-01 16:11:36.309356 (5)   tsx0x2d8a264: Incoming Request msg INVITE/cseq=17400 (rdata0x9e2cd2c) in state Null
> 2013-08-01 16:11:36.310410 (5)   tsx0x2d8a264: State changed from Null to Trying, event=RX_MSG
> 2013-08-01 16:11:36.310906 (5)   dlg0x2d91664: Transaction tsx0x2d8a264 state changed to Trying
> 2013-08-01 16:11:36.311618 (5)   dlg0x2d91664: UAS dialog created
> 2013-08-01 16:11:36.312469 (5)   dlg0x2d91664: Module mod-invite added as dialog usage, data=0x2ca64c0
> 2013-08-01 16:11:36.313346 (5)   dlg0x2d91664: Session count inc to 2 by mod-invite
> 2013-08-01 16:11:36.313996 (5)   inv0x2d91664: UAS invite session created for dialog dlg0x2d91664
> 2013-08-01 16:11:36.314512 (5)       endpoint: Response msg 100/INVITE/cseq=17400 (tdta0x2e0a400) created
> 2013-08-01 16:11:36.315295 (5)   dlg0x2d91664: Initial answer Response msg 100/INVITE/cseq=17400 (tdta0x2e0a400)
> 2013-08-01 16:11:36.454954 (5)   inv0x2d91664: Sending Response msg 100/INVITE/cseq=17400 (tdta0x2e0a400)
> 2013-08-01 16:11:36.460540 (5)   dlg0x2d91664: Sending Response msg 100/INVITE/cseq=17400 (tdta0x2e0a400)
> 2013-08-01 16:11:36.461410 (5)   tsx0x2d8a264: Sending Response msg 100/INVITE/cseq=17400 (tdta0x2e0a400) in state Trying
> 2013-08-01 16:11:36.464058 (5)   tsx0x2d8a264: State changed from Trying to Proceeding, event=TX_MSG
> 2013-08-01 16:11:36.465415 (5)   dlg0x2d91664: Transaction tsx0x2d8a264 state changed to Proceeding
> 2013-08-01 16:11:36.466079 (5)      pasound.c: Opened device Built-in Microph(Core Audio)/Built-in Output(Core Audio) for recording and playback, sample rate=16000, ch=1, bits=16, 320 samples per frame, input latency=0 ms, output latency=0 ms
> 2013-08-01 16:11:36.467719 (5)      pasound.c: Starting Built-in Microph stream..
> 2013-08-01 16:11:36.468845 (5)      pasound.c: Done, status=0
> 2013-08-01 16:11:36.469347 (5)    ec0xe3ea0e0: Creating WEBRTC AEC
> 2013-08-01 16:11:36.470802 (4)    ec0xe3ea0e0: WEBRTC AEC created, clock_rate=16000, channel=1, samples per frame=320, tail length=15 ms, latency=20 ms
> 2013-08-01 16:11:36.471458 (4)   conference.c: Port 2 (mixer-port) transmitting to port 0 (Master/sound)
> 2013-08-01 16:11:36.472856 (4)   conference.c: Port 0 (Master/sound) transmitting to port 1 (mixer-port)
> 2013-08-01 16:11:36.473317 (4)   wav_player.c: File player '/Applications/Blink Pro.app/Contents/Resources/ring_inbound.wav' created: samp.rate=44100, ch=1, bufsize=4KB, filesize=110KB
> 2013-08-01 16:11:36.474129 (5)     resample.c: resample created: high qualiy, large filter, in/out rate=44100/16000
> 2013-08-01 16:11:36.475177 (5)     resample.c: resample created: high qualiy, large filter, in/out rate=16000/44100
> 2013-08-01 16:11:36.475815 (4)   conference.c: Port 3 (/Applications/Blink Pro.app/Contents/Resources/ring_inbound.wav) transmitting to port 0 (Master/sound)
> 2013-08-01 16:11:36.476562 (5)   inv0x2d91664: Sending Response msg 180/INVITE/cseq=17400 (tdta0x2e0a400)
> 2013-08-01 16:11:36.477232 (5)   dlg0x2d91664: Sending Response msg 180/INVITE/cseq=17400 (tdta0x2e0a400)
> 2013-08-01 16:11:36.478032 (5)   tsx0x2d8a264: Sending Response msg 180/INVITE/cseq=17400 (tdta0x2e0a400) in state Proceeding
> 2013-08-01 16:11:36.479585 (5)   tsx0x2d8a264: State changed from Proceeding to Proceeding, event=TX_MSG
> 2013-08-01 16:11:36.480593 (5)   dlg0x2d91664: Transaction tsx0x2d8a264 state changed to Proceeding
> 2013-08-01 16:11:37.089202 (5)      pasound.c: Player thread started
> 2013-08-01 16:11:37.090650 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.091569 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.092384 (5)    ec0xe3ea0e0: Latency bufferring complete
> 2013-08-01 16:11:37.093202 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.194957 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.195964 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.197921 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.198764 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.200220 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.301227 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.301981 (5)    ec0xe3ea0e0: 639 samples reduced, buf_cnt=1601
> 2013-08-01 16:11:37.303477 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.304287 (5)    ec0xe3ea0e0: 98 samples reduced, buf_cnt=1823
> 2013-08-01 16:11:37.305324 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.306226 (5)    ec0xe3ea0e0: 255 samples reduced, buf_cnt=1888
> 2013-08-01 16:11:37.306676 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.307700 (5)    ec0xe3ea0e0: 429 samples reduced, buf_cnt=1779
> 2013-08-01 16:11:37.308219 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.308889 (5)    ec0xe3ea0e0: 186 samples reduced, buf_cnt=1913
> 2013-08-01 16:11:37.309875 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.310383 (5)    ec0xe3ea0e0: 513 samples reduced, buf_cnt=1720
> 2013-08-01 16:11:37.412403 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.413382 (5)    ec0xe3ea0e0: 363 samples reduced, buf_cnt=1677
> 2013-08-01 16:11:37.414860 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.415923 (5)    ec0xe3ea0e0: 77 samples reduced, buf_cnt=1920
> 2013-08-01 16:11:37.416758 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.417944 (5)    ec0xe3ea0e0: 340 samples reduced, buf_cnt=1900
> 2013-08-01 16:11:37.418473 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.419200 (5)    ec0xe3ea0e0: 564 samples reduced, buf_cnt=1656
> 2013-08-01 16:11:37.420188 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.420773 (5)    ec0xe3ea0e0: 350 samples reduced, buf_cnt=1626
> 2013-08-01 16:11:37.522695 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.523348 (5)    ec0xe3ea0e0: 77 samples reduced, buf_cnt=1869
> 2013-08-01 16:11:37.524295 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.524997 (5)    ec0xe3ea0e0: 276 samples reduced, buf_cnt=1913
> 2013-08-01 16:11:37.525894 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.526675 (5)    ec0xe3ea0e0: 545 samples reduced, buf_cnt=1688
> 2013-08-01 16:11:37.527416 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.528239 (5)    ec0xe3ea0e0: 404 samples reduced, buf_cnt=1604
> 2013-08-01 16:11:37.528859 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.529896 (5)    ec0xe3ea0e0: 90 samples reduced, buf_cnt=1834
> 2013-08-01 16:11:37.530862 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.531708 (5)    ec0xe3ea0e0: 257 samples reduced, buf_cnt=1897
> 2013-08-01 16:11:37.633274 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.634055 (5)    ec0xe3ea0e0: 302 samples reduced, buf_cnt=1915
> 2013-08-01 16:11:37.635701 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.636579 (5)    ec0xe3ea0e0: 631 samples reduced, buf_cnt=1604
> 2013-08-01 16:11:37.637394 (4)   Master/sound: Underflow, buf_cnt=0, will generate 1 frame
> 2013-08-01 16:11:37.638260 (5)    ec0xe3ea0e0: 109 samples reduced, buf_cnt=1815
> 2013-08-01 16:11:37.639248 (5)      pasound.c: Recorder thread started
> 2013-08-01 16:11:37.639541 (5)    ec0xe3ea0e0: 180 samples reduced, buf_cnt=1635
> 2013-08-01 16:11:37.640549 (4)    ec0xe3ea0e0: Buffer size adjusted from 1815 to 1635 (eff_cnt=1120)
> 2013-08-01 16:11:37.641481 (5)    ec0xe3ea0e0: 452 samples reduced, buf_cnt=1183
> 2013-08-01 16:11:37.642192 (4)    ec0xe3ea0e0: Buffer size adjusted from 1635 to 1183 (eff_cnt=1120)
> 2013-08-01 16:11:38.348214 (5)   wav_player.c: File port /Applications/Blink Pro.app/Contents/Resources/ring_inbound.wav EOF
> 2013-08-01 16:11:38.349183 (5)   wav_player.c: File port /Applications/Blink Pro.app/Contents/Resources/ring_inbound.wav EOF
> 2013-08-01 16:11:38.349789 (5)   wav_player.c: File port /Applications/Blink Pro.app/Contents/Resources/ring_inbound.wav EOF
> 2013-08-01 16:11:43.297569 (4)   sound_port.c: EC suspended because of inactivity
> 2013-08-01 16:11:44.405329 (4)   wav_player.c: File player '/Applications/Blink Pro.app/Contents/Resources/ring_inbound.wav' created: samp.rate=44100, ch=1, bufsize=4KB, filesize=110KB
> 2013-08-01 16:11:44.406421 (5)     resample.c: resample created: high qualiy, large filter, in/out rate=44100/16000
> 2013-08-01 16:11:44.406928 (5)     resample.c: resample created: high qualiy, large filter, in/out rate=16000/44100
> 2013-08-01 16:11:44.407677 (4)   conference.c: Port 3 (/Applications/Blink Pro.app/Contents/Resources/ring_inbound.wav) transmitting to port 0 (Master/sound)
> 2013-08-01 16:11:44.408235 (5)    ec0xe3ea0e0: Delay buffer is reset
> 2013-08-01 16:11:44.408985 (4) echo_webrtc_ae: WebRTC AEC reset succeeded
> 2013-08-01 16:11:44.409789 (4)   sound_port.c: EC activated
> 2013-08-01 16:11:44.410685 (5)    ec0xe3ea0e0: Prefetching..
> 2013-08-01 16:11:44.411702 (5)    ec0xe3ea0e0: Latency bufferring complete
> 2013-08-01 16:11:45.725012 (5)   wav_player.c: File port /Applications/Blink Pro.app/Contents/Resources/ring_inbound.wav EOF
> 2013-08-01 16:11:45.726135 (5)   wav_player.c: File port /Applications/Blink Pro.app/Contents/Resources/ring_inbound.wav EOF
> 2013-08-01 16:11:45.726703 (5)   wav_player.c: File port /Applications/Blink Pro.app/Contents/Resources/ring_inbound.wav EOF
> 2013-08-01 16:11:45.727560 (5)   wav_player.c: File port /Applications/Blink Pro.app/Contents/Resources/ring_inbound.wav EOF
> 2013-08-01 16:11:45.728067 (5)   wav_player.c: File port /Applications/Blink Pro.app/Contents/Resources/ring_inbound.wav EOF
> 2013-08-01 16:11:46.536866 (4)   conference.c: Port 0 (Master/sound) stop transmitting to port 1 (mixer-port)
> 2013-08-01 16:11:46.537698 (5)   Master/sound: Delay buffer is reset
> 2013-08-01 16:11:46.746528 (5)   inv0x2d91664: SDP negotiation done, status=0
> 2013-08-01 16:11:46.747434 (5)   inv0x2d91664: Sending Response msg 200/INVITE/cseq=17400 (tdta0x2e0a400)
> 2013-08-01 16:11:46.748089 (5)   dlg0x2d91664: Sending Response msg 200/INVITE/cseq=17400 (tdta0x2e0a400)
> 2013-08-01 16:11:46.748835 (5)   tsx0x2d8a264: Sending Response msg 200/INVITE/cseq=17400 (tdta0x2e0a400) in state Proceeding
> 2013-08-01 16:11:46.750330 (5)   tsx0x2d8a264: State changed from Proceeding to Completed, event=TX_MSG
> 2013-08-01 16:11:46.751196 (5)   dlg0x2d91664: Transaction tsx0x2d8a264 state changed to Completed
> 2013-08-01 16:11:46.855441 (5)          rtp.c: pjmedia_rtp_session_init: ses=0xb24188, default_pt=0, ssrc=0x6e4dbc2
> 2013-08-01 16:11:46.856618 (5)          rtp.c: pjmedia_rtp_session_init: ses=0xb247ac, default_pt=0, ssrc=0x6e4dbc2
> 2013-08-01 16:11:46.857317 (5)       stream.c: Stream strm0x2d56ab4 created
> 2013-08-01 16:11:46.857941 (4)  strm0x2d56ab4: Encoder stream started
> 2013-08-01 16:11:46.859005 (4)  strm0x2d56ab4: Decoder stream started
> 2013-08-01 16:11:46.860150 (5)     resample.c: resample created: high qualiy, large filter, in/out rate=8000/16000
> 2013-08-01 16:11:46.860649 (5)     resample.c: resample created: high qualiy, large filter, in/out rate=16000/8000
> 2013-08-01 16:11:46.861616 (5)    ec0xe3ea0e0: Delay buffer is reset
> 2013-08-01 16:11:46.862233 (4) echo_webrtc_ae: WebRTC AEC reset succeeded
> 2013-08-01 16:11:46.863182 (4)   sound_port.c: EC reset
> 2013-08-01 16:11:46.864018 (5)    ec0xe3ea0e0: Delay buffer is reset
> 2013-08-01 16:11:46.865162 (4) echo_webrtc_ae: WebRTC AEC reset succeeded
> 2013-08-01 16:11:46.865887 (4)   sound_port.c: EC reset
> 2013-08-01 16:11:46.866506 (4)   conference.c: Port 2 (mixer-port) transmitting to port 3 (strm0x2d56ab4)
> 2013-08-01 16:11:46.867269 (4)   conference.c: Port 3 (strm0x2d56ab4) transmitting to port 1 (mixer-port)
> 2013-08-01 16:11:46.867814 (4)   conference.c: Port 3 (strm0x2d56ab4) transmitting to port 0 (Master/sound)
> 2013-08-01 16:11:46.868952 (5)  strm0x2d56ab4: Jitter buffer empty (prefetch=0)
> 2013-08-01 16:11:46.869798 (5)  strm0x2d56ab4: Start talksprut..
> 2013-08-01 16:11:46.870782 (5)    ec0xe3ea0e0: Prefetching..
> 2013-08-01 16:11:46.871437 (5)    ec0xe3ea0e0: Latency bufferring complete
> 2013-08-01 16:11:47.247014 (5)   tsx0x2d8a264: Retransmit timer event
> 2013-08-01 16:11:47.248138 (5)   tsx0x2d8a264: Retransmiting Response msg 200/INVITE/cseq=17400 (tdta0x2e0a400), count=0, restart?=1
> 2013-08-01 16:11:48.248498 (5)   tsx0x2d8a264: Retransmit timer event
> 2013-08-01 16:11:48.249629 (5)   tsx0x2d8a264: Retransmiting Response msg 200/INVITE/cseq=17400 (tdta0x2e0a400), count=1, restart?=1
> 2013-08-01 16:11:48.554271 (4)   wav_player.c: File player '/Applications/Blink Pro.app/Contents/Resources/unavailable_message.wav' created: samp.rate=8000, ch=1, bufsize=4KB, filesize=81KB
> 2013-08-01 16:11:48.554886 (5)     resample.c: resample created: high qualiy, large filter, in/out rate=8000/16000
> 2013-08-01 16:11:48.555916 (5)     resample.c: resample created: high qualiy, large filter, in/out rate=16000/8000
> 2013-08-01 16:11:48.556758 (4)   conference.c: Port 4 (/Applications/Blink Pro.app/Contents/Resources/unavailable_message.wav) transmitting to port 1 (mixer-port)
> 2013-08-01 16:11:48.557496 (4)   conference.c: Port 4 (/Applications/Blink Pro.app/Contents/Resources/unavailable_message.wav) transmitting to port 0 (Master/sound)
> 2013-08-01 16:11:48.558166 (4)   conference.c: Port 4 (/Applications/Blink Pro.app/Contents/Resources/unavailable_message.wav) transmitting to port 3 (strm0x2d56ab4)
> 2013-08-01 16:11:50.249796 (5)   tsx0x2d8a264: Retransmit timer event
> 2013-08-01 16:11:50.250431 (5)   tsx0x2d8a264: Retransmiting Response msg 200/INVITE/cseq=17400 (tdta0x2e0a400), count=2, restart?=1
> 2013-08-01 16:11:50.656226 (5)       endpoint: Request msg BYE/cseq=30265 (tdta0x9d65200) created.
> 2013-08-01 16:11:50.656808 (5)   inv0x2d91664: Sending Request msg BYE/cseq=30265 (tdta0x9d65200)
> 2013-08-01 16:11:50.658087 (5)   dlg0x2d91664: Sending Request msg BYE/cseq=30265 (tdta0x9d65200)
> 2013-08-01 16:11:50.658630 (5)   tsx0x9be7c64: Transaction created for Request msg BYE/cseq=30264 (tdta0x9d65200)
> 2013-08-01 16:11:50.659457 (5)   tsx0x9be7c64: Sending Request msg BYE/cseq=30264 (tdta0x9d65200) in state Null
> 2013-08-01 16:11:50.660391 (5)  sip_resolve.c: Target '81.23.228.129:443' type=TLS resolved to '81.23.228.129:443' type=TLS (TLS transport)
> 2013-08-01 16:11:50.662208 (5)   tsx0x9be7c64: State changed from Null to Calling, event=TX_MSG
> 2013-08-01 16:11:50.662697 (5)   dlg0x2d91664: Transaction tsx0x9be7c64 state changed to Calling
> 2013-08-01 16:11:51.625572 (5)   tsx0x2d8a264: Request to terminate transaction
> 2013-08-01 16:11:51.626705 (5)   tsx0x2d8a264: State changed from Completed to Terminated, event=USER
> 2013-08-01 16:11:51.627635 (5)   dlg0x2d91664: Transaction tsx0x2d8a264 state changed to Terminated
> 2013-08-01 16:11:51.628468 (5)   dlg0x2d91664: Session count dec to 2 by mod-invite
> 2013-08-01 16:11:51.629047 (5)       endpoint: Request msg BYE/cseq=30266 (tdta0xb42600) created.
> 2013-08-01 16:11:51.629778 (5)   inv0x2d91664: Sending Request msg BYE/cseq=30266 (tdta0xb42600)
> 2013-08-01 16:11:51.630885 (5)   dlg0x2d91664: Sending Request msg BYE/cseq=30266 (tdta0xb42600)
> 2013-08-01 16:11:51.631418 (5)    tsx0xb7fe64: Transaction created for Request msg BYE/cseq=30265 (tdta0xb42600)
> 2013-08-01 16:11:51.632293 (5)    tsx0xb7fe64: Sending Request msg BYE/cseq=30265 (tdta0xb42600) in state Null
> 2013-08-01 16:11:51.632981 (5)  sip_resolve.c: Target '81.23.228.129:443' type=TLS resolved to '81.23.228.129:443' type=TLS (TLS transport)
> 2013-08-01 16:11:51.634515 (5)    tsx0xb7fe64: State changed from Null to Calling, event=TX_MSG
> 2013-08-01 16:11:51.635400 (5)   dlg0x2d91664: Transaction tsx0xb7fe64 state changed to Calling
> 2013-08-01 16:11:51.637399 (5)   tsx0x2d8a264: Timeout timer event
> 2013-08-01 16:11:51.638122 (5)   tsx0x2d8a264: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:11:51.638643 (5)  tdta0x2e0a400: Destroying txdata Response msg 200/INVITE/cseq=17400 (tdta0x2e0a400)
> 2013-08-01 16:11:51.639385 (5)   tsx0x2d8a264: Transaction destroyed!
> 2013-08-01 16:11:51.742289 (4)   conference.c: Port 4 (/Applications/Blink Pro.app/Contents/Resources/unavailable_message.wav) stop transmitting to port 0 (Master/sound)
> 2013-08-01 16:11:51.742696 (4)   wav_player.c: File player '/Applications/Blink Pro.app/Contents/Resources/hangup_tone.wav' created: samp.rate=32000, ch=1, bufsize=4KB, filesize=32KB
> 2013-08-01 16:11:51.743817 (5)     resample.c: resample created: high qualiy, large filter, in/out rate=32000/16000
> 2013-08-01 16:11:51.744273 (5)     resample.c: resample created: high qualiy, large filter, in/out rate=16000/32000
> 2013-08-01 16:11:51.744822 (4)   conference.c: Port 1 (/Applications/Blink Pro.app/Contents/Resources/hangup_tone.wav) transmitting to port 0 (Master/sound)
> 2013-08-01 16:11:52.048714 (5)       endpoint: Request msg PUBLISH/cseq=1 (tdta0x9fad800) created.
> 2013-08-01 16:11:52.049285 (5)   tsx0x9e9d264: Transaction created for Request msg PUBLISH/cseq=1 (tdta0x9fad800)
> 2013-08-01 16:11:52.050390 (5)   tsx0x9e9d264: Sending Request msg PUBLISH/cseq=1 (tdta0x9fad800) in state Null
> 2013-08-01 16:11:52.051116 (5)  sip_resolve.c: Target '85.17.186.7:443' type=TLS resolved to '85.17.186.7:443' type=TLS (TLS transport)
> 2013-08-01 16:11:52.052994 (5)   tsx0x9e9d264: State changed from Null to Calling, event=TX_MSG
> 2013-08-01 16:11:52.213773 (5)   wav_player.c: File port /Applications/Blink Pro.app/Contents/Resources/hangup_tone.wav EOF
> 2013-08-01 16:11:52.214280 (5)   wav_player.c: File port /Applications/Blink Pro.app/Contents/Resources/hangup_tone.wav EOF
> 2013-08-01 16:11:52.215247 (5)   wav_player.c: File port /Applications/Blink Pro.app/Contents/Resources/hangup_tone.wav EOF
> 2013-08-01 16:11:52.216137 (5)   wav_player.c: File port /Applications/Blink Pro.app/Contents/Resources/hangup_tone.wav EOF
> 2013-08-01 16:11:52.217229 (5) sip_endpoint.c: Processing incoming message: Response msg 407/PUBLISH/cseq=1 (rdata0x9d24f2c)
> 2013-08-01 16:11:52.218654 (5)   tsx0x9e9d264: Incoming Response msg 407/PUBLISH/cseq=1 (rdata0x9d24f2c) in state Calling
> 2013-08-01 16:11:52.219402 (5)   tsx0x9e9d264: State changed from Calling to Completed, event=RX_MSG
> 2013-08-01 16:11:52.220136 (5)   tsx0x2e94264: Transaction created for Request msg PUBLISH/cseq=2 (tdta0x9fad800)
> 2013-08-01 16:11:52.221132 (5)   tsx0x2e94264: Sending Request msg PUBLISH/cseq=2 (tdta0x9fad800) in state Null
> 2013-08-01 16:11:52.221864 (5)  sip_resolve.c: Target '85.17.186.7:443' type=TLS resolved to '85.17.186.7:443' type=TLS (TLS transport)
> 2013-08-01 16:11:52.223342 (5)   tsx0x2e94264: State changed from Null to Calling, event=TX_MSG
> 2013-08-01 16:11:52.453808 (5)   tsx0x9e9d264: Timeout timer event
> 2013-08-01 16:11:52.454925 (5)   tsx0x9e9d264: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:11:52.461741 (5)      pasound.c: Stopping stream..
> 2013-08-01 16:11:52.462875 (5)      pasound.c: Done, status=0
> 2013-08-01 16:11:52.463930 (5)      pasound.c: Closing Built-in Microph: 0 underflow, 0 overflow
> 2013-08-01 16:11:52.465475 (5)   tsx0x9e9d264: Timeout timer event
> 2013-08-01 16:11:52.466053 (5)   tsx0x9e9d264: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:11:52.467151 (5)   tsx0x9e9d264: Transaction destroyed!
> 2013-08-01 16:11:52.467849 (5) sip_endpoint.c: Processing incoming message: Response msg 200/PUBLISH/cseq=2 (rdata0x9d24f2c)
> 2013-08-01 16:11:52.469838 (5)   tsx0x2e94264: Incoming Response msg 200/PUBLISH/cseq=2 (rdata0x9d24f2c) in state Calling
> 2013-08-01 16:11:52.471290 (5)   tsx0x2e94264: State changed from Calling to Completed, event=RX_MSG
> 2013-08-01 16:11:52.475387 (5)   tsx0x2e94264: Timeout timer event
> 2013-08-01 16:11:52.476069 (5)   tsx0x2e94264: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:11:52.477278 (5)   tsx0x2e94264: Timeout timer event
> 2013-08-01 16:11:52.478489 (5)   tsx0x2e94264: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:11:52.479174 (5)   tsx0x2e94264: Transaction destroyed!
> 2013-08-01 16:11:52.483388 (5) sip_endpoint.c: Processing incoming message: Request msg NOTIFY/cseq=2 (rdata0x9e2cd2c)
> 2013-08-01 16:11:52.486864 (5)    dlg0xb56864: Received Request msg NOTIFY/cseq=2 (rdata0x9e2cd2c)
> 2013-08-01 16:11:52.487861 (5)   tsx0x2e0a864: Transaction created for Request msg NOTIFY/cseq=2 (rdata0x9e2cd2c)
> 2013-08-01 16:11:52.488688 (5)   tsx0x2e0a864: Incoming Request msg NOTIFY/cseq=2 (rdata0x9e2cd2c) in state Null
> 2013-08-01 16:11:52.489669 (5)   tsx0x2e0a864: State changed from Null to Trying, event=RX_MSG
> 2013-08-01 16:11:52.491140 (5)    dlg0xb56864: Transaction tsx0x2e0a864 state changed to Trying
> 2013-08-01 16:11:52.492883 (5) evsub0x13ea214: Will refresh in 106 seconds
> 2013-08-01 16:11:52.493666 (5) evsub0x13ea214: Updating UAC_REFRESH timer
> 2013-08-01 16:11:52.494395 (5) evsub0x13ea214: Timer UAC_REFRESH scheduled in 106 seconds
> 2013-08-01 16:11:52.495456 (5)       endpoint: Response msg 200/NOTIFY/cseq=2 (tdta0x9fc2400) created
> 2013-08-01 16:11:52.496858 (5)    dlg0xb56864: Sending Response msg 200/NOTIFY/cseq=2 (tdta0x9fc2400)
> 2013-08-01 16:11:52.498442 (5)   tsx0x2e0a864: Sending Response msg 200/NOTIFY/cseq=2 (tdta0x9fc2400) in state Trying
> 2013-08-01 16:11:52.500139 (5)   tsx0x2e0a864: State changed from Trying to Completed, event=TX_MSG
> 2013-08-01 16:11:52.500885 (5)    dlg0xb56864: Transaction tsx0x2e0a864 state changed to Completed
> 2013-08-01 16:11:52.501682 (4) evsub0x13ea214: Subscription state changed ACTIVE --> ACTIVE
> 2013-08-01 16:11:52.603851 (5)   tsx0x2e0a864: Timeout timer event
> 2013-08-01 16:11:52.607131 (5)   tsx0x2e0a864: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:11:52.610279 (5)    dlg0xb56864: Transaction tsx0x2e0a864 state changed to Terminated
> 2013-08-01 16:11:52.614708 (5)   tsx0x2e0a864: Timeout timer event
> 2013-08-01 16:11:52.620735 (5)   tsx0x2e0a864: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:11:52.625529 (5)  tdta0x9fc2400: Destroying txdata Response msg 200/NOTIFY/cseq=2 (tdta0x9fc2400)
> 2013-08-01 16:11:52.628505 (5)   tsx0x2e0a864: Transaction destroyed!
> 2013-08-01 16:11:56.570074 (5)       endpoint: Request msg PUBLISH/cseq=1 (tdta0x9e48000) created.
> 2013-08-01 16:11:56.570870 (5)   tsx0x9f3a464: Transaction created for Request msg PUBLISH/cseq=1 (tdta0x9e48000)
> 2013-08-01 16:11:56.571373 (5)   tsx0x9f3a464: Sending Request msg PUBLISH/cseq=1 (tdta0x9e48000) in state Null
> 2013-08-01 16:11:56.572261 (5)  sip_resolve.c: Target '85.17.186.7:443' type=TLS resolved to '85.17.186.7:443' type=TLS (TLS transport)
> 2013-08-01 16:11:56.573600 (5)   tsx0x9f3a464: State changed from Null to Calling, event=TX_MSG
> 2013-08-01 16:11:56.750950 (5) sip_endpoint.c: Processing incoming message: Response msg 407/PUBLISH/cseq=1 (rdata0x9d24f2c)
> 2013-08-01 16:11:56.752581 (5)   tsx0x9f3a464: Incoming Response msg 407/PUBLISH/cseq=1 (rdata0x9d24f2c) in state Calling
> 2013-08-01 16:11:56.753520 (5)   tsx0x9f3a464: State changed from Calling to Completed, event=RX_MSG
> 2013-08-01 16:11:56.754042 (5)    tsx0xa91464: Transaction created for Request msg PUBLISH/cseq=2 (tdta0x9e48000)
> 2013-08-01 16:11:56.755033 (5)    tsx0xa91464: Sending Request msg PUBLISH/cseq=2 (tdta0x9e48000) in state Null
> 2013-08-01 16:11:56.755589 (5)  sip_resolve.c: Target '85.17.186.7:443' type=TLS resolved to '85.17.186.7:443' type=TLS (TLS transport)
> 2013-08-01 16:11:56.757537 (5)    tsx0xa91464: State changed from Null to Calling, event=TX_MSG
> 2013-08-01 16:11:56.859947 (5)   tsx0x9f3a464: Timeout timer event
> 2013-08-01 16:11:56.860486 (5)   tsx0x9f3a464: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:11:56.861746 (5)   tsx0x9f3a464: Timeout timer event
> 2013-08-01 16:11:56.862212 (5)   tsx0x9f3a464: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:11:56.863189 (5)   tsx0x9f3a464: Transaction destroyed!
> 2013-08-01 16:11:56.984149 (5) sip_endpoint.c: Processing incoming message: Response msg 200/PUBLISH/cseq=2 (rdata0x9d24f2c)
> 2013-08-01 16:11:56.985709 (5)    tsx0xa91464: Incoming Response msg 200/PUBLISH/cseq=2 (rdata0x9d24f2c) in state Calling
> 2013-08-01 16:11:56.986459 (5)    tsx0xa91464: State changed from Calling to Completed, event=RX_MSG
> 2013-08-01 16:11:56.994516 (5)    tsx0xa91464: Timeout timer event
> 2013-08-01 16:11:56.995290 (5)    tsx0xa91464: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:11:56.995853 (5)    tsx0xa91464: Timeout timer event
> 2013-08-01 16:11:56.996743 (5)    tsx0xa91464: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:11:56.997751 (5)    tsx0xa91464: Transaction destroyed!
> 2013-08-01 16:11:57.023187 (5) sip_endpoint.c: Processing incoming message: Request msg NOTIFY/cseq=3 (rdata0x9e2cd2c)
> 2013-08-01 16:11:57.025114 (5)    dlg0xb56864: Received Request msg NOTIFY/cseq=3 (rdata0x9e2cd2c)
> 2013-08-01 16:11:57.025744 (5)    tsx0xb3e064: Transaction created for Request msg NOTIFY/cseq=3 (rdata0x9e2cd2c)
> 2013-08-01 16:11:57.026815 (5)    tsx0xb3e064: Incoming Request msg NOTIFY/cseq=3 (rdata0x9e2cd2c) in state Null
> 2013-08-01 16:11:57.027381 (5)    tsx0xb3e064: State changed from Null to Trying, event=RX_MSG
> 2013-08-01 16:11:57.028381 (5)    dlg0xb56864: Transaction tsx0xb3e064 state changed to Trying
> 2013-08-01 16:11:57.029536 (5) evsub0x13ea214: Will refresh in 101 seconds
> 2013-08-01 16:11:57.031925 (5) evsub0x13ea214: Updating UAC_REFRESH timer
> 2013-08-01 16:11:57.038628 (5) evsub0x13ea214: Timer UAC_REFRESH scheduled in 101 seconds
> 2013-08-01 16:11:57.039462 (5)       endpoint: Response msg 200/NOTIFY/cseq=3 (tdta0xb2f200) created
> 2013-08-01 16:11:57.040600 (5)    dlg0xb56864: Sending Response msg 200/NOTIFY/cseq=3 (tdta0xb2f200)
> 2013-08-01 16:11:57.041068 (5)    tsx0xb3e064: Sending Response msg 200/NOTIFY/cseq=3 (tdta0xb2f200) in state Trying
> 2013-08-01 16:11:57.042311 (5)    tsx0xb3e064: State changed from Trying to Completed, event=TX_MSG
> 2013-08-01 16:11:57.042795 (5)    dlg0xb56864: Transaction tsx0xb3e064 state changed to Completed
> 2013-08-01 16:11:57.043182 (4) evsub0x13ea214: Subscription state changed ACTIVE --> ACTIVE
> 2013-08-01 16:11:57.144950 (5)    tsx0xb3e064: Timeout timer event
> 2013-08-01 16:11:57.146033 (5)    tsx0xb3e064: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:11:57.148290 (5)    dlg0xb56864: Transaction tsx0xb3e064 state changed to Terminated
> 2013-08-01 16:11:57.148975 (5)    tsx0xb3e064: Timeout timer event
> 2013-08-01 16:11:57.150273 (5)    tsx0xb3e064: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:11:57.150796 (5)   tdta0xb2f200: Destroying txdata Response msg 200/NOTIFY/cseq=3 (tdta0xb2f200)
> 2013-08-01 16:11:57.151408 (5)    tsx0xb3e064: Transaction destroyed!
> 2013-08-01 16:12:05.598778 (5) sip_endpoint.c: Processing incoming message: Response msg 408/BYE/cseq=30264 (rdata0x9e2cd2c)
> 2013-08-01 16:12:05.600377 (5)   tsx0x9be7c64: Incoming Response msg 408/BYE/cseq=30264 (rdata0x9e2cd2c) in state Calling
> 2013-08-01 16:12:05.601278 (5)   tsx0x9be7c64: State changed from Calling to Completed, event=RX_MSG
> 2013-08-01 16:12:05.602169 (5)   dlg0x2d91664: Received Response msg 408/BYE/cseq=30264 (rdata0x9e2cd2c)
> 2013-08-01 16:12:05.603122 (5)   dlg0x2d91664: Transaction tsx0x9be7c64 state changed to Completed
> 2013-08-01 16:12:05.705457 (5)   tsx0x9be7c64: Timeout timer event
> 2013-08-01 16:12:05.707072 (5)   tsx0x9be7c64: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:12:05.708769 (5)   dlg0x2d91664: Transaction tsx0x9be7c64 state changed to Terminated
> 2013-08-01 16:12:05.709998 (5)   tsx0x9be7c64: Timeout timer event
> 2013-08-01 16:12:05.711328 (5)   tsx0x9be7c64: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:12:05.712008 (5)  tdta0x9d65200: Destroying txdata Request msg BYE/cseq=30264 (tdta0x9d65200)
> 2013-08-01 16:12:05.712884 (5)   tsx0x9be7c64: Transaction destroyed!
> 2013-08-01 16:12:06.819381 (5) sip_endpoint.c: Processing incoming message: Response msg 408/BYE/cseq=30265 (rdata0x9e2cd2c)
> 2013-08-01 16:12:06.820583 (5)    tsx0xb7fe64: Incoming Response msg 408/BYE/cseq=30265 (rdata0x9e2cd2c) in state Calling
> 2013-08-01 16:12:06.821361 (5)    tsx0xb7fe64: State changed from Calling to Completed, event=RX_MSG
> 2013-08-01 16:12:06.821725 (5)   dlg0x2d91664: Received Response msg 408/BYE/cseq=30265 (rdata0x9e2cd2c)
> 2013-08-01 16:12:06.822365 (5)   dlg0x2d91664: Transaction tsx0xb7fe64 state changed to Completed
> 2013-08-01 16:12:06.924195 (5)    tsx0xb7fe64: Timeout timer event
> 2013-08-01 16:12:06.925245 (5)    tsx0xb7fe64: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:12:06.925689 (5)   dlg0x2d91664: Transaction tsx0xb7fe64 state changed to Terminated
> 2013-08-01 16:12:06.926274 (5)   dlg0x2d91664: Dialog destroyed
> 2013-08-01 16:12:06.926767 (5)    tsx0xb7fe64: Timeout timer event
> 2013-08-01 16:12:06.927256 (5)    tsx0xb7fe64: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:12:06.928078 (5)   tdta0xb42600: Destroying txdata Request msg BYE/cseq=30265 (tdta0xb42600)
> 2013-08-01 16:12:06.928859 (5)    tsx0xb7fe64: Transaction destroyed!
> 2013-08-01 16:13:12.751662 (5)       endpoint: Request msg SUBSCRIBE/cseq=16754 (tdta0xb18000) created.
> 2013-08-01 16:13:12.753430 (5)   dlg0x9d63064: Sending Request msg SUBSCRIBE/cseq=16754 (tdta0xb18000)
> 2013-08-01 16:13:12.754496 (5)    tsx0xbee264: Transaction created for Request msg SUBSCRIBE/cseq=16753 (tdta0xb18000)
> 2013-08-01 16:13:12.755703 (5)    tsx0xbee264: Sending Request msg SUBSCRIBE/cseq=16753 (tdta0xb18000) in state Null
> 2013-08-01 16:13:12.756974 (5)  sip_resolve.c: Target '81.23.228.129:443' type=TLS resolved to '81.23.228.129:443' type=TLS (TLS transport)
> 2013-08-01 16:13:12.758425 (5)    tsx0xbee264: State changed from Null to Calling, event=TX_MSG
> 2013-08-01 16:13:12.759006 (5)   dlg0x9d63064: Transaction tsx0xbee264 state changed to Calling
> 2013-08-01 16:13:12.779615 (5)       endpoint: Request msg SUBSCRIBE/cseq=27398 (tdta0x9ec9a00) created.
> 2013-08-01 16:13:12.780772 (5)   dlg0x9d35264: Sending Request msg SUBSCRIBE/cseq=27398 (tdta0x9ec9a00)
> 2013-08-01 16:13:12.782447 (5)   tsx0x9baa464: Transaction created for Request msg SUBSCRIBE/cseq=27397 (tdta0x9ec9a00)
> 2013-08-01 16:13:12.783568 (5)   tsx0x9baa464: Sending Request msg SUBSCRIBE/cseq=27397 (tdta0x9ec9a00) in state Null
> 2013-08-01 16:13:12.785138 (5)  sip_resolve.c: Target '81.23.228.129:443' type=TLS resolved to '81.23.228.129:443' type=TLS (TLS transport)
> 2013-08-01 16:13:12.786733 (5)   tsx0x9baa464: State changed from Null to Calling, event=TX_MSG
> 2013-08-01 16:13:12.787428 (5)   dlg0x9d35264: Transaction tsx0x9baa464 state changed to Calling
> 2013-08-01 16:13:12.889940 (5)       endpoint: Request msg SUBSCRIBE/cseq=2696 (tdta0x24fec00) created.
> 2013-08-01 16:13:12.891002 (5)   dlg0x2d71864: Sending Request msg SUBSCRIBE/cseq=2696 (tdta0x24fec00)
> 2013-08-01 16:13:12.892784 (5)   tsx0x2d91664: Transaction created for Request msg SUBSCRIBE/cseq=2695 (tdta0x24fec00)
> 2013-08-01 16:13:12.893930 (5)   tsx0x2d91664: Sending Request msg SUBSCRIBE/cseq=2695 (tdta0x24fec00) in state Null
> 2013-08-01 16:13:12.895191 (5)  sip_resolve.c: Target '81.23.228.129:443' type=TLS resolved to '81.23.228.129:443' type=TLS (TLS transport)
> 2013-08-01 16:13:12.897210 (5)   tsx0x2d91664: State changed from Null to Calling, event=TX_MSG
> 2013-08-01 16:13:12.897882 (5)   dlg0x2d71864: Transaction tsx0x2d91664 state changed to Calling
> 2013-08-01 16:13:12.991541 (5) sip_endpoint.c: Processing incoming message: Response msg 200/SUBSCRIBE/cseq=16753 (rdata0x9e2cd2c)
> 2013-08-01 16:13:12.997141 (5)    tsx0xbee264: Incoming Response msg 200/SUBSCRIBE/cseq=16753 (rdata0x9e2cd2c) in state Calling
> 2013-08-01 16:13:12.998550 (5)    tsx0xbee264: State changed from Calling to Completed, event=RX_MSG
> 2013-08-01 16:13:12.999290 (5)   dlg0x9d63064: Received Response msg 200/SUBSCRIBE/cseq=16753 (rdata0x9e2cd2c)
> 2013-08-01 16:13:12.999933 (5)   dlg0x9d63064: Transaction tsx0xbee264 state changed to Completed
> 2013-08-01 16:13:13.000756 (5) evsub0x9da0414: Will refresh in 295 seconds
> 2013-08-01 16:13:13.001317 (5) evsub0x9da0414: Updating UAC_REFRESH timer
> 2013-08-01 16:13:13.001871 (5) evsub0x9da0414: Timer UAC_REFRESH scheduled in 295 seconds
> 2013-08-01 16:13:13.003896 (5)    tsx0xbee264: Timeout timer event
> 2013-08-01 16:13:13.004672 (5)    tsx0xbee264: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:13:13.005094 (5)   dlg0x9d63064: Transaction tsx0xbee264 state changed to Terminated
> 2013-08-01 16:13:13.006240 (5)       endpoint: Request msg SUBSCRIBE/cseq=21877 (tdta0xb23e00) created.
> 2013-08-01 16:13:13.006622 (5)   dlg0x9ed2864: Sending Request msg SUBSCRIBE/cseq=21877 (tdta0xb23e00)
> 2013-08-01 16:13:13.007433 (5)    tsx0xb3e064: Transaction created for Request msg SUBSCRIBE/cseq=21876 (tdta0xb23e00)
> 2013-08-01 16:13:13.008132 (5)    tsx0xb3e064: Sending Request msg SUBSCRIBE/cseq=21876 (tdta0xb23e00) in state Null
> 2013-08-01 16:13:13.008771 (5)  sip_resolve.c: Target '81.23.228.129:443' type=TLS resolved to '81.23.228.129:443' type=TLS (TLS transport)
> 2013-08-01 16:13:13.009977 (5)    tsx0xb3e064: State changed from Null to Calling, event=TX_MSG
> 2013-08-01 16:13:13.010687 (5)   dlg0x9ed2864: Transaction tsx0xb3e064 state changed to Calling
> 2013-08-01 16:13:13.011413 (5)    tsx0xbee264: Timeout timer event
> 2013-08-01 16:13:13.012181 (5)    tsx0xbee264: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:13:13.012722 (5)   tdta0xb18000: Destroying txdata Request msg SUBSCRIBE/cseq=16753 (tdta0xb18000)
> 2013-08-01 16:13:13.013446 (5)    tsx0xbee264: Transaction destroyed!
> 2013-08-01 16:13:13.013936 (5) sip_endpoint.c: Processing incoming message: Request msg NOTIFY/cseq=3 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.015303 (5)   dlg0x9d63064: Received Request msg NOTIFY/cseq=3 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.015857 (5)    tsx0xbee264: Transaction created for Request msg NOTIFY/cseq=3 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.016705 (5)    tsx0xbee264: Incoming Request msg NOTIFY/cseq=3 (rdata0x9e2cd2c) in state Null
> 2013-08-01 16:13:13.017248 (5)    tsx0xbee264: State changed from Null to Trying, event=RX_MSG
> 2013-08-01 16:13:13.017844 (5)   dlg0x9d63064: Transaction tsx0xbee264 state changed to Trying
> 2013-08-01 16:13:13.019093 (5) evsub0x9da0414: Will refresh in 295 seconds
> 2013-08-01 16:13:13.019919 (5) evsub0x9da0414: Updating UAC_REFRESH timer
> 2013-08-01 16:13:13.020558 (5) evsub0x9da0414: Timer UAC_REFRESH scheduled in 295 seconds
> 2013-08-01 16:13:13.021169 (5)       endpoint: Response msg 200/NOTIFY/cseq=3 (tdta0xb18000) created
> 2013-08-01 16:13:13.021899 (5)   dlg0x9d63064: Sending Response msg 200/NOTIFY/cseq=3 (tdta0xb18000)
> 2013-08-01 16:13:13.022571 (5)    tsx0xbee264: Sending Response msg 200/NOTIFY/cseq=3 (tdta0xb18000) in state Trying
> 2013-08-01 16:13:13.023561 (5)    tsx0xbee264: State changed from Trying to Completed, event=TX_MSG
> 2013-08-01 16:13:13.024140 (5)   dlg0x9d63064: Transaction tsx0xbee264 state changed to Completed
> 2013-08-01 16:13:13.024729 (4) evsub0x9da0414: Subscription state changed ACTIVE --> ACTIVE
> 2013-08-01 16:13:13.025989 (5)    tsx0xbee264: Timeout timer event
> 2013-08-01 16:13:13.026487 (5)    tsx0xbee264: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:13:13.026981 (5)   dlg0x9d63064: Transaction tsx0xbee264 state changed to Terminated
> 2013-08-01 16:13:13.027546 (5)    tsx0xbee264: Timeout timer event
> 2013-08-01 16:13:13.028139 (5)    tsx0xbee264: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:13:13.028790 (5)   tdta0xb18000: Destroying txdata Response msg 200/NOTIFY/cseq=3 (tdta0xb18000)
> 2013-08-01 16:13:13.029692 (5)    tsx0xbee264: Transaction destroyed!
> 2013-08-01 16:13:13.030460 (5) sip_endpoint.c: Processing incoming message: Response msg 200/SUBSCRIBE/cseq=27397 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.032138 (5)   tsx0x9baa464: Incoming Response msg 200/SUBSCRIBE/cseq=27397 (rdata0x9e2cd2c) in state Calling
> 2013-08-01 16:13:13.032736 (5)   tsx0x9baa464: State changed from Calling to Completed, event=RX_MSG
> 2013-08-01 16:13:13.033435 (5)   dlg0x9d35264: Received Response msg 200/SUBSCRIBE/cseq=27397 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.034121 (5)   dlg0x9d35264: Transaction tsx0x9baa464 state changed to Completed
> 2013-08-01 16:13:13.034779 (5) evsub0x9cc3e14: Will refresh in 295 seconds
> 2013-08-01 16:13:13.035370 (5) evsub0x9cc3e14: Updating UAC_REFRESH timer
> 2013-08-01 16:13:13.036153 (5) evsub0x9cc3e14: Timer UAC_REFRESH scheduled in 295 seconds
> 2013-08-01 16:13:13.037993 (5)   tsx0x9baa464: Timeout timer event
> 2013-08-01 16:13:13.038403 (5)   tsx0x9baa464: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:13:13.039107 (5)   dlg0x9d35264: Transaction tsx0x9baa464 state changed to Terminated
> 2013-08-01 16:13:13.039707 (5)   tsx0x9baa464: Timeout timer event
> 2013-08-01 16:13:13.040362 (5)   tsx0x9baa464: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:13:13.041027 (5)  tdta0x9ec9a00: Destroying txdata Request msg SUBSCRIBE/cseq=27397 (tdta0x9ec9a00)
> 2013-08-01 16:13:13.041774 (5)   tsx0x9baa464: Transaction destroyed!
> 2013-08-01 16:13:13.042705 (5) sip_endpoint.c: Processing incoming message: Request msg NOTIFY/cseq=4 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.043704 (5)   dlg0x9d35264: Received Request msg NOTIFY/cseq=4 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.044318 (5)    tsx0xbee264: Transaction created for Request msg NOTIFY/cseq=4 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.044958 (5)    tsx0xbee264: Incoming Request msg NOTIFY/cseq=4 (rdata0x9e2cd2c) in state Null
> 2013-08-01 16:13:13.045780 (5)    tsx0xbee264: State changed from Null to Trying, event=RX_MSG
> 2013-08-01 16:13:13.046426 (5)   dlg0x9d35264: Transaction tsx0xbee264 state changed to Trying
> 2013-08-01 16:13:13.047758 (5) evsub0x9cc3e14: Will refresh in 295 seconds
> 2013-08-01 16:13:13.048599 (5) evsub0x9cc3e14: Updating UAC_REFRESH timer
> 2013-08-01 16:13:13.049189 (5) evsub0x9cc3e14: Timer UAC_REFRESH scheduled in 295 seconds
> 2013-08-01 16:13:13.049962 (5)       endpoint: Response msg 200/NOTIFY/cseq=4 (tdta0xb18000) created
> 2013-08-01 16:13:13.050633 (5)   dlg0x9d35264: Sending Response msg 200/NOTIFY/cseq=4 (tdta0xb18000)
> 2013-08-01 16:13:13.051528 (5)    tsx0xbee264: Sending Response msg 200/NOTIFY/cseq=4 (tdta0xb18000) in state Trying
> 2013-08-01 16:13:13.052644 (5)    tsx0xbee264: State changed from Trying to Completed, event=TX_MSG
> 2013-08-01 16:13:13.053129 (5)   dlg0x9d35264: Transaction tsx0xbee264 state changed to Completed
> 2013-08-01 16:13:13.053811 (4) evsub0x9cc3e14: Subscription state changed ACTIVE --> ACTIVE
> 2013-08-01 16:13:13.054958 (5)    tsx0xbee264: Timeout timer event
> 2013-08-01 16:13:13.055595 (5)    tsx0xbee264: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:13:13.056176 (5)   dlg0x9d35264: Transaction tsx0xbee264 state changed to Terminated
> 2013-08-01 16:13:13.056809 (5)    tsx0xbee264: Timeout timer event
> 2013-08-01 16:13:13.057548 (5)    tsx0xbee264: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:13:13.058125 (5)   tdta0xb18000: Destroying txdata Response msg 200/NOTIFY/cseq=4 (tdta0xb18000)
> 2013-08-01 16:13:13.058981 (5)    tsx0xbee264: Transaction destroyed!
> 2013-08-01 16:13:13.059532 (5) sip_endpoint.c: Processing incoming message: Response msg 200/SUBSCRIBE/cseq=2695 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.060778 (5)   tsx0x2d91664: Incoming Response msg 200/SUBSCRIBE/cseq=2695 (rdata0x9e2cd2c) in state Calling
> 2013-08-01 16:13:13.061415 (5)   tsx0x2d91664: State changed from Calling to Completed, event=RX_MSG
> 2013-08-01 16:13:13.062167 (5)   dlg0x2d71864: Received Response msg 200/SUBSCRIBE/cseq=2695 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.062697 (5)   dlg0x2d71864: Transaction tsx0x2d91664 state changed to Completed
> 2013-08-01 16:13:13.063162 (5) evsub0x2d37e14: Will refresh in 295 seconds
> 2013-08-01 16:13:13.064050 (5) evsub0x2d37e14: Updating UAC_REFRESH timer
> 2013-08-01 16:13:13.064602 (5) evsub0x2d37e14: Timer UAC_REFRESH scheduled in 295 seconds
> 2013-08-01 16:13:13.066393 (5)   tsx0x2d91664: Timeout timer event
> 2013-08-01 16:13:13.066776 (5)   tsx0x2d91664: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:13:13.067334 (5)   dlg0x2d71864: Transaction tsx0x2d91664 state changed to Terminated
> 2013-08-01 16:13:13.068148 (5)   tsx0x2d91664: Timeout timer event
> 2013-08-01 16:13:13.068683 (5)   tsx0x2d91664: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:13:13.069274 (5)  tdta0x24fec00: Destroying txdata Request msg SUBSCRIBE/cseq=2695 (tdta0x24fec00)
> 2013-08-01 16:13:13.069913 (5)   tsx0x2d91664: Transaction destroyed!
> 2013-08-01 16:13:13.070504 (5) sip_endpoint.c: Processing incoming message: Request msg NOTIFY/cseq=3 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.071801 (5)   dlg0x2d71864: Received Request msg NOTIFY/cseq=3 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.072352 (5)   tsx0x9baa464: Transaction created for Request msg NOTIFY/cseq=3 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.072952 (5)   tsx0x9baa464: Incoming Request msg NOTIFY/cseq=3 (rdata0x9e2cd2c) in state Null
> 2013-08-01 16:13:13.073692 (5)   tsx0x9baa464: State changed from Null to Trying, event=RX_MSG
> 2013-08-01 16:13:13.074263 (5)   dlg0x2d71864: Transaction tsx0x9baa464 state changed to Trying
> 2013-08-01 16:13:13.075687 (5) evsub0x2d37e14: Will refresh in 295 seconds
> 2013-08-01 16:13:13.076592 (5) evsub0x2d37e14: Updating UAC_REFRESH timer
> 2013-08-01 16:13:13.077270 (5) evsub0x2d37e14: Timer UAC_REFRESH scheduled in 295 seconds
> 2013-08-01 16:13:13.077855 (5)       endpoint: Response msg 200/NOTIFY/cseq=3 (tdta0x9ec9a00) created
> 2013-08-01 16:13:13.078705 (5)   dlg0x2d71864: Sending Response msg 200/NOTIFY/cseq=3 (tdta0x9ec9a00)
> 2013-08-01 16:13:13.079350 (5)   tsx0x9baa464: Sending Response msg 200/NOTIFY/cseq=3 (tdta0x9ec9a00) in state Trying
> 2013-08-01 16:13:13.080439 (5)   tsx0x9baa464: State changed from Trying to Completed, event=TX_MSG
> 2013-08-01 16:13:13.081024 (5)   dlg0x2d71864: Transaction tsx0x9baa464 state changed to Completed
> 2013-08-01 16:13:13.081721 (4) evsub0x2d37e14: Subscription state changed ACTIVE --> ACTIVE
> 2013-08-01 16:13:13.182725 (5)   tsx0x9baa464: Timeout timer event
> 2013-08-01 16:13:13.183257 (5)   tsx0x9baa464: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:13:13.184491 (5)   dlg0x2d71864: Transaction tsx0x9baa464 state changed to Terminated
> 2013-08-01 16:13:13.185404 (5)   tsx0x9baa464: Timeout timer event
> 2013-08-01 16:13:13.186078 (5)   tsx0x9baa464: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:13:13.186912 (5)  tdta0x9ec9a00: Destroying txdata Response msg 200/NOTIFY/cseq=3 (tdta0x9ec9a00)
> 2013-08-01 16:13:13.188037 (5)   tsx0x9baa464: Transaction destroyed!
> 2013-08-01 16:13:13.188488 (5)       endpoint: Request msg REGISTER/cseq=5 (tdta0x9fc2400) created.
> 2013-08-01 16:13:13.190105 (5)   tsx0x9be6864: Transaction created for Request msg REGISTER/cseq=5 (tdta0x9fc2400)
> 2013-08-01 16:13:13.190802 (5)   tsx0x9be6864: Sending Request msg REGISTER/cseq=5 (tdta0x9fc2400) in state Null
> 2013-08-01 16:13:13.191467 (5)  sip_resolve.c: Target '81.23.228.129:443' type=TLS resolved to '81.23.228.129:443' type=TLS (TLS transport)
> 2013-08-01 16:13:13.193085 (5)   tsx0x9be6864: State changed from Null to Calling, event=TX_MSG
> 2013-08-01 16:13:13.206586 (5)       endpoint: Request msg SUBSCRIBE/cseq=16046 (tdta0x9fec200) created.
> 2013-08-01 16:13:13.207025 (5)    dlg0xb56864: Sending Request msg SUBSCRIBE/cseq=16046 (tdta0x9fec200)
> 2013-08-01 16:13:13.207963 (5)   tsx0x9f05864: Transaction created for Request msg SUBSCRIBE/cseq=16045 (tdta0x9fec200)
> 2013-08-01 16:13:13.208828 (5)   tsx0x9f05864: Sending Request msg SUBSCRIBE/cseq=16045 (tdta0x9fec200) in state Null
> 2013-08-01 16:13:13.209576 (5)  sip_resolve.c: Target '81.23.228.129:443' type=TLS resolved to '81.23.228.129:443' type=TLS (TLS transport)
> 2013-08-01 16:13:13.211189 (5)   tsx0x9f05864: State changed from Null to Calling, event=TX_MSG
> 2013-08-01 16:13:13.212033 (5)    dlg0xb56864: Transaction tsx0x9f05864 state changed to Calling
> 2013-08-01 16:13:13.212876 (5) sip_endpoint.c: Processing incoming message: Response msg 200/SUBSCRIBE/cseq=21876 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.214736 (5)    tsx0xb3e064: Incoming Response msg 200/SUBSCRIBE/cseq=21876 (rdata0x9e2cd2c) in state Calling
> 2013-08-01 16:13:13.215399 (5)    tsx0xb3e064: State changed from Calling to Completed, event=RX_MSG
> 2013-08-01 16:13:13.216294 (5)   dlg0x9ed2864: Received Response msg 200/SUBSCRIBE/cseq=21876 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.217065 (5)   dlg0x9ed2864: Transaction tsx0xb3e064 state changed to Completed
> 2013-08-01 16:13:13.217887 (5) evsub0x9ed3014: Will refresh in 295 seconds
> 2013-08-01 16:13:13.218475 (5) evsub0x9ed3014: Updating UAC_REFRESH timer
> 2013-08-01 16:13:13.219144 (5) evsub0x9ed3014: Timer UAC_REFRESH scheduled in 295 seconds
> 2013-08-01 16:13:13.220850 (5)    tsx0xb3e064: Timeout timer event
> 2013-08-01 16:13:13.221375 (5)    tsx0xb3e064: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:13:13.222182 (5)   dlg0x9ed2864: Transaction tsx0xb3e064 state changed to Terminated
> 2013-08-01 16:13:13.223045 (5)    tsx0xb3e064: Timeout timer event
> 2013-08-01 16:13:13.223804 (5)    tsx0xb3e064: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:13:13.224800 (5)   tdta0xb23e00: Destroying txdata Request msg SUBSCRIBE/cseq=21876 (tdta0xb23e00)
> 2013-08-01 16:13:13.225680 (5)    tsx0xb3e064: Transaction destroyed!
> 2013-08-01 16:13:13.226159 (5) sip_endpoint.c: Processing incoming message: Request msg NOTIFY/cseq=2 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.227645 (5)   dlg0x9ed2864: Received Request msg NOTIFY/cseq=2 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.228682 (5)    tsx0xb3e064: Transaction created for Request msg NOTIFY/cseq=2 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.229421 (5)    tsx0xb3e064: Incoming Request msg NOTIFY/cseq=2 (rdata0x9e2cd2c) in state Null
> 2013-08-01 16:13:13.229943 (5)    tsx0xb3e064: State changed from Null to Trying, event=RX_MSG
> 2013-08-01 16:13:13.230724 (5)   dlg0x9ed2864: Transaction tsx0xb3e064 state changed to Trying
> 2013-08-01 16:13:13.232411 (5) evsub0x9ed3014: Will refresh in 295 seconds
> 2013-08-01 16:13:13.233908 (5) evsub0x9ed3014: Updating UAC_REFRESH timer
> 2013-08-01 16:13:13.237148 (5) evsub0x9ed3014: Timer UAC_REFRESH scheduled in 295 seconds
> 2013-08-01 16:13:13.238653 (5)       endpoint: Response msg 200/NOTIFY/cseq=2 (tdta0xb23e00) created
> 2013-08-01 16:13:13.239791 (5)   dlg0x9ed2864: Sending Response msg 200/NOTIFY/cseq=2 (tdta0xb23e00)
> 2013-08-01 16:13:13.240885 (5)    tsx0xb3e064: Sending Response msg 200/NOTIFY/cseq=2 (tdta0xb23e00) in state Trying
> 2013-08-01 16:13:13.242353 (5)    tsx0xb3e064: State changed from Trying to Completed, event=TX_MSG
> 2013-08-01 16:13:13.243120 (5)   dlg0x9ed2864: Transaction tsx0xb3e064 state changed to Completed
> 2013-08-01 16:13:13.243904 (4) evsub0x9ed3014: Subscription state changed ACTIVE --> ACTIVE
> 2013-08-01 16:13:13.346135 (5)    tsx0xb3e064: Timeout timer event
> 2013-08-01 16:13:13.347179 (5)    tsx0xb3e064: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:13:13.348304 (5)   dlg0x9ed2864: Transaction tsx0xb3e064 state changed to Terminated
> 2013-08-01 16:13:13.349491 (5)    tsx0xb3e064: Timeout timer event
> 2013-08-01 16:13:13.350360 (5)    tsx0xb3e064: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:13:13.351221 (5)   tdta0xb23e00: Destroying txdata Response msg 200/NOTIFY/cseq=2 (tdta0xb23e00)
> 2013-08-01 16:13:13.352105 (5)    tsx0xb3e064: Transaction destroyed!
> 2013-08-01 16:13:13.409143 (5) sip_endpoint.c: Processing incoming message: Response msg 401/REGISTER/cseq=5 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.411792 (5)   tsx0x9be6864: Incoming Response msg 401/REGISTER/cseq=5 (rdata0x9e2cd2c) in state Calling
> 2013-08-01 16:13:13.412927 (5)   tsx0x9be6864: State changed from Calling to Completed, event=RX_MSG
> 2013-08-01 16:13:13.414328 (5)    tsx0xb3e064: Transaction created for Request msg REGISTER/cseq=6 (tdta0x9fc2400)
> 2013-08-01 16:13:13.414891 (5)    tsx0xb3e064: Sending Request msg REGISTER/cseq=6 (tdta0x9fc2400) in state Null
> 2013-08-01 16:13:13.415574 (5)  sip_resolve.c: Target '81.23.228.129:443' type=TLS resolved to '81.23.228.129:443' type=TLS (TLS transport)
> 2013-08-01 16:13:13.417044 (5)    tsx0xb3e064: State changed from Null to Calling, event=TX_MSG
> 2013-08-01 16:13:13.418344 (5)   tsx0x9be6864: Timeout timer event
> 2013-08-01 16:13:13.418924 (5)   tsx0x9be6864: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:13:13.419733 (5) sip_endpoint.c: Processing incoming message: Response msg 200/SUBSCRIBE/cseq=16045 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.421597 (5)   tsx0x9f05864: Incoming Response msg 200/SUBSCRIBE/cseq=16045 (rdata0x9e2cd2c) in state Calling
> 2013-08-01 16:13:13.422259 (5)   tsx0x9f05864: State changed from Calling to Completed, event=RX_MSG
> 2013-08-01 16:13:13.423247 (5)    dlg0xb56864: Received Response msg 200/SUBSCRIBE/cseq=16045 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.423734 (5)    dlg0xb56864: Transaction tsx0x9f05864 state changed to Completed
> 2013-08-01 16:13:13.424764 (5) evsub0x13ea214: Will refresh in 295 seconds
> 2013-08-01 16:13:13.425483 (5) evsub0x13ea214: Updating UAC_REFRESH timer
> 2013-08-01 16:13:13.426593 (5) evsub0x13ea214: Timer UAC_REFRESH scheduled in 295 seconds
> 2013-08-01 16:13:13.428141 (5)   tsx0x9be6864: Timeout timer event
> 2013-08-01 16:13:13.428751 (5)   tsx0x9be6864: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:13:13.429817 (5)   tsx0x9be6864: Transaction destroyed!
> 2013-08-01 16:13:13.430184 (5)   tsx0x9f05864: Timeout timer event
> 2013-08-01 16:13:13.430899 (5)   tsx0x9f05864: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:13:13.431848 (5)    dlg0xb56864: Transaction tsx0x9f05864 state changed to Terminated
> 2013-08-01 16:13:13.432537 (5)   tsx0x9f05864: Timeout timer event
> 2013-08-01 16:13:13.433487 (5)   tsx0x9f05864: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:13:13.434292 (5)  tdta0x9fec200: Destroying txdata Request msg SUBSCRIBE/cseq=16045 (tdta0x9fec200)
> 2013-08-01 16:13:13.435190 (5)   tsx0x9f05864: Transaction destroyed!
> 2013-08-01 16:13:13.436214 (5) sip_endpoint.c: Processing incoming message: Request msg NOTIFY/cseq=5 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.437652 (5)   dlg0x9d35264: Received Request msg NOTIFY/cseq=5 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.438442 (5)    tsx0xb1e064: Transaction created for Request msg NOTIFY/cseq=5 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.439257 (5)    tsx0xb1e064: Incoming Request msg NOTIFY/cseq=5 (rdata0x9e2cd2c) in state Null
> 2013-08-01 16:13:13.439917 (5)    tsx0xb1e064: State changed from Null to Trying, event=RX_MSG
> 2013-08-01 16:13:13.440794 (5)   dlg0x9d35264: Transaction tsx0xb1e064 state changed to Trying
> 2013-08-01 16:13:13.442589 (5) evsub0x9cc3e14: Will refresh in 295 seconds
> 2013-08-01 16:13:13.443515 (5) evsub0x9cc3e14: Updating UAC_REFRESH timer
> 2013-08-01 16:13:13.446011 (5) evsub0x9cc3e14: Timer UAC_REFRESH scheduled in 295 seconds
> 2013-08-01 16:13:13.447290 (5)       endpoint: Response msg 200/NOTIFY/cseq=5 (tdta0xb0f000) created
> 2013-08-01 16:13:13.448126 (5)   dlg0x9d35264: Sending Response msg 200/NOTIFY/cseq=5 (tdta0xb0f000)
> 2013-08-01 16:13:13.449370 (5)    tsx0xb1e064: Sending Response msg 200/NOTIFY/cseq=5 (tdta0xb0f000) in state Trying
> 2013-08-01 16:13:13.450491 (5)    tsx0xb1e064: State changed from Trying to Completed, event=TX_MSG
> 2013-08-01 16:13:13.451320 (5)   dlg0x9d35264: Transaction tsx0xb1e064 state changed to Completed
> 2013-08-01 16:13:13.452030 (4) evsub0x9cc3e14: Subscription state changed ACTIVE --> ACTIVE
> 2013-08-01 16:13:13.453901 (5)    tsx0xb1e064: Timeout timer event
> 2013-08-01 16:13:13.454559 (5)    tsx0xb1e064: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:13:13.455219 (5)   dlg0x9d35264: Transaction tsx0xb1e064 state changed to Terminated
> 2013-08-01 16:13:13.455845 (5) sip_endpoint.c: Processing incoming message: Request msg NOTIFY/cseq=4 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.457625 (5)    dlg0xb56864: Received Request msg NOTIFY/cseq=4 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.458520 (5)   tsx0x9baa464: Transaction created for Request msg NOTIFY/cseq=4 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.459112 (5)   tsx0x9baa464: Incoming Request msg NOTIFY/cseq=4 (rdata0x9e2cd2c) in state Null
> 2013-08-01 16:13:13.460037 (5)   tsx0x9baa464: State changed from Null to Trying, event=RX_MSG
> 2013-08-01 16:13:13.460707 (5)    dlg0xb56864: Transaction tsx0x9baa464 state changed to Trying
> 2013-08-01 16:13:13.462640 (5) evsub0x13ea214: Will refresh in 295 seconds
> 2013-08-01 16:13:13.464241 (5) evsub0x13ea214: Updating UAC_REFRESH timer
> 2013-08-01 16:13:13.473699 (5) evsub0x13ea214: Timer UAC_REFRESH scheduled in 295 seconds
> 2013-08-01 16:13:13.474856 (5)       endpoint: Response msg 200/NOTIFY/cseq=4 (tdta0x9f43000) created
> 2013-08-01 16:13:13.476513 (5)    dlg0xb56864: Sending Response msg 200/NOTIFY/cseq=4 (tdta0x9f43000)
> 2013-08-01 16:13:13.477321 (5)   tsx0x9baa464: Sending Response msg 200/NOTIFY/cseq=4 (tdta0x9f43000) in state Trying
> 2013-08-01 16:13:13.478885 (5)   tsx0x9baa464: State changed from Trying to Completed, event=TX_MSG
> 2013-08-01 16:13:13.479854 (5)    dlg0xb56864: Transaction tsx0x9baa464 state changed to Completed
> 2013-08-01 16:13:13.480399 (4) evsub0x13ea214: Subscription state changed ACTIVE --> ACTIVE
> 2013-08-01 16:13:13.581977 (5)    tsx0xb1e064: Timeout timer event
> 2013-08-01 16:13:13.583478 (5)    tsx0xb1e064: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:13:13.584662 (5)   tdta0xb0f000: Destroying txdata Response msg 200/NOTIFY/cseq=5 (tdta0xb0f000)
> 2013-08-01 16:13:13.586224 (5)    tsx0xb1e064: Transaction destroyed!
> 2013-08-01 16:13:13.587184 (5)   tsx0x9baa464: Timeout timer event
> 2013-08-01 16:13:13.588300 (5)   tsx0x9baa464: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:13:13.588980 (5)    dlg0xb56864: Transaction tsx0x9baa464 state changed to Terminated
> 2013-08-01 16:13:13.589786 (5)   tsx0x9baa464: Timeout timer event
> 2013-08-01 16:13:13.590173 (5)   tsx0x9baa464: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:13:13.591052 (5)  tdta0x9f43000: Destroying txdata Response msg 200/NOTIFY/cseq=4 (tdta0x9f43000)
> 2013-08-01 16:13:13.591677 (5)   tsx0x9baa464: Transaction destroyed!
> 2013-08-01 16:13:13.619608 (5) sip_endpoint.c: Processing incoming message: Response msg 200/REGISTER/cseq=6 (rdata0x9e2cd2c)
> 2013-08-01 16:13:13.621024 (5)    tsx0xb3e064: Incoming Response msg 200/REGISTER/cseq=6 (rdata0x9e2cd2c) in state Calling
> 2013-08-01 16:13:13.621938 (5)    tsx0xb3e064: State changed from Calling to Completed, event=RX_MSG
> 2013-08-01 16:13:13.625639 (5)    tsx0xb3e064: Timeout timer event
> 2013-08-01 16:13:13.626445 (5)    tsx0xb3e064: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:13:13.728405 (5)    tsx0xb3e064: Timeout timer event
> 2013-08-01 16:13:13.730544 (5)    tsx0xb3e064: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:13:13.731314 (5)    tsx0xb3e064: Transaction destroyed!
> 2013-08-01 16:13:27.741114 (5)  tlsc0x9d24e14: Sending 4 byte(s) keep-alive to 85.17.186.7:443
> 2013-08-01 16:13:30.753123 (5)  tdta0x9ff6000: Destroying txdata Request msg PUBLISH/cseq=2 (tdta0x9ff6000)
> 2013-08-01 16:13:34.420126 (5)  tdta0x24fc000: Destroying txdata Request msg PUBLISH/cseq=2 (tdta0x24fc000)
> 2013-08-01 16:13:43.150496 (5)  tdta0x24fd000: Destroying txdata Request msg REGISTER/cseq=4 (tdta0x24fd000)
> 2013-08-01 16:14:44.205160 (5)  tlsc0x9e2cc14: Sending 4 byte(s) keep-alive to 81.23.228.129:443
> 2013-08-01 16:14:58.743398 (5)  tlsc0x9d24e14: Sending 4 byte(s) keep-alive to 85.17.186.7:443
> 2013-08-01 16:16:15.207577 (5)  tlsc0x9e2cc14: Sending 4 byte(s) keep-alive to 81.23.228.129:443
> 2013-08-01 16:16:27.290814 (5)       endpoint: Request msg PUBLISH/cseq=1 (tdta0x2d56000) created.
> 2013-08-01 16:16:27.291537 (5)   tsx0x2e08064: Transaction created for Request msg PUBLISH/cseq=1 (tdta0x2d56000)
> 2013-08-01 16:16:27.291839 (5)   tsx0x2e08064: Sending Request msg PUBLISH/cseq=1 (tdta0x2d56000) in state Null
> 2013-08-01 16:16:27.292472 (5)  sip_resolve.c: Target '85.17.186.7:443' type=TLS resolved to '85.17.186.7:443' type=TLS (TLS transport)
> 2013-08-01 16:16:27.293366 (5)   tsx0x2e08064: State changed from Null to Calling, event=TX_MSG
> 2013-08-01 16:16:27.490907 (5) sip_endpoint.c: Processing incoming message: Response msg 407/PUBLISH/cseq=1 (rdata0x9d24f2c)
> 2013-08-01 16:16:27.493492 (5)   tsx0x2e08064: Incoming Response msg 407/PUBLISH/cseq=1 (rdata0x9d24f2c) in state Calling
> 2013-08-01 16:16:27.494080 (5)   tsx0x2e08064: State changed from Calling to Completed, event=RX_MSG
> 2013-08-01 16:16:27.494826 (5)    tsx0xabfe64: Transaction created for Request msg PUBLISH/cseq=2 (tdta0x2d56000)
> 2013-08-01 16:16:27.495421 (5)    tsx0xabfe64: Sending Request msg PUBLISH/cseq=2 (tdta0x2d56000) in state Null
> 2013-08-01 16:16:27.496257 (5)  sip_resolve.c: Target '85.17.186.7:443' type=TLS resolved to '85.17.186.7:443' type=TLS (TLS transport)
> 2013-08-01 16:16:27.497705 (5)    tsx0xabfe64: State changed from Null to Calling, event=TX_MSG
> 2013-08-01 16:16:27.599990 (5)   tsx0x2e08064: Timeout timer event
> 2013-08-01 16:16:27.600698 (5)   tsx0x2e08064: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:16:27.601740 (5)   tsx0x2e08064: Timeout timer event
> 2013-08-01 16:16:27.602483 (5)   tsx0x2e08064: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:16:27.603158 (5)   tsx0x2e08064: Transaction destroyed!
> 2013-08-01 16:16:27.742947 (5) sip_endpoint.c: Processing incoming message: Response msg 200/PUBLISH/cseq=2 (rdata0x9d24f2c)
> 2013-08-01 16:16:27.745191 (5)    tsx0xabfe64: Incoming Response msg 200/PUBLISH/cseq=2 (rdata0x9d24f2c) in state Calling
> 2013-08-01 16:16:27.746325 (5)    tsx0xabfe64: State changed from Calling to Completed, event=RX_MSG
> 2013-08-01 16:16:27.850159 (5)    tsx0xabfe64: Timeout timer event
> 2013-08-01 16:16:27.851235 (5)    tsx0xabfe64: State changed from Completed to Terminated, event=TIMER
> 2013-08-01 16:16:27.852449 (5)    tsx0xabfe64: Timeout timer event
> 2013-08-01 16:16:27.853338 (5)    tsx0xabfe64: State changed from Terminated to Destroyed, event=TIMER
> 2013-08-01 16:16:27.854145 (5)    tsx0xabfe64: Transaction destroyed!
> 2013-08-01 16:16:28.744748 (5)  tlsc0x9d24e14: Sending 4 byte(s) keep-alive to 85.17.186.7:443
> 2013-08-01 16:16:52.668784 (5)  tdta0x9fad800: Destroying txdata Request msg PUBLISH/cseq=2 (tdta0x9fad800)
> 2013-08-01 16:16:57.189236 (5)  tdta0x9e48000: Destroying txdata Request msg PUBLISH/cseq=2 (tdta0x9e48000)
> 
> 
> Mike Reilly, Owner
> Palm Database Solutions
> www.PalmDBS.com
> Solutions at PalmDBS.com
> 858.215.2555 Phone
> 858.345.3922 Fax
> FileMaker 12/11 Certified Developer
> 
> _______________________________________________
> Blink mailing list
> Blink at lists.ag-projects.com
> http://lists.ag-projects.com/mailman/listinfo/blink

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ag-projects.com/pipermail/blink/attachments/20130802/0b661a0d/attachment.html>


More information about the Blink mailing list