[Home]

Summary:ASTERISK-11227: Hangup request recieved on q931 but asterisk does not hangup the channel
Reporter:Khurram Dar (khurramdar)Labels:
Date Opened:2008-01-13 07:18:18.000-0600Date Closed:2008-01-14 02:00:04.000-0600
Priority:MajorRegression?No
Status:Closed/CompleteComponents:. I did not set the category correctly.
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:
Description:We have an Asterisk based VAS setup. We use an 8 PRI Sangoma A104 card.

We use:
CentOS 5   v2.6.18-8.el5
wanpipe    v3.2..1
libpri     v1.4.3
zaptel     v1.4.7.1
asterisk   v1.4.16.2

The problem is that there are some calls which Asterisk does not hangup, even when it receives the disconnect signal.

Here is a sample call (pri intense debug) log: (conf files are at the end)

[Jan  8 13:59:47] VERBOSE[4565] logger.c: -- ACKing all packets from 15 to (but not including) 16 [Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Since there was nothing left, stopping T200 counter [Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Stopping T203 counter since we got an ACK [Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Nothing left, starting T203 counter [Jan  8 13:59:47] VERBOSE[4565] logger.c: < Protocol Discriminator: Q.931
(8)  len=47
[Jan  8 13:59:47] VERBOSE[4565] logger.c: < Call Ref: len= 2 (reference
34/0x22) (Originator)
[Jan  8 13:59:47] VERBOSE[4565] logger.c: < Message type: SETUP (5) [Jan  8 13:59:47] VERBOSE[4565] logger.c: < [a1] [Jan  8 13:59:47] VERBOSE[4565] logger.c: < Sending Complete (len= 1) [Jan  8 13:59:47] VERBOSE[4565] logger.c: < [04 03 80 90 a3] [Jan  8 13:59:47] VERBOSE[4565] logger.c: < Bearer Capability (len= 5) [
Ext: 1  Q.931 Std: 0  Info transfer capability: Speech (0)
[Jan  8 13:59:47] VERBOSE[4565] logger.c: <                            
Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
[Jan  8 13:59:47] VERBOSE[4565] logger.c: <                            
Ext: 1  User information layer 1: A-Law (35) [Jan  8 13:59:47] VERBOSE[4565] logger.c: < [18 03 a9 83 8d] [Jan  8 13:59:47] VERBOSE[4565] logger.c: < Channel ID (len= 5) [ Ext: 1
IntID: Implicit  PRI  Spare: 0  Exclusive  Dchan: 0
[Jan  8 13:59:47] VERBOSE[4565] logger.c: <                      
ChanSel: Reserved
[Jan  8 13:59:47] VERBOSE[4565] logger.c: <                       Ext: 1
Coding: 0  Number Specified  Channel Type: 3
[Jan  8 13:59:47] VERBOSE[4565] logger.c: <                       Ext: 1
Channel: 13 ]
[Jan  8 13:59:47] VERBOSE[4565] logger.c: < [6c 0c 21 83 33 34 33 32 31 33
36 35 39 33]
[Jan  8 13:59:47] VERBOSE[4565] logger.c: < Calling Number (len=14) [ Ext:
0  TON: National Number (2)  NPI: ISDN/Telephony Numbering Plan
(E.164/E.163) (1)
[Jan  8 13:59:47] VERBOSE[4565] logger.c: <                          
Presentation: Presentation allowed of network provided number (3) 'xxx1234567' ] [Jan  8 13:59:47] VERBOSE[4565] logger.c: < [70 0b 81 34 35 35 34 39 30 30
34 34 34]
[Jan  8 13:59:47] VERBOSE[4565] logger.c: < Called Number (len=13) [ Ext:
1  TON: Unknown Number Type (0)  NPI: ISDN/Telephony Numbering Plan
(E.164/E.163) (1)  '1234567890' ]
[Jan  8 13:59:47] VERBOSE[4565] logger.c: < [7d 02 91 81] [Jan  8 13:59:47] VERBOSE[4565] logger.c: < IE: High-layer Compatibility (len = 4) [Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Making new call for cr 34 [Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Processing Q.931 Call Setup [Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Processing IE 161 (cs0, Sending Complete) [Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Processing IE 4 (cs0, Bearer
Capability)
[Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Processing IE 24 (cs0, Channel Identification) [Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Processing IE 108 (cs0, Calling Party Number) [Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Processing IE 112 (cs0, Called Party Number) [Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Processing IE 125 (cs0, High-layer Compatibility) [Jan  8 13:59:47] VERBOSE[4565] logger.c: q931.c:3298 q931_receive: call
34 on channel 13 enters state 6 (Call Present) [Jan  8 13:59:47] VERBOSE[4565] logger.c: Sending Receiver Ready (6)

[Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Restarting T203 counter [Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Restarting T203 counter [Jan  8 13:59:47] VERBOSE[4565] logger.c: q931.c:2571
q931_call_proceeding: call 34 on channel 13 enters state 9 (Incoming Call
Proceeding)
[Jan  8 13:59:47] VERBOSE[4565] logger.c:

[Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Restarting T203 counter [Jan  8 13:59:47] VERBOSE[4565] logger.c: Stopping T_203 timer [Jan  8 13:59:47] VERBOSE[4565] logger.c: Starting T_200 timer [Jan  8 13:59:47] VERBOSE[4565] logger.c: > Protocol Discriminator: Q.931
(8)  len=10
[Jan  8 13:59:47] VERBOSE[4565] logger.c: > Call Ref: len= 2 (reference
34/0x22) (Terminator)
[Jan  8 13:59:47] VERBOSE[4565] logger.c: > Message type: CALL PROCEEDING (2) [Jan  8 13:59:47] VERBOSE[4565] logger.c: > [18 03 a9 83 8d] [Jan  8 13:59:47] VERBOSE[4565] logger.c: > Channel ID (len= 5) [ Ext: 1
IntID: Implicit  PRI  Spare: 0  Exclusive  Dchan: 0
[Jan  8 13:59:47] VERBOSE[4565] logger.c: >                      
ChanSel: Reserved
[Jan  8 13:59:47] VERBOSE[4565] logger.c: >                       Ext: 1
Coding: 0  Number Specified  Channel Type: 3
[Jan  8 13:59:47] VERBOSE[4565] logger.c: >                       Ext: 1
Channel: 13 ]
[Jan  8 13:59:47] VERBOSE[4565] logger.c:     -- Accepting call from
'xxx1234567' to '1234567890' on channel 0/13, span 5
[Jan  8 13:59:47] VERBOSE[25905] logger.c:     -- Executing
[1234567890@default:1] Set("Zap/106-1", "__DEST=123") in new stack
[Jan  8 13:59:47] VERBOSE[25905] logger.c:     -- Executing
[1234567890@default:2] SetCDRUserField("Zap/106-1", "123") in new stack
[Jan  8 13:59:47] VERBOSE[25905] logger.c:     -- Executing
[1234567890@default:3] Goto("Zap/106-1", "SERVICE1|s|1") in new stack
[Jan  8 13:59:47] VERBOSE[25905] logger.c:     -- Goto (SERVICE1,s,1)
[Jan  8 13:59:47] VERBOSE[25905] logger.c:     -- Executing [s@SERVICE1:1]
Answer("Zap/106-1", "") in new stack
[Jan  8 13:59:47] VERBOSE[25905] logger.c: q931.c:2700 q931_connect: call
34 on channel 13 enters state 8 (Connect Request) [Jan  8 13:59:47] VERBOSE[25905] logger.c:

[Jan  8 13:59:47] VERBOSE[25905] logger.c: T_200 timer already going (1) [Jan  8 13:59:47] VERBOSE[25905] logger.c: > Protocol Discriminator: Q.931
(8)  len=14
[Jan  8 13:59:47] VERBOSE[25905] logger.c: > Call Ref: len= 2 (reference
34/0x22) (Terminator)
[Jan  8 13:59:47] VERBOSE[25905] logger.c: > Message type: CONNECT (7) [Jan  8 13:59:47] VERBOSE[25905] logger.c: > [18 03 a9 83 8d] [Jan  8 13:59:47] VERBOSE[25905] logger.c: > Channel ID (len= 5) [ Ext: 1
IntID: Implicit  PRI  Spare: 0  Exclusive  Dchan: 0
[Jan  8 13:59:47] VERBOSE[25905] logger.c: >                      
ChanSel: Reserved
[Jan  8 13:59:47] VERBOSE[25905] logger.c: >                       Ext: 1
Coding: 0  Number Specified  Channel Type: 3
[Jan  8 13:59:47] VERBOSE[25905] logger.c: >                       Ext: 1
Channel: 13 ]
[Jan  8 13:59:47] VERBOSE[25905] logger.c: > [1e 02 81 82] [Jan  8 13:59:47] VERBOSE[25905] logger.c: > Progress Indicator (len= 4) [
Ext: 1  Coding: CCITT (ITU) standard (0)  0: 0  Location: Private network serving the local user (1)
[Jan  8 13:59:47] VERBOSE[25905] logger.c: >                              
Ext: 1  Progress Description: Called equipment is non-ISDN. (2) ]
[Jan  8 13:59:47] VERBOSE[25905] logger.c:     -- Executing [s@SERVICE1:2]
Set("Zap/106-1", "__DB_USER_ID=1199782787") in new stack
[Jan  8 13:59:47] VERBOSE[25905] logger.c:     -- Executing [s@SERVICE1:3]
Set("Zap/106-1", "__DB_CLI=3432136593") in new stack
[Jan  8 13:59:47] VERBOSE[25905] logger.c:     -- Executing [s@SERVICE1:4]
Playback("Zap/106-1", "/prompts/service1/welcome") in new stack
[Jan  8 13:59:47] VERBOSE[25905] logger.c:     -- <Zap/106-1> Playing
'/prompts/service1/welcome' (language 'en') [Jan  8 13:59:47] VERBOSE[4565] logger.c:

[Jan  8 13:59:47] VERBOSE[4565] logger.c: -- ACKing all packets from 17 to (but not including) 18 [Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Since there was nothing left, stopping T200 counter [Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Stopping T203 counter since we got an ACK [Jan  8 13:59:47] VERBOSE[4565] logger.c: -- Nothing left, starting T203 counter [Jan  8 13:59:47] VERBOSE[4565] logger.c: < Protocol Discriminator: Q.931
(8)  len=5
[Jan  8 13:59:47] VERBOSE[4565] logger.c: < Call Ref: len= 2 (reference
34/0x22) (Originator)
[Jan  8 13:59:47] VERBOSE[4565] logger.c: < Message type: CONNECT ACKNOWLEDGE (15) [Jan  8 13:59:47] VERBOSE[4565] logger.c: q931.c:3456 q931_receive: call
34 on channel 13 enters state 10 (Active) [Jan  8 13:59:47] VERBOSE[4565] logger.c: Sending Receiver Ready (7) [Jan  8 13:59:47] VERBOSE[4565] logger.c:

[Jan  8 14:01:47] VERBOSE[4565] logger.c: -- ACKing all packets from 29 to (but not including) 30 [Jan  8 14:01:47] VERBOSE[4565] logger.c: -- Since there was nothing left, stopping T200 counter [Jan  8 14:01:47] VERBOSE[4565] logger.c: -- Stopping T203 counter since we got an ACK [Jan  8 14:01:47] VERBOSE[4565] logger.c: -- Nothing left, starting T203 counter [Jan  8 14:01:47] VERBOSE[4565] logger.c: < Protocol Discriminator: Q.931
(8)  len=13
[Jan  8 14:01:47] VERBOSE[4565] logger.c: < Call Ref: len= 2 (reference
34/0x22) (Originator)
[Jan  8 14:01:47] VERBOSE[4565] logger.c: < Message type: DISCONNECT (69) [Jan  8 14:01:47] VERBOSE[4565] logger.c: < [08 02 80 90] [Jan  8 14:01:47] VERBOSE[4565] logger.c: < Cause (len= 4) [ Ext: 1
Coding: CCITT (ITU) standard (0)  Spare: 0  Location: User (0)
[Jan  8 14:01:47] VERBOSE[4565] logger.c: <                  Ext: 1
Cause: Normal Clearing (16), class = Normal Event (1) ] [Jan  8 14:01:47] VERBOSE[4565] logger.c: < [1e 02 82 88] [Jan  8 14:01:47] VERBOSE[4565] logger.c: < Progress Indicator (len= 4) [
Ext: 1  Coding: CCITT (ITU) standard (0)  0: 0  Location: Public network serving the local user (2)
[Jan  8 14:01:47] VERBOSE[4565] logger.c: <                              
Ext: 1  Progress Description: Inband information or appropriate pattern now available. (8) ] [Jan  8 14:01:47] VERBOSE[4565] logger.c: -- Processing IE 8 (cs0, Cause) [Jan  8 14:01:47] VERBOSE[4565] logger.c: -- Processing IE 30 (cs0, Progress Indicator) [Jan  8 14:01:47] VERBOSE[4565] logger.c: q931.c:3563 q931_receive: call
34 on channel 13 enters state 12 (Disconnect Indication) [Jan  8 14:01:47] VERBOSE[4565] logger.c: Sending Receiver Ready (63) [Jan  8 14:01:47] VERBOSE[4565] logger.c:

[Jan  8 14:02:27] VERBOSE[4565] logger.c: -- ACKing all packets from 80 to (but not including) 81 [Jan  8 14:02:27] VERBOSE[4565] logger.c: -- Since there was nothing left, stopping T200 counter [Jan  8 14:02:27] VERBOSE[4565] logger.c: -- Stopping T203 counter since we got an ACK [Jan  8 14:02:27] VERBOSE[4565] logger.c: -- Nothing left, starting T203 counter [Jan  8 14:02:27] VERBOSE[4565] logger.c: < Protocol Discriminator: Q.931
(8)  len=9
[Jan  8 14:02:27] VERBOSE[4565] logger.c: < Call Ref: len= 2 (reference
34/0x22) (Originator)
[Jan  8 14:02:27] VERBOSE[4565] logger.c: < Message type: RELEASE (77) [Jan  8 14:02:27] VERBOSE[4565] logger.c: < [08 02 80 90] [Jan  8 14:02:27] VERBOSE[4565] logger.c: < Cause (len= 4) [ Ext: 1
Coding: CCITT (ITU) standard (0)  Spare: 0  Location: User (0)
[Jan  8 14:02:27] VERBOSE[4565] logger.c: <                  Ext: 1
Cause: Normal Clearing (16), class = Normal Event (1) ] [Jan  8 14:02:27] VERBOSE[4565] logger.c: -- Processing IE 8 (cs0, Cause) [Jan  8 14:02:27] VERBOSE[4565] logger.c: q931.c:3538 q931_receive: call
34 on channel 13 enters state 0 (Null)
[Jan  8 14:02:27] VERBOSE[4565] logger.c: Sending Receiver Ready (6) [Jan  8 14:02:27] VERBOSE[4565] logger.c:

[Jan  8 14:02:31] VERBOSE[4565] logger.c: -- ACKing all packets from 84 to (but not including) 85 [Jan  8 14:02:31] VERBOSE[4565] logger.c: -- Since there was nothing left, stopping T200 counter [Jan  8 14:02:31] VERBOSE[4565] logger.c: -- Stopping T203 counter since we got an ACK [Jan  8 14:02:31] VERBOSE[4565] logger.c: -- Nothing left, starting T203 counter [Jan  8 14:02:31] VERBOSE[4565] logger.c: < Protocol Discriminator: Q.931
(8)  len=9
[Jan  8 14:02:31] VERBOSE[4565] logger.c: < Call Ref: len= 2 (reference
34/0x22) (Originator)
[Jan  8 14:02:31] VERBOSE[4565] logger.c: < Message type: RELEASE (77) [Jan  8 14:02:31] VERBOSE[4565] logger.c: < [08 02 80 90] [Jan  8 14:02:31] VERBOSE[4565] logger.c: < Cause (len= 4) [ Ext: 1
Coding: CCITT (ITU) standard (0)  Spare: 0  Location: User (0)
[Jan  8 14:02:31] VERBOSE[4565] logger.c: <                  Ext: 1
Cause: Normal Clearing (16), class = Normal Event (1) ] [Jan  8 14:02:31] VERBOSE[4565] logger.c: -- Processing IE 8 (cs0, Cause) [Jan  8 14:02:31] VERBOSE[4565] logger.c: Sending Receiver Ready (13) [Jan  8 14:02:31] VERBOSE[4565] logger.c:

[Jan  8 14:02:31] VERBOSE[4565] logger.c: -- Restarting T203 counter [Jan  8 14:02:31] VERBOSE[4565] logger.c: -- Restarting T203 counter [Jan  8 14:02:31] VERBOSE[4565] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Release Request [Jan  8 14:02:31] VERBOSE[4565] logger.c:

[Jan  8 14:02:31] VERBOSE[4565] logger.c: -- Restarting T203 counter [Jan  8 14:02:31] VERBOSE[4565] logger.c: Stopping T_203 timer [Jan  8 14:02:31] VERBOSE[4565] logger.c: Starting T_200 timer [Jan  8 14:02:31] VERBOSE[4565] logger.c: > Protocol Discriminator: Q.931
(8)  len=9
[Jan  8 14:02:31] VERBOSE[4565] logger.c: > Call Ref: len= 2 (reference
34/0x22) (Terminator)
[Jan  8 14:02:31] VERBOSE[4565] logger.c: > Message type: RELEASE COMPLETE
(90)
[Jan  8 14:02:31] VERBOSE[4565] logger.c: > [08 02 81 90] [Jan  8 14:02:31] VERBOSE[4565] logger.c: > Cause (len= 4) [ Ext: 1
Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Private network serving the local user (1)
[Jan  8 14:02:31] VERBOSE[4565] logger.c: >                  Ext: 1
Cause: Normal Clearing (16), class = Normal Event (1) ] [Jan  8 14:02:31] VERBOSE[4565] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null [Jan  8 14:02:31] VERBOSE[4565] logger.c: NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null [Jan  8 14:02:31] VERBOSE[4565] logger.c:

[Jan  8 14:02:50] VERBOSE[4565] logger.c: -- Restarting T203 counter [Jan  8 14:02:50] VERBOSE[4565] logger.c: -- Restarting T203 counter [Jan  8 14:02:50] DEBUG[4565] chan_zap.c: Ring requested on channel 0/13 already in use or previously requested on span 5.  Attempting to renegotiating channel.
[Jan  8 14:02:50] VERBOSE[4565] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Call Present, peerstate Call Initiated [Jan  8 14:02:50] VERBOSE[4565] logger.c: q931.c:2893
q931_release_complete: call 121 on channel 13 enters state 0 (Null) [Jan  8 14:02:50] VERBOSE[4565] logger.c:

[Jan  8 14:03:14] DEBUG[4565] chan_zap.c: Ring requested on channel 0/13 already in use or previously requested on span 5.  Attempting to renegotiating channel.
[Jan  8 14:03:14] VERBOSE[4565] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Call Present, peerstate Call Initiated [Jan  8 14:03:14] VERBOSE[4565] logger.c: q931.c:2893
q931_release_complete: call 135 on channel 13 enters state 0 (Null) [Jan  8 14:03:14] VERBOSE[4565] logger.c:

[Jan  8 14:03:38] DEBUG[4565] chan_zap.c: Ring requested on channel 0/13 already in use or previously requested on span 5.  Attempting to renegotiating channel.
[Jan  8 14:03:38] VERBOSE[4565] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Call Present, peerstate Call Initiated [Jan  8 14:03:38] VERBOSE[4565] logger.c: q931.c:2893
q931_release_complete: call 155 on channel 13 enters state 0 (Null) [Jan  8 14:03:38] VERBOSE[4565] logger.c:

[Jan  8 14:04:14] DEBUG[4565] chan_zap.c: Ring requested on channel 0/13 already in use or previously requested on span 5.  Attempting to renegotiating channel.
[Jan  8 14:04:14] VERBOSE[4565] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Call Present, peerstate Call Initiated [Jan  8 14:04:14] VERBOSE[4565] logger.c: q931.c:2893
q931_release_complete: call 171 on channel 13 enters state 0 (Null) [Jan  8 14:04:14] VERBOSE[4565] logger.c:

[Jan  8 14:04:49] DEBUG[4565] chan_zap.c: Ring requested on channel 0/13 already in use or previously requested on span 5.  Attempting to renegotiating channel.
[Jan  8 14:04:49] VERBOSE[4565] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Call Present, peerstate Call Initiated [Jan  8 14:04:49] VERBOSE[4565] logger.c: q931.c:2893
q931_release_complete: call 193 on channel 13 enters state 0 (Null) [Jan  8 14:04:49] VERBOSE[4565] logger.c:

[Jan  8 14:05:23] DEBUG[4565] chan_zap.c: Ring requested on channel 0/13 already in use or previously requested on span 5.  Attempting to renegotiating channel.
[Jan  8 14:05:23] VERBOSE[4565] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Call Present, peerstate Call Initiated [Jan  8 14:05:23] VERBOSE[4565] logger.c: q931.c:2893
q931_release_complete: call 214 on channel 13 enters state 0 (Null) [Jan  8 14:05:23] VERBOSE[4565] logger.c:

[Jan  8 14:05:58] DEBUG[4565] chan_zap.c: Ring requested on channel 0/13 already in use or previously requested on span 5.  Attempting to renegotiating channel.
[Jan  8 14:05:58] VERBOSE[4565] logger.c: NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Call Present, peerstate Call Initiated [Jan  8 14:05:58] VERBOSE[4565] logger.c: q931.c:2893
q931_release_complete: call 235 on channel 13 enters state 0 (Null) [Jan  8 14:05:58] VERBOSE[4565] logger.c:

[Jan  8 14:06:19] VERBOSE[4565] logger.c: -- Restarting T203 counter [Jan  8 14:06:19] VERBOSE[4565] logger.c: -- Restarting T203 counter
[Jan  8 14:06:19] VERBOSE[25905] logger.c:   == Spawn extension
(SERVICE1_STEP2, s, 8) exited non-zero on 'Zap/106-1'
[Jan  8 14:06:19] DEBUG[25905] chan_zap.c: Set option AUDIO MODE, value:
ON(1) on Zap/106-1
[Jan  8 14:06:19] DEBUG[25905] chan_zap.c: Set option AUDIO MODE, value:
OFF(0) on Zap/106-1
[Jan  8 14:06:19] VERBOSE[25905] logger.c:     -- Hungup 'Zap/106-1'

[Jan  8 14:06:34] VERBOSE[4565] logger.c: -- ACKing all packets from 78 to (but not including) 79 [Jan  8 14:06:34] VERBOSE[4565] logger.c: -- Since there was nothing left, stopping T200 counter [Jan  8 14:06:34] VERBOSE[4565] logger.c: -- Stopping T203 counter since we got an ACK [Jan  8 14:06:34] VERBOSE[4565] logger.c: -- Nothing left, starting T203 counter [Jan  8 14:06:34] VERBOSE[4565] logger.c: < Protocol Discriminator: Q.931
(8)  len=47
[Jan  8 14:06:34] VERBOSE[4565] logger.c: < Call Ref: len= 2 (reference
260/0x104) (Originator)
[Jan  8 14:06:34] VERBOSE[4565] logger.c: < Message type: SETUP (5) [Jan  8 14:06:34] VERBOSE[4565] logger.c: < [a1] [Jan  8 14:06:34] VERBOSE[4565] logger.c: < Sending Complete (len= 1) [Jan  8 14:06:34] VERBOSE[4565] logger.c: < [04 03 80 90 a3] [Jan  8 14:06:34] VERBOSE[4565] logger.c: < Bearer Capability (len= 5) [
Ext: 1  Q.931 Std: 0  Info transfer capability: Speech (0)
[Jan  8 14:06:34] VERBOSE[4565] logger.c: <                            
Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
[Jan  8 14:06:34] VERBOSE[4565] logger.c: <                            
Ext: 1  User information layer 1: A-Law (35) [Jan  8 14:06:34] VERBOSE[4565] logger.c: < [18 03 a9 83 8d] [Jan  8 14:06:34] VERBOSE[4565] logger.c: < Channel ID (len= 5) [ Ext: 1
IntID: Implicit  PRI  Spare: 0  Exclusive  Dchan: 0
[Jan  8 14:06:34] VERBOSE[4565] logger.c: <                      
ChanSel: Reserved
[Jan  8 14:06:34] VERBOSE[4565] logger.c: <                       Ext: 1
Coding: 0  Number Specified  Channel Type: 3
[Jan  8 14:06:34] VERBOSE[4565] logger.c: <                       Ext: 1
Channel: 13 ]
[Jan  8 14:06:34] VERBOSE[4565] logger.c: < [6c 0c 21 83 33 34 35 34 30 32
36 30 35 33]
[Jan  8 14:06:34] VERBOSE[4565] logger.c: < Calling Number (len=14) [ Ext:
0  TON: National Number (2)  NPI: ISDN/Telephony Numbering Plan
(E.164/E.163) (1)
[Jan  8 14:06:34] VERBOSE[4565] logger.c: <                          
Presentation: Presentation allowed of network provided number (3) '3454026053' ] [Jan  8 14:06:34] VERBOSE[4565] logger.c: < [70 0b 81 34 35 35 34 39 30 30
34 34 34]
[Jan  8 14:06:34] VERBOSE[4565] logger.c: < Called Number (len=13) [ Ext:
1  TON: Unknown Number Type (0)  NPI: ISDN/Telephony Numbering Plan
(E.164/E.163) (1)  '1234567890' ]
[Jan  8 14:06:34] VERBOSE[4565] logger.c: < [7d 02 91 81] [Jan  8 14:06:34] VERBOSE[4565] logger.c: < IE: High-layer Compatibility (len = 4) [Jan  8 14:06:34] VERBOSE[4565] logger.c: -- Making new call for cr 260 [Jan  8 14:06:34] VERBOSE[4565] logger.c: -- Processing Q.931 Call Setup [Jan  8 14:06:34] VERBOSE[4565] logger.c: -- Processing IE 161 (cs0, Sending Complete) [Jan  8 14:06:34] VERBOSE[4565] logger.c: -- Processing IE 4 (cs0, Bearer
Capability)
[Jan  8 14:06:34] VERBOSE[4565] logger.c: -- Processing IE 24 (cs0, Channel Identification) [Jan  8 14:06:34] VERBOSE[4565] logger.c: -- Processing IE 108 (cs0, Calling Party Number) [Jan  8 14:06:34] VERBOSE[4565] logger.c: -- Processing IE 112 (cs0, Called Party Number) [Jan  8 14:06:34] VERBOSE[4565] logger.c: -- Processing IE 125 (cs0, High-layer Compatibility) [Jan  8 14:06:34] VERBOSE[4565] logger.c: q931.c:3298 q931_receive: call 260 on channel 13 enters state 6 (Call Present) [Jan  8 14:06:34] VERBOSE[4565] logger.c: Sending Receiver Ready (122) [Jan  8 14:06:34] VERBOSE[4565] logger.c:



****** ADDITIONAL INFORMATION ******


-------------------------------------------------
Here are the conf files:
-------------------------------------------------
/etc/wanpipe/wanpipe1.conf

[devices]
wanpipe1 = WAN_AFT_TE1, Comment

[interfaces]
w1g1 = wanpipe1, , TDM_VOICE, Comment

[wanpipe1]
CARD_TYPE       = AFT
S514CPU         = A
CommPort        = PRI
AUTO_PCISLOT    = NO
PCISLOT         = 1
PCIBUS          = 6
FE_MEDIA        = E1
FE_LCODE        = HDB3
FE_FRAME        = CRC4
FE_LINE         = 1
TE_CLOCK        = NORMAL
TE_REF_CLOCK    = 0
TE_SIG_MODE     = CCS
TE_HIGHIMPEDANCE        = NO
LBO             = 120OH
FE_TXTRISTATE   = NO
MTU             = 1500
UDPPORT         = 9000
TTL             = 255
IGNORE_FRONT_END = NO
TDMV_SPAN       = 1
TDMV_DCHAN      = 16

[w1g1]
ACTIVE_CH       = ALL
TDMV_ECHO_OFF   = NO
TDMV_HWEC       = NO

wanpipe2 = WAN_AFT_TE1, Comment

[interfaces]
w2g1 = wanpipe2, , TDM_VOICE, Comment

[wanpipe2]
CARD_TYPE       = AFT
S514CPU         = A
CommPort        = PRI
AUTO_PCISLOT    = NO
PCISLOT         = 1
PCIBUS          = 6
FE_MEDIA        = E1
FE_LCODE        = HDB3
FE_FRAME        = CRC4
FE_LINE         = 2
TE_CLOCK        = NORMAL
TE_REF_CLOCK    = 0
TE_SIG_MODE     = CCS
TE_HIGHIMPEDANCE        = NO
LBO             = 120OH
FE_TXTRISTATE   = NO
MTU             = 1500
UDPPORT         = 9000
TTL             = 255
IGNORE_FRONT_END = NO
TDMV_SPAN       = 2
TDMV_DCHAN      = 16

[w2g1]
ACTIVE_CH       = ALL
TDMV_ECHO_OFF   = NO
TDMV_HWEC       = NO

-------------------------------------------------
/etc/wanpipe/wanpipe3.conf

[devices]
wanpipe3 = WAN_AFT_TE1, Comment

[interfaces]
w3g1 = wanpipe3, , TDM_VOICE, Comment

[wanpipe3]
CARD_TYPE       = AFT
S514CPU         = A
CommPort        = PRI
AUTO_PCISLOT    = NO
PCISLOT         = 1
PCIBUS          = 6
FE_MEDIA        = E1
FE_LCODE        = HDB3
FE_FRAME        = CRC4
FE_LINE         = 3
TE_CLOCK        = NORMAL
TE_REF_CLOCK    = 0
TE_SIG_MODE     = CCS
TE_HIGHIMPEDANCE        = NO
LBO             = 120OH
FE_TXTRISTATE   = NO
MTU             = 1500
UDPPORT         = 9000
TTL             = 255
IGNORE_FRONT_END = NO
TDMV_SPAN       = 3
TDMV_DCHAN      = 16

[w3g1]
ACTIVE_CH       = ALL
TDMV_ECHO_OFF   = NO
TDMV_HWEC       = NO

-------------------------------------------------
/etc/wanpipe/wanpipe4.conf

[devices]
wanpipe4 = WAN_AFT_TE1, Comment

[interfaces]
w4g1 = wanpipe4, , TDM_VOICE, Comment

[wanpipe4]
CARD_TYPE       = AFT
S514CPU         = A
CommPort        = PRI
AUTO_PCISLOT    = NO
PCISLOT         = 1
PCIBUS          = 6
FE_MEDIA        = E1
FE_LCODE        = HDB3
FE_FRAME        = CRC4
FE_LINE         = 4
TE_CLOCK        = NORMAL
TE_REF_CLOCK    = 0
TE_SIG_MODE     = CCS
TE_HIGHIMPEDANCE        = NO
LBO             = 120OH
FE_TXTRISTATE   = NO
MTU             = 1500
UDPPORT         = 9000
TTL             = 255
IGNORE_FRONT_END = NO
TDMV_SPAN       = 4
TDMV_DCHAN      = 16

[w4g1]
ACTIVE_CH       = ALL
TDMV_ECHO_OFF   = NO
TDMV_HWEC       = NO

-------------------------------------------------
/etc/wanpipe/wanpipe5.conf

[devices]
wanpipe5 = WAN_AFT_TE1, Comment

[interfaces]
w5g1 = wanpipe5, , TDM_VOICE, Comment

[wanpipe5]
CARD_TYPE       = AFT
S514CPU         = A
CommPort        = PRI
AUTO_PCISLOT    = NO
PCISLOT         = 2
PCIBUS          = 6
FE_MEDIA        = E1
FE_LCODE        = HDB3
FE_FRAME        = CRC4
FE_LINE         = 1
TE_CLOCK        = NORMAL
TE_REF_CLOCK    = 0
TE_SIG_MODE     = CCS
TE_HIGHIMPEDANCE        = NO
LBO             = 120OH
FE_TXTRISTATE   = NO
MTU             = 1500
UDPPORT         = 9000
TTL             = 255
IGNORE_FRONT_END = NO
TDMV_SPAN       = 5
TDMV_DCHAN      = 16

[w5g1]
ACTIVE_CH       = ALL
TDMV_ECHO_OFF   = NO
TDMV_HWEC       = NO

-------------------------------------------------
/etc/wanpipe/wanpipe6.conf

[devices]
wanpipe6 = WAN_AFT_TE1, Comment

[interfaces]
w6g1 = wanpipe6, , TDM_VOICE, Comment

[wanpipe6]
CARD_TYPE       = AFT
S514CPU         = A
CommPort        = PRI
AUTO_PCISLOT    = NO
PCISLOT         = 2
PCIBUS          = 6
FE_MEDIA        = E1
FE_LCODE        = HDB3
FE_FRAME        = CRC4
FE_LINE         = 2
TE_CLOCK        = NORMAL
TE_REF_CLOCK    = 0
TE_SIG_MODE     = CCS
TE_HIGHIMPEDANCE        = NO
LBO             = 120OH
FE_TXTRISTATE   = NO
MTU             = 1500
UDPPORT         = 9000
TTL             = 255
IGNORE_FRONT_END = NO
TDMV_SPAN       = 6
TDMV_DCHAN      = 16

[w6g1]
ACTIVE_CH       = ALL
TDMV_ECHO_OFF   = NO
TDMV_HWEC       = NO

-------------------------------------------------
/etc/wanpipe/wanpipe7.conf

[devices]
wanpipe7 = WAN_AFT_TE1, Comment

[interfaces]
w7g1 = wanpipe7, , TDM_VOICE, Comment

[wanpipe7]
CARD_TYPE       = AFT
S514CPU         = A
CommPort        = PRI
AUTO_PCISLOT    = NO
PCISLOT         = 2
PCIBUS          = 6
FE_MEDIA        = E1
FE_LCODE        = HDB3
FE_FRAME        = CRC4
FE_LINE         = 3
TE_CLOCK        = NORMAL
TE_REF_CLOCK    = 0
TE_SIG_MODE     = CCS
TE_HIGHIMPEDANCE        = NO
LBO             = 120OH
FE_TXTRISTATE   = NO
MTU             = 1500
UDPPORT         = 9000
TTL             = 255
IGNORE_FRONT_END = NO
TDMV_SPAN       = 7
TDMV_DCHAN      = 16

[w7g1]
ACTIVE_CH       = ALL
TDMV_ECHO_OFF   = NO
TDMV_HWEC       = NO

-------------------------------------------------
/etc/wanpipe/wanpipe8.conf

[devices]
wanpipe8 = WAN_AFT_TE1, Comment

[interfaces]
w8g1 = wanpipe8, , TDM_VOICE, Comment

[wanpipe8]
CARD_TYPE       = AFT
S514CPU         = A
CommPort        = PRI
AUTO_PCISLOT    = NO
PCISLOT         = 2
PCIBUS          = 6
FE_MEDIA        = E1
FE_LCODE        = HDB3
FE_FRAME        = CRC4
FE_LINE         = 4
TE_CLOCK        = NORMAL
TE_REF_CLOCK    = 0
TE_SIG_MODE     = CCS
TE_HIGHIMPEDANCE        = NO
LBO             = 120OH
FE_TXTRISTATE   = NO
MTU             = 1500
UDPPORT         = 9000
TTL             = 255
IGNORE_FRONT_END = NO
TDMV_SPAN       = 8
TDMV_DCHAN      = 16

[w8g1]
ACTIVE_CH       = ALL
TDMV_ECHO_OFF   = NO
TDMV_HWEC       = NO

-------------------------------------------------
/etc/zaptel.conf

loadzone=us
defaultzone=us

#Sangoma A104 port 1 [slot:1 bus:6 span: 1]
span=1,6,0,ccs,hdb3,crc4
bchan=1-15,17-31
dchan=16

#Sangoma A104 port 2 [slot:1 bus:6 span: 2]
span=2,7,0,ccs,hdb3,crc4
bchan=32-46,48-62
dchan=47

#Sangoma A104 port 3 [slot:1 bus:6 span: 3]
span=3,8,0,ccs,hdb3,crc4
bchan=63-77,79-93
dchan=78

#Sangoma A104 port 4 [slot:1 bus:6 span: 4]
span=4,1,0,ccs,hdb3,crc4
bchan=94-108,110-124
dchan=109

#Sangoma A104 port 1 [slot:2 bus:6 span: 5]
span=5,2,0,ccs,hdb3,crc4
bchan=125-139,141-155
dchan=140

#Sangoma A104 port 2 [slot:2 bus:6 span: 6]
span=6,3,0,ccs,hdb3,crc4
bchan=156-170,172-186
dchan=171

#Sangoma A104 port 3 [slot:2 bus:6 span: 7]
span=7,4,0,ccs,hdb3,crc4
bchan=187-201,203-217
dchan=202

#Sangoma A104 port 4 [slot:2 bus:6 span: 8]
span=8,5,0,ccs,hdb3,crc4
bchan=218-232,234-248
dchan=233

-------------------------------------------------
/etc/asterisk/zapata.conf

[trunkgroups]

[channels]
context=default
usecallerid=yes
hidecallerid=no
callwaiting=yes
usecallingpres=yes
callwaitingcallerid=yes
threewaycalling=yes
transfer=yes
canpark=yes
cancallforward=yes
callreturn=yes
echocancel=yes
echocancelwhenbridged=yes
relaxdtmf=yes
rxgain=0.0
txgain=0.0
group=1
callgroup=1
pickupgroup=1

immediate=no

;Sangoma A104 port 1 [slot:1 bus:6 span: 1] switchtype=euroisdn context=default
group=1
signalling=pri_cpe
channel => 1-15,17-31

;Sangoma A104 port 2 [slot:1 bus:6 span: 2] switchtype=euroisdn context=default
group=1
signalling=pri_cpe
channel => 32-46,48-62

;Sangoma A104 port 3 [slot:1 bus:6 span: 3] switchtype=euroisdn context=default
group=1
signalling=pri_cpe
channel => 63-77,79-93

;Sangoma A104 port 4 [slot:1 bus:6 span: 4] switchtype=euroisdn context=default
group=1
signalling=pri_cpe
channel => 94-108,110-124

;Sangoma A104 port 1 [slot:2 bus:6 span: 5] switchtype=euroisdn context=default
group=1
signalling=pri_cpe
channel => 125-139,141-155

;Sangoma A104 port 2 [slot:2 bus:6 span: 6] switchtype=euroisdn context=default
group=1
signalling=pri_cpe
channel => 156-170,172-186

;Sangoma A104 port 3 [slot:2 bus:6 span: 7] switchtype=euroisdn context=default
group=1
signalling=pri_cpe
channel => 187-201,203-217

;Sangoma A104 port 4 [slot:2 bus:6 span: 8] switchtype=euroisdn context=default
group=1
signalling=pri_cpe
channel => 218-232,234-248

Comments:By: Michiel van Baak (mvanbaak) 2008-01-14 02:00:03.000-0600

Can you upload those files to ticket 11762 that you created earlier for this?