IVRA1*CLI> 
    -- Accepting call from '08450922005' to '7112' on channel 0/31, span 1
    -- Executing [7112@incomingfromzapata:1] Goto("Zap/31-1", "phrase-mainmenu|s|1") in new stack
    -- Goto (phrase-mainmenu,s,1)
    -- Executing [s@phrase-mainmenu:1] Answer("Zap/31-1", "") in new stack
    -- Executing [s@phrase-mainmenu:2] Set("Zap/31-1", "TIMEOUT(digit)=5|TIMEOUT(response)=10") in new stack
    -- Digit timeout set to 5
    -- Response timeout set to 10
    -- Executing [s@phrase-mainmenu:3] BackGround("Zap/31-1", "custom/1000") in new stack
    -- Playing 'custom/1000' (language 'uk')
  == CDR updated on Zap/31-1
    -- Executing [1@phrase-mainmenu:1] BackGround("Zap/31-1", "custom/1001") in new stack
    -- Playing 'custom/1001' (language 'uk')
    -- Executing [1@phrase-mainmenu:2] Goto("Zap/31-1", "phrase-codecmenu|s|1") in new stack
    -- Goto (phrase-codecmenu,s,1)
    -- Executing [s@phrase-codecmenu:1] Wait("Zap/31-1", "1") in new stack
IVRA1*CLI> logger reload                                             <--- ### Switched Debug 0 on ###
  == Parsing '/etc/asterisk/logger.conf': Found
Asterisk Event Logger restarted
Asterisk Queue Logger restarted
    -- Executing [s@phrase-codecmenu:2] BackGround("Zap/31-1", "custom/1002") in new stack
[Sep 11 13:49:20] DEBUG[1962]: channel.c:1845 ast_settimeout: Scheduling timer at 160 sample intervals
    -- Playing 'custom/1002' (language 'uk')
[Sep 11 13:49:25] DEBUG[1962]: chan_zap.c:4787 zt_read: DTMF digit: 3 on Zap/31-1
[Sep 11 13:49:25] DEBUG[1962]: channel.c:1845 ast_settimeout: Scheduling timer at 0 sample intervals
[Sep 11 13:49:25] DEBUG[1962]: pbx.c:2265 __ast_pbx_run: Oooh, got something to jump out with ('3')!
  == CDR updated on Zap/31-1
    -- Executing [3@phrase-codecmenu:1] Set("Zap/31-1", "codectorecord=alaw") in new stack
    -- Executing [3@phrase-codecmenu:2] Goto("Zap/31-1", "phrase-recording|s|1") in new stack
    -- Goto (phrase-recording,s,1)
    -- Executing [s@phrase-recording:1] Wait("Zap/31-1", "1") in new stack
    -- Executing [s@phrase-recording:2] Read("Zap/31-1", "PHRASEID|custom/1003") in new stack
[Sep 11 13:49:26] DEBUG[1962]: channel.c:1845 ast_settimeout: Scheduling timer at 160 sample intervals
    -- Playing 'custom/1003' (language 'uk')
[Sep 11 13:49:29] DEBUG[1962]: channel.c:1845 ast_settimeout: Scheduling timer at 0 sample intervals
[Sep 11 13:49:29] DEBUG[1962]: channel.c:1845 ast_settimeout: Scheduling timer at 0 sample intervals
[Sep 11 13:49:32] DEBUG[1962]: chan_zap.c:4787 zt_read: DTMF digit: 1 on Zap/31-1
[Sep 11 13:49:33] DEBUG[1962]: chan_zap.c:4787 zt_read: DTMF digit: 0 on Zap/31-1
[Sep 11 13:49:33] DEBUG[1962]: chan_zap.c:4787 zt_read: DTMF digit: 0 on Zap/31-1
[Sep 11 13:49:34] DEBUG[1962]: chan_zap.c:4787 zt_read: DTMF digit: 5 on Zap/31-1
[Sep 11 13:49:34] DEBUG[1962]: chan_zap.c:4787 zt_read: DTMF digit: # on Zap/31-1
    -- User entered '1005'
    -- Executing [s@phrase-recording:3] Wait("Zap/31-1", "2") in new stack
    -- Executing [s@phrase-recording:4] Record("Zap/31-1", "record-test-trunk-r42684.alaw") in new stack
[Sep 11 13:49:37] DEBUG[1962]: channel.c:1845 ast_settimeout: Scheduling timer at 160 sample intervals
    -- Playing 'beep' (language 'uk')
[Sep 11 13:49:37] DEBUG[1962]: channel.c:1845 ast_settimeout: Scheduling timer at 0 sample intervals
[Sep 11 13:49:37] DEBUG[1962]: channel.c:1845 ast_settimeout: Scheduling timer at 0 sample intervals
[Sep 11 13:49:37] DEBUG[1962]: chan_zap.c:4976 zt_indicate: Requested indication 18 on channel Zap/31-1
[Sep 11 13:49:42] DEBUG[1962]: chan_zap.c:4787 zt_read: DTMF digit: # on Zap/31-1
[Sep 11 13:49:42] WARNING[1962]: file.c:170 ast_writestream: Tried to write non-voice frame
[Sep 11 13:49:42] WARNING[1962]: app_record.c:335 record_exec: Problem writing final record frame
  == Spawn extension (phrase-recording, s, 4) exited non-zero on 'Zap/31-1'
[Sep 11 13:49:42] DEBUG[1962]: cdr_addon_mysql.c:210 mysql_log: cdr_mysql: inserting a CDR record.
[Sep 11 13:49:42] DEBUG[1962]: cdr_addon_mysql.c:226 mysql_log: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield) VALUES ('2006-09-11 13:49:04','08450922005','08450922005','s','phrase-recording', 'Zap/31-1','','Record','record-test-trunk-r42684.alaw',38,38,'ANSWERED',3,'','')
[Sep 11 13:49:42] DEBUG[1962]: chan_zap.c:2957 zt_setoption: Set option AUDIO MODE, value: ON(1) on Zap/31-1
[Sep 11 13:49:42] DEBUG[1962]: chan_zap.c:2446 zt_hangup: Hangup: channel: 31 index = 0, normal = 138, callwait = -1, thirdcall = -1
[Sep 11 13:49:42] DEBUG[1962]: chan_zap.c:2600 zt_hangup: Not yet hungup...  Calling hangup once with icause, and clearing call
[Sep 11 13:49:42] DEBUG[1962]: chan_zap.c:2874 zt_setoption: Set option TDD MODE, value: OFF(0) on Zap/31-1
[Sep 11 13:49:42] DEBUG[1962]: chan_zap.c:1424 update_conf: Updated conferencing on 31, with 0 conference users
[Sep 11 13:49:42] DEBUG[1962]: chan_zap.c:2953 zt_setoption: Set option AUDIO MODE, value: OFF(0) on Zap/31-1
    -- Hungup 'Zap/31-1'
IVRA1*CLI>