*CLI> <--- SIP read from 192.168.168.74:5060 ---> INVITE sip:69@192.168.170.124 SIP/2.0 Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK5a6f6369 From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 Date: Sun, 20 May 2007 20:26:07 GMT CSeq: 101 INVITE User-Agent: CSCO/6 Contact: Expires: 180 Content-Type: application/sdp Content-Length: 251 Accept: application/sdp v=0 o=Cisco-SIPUA 19648 18021 IN IP4 192.168.168.74 s=SIP Call c=IN IP4 192.168.168.74 t=0 0 m=audio 26180 RTP/AVP 8 0 18 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 <-------------> --- (13 headers 11 lines) --- Sending to 192.168.168.74 : 5060 (NAT) Using INVITE request as basis request - 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 <--- Reliably Transmitting (no NAT) to 192.168.168.74:5060 ---> SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK5a6f6369;received=192.168.168.74 From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: ;tag=as3d20e68c Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 CSeq: 101 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="1a20d711" Content-Length: 0 <------------> Scheduling destruction of SIP dialog '001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74' in 32000 ms (Method: INVITE) Found user '389' <--- SIP read from 192.168.168.74:5060 ---> INVITE sip:69@192.168.170.124 SIP/2.0 Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK5a6f6369 From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 Date: Sun, 20 May 2007 20:26:07 GMT CSeq: 101 INVITE User-Agent: CSCO/6 Contact: Expires: 180 Content-Type: application/sdp Content-Length: 251 Accept: application/sdp v=0 o=Cisco-SIPUA 19648 18021 IN IP4 192.168.168.74 s=SIP Call c=IN IP4 192.168.168.74 t=0 0 m=audio 26180 RTP/AVP 8 0 18 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 <-------------> --- (13 headers 11 lines) --- Ignoring this INVITE request Retransmitting #1 (no NAT) to 192.168.168.74:5060: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK5a6f6369;received=192.168.168.74 From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: ;tag=as3d20e68c Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 CSeq: 101 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="1a20d711" Content-Length: 0 --- <--- SIP read from 192.168.168.74:5060 ---> INVITE sip:69@192.168.170.124 SIP/2.0 Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK5a6f6369 From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 Date: Sun, 20 May 2007 20:26:07 GMT CSeq: 101 INVITE User-Agent: CSCO/6 Contact: Expires: 180 Content-Type: application/sdp Content-Length: 251 Accept: application/sdp v=0 o=Cisco-SIPUA 19648 18021 IN IP4 192.168.168.74 s=SIP Call c=IN IP4 192.168.168.74 t=0 0 m=audio 26180 RTP/AVP 8 0 18 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 <-------------> --- (13 headers 11 lines) --- Ignoring this INVITE request Retransmitting #2 (no NAT) to 192.168.168.74:5060: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK5a6f6369;received=192.168.168.74 From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: ;tag=as3d20e68c Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 CSeq: 101 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="1a20d711" Content-Length: 0 --- <--- SIP read from 192.168.168.74:5060 ---> INVITE sip:69@192.168.170.124 SIP/2.0 Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK5a6f6369 From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 Date: Sun, 20 May 2007 20:26:07 GMT CSeq: 101 INVITE User-Agent: CSCO/6 Contact: Expires: 180 Content-Type: application/sdp Content-Length: 251 Accept: application/sdp v=0 o=Cisco-SIPUA 19648 18021 IN IP4 192.168.168.74 s=SIP Call c=IN IP4 192.168.168.74 t=0 0 m=audio 26180 RTP/AVP 8 0 18 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 <-------------> --- (13 headers 11 lines) --- Ignoring this INVITE request Retransmitting #3 (no NAT) to 192.168.168.74:5060: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK5a6f6369;received=192.168.168.74 From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: ;tag=as3d20e68c Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 CSeq: 101 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="1a20d711" Content-Length: 0 --- <--- SIP read from 192.168.168.74:5060 ---> INVITE sip:69@192.168.170.124 SIP/2.0 Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK5a6f6369 From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 Date: Sun, 20 May 2007 20:26:07 GMT CSeq: 101 INVITE User-Agent: CSCO/6 Contact: Expires: 180 Content-Type: application/sdp Content-Length: 251 Accept: application/sdp v=0 o=Cisco-SIPUA 19648 18021 IN IP4 192.168.168.74 s=SIP Call c=IN IP4 192.168.168.74 t=0 0 m=audio 26180 RTP/AVP 8 0 18 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 <-------------> --- (13 headers 11 lines) --- Ignoring this INVITE request Retransmitting #4 (no NAT) to 192.168.168.74:5060: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK5a6f6369;received=192.168.168.74 From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: ;tag=as3d20e68c Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 CSeq: 101 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="1a20d711" Content-Length: 0 --- <--- SIP read from 192.168.168.74:5060 ---> ACK sip:69@192.168.170.124 SIP/2.0 Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK5a6f6369 From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: ;tag=as3d20e68c Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 Date: Sun, 20 May 2007 20:26:16 GMT CSeq: 101 ACK Content-Length: 0 <-------------> --- (8 headers 0 lines) --- <--- SIP read from 192.168.168.74:5060 ---> INVITE sip:69@192.168.170.124 SIP/2.0 Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK6df333be From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 Date: Sun, 20 May 2007 20:26:16 GMT CSeq: 102 INVITE User-Agent: CSCO/6 Contact: Proxy-Authorization: Digest username="389",realm="asterisk",uri="sip:192.168.170.124",response="620c82d697d752824ef59be85ae6dfff",nonce="1a20d711",algorithm=MD5Expires: 180 Content-Type: application/sdp Content-Length: 251 v=0 o=Cisco-SIPUA 19648 18021 IN IP4 192.168.168.74 s=SIP Call c=IN IP4 192.168.168.74 t=0 0 m=audio 26180 RTP/AVP 8 0 18 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 <-------------> --- (13 headers 11 lines) --- Sending to 192.168.168.74 : 5060 (no NAT) Using INVITE request as basis request - 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 Found user '389' Found RTP audio format 8 Found RTP audio format 0 Found RTP audio format 18 Found RTP audio format 101 Peer audio RTP is at port 192.168.168.74:26180 Found description format PCMA for ID 8 Found description format PCMU for ID 0 Found description format G729 for ID 18 Found description format telephone-event for ID 101 Capabilities: us - 0x8 (alaw), peer - audio=0x10c (ulaw|alaw|g729)/video=0x0 (nothing), combined - 0x8 (alaw) Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Peer audio RTP is at port 192.168.168.74:26180 [May 20 21:24:10] DEBUG[11219]: chan_sip.c:3078 update_call_counter: Call from user '389' is 1 out of 10 Looking for 69 in Internal (domain 192.168.170.124) [May 20 21:24:10] DEBUG[11219]: res_config_mysql.c:650 mysql_reconnect: MySQL RealTime: Everything is fine. [May 20 21:24:10] DEBUG[11219]: res_config_mysql.c:138 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '69' AND context = 'Internal_Numbers' AND priority = '1' list_route: hop: <--- Transmitting (no NAT) to 192.168.168.74:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK6df333be;received=192.168.168.74 From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <------------> [May 20 21:24:10] DEBUG[12958]: res_config_mysql.c:650 mysql_reconnect: MySQL RealTime: Everything is fine. [May 20 21:24:10] DEBUG[12958]: res_config_mysql.c:138 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '69' AND context = 'Internal_Numbers' AND priority = '1' [May 20 21:24:10] DEBUG[12958]: res_config_mysql.c:650 mysql_reconnect: MySQL RealTime: Everything is fine. [May 20 21:24:10] DEBUG[12958]: res_config_mysql.c:138 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '69' AND context = 'Internal_Numbers' AND priority = '1' [May 20 21:24:10] DEBUG[12958]: res_config_mysql.c:650 mysql_reconnect: MySQL RealTime: Everything is fine. [May 20 21:24:10] DEBUG[12958]: res_config_mysql.c:138 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '69' AND context = 'Internal_Numbers' AND priority = '1' [May 20 21:24:10] DEBUG[12958]: res_config_mysql.c:650 mysql_reconnect: MySQL RealTime: Everything is fine. [May 20 21:24:10] DEBUG[12958]: res_config_mysql.c:138 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '69' AND context = 'Internal_Numbers' AND priority = '1' -- Executing Macro("SIP/389-088d4b20", "dial_with_voicemail|69|SIP/69|Griffin") -- Executing [s@macro-dial_with_voicemail:1] Ringing("SIP/389-088d4b20", "") in new stack <--- Transmitting (no NAT) to 192.168.168.74:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK6df333be;received=192.168.168.74 From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: ;tag=as16560469 Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <------------> [May 20 21:24:10] DEBUG[12958]: app_macro.c:320 _macro_exec: Executed application: Ringing -- Executing [s@macro-dial_with_voicemail:2] Dial("SIP/389-088d4b20", "SIP/69|20") in new stack [May 20 21:24:10] DEBUG[12958]: chan_sip.c:3078 update_call_counter: Call to peer '69' is 1 out of 10 -- Called 69 [May 20 21:24:10] DEBUG[12958]: res_config_mysql.c:650 mysql_reconnect: MySQL RealTime: Everything is fine. [May 20 21:24:10] DEBUG[12958]: res_config_mysql.c:138 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '69' AND context = 'Internal_Numbers' AND priority = '-1' [May 20 21:24:10] DEBUG[12958]: res_config_mysql.c:650 mysql_reconnect: MySQL RealTime: Everything is fine. [May 20 21:24:10] DEBUG[12958]: res_config_mysql.c:258 realtime_multi_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'Internal_Numbers' AND priority = '-1' ORDER BY exten <--- SIP read from 192.168.168.74:5060 ---> CANCEL sip:69@192.168.170.124 SIP/2.0 Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK6df333be From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 Date: Sun, 20 May 2007 20:26:17 GMT CSeq: 102 CANCEL User-Agent: CSCO/6 Content-Length: 0 Proxy-Authorization: Digest username="389",realm="asterisk",uri="sip:192.168.170.124",response="b486e4c09de46c79bd0722ef5802d86a",nonce="1a20d711",algorithm=MD5 <-------------> --- (10 headers 0 lines) --- Sending to 192.168.168.74 : 5060 (no NAT) <--- Reliably Transmitting (no NAT) to 192.168.168.74:5060 ---> SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK6df333be;received=192.168.168.74 From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: ;tag=as16560469 Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> <--- Transmitting (no NAT) to 192.168.168.74:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK6df333be;received=192.168.168.74 From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: ;tag=as16560469 Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 CSeq: 102 CANCEL User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <------------> [May 20 21:24:11] DEBUG[12958]: cdr_addon_mysql.c:212 mysql_log: cdr_mysql: inserting a CDR record. [May 20 21:24:11] DEBUG[12958]: cdr_addon_mysql.c:228 mysql_log: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES ('2007-05-20 21:24:10','','','s','Internal', 'SIP/69-088b6370','','','',1,0,'NO ANSWER',3,'','1179692650.239','') [May 20 21:24:11] DEBUG[12958]: chan_sip.c:3052 update_call_counter: Call to peer '69' removed from call limit 10 [May 20 21:24:11] DEBUG[12958]: chan_sip.c:3078 update_call_counter: Call to peer '69' is 1 out of 10 == Spawn extension (macro-dial_with_voicemail, s, 2) exited non-zero on 'SIP/389-088d4b20' in macro 'dial_with_voicemail' == Spawn extension (macro-dial_with_voicemail, s, 2) exited non-zero on 'SIP/389-088d4b20' [May 20 21:24:11] DEBUG[12958]: cdr_addon_mysql.c:212 mysql_log: cdr_mysql: inserting a CDR record. [May 20 21:24:11] DEBUG[12958]: cdr_addon_mysql.c:228 mysql_log: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES ('2007-05-20 21:24:10','\"David Craigon\" <89>','89','69','Internal', 'SIP/389-088d4b20','SIP/69-088b6370','Dial','SIP/69|20',1,0,'NO ANSWER',3,'51052','1179692650.238','') [May 20 21:24:11] DEBUG[12958]: chan_sip.c:3052 update_call_counter: Call from user '389' removed from call limit 10 <--- SIP read from 192.168.168.74:5060 ---> ACK sip:69@192.168.170.124 SIP/2.0 Via: SIP/2.0/UDP 192.168.168.74:5060;branch=z9hG4bK6df333be From: "Anonymous" ;tag=001562ea6eaa01947bb96a6f-1c824dd5 To: ;tag=as16560469 Call-ID: 001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74 Date: Sun, 20 May 2007 20:26:17 GMT CSeq: 102 ACK Content-Length: 0 <-------------> --- (8 headers 0 lines) --- Really destroying SIP dialog '001562ea-6eaa00d0-0cc600bd-35253050@192.168.168.74' Method: ACK