Incomming Call Connects to External Phone Number When Internal Extension Does Not Answer

Here’s what happens when someone calls our reception number normally:

Event Type CID Name CID Num Exten Context Channel
CHAN_START Name Omitted 401640xxxx Incoming # from-trunk SIP/VOIPMS-00000a45
ANSWER Name Omitted 401640xxxx s ivr-1 SIP/VOIPMS-00000a45
CHAN_START Front Desk 10 s from-internal PJSIP/10-00000b6b
ANSWER Front Desk 10 10 from-internal PJSIP/10-00000b6b
BRIDGE_ENTER Front Desk 10 from-internal PJSIP/10-00000b6b
BRIDGE_ENTER Name Omitted 401640xxxx s macro-dial-one SIP/VOIPMS-00000a45
BRIDGE_EXIT Name Omitted 401640xxxx s macro-dial-one SIP/VOIPMS-00000a45
BRIDGE_EXIT Front Desk 10 from-internal PJSIP/10-00000b6b
HANGUP Front Desk 10 from-internal PJSIP/10-00000b6b
CHAN_END Front Desk 10 from-internal PJSIP/10-00000b6b
HANGUP Name Omitted 401640xxxx h ext-local SIP/VOIPMS-00000a45
CHAN_END Name Omitted 401640xxxx h ext-local SIP/VOIPMS-00000a45
LINKEDID_END Name Omitted 401640xxxx h ext-local SIP/VOIPMS-00000a45

Over the weekend this started occuring sometimes:

Event Type CID Name CID Num Exten Context Channel
CHAN_START 401640xxxx 401640xxxx Incoming # from-trunk SIP/VOIPMS-00000a46
ANSWER Name Omitted 401640xxxx s ivr-1 SIP/VOIPMS-00000a46
CHAN_START Front Desk 10 s from-internal PJSIP/10-00000b6c
HANGUP Front Desk 10 10 from-internal PJSIP/10-00000b6c
CHAN_END Front Desk 10 10 from-internal PJSIP/10-00000b6c
CHAN_START 401253xxxx from-internal Local/[email protected];1
CHAN_START 401253xxxx from-internal Local/[email protected];2
CHAN_START s from-trunk SIP/VOIPMS-00000a47
ANSWER 401253xxxx 401253xxxx from-trunk SIP/VOIPMS-00000a47
ANSWER 401640xxxx s macro-dialout-trunk Local/[email protected];2
ANSWER 10 10 from-internal Local/[email protected];1
BRIDGE_ENTER 10 from-internal Local/[email protected];1
BRIDGE_ENTER 401253xxxx from-trunk SIP/VOIPMS-00000a47
BRIDGE_ENTER Name Omitted 401640xxxx docfu macro-exten-vm SIP/VOIPMS-00000a46
BRIDGE_ENTER 401640xxxx s macro-dialout-trunk Local/[email protected];2
BRIDGE_EXIT Name Omitted 401640xxxx docfu macro-exten-vm SIP/VOIPMS-00000a46
BRIDGE_EXIT 10 from-internal Local/[email protected];1
HANGUP 10 from-internal Local/[email protected];1
CHAN_END 10 from-internal Local/[email protected];1
BRIDGE_EXIT 401640xxxx s macro-dialout-trunk Local/[email protected];2
BRIDGE_EXIT 401253xxxx from-trunk SIP/VOIPMS-00000a47
HANGUP 401253xxxx from-trunk SIP/VOIPMS-00000a47
CHAN_END 401253xxxx from-trunk SIP/VOIPMS-00000a47
HANGUP 401640xxxx h from-internal Local/[email protected];2
CHAN_END 401640xxxx h from-internal Local/[email protected];2
HANGUP Name Omitted 401640xxxx h ext-local SIP/VOIPMS-00000a46
CHAN_END Name Omitted 401640xxxx h ext-local SIP/VOIPMS-00000a46
LINKEDID_END Name Omitted 401640xxxx h ext-local SIP/VOIPMS-00000a46

The Front Desk extenstion does not always answer the call and join the bridge, but the strangest part is that a call is then intiated out to an external number. I’ve looked at the Extension, Find Me/Follow Me, the phone, but I cannot seem to figure out:

  1. Why an outbound call is being initiated
  2. Where the external number that’s being called has been configured

We should be able to get some context hints from the /var/log/asterisk/full log. If you could look through there and pull just this call from the log, we should be able to point out where the disconnect is.

-07-10 12:41:51] VERBOSE[13766][C-00000c17] netsock2.c: Using SIP VIDEO TOS bits 136
[2018-07-10 12:41:51] VERBOSE[13766][C-00000c17] netsock2.c: Using SIP VIDEO CoS mark 6
[2018-07-10 12:41:51] VERBOSE[13766][C-00000c17] netsock2.c: Using SIP RTP TOS bits 184
[2018-07-10 12:41:51] VERBOSE[13766][C-00000c17] netsock2.c: Using SIP RTP CoS mark 5
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] Set("SIP/VOIPMS-00000a7b", "__DIRECTION=INBOUND") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Gosub("SIP/VOIPMS-00000a7b", "sub-record-check,s,1(in,4012539221,dontcare)") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] GotoIf("SIP/VOIPMS-00000a7b", "0?initialized") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Set("SIP/VOIPMS-00000a7b", "__REC_STATUS=INITIALIZED") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] Set("SIP/VOIPMS-00000a7b", "NOW=1531240911") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:4] Set("SIP/VOIPMS-00000a7b", "__DAY=10") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:5] Set("SIP/VOIPMS-00000a7b", "__MONTH=07") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:6] Set("SIP/VOIPMS-00000a7b", "__YEAR=2018") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:7] Set("SIP/VOIPMS-00000a7b", "__TIMESTR=20180710-124151") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:8] Set("SIP/VOIPMS-00000a7b", "__FROMEXTEN=unknown") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:9] Set("SIP/VOIPMS-00000a7b", "__MON_FMT=wav") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:10] NoOp("SIP/VOIPMS-00000a7b", "Recordings initialized") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:11] ExecIf("SIP/VOIPMS-00000a7b", "0?Set(ARG3=dontcare)") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:12] Set("SIP/VOIPMS-00000a7b", "REC_POLICY_MODE_SAVE=") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:13] ExecIf("SIP/VOIPMS-00000a7b", "0?Set(REC_STATUS=NO)") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:14] GotoIf("SIP/VOIPMS-00000a7b", "2?checkaction") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:17] GotoIf("SIP/VOIPMS-00000a7b", "1?sub-record-check,in,1") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (sub-record-check,in,1)
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] NoOp("SIP/VOIPMS-00000a7b", "Inbound Recording Check to 4012539221") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Set("SIP/VOIPMS-00000a7b", "FROMEXTEN=unknown") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] ExecIf("SIP/VOIPMS-00000a7b", "10?Set(FROMEXTEN=4013057000)") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:4] Gosub("SIP/VOIPMS-00000a7b", "recordcheck,1(dontcare,in,4012539221)") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] NoOp("SIP/VOIPMS-00000a7b", "Starting recording check against dontcare") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Goto("SIP/VOIPMS-00000a7b", "dontcare") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] Return("SIP/VOIPMS-00000a7b", "") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:5] Return("SIP/VOIPMS-00000a7b", "") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] Gosub("SIP/VOIPMS-00000a7b", "app-blacklist-check,s,1()") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] GotoIf("SIP/VOIPMS-00000a7b", "0?check-blocked") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] GotoIf("SIP/VOIPMS-00000a7b", "0?check-blocked") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] GotoIf("SIP/VOIPMS-00000a7b", "0?check-blocked:check") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (app-blacklist-check,s,5)
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:5] GotoIf("SIP/VOIPMS-00000a7b", "0?blacklisted") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:6] Set("SIP/VOIPMS-00000a7b", "CALLED_BLACKLIST=1") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:7] Return("SIP/VOIPMS-00000a7b", "") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:4] Set("SIP/VOIPMS-00000a7b", "__FROM_DID=4012539221") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:5] Set("SIP/VOIPMS-00000a7b", "CDR(did)=4012539221") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:6] ExecIf("SIP/VOIPMS-00000a7b", "0 ?Set(CALLERID(name)=4013057000)") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:7] Set("SIP/VOIPMS-00000a7b", "__MOHCLASS=") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:8] Set("SIP/VOIPMS-00000a7b", "__REVERSAL_REJECT=FALSE") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:9] GotoIf("SIP/VOIPMS-00000a7b", "1?post-reverse-charge") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (from-trunk,4012539221,11)
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:11] NoOp("SIP/VOIPMS-00000a7b", "") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:12] Set("SIP/VOIPMS-00000a7b", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:13] Set("SIP/VOIPMS-00000a7b", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:14] Set("SIP/VOIPMS-00000a7b", "CALLERID(name-pres)=allowed_not_screened") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:15] Set("SIP/VOIPMS-00000a7b", "CALLERID(num-pres)=allowed_not_screened") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:16] Gosub("SIP/VOIPMS-00000a7b", "cidlookup,cidlookup_1,1()") in new stack
[2018-07-10 12:41:51] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] Set("SIP/VOIPMS-00000a7b", "CURLOPT(httptimeout)=7") in new stack
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Set("SIP/VOIPMS-00000a7b", "CALLERID(name)=HOME CARE NE") in new stack
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] Set("SIP/VOIPMS-00000a7b", "DB(cidname/4013057000)=HOME CARE NE") in new stack
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:4] Return("SIP/VOIPMS-00000a7b", "") in new stack
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:17] Set("SIP/VOIPMS-00000a7b", "CIDSFSCHEME=YmFzZV9EZWZhdWx0") in new stack
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:18] AGI("SIP/VOIPMS-00000a7b", "/var/www/html/admin/modules/superfecta/agi/superfecta.agi") in new stack
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] res_agi.c: Launched AGI Script /var/www/html/admin/modules/superfecta/agi/superfecta.agi
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] res_agi.c: /var/www/html/admin/modules/superfecta/agi/superfecta.agi: Executing Scheme..
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] res_agi.c: /var/www/html/admin/modules/superfecta/agi/superfecta.agi: Scheme Asked is: Default
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] res_agi.c: /var/www/html/admin/modules/superfecta/agi/superfecta.agi: The DID is: 4012539221
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] res_agi.c: /var/www/html/admin/modules/superfecta/agi/superfecta.agi: The CNUM is: 4013057000
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] res_agi.c: /var/www/html/admin/modules/superfecta/agi/superfecta.agi: The CNAME is: HOME CARE NE
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] res_agi.c: /var/www/html/admin/modules/superfecta/agi/superfecta.agi:
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] res_agi.c: /var/www/html/admin/modules/superfecta/agi/superfecta.agi: Starting scheme Default
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] res_agi.c: /var/www/html/admin/modules/superfecta/agi/superfecta.agi: Converting result to UTF-8
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] res_agi.c: /var/www/html/admin/modules/superfecta/agi/superfecta.agi: Setting caller id to: HOME CARE NE
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] res_agi.c: /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Determined to be: 'HOME CARE NE'
[2018-07-10 12:41:52] VERBOSE[18558][C-00000c17] res_agi.c: /var/www/html/admin/modules/superfecta/agi/superfecta.agi: Attempting to set lookupcid
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] res_agi.c: <SIP/VOIPMS-00000a7b>AGI Script /var/www/html/admin/modules/superfecta/agi/superfecta.agi completed, returning 0
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:19] Set("SIP/VOIPMS-00000a7b", "CALLERID(name)=HOME CARE NE") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:20] NoOp("SIP/VOIPMS-00000a7b", "CallerID Entry Point") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:21] Set("SIP/VOIPMS-00000a7b", "__CRM_DIRECTION=INBOUND") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:22] Set("SIP/VOIPMS-00000a7b", "__CRM_SOURCE=4013057000") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:23] Set("SIP/VOIPMS-00000a7b", "__CRM_LINKEDID=1531240911.6601") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:24] ExecIf("SIP/VOIPMS-00000a7b", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:25] Goto("SIP/VOIPMS-00000a7b", "timeconditions,1,1") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (timeconditions,1,1)
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] Set("SIP/VOIPMS-00000a7b", "DB(TC/1/INUSESTATE)=INUSE") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Set("SIP/VOIPMS-00000a7b", "DB(TC/1/NOT_INUSESTATE)=NOT_INUSE") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] NoOp("SIP/VOIPMS-00000a7b", "TIMENOW: 12:41,Tue,10,Jul,America/New_York") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:4] NoOp("SIP/VOIPMS-00000a7b", "TIMEMATCHED: TRUE") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:5] GotoIfTime("SIP/VOIPMS-00000a7b", "07:30-15:30,mon-thu,*,*,America/New_York?truestate") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (timeconditions,1,17)
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:17] GotoIf("SIP/VOIPMS-00000a7b", "0?falsegoto") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:18] ExecIf("SIP/VOIPMS-00000a7b", "0?Set(DB(TC/1)=)") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:19] Set("SIP/VOIPMS-00000a7b", "DEVICE_STATE(Custom:TC1)=NOT_INUSE") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:20] ExecIf("SIP/VOIPMS-00000a7b", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:21] GotoIf("SIP/VOIPMS-00000a7b", "1?ivr-1,s,1") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (ivr-1,s,1)
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] Set("SIP/VOIPMS-00000a7b", "_IVR_CONTEXT_ivr-1=") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Set("SIP/VOIPMS-00000a7b", "_IVR_CONTEXT=ivr-1") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] Set("SIP/VOIPMS-00000a7b", "__IVR_RETVM=") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:4] GotoIf("SIP/VOIPMS-00000a7b", "0?skip") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:5] Answer("SIP/VOIPMS-00000a7b", "") in new stack
[2018-07-10 12:41:53] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:6] Wait("SIP/VOIPMS-00000a7b", "1") in new stack
[2018-07-10 12:41:54] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:7] Set("SIP/VOIPMS-00000a7b", "IVR_MSG=custom/Main") in new stack
[2018-07-10 12:41:54] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:8] Set("SIP/VOIPMS-00000a7b", "TIMEOUT(digit)=3") in new stack
[2018-07-10 12:41:54] VERBOSE[18558][C-00000c17] func_timeout.c: Digit timeout set to 3.000
[2018-07-10 12:41:54] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:9] ExecIf("SIP/VOIPMS-00000a7b", "1?Background(custom/Main)") in new stack
[2018-07-10 12:41:54] VERBOSE[18558][C-00000c17] file.c: <SIP/VOIPMS-00000a7b> Playing 'custom/Main.slin' (language 'en')
[2018-07-10 12:42:21] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:10] WaitExten("SIP/VOIPMS-00000a7b", "6,") in new stack
[2018-07-10 12:42:27] VERBOSE[18558][C-00000c17] pbx_builtins.c: Timeout on SIP/VOIPMS-00000a7b, going to 't'
[2018-07-10 12:42:27] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] Playback("SIP/VOIPMS-00000a7b", "no-valid-responce-transfering") in new stack
[2018-07-10 12:42:27] VERBOSE[18558][C-00000c17] file.c: <SIP/VOIPMS-00000a7b> Playing 'no-valid-responce-transfering.slin' (language 'en')
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Goto("SIP/VOIPMS-00000a7b", "from-did-direct,10,1") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (from-did-direct,10,1)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] GotoIf("SIP/VOIPMS-00000a7b", "1?ext-local,10,1:followme-check,10,1") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (ext-local,10,1)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] Set("SIP/VOIPMS-00000a7b", "__RINGTIMER=30") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Macro("SIP/VOIPMS-00000a7b", "exten-vm,10,10,0,0,0") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] Macro("SIP/VOIPMS-00000a7b", "user-callerid,") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] Set("SIP/VOIPMS-00000a7b", "TOUCH_MONITOR=1531240911.6601") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Set("SIP/VOIPMS-00000a7b", "AMPUSER=4013057000") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] GotoIf("SIP/VOIPMS-00000a7b", "0?report") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:4] ExecIf("SIP/VOIPMS-00000a7b", "1?Set(REALCALLERIDNUM=4013057000)") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:5] Set("SIP/VOIPMS-00000a7b", "AMPUSER=") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:6] GotoIf("SIP/VOIPMS-00000a7b", "0?limit") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:7] Set("SIP/VOIPMS-00000a7b", "AMPUSERCIDNAME=") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:8] ExecIf("SIP/VOIPMS-00000a7b", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:9] GotoIf("SIP/VOIPMS-00000a7b", "1?report") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:16] NoOp("SIP/VOIPMS-00000a7b", "Macro Depth is 2") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:17] GotoIf("SIP/VOIPMS-00000a7b", "1?report2:macroerror") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:18] GotoIf("SIP/VOIPMS-00000a7b", "0?continue") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:19] ExecIf("SIP/VOIPMS-00000a7b", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:20] Set("SIP/VOIPMS-00000a7b", "__TTL=64") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:21] GotoIf("SIP/VOIPMS-00000a7b", "1?continue") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:37] Set("SIP/VOIPMS-00000a7b", "CALLERID(number)=4013057000") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:38] Set("SIP/VOIPMS-00000a7b", "CALLERID(name)=HOME CARE NE") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:39] GotoIf("SIP/VOIPMS-00000a7b", "0?cnum") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:40] Set("SIP/VOIPMS-00000a7b", "CDR(cnam)=HOME CARE NE") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:41] Set("SIP/VOIPMS-00000a7b", "CDR(cnum)=4013057000") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:42] Set("SIP/VOIPMS-00000a7b", "CHANNEL(language)=en") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Set("SIP/VOIPMS-00000a7b", "RingGroupMethod=none") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] Set("SIP/VOIPMS-00000a7b", "__EXTTOCALL=10") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:4] Set("SIP/VOIPMS-00000a7b", "__PICKUPMARK=10") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:5] Set("SIP/VOIPMS-00000a7b", "RT=30") in new stack
[2018-07-10 12:42:32] ERROR[18558][C-00000c17] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:6] ExecIf("SIP/VOIPMS-00000a7b", "0?Macro(vm,10,DIRECTDIAL,)") in new stack
[2018-07-10 12:42:32] ERROR[18558][C-00000c17] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2018-07-10 12:42:32] ERROR[18558][C-00000c17] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:7] ExecIf("SIP/VOIPMS-00000a7b", "0?MacroExit()") in new stack
[2018-07-10 12:42:32] ERROR[18558][C-00000c17] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2018-07-10 12:42:32] ERROR[18558][C-00000c17] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:8] ExecIf("SIP/VOIPMS-00000a7b", "0?Gosub(ext-intercom,*8010,1())") in new stack
[2018-07-10 12:42:32] ERROR[18558][C-00000c17] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2018-07-10 12:42:32] ERROR[18558][C-00000c17] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:9] ExecIf("SIP/VOIPMS-00000a7b", "0?MacroExit()") in new stack
[2018-07-10 12:42:32] ERROR[18558][C-00000c17] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2018-07-10 12:42:32] ERROR[18558][C-00000c17] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:10] ExecIf("SIP/VOIPMS-00000a7b", "0?ChanSpy(PJSIP/10,q)") in new stack
[2018-07-10 12:42:32] ERROR[18558][C-00000c17] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2018-07-10 12:42:32] ERROR[18558][C-00000c17] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:11] ExecIf("SIP/VOIPMS-00000a7b", "0?MacroExit()") in new stack
[2018-07-10 12:42:32] ERROR[18558][C-00000c17] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:12] Gosub("SIP/VOIPMS-00000a7b", "sub-record-check,s,1(exten,10,dontcare)") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] GotoIf("SIP/VOIPMS-00000a7b", "10?initialized") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (sub-record-check,s,10)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:10] NoOp("SIP/VOIPMS-00000a7b", "Recordings initialized") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:11] ExecIf("SIP/VOIPMS-00000a7b", "0?Set(ARG3=dontcare)") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:12] Set("SIP/VOIPMS-00000a7b", "REC_POLICY_MODE_SAVE=") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:13] ExecIf("SIP/VOIPMS-00000a7b", "0?Set(REC_STATUS=NO)") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:14] GotoIf("SIP/VOIPMS-00000a7b", "5?checkaction") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:17] GotoIf("SIP/VOIPMS-00000a7b", "1?sub-record-check,exten,1") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] NoOp("SIP/VOIPMS-00000a7b", "Exten Recording Check between 4013057000 and 10") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Set("SIP/VOIPMS-00000a7b", "CALLTYPE=external") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] ExecIf("SIP/VOIPMS-00000a7b", "0?Set(CALLTYPE=)") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:4] Set("SIP/VOIPMS-00000a7b", "CALLEE=dontcare") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:5] ExecIf("SIP/VOIPMS-00000a7b", "0?Set(CALLEE=dontcare)") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:6] GotoIf("SIP/VOIPMS-00000a7b", "1?callee") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (sub-record-check,exten,11)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:11] Gosub("SIP/VOIPMS-00000a7b", "recordcheck,1(dontcare,external,10)") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] NoOp("SIP/VOIPMS-00000a7b", "Starting recording check against dontcare") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Goto("SIP/VOIPMS-00000a7b", "dontcare") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] Return("SIP/VOIPMS-00000a7b", "") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:12] Return("SIP/VOIPMS-00000a7b", "") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:13] GotoIf("SIP/VOIPMS-00000a7b", "1?macrodial") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (macro-exten-vm,s,19)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:19] GosubIf("SIP/VOIPMS-00000a7b", "0?clrheader,1()") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:20] Macro("SIP/VOIPMS-00000a7b", "dial-one,30,Ttr,10") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] Set("SIP/VOIPMS-00000a7b", "DEXTEN=10") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Set("SIP/VOIPMS-00000a7b", "__CRM_SOURCE=4013057000") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] ExecIf("SIP/VOIPMS-00000a7b", "0?Set(__EXTTOCALL=10)") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:4] Set("SIP/VOIPMS-00000a7b", "DIALSTATUS_CW=") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:5] GosubIf("SIP/VOIPMS-00000a7b", "0?screen,1()") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:6] GosubIf("SIP/VOIPMS-00000a7b", "0?cf,1()") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:7] GotoIf("SIP/VOIPMS-00000a7b", "1?skip1") in new stack

Offhand, it looks to me like the phone at extension 10 is set to call forward the calls.

(continued)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (macro-dial-one,s,10)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:10] GotoIf(“SIP/VOIPMS-00000a7b”, “0?nodial”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:11] GotoIf(“SIP/VOIPMS-00000a7b”, “0?continue”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:12] ExecIf(“SIP/VOIPMS-00000a7b”, “0?Set(D_OPTIONS=g)”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:13] Set(“SIP/VOIPMS-00000a7b”, “EXTHASCW=ENABLED”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:14] GotoIf(“SIP/VOIPMS-00000a7b”, “1?next1:cwinusebusy”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (macro-dial-one,s,15)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:15] GotoIf(“SIP/VOIPMS-00000a7b”, “0?docfu:skip3”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (macro-dial-one,s,19)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:19] GotoIf(“SIP/VOIPMS-00000a7b”, “0?next2:continue”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (macro-dial-one,s,28)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:28] GotoIf(“SIP/VOIPMS-00000a7b”, “0?nodial”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:29] GosubIf(“SIP/VOIPMS-00000a7b”, “1?dstring,1():dlocal,1()”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] Set(“SIP/VOIPMS-00000a7b”, “DSTRING=”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Set(“SIP/VOIPMS-00000a7b”, “DEVICES=10”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] ExecIf(“SIP/VOIPMS-00000a7b”, “0?Return()”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:4] ExecIf(“SIP/VOIPMS-00000a7b”, “0?Set(DEVICES=0)”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:5] Set(“SIP/VOIPMS-00000a7b”, “LOOPCNT=1”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:6] Set(“SIP/VOIPMS-00000a7b”, “ITER=1”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:7] Set(“SIP/VOIPMS-00000a7b”, “THISDIAL=PJSIP/10”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:8] GosubIf(“SIP/VOIPMS-00000a7b”, “1?zap2dahdi,1()”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] ExecIf(“SIP/VOIPMS-00000a7b”, “0?Return()”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Set(“SIP/VOIPMS-00000a7b”, “NEWDIAL=”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] Set(“SIP/VOIPMS-00000a7b”, “LOOPCNT2=1”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:4] Set(“SIP/VOIPMS-00000a7b”, “ITER2=1”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:5] Set(“SIP/VOIPMS-00000a7b”, “THISPART2=PJSIP/10”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:6] ExecIf(“SIP/VOIPMS-00000a7b”, “0?Set(THISPART2=DAHDIIP/10)”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:7] Set(“SIP/VOIPMS-00000a7b”, “NEWDIAL=PJSIP/10&”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:8] Set(“SIP/VOIPMS-00000a7b”, “ITER2=2”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:9] GotoIf(“SIP/VOIPMS-00000a7b”, “0?begin2”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:10] Set(“SIP/VOIPMS-00000a7b”, “THISDIAL=PJSIP/10”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:11] Return(“SIP/VOIPMS-00000a7b”, “”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:9] GotoIf(“SIP/VOIPMS-00000a7b”, “0?docheck”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:10] NoOp(“SIP/VOIPMS-00000a7b”, “Debug: Found PJSIP Destination PJSIP/10”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:11] GotoIf(“SIP/VOIPMS-00000a7b”, “0?doset”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:12] NoOp(“SIP/VOIPMS-00000a7b”, “Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:13] Set(“SIP/VOIPMS-00000a7b”, “THISDIAL=PJSIP/10/sip:[email protected]:5060”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:14] GotoIf(“SIP/VOIPMS-00000a7b”, “0?skipset”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:15] Set(“SIP/VOIPMS-00000a7b”, “DSTRING=PJSIP/10/sip:[email protected]:5060&”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:16] Set(“SIP/VOIPMS-00000a7b”, “ITER=2”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:17] GotoIf(“SIP/VOIPMS-00000a7b”, “0?begin”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:18] ExecIf(“SIP/VOIPMS-00000a7b”, “0?Return()”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:19] Set(“SIP/VOIPMS-00000a7b”, “DSTRING=PJSIP/10/sip:[email protected]:5060”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:20] Return(“SIP/VOIPMS-00000a7b”, “”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:30] GotoIf(“SIP/VOIPMS-00000a7b”, “0?nodial”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:31] GotoIf(“SIP/VOIPMS-00000a7b”, “0?skiptrace”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:32] GosubIf(“SIP/VOIPMS-00000a7b”, “1?ctset,1():ctclear,1()”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] Set(“SIP/VOIPMS-00000a7b”, “DB(CALLTRACE/10)=4013057000”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] Return(“SIP/VOIPMS-00000a7b”, “”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:33] Set(“SIP/VOIPMS-00000a7b”, “D_OPTIONS=Ttr”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:34] NoOp(“SIP/VOIPMS-00000a7b”, "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:35] ExecIf(“SIP/VOIPMS-00000a7b”, “0?Set(ALERT_INFO=)”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:36] ExecIf(“SIP/VOIPMS-00000a7b”, “0?Set(ALERT_INFO=)”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:37] ExecIf(“SIP/VOIPMS-00000a7b”, “0?Set(ALERT_INFO=)”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:38] ExecIf(“SIP/VOIPMS-00000a7b”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:39] ExecIf(“SIP/VOIPMS-00000a7b”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:40] GosubIf(“SIP/VOIPMS-00000a7b”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:41] ExecIf(“SIP/VOIPMS-00000a7b”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:42] GosubIf(“SIP/VOIPMS-00000a7b”, “0?qwait,1()”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:43] Set(“SIP/VOIPMS-00000a7b”, “__CWIGNORE=”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:44] Set(“SIP/VOIPMS-00000a7b”, “__KEEPCID=TRUE”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:45] GotoIf(“SIP/VOIPMS-00000a7b”, “0?usegoto,1”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:46] GotoIf(“SIP/VOIPMS-00000a7b”, “1?godial”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (macro-dial-one,s,51)
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:51] Macro(“SIP/VOIPMS-00000a7b”, “dialout-one-predial-hook,”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] MacroExit(“SIP/VOIPMS-00000a7b”, “”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:52] ExecIf(“SIP/VOIPMS-00000a7b”, “1?Set(D_OPTIONS=trI)”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:53] NoOp(“SIP/VOIPMS-00000a7b”, “”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:54] Dial(“SIP/VOIPMS-00000a7b”, “PJSIP/10/sip:[email protected]:5060,30,trIb(func-apply-sipheaders^s^1)”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] app_stack.c: PJSIP/10-00000ba3 Internal Gosub(func-apply-sipheaders,s,1) start
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] ExecIf(“PJSIP/10-00000ba3”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] NoOp(“PJSIP/10-00000ba3”, “Applying SIP Headers to channel”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] Set(“PJSIP/10-00000ba3”, “SIPHEADERKEYS=”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:4] While(“PJSIP/10-00000ba3”, “0”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] app_while.c: Jumping to priority 8
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:9] Return(“PJSIP/10-00000ba3”, “”) in new stack
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] app_stack.c: Spawn extension (from-internal, 10, 1) exited non-zero on ‘PJSIP/10-00000ba3’
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] app_stack.c: PJSIP/10-00000ba3 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-07-10 12:42:32] VERBOSE[5905] netsock2.c: Using SIP RTP Audio TOS bits 184
[2018-07-10 12:42:32] VERBOSE[5905] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2018-07-10 12:42:32] VERBOSE[5905] netsock2.c: Using SIP RTP Audio CoS mark 5
[2018-07-10 12:42:32] VERBOSE[5905] netsock2.c: Using SIP RTP Video TOS bits 136
[2018-07-10 12:42:32] VERBOSE[5905] netsock2.c: Using SIP RTP Video TOS bits 136 in TCLASS field.
[2018-07-10 12:42:32] VERBOSE[5905] netsock2.c: Using SIP RTP Video CoS mark 4
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] app_dial.c: Called PJSIP/10/sip:[email protected]:5060
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] app_dial.c: Connected line update to SIP/VOIPMS-00000a7b prevented.
[2018-07-10 12:42:32] VERBOSE[18558][C-00000c17] app_dial.c: PJSIP/10-00000ba3 is ringing
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] app_dial.c: Nobody picked up in 30000 ms
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] app_stack.c: PJSIP/10-00000ba3 Internal Gosub(crm-hangup,s,1) start
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] NoOp(“PJSIP/10-00000ba3”, “Sending Hangup to CRM”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] NoOp(“PJSIP/10-00000ba3”, “HANGUP CAUSE: 0”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]gup:3] ExecIf(“PJSIP/10-00000ba3”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:4] NoOp(“PJSIP/10-00000ba3”, “MASTER CHANNEL: 1531240952.6602 = 1531240911.6601”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:5] GotoIf(“PJSIP/10-00000ba3”, “1?return”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx_builtins.c: Goto (crm-hangup,s,8)
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:8] Return(“PJSIP/10-00000ba3”, “”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] app_stack.c: Spawn extension (from-internal, 10, 1) exited non-zero on ‘PJSIP/10-00000ba3’
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] app_stack.c: PJSIP/10-00000ba3 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:55] ExecIf(“SIP/VOIPMS-00000a7b”, “0?MacroExit()”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:56] ExecIf(“SIP/VOIPMS-00000a7b”, “0?Set(DIALSTATUS=)”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:57] GosubIf(“SIP/VOIPMS-00000a7b”, “0?s-NOANSWER,1()”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:58] MacroExit(“SIP/VOIPMS-00000a7b”, “”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:21] Set(“SIP/VOIPMS-00000a7b”, “SV_DIALSTATUS=NOANSWER”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:22] GosubIf(“SIP/VOIPMS-00000a7b”, “1?docfu,1()”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:1] ExecIf(“SIP/VOIPMS-00000a7b”, “0?StackPop()”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:2] GotoIf(“SIP/VOIPMS-00000a7b”, “0?from-internal,4012536633,1”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:3] Set(“SIP/VOIPMS-00000a7b”, “RTCF=30”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:4] ExecIf(“SIP/VOIPMS-00000a7b”, “1?Set(DIAL_OPTIONS=trI)”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] pbx.c: Executing [[email protected]:5] Dial(“SIP/VOIPMS-00000a7b”, “Local/[email protected]/n,30,trI”) in new stack
[2018-07-10 12:43:02] VERBOSE[18558][C-00000c17] app_dial.c: Called Local/[email protected]/n
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:1] Macro(“Local/[email protected];2”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:1] Set(“Local/[email protected];2”, “TOUCH_MONITOR=1531240982.6604”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:2] Set(“Local/[email protected];2”, “AMPUSER=4013057000”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:3] GotoIf(“Local/[email protected];2”, “0?report”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:4] ExecIf(“Local/[email protected];2”, “1?Set(REALCALLERIDNUM=4013057000)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:5] Set(“Local/[email protected];2”, “AMPUSER=”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]rid:6] GotoIf(“Local/[email protected];2”, “0?limit”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:7] Set(“Local/[email protected];2”, “AMPUSERCIDNAME=”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:8] ExecIf(“Local/[email protected];2”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “1?report”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:16] NoOp(“Local/[email protected];2”, “Macro Depth is 1”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:17] GotoIf(“Local/[email protected];2”, “1?report2:macroerror”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:18] GotoIf(“Local/[email protected];2”, “1?continue”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:37] Set(“Local/[email protected];2”, “CALLERID(number)=4013057000”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:38] Set(“Local/[email protected];2”, “CALLERID(name)=HOME CARE NE”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:39] GotoIf(“Local/[email protected];2”, “0?cnum”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:40] Set(“Local/[email protected];2”, “CDR(cnam)=HOME CARE NE”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:41] Set(“Local/[email protected];2”, “CDR(cnum)=4013057000”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:42] Set(“Local/[email protected];2”, “CHANNEL(language)=en”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:2] Gosub(“Local/[email protected];2”, “sub-record-check,s,1(out,4012536633,dontcare)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:1] GotoIf(“Local/[email protected];2”, “0?initialized”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:2] Set(“Local/[email protected];2”, “__REC_STATUS=INITIALIZED”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:3] Set(“Local/[email protected];2”, “NOW=1531240982”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:4] Set(“Local/[email protected];2”, “__DAY=10”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:5] Set(“Local/[email protected];2”, “__MONTH=07”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:6] Set(“Local/[email protected];2”, “__YEAR=2018”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:7] Set(“Local/[email protected]0045;2”, “__TIMESTR=20180710-124302”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:8] Set(“Local/[email protected];2”, “__FROMEXTEN=4013057000”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:9] Set(“Local/[email protected];2”, “__MON_FMT=wav”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:10] NoOp(“Local/[email protected];2”, “Recordings initialized”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:11] ExecIf(“Local/[email protected];2”, “0?Set(ARG3=dontcare)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:12] Set(“Local/[email protected];2”, “REC_POLICY_MODE_SAVE=”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:13] ExecIf(“Local/[email protected];2”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:14] GotoIf(“Local/[email protected];2”, “3?checkaction”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:17] GotoIf(“Local/[email protected];2”, “1?sub-record-check,out,1”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx_builtins.c: Goto (sub-record-check,out,1)
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:1] NoOp(“Local/[email protected];2”, “Outbound Recording Check from 4013057000 to 4012536633”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:2] Set(“Local/[email protected];2”, “RECMODE=”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:3] ExecIf(“Local/[email protected];2”, “1?Goto(routewins)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx_builtins.c: Goto (sub-record-check,out,7)
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:7] Gosub(“Local/[email protected];2”, “recordcheck,1(dontcare,out,4012536633)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:1] NoOp(“Local/[email protected];2”, “Starting recording check against dontcare”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:2] Goto(“Local/[email protected];2”, “dontcare”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)

[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:3] Return(“Local/[email protected];2”, “”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:8] Return(“Local/[email protected];2”, “”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:3] ExecIf(“Local/[email protected];2”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:4] Set(“Local/[email protected];2”, “MOHCLASS=default”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:5] ExecIf(“Local/[email protected];2”, “0?Set(TRUNKCIDOVERRIDE=“hidden”)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:6] Set(“Local/[email protected];2”, “_NODEST=”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:7] Macro(“Local/[email protected];2”, “dialout-trunk,2,4012536633,off”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:1] Set(“Local/[email protected];2”, “DIAL_TRUNK=2”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:2] GosubIf(“Local/[email protected];2”, “0?sub-pincheck,s,1()”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:3] ExecIf(“Local/[email protected];2”, “0?Set(CALLERID(num)=)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:4] GotoIf(“Local/[email protected];2”, “0?disabletrunk,1”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:5] Set(“Local/[email protected];2”, “DIAL_NUMBER=4012536633”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:6] Set(“Local/[email protected];2”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:7] Set(“Local/[email protected];2”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:8] Set(“Local/[email protected];2”, “DIAL_TRUNK_OPTIONS=T”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “1?nomax”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx_builtins.c: Goto (macro-dialout-trunk,s,11)
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:11] GotoIf(“Local/[email protected];2”, “0?skipoutcid”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:12] Macro(“Local/[email protected];2”, “outbound-callerid,2”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:1] NoOp(“Local/[email protected];2”, “4013057000”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:2] NoOp(“Local/[email protected];2”, “TRUE”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:3] NoOp(“Local/[email protected];2”, “off”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:4] ExecIf(“Local/[email protected];2”, “1?Set(CALLERPRES(name-pres)=allowed_not_screened)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:5] ExecIf(“Local/[email protected];2”, “1?Set(CALLERPRES(num-pres)=allowed_not_screened)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:6] ExecIf(“Local/[email protected];2”, “0?Set(REALCALLERIDNUM=4013057000)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:7] GotoIf(“Local/[email protected];2”, “0?normcid”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:8] Set(“Local/[email protected];2”, “USEROUTCID=4013057000”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “0?normcid”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:10] GotoIf(“Local/[email protected];2”, “1?bypass”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx_builtins.c: Goto (macro-outbound-callerid,s,12)
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:12] Set(“Local/[email protected];2”, “EMERGENCYCID=”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:13] Set(“Local/[email protected];2”, “TRUNKOUTCID=“Bristol Housing Authority”<401253-4464>”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:14] GotoIf(“Local/[email protected];2”, “1?trunkcid”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx_builtins.c: Goto (macro-outbound-callerid,s,19)
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:19] ExecIf(“Local/[email protected];2”, “1?Set(CALLERID(all)=“Bristol Housing Authority”<401253-4464>)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:20] ExecIf(“Local/[email protected];2”, “1?Set(CALLERID(all)=4013057000)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:21] ExecIf(“Local/[email protected];2”, “0?Set(CALLERID(all)=)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:22] ExecIf(“Local/[email protected];2”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:23] ExecIf(“Local/[email protected];2”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:24] Set(“Local/[email protected];2”, “CDR(outbound_cnum)=4013057000”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:25] Set(“Local/[email protected];2”, “CDR(outbound_cnam)=”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:13] GosubIf(“Local/[email protected];2”, “0?sub-flp-2,s,1()”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:14] Set(“Local/[email protected];2”, “OUTNUM=4012536633”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:15] Set(“Local/[email protected];2”, “custom=SIP/VOIPMS”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:16] ExecIf(“Local/[email protected];2”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:17] ExecIf(“Local/[email protected];2”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:18] Macro(“Local/[email protected];2”, “dialout-trunk-predial-hook,”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:1] MacroExit(“Local/[email protected];2”, “”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:19] GotoIf(“Local/[email protected];2”, “1?skipcrm”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx_builtins.c: Goto (macro-dialout-trunk,s,25)
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:25] NoOp(“Local/[email protected];2”, “CRM Finished”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:26] GotoIf(“Local/[email protected];2”, “0?bypass,1”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:27] ExecIf(“Local/[email protected];2”, “0?Set(CONNECTEDLINE(num,i)=4012536633)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:28] ExecIf(“Local/[email protected];2”, “0?Set(CONNECTEDLINE(name,i)=CID:4013057000)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:29] ExecIf(“Local/[email protected];2”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)4013057000)”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:30] GotoIf(“Local/[email protected];2”, “0?customtrunk”) in new stack
[2018-07-10 12:43:02] VERBOSE[6653][C-00000c17] pbx.c: Executing [[email protected]:31] Dial(“Local/[email protected];2”, “SIP/VOIPMS/4012536633,300,Tb(func-apply-sipheaders^s^1)”) in new stack

This topic was automatically closed 365 days after the last reply. New replies are no longer allowed.