Let’s see if the forum will let me split the log in half… First half:
[2015-03-25 07:44:00] VERBOSE[28242][C-000019b2] pbx.c: == Spawn extension (tc-maint, s, 6) exited non-zero on 'Local/s@tc-maint-00001a5c;2'
[2015-03-25 07:44:11] VERBOSE[2016][C-000019b3] netsock2.c: == Using SIP RTP TOS bits 184
[2015-03-25 07:44:11] VERBOSE[2016][C-000019b3] netsock2.c: == Using SIP RTP CoS mark 5
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [7777@from-trunk:1] NoOp("SIP/Mediatrix1204-00000351", "Catch-All DID Match - Found 7777 - You probably want a DID for this.") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [7777@from-trunk:2] Log("SIP/Mediatrix1204-00000351", "WARNING,Friendly Scanner from 192.168.11.3;branch=z9hG4bK04b646de2") in new stack
[2015-03-25 07:44:11] WARNING[28274][C-000019b3] Ext. 7777: Friendly Scanner from 192.168.11.3;branch=z9hG4bK04b646de2
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [7777@from-trunk:3] Set("SIP/Mediatrix1204-00000351", "__FROM_DID=7777") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [7777@from-trunk:4] Goto("SIP/Mediatrix1204-00000351", "ext-did,s,1") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Goto (ext-did,s,1)
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@ext-did:1] ExecIf("SIP/Mediatrix1204-00000351", "0?Set(__FROM_DID=s)") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@ext-did:2] Gosub("SIP/Mediatrix1204-00000351", "app-blacklist-check,s,1()") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("SIP/Mediatrix1204-00000351", "0?blacklisted") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@app-blacklist-check:2] Set("SIP/Mediatrix1204-00000351", "CALLED_BLACKLIST=1") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@app-blacklist-check:3] Return("SIP/Mediatrix1204-00000351", "") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@ext-did:3] Set("SIP/Mediatrix1204-00000351", "CDR(did)=7777") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@ext-did:4] ExecIf("SIP/Mediatrix1204-00000351", "0 ?Set(CALLERID(name)=mycallerid)") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@ext-did:5] Set("SIP/Mediatrix1204-00000351", "CHANNEL(musicclass)=default") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@ext-did:6] Set("SIP/Mediatrix1204-00000351", "__MOHCLASS=default") in new stack
[2015-03-25 07:44:11] WARNING[28274][C-000019b3] func_channel.c: Unknown or unavailable item requested: 'reversecharge'
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@ext-did:7] GotoIf("SIP/Mediatrix1204-00000351", "0?macro-hangupcall") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@ext-did:8] Set("SIP/Mediatrix1204-00000351", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@ext-did:9] Set("SIP/Mediatrix1204-00000351", "CALLERPRES()=allowed_not_screened") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@ext-did:10] Goto("SIP/Mediatrix1204-00000351", "timeconditions,2,1") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Goto (timeconditions,2,1)
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [2@timeconditions:1] Set("SIP/Mediatrix1204-00000351", "DB(TC/2/INUSESTATE)=INUSE") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [2@timeconditions:2] Set("SIP/Mediatrix1204-00000351", "DB(TC/2/NOT_INUSESTATE)=NOT_INUSE") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [2@timeconditions:3] GotoIfTime("SIP/Mediatrix1204-00000351", "07:30-16:30,*,25,may?truestate") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [2@timeconditions:4] GotoIf("SIP/Mediatrix1204-00000351", "0?truegoto") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [2@timeconditions:5] ExecIf("SIP/Mediatrix1204-00000351", "0?Set(DB(TC/2)=)") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [2@timeconditions:6] Set("SIP/Mediatrix1204-00000351", "DEVICE_STATE(Custom:TC2)=INUSE") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [2@timeconditions:7] ExecIf("SIP/Mediatrix1204-00000351", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [2@timeconditions:8] GotoIf("SIP/Mediatrix1204-00000351", "1?timeconditions,1,1") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Goto (timeconditions,1,1)
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [1@timeconditions:1] Set("SIP/Mediatrix1204-00000351", "DB(TC/1/INUSESTATE)=INUSE") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [1@timeconditions:2] Set("SIP/Mediatrix1204-00000351", "DB(TC/1/NOT_INUSESTATE)=NOT_INUSE") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [1@timeconditions:3] GotoIfTime("SIP/Mediatrix1204-00000351", "07:30-16:30,mon-fri,*,*?truestate") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Goto (timeconditions,1,12)
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [1@timeconditions:12] GotoIf("SIP/Mediatrix1204-00000351", "0?falsegoto") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [1@timeconditions:13] ExecIf("SIP/Mediatrix1204-00000351", "0?Set(DB(TC/1)=)") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [1@timeconditions:14] Set("SIP/Mediatrix1204-00000351", "DEVICE_STATE(Custom:TC1)=NOT_INUSE") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [1@timeconditions:15] ExecIf("SIP/Mediatrix1204-00000351", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [1@timeconditions:16] GotoIf("SIP/Mediatrix1204-00000351", "1?ext-queues,9001,1") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Goto (ext-queues,9001,1)
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:1] Macro("SIP/Mediatrix1204-00000351", "user-callerid,") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/Mediatrix1204-00000351", "TOUCH_MONITOR=1427287451.14404") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:2] Set("SIP/Mediatrix1204-00000351", "AMPUSER=mycallerid") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/Mediatrix1204-00000351", "0?report") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/Mediatrix1204-00000351", "1?Set(REALCALLERIDNUM=mycallerid)") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/Mediatrix1204-00000351", "AMPUSER=") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/Mediatrix1204-00000351", "0?limit") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/Mediatrix1204-00000351", "AMPUSERCIDNAME=") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:8] GotoIf("SIP/Mediatrix1204-00000351", "1?report") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Goto (macro-user-callerid,s,16)
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:16] GotoIf("SIP/Mediatrix1204-00000351", "0?continue") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:17] ExecIf("SIP/Mediatrix1204-00000351", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:18] Set("SIP/Mediatrix1204-00000351", "__TTL=64") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:19] GotoIf("SIP/Mediatrix1204-00000351", "1?continue") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Goto (macro-user-callerid,s,30)
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:30] Set("SIP/Mediatrix1204-00000351", "CALLERID(number)=mycallerid") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:31] Set("SIP/Mediatrix1204-00000351", "CALLERID(name)=my CNAM") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:32] Set("SIP/Mediatrix1204-00000351", "CDR(cnum)=mycallerid") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:33] Set("SIP/Mediatrix1204-00000351", "CDR(cnam)=my CNAM") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:34] Set("SIP/Mediatrix1204-00000351", "CHANNEL(language)=en") in new stack
[2015-03-25 07:44:11] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:2] Answer("SIP/Mediatrix1204-00000351", "") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:3] Macro("SIP/Mediatrix1204-00000351", "blkvm-set,reset") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-blkvm-set:1] ExecIf("SIP/Mediatrix1204-00000351", "1?Set(__BLKVM_CHANNEL=SIP/Mediatrix1204-00000351)") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-blkvm-set:2] Set("SIP/Mediatrix1204-00000351", "SHARED(BLKVM,SIP/Mediatrix1204-00000351)=TRUE") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-blkvm-set:3] Set("SIP/Mediatrix1204-00000351", "GOSUB_RETVAL=TRUE") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@macro-blkvm-set:4] MacroExit("SIP/Mediatrix1204-00000351", "") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:4] ExecIf("SIP/Mediatrix1204-00000351", "1?Set(_DIAL_OPTIONS=TtrM(auto-blkvm))") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:5] Set("SIP/Mediatrix1204-00000351", "__NODEST=9001") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:6] Set("SIP/Mediatrix1204-00000351", "QCIDPP=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:7] Set("SIP/Mediatrix1204-00000351", "VQ_CIDPP=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:8] ExecIf("SIP/Mediatrix1204-00000351", "0?Macro(prepend-cid,)") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:9] Set("SIP/Mediatrix1204-00000351", "QAINFO=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:10] Set("SIP/Mediatrix1204-00000351", "VQ_AINFO=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:11] ExecIf("SIP/Mediatrix1204-00000351", "0?Set(__ALERT_INFO=)") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:12] Set("SIP/Mediatrix1204-00000351", "QJOINMSG=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:13] Set("SIP/Mediatrix1204-00000351", "VQ_JOINMSG=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:14] Set("SIP/Mediatrix1204-00000351", "QRINGOPTS=r") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:15] Set("SIP/Mediatrix1204-00000351", "QRETRY=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:16] Set("SIP/Mediatrix1204-00000351", "VQ_RETRY=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:17] Set("SIP/Mediatrix1204-00000351", "QOPTIONS=tr") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:18] Set("SIP/Mediatrix1204-00000351", "VQ_OPTIONS=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:19] Set("SIP/Mediatrix1204-00000351", "QGOSUB=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:20] Set("SIP/Mediatrix1204-00000351", "VQ_GOSUB=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:21] Set("SIP/Mediatrix1204-00000351", "QAGI=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:22] Set("SIP/Mediatrix1204-00000351", "VQ_AGI=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:23] Set("SIP/Mediatrix1204-00000351", "QRULE=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:24] Set("SIP/Mediatrix1204-00000351", "VQ_RULE=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:25] Set("SIP/Mediatrix1204-00000351", "QPOSITION=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:26] Set("SIP/Mediatrix1204-00000351", "VQ_POSITION=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:27] Gosub("SIP/Mediatrix1204-00000351", "sub-record-check,s,1(q,9001,dontcare)") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("SIP/Mediatrix1204-00000351", "0?initialized") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:2] Set("SIP/Mediatrix1204-00000351", "__REC_STATUS=INITIALIZED") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:3] Set("SIP/Mediatrix1204-00000351", "NOW=1427287452") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:4] Set("SIP/Mediatrix1204-00000351", "__DAY=25") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:5] Set("SIP/Mediatrix1204-00000351", "__MONTH=03") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:6] Set("SIP/Mediatrix1204-00000351", "__YEAR=2015") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/Mediatrix1204-00000351", "__TIMESTR=20150325-074412") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:8] Set("SIP/Mediatrix1204-00000351", "__FROMEXTEN=mycallerid") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:9] Set("SIP/Mediatrix1204-00000351", "__MON_FMT=wav") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:10] NoOp("SIP/Mediatrix1204-00000351", "Recordings initialized") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("SIP/Mediatrix1204-00000351", "0?Set(ARG3=dontcare)") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:12] Set("SIP/Mediatrix1204-00000351", "REC_POLICY_MODE_SAVE=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("SIP/Mediatrix1204-00000351", "0?Set(REC_STATUS=NO)") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:14] GotoIf("SIP/Mediatrix1204-00000351", "1?checkaction") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Goto (sub-record-check,s,17)
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:17] GotoIf("SIP/Mediatrix1204-00000351", "0?sub-record-check,q,1") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:18] NoOp("SIP/Mediatrix1204-00000351", "Generic q Recording Check - mycallerid 9001") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:19] Gosub("SIP/Mediatrix1204-00000351", "recordcheck,1(dontcare,q,9001)") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/Mediatrix1204-00000351", "Starting recording check against dontcare") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("SIP/Mediatrix1204-00000351", "dontcare") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Goto (sub-record-check,recordcheck,3)
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [recordcheck@sub-record-check:3] Return("SIP/Mediatrix1204-00000351", "") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [s@sub-record-check:20] Return("SIP/Mediatrix1204-00000351", "") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:28] Set("SIP/Mediatrix1204-00000351", "__CWIGNORE=TRUE") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:29] ExecIf("SIP/Mediatrix1204-00000351", "0?Playback(, )") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:30] QueueLog("SIP/Mediatrix1204-00000351", "9001,1427287451.14404,NONE,DID,7777") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:31] Set("SIP/Mediatrix1204-00000351", "QAANNOUNCE=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:32] Set("SIP/Mediatrix1204-00000351", "VQ_AANNOUNCE=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:33] Set("SIP/Mediatrix1204-00000351", "QMOH=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:34] Set("SIP/Mediatrix1204-00000351", "VQ_MOH=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:35] ExecIf("SIP/Mediatrix1204-00000351", "0?Set(__MOHCLASS=)") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:36] ExecIf("SIP/Mediatrix1204-00000351", "1?Set(CHANNEL(musicclass)=default)") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:37] Set("SIP/Mediatrix1204-00000351", "QMAXWAIT=10") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:38] Set("SIP/Mediatrix1204-00000351", "VQ_MAXWAIT=") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:39] Set("SIP/Mediatrix1204-00000351", "QUEUENUM=9001") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:40] Set("SIP/Mediatrix1204-00000351", "QUEUEJOINTIME=1427287452") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] pbx.c: -- Executing [9001@ext-queues:41] Queue("SIP/Mediatrix1204-00000351", "9001,tr,,,10,,,,,") in new stack
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] app_queue.c: -- Called Local/103@from-queue/n
[2015-03-25 07:44:12] VERBOSE[28274][C-000019b3] app_queue.c: -- LazyMembers debugging - Numbusies: 0, Nummems: 1
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [103@from-queue:1] Set("Local/103@from-queue-00001a5d;2", "QAGENT=103") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [103@from-queue:2] Goto("Local/103@from-queue-00001a5d;2", "9001,1") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Goto (from-queue,9001,1)
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [9001@from-queue:1] Goto("Local/103@from-queue-00001a5d;2", "from-internal,103,1") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Goto (from-internal,103,1)
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [103@from-internal:1] GotoIf("Local/103@from-queue-00001a5d;2", "1?ext-local,103,1") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Goto (ext-local,103,1)
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [103@ext-local:1] Set("Local/103@from-queue-00001a5d;2", "__RINGTIMER=15") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [103@ext-local:2] Macro("Local/103@from-queue-00001a5d;2", "exten-vm,103,103,0,0,0") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-exten-vm:1] Macro("Local/103@from-queue-00001a5d;2", "user-callerid,") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:1] Set("Local/103@from-queue-00001a5d;2", "TOUCH_MONITOR=1427287452.14406") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:2] Set("Local/103@from-queue-00001a5d;2", "AMPUSER=mycallerid") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("Local/103@from-queue-00001a5d;2", "1?report") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Goto (macro-user-callerid,s,16)
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:16] GotoIf("Local/103@from-queue-00001a5d;2", "0?continue") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:17] ExecIf("Local/103@from-queue-00001a5d;2", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:18] Set("Local/103@from-queue-00001a5d;2", "__TTL=63") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:19] GotoIf("Local/103@from-queue-00001a5d;2", "1?continue") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Goto (macro-user-callerid,s,30)
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:30] Set("Local/103@from-queue-00001a5d;2", "CALLERID(number)=mycallerid") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:31] Set("Local/103@from-queue-00001a5d;2", "CALLERID(name)=my CNAM") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:32] Set("Local/103@from-queue-00001a5d;2", "CDR(cnum)=mycallerid") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:33] Set("Local/103@from-queue-00001a5d;2", "CDR(cnam)=my CNAM") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-user-callerid:34] Set("Local/103@from-queue-00001a5d;2", "CHANNEL(language)=en") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-exten-vm:2] Set("Local/103@from-queue-00001a5d;2", "RingGroupMethod=none") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-exten-vm:3] Set("Local/103@from-queue-00001a5d;2", "__EXTTOCALL=103") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-exten-vm:4] Set("Local/103@from-queue-00001a5d;2", "__PICKUPMARK=103") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-exten-vm:5] Set("Local/103@from-queue-00001a5d;2", "RT=15") in new stack
[2015-03-25 07:44:12] WARNING[28275][C-000019b3] chan_sip.c: This function can only be used on SIP channels.
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-exten-vm:6] ExecIf("Local/103@from-queue-00001a5d;2", "0?Macro(vm,103,DIRECTDIAL,)") in new stack
[2015-03-25 07:44:12] WARNING[28275][C-000019b3] chan_sip.c: This function can only be used on SIP channels.
[2015-03-25 07:44:12] WARNING[28275][C-000019b3] chan_sip.c: This function can only be used on SIP channels.
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-exten-vm:7] ExecIf("Local/103@from-queue-00001a5d;2", "0?MacroExit()") in new stack
[2015-03-25 07:44:12] WARNING[28275][C-000019b3] chan_sip.c: This function can only be used on SIP channels.
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@macro-exten-vm:8] Gosub("Local/103@from-queue-00001a5d;2", "sub-record-check,s,1(exten,103,dontcare)") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("Local/103@from-queue-00001a5d;2", "10?initialized") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Goto (sub-record-check,s,10)
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@sub-record-check:10] NoOp("Local/103@from-queue-00001a5d;2", "Recordings initialized") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("Local/103@from-queue-00001a5d;2", "0?Set(ARG3=dontcare)") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@sub-record-check:12] Set("Local/103@from-queue-00001a5d;2", "REC_POLICY_MODE_SAVE=") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("Local/103@from-queue-00001a5d;2", "0?Set(REC_STATUS=NO)") in new stack
[2015-03-25 07:44:12] VERBOSE[28275][C-000019b3] pbx.c: -- Executing [s@sub-record-check:14] GotoIf("Local/103@from-queue-00001a5d;2", "5?checkaction") in new stack