Can't call specific numbers

[tpolak]

I have a really weird problem. I can't call certain numbers out of our PIAF box. For example I can't call 555-9520, but I can call 555-9521 and 555-9519 (also 555-4255 can't be called, but 555-4256 and 555-4254 can). It is not just these two examples, but probably about 10 or so numbers.
I have reported this trouble to my phone company and they don't seem to think it is their problem. They supposedly ran testing to make sure it was not their problem.
I am running asterisk 1.4.2. I have a sangoma card (dual T1 ports), but only 1 PRI is currently hooked up. All of these callers can call inbound. There is nothing in the outbound routes in Free PBX that are specific to these numbers. The only thing is dial 9 to get a trunk.
Below is the log of what happens.
(ext 203 is the line I am calling from)

[Jul 9 13:23:53] VERBOSE[4887] logger.c: -- Called g0/18155559520
[Jul 9 13:23:53] DEBUG[4154] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/3 span 1
[Jul 9 13:23:53] VERBOSE[4887] logger.c: -- Zap/3-1 is proceeding passing it to SCCP/203-00000005
[Jul 9 13:23:54] VERBOSE[4154] logger.c: -- Channel 0/3, span 1 got hangup request, cause 27
[Jul 9 13:23:54] VERBOSE[4887] logger.c: -- Zap/3-1 is circuit-busy
[Jul 9 13:23:54] DEBUG[4887] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/3-1
[Jul 9 13:23:54] DEBUG[4887] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
[Jul 9 13:23:54] DEBUG[4887] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/3-1
[Jul 9 13:23:54] VERBOSE[4887] logger.c: -- Hungup 'Zap/3-1'
[Jul 9 13:23:54] VERBOSE[4887] logger.c: == Everyone is busy/congested at this time (1:0/1/0)
[Jul 9 13:23:54] DEBUG[4887] app_macro.c: Executed application: Dial
[Jul 9 13:23:54] VERBOSE[4887] logger.c: -- Executing [s@macro-dialout-trunk:20] Goto("SCCP/203-00000005", "s-CONGESTION|1") in new stack
[Jul 9 13:23:54] VERBOSE[4887] logger.c: -- Goto (macro-dialout-trunk,s-CONGESTION,1)
[Jul 9 13:23:54] DEBUG[4887] app_macro.c: Executed application: Goto
[Jul 9 13:23:54] VERBOSE[4887] logger.c: -- Executing [s-CONGESTION@macro-dialout-trunk:1] GotoIf("SCCP/203-00000005", "1?noreport") in new stack
[Jul 9 13:23:54] VERBOSE[4887] logger.c: -- Goto (macro-dialout-trunk,s-CONGESTION,3)
[Jul 9 13:23:54] DEBUG[4887] app_macro.c: Executed application: GotoIf
[Jul 9 13:23:54] VERBOSE[4887] logger.c: -- Executing [s-CONGESTION@macro-dialout-trunk:3] NoOp("SCCP/203-00000005", "TRUNK Dial failed due to CONGESTION - failing through to other trunks") in new stack
[Jul 9 13:23:54] DEBUG[4887] app_macro.c: Executed application: Noop
[Jul 9 13:23:54] VERBOSE[4887] logger.c: -- Executing [5559520@from-internal:5] Macro("SCCP/203-00000005", "outisbusy|") in new stack
[Jul 9 13:23:54] VERBOSE[4887] logger.c: -- Executing [s@macro-outisbusy:1] Playback("SCCP/203-00000005", "all-circuits-busy-now|noanswer") in new stack

Any advice on what is happening or how to trouble shoot it more? Need any more logs? Please help me on this one as I am a little lost.

 

[tpolak]

I did a little more digging. Here is the PRI debug option in asterisk.

....
("SCCP/203-00000024", "1|Set|CALLERID(all)=Rockford Assocation of Realtors <8153956776>") in new stack
    -- Executing [s@macro-outbound-callerid:13] GotoIf("SCCP/203-00000024", "1?exit") in new stack
    -- Goto (macro-outbound-callerid,s,11)
    -- Executing [s@macro-outbound-callerid:11] MacroExit("SCCP/203-00000024", "") in new stack
    -- Executing [s@macro-dialout-trunk:12] ExecIf("SCCP/203-00000024", "1|AGI|fixlocalprefix") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
  ==  fixlocalprefix: Dialpattern 1815+NXXXXXX matched. 5199520 -> 18155199520
    -- AGI Script fixlocalprefix completed, returning 0
    -- Executing [s@macro-dialout-trunk:13] Set("SCCP/203-00000024", "OUTNUM=18155199520") in new stack
    -- Executing [s@macro-dialout-trunk:14] Set("SCCP/203-00000024", "custom=ZAP/g0") in new stack
    -- Executing [s@macro-dialout-trunk:15] ExecIf("SCCP/203-00000024", "0|Set|DIAL_TRUNK_OPTIONS=M(setmusic^)") in new stack
    -- Executing [s@macro-dialout-trunk:16] Macro("SCCP/203-00000024", "dialout-trunk-predial-hook|") in new stack
    -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SCCP/203-00000024", "") in new stack
    -- Executing [s@macro-dialout-trunk:17] GotoIf("SCCP/203-00000024", "0?bypass|1") in new stack
    -- Executing [s@macro-dialout-trunk:18] GotoIf("SCCP/203-00000024", "0?customtrunk") in new stack
    -- Executing [s@macro-dialout-trunk:19] Dial("SCCP/203-00000024", "ZAP/g0/18155199520|300|") in new stack
-- Making new call for cr 32785
    -- Requested transfer capability: 0x00 - SPEECH
> Protocol Discriminator: Q.931 (8)  len=81
> Call Ref: len= 2 (reference 17/0x11) (Originator)
> Message type: SETUP (5)
> [04 03 80 90 a2]
> Bearer Capability (len= 5) [ Ext: 1  Q.931 Std: 0  Info transfer capability: Speech (0)
>                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
>                                User information layer 1: u-Law (34)
> [18 03 a9 83 82]
> Channel ID (len= 5) [ Ext: 1  IntID: Implicit  PRI  Spare: 0  Exclusive  Dchan: 0
>                        ChanSel: As indicated in following octets
>                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
>                       Ext: 1  Channel: 2 ]
> [1e 02 80 83]
> Progress Indicator (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  0: 0  Location: User (0)
>                               Ext: 1  Progress Description: Calling equipment is non-ISDN. (3) ]
> [28 20 b1 52 6f 63 6b 66 6f 72 64 20 41 73 73 6f 63 61 74 69 6f 6e 20 6f 66 20 52 65 61 6c 74 6f 72 73]
> Display (len=32) Charset: 31 [ Rockford Assocation of Realtors ]
> [6c 0c 21 80 38 31 35 33 39 35 36 37 37 36]
> Calling Number (len=14) [ Ext: 0  TON: National Number (2)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
>                           Presentation: Presentation permitted, user number not screened (0)  '8153956776' ]
> [70 0c a1 31 38 31 35 35 31 39 39 35 32 30]
> Called Number (len=14) [ Ext: 1  TON: National Number (2)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)  '18155199520' ]
q931.c:3092 q931_setup: call 32785 on channel 2 enters state 1 (Call Initiated)
    -- Called g0/18155199520
< Protocol Discriminator: Q.931 (8)  len=10
< Call Ref: len= 2 (reference 17/0x11) (Terminator)
< Message type: CALL PROCEEDING (2)
< [18 03 a9 83 82]
< Channel ID (len= 5) [ Ext: 1  IntID: Implicit  PRI  Spare: 0  Exclusive  Dchan: 0
<                        ChanSel: As indicated in following octets
<                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
<                       Ext: 1  Channel: 2 ]
-- Processing IE 24 (cs0, Channel Identification)
q931.c:3641 q931_receive: call 32785 on channel 2 enters state 3 (Outgoing call  Proceeding)
    -- Zap/2-1 is proceeding passing it to SCCP/203-00000024
< Protocol Discriminator: Q.931 (8)  len=9
< Call Ref: len= 2 (reference 17/0x11) (Terminator)
< Message type: DISCONNECT (69)
< [08 02 84 9b]
< Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Public network serving the remote user (4)
<                  Ext: 1  Cause: Destination out of order (27), class = Normal Event (1) ]
-- Processing IE 8 (cs0, Cause)
q931.c:3784 q931_receive: call 32785 on channel 2 enters state 12 (Disconnect Indication)
    -- Channel 0/2, span 1 got hangup request, cause 27
    -- Zap/2-1 is circuit-busy
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Disconnect Indication, peerstate Disconnect Request
q931.c:2925 q931_release: call 32785 on channel 2 enters state 19 (Release Request)
> Protocol Discriminator: Q.931 (8)  len=9
> Call Ref: len= 2 (reference 17/0x11) (Originator)
> Message type: RELEASE (77)
> [08 02 81 9b]
> Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Private network serving the local user (1)
>                  Ext: 1  Cause: Destination out of order (27), class = Normal Event (1) ]
    -- Hungup 'Zap/2-1'
  == Everyone is busy/congested at this time (1:0/1/0)
    -- Executing [s@macro-dialout-trunk:20] Goto("SCCP/203-00000024", "s-CONGESTION|1") in new stack
    -- Goto (macro-dialout-trunk,s-CONGESTION,1)
    -- Executing [s-CONGESTION@macro-dialout-trunk:1] GotoIf("SCCP/203-00000024", "1?noreport") in new stack
    -- Goto (macro-dialout-trunk,s-CONGESTION,3)
    -- Executing [s-CONGESTION@macro-dialout-trunk:3] NoOp("SCCP/203-00000024", "TRUNK Dial failed due to CONGESTION - failing through to other trunks") in new stack
    -- Executing [95199520@from-internal:5] Macro("SCCP/203-00000024", "outisbusy|") in new stack
    -- Executing [s@macro-outisbusy:1] Playback("SCCP/203-00000024", "all-circuits-busy-now|noanswer") in new stack
    -- <SCCP/203-00000024> Playing 'all-circuits-busy-now' (language 'en')
< Protocol Discriminator: Q.931 (8)  len=5
< Call Ref: len= 2 (reference 17/0x11) (Terminator)
< Message type: RELEASE COMPLETE (90)
q931.c:3724 q931_receive: call 32785 on channel 2 enters state 0 (Null)
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null
  == Spawn extension (macro-outisbusy, s, 1) exited non-zero on 'SCCP/203-00000024' in macro 'outisbusy'
  == Spawn extension (macro-outisbusy, s, 1) exited non-zero on 'SCCP/203-00000024'

Any advise? The phone company is not reporting the line as out of order. I can call them from my cell phone and they can call in.
I tried disabling 'r' on outbound like on this forum http://www.trixbox.org/forums/trixb...ertain-numbers-and-cant-call-t-conference-bri but that did not change anything. Also I see the call output from asterisk is different.

 

[cjkeeme]

If you, for example, call 555-9520 and it fails - will it always fail? Will 555-9520 always fail or will it sometimes work?

 

[jmullinix]

When this happens to me on an analog card, I add

relaxdtmf=yes

to zapata.conf.

If that doesn't fix it, I add a couple of w' to the number before I dial it in the trunk's dial rules section:

ww+NXXNXXXXXX

Each w is a couple hundred milliseconds of pre-dial delay. Sometime Asterisk is just too fast for the phone company.

 

[dswartz]

I wondered about the 'w' issue, since I have seen that in the past too, but thought maybe it did not apply to PRI?

 

[tpolak]

Thanks for the responses.

This is always happening on these few numbers. Every call out to these numbers fail.

I tried everything suggested so far (already had relaxdtmf=yes in the zaptel.conf file).

Any other ideas?

 

[dswartz]

On second thought, my experiences with the 'w' issue is that it either always happened or never happened - e.g. not sensitive to the number being called. Sure sounds like something wrong with the line. If it were not a PRI, it would be possible to plug some other CPE in to confirm/disprove this, but I don't know what to suggest.

 

[tpolak]

After talking with the staff more it seems to only be happening when they call Cell phones. It does not seem carrier specific. Two are Sprint, One Verizon, and one is US Celluar. I emailed sangoma to see if they have ran into this problem before.

Thanks for everyone's help. Hopefully it just ends up being a phone company problem.

 

[dswartz]

Now that's weird! I can't imagine any possible way this can be sangoma or asterisk's fault if it only happens calling certain types of numbers! Telco issue!!!!!

 

[tpolak]

Sangoma called me and talked to me about the issue. I am impressed with that, if only more places operated like that. Any way they say it is a problem with the phone company. The key lines are.

> Called Number (len=14) [ Ext: 1  TON: National Number (2)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)  '18155199520' ]
q931.c:3092 q931_setup: call 32785 on channel 2 enters state 1 (Call Initiated)
    -- Called g0/18155199520
< Protocol Discriminator: Q.931 (8)  len=10
< Call Ref: len= 2 (reference 17/0x11) (Terminator)
< Message type: CALL PROCEEDING (2)
< [18 03 a9 83 82]
< Channel ID (len= 5) [ Ext: 1  IntID: Implicit  PRI  Spare: 0  Exclusive  Dchan: 0
<                        ChanSel: As indicated in following octets
<                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
<                       Ext: 1  Channel: 2 ]
-- Processing IE 24 (cs0, Channel Identification)
q931.c:3641 q931_receive: call 32785 on channel 2 enters state 3 (Outgoing call  Proceeding)
    -- Zap/2-1 is proceeding passing it to SCCP/203-00000024
< Protocol Discriminator: Q.931 (8)  len=9
< Call Ref: len= 2 (reference 17/0x11) (Terminator)
< Message type: DISCONNECT (69)
< [08 02 84 9b]
< Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Public network serving the remote user (4)
<                  Ext: 1  Cause: Destination out of order (27), class = Normal Event (1) ]

Sangoma said that the phone company was sending back a disconnect message of [08 02 84 9b]. They said they are 100% sure it is a phone company issue.

Sangoma gets two thumbs up in my book for customer service.

Thanks for everyone's help, just wanted to update the thread with the solution. Now onto argue with the phone company.

 

[cjkeeme]

Happy to see this resolved!

 

[jmullinix]

I don't know whether it works with PRI's or not. I just threw it out there as something to try. I live in Appalachia and there aren't too many PRI's here.