Oct  3 19:06:25 VERBOSE[27650] logger.c:     -- Accepting call from '' to '7475858' on channel 0/1, span 1
Oct  3 19:06:25 DEBUG[27650] chan_zap.c: Enabled echo cancellation on channel 1
Oct  3 19:06:25 VERBOSE[545] logger.c:     -- Executing Set("Zap/1-1", "FROM_DID=7475858") in new stack
Oct  3 19:06:25 VERBOSE[545] logger.c:     -- Executing Set("Zap/1-1", "FAX_RX=system") in new stack
Oct  3 19:06:25 VERBOSE[545] logger.c:     -- Executing Set("Zap/1-1", "FAX_RX_EMAIL=tim@centrisys.com") in new stack
Oct  3 19:06:25 VERBOSE[545] logger.c:     -- Executing Answer("Zap/1-1", "") in new stack
Oct  3 19:06:25 DEBUG[27642] channel.c: Avoiding initial deadlock for 'Zap/1-1'
Oct  3 19:06:25 VERBOSE[545] logger.c:     -- Executing Wait("Zap/1-1", "10") in new stack
Oct  3 19:06:29 DEBUG[545] chan_zap.c: DTMF digit: f on Zap/1-1
Oct  3 19:06:29 VERBOSE[545] logger.c:     -- Redirecting Zap/1-1 to fax extension
Oct  3 19:06:29 VERBOSE[545] logger.c:   == Spawn extension (from-pstn, fax, 0) exited non-zero on 'Zap/1-1'
Oct  3 19:06:29 VERBOSE[545] logger.c:     -- Executing Goto("Zap/1-1", "ext-fax|in_fax|1") in new stack
Oct  3 19:06:29 VERBOSE[545] logger.c:     -- Goto (ext-fax,in_fax,1)
Oct  3 19:06:29 VERBOSE[545] logger.c:     -- Executing StopPlayTones("Zap/1-1", "") in new stack
Oct  3 19:06:29 DEBUG[545] pbx.c: Expression result is '1'
Oct  3 19:06:29 VERBOSE[545] logger.c:     -- Executing GotoIf("Zap/1-1", "1?3:analog_fax|1") in new stack
Oct  3 19:06:29 VERBOSE[545] logger.c:     -- Goto (ext-fax,in_fax,3)
Oct  3 19:06:29 VERBOSE[545] logger.c:     -- Executing Macro("Zap/1-1", "faxreceive") in new stack
Oct  3 19:06:29 VERBOSE[545] logger.c:     -- Executing Set("Zap/1-1", "FAXFILE=/var/spool/asterisk/fax/1159923985.72.tif") in new stack
Oct  3 19:06:29 VERBOSE[545] logger.c:     -- Executing Set("Zap/1-1", "EMAILADDR=tim@centrisys.com") in new stack
Oct  3 19:06:29 WARNING[545] pbx.c: No application 'rxfax' for extension (macro-faxreceive, s, 3)
Oct  3 19:06:29 VERBOSE[545] logger.c:   == Spawn extension (macro-faxreceive, s, 3) exited non-zero on 'Zap/1-1' in macro 'faxreceive'
Oct  3 19:06:29 VERBOSE[545] logger.c:   == Spawn extension (macro-faxreceive, s, 3) exited non-zero on 'Zap/1-1'
Oct  3 19:06:29 DEBUG[545] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Oct  3 19:06:29 DEBUG[545] 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-10-03 19:06:25','','','in_fax','ext-fax', 'Zap/1-1','','Set','EMAILADDR=tim@centrisys.com',4,4,'ANSWERED',3,'')
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is '(null)'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is '(null)'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is 'in_fax'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is 'ext-fax'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is 'Zap/1-1'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is '(null)'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is 'Set'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is 'EMAILADDR=tim@centrisys.com'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is '2006-10-03 19:06:25'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is '2006-10-03 19:06:25'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is '2006-10-03 19:06:29'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is '4'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is '4'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is 'ANSWERED'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is 'DOCUMENTATION'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is '(null)'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is '1159923985.72'
Oct  3 19:06:29 DEBUG[545] pbx.c: Function result is '(null)'
Oct  3 19:06:29 DEBUG[545] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/1-1
Oct  3 19:06:29 DEBUG[545] chan_zap.c: Hangup: channel: 1 index = 0, normal = 12, callwait = -1, thirdcall = -1
Oct  3 19:06:29 DEBUG[545] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
Oct  3 19:06:29 DEBUG[545] chan_zap.c: disabled echo cancellation on channel 1
Oct  3 19:06:29 DEBUG[545] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1
Oct  3 19:06:29 DEBUG[545] chan_zap.c: Updated conferencing on 1, with 0 conference users
Oct  3 19:06:29 DEBUG[545] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/1-1
Oct  3 19:06:29 DEBUG[545] chan_zap.c: disabled echo cancellation on channel 1
Oct  3 19:06:29 VERBOSE[545] logger.c:     -- Hungup 'Zap/1-1'
Oct  3 19:06:32 VERBOSE[27650] logger.c:     -- Accepting call from '' to '7475858' on channel 0/1, span 1
Oct  3 19:06:32 DEBUG[27650] chan_zap.c: Enabled echo cancellation on channel 1
Oct  3 19:06:32 VERBOSE[549] logger.c:     -- Executing Set("Zap/1-1", "FROM_DID=7475858") in new stack
Oct  3 19:06:32 VERBOSE[549] logger.c:     -- Executing Set("Zap/1-1", "FAX_RX=system") in new stack
Oct  3 19:06:32 VERBOSE[549] logger.c:     -- Executing Set("Zap/1-1", "FAX_RX_EMAIL=tim@centrisys.com") in new stack
Oct  3 19:06:32 VERBOSE[549] logger.c:     -- Executing Answer("Zap/1-1", "") in new stack
Oct  3 19:06:32 DEBUG[27642] channel.c: Avoiding initial deadlock for 'Zap/1-1'
Oct  3 19:06:32 VERBOSE[549] logger.c:     -- Executing Wait("Zap/1-1", "10") in new stack



    -- Accepting call from '' to '7475858' on channel 0/1, span 1
    -- Executing Set("Zap/1-1", "FROM_DID=7475858") in new stack
    -- Executing Set("Zap/1-1", "FAX_RX=system") in new stack
    -- Executing Set("Zap/1-1", "FAX_RX_EMAIL=tim@centrisys.com") in new stack
    -- Executing Answer("Zap/1-1", "") in new stack
    -- Executing Wait("Zap/1-1", "10") in new stack
    -- Redirecting Zap/1-1 to fax extension
  == Spawn extension (from-pstn, fax, 0) exited non-zero on 'Zap/1-1'
    -- Executing Goto("Zap/1-1", "ext-fax|in_fax|1") in new stack
    -- Goto (ext-fax,in_fax,1)
    -- Executing StopPlayTones("Zap/1-1", "") in new stack
    -- Executing GotoIf("Zap/1-1", "1?3:analog_fax|1") in new stack
    -- Goto (ext-fax,in_fax,3)
    -- Executing Macro("Zap/1-1", "faxreceive") in new stack
    -- Executing Set("Zap/1-1", "FAXFILE=/var/spool/asterisk/fax/1159923985.72.tif") in new stack
    -- Executing Set("Zap/1-1", "EMAILADDR=tim@centrisys.com") in new stack
  == Spawn extension (macro-faxreceive, s, 3) exited non-zero on 'Zap/1-1' in macro 'faxreceive'
  == Spawn extension (macro-faxreceive, s, 3) exited non-zero on 'Zap/1-1'
    -- Hungup 'Zap/1-1'
    -- Accepting call from '' to '7475858' on channel 0/1, span 1
    -- Executing Set("Zap/1-1", "FROM_DID=7475858") in new stack
    -- Executing Set("Zap/1-1", "FAX_RX=system") in new stack
    -- Executing Set("Zap/1-1", "FAX_RX_EMAIL=tim@centrisys.com") in new stack
    -- Executing Answer("Zap/1-1", "") in new stack
    -- Executing Wait("Zap/1-1", "10") in new stack
    -- Redirecting Zap/1-1 to fax extension
  == Spawn extension (from-pstn, fax, 0) exited non-zero on 'Zap/1-1'
    -- Executing Goto("Zap/1-1", "ext-fax|in_fax|1") in new stack
    -- Goto (ext-fax,in_fax,1)
    -- Executing StopPlayTones("Zap/1-1", "") in new stack
    -- Executing GotoIf("Zap/1-1", "1?3:analog_fax|1") in new stack
    -- Goto (ext-fax,in_fax,3)
    -- Executing Macro("Zap/1-1", "faxreceive") in new stack
    -- Executing Set("Zap/1-1", "FAXFILE=/var/spool/asterisk/fax/1159923992.73.tif") in new stack
    -- Executing Set("Zap/1-1", "EMAILADDR=tim@centrisys.com") in new stack
  == Spawn extension (macro-faxreceive, s, 3) exited non-zero on 'Zap/1-1' in macro 'faxreceive'
  == Spawn extension (macro-faxreceive, s, 3) exited non-zero on 'Zap/1-1'
    -- Hungup 'Zap/1-1'