Odd call failure issue

rjdalejr · April 25, 2017, 1:53pm

I have an older freepbx install where inbound calls pass through time conditions and hit a ring group during business hours. If no one answers, the call is reouted to a IVR. One of the picks dials a cell phone via a miscellaneous destination. When this happens, the call is rejected by the carrier (CBeyond aka Birch) and I don’t understand why it fails. The odd this is, after hours, the calls rout directly to the same IVR and the call to the miscellaneous destination is successful. The only differenc is that calls to the cell fail when they ring the ring group before hitting the IVR.

The system is running freepbx 12.0.76.4, Asterisk 11.21.0, and the distro version reports:
PBX Firmware: 6.12.65-32
PBX Service Pack: 1.0.0.0

Here is a log of one of the failed calls (some of the extension ringing entries removed dues to size constraints):

  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
    -- Executing [2147527075@from-pstn:1] Set("SIP/cbeyond-in-00000116", "__DIRECTION=INBOUND") in new stack
    -- Executing [2147527075@from-pstn:2] Set("SIP/cbeyond-in-00000116", "CIDSFSCHEME=QUxMfEFMTA==") in new stack
    -- Executing [2147527075@from-pstn:3] AGI("SIP/cbeyond-in-00000116", "/var/www/html/admin/modules/superfecta/agi/superfecta.agi") in new stack
    -- Launched AGI Script /var/www/html/admin/modules/superfecta/agi/superfecta.agi
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta is Answering the Channel
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: Scheme is ALL
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: The DID passed from Asterisk is: 2147527075
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: The number passed from Asterisk is: 2147503646
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: The CID name passed from Asterisk is: DLS NEUROSURGCL
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: Executing Scheme..
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: Converting result to UTF-8
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: CID Determined to be: 'DLS NEUROSURGCL'
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: Attempting to set lookupcid
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: Success!
    -- <SIP/cbeyond-in-00000116>AGI Script /var/www/html/admin/modules/superfecta/agi/superfecta.agi completed, returning 0
    -- Executing [2147527075@from-pstn:4] Set("SIP/cbeyond-in-00000116", "CALLERID(name)=DLS NEUROSURGCL") in new stack
    -- Executing [2147527075@from-pstn:5] Gosub("SIP/cbeyond-in-00000116", "sub-record-check,s,1(in,2147527075,dontcare)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("SIP/cbeyond-in-00000116", "0?initialized") in new stack
    -- Executing [s@sub-record-check:2] Set("SIP/cbeyond-in-00000116", "__REC_STATUS=INITIALIZED") in new stack
    -- Executing [s@sub-record-check:3] Set("SIP/cbeyond-in-00000116", "NOW=1493126976") in new stack
    -- Executing [s@sub-record-check:4] Set("SIP/cbeyond-in-00000116", "__DAY=25") in new stack
    -- Executing [s@sub-record-check:5] Set("SIP/cbeyond-in-00000116", "__MONTH=04") in new stack
    -- Executing [s@sub-record-check:6] Set("SIP/cbeyond-in-00000116", "__YEAR=2017") in new stack
    -- Executing [s@sub-record-check:7] Set("SIP/cbeyond-in-00000116", "__TIMESTR=20170425-082936") in new stack
    -- Executing [s@sub-record-check:8] Set("SIP/cbeyond-in-00000116", "__FROMEXTEN=unknown") in new stack
    -- Executing [s@sub-record-check:9] Set("SIP/cbeyond-in-00000116", "__MON_FMT=wav") in new stack
    -- Executing [s@sub-record-check:10] NoOp("SIP/cbeyond-in-00000116", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("SIP/cbeyond-in-00000116", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("SIP/cbeyond-in-00000116", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("SIP/cbeyond-in-00000116", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("SIP/cbeyond-in-00000116", "2?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("SIP/cbeyond-in-00000116", "1?sub-record-check,in,1") in new stack
    -- Goto (sub-record-check,in,1)
    -- Executing [in@sub-record-check:1] NoOp("SIP/cbeyond-in-00000116", "Inbound Recording Check to 2147527075") in new stack
    -- Executing [in@sub-record-check:2] Set("SIP/cbeyond-in-00000116", "FROMEXTEN=unknown") in new stack
    -- Executing [in@sub-record-check:3] ExecIf("SIP/cbeyond-in-00000116", "10?Set(FROMEXTEN=2147503646)") in new stack
    -- Executing [in@sub-record-check:4] Gosub("SIP/cbeyond-in-00000116", "recordcheck,1(dontcare,in,2147527075)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/cbeyond-in-00000116", "Starting recording check against dontcare") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("SIP/cbeyond-in-00000116", "dontcare") in new stack
    -- Goto (sub-record-check,recordcheck,3)
    -- Executing [recordcheck@sub-record-check:3] Return("SIP/cbeyond-in-00000116", "") in new stack
    -- Executing [in@sub-record-check:5] Return("SIP/cbeyond-in-00000116", "") in new stack
    -- Executing [2147527075@from-pstn:6] Gosub("SIP/cbeyond-in-00000116", "app-blacklist-check,s,1()") in new stack
    -- Executing [s@app-blacklist-check:1] GotoIf("SIP/cbeyond-in-00000116", "0?blacklisted") in new stack
    -- Executing [s@app-blacklist-check:2] Set("SIP/cbeyond-in-00000116", "CALLED_BLACKLIST=1") in new stack
    -- Executing [s@app-blacklist-check:3] Return("SIP/cbeyond-in-00000116", "") in new stack
    -- Executing [2147527075@from-pstn:7] Set("SIP/cbeyond-in-00000116", "__FROM_DID=2147527075") in new stack
    -- Executing [2147527075@from-pstn:8] Set("SIP/cbeyond-in-00000116", "CDR(did)=2147527075") in new stack
    -- Executing [2147527075@from-pstn:9] ExecIf("SIP/cbeyond-in-00000116", "0 ?Set(CALLERID(name)=2147503646)") in new stack
    -- Executing [2147527075@from-pstn:10] Set("SIP/cbeyond-in-00000116", "CHANNEL(musicclass)=default") in new stack
    -- Executing [2147527075@from-pstn:11] Set("SIP/cbeyond-in-00000116", "__MOHCLASS=default") in new stack
    -- Executing [2147527075@from-pstn:12] Ringing("SIP/cbeyond-in-00000116", "") in new stack
    -- Executing [2147527075@from-pstn:13] Set("SIP/cbeyond-in-00000116", "__RINGINGSENT=TRUE") in new stack
[2017-04-25 08:29:36] WARNING[24894][C-00001541]: func_channel.c:538 func_channel_read: Unknown or unavailable item requested: 'reversecharge'
    -- Executing [2147527075@from-pstn:14] GotoIf("SIP/cbeyond-in-00000116", "0?macro-hangupcall") in new stack
    -- Executing [2147527075@from-pstn:15] Set("SIP/cbeyond-in-00000116", "__CALLINGPRES_SV=allowed_not_screened") in new stack
    -- Executing [2147527075@from-pstn:16] Set("SIP/cbeyond-in-00000116", "CALLERPRES()=allowed_not_screened") in new stack
    -- Executing [2147527075@from-pstn:17] Goto("SIP/cbeyond-in-00000116", "app-daynight,0,1") in new stack
    -- Goto (app-daynight,0,1)
    -- Executing [0@app-daynight:1] GotoIf("SIP/cbeyond-in-00000116", "0?ivr-1,s,1:timeconditions,1,1") in new stack
    -- Goto (timeconditions,1,1)
    -- Executing [1@timeconditions:1] Set("SIP/cbeyond-in-00000116", "DB(TC/1/INUSESTATE)=INUSE") in new stack
    -- Executing [1@timeconditions:2] Set("SIP/cbeyond-in-00000116", "DB(TC/1/NOT_INUSESTATE)=NOT_INUSE") in new stack
    -- Executing [1@timeconditions:3] GotoIfTime("SIP/cbeyond-in-00000116", "17:30-07:59,mon-fri,*,*?truestate") in new stack
    -- Executing [1@timeconditions:4] GotoIfTime("SIP/cbeyond-in-00000116", "*,sat-sun,*,*?truestate") in new stack
    -- Executing [1@timeconditions:5] GotoIf("SIP/cbeyond-in-00000116", "0?truegoto") in new stack
    -- Executing [1@timeconditions:6] ExecIf("SIP/cbeyond-in-00000116", "0?Set(DB(TC/1)=)") in new stack
    -- Executing [1@timeconditions:7] Set("SIP/cbeyond-in-00000116", "DEVICE_STATE(Custom:TC1)=INUSE") in new stack
    -- Executing [1@timeconditions:8] ExecIf("SIP/cbeyond-in-00000116", "0?Set(NOT_INUSE)") in new stack
    -- Executing [1@timeconditions:9] GotoIf("SIP/cbeyond-in-00000116", "1?ext-group,600,1") in new stack
    -- Goto (ext-group,600,1)
    -- Executing [600@ext-group:1] Macro("SIP/cbeyond-in-00000116", "user-callerid,") in new stack
    -- Executing [s@macro-user-callerid:1] Set("SIP/cbeyond-in-00000116", "TOUCH_MONITOR=1493126976.11015") in new stack
    -- Executing [s@macro-user-callerid:2] Set("SIP/cbeyond-in-00000116", "AMPUSER=2147503646") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("SIP/cbeyond-in-00000116", "0?report") in new stack
    -- Executing [s@macro-user-callerid:4] ExecIf("SIP/cbeyond-in-00000116", "1?Set(REALCALLERIDNUM=2147503646)") in new stack
    -- Executing [s@macro-user-callerid:5] Set("SIP/cbeyond-in-00000116", "AMPUSER=") in new stack
    -- Executing [s@macro-user-callerid:6] GotoIf("SIP/cbeyond-in-00000116", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:7] Set("SIP/cbeyond-in-00000116", "AMPUSERCIDNAME=") in new stack
    -- Executing [s@macro-user-callerid:8] GotoIf("SIP/cbeyond-in-00000116", "1?report") in new stack
    -- Goto (macro-user-callerid,s,16)
    -- Executing [s@macro-user-callerid:16] GotoIf("SIP/cbeyond-in-00000116", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:17] ExecIf("SIP/cbeyond-in-00000116", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
    -- Executing [s@macro-user-callerid:18] Set("SIP/cbeyond-in-00000116", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:19] GotoIf("SIP/cbeyond-in-00000116", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,30)
    -- Executing [s@macro-user-callerid:30] Set("SIP/cbeyond-in-00000116", "CALLERID(number)=2147503646") in new stack
    -- Executing [s@macro-user-callerid:31] Set("SIP/cbeyond-in-00000116", "CALLERID(name)=DLS NEUROSURGCL") in new stack
    -- Executing [s@macro-user-callerid:32] Set("SIP/cbeyond-in-00000116", "CDR(cnum)=2147503646") in new stack
    -- Executing [s@macro-user-callerid:33] Set("SIP/cbeyond-in-00000116", "CDR(cnam)=DLS NEUROSURGCL") in new stack
    -- Executing [s@macro-user-callerid:34] Set("SIP/cbeyond-in-00000116", "CHANNEL(language)=en") in new stack
    -- Executing [600@ext-group:2] Macro("SIP/cbeyond-in-00000116", "blkvm-setifempty,") in new stack
    -- Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/cbeyond-in-00000116", "1?init") in new stack
    -- Goto (macro-blkvm-setifempty,s,4)
    -- Executing [s@macro-blkvm-setifempty:4] Set("SIP/cbeyond-in-00000116", "__BLKVM_CHANNEL=SIP/cbeyond-in-00000116") in new stack
    -- Executing [s@macro-blkvm-setifempty:5] Set("SIP/cbeyond-in-00000116", "SHARED(BLKVM,SIP/cbeyond-in-00000116)=TRUE") in new stack
    -- Executing [s@macro-blkvm-setifempty:6] Set("SIP/cbeyond-in-00000116", "GOSUB_RETVAL=TRUE") in new stack
    -- Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/cbeyond-in-00000116", "") in new stack
    -- Executing [600@ext-group:3] GotoIf("SIP/cbeyond-in-00000116", "1?skipov") in new stack
    -- Goto (ext-group,600,6)
    -- Executing [600@ext-group:6] Set("SIP/cbeyond-in-00000116", "RRNODEST=") in new stack
    -- Executing [600@ext-group:7] Set("SIP/cbeyond-in-00000116", "__NODEST=600") in new stack
    -- Executing [600@ext-group:8] GosubIf("SIP/cbeyond-in-00000116", "0?sub-rgsetcid,s,1()") in new stack
    -- Executing [600@ext-group:9] Gosub("SIP/cbeyond-in-00000116", "sub-record-check,s,1(rg,600,dontcare)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("SIP/cbeyond-in-00000116", "10?initialized") in new stack
    -- Goto (sub-record-check,s,10)
    -- Executing [s@sub-record-check:10] NoOp("SIP/cbeyond-in-00000116", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("SIP/cbeyond-in-00000116", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("SIP/cbeyond-in-00000116", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("SIP/cbeyond-in-00000116", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("SIP/cbeyond-in-00000116", "2?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("SIP/cbeyond-in-00000116", "0?sub-record-check,rg,1") in new stack
    -- Executing [s@sub-record-check:18] NoOp("SIP/cbeyond-in-00000116", "Generic rg Recording Check - 2147503646 600") in new stack
    -- Executing [s@sub-record-check:19] Gosub("SIP/cbeyond-in-00000116", "recordcheck,1(dontcare,rg,600)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/cbeyond-in-00000116", "Starting recording check against dontcare") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("SIP/cbeyond-in-00000116", "dontcare") in new stack
    -- Goto (sub-record-check,recordcheck,3)
    -- Executing [recordcheck@sub-record-check:3] Return("SIP/cbeyond-in-00000116", "") in new stack
    -- Executing [s@sub-record-check:20] Return("SIP/cbeyond-in-00000116", "") in new stack
    -- Executing [600@ext-group:10] Set("SIP/cbeyond-in-00000116", "RingGroupMethod=ringall") in new stack
    -- Executing [600@ext-group:11] Macro("SIP/cbeyond-in-00000116", "dial,30,Ttr,101-103-104-105-106-112") in new stack
    -- Executing [s@macro-dial:1] GotoIf("SIP/cbeyond-in-00000116", "0?dial") in new stack
    -- Executing [s@macro-dial:2] Set("SIP/cbeyond-in-00000116", "CHANNEL(musicclass)=default") in new stack
    -- Executing [s@macro-dial:3] AGI("SIP/cbeyond-in-00000116", "dialparties.agi") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
 dialparties.agi: Starting New Dialparties.agi
 dialparties.agi: Caller ID name is 'DLS NEUROSURGCL' number is '2147503646'
 dialparties.agi: Methodology of ring is  'ringall'
    -- dialparties.agi: Added extension 101 to extension map
    -- dialparties.agi: Added extension 103 to extension map
    -- dialparties.agi: Added extension 104 to extension map
    -- dialparties.agi: Added extension 105 to extension map
    -- dialparties.agi: Added extension 106 to extension map
    -- dialparties.agi: Added extension 112 to extension map
    -- dialparties.agi: Extension 101 cf is disabled
    -- dialparties.agi: Extension 103 cf is disabled
    -- dialparties.agi: Extension 104 cf is disabled
    -- dialparties.agi: Extension 105 cf is disabled
    -- dialparties.agi: Extension 106 cf is disabled
    -- dialparties.agi: Extension 112 cf is disabled
    -- dialparties.agi: Extension 101 do not disturb is disabled
    -- dialparties.agi: Extension 103 do not disturb is disabled
    -- dialparties.agi: Extension 104 do not disturb is disabled
    -- dialparties.agi: Extension 105 do not disturb is disabled
    -- dialparties.agi: Extension 106 do not disturb is disabled
    -- dialparties.agi: Extension 112 do not disturb is disabled
    -- dialparties.agi: dbset CALLTRACE/101 to 2147503646
    -- dialparties.agi: dbset CALLTRACE/103 to 2147503646
    -- dialparties.agi: dbset CALLTRACE/104 to 2147503646
    -- dialparties.agi: dbset CALLTRACE/105 to 2147503646
    -- dialparties.agi: dbset CALLTRACE/106 to 2147503646
    -- dialparties.agi: dbset CALLTRACE/112 to 2147503646
    -- dialparties.agi: Filtered ARG3: 101-103-104-105-106-112
    -- <SIP/cbeyond-in-00000116>AGI Script dialparties.agi completed, returning 0
    -- Executing [s@macro-dial:7] Dial("SIP/cbeyond-in-00000116", "SIP/101&SIP/103&SIP/104&SIP/105&SIP/106&SIP/112,30,trM(auto-blkvm)") in new stack
    -- Executing [s@macro-dial:8] Set("SIP/cbeyond-in-00000116", "DIALSTATUS=NOANSWER") in new stack
  == Extension Changed 101[ext-local] new state Idle for Notify User 108
  == Extension Changed 103[ext-local] new state Idle for Notify User 104
    -- Executing [s@macro-dial:9] GosubIf("SIP/cbeyond-in-00000116", "0?NOANSWER,1") in new stack
    -- Executing [600@ext-group:12] Gosub("SIP/cbeyond-in-00000116", "sub-record-cancel,s,1()") in new stack
  == Extension Changed 103[ext-local] new state Idle for Notify User 102
    -- Executing [s@sub-record-cancel:1] Return("SIP/cbeyond-in-00000116", "") in new stack
    -- Executing [600@ext-group:13] Set("SIP/cbeyond-in-00000116", "RingGroupMethod=") in new stack
  == Extension Changed 103[ext-local] new state Idle for Notify User 109
  == Extension Changed 103[ext-local] new state Idle for Notify User 107
    -- Executing [600@ext-group:14] GotoIf("SIP/cbeyond-in-00000116", "0?nodest") in new stack
  == Extension Changed 103[ext-local] new state Idle for Notify User 103
    -- Executing [600@ext-group:15] Set("SIP/cbeyond-in-00000116", "__NODEST=") in new stack
  == Extension Changed 103[ext-local] new state Idle for Notify User 106
  == Extension Changed 103[ext-local] new state Idle for Notify User 112
  == Extension Changed 103[ext-local] new state Idle for Notify User 105
  == Extension Changed 103[ext-local] new state Idle for Notify User 111
  == Extension Changed 103[ext-local] new state Idle for Notify User 101
  == Extension Changed 103[ext-local] new state Idle for Notify User 108
  == Extension Changed 104[ext-local] new state Idle for Notify User 104
    -- Executing [600@ext-group:16] Macro("SIP/cbeyond-in-00000116", "blkvm-clr,") in new stack
    -- Executing [s@macro-blkvm-clr:1] Set("SIP/cbeyond-in-00000116", "SHARED(BLKVM,SIP/cbeyond-in-00000116)=") in new stack
  == Extension Changed 104[ext-local] new state Idle for Notify User 102
    -- Executing [s@macro-blkvm-clr:2] Set("SIP/cbeyond-in-00000116", "GOSUB_RETVAL=") in new stack
    -- Executing [s@macro-blkvm-clr:3] MacroExit("SIP/cbeyond-in-00000116", "") in new stack
    -- Executing [600@ext-group:17] Goto("SIP/cbeyond-in-00000116", "ivr-1,s,1") in new stack
    -- Goto (ivr-1,s,1)
    -- Executing [s@ivr-1:1] Set("SIP/cbeyond-in-00000116", "TIMEOUT_LOOPCOUNT=0") in new stack
  == Extension Changed 104[ext-local] new state Idle for Notify User 109
  == Extension Changed 104[ext-local] new state Idle for Notify User 107
    -- Executing [s@ivr-1:2] Set("SIP/cbeyond-in-00000116", "INVALID_LOOPCOUNT=0") in new stack
    -- Executing [s@ivr-1:3] Set("SIP/cbeyond-in-00000116", "_IVR_CONTEXT_ivr-1=") in new stack
    -- Executing [s@ivr-1:4] Set("SIP/cbeyond-in-00000116", "_IVR_CONTEXT=ivr-1") in new stack
    -- Executing [s@ivr-1:5] Set("SIP/cbeyond-in-00000116", "__IVR_RETVM=") in new stack
    -- Executing [s@ivr-1:6] GotoIf("SIP/cbeyond-in-00000116", "0?skip") in new stack
    -- Executing [s@ivr-1:7] Answer("SIP/cbeyond-in-00000116", "") in new stack
    -- Executing [s@ivr-1:8] Wait("SIP/cbeyond-in-00000116", "1") in new stack
    -- Executing [s@ivr-1:9] Set("SIP/cbeyond-in-00000116", "IVR_MSG=custom/Main-IVR") in new stack
    -- Executing [s@ivr-1:10] Set("SIP/cbeyond-in-00000116", "TIMEOUT(digit)=3") in new stack
    -- Digit timeout set to 3.000
    -- Executing [s@ivr-1:11] ExecIf("SIP/cbeyond-in-00000116", "1?Background(custom/Main-IVR)") in new stack
    -- <SIP/cbeyond-in-00000116> Playing 'custom/Main-IVR.slin' (language 'en')
    -- Executing [5@ivr-1:1] Goto("SIP/cbeyond-in-00000116", "ext-miscdests,1,1") in new stack
    -- Goto (ext-miscdests,1,1)
    -- Executing [1@ext-miscdests:1] NoOp("SIP/cbeyond-in-00000116", "MiscDest: Emergency Cell") in new stack
    -- Executing [1@ext-miscdests:2] Goto("SIP/cbeyond-in-00000116", "from-internal,8173198085,1") in new stack
    -- Goto (from-internal,8173198085,1)
    -- Executing [8173198085@from-internal:1] Macro("SIP/cbeyond-in-00000116", "user-callerid,LIMIT") in new stack
    -- Executing [s@macro-user-callerid:1] Set("SIP/cbeyond-in-00000116", "TOUCH_MONITOR=1493126976.11015") in new stack
    -- Executing [s@macro-user-callerid:2] Set("SIP/cbeyond-in-00000116", "AMPUSER=2147503646") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("SIP/cbeyond-in-00000116", "0?report") in new stack
    -- Executing [s@macro-user-callerid:4] ExecIf("SIP/cbeyond-in-00000116", "0?Set(REALCALLERIDNUM=2147503646)") in new stack
    -- Executing [s@macro-user-callerid:5] Set("SIP/cbeyond-in-00000116", "AMPUSER=") in new stack
    -- Executing [s@macro-user-callerid:6] GotoIf("SIP/cbeyond-in-00000116", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:7] Set("SIP/cbeyond-in-00000116", "AMPUSERCIDNAME=") in new stack
    -- Executing [s@macro-user-callerid:8] GotoIf("SIP/cbeyond-in-00000116", "1?report") in new stack
    -- Goto (macro-user-callerid,s,16)
    -- Executing [s@macro-user-callerid:16] GotoIf("SIP/cbeyond-in-00000116", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,30)
    -- Executing [s@macro-user-callerid:30] Set("SIP/cbeyond-in-00000116", "CALLERID(number)=2147503646") in new stack
    -- Executing [s@macro-user-callerid:31] Set("SIP/cbeyond-in-00000116", "CALLERID(name)=DLS NEUROSURGCL") in new stack
    -- Executing [s@macro-user-callerid:32] Set("SIP/cbeyond-in-00000116", "CDR(cnum)=2147503646") in new stack
    -- Executing [s@macro-user-callerid:33] Set("SIP/cbeyond-in-00000116", "CDR(cnam)=DLS NEUROSURGCL") in new stack
    -- Executing [s@macro-user-callerid:34] Set("SIP/cbeyond-in-00000116", "CHANNEL(language)=en") in new stack
    -- Executing [8173198085@from-internal:2] Set("SIP/cbeyond-in-00000116", "ROUTEUSER=") in new stack
    -- Executing [8173198085@from-internal:3] GotoIf("SIP/cbeyond-in-00000116", "1?notblind") in new stack
    -- Goto (from-internal,8173198085,6)
    -- Executing [8173198085@from-internal:6] GotoIf("SIP/cbeyond-in-00000116", "0?,8173198085,2:outbound-allroutes,8173198085,2") in new stack
    -- Goto (outbound-allroutes,8173198085,2)
    -- Executing [8173198085@outbound-allroutes:2] Gosub("SIP/cbeyond-in-00000116", "sub-record-check,s,1(out,8173198085,dontcare)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("SIP/cbeyond-in-00000116", "10?initialized") in new stack
    -- Goto (sub-record-check,s,10)
    -- Executing [s@sub-record-check:10] NoOp("SIP/cbeyond-in-00000116", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("SIP/cbeyond-in-00000116", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("SIP/cbeyond-in-00000116", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("SIP/cbeyond-in-00000116", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("SIP/cbeyond-in-00000116", "3?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("SIP/cbeyond-in-00000116", "1?sub-record-check,out,1") in new stack
    -- Goto (sub-record-check,out,1)
    -- Executing [out@sub-record-check:1] NoOp("SIP/cbeyond-in-00000116", "Outbound Recording Check from 2147503646 to 8173198085") in new stack
    -- Executing [out@sub-record-check:2] Set("SIP/cbeyond-in-00000116", "RECMODE=") in new stack
    -- Executing [out@sub-record-check:3] ExecIf("SIP/cbeyond-in-00000116", "1?Goto(routewins)") in new stack
    -- Goto (sub-record-check,out,7)
    -- Executing [out@sub-record-check:7] Gosub("SIP/cbeyond-in-00000116", "recordcheck,1(dontcare,out,8173198085)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/cbeyond-in-00000116", "Starting recording check against dontcare") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("SIP/cbeyond-in-00000116", "dontcare") in new stack
    -- Goto (sub-record-check,recordcheck,3)
    -- Executing [recordcheck@sub-record-check:3] Return("SIP/cbeyond-in-00000116", "") in new stack
    -- Executing [out@sub-record-check:8] Return("SIP/cbeyond-in-00000116", "") in new stack
    -- Executing [8173198085@outbound-allroutes:3] ExecIf("SIP/cbeyond-in-00000116", "0 ?Set(CDR(accountcode)=)") in new stack
    -- Executing [8173198085@outbound-allroutes:4] Set("SIP/cbeyond-in-00000116", "MOHCLASS=default") in new stack
    -- Executing [8173198085@outbound-allroutes:5] Set("SIP/cbeyond-in-00000116", "_NODEST=") in new stack
    -- Executing [8173198085@outbound-allroutes:6] Macro("SIP/cbeyond-in-00000116", "dialout-trunk,3,8173198085,,off") in new stack
    -- Executing [s@macro-dialout-trunk:1] Set("SIP/cbeyond-in-00000116", "DIAL_TRUNK=3") in new stack
    -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/cbeyond-in-00000116", "0?sub-pincheck,s,1()") in new stack
    -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/cbeyond-in-00000116", "0?disabletrunk,1") in new stack
    -- Executing [s@macro-dialout-trunk:4] Set("SIP/cbeyond-in-00000116", "DIAL_NUMBER=8173198085") in new stack
    -- Executing [s@macro-dialout-trunk:5] Set("SIP/cbeyond-in-00000116", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
    -- Executing [s@macro-dialout-trunk:6] Set("SIP/cbeyond-in-00000116", "OUTBOUND_GROUP=OUT_3") in new stack
    -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/cbeyond-in-00000116", "0?nomax") in new stack
    -- Executing [s@macro-dialout-trunk:8] GotoIf("SIP/cbeyond-in-00000116", "0?chanfull") in new stack
    -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/cbeyond-in-00000116", "0?skipoutcid") in new stack
    -- Executing [s@macro-dialout-trunk:10] Set("SIP/cbeyond-in-00000116", "DIAL_TRUNK_OPTIONS=Tt") in new stack
    -- Executing [s@macro-dialout-trunk:11] Macro("SIP/cbeyond-in-00000116", "outbound-callerid,3") in new stack
    -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/cbeyond-in-00000116", "1?Set(CALLERPRES()=allowed_not_screened)") in new stack
    -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/cbeyond-in-00000116", "0?Set(REALCALLERIDNUM=2147503646)") in new stack
    -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/cbeyond-in-00000116", "0?normcid") in new stack
    -- Executing [s@macro-outbound-callerid:4] Set("SIP/cbeyond-in-00000116", "USEROUTCID=2147503646") in new stack
    -- Executing [s@macro-outbound-callerid:5] GotoIf("SIP/cbeyond-in-00000116", "1?bypass") in new stack
    -- Goto (macro-outbound-callerid,s,7)
    -- Executing [s@macro-outbound-callerid:7] Set("SIP/cbeyond-in-00000116", "EMERGENCYCID=") in new stack
    -- Executing [s@macro-outbound-callerid:8] Set("SIP/cbeyond-in-00000116", "TRUNKOUTCID="NuVista Evt Svcs"<2147527075>") in new stack
    -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/cbeyond-in-00000116", "1?trunkcid") in new stack
    -- Goto (macro-outbound-callerid,s,14)
    -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/cbeyond-in-00000116", "1?Set(CALLERID(all)="NuVista Evt Svcs"<2147527075>)") in new stack
    -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/cbeyond-in-00000116", "1?Set(CALLERID(all)=2147503646)") in new stack
    -- Executing [s@macro-outbound-callerid:16] ExecIf("SIP/cbeyond-in-00000116", "0?Set(CALLERID(all)=)") in new stack
    -- Executing [s@macro-outbound-callerid:17] ExecIf("SIP/cbeyond-in-00000116", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
    -- Executing [s@macro-outbound-callerid:18] Set("SIP/cbeyond-in-00000116", "CDR(outbound_cnum)=2147503646") in new stack
    -- Executing [s@macro-outbound-callerid:19] Set("SIP/cbeyond-in-00000116", "CDR(outbound_cnam)=") in new stack
    -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/cbeyond-in-00000116", "0?sub-flp-3,s,1()") in new stack
    -- Executing [s@macro-dialout-trunk:13] Set("SIP/cbeyond-in-00000116", "OUTNUM=8173198085") in new stack
    -- Executing [s@macro-dialout-trunk:14] Set("SIP/cbeyond-in-00000116", "custom=SIP/cbeyond") in new stack
    -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/cbeyond-in-00000116", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
    -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/cbeyond-in-00000116", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
    -- Executing [s@macro-dialout-trunk:17] Macro("SIP/cbeyond-in-00000116", "dialout-trunk-predial-hook,") in new stack
    -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/cbeyond-in-00000116", "") in new stack
    -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/cbeyond-in-00000116", "0?bypass,1") in new stack
    -- Executing [s@macro-dialout-trunk:19] ExecIf("SIP/cbeyond-in-00000116", "0?Set(CONNECTEDLINE(num,i)=8173198085)") in new stack
    -- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/cbeyond-in-00000116", "0?Set(CONNECTEDLINE(name,i)=CID:2147503646)") in new stack
    -- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/cbeyond-in-00000116", "0?customtrunk") in new stack
    -- Executing [s@macro-dialout-trunk:22] Dial("SIP/cbeyond-in-00000116", "SIP/cbeyond/8173198085,300,Tt") in new stack
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
    -- Called SIP/cbeyond/8173198085
  == Everyone is busy/congested at this time (1:0/0/1)
    -- Executing [s@macro-dialout-trunk:23] NoOp("SIP/cbeyond-in-00000116", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 1") in new stack
    -- Executing [s@macro-dialout-trunk:24] GotoIf("SIP/cbeyond-in-00000116", "0?continue,1:s-CHANUNAVAIL,1") in new stack
    -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
    -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("SIP/cbeyond-in-00000116", "RC=1") in new stack
    -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("SIP/cbeyond-in-00000116", "1,1") in new stack
    -- Goto (macro-dialout-trunk,1,1)
    -- Executing [1@macro-dialout-trunk:1] Goto("SIP/cbeyond-in-00000116", "s-INVALIDNMBR,1") in new stack
    -- Goto (macro-dialout-trunk,s-INVALIDNMBR,1)
    -- Executing [s-INVALIDNMBR@macro-dialout-trunk:1] NoOp("SIP/cbeyond-in-00000116", "Dial failed due to trunk reporting Address Incomplete - giving up") in new stack
    -- Executing [s-INVALIDNMBR@macro-dialout-trunk:2] Progress("SIP/cbeyond-in-00000116", "") in new stack
    -- Executing [s-INVALIDNMBR@macro-dialout-trunk:3] Playback("SIP/cbeyond-in-00000116", "ss-noservice,noanswer") in new stack
    -- <SIP/cbeyond-in-00000116> Playing 'ss-noservice.ulaw' (language 'en')
    -- Executing [s-INVALIDNMBR@macro-dialout-trunk:4] Busy("SIP/cbeyond-in-00000116", "20") in new stack
  == Spawn extension (macro-dialout-trunk, s-INVALIDNMBR, 4) exited non-zero on 'SIP/cbeyond-in-00000116' in macro 'dialout-trunk'
  == Spawn extension (outbound-allroutes, 8173198085, 6) exited non-zero on 'SIP/cbeyond-in-00000116'
pbx*CLI>

Any thoughts?

GSnover · April 25, 2017, 1:58pm

Looks like your Trunk Caller ID is this:[quote=“rjdalejr, post:1, topic:40823”]
TRUNKOUTCID=“NuVista Evt Svcs”<2147527075>
[/quote]

But your setting the outbound Caller ID to this:

“1?Set(CALLERID(all)=2147503646)”

And then getting this:

– Called SIP/cbeyond/8173198085
== Everyone is busy/congested at this time (1:0/0/1)

My (simple) guess is that your carrier is rejecting the call because your outbound caller id does not match the trunk, so it is considered foreign.

Two ways to get around this - if your carrier supports P-Asserted Identity, set that.

If not, force the trunk Caller ID and the call should complete.

rjdalejr · April 25, 2017, 2:11pm

I set the Route CID and told it to override extension. There was no change in the behavior. To reiterate, the call only fails after hitting the ring group and no one answers. The night and no answer destination are the same IVR. I would expect the number dialed to be exactly the same in both scenarios.

Here is the trace from hitting the IVR forward with override CID set in the outbound route:

    -- Executing [s@ivr-1:8] Wait("SIP/cbeyond-in-0000012d", "1") in new stack
    -- Executing [s@ivr-1:9] Set("SIP/cbeyond-in-0000012d", "IVR_MSG=custom/Main-IVR") in new stack
    -- Executing [s@ivr-1:10] Set("SIP/cbeyond-in-0000012d", "TIMEOUT(digit)=3") in new stack
    -- Digit timeout set to 3.000
    -- Executing [s@ivr-1:11] ExecIf("SIP/cbeyond-in-0000012d", "1?Background(custom/Main-IVR)") in new stack
    -- <SIP/cbeyond-in-0000012d> Playing 'custom/Main-IVR.slin' (language 'en')
    -- Executing [5@ivr-1:1] Goto("SIP/cbeyond-in-0000012d", "ext-miscdests,1,1") in new stack
    -- Goto (ext-miscdests,1,1)
    -- Executing [1@ext-miscdests:1] NoOp("SIP/cbeyond-in-0000012d", "MiscDest: Emergency Cell") in new stack
    -- Executing [1@ext-miscdests:2] Goto("SIP/cbeyond-in-0000012d", "from-internal,8173198085,1") in new stack
    -- Goto (from-internal,8173198085,1)
    -- Executing [8173198085@from-internal:1] Macro("SIP/cbeyond-in-0000012d", "user-callerid,LIMIT") in new stack
    -- Executing [s@macro-user-callerid:1] Set("SIP/cbeyond-in-0000012d", "TOUCH_MONITOR=1493129158.11110") in new stack
    -- Executing [s@macro-user-callerid:2] Set("SIP/cbeyond-in-0000012d", "AMPUSER=2147503646") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("SIP/cbeyond-in-0000012d", "0?report") in new stack
    -- Executing [s@macro-user-callerid:4] ExecIf("SIP/cbeyond-in-0000012d", "0?Set(REALCALLERIDNUM=2147503646)") in new stack
    -- Executing [s@macro-user-callerid:5] Set("SIP/cbeyond-in-0000012d", "AMPUSER=") in new stack
    -- Executing [s@macro-user-callerid:6] GotoIf("SIP/cbeyond-in-0000012d", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:7] Set("SIP/cbeyond-in-0000012d", "AMPUSERCIDNAME=") in new stack
    -- Executing [s@macro-user-callerid:8] GotoIf("SIP/cbeyond-in-0000012d", "1?report") in new stack
    -- Goto (macro-user-callerid,s,16)
    -- Executing [s@macro-user-callerid:16] GotoIf("SIP/cbeyond-in-0000012d", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,30)
    -- Executing [s@macro-user-callerid:30] Set("SIP/cbeyond-in-0000012d", "CALLERID(number)=2147503646") in new stack
    -- Executing [s@macro-user-callerid:31] Set("SIP/cbeyond-in-0000012d", "CALLERID(name)=DLS NEUROSURGCL") in new stack
    -- Executing [s@macro-user-callerid:32] Set("SIP/cbeyond-in-0000012d", "CDR(cnum)=2147503646") in new stack
    -- Executing [s@macro-user-callerid:33] Set("SIP/cbeyond-in-0000012d", "CDR(cnam)=DLS NEUROSURGCL") in new stack
    -- Executing [s@macro-user-callerid:34] Set("SIP/cbeyond-in-0000012d", "CHANNEL(language)=en") in new stack
    -- Executing [8173198085@from-internal:2] Set("SIP/cbeyond-in-0000012d", "ROUTEUSER=") in new stack
    -- Executing [8173198085@from-internal:3] GotoIf("SIP/cbeyond-in-0000012d", "1?notblind") in new stack
    -- Goto (from-internal,8173198085,6)
    -- Executing [8173198085@from-internal:6] GotoIf("SIP/cbeyond-in-0000012d", "0?,8173198085,2:outbound-allroutes,8173198085,2") in new stack
    -- Goto (outbound-allroutes,8173198085,2)
    -- Executing [8173198085@outbound-allroutes:2] Gosub("SIP/cbeyond-in-0000012d", "sub-record-check,s,1(out,8173198085,dontcare)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("SIP/cbeyond-in-0000012d", "10?initialized") in new stack
    -- Goto (sub-record-check,s,10)
    -- Executing [s@sub-record-check:10] NoOp("SIP/cbeyond-in-0000012d", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("SIP/cbeyond-in-0000012d", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("SIP/cbeyond-in-0000012d", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("SIP/cbeyond-in-0000012d", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("SIP/cbeyond-in-0000012d", "3?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("SIP/cbeyond-in-0000012d", "1?sub-record-check,out,1") in new stack
    -- Goto (sub-record-check,out,1)
    -- Executing [out@sub-record-check:1] NoOp("SIP/cbeyond-in-0000012d", "Outbound Recording Check from 2147503646 to 8173198085") in new stack
    -- Executing [out@sub-record-check:2] Set("SIP/cbeyond-in-0000012d", "RECMODE=") in new stack
    -- Executing [out@sub-record-check:3] ExecIf("SIP/cbeyond-in-0000012d", "1?Goto(routewins)") in new stack
    -- Goto (sub-record-check,out,7)
    -- Executing [out@sub-record-check:7] Gosub("SIP/cbeyond-in-0000012d", "recordcheck,1(dontcare,out,8173198085)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/cbeyond-in-0000012d", "Starting recording check against dontcare") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("SIP/cbeyond-in-0000012d", "dontcare") in new stack
    -- Goto (sub-record-check,recordcheck,3)
    -- Executing [recordcheck@sub-record-check:3] Return("SIP/cbeyond-in-0000012d", "") in new stack
    -- Executing [out@sub-record-check:8] Return("SIP/cbeyond-in-0000012d", "") in new stack
    -- Executing [8173198085@outbound-allroutes:3] ExecIf("SIP/cbeyond-in-0000012d", "0 ?Set(CDR(accountcode)=)") in new stack
    -- Executing [8173198085@outbound-allroutes:4] Set("SIP/cbeyond-in-0000012d", "MOHCLASS=default") in new stack
    -- Executing [8173198085@outbound-allroutes:5] ExecIf("SIP/cbeyond-in-0000012d", "0?Set(TRUNKCIDOVERRIDE=<2147527075>)") in new stack
    -- Executing [8173198085@outbound-allroutes:6] Set("SIP/cbeyond-in-0000012d", "_NODEST=") in new stack
    -- Executing [8173198085@outbound-allroutes:7] Macro("SIP/cbeyond-in-0000012d", "dialout-trunk,3,8173198085,,off") in new stack
    -- Executing [s@macro-dialout-trunk:1] Set("SIP/cbeyond-in-0000012d", "DIAL_TRUNK=3") in new stack
    -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/cbeyond-in-0000012d", "0?sub-pincheck,s,1()") in new stack
    -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/cbeyond-in-0000012d", "0?disabletrunk,1") in new stack
    -- Executing [s@macro-dialout-trunk:4] Set("SIP/cbeyond-in-0000012d", "DIAL_NUMBER=8173198085") in new stack
    -- Executing [s@macro-dialout-trunk:5] Set("SIP/cbeyond-in-0000012d", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
    -- Executing [s@macro-dialout-trunk:6] Set("SIP/cbeyond-in-0000012d", "OUTBOUND_GROUP=OUT_3") in new stack
    -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/cbeyond-in-0000012d", "0?nomax") in new stack
    -- Executing [s@macro-dialout-trunk:8] GotoIf("SIP/cbeyond-in-0000012d", "0?chanfull") in new stack
    -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/cbeyond-in-0000012d", "0?skipoutcid") in new stack
    -- Executing [s@macro-dialout-trunk:10] Set("SIP/cbeyond-in-0000012d", "DIAL_TRUNK_OPTIONS=Tt") in new stack
    -- Executing [s@macro-dialout-trunk:11] Macro("SIP/cbeyond-in-0000012d", "outbound-callerid,3") in new stack
    -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/cbeyond-in-0000012d", "1?Set(CALLERPRES()=allowed_not_screened)") in new stack
    -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/cbeyond-in-0000012d", "0?Set(REALCALLERIDNUM=2147503646)") in new stack
    -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/cbeyond-in-0000012d", "0?normcid") in new stack
    -- Executing [s@macro-outbound-callerid:4] Set("SIP/cbeyond-in-0000012d", "USEROUTCID=2147503646") in new stack
    -- Executing [s@macro-outbound-callerid:5] GotoIf("SIP/cbeyond-in-0000012d", "1?bypass") in new stack
    -- Goto (macro-outbound-callerid,s,7)
    -- Executing [s@macro-outbound-callerid:7] Set("SIP/cbeyond-in-0000012d", "EMERGENCYCID=") in new stack
    -- Executing [s@macro-outbound-callerid:8] Set("SIP/cbeyond-in-0000012d", "TRUNKOUTCID="NuVista Evt Svcs"<2147527075>") in new stack
    -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/cbeyond-in-0000012d", "1?trunkcid") in new stack
    -- Goto (macro-outbound-callerid,s,14)
    -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/cbeyond-in-0000012d", "1?Set(CALLERID(all)="NuVista Evt Svcs"<2147527075>)") in new stack
    -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/cbeyond-in-0000012d", "1?Set(CALLERID(all)=2147503646)") in new stack
    -- Executing [s@macro-outbound-callerid:16] ExecIf("SIP/cbeyond-in-0000012d", "0?Set(CALLERID(all)=)") in new stack
    -- Executing [s@macro-outbound-callerid:17] ExecIf("SIP/cbeyond-in-0000012d", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
    -- Executing [s@macro-outbound-callerid:18] Set("SIP/cbeyond-in-0000012d", "CDR(outbound_cnum)=2147503646") in new stack
    -- Executing [s@macro-outbound-callerid:19] Set("SIP/cbeyond-in-0000012d", "CDR(outbound_cnam)=") in new stack
    -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/cbeyond-in-0000012d", "0?sub-flp-3,s,1()") in new stack
    -- Executing [s@macro-dialout-trunk:13] Set("SIP/cbeyond-in-0000012d", "OUTNUM=8173198085") in new stack
    -- Executing [s@macro-dialout-trunk:14] Set("SIP/cbeyond-in-0000012d", "custom=SIP/cbeyond") in new stack
    -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/cbeyond-in-0000012d", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
    -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/cbeyond-in-0000012d", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
    -- Executing [s@macro-dialout-trunk:17] Macro("SIP/cbeyond-in-0000012d", "dialout-trunk-predial-hook,") in new stack
    -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/cbeyond-in-0000012d", "") in new stack
    -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/cbeyond-in-0000012d", "0?bypass,1") in new stack
    -- Executing [s@macro-dialout-trunk:19] ExecIf("SIP/cbeyond-in-0000012d", "0?Set(CONNECTEDLINE(num,i)=8173198085)") in new stack
    -- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/cbeyond-in-0000012d", "0?Set(CONNECTEDLINE(name,i)=CID:2147503646)") in new stack
    -- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/cbeyond-in-0000012d", "0?customtrunk") in new stack
    -- Executing [s@macro-dialout-trunk:22] Dial("SIP/cbeyond-in-0000012d", "SIP/cbeyond/8173198085,300,Tt") in new stack
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
    -- Called SIP/cbeyond/8173198085
  == Everyone is busy/congested at this time (1:0/0/1)
    -- Executing [s@macro-dialout-trunk:23] NoOp("SIP/cbeyond-in-0000012d", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 1") in new stack
    -- Executing [s@macro-dialout-trunk:24] GotoIf("SIP/cbeyond-in-0000012d", "0?continue,1:s-CHANUNAVAIL,1") in new stack
    -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
    -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("SIP/cbeyond-in-0000012d", "RC=1") in new stack
    -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("SIP/cbeyond-in-0000012d", "1,1") in new stack
    -- Goto (macro-dialout-trunk,1,1)
    -- Executing [1@macro-dialout-trunk:1] Goto("SIP/cbeyond-in-0000012d", "s-INVALIDNMBR,1") in new stack
    -- Goto (macro-dialout-trunk,s-INVALIDNMBR,1)
    -- Executing [s-INVALIDNMBR@macro-dialout-trunk:1] NoOp("SIP/cbeyond-in-0000012d", "Dial failed due to trunk reporting Address Incomplete - giving up") in new stack
    -- Executing [s-INVALIDNMBR@macro-dialout-trunk:2] Progress("SIP/cbeyond-in-0000012d", "") in new stack
    -- Executing [s-INVALIDNMBR@macro-dialout-trunk:3] Playback("SIP/cbeyond-in-0000012d", "ss-noservice,noanswer") in new stack
    -- <SIP/cbeyond-in-0000012d> Playing 'ss-noservice.ulaw' (language 'en')
  == Spawn extension (macro-dialout-trunk, s-INVALIDNMBR, 3) exited non-zero on 'SIP/cbeyond-in-0000012d' in macro 'dialout-trunk'
  == Spawn extension (outbound-allroutes, 8173198085, 7) exited non-zero on 'SIP/cbeyond-in-0000012d'
    -- Attempting call on Local/s@tc-maint for application NoCDR() (Retry 1)
    -- Executing [s@tc-maint:1] NoCDR("Local/s@tc-maint-0000151d;2", "") in new stack
    -- Executing [s@tc-maint:2] Set("Local/s@tc-maint-0000151d;2", "TCMAINT=RETURN") in new stack
    -- Executing [s@tc-maint:3] Gosub("Local/s@tc-maint-0000151d;2", "timeconditions,1,1()") in new stack
    -- Executing [1@timeconditions:1] Set("Local/s@tc-maint-0000151d;2", "DB(TC/1/INUSESTATE)=INUSE") in new stack
    -- Executing [1@timeconditions:2] Set("Local/s@tc-maint-0000151d;2", "DB(TC/1/NOT_INUSESTATE)=NOT_INUSE") in new stack
    -- Executing [1@timeconditions:3] GotoIfTime("Local/s@tc-maint-0000151d;2", "17:30-07:59,mon-fri,*,*?truestate") in new stack
    -- Executing [1@timeconditions:4] GotoIfTime("Local/s@tc-maint-0000151d;2", "*,sat-sun,*,*?truestate") in new stack
    -- Executing [1@timeconditions:5] GotoIf("Local/s@tc-maint-0000151d;2", "0?truegoto") in new stack
    -- Executing [1@timeconditions:6] ExecIf("Local/s@tc-maint-0000151d;2", "0?Set(DB(TC/1)=)") in new stack
    -- Executing [1@timeconditions:7] Set("Local/s@tc-maint-0000151d;2", "DEVICE_STATE(Custom:TC1)=INUSE") in new stack
    -- Executing [1@timeconditions:8] ExecIf("Local/s@tc-maint-0000151d;2", "0?Set(NOT_INUSE)") in new stack
    -- Executing [1@timeconditions:9] GotoIf("Local/s@tc-maint-0000151d;2", "0?ext-group,600,1") in new stack
    -- Executing [1@timeconditions:10] Set("Local/s@tc-maint-0000151d;2", "TCSTATE=false") in new stack
    -- Executing [1@timeconditions:11] Set("Local/s@tc-maint-0000151d;2", "TCOVERRIDE=false") in new stack
    -- Executing [1@timeconditions:12] Return("Local/s@tc-maint-0000151d;2", "") in new stack
    -- Executing [s@tc-maint:4] System("Local/s@tc-maint-0000151d;2", "/var/lib/asterisk/bin/schedtc.php 60 /var/spool/asterisk/outgoing 0") in new stack
    -- Executing [s@tc-maint:5] Answer("Local/s@tc-maint-0000151d;2", "") in new stack
[2017-04-25 09:07:01] NOTICE[28075]: pbx_spool.c:427 attempt_thread: Call completed to Local/s@tc-maint

GSnover · April 25, 2017, 2:15pm

You haven’t changed anything - It is still changing the Caller ID.

rjdalejr · April 25, 2017, 2:17pm

Here is a trach when the system is in night mode. The call is successful:

  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
    -- Executing [2147527075@from-pstn:1] Set("SIP/cbeyond-in-00000137", "__DIRECTION=INBOUND") in new stack
    -- Executing [2147527075@from-pstn:2] Set("SIP/cbeyond-in-00000137", "CIDSFSCHEME=QUxMfEFMTA==") in new stack
    -- Executing [2147527075@from-pstn:3] AGI("SIP/cbeyond-in-00000137", "/var/www/html/admin/modules/superfecta/agi/superfecta.agi") in new stack
    -- Launched AGI Script /var/www/html/admin/modules/superfecta/agi/superfecta.agi
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta is Answering the Channel
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: Scheme is ALL
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: The DID passed from Asterisk is: 2147527075
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: The number passed from Asterisk is: 2147503646
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: The CID name passed from Asterisk is: DLS NEUROSURGCL
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: Executing Scheme..
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: Converting result to UTF-8
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: CID Determined to be: 'DLS NEUROSURGCL'
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: Attempting to set lookupcid
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: Success!
    -- <SIP/cbeyond-in-00000137>AGI Script /var/www/html/admin/modules/superfecta/agi/superfecta.agi completed, returning 0
    -- Executing [2147527075@from-pstn:4] Set("SIP/cbeyond-in-00000137", "CALLERID(name)=DLS NEUROSURGCL") in new stack
    -- Executing [2147527075@from-pstn:5] Gosub("SIP/cbeyond-in-00000137", "sub-record-check,s,1(in,2147527075,dontcare)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("SIP/cbeyond-in-00000137", "0?initialized") in new stack
    -- Executing [s@sub-record-check:2] Set("SIP/cbeyond-in-00000137", "__REC_STATUS=INITIALIZED") in new stack
    -- Executing [s@sub-record-check:3] Set("SIP/cbeyond-in-00000137", "NOW=1493129760") in new stack
    -- Executing [s@sub-record-check:4] Set("SIP/cbeyond-in-00000137", "__DAY=25") in new stack
    -- Executing [s@sub-record-check:5] Set("SIP/cbeyond-in-00000137", "__MONTH=04") in new stack
    -- Executing [s@sub-record-check:6] Set("SIP/cbeyond-in-00000137", "__YEAR=2017") in new stack
    -- Executing [s@sub-record-check:7] Set("SIP/cbeyond-in-00000137", "__TIMESTR=20170425-091600") in new stack
    -- Executing [s@sub-record-check:8] Set("SIP/cbeyond-in-00000137", "__FROMEXTEN=unknown") in new stack
    -- Executing [s@sub-record-check:9] Set("SIP/cbeyond-in-00000137", "__MON_FMT=wav") in new stack
    -- Executing [s@sub-record-check:10] NoOp("SIP/cbeyond-in-00000137", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("SIP/cbeyond-in-00000137", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("SIP/cbeyond-in-00000137", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("SIP/cbeyond-in-00000137", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("SIP/cbeyond-in-00000137", "2?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("SIP/cbeyond-in-00000137", "1?sub-record-check,in,1") in new stack
    -- Goto (sub-record-check,in,1)
    -- Executing [in@sub-record-check:1] NoOp("SIP/cbeyond-in-00000137", "Inbound Recording Check to 2147527075") in new stack
    -- Executing [in@sub-record-check:2] Set("SIP/cbeyond-in-00000137", "FROMEXTEN=unknown") in new stack
    -- Executing [in@sub-record-check:3] ExecIf("SIP/cbeyond-in-00000137", "10?Set(FROMEXTEN=2147503646)") in new stack
    -- Executing [in@sub-record-check:4] Gosub("SIP/cbeyond-in-00000137", "recordcheck,1(dontcare,in,2147527075)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/cbeyond-in-00000137", "Starting recording check against dontcare") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("SIP/cbeyond-in-00000137", "dontcare") in new stack
    -- Goto (sub-record-check,recordcheck,3)
    -- Executing [recordcheck@sub-record-check:3] Return("SIP/cbeyond-in-00000137", "") in new stack
    -- Executing [in@sub-record-check:5] Return("SIP/cbeyond-in-00000137", "") in new stack
    -- Executing [2147527075@from-pstn:6] Gosub("SIP/cbeyond-in-00000137", "app-blacklist-check,s,1()") in new stack
    -- Executing [s@app-blacklist-check:1] GotoIf("SIP/cbeyond-in-00000137", "0?blacklisted") in new stack
    -- Executing [s@app-blacklist-check:2] Set("SIP/cbeyond-in-00000137", "CALLED_BLACKLIST=1") in new stack
    -- Executing [s@app-blacklist-check:3] Return("SIP/cbeyond-in-00000137", "") in new stack
    -- Executing [2147527075@from-pstn:7] Set("SIP/cbeyond-in-00000137", "__FROM_DID=2147527075") in new stack
    -- Executing [2147527075@from-pstn:8] Set("SIP/cbeyond-in-00000137", "CDR(did)=2147527075") in new stack
    -- Executing [2147527075@from-pstn:9] ExecIf("SIP/cbeyond-in-00000137", "0 ?Set(CALLERID(name)=2147503646)") in new stack
    -- Executing [2147527075@from-pstn:10] Set("SIP/cbeyond-in-00000137", "CHANNEL(musicclass)=default") in new stack
    -- Executing [2147527075@from-pstn:11] Set("SIP/cbeyond-in-00000137", "__MOHCLASS=default") in new stack
    -- Executing [2147527075@from-pstn:12] Ringing("SIP/cbeyond-in-00000137", "") in new stack
    -- Executing [2147527075@from-pstn:13] Set("SIP/cbeyond-in-00000137", "__RINGINGSENT=TRUE") in new stack
[2017-04-25 09:16:00] WARNING[28937][C-00001578]: func_channel.c:538 func_channel_read: Unknown or unavailable item requested: 'reversecharge'
    -- Executing [2147527075@from-pstn:14] GotoIf("SIP/cbeyond-in-00000137", "0?macro-hangupcall") in new stack
    -- Executing [2147527075@from-pstn:15] Set("SIP/cbeyond-in-00000137", "__CALLINGPRES_SV=allowed_not_screened") in new stack
    -- Executing [2147527075@from-pstn:16] Set("SIP/cbeyond-in-00000137", "CALLERPRES()=allowed_not_screened") in new stack
    -- Executing [2147527075@from-pstn:17] Goto("SIP/cbeyond-in-00000137", "app-daynight,0,1") in new stack
    -- Goto (app-daynight,0,1)
    -- Executing [0@app-daynight:1] GotoIf("SIP/cbeyond-in-00000137", "0?ivr-1,s,1:timeconditions,1,1") in new stack
    -- Goto (timeconditions,1,1)
    -- Executing [1@timeconditions:1] Set("SIP/cbeyond-in-00000137", "DB(TC/1/INUSESTATE)=INUSE") in new stack
    -- Executing [1@timeconditions:2] Set("SIP/cbeyond-in-00000137", "DB(TC/1/NOT_INUSESTATE)=NOT_INUSE") in new stack
    -- Executing [1@timeconditions:3] GotoIfTime("SIP/cbeyond-in-00000137", "17:30-09:59,mon-fri,*,*?truestate") in new stack
    -- Goto (timeconditions,1,13)
    -- Executing [1@timeconditions:13] GotoIf("SIP/cbeyond-in-00000137", "0?falsegoto") in new stack
    -- Executing [1@timeconditions:14] ExecIf("SIP/cbeyond-in-00000137", "0?Set(DB(TC/1)=)") in new stack
    -- Executing [1@timeconditions:15] Set("SIP/cbeyond-in-00000137", "DEVICE_STATE(Custom:TC1)=NOT_INUSE") in new stack
    -- Executing [1@timeconditions:16] ExecIf("SIP/cbeyond-in-00000137", "0?Set(NOT_INUSE)") in new stack
    -- Executing [1@timeconditions:17] GotoIf("SIP/cbeyond-in-00000137", "1?ivr-1,s,1") in new stack
    -- Goto (ivr-1,s,1)
    -- Executing [s@ivr-1:1] Set("SIP/cbeyond-in-00000137", "TIMEOUT_LOOPCOUNT=0") in new stack
    -- Executing [s@ivr-1:2] Set("SIP/cbeyond-in-00000137", "INVALID_LOOPCOUNT=0") in new stack
    -- Executing [s@ivr-1:3] Set("SIP/cbeyond-in-00000137", "_IVR_CONTEXT_ivr-1=") in new stack
    -- Executing [s@ivr-1:4] Set("SIP/cbeyond-in-00000137", "_IVR_CONTEXT=ivr-1") in new stack
    -- Executing [s@ivr-1:5] Set("SIP/cbeyond-in-00000137", "__IVR_RETVM=") in new stack
    -- Executing [s@ivr-1:6] GotoIf("SIP/cbeyond-in-00000137", "0?skip") in new stack
    -- Executing [s@ivr-1:7] Answer("SIP/cbeyond-in-00000137", "") in new stack
    -- Executing [s@ivr-1:8] Wait("SIP/cbeyond-in-00000137", "1") in new stack
    -- Attempting call on Local/s@tc-maint for application NoCDR() (Retry 1)
    -- Executing [s@tc-maint:1] NoCDR("Local/s@tc-maint-00001526;2", "") in new stack
    -- Executing [s@tc-maint:2] Set("Local/s@tc-maint-00001526;2", "TCMAINT=RETURN") in new stack
    -- Executing [s@tc-maint:3] Gosub("Local/s@tc-maint-00001526;2", "timeconditions,1,1()") in new stack
    -- Executing [1@timeconditions:1] Set("Local/s@tc-maint-00001526;2", "DB(TC/1/INUSESTATE)=INUSE") in new stack
    -- Executing [1@timeconditions:2] Set("Local/s@tc-maint-00001526;2", "DB(TC/1/NOT_INUSESTATE)=NOT_INUSE") in new stack
    -- Executing [1@timeconditions:3] GotoIfTime("Local/s@tc-maint-00001526;2", "17:30-09:59,mon-fri,*,*?truestate") in new stack
    -- Goto (timeconditions,1,13)
    -- Executing [1@timeconditions:13] GotoIf("Local/s@tc-maint-00001526;2", "0?falsegoto") in new stack
    -- Executing [1@timeconditions:14] ExecIf("Local/s@tc-maint-00001526;2", "0?Set(DB(TC/1)=)") in new stack
    -- Executing [1@timeconditions:15] Set("Local/s@tc-maint-00001526;2", "DEVICE_STATE(Custom:TC1)=NOT_INUSE") in new stack
    -- Executing [1@timeconditions:16] ExecIf("Local/s@tc-maint-00001526;2", "0?Set(NOT_INUSE)") in new stack
    -- Executing [1@timeconditions:17] GotoIf("Local/s@tc-maint-00001526;2", "0?ivr-1,s,1") in new stack
    -- Executing [1@timeconditions:18] Set("Local/s@tc-maint-00001526;2", "TCSTATE=true") in new stack
    -- Executing [1@timeconditions:19] Set("Local/s@tc-maint-00001526;2", "TCOVERRIDE=false") in new stack
    -- Executing [1@timeconditions:20] Return("Local/s@tc-maint-00001526;2", "") in new stack
    -- Executing [s@tc-maint:4] System("Local/s@tc-maint-00001526;2", "/var/lib/asterisk/bin/schedtc.php 60 /var/spool/asterisk/outgoing 1") in new stack
    -- Executing [s@tc-maint:5] Answer("Local/s@tc-maint-00001526;2", "") in new stack
[2017-04-25 09:16:00] NOTICE[28951]: pbx_spool.c:427 attempt_thread: Call completed to Local/s@tc-maint
  == Spawn extension (tc-maint, s, 5) exited non-zero on 'Local/s@tc-maint-00001526;2'
    -- Executing [s@ivr-1:9] Set("SIP/cbeyond-in-00000137", "IVR_MSG=custom/Main-IVR") in new stack
    -- Executing [s@ivr-1:10] Set("SIP/cbeyond-in-00000137", "TIMEOUT(digit)=3") in new stack
    -- Digit timeout set to 3.000
    -- Executing [s@ivr-1:11] ExecIf("SIP/cbeyond-in-00000137", "1?Background(custom/Main-IVR)") in new stack
    -- <SIP/cbeyond-in-00000137> Playing 'custom/Main-IVR.slin' (language 'en')
    -- Executing [5@ivr-1:1] Goto("SIP/cbeyond-in-00000137", "ext-miscdests,1,1") in new stack
    -- Goto (ext-miscdests,1,1)
    -- Executing [1@ext-miscdests:1] NoOp("SIP/cbeyond-in-00000137", "MiscDest: Emergency Cell") in new stack
    -- Executing [1@ext-miscdests:2] Goto("SIP/cbeyond-in-00000137", "from-internal,8173198085,1") in new stack
    -- Goto (from-internal,8173198085,1)
    -- Executing [8173198085@from-internal:1] Macro("SIP/cbeyond-in-00000137", "user-callerid,LIMIT") in new stack
    -- Executing [s@macro-user-callerid:1] Set("SIP/cbeyond-in-00000137", "TOUCH_MONITOR=1493129759.11140") in new stack
    -- Executing [s@macro-user-callerid:2] Set("SIP/cbeyond-in-00000137", "AMPUSER=2147503646") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("SIP/cbeyond-in-00000137", "0?report") in new stack
    -- Executing [s@macro-user-callerid:4] ExecIf("SIP/cbeyond-in-00000137", "1?Set(REALCALLERIDNUM=2147503646)") in new stack
    -- Executing [s@macro-user-callerid:5] Set("SIP/cbeyond-in-00000137", "AMPUSER=") in new stack
    -- Executing [s@macro-user-callerid:6] GotoIf("SIP/cbeyond-in-00000137", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:7] Set("SIP/cbeyond-in-00000137", "AMPUSERCIDNAME=") in new stack
    -- Executing [s@macro-user-callerid:8] GotoIf("SIP/cbeyond-in-00000137", "1?report") in new stack
    -- Goto (macro-user-callerid,s,16)
    -- Executing [s@macro-user-callerid:16] GotoIf("SIP/cbeyond-in-00000137", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,30)
    -- Executing [s@macro-user-callerid:30] Set("SIP/cbeyond-in-00000137", "CALLERID(number)=2147503646") in new stack
    -- Executing [s@macro-user-callerid:31] Set("SIP/cbeyond-in-00000137", "CALLERID(name)=DLS NEUROSURGCL") in new stack
    -- Executing [s@macro-user-callerid:32] Set("SIP/cbeyond-in-00000137", "CDR(cnum)=2147503646") in new stack
    -- Executing [s@macro-user-callerid:33] Set("SIP/cbeyond-in-00000137", "CDR(cnam)=DLS NEUROSURGCL") in new stack
    -- Executing [s@macro-user-callerid:34] Set("SIP/cbeyond-in-00000137", "CHANNEL(language)=en") in new stack
    -- Executing [8173198085@from-internal:2] Set("SIP/cbeyond-in-00000137", "ROUTEUSER=") in new stack
    -- Executing [8173198085@from-internal:3] GotoIf("SIP/cbeyond-in-00000137", "1?notblind") in new stack
    -- Goto (from-internal,8173198085,6)
    -- Executing [8173198085@from-internal:6] GotoIf("SIP/cbeyond-in-00000137", "0?,8173198085,2:outbound-allroutes,8173198085,2") in new stack
    -- Goto (outbound-allroutes,8173198085,2)
    -- Executing [8173198085@outbound-allroutes:2] Gosub("SIP/cbeyond-in-00000137", "sub-record-check,s,1(out,8173198085,dontcare)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("SIP/cbeyond-in-00000137", "10?initialized") in new stack
    -- Goto (sub-record-check,s,10)
    -- Executing [s@sub-record-check:10] NoOp("SIP/cbeyond-in-00000137", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("SIP/cbeyond-in-00000137", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("SIP/cbeyond-in-00000137", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("SIP/cbeyond-in-00000137", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("SIP/cbeyond-in-00000137", "3?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("SIP/cbeyond-in-00000137", "1?sub-record-check,out,1") in new stack
    -- Goto (sub-record-check,out,1)
    -- Executing [out@sub-record-check:1] NoOp("SIP/cbeyond-in-00000137", "Outbound Recording Check from 2147503646 to 8173198085") in new stack
    -- Executing [out@sub-record-check:2] Set("SIP/cbeyond-in-00000137", "RECMODE=") in new stack
    -- Executing [out@sub-record-check:3] ExecIf("SIP/cbeyond-in-00000137", "1?Goto(routewins)") in new stack
    -- Goto (sub-record-check,out,7)
    -- Executing [out@sub-record-check:7] Gosub("SIP/cbeyond-in-00000137", "recordcheck,1(dontcare,out,8173198085)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/cbeyond-in-00000137", "Starting recording check against dontcare") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("SIP/cbeyond-in-00000137", "dontcare") in new stack
    -- Goto (sub-record-check,recordcheck,3)
    -- Executing [recordcheck@sub-record-check:3] Return("SIP/cbeyond-in-00000137", "") in new stack
    -- Executing [out@sub-record-check:8] Return("SIP/cbeyond-in-00000137", "") in new stack
    -- Executing [8173198085@outbound-allroutes:3] ExecIf("SIP/cbeyond-in-00000137", "0 ?Set(CDR(accountcode)=)") in new stack
    -- Executing [8173198085@outbound-allroutes:4] Set("SIP/cbeyond-in-00000137", "MOHCLASS=default") in new stack
    -- Executing [8173198085@outbound-allroutes:5] ExecIf("SIP/cbeyond-in-00000137", "1?Set(TRUNKCIDOVERRIDE=<2147527075>)") in new stack
    -- Executing [8173198085@outbound-allroutes:6] Set("SIP/cbeyond-in-00000137", "_NODEST=") in new stack
    -- Executing [8173198085@outbound-allroutes:7] Macro("SIP/cbeyond-in-00000137", "dialout-trunk,3,8173198085,,off") in new stack
    -- Executing [s@macro-dialout-trunk:1] Set("SIP/cbeyond-in-00000137", "DIAL_TRUNK=3") in new stack
    -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/cbeyond-in-00000137", "0?sub-pincheck,s,1()") in new stack
    -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/cbeyond-in-00000137", "0?disabletrunk,1") in new stack
    -- Executing [s@macro-dialout-trunk:4] Set("SIP/cbeyond-in-00000137", "DIAL_NUMBER=8173198085") in new stack
    -- Executing [s@macro-dialout-trunk:5] Set("SIP/cbeyond-in-00000137", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
    -- Executing [s@macro-dialout-trunk:6] Set("SIP/cbeyond-in-00000137", "OUTBOUND_GROUP=OUT_3") in new stack
    -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/cbeyond-in-00000137", "0?nomax") in new stack
    -- Executing [s@macro-dialout-trunk:8] GotoIf("SIP/cbeyond-in-00000137", "0?chanfull") in new stack
    -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/cbeyond-in-00000137", "0?skipoutcid") in new stack
    -- Executing [s@macro-dialout-trunk:10] Set("SIP/cbeyond-in-00000137", "DIAL_TRUNK_OPTIONS=Tt") in new stack
    -- Executing [s@macro-dialout-trunk:11] Macro("SIP/cbeyond-in-00000137", "outbound-callerid,3") in new stack
    -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/cbeyond-in-00000137", "1?Set(CALLERPRES()=allowed_not_screened)") in new stack
    -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/cbeyond-in-00000137", "0?Set(REALCALLERIDNUM=2147503646)") in new stack
    -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/cbeyond-in-00000137", "1?normcid") in new stack
    -- Goto (macro-outbound-callerid,s,6)
    -- Executing [s@macro-outbound-callerid:6] Set("SIP/cbeyond-in-00000137", "USEROUTCID=") in new stack
    -- Executing [s@macro-outbound-callerid:7] Set("SIP/cbeyond-in-00000137", "EMERGENCYCID=") in new stack
    -- Executing [s@macro-outbound-callerid:8] Set("SIP/cbeyond-in-00000137", "TRUNKOUTCID="NuVista Evt Svcs"<2147527075>") in new stack
    -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/cbeyond-in-00000137", "1?trunkcid") in new stack
    -- Goto (macro-outbound-callerid,s,14)
    -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/cbeyond-in-00000137", "1?Set(CALLERID(all)="NuVista Evt Svcs"<2147527075>)") in new stack
    -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/cbeyond-in-00000137", "0?Set(CALLERID(all)=)") in new stack
    -- Executing [s@macro-outbound-callerid:16] ExecIf("SIP/cbeyond-in-00000137", "1?Set(CALLERID(all)=<2147527075>)") in new stack
    -- Executing [s@macro-outbound-callerid:17] ExecIf("SIP/cbeyond-in-00000137", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
    -- Executing [s@macro-outbound-callerid:18] Set("SIP/cbeyond-in-00000137", "CDR(outbound_cnum)=2147527075") in new stack
    -- Executing [s@macro-outbound-callerid:19] Set("SIP/cbeyond-in-00000137", "CDR(outbound_cnam)=") in new stack
    -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/cbeyond-in-00000137", "0?sub-flp-3,s,1()") in new stack
    -- Executing [s@macro-dialout-trunk:13] Set("SIP/cbeyond-in-00000137", "OUTNUM=8173198085") in new stack
    -- Executing [s@macro-dialout-trunk:14] Set("SIP/cbeyond-in-00000137", "custom=SIP/cbeyond") in new stack
    -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/cbeyond-in-00000137", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
    -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/cbeyond-in-00000137", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
    -- Executing [s@macro-dialout-trunk:17] Macro("SIP/cbeyond-in-00000137", "dialout-trunk-predial-hook,") in new stack
    -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/cbeyond-in-00000137", "") in new stack
    -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/cbeyond-in-00000137", "0?bypass,1") in new stack
    -- Executing [s@macro-dialout-trunk:19] ExecIf("SIP/cbeyond-in-00000137", "0?Set(CONNECTEDLINE(num,i)=8173198085)") in new stack
    -- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/cbeyond-in-00000137", "0?Set(CONNECTEDLINE(name,i)=CID:2147527075)") in new stack
    -- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/cbeyond-in-00000137", "0?customtrunk") in new stack
    -- Executing [s@macro-dialout-trunk:22] Dial("SIP/cbeyond-in-00000137", "SIP/cbeyond/8173198085,300,Tt") in new stack
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
    -- Called SIP/cbeyond/8173198085
    -- SIP/cbeyond-00000138 is making progress passing it to SIP/cbeyond-in-00000137
  == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on 'SIP/cbeyond-in-00000137' in macro 'dialout-trunk'
  == Spawn extension (outbound-allroutes, 8173198085, 7) exited non-zero on 'SIP/cbeyond-in-00000137'

GSnover · April 25, 2017, 2:18pm

Yup - You are setting the Trunk Caller ID on that call.

rjdalejr · April 25, 2017, 2:20pm

But the only difference in the call flow is it passes through a ring group before the ivr… I really don’t see how this would change the clid passed…

rjdalejr · April 25, 2017, 2:21pm

Or how I can override the CLID passed in that scenario…

GSnover · April 25, 2017, 2:21pm

Dude - it’s in the call trace - the call that works set’s the trunk Caller ID. The call that fails set’s a different one.

That is what is going wrong.

rjdalejr · April 25, 2017, 2:25pm

I see it in the trace. I just don’t know why its different or how to fix it. The ring group has no way to manipulate the clid as far as I can see. The in and out routes used are the same. The trunk is the same.

So inbound call --> call flow = day --> ring group --> no answer --> IVR --> misc destination = failed call
and inbound call --> call flow = night --> IVR --> misc destination = successful call

rjdalejr · April 25, 2017, 2:30pm

Through process of elimination it seems the problem only occurs when passed through the ring group first.

rjdalejr · April 25, 2017, 2:38pm

Thanks Greg! The solution was right in front of me. In the ring group, I set “Change External CID Configuration” to “Outside Calls FIXED CID Value” and the issue is resolved. I’ve never seen this issue before, but this is the only install I’ve seen that uses CBeyond / Birch as the sip provider.

GSnover · April 25, 2017, 2:47pm

You can override on the trunk as a test - under the options for the trunk, check “Force Trunk Caller ID” and I bet it will work perfectly.

As for the permanent solution, usually SIP trunking providers allow P-Asserted identity - add this to your /etc/asterisk/extensions_custom.conf:

[macro-dialout-trunk-predial-hook]
exten => s,1,NoOp(Adding P-Asserted-Identity)
exten => s,n,ExecIf($["${TRUNKOUTCID}"=“NXXNXXXXXX”]?SipAddHeader(P-Asserted-Identity: sip:[email protected] )
exten => s,n,MacroExit()

Substitute your proper CallerID (Trunk) and the domain from your SIP account.