Не работает переадресация freepbx distro

Добрый день, у абонента 400 стоит переадресация по не ответу через 15 сек на городской мобильный телефон формата 279ЧЧЧЧ, ожнако после первого гудка при переадресации происходит сбой, елси же установить переадресацию на мобильный номер формата 8928ЧЧЧЧЧЧЧ то переадресация срабатывает нормально! Помогите пожалуйста разобраться! хотя если просто так позвонить на номер формата 279ЧЧЧЧ все прекрасно звонится!!!

[2014-08-16 13:08:43] VERBOSE[4058][C-00000f1a] pbx.c: -- Executing [s@macro-hangupcall:3] ExecIf("Local/400@from-internal-00000cc3;2", "0?Set(CDR(recordingfile)=)") in new stack
[2014-08-16 13:08:43] VERBOSE[4058][C-00000f1a] pbx.c: -- Executing [s@macro-hangupcall:4] Hangup("Local/400@from-internal-00000cc3;2", "") in new stack
[2014-08-16 13:08:43] VERBOSE[4058][C-00000f1a] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'Local/400@from-internal-00000cc3;2' in macro 'hangupcall'
[2014-08-16 13:08:43] VERBOSE[4058][C-00000f1a] pbx.c: == Spawn extension (ext-local, h, 1) exited non-zero on 'Local/400@from-internal-00000cc3;2'
[2014-08-16 13:08:43] VERBOSE[4014][C-00000f1a] pbx.c: -- Executing [400@disa-dial:3] Gosub("SIP/AGAT_4.24_OUT-00000685", "s-ANSWER,1()") in new stack
[2014-08-16 13:08:43] VERBOSE[4014][C-00000f1a] pbx.c: -- Executing [s-ANSWER@disa-dial:1] Return("SIP/AGAT_4.24_OUT-00000685", "") in new stack
[2014-08-16 13:08:43] VERBOSE[4014][C-00000f1a] pbx.c: -- Executing [400@disa-dial:4] Goto("SIP/AGAT_4.24_OUT-00000685", "disa,1,newcall") in new stack
[2014-08-16 13:08:43] VERBOSE[4014][C-00000f1a] pbx.c: -- Goto (disa,1,3)
[2014-08-16 13:08:43] VERBOSE[4014][C-00000f1a] pbx.c: -- Executing [1@disa:3] Set("SIP/AGAT_4.24_OUT-00000685", "_DISACONTEXT=from-internal") in new stack
[2014-08-16 13:08:43] VERBOSE[4014][C-00000f1a] pbx.c: -- Executing [1@disa:4] Set("SIP/AGAT_4.24_OUT-00000685", "_KEEPCID=FALSE") in new stack
[2014-08-16 13:08:43] VERBOSE[4014][C-00000f1a] pbx.c: -- Executing [1@disa:5] Set("SIP/AGAT_4.24_OUT-00000685", "_HANGUP=TtHg") in new stack
[2014-08-16 13:08:43] VERBOSE[4014][C-00000f1a] pbx.c: -- Executing [1@disa:6] Set("SIP/AGAT_4.24_OUT-00000685", "TIMEOUT(digit)=5") in new stack
[2014-08-16 13:08:43] VERBOSE[4014][C-00000f1a] func_timeout.c: -- Digit timeout set to 5.000
[2014-08-16 13:08:43] VERBOSE[4014][C-00000f1a] pbx.c: -- Executing [1@disa:7] Set("SIP/AGAT_4.24_OUT-00000685", "TIMEOUT(response)=30") in new stack
[2014-08-16 13:08:43] VERBOSE[4014][C-00000f1a] func_timeout.c: -- Response timeout set to 30.000
[2014-08-16 13:08:43] VERBOSE[4014][C-00000f1a] pbx.c: -- Executing [1@disa:8] DISA("SIP/AGAT_4.24_OUT-00000685", "no-password,disa-dial") in new stack
[2014-08-16 13:08:43] VERBOSE[4059][C-00000f1a] app_mixmonitor.c: == MixMonitor close filestream (mixed)
[2014-08-16 13:08:43] VERBOSE[4059][C-00000f1a] app_mixmonitor.c: == End MixMonitor Recording Local/400@from-internal-00000cc3;2
[2014-08-16 13:08:47] VERBOSE[4014][C-00000f1a] pbx.c: == Spawn extension (disa, 1, 8) exited non-zero on 'SIP/AGAT_4.24_OUT-00000685'
[2014-08-16 13:09:00] VERBOSE[4094] pbx_spool.c: -- Attempting call on Local/s@tc-maint for application NoCDR() (Retry 1)
[2014-08-16 13:09:00] VERBOSE[4095][C-00000f1d] pbx.c: -- Executing [s@tc-maint:1] NoCDR("Local/s@tc-maint-00000cc5;2", "") in new stack
[2014-08-16 13:09:00] VERBOSE[4095][C-00000f1d] pbx.c: -- Executing [s@tc-maint:2] Set("Local/s@tc-maint-00000cc5;2", "TCMAINT=RETURN") in new stack
[2014-08-16 13:09:00] VERBOSE[4095][C-00000f1d] pbx.c: -- Executing [s@tc-maint:3] Gosub("Local/s@tc-maint-00000cc5;2", "timeconditions,1,1()") in new stack
[2014-08-16 13:09:00] VERBOSE[4095][C-00000f1d] pbx.c: -- Executing [1@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000cc5;2", "08:45-17:45,mon-fri,*,*?truestate") in new stack
[2014-08-16 13:09:00] VERBOSE[4095][C-00000f1d] pbx.c: -- Executing [1@timeconditions:2] GotoIf("Local/s@tc-maint-00000cc5;2", "0?truegoto") in new stack
[2014-08-16 13:09:00] VERBOSE[4095][C-00000f1d] pbx.c: -- Executing [1@timeconditions:3] ExecIf("Local/s@tc-maint-00000cc5;2", "0?Set(DB(TC/1)=)") in new stack
[2014-08-16 13:09:00] VERBOSE[4095][C-00000f1d] pbx.c: -- Executing [1@timeconditions:4] Set("Local/s@tc-maint-00000cc5;2", "DEVICE_STATE(Custom:TC1)=INUSE") in new stack
[2014-08-16 13:09:00] VERBOSE[4095][C-00000f1d] pbx.c: -- Executing [1@timeconditions:5] ExecIf("Local/s@tc-maint-00000cc5;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2014-08-16 13:09:00] VERBOSE[4095][C-00000f1d] pbx.c: -- Executing [1@timeconditions:6] GotoIf("Local/s@tc-maint-00000cc5;2", "0?ext-miscdests,1,1") in new stack
[2014-08-16 13:09:00] VERBOSE[4095][C-00000f1d] pbx.c: -- Executing [1@timeconditions:7] Set("Local/s@tc-maint-00000cc5;2", "TCSTATE=false") in new stack
[2014-08-16 13:09:00] VERBOSE[4095][C-00000f1d] pbx.c: -- Executing [1@timeconditions:8] Return("Local/s@tc-maint-00000cc5;2", "") in new stack
[2014-08-16 13:09:00] VERBOSE[4095][C-00000f1d] pbx.c: -- Executing [s@tc-maint:4] System("Local/s@tc-maint-00000cc5;2", "/var/lib/asterisk/bin/schedtc.php 60 /var/spool/asterisk/outgoing 0") in new stack
[2014-08-16 13:09:00] VERBOSE[4095][C-00000f1d] pbx.c: -- Executing [s@tc-maint:5] Answer("Local/s@tc-maint-00000cc5;2", "") in new stack
[2014-08-16 13:09:00] NOTICE[4094] pbx_spool.c: Call completed to Local/s@tc-maint
[2014-08-16 13:09:00] VERBOSE[4095][C-00000f1d] pbx.c: == Spawn extension (tc-maint, s, 5) exited non-zero on 'Local/s@tc-maint-00000cc5;2'
[2014-08-16 13:09:58] VERBOSE[2029][C-00000f1e] netsock2.c: == Using SIP RTP TOS bits 184
[2014-08-16 13:09:58] VERBOSE[2029][C-00000f1e] netsock2.c: == Using SIP RTP CoS mark 5
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [2077578@from-trunk-sip-AGAT_4.24_OUT:1] Set("SIP/AGAT_4.24_OUT-0000068b", "GROUP()=OUT_4") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [2077578@from-trunk-sip-AGAT_4.24_OUT:2] Goto("SIP/AGAT_4.24_OUT-0000068b", "from-trunk,2077578,1") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Goto (from-trunk,2077578,1)
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [2077578@from-trunk:1] Set("SIP/AGAT_4.24_OUT-0000068b", "__FROM_DID=2077578") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [2077578@from-trunk:2] Gosub("SIP/AGAT_4.24_OUT-0000068b", "app-blacklist-check,s,1()") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("SIP/AGAT_4.24_OUT-0000068b", "0?blacklisted") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [s@app-blacklist-check:2] Set("SIP/AGAT_4.24_OUT-0000068b", "CALLED_BLACKLIST=1") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [s@app-blacklist-check:3] Return("SIP/AGAT_4.24_OUT-0000068b", "") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [2077578@from-trunk:3] Set("SIP/AGAT_4.24_OUT-0000068b", "__REC_POLICY_MODE=always") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [2077578@from-trunk:4] Set("SIP/AGAT_4.24_OUT-0000068b", "CHANNEL(language)=ru") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [2077578@from-trunk:5] Set("SIP/AGAT_4.24_OUT-0000068b", "CDR(did)=2077578") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [2077578@from-trunk:6] ExecIf("SIP/AGAT_4.24_OUT-0000068b", "0 ?Set(CALLERID(name)=8632268993)") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [2077578@from-trunk:7] Set("SIP/AGAT_4.24_OUT-0000068b", "CHANNEL(musicclass)=default") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [2077578@from-trunk:8] Set("SIP/AGAT_4.24_OUT-0000068b", "__MOHCLASS=default") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [2077578@from-trunk:9] Set("SIP/AGAT_4.24_OUT-0000068b", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [2077578@from-trunk:10] Set("SIP/AGAT_4.24_OUT-0000068b", "CALLERPRES()=allowed_not_screened") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [2077578@from-trunk:11] Goto("SIP/AGAT_4.24_OUT-0000068b", "disa,1,1") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Goto (disa,1,1)
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [1@disa:1] Authenticate("SIP/AGAT_4.24_OUT-0000068b", "1644,") in new stack
[2014-08-16 13:09:58] VERBOSE[4114][C-00000f1e] file.c: -- <SIP/AGAT_4.24_OUT-0000068b> Playing 'agent-pass.alaw' (language 'ru')
[2014-08-16 13:10:02] VERBOSE[4227] pbx_spool.c: -- Attempting call on Local/s@tc-maint for application NoCDR() (Retry 1)
[2014-08-16 13:10:02] VERBOSE[4228][C-00000f1f] pbx.c: -- Executing [s@tc-maint:1] NoCDR("Local/s@tc-maint-00000cc6;2", "") in new stack
[2014-08-16 13:10:02] VERBOSE[4228][C-00000f1f] pbx.c: -- Executing [s@tc-maint:2] Set("Local/s@tc-maint-00000cc6;2", "TCMAINT=RETURN") in new stack
[2014-08-16 13:10:02] VERBOSE[4228][C-00000f1f] pbx.c: -- Executing [s@tc-maint:3] Gosub("Local/s@tc-maint-00000cc6;2", "timeconditions,1,1()") in new stack
[2014-08-16 13:10:02] VERBOSE[4228][C-00000f1f] pbx.c: -- Executing [1@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000cc6;2", "08:45-17:45,mon-fri,*,*?truestate") in new stack
[2014-08-16 13:10:02] VERBOSE[4228][C-00000f1f] pbx.c: -- Executing [1@timeconditions:2] GotoIf("Local/s@tc-maint-00000cc6;2", "0?truegoto") in new stack
[2014-08-16 13:10:02] VERBOSE[4228][C-00000f1f] pbx.c: -- Executing [1@timeconditions:3] ExecIf("Local/s@tc-maint-00000cc6;2", "0?Set(DB(TC/1)=)") in new stack
[2014-08-16 13:10:02] VERBOSE[4228][C-00000f1f] pbx.c: -- Executing [1@timeconditions:4] Set("Local/s@tc-maint-00000cc6;2", "DEVICE_STATE(Custom:TC1)=INUSE") in new stack
[2014-08-16 13:10:02] VERBOSE[4228][C-00000f1f] pbx.c: -- Executing [1@timeconditions:5] ExecIf("Local/s@tc-maint-00000cc6;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2014-08-16 13:10:02] VERBOSE[4228][C-00000f1f] pbx.c: -- Executing [1@timeconditions:6] GotoIf("Local/s@tc-maint-00000cc6;2", "0?ext-miscdests,1,1") in new stack
[2014-08-16 13:10:02] VERBOSE[4228][C-00000f1f] pbx.c: -- Executing [1@timeconditions:7] Set("Local/s@tc-maint-00000cc6;2", "TCSTATE=false") in new stack
[2014-08-16 13:10:02] VERBOSE[4228][C-00000f1f] pbx.c: -- Executing [1@timeconditions:8] Return("Local/s@tc-maint-00000cc6;2", "") in new stack
[2014-08-16 13:10:02] VERBOSE[4228][C-00000f1f] pbx.c: -- Executing [s@tc-maint:4] System("Local/s@tc-maint-00000cc6;2", "/var/lib/asterisk/bin/schedtc.php 60 /var/spool/asterisk/outgoing 1") in new stack
[2014-08-16 13:10:02] VERBOSE[4228][C-00000f1f] pbx.c: -- Executing [s@tc-maint:5] Answer("Local/s@tc-maint-00000cc6;2", "") in new stack
[2014-08-16 13:10:02] VERBOSE[4228][C-00000f1f] pbx.c: == Spawn extension (tc-maint, s, 5) exited non-zero on 'Local/s@tc-maint-00000cc6;2'
[2014-08-16 13:10:02] NOTICE[4227] pbx_spool.c: Call completed to Local/s@tc-maint
[2014-08-16 13:10:04] VERBOSE[4114][C-00000f1e] file.c: -- <SIP/AGAT_4.24_OUT-0000068b> Playing 'auth-thankyou.alaw' (language 'ru')
[2014-08-16 13:10:05] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [1@disa:2] Set("SIP/AGAT_4.24_OUT-0000068b", "_DISA=disa^1^newcall") in new stack
[2014-08-16 13:10:05] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [1@disa:3] Set("SIP/AGAT_4.24_OUT-0000068b", "_DISACONTEXT=from-internal") in new stack
[2014-08-16 13:10:05] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [1@disa:4] Set("SIP/AGAT_4.24_OUT-0000068b", "_KEEPCID=FALSE") in new stack
[2014-08-16 13:10:05] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [1@disa:5] Set("SIP/AGAT_4.24_OUT-0000068b", "_HANGUP=TtHg") in new stack
[2014-08-16 13:10:05] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [1@disa:6] Set("SIP/AGAT_4.24_OUT-0000068b", "TIMEOUT(digit)=5") in new stack
[2014-08-16 13:10:05] VERBOSE[4114][C-00000f1e] func_timeout.c: -- Digit timeout set to 5.000
[2014-08-16 13:10:05] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [1@disa:7] Set("SIP/AGAT_4.24_OUT-0000068b", "TIMEOUT(response)=30") in new stack
[2014-08-16 13:10:05] VERBOSE[4114][C-00000f1e] func_timeout.c: -- Response timeout set to 30.000
[2014-08-16 13:10:05] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [1@disa:8] DISA("SIP/AGAT_4.24_OUT-0000068b", "no-password,disa-dial") in new stack
[2014-08-16 13:10:13] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [400@disa-dial:1] NoOp("SIP/AGAT_4.24_OUT-0000068b", "called 400 in from-internal by ID: 1") in new stack
[2014-08-16 13:10:13] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [400@disa-dial:2] Dial("SIP/AGAT_4.24_OUT-0000068b", "Local/400@from-internal,300,TtHg") in new stack
[2014-08-16 13:10:13] VERBOSE[4114][C-00000f1e] app_dial.c: -- Called Local/400@from-internal
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:1] GotoIf("Local/400@from-internal-00000cc7;2", "0?ext-local,400,1") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:2] Macro("Local/400@from-internal-00000cc7;2", "user-callerid,") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:1] Set("Local/400@from-internal-00000cc7;2", "TOUCH_MONITOR=1408180213.8219") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:2] Set("Local/400@from-internal-00000cc7;2", "AMPUSER=8632268993") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("Local/400@from-internal-00000cc7;2", "0?report") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("Local/400@from-internal-00000cc7;2", "1?Set(REALCALLERIDNUM=8632268993)") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:5] Set("Local/400@from-internal-00000cc7;2", "AMPUSER=") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("Local/400@from-internal-00000cc7;2", "0?limit") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:7] Set("Local/400@from-internal-00000cc7;2", "AMPUSERCIDNAME=") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:8] GotoIf("Local/400@from-internal-00000cc7;2", "1?report") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (macro-user-callerid,s,16)
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:16] GotoIf("Local/400@from-internal-00000cc7;2", "0?continue") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:17] ExecIf("Local/400@from-internal-00000cc7;2", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:18] Set("Local/400@from-internal-00000cc7;2", "__TTL=64") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:19] GotoIf("Local/400@from-internal-00000cc7;2", "1?continue") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (macro-user-callerid,s,30)
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:30] Set("Local/400@from-internal-00000cc7;2", "CALLERID(number)=8632268993") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:31] Set("Local/400@from-internal-00000cc7;2", "CALLERID(name)=8632268993") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:32] Set("Local/400@from-internal-00000cc7;2", "CDR(cnum)=8632268993") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:33] Set("Local/400@from-internal-00000cc7;2", "CDR(cnam)=8632268993") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:34] Set("Local/400@from-internal-00000cc7;2", "CHANNEL(language)=ru") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:35] ExecIf("Local/400@from-internal-00000cc7;2", "0?Set(CALLERID(name)=)") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:3] Set("Local/400@from-internal-00000cc7;2", "DIAL_OPTIONS=TtrI") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:4] Set("Local/400@from-internal-00000cc7;2", "CONNECTEDLINE(num)=400") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:5] Gosub("Local/400@from-internal-00000cc7;2", "sub-presencestate-display,s,1(400)") in new stack
[2014-08-16 13:10:13] WARNING[4246][C-00000f1e] func_presencestate.c: PRESENCE_STATE unknown
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-presencestate-display:1] Goto("Local/400@from-internal-00000cc7;2", "state-,1") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (sub-presencestate-display,state-,1)
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [state-@sub-presencestate-display:1] Set("Local/400@from-internal-00000cc7;2", "PRESENCESTATE_DISPLAY=") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [state-@sub-presencestate-display:2] Return("Local/400@from-internal-00000cc7;2", "") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:6] Set("Local/400@from-internal-00000cc7;2", "CONNECTEDLINE(name,i)=Goncharova Anna") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:7] Set("Local/400@from-internal-00000cc7;2", "FM_DIALSTATUS=NOT_INUSE") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:8] Set("Local/400@from-internal-00000cc7;2", "__EXTTOCALL=400") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:9] Set("Local/400@from-internal-00000cc7;2", "__PICKUPMARK=400") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:10] Macro("Local/400@from-internal-00000cc7;2", "blkvm-setifempty,") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-blkvm-setifempty:1] GotoIf("Local/400@from-internal-00000cc7;2", "1?init") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (macro-blkvm-setifempty,s,4)
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-blkvm-setifempty:4] Set("Local/400@from-internal-00000cc7;2", "__BLKVM_CHANNEL=Local/400@from-internal-00000cc7;2") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-blkvm-setifempty:5] Set("Local/400@from-internal-00000cc7;2", "SHARED(BLKVM,Local/400@from-internal-00000cc7;2)=TRUE") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-blkvm-setifempty:6] Set("Local/400@from-internal-00000cc7;2", "GOSUB_RETVAL=TRUE") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-blkvm-setifempty:7] MacroExit("Local/400@from-internal-00000cc7;2", "") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:11] GotoIf("Local/400@from-internal-00000cc7;2", "1?skipov") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (from-internal,400,14)
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:14] Set("Local/400@from-internal-00000cc7;2", "RRNODEST=") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:15] Set("Local/400@from-internal-00000cc7;2", "__NODEST=400") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:16] GosubIf("Local/400@from-internal-00000cc7;2", "0?sub-fmsetcid,s,1()") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:17] Set("Local/400@from-internal-00000cc7;2", "RecordMethod=Group") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:18] Gosub("Local/400@from-internal-00000cc7;2", "sub-record-check,s,1(exten,400,)") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:1] Set("Local/400@from-internal-00000cc7;2", "REC_POLICY_MODE_SAVE=always") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:2] GotoIf("Local/400@from-internal-00000cc7;2", "1?check") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (sub-record-check,s,7)
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:7] Set("Local/400@from-internal-00000cc7;2", "__MON_FMT=wav") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:8] GotoIf("Local/400@from-internal-00000cc7;2", "1?next") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (sub-record-check,s,11)
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("Local/400@from-internal-00000cc7;2", "0?Return()") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:12] ExecIf("Local/400@from-internal-00000cc7;2", "0?Set(__REC_POLICY_MODE=)") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:13] GotoIf("Local/400@from-internal-00000cc7;2", "0?exten,1") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:14] Set("Local/400@from-internal-00000cc7;2", "__REC_STATUS=INITIALIZED") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:15] Set("Local/400@from-internal-00000cc7;2", "NOW=1408180213") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:16] Set("Local/400@from-internal-00000cc7;2", "__DAY=16") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:17] Set("Local/400@from-internal-00000cc7;2", "__MONTH=08") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:18] Set("Local/400@from-internal-00000cc7;2", "__YEAR=2014") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:19] Set("Local/400@from-internal-00000cc7;2", "__TIMESTR=20140816-131013") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:20] Set("Local/400@from-internal-00000cc7;2", "__FROMEXTEN=8632268993") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:21] Set("Local/400@from-internal-00000cc7;2", "__CALLFILENAME=exten-400-8632268993-20140816-131013-1408180213.8219") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:22] Goto("Local/400@from-internal-00000cc7;2", "exten,1") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (sub-record-check,exten,1)
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [exten@sub-record-check:1] GotoIf("Local/400@from-internal-00000cc7;2", "1?callee") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (sub-record-check,exten,8)
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [exten@sub-record-check:8] GosubIf("Local/400@from-internal-00000cc7;2", "1?record,1(exten,400,8632268993)") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [record@sub-record-check:1] Set("Local/400@from-internal-00000cc7;2", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [record@sub-record-check:2] MixMonitor("Local/400@from-internal-00000cc7;2", "2014/08/16/exten-400-8632268993-20140816-131013-1408180213.8219.wav,,") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [record@sub-record-check:3] Set("Local/400@from-internal-00000cc7;2", "__REC_STATUS=RECORDING") in new stack
[2014-08-16 13:10:13] VERBOSE[4249][C-00000f1e] app_mixmonitor.c: == Begin MixMonitor Recording Local/400@from-internal-00000cc7;2
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [record@sub-record-check:4] Set("Local/400@from-internal-00000cc7;2", "CDR(recordingfile)=exten-400-8632268993-20140816-131013-1408180213.8219.wav") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [record@sub-record-check:5] Return("Local/400@from-internal-00000cc7;2", "") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [exten@sub-record-check:9] Return("Local/400@from-internal-00000cc7;2", "") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:19] GotoIf("Local/400@from-internal-00000cc7;2", "0 ?skipsimple") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:20] Macro("Local/400@from-internal-00000cc7;2", "simple-dial,400,15") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-simple-dial:1] Set("Local/400@from-internal-00000cc7;2", "__EXTTOCALL=400") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-simple-dial:2] Set("Local/400@from-internal-00000cc7;2", "RT=15") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-simple-dial:3] Set("Local/400@from-internal-00000cc7;2", "CFUEXT=") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-simple-dial:4] Set("Local/400@from-internal-00000cc7;2", "CFBEXT=") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-simple-dial:5] Set("Local/400@from-internal-00000cc7;2", "CWI_TMP=") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-simple-dial:6] Macro("Local/400@from-internal-00000cc7;2", "dial-one,15,TtrI,400") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:1] Set("Local/400@from-internal-00000cc7;2", "DEXTEN=400") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:2] Set("Local/400@from-internal-00000cc7;2", "DIALSTATUS_CW=") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:3] GosubIf("Local/400@from-internal-00000cc7;2", "0?screen,1()") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:4] GosubIf("Local/400@from-internal-00000cc7;2", "0?cf,1()") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:5] GotoIf("Local/400@from-internal-00000cc7;2", "1?skip1") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (macro-dial-one,s,8)
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:8] GotoIf("Local/400@from-internal-00000cc7;2", "0?nodial") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:9] GotoIf("Local/400@from-internal-00000cc7;2", "0?continue") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:10] Set("Local/400@from-internal-00000cc7;2", "EXTHASCW=ENABLED") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:11] GotoIf("Local/400@from-internal-00000cc7;2", "0?next1:cwinusebusy") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (macro-dial-one,s,23)
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:23] GotoIf("Local/400@from-internal-00000cc7;2", "1?next3:continue") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (macro-dial-one,s,24)
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:24] ExecIf("Local/400@from-internal-00000cc7;2", "0?Set(DIALSTATUS_CW=BUSY)") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:25] GotoIf("Local/400@from-internal-00000cc7;2", "0?nodial") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:26] GosubIf("Local/400@from-internal-00000cc7;2", "1?dstring,1():dlocal,1()") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [dstring@macro-dial-one:1] Set("Local/400@from-internal-00000cc7;2", "DSTRING=") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [dstring@macro-dial-one:2] Set("Local/400@from-internal-00000cc7;2", "DEVICES=400") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [dstring@macro-dial-one:3] ExecIf("Local/400@from-internal-00000cc7;2", "0?Return()") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [dstring@macro-dial-one:4] ExecIf("Local/400@from-internal-00000cc7;2", "0?Set(DEVICES=00)") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [dstring@macro-dial-one:5] Set("Local/400@from-internal-00000cc7;2", "LOOPCNT=1") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [dstring@macro-dial-one:6] Set("Local/400@from-internal-00000cc7;2", "ITER=1") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [dstring@macro-dial-one:7] Set("Local/400@from-internal-00000cc7;2", "THISDIAL=SIP/400") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [dstring@macro-dial-one:8] GosubIf("Local/400@from-internal-00000cc7;2", "1?zap2dahdi,1()") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("Local/400@from-internal-00000cc7;2", "0?Return()") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [zap2dahdi@macro-dial-one:2] Set("Local/400@from-internal-00000cc7;2", "NEWDIAL=") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [zap2dahdi@macro-dial-one:3] Set("Local/400@from-internal-00000cc7;2", "LOOPCNT2=1") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [zap2dahdi@macro-dial-one:4] Set("Local/400@from-internal-00000cc7;2", "ITER2=1") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [zap2dahdi@macro-dial-one:5] Set("Local/400@from-internal-00000cc7;2", "THISPART2=SIP/400") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("Local/400@from-internal-00000cc7;2", "0?Set(THISPART2=DAHDI/400)") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [zap2dahdi@macro-dial-one:7] Set("Local/400@from-internal-00000cc7;2", "NEWDIAL=SIP/400&") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [zap2dahdi@macro-dial-one:8] Set("Local/400@from-internal-00000cc7;2", "ITER2=2") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("Local/400@from-internal-00000cc7;2", "0?begin2") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [zap2dahdi@macro-dial-one:10] Set("Local/400@from-internal-00000cc7;2", "THISDIAL=SIP/400") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [zap2dahdi@macro-dial-one:11] Return("Local/400@from-internal-00000cc7;2", "") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [dstring@macro-dial-one:9] Set("Local/400@from-internal-00000cc7;2", "DSTRING=SIP/400&") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [dstring@macro-dial-one:10] Set("Local/400@from-internal-00000cc7;2", "ITER=2") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [dstring@macro-dial-one:11] GotoIf("Local/400@from-internal-00000cc7;2", "0?begin") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [dstring@macro-dial-one:12] Set("Local/400@from-internal-00000cc7;2", "DSTRING=SIP/400") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [dstring@macro-dial-one:13] Return("Local/400@from-internal-00000cc7;2", "") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:27] GotoIf("Local/400@from-internal-00000cc7;2", "0?nodial") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:28] GotoIf("Local/400@from-internal-00000cc7;2", "0?skiptrace") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:29] GosubIf("Local/400@from-internal-00000cc7;2", "1?ctset,1():ctclear,1()") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [ctset@macro-dial-one:1] Set("Local/400@from-internal-00000cc7;2", "DB(CALLTRACE/400)=8632268993") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [ctset@macro-dial-one:2] Return("Local/400@from-internal-00000cc7;2", "") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:30] Set("Local/400@from-internal-00000cc7;2", "D_OPTIONS=TtrIM(auto-blkvm)") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:31] ExecIf("Local/400@from-internal-00000cc7;2", "0?SIPAddHeader(Alert-Info: )") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:32] ExecIf("Local/400@from-internal-00000cc7;2", "0?SIPAddHeader()") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:33] ExecIf("Local/400@from-internal-00000cc7;2", "1?Set(CHANNEL(musicclass)=default)") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:34] GosubIf("Local/400@from-internal-00000cc7;2", "0?qwait,1()") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:35] Set("Local/400@from-internal-00000cc7;2", "__CWIGNORE=") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:36] Set("Local/400@from-internal-00000cc7;2", "__KEEPCID=TRUE") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:37] GotoIf("Local/400@from-internal-00000cc7;2", "0?usegoto,1") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:38] GotoIf("Local/400@from-internal-00000cc7;2", "1?godial") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (macro-dial-one,s,43)
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:43] Dial("Local/400@from-internal-00000cc7;2", "SIP/400,15,TtrIM(auto-blkvm)") in new stack
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] netsock2.c: == Using SIP VIDEO TOS bits 136
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] netsock2.c: == Using SIP VIDEO CoS mark 6
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] netsock2.c: == Using SIP RTP TOS bits 184
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] netsock2.c: == Using SIP RTP CoS mark 5
[2014-08-16 13:10:13] VERBOSE[1922] chan_sip.c: == Extension Changed 400[ext-local] new state Ringing for Notify User 402
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] app_dial.c: -- Called SIP/400
[2014-08-16 13:10:13] VERBOSE[4114][C-00000f1e] app_dial.c: -- Local/400@from-internal-00000cc7;1 is ringing
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] app_dial.c: -- Connected line update to Local/400@from-internal-00000cc7;2 prevented.
[2014-08-16 13:10:13] VERBOSE[4246][C-00000f1e] app_dial.c: -- SIP/400-0000068c is ringing
[2014-08-16 13:10:13] VERBOSE[4114][C-00000f1e] app_dial.c: -- Local/400@from-internal-00000cc7;1 is ringing
[2014-08-16 13:10:13] VERBOSE[1922] chan_sip.c: == Extension Changed 400[ext-local] new state Ringing for Notify User 402
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] app_dial.c: -- Nobody picked up in 15000 ms
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:44] ExecIf("Local/400@from-internal-00000cc7;2", "0?MacroExit()") in new stack
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:45] ExecIf("Local/400@from-internal-00000cc7;2", "0?Set(DIALSTATUS=)") in new stack
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:46] GosubIf("Local/400@from-internal-00000cc7;2", "0?s-NOANSWER,1()") in new stack
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial-one:47] MacroExit("Local/400@from-internal-00000cc7;2", "") in new stack
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-simple-dial:7] Set("Local/400@from-internal-00000cc7;2", "__CWIGNORE=") in new stack
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-simple-dial:8] Set("Local/400@from-internal-00000cc7;2", "PR_DIALSTATUS=NOANSWER") in new stack
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-simple-dial:9] GosubIf("Local/400@from-internal-00000cc7;2", "0?docfu,1()") in new stack
[2014-08-16 13:10:28] VERBOSE[1922] chan_sip.c: == Extension Changed 400[ext-local] new state Idle for Notify User 402
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-simple-dial:10] GosubIf("Local/400@from-internal-00000cc7;2", "0?docfb,1()") in new stack
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-simple-dial:11] Set("Local/400@from-internal-00000cc7;2", "DIALSTATUS=NOANSWER") in new stack
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-simple-dial:12] Goto("Local/400@from-internal-00000cc7;2", "s-NOANSWER,1") in new stack
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (macro-simple-dial,s-NOANSWER,1)
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s-NOANSWER@macro-simple-dial:1] NoOp("Local/400@from-internal-00000cc7;2", "Extension is reporting s-NOANSWER") in new stack
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:21] Set("Local/400@from-internal-00000cc7;2", "RingGroupMethod=ringall-prim") in new stack
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:22] Set("Local/400@from-internal-00000cc7;2", "_FMGRP=400") in new stack
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:23] GotoIf("Local/400@from-internal-00000cc7;2", "0?DIALGRP") in new stack
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:24] Answer("Local/400@from-internal-00000cc7;2", "") in new stack
[2014-08-16 13:10:28] VERBOSE[4114][C-00000f1e] app_dial.c: -- Local/400@from-internal-00000cc7;1 answered SIP/AGAT_4.24_OUT-0000068b
[2014-08-16 13:10:28] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:25] Wait("Local/400@from-internal-00000cc7;2", "1") in new stack
[2014-08-16 13:10:29] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:26] Playback("Local/400@from-internal-00000cc7;2", "custom/call-forwarding") in new stack
[2014-08-16 13:10:29] VERBOSE[4246][C-00000f1e] file.c: -- <Local/400@from-internal-00000cc7;2> Playing 'custom/call-forwarding.slin' (language 'ru')
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:27] GotoIf("Local/400@from-internal-00000cc7;2", "0?doconfirm") in new stack
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:28] Macro("Local/400@from-internal-00000cc7;2", "dial,60,TtrI,2791576#") in new stack
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial:1] GotoIf("Local/400@from-internal-00000cc7;2", "0?dial") in new stack
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial:2] SetMusicOnHold("Local/400@from-internal-00000cc7;2", "default") in new stack
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial:3] AGI("Local/400@from-internal-00000cc7;2", "dialparties.agi") in new stack
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] res_agi.c: dialparties.agi: Caller ID name is '8632268993' number is '8632268993'
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] res_agi.c: dialparties.agi: Methodology of ring is 'ringall-prim'
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] res_agi.c: -- dialparties.agi: Added extension 2791576# to extension map
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] res_agi.c: -- dialparties.agi: Extension 2791576# cf is disabled
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] res_agi.c: dialparties.agi: EXTENSION_STATE: 4 (UNKNOWN)
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] res_agi.c: dialparties.agi: Extension 2791576# has ExtensionState: 4
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] res_agi.c: -- dialparties.agi: Filtered ARG3: 2791576
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] res_agi.c: -- <Local/400@from-internal-00000cc7;2>AGI Script dialparties.agi completed, returning 0
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial:7] Dial("Local/400@from-internal-00000cc7;2", "Local/2791576@from-internal/n,60,TtrIM(auto-blkvm)") in new stack
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] app_dial.c: -- Called Local/2791576@from-internal/n
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [2791576@from-internal:1] Macro("Local/2791576@from-internal-00000cc8;2", "user-callerid,LIMIT") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:1] Set("Local/2791576@from-internal-00000cc8;2", "TOUCH_MONITOR=1408180231.8222") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:2] Set("Local/2791576@from-internal-00000cc8;2", "AMPUSER=8632268993") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("Local/2791576@from-internal-00000cc8;2", "0?report") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("Local/2791576@from-internal-00000cc8;2", "1?Set(REALCALLERIDNUM=8632268993)") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:5] Set("Local/2791576@from-internal-00000cc8;2", "AMPUSER=") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("Local/2791576@from-internal-00000cc8;2", "0?limit") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:7] Set("Local/2791576@from-internal-00000cc8;2", "AMPUSERCIDNAME=") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:8] GotoIf("Local/2791576@from-internal-00000cc8;2", "1?report") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Goto (macro-user-callerid,s,16)
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:16] GotoIf("Local/2791576@from-internal-00000cc8;2", "1?continue") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Goto (macro-user-callerid,s,30)
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:30] Set("Local/2791576@from-internal-00000cc8;2", "CALLERID(number)=8632268993") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:31] Set("Local/2791576@from-internal-00000cc8;2", "CALLERID(name)=8632268993") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:32] Set("Local/2791576@from-internal-00000cc8;2", "CDR(cnum)=8632268993") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:33] Set("Local/2791576@from-internal-00000cc8;2", "CDR(cnam)=8632268993") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:34] Set("Local/2791576@from-internal-00000cc8;2", "CHANNEL(language)=ru") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-user-callerid:35] ExecIf("Local/2791576@from-internal-00000cc8;2", "0?Set(CALLERID(name)=)") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [2791576@from-internal:2] Set("Local/2791576@from-internal-00000cc8;2", "ROUTEUSER=400") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [2791576@from-internal:3] GotoIf("Local/2791576@from-internal-00000cc8;2", "1?restrictedroute-1-2-3-4-5-6-7-8-9-10-11-12-13-14-15-16-17-18-21-22,2791576,2:outbound-allroutes,2791576,2") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Goto (restrictedroute-1-2-3-4-5-6-7-8-9-10-11-12-13-14-15-16-17-18-21-22,2791576,2)
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [2791576@restrictedroute-1-2-3-4-5-6-7-8-9-10-11-12-13-14-15-16-17-18-21-22:2] Set("Local/2791576@from-internal-00000cc8;2", "__REC_POLICY_MODE=always") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [2791576@restrictedroute-1-2-3-4-5-6-7-8-9-10-11-12-13-14-15-16-17-18-21-22:3] ExecIf("Local/2791576@from-internal-00000cc8;2", "0 ?Set(CDR(accountcode)=)") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [2791576@restrictedroute-1-2-3-4-5-6-7-8-9-10-11-12-13-14-15-16-17-18-21-22:4] Set("Local/2791576@from-internal-00000cc8;2", "MOHCLASS=default") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [2791576@restrictedroute-1-2-3-4-5-6-7-8-9-10-11-12-13-14-15-16-17-18-21-22:5] Set("Local/2791576@from-internal-00000cc8;2", "_NODEST=") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [2791576@restrictedroute-1-2-3-4-5-6-7-8-9-10-11-12-13-14-15-16-17-18-21-22:6] Gosub("Local/2791576@from-internal-00000cc8;2", "sub-record-check,s,1(out,2791576,)") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:1] Set("Local/2791576@from-internal-00000cc8;2", "REC_POLICY_MODE_SAVE=always") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:2] GotoIf("Local/2791576@from-internal-00000cc8;2", "1?check") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Goto (sub-record-check,s,7)
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:7] Set("Local/2791576@from-internal-00000cc8;2", "__MON_FMT=wav") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:8] GotoIf("Local/2791576@from-internal-00000cc8;2", "0?next") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:9] Set("Local/2791576@from-internal-00000cc8;2", "CDR(recordingfile)=exten-400-8632268993-20140816-131013-1408180213.8219.wav") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@sub-record-check:10] Return("Local/2791576@from-internal-00000cc8;2", "") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [2791576@restrictedroute-1-2-3-4-5-6-7-8-9-10-11-12-13-14-15-16-17-18-21-22:7] Macro("Local/2791576@from-internal-00000cc8;2", "dialout-trunk,4,2791576,,off") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:1] Set("Local/2791576@from-internal-00000cc8;2", "DIAL_TRUNK=4") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("Local/2791576@from-internal-00000cc8;2", "0?sub-pincheck,s,1()") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("Local/2791576@from-internal-00000cc8;2", "0?disabletrunk,1") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:4] Set("Local/2791576@from-internal-00000cc8;2", "DIAL_NUMBER=2791576") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:5] Set("Local/2791576@from-internal-00000cc8;2", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:6] Set("Local/2791576@from-internal-00000cc8;2", "OUTBOUND_GROUP=OUT_4") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("Local/2791576@from-internal-00000cc8;2", "1?nomax") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Goto (macro-dialout-trunk,s,9)
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("Local/2791576@from-internal-00000cc8;2", "0?skipoutcid") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:10] Set("Local/2791576@from-internal-00000cc8;2", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:11] Macro("Local/2791576@from-internal-00000cc8;2", "outbound-callerid,4") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("Local/2791576@from-internal-00000cc8;2", "1?Set(CALLERPRES()=allowed_not_screened)") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("Local/2791576@from-internal-00000cc8;2", "0?Set(REALCALLERIDNUM=8632268993)") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("Local/2791576@from-internal-00000cc8;2", "0?normcid") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outbound-callerid:4] Set("Local/2791576@from-internal-00000cc8;2", "USEROUTCID=8632268993") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outbound-callerid:5] GotoIf("Local/2791576@from-internal-00000cc8;2", "1?bypass") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Goto (macro-outbound-callerid,s,7)
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outbound-callerid:7] Set("Local/2791576@from-internal-00000cc8;2", "EMERGENCYCID=") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outbound-callerid:8] Set("Local/2791576@from-internal-00000cc8;2", "TRUNKOUTCID=8632077878") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("Local/2791576@from-internal-00000cc8;2", "1?trunkcid") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Goto (macro-outbound-callerid,s,14)
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("Local/2791576@from-internal-00000cc8;2", "1?Set(CALLERID(all)=8632077878)") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("Local/2791576@from-internal-00000cc8;2", "1?Set(CALLERID(all)=8632268993)") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outbound-callerid:16] ExecIf("Local/2791576@from-internal-00000cc8;2", "0?Set(CALLERID(all)=)") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outbound-callerid:17] ExecIf("Local/2791576@from-internal-00000cc8;2", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outbound-callerid:18] Set("Local/2791576@from-internal-00000cc8;2", "CDR(outbound_cnum)=8632268993") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outbound-callerid:19] Set("Local/2791576@from-internal-00000cc8;2", "CDR(outbound_cnam)=") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:12] GosubIf("Local/2791576@from-internal-00000cc8;2", "0?sub-flp-4,s,1()") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:13] Set("Local/2791576@from-internal-00000cc8;2", "OUTNUM=2791576") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:14] Set("Local/2791576@from-internal-00000cc8;2", "custom=SIP/AGAT_4.24_OUT") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("Local/2791576@from-internal-00000cc8;2", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:16] ExecIf("Local/2791576@from-internal-00000cc8;2", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:17] Macro("Local/2791576@from-internal-00000cc8;2", "dialout-trunk-predial-hook,") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("Local/2791576@from-internal-00000cc8;2", "") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("Local/2791576@from-internal-00000cc8;2", "0?bypass,1") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:19] ExecIf("Local/2791576@from-internal-00000cc8;2", "0?Set(CONNECTEDLINE(num,i)=2791576)") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:20] ExecIf("Local/2791576@from-internal-00000cc8;2", "0?Set(CONNECTEDLINE(name,i)=CID:8632268993)") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:21] GotoIf("Local/2791576@from-internal-00000cc8;2", "0?customtrunk") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:22] Dial("Local/2791576@from-internal-00000cc8;2", "SIP/AGAT_4.24_OUT/2791576,300,Tt") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] netsock2.c: == Using SIP RTP TOS bits 184
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] netsock2.c: == Using SIP RTP CoS mark 5
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] app_dial.c: -- Called SIP/AGAT_4.24_OUT/2791576
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] app_dial.c: -- SIP/AGAT_4.24_OUT-0000068d is ringing
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] app_dial.c: -- Local/2791576@from-internal-00000cc8;1 is ringing
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1)
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:23] NoOp("Local/2791576@from-internal-00000cc8;2", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 1") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-dialout-trunk:24] GotoIf("Local/2791576@from-internal-00000cc8;2", "0?continue,1:s-CHANUNAVAIL,1") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("Local/2791576@from-internal-00000cc8;2", "RC=1") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("Local/2791576@from-internal-00000cc8;2", "1,1") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Goto (macro-dialout-trunk,1,1)
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [1@macro-dialout-trunk:1] Goto("Local/2791576@from-internal-00000cc8;2", "continue,1") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Goto (macro-dialout-trunk,continue,1)
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [continue@macro-dialout-trunk:1] NoOp("Local/2791576@from-internal-00000cc8;2", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 1 - failing through to other trunks") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [continue@macro-dialout-trunk:2] Set("Local/2791576@from-internal-00000cc8;2", "CALLERID(number)=") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [2791576@restrictedroute-1-2-3-4-5-6-7-8-9-10-11-12-13-14-15-16-17-18-21-22:8] Macro("Local/2791576@from-internal-00000cc8;2", "outisbusy,") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outisbusy:1] Progress("Local/2791576@from-internal-00000cc8;2", "") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outisbusy:2] GotoIf("Local/2791576@from-internal-00000cc8;2", "0?emergency,1") in new stack
[2014-08-16 13:10:31] VERBOSE[4246][C-00000f1e] app_dial.c: -- Local/2791576@from-internal-00000cc8;1 is making progress passing it to Local/400@from-internal-00000cc7;2
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outisbusy:3] GotoIf("Local/2791576@from-internal-00000cc8;2", "0?intracompany,1") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outisbusy:4] Playback("Local/2791576@from-internal-00000cc8;2", "all-circuits-busy-now&pls-try-call-later, noanswer") in new stack
[2014-08-16 13:10:31] VERBOSE[4289][C-00000f1e] file.c: -- <Local/2791576@from-internal-00000cc8;2> Playing 'all-circuits-busy-now.slin' (language 'ru')
[2014-08-16 13:10:34] VERBOSE[4289][C-00000f1e] file.c: -- <Local/2791576@from-internal-00000cc8;2> Playing 'pls-try-call-later.slin' (language 'ru')
[2014-08-16 13:10:36] VERBOSE[4289][C-00000f1e] pbx.c: -- Executing [s@macro-outisbusy:5] Congestion("Local/2791576@from-internal-00000cc8;2", "20") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] app_dial.c: -- Local/2791576@from-internal-00000cc8;1 is circuit-busy
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0)
[2014-08-16 13:10:36] VERBOSE[4289][C-00000f1e] app_macro.c: == Spawn extension (macro-outisbusy, s, 5) exited non-zero on 'Local/2791576@from-internal-00000cc8;2' in macro 'outisbusy'
[2014-08-16 13:10:36] VERBOSE[4289][C-00000f1e] pbx.c: == Spawn extension (restrictedroute-1-2-3-4-5-6-7-8-9-10-11-12-13-14-15-16-17-18-21-22, 2791576, 8) exited non-zero on 'Local/2791576@from-internal-00000cc8;2'
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial:8] Set("Local/400@from-internal-00000cc7;2", "DIALSTATUS=CONGESTION") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-dial:9] GosubIf("Local/400@from-internal-00000cc7;2", "0?CONGESTION,1") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:29] Goto("Local/400@from-internal-00000cc7;2", "nextstep") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (from-internal,400,31)
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:31] Set("Local/400@from-internal-00000cc7;2", "RingGroupMethod=") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:32] GotoIf("Local/400@from-internal-00000cc7;2", "0?nodest") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:33] Set("Local/400@from-internal-00000cc7;2", "__NODEST=") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:34] Set("Local/400@from-internal-00000cc7;2", "__PICKUPMARK=") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:35] Macro("Local/400@from-internal-00000cc7;2", "blkvm-clr,") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-blkvm-clr:1] Set("Local/400@from-internal-00000cc7;2", "SHARED(BLKVM,Local/400@from-internal-00000cc7;2)=") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-blkvm-clr:2] Set("Local/400@from-internal-00000cc7;2", "GOSUB_RETVAL=") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-blkvm-clr:3] MacroExit("Local/400@from-internal-00000cc7;2", "") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:36] Set("Local/400@from-internal-00000cc7;2", "DIALSTATUS=NOANSWER") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@from-internal:37] Goto("Local/400@from-internal-00000cc7;2", "ext-local,400,dest") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (ext-local,400,3)
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@ext-local:3] Set("Local/400@from-internal-00000cc7;2", "__PICKUPMARK=") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [400@ext-local:4] Goto("Local/400@from-internal-00000cc7;2", ",return,1") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (ext-local,return,1)
[2014-08-16 13:10:36] WARNING[4246][C-00000f1e] pbx.c: Channel 'Local/400@from-internal-00000cc7;2' sent to invalid extension but no invalid handler: context,exten,priority=ext-local,return,1
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [h@ext-local:1] Macro("Local/400@from-internal-00000cc7;2", "hangupcall,") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("Local/400@from-internal-00000cc7;2", "1?theend") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Goto (macro-hangupcall,s,3)
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-hangupcall:3] ExecIf("Local/400@from-internal-00000cc7;2", "0?Set(CDR(recordingfile)=)") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: -- Executing [s@macro-hangupcall:4] Hangup("Local/400@from-internal-00000cc7;2", "") in new stack
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'Local/400@from-internal-00000cc7;2' in macro 'hangupcall'
[2014-08-16 13:10:36] VERBOSE[4246][C-00000f1e] pbx.c: == Spawn extension (ext-local, h, 1) exited non-zero on 'Local/400@from-internal-00000cc7;2'
[2014-08-16 13:10:36] VERBOSE[4249][C-00000f1e] app_mixmonitor.c: == MixMonitor close filestream (mixed)
[2014-08-16 13:10:36] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [400@disa-dial:3] Gosub("SIP/AGAT_4.24_OUT-0000068b", "s-ANSWER,1()") in new stack
[2014-08-16 13:10:36] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [s-ANSWER@disa-dial:1] Return("SIP/AGAT_4.24_OUT-0000068b", "") in new stack
[2014-08-16 13:10:36] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [400@disa-dial:4] Goto("SIP/AGAT_4.24_OUT-0000068b", "disa,1,newcall") in new stack
[2014-08-16 13:10:36] VERBOSE[4114][C-00000f1e] pbx.c: -- Goto (disa,1,3)
[2014-08-16 13:10:36] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [1@disa:3] Set("SIP/AGAT_4.24_OUT-0000068b", "_DISACONTEXT=from-internal") in new stack
[2014-08-16 13:10:36] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [1@disa:4] Set("SIP/AGAT_4.24_OUT-0000068b", "_KEEPCID=FALSE") in new stack
[2014-08-16 13:10:36] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [1@disa:5] Set("SIP/AGAT_4.24_OUT-0000068b", "_HANGUP=TtHg") in new stack
[2014-08-16 13:10:36] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [1@disa:6] Set("SIP/AGAT_4.24_OUT-0000068b", "TIMEOUT(digit)=5") in new stack
[2014-08-16 13:10:36] VERBOSE[4114][C-00000f1e] func_timeout.c: -- Digit timeout set to 5.000
[2014-08-16 13:10:36] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [1@disa:7] Set("SIP/AGAT_4.24_OUT-0000068b", "TIMEOUT(response)=30") in new stack
[2014-08-16 13:10:36] VERBOSE[4114][C-00000f1e] func_timeout.c: -- Response timeout set to 30.000
[2014-08-16 13:10:36] VERBOSE[4114][C-00000f1e] pbx.c: -- Executing [1@disa:8] DISA("SIP/AGAT_4.24_OUT-0000068b", "no-password,disa-dial") in new stack
[2014-08-16 13:10:36] VERBOSE[4249][C-00000f1e] app_mixmonitor.c: == End MixMonitor Recording Local/400@from-internal-00000cc7;2
[2014-08-16 13:10:39] VERBOSE[4114][C-00000f1e] pbx.c: == Spawn extension (disa, 1, 8) exited non-zero on 'SIP/AGAT_4.24_OUT-0000068b'
[2014-08-16 13:11:00] VERBOSE[4341] pbx_spool.c: -- Attempting call on Local/s@tc-maint for application NoCDR() (Retry 1)
[2014-08-16 13:11:00] VERBOSE[4342][C-00000f20] pbx.c: -- Executing [s@tc-maint:1] NoCDR("Local/s@tc-maint-00000cc9;2", "") in new stack
[2014-08-16 13:11:00] VERBOSE[4342][C-00000f20] pbx.c: -- Executing [s@tc-maint:2] Set("Local/s@tc-maint-00000cc9;2", "TCMAINT=RETURN") in new stack
[2014-08-16 13:11:00] VERBOSE[4342][C-00000f20] pbx.c: -- Executing [s@tc-maint:3] Gosub("Local/s@tc-maint-00000cc9;2", "timeconditions,1,1()") in new stack
[2014-08-16 13:11:00] VERBOSE[4342][C-00000f20] pbx.c: -- Executing [1@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000cc9;2", "08:45-17:45,mon-fri,*,*?truestate") in new stack
[2014-08-16 13:11:00] VERBOSE[4342][C-00000f20] pbx.c: -- Executing [1@timeconditions:2] GotoIf("Local/s@tc-maint-00000cc9;2", "0?truegoto") in new stack
[2014-08-16 13:11:00] VERBOSE[4342][C-00000f20] pbx.c: -- Executing [1@timeconditions:3] ExecIf("Local/s@tc-maint-00000cc9;2", "0?Set(DB(TC/1)=)") in new stack
[2014-08-16 13:11:00] VERBOSE[4342][C-00000f20] pbx.c: -- Executing [1@timeconditions:4] Set("Local/s@tc-maint-00000cc9;2", "DEVICE_STATE(Custom:TC1)=INUSE") in new stack
[2014-08-16 13:11:00] VERBOSE[4342][C-00000f20] pbx.c: -- Executing [1@timeconditions:5] ExecIf("Local/s@tc-maint-00000cc9;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2014-08-16 13:11:00] VERBOSE[4342][C-00000f20] pbx.c: -- Executing [1@timeconditions:6] GotoIf("Local/s@tc-maint-00000cc9;2", "0?ext-miscdests,1,1") in new stack
[2014-08-16 13:11:00] VERBOSE[4342][C-00000f20] pbx.c: -- Executing [1@timeconditions:7] Set("Local/s@tc-maint-00000cc9;2", "TCSTATE=false") in new stack
[2014-08-16 13:11:00] VERBOSE[4342][C-00000f20] pbx.c: -- Executing [1@timeconditions:8] Return("Local/s@tc-maint-00000cc9;2", "") in new stack
[2014-08-16 13:11:00] VERBOSE[4342][C-00000f20] pbx.c: -- Executing [s@tc-maint:4] System("Local/s@tc-maint-00000cc9;2", "/var/lib/asterisk/bin/schedtc.php 60 /var/spool/asterisk/outgoing 0") in new stack
[2014-08-16 13:11:00] VERBOSE[4342][C-00000f20] pbx.c: -- Executing [s@tc-maint:5] Answer("Local/s@tc-maint-00000cc9;2", "") in new stack
[2014-08-16 13:11:00] NOTICE[4341] pbx_spool.c: Call completed to Local/s@tc-maint
[2014-08-16 13:11:00] VERBOSE[4342][C-00000f20] pbx.c: == Spawn extension (tc-maint, s, 5) exited non-zero on 'Local/s@tc-maint-00000cc9;2'
[2014-08-16 13:12:00] VERBOSE[4377] pbx_spool.c: -- Attempting call on Local/s@tc-maint for application NoCDR() (Retry 1)
[2014-08-16 13:12:00] VERBOSE[4378][C-00000f21] pbx.c: -- Executing [s@tc-maint:1] NoCDR("Local/s@tc-maint-00000cca;2", "") in new stack
[2014-08-16 13:12:00] VERBOSE[4378][C-00000f21] pbx.c: -- Executing [s@tc-maint:2] Set("Local/s@tc-maint-00000cca;2", "TCMAINT=RETURN") in new stack
[2014-08-16 13:12:00] VERBOSE[4378][C-00000f21] pbx.c: -- Executing [s@tc-maint:3] Gosub("Local/s@tc-maint-00000cca;2", "timeconditions,1,1()") in new stack
[2014-08-16 13:12:00] VERBOSE[4378][C-00000f21] pbx.c: -- Executing [1@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000cca;2", "08:45-17:45,mon-fri,*,*?truestate") in new stack
[2014-08-16 13:12:00] VERBOSE[4378][C-00000f21] pbx.c: -- Executing [1@timeconditions:2] GotoIf("Local/s@tc-maint-00000cca;2", "0?truegoto") in new stack
[2014-08-16 13:12:00] VERBOSE[4378][C-00000f21] pbx.c: -- Executing [1@timeconditions:3] ExecIf("Local/s@tc-maint-00000cca;2", "0?Set(DB(TC/1)=)") in new stack
[2014-08-16 13:12:00] VERBOSE[4378][C-00000f21] pbx.c: -- Executing [1@timeconditions:4] Set("Local/s@tc-maint-00000cca;2", "DEVICE_STATE(Custom:TC1)=INUSE") in new stack
[2014-08-16 13:12:00] VERBOSE[4378][C-00000f21] pbx.c: -- Executing [1@timeconditions:5] ExecIf("Local/s@tc-maint-00000cca;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2014-08-16 13:12:00] VERBOSE[4378][C-00000f21] pbx.c: -- Executing [1@timeconditions:6] GotoIf("Local/s@tc-maint-00000cca;2", "0?ext-miscdests,1,1") in new stack
[2014-08-16 13:12:00] VERBOSE[4378][C-00000f21] pbx.c: -- Executing [1@timeconditions:7] Set("Local/s@tc-maint-00000cca;2", "TCSTATE=false") in new stack
[2014-08-16 13:12:00] VERBOSE[4378][C-00000f21] pbx.c: -- Executing [1@timeconditions:8] Return("Local/s@tc-maint-00000cca;2", "") in new stack
[2014-08-16 13:12:00] VERBOSE[4378][C-00000f21] pbx.c: -- Executing [s@tc-maint:4] System("Local/s@tc-maint-00000cca;2", "/var/lib/asterisk/bin/schedtc.php 60 /var/spool/asterisk/outgoing 1") in new stack
[2014-08-16 13:12:00] VERBOSE[4378][C-00000f21] pbx.c: -- Executing [s@tc-maint:5] Answer("Local/s@tc-maint-00000cca;2", "") in new stack
[2014-08-16 13:12:00] NOTICE[4377] pbx_spool.c: Call completed to Local/s@tc-maint
[2014-08-16 13:12:00] VERBOSE[4378][C-00000f21] pbx.c: == Spawn extension (tc-maint, s, 5) exited non-zero on 'Local/s@tc-maint-00000cca;2'
[2014-08-16 13:12:05] VERBOSE[4448] pbx_spool.c: -- Attempting call on Local/s@tc-maint for application NoCDR() (Retry 1)
[2014-08-16 13:12:05] VERBOSE[4449][C-00000f22] pbx.c: -- Executing [s@tc-maint:1] NoCDR("Local/s@tc-maint-00000ccb;2", "") in new stack
[2014-08-16 13:12:05] VERBOSE[4449][C-00000f22] pbx.c: -- Executing [s@tc-maint:2] Set("Local/s@tc-maint-00000ccb;2", "TCMAINT=RETURN") in new stack
[2014-08-16 13:12:05] VERBOSE[4449][C-00000f22] pbx.c: -- Executing [s@tc-maint:3] Gosub("Local/s@tc-maint-00000ccb;2", "timeconditions,1,1()") in new stack
[2014-08-16 13:12:05] VERBOSE[4449][C-00000f22] pbx.c: -- Executing [1@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000ccb;2", "08:45-17:45,mon-fri,*,*?truestate") in new stack
[2014-08-16 13:12:05] VERBOSE[4449][C-00000f22] pbx.c: -- Executing [1@timeconditions:2] GotoIf("Local/s@tc-maint-00000ccb;2", "0?truegoto") in new stack
[2014-08-16 13:12:05] VERBOSE[4449][C-00000f22] pbx.c: -- Executing [1@timeconditions:3] ExecIf("Local/s@tc-maint-00000ccb;2", "0?Set(DB(TC/1)=)") in new stack
[2014-08-16 13:12:05] VERBOSE[4449][C-00000f22] pbx.c: -- Executing [1@timeconditions:4] Set("Local/s@tc-maint-00000ccb;2", "DEVICE_STATE(Custom:TC1)=INUSE") in new stack
[2014-08-16 13:12:05] VERBOSE[4449][C-00000f22] pbx.c: -- Executing [1@timeconditions:5] ExecIf("Local/s@tc-maint-00000ccb;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2014-08-16 13:12:05] VERBOSE[4449][C-00000f22] pbx.c: -- Executing [1@timeconditions:6] GotoIf("Local/s@tc-maint-00000ccb;2", "0?ext-miscdests,1,1") in new stack
[2014-08-16 13:12:05] VERBOSE[4449][C-00000f22] pbx.c: -- Executing [1@timeconditions:7] Set("Local/s@tc-maint-00000ccb;2", "TCSTATE=false") in new stack
[2014-08-16 13:12:05] VERBOSE[4449][C-00000f22] pbx.c: -- Executing [1@timeconditions:8] Return("Local/s@tc-maint-00000ccb;2", "") in new stack
[2014-08-16 13:12:05] VERBOSE[4449][C-00000f22] pbx.c: -- Executing [s@tc-maint:4] System("Local/s@tc-maint-00000ccb;2", "/var/lib/asterisk/bin/schedtc.php 60 /var/spool/asterisk/outgoing 0") in new stack
[2014-08-16 13:12:05] VERBOSE[4449][C-00000f22] pbx.c: -- Executing [s@tc-maint:5] Answer("Local/s@tc-maint-00000ccb;2", "") in new stack
[2014-08-16 13:12:05] VERBOSE[4449][C-00000f22] pbx.c: == Spawn extension (tc-maint, s, 5) exited non-zero on 'Local/s@tc-maint-00000ccb;2'
[2014-08-16 13:12:05] NOTICE[4448] pbx_spool.c: Call completed to Local/s@tc-maint
[2014-08-16 13:13:00] VERBOSE[4481] pbx_spool.c: -- Attempting call on Local/s@tc-maint for application NoCDR() (Retry 1)
[2014-08-16 13:13:00] VERBOSE[4482][C-00000f23] pbx.c: -- Executing [s@tc-maint:1] NoCDR("Local/s@tc-maint-00000ccc;2", "") in new stack
[2014-08-16 13:13:00] VERBOSE[4482][C-00000f23] pbx.c: -- Executing [s@tc-maint:2] Set("Local/s@tc-maint-00000ccc;2", "TCMAINT=RETURN") in new stack
[2014-08-16 13:13:00] VERBOSE[4482][C-00000f23] pbx.c: -- Executing [s@tc-maint:3] Gosub("Local/s@tc-maint-00000ccc;2", "timeconditions,1,1()") in new stack
[2014-08-16 13:13:00] VERBOSE[4482][C-00000f23] pbx.c: -- Executing [1@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000ccc;2", "08:45-17:45,mon-fri,*,*?truestate") in new stack
[2014-08-16 13:13:00] VERBOSE[4482][C-00000f23] pbx.c: -- Executing [1@timeconditions:2] GotoIf("Local/s@tc-maint-00000ccc;2", "0?truegoto") in new stack
[2014-08-16 13:13:00] VERBOSE[4482][C-00000f23] pbx.c: -- Executing [1@timeconditions:3] ExecIf("Local/s@tc-maint-00000ccc;2", "0?Set(DB(TC/1)=)") in new stack
[2014-08-16 13:13:00] VERBOSE[4482][C-00000f23] pbx.c: -- Executing [1@timeconditions:4] Set("Local/s@tc-maint-00000ccc;2", "DEVICE_STATE(Custom:TC1)=INUSE") in new stack
[2014-08-16 13:13:00] VERBOSE[4482][C-00000f23] pbx.c: -- Executing [1@timeconditions:5] ExecIf("Local/s@tc-maint-00000ccc;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2014-08-16 13:13:00] VERBOSE[4482][C-00000f23] pbx.c: -- Executing [1@timeconditions:6] GotoIf("Local/s@tc-maint-00000ccc;2", "0?ext-miscdests,1,1") in new stack
[2014-08-16 13:13:00] VERBOSE[4482][C-00000f23] pbx.c: -- Executing [1@timeconditions:7] Set("Local/s@tc-maint-00000ccc;2", "TCSTATE=false") in new stack
[2014-08-16 13:13:00] VERBOSE[4482][C-00000f23] pbx.c: -- Executing [1@timeconditions:8] Return("Local/s@tc-maint-00000ccc;2", "") in new stack
[2014-08-16 13:13:00] VERBOSE[4482][C-00000f23] pbx.c: -- Executing [s@tc-maint:4] System("Local/s@tc-maint-00000ccc;2", "/var/lib/asterisk/bin/schedtc.php 60 /var/spool/asterisk/outgoing 1") in new stack
[2014-08-16 13:13:00] VERBOSE[4482][C-00000f23] pbx.c: -- Executing [s@tc-maint:5] Answer("Local/s@tc-maint-00000ccc;2", "") in new stack
[2014-08-16 13:13:00] VERBOSE[4482][C-00000f23] pbx.c: == Spawn extension (tc-maint, s, 5) exited non-zero on 'Local/s@tc-maint-00000ccc;2'
[2014-08-16 13:13:00] NOTICE[4481] pbx_spool.c: Call completed to Local/s@tc-maint
[2014-08-16 13:14:00] VERBOSE[4595] pbx_spool.c: -- Attempting call on Local/s@tc-maint for application NoCDR() (Retry 1)
[2014-08-16 13:14:00] VERBOSE[4596][C-00000f24] pbx.c: -- Executing [s@tc-maint:1] NoCDR("Local/s@tc-maint-00000ccd;2", "") in new stack
[2014-08-16 13:14:00] VERBOSE[4596][C-00000f24] pbx.c: -- Executing [s@tc-maint:2] Set("Local/s@tc-maint-00000ccd;2", "TCMAINT=RETURN") in new stack
[2014-08-16 13:14:00] VERBOSE[4596][C-00000f24] pbx.c: -- Executing [s@tc-maint:3] Gosub("Local/s@tc-maint-00000ccd;2", "timeconditions,1,1()") in new stack
[2014-08-16 13:14:00] VERBOSE[4596][C-00000f24] pbx.c: -- Executing [1@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000ccd;2", "08:45-17:45,mon-fri,*,*?truestate") in new stack
[2014-08-16 13:14:00] VERBOSE[4596][C-00000f24] pbx.c: -- Executing [1@timeconditions:2] GotoIf("Local/s@tc-maint-00000ccd;2", "0?truegoto") in new stack
[2014-08-16 13:14:00] VERBOSE[4596][C-00000f24] pbx.c: -- Executing [1@timeconditions:3] ExecIf("Local/s@tc-maint-00000ccd;2", "0?Set(DB(TC/1)=)") in new stack
[2014-08-16 13:14:00] VERBOSE[4596][C-00000f24] pbx.c: -- Executing [1@timeconditions:4] Set("Local/s@tc-maint-00000ccd;2", "DEVICE_STATE(Custom:TC1)=INUSE") in new stack
[2014-08-16 13:14:00] VERBOSE[4596][C-00000f24] pbx.c: -- Executing [1@timeconditions:5] ExecIf("Local/s@tc-maint-00000ccd;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2014-08-16 13:14:00] VERBOSE[4596][C-00000f24] pbx.c: -- Executing [1@timeconditions:6] GotoIf("Local/s@tc-maint-00000ccd;2", "0?ext-miscdests,1,1") in new stack
[2014-08-16 13:14:00] VERBOSE[4596][C-00000f24] pbx.c: -- Executing [1@timeconditions:7] Set("Local/s@tc-maint-00000ccd;2", "TCSTATE=false") in new stack
[2014-08-16 13:14:00] VERBOSE[4596][C-00000f24] pbx.c: -- Executing [1@timeconditions:8] Return("Local/s@tc-maint-00000ccd;2", "") in new stack
[2014-08-16 13:14:00] VERBOSE[4596][C-00000f24] pbx.c: -- Executing [s@tc-maint:4] System("Local/s@tc-maint-00000ccd;2", "/var/lib/asterisk/bin/schedtc.php 60 /var/spool/asterisk/outgoing 0") in new stack
[2014-08-16 13:14:00] VERBOSE[4596][C-00000f24] pbx.c: -- Executing [s@tc-maint:5] Answer("Local/s@tc-maint-00000ccd;2", "") in new stack
[2014-08-16 13:14:00] VERBOSE[4596][C-00000f24] pbx.c: == Spawn extension (tc-maint, s, 5) exited non-zero on 'Local/s@tc-maint-00000ccd;2'
[2014-08-16 13:14:00] NOTICE[4595] pbx_spool.c: Call completed to Local/s@tc-maint