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