[Oct 27 18:56:02] VERBOSE[25284] config.c: == Parsing '/etc/asterisk/logger.conf': [Oct 27 18:56:02] DEBUG[25284] config.c: Parsing /etc/asterisk/logger.conf [Oct 27 18:56:02] VERBOSE[25284] config.c: == Found [Oct 27 18:56:03] VERBOSE[25284] asterisk.c: -- Remote UNIX connection disconnected [Oct 27 18:56:27] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5061 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-dc2a664 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42140 REGISTER Max-Forwards: 70 Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="1c60404d",uri="sip:10.15.1.1:6010",algorithm=MD5,response="75488b4be911041b8c1100806ade1ce3" Contact: Sipura-PSTN ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 1 [ 56]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-dc2a664 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 3 [ 48]: To: Sipura-PSTN [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42140 REGISTER [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 7 [161]: Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="1c60404d",uri="sip:10.15.1.1:6010",algorithm=MD5,response="75488b4be911041b8c1100806ade1ce3" [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 8 [ 67]: Contact: Sipura-PSTN ;expires=180 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 18:56:27] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 18:56:27] DEBUG[1676] acl.c: Found IP address for this socket [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.15.1.1:6010 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Allocating new SIP dialog for 4948b0a5-f0ed3274@10.15.1.190 - REGISTER (No RTP) [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 18:56:27] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5061 (no NAT) [Oct 27 18:56:27] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5061 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-dc2a664;received=10.15.1.190 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN ;tag=as7187e867 Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42140 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="18b4aa4e" Content-Length: 0 <------------> [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 1 [ 77]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-dc2a664;received=10.15.1.190 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 3 [ 63]: To: Sipura-PSTN ;tag=as7187e867 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42140 REGISTER [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 9 [ 74]: WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="18b4aa4e" [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 11 [ 0]: [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.15.1.190:5061 [Oct 27 18:56:27] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog '4948b0a5-f0ed3274@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 18:56:27] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5061 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-3d80835 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42141 REGISTER Max-Forwards: 70 Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="18b4aa4e",uri="sip:10.15.1.1:6010",algorithm=MD5,response="9f505b7f455401ccf2941b293bfb6711" Contact: Sipura-PSTN ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 1 [ 56]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-3d80835 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 3 [ 48]: To: Sipura-PSTN [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42141 REGISTER [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 7 [161]: Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="18b4aa4e",uri="sip:10.15.1.1:6010",algorithm=MD5,response="9f505b7f455401ccf2941b293bfb6711" [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 8 [ 67]: Contact: Sipura-PSTN ;expires=180 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 18:56:27] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 18:56:27] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5061 (no NAT) [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Store REGISTER's Contact header for call routing. [Oct 27 18:56:27] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5061 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-3d80835;received=10.15.1.190 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN ;tag=as7187e867 Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42141 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Expires: 180 Contact: ;expires=180 Date: Wed, 27 Oct 2010 20:56:27 GMT Content-Length: 0 <------------> [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 1 [ 77]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-3d80835;received=10.15.1.190 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 3 [ 63]: To: Sipura-PSTN ;tag=as7187e867 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42141 REGISTER [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 9 [ 12]: Expires: 180 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 10 [ 55]: Contact: ;expires=180 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 11 [ 35]: Date: Wed, 27 Oct 2010 20:56:27 GMT [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 18:56:27] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.15.1.190:5061 [Oct 27 18:56:27] DEBUG[1564] devicestate.c: No provider found, checking channel drivers for SIP - sipura-pstn [Oct 27 18:56:27] DEBUG[1564] chan_sip.c: Checking device state for peer sipura-pstn [Oct 27 18:56:27] DEBUG[1564] devicestate.c: Changing state for SIP/sipura-pstn - state 1 (Not in use) [Oct 27 18:56:27] DEBUG[1564] devicestate.c: device 'SIP/sipura-pstn' state '1' [Oct 27 18:56:27] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog '4948b0a5-f0ed3274@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 18:56:28] VERBOSE[1554] asterisk.c: -- Remote UNIX connection [Oct 27 18:56:29] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5060 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-c1f83233 From: User Name ;tag=c16edcec69b432fao0 To: User Name Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28827 REGISTER Max-Forwards: 70 Authorization: Digest username="101",realm="asterisk",nonce="4d8afa7b",uri="sip:10.15.1.1:6010",algorithm=MD5,response="25c080fc2c24caeb764807013e1c2a45" Contact: User Name ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-c1f83233 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 3 [ 41]: To: User Name [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28827 REGISTER [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 7 [153]: Authorization: Digest username="101",realm="asterisk",nonce="4d8afa7b",uri="sip:10.15.1.1:6010",algorithm=MD5,response="25c080fc2c24caeb764807013e1c2a45" [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 8 [ 60]: Contact: User Name ;expires=180 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 18:56:29] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 18:56:29] DEBUG[1676] acl.c: Found IP address for this socket [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.15.1.1:6010 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Allocating new SIP dialog for ac8b890c-d8d25d39@10.15.1.190 - REGISTER (No RTP) [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid ac8b890c-d8d25d39@10.15.1.190 [Oct 27 18:56:29] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5060 (no NAT) [Oct 27 18:56:29] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-c1f83233;received=10.15.1.190 From: User Name ;tag=c16edcec69b432fao0 To: User Name ;tag=as04d01e0c Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28827 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="07b322fb" Content-Length: 0 <------------> [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-c1f83233;received=10.15.1.190 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 3 [ 56]: To: User Name ;tag=as04d01e0c [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28827 REGISTER [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 9 [ 74]: WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="07b322fb" [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 11 [ 0]: [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.15.1.190:5060 [Oct 27 18:56:29] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 18:56:29] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5060 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-be2840d8 From: User Name ;tag=c16edcec69b432fao0 To: User Name Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28828 REGISTER Max-Forwards: 70 Authorization: Digest username="101",realm="asterisk",nonce="07b322fb",uri="sip:10.15.1.1:6010",algorithm=MD5,response="88067481db05c5745009d699a95000e2" Contact: User Name ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-be2840d8 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 3 [ 41]: To: User Name [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28828 REGISTER [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 7 [153]: Authorization: Digest username="101",realm="asterisk",nonce="07b322fb",uri="sip:10.15.1.1:6010",algorithm=MD5,response="88067481db05c5745009d699a95000e2" [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 8 [ 60]: Contact: User Name ;expires=180 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 18:56:29] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid ac8b890c-d8d25d39@10.15.1.190 [Oct 27 18:56:29] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5060 (no NAT) [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Store REGISTER's Contact header for call routing. [Oct 27 18:56:29] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-be2840d8;received=10.15.1.190 From: User Name ;tag=c16edcec69b432fao0 To: User Name ;tag=as04d01e0c Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28828 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Expires: 180 Contact: ;expires=180 Date: Wed, 27 Oct 2010 20:56:29 GMT Content-Length: 0 <------------> [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-be2840d8;received=10.15.1.190 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 3 [ 56]: To: User Name ;tag=as04d01e0c [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28828 REGISTER [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 9 [ 12]: Expires: 180 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 10 [ 47]: Contact: ;expires=180 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 11 [ 35]: Date: Wed, 27 Oct 2010 20:56:29 GMT [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 18:56:29] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.15.1.190:5060 [Oct 27 18:56:29] DEBUG[1564] devicestate.c: No provider found, checking channel drivers for SIP - 101 [Oct 27 18:56:29] DEBUG[1564] chan_sip.c: Checking device state for peer 101 [Oct 27 18:56:29] DEBUG[1564] devicestate.c: Changing state for SIP/101 - state 1 (Not in use) [Oct 27 18:56:29] DEBUG[1564] devicestate.c: device 'SIP/101' state '1' [Oct 27 18:56:29] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 18:56:59] DEBUG[1676] chan_sip.c: Auto destroying SIP dialog '4948b0a5-f0ed3274@10.15.1.190' [Oct 27 18:56:59] DEBUG[1676] chan_sip.c: Destroying SIP dialog 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 18:56:59] VERBOSE[1676] chan_sip.c: Really destroying SIP dialog '4948b0a5-f0ed3274@10.15.1.190' Method: REGISTER [Oct 27 18:57:01] DEBUG[1676] chan_sip.c: Auto destroying SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' [Oct 27 18:57:01] DEBUG[1676] chan_sip.c: Destroying SIP dialog ac8b890c-d8d25d39@10.15.1.190 [Oct 27 18:57:01] VERBOSE[1676] chan_sip.c: Really destroying SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' Method: REGISTER [Oct 27 18:59:24] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5061 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-2b675e2b From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42142 REGISTER Max-Forwards: 70 Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="18b4aa4e",uri="sip:10.15.1.1:6010",algorithm=MD5,response="9f505b7f455401ccf2941b293bfb6711" Contact: Sipura-PSTN ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-2b675e2b [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 3 [ 48]: To: Sipura-PSTN [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42142 REGISTER [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 7 [161]: Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="18b4aa4e",uri="sip:10.15.1.1:6010",algorithm=MD5,response="9f505b7f455401ccf2941b293bfb6711" [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 8 [ 67]: Contact: Sipura-PSTN ;expires=180 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 18:59:24] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 18:59:24] DEBUG[1676] acl.c: Found IP address for this socket [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.15.1.1:6010 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Allocating new SIP dialog for 4948b0a5-f0ed3274@10.15.1.190 - REGISTER (No RTP) [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 18:59:24] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5061 (no NAT) [Oct 27 18:59:24] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5061 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-2b675e2b;received=10.15.1.190 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN ;tag=as5e565643 Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42142 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="766c8451" Content-Length: 0 <------------> [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-2b675e2b;received=10.15.1.190 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 3 [ 63]: To: Sipura-PSTN ;tag=as5e565643 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42142 REGISTER [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 9 [ 74]: WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="766c8451" [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 11 [ 0]: [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.15.1.190:5061 [Oct 27 18:59:24] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog '4948b0a5-f0ed3274@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 18:59:24] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5061 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-1aa364a8 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42143 REGISTER Max-Forwards: 70 Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="766c8451",uri="sip:10.15.1.1:6010",algorithm=MD5,response="7bfa92aeadadb4dfb001fd9a39d36dff" Contact: Sipura-PSTN ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-1aa364a8 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 3 [ 48]: To: Sipura-PSTN [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42143 REGISTER [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 7 [161]: Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="766c8451",uri="sip:10.15.1.1:6010",algorithm=MD5,response="7bfa92aeadadb4dfb001fd9a39d36dff" [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 8 [ 67]: Contact: Sipura-PSTN ;expires=180 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 18:59:24] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 18:59:24] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5061 (no NAT) [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Store REGISTER's Contact header for call routing. [Oct 27 18:59:24] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5061 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-1aa364a8;received=10.15.1.190 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN ;tag=as5e565643 Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42143 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Expires: 180 Contact: ;expires=180 Date: Wed, 27 Oct 2010 20:59:24 GMT Content-Length: 0 <------------> [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-1aa364a8;received=10.15.1.190 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 3 [ 63]: To: Sipura-PSTN ;tag=as5e565643 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42143 REGISTER [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 9 [ 12]: Expires: 180 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 10 [ 55]: Contact: ;expires=180 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 11 [ 35]: Date: Wed, 27 Oct 2010 20:59:24 GMT [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 18:59:24] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.15.1.190:5061 [Oct 27 18:59:24] DEBUG[1564] devicestate.c: No provider found, checking channel drivers for SIP - sipura-pstn [Oct 27 18:59:24] DEBUG[1564] chan_sip.c: Checking device state for peer sipura-pstn [Oct 27 18:59:24] DEBUG[1564] devicestate.c: Changing state for SIP/sipura-pstn - state 1 (Not in use) [Oct 27 18:59:24] DEBUG[1564] devicestate.c: device 'SIP/sipura-pstn' state '1' [Oct 27 18:59:24] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog '4948b0a5-f0ed3274@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 18:59:26] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5060 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-f25a0d86 From: User Name ;tag=c16edcec69b432fao0 To: User Name Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28829 REGISTER Max-Forwards: 70 Authorization: Digest username="101",realm="asterisk",nonce="07b322fb",uri="sip:10.15.1.1:6010",algorithm=MD5,response="88067481db05c5745009d699a95000e2" Contact: User Name ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-f25a0d86 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 3 [ 41]: To: User Name [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28829 REGISTER [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 7 [153]: Authorization: Digest username="101",realm="asterisk",nonce="07b322fb",uri="sip:10.15.1.1:6010",algorithm=MD5,response="88067481db05c5745009d699a95000e2" [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 8 [ 60]: Contact: User Name ;expires=180 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 18:59:26] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 18:59:26] DEBUG[1676] acl.c: Found IP address for this socket [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.15.1.1:6010 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Allocating new SIP dialog for ac8b890c-d8d25d39@10.15.1.190 - REGISTER (No RTP) [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid ac8b890c-d8d25d39@10.15.1.190 [Oct 27 18:59:26] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5060 (no NAT) [Oct 27 18:59:26] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-f25a0d86;received=10.15.1.190 From: User Name ;tag=c16edcec69b432fao0 To: User Name ;tag=as0a4651f1 Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28829 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="74198a46" Content-Length: 0 <------------> [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-f25a0d86;received=10.15.1.190 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 3 [ 56]: To: User Name ;tag=as0a4651f1 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28829 REGISTER [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 9 [ 74]: WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="74198a46" [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 11 [ 0]: [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.15.1.190:5060 [Oct 27 18:59:26] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 18:59:26] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5060 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-bc526cab From: User Name ;tag=c16edcec69b432fao0 To: User Name Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28830 REGISTER Max-Forwards: 70 Authorization: Digest username="101",realm="asterisk",nonce="74198a46",uri="sip:10.15.1.1:6010",algorithm=MD5,response="8473170ed4041e8b4953ef9eadba9fdc" Contact: User Name ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-bc526cab [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 3 [ 41]: To: User Name [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28830 REGISTER [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 7 [153]: Authorization: Digest username="101",realm="asterisk",nonce="74198a46",uri="sip:10.15.1.1:6010",algorithm=MD5,response="8473170ed4041e8b4953ef9eadba9fdc" [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 8 [ 60]: Contact: User Name ;expires=180 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 18:59:26] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid ac8b890c-d8d25d39@10.15.1.190 [Oct 27 18:59:26] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5060 (no NAT) [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Store REGISTER's Contact header for call routing. [Oct 27 18:59:26] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-bc526cab;received=10.15.1.190 From: User Name ;tag=c16edcec69b432fao0 To: User Name ;tag=as0a4651f1 Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28830 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Expires: 180 Contact: ;expires=180 Date: Wed, 27 Oct 2010 20:59:26 GMT Content-Length: 0 <------------> [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-bc526cab;received=10.15.1.190 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 3 [ 56]: To: User Name ;tag=as0a4651f1 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28830 REGISTER [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 9 [ 12]: Expires: 180 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 10 [ 47]: Contact: ;expires=180 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 11 [ 35]: Date: Wed, 27 Oct 2010 20:59:26 GMT [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 18:59:26] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.15.1.190:5060 [Oct 27 18:59:26] DEBUG[1564] devicestate.c: No provider found, checking channel drivers for SIP - 101 [Oct 27 18:59:26] DEBUG[1564] chan_sip.c: Checking device state for peer 101 [Oct 27 18:59:26] DEBUG[1564] devicestate.c: Changing state for SIP/101 - state 1 (Not in use) [Oct 27 18:59:26] DEBUG[1564] devicestate.c: device 'SIP/101' state '1' [Oct 27 18:59:26] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 18:59:56] DEBUG[1676] chan_sip.c: Auto destroying SIP dialog '4948b0a5-f0ed3274@10.15.1.190' [Oct 27 18:59:56] DEBUG[1676] chan_sip.c: Destroying SIP dialog 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 18:59:56] VERBOSE[1676] chan_sip.c: Really destroying SIP dialog '4948b0a5-f0ed3274@10.15.1.190' Method: REGISTER [Oct 27 18:59:58] DEBUG[1676] chan_sip.c: Auto destroying SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' [Oct 27 18:59:58] DEBUG[1676] chan_sip.c: Destroying SIP dialog ac8b890c-d8d25d39@10.15.1.190 [Oct 27 18:59:58] VERBOSE[1676] chan_sip.c: Really destroying SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' Method: REGISTER [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5060 ---> INVITE sip:1510xxxyyyy@10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-8a76300b From: User Name ;tag=efa7c5d9b99178dbo0 To: Remote-Party-ID: User Name ;screen=yes;party=calling Call-ID: 8c39b031-fa14d263@10.15.1.190 CSeq: 101 INVITE Max-Forwards: 70 Contact: User Name Expires: 240 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 443 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Content-Type: application/sdp v=0 o=- 19233377 19233377 IN IP4 10.15.1.190 s=- c=IN IP4 10.15.1.190 t=0 0 m=audio 16468 RTP/AVP 18 0 2 4 8 96 97 98 100 101 a=rtpmap:18 G729/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:4 G723/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:96 G726-40/8000 a=rtpmap:97 G726-24/8000 a=rtpmap:98 G726-16/8000 a=rtpmap:100 NSE/8000 a=fmtp:100 192-193 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv <-------------> [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 0 [ 45]: INVITE sip:1510xxxyyyy@10.15.1.1:6010 SIP/2.0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-8a76300b [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=efa7c5d9b99178dbo0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 3 [ 36]: To: [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 4 [ 79]: Remote-Party-ID: User Name ;screen=yes;party=calling [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 5 [ 38]: Call-ID: 8c39b031-fa14d263@10.15.1.190 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 6 [ 16]: CSeq: 101 INVITE [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 8 [ 48]: Contact: User Name [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 9 [ 12]: Expires: 240 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 10 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 11 [ 19]: Content-Length: 443 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 12 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 13 [ 19]: Supported: x-sipura [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 14 [ 29]: Content-Type: application/sdp [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 15 [ 0]: [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 0 [ 3]: v=0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 1 [ 40]: o=- 19233377 19233377 IN IP4 10.15.1.190 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 2 [ 3]: s=- [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 3 [ 20]: c=IN IP4 10.15.1.190 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 4 [ 5]: t=0 0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 5 [ 49]: m=audio 16468 RTP/AVP 18 0 2 4 8 96 97 98 100 101 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 6 [ 21]: a=rtpmap:18 G729/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 7 [ 20]: a=rtpmap:0 PCMU/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 8 [ 23]: a=rtpmap:2 G726-32/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 9 [ 20]: a=rtpmap:4 G723/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 10 [ 20]: a=rtpmap:8 PCMA/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 11 [ 24]: a=rtpmap:96 G726-40/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 12 [ 24]: a=rtpmap:97 G726-24/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 13 [ 24]: a=rtpmap:98 G726-16/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 14 [ 21]: a=rtpmap:100 NSE/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 15 [ 18]: a=fmtp:100 192-193 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 16 [ 33]: a=rtpmap:101 telephone-event/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 17 [ 15]: a=fmtp:101 0-15 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 18 [ 10]: a=ptime:20 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 19 [ 10]: a=sendrecv [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: --- (15 headers 20 lines) --- [Oct 27 19:02:17] DEBUG[1676] acl.c: Found IP address for this socket [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.15.1.1:6010 [Oct 27 19:02:17] VERBOSE[1676] netsock.c: == Using SIP RTP CoS mark 5 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Setting NAT on RTP to Off [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Allocating new SIP dialog for 8c39b031-fa14d263@10.15.1.190 - INVITE (With RTP) [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Begin: parsing SIP "Supported: x-sipura" [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Found SIP option: -x-sipura- [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Found private SIP option, not supported: x-sipura [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5060 (no NAT) [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Initializing initreq for method INVITE - callid 8c39b031-fa14d263@10.15.1.190 [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Using INVITE request as basis request - 8c39b031-fa14d263@10.15.1.190 [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found peer '101' for '101' from 10.15.1.190:5060 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Setting NAT on RTP to Off [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: <--- Reliably Transmitting (no NAT) to 10.15.1.190:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-8a76300b;received=10.15.1.190 From: User Name ;tag=efa7c5d9b99178dbo0 To: ;tag=as22ee20c4 Call-ID: 8c39b031-fa14d263@10.15.1.190 CSeq: 101 INVITE Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="6a9237d6" Content-Length: 0 <------------> [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-8a76300b;received=10.15.1.190 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=efa7c5d9b99178dbo0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 3 [ 51]: To: ;tag=as22ee20c4 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 8c39b031-fa14d263@10.15.1.190 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 5 [ 16]: CSeq: 101 INVITE [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 9 [ 74]: WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="6a9237d6" [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 11 [ 0]: [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #8244 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.15.1.190:5060 [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog '8c39b031-fa14d263@10.15.1.190' in 32000 ms (Method: INVITE) [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5060 ---> ACK sip:1510xxxyyyy@10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-8a76300b From: User Name ;tag=efa7c5d9b99178dbo0 To: ;tag=as22ee20c4 Call-ID: 8c39b031-fa14d263@10.15.1.190 CSeq: 101 ACK Max-Forwards: 70 Contact: User Name User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 <-------------> [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 0 [ 42]: ACK sip:1510xxxyyyy@10.15.1.1:6010 SIP/2.0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-8a76300b [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=efa7c5d9b99178dbo0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 3 [ 51]: To: ;tag=as22ee20c4 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 8c39b031-fa14d263@10.15.1.190 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 5 [ 13]: CSeq: 101 ACK [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 7 [ 48]: Contact: User Name [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 8 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 10 [ 0]: [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: --- (10 headers 0 lines) --- [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #8244 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Stopping retransmission on '8c39b031-fa14d263@10.15.1.190' of Response 101: Match Found [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5060 ---> INVITE sip:1510xxxyyyy@10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-41052c93 From: User Name ;tag=efa7c5d9b99178dbo0 To: Remote-Party-ID: User Name ;screen=yes;party=calling Call-ID: 8c39b031-fa14d263@10.15.1.190 CSeq: 102 INVITE Max-Forwards: 70 Authorization: Digest username="101",realm="asterisk",nonce="6a9237d6",uri="sip:1510xxxyyyy@10.15.1.1:6010",algorithm=MD5,response="239320af00e0a7ed6357a3be3c0b8413" Contact: User Name Expires: 240 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 443 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Content-Type: application/sdp v=0 o=- 19233377 19233377 IN IP4 10.15.1.190 s=- c=IN IP4 10.15.1.190 t=0 0 m=audio 16468 RTP/AVP 18 0 2 4 8 96 97 98 100 101 a=rtpmap:18 G729/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:4 G723/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:96 G726-40/8000 a=rtpmap:97 G726-24/8000 a=rtpmap:98 G726-16/8000 a=rtpmap:100 NSE/8000 a=fmtp:100 192-193 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv <-------------> [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 0 [ 45]: INVITE sip:1510xxxyyyy@10.15.1.1:6010 SIP/2.0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-41052c93 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=efa7c5d9b99178dbo0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 3 [ 36]: To: [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 4 [ 79]: Remote-Party-ID: User Name ;screen=yes;party=calling [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 5 [ 38]: Call-ID: 8c39b031-fa14d263@10.15.1.190 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 6 [ 16]: CSeq: 102 INVITE [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 8 [165]: Authorization: Digest username="101",realm="asterisk",nonce="6a9237d6",uri="sip:1510xxxyyyy@10.15.1.1:6010",algorithm=MD5,response="239320af00e0a7ed6357a3be3c0b8413" [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 9 [ 48]: Contact: User Name [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 10 [ 12]: Expires: 240 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 11 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Content-Length: 443 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 13 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 14 [ 19]: Supported: x-sipura [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 15 [ 29]: Content-Type: application/sdp [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 16 [ 0]: [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 0 [ 3]: v=0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 1 [ 40]: o=- 19233377 19233377 IN IP4 10.15.1.190 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 2 [ 3]: s=- [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 3 [ 20]: c=IN IP4 10.15.1.190 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 4 [ 5]: t=0 0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 5 [ 49]: m=audio 16468 RTP/AVP 18 0 2 4 8 96 97 98 100 101 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 6 [ 21]: a=rtpmap:18 G729/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 7 [ 20]: a=rtpmap:0 PCMU/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 8 [ 23]: a=rtpmap:2 G726-32/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 9 [ 20]: a=rtpmap:4 G723/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 10 [ 20]: a=rtpmap:8 PCMA/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 11 [ 24]: a=rtpmap:96 G726-40/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 12 [ 24]: a=rtpmap:97 G726-24/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 13 [ 24]: a=rtpmap:98 G726-16/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 14 [ 21]: a=rtpmap:100 NSE/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 15 [ 18]: a=fmtp:100 192-193 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 16 [ 33]: a=rtpmap:101 telephone-event/8000 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 17 [ 15]: a=fmtp:101 0-15 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 18 [ 10]: a=ptime:20 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Body 19 [ 10]: a=sendrecv [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: --- (16 headers 20 lines) --- [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5060 (no NAT) [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Initializing initreq for method INVITE - callid 8c39b031-fa14d263@10.15.1.190 [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Using INVITE request as basis request - 8c39b031-fa14d263@10.15.1.190 [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found peer '101' for '101' from 10.15.1.190:5060 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Setting NAT on RTP to Off [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing session-level SDP o=- 19233377 19233377 IN IP4 10.15.1.190... UNSUPPORTED. [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing session-level SDP s=-... UNSUPPORTED. [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing session-level SDP c=IN IP4 10.15.1.190... OK. [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found RTP audio format 18 [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found RTP audio format 0 [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found RTP audio format 2 [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found RTP audio format 4 [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found RTP audio format 8 [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found RTP audio format 96 [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found RTP audio format 97 [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found RTP audio format 98 [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found RTP audio format 100 [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found RTP audio format 101 [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found audio description format G729 for ID 18 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found audio description format PCMU for ID 0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found audio description format G726-32 for ID 2 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:2 G726-32/8000... OK. [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found audio description format G723 for ID 4 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:4 G723/8000... OK. [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found audio description format PCMA for ID 8 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found audio description format G726-40 for ID 96 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:96 G726-40/8000... OK. [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found audio description format G726-24 for ID 97 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:97 G726-24/8000... OK. [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found audio description format G726-16 for ID 98 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:98 G726-16/8000... OK. [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found audio description format NSE for ID 100 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:100 NSE/8000... OK. [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing media-level (audio) SDP a=fmtp:100 192-193... UNSUPPORTED. [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Found audio description format telephone-event for ID 101 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED. [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing media-level (audio) SDP a=ptime:20... OK. [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Capabilities: us - 0x4 (ulaw), peer - audio=0x100d0d (g723|ulaw|alaw|g726|g729|ilbc|h263p)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x4 (ulaw) [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Peer audio RTP is at port 10.15.1.190:16468 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: We're settling with these formats: 0x4 (ulaw) [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Checking SIP call limits for device 101 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Updating call counter for incoming call [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: Looking for 1510xxxyyyy in phones (domain 10.15.1.1) [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: *** Our native formats are 0x4 (ulaw) [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: *** Joint capabilities are 0x4 (ulaw) [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: *** Our capabilities are 0x4 (ulaw) [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: This channel will not be able to handle video. [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: build_route: Contact hop: User Name [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: list_route: hop: [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: SIP/101-0000000d: New call is still down.... Trying... [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-41052c93;received=10.15.1.190 From: User Name ;tag=efa7c5d9b99178dbo0 To: Call-ID: 8c39b031-fa14d263@10.15.1.190 CSeq: 102 INVITE Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Content-Length: 0 <------------> [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 0 [ 18]: SIP/2.0 100 Trying [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-41052c93;received=10.15.1.190 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=efa7c5d9b99178dbo0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 3 [ 36]: To: [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 8c39b031-fa14d263@10.15.1.190 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 9 [ 41]: Contact: [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 11 [ 0]: [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 10.15.1.190:5060 [Oct 27 19:02:17] DEBUG[1564] devicestate.c: No provider found, checking channel drivers for SIP - 101 [Oct 27 19:02:17] DEBUG[1564] chan_sip.c: Checking device state for peer 101 [Oct 27 19:02:17] DEBUG[1564] devicestate.c: Changing state for SIP/101 - state 1 (Not in use) [Oct 27 19:02:17] DEBUG[1564] devicestate.c: device 'SIP/101' state '1' [Oct 27 19:02:17] DEBUG[25518] pbx.c: Launching 'Set' [Oct 27 19:02:17] VERBOSE[25518] pbx.c: -- Executing [1510xxxyyyy@phones:1] Set("SIP/101-0000000d", "CALLERID(all)="MYCALLERID" <415xxxyyyy>") in new stack [Oct 27 19:02:17] DEBUG[25518] pbx.c: Launching 'Dial' [Oct 27 19:02:17] VERBOSE[25518] pbx.c: -- Executing [1510xxxyyyy@phones:2] Dial("SIP/101-0000000d", "SIP/1510xxxyyyy@voipms") in new stack [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Asked to create a SIP channel with formats: 0x4 (ulaw) [Oct 27 19:02:17] VERBOSE[25518] netsock.c: == Using SIP RTP CoS mark 5 [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Allocating new SIP dialog for 31ee1ad21c6f6672472135504eaadf8d@10.15.1.1 - INVITE (With RTP) [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Cant create SIP call - target device not registered [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Destroying SIP dialog 31ee1ad21c6f6672472135504eaadf8d@10.15.1.1 [Oct 27 19:02:17] VERBOSE[25518] chan_sip.c: Really destroying SIP dialog '31ee1ad21c6f6672472135504eaadf8d@10.15.1.1' Method: INVITE [Oct 27 19:02:17] WARNING[25518] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Unknown) [Oct 27 19:02:17] VERBOSE[25518] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1) [Oct 27 19:02:17] DEBUG[25518] rtp.c: Channel '' has no RTP, not doing anything [Oct 27 19:02:17] DEBUG[25518] app_dial.c: Exiting with DIALSTATUS=CHANUNAVAIL. [Oct 27 19:02:17] DEBUG[25518] pbx.c: Launching 'Hangup' [Oct 27 19:02:17] VERBOSE[25518] pbx.c: -- Executing [1510xxxyyyy@phones:3] Hangup("SIP/101-0000000d", "") in new stack [Oct 27 19:02:17] DEBUG[25518] pbx.c: Spawn extension (phones,1510xxxyyyy,3) exited non-zero on 'SIP/101-0000000d' [Oct 27 19:02:17] VERBOSE[25518] pbx.c: == Spawn extension (phones, 1510xxxyyyy, 3) exited non-zero on 'SIP/101-0000000d' [Oct 27 19:02:17] DEBUG[25518] channel.c: Soft-Hanging up channel 'SIP/101-0000000d' [Oct 27 19:02:17] DEBUG[25518] channel.c: Hanging up channel 'SIP/101-0000000d' [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Hangup call SIP/101-0000000d, SIP callid 8c39b031-fa14d263@10.15.1.190 [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Hanging up channel in state Ring (not UP) [Oct 27 19:02:17] VERBOSE[25518] chan_sip.c: Scheduling destruction of SIP dialog '8c39b031-fa14d263@10.15.1.190' in 32000 ms (Method: INVITE) [Oct 27 19:02:17] VERBOSE[25518] chan_sip.c: <--- Reliably Transmitting (no NAT) to 10.15.1.190:5060 ---> SIP/2.0 480 Temporarily unavailable Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-41052c93;received=10.15.1.190 From: User Name ;tag=efa7c5d9b99178dbo0 To: ;tag=as444797cf Call-ID: 8c39b031-fa14d263@10.15.1.190 CSeq: 102 INVITE Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 <------------> [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Header 0 [ 35]: SIP/2.0 480 Temporarily unavailable [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-41052c93;received=10.15.1.190 [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=efa7c5d9b99178dbo0 [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Header 3 [ 51]: To: ;tag=as444797cf [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Header 4 [ 38]: Call-ID: 8c39b031-fa14d263@10.15.1.190 [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Header 10 [ 0]: [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #8248 [Oct 27 19:02:17] DEBUG[25518] chan_sip.c: Trying to put 'SIP/2.0 480' onto UDP socket destined for 10.15.1.190:5060 [Oct 27 19:02:17] DEBUG[1564] devicestate.c: No provider found, checking channel drivers for SIP - 101 [Oct 27 19:02:17] DEBUG[1564] chan_sip.c: Checking device state for peer 101 [Oct 27 19:02:17] DEBUG[1564] devicestate.c: Changing state for SIP/101 - state 1 (Not in use) [Oct 27 19:02:17] DEBUG[1564] devicestate.c: device 'SIP/101' state '1' [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5060 ---> ACK sip:1510xxxyyyy@10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-41052c93 From: User Name ;tag=efa7c5d9b99178dbo0 To: ;tag=as444797cf Call-ID: 8c39b031-fa14d263@10.15.1.190 CSeq: 102 ACK Max-Forwards: 70 Authorization: Digest username="101",realm="asterisk",nonce="6a9237d6",uri="sip:1510xxxyyyy@10.15.1.1:6010",algorithm=MD5,response="a07b82a9023fba8a3254bca76958d448" Contact: User Name User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 <-------------> [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 0 [ 42]: ACK sip:1510xxxyyyy@10.15.1.1:6010 SIP/2.0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-41052c93 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=efa7c5d9b99178dbo0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 3 [ 51]: To: ;tag=as444797cf [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 8c39b031-fa14d263@10.15.1.190 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 5 [ 13]: CSeq: 102 ACK [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 7 [165]: Authorization: Digest username="101",realm="asterisk",nonce="6a9237d6",uri="sip:1510xxxyyyy@10.15.1.1:6010",algorithm=MD5,response="a07b82a9023fba8a3254bca76958d448" [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 8 [ 48]: Contact: User Name [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Header 11 [ 0]: [Oct 27 19:02:17] VERBOSE[1676] chan_sip.c: --- (11 headers 0 lines) --- [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #8248 [Oct 27 19:02:17] DEBUG[1676] chan_sip.c: Stopping retransmission on '8c39b031-fa14d263@10.15.1.190' of Response 102: Match Found [Oct 27 19:02:21] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5061 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-d90fb2d5 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42144 REGISTER Max-Forwards: 70 Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="766c8451",uri="sip:10.15.1.1:6010",algorithm=MD5,response="7bfa92aeadadb4dfb001fd9a39d36dff" Contact: Sipura-PSTN ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-d90fb2d5 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 3 [ 48]: To: Sipura-PSTN [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42144 REGISTER [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 7 [161]: Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="766c8451",uri="sip:10.15.1.1:6010",algorithm=MD5,response="7bfa92aeadadb4dfb001fd9a39d36dff" [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 8 [ 67]: Contact: Sipura-PSTN ;expires=180 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:02:21] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 19:02:21] DEBUG[1676] acl.c: Found IP address for this socket [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.15.1.1:6010 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Allocating new SIP dialog for 4948b0a5-f0ed3274@10.15.1.190 - REGISTER (No RTP) [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:02:21] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5061 (no NAT) [Oct 27 19:02:21] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5061 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-d90fb2d5;received=10.15.1.190 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN ;tag=as24f40634 Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42144 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="05c69634" Content-Length: 0 <------------> [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-d90fb2d5;received=10.15.1.190 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 3 [ 63]: To: Sipura-PSTN ;tag=as24f40634 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42144 REGISTER [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 9 [ 74]: WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="05c69634" [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 11 [ 0]: [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.15.1.190:5061 [Oct 27 19:02:21] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog '4948b0a5-f0ed3274@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 19:02:21] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5061 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-dc4702f6 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42145 REGISTER Max-Forwards: 70 Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="05c69634",uri="sip:10.15.1.1:6010",algorithm=MD5,response="df31f87229b34425af5216debddba404" Contact: Sipura-PSTN ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-dc4702f6 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 3 [ 48]: To: Sipura-PSTN [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42145 REGISTER [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 7 [161]: Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="05c69634",uri="sip:10.15.1.1:6010",algorithm=MD5,response="df31f87229b34425af5216debddba404" [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 8 [ 67]: Contact: Sipura-PSTN ;expires=180 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:02:21] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:02:21] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5061 (no NAT) [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Store REGISTER's Contact header for call routing. [Oct 27 19:02:21] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5061 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-dc4702f6;received=10.15.1.190 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN ;tag=as24f40634 Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42145 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Expires: 180 Contact: ;expires=180 Date: Wed, 27 Oct 2010 21:02:21 GMT Content-Length: 0 <------------> [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-dc4702f6;received=10.15.1.190 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 3 [ 63]: To: Sipura-PSTN ;tag=as24f40634 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42145 REGISTER [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 9 [ 12]: Expires: 180 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 10 [ 55]: Contact: ;expires=180 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 11 [ 35]: Date: Wed, 27 Oct 2010 21:02:21 GMT [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:02:21] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.15.1.190:5061 [Oct 27 19:02:21] DEBUG[1564] devicestate.c: No provider found, checking channel drivers for SIP - sipura-pstn [Oct 27 19:02:21] DEBUG[1564] chan_sip.c: Checking device state for peer sipura-pstn [Oct 27 19:02:21] DEBUG[1564] devicestate.c: Changing state for SIP/sipura-pstn - state 1 (Not in use) [Oct 27 19:02:21] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog '4948b0a5-f0ed3274@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 19:02:21] DEBUG[1564] devicestate.c: device 'SIP/sipura-pstn' state '1' [Oct 27 19:02:23] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5060 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-eb2398bb From: User Name ;tag=c16edcec69b432fao0 To: User Name Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28831 REGISTER Max-Forwards: 70 Authorization: Digest username="101",realm="asterisk",nonce="74198a46",uri="sip:10.15.1.1:6010",algorithm=MD5,response="8473170ed4041e8b4953ef9eadba9fdc" Contact: User Name ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-eb2398bb [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 3 [ 41]: To: User Name [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28831 REGISTER [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 7 [153]: Authorization: Digest username="101",realm="asterisk",nonce="74198a46",uri="sip:10.15.1.1:6010",algorithm=MD5,response="8473170ed4041e8b4953ef9eadba9fdc" [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 8 [ 60]: Contact: User Name ;expires=180 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:02:23] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 19:02:23] DEBUG[1676] acl.c: Found IP address for this socket [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.15.1.1:6010 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Allocating new SIP dialog for ac8b890c-d8d25d39@10.15.1.190 - REGISTER (No RTP) [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:02:23] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5060 (no NAT) [Oct 27 19:02:23] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-eb2398bb;received=10.15.1.190 From: User Name ;tag=c16edcec69b432fao0 To: User Name ;tag=as4a7cbacf Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28831 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="0de0df7f" Content-Length: 0 <------------> [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-eb2398bb;received=10.15.1.190 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 3 [ 56]: To: User Name ;tag=as4a7cbacf [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28831 REGISTER [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 9 [ 74]: WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="0de0df7f" [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 11 [ 0]: [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.15.1.190:5060 [Oct 27 19:02:23] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 19:02:23] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5060 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-f1973cbc From: User Name ;tag=c16edcec69b432fao0 To: User Name Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28832 REGISTER Max-Forwards: 70 Authorization: Digest username="101",realm="asterisk",nonce="0de0df7f",uri="sip:10.15.1.1:6010",algorithm=MD5,response="d4a60f33138d39257fccedb58bbd46be" Contact: User Name ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-f1973cbc [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 3 [ 41]: To: User Name [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28832 REGISTER [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 7 [153]: Authorization: Digest username="101",realm="asterisk",nonce="0de0df7f",uri="sip:10.15.1.1:6010",algorithm=MD5,response="d4a60f33138d39257fccedb58bbd46be" [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 8 [ 60]: Contact: User Name ;expires=180 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:02:23] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:02:23] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5060 (no NAT) [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Store REGISTER's Contact header for call routing. [Oct 27 19:02:23] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-f1973cbc;received=10.15.1.190 From: User Name ;tag=c16edcec69b432fao0 To: User Name ;tag=as4a7cbacf Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28832 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Expires: 180 Contact: ;expires=180 Date: Wed, 27 Oct 2010 21:02:23 GMT Content-Length: 0 <------------> [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-f1973cbc;received=10.15.1.190 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 3 [ 56]: To: User Name ;tag=as4a7cbacf [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28832 REGISTER [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 9 [ 12]: Expires: 180 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 10 [ 47]: Contact: ;expires=180 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 11 [ 35]: Date: Wed, 27 Oct 2010 21:02:23 GMT [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:02:23] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.15.1.190:5060 [Oct 27 19:02:23] DEBUG[1564] devicestate.c: No provider found, checking channel drivers for SIP - 101 [Oct 27 19:02:23] DEBUG[1564] chan_sip.c: Checking device state for peer 101 [Oct 27 19:02:23] DEBUG[1564] devicestate.c: Changing state for SIP/101 - state 1 (Not in use) [Oct 27 19:02:23] DEBUG[1564] devicestate.c: device 'SIP/101' state '1' [Oct 27 19:02:23] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 19:02:49] DEBUG[1676] chan_sip.c: Auto destroying SIP dialog '8c39b031-fa14d263@10.15.1.190' [Oct 27 19:02:49] DEBUG[1676] chan_sip.c: Destroying SIP dialog 8c39b031-fa14d263@10.15.1.190 [Oct 27 19:02:49] VERBOSE[1676] chan_sip.c: Really destroying SIP dialog '8c39b031-fa14d263@10.15.1.190' Method: ACK [Oct 27 19:02:53] DEBUG[1676] chan_sip.c: Auto destroying SIP dialog '4948b0a5-f0ed3274@10.15.1.190' [Oct 27 19:02:53] DEBUG[1676] chan_sip.c: Destroying SIP dialog 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:02:53] VERBOSE[1676] chan_sip.c: Really destroying SIP dialog '4948b0a5-f0ed3274@10.15.1.190' Method: REGISTER [Oct 27 19:02:55] DEBUG[1676] chan_sip.c: Auto destroying SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' [Oct 27 19:02:55] DEBUG[1676] chan_sip.c: Destroying SIP dialog ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:02:55] VERBOSE[1676] chan_sip.c: Really destroying SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' Method: REGISTER [Oct 27 19:04:03] VERBOSE[25295] asterisk.c: -- Remote UNIX connection disconnected [Oct 27 19:04:06] VERBOSE[1554] asterisk.c: -- Remote UNIX connection [Oct 27 19:04:22] NOTICE[1676] chan_sip.c: -- Re-registration for myuserid@dallas.voip.ms [Oct 27 19:04:22] VERBOSE[1676] dnsmgr.c: > doing dnsmgr_lookup for 'dallas.voip.ms' [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Allocating new SIP dialog for 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 - REGISTER (No RTP) [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: OBPROXY: Not applying OBproxy to this call [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: SIP Registry dallas.voip.ms: refcount now 3 [Oct 27 19:04:24] DEBUG[1676] acl.c: Found IP address for this socket [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 186.48.193.36:6010 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: SIP Registry dallas.voip.ms: refcount now 4 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Scheduled a registration timeout for dallas.voip.ms id #8255 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: >>> Re-using Auth data for myuserid@dallas.voip.ms [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:dallas.voip.ms SIP/2.0 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 186.48.193.36:6010;branch=z9hG4bK287cd2b0;rport [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 3 [ 48]: From: ;tag=as00d64bb4 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 4 [ 31]: To: [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 5 [ 51]: Call-ID: 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 6 [ 18]: CSeq: 761 REGISTER [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 7 [ 33]: User-Agent: Asterisk PBX 1.6.2.13 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 8 [167]: Authorization: Digest username="myuserid", realm="dallas.voip.ms", algorithm=MD5, uri="sip:dallas.voip.ms", nonce="05032d80", response="21323bb5c6dcaba3537c871ef86e0ff5" [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 9 [ 12]: Expires: 600 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 10 [ 35]: Contact: [Oct 27 19:04:24] VERBOSE[1676] chan_sip.c: REGISTER 11 headers, 0 lines [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: REGISTER attempt 1 to myuserid@dallas.voip.ms [Oct 27 19:04:24] VERBOSE[1676] chan_sip.c: Reliably Transmitting (no NAT) to 74.54.54.178:5060: REGISTER sip:dallas.voip.ms SIP/2.0 Via: SIP/2.0/UDP 186.48.193.36:6010;branch=z9hG4bK287cd2b0;rport Max-Forwards: 70 From: ;tag=as00d64bb4 To: Call-ID: 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 CSeq: 761 REGISTER User-Agent: Asterisk PBX 1.6.2.13 Authorization: Digest username="myuserid", realm="dallas.voip.ms", algorithm=MD5, uri="sip:dallas.voip.ms", nonce="05032d80", response="21323bb5c6dcaba3537c871ef86e0ff5" Expires: 600 Contact: Content-Length: 0 --- [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:dallas.voip.ms SIP/2.0 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 186.48.193.36:6010;branch=z9hG4bK287cd2b0;rport [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 3 [ 48]: From: ;tag=as00d64bb4 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 4 [ 31]: To: [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 5 [ 51]: Call-ID: 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 6 [ 18]: CSeq: 761 REGISTER [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 7 [ 33]: User-Agent: Asterisk PBX 1.6.2.13 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 8 [167]: Authorization: Digest username="myuserid", realm="dallas.voip.ms", algorithm=MD5, uri="sip:dallas.voip.ms", nonce="05032d80", response="21323bb5c6dcaba3537c871ef86e0ff5" [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 9 [ 12]: Expires: 600 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 10 [ 35]: Contact: [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 12 [ 0]: [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #8256 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 74.54.54.178:5060 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: SIP Registry dallas.voip.ms: refcount now 3 [Oct 27 19:04:24] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:74.54.54.178:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 186.48.193.36:6010;branch=z9hG4bK287cd2b0;received=186.48.193.36;rport=6010 From: ;tag=as00d64bb4 To: Call-ID: 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 CSeq: 761 REGISTER User-Agent: VoIPMS SERAST Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Content-Length: 0 <-------------> [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 0 [ 18]: SIP/2.0 100 Trying [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 1 [ 92]: Via: SIP/2.0/UDP 186.48.193.36:6010;branch=z9hG4bK287cd2b0;received=186.48.193.36;rport=6010 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 2 [ 48]: From: ;tag=as00d64bb4 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 3 [ 31]: To: [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 4 [ 51]: Call-ID: 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 5 [ 18]: CSeq: 761 REGISTER [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 6 [ 25]: User-Agent: VoIPMS SERAST [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 8 [ 19]: Supported: replaces [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 10 [ 0]: [Oct 27 19:04:24] VERBOSE[1676] chan_sip.c: --- (10 headers 0 lines) --- [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: *** SIP TIMER: Cancelling retransmission #8256 - REGISTER (got response) [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '4de52e9f1767757d681c7dea6d34e288@10.15.1.1' Request 761: Found [Oct 27 19:04:24] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:74.54.54.178:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 186.48.193.36:6010;branch=z9hG4bK287cd2b0;received=186.48.193.36;rport=6010 From: ;tag=as00d64bb4 To: ;tag=as7a4bcc8d Call-ID: 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 CSeq: 761 REGISTER User-Agent: VoIPMS SERAST Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces WWW-Authenticate: Digest algorithm=MD5, realm="dallas.voip.ms", nonce="756d4444" Content-Length: 0 <-------------> [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 1 [ 92]: Via: SIP/2.0/UDP 186.48.193.36:6010;branch=z9hG4bK287cd2b0;received=186.48.193.36;rport=6010 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 2 [ 48]: From: ;tag=as00d64bb4 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 3 [ 46]: To: ;tag=as7a4bcc8d [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 4 [ 51]: Call-ID: 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 5 [ 18]: CSeq: 761 REGISTER [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 6 [ 25]: User-Agent: VoIPMS SERAST [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 8 [ 19]: Supported: replaces [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 9 [ 80]: WWW-Authenticate: Digest algorithm=MD5, realm="dallas.voip.ms", nonce="756d4444" [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 11 [ 0]: [Oct 27 19:04:24] VERBOSE[1676] chan_sip.c: --- (11 headers 0 lines) --- [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Stopping retransmission on '4de52e9f1767757d681c7dea6d34e288@10.15.1.1' of Request 761: Match Found [Oct 27 19:04:24] VERBOSE[1676] chan_sip.c: Responding to challenge, registration to domain/host name dallas.voip.ms [Oct 27 19:04:24] VERBOSE[1676] dnsmgr.c: > doing dnsmgr_lookup for 'dallas.voip.ms' [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Initializing already initialized SIP dialog 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 (presumably reinvite) [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:dallas.voip.ms SIP/2.0 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 186.48.193.36:6010;branch=z9hG4bK513088cc;rport [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 3 [ 48]: From: ;tag=as5cec1a2e [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 4 [ 31]: To: [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 5 [ 51]: Call-ID: 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 6 [ 18]: CSeq: 762 REGISTER [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 7 [ 33]: User-Agent: Asterisk PBX 1.6.2.13 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 8 [167]: Authorization: Digest username="myuserid", realm="dallas.voip.ms", algorithm=MD5, uri="sip:dallas.voip.ms", nonce="756d4444", response="e7d089c211b17ea25f52e5fc1c23d00e" [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 9 [ 12]: Expires: 600 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 10 [ 35]: Contact: [Oct 27 19:04:24] VERBOSE[1676] chan_sip.c: REGISTER 11 headers, 0 lines [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: REGISTER attempt 2 to myuserid@dallas.voip.ms [Oct 27 19:04:24] VERBOSE[1676] chan_sip.c: Reliably Transmitting (no NAT) to 74.54.54.178:5060: REGISTER sip:dallas.voip.ms SIP/2.0 Via: SIP/2.0/UDP 186.48.193.36:6010;branch=z9hG4bK513088cc;rport Max-Forwards: 70 From: ;tag=as5cec1a2e To: Call-ID: 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 CSeq: 762 REGISTER User-Agent: Asterisk PBX 1.6.2.13 Authorization: Digest username="myuserid", realm="dallas.voip.ms", algorithm=MD5, uri="sip:dallas.voip.ms", nonce="756d4444", response="e7d089c211b17ea25f52e5fc1c23d00e" Expires: 600 Contact: Content-Length: 0 --- [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:dallas.voip.ms SIP/2.0 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 186.48.193.36:6010;branch=z9hG4bK513088cc;rport [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 3 [ 48]: From: ;tag=as5cec1a2e [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 4 [ 31]: To: [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 5 [ 51]: Call-ID: 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 6 [ 18]: CSeq: 762 REGISTER [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 7 [ 33]: User-Agent: Asterisk PBX 1.6.2.13 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 8 [167]: Authorization: Digest username="myuserid", realm="dallas.voip.ms", algorithm=MD5, uri="sip:dallas.voip.ms", nonce="756d4444", response="e7d089c211b17ea25f52e5fc1c23d00e" [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 9 [ 12]: Expires: 600 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 10 [ 35]: Contact: [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 12 [ 0]: [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #8257 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 74.54.54.178:5060 [Oct 27 19:04:24] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:74.54.54.178:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 186.48.193.36:6010;branch=z9hG4bK513088cc;received=186.48.193.36;rport=6010 From: ;tag=as5cec1a2e To: Call-ID: 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 CSeq: 762 REGISTER User-Agent: VoIPMS SERAST Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Content-Length: 0 <-------------> [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 0 [ 18]: SIP/2.0 100 Trying [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 1 [ 92]: Via: SIP/2.0/UDP 186.48.193.36:6010;branch=z9hG4bK513088cc;received=186.48.193.36;rport=6010 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 2 [ 48]: From: ;tag=as5cec1a2e [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 3 [ 31]: To: [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 4 [ 51]: Call-ID: 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 5 [ 18]: CSeq: 762 REGISTER [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 6 [ 25]: User-Agent: VoIPMS SERAST [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 8 [ 19]: Supported: replaces [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 10 [ 0]: [Oct 27 19:04:24] VERBOSE[1676] chan_sip.c: --- (10 headers 0 lines) --- [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: *** SIP TIMER: Cancelling retransmission #8257 - REGISTER (got response) [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '4de52e9f1767757d681c7dea6d34e288@10.15.1.1' Request 762: Found [Oct 27 19:04:24] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:74.54.54.178:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 186.48.193.36:6010;branch=z9hG4bK513088cc;received=186.48.193.36;rport=6010 From: ;tag=as5cec1a2e To: ;tag=as7a4bcc8d Call-ID: 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 CSeq: 762 REGISTER User-Agent: VoIPMS SERAST Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Expires: 600 Contact: ;expires=600 Date: Wed, 27 Oct 2010 21:04:24 GMT Content-Length: 0 <-------------> [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 1 [ 92]: Via: SIP/2.0/UDP 186.48.193.36:6010;branch=z9hG4bK513088cc;received=186.48.193.36;rport=6010 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 2 [ 48]: From: ;tag=as5cec1a2e [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 3 [ 46]: To: ;tag=as7a4bcc8d [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 4 [ 51]: Call-ID: 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 5 [ 18]: CSeq: 762 REGISTER [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 6 [ 25]: User-Agent: VoIPMS SERAST [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 8 [ 19]: Supported: replaces [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 9 [ 12]: Expires: 600 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 10 [ 47]: Contact: ;expires=600 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 11 [ 35]: Date: Wed, 27 Oct 2010 21:04:24 GMT [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:04:24] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Stopping retransmission on '4de52e9f1767757d681c7dea6d34e288@10.15.1.1' of Request 762: Match Found [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Registration successful [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: Cancelling timeout 8255 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: SIP Registry dallas.voip.ms: refcount now 2 [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: SIP Registry dallas.voip.ms: refcount now 1 [Oct 27 19:04:24] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog '4de52e9f1767757d681c7dea6d34e288@10.15.1.1' in 32000 ms (Method: REGISTER) [Oct 27 19:04:24] NOTICE[1676] chan_sip.c: Outbound Registration: Expiry for dallas.voip.ms is 600 sec (Scheduling reregistration in 585 s) [Oct 27 19:04:24] DEBUG[1676] chan_sip.c: SIP Registry dallas.voip.ms: refcount now 2 [Oct 27 19:04:35] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:74.54.54.178:5060 ---> NOTIFY sip:s@186.48.193.36:6010 SIP/2.0 Via: SIP/2.0/UDP 74.54.54.178:5060;branch=z9hG4bK48c921ff;rport From: "Unknown" ;tag=as38428dfb To: Contact: Call-ID: 21563a495a3af2cb2c98deeb166244de@74.54.54.178 CSeq: 102 NOTIFY User-Agent: VoIPMS SERAST Max-Forwards: 70 Event: message-summary Content-Type: application/simple-message-summary Content-Length: 92 Messages-Waiting: no Message-Account: sip:asterisk@74.54.54.178 Voice-Message: 0/0 (0/0) <-------------> [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Header 0 [ 39]: NOTIFY sip:s@186.48.193.36:6010 SIP/2.0 [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/UDP 74.54.54.178:5060;branch=z9hG4bK48c921ff;rport [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Header 2 [ 57]: From: "Unknown" ;tag=as38428dfb [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Header 3 [ 30]: To: [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Header 4 [ 35]: Contact: [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Header 5 [ 54]: Call-ID: 21563a495a3af2cb2c98deeb166244de@74.54.54.178 [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Header 6 [ 16]: CSeq: 102 NOTIFY [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Header 7 [ 25]: User-Agent: VoIPMS SERAST [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Header 8 [ 16]: Max-Forwards: 70 [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Header 9 [ 22]: Event: message-summary [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Header 10 [ 48]: Content-Type: application/simple-message-summary [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Header 11 [ 18]: Content-Length: 92 [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Header 12 [ 0]: [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Body 0 [ 20]: Messages-Waiting: no [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Body 1 [ 42]: Message-Account: sip:asterisk@74.54.54.178 [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Body 2 [ 24]: Voice-Message: 0/0 (0/0) [Oct 27 19:04:35] VERBOSE[1676] chan_sip.c: --- (12 headers 3 lines) --- [Oct 27 19:04:35] DEBUG[1676] acl.c: Found IP address for this socket [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 186.48.193.36:6010 [Oct 27 19:04:35] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 74.54.54.178:5060 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 74.54.54.178:5060;branch=z9hG4bK48c921ff;received=74.54.54.178;rport=5060 From: "Unknown" ;tag=as38428dfb To: ;tag=as1c124edd Call-ID: 21563a495a3af2cb2c98deeb166244de@74.54.54.178 CSeq: 102 NOTIFY Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 <------------> [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 489' onto UDP socket destined for 74.54.54.178:5060 [Oct 27 19:04:35] DEBUG[1676] chan_sip.c: Invalid SIP message - rejected , no callid, len 541 [Oct 27 19:04:56] DEBUG[1676] chan_sip.c: Auto destroying SIP dialog '4de52e9f1767757d681c7dea6d34e288@10.15.1.1' [Oct 27 19:04:56] DEBUG[1676] chan_sip.c: Destroying SIP dialog 4de52e9f1767757d681c7dea6d34e288@10.15.1.1 [Oct 27 19:04:56] VERBOSE[1676] chan_sip.c: Really destroying SIP dialog '4de52e9f1767757d681c7dea6d34e288@10.15.1.1' Method: REGISTER [Oct 27 19:05:18] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5061 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-61a07dea From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42146 REGISTER Max-Forwards: 70 Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="05c69634",uri="sip:10.15.1.1:6010",algorithm=MD5,response="df31f87229b34425af5216debddba404" Contact: Sipura-PSTN ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-61a07dea [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 3 [ 48]: To: Sipura-PSTN [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42146 REGISTER [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 7 [161]: Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="05c69634",uri="sip:10.15.1.1:6010",algorithm=MD5,response="df31f87229b34425af5216debddba404" [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 8 [ 67]: Contact: Sipura-PSTN ;expires=180 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:05:18] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 19:05:18] DEBUG[1676] acl.c: Found IP address for this socket [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.15.1.1:6010 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Allocating new SIP dialog for 4948b0a5-f0ed3274@10.15.1.190 - REGISTER (No RTP) [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:05:18] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5061 (no NAT) [Oct 27 19:05:18] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5061 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-61a07dea;received=10.15.1.190 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN ;tag=as5b216a78 Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42146 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="21ffdc9a" Content-Length: 0 <------------> [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-61a07dea;received=10.15.1.190 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 3 [ 63]: To: Sipura-PSTN ;tag=as5b216a78 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42146 REGISTER [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 9 [ 74]: WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="21ffdc9a" [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 11 [ 0]: [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.15.1.190:5061 [Oct 27 19:05:18] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog '4948b0a5-f0ed3274@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 19:05:18] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5061 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-35c2fde7 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42147 REGISTER Max-Forwards: 70 Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="21ffdc9a",uri="sip:10.15.1.1:6010",algorithm=MD5,response="3af90f4f9b84e3971023be19e9759b08" Contact: Sipura-PSTN ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-35c2fde7 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 3 [ 48]: To: Sipura-PSTN [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42147 REGISTER [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 7 [161]: Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="21ffdc9a",uri="sip:10.15.1.1:6010",algorithm=MD5,response="3af90f4f9b84e3971023be19e9759b08" [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 8 [ 67]: Contact: Sipura-PSTN ;expires=180 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:05:18] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:05:18] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5061 (no NAT) [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Store REGISTER's Contact header for call routing. [Oct 27 19:05:18] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5061 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-35c2fde7;received=10.15.1.190 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN ;tag=as5b216a78 Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42147 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Expires: 180 Contact: ;expires=180 Date: Wed, 27 Oct 2010 21:05:18 GMT Content-Length: 0 <------------> [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-35c2fde7;received=10.15.1.190 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 3 [ 63]: To: Sipura-PSTN ;tag=as5b216a78 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42147 REGISTER [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 9 [ 12]: Expires: 180 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 10 [ 55]: Contact: ;expires=180 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 11 [ 35]: Date: Wed, 27 Oct 2010 21:05:18 GMT [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:05:18] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.15.1.190:5061 [Oct 27 19:05:18] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog '4948b0a5-f0ed3274@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 19:05:18] DEBUG[1564] devicestate.c: No provider found, checking channel drivers for SIP - sipura-pstn [Oct 27 19:05:18] DEBUG[1564] chan_sip.c: Checking device state for peer sipura-pstn [Oct 27 19:05:18] DEBUG[1564] devicestate.c: Changing state for SIP/sipura-pstn - state 1 (Not in use) [Oct 27 19:05:18] DEBUG[1564] devicestate.c: device 'SIP/sipura-pstn' state '1' [Oct 27 19:05:20] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5060 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-eed237a7 From: User Name ;tag=c16edcec69b432fao0 To: User Name Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28833 REGISTER Max-Forwards: 70 Authorization: Digest username="101",realm="asterisk",nonce="0de0df7f",uri="sip:10.15.1.1:6010",algorithm=MD5,response="d4a60f33138d39257fccedb58bbd46be" Contact: User Name ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-eed237a7 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 3 [ 41]: To: User Name [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28833 REGISTER [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 7 [153]: Authorization: Digest username="101",realm="asterisk",nonce="0de0df7f",uri="sip:10.15.1.1:6010",algorithm=MD5,response="d4a60f33138d39257fccedb58bbd46be" [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 8 [ 60]: Contact: User Name ;expires=180 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:05:20] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 19:05:20] DEBUG[1676] acl.c: Found IP address for this socket [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.15.1.1:6010 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Allocating new SIP dialog for ac8b890c-d8d25d39@10.15.1.190 - REGISTER (No RTP) [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:05:20] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5060 (no NAT) [Oct 27 19:05:20] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-eed237a7;received=10.15.1.190 From: User Name ;tag=c16edcec69b432fao0 To: User Name ;tag=as2706d282 Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28833 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="30b35864" Content-Length: 0 <------------> [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-eed237a7;received=10.15.1.190 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 3 [ 56]: To: User Name ;tag=as2706d282 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28833 REGISTER [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 9 [ 74]: WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="30b35864" [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 11 [ 0]: [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.15.1.190:5060 [Oct 27 19:05:20] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 19:05:20] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5060 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-854b009c From: User Name ;tag=c16edcec69b432fao0 To: User Name Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28834 REGISTER Max-Forwards: 70 Authorization: Digest username="101",realm="asterisk",nonce="30b35864",uri="sip:10.15.1.1:6010",algorithm=MD5,response="327891931cf39291885d9b18d5bedfa6" Contact: User Name ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-854b009c [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 3 [ 41]: To: User Name [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28834 REGISTER [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 7 [153]: Authorization: Digest username="101",realm="asterisk",nonce="30b35864",uri="sip:10.15.1.1:6010",algorithm=MD5,response="327891931cf39291885d9b18d5bedfa6" [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 8 [ 60]: Contact: User Name ;expires=180 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:05:20] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:05:20] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5060 (no NAT) [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Store REGISTER's Contact header for call routing. [Oct 27 19:05:20] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-854b009c;received=10.15.1.190 From: User Name ;tag=c16edcec69b432fao0 To: User Name ;tag=as2706d282 Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28834 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Expires: 180 Contact: ;expires=180 Date: Wed, 27 Oct 2010 21:05:20 GMT Content-Length: 0 <------------> [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-854b009c;received=10.15.1.190 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 3 [ 56]: To: User Name ;tag=as2706d282 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28834 REGISTER [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 9 [ 12]: Expires: 180 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 10 [ 47]: Contact: ;expires=180 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 11 [ 35]: Date: Wed, 27 Oct 2010 21:05:20 GMT [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:05:20] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.15.1.190:5060 [Oct 27 19:05:20] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 19:05:20] DEBUG[1564] devicestate.c: No provider found, checking channel drivers for SIP - 101 [Oct 27 19:05:20] DEBUG[1564] chan_sip.c: Checking device state for peer 101 [Oct 27 19:05:20] DEBUG[1564] devicestate.c: Changing state for SIP/101 - state 1 (Not in use) [Oct 27 19:05:20] DEBUG[1564] devicestate.c: device 'SIP/101' state '1' [Oct 27 19:05:50] DEBUG[1676] chan_sip.c: Auto destroying SIP dialog '4948b0a5-f0ed3274@10.15.1.190' [Oct 27 19:05:50] DEBUG[1676] chan_sip.c: Destroying SIP dialog 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:05:50] VERBOSE[1676] chan_sip.c: Really destroying SIP dialog '4948b0a5-f0ed3274@10.15.1.190' Method: REGISTER [Oct 27 19:05:52] DEBUG[1676] chan_sip.c: Auto destroying SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' [Oct 27 19:05:52] DEBUG[1676] chan_sip.c: Destroying SIP dialog ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:05:52] VERBOSE[1676] chan_sip.c: Really destroying SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' Method: REGISTER [Oct 27 19:08:15] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5061 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-146cd0b2 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42148 REGISTER Max-Forwards: 70 Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="21ffdc9a",uri="sip:10.15.1.1:6010",algorithm=MD5,response="3af90f4f9b84e3971023be19e9759b08" Contact: Sipura-PSTN ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-146cd0b2 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 3 [ 48]: To: Sipura-PSTN [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42148 REGISTER [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 7 [161]: Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="21ffdc9a",uri="sip:10.15.1.1:6010",algorithm=MD5,response="3af90f4f9b84e3971023be19e9759b08" [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 8 [ 67]: Contact: Sipura-PSTN ;expires=180 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:08:15] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 19:08:15] DEBUG[1676] acl.c: Found IP address for this socket [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.15.1.1:6010 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Allocating new SIP dialog for 4948b0a5-f0ed3274@10.15.1.190 - REGISTER (No RTP) [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:08:15] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5061 (no NAT) [Oct 27 19:08:15] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5061 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-146cd0b2;received=10.15.1.190 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN ;tag=as17fd57f4 Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42148 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="49e298ec" Content-Length: 0 <------------> [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-146cd0b2;received=10.15.1.190 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 3 [ 63]: To: Sipura-PSTN ;tag=as17fd57f4 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42148 REGISTER [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 9 [ 74]: WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="49e298ec" [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 11 [ 0]: [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.15.1.190:5061 [Oct 27 19:08:15] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog '4948b0a5-f0ed3274@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 19:08:15] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5061 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-3bb63e03 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42149 REGISTER Max-Forwards: 70 Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="49e298ec",uri="sip:10.15.1.1:6010",algorithm=MD5,response="9cd82f136f9746b60e7984127c3697e4" Contact: Sipura-PSTN ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-3bb63e03 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 3 [ 48]: To: Sipura-PSTN [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42149 REGISTER [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 7 [161]: Authorization: Digest username="sipura-pstn",realm="asterisk",nonce="49e298ec",uri="sip:10.15.1.1:6010",algorithm=MD5,response="9cd82f136f9746b60e7984127c3697e4" [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 8 [ 67]: Contact: Sipura-PSTN ;expires=180 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:08:15] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:08:15] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5061 (no NAT) [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Store REGISTER's Contact header for call routing. [Oct 27 19:08:15] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5061 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-3bb63e03;received=10.15.1.190 From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 To: Sipura-PSTN ;tag=as17fd57f4 Call-ID: 4948b0a5-f0ed3274@10.15.1.190 CSeq: 42149 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Expires: 180 Contact: ;expires=180 Date: Wed, 27 Oct 2010 21:08:15 GMT Content-Length: 0 <------------> [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5061;branch=z9hG4bK-3bb63e03;received=10.15.1.190 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 2 [ 73]: From: Sipura-PSTN ;tag=8323bd5c33974d4ao1 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 3 [ 63]: To: Sipura-PSTN ;tag=as17fd57f4 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 42149 REGISTER [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 9 [ 12]: Expires: 180 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 10 [ 55]: Contact: ;expires=180 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 11 [ 35]: Date: Wed, 27 Oct 2010 21:08:15 GMT [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:08:15] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.15.1.190:5061 [Oct 27 19:08:15] DEBUG[1564] devicestate.c: No provider found, checking channel drivers for SIP - sipura-pstn [Oct 27 19:08:15] DEBUG[1564] chan_sip.c: Checking device state for peer sipura-pstn [Oct 27 19:08:15] DEBUG[1564] devicestate.c: Changing state for SIP/sipura-pstn - state 1 (Not in use) [Oct 27 19:08:15] DEBUG[1564] devicestate.c: device 'SIP/sipura-pstn' state '1' [Oct 27 19:08:15] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog '4948b0a5-f0ed3274@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 19:08:17] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5060 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-363cdb62 From: User Name ;tag=c16edcec69b432fao0 To: User Name Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28835 REGISTER Max-Forwards: 70 Authorization: Digest username="101",realm="asterisk",nonce="30b35864",uri="sip:10.15.1.1:6010",algorithm=MD5,response="327891931cf39291885d9b18d5bedfa6" Contact: User Name ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-363cdb62 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 3 [ 41]: To: User Name [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28835 REGISTER [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 7 [153]: Authorization: Digest username="101",realm="asterisk",nonce="30b35864",uri="sip:10.15.1.1:6010",algorithm=MD5,response="327891931cf39291885d9b18d5bedfa6" [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 8 [ 60]: Contact: User Name ;expires=180 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:08:17] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 19:08:17] DEBUG[1676] acl.c: Found IP address for this socket [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.15.1.1:6010 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Allocating new SIP dialog for ac8b890c-d8d25d39@10.15.1.190 - REGISTER (No RTP) [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:08:17] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5060 (no NAT) [Oct 27 19:08:17] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-363cdb62;received=10.15.1.190 From: User Name ;tag=c16edcec69b432fao0 To: User Name ;tag=as3bcd0062 Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28835 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="033c065c" Content-Length: 0 <------------> [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-363cdb62;received=10.15.1.190 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 3 [ 56]: To: User Name ;tag=as3bcd0062 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28835 REGISTER [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 9 [ 74]: WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="033c065c" [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 11 [ 0]: [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.15.1.190:5060 [Oct 27 19:08:17] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 19:08:17] VERBOSE[1676] chan_sip.c: <--- SIP read from UDP:10.15.1.190:5060 ---> REGISTER sip:10.15.1.1:6010 SIP/2.0 Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-9aa06123 From: User Name ;tag=c16edcec69b432fao0 To: User Name Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28836 REGISTER Max-Forwards: 70 Authorization: Digest username="101",realm="asterisk",nonce="033c065c",uri="sip:10.15.1.1:6010",algorithm=MD5,response="a480cf20c096ac0b9ea423c9365cad98" Contact: User Name ;expires=180 User-Agent: Linksys/SPA3102-3.3.6(GW) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 0 [ 35]: REGISTER sip:10.15.1.1:6010 SIP/2.0 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-9aa06123 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 3 [ 41]: To: User Name [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28836 REGISTER [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 7 [153]: Authorization: Digest username="101",realm="asterisk",nonce="033c065c",uri="sip:10.15.1.1:6010",algorithm=MD5,response="a480cf20c096ac0b9ea423c9365cad98" [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 8 [ 60]: Contact: User Name ;expires=180 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 9 [ 37]: User-Agent: Linksys/SPA3102-3.3.6(GW) [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 11 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 12 [ 19]: Supported: x-sipura [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:08:17] VERBOSE[1676] chan_sip.c: --- (13 headers 0 lines) --- [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Initializing initreq for method REGISTER - callid ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:08:17] VERBOSE[1676] chan_sip.c: Sending to 10.15.1.190 : 5060 (no NAT) [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Store REGISTER's Contact header for call routing. [Oct 27 19:08:17] VERBOSE[1676] chan_sip.c: <--- Transmitting (no NAT) to 10.15.1.190:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-9aa06123;received=10.15.1.190 From: User Name ;tag=c16edcec69b432fao0 To: User Name ;tag=as3bcd0062 Call-ID: ac8b890c-d8d25d39@10.15.1.190 CSeq: 28836 REGISTER Server: Asterisk PBX 1.6.2.13 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Expires: 180 Contact: ;expires=180 Date: Wed, 27 Oct 2010 21:08:17 GMT Content-Length: 0 <------------> [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 1 [ 78]: Via: SIP/2.0/UDP 10.15.1.190:5060;branch=z9hG4bK-9aa06123;received=10.15.1.190 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 2 [ 66]: From: User Name ;tag=c16edcec69b432fao0 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 3 [ 56]: To: User Name ;tag=as3bcd0062 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 4 [ 38]: Call-ID: ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 5 [ 20]: CSeq: 28836 REGISTER [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 6 [ 29]: Server: Asterisk PBX 1.6.2.13 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 7 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 9 [ 12]: Expires: 180 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 10 [ 47]: Contact: ;expires=180 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 11 [ 35]: Date: Wed, 27 Oct 2010 21:08:17 GMT [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Header 13 [ 0]: [Oct 27 19:08:17] DEBUG[1676] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.15.1.190:5060 [Oct 27 19:08:17] DEBUG[1564] devicestate.c: No provider found, checking channel drivers for SIP - 101 [Oct 27 19:08:17] DEBUG[1564] chan_sip.c: Checking device state for peer 101 [Oct 27 19:08:17] DEBUG[1564] devicestate.c: Changing state for SIP/101 - state 1 (Not in use) [Oct 27 19:08:17] DEBUG[1564] devicestate.c: device 'SIP/101' state '1' [Oct 27 19:08:17] VERBOSE[1676] chan_sip.c: Scheduling destruction of SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' in 32000 ms (Method: REGISTER) [Oct 27 19:08:47] DEBUG[1676] chan_sip.c: Auto destroying SIP dialog '4948b0a5-f0ed3274@10.15.1.190' [Oct 27 19:08:47] DEBUG[1676] chan_sip.c: Destroying SIP dialog 4948b0a5-f0ed3274@10.15.1.190 [Oct 27 19:08:47] VERBOSE[1676] chan_sip.c: Really destroying SIP dialog '4948b0a5-f0ed3274@10.15.1.190' Method: REGISTER [Oct 27 19:08:49] DEBUG[1676] chan_sip.c: Auto destroying SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' [Oct 27 19:08:49] DEBUG[1676] chan_sip.c: Destroying SIP dialog ac8b890c-d8d25d39@10.15.1.190 [Oct 27 19:08:49] VERBOSE[1676] chan_sip.c: Really destroying SIP dialog 'ac8b890c-d8d25d39@10.15.1.190' Method: REGISTER