When trying to place an outbound call I get a fast busy Please HELP!:
[2017-04-06 14:38:51] WARNING[3899][C-00000013] chan_sip.c: Got Opus useinbandfec=1
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [8049312266@from-internal:1] Macro("SIP/10-00000011", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/10-00000011", "TOUCH_MONITOR=1491503931.33") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:2] Set("SIP/10-00000011", "AMPUSER=10") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/10-00000011", "0?report") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/10-00000011", "1?Set(REALCALLERIDNUM=10)") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/10-00000011", "AMPUSER=10") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/10-00000011", "0?limit") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/10-00000011", "AMPUSERCIDNAME=Test Ext") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:8] GotoIf("SIP/10-00000011", "0?report") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:9] Set("SIP/10-00000011", "AMPUSERCID=10") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:10] Set("SIP/10-00000011", "DIAL_OPTIONS=tr") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:11] Set("SIP/10-00000011", "CALLERID(all)="Test Ext" <10>") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/10-00000011", "0?limit") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:13] ExecIf("SIP/10-00000011", "1?Set(GROUP(concurrency_limit)=10)") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:14] ExecIf("SIP/10-00000011", "0?Set(CHANNEL(language)=)") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:15] GotoIf("SIP/10-00000011", "1?continue") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Goto (macro-user-callerid,s,28)
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:28] Set("SIP/10-00000011", "CALLERID(number)=10") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:29] Set("SIP/10-00000011", "CALLERID(name)=Test Ext") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:30] Set("SIP/10-00000011", "CDR(cnum)=10") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:31] Set("SIP/10-00000011", "CDR(cnam)=Test Ext") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-user-callerid:32] Set("SIP/10-00000011", "CHANNEL(language)=en") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [8049312266@from-internal:2] Set("SIP/10-00000011", "REC_POLICY_MODE=always") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [8049312266@from-internal:3] Set("SIP/10-00000011", "MOHCLASS=default") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [8049312266@from-internal:4] Set("SIP/10-00000011", "_NODEST=") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [8049312266@from-internal:5] Gosub("SIP/10-00000011", "sub-record-check,s,1(out,8049312266,)") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@sub-record-check:1] Set("SIP/10-00000011", "REC_POLICY_MODE_SAVE=always") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@sub-record-check:2] GotoIf("SIP/10-00000011", "1?check") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Goto (sub-record-check,s,7)
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/10-00000011", "MON_FMT=wav") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@sub-record-check:8] GotoIf("SIP/10-00000011", "1?next") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Goto (sub-record-check,s,11)
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("SIP/10-00000011", "0?Return()") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@sub-record-check:12] ExecIf("SIP/10-00000011", "0?Set(REC_POLICY_MODE=)") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@sub-record-check:13] GotoIf("SIP/10-00000011", "0?out,1") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@sub-record-check:14] Set("SIP/10-00000011", "REC_STATUS=INITIALIZED") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@sub-record-check:15] Set("SIP/10-00000011", "NOW=1491503931") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@sub-record-check:16] Set("SIP/10-00000011", "DAY=06") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@sub-record-check:17] Set("SIP/10-00000011", "MONTH=04") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@sub-record-check:18] Set("SIP/10-00000011", "YEAR=2017") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@sub-record-check:19] Set("SIP/10-00000011", "TIMESTR=20170406-143851") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@sub-record-check:20] Set("SIP/10-00000011", "FROMEXTEN=10") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@sub-record-check:21] Set("SIP/10-00000011", "CALLFILENAME=out-8049312266-10-20170406-143851-1491503931.33") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@sub-record-check:22] Goto("SIP/10-00000011", "out,1") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Goto (sub-record-check,out,1)
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [out@sub-record-check:1] ExecIf("SIP/10-00000011", "0?Set(REC_POLICY_MODE=always)") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [out@sub-record-check:2] GosubIf("SIP/10-00000011", "1?record,1(exten,8049312266,10)") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [record@sub-record-check:1] Set("SIP/10-00000011", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [record@sub-record-check:2] MixMonitor("SIP/10-00000011", "2017/04/06/out-8049312266-10-20170406-143851-1491503931.33.wav,,") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [record@sub-record-check:3] Set("SIP/10-00000011", "__REC_STATUS=RECORDING") in new stack
[2017-04-06 14:38:51] VERBOSE[8468][C-00000013] app_mixmonitor.c: == Begin MixMonitor Recording SIP/10-00000011
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [record@sub-record-check:4] Set("SIP/10-00000011", "CDR(recordingfile)=out-8049312266-10-20170406-143851-1491503931.33.wav") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [record@sub-record-check:5] Return("SIP/10-00000011", "") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [out@sub-record-check:3] Return("SIP/10-00000011", "") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [8049312266@from-internal:6] Macro("SIP/10-00000011", "outisbusy,") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-outisbusy:1] Progress("SIP/10-00000011", "") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-outisbusy:2] GotoIf("SIP/10-00000011", "0?emergency,1") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-outisbusy:3] GotoIf("SIP/10-00000011", "0?intracompany,1") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-outisbusy:4] Playback("SIP/10-00000011", "all-circuits-busy-now&pls-try-call-later, noanswer") in new stack
[2017-04-06 14:38:51] VERBOSE[8467][C-00000013] file.c: -- <SIP/10-00000011> Playing 'all-circuits-busy-now.gsm' (language 'en')
[2017-04-06 14:38:52] WARNING[8467][C-00000013] file.c: File pls-try-call-later does not exist in any format
[2017-04-06 14:38:52] WARNING[8467][C-00000013] file.c: Unable to open pls-try-call-later (format (alaw)): No such file or directory
[2017-04-06 14:38:52] WARNING[8467][C-00000013] app_playback.c: ast_streamfile failed on SIP/10-00000011 for all-circuits-busy-now&pls-try-call-later, noanswer
[2017-04-06 14:38:52] VERBOSE[8467][C-00000013] pbx.c: -- Executing [s@macro-outisbusy:5] Congestion("SIP/10-00000011", "20") in new stack
[2017-04-06 14:38:52] WARNING[8467][C-00000013] channel.c: Prodding channel 'SIP/10-00000011' failed
[2017-04-06 14:38:52] VERBOSE[8467][C-00000013] app_macro.c: == Spawn extension (macro-outisbusy, s, 5) exited non-zero on 'SIP/10-00000011' in macro 'outisbusy'
[2017-04-06 14:38:52] VERBOSE[8467][C-00000013] pbx.c: == Spawn extension (from-internal, 8049312266, 6) exited non-zero on 'SIP/10-00000011'
[2017-04-06 14:38:52] VERBOSE[8467][C-00000013] pbx.c: -- Executing [h@from-internal:1] Hangup("SIP/10-00000011", "") in new stack
[2017-04-06 14:38:52] VERBOSE[8467][C-00000013] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/10-00000011'
[2017-04-06 14:38:52] VERBOSE[8468][C-00000013] app_mixmonitor.c: == MixMonitor close filestream (mixed)
[2017-04-06 14:38:52] VERBOSE[8468][C-00000013] app_mixmonitor.c: == End MixMonitor Recording SIP/10-00000011
[2017-04-06 14:39:00] VERBOSE[8469] pbx_spool.c: -- Attempting call on Local/s@tc-maint for application NoCDR() (Retry 1)
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [s@tc-maint:1] NoCDR("Local/s@tc-maint-00000008;2", "") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [s@tc-maint:2] Set("Local/s@tc-maint-00000008;2", "TCMAINT=RETURN") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [s@tc-maint:3] Gosub("Local/s@tc-maint-00000008;2", "timeconditions,1,1()") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [1@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000008;2", "22:20-09:00,mon-thu,1-31,jan-dec?truestate") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [1@timeconditions:2] GotoIf("Local/s@tc-maint-00000008;2", "0?truegoto") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [1@timeconditions:3] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DB(TC/1)=)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [1@timeconditions:4] Set("Local/s@tc-maint-00000008;2", "DEVICE_STATE(Custom:TC1)=INUSE") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [1@timeconditions:5] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [1@timeconditions:6] GotoIf("Local/s@tc-maint-00000008;2", "0?ivr-5,s,1") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [1@timeconditions:7] Set("Local/s@tc-maint-00000008;2", "TCSTATE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [1@timeconditions:8] Set("Local/s@tc-maint-00000008;2", "TCOVERRIDE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [1@timeconditions:9] Return("Local/s@tc-maint-00000008;2", "") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [s@tc-maint:4] Gosub("Local/s@tc-maint-00000008;2", "timeconditions,11,1()") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [11@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000008;2", "00:00-23:59,sat-sun,1-31,jan-dec?truestate") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [11@timeconditions:2] GotoIf("Local/s@tc-maint-00000008;2", "0?truegoto") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [11@timeconditions:3] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DB(TC/11)=)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [11@timeconditions:4] Set("Local/s@tc-maint-00000008;2", "DEVICE_STATE(Custom:TC11)=INUSE") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [11@timeconditions:5] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [11@timeconditions:6] GotoIf("Local/s@tc-maint-00000008;2", "0?ivr-21,s,1") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [11@timeconditions:7] Set("Local/s@tc-maint-00000008;2", "TCSTATE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [11@timeconditions:8] Set("Local/s@tc-maint-00000008;2", "TCOVERRIDE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [11@timeconditions:9] Return("Local/s@tc-maint-00000008;2", "") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [s@tc-maint:5] Gosub("Local/s@tc-maint-00000008;2", "timeconditions,10,1()") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [10@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000008;2", "00:00-23:59,sat-sun,1-31,jan-dec?truestate") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [10@timeconditions:2] GotoIf("Local/s@tc-maint-00000008;2", "0?truegoto") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [10@timeconditions:3] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DB(TC/10)=)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [10@timeconditions:4] Set("Local/s@tc-maint-00000008;2", "DEVICE_STATE(Custom:TC10)=INUSE") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [10@timeconditions:5] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [10@timeconditions:6] GotoIf("Local/s@tc-maint-00000008;2", "0?ivr-20,s,1") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [10@timeconditions:7] Set("Local/s@tc-maint-00000008;2", "TCSTATE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [10@timeconditions:8] Set("Local/s@tc-maint-00000008;2", "TCOVERRIDE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [10@timeconditions:9] Return("Local/s@tc-maint-00000008;2", "") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [s@tc-maint:6] Gosub("Local/s@tc-maint-00000008;2", "timeconditions,9,1()") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [9@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000008;2", "00:00-23:59,sat-sun,1-31,jan-dec?truestate") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [9@timeconditions:2] GotoIf("Local/s@tc-maint-00000008;2", "0?truegoto") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [9@timeconditions:3] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DB(TC/9)=)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [9@timeconditions:4] Set("Local/s@tc-maint-00000008;2", "DEVICE_STATE(Custom:TC9)=INUSE") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [9@timeconditions:5] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [9@timeconditions:6] GotoIf("Local/s@tc-maint-00000008;2", "0?ivr-19,s,1") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [9@timeconditions:7] Set("Local/s@tc-maint-00000008;2", "TCSTATE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [9@timeconditions:8] Set("Local/s@tc-maint-00000008;2", "TCOVERRIDE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [9@timeconditions:9] Return("Local/s@tc-maint-00000008;2", "") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [s@tc-maint:7] Gosub("Local/s@tc-maint-00000008;2", "timeconditions,8,1()") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [8@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000008;2", "00:00-23:59,sat-sun,1-31,jan-dec?truestate") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [8@timeconditions:2] GotoIf("Local/s@tc-maint-00000008;2", "0?truegoto") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [8@timeconditions:3] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DB(TC/8)=)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [8@timeconditions:4] Set("Local/s@tc-maint-00000008;2", "DEVICE_STATE(Custom:TC8)=INUSE") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [8@timeconditions:5] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [8@timeconditions:6] GotoIf("Local/s@tc-maint-00000008;2", "0?ivr-15,s,1") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [8@timeconditions:7] Set("Local/s@tc-maint-00000008;2", "TCSTATE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [8@timeconditions:8] Set("Local/s@tc-maint-00000008;2", "TCOVERRIDE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [8@timeconditions:9] Return("Local/s@tc-maint-00000008;2", "") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [s@tc-maint:8] Gosub("Local/s@tc-maint-00000008;2", "timeconditions,7,1()") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [7@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000008;2", "00:00-23:59,sat-sun,1-31,jan-dec?truestate") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [7@timeconditions:2] GotoIf("Local/s@tc-maint-00000008;2", "0?truegoto") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [7@timeconditions:3] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DB(TC/7)=)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [7@timeconditions:4] Set("Local/s@tc-maint-00000008;2", "DEVICE_STATE(Custom:TC7)=INUSE") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [7@timeconditions:5] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [7@timeconditions:6] GotoIf("Local/s@tc-maint-00000008;2", "0?ext-group,603,1") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [7@timeconditions:7] Set("Local/s@tc-maint-00000008;2", "TCSTATE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [7@timeconditions:8] Set("Local/s@tc-maint-00000008;2", "TCOVERRIDE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [7@timeconditions:9] Return("Local/s@tc-maint-00000008;2", "") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [s@tc-maint:9] Gosub("Local/s@tc-maint-00000008;2", "timeconditions,6,1()") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [6@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000008;2", "00:00-23:59,sat-sun,1-31,jan-dec?truestate") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [6@timeconditions:2] GotoIf("Local/s@tc-maint-00000008;2", "0?truegoto") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [6@timeconditions:3] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DB(TC/6)=)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [6@timeconditions:4] Set("Local/s@tc-maint-00000008;2", "DEVICE_STATE(Custom:TC6)=INUSE") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [6@timeconditions:5] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [6@timeconditions:6] GotoIf("Local/s@tc-maint-00000008;2", "0?ivr-17,s,1") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [6@timeconditions:7] Set("Local/s@tc-maint-00000008;2", "TCSTATE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [6@timeconditions:8] Set("Local/s@tc-maint-00000008;2", "TCOVERRIDE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [6@timeconditions:9] Return("Local/s@tc-maint-00000008;2", "") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [s@tc-maint:10] Gosub("Local/s@tc-maint-00000008;2", "timeconditions,5,1()") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [5@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000008;2", "00:00-23:59,sat-sun,1-31,jan-dec?truestate") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [5@timeconditions:2] GotoIf("Local/s@tc-maint-00000008;2", "0?truegoto") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [5@timeconditions:3] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DB(TC/5)=)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [5@timeconditions:4] Set("Local/s@tc-maint-00000008;2", "DEVICE_STATE(Custom:TC5)=INUSE") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [5@timeconditions:5] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [5@timeconditions:6] GotoIf("Local/s@tc-maint-00000008;2", "0?ivr-16,s,1") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [5@timeconditions:7] Set("Local/s@tc-maint-00000008;2", "TCSTATE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [5@timeconditions:8] Set("Local/s@tc-maint-00000008;2", "TCOVERRIDE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [5@timeconditions:9] Return("Local/s@tc-maint-00000008;2", "") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [s@tc-maint:11] Gosub("Local/s@tc-maint-00000008;2", "timeconditions,4,1()") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [4@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000008;2", "00:00-23:59,sat-sun,1-31,jan-dec?truestate") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [4@timeconditions:2] GotoIf("Local/s@tc-maint-00000008;2", "0?truegoto") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [4@timeconditions:3] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DB(TC/4)=)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [4@timeconditions:4] Set("Local/s@tc-maint-00000008;2", "DEVICE_STATE(Custom:TC4)=INUSE") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [4@timeconditions:5] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [4@timeconditions:6] GotoIf("Local/s@tc-maint-00000008;2", "0?ivr-14,s,1") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [4@timeconditions:7] Set("Local/s@tc-maint-00000008;2", "TCSTATE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [4@timeconditions:8] Set("Local/s@tc-maint-00000008;2", "TCOVERRIDE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [4@timeconditions:9] Return("Local/s@tc-maint-00000008;2", "") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [s@tc-maint:12] Gosub("Local/s@tc-maint-00000008;2", "timeconditions,3,1()") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [3@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000008;2", "00:00-23:59,sat-sun,1-31,jan-dec?truestate") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [3@timeconditions:2] GotoIf("Local/s@tc-maint-00000008;2", "0?truegoto") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [3@timeconditions:3] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DB(TC/3)=)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [3@timeconditions:4] Set("Local/s@tc-maint-00000008;2", "DEVICE_STATE(Custom:TC3)=INUSE") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [3@timeconditions:5] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [3@timeconditions:6] GotoIf("Local/s@tc-maint-00000008;2", "0?ivr-18,s,1") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [3@timeconditions:7] Set("Local/s@tc-maint-00000008;2", "TCSTATE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [3@timeconditions:8] Set("Local/s@tc-maint-00000008;2", "TCOVERRIDE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [3@timeconditions:9] Return("Local/s@tc-maint-00000008;2", "") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [s@tc-maint:13] Gosub("Local/s@tc-maint-00000008;2", "timeconditions,2,1()") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [2@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000008;2", "00:00-23:59,sat-sun,1-31,jan-dec?truestate") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [2@timeconditions:2] GotoIf("Local/s@tc-maint-00000008;2", "0?truegoto") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [2@timeconditions:3] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DB(TC/2)=)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [2@timeconditions:4] Set("Local/s@tc-maint-00000008;2", "DEVICE_STATE(Custom:TC2)=INUSE") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [2@timeconditions:5] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [2@timeconditions:6] GotoIf("Local/s@tc-maint-00000008;2", "0?ivr-13,s,1") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [2@timeconditions:7] Set("Local/s@tc-maint-00000008;2", "TCSTATE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [2@timeconditions:8] Set("Local/s@tc-maint-00000008;2", "TCOVERRIDE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [2@timeconditions:9] Return("Local/s@tc-maint-00000008;2", "") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [s@tc-maint:14] Gosub("Local/s@tc-maint-00000008;2", "timeconditions,13,1()") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [13@timeconditions:1] GotoIfTime("Local/s@tc-maint-00000008;2", "00:00-23:59,sat-sun,1-31,jan-dec?truestate") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [13@timeconditions:2] GotoIf("Local/s@tc-maint-00000008;2", "0?truegoto") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [13@timeconditions:3] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DB(TC/13)=)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [13@timeconditions:4] Set("Local/s@tc-maint-00000008;2", "DEVICE_STATE(Custom:TC13)=INUSE") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [13@timeconditions:5] ExecIf("Local/s@tc-maint-00000008;2", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [13@timeconditions:6] GotoIf("Local/s@tc-maint-00000008;2", "0?ivr-22,s,1") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [13@timeconditions:7] Set("Local/s@tc-maint-00000008;2", "TCSTATE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [13@timeconditions:8] Set("Local/s@tc-maint-00000008;2", "TCOVERRIDE=false") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [13@timeconditions:9] Return("Local/s@tc-maint-00000008;2", "") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [s@tc-maint:15] System("Local/s@tc-maint-00000008;2", "/var/lib/asterisk/bin/schedtc.php 60 /var/spool/asterisk/outgoing 1") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: -- Executing [s@tc-maint:16] Answer("Local/s@tc-maint-00000008;2", "") in new stack
[2017-04-06 14:39:00] VERBOSE[8470][C-00000014] pbx.c: == Spawn extension (tc-maint, s, 16) exited non-zero on 'Local/s@tc-maint-00000008;2'
[2017-04-06 14:39:00] NOTICE[8469] pbx_spool.c: Call completed to Local/s@tc-maint