Nov 11 18:10:25 DEBUG[114695]: Check for res for Nov 11 18:10:25 DEBUG[114695]: is not a local user Nov 11 18:10:25 DEBUG[114695]: build_route: Contact hop: Nov 11 18:10:25 VERBOSE[376854]: -- Executing Wait("SIP/66.114.83.12-08163c10", "1") in new stack Nov 11 18:10:26 VERBOSE[376854]: -- Executing AbsoluteTimeout("SIP/66.114.83.12-08163c10", "900") in new stack Nov 11 18:10:26 VERBOSE[376854]: -- Set Absolute Timeout to 900 Nov 11 18:10:26 VERBOSE[376854]: -- Executing AGI("SIP/66.114.83.12-08163c10", "test.agi") in new stack Nov 11 18:10:26 VERBOSE[376854]: -- Launched AGI Script /usr/local/asterisk/var/lib/asterisk/agi-bin/test.agi Nov 11 18:10:27 VERBOSE[376854]: -- AGI Script Executing Application: (Dial) Options: (SIP/121281212070000|10|tT) Nov 11 18:10:27 DEBUG[376854]: SIMPLE DIAL (NO URL) Nov 11 18:10:27 DEBUG[376854]: Setting NAT on RTP to 4 Nov 11 18:10:27 DEBUG[376854]: Outgoing Call for 121281212070000 Nov 11 18:10:27 DEBUG[376854]: Call from user '121281212070000' is 1 out of 0 Nov 11 18:10:27 VERBOSE[376854]: -- Called 121281212070000 Nov 11 18:10:27 DEBUG[114695]: (Provisional) Stopping retransmission (but retaining packet) on '271f85f34afba81012bb597c67895d81@66.114.83.67' Request 102: Found Nov 11 18:10:28 DEBUG[114695]: (Provisional) Stopping retransmission (but retaining packet) on '271f85f34afba81012bb597c67895d81@66.114.83.67' Request 102: Found Nov 11 18:10:28 VERBOSE[376854]: -- SIP/121281212070000-270f is ringing Nov 11 18:10:31 DEBUG[114695]: Auto destroying call '0009e881-22b811d0-4e35cbad-2b382cf7@66.114.76.250' Nov 11 18:10:32 DEBUG[114695]: Acked pending invite 102 Nov 11 18:10:32 DEBUG[114695]: Stopping retransmission on '271f85f34afba81012bb597c67895d81@66.114.83.67' of Request 102: Found Nov 11 18:10:32 DEBUG[114695]: build_route: Contact hop: Nov 11 18:10:32 VERBOSE[376854]: -- SIP/121281212070000-270f answered SIP/66.114.83.12-08163c10 Nov 11 18:10:32 VERBOSE[376854]: -- Attempting native bridge of SIP/66.114.83.12-08163c10 and SIP/121281212070000-270f Nov 11 18:10:32 DEBUG[114695]: Stopping retransmission on '1aa7e8867ec0a5ee758b03f2717ead5a@66.114.83.12' of Response 102: Found Nov 11 18:10:33 DEBUG[376854]: Ooh, format changed from UNKN to ULAW Nov 11 18:10:33 DEBUG[376854]: Ooh, format changed from UNKN to ULAW Nov 11 18:10:35 VERBOSE[114695]: -- Started music on hold, class 'default', on SIP/66.114.83.12-08163c10 Nov 11 18:10:35 DEBUG[376854]: Difference is 1640, ms is 225 Nov 11 18:10:36 DEBUG[114695]: Stopping retransmission on '271f85f34afba81012bb597c67895d81@66.114.83.67' of Response 101: Found Nov 11 18:10:38 DEBUG[114695]: We found a REFER! Nov 11 18:10:38 DEBUG[114695]: Assigning Extension 1100 to REFER-TO Nov 11 18:10:38 DEBUG[114695]: Assigning Extension 121281212070000 to REFERRED-BY Nov 11 18:10:38 DEBUG[114695]: Assigning Contact Info to REFER_CONTACT Nov 11 18:10:38 DEBUG[114695]: 202 Accepted (blind) Nov 11 18:10:38 VERBOSE[114695]: -- Stopped music on hold on SIP/66.114.83.12-08163c10 Nov 11 18:10:38 DEBUG[376854]: Didn't get a frame from channel: SIP/66.114.83.12-08163c10 Nov 11 18:10:38 DEBUG[376854]: Bridge stops bridging channels SIP/66.114.83.12-08163c10 and SIP/121281212070000-270f Nov 11 18:10:38 DEBUG[376854]: update_user_counter(121281212070000) - decrement inUse counter Nov 11 18:10:38 DEBUG[376854]: Exiting with DIALSTATUS=ANSWER. Nov 11 18:10:38 VERBOSE[376854]: -- AGI Script test.agi completed, returning 0 Nov 11 18:10:38 DEBUG[376854]: Extension 1100, priority 0 returned normally even though call was hung up Nov 11 18:10:38 DEBUG[376854]: cdr_pgsql: inserting a CDR record. Nov 11 18:10:38 DEBUG[376854]: cdr_pgsql: SQL command executed: INSERT INTO voice_cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES ('2004-11-11 18:10:25','"2128121201" <2128121201>','2128121201','12128121207','sip', 'SIP/66.114.83.12-08163c10','SIP/121281212070000-270f','Dial','SIP/121281212070000|10|tT',13,6,'ANSWERED',3,'','1100214625.2','') Nov 11 18:10:38 DEBUG[376854]: update_user_counter() - decrement inUse counter Nov 11 18:10:38 DEBUG[376854]: is not a local user Nov 11 18:10:38 DEBUG[114695]: Stopping retransmission on '1aa7e8867ec0a5ee758b03f2717ead5a@66.114.83.12' of Request 102: Found Nov 11 18:10:38 DEBUG[114695]: Stopping retransmission on '271f85f34afba81012bb597c67895d81@66.114.83.67' of Request 103: Found Nov 11 18:10:39 DEBUG[114695]: Stopping retransmission on '271f85f34afba81012bb597c67895d81@66.114.83.67' of Request 104: Found