Outbound CallerID only showing main number on PRI

On each extension I’ve setup the outbound CID to match the extension DID but when calls are placed, only the main PRI number is displayed.

I’ve tried different combinations of Outbound CID,

xxxxxxxxxx
and
“name”

I’ve been corresponding with Verizon, my PRI provider and they stated:

"Verizon provisioning verified call party screen list individual DID display on outbound calls.

The customer equipment must match the calling party number screening table list to send out 10 digit dialing. "

Here is the log from a test extension, 5600 to a test google number. I’m sure I’m missing something simple just unsure what i’m doing wrong.

[2013-10-17 18:35:23] VERBOSE[9138][C-000001c1] netsock2.c: == Using SIP RTP TOS bits 184
[2013-10-17 18:35:23] VERBOSE[9138][C-000001c1] netsock2.c: == Using SIP RTP CoS mark 5
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [9562780798@from-internal:1] Macro(“SIP/5600-00000281”, “user-callerid,LIMIT”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/5600-00000281”, “TOUCH_MONITOR=1382052923.1334”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:2] Set(“SIP/5600-00000281”, “AMPUSER=5600”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:3] GotoIf(“SIP/5600-00000281”, “0?report”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:4] ExecIf(“SIP/5600-00000281”, “1?Set(REALCALLERIDNUM=5600)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/5600-00000281”, “AMPUSER=5600”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:6] Set(“SIP/5600-00000281”, “AMPUSERCIDNAME=Test”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:7] GotoIf(“SIP/5600-00000281”, “0?report”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:8] Set(“SIP/5600-00000281”, “AMPUSERCID=5600”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:9] Set(“SIP/5600-00000281”, “__DIAL_OPTIONS=Ttr”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:10] Set(“SIP/5600-00000281”, “CALLERID(all)=“Test” <5600>”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:11] GotoIf(“SIP/5600-00000281”, “0?limit”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:12] ExecIf(“SIP/5600-00000281”, “1?Set(GROUP(concurrency_limit)=5600)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:13] ExecIf(“SIP/5600-00000281”, “0?Set(CHANNEL(language)=)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:14] GosubIf(“SIP/5600-00000281”, “7?sub-ccss,s,1(from-internal,9562780798)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-ccss:1] ExecIf(“SIP/5600-00000281”, “0?Return()”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-ccss:2] Set(“SIP/5600-00000281”, “CCSS_SETUP=TRUE”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-ccss:3] GosubIf(“SIP/5600-00000281”, “0?monitor_config,1(from-internal,9562780798):monitor_default,1(from-internal,9562780798)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [monitor_default@sub-ccss:1] GotoIf(“SIP/5600-00000281”, “0?is_exten”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [monitor_default@sub-ccss:2] StackPop(“SIP/5600-00000281”, “”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [monitor_default@sub-ccss:3] Return(“SIP/5600-00000281”, “FALSE”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:15] GotoIf(“SIP/5600-00000281”, “1?continue”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Goto (macro-user-callerid,s,28)
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:28] Set(“SIP/5600-00000281”, “CALLERID(number)=5600”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:29] Set(“SIP/5600-00000281”, “CALLERID(name)=Test”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:30] Set(“SIP/5600-00000281”, “CDR(cnum)=5600”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:31] Set(“SIP/5600-00000281”, “CDR(cnam)=Test”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-user-callerid:32] Set(“SIP/5600-00000281”, “CHANNEL(language)=en”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [9562780798@from-internal:2] Set(“SIP/5600-00000281”, “ROUTEUSER=5600”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [9562780798@from-internal:3] GotoIf(“SIP/5600-00000281”, “0?,9562780798,2:outbound-allroutes,9562780798,2”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Goto (outbound-allroutes,9562780798,2)
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [9562780798@outbound-allroutes:2] Set(“SIP/5600-00000281”, “MOHCLASS=default”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [9562780798@outbound-allroutes:3] Set(“SIP/5600-00000281”, “_NODEST=”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [9562780798@outbound-allroutes:4] Gosub(“SIP/5600-00000281”, “sub-record-check,s,1(out,9562780798,)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-record-check:1] Set(“SIP/5600-00000281”, “REC_POLICY_MODE_SAVE=”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-record-check:2] GotoIf(“SIP/5600-00000281”, “1?check”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Goto (sub-record-check,s,7)
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-record-check:7] Set(“SIP/5600-00000281”, “__MON_FMT=wav”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-record-check:8] GotoIf(“SIP/5600-00000281”, “1?next”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Goto (sub-record-check,s,11)
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-record-check:11] ExecIf(“SIP/5600-00000281”, “0?Return()”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-record-check:12] ExecIf(“SIP/5600-00000281”, “0?Set(__REC_POLICY_MODE=)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-record-check:13] GotoIf(“SIP/5600-00000281”, “0?out,1”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-record-check:14] Set(“SIP/5600-00000281”, “__REC_STATUS=INITIALIZED”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-record-check:15] Set(“SIP/5600-00000281”, “NOW=1382052923”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-record-check:16] Set(“SIP/5600-00000281”, “__DAY=17”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-record-check:17] Set(“SIP/5600-00000281”, “__MONTH=10”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-record-check:18] Set(“SIP/5600-00000281”, “__YEAR=2013”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-record-check:19] Set(“SIP/5600-00000281”, “__TIMESTR=20131017-183523”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-record-check:20] Set(“SIP/5600-00000281”, “__FROMEXTEN=5600”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-record-check:21] Set(“SIP/5600-00000281”, “__CALLFILENAME=out-9562780798-5600-20131017-183523-1382052923.1334”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@sub-record-check:22] Goto(“SIP/5600-00000281”, “out,1”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Goto (sub-record-check,out,1)
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [out@sub-record-check:1] ExecIf(“SIP/5600-00000281”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [out@sub-record-check:2] GosubIf(“SIP/5600-00000281”, “0?record,1(exten,9562780798,5600)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [out@sub-record-check:3] Return(“SIP/5600-00000281”, “”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [9562780798@outbound-allroutes:5] Macro(“SIP/5600-00000281”, “dialout-trunk,2,9562780798,off”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:1] Set(“SIP/5600-00000281”, “DIAL_TRUNK=2”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/5600-00000281”, “0?sub-pincheck,s,1()”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/5600-00000281”, “0?disabletrunk,1”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:4] Set(“SIP/5600-00000281”, “DIAL_NUMBER=9562780798”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:5] Set(“SIP/5600-00000281”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:6] Set(“SIP/5600-00000281”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/5600-00000281”, “1?nomax”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Goto (macro-dialout-trunk,s,9)
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/5600-00000281”, “0?skipoutcid”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:10] Set(“SIP/5600-00000281”, “DIAL_TRUNK_OPTIONS=Tt”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro(“SIP/5600-00000281”, “outbound-callerid,2”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/5600-00000281”, “0?Set(CALLERPRES()=)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/5600-00000281”, “0?Set(REALCALLERIDNUM=5600)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“SIP/5600-00000281”, “1?normcid”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Goto (macro-outbound-callerid,s,6)
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-outbound-callerid:6] Set(“SIP/5600-00000281”, “USEROUTCID=<9569695600>”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-outbound-callerid:7] Set(“SIP/5600-00000281”, “EMERGENCYCID=”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-outbound-callerid:8] Set(“SIP/5600-00000281”, “TRUNKOUTCID=<9569685581>”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“SIP/5600-00000281”, “1?trunkcid”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Goto (macro-outbound-callerid,s,14)
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“SIP/5600-00000281”, “1?Set(CALLERID(all)=<9569685581>)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/5600-00000281”, “1?Set(CALLERID(all)=<9569695600>)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-outbound-callerid:16] ExecIf(“SIP/5600-00000281”, “0?Set(CALLERID(all)=)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-outbound-callerid:17] ExecIf(“SIP/5600-00000281”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-outbound-callerid:18] Set(“SIP/5600-00000281”, “CDR(outbound_cnum)=9569695600”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-outbound-callerid:19] Set(“SIP/5600-00000281”, “CDR(outbound_cnam)=”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/5600-00000281”, “0?sub-flp-2,s,1()”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“SIP/5600-00000281”, “OUTNUM=9562780798”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“SIP/5600-00000281”, “custom=DAHDI/r1”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/5600-00000281”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/5600-00000281”, “0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:17] Macro(“SIP/5600-00000281”, “dialout-trunk-predial-hook,”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/5600-00000281”, “”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/5600-00000281”, “0?bypass,1”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/5600-00000281”, “1?Set(CONNECTEDLINE(num,i)=9562780798)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/5600-00000281”, “1?Set(CONNECTEDLINE(name,i)=CID:9569695600)”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:21] GotoIf(“SIP/5600-00000281”, “0?customtrunk”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:22] Dial(“SIP/5600-00000281”, “DAHDI/r1/9562780798,300,Tt”) in new stack
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] sig_pri.c: – Requested transfer capability: 0x00 - SPEECH
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] app_dial.c: – Called DAHDI/r1/9562780798
[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] app_dial.c: – DAHDI/i1/9562780798-136 is proceeding passing it to SIP/5600-00000281
[2013-10-17 18:35:25] VERBOSE[18378][C-000001c1] app_dial.c: – DAHDI/i1/9562780798-136 is making progress passing it to SIP/5600-00000281
[2013-10-17 18:35:35] VERBOSE[18378][C-000001c1] chan_dahdi.c: – Hungup ‘DAHDI/i1/9562780798-136’
[2013-10-17 18:35:35] VERBOSE[18378][C-000001c1] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘SIP/5600-00000281’ in macro ‘dialout-trunk’
[2013-10-17 18:35:35] VERBOSE[18378][C-000001c1] pbx.c: == Spawn extension (outbound-allroutes, 9562780798, 5) exited non-zero on ‘SIP/5600-00000281’

I doubt that your proposed CallerID, “5600” will meet Verizon’s “. . calling party number screening table list. . to send out 10 digit dialing.”, How are you attempting to set that ( callerid(number) )?, it must be 10 digits and it must lie within what the carrier will accept on your account . . .

Under Applications, Extensions, and then Outbound CID i’m using <9569695600>

is it only passing along 5600?

I reread your post more fully:-

[2013-10-17 18:35:23] VERBOSE[18378][C-000001c1] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/5600-00000281”, “1?Set(CONNECTEDLINE(name,i)=CID:9569695600)”) in new stack

So yes you are sending 9569695600, is that withion your Verizon block? If it is you can use

pri set debug on span 1

and see what transpires.

I’m not 100% sure what to look for in here but i do see “user number not screened” Might the LEC not have a complete screening table with all our DIDs on it?

[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 – Making new call for cref 32929
[2013-10-18 08:27:18] VERBOSE[20424][C-000001fd] sig_pri.c: – Requested transfer capability: 0x00 - SPEECH
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > DL-DATA request
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=53
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 161/0xA1) (Sent from originator)
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Message Type: SETUP (5)
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 TEI=0 Transmitting N(S)=92, window is open V(A)=92 K=7
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=53
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 161/0xA1) (Sent from originator)
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Message Type: SETUP (5)
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > [04 03 80 90 a2]
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Bearer Capability (len= 5) [ Ext: 1 Coding-Std: 0 Info transfer capability: Speech (0)
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > User information layer 1: u-Law (34)
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > [18 03 a1 83 94]
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Channel ID (len= 5) [ Ext: 1 IntID: Implicit Other(PRI) Spare: 0 Preferred Dchan: 0
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > ChanSel: As indicated in following octets
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Ext: 1 Coding: 0 Number Specified Channel Type: 3
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Ext: 1 Channel: 20 Type: CPE]
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > [1e 02 80 83]
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: User (0)
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Ext: 1 Progress Description: Calling equipment is non-ISDN. (3) ]
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > [28 05 b1 4a 6f 68 6e]
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Display (len= 5) Charset: 31 [ John ]
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > [6c 0c 21 80 39 35 36 39 36 39 35 36 32 36]
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Calling Number (len=14) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Presentation: Presentation permitted, user number not screened (0) ‘9569695626’ ]
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > [70 0b a1 39 35 36 32 37 38 30 37 39 38]
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Called Number (len=13) [ Ext: 1 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) ‘9562780798’ ]
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 q931.c:6036 q931_setup: Call 32929 enters state 1 (Call Initiated). Hold state: Idle
[2013-10-18 08:27:18] VERBOSE[20424][C-000001fd] app_dial.c: – Called DAHDI/r1/9562780798
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Protocol Discriminator: Q.931 (8) len=12
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 161/0xA1) (Sent to originator)
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Message Type: STATUS (125)
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < [08 02 82 e3]
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Public network serving the local user (2)
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Ext: 1 Cause: Info. element nonexist or not implemented (99), class = Protocol Error (e.g. unknown message) (6) ]
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < [14 01 01]
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Call State (len= 3) [ Ext: 0 Coding: CCITT (ITU) standard (0) Call state: Call Initiated (1)
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 Received message for call 0x7f95e8051080 on link 0x17337c0 TEI/SAPI 0/0
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 – Processing IE 8 (cs0, Cause)
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 – Processing IE 20 (cs0, Call State)
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Protocol Discriminator: Q.931 (8) len=13
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 161/0xA1) (Sent to originator)
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Message Type: STATUS (125)
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < [08 03 82 ab 6c]
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Cause (len= 5) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Public network serving the local user (2)
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Ext: 1 Cause: Access information discarded (43), class = Network Congestion (resource unavailable) (2) ]
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Cause data 1: 6c (108)
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < [14 01 01]
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Call State (len= 3) [ Ext: 0 Coding: CCITT (ITU) standard (0) Call state: Call Initiated (1)
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 Received message for call 0x7f95e8051080 on link 0x17337c0 TEI/SAPI 0/0
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 – Processing IE 8 (cs0, Cause)
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 – Processing IE 20 (cs0, Call State)
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Protocol Discriminator: Q.931 (8) len=10
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 161/0xA1) (Sent to originator)
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Message Type: CALL PROCEEDING (2)
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < [18 03 a1 83 94]
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Channel ID (len= 5) [ Ext: 1 IntID: Implicit Other(PRI) Spare: 0 Preferred Dchan: 0
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < ChanSel: As indicated in following octets
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Ext: 1 Coding: 0 Number Specified Channel Type: 3
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Ext: 1 Channel: 20 Type: CPE]
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 Received message for call 0x7f95e8051080 on link 0x17337c0 TEI/SAPI 0/0
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 – Processing IE 24 (cs0, Channel Identification)
[2013-10-18 08:27:18] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 q931.c:8454 post_handle_q931_message: Call 32929 enters state 3 (Outgoing Call Proceeding). Hold state: Idle
[2013-10-18 08:27:18] VERBOSE[9139] sig_pri.c: Span 1: Processing event PRI_EVENT_PROCEEDING(13)
[2013-10-18 08:27:18] VERBOSE[20424][C-000001fd] app_dial.c: – DAHDI/i1/9562780798-16d is proceeding passing it to SIP/5626-000002ba
[2013-10-18 08:27:19] VERBOSE[9139] chan_dahdi.c: PRI Span: 1
[2013-10-18 08:27:19] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Protocol Discriminator: Q.931 (8) len=9
[2013-10-18 08:27:19] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 161/0xA1) (Sent to originator)
[2013-10-18 08:27:19] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Message Type: PROGRESS (3)
[2013-10-18 08:27:19] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < [1e 02 8a 81]
[2013-10-18 08:27:19] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Network beyond the interworking point (10)
[2013-10-18 08:27:19] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Ext: 1 Progress Description: Call is not end-to-end ISDN; further call progress information may be available inband. (1) ]
[2013-10-18 08:27:19] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 Received message for call 0x7f95e8051080 on link 0x17337c0 TEI/SAPI 0/0
[2013-10-18 08:27:19] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 – Processing IE 30 (cs0, Progress Indicator)
[2013-10-18 08:27:19] VERBOSE[9139] sig_pri.c: Span 1: Processing event PRI_EVENT_PROGRESS(17)
[2013-10-18 08:27:19] VERBOSE[20424][C-000001fd] app_dial.c: – DAHDI/i1/9562780798-16d is making progress passing it to SIP/5626-000002ba
[2013-10-18 08:27:25] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 q931.c:6837 q931_hangup: Hangup other cref:32929
[2013-10-18 08:27:25] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 q931.c:6594 __q931_hangup: ourstate Outgoing Call Proceeding, peerstate Incoming Call Proceeding, hold-state Idle
[2013-10-18 08:27:25] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 q931.c:5783 q931_disconnect: Call 32929 enters state 11 (Disconnect Request). Hold state: Idle
[2013-10-18 08:27:25] VERBOSE[20424] chan_dahdi.c: PRI Span: 1
[2013-10-18 08:27:25] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > DL-DATA request
[2013-10-18 08:27:25] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=9
[2013-10-18 08:27:25] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 161/0xA1) (Sent from originator)
[2013-10-18 08:27:25] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Message Type: DISCONNECT (69)
[2013-10-18 08:27:25] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 TEI=0 Transmitting N(S)=93, window is open V(A)=93 K=7
[2013-10-18 08:27:25] VERBOSE[20424] chan_dahdi.c: PRI Span: 1
[2013-10-18 08:27:25] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=9
[2013-10-18 08:27:25] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 161/0xA1) (Sent from originator)
[2013-10-18 08:27:25] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Message Type: DISCONNECT (69)
[2013-10-18 08:27:25] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > [08 02 81 90]
[2013-10-18 08:27:25] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
[2013-10-18 08:27:25] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]
[2013-10-18 08:27:25] VERBOSE[20424][C-000001fd] chan_dahdi.c: – Hungup ‘DAHDI/i1/9562780798-16d’
[2013-10-18 08:27:25] VERBOSE[20424][C-000001fd] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘SIP/5626-000002ba’ in macro ‘dialout-trunk’
[2013-10-18 08:27:25] VERBOSE[20424][C-000001fd] pbx.c: == Spawn extension (outbound-allroutes, 2780798, 5) exited non-zero on ‘SIP/5626-000002ba’
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Protocol Discriminator: Q.931 (8) len=5
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 161/0xA1) (Sent to originator)
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 < Message Type: RELEASE (77)
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 Received message for call 0x7f95e8051080 on link 0x17337c0 TEI/SAPI 0/0
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 q931.c:8620 post_handle_q931_message: Call 32929 enters state 0 (Null). Hold state: Idle
[2013-10-18 08:27:26] VERBOSE[9139] sig_pri.c: Span 1: Processing event PRI_EVENT_HANGUP(6)
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 q931.c:6837 q931_hangup: Hangup other cref:32929
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 q931.c:6594 __q931_hangup: ourstate Null, peerstate Release Request, hold-state Idle
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 > DL-DATA request
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=9
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 161/0xA1) (Sent from originator)
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 > Message Type: RELEASE COMPLETE (90)
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 TEI=0 Transmitting N(S)=94, window is open V(A)=94 K=7
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=9
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 161/0xA1) (Sent from originator)
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 > Message Type: RELEASE COMPLETE (90)
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 > [08 02 81 90]
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 > Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 q931.c:6837 q931_hangup: Hangup other cref:32929
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 q931.c:6594 __q931_hangup: ourstate Null, peerstate Null, hold-state Idle
[2013-10-18 08:27:26] VERBOSE[9139] chan_dahdi.c: PRI Span: 1 Destroying call 0x7f95e8051080, ourstate Null, peerstate Null, hold-state Idle

[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Presentation: Presentation permitted, user number not screened (0) ‘9569695626’ ]
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > [70 0b a1 39 35 36 32 37 38 30 37 39 38]
[2013-10-18 08:27:18] VERBOSE[20424] chan_dahdi.c: PRI Span: 1 > Called Number (len=13) [ Ext: 1 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) ‘9562780798’ ]

shows the negotiated calling number as 9569695626 and the called number as 9562780798. If this is not in agreement, then call Verizon.

Make sure your trunk settings and outbound route don’t override your extension’s request.

Override is disabled on both trunks and outgoing routes. Got back the screend table and they are listing numbers that are not part of our range… and some that are. Having the LEC verify that they are actually working on the correct circut ID.

I remember that Verizon used to be GTE, in their day, the most incompetent of all NANP providers, find an engineer that is not stupid.

Dicko - I think that title has to go to Century/United. There techs still remove the red caps and butt on to T1 lines.

WRT the original post. There is no bug in the code. You can do some protocol debugging to gather proof to show the LEC. But for all practical matters I would just open a ticket with the LEC and not let up. There is no bug in the DAHDI code for DNIS on PRI interfaces.

And I thought the red caps were there for just decoration. Must not be used for anything…no Dialtone or battery!!!

Thanks for all your help. Been working with the LEC for a few days now… they are going on the excuse that different carriers are not all talking with the same “CID” language and some data may be getting lost but it’s not their fault. I called our sister office using the same LEC and they are not getting our CID info either. However, our PRI has 4 metro channels and 19 LD channels, when i dial my cell using the LD carrier, the proper outgoing CID appears.

You could call them on that B S by asking them what “Cid language” they are using on their D channel. It is really quite well defined.