Andrew Kohlsmith
2005-Dec-24 12:04 UTC
[Asterisk-Users] PRI outgoing caller ID stopped working
For the last 2.5 years I've been using Asterisk with a Bell Canada PRI with no issues. In the last month or so something has changed; I cannot send *any* caller ID. Incoming works great, and if I place a call through a VOIP provider the caller ID I'm sending shows up. I have not changed any configuration values, and I know that Bell Canada allows me to set Caller ID, as I've been doing it for the last 2.5 years. Looking at the q.931 logs it certainly looks like I'm sending it out, but I'm not fluent enough to tell if it's right. Perhaps someone can assist? The log clearly shows that I'm not blocking outgoing CID. It's also interesting to note that at the same time that this happened, I lost the ability to dial internationally through my PRI. I get a hangupcause of 2: No Route to Transit Network. Again, I have not changed any configurations around that time, but I did move from CVS HEAD to SVN TRUNK. The debug log for an example call is inline. Merry Christmas, everyone. :-) -A. Dec 24 14:01:02 DEBUG[26872] chan_zap.c: Using channel 1 Dec 24 14:01:02 DEBUG[26872] chan_zap.c: -- Making new call for cr 32772 Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > Protocol Discriminator: Q.931 (8) len=60 Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > Call Ref: len= 2 (reference 4/0x4) (Originator) Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > Message type: SETUP (5) Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > [Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 04Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 03Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 80Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 90Dec 24 14:01:02 DEBUG[26872] chan_zap.c: a2Dec 24 14:01:02 DEBUG[26872] chan_zap.c: ] Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > Bearer Capability (len= 5) [ Ext: 1 Q.931 Std: 0 Info transfer capability: Speech (0) Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16) Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > Ext: 1 User information layer 1: u-Law (34) Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > [Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 18Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 03Dec 24 14:01:02 DEBUG[26872] chan_zap.c: a9Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 83Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 81Dec 24 14:01:02 DEBUG[26872] chan_zap.c: ] Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0 Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > ChanSel: Reserved Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > Ext: 1 Coding: 0 Number Specified Channel Type: 3 Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > Ext: 1 Channel: 1 ] Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > [Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 1eDec 24 14:01:02 DEBUG[26872] chan_zap.c: 02Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 80Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 83Dec 24 14:01:02 DEBUG[26872] chan_zap.c: ] Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: User (0) Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > Ext: 1 Progress Description: Calling equipment is non-ISDN. (3) ] Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > [Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 28Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 0fDec 24 14:01:02 DEBUG[26872] chan_zap.c: b1Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 42Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 45Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 4eDec 24 14:01:02 DEBUG[26872] chan_zap.c: 53Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 48Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 41Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 57Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 20Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 43Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 41Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 4eDec 24 14:01:02 DEBUG[26872] chan_zap.c: 41Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 44Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 41Dec 24 14:01:02 DEBUG[26872] chan_zap.c: ] Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > Display (len=15) Charset: 31 [ BENSHAW CANADA ] Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > [Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 6cDec 24 14:01:02 DEBUG[26872] chan_zap.c: 0cDec 24 14:01:02 DEBUG[26872] chan_zap.c: 21Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 81Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 35Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 31Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 39Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 32Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 39Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 31Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 35Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 31Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 31Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 32Dec 24 14:01:02 DEBUG[26872] chan_zap.c: ] Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > Calling Number (len=14) [ Ext: 0 TON: National Number (2) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > Presentation: Presentation permitted, user number passed network screening (1) '5192915112' ] Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > [Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 70Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 08Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 80Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 34Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 39Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 36Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 39Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 39Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 31Dec 24 14:01:02 DEBUG[26872] chan_zap.c: 36Dec 24 14:01:02 DEBUG[26872] chan_zap.c: ] Dec 24 14:01:02 DEBUG[26872] chan_zap.c: > Called Number (len=10) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '4969916' ] Dec 24 14:01:03 DEBUG[26760] chan_zap.c: < Protocol Discriminator: Q.931 (8) len=10 Dec 24 14:01:03 DEBUG[26760] chan_zap.c: < Call Ref: len= 2 (reference 4/0x4) (Terminator) Dec 24 14:01:03 DEBUG[26760] chan_zap.c: < Message type: CALL PROCEEDING (2) Dec 24 14:01:03 DEBUG[26760] chan_zap.c: < [Dec 24 14:01:03 DEBUG[26760] chan_zap.c: 18Dec 24 14:01:03 DEBUG[26760] chan_zap.c: 03Dec 24 14:01:03 DEBUG[26760] chan_zap.c: a9Dec 24 14:01:03 DEBUG[26760] chan_zap.c: 83Dec 24 14:01:03 DEBUG[26760] chan_zap.c: 81Dec 24 14:01:03 DEBUG[26760] chan_zap.c: ] Dec 24 14:01:03 DEBUG[26760] chan_zap.c: < Channel ID (len= 5) [ Ext: 1 IntID: Implicit, PRI Spare: 0, Exclusive Dchan: 0 Dec 24 14:01:03 DEBUG[26760] chan_zap.c: < ChanSel: Reserved Dec 24 14:01:03 DEBUG[26760] chan_zap.c: < Ext: 1 Coding: 0 Number Specified Channel Type: 3 Dec 24 14:01:03 DEBUG[26760] chan_zap.c: < Ext: 1 Channel: 1 ] Dec 24 14:01:03 DEBUG[26760] chan_zap.c: -- Processing IE 24 (cs0, Channel Identification) Dec 24 14:01:03 DEBUG[26760] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/1 span 1 Dec 24 14:01:03 DEBUG[26760] chan_zap.c: < Protocol Discriminator: Q.931 (8) len=9 Dec 24 14:01:03 DEBUG[26760] chan_zap.c: < Call Ref: len= 2 (reference 4/0x4) (Terminator) Dec 24 14:01:03 DEBUG[26760] chan_zap.c: < Message type: PROGRESS (3) Dec 24 14:01:03 DEBUG[26760] chan_zap.c: < [Dec 24 14:01:03 DEBUG[26760] chan_zap.c: 1eDec 24 14:01:03 DEBUG[26760] chan_zap.c: 02Dec 24 14:01:03 DEBUG[26760] chan_zap.c: 82Dec 24 14:01:03 DEBUG[26760] chan_zap.c: 88Dec 24 14:01:03 DEBUG[26760] chan_zap.c: ] Dec 24 14:01:03 DEBUG[26760] chan_zap.c: < Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Public network serving the local user (2) Dec 24 14:01:03 DEBUG[26760] chan_zap.c: < Ext: 1 Progress Description: Inband information or appropriate pattern now available. (8) ] Dec 24 14:01:03 DEBUG[26760] chan_zap.c: -- Processing IE 30 (cs0, Progress Indicator) Dec 24 14:01:03 DEBUG[26760] chan_zap.c: Queuing frame from PRI_EVENT_PROGRESS on channel 0/1 span 1 Dec 24 14:01:07 DEBUG[26872] chan_zap.c: zt_hangup(Zap/1-1) Dec 24 14:01:07 DEBUG[26872] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/1-1 Dec 24 14:01:07 DEBUG[26872] chan_zap.c: Hangup: channel: 1 index = 0, normal = 21, callwait = -1, thirdcall = -1 Dec 24 14:01:07 DEBUG[26872] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call Dec 24 14:01:07 DEBUG[26872] chan_zap.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Outgoing call Proceeding, peerstate Incoming Call Proceeding Dec 24 14:01:07 DEBUG[26872] chan_zap.c: > Protocol Discriminator: Q.931 (8) len=9 Dec 24 14:01:07 DEBUG[26872] chan_zap.c: > Call Ref: len= 2 (reference 4/0x4) (Originator) Dec 24 14:01:07 DEBUG[26872] chan_zap.c: > Message type: DISCONNECT (69) Dec 24 14:01:07 DEBUG[26872] chan_zap.c: > [Dec 24 14:01:07 DEBUG[26872] chan_zap.c: 08Dec 24 14:01:07 DEBUG[26872] chan_zap.c: 02Dec 24 14:01:07 DEBUG[26872] chan_zap.c: 81Dec 24 14:01:07 DEBUG[26872] chan_zap.c: 90Dec 24 14:01:07 DEBUG[26872] chan_zap.c: ] Dec 24 14:01:07 DEBUG[26872] chan_zap.c: > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1) Dec 24 14:01:07 DEBUG[26872] chan_zap.c: > Ext: 1 Cause: Unknown (16), class = Normal Event (1) ] Dec 24 14:01:07 DEBUG[26872] chan_zap.c: disabled echo cancellation on channel 1 Dec 24 14:01:07 DEBUG[26872] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1 Dec 24 14:01:07 DEBUG[26872] chan_zap.c: Updated conferencing on 1, with 0 conference users Dec 24 14:01:07 DEBUG[26872] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/1-1 Dec 24 14:01:07 DEBUG[26872] chan_zap.c: disabled echo cancellation on channel 1 Dec 24 14:01:07 DEBUG[26760] chan_zap.c: < Protocol Discriminator: Q.931 (8) len=5 Dec 24 14:01:07 DEBUG[26760] chan_zap.c: < Call Ref: len= 2 (reference 4/0x4) (Terminator) Dec 24 14:01:07 DEBUG[26760] chan_zap.c: < Message type: RELEASE (77) Dec 24 14:01:07 DEBUG[26760] chan_zap.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Release Request Dec 24 14:01:07 DEBUG[26760] chan_zap.c: > Protocol Discriminator: Q.931 (8) len=9 Dec 24 14:01:07 DEBUG[26760] chan_zap.c: > Call Ref: len= 2 (reference 4/0x4) (Originator) Dec 24 14:01:07 DEBUG[26760] chan_zap.c: > Message type: RELEASE COMPLETE (90) Dec 24 14:01:07 DEBUG[26760] chan_zap.c: > [Dec 24 14:01:07 DEBUG[26760] chan_zap.c: 08Dec 24 14:01:07 DEBUG[26760] chan_zap.c: 02Dec 24 14:01:07 DEBUG[26760] chan_zap.c: 81Dec 24 14:01:07 DEBUG[26760] chan_zap.c: 90Dec 24 14:01:07 DEBUG[26760] chan_zap.c: ] Dec 24 14:01:07 DEBUG[26760] chan_zap.c: > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1) Dec 24 14:01:07 DEBUG[26760] chan_zap.c: > Ext: 1 Cause: Unknown (16), class = Normal Event (1) ] Dec 24 14:01:07 DEBUG[26760] chan_zap.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null Dec 24 14:01:07 DEBUG[26760] chan_zap.c: NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null
Kevin P. Fleming
2005-Dec-24 14:40 UTC
[Asterisk-Users] PRI outgoing caller ID stopped working
Andrew Kohlsmith wrote:> For the last 2.5 years I've been using Asterisk with a Bell Canada PRI with no > issues. In the last month or so something has changed; I cannot send *any* > caller ID. Incoming works great, and if I place a call through a VOIP > provider the caller ID I'm sending shows up.Interestingly, some systems I manage also began exhibiting this behavior in the past ten days or so. I have been working with the telco and they too show the Calling Number being received as expected over the PRI, but yet the far end receives 'Unknown' or 'Out of Area' depending on their CLID display device. I will continue to try to debug it, but I can't back down the code on that box to an older version for comparison of the PRI traffic; if you can do so, that would be most helpful.