Dec 30 01:42:58 DEBUG[11778] pbx.c: Not taking any branch
Dec 30 01:42:58 DEBUG[11778] pbx.c: Function result is '1'
Dec 30 01:42:58 DEBUG[11778] pbx.c: Expression result is '0'
Dec 30 01:42:58 VERBOSE[11778] logger.c: -- Executing GotoIf("SIP/100-089077d0", "0?chanfull") in new stack
Dec 30 01:42:58 DEBUG[11778] pbx.c: Not taking any branch
Dec 30 01:42:58 VERBOSE[11778] logger.c: -- Executing AGI("SIP/100-089077d0", "fixlocalprefix") in new stack
Dec 30 01:42:58 VERBOSE[11778] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
Dec 30 01:42:58 VERBOSE[11778] logger.c: fixlocalprefix: Could not parse /etc/asterisk/localprefixes.conf
Dec 30 01:42:58 VERBOSE[11778] logger.c: -- AGI Script fixlocalprefix completed, returning 0
Dec 30 01:42:58 VERBOSE[11778] logger.c: -- Executing Set("SIP/100-089077d0", "OUTNUM=96948806376") in new stack
Dec 30 01:42:58 DEBUG[11778] pbx.c: Function result is 'SIP/2130231710'
Dec 30 01:42:58 VERBOSE[11778] logger.c: -- Executing Set("SIP/100-089077d0", "custom=SIP/2130231710") in new stack
Dec 30 01:42:58 DEBUG[11778] pbx.c: Expression result is '0'
Dec 30 01:42:58 VERBOSE[11778] logger.c: -- Executing GotoIf("SIP/100-089077d0", "0?customtrunk") in new stack
Dec 30 01:42:58 DEBUG[11778] pbx.c: Not taking any branch
Dec 30 01:42:58 VERBOSE[11778] logger.c: -- Executing Dial("SIP/100-089077d0", "SIP/2130231710/96948806376|120|r") in new stack
Dec 30 01:42:58 DEBUG[11778] chan_sip.c: Setting NAT on RTP to 0
Dec 30 01:42:58 DEBUG[11778] chan_sip.c: Outgoing Call for 96948806376
Dec 30 01:42:58 VERBOSE[11778] logger.c: -- Called 2130231710/96948806376
Dec 30 01:42:58 DEBUG[2576] chan_sip.c: Acked pending invite 102
Dec 30 01:42:58 DEBUG[2576] chan_sip.c: Stopping retransmission on '75a39ad221d8bd05407cb6413915e9b2@192.168.254.24' of Request 102: Match Found
Dec 30 01:42:58 DEBUG[2576] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '75a39ad221d8bd05407cb6413915e9b2@192.168.254.24' Request 103: Found
Dec 30 01:42:58 DEBUG[2576] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '75a39ad221d8bd05407cb6413915e9b2@192.168.254.24' Request 103: Found
Dec 30 01:42:58 VERBOSE[11778] logger.c: -- SIP/2130231710-0890a3f0 is making progress passing it to SIP/100-089077d0
Dec 30 01:43:07 DEBUG[2576] chan_sip.c: Acked pending invite 103
Dec 30 01:43:07 DEBUG[2576] chan_sip.c: Stopping retransmission on '75a39ad221d8bd05407cb6413915e9b2@192.168.254.24' of Request 103: Match Found
Dec 30 01:43:07 VERBOSE[2576] logger.c: -- Got SIP response 486 "Busy Here" back from 194.30.193.119
Dec 30 01:43:07 VERBOSE[11778] logger.c: -- SIP/2130231710-0890a3f0 is busy
Dec 30 01:43:07 DEBUG[11778] chan_sip.c: update_call_counter(96948806376) - decrement call limit counter
Dec 30 01:43:07 VERBOSE[11778] logger.c: == Everyone is busy/congested at this time (1:1/0/0)
Dec 30 01:43:07 DEBUG[11778] app_dial.c: Exiting with DIALSTATUS=BUSY.
Dec 30 01:43:07 VERBOSE[11778] logger.c: -- Executing Goto("SIP/100-089077d0", "s-BUSY|1") in new stack
Dec 30 01:43:07 VERBOSE[11778] logger.c: -- Goto (macro-dialout-trunk,s-BUSY,1)
Dec 30 01:43:07 VERBOSE[11778] logger.c: -- Executing NoOp("SIP/100-089077d0", "Trunk is reporting BUSY") in new stack
Dec 30 01:43:07 VERBOSE[11778] logger.c: -- Executing Busy("SIP/100-089077d0", "") in new stack
Dec 30 01:43:07 VERBOSE[11778] logger.c: == Spawn extension (macro-dialout-trunk, s-BUSY, 2) exited non-zero on 'SIP/100-089077d0' in macro 'dialout-trunk'
Dec 30 01:43:07 VERBOSE[11778] logger.c: == Spawn extension (macro-dialout-trunk, s-BUSY, 2) exited non-zero on 'SIP/100-089077d0'
Dec 30 01:43:07 DEBUG[2576] chan_sip.c: Stopping retransmission on '99A1DD1E-9F9C-4EB5-9336-45678F120532@10.0.0.100' of Response 36181: Match Not Found
Dec 30 01:43:07 VERBOSE[11778] logger.c: -- Executing Macro("SIP/100-089077d0", "hangupcall") in new stack
Dec 30 01:43:07 VERBOSE[11778] logger.c: -- Executing ResetCDR("SIP/100-089077d0", "w") in new stack
Dec 30 01:43:07 DEBUG[11778] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Dec 30 01:43:07 DEBUG[11778] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES ('2006-12-30 01:42:57','2130231710','2130231710','96948806376','from-internal', 'SIP/100-089077d0','SIP/2130231710-0890a3f0','ResetCDR','w',10,0,'BUSY',3,'','1167435777.6')
Dec 30 01:43:07 VERBOSE[11778] logger.c: -- Executing NoCDR("SIP/100-089077d0", "") in new stack
Dec 30 01:43:07 NOTICE[11778] cdr.c: CDR on channel 'SIP/100-089077d0' not posted
Dec 30 01:43:07 NOTICE[11778] cdr.c: CDR on channel 'SIP/100-089077d0' lacks end
Dec 30 01:43:07 VERBOSE[11778] logger.c: -- Executing Wait("SIP/100-089077d0", "5") in new stack
Dec 30 01:43:07 VERBOSE[11778] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/100-089077d0' in macro 'hangupcall'
Dec 30 01:43:07 VERBOSE[11778] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/100-089077d0'
Dec 30 01:43:07 DEBUG[11778] chan_sip.c: update_call_counter(100) - decrement call limit counter
Dec 30 01:43:08 DEBUG[2576] chan_sip.c: Stopping retransmission on '5fc44f3f7e7131a75478758e5d38a5ec@10.0.0.1' of Request 102: Match Found
Dec 30 01:43:16 DEBUG[9616] manager.c: Manager received command 'Command'
Dec 30 01:43:16 DEBUG[9616] manager.c: Manager received command 'Command'
Dec 30 01:44:08 DEBUG[2576] chan_sip.c: Stopping retransmission on '4ceca46810eeb45d1be752c214948445@10.0.0.1' of Request 102: Match Found
Dec 30 01:44:09 DEBUG[2576] chan_sip.c: Scheduled a registration timeout for evoice.hol.gr id #351
Dec 30 01:44:09 DEBUG[2576] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '54011ec766f0e64f4a2c65a509f3e679@10.0.0.1' Request 121: Found
Dec 30 01:44:09 DEBUG[2576] chan_sip.c: Stopping retransmission on '54011ec766f0e64f4a2c65a509f3e679@10.0.0.1' of Request 121: Match Found
Dec 30 01:44:09 DEBUG[2576] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '54011ec766f0e64f4a2c65a509f3e679@10.0.0.1' Request 122: Found
Dec 30 01:44:09 DEBUG[2576] chan_sip.c: Stopping retransmission on '54011ec766f0e64f4a2c65a509f3e679@10.0.0.1' of Request 122: Match Found
Dec 30 01:44:09 DEBUG[2576] chan_sip.c: Registration successful
Dec 30 01:44:09 DEBUG[2576] chan_sip.c: Cancelling timeout 351
Dec 30 01:44:41 DEBUG[2576] chan_sip.c: Auto destroying call '54011ec766f0e64f4a2c65a509f3e679@10.0.0.1'
Dec 30 01:44:41 DEBUG[2576] chan_sip.c: Auto destroying call '54011ec766f0e64f4a2c65a509f3e679@10.0.0.1'