[asterisk-users] PRI QSIG Asterisk - Legacy PBX

Vieri rentorbuy at yahoo.com
Thu Mar 19 09:10:37 CDT 2009


On my EuroISDN PRI link, a pri debug on the same type of call yields the messages below. What could I try to do to see why the QSIG pri link doesn't work (times out)?

Thanks

< Call Ref: len= 2 (reference 1065/0x429) (Originator)
< Message type: SETUP (5)
< [04 03 80 90 a3]
< 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: A-Law (35)
< [18 03 a9 83 93]
< 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: 19 ]
< [6c 06 00 81 33 32 31 30]
< Calling Number (len= 8) [ Ext: 0  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)
<                           Presentation: Presentation permitted, user number passed network screening (1)  '3210' ]
< [70 08 80 31 30 30 34 30 35 33]
< Called Number (len=10) [ Ext: 1  TON: Unknown Number Type (0)  NPI: Unknown Number Plan (0)  '1004053' ]
< [7d 02 91 81]
< IE: High-layer Compatibility (len = 4)
< [a1]
< Sending Complete (len= 1)
-- Making new call for cr 1065
-- Processing Q.931 Call Setup
-- Processing IE 4 (cs0, Bearer Capability)
-- Processing IE 24 (cs0, Channel Identification)
-- Processing IE 108 (cs0, Calling Party Number)
-- Processing IE 112 (cs0, Called Party Number)
-- Processing IE 125 (cs0, High-layer Compatibility)
-- Processing IE 161 (cs0, Sending Complete)
q931.c:3545 q931_receive: call 1065 on channel 19 enters state 6 (Call Present)
q931.c:2810 q931_call_proceeding: call 1065 on channel 19 enters state 9 (Incoming Call Proceeding)
> Protocol Discriminator: Q.931 (8)  len=11
> Call Ref: len= 2 (reference 1065/0x429) (Terminator)
> Message type: CALL PROCEEDING (2)
> [18 04 e9 81 83 93]
> Channel ID (len= 6) [ Ext: 1  IntID: Explicit  PRI  Spare: 0  Exclusive  Dchan: 0
>                        ChanSel: As indicated in following octets
>                       Ext: 1  DS1 Identifier: 1  
>                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
>                       Ext: 1  Channel: 19 ]
q931.c:2945 q931_connect: call 1065 on channel 19 enters state 8 (Connect Request)
> Protocol Discriminator: Q.931 (8)  len=15
> Call Ref: len= 2 (reference 1065/0x429) (Terminator)
> Message type: CONNECT (7)
> [18 04 e9 81 83 93]
> Channel ID (len= 6) [ Ext: 1  IntID: Explicit  PRI  Spare: 0  Exclusive  Dchan: 0
>                        ChanSel: As indicated in following octets
>                       Ext: 1  DS1 Identifier: 1  
>                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
>                       Ext: 1  Channel: 19 ]
> [1e 02 81 82]
> Progress Indicator (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  0: 0  Location: Private network serving the local user (1)
>                               Ext: 1  Progress Description: Called equipment is non-ISDN. (2) ]
< Protocol Discriminator: Q.931 (8)  len=10
< Call Ref: len= 2 (reference 1065/0x429) (Originator)
< Message type: CONNECT ACKNOWLEDGE (15)
< [18 03 a9 83 93]
< 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: 19 ]
-- Processing IE 24 (cs0, Channel Identification)
q931.c:3705 q931_receive: call 1065 on channel 19 enters state 10 (Active)
< Protocol Discriminator: Q.931 (8)  len=9
< Call Ref: len= 2 (reference 1065/0x429) (Originator)
< Message type: DISCONNECT (69)
< [08 02 81 90]
< Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Private network serving the local user (1)
<                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
-- Processing IE 8 (cs0, Cause)
q931.c:3820 q931_receive: call 1065 on channel 19 enters state 12 (Disconnect Indication)
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Disconnect Indication, peerstate Disconnect Request
q931.c:2961 q931_release: call 1065 on channel 19 enters state 19 (Release Request)
> Protocol Discriminator: Q.931 (8)  len=9
> Call Ref: len= 2 (reference 1065/0x429) (Terminator)
> Message type: RELEASE (77)
> [08 02 81 90]
> Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Private network serving the local user (1)
>                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
< Protocol Discriminator: Q.931 (8)  len=9
< Call Ref: len= 2 (reference 1065/0x429) (Originator)
< Message type: RELEASE COMPLETE (90)
< [08 02 81 90]
< Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Private network serving the local user (1)
<                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
-- Processing IE 8 (cs0, Cause)
q931.c:3760 q931_receive: call 1065 on channel 19 enters state 0 (Null)
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null


--- On Thu, 3/19/09, Vieri <rentorbuy at yahoo.com> wrote:

> I have a PRI E1 link between Asterisk 1.4.24 and
> Alcatel-Lucent OmniPCX Enterprise R9.0.
> 
> As EuroISDN it works fine.
> 
> However, I need to move to QSIG because of a firmware
> upgrade on the Alcatel PBX which doesn't support EuroISDN
> (please don't ask why).
> 
> Besides, I've read somewhere that 2 B Channel Transfers
> "should" work with * 1.4, the latest 1.4 libpri and QSIG.
> So this morning I thought I'd try migrating from EuroISDN
> to QSIG. I kept the exact same settings on both Asterisk and
> Alcatel since they worked fine over EuroISDN; all I changed
> was "switchtype=qsig" on * and used a QSIG trunk group on
> Alcatel.
> 
> I tried calling from an Alcatel to an * extension and found
> that the destination phone rings but after about 4 seconds
> the call is aborted, whether I pick the phone up or not.
> 
> In the following log, Alcatel extension 3210 tries to call
> Asterisk extension 4062.
> 
> Can anyone please help?
> 
> Thanks,
> Vieri
> 
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Protocol
> Discriminator: Q.931 (8)  len=39
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Call Ref:
> len= 2 (reference 20968/0x51E8) (Originator)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Message
> type: SETUP (5)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < [a1]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Sending
> Complete (len= 1)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < [04 03 80
> 90 a3]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Bearer
> Capability (len= 5) [ Ext: 1  Q.931 Std: 0  Info
> transfer capability: Speech (0)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < 
>                
>             Ext: 1  Trans
> mode/rate: 64kbps, circuit-mode (16)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < 
>                
>               User
> information layer 1: A-Law (35)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < [18 03 a9
> 83 87]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Channel ID
> (len= 5) [ Ext: 1  IntID: Implicit  PRI 
> Spare: 0  Exclusive  Dchan: 0
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < 
>                
>       ChanSel: As indicated in following
> octets
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < 
>                
>      Ext: 1  Coding: 0  Number
> Specified  Channel Type: 3
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < 
>                
>      Ext: 1  Channel: 7 ]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < [6c 06 00
> 81 33 32 31 30]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Calling
> Number (len= 8) [ Ext: 0  TON: Unknown Number Type
> (0)  NPI: Unknown Number Plan (0)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < 
>                
>          Presentation:
> Presentation permitted, user number passed network screening
> (1)  '3210' ]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < [70 05 80
> 34 30 36 32]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Called
> Number (len= 7) [ Ext: 1  TON: Unknown Number Type
> (0)  NPI: Unknown Number Plan (0)  '4062' ]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < [7d 02 91
> 81]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < IE:
> High-layer Compatibility (len = 4)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < [95]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Locking
> Shift (len=01): Requested codeset 5
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < [32 01 81]
> [Mar 19 08:04:21] ERROR[11756] chan_dahdi.c: !! <
> Unknown IE 50 (cs5, len = 3)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: -- Making new
> call for cr 20968
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: -- Processing
> Q.931 Call Setup
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: -- Processing IE
> 161 (cs0, Sending Complete)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: -- Processing IE
> 4 (cs0, Bearer Capability)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: -- Processing IE
> 24 (cs0, Channel Identification)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: -- Processing IE
> 108 (cs0, Calling Party Number)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: -- Processing IE
> 112 (cs0, Called Party Number)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: -- Processing IE
> 125 (cs0, High-layer Compatibility)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: -- Processing IE
> 50 (cs5, Unknown Information Element)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: !! Unknown IE 50
> (cs5, Unknown Information Element)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: q931.c:3545
> q931_receive: call 20968 on channel 7 enters state 6 (Call
> Present)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: q931.c:2810
> q931_call_proceeding: call 20968 on channel 7 enters state 9
> (Incoming Call Proceeding)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: > Protocol
> Discriminator: Q.931 (8)  len=11
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: > Call Ref:
> len= 2 (reference 20968/0x51E8) (Terminator)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: > Message
> type: CALL PROCEEDING (2)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: > [18 04 e9
> 81 83 87]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: > Channel ID
> (len= 6) [ Ext: 1  IntID: Explicit  PRI 
> Spare: 0  Exclusive  Dchan: 0
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: > 
>                
>       ChanSel: As indicated in following
> octets
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: > 
>                
>      Ext: 1  DS1 Identifier:
>> [Mar 19 08:04:21] VERBOSE[11756] logger.c: > 
>                
>      Ext: 1  Coding: 0  Number
> Specified  Channel Type: 3
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: > 
>                
>      Ext: 1  Channel: 7 ]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: 
>    -- Accepting call from '3210' to '4062' on
> channel 1/7, span 1
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: 
>    -- Executing
> [4062 at from-pstn-deviate-custom:4] Answer("Zap/7-1", "") in
> new stack
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: q931.c:2943
> q931_connect: call 20968 on channel 7 enters state 10
> (Active)
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: > Protocol
> Discriminator: Q.931 (8)  len=15
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: > Call Ref:
> len= 2 (reference 20968/0x51E8) (Terminator)
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: > Message
> type: CONNECT (7)
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: > [18 04 e9
> 81 83 87]
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: > Channel ID
> (len= 6) [ Ext: 1  IntID: Explicit  PRI 
> Spare: 0  Exclusive  Dchan: 0
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: > 
>                
>       ChanSel: As indicated in following
> octets
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: > 
>                
>      Ext: 1  DS1 Identifier:
>> [Mar 19 08:04:21] VERBOSE[12808] logger.c: > 
>                
>      Ext: 1  Coding: 0  Number
> Specified  Channel Type: 3
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: > 
>                
>      Ext: 1  Channel: 7 ]
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: > [1e 02 81
> 82]
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: > Progress
> Indicator (len= 4) [ Ext: 1  Coding: CCITT (ITU)
> standard (0)  0: 0  Location: Private network
> serving the local user (1)
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: > 
>                
>              Ext:
> 1  Progress Description: Called equipment is non-ISDN.
> (2) ]
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: 
>    -- Goto (custom-TOSIP,4062,1)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Protocol
> Discriminator: Q.931 (8)  len=13
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Call Ref:
> len= 2 (reference 20968/0x51E8) (Originator)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Message
> type: STATUS (125)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < [08 03 80
> e4 18]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Cause (len=
> 5) [ Ext: 1  Coding: CCITT (ITU) standard (0) 
> Spare: 0  Location: User (0)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < 
>                 Ext:
> 1  Cause: Invalid information element contents (100),
> class = Protocol Error (e.g. unknown message) (6) ]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < 
>             Cause data 1: 18
> (24)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < [14 01 01]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Call State
> (len= 3) [ Ext: 0  Coding: CCITT (ITU) standard
> (0)  Call state: Call Initiated (1)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: -- Processing IE
> 8 (cs0, Cause)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: -- Processing IE
> 20 (cs0, Call State)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Protocol
> Discriminator: Q.931 (8)  len=13
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Call Ref:
> len= 2 (reference 20968/0x51E8) (Originator)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Message
> type: STATUS (125)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < [08 03 80
> e4 18]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Cause (len=
> 5) [ Ext: 1  Coding: CCITT (ITU) standard (0) 
> Spare: 0  Location: User (0)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < 
>                 Ext:
> 1  Cause: Invalid information element contents (100),
> class = Protocol Error (e.g. unknown message) (6) ]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < 
>             Cause data 1: 18
> (24)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < [14 01 01]
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: < Call State
> (len= 3) [ Ext: 0  Coding: CCITT (ITU) standard
> (0)  Call state: Call Initiated (1)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: -- Processing IE
> 8 (cs0, Cause)
> [Mar 19 08:04:21] VERBOSE[11756] logger.c: -- Processing IE
> 20 (cs0, Call State)
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: 
>    -- Executing [s at macro-dial:7]
> Dial("Zap/7-1", "SIP/4062||tTwW") in new stack
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: 
>    -- Called 4062
> [Mar 19 08:04:21] VERBOSE[12808] logger.c: 
>    -- SIP/4062-b4143ed0 is ringing
> [Mar 19 08:04:23] VERBOSE[12808] logger.c: 
>    -- SIP/4062-b4143ed0 answered Zap/7-1
> [Mar 19 08:04:25] VERBOSE[11756] logger.c: < Protocol
> Discriminator: Q.931 (8)  len=12
> [Mar 19 08:04:25] VERBOSE[11756] logger.c: < Call Ref:
> len= 2 (reference 20968/0x51E8) (Originator)
> [Mar 19 08:04:25] VERBOSE[11756] logger.c: < Message
> type: RELEASE COMPLETE (90)
> [Mar 19 08:04:25] VERBOSE[11756] logger.c: < [08 05 80
> e6 33 30 33]
> [Mar 19 08:04:25] VERBOSE[11756] logger.c: < Cause (len=
> 7) [ Ext: 1  Coding: CCITT (ITU) standard (0) 
> Spare: 0  Location: User (0)
> [Mar 19 08:04:25] VERBOSE[11756] logger.c: < 
>                 Ext:
> 1  Cause: Recover on timer expiry (102), class =
> Protocol Error (e.g. unknown message) (6) ]
> [Mar 19 08:04:25] VERBOSE[11756] logger.c: < 
>             Cause data:[Mar 19
> 08:04:25] VERBOSE[11756] logger.c:  33[Mar 19 08:04:25]
> VERBOSE[11756] logger.c:  30[Mar 19 08:04:25]
> VERBOSE[11756] logger.c:  33[Mar 19 08:04:25]
> VERBOSE[11756] logger.c:  (Timer T[Mar 19 08:04:25]
> VERBOSE[11756] logger.c: 3[Mar 19 08:04:25] VERBOSE[11756]
> logger.c: 0[Mar 19 08:04:25] VERBOSE[11756] logger.c: 3[Mar
> 19 08:04:25] VERBOSE[11756] logger.c: )
> [Mar 19 08:04:25] VERBOSE[11756] logger.c: -- Processing IE
> 8 (cs0, Cause)
> [Mar 19 08:04:25] VERBOSE[11756] logger.c: q931.c:3760
> q931_receive: call 20968 on channel 7 enters state 0 (Null)
> [Mar 19 08:04:25] VERBOSE[11756] logger.c: 
>    -- Channel 1/7, span 1 got hangup, cause
> 102
> [Mar 19 08:04:25] VERBOSE[12808] logger.c: 
>    -- Executing [h at macro-dial:1]
> Macro("Zap/7-1", "hangupcall") in new stack
> [Mar 19 08:04:25] VERBOSE[12808] logger.c: 
>    -- Executing [s at macro-hangupcall:11]
> Hangup("Zap/7-1", "") in new stack
> [Mar 19 08:04:25] DEBUG[12808] chan_dahdi.c: Set option
> AUDIO MODE, value: ON(1) on Zap/7-1
> [Mar 19 08:04:25] DEBUG[12808] chan_dahdi.c: Already
> hungup...  Calling hangup once, and clearing call
> [Mar 19 08:04:25] VERBOSE[12808] logger.c: NEW_HANGUP
> DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
> [Mar 19 08:04:25] VERBOSE[12808] logger.c: NEW_HANGUP
> DEBUG: Destroying the call, ourstate Null, peerstate Null
> [Mar 19 08:04:25] DEBUG[12808] chan_dahdi.c: Set option
> AUDIO MODE, value: OFF(0) on Zap/7-1
> [Mar 19 08:04:25] VERBOSE[12808] logger.c: 
>    -- Hungup 'Zap/7-1'
> 



      



More information about the asterisk-users mailing list