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

Thu Aug 8 14:47:38 MSD 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.

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.
----------------------------------------------------------------------------------