[asterisk-users] PRI QSIG Asterisk - Legacy PBX

[Vieri]

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: 1  
[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: 1  
[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'