<--- SIP read from UDP://XXX.XXX.XXX.XXX:5688 ---> REGISTER sip:mydomain.net SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bKf4f59cd7216de3ca28412ebdb80d039b;rport From: "John Doe" ;tag=1738044141 To: "John Doe" Call-ID: 3602491317@192_168_1_86 CSeq: 2146 REGISTER Contact: Max-Forwards: 70 User-Agent: C470IP021910000000 Expires: 180 Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 <-------------> [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bKf4f59cd7216de3ca28412ebdb80d039b;rport [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: "John Doe" ;tag=1738044141 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: "John Doe" [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2146 REGISTER [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 8 [ 30]: User-Agent: C470IP021910000000 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 9 [ 12]: Expires: 180 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 12 [ 0]: [May 5 20:15:44] VERBOSE[1776] chan_sip.c: --- (12 headers 0 lines) --- [May 5 20:15:44] DEBUG[1776] acl.c: Found IP address for this socket [May 5 20:15:44] DEBUG[1776] chan_sip.c: Target address XXX.XXX.XXX.XXX is not local, substituting externip [May 5 20:15:44] DEBUG[1776] chan_sip.c: Setting SIP_TRANSPORT_UDP with address YYY.YYY.YYY.YYY:5060 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Allocating new SIP dialog for 3602491317@192_168_1_86 - REGISTER (No RTP) [May 5 20:15:44] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 5 20:15:44] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86 [May 5 20:15:44] VERBOSE[1776] chan_sip.c: Sending to XXX.XXX.XXX.XXX : 5688 (NAT) [May 5 20:15:44] VERBOSE[1776] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX.XXX:5688 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bKf4f59cd7216de3ca28412ebdb80d039b;received=XXX.XXX.XXX.XXX;rport=5688 From: "John Doe" ;tag=1738044141 To: "John Doe" ;tag=as7cef0651 Call-ID: 3602491317@192_168_1_86 CSeq: 2146 REGISTER Server: EasyNUM Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="4d3eb05c" Content-Length: 0 <------------> [May 5 20:15:44] DEBUG[1776] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for XXX.XXX.XXX.XXX:5688 [May 5 20:15:44] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog '3602491317@192_168_1_86' in 32000 ms (Method: REGISTER) [May 5 20:15:44] VERBOSE[1776] chan_sip.c: <--- SIP read from UDP://202.90.86.123:1068 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK071dab34;rport=5060 From: "asterisk" ;tag=as23466699 To: ;tag=460490096 Call-ID: 0e653d77462d6faf6f1c7b962bbb5fa2@YYY.YYY.YYY.YYY CSeq: 102 OPTIONS Contact: Supported: replaces Allow-Events: refer Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag Accept-Encoding: identity Accept-Language: en Content-Length: 0 <--- SIP read from UDP://XXX.XXX.XXX.XXX:5688 ---> REGISTER sip:mydomain.net SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK3f334386f3fcea0f3be53b7f783d88c;rport From: "John Doe" ;tag=1738044141 To: "John Doe" Call-ID: 3602491317@192_168_1_86 CSeq: 2147 REGISTER Contact: Authorization: Digest username="60070", realm="asterisk", algorithm=MD5, uri="sip:mydomain.net", nonce="4d3eb05c", response="f17ad882b021bd517e58a509b47ff3f1" Max-Forwards: 70 User-Agent: C470IP021910000000 Expires: 180 Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 <-------------> [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 1 [ 87]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK3f334386f3fcea0f3be53b7f783d88c;rport [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: "John Doe" ;tag=1738044141 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: "John Doe" [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2147 REGISTER [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 7 [157]: Authorization: Digest username="60070", realm="asterisk", algorithm=MD5, uri="sip:mydomain.net", nonce="4d3eb05c", response="f17ad882b021bd517e58a509b47ff3f1" [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 8 [ 16]: Max-Forwards: 70 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 9 [ 30]: User-Agent: C470IP021910000000 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 10 [ 12]: Expires: 180 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 11 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 13 [ 0]: [May 5 20:15:44] VERBOSE[1776] chan_sip.c: --- (13 headers 0 lines) --- [May 5 20:15:44] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 5 20:15:44] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86 [May 5 20:15:44] VERBOSE[1776] chan_sip.c: Sending to XXX.XXX.XXX.XXX : 5688 (NAT) [May 5 20:15:44] DEBUG[1776] chan_sip.c: Allocating new SIP dialog for 15baa4a421fe50fe39bf0efd1b1e2913@127.0.0.1 - OPTIONS (No RTP) [May 5 20:15:44] DEBUG[1776] acl.c: Found IP address for this socket [May 5 20:15:44] DEBUG[1776] chan_sip.c: Target address XXX.XXX.XXX.XXX is not local, substituting externip [May 5 20:15:44] DEBUG[1776] chan_sip.c: Setting SIP_TRANSPORT_UDP with address YYY.YYY.YYY.YYY:5060 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Initializing initreq for method OPTIONS - callid 6d5cd6e4614786c666f8f89a7ee396ae@YYY.YYY.YYY.YYY [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 0 [ 44]: OPTIONS sip:60070@XXX.XXX.XXX.XXX:5688 SIP/2.0 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 1 [ 66]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK38fd7a31;rport [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 3 [ 62]: From: "asterisk" ;tag=as5751f653 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 4 [ 34]: To: [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 5 [ 39]: Contact: [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 6 [ 57]: Call-ID: 6d5cd6e4614786c666f8f89a7ee396ae@YYY.YYY.YYY.YYY [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 8 [ 19]: User-Agent: EasyNUM [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 9 [ 35]: Date: Thu, 06 May 2010 06:15:44 GMT [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [May 5 20:15:44] VERBOSE[1776] chan_sip.c: Reliably Transmitting (NAT) to XXX.XXX.XXX.XXX:5688: OPTIONS sip:60070@XXX.XXX.XXX.XXX:5688 SIP/2.0 Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK38fd7a31;rport Max-Forwards: 70 From: "asterisk" ;tag=as5751f653 To: Contact: Call-ID: 6d5cd6e4614786c666f8f89a7ee396ae@YYY.YYY.YYY.YYY CSeq: 102 OPTIONS User-Agent: EasyNUM Date: Thu, 06 May 2010 06:15:44 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 --- [May 5 20:15:44] DEBUG[1776] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #2917 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for XXX.XXX.XXX.XXX:5688 [May 5 20:15:44] VERBOSE[1776] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX.XXX:5688 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK3f334386f3fcea0f3be53b7f783d88c;received=XXX.XXX.XXX.XXX;rport=5688 From: "John Doe" ;tag=1738044141 To: "John Doe" ;tag=as7cef0651 Call-ID: 3602491317@192_168_1_86 CSeq: 2147 REGISTER Server: EasyNUM Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Expires: 180 Contact: ;expires=180 Date: Thu, 06 May 2010 06:15:44 GMT Content-Length: 0 <------------> [May 5 20:15:44] DEBUG[1776] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for XXX.XXX.XXX.XXX:5688 [May 5 20:15:44] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog '3602491317@192_168_1_86' in 32000 ms (Method: REGISTER) [May 5 20:15:44] DEBUG[1680] devicestate.c: No provider found, checking channel drivers for SIP - 60070 [May 5 20:15:44] DEBUG[1680] chan_sip.c: Checking device state for peer 60070 [May 5 20:15:44] DEBUG[1680] devicestate.c: Changing state for SIP/60070 - state 1 (Not in use) [May 5 20:15:44] DEBUG[1680] devicestate.c: device 'SIP/60070' state '1' [May 5 20:15:44] DEBUG[1761] app_queue.c: Device 'SIP/60070' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 5 20:15:44] VERBOSE[1776] chan_sip.c: <--- SIP read from UDP://XXX.XXX.XXX.XXX:5688 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK38fd7a31;rport=5060 From: "asterisk" ;tag=as5751f653 To: ;tag=4105476699 Call-ID: 6d5cd6e4614786c666f8f89a7ee396ae@YYY.YYY.YYY.YYY CSeq: 102 OPTIONS Contact: Supported: replaces Allow-Events: refer Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag Accept-Encoding: identity Accept-Language: en Content-Length: 0 <-------------> [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 1 [ 71]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK38fd7a31;rport=5060 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 2 [ 62]: From: "asterisk" ;tag=as5751f653 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 3 [ 49]: To: ;tag=4105476699 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 4 [ 57]: Call-ID: 6d5cd6e4614786c666f8f89a7ee396ae@YYY.YYY.YYY.YYY [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 5 [ 17]: CSeq: 102 OPTIONS [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 7 [ 19]: Supported: replaces [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 8 [ 19]: Allow-Events: refer [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 9 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 10 [ 97]: Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 11 [ 25]: Accept-Encoding: identity [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 12 [ 19]: Accept-Language: en [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 13 [ 17]: Content-Length: 0 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 14 [ 0]: [May 5 20:15:44] VERBOSE[1776] chan_sip.c: --- (14 headers 0 lines) --- [May 5 20:15:44] DEBUG[1776] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #2917 [May 5 20:15:44] DEBUG[1776] chan_sip.c: Stopping retransmission on '6d5cd6e4614786c666f8f89a7ee396ae@YYY.YYY.YYY.YYY' of Request 102: Match Found [May 5 20:15:44] DEBUG[1776] chan_sip.c: Destroying SIP dialog 6d5cd6e4614786c666f8f89a7ee396ae@YYY.YYY.YYY.YYY [May 5 20:15:44] VERBOSE[1776] chan_sip.c: Really destroying SIP dialog '6d5cd6e4614786c666f8f89a7ee396ae@YYY.YYY.YYY.YYY' Method: OPTIONS [May 5 20:15:44] VERBOSE[1776] chan_sip.c: [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 0 [ 44]: OPTIONS sip:60070@XXX.XXX.XXX.XXX:5688 SIP/2.0 [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 1 [ 66]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK408e5281;rport [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 3 [ 62]: From: "asterisk" ;tag=as2e2eba50 [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 4 [ 34]: To: [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 5 [ 39]: Contact: [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 6 [ 57]: Call-ID: 30262435108f6e1d1a13b2ca076a0785@YYY.YYY.YYY.YYY [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 8 [ 19]: User-Agent: EasyNUM [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 9 [ 35]: Date: Thu, 06 May 2010 06:16:44 GMT [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [May 5 20:16:44] VERBOSE[1776] chan_sip.c: Reliably Transmitting (NAT) to XXX.XXX.XXX.XXX:5688: OPTIONS sip:60070@XXX.XXX.XXX.XXX:5688 SIP/2.0 Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK408e5281;rport Max-Forwards: 70 From: "asterisk" ;tag=as2e2eba50 To: Contact: Call-ID: 30262435108f6e1d1a13b2ca076a0785@YYY.YYY.YYY.YYY CSeq: 102 OPTIONS User-Agent: EasyNUM Date: Thu, 06 May 2010 06:16:44 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 --- [May 5 20:16:44] DEBUG[1776] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #2998 [May 5 20:16:44] DEBUG[1776] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for XXX.XXX.XXX.XXX:5688 [May 5 20:16:44] DEBUG[1776] chan_sip.c: Allocating new SIP dialog for 183e08d5456e8aa125b6ace676c1cbc0@127.0.0.1 - OPTIONS (No RTP) [May 5 20:16:44] DEBUG[1776] acl.c: Found IP address for this socket [May 5 20:16:44] DEBUG[1776] chan_sip.c: Target address 202.90.78.12 is not local, substituting externip [May 5 20:16:44] DEBUG[1776] chan_sip.c: Setting SIP_TRANSPORT_UDP with address YYY.YYY.YYY.YYY:5060 [May 5 20:16:44] DEBUG[1776] chan_sip.c: Initializing initreq for method OPTIONS - callid 4abc442a62658df64ade28457896e814@YYY.YYY.YYY.YYY [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 0 [ 43]: OPTIONS sip:60100@202.90.78.12:5060 SIP/2.0 [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 1 [ 66]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK45286e7b;rport [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 3 [ 62]: From: "asterisk" ;tag=as5876953d [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 4 [ 33]: To: [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 5 [ 39]: Contact: [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 6 [ 57]: Call-ID: 4abc442a62658df64ade28457896e814@YYY.YYY.YYY.YYY [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 8 [ 19]: User-Agent: EasyNUM [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 9 [ 35]: Date: Thu, 06 May 2010 06:16:44 GMT [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0 <--- SIP read from UDP://XXX.XXX.XXX.XXX:5688 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK408e5281;rport=5060 From: "asterisk" ;tag=as2e2eba50 To: ;tag=865471299 Call-ID: 30262435108f6e1d1a13b2ca076a0785@YYY.YYY.YYY.YYY CSeq: 102 OPTIONS Contact: Supported: replaces Allow-Events: refer Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag Accept-Encoding: identity Accept-Language: en Content-Length: 0 <-------------> [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 1 [ 71]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK408e5281;rport=5060 [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 2 [ 62]: From: "asterisk" ;tag=as2e2eba50 [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 3 [ 48]: To: ;tag=865471299 [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 4 [ 57]: Call-ID: 30262435108f6e1d1a13b2ca076a0785@YYY.YYY.YYY.YYY [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 5 [ 17]: CSeq: 102 OPTIONS [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 7 [ 19]: Supported: replaces [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 8 [ 19]: Allow-Events: refer [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 9 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 10 [ 97]: Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 11 [ 25]: Accept-Encoding: identity [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 12 [ 19]: Accept-Language: en [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 13 [ 17]: Content-Length: 0 [May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 14 [ 0]: [May 5 20:16:44] VERBOSE[1776] chan_sip.c: --- (14 headers 0 lines) --- [May 5 20:16:44] DEBUG[1776] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #2998 [May 5 20:16:44] DEBUG[1776] chan_sip.c: Stopping retransmission on '30262435108f6e1d1a13b2ca076a0785@YYY.YYY.YYY.YYY' of Request 102: Match Found [May 5 20:16:44] DEBUG[1776] chan_sip.c: Destroying SIP dialog 30262435108f6e1d1a13b2ca076a0785@YYY.YYY.YYY.YYY [May 5 20:16:44] VERBOSE[1776] chan_sip.c: Really destroying SIP dialog '30262435108f6e1d1a13b2ca076a0785@YYY.YYY.YYY.YYY' Method: OPTIONS [May 5 20:16:45] VERBOSE[1776] chan_sip.c: [May 5 20:17:44] DEBUG[1776] chan_sip.c: Allocating new SIP dialog for 6ecf70765da6a4d63e0ab140610b0a4e@127.0.0.1 - OPTIONS (No RTP) [May 5 20:17:44] DEBUG[1776] acl.c: Found IP address for this socket [May 5 20:17:44] DEBUG[1776] chan_sip.c: Target address XXX.XXX.XXX.XXX is not local, substituting externip [May 5 20:17:44] DEBUG[1776] chan_sip.c: Setting SIP_TRANSPORT_UDP with address YYY.YYY.YYY.YYY:5060 [May 5 20:17:44] DEBUG[1776] chan_sip.c: Initializing initreq for method OPTIONS - callid 41605cf27ec2fd163f28dadf41cb9d78@YYY.YYY.YYY.YYY [May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 0 [ 44]: OPTIONS sip:60070@XXX.XXX.XXX.XXX:5688 SIP/2.0 [May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 1 [ 66]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK28a4c2b8;rport [May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 3 [ 62]: From: "asterisk" ;tag=as02ca55e2 [May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 4 [ 34]: To: [May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 5 [ 39]: Contact: [May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 6 [ 57]: Call-ID: 41605cf27ec2fd163f28dadf41cb9d78@YYY.YYY.YYY.YYY [May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 8 [ 19]: User-Agent: EasyNUM [May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 9 [ 35]: Date: Thu, 06 May 2010 06:17:44 GMT [May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [May 5 20:17:44] VERBOSE[1776] chan_sip.c: Reliably Transmitting (NAT) to XXX.XXX.XXX.XXX:5688: OPTIONS sip:60070@XXX.XXX.XXX.XXX:5688 SIP/2.0 Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK28a4c2b8;rport Max-Forwards: 70 From: "asterisk" ;tag=as02ca55e2 To: Contact: Call-ID: 41605cf27ec2fd163f28dadf41cb9d78@YYY.YYY.YYY.YYY CSeq: 102 OPTIONS User-Agent: EasyNUM Date: Thu, 06 May 2010 06:17:44 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 --- [May 5 20:17:44] DEBUG[1776] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #3051 [May 5 20:17:44] DEBUG[1776] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for XXX.XXX.XXX.XXX:5688 [May 5 20:17:45] VERBOSE[1776] chan_sip.c: <--- SIP read from UDP://XXX.XXX.XXX.XXX:5688 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK28a4c2b8;rport=5060 From: "asterisk" ;tag=as02ca55e2 To: ;tag=2926259639 Call-ID: 41605cf27ec2fd163f28dadf41cb9d78@YYY.YYY.YYY.YYY CSeq: 102 OPTIONS Contact: Supported: replaces Allow-Events: refer Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag Accept-Encoding: identity Accept-Language: en Content-Length: 0 <-------------> [May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 1 [ 71]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK28a4c2b8;rport=5060 [May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 2 [ 62]: From: "asterisk" ;tag=as02ca55e2 [May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 3 [ 49]: To: ;tag=2926259639 [May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 4 [ 57]: Call-ID: 41605cf27ec2fd163f28dadf41cb9d78@YYY.YYY.YYY.YYY [May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 5 [ 17]: CSeq: 102 OPTIONS [May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: [May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 7 [ 19]: Supported: replaces [May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 8 [ 19]: Allow-Events: refer [May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 9 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 10 [ 97]: Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag [May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 11 [ 25]: Accept-Encoding: identity [May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 12 [ 19]: Accept-Language: en [May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 13 [ 17]: Content-Length: 0 [May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 14 [ 0]: [May 5 20:17:45] VERBOSE[1776] chan_sip.c: --- (14 headers 0 lines) --- [May 5 20:17:45] DEBUG[1776] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #3051 [May 5 20:17:45] DEBUG[1776] chan_sip.c: Stopping retransmission on '41605cf27ec2fd163f28dadf41cb9d78@YYY.YYY.YYY.YYY' of Request 102: Match Found [May 5 20:17:45] DEBUG[1776] chan_sip.c: Destroying SIP dialog 41605cf27ec2fd163f28dadf41cb9d78@YYY.YYY.YYY.YYY [May 5 20:17:45] VERBOSE[1776] chan_sip.c: Really destroying SIP dialog '41605cf27ec2fd163f28dadf41cb9d78@YYY.YYY.YYY.YYY' Method: OPTIONS [May 5 20:17:45] VERBOSE[1776] chan_sip.c: [May 5 20:18:59] DEBUG[1776] chan_sip.c: Setting SIP_TRANSPORT_UDP with address YYY.YYY.YYY.YYY:5060 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Initializing initreq for method OPTIONS - callid 59e59e6958364d436fa475d34ea916a8@YYY.YYY.YYY.YYY [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 0 [ 44]: OPTIONS sip:60070@XXX.XXX.XXX.XXX:5688 SIP/2.0 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 1 [ 66]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK53abb295;rport [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 3 [ 62]: From: "asterisk" ;tag=as2c10d6b0 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 4 [ 34]: To: [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 5 [ 39]: Contact: [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 6 [ 57]: Call-ID: 59e59e6958364d436fa475d34ea916a8@YYY.YYY.YYY.YYY [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 8 [ 19]: User-Agent: EasyNUM [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 9 [ 35]: Date: Thu, 06 May 2010 06:18:59 GMT [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [May 5 20:18:59] VERBOSE[1776] chan_sip.c: Reliably Transmitting (NAT) to XXX.XXX.XXX.XXX:5688: OPTIONS sip:60070@XXX.XXX.XXX.XXX:5688 SIP/2.0 Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK53abb295;rport Max-Forwards: 70 From: "asterisk" ;tag=as2c10d6b0 To: Contact: Call-ID: 59e59e6958364d436fa475d34ea916a8@YYY.YYY.YYY.YYY CSeq: 102 OPTIONS User-Agent: EasyNUM Date: Thu, 06 May 2010 06:18:59 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 [May 5 20:18:59] DEBUG[1680] devicestate.c: No provider found, checking channel drivers for SIP - 60070 [May 5 20:18:59] DEBUG[1680] chan_sip.c: Checking device state for peer 60070 [May 5 20:18:59] DEBUG[1680] devicestate.c: Changing state for SIP/60070 - state 5 (Unavailable) [May 5 20:18:59] DEBUG[1680] devicestate.c: device 'SIP/60070' state '5' [May 5 20:18:59] DEBUG[1761] app_queue.c: Device 'SIP/60070' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [May 5 20:18:59] VERBOSE[1776] chan_sip.c: <--- SIP read from UDP://XXX.XXX.XXX.XXX:5688 ---> REGISTER sip:mydomain.net SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport From: "John Doe" ;tag=1973517905 To: "John Doe" Call-ID: 3602491317@192_168_1_86 CSeq: 2148 REGISTER Contact: Max-Forwards: 70 User-Agent: C470IP021910000000 Expires: 180 Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 <-------------> [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: "John Doe" ;tag=1973517905 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: "John Doe" [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2148 REGISTER [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 8 [ 30]: User-Agent: C470IP021910000000 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 9 [ 12]: Expires: 180 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 12 [ 0]: [May 5 20:18:59] VERBOSE[1776] chan_sip.c: --- (12 headers 0 lines) --- [May 5 20:18:59] DEBUG[1776] acl.c: Found IP address for this socket [May 5 20:18:59] DEBUG[1776] chan_sip.c: Target address XXX.XXX.XXX.XXX is not local, substituting externip [May 5 20:18:59] DEBUG[1776] chan_sip.c: Setting SIP_TRANSPORT_UDP with address YYY.YYY.YYY.YYY:5060 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Allocating new SIP dialog for 3602491317@192_168_1_86 - REGISTER (No RTP) [May 5 20:18:59] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 5 20:18:59] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86 [May 5 20:18:59] VERBOSE[1776] chan_sip.c: Sending to XXX.XXX.XXX.XXX : 5688 (NAT) [May 5 20:18:59] VERBOSE[1776] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX.XXX:5688 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;received=XXX.XXX.XXX.XXX;rport=5688 From: "John Doe" ;tag=1973517905 To: "John Doe" ;tag=as7a152f8c Call-ID: 3602491317@192_168_1_86 CSeq: 2148 REGISTER Server: EasyNUM Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="410049f4" Content-Length: 0 <------------> [May 5 20:18:59] DEBUG[1776] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for XXX.XXX.XXX.XXX:5688 [May 5 20:18:59] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog '3602491317@192_168_1_86' in 32000 ms (Method: REGISTER) [May 5 20:18:59] VERBOSE[1776] chan_sip.c: [May 5 20:18:59] VERBOSE[1776] chan_sip.c: <--- SIP read from UDP://XXX.XXX.XXX.XXX:5688 ---> REGISTER sip:mydomain.net SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport From: "John Doe" ;tag=1973517905 To: "John Doe" Call-ID: 3602491317@192_168_1_86 CSeq: 2148 REGISTER Contact: Max-Forwards: 70 User-Agent: C470IP021910000000 Expires: 180 Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 <-------------> [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: "John Doe" ;tag=1973517905 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: "John Doe" [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2148 REGISTER [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 8 [ 30]: User-Agent: C470IP021910000000 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 9 [ 12]: Expires: 180 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 12 [ 0]: [May 5 20:18:59] VERBOSE[1776] chan_sip.c: --- (12 headers 0 lines) --- [May 5 20:18:59] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 5 20:18:59] DEBUG[1776] chan_sip.c: Ignoring SIP message because of retransmit (REGISTER Seqno 2148, ours 2148) [May 5 20:18:59] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86 [May 5 20:18:59] VERBOSE[1776] chan_sip.c: Sending to XXX.XXX.XXX.XXX : 5688 (NAT) [May 5 20:18:59] VERBOSE[1776] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX.XXX:5688 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;received=XXX.XXX.XXX.XXX;rport=5688 From: "John Doe" ;tag=1973517905 To: "John Doe" ;tag=as7a152f8c Call-ID: 3602491317@192_168_1_86 CSeq: 2148 REGISTER Server: EasyNUM Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="410049f4" Content-Length: 0 <------------> [May 5 20:18:59] DEBUG[1776] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for XXX.XXX.XXX.XXX:5688 [May 5 20:18:59] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog '3602491317@192_168_1_86' in 32000 ms (Method: REGISTER) [May 5 20:18:59] VERBOSE[1776] chan_sip.c: [May 5 20:18:59] VERBOSE[1776] chan_sip.c: <--- SIP read from UDP://XXX.XXX.XXX.XXX:5688 ---> REGISTER sip:mydomain.net SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport From: "John Doe" ;tag=1973517905 To: "John Doe" Call-ID: 3602491317@192_168_1_86 CSeq: 2148 REGISTER Contact: Max-Forwards: 70 User-Agent: C470IP021910000000 Expires: 180 Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 <-------------> [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: "John Doe" ;tag=1973517905 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: "John Doe" [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2148 REGISTER [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 8 [ 30]: User-Agent: C470IP021910000000 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 9 [ 12]: Expires: 180 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 12 [ 0]: [May 5 20:18:59] VERBOSE[1776] chan_sip.c: --- (12 headers 0 lines) --- [May 5 20:18:59] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 5 20:18:59] DEBUG[1776] chan_sip.c: Ignoring SIP message because of retransmit (REGISTER Seqno 2148, ours 2148) [May 5 20:18:59] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86 [May 5 20:18:59] VERBOSE[1776] chan_sip.c: Sending to XXX.XXX.XXX.XXX : 5688 (NAT) [May 5 20:18:59] VERBOSE[1776] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX.XXX:5688 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;received=XXX.XXX.XXX.XXX;rport=5688 From: "John Doe" ;tag=1973517905 To: "John Doe" ;tag=as7a152f8c Call-ID: 3602491317@192_168_1_86 CSeq: 2148 REGISTER Server: EasyNUM Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="410049f4" Content-Length: 0 <------------> [May 5 20:18:59] DEBUG[1776] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for XXX.XXX.XXX.XXX:5688 [May 5 20:18:59] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog '3602491317@192_168_1_86' in 32000 ms (Method: REGISTER) [May 5 20:18:59] VERBOSE[1776] chan_sip.c: <--- SIP read from UDP://XXX.XXX.XXX.XXX:5688 ---> REGISTER sip:mydomain.net SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport From: "John Doe" ;tag=1973517905 To: "John Doe" Call-ID: 3602491317@192_168_1_86 CSeq: 2148 REGISTER Contact: Max-Forwards: 70 User-Agent: C470IP021910000000 Expires: 180 Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 <-------------> [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: "John Doe" ;tag=1973517905 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: "John Doe" [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2148 REGISTER [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 8 [ 30]: User-Agent: C470IP021910000000 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 9 [ 12]: Expires: 180 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 12 [ 0]: [May 5 20:18:59] VERBOSE[1776] chan_sip.c: --- (12 headers 0 lines) --- [May 5 20:18:59] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 5 20:18:59] DEBUG[1776] chan_sip.c: Ignoring SIP message because of retransmit (REGISTER Seqno 2148, ours 2148) [May 5 20:18:59] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86 [May 5 20:18:59] VERBOSE[1776] chan_sip.c: Sending to XXX.XXX.XXX.XXX : 5688 (NAT) [May 5 20:18:59] VERBOSE[1776] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX.XXX:5688 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;received=XXX.XXX.XXX.XXX;rport=5688 From: "John Doe" ;tag=1973517905 To: "John Doe" ;tag=as7a152f8c Call-ID: 3602491317@192_168_1_86 CSeq: 2148 REGISTER Server: EasyNUM Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="410049f4" Content-Length: 0 <------------> [May 5 20:18:59] DEBUG[1776] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for XXX.XXX.XXX.XXX:5688 [May 5 20:18:59] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog '3602491317@192_168_1_86' in 32000 ms (Method: REGISTER) [May 5 20:19:05] DEBUG[1776] chan_sip.c: SIP TIMER: Not rescheduling id #3089:OPTIONS (Method 3) (No timer T1) [May 5 20:19:05] VERBOSE[1776] chan_sip.c: Retransmitting #1 (NAT) to XXX.XXX.XXX.XXX:5688: OPTIONS sip:60070@XXX.XXX.XXX.XXX:5688 SIP/2.0 Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK53abb295;rport Max-Forwards: 70 From: "asterisk" ;tag=as2c10d6b0 To: Contact: Call-ID: 59e59e6958364d436fa475d34ea916a8@YYY.YYY.YYY.YYY CSeq: 102 OPTIONS User-Agent: EasyNUM Date: Thu, 06 May 2010 06:18:59 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 --- [May 5 20:19:05] DEBUG[1776] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for XXX.XXX.XXX.XXX:5688 [May 5 20:19:05] DEBUG[1776] chan_sip.c: SIP TIMER: Not rescheduling id #3091:OPTIONS (Method 3) (No timer T1) [May 5 20:19:05] NOTICE[1776] chan_sip.c: Peer '60070' is now UNREACHABLE! Last qualify: 126 [May 5 20:19:05] DEBUG[1680] devicestate.c: No provider found, checking channel drivers for SIP - 60070 [May 5 20:19:05] DEBUG[1680] chan_sip.c: Checking device state for peer 60070 [May 5 20:19:05] DEBUG[1680] devicestate.c: Changing state for SIP/60070 - state 5 (Unavailable) [May 5 20:19:05] DEBUG[1680] devicestate.c: device 'SIP/60070' state '5' [May 5 20:19:05] DEBUG[1761] app_queue.c: Device 'SIP/60070' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [May 5 20:19:05] VERBOSE[1776] chan_sip.c: <--- SIP read from UDP://XXX.XXX.XXX.XXX:5688 ---> REGISTER sip:mydomain.net SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport From: "John Doe" ;tag=1973517905 To: "John Doe" Call-ID: 3602491317@192_168_1_86 CSeq: 2148 REGISTER Contact: Max-Forwards: 70 User-Agent: C470IP021910000000 Expires: 180 Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 <-------------> [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: "John Doe" ;tag=1973517905 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: "John Doe" [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2148 REGISTER [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 8 [ 30]: User-Agent: C470IP021910000000 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 9 [ 12]: Expires: 180 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 12 [ 0]: [May 5 20:19:05] VERBOSE[1776] chan_sip.c: --- (12 headers 0 lines) --- [May 5 20:19:05] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 5 20:19:05] DEBUG[1776] chan_sip.c: Ignoring SIP message because of retransmit (REGISTER Seqno 2148, ours 2148) [May 5 20:19:05] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86 [May 5 20:19:05] VERBOSE[1776] chan_sip.c: Sending to XXX.XXX.XXX.XXX : 5688 (NAT) [May 5 20:19:05] VERBOSE[1776] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX.XXX:5688 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;received=XXX.XXX.XXX.XXX;rport=5688 From: "John Doe" ;tag=1973517905 To: "John Doe" ;tag=as7a152f8c Call-ID: 3602491317@192_168_1_86 CSeq: 2148 REGISTER Server: EasyNUM Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="410049f4" Content-Length: 0 <------------> [May 5 20:19:05] DEBUG[1776] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for XXX.XXX.XXX.XXX:5688 [May 5 20:19:05] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog '3602491317@192_168_1_86' in 32000 ms (Method: REGISTER) [May 5 20:19:05] VERBOSE[1776] chan_sip.c: <--- SIP read from UDP://XXX.XXX.XXX.XXX:5688 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK53abb295;rport=5060 From: "asterisk" ;tag=as2c10d6b0 To: ;tag=1881441311 Call-ID: 59e59e6958364d436fa475d34ea916a8@YYY.YYY.YYY.YYY CSeq: 102 OPTIONS Contact: Supported: replaces Allow-Events: refer Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag Accept-Encoding: identity Accept-Language: en Content-Length: 0 <-------------> [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 1 [ 71]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK53abb295;rport=5060 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 2 [ 62]: From: "asterisk" ;tag=as2c10d6b0 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 3 [ 49]: To: ;tag=1881441311 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 4 [ 57]: Call-ID: 59e59e6958364d436fa475d34ea916a8@YYY.YYY.YYY.YYY [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 5 [ 17]: CSeq: 102 OPTIONS [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 7 [ 19]: Supported: replaces [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 8 [ 19]: Allow-Events: refer [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 9 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 10 [ 97]: Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 11 [ 25]: Accept-Encoding: identity [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 12 [ 19]: Accept-Language: en [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 13 [ 17]: Content-Length: 0 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 14 [ 0]: [May 5 20:19:05] VERBOSE[1776] chan_sip.c: --- (14 headers 0 lines) --- [May 5 20:19:05] DEBUG[1776] chan_sip.c: That's odd... Got a response on a call we dont know about. Callid 59e59e6958364d436fa475d34ea916a8@YYY.YYY.YYY.YYY [May 5 20:19:05] DEBUG[1776] chan_sip.c: Invalid SIP message - rejected , no callid, len 607 [May 5 20:19:05] VERBOSE[1776] chan_sip.c: <--- SIP read from UDP://XXX.XXX.XXX.XXX:5688 ---> REGISTER sip:mydomain.net SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;rport From: "John Doe" ;tag=1973517905 To: "John Doe" Call-ID: 3602491317@192_168_1_86 CSeq: 2149 REGISTER Contact: Authorization: Digest username="60070", realm="asterisk", algorithm=MD5, uri="sip:mydomain.net", nonce="410049f4", response="537735e7f7b26b4e712654a84fea42f7" Max-Forwards: 70 User-Agent: C470IP021910000000 Expires: 180 Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 <-------------> [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;rport [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: "John Doe" ;tag=1973517905 [May 5 20:19:05] DEBUG[1761] app_queue.c: Device 'SIP/60078' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: "John Doe" [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2149 REGISTER [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 7 [157]: Authorization: Digest username="60070", realm="asterisk", algorithm=MD5, uri="sip:mydomain.net", nonce="410049f4", response="537735e7f7b26b4e712654a84fea42f7" [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 8 [ 16]: Max-Forwards: 70 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 9 [ 30]: User-Agent: C470IP021910000000 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 10 [ 12]: Expires: 180 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 11 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 13 [ 0]: [May 5 20:19:05] VERBOSE[1776] chan_sip.c: --- (13 headers 0 lines) --- [May 5 20:19:05] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 5 20:19:05] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86 [May 5 20:19:05] VERBOSE[1776] chan_sip.c: Sending to XXX.XXX.XXX.XXX : 5688 (NAT) [May 5 20:19:05] VERBOSE[1776] chan_sip.c: -- Registered SIP '60070' at XXX.XXX.XXX.XXX port 5688 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Allocating new SIP dialog for 67356ab041d35c6818cc76f466b3bbc2@127.0.0.1 - OPTIONS (No RTP) [May 5 20:19:05] DEBUG[1776] acl.c: Found IP address for this socket [May 5 20:19:05] DEBUG[1776] chan_sip.c: Target address XXX.XXX.XXX.XXX is not local, substituting externip [May 5 20:19:05] DEBUG[1776] chan_sip.c: Setting SIP_TRANSPORT_UDP with address YYY.YYY.YYY.YYY:5060 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Initializing initreq for method OPTIONS - callid 638cfdc20bd6f99b39cb77c0524a8337@YYY.YYY.YYY.YYY [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 0 [ 44]: OPTIONS sip:60070@XXX.XXX.XXX.XXX:5688 SIP/2.0 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 1 [ 66]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK0811af14;rport [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 3 [ 62]: From: "asterisk" ;tag=as60d28455 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 4 [ 34]: To: [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 5 [ 39]: Contact: [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 6 [ 57]: Call-ID: 638cfdc20bd6f99b39cb77c0524a8337@YYY.YYY.YYY.YYY [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 8 [ 19]: User-Agent: EasyNUM [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 9 [ 35]: Date: Thu, 06 May 2010 06:19:05 GMT [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [May 5 20:19:05] VERBOSE[1776] chan_sip.c: Reliably Transmitting (NAT) to XXX.XXX.XXX.XXX:5688: OPTIONS sip:60070@XXX.XXX.XXX.XXX:5688 SIP/2.0 Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK0811af14;rport Max-Forwards: 70 From: "asterisk" ;tag=as60d28455 To: Contact: Call-ID: 638cfdc20bd6f99b39cb77c0524a8337@YYY.YYY.YYY.YYY CSeq: 102 OPTIONS User-Agent: EasyNUM Date: Thu, 06 May 2010 06:19:05 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 --- [May 5 20:19:05] DEBUG[1776] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #3183 [May 5 20:19:05] DEBUG[1776] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for XXX.XXX.XXX.XXX:5688 [May 5 20:19:05] VERBOSE[1776] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX.XXX:5688 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;received=XXX.XXX.XXX.XXX;rport=5688 From: "John Doe" ;tag=1973517905 To: "John Doe" ;tag=as7a152f8c Call-ID: 3602491317@192_168_1_86 CSeq: 2149 REGISTER Server: EasyNUM Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Expires: 180 Contact: ;expires=180 Date: Thu, 06 May 2010 06:19:05 GMT Content-Length: 0 <------------> [May 5 20:19:05] DEBUG[1776] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for XXX.XXX.XXX.XXX:5688 [May 5 20:19:05] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog '3602491317@192_168_1_86' in 32000 ms (Method: REGISTER) [May 5 20:19:05] DEBUG[1680] devicestate.c: No provider found, checking channel drivers for SIP - 60070 [May 5 20:19:05] DEBUG[1680] chan_sip.c: Checking device state for peer 60070 [May 5 20:19:05] DEBUG[1680] devicestate.c: Changing state for SIP/60070 - state 5 (Unavailable) [May 5 20:19:05] DEBUG[1680] devicestate.c: device 'SIP/60070' state '5' [May 5 20:19:05] DEBUG[1761] app_queue.c: Device 'SIP/60070' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [May 5 20:19:06] VERBOSE[1776] chan_sip.c: <--- SIP read from UDP://XXX.XXX.XXX.XXX:5688 ---> REGISTER sip:mydomain.net SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;rport From: "John Doe" ;tag=1973517905 To: "John Doe" Call-ID: 3602491317@192_168_1_86 CSeq: 2149 REGISTER Contact: Authorization: Digest username="60070", realm="asterisk", algorithm=MD5, uri="sip:mydomain.net", nonce="410049f4", response="537735e7f7b26b4e712654a84fea42f7" Max-Forwards: 70 User-Agent: C470IP021910000000 Expires: 180 Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 <-------------> [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;rport [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: "John Doe" ;tag=1973517905 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: "John Doe" [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2149 REGISTER [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 7 [157]: Authorization: Digest username="60070", realm="asterisk", algorithm=MD5, uri="sip:mydomain.net", nonce="410049f4", response="537735e7f7b26b4e712654a84fea42f7" [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 8 [ 16]: Max-Forwards: 70 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 9 [ 30]: User-Agent: C470IP021910000000 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 10 [ 12]: Expires: 180 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 11 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 13 [ 0]: [May 5 20:19:06] VERBOSE[1776] chan_sip.c: --- (13 headers 0 lines) --- [May 5 20:19:06] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 5 20:19:06] DEBUG[1776] chan_sip.c: Ignoring SIP message because of retransmit (REGISTER Seqno 2149, ours 2149) [May 5 20:19:06] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86 [May 5 20:19:06] VERBOSE[1776] chan_sip.c: Sending to XXX.XXX.XXX.XXX : 5688 (NAT) [May 5 20:19:06] NOTICE[1776] chan_sip.c: Correct auth, but based on stale nonce received from '"John Doe" ' [May 5 20:19:06] VERBOSE[1776] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX.XXX:5688 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;received=XXX.XXX.XXX.XXX;rport=5688 From: "John Doe" ;tag=1973517905 To: "John Doe" ;tag=as7a152f8c Call-ID: 3602491317@192_168_1_86 CSeq: 2149 REGISTER Server: EasyNUM Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="4e71df6a", stale=true Content-Length: 0 <------------> [May 5 20:19:06] DEBUG[1776] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for XXX.XXX.XXX.XXX:5688 [May 5 20:19:06] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog '3602491317@192_168_1_86' in 32000 ms (Method: REGISTER) [May 5 20:19:06] VERBOSE[1776] chan_sip.c: <--- SIP read from UDP://XXX.XXX.XXX.XXX:5688 ---> REGISTER sip:mydomain.net SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;rport From: "John Doe" ;tag=1973517905 To: "John Doe" Call-ID: 3602491317@192_168_1_86 CSeq: 2149 REGISTER Contact: Authorization: Digest username="60070", realm="asterisk", algorithm=MD5, uri="sip:mydomain.net", nonce="410049f4", response="537735e7f7b26b4e712654a84fea42f7" Max-Forwards: 70 User-Agent: C470IP021910000000 Expires: 180 Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 <-------------> [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;rport [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: "John Doe" ;tag=1973517905 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: "John Doe" [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2149 REGISTER [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 7 [157]: Authorization: Digest username="60070", realm="asterisk", algorithm=MD5, uri="sip:mydomain.net", nonce="410049f4", response="537735e7f7b26b4e712654a84fea42f7" [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 8 [ 16]: Max-Forwards: 70 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 9 [ 30]: User-Agent: C470IP021910000000 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 10 [ 12]: Expires: 180 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 11 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 13 [ 0]: [May 5 20:19:06] VERBOSE[1776] chan_sip.c: --- (13 headers 0 lines) --- [May 5 20:19:06] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 5 20:19:06] DEBUG[1776] chan_sip.c: Ignoring SIP message because of retransmit (REGISTER Seqno 2149, ours 2149) [May 5 20:19:06] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86 [May 5 20:19:06] VERBOSE[1776] chan_sip.c: Sending to XXX.XXX.XXX.XXX : 5688 (NAT) [May 5 20:19:06] NOTICE[1776] chan_sip.c: Correct auth, but based on stale nonce received from '"John Doe" ' [May 5 20:19:06] VERBOSE[1776] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX.XXX:5688 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;received=XXX.XXX.XXX.XXX;rport=5688 From: "John Doe" ;tag=1973517905 To: "John Doe" ;tag=as7a152f8c Call-ID: 3602491317@192_168_1_86 CSeq: 2149 REGISTER Server: EasyNUM Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="4e71df6a", stale=true Content-Length: 0 <------------> [May 5 20:19:06] DEBUG[1776] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for XXX.XXX.XXX.XXX:5688 [May 5 20:19:06] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog '3602491317@192_168_1_86' in 32000 ms (Method: REGISTER) [May 5 20:19:06] VERBOSE[1776] chan_sip.c: <--- SIP read from UDP://XXX.XXX.XXX.XXX:5688 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK53abb295;rport=5060 From: "asterisk" ;tag=as2c10d6b0 To: ;tag=1881441311 Call-ID: 59e59e6958364d436fa475d34ea916a8@YYY.YYY.YYY.YYY CSeq: 102 OPTIONS Contact: Supported: replaces Allow-Events: refer Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag Accept-Encoding: identity Accept-Language: en Content-Length: 0 <-------------> [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 1 [ 71]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK53abb295;rport=5060 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 2 [ 62]: From: "asterisk" ;tag=as2c10d6b0 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 3 [ 49]: To: ;tag=1881441311 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 4 [ 57]: Call-ID: 59e59e6958364d436fa475d34ea916a8@YYY.YYY.YYY.YYY [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 5 [ 17]: CSeq: 102 OPTIONS [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 7 [ 19]: Supported: replaces [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 8 [ 19]: Allow-Events: refer [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 9 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 10 [ 97]: Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 11 [ 25]: Accept-Encoding: identity [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 12 [ 19]: Accept-Language: en [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 13 [ 17]: Content-Length: 0 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 14 [ 0]: [May 5 20:19:06] VERBOSE[1776] chan_sip.c: --- (14 headers 0 lines) --- [May 5 20:19:06] DEBUG[1776] chan_sip.c: That's odd... Got a response on a call we dont know about. Callid 59e59e6958364d436fa475d34ea916a8@YYY.YYY.YYY.YYY [May 5 20:19:06] DEBUG[1776] chan_sip.c: Invalid SIP message - rejected , no callid, len 607 [May 5 20:19:06] VERBOSE[1776] chan_sip.c: <--- SIP read from UDP://XXX.XXX.XXX.XXX:5688 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK0811af14;rport=5060 From: "asterisk" ;tag=as60d28455 To: ;tag=4034954609 Call-ID: 638cfdc20bd6f99b39cb77c0524a8337@YYY.YYY.YYY.YYY CSeq: 102 OPTIONS Contact: Supported: replaces Allow-Events: refer Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag Accept-Encoding: identity Accept-Language: en Content-Length: 0 <-------------> [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 1 [ 71]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5060;branch=z9hG4bK0811af14;rport=5060 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 2 [ 62]: From: "asterisk" ;tag=as60d28455 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 3 [ 49]: To: ;tag=4034954609 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 4 [ 57]: Call-ID: 638cfdc20bd6f99b39cb77c0524a8337@YYY.YYY.YYY.YYY [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 5 [ 17]: CSeq: 102 OPTIONS [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 7 [ 19]: Supported: replaces [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 8 [ 19]: Allow-Events: refer [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 9 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 10 [ 97]: Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 11 [ 25]: Accept-Encoding: identity [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 12 [ 19]: Accept-Language: en [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 13 [ 17]: Content-Length: 0 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 14 [ 0]: [May 5 20:19:06] VERBOSE[1776] chan_sip.c: --- (14 headers 0 lines) --- [May 5 20:19:06] DEBUG[1776] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #3183 [May 5 20:19:06] DEBUG[1776] chan_sip.c: Stopping retransmission on '638cfdc20bd6f99b39cb77c0524a8337@YYY.YYY.YYY.YYY' of Request 102: Match Found [May 5 20:19:06] NOTICE[1776] chan_sip.c: Peer '60070' is now Reachable. (789ms / 2000ms) [May 5 20:19:06] DEBUG[1776] chan_sip.c: Destroying SIP dialog 638cfdc20bd6f99b39cb77c0524a8337@YYY.YYY.YYY.YYY [May 5 20:19:06] DEBUG[1680] devicestate.c: No provider found, checking channel drivers for SIP - 60070 [May 5 20:19:06] DEBUG[1680] chan_sip.c: Checking device state for peer 60070 [May 5 20:19:06] DEBUG[1680] devicestate.c: Changing state for SIP/60070 - state 1 (Not in use) [May 5 20:19:06] VERBOSE[1776] chan_sip.c: Really destroying SIP dialog '638cfdc20bd6f99b39cb77c0524a8337@YYY.YYY.YYY.YYY' Method: OPTIONS [May 5 20:19:06] DEBUG[1680] devicestate.c: device 'SIP/60070' state '1' [May 5 20:19:06] DEBUG[1761] app_queue.c: Device 'SIP/60070' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.