Sep 28 15:28:00 VERBOSE[16488] logger.c:     -- Attempting call on ZAP/g0/13093466269 for s@custom-neil01-cmpg_neil01_wise_26_09_06:1 (Retry 1)
Sep 28 15:28:25 VERBOSE[16488] logger.c:     -- Executing Set("Zap/22-1", "TIMEOUT(absolute) = 40") in new stack
Sep 28 15:28:25 VERBOSE[16488] logger.c:     -- Executing Macro("Zap/22-1", "record-dial_result|cmpg_neil01_wise_26_09_06|13093466269|9|4405|Dialed") in new stack
Sep 28 15:28:25 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Connect connid localhost root nyana cmpg_neil01_wise_26_09_06") in new stack
Sep 28 15:28:25 DEBUG[16488] pbx.c: Function result is '2006-09-28 19:28:25'
Sep 28 15:28:25 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Query resultid 1 INSERT INTO results VALUES ('13093466269','Dialed','2006-09-28 19:28:25','','',9,4405)") in new stack
Sep 28 15:28:25 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Clear ") in new stack
Sep 28 15:28:25 WARNING[16488] app_addon_sql_mysql.c: Identifier 0, identifier_type 2 not found in identifier list
Sep 28 15:28:25 WARNING[16488] app_addon_sql_mysql.c: Invalid result identifier 0 passed in aMYSQL_clear
Sep 28 15:28:25 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Disconnect 1") in new stack
Sep 28 15:28:25 VERBOSE[16488] logger.c:     -- Executing NoOp("Zap/22-1", "Using Zap/22-1") in new stack
Sep 28 15:28:25 VERBOSE[16488] logger.c:     -- Executing Answer("Zap/22-1", "") in new stack
Sep 28 15:28:25 VERBOSE[16488] logger.c:     -- Executing AMD("Zap/22-1", "3500|1500|300|5000|120|50|5|256") in new stack
Sep 28 15:28:25 VERBOSE[16488] logger.c:     -- AMD: Zap/22-1 8665487636 (null) (Fmt: 4)
Sep 28 15:28:25 VERBOSE[16488] logger.c:     -- AMD: initialSilence [3500] greeting [1500] afterGreetingSilence [300] totalAnalysisTime [5000] minimumWordLength [120] betweenWordsSilence [50] maximumNumberOfWords [5] silenceThreshold [256]
Sep 28 15:28:25 VERBOSE[16488] logger.c:     -- AMD: Word detected. iWordsCount:1
Sep 28 15:28:26 VERBOSE[16488] logger.c:     -- AMD: Changed state to STATE_IN_SILENCE
Sep 28 15:28:26 VERBOSE[16488] logger.c:     -- AMD: Word detected. iWordsCount:2
Sep 28 15:28:26 VERBOSE[16488] logger.c:     -- AMD: Changed state to STATE_IN_SILENCE
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- AMD: Word detected. iWordsCount:3
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- AMD: Changed state to STATE_IN_SILENCE
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- AMD: Word detected. iWordsCount:4
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- AMD: Changed state to STATE_IN_SILENCE
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- AMD: ANSWERING MACHINE: voiceDuration:1500 greeting:1500
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing GotoIf("Zap/22-1", "1?machine|1") in new stack
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Goto (custom-neil01-cmpg_neil01_wise_26_09_06,machine,1)
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing Macro("Zap/22-1", "record-dial_result1|cmpg_neil01_wise_26_09_06|13093466269|9|4405|AnsweringMachine|phone_numbers_9|4405") in new stack
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Connect connid localhost root nyana cmpg_neil01_wise_26_09_06") in new stack
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is '2006-09-28 19:28:27'
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Query resultid 1 INSERT INTO results VALUES ('13093466269','AnsweringMachine','2006-09-28 19:28:27','','',9,4405)") in new stack
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Clear ") in new stack
Sep 28 15:28:27 WARNING[16488] app_addon_sql_mysql.c: Identifier 0, identifier_type 2 not found in identifier list
Sep 28 15:28:27 WARNING[16488] app_addon_sql_mysql.c: Invalid result identifier 0 passed in aMYSQL_clear
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Disconnect 1") in new stack
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Connect connid localhost root nyana neil01") in new stack
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is '2006-09-28 19:28:27'
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Query resultid 1 update phone_numbers_9 set dial_counter=dial_counter+1, last_dial_date='2006-09-28 19:28:27', last_dial_result='AnsweringMachine' where phone_number_id=4405") in new stack
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Clear ") in new stack
Sep 28 15:28:27 WARNING[16488] app_addon_sql_mysql.c: Identifier 0, identifier_type 2 not found in identifier list
Sep 28 15:28:27 WARNING[16488] app_addon_sql_mysql.c: Invalid result identifier 0 passed in aMYSQL_clear
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Disconnect 1") in new stack
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Connect connid localhost root nyana neil01") in new stack
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is '2006-09-28 19:28:27'
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Query resultid 1 update phone_numbers_9 set dial_counter=dial_counter+1, last_dial_date='2006-09-28 19:28:27', last_dial_result='AnsweringMachine' where phone_number_id=4405") in new stack
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Clear ") in new stack
Sep 28 15:28:27 WARNING[16488] app_addon_sql_mysql.c: Identifier 0, identifier_type 2 not found in identifier list
Sep 28 15:28:27 WARNING[16488] app_addon_sql_mysql.c: Invalid result identifier 0 passed in aMYSQL_clear
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Disconnect 1") in new stack
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing Set("Zap/22-1", "skip_hangup=1") in new stack
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing Hangup("Zap/22-1", "") in new stack
Sep 28 15:28:27 VERBOSE[16488] logger.c:   == Spawn extension (custom-neil01-cmpg_neil01_wise_26_09_06, machine, 3) exited non-zero on 'Zap/22-1'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Expression result is '1'
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing GotoIf("Zap/22-1", "1?3") in new stack
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Goto (custom-neil01-cmpg_neil01_wise_26_09_06,h,3)
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing Macro("Zap/22-1", "record-decrement-line-counter|cmpg_neil01_wise_26_09_06") in newstack
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Connect connid localhost root nyana cmpg_neil01_wise_26_09_06") in new stack
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Query resultid 1 update status set campaign_lines_inuse=campaign_lines_inuse-1") in new stack
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Clear ") in new stack
Sep 28 15:28:27 WARNING[16488] app_addon_sql_mysql.c: Identifier 0, identifier_type 2 not found in identifier list
Sep 28 15:28:27 WARNING[16488] app_addon_sql_mysql.c: Invalid result identifier 0 passed in aMYSQL_clear
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing MYSQL("Zap/22-1", "Disconnect 1") in new stack
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing NoOp("Zap/22-1", "Hangingup Zap/22-1") in new stack
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Executing Hangup("Zap/22-1", "") in new stack
Sep 28 15:28:27 VERBOSE[16488] logger.c:   == Spawn extension (custom-neil01-cmpg_neil01_wise_26_09_06, h, 5) exited non-zero on 'Zap/22-1'
Sep 28 15:28:27 DEBUG[16488] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Sep 28 15:28:27 DEBUG[16488] 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) VALUES ('2006-09-28 15:28:25','8665487636','8665487636','h','custom-neil01-cmpg_neil01_wise_26_09_06', 'Zap/22-1','','Hangup','',2,2,'ANSWERED',3,'')
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is '8665487636'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is '8665487636'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is 'h'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is 'custom-neil01-cmpg_neil01_wise_26_09_06'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is 'Zap/22-1'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is '(null)'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is 'Hangup'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is '(null)'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is '2006-09-28 15:28:25'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is '2006-09-28 15:28:25'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is '2006-09-28 15:28:27'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is '2'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is '2'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is 'ANSWERED'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is 'DOCUMENTATION'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is '(null)'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is '1159471680.9246'
Sep 28 15:28:27 DEBUG[16488] pbx.c: Function result is '(null)'
Sep 28 15:28:27 ERROR[16488] cdr_custom.c: Unable to re-open master file /var/log/asterisk/cdr-custom/Master.csv : No such file or directory
Sep 28 15:28:27 DEBUG[16488] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/22-1
Sep 28 15:28:27 DEBUG[16488] chan_zap.c: Hangup: channel: 22 index = 0, normal = 34, callwait = -1, thirdcall = -1
Sep 28 15:28:27 DEBUG[16488] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
Sep 28 15:28:27 DEBUG[16488] chan_zap.c: disabled echo cancellation on channel 22
Sep 28 15:28:27 DEBUG[16488] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/22-1
Sep 28 15:28:27 DEBUG[16488] chan_zap.c: Updated conferencing on 22, with 0 conference users
Sep 28 15:28:27 DEBUG[16488] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/22-1
Sep 28 15:28:27 DEBUG[16488] chan_zap.c: disabled echo cancellation on channel 22
Sep 28 15:28:27 VERBOSE[16488] logger.c:     -- Hungup 'Zap/22-1'
Sep 28 15:28:27 NOTICE[16488] pbx_spool.c: Call completed to ZAP/g0/13093466269



Sep 28 15:28:28 VERBOSE[16794] logger.c:     -- Attempting call on ZAP/g0/17248827165 for s@custom-neil01-cmpg_neil01_to0928:1 (Retry 1)
Sep 28 15:28:28 VERBOSE[16794] logger.c:     -- Requested transfer capability: 0x00 - SPEECH
Sep 28 15:28:28 DEBUG[2158] channel.c: Avoiding initial deadlock for 'Zap/22-1'
Sep 28 15:28:28 DEBUG[2158] channel.c: Avoiding initial deadlock for 'Zap/22-1'



Sep 28 15:28:29 DEBUG[2167] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/22 span 1
Sep 28 15:28:29 NOTICE[16794] channel.c: Don't know what to do with control frame 15
Sep 28 15:28:32 DEBUG[2167] chan_zap.c: Queuing frame from PRI_EVENT_PROGRESS on channel 0/22 span 1



Sep 28 15:28:59 DEBUG[16794] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Sep 28 15:28:29 NOTICE[16794] channel.c: Don't know what to do with control frame 15
Sep 28 15:28:59 DEBUG[16794] 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) VALUES ('2006-09-28 15:28:59','8665487636','8665487636','s','custom-neil01-cmpg_neil01_to0928', 'Zap/22-1','','Dial','ZAP/g0/17248827165',0,0,'FAILED',3,'')
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '8665487636'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '8665487636'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 's'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 'custom-neil01-cmpg_neil01_to0928'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 'Zap/22-1'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 'Dial'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 'ZAP/g0/17248827165'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '2006-09-28 15:28:59'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '2006-09-28 15:28:59'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '0'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '0'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 'FAILED'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 'DOCUMENTATION'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '1159471708.9305'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 ERROR[16794] cdr_custom.c: Unable to re-open master file /var/log/asterisk/cdr-custom/Master.csv : No such file or directory
Sep 28 15:28:59 DEBUG[16794] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/22-1
Sep 28 15:28:59 DEBUG[16794] chan_zap.c: Hangup: channel: 22 index = 0, normal = 34, callwait = -1, thirdcall = -1
Sep 28 15:28:59 DEBUG[16794] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
Sep 28 15:28:59 DEBUG[16794] chan_zap.c: disabled echo cancellation on channel 22
Sep 28 15:28:59 DEBUG[16794] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/22-1
Sep 28 15:28:59 DEBUG[16794] chan_zap.c: Updated conferencing on 22, with 0 conference users
Sep 28 15:28:59 DEBUG[16794] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/22-1
Sep 28 15:28:59 DEBUG[16794] chan_zap.c: disabled echo cancellation on channel 22
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Hungup 'Zap/22-1'
Sep 28 15:28:59 DEBUG[16794] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Sep 28 15:28:59 DEBUG[16794] 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) VALUES ('2006-09-28 15:28:59','','','s','default', '**Unknown**','','','',0,0,'FAILED',3,'')
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 's'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 'default'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '**Unknown**'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '2006-09-28 15:28:59'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '2006-09-28 15:28:59'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '0'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '0'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 'FAILED'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 'DOCUMENTATION'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '1159471739.9369'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 ERROR[16794] cdr_custom.c: Unable to re-open master file /var/log/asterisk/cdr-custom/Master.csv : No such file or directory
Sep 28 15:28:59 DEBUG[16794] pbx.c: Expression result is '0'
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing GotoIf("OutgoingSpoolFailed", "0?noanswer|1") in new stack
Sep 28 15:28:59 DEBUG[16794] pbx.c: Not taking any branch
Sep 28 15:28:59 DEBUG[16794] pbx.c: Expression result is '1'
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing GotoIf("OutgoingSpoolFailed", "1?disconnected|1") in new stack
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Goto (custom-neil01-cmpg_neil01_to0928,disconnected,1)
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing Macro("OutgoingSpoolFailed", "record-dial_result1|cmpg_neil01_to0928|17248827165|10|3849|Disconnected|phone_numbers_10|3849") in new stack
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing MYSQL("OutgoingSpoolFailed", "Connect connid localhost root nyana cmpg_neil01_to0928") in new stack
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '2006-09-28 19:28:59'
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing MYSQL("OutgoingSpoolFailed", "Query resultid 1 INSERT INTO results VALUES ('17248827165','Disconnected','2006-09-28 19:28:59','','',10,3849)") in new stack
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing MYSQL("OutgoingSpoolFailed", "Clear ") in new stack
Sep 28 15:28:59 WARNING[16794] app_addon_sql_mysql.c: Identifier 0, identifier_type 2 not found in identifier list
Sep 28 15:28:59 WARNING[16794] app_addon_sql_mysql.c: Invalid result identifier 0 passed in aMYSQL_clear
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing MYSQL("OutgoingSpoolFailed", "Disconnect 1") in new stack
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing MYSQL("OutgoingSpoolFailed", "Connect connid localhost root nyana neil01") in new stack
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '2006-09-28 19:28:59'
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing MYSQL("OutgoingSpoolFailed", "Query resultid 1 update phone_numbers_10 set dial_counter=dial_counter+1, last_dial_date='2006-09-28 19:28:59', last_dial_result='Disconnected' where phone_number_id=3849") in new stack
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing MYSQL("OutgoingSpoolFailed", "Clear ") in new stack
Sep 28 15:28:59 WARNING[16794] app_addon_sql_mysql.c: Identifier 0, identifier_type 2 not found in identifier list
Sep 28 15:28:59 WARNING[16794] app_addon_sql_mysql.c: Invalid result identifier 0 passed in aMYSQL_clear
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing MYSQL("OutgoingSpoolFailed", "Disconnect 1") in new stack
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing Set("OutgoingSpoolFailed", "skip_hangup=1") in new stack
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing Hangup("OutgoingSpoolFailed", "") in new stack
Sep 28 15:28:59 VERBOSE[16794] logger.c:   == Spawn extension (custom-neil01-cmpg_neil01_to0928, disconnected, 3) exited non-zero on 'OutgoingSpoolFailed'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Expression result is '1'
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing GotoIf("OutgoingSpoolFailed", "1?3") in new stack
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Goto (custom-neil01-cmpg_neil01_to0928,h,3)
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing Macro("OutgoingSpoolFailed", "record-decrement-line-counter|cmpg_neil01_to0928") innew stack
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing MYSQL("OutgoingSpoolFailed", "Connect connid localhost root nyana cmpg_neil01_to0928") in new stack
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing MYSQL("OutgoingSpoolFailed", "Query resultid 1 update status set campaign_lines_inuse=campaign_lines_inuse-1") in new stack
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing MYSQL("OutgoingSpoolFailed", "Clear ") in new stack
Sep 28 15:28:59 WARNING[16794] app_addon_sql_mysql.c: Identifier 0, identifier_type 2 not found in identifier list
Sep 28 15:28:59 WARNING[16794] app_addon_sql_mysql.c: Invalid result identifier 0 passed in aMYSQL_clear
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing MYSQL("OutgoingSpoolFailed", "Disconnect 1") in new stack
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing NoOp("OutgoingSpoolFailed", "Hangingup OutgoingSpoolFailed") in new stack
Sep 28 15:28:59 VERBOSE[16794] logger.c:     -- Executing Hangup("OutgoingSpoolFailed", "") in new stack
Sep 28 15:28:59 VERBOSE[16794] logger.c:   == Spawn extension (custom-neil01-cmpg_neil01_to0928, h, 5) exited non-zero on 'OutgoingSpoolFailed'
Sep 28 15:28:59 DEBUG[16794] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Sep 28 15:28:59 DEBUG[16794] 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) VALUES ('2006-09-28 15:28:59','','','h','custom-neil01-cmpg_neil01_to0928', 'OutgoingSpoolFailed','','Hangup','',0,0,'NO ANSWER',3,'')
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 'h'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 'custom-neil01-cmpg_neil01_to0928'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 'OutgoingSpoolFailed'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 'Hangup'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '2006-09-28 15:28:59'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '2006-09-28 15:28:59'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '0'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '0'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 'NO ANSWER'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is 'DOCUMENTATION'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '1159471739.9370'
Sep 28 15:28:59 DEBUG[16794] pbx.c: Function result is '(null)'
Sep 28 15:28:59 ERROR[16794] cdr_custom.c: Unable to re-open master file /var/log/asterisk/cdr-custom/Master.csv : No such file or directory
Sep 28 15:28:59 NOTICE[16794] pbx_spool.c: Call failed to go through, reason 0