[Freeswitch-users] Digium TE220P problem.

17 Mar 2009

      Hi,

I have problem with Digium TE220P. Everything works, i can call & talk, 
but everytime i have CRIT message:
2009-03-14 17:50:30 [CRIT] ozmod_isdn.c:904 zap_isdn_931_34() Received 
CALL PROCEEDING message for channel 0

When FS start show me ERR message:
2009-03-14 17:44:06 [ERR] Span:0 Q.921() Received UA frame in invalid state

This is my config:

cat zaptel.conf
span=1,1,0,ccs,hdb3,crc4
bchan=1-15,17-31
dchan=16

span = 2,0,0,ccs,hdb3,crc4
bchan = 32-46,48-62
dchan = 47

loadzone = ru
defaultzone=ru

cat openzap.conf
[span zt]
name => OpenZAP
number => 1
trunk_type => E1
b-channel => 1-15
d-channel => 16
b-channel => 17-31

[span zt]
name => OpenZAP
number => 2
trunk_type => E1
b-channel => 32-46
d-channel => 47
b-channel => 48-62

cat openzap.conf.xml
<configuration name="openzap.conf" description="OpenZAP Configuration">
 <settings>
   <param name="debug" value="0"/>
 </settings>
 <pri_spans>
   <span id="1">
       <param name="q921loglevel" value="debug"/>
       <param name="q931loglevel" value="debug"/>
       <param name="mode" value="user"/>
       <param name="dialect" value="q931"/>
       <param name="dialplan" value="XML"/>
       <param name="context" value="default"/>
   </span>
   <span id="2">
       <param name="q921loglevel" value="debug"/>
       <param name="q931loglevel" value="debug"/>
       <param name="mode" value="user"/>
       <param name="dialect" value="q931"/>
       <param name="dialplan" value="XML"/>
       <param name="context" value="default"/>
  </span>
</pri_spans>
</configuration>

and FS start LOGS:
2009-03-14 17:44:06 [NOTICE] zap_io.c:2612 zap_global_init() Modules 
configured: 1

2009-03-14 17:44:06 [NOTICE] ozmod_zt.c:922 zt_init() Using Zaptel 
control device
2009-03-14 17:44:06 [INFO] zap_io.c:2433 zap_load_module() Loading IO 
from /usr/local/freeswitch/mod/ozmod_zt.so [zt]
2009-03-14 17:44:06 [INFO] zap_io.c:2233 load_config() auto-loaded 'zt'
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 1 as OpenZAP device 1:1 fd:38
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 2 as OpenZAP device 1:2 fd:39
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 3 as OpenZAP device 1:3 fd:40
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 4 as OpenZAP device 1:4 fd:41
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 5 as OpenZAP device 1:5 fd:42
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 6 as OpenZAP device 1:6 fd:43
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 7 as OpenZAP device 1:7 fd:44
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 8 as OpenZAP device 1:8 fd:45
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 9 as OpenZAP device 1:9 fd:46
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 10 as OpenZAP device 1:10 fd:47
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 11 as OpenZAP device 1:11 fd:48
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 12 as OpenZAP device 1:12 fd:49
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 13 as OpenZAP device 1:13 fd:50
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 14 as OpenZAP device 1:14 fd:51
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 15 as OpenZAP device 1:15 fd:52
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 16 as OpenZAP device 1:16 fd:53
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 17 as OpenZAP device 1:17 fd:54
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 18 as OpenZAP device 1:18 fd:55
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 19 as OpenZAP device 1:19 fd:56
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 20 as OpenZAP device 1:20 fd:57
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 21 as OpenZAP device 1:21 fd:58
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 22 as OpenZAP device 1:22 fd:59
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 23 as OpenZAP device 1:23 fd:60
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 24 as OpenZAP device 1:24 fd:61
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 25 as OpenZAP device 1:25 fd:62
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 26 as OpenZAP device 1:26 fd:63
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 27 as OpenZAP device 1:27 fd:64
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 28 as OpenZAP device 1:28 fd:65
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 29 as OpenZAP device 1:29 fd:66
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 30 as OpenZAP device 1:30 fd:67
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 31 as OpenZAP device 1:31 fd:68
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 32 as OpenZAP device 2:1 fd:69
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 33 as OpenZAP device 2:2 fd:70
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 34 as OpenZAP device 2:3 fd:71
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 35 as OpenZAP device 2:4 fd:72
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 36 as OpenZAP device 2:5 fd:73
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 37 as OpenZAP device 2:6 fd:74
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 38 as OpenZAP device 2:7 fd:75
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 39 as OpenZAP device 2:8 fd:76
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 40 as OpenZAP device 2:9 fd:77
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 41 as OpenZAP device 2:10 fd:78
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 42 as OpenZAP device 2:11 fd:79
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 43 as OpenZAP device 2:12 fd:80
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 44 as OpenZAP device 2:13 fd:81
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 45 as OpenZAP device 2:14 fd:82
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 46 as OpenZAP device 2:15 fd:83
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 47 as OpenZAP device 2:16 fd:84
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 48 as OpenZAP device 2:17 fd:85
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 49 as OpenZAP device 2:18 fd:86
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 50 as OpenZAP device 2:19 fd:87
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 51 as OpenZAP device 2:20 fd:88
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 52 as OpenZAP device 2:21 fd:89
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 53 as OpenZAP device 2:22 fd:90
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 54 as OpenZAP device 2:23 fd:91
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 55 as OpenZAP device 2:24 fd:92
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 56 as OpenZAP device 2:25 fd:93
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 57 as OpenZAP device 2:26 fd:94
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 58 as OpenZAP device 2:27 fd:95
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 59 as OpenZAP device 2:28 fd:96
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 60 as OpenZAP device 2:29 fd:97
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 61 as OpenZAP device 2:30 fd:98
2009-03-14 17:44:06 [INFO] ozmod_zt.c:299 zt_open_range() configuring 
device /dev/zap/channel channel 62 as OpenZAP device 2:31 fd:99
2009-03-14 17:44:06 [INFO] zap_io.c:2356 load_config() Configured 62 
channel(s)
2009-03-14 17:44:06 [INFO] zap_io.c:2450 zap_load_module() Loading SIG 
from /usr/local/freeswitch/mod/ozmod_isdn.so
2009-03-14 17:44:06 [INFO] zap_io.c:2566 zap_configure_span() 
auto-loaded 'isdn'
2009-03-14 17:44:06 [CONSOLE] switch_loadable_module.c:863 
switch_loadable_module_load_file() Successfully Loaded [mod_openzap]
2009-03-14 17:44:06 [NOTICE] switch_loadable_module.c:142 
switch_loadable_module_process() Adding Endpoint 'openzap'
2009-03-14 17:44:06 [NOTICE] switch_loadable_module.c:240 
switch_loadable_module_process() Adding Application 'disable_ec'
2009-03-14 17:44:06 [NOTICE] switch_loadable_module.c:260 
switch_loadable_module_process() Adding API Function 'oz'
2009-03-14 17:44:06 [ERR] Span:0 Q.921() Received UA frame in invalid state

When i dial from onference: freeswitch@voipgw> conference test@moh dial 
openzap/1/3/81048663000000
2009-03-14 17:50:26 [NOTICE] switch_channel.c:592 
switch_channel_set_name() New Channel OpenZAP/1:4/81048663000000
[d6137f1a-10af-11de-8496-c9e9c714d68b]
2009-03-14 17:50:30 [CRIT] ozmod_isdn.c:904 zap_isdn_931_34() Received 
CALL PROCEEDING message for channel 0
2009-03-14 17:50:30 [NOTICE] mod_openzap.c:1500 
on_clear_channel_signal() Ring-Ready OpenZAP/1:4/81048663000000!
2009-03-14 17:50:34 [NOTICE] mod_openzap.c:1491 
on_clear_channel_signal() Pre-Answer OpenZAP/1:4/81048663000000!
2009-03-14 17:50:35 [NOTICE] mod_openzap.c:1480 
on_clear_channel_signal() Channel [OpenZAP/1:4/81048663000000] has been 
answered
API CALL [conference(test@moh dial openzap/1/4/81048663000000)] output:
Call Requested: result: [SUCCESS]
2009-03-14 17:44:06 [CONSOLE] switch_loadable_module.c:863 
switch_loadable_module_load_file() Successfully Loaded [mod_commands]

freeswitch@voipgw> oz list
API CALL [oz(list)] output:
+OK
span: 1 (span1)
type: isdn
chan_count: 31
dialplan: XML
context: default
dial_regex: fail_dial_regex: hold_music: analog_options none

freeswitch@voipgw> oz dump 1
API CALL [oz(dump 1)] output:
+OK
span_id: 1
chan_id: 1
physical_span_id: 1
physical_chan_id: 1
type: B
state: DOWN
last_state: DOWN   cid_date:
cid_name:
cid_num:
ani:
aniII:
dnis:
rdnis:
cause: NONE

span_id: 1
chan_id: 2
physical_span_id: 1
physical_chan_id: 2
type: B
state: DOWN
last_state: DOWN   cid_date:
cid_name:
cid_num:
ani:
aniII:
dnis:
rdnis:
cause: NONE

span_id: 1
chan_id: 3
physical_span_id: 1
physical_chan_id: 3
type: B
state: DOWN
last_state: DOWN   cid_date:
cid_name:
cid_num:
ani:
aniII:
dnis:
rdnis:
cause: NONE
[...]

any idea how i can fix this error ?
Krzysztof Zimnicki