[asterisk-users] Freeswitch with Digium T316 timed out, T316 timed out

Rajat toshniwal rajat.toshniwal at tekmindz.com
Thu Aug 8 09:39:36 CDT 2013


Hi

I am trying to deploy freeswitch with Digium TE121 card for my office
setup, but it is continuously showing Signaling is up and channels are
down except D channel.
Our Architecture is like
We have freeswitch installed with libpri1.4 and Dahdi.
I am from India and here we are having E1 trunk.

Dahdi Configuration is

cat system.conf
# Autogenerated by /usr/sbin/dahdi_genconf on Wed Aug  7 19:39:07 2013
# If you edit this file and execute /usr/sbin/dahdi_genconf again,
# your manual changes will be LOST.
# Dahdi Configuration File
#
# This file is parsed by the Dahdi Configurator, dahdi_cfg
#
# Span 1: WCT1/0 "Wildcard TE121 Card 0" (MASTER)
span=1,1,0,ccs,hdb3,crc4
# termtype: te
bchan=1-15,17-31
dchan=16
echocanceller=mg2,1-15,17-31

# Global data

loadzone    = uk
defaultzone    = uk



cat modules
# Autogenerated by /usr/sbin/dahdi_genconf (Dahdi::Config::Gen::Modules)
on Wed Aug  7 19:37:48 2013
# If you edit this file and execute /usr/sbin/dahdi_genconf again,
# your manual changes will be LOST.
wcte12xp
# Xorcom Astribank Devices
xpp_usb


dahdi_hardware
pci:0000:02:08.0     wcte12xp+    d161:8000 Wildcard TE121

dahdi_scan
[1]
active=yes
alarms=OK
description=Wildcard TE121 Card 0
name=WCT1/0
manufacturer=Digium
devicetype=Wildcard TE121 (VPMOCT032)
location=PCI Bus 02 Slot 09
basechan=1
totchans=31
irq=0
type=digital-E1
syncsrc=1
lbo=0 db (CSU)/0-133 feet (DSX-1)
coding_opts=AMI,HDB3
framing_opts=CCS,CRC4
coding=HDB3
framing=CCS/CRC4


Card is properly installed and recognized by Dahdi

Freetdm is compiled with libpri and configuration is like
cat /usr/local/freeswitch/conf/freetdm.conf
[general]
cpu_monitor =>  yes
cpu_monitoring_interval =>  2000 ; monitor usage every 2 seconds
cpu_set_alarm_threshold =>  90 ; whenever 90% of global CPU usage is
reached, trigger the alarm.
cpu_reset_alarm_threshold =>  80 ; when the CPU usage decreases at 80%,
clear the alarm.
cpu_alarm_action =>  reject,warn ; Start rejecting calls when the CPU
alarm is triggered and also print warnings.

[span zt myDAHDISpan]
trunk_type =>  E1
group =>  g1
b-channel =>  1-15
d-channel =>  16
b-channel =>  17-31


cat /usr/local/freeswitch/conf/autoload_configs/freetdm.conf.xml
<configuration name="freetdm.conf" description="FreeTDM Configuration">
<settings>
<param name="debug" value="1"/>
</settings>
<libpri_spans>
<span name="myDAHDISpan">
<!-- Log Levels: none, alert, crit, err, warning, notice, info, debug -->
<param name="switch" value="euroisdn"/>
<param name="node" value="cpe"/>
<param name="dialect" value="q931"/>
<param name="debug" value="all"/>
<param name="dialplan" value="XML"/>
<param name="context" value="public"/>
<param name="l1" value="alaw"/>
</span>
</libpri_spans>
</configuration>


Freeswitch logs are showing

2013-08-08 15:38:18.613848 [WARNING] ftmod_libpri.c:1975 [s1c10][1:10]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.613848 [WARNING] ftmod_libpri.c:1975 [s1c11][1:11]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.613848 [WARNING] ftmod_libpri.c:1975 [s1c12][1:12]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.613848 [WARNING] ftmod_libpri.c:1975 [s1c13][1:13]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c14][1:14]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c15][1:15]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c17][1:17]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c18][1:18]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c19][1:19]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c20][1:20]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c21][1:21]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c22][1:22]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c23][1:23]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c24][1:24]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c25][1:25]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c26][1:26]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c27][1:27]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c28][1:28]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c29][1:29]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c30][1:30]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:18.633847 [WARNING] ftmod_libpri.c:1975 [s1c31][1:31]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:23.673847 [WARNING] ftdm_io.c:3022 [s1c5][1:5] Channel
not opened, proceeding anyway
2013-08-08 15:38:48.613848 [WARNING] ftmod_libpri.c:1975 [s1c6][1:6] --
T316 timed out, resending RESTART request
2013-08-08 15:38:48.613848 [WARNING] ftmod_libpri.c:1975 [s1c7][1:7] --
T316 timed out, resending RESTART request
2013-08-08 15:38:48.633850 [WARNING] ftmod_libpri.c:1975 [s1c8][1:8] --
T316 timed out, resending RESTART request
2013-08-08 15:38:48.633850 [WARNING] ftmod_libpri.c:1975 [s1c9][1:9] --
T316 timed out, resending RESTART request
2013-08-08 15:38:48.633850 [WARNING] ftmod_libpri.c:1975 [s1c10][1:10]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:48.633850 [WARNING] ftmod_libpri.c:1975 [s1c11][1:11]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:48.633850 [WARNING] ftmod_libpri.c:1975 [s1c12][1:12]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:48.633850 [WARNING] ftmod_libpri.c:1975 [s1c13][1:13]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:48.633850 [WARNING] ftmod_libpri.c:1975 [s1c14][1:14]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:48.633850 [WARNING] ftmod_libpri.c:1975 [s1c15][1:15]
-- T316 timed out, resending RESTART request
2013-08-08 15:38:48.633850 [WARNING] ftmod_libpri.c:1975 [s1c17][1:17]
-- T316 timed out, resending RESTART request



Libpri logs are showing

2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
DL-DATA request
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
Protocol Discriminator: Q.931 (8)  len=13
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
TEI=0 Call Ref: len= 2 (reference 0/0x0) (Sent from originator)
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
Message Type: RESTART (70)
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]
TEI=0 Transmitting N(S)=4, window is open V(A)=2 K=7
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
TEI: 0 State 7(Multi-frame established)
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
V(A)=2, V(S)=4, V(R)=6
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
T200_id=8192, N200=3, T203_id=0
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>  [
00 01 08 0c 08 02 00 00 46 18 03 a9 83 8d 79 01 80 ]
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
Informational frame:
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
SAPI: 00  C/R: 0 EA: 0
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
TEI: 000        EA: 1
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
N(S): 004   0: 0
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
N(R): 006   P: 0
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>  13
bytes of data
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
Protocol Discriminator: Q.931 (8)  len=13
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
TEI=0 Call Ref: len= 2 (reference 0/0x0) (Sent from originator)
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
Message Type: RESTART (70)
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
[18 03 a9 83 8d]
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0
Exclusive  Dchan: 0
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]
  >                        ChanSel: As indicated in following octets
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]
  >                        Ext: 1  Coding: 0  Number Specified  Channel
Type: 3
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]
  >                        Ext: 1  Channel: 13 Type: CPE]
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
[79 01 80]
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
Restart Indicator (len= 3) [ Ext: 1  Spare: 0  Resetting Indicated
Channel (0) ]
2013-08-08 15:41:18.633844 [DEBUG] lpwrap_pri.c:199 [s1c16][1:16] --
Starting timer 0x2678478 with timeout 30000 ms
2013-08-08 15:41:18.633844 [WARNING] ftmod_libpri.c:1975 [s1c14][1:14]
-- T316 timed out, resending RESTART request
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
DL-DATA request
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
Protocol Discriminator: Q.931 (8)  len=13
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
TEI=0 Call Ref: len= 2 (reference 0/0x0) (Sent from originator)
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
Message Type: RESTART (70)
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]
TEI=0 Transmitting N(S)=5, window is open V(A)=2 K=7
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
TEI: 0 State 7(Multi-frame established)
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
V(A)=2, V(S)=5, V(R)=6
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
K=7, RC=0, l3_initiated=0, reject_except=0, ack_pend=0
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
T200_id=8192, N200=3, T203_id=0
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>  [
00 01 0a 0c 08 02 00 00 46 18 03 a9 83 8e 79 01 80 ]
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
Informational frame:
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
SAPI: 00  C/R: 0 EA: 0
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
TEI: 000        EA: 1
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
N(S): 005   0: 0
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
N(R): 006   P: 0
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>  13
bytes of data
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
Protocol Discriminator: Q.931 (8)  len=13
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
TEI=0 Call Ref: len= 2 (reference 0/0x0) (Sent from originator)
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
Message Type: RESTART (70)
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
[18 03 a9 83 8e]
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0
Exclusive  Dchan: 0
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]
  >                        ChanSel: As indicated in following octets
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]
  >                        Ext: 1  Coding: 0  Number Specified  Channel
Type: 3
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]
  >                        Ext: 1  Channel: 14 Type: CPE]
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
[79 01 80]
2013-08-08 15:41:18.633844 [DEBUG] ftmod_libpri.c:134 [s1c16][1:16]>
Restart Indicator (len= 3) [ Ext: 1  Spare: 0  Resetting Indicated
Channel (0) ]
2013-08-08 15:41:18.633844 [DEBUG] lpwrap_pri.c:199 [s1c16][1:16] --
Starting timer 0x26784d8 with timeout 30000 ms
2013-08-08 15:41:18.633844 [WARNING] ftmod_libpri.c:1975 [s1c15][1:15]
-- T316 timed out, resending RESTART request


ftdm dump 1 n (where n is from 1 to 15 and  17 to 31) is showing state
as down

freeswitch at dst1>  ftdm dump 1 1

span_id: 1
chan_id: n
physical_span_id: 1
physical_chan_id: n
physical_status: ok
physical_status_red: 0
physical_status_yellow: 0
physical_status_rai: 0
physical_status_blue: 0
physical_status_ais: 0
physical_status_general: 0
signaling_status: UP
type: B
state: DOWN
last_state: RESTART
txgain: 0.00
rxgain: 0.00
cid_date:
cid_name:
cid_num:
ani:
aniII:
dnis:
rdnis:
cause: NONE
session: (none)

I have tried many configurations, but I am not able to fix that issue ,
If I put my Pri line into panasonic PBX, it is working properly.

Kindly help me in solving this issue.


Forget to mention that I have already tested below mentioned
     configuration

     


   1. Go to FS source directory and change dir to this
      path libs/freetdm/src/ftmod/ftmod_libpri
   2. change #define T316_TIMEOUT_MS_DEFAULT to 10000
   3. #define T316_TIMEOUT_MS_MAX to 300000
   4. #define T316_ATTEMPT_LIMIT_DEFAULT to 30 (max. channels in PRI lines)

It didn't work

Also we are using the latest version of Freeswitch 1.2.12 and libpri1.4.14


Regards
Rajat Toshniwal
----------------------------------------------------------------------------------
Disclaimer: The information contained in this communication is confidential, private, proprietary, or otherwise privileged and is intended only for the use of the addressee. Unauthorized use, disclosure, distribution or copying is strictly prohibited and may be unlawful. If you have received this communication in error, please delete this message and notify the sender immediately - Samin TekMindz India Pvt. Ltd.
----------------------------------------------------------------------------------

_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
consulting at freeswitch.org
http://www.freeswitchsolutions.com

FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.com

Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com

FreeSWITCH-users mailing list
FreeSWITCH-users at lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org


----------------------------------------------------------------------------------
Disclaimer: The information contained in this communication is confidential, private, proprietary, or otherwise privileged and is intended only for the use of the addressee. Unauthorized use, disclosure, distribution or copying is strictly prohibited and may be unlawful. If you have received this communication in error, please delete this message and notify the sender immediately - Samin TekMindz India Pvt. Ltd.
----------------------------------------------------------------------------------
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.digium.com/pipermail/asterisk-users/attachments/20130808/49e8a573/attachment.htm>


More information about the asterisk-users mailing list