[2018-07-30 09:26:08] VERBOSE[59125][C-00001e08] pbx.c: Executing [recordcheck@sub-record-check:19] MixMonitor(“SIP/voipinno-000048fe”, “2018/07/30/q-9981-16142263243-20180730-092608-1532957168.466675.wav,abi(LOCAL_MIXMON_ID),”) in new stack
[2018-07-30 09:26:08] VERBOSE[59128][C-00001e08] app_mixmonitor.c: Begin MixMonitor Recording SIP/voipinno-000048fe
[2018-07-30 09:26:08] VERBOSE[59125][C-00001e08] pbx.c: Executing [recordcheck@sub-record-check:20] Set(“SIP/voipinno-000048fe”, “__MIXMON_ID=0x7f5a0d6b9ff0”) in new stack
[2018-07-30 09:26:08] VERBOSE[59125][C-00001e08] pbx.c: Executing [recordcheck@sub-record-check:21] Set(“SIP/voipinno-000048fe”, “__RECORD_ID=SIP/voipinno-000048fe”) in new stack
[2018-07-30 09:26:08] VERBOSE[59125][C-00001e08] pbx.c: Executing [recordcheck@sub-record-check:22] Set(“SIP/voipinno-000048fe”, “__REC_STATUS=RECORDING”) in new stack
[2018-07-30 09:26:08] VERBOSE[59125][C-00001e08] pbx.c: Executing [recordcheck@sub-record-check:23] Set(“SIP/voipinno-000048fe”, “CDR(recordingfile)=q-9981-16142263243-20180730-092608-1532957168.466675.wav”) in new stack
[2018-07-30 09:26:08] VERBOSE[59125][C-00001e08] pbx.c: Executing [recordcheck@sub-record-check:24] Return(“SIP/voipinno-000048fe”, “”) in new stack
[2018-07-30 09:26:08] VERBOSE[59125][C-00001e08] pbx.c: Executing [s@sub-record-check:20] Return(“SIP/voipinno-000048fe”, “”) in new stack
[2018-07-30 09:26:08] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:29] Set(“SIP/voipinno-000048fe”, “__CWIGNORE=TRUE”) in new stack
[2018-07-30 09:26:08] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:30] Set(“SIP/voipinno-000048fe”, “__CFIGNORE=TRUE”) in new stack
[2018-07-30 09:26:08] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:31] Set(“SIP/voipinno-000048fe”, “__FORWARD_CONTEXT=block-cf”) in new stack
[2018-07-30 09:26:08] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:32] ExecIf(“SIP/voipinno-000048fe”, “1?Playback(custom/Recorded_Call_Disclaimer, )”) in new stack
[2018-07-30 09:26:08] VERBOSE[59125][C-00001e08] file.c: <SIP/voipinno-000048fe> Playing ‘custom/Recorded_Call_Disclaimer.slin’ (language ‘en’)
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:33] QueueLog(“SIP/voipinno-000048fe”, “9981,1532957168.466675,NONE,DID,6149014100”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:34] Set(“SIP/voipinno-000048fe”, “QAANNOUNCE=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:35] Set(“SIP/voipinno-000048fe”, “VQ_AANNOUNCE=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:36] Set(“SIP/voipinno-000048fe”, “QMOH=DoNotDelete”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:37] Set(“SIP/voipinno-000048fe”, “VQ_MOH=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:38] ExecIf(“SIP/voipinno-000048fe”, “1?Set(__MOHCLASS=DoNotDelete)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:39] ExecIf(“SIP/voipinno-000048fe”, “1?Set(CHANNEL(musicclass)=DoNotDelete)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:40] Set(“SIP/voipinno-000048fe”, “QMAXWAIT=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:41] Set(“SIP/voipinno-000048fe”, “VQ_MAXWAIT=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:42] Set(“SIP/voipinno-000048fe”, “QUEUENUM=9981”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:43] Set(“SIP/voipinno-000048fe”, “QUEUEJOINTIME=1532957173”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:44] Set(“SIP/voipinno-000048fe”, “QMIN_PENALTY=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:45] Set(“SIP/voipinno-000048fe”, “VQ_MIN_PENALTY=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:46] ExecIf(“SIP/voipinno-000048fe”, “0?Set(QUEUE_MIN_PENALTY=)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:47] Set(“SIP/voipinno-000048fe”, “QMAX_PENALTY=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:48] Set(“SIP/voipinno-000048fe”, “VQ_MAX_PENALTY=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:49] ExecIf(“SIP/voipinno-000048fe”, “0?Set(QUEUE_MAX_PENALTY=)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:50] Set(“SIP/voipinno-000048fe”, “_CALLER_DEST=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:51] Set(“SIP/voipinno-000048fe”, “VQ_CDEST=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:52] Set(“SIP/voipinno-000048fe”, “_AGENT_DEST=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:53] Set(“SIP/voipinno-000048fe”, “VQ_ADEST=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] pbx.c: Executing [9981@ext-queues:54] Queue(“SIP/voipinno-000048fe”, “9981,t,1,”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] res_musiconhold.c: Started music on hold, class ‘DoNotDelete’, on channel ‘SIP/voipinno-000048fe’
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [3017@from-queue:1] Set(“Local/3017@from-queue-00007c53;2”, “QAGENT=3017”) in new stack
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] app_queue.c: Called Local/3017@from-queue/n
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] app_queue.c: – LazyMembers debugging - Numbusies: 0, Nummems: 2
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [3017@from-queue:2] Set(“Local/3017@from-queue-00007c53;2”, “__FROMQ=true”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [3017@from-queue:3] Goto(“Local/3017@from-queue-00007c53;2”, “9981,1”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx_builtins.c: Goto (from-queue,9981,1)
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [9981@from-queue:1] Goto(“Local/3017@from-queue-00007c53;2”, “from-queue-exten-internal,3017,1”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx_builtins.c: Goto (from-queue-exten-internal,3017,1)
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [3017@from-queue-exten-internal:1] GotoIf(“Local/3017@from-queue-00007c53;2”, “0?from-queue-exten-only-x,3017,1”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [3017@from-queue-exten-internal:2] Set(“Local/3017@from-queue-00007c53;2”, “RingGroupMethod=none”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [3017@from-queue-exten-internal:3] Set(“Local/3017@from-queue-00007c53;2”, “CHANNEL(language)=en”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [3017@from-queue-exten-internal:4] Set(“Local/3017@from-queue-00007c53;2”, “QDOPTS=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [3017@from-queue-exten-internal:5] Set(“Local/3017@from-queue-00007c53;2”, “CALLTYPE_OVERRIDE=external”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [3017@from-queue-exten-internal:6] ExecIf(“Local/3017@from-queue-00007c53;2”, “1?Set(FROMEXTEN=9981)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [3017@from-queue-exten-internal:7] Gosub(“Local/3017@from-queue-00007c53;2”, “sub-record-check,s,1(exten,3017,)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@sub-record-check:1] GotoIf(“Local/3017@from-queue-00007c53;2”, “4?initialized”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx_builtins.c: Goto (sub-record-check,s,10)
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@sub-record-check:10] NoOp(“Local/3017@from-queue-00007c53;2”, “Recordings initialized”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@sub-record-check:11] ExecIf(“Local/3017@from-queue-00007c53;2”, “1?Set(ARG3=dontcare)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@sub-record-check:12] Set(“Local/3017@from-queue-00007c53;2”, “REC_POLICY_MODE_SAVE=YES”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@sub-record-check:13] ExecIf(“Local/3017@from-queue-00007c53;2”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@sub-record-check:14] GotoIf(“Local/3017@from-queue-00007c53;2”, “5?checkaction”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@sub-record-check:17] GotoIf(“Local/3017@from-queue-00007c53;2”, “1?sub-record-check,exten,1”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [exten@sub-record-check:1] NoOp(“Local/3017@from-queue-00007c53;2”, “Exten Recording Check between 9981 and 3017”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [exten@sub-record-check:2] Set(“Local/3017@from-queue-00007c53;2”, “CALLTYPE=external”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [exten@sub-record-check:3] ExecIf(“Local/3017@from-queue-00007c53;2”, “8?Set(CALLTYPE=external)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [exten@sub-record-check:4] Set(“Local/3017@from-queue-00007c53;2”, “CALLEE=force”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [exten@sub-record-check:5] ExecIf(“Local/3017@from-queue-00007c53;2”, “0?Set(CALLEE=dontcare)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [exten@sub-record-check:6] GotoIf(“Local/3017@from-queue-00007c53;2”, “1?callee”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx_builtins.c: Goto (sub-record-check,exten,11)
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [exten@sub-record-check:11] Gosub(“Local/3017@from-queue-00007c53;2”, “recordcheck,1(force,external,3017)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“Local/3017@from-queue-00007c53;2”, “Starting recording check against force”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“Local/3017@from-queue-00007c53;2”, “force”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx_builtins.c: Goto (sub-record-check,recordcheck,5)
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [recordcheck@sub-record-check:5] Set(“Local/3017@from-queue-00007c53;2”, “__REC_POLICY_MODE=FORCE”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [recordcheck@sub-record-check:6] GotoIf(“Local/3017@from-queue-00007c53;2”, “0?startrec”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [recordcheck@sub-record-check:7] Return(“Local/3017@from-queue-00007c53;2”, “”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [exten@sub-record-check:12] Return(“Local/3017@from-queue-00007c53;2”, “”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [3017@from-queue-exten-internal:8] Macro(“Local/3017@from-queue-00007c53;2”, “dial-one,TtrM(auto-blkvm),3017”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:1] Set(“Local/3017@from-queue-00007c53;2”, “DEXTEN=3017”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:2] Set(“Local/3017@from-queue-00007c53;2”, “__CRM_SOURCE=16142263243”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:3] ExecIf(“Local/3017@from-queue-00007c53;2”, “1?Set(__EXTTOCALL=3017)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:4] Set(“Local/3017@from-queue-00007c53;2”, “DIALSTATUS_CW=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:5] GosubIf(“Local/3017@from-queue-00007c53;2”, “0?screen,1()”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:6] GosubIf(“Local/3017@from-queue-00007c53;2”, “0?cf,1()”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:7] GotoIf(“Local/3017@from-queue-00007c53;2”, “1?skip1”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx_builtins.c: Goto (macro-dial-one,s,10)
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:10] GotoIf(“Local/3017@from-queue-00007c53;2”, “0?nodial”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:11] GotoIf(“Local/3017@from-queue-00007c53;2”, “0?continue”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:12] ExecIf(“Local/3017@from-queue-00007c53;2”, “0?Set(D_OPTIONS=g)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:13] Set(“Local/3017@from-queue-00007c53;2”, “EXTHASCW=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:14] GotoIf(“Local/3017@from-queue-00007c53;2”, “1?next1:cwinusebusy”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx_builtins.c: Goto (macro-dial-one,s,15)
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:15] GotoIf(“Local/3017@from-queue-00007c53;2”, “0?docfu:skip3”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx_builtins.c: Goto (macro-dial-one,s,19)
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:19] GotoIf(“Local/3017@from-queue-00007c53;2”, “1?next2:continue”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx_builtins.c: Goto (macro-dial-one,s,20)
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:20] GotoIf(“Local/3017@from-queue-00007c53;2”, “1?continue”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx_builtins.c: Goto (macro-dial-one,s,28)
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:28] GotoIf(“Local/3017@from-queue-00007c53;2”, “0?nodial”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:29] GosubIf(“Local/3017@from-queue-00007c53;2”, “1?dstring,1():dlocal,1()”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [dstring@macro-dial-one:1] Set(“Local/3017@from-queue-00007c53;2”, “DSTRING=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [dstring@macro-dial-one:2] Set(“Local/3017@from-queue-00007c53;2”, “DEVICES=3017”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf(“Local/3017@from-queue-00007c53;2”, “0?Return()”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf(“Local/3017@from-queue-00007c53;2”, “0?Set(DEVICES=017)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [dstring@macro-dial-one:5] Set(“Local/3017@from-queue-00007c53;2”, “LOOPCNT=1”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [dstring@macro-dial-one:6] Set(“Local/3017@from-queue-00007c53;2”, “ITER=1”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [dstring@macro-dial-one:7] Set(“Local/3017@from-queue-00007c53;2”, “THISDIAL=SIP/3017”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf(“Local/3017@from-queue-00007c53;2”, “1?zap2dahdi,1()”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf(“Local/3017@from-queue-00007c53;2”, “0?Return()”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set(“Local/3017@from-queue-00007c53;2”, “NEWDIAL=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set(“Local/3017@from-queue-00007c53;2”, “LOOPCNT2=1”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set(“Local/3017@from-queue-00007c53;2”, “ITER2=1”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set(“Local/3017@from-queue-00007c53;2”, “THISPART2=SIP/3017”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf(“Local/3017@from-queue-00007c53;2”, “0?Set(THISPART2=DAHDI/3017)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set(“Local/3017@from-queue-00007c53;2”, “NEWDIAL=SIP/3017&”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set(“Local/3017@from-queue-00007c53;2”, “ITER2=2”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf(“Local/3017@from-queue-00007c53;2”, “0?begin2”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set(“Local/3017@from-queue-00007c53;2”, “THISDIAL=SIP/3017”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return(“Local/3017@from-queue-00007c53;2”, “”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf(“Local/3017@from-queue-00007c53;2”, “1?docheck”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx_builtins.c: Goto (macro-dial-one,dstring,14)
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf(“Local/3017@from-queue-00007c53;2”, “0?skipset”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [dstring@macro-dial-one:15] Set(“Local/3017@from-queue-00007c53;2”, “DSTRING=SIP/3017&”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [dstring@macro-dial-one:16] Set(“Local/3017@from-queue-00007c53;2”, “ITER=2”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf(“Local/3017@from-queue-00007c53;2”, “0?begin”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf(“Local/3017@from-queue-00007c53;2”, “0?Return()”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [dstring@macro-dial-one:19] Set(“Local/3017@from-queue-00007c53;2”, “DSTRING=SIP/3017”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [dstring@macro-dial-one:20] Return(“Local/3017@from-queue-00007c53;2”, “”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:30] GotoIf(“Local/3017@from-queue-00007c53;2”, “0?nodial”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:31] GotoIf(“Local/3017@from-queue-00007c53;2”, “0?skiptrace”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:32] GosubIf(“Local/3017@from-queue-00007c53;2”, “1?ctset,1():ctclear,1()”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [ctset@macro-dial-one:1] Set(“Local/3017@from-queue-00007c53;2”, “DB(CALLTRACE/3017)=16142263243”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [ctset@macro-dial-one:2] Return(“Local/3017@from-queue-00007c53;2”, “”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:33] Set(“Local/3017@from-queue-00007c53;2”, “D_OPTIONS=TtrM(auto-blkvm)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:34] NoOp(“Local/3017@from-queue-00007c53;2”, "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:35] ExecIf(“Local/3017@from-queue-00007c53;2”, “0?Set(ALERT_INFO=)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:36] ExecIf(“Local/3017@from-queue-00007c53;2”, “0?Set(ALERT_INFO=)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:37] ExecIf(“Local/3017@from-queue-00007c53;2”, “0?Set(ALERT_INFO=)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:38] ExecIf(“Local/3017@from-queue-00007c53;2”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:39] ExecIf(“Local/3017@from-queue-00007c53;2”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:40] GosubIf(“Local/3017@from-queue-00007c53;2”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:41] ExecIf(“Local/3017@from-queue-00007c53;2”, “1?Set(CHANNEL(musicclass)=DoNotDelete)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:42] GosubIf(“Local/3017@from-queue-00007c53;2”, “1?qwait,1()”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [qwait@macro-dial-one:1] ExecIf(“Local/3017@from-queue-00007c53;2”, “1?Set(__SAVEDCIDNAME=16142263243)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [qwait@macro-dial-one:2] Set(“Local/3017@from-queue-00007c53;2”, “ELAPSED=0”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [qwait@macro-dial-one:3] Set(“Local/3017@from-queue-00007c53;2”, “CALLERID(name)=M0:16142263243”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [qwait@macro-dial-one:4] Return(“Local/3017@from-queue-00007c53;2”, “”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:43] Set(“Local/3017@from-queue-00007c53;2”, “__CWIGNORE=TRUE”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:44] Set(“Local/3017@from-queue-00007c53;2”, “__KEEPCID=TRUE”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:45] GotoIf(“Local/3017@from-queue-00007c53;2”, “0?usegoto,1”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:46] GotoIf(“Local/3017@from-queue-00007c53;2”, “1?godial”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx_builtins.c: Goto (macro-dial-one,s,51)
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:51] Macro(“Local/3017@from-queue-00007c53;2”, “dialout-one-predial-hook,”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit(“Local/3017@from-queue-00007c53;2”, “”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:52] ExecIf(“Local/3017@from-queue-00007c53;2”, “1?Set(D_OPTIONS=trM(auto-blkvm)I)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:53] NoOp(“Local/3017@from-queue-00007c53;2”, “”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-dial-one:54] Dial(“Local/3017@from-queue-00007c53;2”, “SIP/3017,trM(auto-blkvm)Ib(func-apply-sipheaders^s^1)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] netsock2.c: Using SIP RTP TOS bits 184
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] netsock2.c: Using SIP RTP CoS mark 5
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] app_stack.c: SIP/3017-000048ff Internal Gosub(func-apply-sipheaders,s,1) start
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“SIP/3017-000048ff”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“SIP/3017-000048ff”, “Applying SIP Headers to channel”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“SIP/3017-000048ff”, “SIPHEADERKEYS=”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@func-apply-sipheaders:4] While(“SIP/3017-000048ff”, “0”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] app_while.c: Jumping to priority 7
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@func-apply-sipheaders:8] Return(“SIP/3017-000048ff”, “”) in new stack
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] app_stack.c: Spawn extension (from-internal, 3017, 1) exited non-zero on ‘SIP/3017-000048ff’
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] app_stack.c: SIP/3017-000048ff Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] app_dial.c: Called SIP/3017
[2018-07-30 09:26:13] VERBOSE[59158][C-00001e08] app_dial.c: Connected line update to Local/3017@from-queue-00007c53;2 prevented.
[2018-07-30 09:26:13] VERBOSE[59125][C-00001e08] app_queue.c: Local/3017@from-queue-00007c53;1 is ringing
[2018-07-30 09:26:14] VERBOSE[59158][C-00001e08] app_dial.c: SIP/3017-000048ff is ringing
[2018-07-30 09:26:14] VERBOSE[59125][C-00001e08] app_queue.c: Local/3017@from-queue-00007c53;1 is ringing
[2018-07-30 09:26:33] VERBOSE[59125][C-00001e08] app_queue.c: – Excluding Aheliotech - Test 1 from the members, because LazyMembers is set.
[2018-07-30 09:26:33] VERBOSE[59125][C-00001e08] app_queue.c: Nobody picked up in 20000 ms
[2018-07-30 09:26:33] VERBOSE[59125][C-00001e08] app_queue.c: – LazyMembers debugging - Numbusies: 1, Nummems: 2
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] app_stack.c: SIP/3017-000048ff Internal Gosub(crm-hangup,s,1) start
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@crm-hangup:1] NoOp(“SIP/3017-000048ff”, “Sending Hangup to CRM”) in new stack
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@crm-hangup:2] NoOp(“SIP/3017-000048ff”, “HANGUP CAUSE: 16”) in new stack
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@crm-hangup:3] ExecIf(“SIP/3017-000048ff”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@crm-hangup:4] NoOp(“SIP/3017-000048ff”, “MASTER CHANNEL: 1532957173.466685 = 1532957168.466675”) in new stack
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@crm-hangup:5] GotoIf(“SIP/3017-000048ff”, “1?return”) in new stack
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] pbx_builtins.c: Goto (crm-hangup,s,8)
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@crm-hangup:8] Return(“SIP/3017-000048ff”, “”) in new stack
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] app_stack.c: Spawn extension (from-internal, 3017, 1) exited non-zero on ‘SIP/3017-000048ff’
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] app_stack.c: SIP/3017-000048ff Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] app_macro.c: Spawn extension (macro-dial-one, s, 54) exited non-zero on ‘Local/3017@from-queue-00007c53;2’ in macro ‘dial-one’
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] pbx.c: Spawn extension (from-queue-exten-internal, 3017, 8) exited non-zero on ‘Local/3017@from-queue-00007c53;2’
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] pbx.c: Executing [h@from-queue-exten-internal:1] Macro(“Local/3017@from-queue-00007c53;2”, “hangupcall,”) in new stack
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“Local/3017@from-queue-00007c53;2”, “1?theend”) in new stack
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“Local/3017@from-queue-00007c53;2”, “0?Set(CDR(recordingfile)=)”) in new stack
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] pbx.c: Executing [s@macro-hangupcall:4] Hangup(“Local/3017@from-queue-00007c53;2”, “”) in new stack
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘Local/3017@from-queue-00007c53;2’ in macro ‘hangupcall’
[2018-07-30 09:26:33] VERBOSE[59158][C-00001e08] pbx.c: Spawn extension (from-queue-exten-internal, h, 1) exited non-zero on ‘Local/3017@from-queue-00007c53;2’
[2018-07-30 09:33:47] VERBOSE[59125][C-00001e08] res_musiconhold.c: Stopped music on hold on SIP/voipinno-000048fe
[2018-07-30 09:33:47] VERBOSE[59125][C-00001e08] app_queue.c: – Abandoned! resetting all the member lastnoanswer values
[2018-07-30 09:33:47] VERBOSE[59125][C-00001e08] pbx.c: Spawn extension (ext-queues, 9981, 54) exited non-zero on ‘SIP/voipinno-000048fe’
[2018-07-30 09:33:47] VERBOSE[59125][C-00001e08] pbx.c: Executing [h@ext-queues:1] Macro(“SIP/voipinno-000048fe”, “hangupcall,”) in new stack
[2018-07-30 09:33:47] VERBOSE[59125][C-00001e08] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/voipinno-000048fe”, “1?theend”) in new stack
[2018-07-30 09:33:47] VERBOSE[59125][C-00001e08] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-07-30 09:33:47] VERBOSE[59125][C-00001e08] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/voipinno-000048fe”, “0?Set(CDR(recordingfile)=)”) in new stack
[2018-07-30 09:33:47] VERBOSE[59125][C-00001e08] pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/voipinno-000048fe”, “”) in new stack
[2018-07-30 09:33:47] VERBOSE[59125][C-00001e08] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/voipinno-000048fe’ in macro ‘hangupcall’