Nov 11 11:28:03 DEBUG[1329]: Expression is '0'
Nov 11 11:28:03 VERBOSE[1329]: -- Executing GotoIf("SIP/7908091-96bf", "0?4:3") in new stack
Nov 11 11:28:03 VERBOSE[1329]: -- Goto (macro-dial,s,3)
Nov 11 11:28:03 VERBOSE[1329]: -- Executing SetCIDName("SIP/7908091-96bf", "Unknown") in new stack
Nov 11 11:28:03 VERBOSE[1329]: -- Executing AGI("SIP/7908091-96bf", "dialparties.agi") in new stack
Nov 11 11:28:03 VERBOSE[1329]: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
Nov 11 11:28:03 VERBOSE[1329]: -- AGI Script dialparties.agi completed, returning 0
Nov 11 11:28:03 VERBOSE[1329]: -- Executing NoOp("SIP/7908091-96bf", "Returned from dialparties with no extensions to call") in new stack
Nov 11 11:28:03 VERBOSE[1329]: -- Executing SetVar("SIP/7908091-96bf", "DIALSTATUS=BUSY") in new stack
Nov 11 11:28:03 DEBUG[1329]: Expression is '0'
Nov 11 11:28:03 VERBOSE[1329]: -- Executing GotoIf("SIP/7908091-96bf", "0?s-BUSY|1") in new stack
Nov 11 11:28:03 DEBUG[1329]: Not taking any branch
Nov 11 11:28:03 DEBUG[1329]: Expression is '1'
Nov 11 11:28:03 VERBOSE[1329]: -- Executing GotoIf("SIP/7908091-96bf", "1?s-BUSY|1") in new stack
Nov 11 11:28:03 VERBOSE[1329]: -- Goto (macro-exten-vm,s-BUSY,1)
Nov 11 11:28:03 VERBOSE[1329]: -- Executing NoOp("SIP/7908091-96bf", "Extension is reporting BUSY and has no Voicemail") in new stack
Nov 11 11:28:03 VERBOSE[1329]: -- Executing Busy("SIP/7908091-96bf", "") in new stack
Nov 11 11:28:03 VERBOSE[1329]: == Spawn extension (macro-exten-vm, s-BUSY, 2) exited non-zero on 'SIP/7908091-96bf' in macro 'exten-vm'
Nov 11 11:28:03 VERBOSE[1329]: == Spawn extension (ext-local, 200, 1) exited non-zero on 'SIP/7908091-96bf'
Nov 11 11:28:03 DEBUG[1329]: cdr_mysql: inserting a CDR record.
Nov 11 11:28:03 DEBUG[1329]: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode) VALUES ('2005-11-11 11:28:03','\"Unknown\" ','Unknown','200','ext-local', 'SIP/7908091-96bf','','Busy','',0,0,'NO ANSWER',3,'')
Nov 11 11:28:03 DEBUG[1329]: update_user_counter(7908091) - decrement inUse counter
Nov 11 11:28:03 DEBUG[1329]: 7908091 is not a local user
Nov 11 11:28:03 DEBUG[1329]: Stopping retransmission on '3043be8100d7015a6dd1b6e90a9b1fb2@gw02.uk.sipgate.net' of Response 102: Not Found
Nov 11 11:28:04 DEBUG[1329]: Stopping retransmission on '1a70fa59181d11271b0cd73b1bf25191@192.168.2.6' of Request 102: Found
Nov 11 11:28:04 DEBUG[1329]: Stopping retransmission on '0f8f8953408b075a60b9957741e8ce29@192.168.2.6' of Request 102: Found