Trouble with LD Dialing on dahdi trunk

Chimnysweep · March 10, 2014, 6:12pm

Have an 8 port Rhino analog card in a Ceros box running FreePBX 2.11 and Asterisk (Ver. 11.7.0).

Having an issue with dialing long distance on one particular port. If I dial LD and grab the port (4) as part of the trunk group, the call fails with the local TELCO (ATT) saying I need to dial a 1+area code+number.

If I use a dial plan to grab port 4 to make the call, everything goes fine.

I can make LD calls on all the other ports with no issue (I changed the rotation order of the ports to test each one).

I’ve compared my settings for Trunk (port) 4 to my other trunks and everything looks the same. I can go to my demarc using a butt set and place LD calls with no issue.

Looking at a trace log doesn’t show anything to me, but I’m green at looking at these logs.

In the log below, call 125 was a bad call while call 126 was a good call (I did change the log to hide phone numbers).

Any suggestions on where to look appreciated.

Thanks,
Westley

   > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('CHAN_START',{ts '2014-03-10 12:47:59'},'Username','100','','','','NXXNXX6407','from-internal','SIP/100-00000125','','',3,'','1394473679.496','1394473679.496','','','')]
-- Executing [NXXNXX6407@from-internal:1] Macro("SIP/100-00000125", "user-callerid,LIMIT,EXTERNAL,") in new stack
-- Executing [s@macro-user-callerid:1] Set("SIP/100-00000125", "TOUCH_MONITOR=1394473679.496") in new stack
-- Executing [s@macro-user-callerid:2] Set("SIP/100-00000125", "AMPUSER=100") in new stack
-- Executing [s@macro-user-callerid:3] GotoIf("SIP/100-00000125", "0?report") in new stack
-- Executing [s@macro-user-callerid:4] ExecIf("SIP/100-00000125", "1?Set(REALCALLERIDNUM=100)") in new stack
-- Executing [s@macro-user-callerid:5] Set("SIP/100-00000125", "AMPUSER=100") in new stack
-- Executing [s@macro-user-callerid:6] Set("SIP/100-00000125", "AMPUSERCIDNAME=Username") in new stack
-- Executing [s@macro-user-callerid:7] GotoIf("SIP/100-00000125", "0?report") in new stack
-- Executing [s@macro-user-callerid:8] Set("SIP/100-00000125", "AMPUSERCID=100") in new stack
-- Executing [s@macro-user-callerid:9] Set("SIP/100-00000125", "__DIAL_OPTIONS=Ttr") in new stack
-- Executing [s@macro-user-callerid:10] Set("SIP/100-00000125", "CALLERID(all)="Username" <100>") in new stack
-- Executing [s@macro-user-callerid:11] GotoIf("SIP/100-00000125", "0?limit") in new stack
-- Executing [s@macro-user-callerid:12] ExecIf("SIP/100-00000125", "1?Set(GROUP(concurrency_limit)=100)") in new stack
-- Executing [s@macro-user-callerid:13] ExecIf("SIP/100-00000125", "0?Set(CHANNEL(language)=)") in new stack
-- Executing [s@macro-user-callerid:14] GosubIf("SIP/100-00000125", "7?sub-ccss,s,1(from-internal,NXXNXX6407)") in new stack
-- Executing [s@sub-ccss:1] ExecIf("SIP/100-00000125", "0?Return()") in new stack
-- Executing [s@sub-ccss:2] Set("SIP/100-00000125", "CCSS_SETUP=TRUE") in new stack
-- Executing [s@sub-ccss:3] GosubIf("SIP/100-00000125", "0?monitor_config,1(from-internal,NXXNXX6407):monitor_default,1(from-internal,NXXNXX6407)") in new stack
-- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/100-00000125", "0?is_exten") in new stack
-- Executing [monitor_default@sub-ccss:2] StackPop("SIP/100-00000125", "") in new stack
-- Executing [monitor_default@sub-ccss:3] Return("SIP/100-00000125", "FALSE") in new stack
-- Executing [s@macro-user-callerid:15] GotoIf("SIP/100-00000125", "1?continue") in new stack
-- Goto (macro-user-callerid,s,28)
-- Executing [s@macro-user-callerid:28] Set("SIP/100-00000125", "CALLERID(number)=100") in new stack
-- Executing [s@macro-user-callerid:29] Set("SIP/100-00000125", "CALLERID(name)=Username") in new stack
-- Executing [s@macro-user-callerid:30] Set("SIP/100-00000125", "CDR(cnum)=100") in new stack
-- Executing [s@macro-user-callerid:31] Set("SIP/100-00000125", "CDR(cnam)=Username") in new stack
-- Executing [s@macro-user-callerid:32] Set("SIP/100-00000125", "CHANNEL(language)=en") in new stack
-- Executing [NXXNXX6407@from-internal:2] Set("SIP/100-00000125", "MOHCLASS=default") in new stack
-- Executing [NXXNXX6407@from-internal:3] Set("SIP/100-00000125", "_NODEST=") in new stack
-- Executing [NXXNXX6407@from-internal:4] Gosub("SIP/100-00000125", "sub-record-check,s,1(out,NXXNXX6407,)") in new stack
-- Executing [s@sub-record-check:1] Set("SIP/100-00000125", "REC_POLICY_MODE_SAVE=") in new stack
-- Executing [s@sub-record-check:2] GotoIf("SIP/100-00000125", "1?check") in new stack
-- Goto (sub-record-check,s,7)
-- Executing [s@sub-record-check:7] Set("SIP/100-00000125", "__MON_FMT=wav") in new stack
-- Executing [s@sub-record-check:8] GotoIf("SIP/100-00000125", "1?next") in new stack
-- Goto (sub-record-check,s,11)
-- Executing [s@sub-record-check:11] ExecIf("SIP/100-00000125", "0?Return()") in new stack
-- Executing [s@sub-record-check:12] ExecIf("SIP/100-00000125", "0?Set(__REC_POLICY_MODE=)") in new stack
-- Executing [s@sub-record-check:13] GotoIf("SIP/100-00000125", "0?out,1") in new stack
-- Executing [s@sub-record-check:14] Set("SIP/100-00000125", "__REC_STATUS=INITIALIZED") in new stack
-- Executing [s@sub-record-check:15] Set("SIP/100-00000125", "NOW=1394473679") in new stack
-- Executing [s@sub-record-check:16] Set("SIP/100-00000125", "__DAY=10") in new stack
-- Executing [s@sub-record-check:17] Set("SIP/100-00000125", "__MONTH=03") in new stack
-- Executing [s@sub-record-check:18] Set("SIP/100-00000125", "__YEAR=2014") in new stack
-- Executing [s@sub-record-check:19] Set("SIP/100-00000125", "__TIMESTR=20140310-124759") in new stack
-- Executing [s@sub-record-check:20] Set("SIP/100-00000125", "__FROMEXTEN=100") in new stack
-- Executing [s@sub-record-check:21] Set("SIP/100-00000125", "__CALLFILENAME=out-NXXNXX6407-100-20140310-124759-1394473679.496") in new stack
-- Executing [s@sub-record-check:22] Goto("SIP/100-00000125", "out,1") in new stack
-- Goto (sub-record-check,out,1)
-- Executing [out@sub-record-check:1] ExecIf("SIP/100-00000125", "1?Set(__REC_POLICY_MODE=dontcare)") in new stack
-- Executing [out@sub-record-check:2] GosubIf("SIP/100-00000125", "0?record,1(exten,NXXNXX6407,100)") in new stack
-- Executing [out@sub-record-check:3] Return("SIP/100-00000125", "") in new stack
-- Executing [NXXNXX6407@from-internal:5] Macro("SIP/100-00000125", "dialout-trunk,8,1NXXNXX6407,,on") in new stack
-- Executing [s@macro-dialout-trunk:1] Set("SIP/100-00000125", "DIAL_TRUNK=8") in new stack
-- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/100-00000125", "0?sub-pincheck,s,1()") in new stack
-- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/100-00000125", "0?disabletrunk,1") in new stack
-- Executing [s@macro-dialout-trunk:4] Set("SIP/100-00000125", "DIAL_NUMBER=1NXXNXX6407") in new stack
-- Executing [s@macro-dialout-trunk:5] Set("SIP/100-00000125", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
-- Executing [s@macro-dialout-trunk:6] Set("SIP/100-00000125", "OUTBOUND_GROUP=OUT_8") in new stack
-- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/100-00000125", "0?nomax") in new stack
-- Executing [s@macro-dialout-trunk:8] GotoIf("SIP/100-00000125", "0?chanfull") in new stack
-- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/100-00000125", "0?skipoutcid") in new stack
-- Executing [s@macro-dialout-trunk:10] Set("SIP/100-00000125", "DIAL_TRUNK_OPTIONS=Tt") in new stack
-- Executing [s@macro-dialout-trunk:11] Macro("SIP/100-00000125", "outbound-callerid,8") in new stack
-- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/100-00000125", "0?Set(CALLERPRES()=)") in new stack
-- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/100-00000125", "0?Set(REALCALLERIDNUM=100)") in new stack
-- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/100-00000125", "1?normcid") in new stack
-- Goto (macro-outbound-callerid,s,6)
-- Executing [s@macro-outbound-callerid:6] Set("SIP/100-00000125", "USEROUTCID=") in new stack
-- Executing [s@macro-outbound-callerid:7] Set("SIP/100-00000125", "EMERGENCYCID=") in new stack
-- Executing [s@macro-outbound-callerid:8] Set("SIP/100-00000125", "TRUNKOUTCID=Trunk4") in new stack
-- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/100-00000125", "1?trunkcid") in new stack
-- Goto (macro-outbound-callerid,s,14)
-- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/100-00000125", "1?Set(CALLERID(all)=Trunk4)") in new stack
-- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/100-00000125", "0?Set(CALLERID(all)=)") in new stack
-- Executing [s@macro-outbound-callerid:16] ExecIf("SIP/100-00000125", "0?Set(CALLERID(all)=)") in new stack
-- Executing [s@macro-outbound-callerid:17] ExecIf("SIP/100-00000125", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
-- Executing [s@macro-outbound-callerid:18] Set("SIP/100-00000125", "CDR(outbound_cnum)=Trunk4") in new stack
-- Executing [s@macro-outbound-callerid:19] Set("SIP/100-00000125", "CDR(outbound_cnam)=") in new stack
-- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/100-00000125", "0?sub-flp-8,s,1()") in new stack
-- Executing [s@macro-dialout-trunk:13] Set("SIP/100-00000125", "OUTNUM=1NXXNXX6407") in new stack
-- Executing [s@macro-dialout-trunk:14] Set("SIP/100-00000125", "custom=DAHDI/4") in new stack
-- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/100-00000125", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
-- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/100-00000125", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
-- Executing [s@macro-dialout-trunk:17] Macro("SIP/100-00000125", "dialout-trunk-predial-hook,") in new stack
-- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/100-00000125", "") in new stack
-- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/100-00000125", "0?bypass,1") in new stack
-- Executing [s@macro-dialout-trunk:19] ExecIf("SIP/100-00000125", "1?Set(CONNECTEDLINE(num,i)=1NXXNXX6407)") in new stack
-- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/100-00000125", "1?Set(CONNECTEDLINE(name,i)=CID:Trunk4)") in new stack
-- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/100-00000125", "0?customtrunk") in new stack
-- Executing [s@macro-dialout-trunk:22] Dial("SIP/100-00000125", "DAHDI/4/1NXXNXX6407,300,Tt") in new stack
   > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('CHAN_START',{ts '2014-03-10 12:47:59'},'','','','','','s','from-analog','DAHDI/4-1','','',3,'','1394473679.497','1394473679.496','','','')]
-- Called DAHDI/4/1NXXNXX6407
   > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('ANSWER',{ts '2014-03-10 12:48:02'},'CID:Trunk4','NXXNXX6407','','','','NXXNXX6407','from-analog','DAHDI/4-1','AppDial','(Outgoing Line)',3,'','1394473679.497','1394473679.496','','','')]
-- DAHDI/4-1 answered SIP/100-00000125
   > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('ANSWER',{ts '2014-03-10 12:48:02'},'','Trunk4','100','','NXXNXX6407','s','macro-dialout-trunk','SIP/100-00000125','Dial','DAHDI/4/1NXXNXX6407,300,Tt',3,'','1394473679.496','1394473679.496','','','')]
   > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('BRIDGE_START',{ts '2014-03-10 12:48:02'},'','Trunk4','100','','NXXNXX6407','s','macro-dialout-trunk','SIP/100-00000125','Dial','DAHDI/4/1NXXNXX6407,300,Tt',3,'','1394473679.496','1394473679.496','DAHDI/4-1','','')]
   > 0xb5968f38 -- Probation passed - setting RTP source address to 192.168.1.39:3000
   > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('BRIDGE_END',{ts '2014-03-10 12:48:11'},'','Trunk4','100','','NXXNXX6407','s','macro-dialout-trunk','SIP/100-00000125','Dial','DAHDI/4/1NXXNXX6407,300,Tt',3,'','1394473679.496','1394473679.496','DAHDI/4-1','','')]
-- Executing [h@macro-dialout-trunk:1] Macro("SIP/100-00000125", "hangupcall,") in new stack
-- Executing [s@macro-hangupcall:1] GotoIf("SIP/100-00000125", "1?theend") in new stack
-- Goto (macro-hangupcall,s,3)
-- Executing [s@macro-hangupcall:3] ExecIf("SIP/100-00000125", "0?Set(CDR(recordingfile)=)") in new stack
-- Executing [s@macro-hangupcall:4] Hangup("SIP/100-00000125", "") in new stack

== Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/100-00000125’ in macro ‘hangupcall’
== Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on ‘SIP/100-00000125’
> [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES (‘HANGUP’,{ts ‘2014-03-10 12:48:11’},‘CID:Trunk4’,‘NXXNXX6407’,’’,’’,’’,’’,‘macro-dialout-trunk’,‘DAHDI/4-1’,‘AppDial’,’(Outgoing Line)’,3,’’,‘1394473679.497’,‘1394473679.496’,’’,’’,’’)]
– Hanging up on ‘DAHDI/4-1’
– Hungup ‘DAHDI/4-1’
> [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES (‘CHAN_END’,{ts ‘2014-03-10 12:48:11’},‘CID:Trunk4’,‘NXXNXX6407’,’’,’’,’’,’’,‘macro-dialout-trunk’,‘DAHDI/4-1’,‘AppDial’,’(Outgoing Line)’,3,’’,‘1394473679.497’,‘1394473679.496’,’’,’’,’’)]
== Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘SIP/100-00000125’ in macro ‘dialout-trunk’
== Spawn extension (from-internal, NXXNXX6407, 5) exited non-zero on ‘SIP/100-00000125’
> [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES (‘HANGUP’,{ts ‘2014-03-10 12:48:11’},’’,‘Trunk4’,‘100’,’’,‘NXXNXX6407’,‘NXXNXX6407’,‘from-internal’,‘SIP/100-00000125’,’’,’’,3,’’,‘1394473679.496’,‘1394473679.496’,’’,’’,’’)]
> [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES (‘CHAN_END’,{ts ‘2014-03-10 12:48:11’},’’,‘Trunk4’,‘100’,’’,‘NXXNXX6407’,‘NXXNXX6407’,‘from-internal’,‘SIP/100-00000125’,’’,’’,3,’’,‘1394473679.496’,‘1394473679.496’,’’,’’,’’)]
> [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES (‘LINKEDID_END’,{ts ‘2014-03-10 12:48:11’},’’,‘Trunk4’,‘100’,’’,‘NXXNXX6407’,‘NXXNXX6407’,‘from-internal’,‘SIP/100-00000125’,’’,’’,3,’’,‘1394473679.496’,‘1394473679.496’,’’,’’,’’)]
l

-- Executing [4444NXXNXX6407@from-internal:1] Macro("SIP/100-00000126", "user-callerid,LIMIT,EXTERNAL,") in new stack
-- Executing [s@macro-user-callerid:1] Set("SIP/100-00000126", "TOUCH_MONITOR=1394473859.498") in new stack
-- Executing [s@macro-user-callerid:2] Set("SIP/100-00000126", "AMPUSER=100") in new stack
-- Executing [s@macro-user-callerid:3] GotoIf("SIP/100-00000126", "0?report") in new stack
-- Executing [s@macro-user-callerid:4] ExecIf("SIP/100-00000126", "1?Set(REALCALLERIDNUM=100)") in new stack
-- Executing [s@macro-user-callerid:5] Set("SIP/100-00000126", "AMPUSER=100") in new stack
-- Executing [s@macro-user-callerid:6] Set("SIP/100-00000126", "AMPUSERCIDNAME=Username") in new stack
-- Executing [s@macro-user-callerid:7] GotoIf("SIP/100-00000126", "0?report") in new stack
-- Executing [s@macro-user-callerid:8] Set("SIP/100-00000126", "AMPUSERCID=100") in new stack
-- Executing [s@macro-user-callerid:9] Set("SIP/100-00000126", "__DIAL_OPTIONS=Ttr") in new stack
   > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('CHAN_START',{ts '2014-03-10 12:50:59'},'Username','100','','','','4444NXXNXX6407','from-internal','SIP/100-00000126','','',3,'','1394473859.498','1394473859.498','','','')]
-- Executing [s@macro-user-callerid:10] Set("SIP/100-00000126", "CALLERID(all)="Username" <100>") in new stack
-- Executing [s@macro-user-callerid:11] GotoIf("SIP/100-00000126", "0?limit") in new stack
-- Executing [s@macro-user-callerid:12] ExecIf("SIP/100-00000126", "1?Set(GROUP(concurrency_limit)=100)") in new stack
-- Executing [s@macro-user-callerid:13] ExecIf("SIP/100-00000126", "0?Set(CHANNEL(language)=)") in new stack
-- Executing [s@macro-user-callerid:14] GosubIf("SIP/100-00000126", "7?sub-ccss,s,1(from-internal,4444NXXNXX6407)") in new stack
-- Executing [s@sub-ccss:1] ExecIf("SIP/100-00000126", "0?Return()") in new stack
-- Executing [s@sub-ccss:2] Set("SIP/100-00000126", "CCSS_SETUP=TRUE") in new stack
-- Executing [s@sub-ccss:3] GosubIf("SIP/100-00000126", "0?monitor_config,1(from-internal,4444NXXNXX6407):monitor_default,1(from-internal,4444NXXNXX6407)") in new stack
-- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/100-00000126", "0?is_exten") in new stack
-- Executing [monitor_default@sub-ccss:2] StackPop("SIP/100-00000126", "") in new stack
-- Executing [monitor_default@sub-ccss:3] Return("SIP/100-00000126", "FALSE") in new stack
-- Executing [s@macro-user-callerid:15] GotoIf("SIP/100-00000126", "1?continue") in new stack
-- Goto (macro-user-callerid,s,28)
-- Executing [s@macro-user-callerid:28] Set("SIP/100-00000126", "CALLERID(number)=100") in new stack
-- Executing [s@macro-user-callerid:29] Set("SIP/100-00000126", "CALLERID(name)=Username") in new stack
-- Executing [s@macro-user-callerid:30] Set("SIP/100-00000126", "CDR(cnum)=100") in new stack
-- Executing [s@macro-user-callerid:31] Set("SIP/100-00000126", "CDR(cnam)=Username") in new stack
-- Executing [s@macro-user-callerid:32] Set("SIP/100-00000126", "CHANNEL(language)=en") in new stack
-- Executing [4444NXXNXX6407@from-internal:2] Set("SIP/100-00000126", "MOHCLASS=default") in new stack
-- Executing [4444NXXNXX6407@from-internal:3] Set("SIP/100-00000126", "_NODEST=") in new stack
-- Executing [4444NXXNXX6407@from-internal:4] Gosub("SIP/100-00000126", "sub-record-check,s,1(out,4444NXXNXX6407,)") in new stack
-- Executing [s@sub-record-check:1] Set("SIP/100-00000126", "REC_POLICY_MODE_SAVE=") in new stack
-- Executing [s@sub-record-check:2] GotoIf("SIP/100-00000126", "1?check") in new stack
-- Goto (sub-record-check,s,7)
-- Executing [s@sub-record-check:7] Set("SIP/100-00000126", "__MON_FMT=wav") in new stack
-- Executing [s@sub-record-check:8] GotoIf("SIP/100-00000126", "1?next") in new stack
-- Goto (sub-record-check,s,11)
-- Executing [s@sub-record-check:11] ExecIf("SIP/100-00000126", "0?Return()") in new stack
-- Executing [s@sub-record-check:12] ExecIf("SIP/100-00000126", "0?Set(__REC_POLICY_MODE=)") in new stack
-- Executing [s@sub-record-check:13] GotoIf("SIP/100-00000126", "0?out,1") in new stack
-- Executing [s@sub-record-check:14] Set("SIP/100-00000126", "__REC_STATUS=INITIALIZED") in new stack
-- Executing [s@sub-record-check:15] Set("SIP/100-00000126", "NOW=1394473859") in new stack
-- Executing [s@sub-record-check:16] Set("SIP/100-00000126", "__DAY=10") in new stack
-- Executing [s@sub-record-check:17] Set("SIP/100-00000126", "__MONTH=03") in new stack
-- Executing [s@sub-record-check:18] Set("SIP/100-00000126", "__YEAR=2014") in new stack
-- Executing [s@sub-record-check:19] Set("SIP/100-00000126", "__TIMESTR=20140310-125059") in new stack
-- Executing [s@sub-record-check:20] Set("SIP/100-00000126", "__FROMEXTEN=100") in new stack
-- Executing [s@sub-record-check:21] Set("SIP/100-00000126", "__CALLFILENAME=out-4444NXXNXX6407-100-20140310-125059-1394473859.498") in new stack
-- Executing [s@sub-record-check:22] Goto("SIP/100-00000126", "out,1") in new stack
-- Goto (sub-record-check,out,1)
-- Executing [out@sub-record-check:1] ExecIf("SIP/100-00000126", "1?Set(__REC_POLICY_MODE=dontcare)") in new stack
-- Executing [out@sub-record-check:2] GosubIf("SIP/100-00000126", "0?record,1(exten,4444NXXNXX6407,100)") in new stack
-- Executing [out@sub-record-check:3] Return("SIP/100-00000126", "") in new stack
-- Executing [4444NXXNXX6407@from-internal:5] Macro("SIP/100-00000126", "dialout-trunk,8,1NXXNXX6407,,on") in new stack
-- Executing [s@macro-dialout-trunk:1] Set("SIP/100-00000126", "DIAL_TRUNK=8") in new stack
-- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/100-00000126", "0?sub-pincheck,s,1()") in new stack
-- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/100-00000126", "0?disabletrunk,1") in new stack
-- Executing [s@macro-dialout-trunk:4] Set("SIP/100-00000126", "DIAL_NUMBER=1NXXNXX6407") in new stack
-- Executing [s@macro-dialout-trunk:5] Set("SIP/100-00000126", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
-- Executing [s@macro-dialout-trunk:6] Set("SIP/100-00000126", "OUTBOUND_GROUP=OUT_8") in new stack
-- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/100-00000126", "0?nomax") in new stack
-- Executing [s@macro-dialout-trunk:8] GotoIf("SIP/100-00000126", "0?chanfull") in new stack
-- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/100-00000126", "0?skipoutcid") in new stack
-- Executing [s@macro-dialout-trunk:10] Set("SIP/100-00000126", "DIAL_TRUNK_OPTIONS=Tt") in new stack
-- Executing [s@macro-dialout-trunk:11] Macro("SIP/100-00000126", "outbound-callerid,8") in new stack
-- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/100-00000126", "0?Set(CALLERPRES()=)") in new stack
-- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/100-00000126", "0?Set(REALCALLERIDNUM=100)") in new stack
-- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/100-00000126", "1?normcid") in new stack
-- Goto (macro-outbound-callerid,s,6)
-- Executing [s@macro-outbound-callerid:6] Set("SIP/100-00000126", "USEROUTCID=") in new stack
-- Executing [s@macro-outbound-callerid:7] Set("SIP/100-00000126", "EMERGENCYCID=") in new stack
-- Executing [s@macro-outbound-callerid:8] Set("SIP/100-00000126", "TRUNKOUTCID=Trunk4") in new stack
-- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/100-00000126", "1?trunkcid") in new stack
-- Goto (macro-outbound-callerid,s,14)
-- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/100-00000126", "1?Set(CALLERID(all)=Trunk4)") in new stack
-- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/100-00000126", "0?Set(CALLERID(all)=)") in new stack
-- Executing [s@macro-outbound-callerid:16] ExecIf("SIP/100-00000126", "0?Set(CALLERID(all)=)") in new stack
-- Executing [s@macro-outbound-callerid:17] ExecIf("SIP/100-00000126", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
-- Executing [s@macro-outbound-callerid:18] Set("SIP/100-00000126", "CDR(outbound_cnum)=Trunk4") in new stack
-- Executing [s@macro-outbound-callerid:19] Set("SIP/100-00000126", "CDR(outbound_cnam)=") in new stack
-- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/100-00000126", "0?sub-flp-8,s,1()") in new stack
-- Executing [s@macro-dialout-trunk:13] Set("SIP/100-00000126", "OUTNUM=1NXXNXX6407") in new stack
-- Executing [s@macro-dialout-trunk:14] Set("SIP/100-00000126", "custom=DAHDI/4") in new stack
-- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/100-00000126", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
-- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/100-00000126", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
-- Executing [s@macro-dialout-trunk:17] Macro("SIP/100-00000126", "dialout-trunk-predial-hook,") in new stack
-- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/100-00000126", "") in new stack
-- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/100-00000126", "0?bypass,1") in new stack
-- Executing [s@macro-dialout-trunk:19] ExecIf("SIP/100-00000126", "1?Set(CONNECTEDLINE(num,i)=1NXXNXX6407)") in new stack
-- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/100-00000126", "1?Set(CONNECTEDLINE(name,i)=CID:Trunk4)") in new stack
-- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/100-00000126", "0?customtrunk") in new stack
-- Executing [s@macro-dialout-trunk:22] Dial("SIP/100-00000126", "DAHDI/4/1NXXNXX6407,300,Tt") in new stack
   > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('CHAN_START',{ts '2014-03-10 12:50:59'},'','','','','','s','from-analog','DAHDI/4-1','','',3,'','1394473859.499','1394473859.498','','','')]
-- Called DAHDI/4/1NXXNXX6407
   > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('ANSWER',{ts '2014-03-10 12:51:02'},'CID:Trunk4','4444NXXNXX6407','','','','4444NXXNXX6407','from-analog','DAHDI/4-1','AppDial','(Outgoing Line)',3,'','1394473859.499','1394473859.498','','','')]
-- DAHDI/4-1 answered SIP/100-00000126
   > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('ANSWER',{ts '2014-03-10 12:51:02'},'','Trunk4','100','','4444NXXNXX6407','s','macro-dialout-trunk','SIP/100-00000126','Dial','DAHDI/4/1NXXNXX6407,300,Tt',3,'','1394473859.498','1394473859.498','','','')]
   > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('BRIDGE_START',{ts '2014-03-10 12:51:02'},'','Trunk4','100','','4444NXXNXX6407','s','macro-dialout-trunk','SIP/100-00000126','Dial','DAHDI/4/1NXXNXX6407,300,Tt',3,'','1394473859.498','1394473859.498','DAHDI/4-1','','')]
   > 0xb5975780 -- Probation passed - setting RTP source address to 192.168.1.39:3000
   > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('BRIDGE_END',{ts '2014-03-10 12:51:12'},'','Trunk4','100','','4444NXXNXX6407','s','macro-dialout-trunk','SIP/100-00000126','Dial','DAHDI/4/1NXXNXX6407,300,Tt',3,'','1394473859.498','1394473859.498','DAHDI/4-1','','')]
-- Executing [h@macro-dialout-trunk:1] Macro("SIP/100-00000126", "hangupcall,") in new stack
-- Executing [s@macro-hangupcall:1] GotoIf("SIP/100-00000126", "1?theend") in new stack
-- Goto (macro-hangupcall,s,3)
-- Executing [s@macro-hangupcall:3] ExecIf("SIP/100-00000126", "0?Set(CDR(recordingfile)=)") in new stack
-- Executing [s@macro-hangupcall:4] Hangup("SIP/100-00000126", "") in new stack

== Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/100-00000126’ in macro ‘hangupcall’
== Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on ‘SIP/100-00000126’
> [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES (‘HANGUP’,{ts ‘2014-03-10 12:51:12’},‘CID:Trunk4’,‘4444NXXNXX6407’,’’,’’,’’,’’,‘macro-dialout-trunk’,‘DAHDI/4-1’,‘AppDial’,’(Outgoing Line)’,3,’’,‘1394473859.499’,‘1394473859.498’,’’,’’,’’)]
– Hanging up on ‘DAHDI/4-1’
– Hungup ‘DAHDI/4-1’
> [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES (‘CHAN_END’,{ts ‘2014-03-10 12:51:12’},‘CID:Trunk4’,‘4444NXXNXX6407’,’’,’’,’’,’’,‘macro-dialout-trunk’,‘DAHDI/4-1’,‘AppDial’,’(Outgoing Line)’,3,’’,‘1394473859.499’,‘1394473859.498’,’’,’’,’’)]
== Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘SIP/100-00000126’ in macro ‘dialout-trunk’
== Spawn extension (from-internal, 4444NXXNXX6407, 5) exited non-zero on ‘SIP/100-00000126’
> [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES (‘HANGUP’,{ts ‘2014-03-10 12:51:12’},’’,‘Trunk4’,‘100’,’’,‘4444NXXNXX6407’,‘4444NXXNXX6407’,‘from-internal’,‘SIP/100-00000126’,’’,’’,3,’’,‘1394473859.498’,‘1394473859.498’,’’,’’,’’)]
> [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES (‘CHAN_END’,{ts ‘2014-03-10 12:51:12’},’’,‘Trunk4’,‘100’,’’,‘4444NXXNXX6407’,‘4444NXXNXX6407’,‘from-internal’,‘SIP/100-00000126’,’’,’’,3,’’,‘1394473859.498’,‘1394473859.498’,’’,’’,’’)]
> [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES (‘LINKEDID_END’,{ts ‘2014-03-10 12:51:12’},’’,‘Trunk4’,‘100’,’’,‘4444NXXNXX6407’,‘4444NXXNXX6407’,‘from-internal’,‘SIP/100-00000126’,’’,’’,3,’’,‘1394473859.498’,‘1394473859.498’,’’,’’,’’)]
localhost*CLI>

lgaetz · March 10, 2014, 7:38pm

It is good practice to insert a “w” (wait) prefix for all calls placed on POTS lines. If the PBX dials digits sooner than the POTS line is able to accept them, it will result in exactly what you describe. Use the “Outbound Dial Prefix” in the DAHDI trunk config for this.

Chimnysweep · March 11, 2014, 12:59am

I guess I’m old school and thought it would check for dial tone before trying to make a call out.

All but one of my other FreePBX boxes use SIP trunks and the one that does have an analog trunk never had any issues with dialing out. This particular box is out in the boonies somewhat, so maybe that had something to do with it.

I’ll add the “w” and see how that works out.

I did notice after I placed my original message that even local numbers were starting to have issues when dialed. I’m still not convinced that my card isn’t bad.

Have had an issue with two ports locking the lines offhook. Moved those two lines to the two spare ports I had for testing, but it only happens after about a week, so I’ve got to wait it out.

Thanks!

Chimnysweep · March 11, 2014, 12:59am