[Freeswitch-users] FreeTDM does not work

Valery Kalinin valery.kalinin at gmail.com
Wed Jun 8 16:18:29 MSD 2011 

Hi all!

FreeTDM does not work with Digium equipment.

Equipment: Digium TE121
OS: CentOS 5.6
DAHDI: 2.4.1.2
# ./dahdi_scan
[1]
active=yes
alarms=OK
description=Wildcard TE121 Card 0
name=WCT1/0
manufacturer=Digium
devicetype=Wildcard TE121 (VPMADT032)
location=PCI Bus 04 Slot 09
basechan=1
totchans=31
irq=5
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

LIBPRI: 1.4.11.5

zt.conf
[defaults]
codec_ms => 20
wink_ms => 150
flash_ms => 750
echo_cancel_level => 64
rxgain => 0.0
txgain => 0.0

freetdm.conf
[span zt pri]
name => pri
trunk_type => E1
group => e1group
b-channel => 1-15
b-channel => 17-31
d-channel => 16

part of freetdm.conf.xml:
    <libpri_spans>
        <span id="1" name="pri">
            <param name="node" value="network"/>
            <param name="switch" value="4ess"/>
            <param name="l1" value="alaw"/>
            <param name="dp" value="unknown"/>
            <param name="debug" value="q931_all"/>
            <param name="dialplan" value="XML"/>
            <param name="context" value="default"/>
        </span>
    </libpri_spans>

and log result (important parts):
start freetdm:
2011-06-09 02:07:00.591482 [DEBUG] ftdm_config.c:52 New mod directory:
/usr/local/freeswitch/mod
2011-06-09 02:07:00.591492 [DEBUG] ftdm_config.c:58 New config directory:
/usr/local/freeswitch/conf
2011-06-09 02:07:00.591539 [DEBUG] ftdm_sched.c:154 Initializing scheduling
API
2011-06-09 02:07:00.591545 [DEBUG] ftdm_sched.c:251 Created schedule
freetdm-master
2011-06-09 02:07:00.591551 [NOTICE] ftdm_sched.c:178 Launching main schedule
thread
2011-06-09 02:07:00.591581 [DEBUG] ftdm_sched.c:187 Running schedule
freetdm-master in the main schedule thread
2011-06-09 02:07:00.591592 [DEBUG] ftdm_config.c:80 Configuration file is
/usr/local/freeswitch/conf/modules.conf.
2011-06-09 02:07:00.591639 [NOTICE] ftdm_io.c:5656 Modules configured: 1
2011-06-09 02:07:00.591645 [DEBUG] ftdm_config.c:80 Configuration file is
/usr/local/freeswitch/conf/freetdm.conf.
2011-06-09 02:07:00.591659 [DEBUG] ftdm_io.c:4595 Reading FreeTDM
configuration file
2011-06-09 02:07:00.591679 [DEBUG] ftdm_io.c:4611 found config for span
2011-06-09 02:07:00.591783 [NOTICE] ftmod_zt.c:1300 Using DAHDI control
device
2011-06-09 02:07:00.591794 [INFO] ftdm_io.c:4898 Loading IO from
/usr/local/freeswitch/mod/ftmod_zt.so [zt]
2011-06-09 02:07:00.591814 [DEBUG] ftdm_config.c:80 Configuration file is
/usr/local/freeswitch/conf/zt.conf.
2011-06-09 02:07:00.591840 [INFO] ftmod_zt.c:577 Setting rxgain val to
0.000000
2011-06-09 02:07:00.591846 [INFO] ftmod_zt.c:585 Setting txgain val to
0.000000
2011-06-09 02:07:00.591860 [INFO] ftdm_io.c:790 Auto-loaded I/O module 'zt'
2011-06-09 02:07:00.591885 [DEBUG] ftdm_io.c:4625 created span 1 (pri) of
type zt
2011-06-09 02:07:00.591890 [DEBUG] ftdm_io.c:4641 span 1 [name]=[pri]
2011-06-09 02:07:00.591896 [DEBUG] ftdm_io.c:4641 span 1 [trunk_type]=[E1]
2011-06-09 02:07:00.591902 [DEBUG] ftdm_io.c:4646 setting trunk type to 'E1'
2011-06-09 02:07:00.591907 [DEBUG] ftdm_io.c:4641 span 1 [group]=[e1group]
2011-06-09 02:07:00.591912 [DEBUG] ftdm_io.c:4641 span 1 [b-channel]=[1-15]
2011-06-09 02:07:00.591940 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 1 as FreeTDM device 1:1 fd:25
2011-06-09 02:07:00.591963 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 2 as FreeTDM device 1:2 fd:26
2011-06-09 02:07:00.591984 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 3 as FreeTDM device 1:3 fd:27
2011-06-09 02:07:00.592006 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 4 as FreeTDM device 1:4 fd:28
2011-06-09 02:07:00.592029 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 5 as FreeTDM device 1:5 fd:29
2011-06-09 02:07:00.592049 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 6 as FreeTDM device 1:6 fd:30
2011-06-09 02:07:00.592069 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 7 as FreeTDM device 1:7 fd:31
2011-06-09 02:07:00.592090 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 8 as FreeTDM device 1:8 fd:32
2011-06-09 02:07:00.592110 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 9 as FreeTDM device 1:9 fd:33
2011-06-09 02:07:00.592131 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 10 as FreeTDM device 1:10 fd:34
2011-06-09 02:07:00.592151 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 11 as FreeTDM device 1:11 fd:35
2011-06-09 02:07:00.592171 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 12 as FreeTDM device 1:12 fd:36
2011-06-09 02:07:00.592192 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 13 as FreeTDM device 1:13 fd:37
2011-06-09 02:07:00.592212 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 14 as FreeTDM device 1:14 fd:38
2011-06-09 02:07:00.592231 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 15 as FreeTDM device 1:15 fd:39
2011-06-09 02:07:00.592239 [DEBUG] ftdm_io.c:5200 Creating new group:e1group
2011-06-09 02:07:00.592275 [DEBUG] ftdm_io.c:4641 span 1 [b-channel]=[17-31]
2011-06-09 02:07:00.592299 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 17 as FreeTDM device 1:16 fd:40
2011-06-09 02:07:00.592323 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 18 as FreeTDM device 1:17 fd:41
2011-06-09 02:07:00.592348 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 19 as FreeTDM device 1:18 fd:42
2011-06-09 02:07:00.592369 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 20 as FreeTDM device 1:19 fd:43
2011-06-09 02:07:00.592390 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 21 as FreeTDM device 1:20 fd:44
2011-06-09 02:07:00.592422 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 22 as FreeTDM device 1:21 fd:45
2011-06-09 02:07:00.592442 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 23 as FreeTDM device 1:22 fd:46
2011-06-09 02:07:00.592462 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 24 as FreeTDM device 1:23 fd:47
2011-06-09 02:07:00.592486 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 25 as FreeTDM device 1:24 fd:48
2011-06-09 02:07:00.592510 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 26 as FreeTDM device 1:25 fd:49
2011-06-09 02:07:00.592531 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 27 as FreeTDM device 1:26 fd:50
2011-06-09 02:07:00.592558 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 28 as FreeTDM device 1:27 fd:51
2011-06-09 02:07:00.592584 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 29 as FreeTDM device 1:28 fd:52
2011-06-09 02:07:00.592609 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 30 as FreeTDM device 1:29 fd:53
2011-06-09 02:07:00.592628 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 31 as FreeTDM device 1:30 fd:54
2011-06-09 02:07:00.592654 [DEBUG] ftdm_io.c:4641 span 1 [d-channel]=[16]
2011-06-09 02:07:00.592692 [INFO] ftmod_zt.c:396 configuring device
/dev/dahdi/channel channel 16 as FreeTDM device 1:31 fd:55
2011-06-09 02:07:00.592712 [INFO] ftdm_io.c:4823 Configured 31 channel(s)
2011-06-09 02:07:00.593269 [INFO] ftdm_io.c:4898 Loading IO from
/usr/local/freeswitch/mod/ftmod_libpri.so [libpri]
2011-06-09 02:07:00.593283 [DEBUG] ftdm_io.c:4839 Module libpri does not
support configuration.
2011-06-09 02:07:00.593287 [INFO] ftdm_io.c:4910 Loading SIG from
/usr/local/freeswitch/mod/ftmod_libpri.so
2011-06-09 02:07:00.593292 [INFO] ftdm_io.c:5133 auto-loaded 'libpri'
2011-06-09 02:07:00.593302 [NOTICE] ftmod_libpri.c:1981 Setting default
Layer 1 to ALAW since this is an E1/BRI/BRI PTMP trunk
2011-06-09 02:07:00.593566 [DEBUG] ftmod_libpri.c:1629 opening D-Channel #0
1:31
2011-06-09 02:07:00.596450 [NOTICE] switch_loadable_module.c:145 Adding
Endpoint 'freetdm'
2011-06-09 02:07:00.596481 [NOTICE] switch_loadable_module.c:252 Adding
Application 'disable_ec'
2011-06-09 02:07:00.596510 [NOTICE] switch_loadable_module.c:252 Adding
Application 'disable_dtmf'
2011-06-09 02:07:00.596530 [NOTICE] switch_loadable_module.c:252 Adding
Application 'enable_dtmf'
2011-06-09 02:07:00.596552 [NOTICE] switch_loadable_module.c:274 Adding API
Function 'ftdm'
2011-06-09 02:07:00.618807 [DEBUG] ftmod_libpri.c:1586 -- Caught Event span
1 4 (CONFIG_ERR)
2011-06-09 02:07:00.622776 [NOTICE] switch_loadable_module.c:274 Adding API
Function 'acl'
...later:
2011-06-09 02:07:01.693551 [DEBUG] ftmod_libpri.c:1586 -- Caught Event span
1 4 (CONFIG_ERR)
2011-06-09 02:07:02.773590 [DEBUG] ftmod_libpri.c:1586 -- Caught Event span
1 4 (CONFIG_ERR)
2011-06-09 02:07:03.833629 [DEBUG] ftmod_libpri.c:1586 -- Caught Event span
1 4 (CONFIG_ERR)
2011-06-09 02:07:04.893667 [DEBUG] ftmod_libpri.c:146 TEI=0 DL event:
Q931_DL_EVENT_DL_RELEASE_IND(3)
2011-06-09 02:07:05.962709 [DEBUG] ftmod_libpri.c:1586 -- Caught Event span
1 4 (CONFIG_ERR)
2011-06-09 02:07:07.982780 [DEBUG] ftmod_libpri.c:1586 -- Caught Event span
1 4 (CONFIG_ERR)
2011-06-09 02:07:10.002854 [DEBUG] ftmod_libpri.c:1586 -- Caught Event span
1 4 (CONFIG_ERR)
...
making a call:
...
Dialplan: sofia/internal/1000 at 192.168.200.11 Action
bridge(freetdm/e1group/1/12345)
Dialplan: sofia/internal/1000 at 192.168.200.11 Action answer()
2011-06-09 02:10:27.343089 [DEBUG] mod_dptools.c:1063 sofia/internal/
1000 at 192.168.200.11 SET [RFC2822_DATE]=[Thu, 09 Jun 2011 02:10:27 +0600]
EXECUTE sofia/internal/1000 at 192.168.200.11 bridge(freetdm/e1group/1/12345)
2011-06-09 02:10:27.343089 [DEBUG] switch_ivr_originate.c:1869 Parsing
global variables
2011-06-09 02:10:27.343089 [NOTICE] mod_freetdm.c:1551 Close Channel N/A
[CS_NEW]
2011-06-09 02:10:27.343089 [DEBUG] switch_core_state_machine.c:457 ()
Running State Change CS_DESTROY
2011-06-09 02:10:27.343089 [DEBUG] switch_core_state_machine.c:467 (N/A)
State DESTROY
2011-06-09 02:10:27.343089 [DEBUG] switch_core_state_machine.c:467 (N/A)
State DESTROY going to sleep
2011-06-09 02:10:27.343089 [ERR] switch_ivr_originate.c:2443 Cannot create
outgoing channel of type [freetdm] cause: [NORMAL_CIRCUIT_CONGESTION]
2011-06-09 02:10:27.343089 [DEBUG] switch_ivr_originate.c:3296 Originate
Resulted in Error Cause: 34 [NORMAL_CIRCUIT_CONGESTION]

Why?
Which means error: Caught Event span 1 4 (CONFIG_ERR)
Which config???
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20110608/adb2e934/attachment-0001.html

More information about the FreeSWITCH-users mailing list