Sorry… I thought I was in debug mode (typo). The requested logs are below.
Dave, that is EXACTLY what I’m doing. This worked on my old version of FreePBX. The call is going through, I just can’t hear the IVR.
Connected to Asterisk 13.11.2 currently running on FreePBX (pid = 1911)
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [2815551212@from-internal:1] Macro(“SIP/105-00000002”, “user-callerid,LIMIT”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/105-00000002”, “TOUCH_MONITOR=1476108653.2”) in new stack
– Executing [s@macro-user-callerid:2] Set(“SIP/105-00000002”, “AMPUSER=105”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“SIP/105-00000002”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“SIP/105-00000002”, “1?Set(REALCALLERIDNUM=105)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/105-00000002”, “AMPUSER=105”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/105-00000002”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:7] Set(“SIP/105-00000002”, “AMPUSERCIDNAME=Test”) in new stack
– Executing [s@macro-user-callerid:8] GotoIf(“SIP/105-00000002”, “0?report”) in new stack
– Executing [s@macro-user-callerid:9] Set(“SIP/105-00000002”, “AMPUSERCID=105”) in new stack
– Executing [s@macro-user-callerid:10] Set(“SIP/105-00000002”, “__DIAL_OPTIONS=Ttr”) in new stack
– Executing [s@macro-user-callerid:11] Set(“SIP/105-00000002”, “CALLERID(all)=“Test” <105>”) in new stack
– Executing [s@macro-user-callerid:12] GotoIf(“SIP/105-00000002”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:13] ExecIf(“SIP/105-00000002”, “1?Set(GROUP(concurrency_limit)=105)”) in new stack
– Executing [s@macro-user-callerid:14] ExecIf(“SIP/105-00000002”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [s@macro-user-callerid:15] GotoIf(“SIP/105-00000002”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [s@macro-user-callerid:29] Set(“SIP/105-00000002”, “CALLERID(number)=105”) in new stack
– Executing [s@macro-user-callerid:30] Set(“SIP/105-00000002”, “CALLERID(name)=Test”) in new stack
– Executing [s@macro-user-callerid:31] GotoIf(“SIP/105-00000002”, “0?cnum”) in new stack
– Executing [s@macro-user-callerid:32] Set(“SIP/105-00000002”, “CDR(cnam)=Test”) in new stack
– Executing [s@macro-user-callerid:33] Set(“SIP/105-00000002”, “CDR(cnum)=105”) in new stack
– Executing [s@macro-user-callerid:34] Set(“SIP/105-00000002”, “CHANNEL(language)=en”) in new stack
– Executing [2815551212@from-internal:2] Set(“SIP/105-00000002”, “ROUTEUSER=105”) in new stack
– Executing [2815551212@from-internal:3] GotoIf(“SIP/105-00000002”, “1?notblind”) in new stack
– Goto (from-internal,2815551212,6)
– Executing [2815551212@from-internal:6] GotoIf(“SIP/105-00000002”, “1?restrictedroute-601bcbd12379a3a03bcbad773954109d,2815551212,2:outbound-allroutes,2815551212,2”) in new stack
– Goto (restrictedroute-601bcbd12379a3a03bcbad773954109d,2815551212,2)
– Executing [2815551212@restrictedroute-601bcbd12379a3a03bcbad773954109d:2] Gosub(“SIP/105-00000002”, “sub-record-check,s,1(out,2815551212,dontcare)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“SIP/105-00000002”, “0?initialized”) in new stack
– Executing [s@sub-record-check:2] Set(“SIP/105-00000002”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:3] Set(“SIP/105-00000002”, “NOW=1476108653”) in new stack
– Executing [s@sub-record-check:4] Set(“SIP/105-00000002”, “__DAY=10”) in new stack
– Executing [s@sub-record-check:5] Set(“SIP/105-00000002”, “__MONTH=10”) in new stack
– Executing [s@sub-record-check:6] Set(“SIP/105-00000002”, “__YEAR=2016”) in new stack
– Executing [s@sub-record-check:7] Set(“SIP/105-00000002”, “__TIMESTR=20161010-091053”) in new stack
– Executing [s@sub-record-check:8] Set(“SIP/105-00000002”, “__FROMEXTEN=105”) in new stack
– Executing [s@sub-record-check:9] Set(“SIP/105-00000002”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:10] NoOp(“SIP/105-00000002”, “Recordings initialized”) in new stack
– Executing [s@sub-record-check:11] ExecIf(“SIP/105-00000002”, “0?Set(ARG3=dontcare)”) in new stack
– Executing [s@sub-record-check:12] Set(“SIP/105-00000002”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“SIP/105-00000002”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [s@sub-record-check:14] GotoIf(“SIP/105-00000002”, “3?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [s@sub-record-check:17] GotoIf(“SIP/105-00000002”, “1?sub-record-check,out,1”) in new stack
– Goto (sub-record-check,out,1)
– Executing [out@sub-record-check:1] NoOp(“SIP/105-00000002”, “Outbound Recording Check from 105 to 2815551212”) in new stack
– Executing [out@sub-record-check:2] Set(“SIP/105-00000002”, “RECMODE=dontcare”) in new stack
– Executing [out@sub-record-check:3] ExecIf(“SIP/105-00000002”, “1?Goto(routewins)”) in new stack
– Goto (sub-record-check,out,7)
– Executing [out@sub-record-check:7] Gosub(“SIP/105-00000002”, “recordcheck,1(dontcare,out,2815551212)”) in new stack
– Executing [recordcheck@sub-record-check:1] NoOp(“SIP/105-00000002”, “Starting recording check against dontcare”) in new stack
– Executing [recordcheck@sub-record-check:2] Goto(“SIP/105-00000002”, “dontcare”) in new stack
– Goto (sub-record-check,recordcheck,3)
– Executing [recordcheck@sub-record-check:3] Return(“SIP/105-00000002”, “”) in new stack
– Executing [out@sub-record-check:8] Return(“SIP/105-00000002”, “”) in new stack
– Executing [2815551212@restrictedroute-601bcbd12379a3a03bcbad773954109d:3] ExecIf(“SIP/105-00000002”, “0 ?Set(CDR(accountcode)=)”) in new stack
– Executing [2815551212@restrictedroute-601bcbd12379a3a03bcbad773954109d:4] Set(“SIP/105-00000002”, “MOHCLASS=default”) in new stack
– Executing [2815551212@restrictedroute-601bcbd12379a3a03bcbad773954109d:5] Set(“SIP/105-00000002”, “_NODEST=”) in new stack
– Executing [2815551212@restrictedroute-601bcbd12379a3a03bcbad773954109d:6] Macro(“SIP/105-00000002”, “dialout-trunk,2,2815551212,on”) in new stack
– Executing [s@macro-dialout-trunk:1] Set(“SIP/105-00000002”, “DIAL_TRUNK=2”) in new stack
– Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/105-00000002”, “0?sub-pincheck,s,1()”) in new stack
– Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/105-00000002”, “0?disabletrunk,1”) in new stack
– Executing [s@macro-dialout-trunk:4] Set(“SIP/105-00000002”, “DIAL_NUMBER=2815551212”) in new stack
– Executing [s@macro-dialout-trunk:5] Set(“SIP/105-00000002”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
– Executing [s@macro-dialout-trunk:6] Set(“SIP/105-00000002”, “OUTBOUND_GROUP=OUT_2”) in new stack
– Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/105-00000002”, “1?nomax”) in new stack
– Goto (macro-dialout-trunk,s,9)
– Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/105-00000002”, “0?skipoutcid”) in new stack
– Executing [s@macro-dialout-trunk:10] Set(“SIP/105-00000002”, “DIAL_TRUNK_OPTIONS=T”) in new stack
– Executing [s@macro-dialout-trunk:11] Macro(“SIP/105-00000002”, “outbound-callerid,2”) in new stack
– Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/105-00000002”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
– Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/105-00000002”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
– Executing [s@macro-outbound-callerid:3] ExecIf(“SIP/105-00000002”, “0?Set(REALCALLERIDNUM=105)”) in new stack
– Executing [s@macro-outbound-callerid:4] GotoIf(“SIP/105-00000002”, “1?normcid”) in new stack
– Goto (macro-outbound-callerid,s,7)
– Executing [s@macro-outbound-callerid:7] Set(“SIP/105-00000002”, “USEROUTCID=”) in new stack
– Executing [s@macro-outbound-callerid:8] Set(“SIP/105-00000002”, “EMERGENCYCID=”) in new stack
– Executing [s@macro-outbound-callerid:9] Set(“SIP/105-00000002”, “TRUNKOUTCID=281-894-9944”) in new stack
– Executing [s@macro-outbound-callerid:10] GotoIf(“SIP/105-00000002”, “1?trunkcid”) in new stack
– Goto (macro-outbound-callerid,s,15)
– Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/105-00000002”, “1?Set(CALLERID(all)=281-894-9944)”) in new stack
– Executing [s@macro-outbound-callerid:16] ExecIf(“SIP/105-00000002”, “0?Set(CALLERID(all)=)”) in new stack
– Executing [s@macro-outbound-callerid:17] ExecIf(“SIP/105-00000002”, “0?Set(CALLERID(all)=)”) in new stack
– Executing [s@macro-outbound-callerid:18] ExecIf(“SIP/105-00000002”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
– Executing [s@macro-outbound-callerid:19] ExecIf(“SIP/105-00000002”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
– Executing [s@macro-outbound-callerid:20] Set(“SIP/105-00000002”, “CDR(outbound_cnum)=2815551212”) in new stack
– Executing [s@macro-outbound-callerid:21] Set(“SIP/105-00000002”, “CDR(outbound_cnam)=”) in new stack
[2016-10-10 09:10:53] WARNING[1935]: func_cdr.c:377 cdr_write_callback: CDR requires a value (CDR(variable)=value)
– Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/105-00000002”, “0?sub-flp-2,s,1()”) in new stack
– Executing [s@macro-dialout-trunk:13] Set(“SIP/105-00000002”, “OUTNUM=2815551212”) in new stack
– Executing [s@macro-dialout-trunk:14] Set(“SIP/105-00000002”, “custom=SIP/VI-TRUNK-OUT”) in new stack
– Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/105-00000002”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
– Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/105-00000002”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
– Executing [s@macro-dialout-trunk:17] Macro(“SIP/105-00000002”, “dialout-trunk-predial-hook,”) in new stack
– Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/105-00000002”, “”) in new stack
– Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/105-00000002”, “0?bypass,1”) in new stack
– Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/105-00000002”, “1?Set(CONNECTEDLINE(num,i)=2815551212)”) in new stack
– Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/105-00000002”, “1?Set(CONNECTEDLINE(name,i)=CID:2815551212)”) in new stack
– Executing [s@macro-dialout-trunk:21] ExecIf(“SIP/105-00000002”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)2815551212)”) in new stack
– Executing [s@macro-dialout-trunk:22] GotoIf(“SIP/105-00000002”, “0?customtrunk”) in new stack
– Executing [s@macro-dialout-trunk:23] Dial(“SIP/105-00000002”, “SIP/VI-TRUNK-OUT/2815551212,300,T”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called SIP/VI-TRUNK-OUT/2815551212
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [12815551212@from-trunk:1] NoOp(“SIP/VI-TRUNK-OUT-00000004”, “Catch-All DID Match - Found 12815551212 - You probably want a DID for this.”) in new stack
– Executing [12815551212@from-trunk:2] Log(“SIP/VI-TRUNK-OUT-00000004”, “WARNING,Friendly Scanner from 64.136.174.30”) in new stack
[2016-10-10 09:10:54] WARNING[3205][C-00000002]: Ext. 12815551212:2 @ from-trunk: Friendly Scanner from 64.136.174.30
– Executing [12815551212@from-trunk:3] Set(“SIP/VI-TRUNK-OUT-00000004”, “__FROM_DID=12815551212”) in new stack
– Executing [12815551212@from-trunk:4] Goto(“SIP/VI-TRUNK-OUT-00000004”, “ext-did,s,1”) in new stack
– Goto (ext-did,s,1)
– Executing [s@ext-did:1] Set(“SIP/VI-TRUNK-OUT-00000004”, “__DIRECTION=INBOUND”) in new stack
– Executing [s@ext-did:2] Gosub(“SIP/VI-TRUNK-OUT-00000004”, “app-blacklist-check,s,1()”) in new stack
– Executing [s@app-blacklist-check:1] GotoIf(“SIP/VI-TRUNK-OUT-00000004”, “0?blacklisted”) in new stack
– Executing [s@app-blacklist-check:2] Set(“SIP/VI-TRUNK-OUT-00000004”, “CALLED_BLACKLIST=1”) in new stack
– Executing [s@app-blacklist-check:3] Return(“SIP/VI-TRUNK-OUT-00000004”, “”) in new stack
– Executing [s@ext-did:3] ExecIf(“SIP/VI-TRUNK-OUT-00000004”, “0?Set(__FROM_DID=s)”) in new stack
– Executing [s@ext-did:4] Set(“SIP/VI-TRUNK-OUT-00000004”, “CDR(did)=12815551212”) in new stack
– Executing [s@ext-did:5] ExecIf(“SIP/VI-TRUNK-OUT-00000004”, “0 ?Set(CALLERID(name)=12815551212)”) in new stack
– Executing [s@ext-did:6] Set(“SIP/VI-TRUNK-OUT-00000004”, “__MOHCLASS=”) in new stack
– Executing [s@ext-did:7] Set(“SIP/VI-TRUNK-OUT-00000004”, “__REVERSAL_REJECT=FALSE”) in new stack
– Executing [s@ext-did:8] GotoIf(“SIP/VI-TRUNK-OUT-00000004”, “1?post-reverse-charge”) in new stack
– Goto (ext-did,s,10)
– Executing [s@ext-did:10] NoOp(“SIP/VI-TRUNK-OUT-00000004”, “”) in new stack
– Executing [s@ext-did:11] Set(“SIP/VI-TRUNK-OUT-00000004”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
– Executing [s@ext-did:12] Set(“SIP/VI-TRUNK-OUT-00000004”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
– Executing [s@ext-did:13] Set(“SIP/VI-TRUNK-OUT-00000004”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
– Executing [s@ext-did:14] Set(“SIP/VI-TRUNK-OUT-00000004”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
– Executing [s@ext-did:15] NoOp(“SIP/VI-TRUNK-OUT-00000004”, “CallerID Entry Point”) in new stack
– Executing [s@ext-did:16] Goto(“SIP/VI-TRUNK-OUT-00000004”, “ivr-1,s,1”) in new stack
– Goto (ivr-1,s,1)
– Executing [s@ivr-1:1] Set(“SIP/VI-TRUNK-OUT-00000004”, “TIMEOUT_LOOPCOUNT=0”) in new stack
– Executing [s@ivr-1:2] Set(“SIP/VI-TRUNK-OUT-00000004”, “INVALID_LOOPCOUNT=0”) in new stack
– Executing [s@ivr-1:3] Set(“SIP/VI-TRUNK-OUT-00000004”, “_IVR_CONTEXT_ivr-1=”) in new stack
– Executing [s@ivr-1:4] Set(“SIP/VI-TRUNK-OUT-00000004”, “_IVR_CONTEXT=ivr-1”) in new stack
– Executing [s@ivr-1:5] Set(“SIP/VI-TRUNK-OUT-00000004”, “__IVR_RETVM=RETURN”) in new stack
– Executing [s@ivr-1:6] GotoIf(“SIP/VI-TRUNK-OUT-00000004”, “0?skip”) in new stack
– Executing [s@ivr-1:7] Answer(“SIP/VI-TRUNK-OUT-00000004”, “”) in new stack
– SIP/VI-TRUNK-OUT-00000003 answered SIP/105-00000002
– Channel SIP/VI-TRUNK-OUT-00000003 joined ‘simple_bridge’ basic-bridge
– Channel SIP/105-00000002 joined ‘simple_bridge’ basic-bridge
> 0x2ac4690 – Probation passed - setting RTP source address to 10.10.10.120:2246
– Executing [s@ivr-1:8] Wait(“SIP/VI-TRUNK-OUT-00000004”, “1”) in new stack
– Executing [s@ivr-1:9] Set(“SIP/VI-TRUNK-OUT-00000004”, “IVR_MSG=custom/ivr-main_menu”) in new stack
– Executing [s@ivr-1:10] Set(“SIP/VI-TRUNK-OUT-00000004”, “TIMEOUT(digit)=3”) in new stack
– Digit timeout set to 3.000
– Executing [s@ivr-1:11] ExecIf(“SIP/VI-TRUNK-OUT-00000004”, “1?Background(custom/ivr-main_menu)”) in new stack
– <SIP/VI-TRUNK-OUT-00000004> Playing ‘custom/ivr-main_menu.slin’ (language ‘en’)
– Executing [s@ivr-1:12] WaitExten(“SIP/VI-TRUNK-OUT-00000004”, “5,”) in new stack
– Timeout on SIP/VI-TRUNK-OUT-00000004, going to ‘t’
– Executing [t@ivr-1:1] Set(“SIP/VI-TRUNK-OUT-00000004”, “TIMEOUT_LOOPCOUNT=1”) in new stack
– Executing [t@ivr-1:2] GotoIf(“SIP/VI-TRUNK-OUT-00000004”, “0?final”) in new stack
– Executing [t@ivr-1:3] Set(“SIP/VI-TRUNK-OUT-00000004”, “IVR_MSG=custom/ivr-main_menu”) in new stack
– Executing [t@ivr-1:4] Goto(“SIP/VI-TRUNK-OUT-00000004”, “s,start”) in new stack
– Goto (ivr-1,s,10)
– Executing [s@ivr-1:10] Set(“SIP/VI-TRUNK-OUT-00000004”, “TIMEOUT(digit)=3”) in new stack
– Digit timeout set to 3.000
– Executing [s@ivr-1:11] ExecIf(“SIP/VI-TRUNK-OUT-00000004”, “1?Background(custom/ivr-main_menu)”) in new stack
– <SIP/VI-TRUNK-OUT-00000004> Playing ‘custom/ivr-main_menu.slin’ (language ‘en’)
– Executing [s@ivr-1:12] WaitExten(“SIP/VI-TRUNK-OUT-00000004”, “5,”) in new stack
– Timeout on SIP/VI-TRUNK-OUT-00000004, going to ‘t’
– Executing [t@ivr-1:1] Set(“SIP/VI-TRUNK-OUT-00000004”, “TIMEOUT_LOOPCOUNT=2”) in new stack
– Executing [t@ivr-1:2] GotoIf(“SIP/VI-TRUNK-OUT-00000004”, “0?final”) in new stack
– Executing [t@ivr-1:3] Set(“SIP/VI-TRUNK-OUT-00000004”, “IVR_MSG=custom/ivr-main_menu”) in new stack
– Executing [t@ivr-1:4] Goto(“SIP/VI-TRUNK-OUT-00000004”, “s,start”) in new stack
– Goto (ivr-1,s,10)
– Executing [s@ivr-1:10] Set(“SIP/VI-TRUNK-OUT-00000004”, “TIMEOUT(digit)=3”) in new stack
– Digit timeout set to 3.000
– Executing [s@ivr-1:11] ExecIf(“SIP/VI-TRUNK-OUT-00000004”, “1?Background(custom/ivr-main_menu)”) in new stack
– <SIP/VI-TRUNK-OUT-00000004> Playing ‘custom/ivr-main_menu.slin’ (language ‘en’)
– Channel SIP/105-00000002 left ‘simple_bridge’ basic-bridge
– Channel SIP/VI-TRUNK-OUT-00000003 left ‘simple_bridge’ basic-bridge
== Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on ‘SIP/105-00000002’ in macro ‘dialout-trunk’
== Spawn extension (restrictedroute-601bcbd12379a3a03bcbad773954109d, 2815551212, 6) exited non-zero on ‘SIP/105-00000002’
– Executing [h@restrictedroute-601bcbd12379a3a03bcbad773954109d:1] Hangup(“SIP/105-00000002”, “”) in new stack
== Spawn extension (restrictedroute-601bcbd12379a3a03bcbad773954109d, h, 1) exited non-zero on ‘SIP/105-00000002’
== Spawn extension (ivr-1, s, 11) exited non-zero on ‘SIP/VI-TRUNK-OUT-00000004’
– Executing [h@ivr-1:1] Hangup(“SIP/VI-TRUNK-OUT-00000004”, “”) in new stack
== Spawn extension (ivr-1, h, 1) exited non-zero on ‘SIP/VI-TRUNK-OUT-00000004’