[asterisk-users] PRI can receive calls but cannot dial out

Fri Dec 7 10:37:08 CST 2012

On 12/7/2012 6:23 AM, Vieri wrote:
>
>
>> Am 05.12.2012 08:48, schrieb Vieri:
>>> Hi,
>>>
>>> I'm trying to call out from a SIP extension to an
>> outbound destination via a PRI E1 (Digium B410P).
>>>
>>> Please take a look at the PRI debug below.
>>>
>>> --------
>>>
>>> # cat /etc/dahdi/system.conf
>>>
>>> # Digium Wildcard TDM400P REV I (WCTDM/4)
>>> fxsks=1
>>> echocanceller=oslec,1
>>> fxsks=2
>>> echocanceller=oslec,2
>>> fxsks=3
>>> echocanceller=oslec,3
>>> fxsks=4
>>> echocanceller=oslec,4
>>>
>>> # Digium Wildcard TDM2400P (WCTDM/0)
>>> fxsks=5
>>> echocanceller=oslec,5
>>> fxsks=6
>>> echocanceller=oslec,6
>>> fxsks=7
>>> echocanceller=oslec,7
>>> fxsks=8
>>> echocanceller=oslec,8
>>> fxsks=9
>>> echocanceller=oslec,9
>>> fxsks=10
>>> echocanceller=oslec,10
>>> fxsks=11
>>> echocanceller=oslec,11
>>> fxsks=12
>>> echocanceller=oslec,12
>>>
>>> # Digium Wildcard B410P (B4/0/1)
>>> span=3,1,0,CCS,AMI
>>> bchan=29-30
>>> hardhdlc=31
>>> echocanceller=oslec,29-30
>>>
>>> # Digium Wildcard B410P (B4/0/2)
>>> span=4,2,0,CCS,AMI
>>> bchan=32-33
>>> hardhdlc=34
>>> echocanceller=oslec,32-33
>>>
>>> # Digium Wildcard B410P (B4/0/3)
>>> span=5,3,0,CCS,AMI
>>> bchan=35-36
>>> hardhdlc=37
>>> echocanceller=oslec,35-36
>>>
>>> # Digium Wildcard B410P (B4/0/4)
>>> span=6,4,0,CCS,AMI
>>> bchan=38-39
>>> hardhdlc=40
>>> echocanceller=oslec,38-39
>>>
>>> --------
>>>
>>> # lsmod | grep wcb4xxp
>>> wcb4xxp
>>    66250  12
>>> dahdi
>>     169899  65
>> dahdi_echocan_oslec,wcb4xxp,wctdm24xxp,dahdi_voicebus,wctdm
>>>
>>> --------
>>>
>>> # cat chan_dahdi.conf
>>>
>>> [trunkgroups]
>>>
>>> [channels]
>>> transfer = yes
>>> usecallerid = yes
>>> cidsignalling = dtmf
>>> callwaiting = yes
>>> usecallingpres = yes
>>> callwaitingcallerid = yes
>>> threewaycalling = yes
>>> canpark = yes
>>> cancallforward = yes
>>> callreturn = yes
>>> callprogress = no
>>> overlapdial = yes
>>> echocancel = yes
>>> facilityenable = yes
>>> immediate = no
>>> busydetect = no
>>>
>>> ; Digium Wildcard TDM400P REV I (WCTDM/4)
>>> signalling = fxs_ks
>>> txgain = 1.0
>>> rxgain = 14.0
>>> group = 3
>>> context = incoming-dahdi-3
>>> faxdetect = incoming
>>> channel => 1,2,3,4
>>>
>>> ; Digium Wildcard TDM2400P (WCTDM/0)
>>> group = 4
>>> context = incoming-dahdi-4
>>> faxdetect = incoming
>>> channel => 5,6,7,8,9,10,11,12
>>>
>>> ; Digium Wildcard B410P (B4/0/1)
>>> signalling = bri_cpe
>>> switchtype = euroisdn
>>> rxgain = 2.0
>>> group = 2
>>> context = incoming-dahdi-2
>>> faxdetect = incoming
>>> channel => 29-30
>>>
>>> ; Digium Wildcard B410P (B4/0/2)
>>> channel => 32-33
>>>
>>> ; Digium Wildcard B410P (B4/0/3)
>>> channel => 35-36
>>>
>>> ; Digium Wildcard B410P (B4/0/4)
>>> channel => 38-39
>>>
>>> -----------
>>>
>>> # asterisk -rx "dahdi show status"
>>> Description
>>
>>    Alarms  IRQ    bpviol CRC
>> Fra Codi Options  LBO
>>> Wildcard TDM400P REV I Board 5
>>       OK      0
>>      0      0
>> CAS Unk           0 db
>> (CSU)/0-133 feet (DSX-1)
>>> Wildcard TDM2400P
>>                OK
>>      0      0
>> 0      CAS Unk
>>     0 db (CSU)/0-133 feet (DSX-1)
>>> B4XXP (PCI) Card 0 Span 1
>>          RED
>>     0      0
>>    0      CCS AMI
>>       0 db (CSU)/0-133 feet (DSX-1)
>>> B4XXP (PCI) Card 0 Span 2
>>          OK      0
>>      0      0
>> CCS AMI           0 db
>> (CSU)/0-133 feet (DSX-1)
>>> B4XXP (PCI) Card 0 Span 3
>>          OK      0
>>      0      0
>> CCS AMI           0 db
>> (CSU)/0-133 feet (DSX-1)
>>> B4XXP (PCI) Card 0 Span 4
>>          OK      0
>>      0      0
>> CCS AMI           0 db
>> (CSU)/0-133 feet (DSX-1)
>>>
>>> Note that I have 3 cables connected and 1 port is free
>> (RED).
>>>
>>> -----------
>>>
>>> in AEL dialplan, I run:
>>>
>>> Dial(DAHDI/g2/XXXXXXXXXX);
>>>
>>> in the *CLI I see the following:
>>>
>>>        -- Requested transfer capability:
>> 0x00 - SPEECH
>>>        -- Called DAHDI/g2/XXXXXXXXXX
>>>        -- Span 4: Channel 0/1 got hangup,
>> cause 18
>>>        -- Hungup 'DAHDI/i4/XXXXXXXXXX-7'
>>>      == Everyone is busy/congested at this time
>> (1:0/0/1)
>>>        -- Auto fallthrough, channel
>> 'SIP/4053-00000089' status is 'CHANUNAVAIL'
>>>
>>>
>>> If I enable PRI debug:
>>>
>>>        -- Executing [@company:1]
>> Dial("SIP/4053-00000001", "DAHDI/g2/XXXXXXXXXX") in new
>> stack
>>> PRI Span: 4 -- Making new call for cref 32772
>>>        -- Requested transfer capability:
>> 0x00 - SPEECH
>>> PRI Span: 4
>>> PRI Span: 4 > DL-DATA request
>>> PRI Span: 4 > Protocol Discriminator: Q.931
>> (8)  len=32
>>> PRI Span: 4 > TEI=0 Call Ref: len= 1 (reference
>> 4/0x4) (Sent from originator)
>>> PRI Span: 4 > Message Type: SETUP (5)
>>> PRI Span: 4 TEI=0 Transmitting N(S)=6, window is open
>> V(A)=6 K=1
>>> PRI Span: 4
>>> PRI Span: 4 > Protocol Discriminator: Q.931
>> (8)  len=32
>>> PRI Span: 4 > TEI=0 Call Ref: len= 1 (reference
>> 4/0x4) (Sent from originator)
>>> PRI Span: 4 > Message Type: SETUP (5)
>>> PRI Span: 4 > [04 03 80 90 a3]
>>> PRI Span: 4 > Bearer Capability (len= 5) [ Ext:
>> 1  Coding-Std: 0  Info transfer capability: Speech
>> (0)
>>> PRI Span: 4 >
>>
>>      Ext: 1  Trans mode/rate: 64kbps,
>> circuit-mode (16)
>>> PRI Span: 4 >
>>
>>        User information layer 1: A-Law (35)
>>> PRI Span: 4 > [18 01 81]
>>> PRI Span: 4 > Channel ID (len= 3) [ Ext: 1
>> IntID: Implicit  BRI  Spare: 0
>> Preferred  Dchan: 0
>>> PRI Span: 4 >
>>
>>     ChanSel: B1 channel
>>> PRI Span: 4 >
>>             ]
>>> PRI Span: 4 > [6c 06 21 80 34 30 35 33]
>>> PRI Span: 4 > Calling Party Number (len= 8) [ Ext:
>> 0  TON: National Number (2)  NPI: ISDN/Telephony
>> Numbering Plan (E.164/E.163) (1)
>>> PRI Span: 4 >
>>
>>         Presentation: Presentation
>> allowed, User-provided, not screened (0)  '4053' ]
>>> PRI Span: 4 > [70 0a 80 36 35 36 36 36 30 34 39 39]
>>> PRI Span: 4 > Called Party Number (len=12) [ Ext:
>> 1  TON: Unknown Number Type (0)  NPI: Unknown
>> Number Plan (0)  'XXXXXXXXXX' ]
>>> PRI Span: 4 q931.c:6291 q931_setup: Call 32772 enters
>> state 1 (Call Initiated).  Hold state: Idle
>>>        -- Called DAHDI/g2/XXXXXXXXXX
>>> PRI Span: 3 TEI=0 Sending SABME
>>> PRI Span: 3 TEI=0 Sending SABME
>>> PRI Span: 3 TEI=0 Sending SABME
>>> PRI Span: 3 TEI=0 MDL-ERROR (G): T200 expired N200
>> times sending SABME in state 5(Awaiting establishment)
>>> PRI Span: 3 Changing from state 5(Awaiting
>> establishment) to 4(TEI assigned)
>>> PRI Span: 3 TEI=0 DL event:
>> Q931_DL_EVENT_DL_RELEASE_IND(3)
>>> PRI Span: 4 T303 timed out.  cref:32772
>>> PRI Span: 4
>>> PRI Span: 4 > DL-DATA request
>>> PRI Span: 4 > Protocol Discriminator: Q.931
>> (8)  len=32
>>> PRI Span: 4 > TEI=0 Call Ref: len= 1 (reference
>> 4/0x4) (Sent from originator)
>>> PRI Span: 4 > Message Type: SETUP (5)
>>> PRI Span: 4 TEI=0 Transmitting N(S)=7, window is open
>> V(A)=7 K=1
>>> PRI Span: 4
>>> PRI Span: 4 > Protocol Discriminator: Q.931
>> (8)  len=32
>>> PRI Span: 4 > TEI=0 Call Ref: len= 1 (reference
>> 4/0x4) (Sent from originator)
>>> PRI Span: 4 > Message Type: SETUP (5)
>>> PRI Span: 4 > [04 03 80 90 a3]
>>> PRI Span: 4 > Bearer Capability (len= 5) [ Ext:
>> 1  Coding-Std: 0  Info transfer capability: Speech
>> (0)
>>> PRI Span: 4 >
>>
>>      Ext: 1  Trans mode/rate: 64kbps,
>> circuit-mode (16)
>>> PRI Span: 4 >
>>
>>        User information layer 1: A-Law (35)
>>> PRI Span: 4 > [18 01 81]
>>> PRI Span: 4 > Channel ID (len= 3) [ Ext: 1
>> IntID: Implicit  BRI  Spare: 0
>> Preferred  Dchan: 0
>>> PRI Span: 4 >
>>
>>     ChanSel: B1 channel
>>> PRI Span: 4 >
>>             ]
>>> PRI Span: 4 > [6c 06 21 80 34 30 35 33]
>>> PRI Span: 4 > Calling Party Number (len= 8) [ Ext:
>> 0  TON: National Number (2)  NPI: ISDN/Telephony
>> Numbering Plan (E.164/E.163) (1)
>>> PRI Span: 4 >
>>
>>         Presentation: Presentation
>> allowed, User-provided, not screened (0)  '4053' ]
>>> PRI Span: 4 > [70 0a 80 36 35 36 36 36 30 34 39 39]
>>> PRI Span: 4 > Called Party Number (len=12) [ Ext:
>> 1  TON: Unknown Number Type (0)  NPI: Unknown
>> Number Plan (0)  'XXXXXXXXXX' ]
>>> PRI Span: 3 SAPI/TEI=0/0 Kick starting link
>>> PRI Span: 3 TEI=0 Sending SABME
>>> PRI Span: 3 Changing from state 4(TEI assigned) to
>> 5(Awaiting establishment)
>>> PRI Span: 3 TEI=0 Sending SABME
>>> PRI Span: 3 TEI=0 Sending SABME
>>> PRI Span: 3 TEI=0 Sending SABME
>>> PRI Span: 4 T303 timed out.  cref:32772
>>> PRI Span: 4 q931.c:6180 t303_expiry: Call 32772 enters
>> state 0 (Null).  Hold state: Idle
>>> PRI Span: 4 Fake clearing.  cref:32772
>>> PRI Span: 4 q931.c:9551 pri_internal_clear: alive 1,
>> hangupack 1
>>> Span 4: Processing event PRI_EVENT_HANGUP(6)
>>>        -- Span 4: Channel 0/1 got hangup,
>> cause 18
>>> PRI Span: 4 q931.c:7092 q931_hangup: Hangup other
>> cref:32772
>>> PRI Span: 4 q931.c:6849 __q931_hangup: ourstate Null,
>> peerstate Null, hold-state Idle
>>> PRI Span: 4 Destroying call 0xb85cb0a0, ourstate Null,
>> peerstate Null, hold-state Idle
>>>        -- Hungup 'DAHDI/i4/XXXXXXXXXX-4'
>>>      == Everyone is busy/congested at this time
>> (1:0/0/1)
>>>        -- Auto fallthrough, channel
>> 'SIP/4053-00000001' status is 'CHANUNAVAIL'
>>>        -- Executing [h at company:3]
>> Hangup("SIP/4053-00000001", "") in new stack
>>>      == Spawn extension (company, h, 3) exited
>> non-zero on 'SIP/4053-00000001'
>>> PRI Span: 3 TEI=0 MDL-ERROR (G): T200 expired N200
>> times sending SABME in state 5(Awaiting establishment)
>>> PRI Span: 3 Changing from state 5(Awaiting
>> establishment) to 4(TEI assigned)
>>> PRI Span: 3 TEI=0 DL event:
>> Q931_DL_EVENT_DL_RELEASE_IND(3)
>>> PRI Span: 3 SAPI/TEI=0/0 Kick starting link
>>> PRI Span: 3 TEI=0 Sending SABME
>>> PRI Span: 3 Changing from state 4(TEI assigned) to
>> 5(Awaiting establishment)
>>>
>>> So the fact that there's a timeout (MDL-ERROR) makes me
>> think that there's some kind of signalling error.
>>>
>>> What could it be?
>>>
>>> I noticed that my system clock was way off so I sync'ed
>> it via NTP and tried to place a call out. It failed again
>> but this time the log shows a "T303 timed out":
>>>
>>>        -- Executing [@company:1]
>> Dial("SIP/4053-00000003", "DAHDI/g2/XXXXXXXXXX") in new
>> stack
>>> PRI Span: 4 -- Making new call for cref 32774
>>>        -- Requested transfer capability:
>> 0x00 - SPEECH
>>> PRI Span: 4
>>> PRI Span: 4 > DL-DATA request
>>> PRI Span: 4 > Protocol Discriminator: Q.931
>> (8)  len=32
>>> PRI Span: 4 > TEI=0 Call Ref: len= 1 (reference
>> 6/0x6) (Sent from originator)
>>> PRI Span: 4 > Message Type: SETUP (5)
>>> PRI Span: 4 TEI=0 Transmitting N(S)=10, window is open
>> V(A)=10 K=1
>>> PRI Span: 4
>>> PRI Span: 4 > Protocol Discriminator: Q.931
>> (8)  len=32
>>> PRI Span: 4 > TEI=0 Call Ref: len= 1 (reference
>> 6/0x6) (Sent from originator)
>>> PRI Span: 4 > Message Type: SETUP (5)
>>> PRI Span: 4 > [04 03 80 90 a3]
>>> PRI Span: 4 > Bearer Capability (len= 5) [ Ext:
>> 1  Coding-Std: 0  Info transfer capability: Speech
>> (0)
>>> PRI Span: 4 >
>>
>>      Ext: 1  Trans mode/rate: 64kbps,
>> circuit-mode (16)
>>> PRI Span: 4 >
>>
>>        User information layer 1: A-Law (35)
>>> PRI Span: 4 > [18 01 81]
>>> PRI Span: 4 > Channel ID (len= 3) [ Ext: 1
>> IntID: Implicit  BRI  Spare: 0
>> Preferred  Dchan: 0
>>> PRI Span: 4 >
>>
>>     ChanSel: B1 channel
>>> PRI Span: 4 >
>>             ]
>>> PRI Span: 4 > [6c 06 21 80 34 30 35 33]
>>> PRI Span: 4 > Calling Party Number (len= 8) [ Ext:
>> 0  TON: National Number (2)  NPI: ISDN/Telephony
>> Numbering Plan (E.164/E.163) (1)
>>> PRI Span: 4 >
>>
>>         Presentation: Presentation
>> allowed, User-provided, not screened (0)  '4053' ]
>>> PRI Span: 4 > [70 0a 80 36 35 36 36 36 30 34 39 39]
>>> PRI Span: 4 > Called Party Number (len=12) [ Ext:
>> 1  TON: Unknown Number Type (0)  NPI: Unknown
>> Number Plan (0)  'XXXXXXXXXX' ]
>>> PRI Span: 4 q931.c:6291 q931_setup: Call 32774 enters
>> state 1 (Call Initiated).  Hold state: Idle
>>>        -- Called DAHDI/g2/XXXXXXXXXX
>>> PRI Span: 4 T303 timed out.  cref:32774
>>> PRI Span: 4
>>> PRI Span: 4 > DL-DATA request
>>> PRI Span: 4 > Protocol Discriminator: Q.931
>> (8)  len=32
>>> PRI Span: 4 > TEI=0 Call Ref: len= 1 (reference
>> 6/0x6) (Sent from originator)
>>> PRI Span: 4 > Message Type: SETUP (5)
>>> PRI Span: 4 TEI=0 Transmitting N(S)=11, window is open
>> V(A)=11 K=1
>>> PRI Span: 4
>>> PRI Span: 4 > Protocol Discriminator: Q.931
>> (8)  len=32
>>> PRI Span: 4 > TEI=0 Call Ref: len= 1 (reference
>> 6/0x6) (Sent from originator)
>>> PRI Span: 4 > Message Type: SETUP (5)
>>> PRI Span: 4 > [04 03 80 90 a3]
>>> PRI Span: 4 > Bearer Capability (len= 5) [ Ext:
>> 1  Coding-Std: 0  Info transfer capability: Speech
>> (0)
>>> PRI Span: 4 >
>>
>>      Ext: 1  Trans mode/rate: 64kbps,
>> circuit-mode (16)
>>> PRI Span: 4 >
>>
>>        User information layer 1: A-Law (35)
>>> PRI Span: 4 > [18 01 81]
>>> PRI Span: 4 > Channel ID (len= 3) [ Ext: 1
>> IntID: Implicit  BRI  Spare: 0
>> Preferred  Dchan: 0
>>> PRI Span: 4 >
>>
>>     ChanSel: B1 channel
>>> PRI Span: 4 >
>>             ]
>>> PRI Span: 4 > [6c 06 21 80 34 30 35 33]
>>> PRI Span: 4 > Calling Party Number (len= 8) [ Ext:
>> 0  TON: National Number (2)  NPI: ISDN/Telephony
>> Numbering Plan (E.164/E.163) (1)
>>> PRI Span: 4 >
>>
>>         Presentation: Presentation
>> allowed, User-provided, not screened (0)  '4053' ]
>>> PRI Span: 4 > [70 0a 80 36 35 36 36 36 30 34 39 39]
>>> PRI Span: 4 > Called Party Number (len=12) [ Ext:
>> 1  TON: Unknown Number Type (0)  NPI: Unknown
>> Number Plan (0)  'XXXXXXXXXX' ]
>>> PRI Span: 4 T303 timed out.  cref:32774
>>> PRI Span: 4 q931.c:6180 t303_expiry: Call 32774 enters
>> state 0 (Null).  Hold state: Idle
>>> PRI Span: 4 Fake clearing.  cref:32774
>>> PRI Span: 4 q931.c:9551 pri_internal_clear: alive 1,
>> hangupack 1
>>> Span 4: Processing event PRI_EVENT_HANGUP(6)
>>>        -- Span 4: Channel 0/1 got hangup,
>> cause 18
>>> PRI Span: 4 q931.c:7092 q931_hangup: Hangup other
>> cref:32774
>>> PRI Span: 4 q931.c:6849 __q931_hangup: ourstate Null,
>> peerstate Null, hold-state Idle
>>> PRI Span: 4 Destroying call 0xb85c61d0, ourstate Null,
>> peerstate Null, hold-state Idle
>>>        -- Hungup 'DAHDI/i4/XXXXXXXXXX-6'
>>>      == Everyone is busy/congested at this time
>> (1:0/0/1)
>>>        -- Auto fallthrough, channel
>> 'SIP/4053-00000003' status is 'CHANUNAVAIL'
>>>        -- Executing [h at company:3]
>> Hangup("SIP/4053-00000003", "") in new stack
>>>      == Spawn extension (company, h, 3) exited
>> non-zero on 'SIP/4053-00000003'
>>>
>>> Note that incoming calls via this PRI work correctly.
>>>
>>> Asterisk 11.0.1
>>> latest libpri and dahdi.
>>>
>>> Thanks,
>>>
>>> Vieri
>>>
> --- On Wed, 12/5/12, Thorsten Göllner <tg at ovm-group.com> wrote:
>
>> From: Thorsten Göllner <tg at ovm-group.com>
>> Subject: Re: [asterisk-users] PRI can receive calls but cannot dial out
>> To: "Asterisk Users Mailing List - Non-Commercial Discussion" <asterisk-users at lists.digium.com>
>> Cc: "Vieri" <rentorbuy at yahoo.com>
>> Date: Wednesday, December 5, 2012, 8:21 AM
>> Hi!
>>
>> 1) How long does the outdial take? Does the Dial-Command
>> return immediatly?
>>
>> 2) Maybe dial-out is blocked by your carrier? Did you try to
>> open a
>> trouble ticket there?
>>
>> 3) What number do you try to call? Did you try some
>> different number?
>> Alway the same problem?
>>
>> You receive ISDN-Cause-Code 18. Not sure though, but I would
>> open a
>> troubke ticket at your carrier.
>>
>> -Thorsten-
>
> Hi,
>
> 1) just a few seconds (max. 5)
> 2) it isn't blocked by the carrier (see below)
> 3) always the same problem regardless of dialed number
>
> I did another test and noticed that if the first port of the card is disconnected (no cable) then calls can't go out (they can only come in). However, if I connect the first port then calls do go out. Why's that?
> Could there be something wrong with my /etc/dahdi/system.conf or chan_dahdi.conf (see above)?
>
> Thanks,
>
> Vieri
>

The first port has channels defined in group 2, but the port is down. 
Have you tried dialing out with G2 as opposed to g2?

Alex