SOLVED Everyone is busy/congested at this time

ashcortech · June 8, 2015, 3:49am

FreePBX Distro 6.12.65-27 on a FreePBX Sangoma 50A
Sangoma FXO card
3 analog phone lines from cable company.

This is my first time setting up freePBX with an FXO card and DAHDI (I normally use SIP)

The first outbound call is fine. If we try to make a second outbound call while the first is in progress the second caller gets a “all circuits are busy now…” message.

here’s a log capture of one of these scenarios ext 201 calls NXX8862772 and the call is in process. Ext 202 calls NXX3908475 while call 1 is in process, The second caller attempted two outbound calls to the same number while the original call was ongoing:

[2015-06-07 22:55:49] VERBOSE[15855] chan_sip.c: == Extension Changed auto_hint_202[from-internal] new state InUse for Notify User 202
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [NXX3908475@from-internal:1] Macro(“SIP/202-00000003”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/202-00000003”, “TOUCH_MONITOR=1433732149.7”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:2] Set(“SIP/202-00000003”, “AMPUSER=202”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:3] GotoIf(“SIP/202-00000003”, “0?report”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:4] ExecIf(“SIP/202-00000003”, “1?Set(REALCALLERIDNUM=202)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/202-00000003”, “AMPUSER=202”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/202-00000003”, “0?limit”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:7] Set(“SIP/202-00000003”, “AMPUSERCIDNAME=Susan”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:8] GotoIf(“SIP/202-00000003”, “0?report”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:9] Set(“SIP/202-00000003”, “AMPUSERCID=202”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:10] Set(“SIP/202-00000003”, “__DIAL_OPTIONS=Ttr”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:11] Set(“SIP/202-00000003”, “CALLERID(all)=“Susan” <202>”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:12] GotoIf(“SIP/202-00000003”, “0?limit”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:13] ExecIf(“SIP/202-00000003”, “1?Set(GROUP(concurrency_limit)=202)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:14] GosubIf(“SIP/202-00000003”, “7?sub-ccss,s,1(from-internal,NXX3908475)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-ccss:1] ExecIf(“SIP/202-00000003”, “0?Return()”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-ccss:2] Set(“SIP/202-00000003”, “CCSS_SETUP=TRUE”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-ccss:3] GosubIf(“SIP/202-00000003”, “0?monitor_config,1(from-internal,NXX3908475):monitor_default,1(from-internal,NXX3908475)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [monitor_default@sub-ccss:1] GotoIf(“SIP/202-00000003”, “0?is_exten”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [monitor_default@sub-ccss:2] StackPop(“SIP/202-00000003”, “”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [monitor_default@sub-ccss:3] Return(“SIP/202-00000003”, “FALSE”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:15] ExecIf(“SIP/202-00000003”, “0?Set(CHANNEL(language)=)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:16] GotoIf(“SIP/202-00000003”, “1?continue”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Goto (macro-user-callerid,s,30)
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:30] Set(“SIP/202-00000003”, “CALLERID(number)=202”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:31] Set(“SIP/202-00000003”, “CALLERID(name)=Susan”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:32] Set(“SIP/202-00000003”, “CDR(cnum)=202”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:33] Set(“SIP/202-00000003”, “CDR(cnam)=Susan”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-user-callerid:34] Set(“SIP/202-00000003”, “CHANNEL(language)=en”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [NXX3908475@from-internal:2] Gosub(“SIP/202-00000003”, “sub-record-check,s,1(out,NXX3908475,dontcare)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“SIP/202-00000003”, “0?initialized”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-record-check:2] Set(“SIP/202-00000003”, “__REC_STATUS=INITIALIZED”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-record-check:3] Set(“SIP/202-00000003”, “NOW=1433732149”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-record-check:4] Set(“SIP/202-00000003”, “__DAY=07”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-record-check:5] Set(“SIP/202-00000003”, “__MONTH=06”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-record-check:6] Set(“SIP/202-00000003”, “__YEAR=2015”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-record-check:7] Set(“SIP/202-00000003”, “__TIMESTR=20150607-225549”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-record-check:8] Set(“SIP/202-00000003”, “__FROMEXTEN=202”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-record-check:9] Set(“SIP/202-00000003”, “__MON_FMT=wav”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-record-check:10] NoOp(“SIP/202-00000003”, “Recordings initialized”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-record-check:11] ExecIf(“SIP/202-00000003”, “0?Set(ARG3=dontcare)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-record-check:12] Set(“SIP/202-00000003”, “REC_POLICY_MODE_SAVE=”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-record-check:13] ExecIf(“SIP/202-00000003”, “0?Set(REC_STATUS=NO)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-record-check:14] GotoIf(“SIP/202-00000003”, “3?checkaction”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Goto (sub-record-check,s,17)
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@sub-record-check:17] GotoIf(“SIP/202-00000003”, “1?sub-record-check,out,1”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Goto (sub-record-check,out,1)
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [out@sub-record-check:1] NoOp(“SIP/202-00000003”, “Outbound Recording Check from 202 to NXX3908475”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [out@sub-record-check:2] Set(“SIP/202-00000003”, “RECMODE=dontcare”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [out@sub-record-check:3] ExecIf(“SIP/202-00000003”, “1?Goto(routewins)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Goto (sub-record-check,out,7)
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [out@sub-record-check:7] Gosub(“SIP/202-00000003”, “recordcheck,1(dontcare,out,NXX3908475)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [recordcheck@sub-record-check:1] NoOp(“SIP/202-00000003”, “Starting recording check against dontcare”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [recordcheck@sub-record-check:2] Goto(“SIP/202-00000003”, “dontcare”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Goto (sub-record-check,recordcheck,3)
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [recordcheck@sub-record-check:3] Return(“SIP/202-00000003”, “”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [out@sub-record-check:8] Return(“SIP/202-00000003”, “”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [NXX3908475@from-internal:3] ExecIf(“SIP/202-00000003”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [NXX3908475@from-internal:4] Set(“SIP/202-00000003”, “MOHCLASS=default”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [NXX3908475@from-internal:5] Set(“SIP/202-00000003”, “_NODEST=”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [NXX3908475@from-internal:6] Macro(“SIP/202-00000003”, “dialout-trunk,1,NXX3908475,off”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:1] Set(“SIP/202-00000003”, “DIAL_TRUNK=1”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/202-00000003”, “0?sub-pincheck,s,1()”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/202-00000003”, “0?disabletrunk,1”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:4] Set(“SIP/202-00000003”, “DIAL_NUMBER=NXX3908475”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:5] Set(“SIP/202-00000003”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:6] Set(“SIP/202-00000003”, “OUTBOUND_GROUP=OUT_1”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/202-00000003”, “1?nomax”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Goto (macro-dialout-trunk,s,9)
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/202-00000003”, “0?skipoutcid”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:10] Set(“SIP/202-00000003”, “DIAL_TRUNK_OPTIONS=Tt”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro(“SIP/202-00000003”, “outbound-callerid,1”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/202-00000003”, “0?Set(CALLERPRES()=)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/202-00000003”, “0?Set(REALCALLERIDNUM=202)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“SIP/202-00000003”, “1?normcid”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Goto (macro-outbound-callerid,s,6)
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outbound-callerid:6] Set(“SIP/202-00000003”, “USEROUTCID=”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outbound-callerid:7] Set(“SIP/202-00000003”, “EMERGENCYCID=”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outbound-callerid:8] Set(“SIP/202-00000003”, “TRUNKOUTCID=NXXNXX8063”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“SIP/202-00000003”, “1?trunkcid”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Goto (macro-outbound-callerid,s,14)
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“SIP/202-00000003”, “1?Set(CALLERID(all)=NXXNXX8063)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/202-00000003”, “0?Set(CALLERID(all)=)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outbound-callerid:16] ExecIf(“SIP/202-00000003”, “0?Set(CALLERID(all)=)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outbound-callerid:17] ExecIf(“SIP/202-00000003”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outbound-callerid:18] Set(“SIP/202-00000003”, “CDR(outbound_cnum)=NXXNXX8063”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outbound-callerid:19] Set(“SIP/202-00000003”, “CDR(outbound_cnam)=”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/202-00000003”, “0?sub-flp-1,s,1()”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“SIP/202-00000003”, “OUTNUM=NXX3908475”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“SIP/202-00000003”, “custom=DAHDI/g1”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/202-00000003”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/202-00000003”, “0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:17] Macro(“SIP/202-00000003”, “dialout-trunk-predial-hook,”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/202-00000003”, “”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/202-00000003”, “0?bypass,1”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/202-00000003”, “1?Set(CONNECTEDLINE(num,i)=NXX3908475)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/202-00000003”, “1?Set(CONNECTEDLINE(name,i)=CID:NXXNXX8063)”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:21] GotoIf(“SIP/202-00000003”, “0?customtrunk”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:22] Dial(“SIP/202-00000003”, “DAHDI/g1/NXX3908475,300,Tt”) in new stack
[2015-06-07 22:55:49] VERBOSE[16253][C-00000006] app_dial.c: – Called DAHDI/g1/NXX3908475
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] sig_analog.c: – Hanging up on ‘DAHDI/2-1’
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] chan_dahdi.c: – Hungup ‘DAHDI/2-1’
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1)
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:23] NoOp(“SIP/202-00000003”, “Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 0”) in new stack
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-dialout-trunk:24] GotoIf(“SIP/202-00000003”, “0?continue,1:s-CHANUNAVAIL,1”) in new stack
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] pbx.c: – Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] pbx.c: – Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set(“SIP/202-00000003”, “RC=0”) in new stack
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] pbx.c: – Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto(“SIP/202-00000003”, “0,1”) in new stack
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] pbx.c: – Goto (macro-dialout-trunk,0,1)
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] pbx.c: – Executing [0@macro-dialout-trunk:1] Goto(“SIP/202-00000003”, “continue,1”) in new stack
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] pbx.c: – Goto (macro-dialout-trunk,continue,1)
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] pbx.c: – Executing [continue@macro-dialout-trunk:1] NoOp(“SIP/202-00000003”, “TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 0 - failing through to other trunks”) in new stack
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] pbx.c: – Executing [continue@macro-dialout-trunk:2] Set(“SIP/202-00000003”, “CALLERID(number)=202”) in new stack
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] pbx.c: – Executing [NXX3908475@from-internal:7] Macro(“SIP/202-00000003”, “outisbusy,”) in new stack
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outisbusy:1] Progress(“SIP/202-00000003”, “”) in new stack
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outisbusy:2] GotoIf(“SIP/202-00000003”, “0?emergency,1”) in new stack
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outisbusy:3] GotoIf(“SIP/202-00000003”, “0?intracompany,1”) in new stack
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] pbx.c: – Executing [s@macro-outisbusy:4] Playback(“SIP/202-00000003”, “all-circuits-busy-now&pls-try-call-later, noanswer”) in new stack
[2015-06-07 22:55:50] VERBOSE[16253][C-00000006] file.c: – <SIP/202-00000003> Playing ‘all-circuits-busy-now.ulaw’ (language ‘en’)
[2015-06-07 22:55:52] VERBOSE[16253][C-00000006] file.c: – <SIP/202-00000003> Playing ‘pls-try-call-later.ulaw’ (language ‘en’)
[2015-06-07 22:55:54] VERBOSE[16253][C-00000006] app_macro.c: == Spawn extension (macro-outisbusy, s, 4) exited non-zero on ‘SIP/202-00000003’ in macro ‘outisbusy’
[2015-06-07 22:55:54] VERBOSE[15855] chan_sip.c: == Extension Changed auto_hint_202[from-internal] new state Idle for Notify User 202
[2015-06-07 22:55:54] VERBOSE[16253][C-00000006] pbx.c: == Spawn extension (from-internal, NXX3908475, 7) exited non-zero on ‘SIP/202-00000003’
[2015-06-07 22:55:54] VERBOSE[16253][C-00000006] pbx.c: – Executing [h@from-internal:1] Hangup(“SIP/202-00000003”, “”) in new stack
[2015-06-07 22:55:54] VERBOSE[16253][C-00000006] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/202-00000003’
[2015-06-07 22:56:00] VERBOSE[15868][C-00000009] netsock2.c: == Using SIP RTP TOS bits 184
[2015-06-07 22:56:00] VERBOSE[15868][C-00000009] netsock2.c: == Using SIP RTP CoS mark 5

dicko · June 8, 2015, 5:00am

How have you grouped your lines into trunks, and what trunkgroups do your outbound routes use?

you only paste the call ID’s by [16253] so the first call is unavailable, but that call is using group g1, which takes quite some effort if you did only the basics

ashcortech · June 8, 2015, 11:39am

The forum would only allow so much so I had to trim the log down a bit, the logs from the first call is below.

Originally everything was in group g0 but had found a reference to the same issue somewhere in the forum saying that the interface didn’t correctly update the conf files with the group reference and that changing the group in the dahdi config forced a correction. So I tried it. That’s how I’ve got g1 as the group.

It obviously didn’t work. I have a single trunk with DAHDI g0. The Outbound route used the g0 (or g1 at the time of the test call). Unfortunately I’m not on site with the system right now and don’t have remote access at the moment. When I get onsite I can provide more exact information about what settings I have.

[2015-06-07 22:55:45] VERBOSE[15868][C-00000005] netsock2.c: == Using SIP RTP TOS bits 184
[2015-06-07 22:55:45] VERBOSE[15868][C-00000005] netsock2.c: == Using SIP RTP CoS mark 5
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [NXX8862772@from-internal:1] Macro(“SIP/201-00000002”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/201-00000002”, “TOUCH_MONITOR=1433732145.5”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:2] Set(“SIP/201-00000002”, “AMPUSER=201”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:3] GotoIf(“SIP/201-00000002”, “0?report”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:4] ExecIf(“SIP/201-00000002”, “1?Set(REALCALLERIDNUM=201)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/201-00000002”, “AMPUSER=201”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/201-00000002”, “0?limit”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:7] Set(“SIP/201-00000002”, “AMPUSERCIDNAME=Jeff”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:8] GotoIf(“SIP/201-00000002”, “0?report”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:9] Set(“SIP/201-00000002”, “AMPUSERCID=201”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:10] Set(“SIP/201-00000002”, “__DIAL_OPTIONS=Ttr”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:11] Set(“SIP/201-00000002”, “CALLERID(all)=“Jeff” <201>”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:12] GotoIf(“SIP/201-00000002”, “0?limit”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:13] ExecIf(“SIP/201-00000002”, “1?Set(GROUP(concurrency_limit)=201)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:14] GosubIf(“SIP/201-00000002”, “7?sub-ccss,s,1(from-internal,NXX8862772)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-ccss:1] ExecIf(“SIP/201-00000002”, “0?Return()”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-ccss:2] Set(“SIP/201-00000002”, “CCSS_SETUP=TRUE”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-ccss:3] GosubIf(“SIP/201-00000002”, “0?monitor_config,1(from-internal,NXX8862772):monitor_default,1(from-internal,NXX8862772)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [monitor_default@sub-ccss:1] GotoIf(“SIP/201-00000002”, “0?is_exten”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [monitor_default@sub-ccss:2] StackPop(“SIP/201-00000002”, “”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [monitor_default@sub-ccss:3] Return(“SIP/201-00000002”, “FALSE”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:15] ExecIf(“SIP/201-00000002”, “0?Set(CHANNEL(language)=)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:16] GotoIf(“SIP/201-00000002”, “1?continue”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Goto (macro-user-callerid,s,30)
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:30] Set(“SIP/201-00000002”, “CALLERID(number)=201”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:31] Set(“SIP/201-00000002”, “CALLERID(name)=Jeff”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:32] Set(“SIP/201-00000002”, “CDR(cnum)=201”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:33] Set(“SIP/201-00000002”, “CDR(cnam)=Jeff”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-user-callerid:34] Set(“SIP/201-00000002”, “CHANNEL(language)=en”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [NXX8862772@from-internal:2] Gosub(“SIP/201-00000002”, “sub-record-check,s,1(out,NXX8862772,dontcare)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“SIP/201-00000002”, “0?initialized”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-record-check:2] Set(“SIP/201-00000002”, “__REC_STATUS=INITIALIZED”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-record-check:3] Set(“SIP/201-00000002”, “NOW=1433732145”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-record-check:4] Set(“SIP/201-00000002”, “__DAY=07”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-record-check:5] Set(“SIP/201-00000002”, “__MONTH=06”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-record-check:6] Set(“SIP/201-00000002”, “__YEAR=2015”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-record-check:7] Set(“SIP/201-00000002”, “__TIMESTR=20150607-225545”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-record-check:8] Set(“SIP/201-00000002”, “__FROMEXTEN=201”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-record-check:9] Set(“SIP/201-00000002”, “__MON_FMT=wav”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-record-check:10] NoOp(“SIP/201-00000002”, “Recordings initialized”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-record-check:11] ExecIf(“SIP/201-00000002”, “0?Set(ARG3=dontcare)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-record-check:12] Set(“SIP/201-00000002”, “REC_POLICY_MODE_SAVE=”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-record-check:13] ExecIf(“SIP/201-00000002”, “0?Set(REC_STATUS=NO)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-record-check:14] GotoIf(“SIP/201-00000002”, “3?checkaction”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Goto (sub-record-check,s,17)
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@sub-record-check:17] GotoIf(“SIP/201-00000002”, “1?sub-record-check,out,1”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Goto (sub-record-check,out,1)
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [out@sub-record-check:1] NoOp(“SIP/201-00000002”, “Outbound Recording Check from 201 to NXX8862772”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [out@sub-record-check:2] Set(“SIP/201-00000002”, “RECMODE=dontcare”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [out@sub-record-check:3] ExecIf(“SIP/201-00000002”, “1?Goto(routewins)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Goto (sub-record-check,out,7)
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [out@sub-record-check:7] Gosub(“SIP/201-00000002”, “recordcheck,1(dontcare,out,NXX8862772)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [recordcheck@sub-record-check:1] NoOp(“SIP/201-00000002”, “Starting recording check against dontcare”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [recordcheck@sub-record-check:2] Goto(“SIP/201-00000002”, “dontcare”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Goto (sub-record-check,recordcheck,3)
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [recordcheck@sub-record-check:3] Return(“SIP/201-00000002”, “”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [out@sub-record-check:8] Return(“SIP/201-00000002”, “”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [NXX8862772@from-internal:3] ExecIf(“SIP/201-00000002”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [NXX8862772@from-internal:4] Set(“SIP/201-00000002”, “MOHCLASS=default”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [NXX8862772@from-internal:5] Set(“SIP/201-00000002”, “_NODEST=”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [NXX8862772@from-internal:6] Macro(“SIP/201-00000002”, “dialout-trunk,1,NXX8862772,off”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:1] Set(“SIP/201-00000002”, “DIAL_TRUNK=1”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/201-00000002”, “0?sub-pincheck,s,1()”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/201-00000002”, “0?disabletrunk,1”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:4] Set(“SIP/201-00000002”, “DIAL_NUMBER=NXX8862772”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:5] Set(“SIP/201-00000002”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:6] Set(“SIP/201-00000002”, “OUTBOUND_GROUP=OUT_1”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/201-00000002”, “1?nomax”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Goto (macro-dialout-trunk,s,9)
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/201-00000002”, “0?skipoutcid”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:10] Set(“SIP/201-00000002”, “DIAL_TRUNK_OPTIONS=Tt”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro(“SIP/201-00000002”, “outbound-callerid,1”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/201-00000002”, “0?Set(CALLERPRES()=)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/201-00000002”, “0?Set(REALCALLERIDNUM=201)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“SIP/201-00000002”, “1?normcid”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Goto (macro-outbound-callerid,s,6)
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-outbound-callerid:6] Set(“SIP/201-00000002”, “USEROUTCID=”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-outbound-callerid:7] Set(“SIP/201-00000002”, “EMERGENCYCID=”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-outbound-callerid:8] Set(“SIP/201-00000002”, “TRUNKOUTCID=NXXNXX8063”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“SIP/201-00000002”, “1?trunkcid”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Goto (macro-outbound-callerid,s,14)
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“SIP/201-00000002”, “1?Set(CALLERID(all)=NXXNXX8063)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/201-00000002”, “0?Set(CALLERID(all)=)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-outbound-callerid:16] ExecIf(“SIP/201-00000002”, “0?Set(CALLERID(all)=)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-outbound-callerid:17] ExecIf(“SIP/201-00000002”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-outbound-callerid:18] Set(“SIP/201-00000002”, “CDR(outbound_cnum)=NXXNXX8063”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-outbound-callerid:19] Set(“SIP/201-00000002”, “CDR(outbound_cnam)=”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/201-00000002”, “0?sub-flp-1,s,1()”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“SIP/201-00000002”, “OUTNUM=NXX8862772”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“SIP/201-00000002”, “custom=DAHDI/g1”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/201-00000002”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/201-00000002”, “0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:17] Macro(“SIP/201-00000002”, “dialout-trunk-predial-hook,”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/201-00000002”, “”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/201-00000002”, “0?bypass,1”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/201-00000002”, “1?Set(CONNECTEDLINE(num,i)=NXX8862772)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/201-00000002”, “1?Set(CONNECTEDLINE(name,i)=CID:NXXNXX8063)”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:21] GotoIf(“SIP/201-00000002”, “0?customtrunk”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] pbx.c: – Executing [s@macro-dialout-trunk:22] Dial(“SIP/201-00000002”, “DAHDI/g1/NXX8862772,300,Tt”) in new stack
[2015-06-07 22:55:45] VERBOSE[16244][C-00000005] app_dial.c: – Called DAHDI/g1/NXX8862772
[2015-06-07 22:55:46] VERBOSE[15850][C-00000004] pbx.c: – Executing [proxy@dpma_message_context:1] Set(“Message/ast_msg_queue”, “MESSAGE(custom_data)=mark_all_outbound”) in new stack
[2015-06-07 22:55:46] VERBOSE[15850][C-00000004] pbx.c: – Executing [proxy@dpma_message_context:2] Set(“Message/ast_msg_queue”, “MESSAGE_DATA(X-Digium-AppServer-Response-URI)=sip:192.168.1.134:5060”) in new stack
[2015-06-07 22:55:46] VERBOSE[15850][C-00000004] pbx.c: – Executing [proxy@dpma_message_context:3] Set(“Message/ast_msg_queue”, “MESSAGE_DATA(X-Digium-AppServer-Response-FullContact)=sip:192.168.1.134:5060;ob”) in new stack
[2015-06-07 22:55:46] VERBOSE[15850][C-00000004] pbx.c: – Executing [proxy@dpma_message_context:4] MessageSend(“Message/ast_msg_queue”, “digium_phone:blah”) in new stack
[2015-06-07 22:55:46] VERBOSE[15850][C-00000004] pbx.c: – Executing [proxy@dpma_message_context:5] Hangup(“Message/ast_msg_queue”, “”) in new stack
[2015-06-07 22:55:46] VERBOSE[15850][C-00000004] pbx.c: == Spawn extension (dpma_message_context, proxy, 5) exited non-zero on ‘Message/ast_msg_queue’
[2015-06-07 22:55:46] VERBOSE[15850][C-00000004] pbx.c: – Executing [digium_phone_module@dpma_message_context:1] Set(“Message/ast_msg_queue”, “MESSAGE(custom_data)=mark_all_outbound”) in new stack
[2015-06-07 22:55:46] VERBOSE[15850][C-00000004] pbx.c: – Executing [digium_phone_module@dpma_message_context:2] Set(“Message/ast_msg_queue”, “TMP_RESPONSE_URI=sip:192.168.1.134:5060”) in new stack
[2015-06-07 22:55:46] VERBOSE[15850][C-00000004] pbx.c: – Executing [digium_phone_module@dpma_message_context:3] Set(“Message/ast_msg_queue”, “MESSAGE_DATA(Request-URI)=sip:192.168.1.134:5060;ob”) in new stack
[2015-06-07 22:55:46] VERBOSE[15850][C-00000004] pbx.c: – Executing [digium_phone_module@dpma_message_context:4] Set(“Message/ast_msg_queue”, “MESSAGE_DATA(X-Digium-AppServer-Response-URI)=”) in new stack
[2015-06-07 22:55:46] VERBOSE[15850][C-00000004] pbx.c: – Executing [digium_phone_module@dpma_message_context:5] Set(“Message/ast_msg_queue”, “MESSAGE_DATA(X-Digium-AppServer-Response-FullContact)=”) in new stack
[2015-06-07 22:55:46] VERBOSE[15850][C-00000004] pbx.c: – Executing [digium_phone_module@dpma_message_context:6] MessageSend(“Message/ast_msg_queue”, “sip:192.168.1.134:5060,proxy”) in new stack
[2015-06-07 22:55:46] VERBOSE[15850][C-00000004] pbx.c: – Executing [digium_phone_module@dpma_message_context:7] Hangup(“Message/ast_msg_queue”, “”) in new stack
[2015-06-07 22:55:46] VERBOSE[15850][C-00000004] pbx.c: == Spawn extension (dpma_message_context, digium_phone_module, 7) exited non-zero on ‘Message/ast_msg_queue’
[2015-06-07 22:55:48] VERBOSE[16244][C-00000005] app_dial.c: – DAHDI/1-1 answered SIP/201-00000002
[2015-06-07 22:55:49] VERBOSE[15868][C-00000006] netsock2.c: == Using SIP RTP TOS bits 184
[2015-06-07 22:55:49] VERBOSE[15868][C-00000006] netsock2.c: == Using SIP RTP CoS mark 5

ashcortech · June 8, 2015, 12:37pm

More Info:

trunk:
Name: DAHDi g0
DAHDi Trunks: Group1 Ascending

DAHDi Config:
FXO Ports 1,2,3,4

Port Settings (All ports):
Signaling: Kewl Start
Group: 1
Context: from-analog

Outbound route (all dail patters are good):
Trunk Sequence for Matched Routes: DAHDi g0
Optional Destination on Congestion: Normal Congestion

dicko · June 8, 2015, 2:45pm

Im sorry but I don"t use the “helper” interface. You will have to untangle that in the various /etc/asterisk/dahdi files. It generates.

When you get it figured out use G0 or G1 (hunt descending) if your indound are hunt ascending to reduce glare.

ashcortech · June 8, 2015, 9:02pm

well FreePBX states pretty clearly that you should use the interface and not edit the conf files directly unless instructed to so I try to stick to that. Not that I have an issue with the CLI or editing confs.

As it turns out my settings were fine (verified by FreePBX support), it wasn’t the phone system at all it was the Cablevision Arris cable modem’s phone ports.

The modem has 4 voice ports and can be configured with up to 4 DIDs. Either one DID per port (as in this case) or DIDs 1&2 on the two pairs in port 1 and DIDs 3&4 on the two pairs of port 3.

The fun starts when you have one DID per port and then plug in a two pair cord into ports 1 and/or 3 from the phone system. When you do, for some reason it shorts out port 2 (and/or 4) on the modem causing those lines to become inoperable. Even though the second pair isn’t used on the cord plugged into port 1 or 3, the modem still shorts out port 2 and/or 4.

once this was verified it was an easy fix of just creating a two port phone jack for lines 1 and 3 from the phone system to plug into and then running single pair patches to the modem ports from there.

dicko · June 8, 2015, 9:18pm

Always good to RTFM’s , now fix your awaiting “glare” problem with your defined groups or plug in your lines in reverse order.

ashcortech · June 8, 2015, 10:03pm

of course, only this “feature” wasn’t in the manual or so claims the tech. The cable companies don’t usually give a technical manual with the equipment. The cable company tech just happened to have seen this situation once before with another Arris modem.

The outbound hunt is the reversed of the inbound so glare shouldn’t be an issue or at least should be rare. It’s not a big office.