Cisco Systems Temporary failure on T.38 fax calls
Bookmark this page:  YahooMyWeb Yahoo!  Google Google  Windows Live Favorites Windows Live  del.icio.us del.icio.us  digg digg  Add to Netscape Netscape
Subject Author Date
Temporary failure on T.38 fax calls jalsot 02-07-07
Posted by jalsot on February 7, 2007, 5:48 am
Please log in for more thread options
Hello,

we are facing to an ugly issue with faxing through Cisco AS5350 IOS
12.4.9T2 from a software fax sending solution. We have the same
software fax working fine with AS5300 IOS 12.3.15 connected to the
same TDM network (there is a slight difference that as5300 is
connected directly to the transit PBX while as5350 has a Definity
switch in between).

>From debugs we see that the as5350 drops the call. The fax process is
in progress, usually disconnect happens during the fax page
transmission. The same fax page, destination number works perfectly
with as5300. We have checked PRIs for frame slips and other errors,
however everything seems to be clear (line is clear). The PBX's
another side is also clear, so I would say, not frame slips are the
cause of issue.

What is interesting is that through the same as5350 T.37 faxing works
fine - this is the sign of that the TDM side is OK. Between the fax
server is a switched LAN based on Cisco equipments, did several packet
tests, there were no packet loss nor jitter changes. I made some fax
packet traces and with analyzer (wireshark) the initial RTP session
was error free, so I think the IP network side might be OK as well.

>From these information I think the issue might be in the as5350's
configuration or bug in IOS. I checked all the options about t38
faxing (changing ls/hs redundancy, ecm, rate, etc.) but didn't help.
It seems this doesn't work well so I would like to ask ideas what else
should we try.

Thank you in advance,
Tamas

Here is a debug, maybe somebody will see something from it:
Feb 6 14:49:18: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:49:18: dsp_stream_mgr_remote_dnld_done: dmgr stmId: 2983
xmitFunc: 0x61E4EA6C
Feb 6 14:49:18: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmp_dsmapi_connect_cb:
Feb 6 14:49:18: evt_id = 9,fax_modem_type 0 mismatch with
current_feature 5

Feb 6 14:49:19: Final pcn:38615403693, poa:T, dial_string:
270038615403693
Feb 6 14:49:19: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmp_dsmapi_reserve_resource_cb: .
Feb 6 14:49:19: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_opened_cb:
Feb 6 14:49:19: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_process_event:
[state:S_SETUP_REQ_PEND, event:E_VTSP_DSM_OPEN_SUCCESS]
Feb 6 14:49:19: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/act_setup_req_pend_succ:
Feb 6 14:49:19: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_request_call:
Mode=0, Peer=361334
Feb 6 14:49:19: digit_strip:1, pcn:446987#38638971123, poa:446987#
Feb 6 14:49:19: pcn:38638971123, poa:
Feb 6 14:49:19: Final pcn:38638971123, poa:, dial_string:
270038638971123
Feb 6 14:49:19: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_set_elog_enable: .
Feb 6 14:49:19: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_create_call_active_on_setup_req:
Target Trkgrp Route Label=DEFINITY
Feb 6 14:49:19: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_create_call_active_on_setup_req:
Source Trkgrp Route Label=
Feb 6 14:49:19: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_request_call:
Progress Indication=0, Info Trans Capability=128
Calling Number=3619997400, TON=Unknown, NPI=ISDN, Screening=Not
Screened, Presentation=Allowed
Called Number=446987#38638971123, TON=Unknown, NPI=ISDN
Feb 6 14:49:20: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_process_event:
[state:S_SETUP_REQUEST, event:E_TSP_PROCEEDING]
Feb 6 14:49:20: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/act_setup_pend_proceeding:
Feb 6 14:49:20: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_reinit_platform_info: .
Feb 6 14:49:20: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_timer:
Timer Start Time=303417995, Timer Value=10000(ms)
Feb 6 14:49:20: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/act_setup_pend_proceeding:
Peer=361334, Progress Indication=0, Return Code=2
Feb 6 14:49:20: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmp_dsmapi_reserve_resource_cb: .
Feb 6 14:49:20: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_opened_cb:
voipgw#
Feb 6 14:49:20: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_process_event:
[state:S_NULL, event:E_VTSP_DSM_OPEN_SUCCESS]
Feb 6 14:49:20: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/act_setup_req_wait_pend_success:
Event=72
Feb 6 14:49:20: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_timer_stop:
Timer Stop Time=303417995

Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_process_event:
[state:S_SETUP_REQ_PROC, event:E_TSP_ALERT]
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/act_setup_pend_alert:
Progress Indication=8, Signal Indication=1
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_ring_noan_timer_start:
Timer Start Time=303418132, No Answer Timer Value=180000(ms)
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/act_setup_pend_alert:
Ringback Indication=FALSE, Ring Timeout=180(s)
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_bridge: .
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_bridge:
Feb 6 14:49:21: dsp_stream_mgr_bridge, src_call_id 2008938,
dst_call_id 2008937
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_bridge: creating packet streams
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_bridge_check_cb:
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_bridge_check_cb:exit@1150
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_insert_conn_array_entry: dmgr->connArr.count = 2
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_bridge_status_cb:
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_save_fax_config:
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_save_fax_config:
Fax Relay=ENABLED
Primary Fax Protocol=T38_FAX_RELAY, Fallback Fax
Protocol=NONE_FAX_RELAY
Fax Relay CM Suppression :=DISABLED, Fax Parameters Set
By=Dialpeer, Peer=361330
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_save_fax_config:
LS Red=0, HS Red=0
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_set_fax_feat_param:
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_save_tty_config:
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_save_tty_config:
Save TTY configuration
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_set_tty_feat_param:
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_set_tty_feat_param:
Set TTY feat parameters
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_process_event:
[state:S_SETUP_REQ_PROC, event:E_CC_DO_CAPS_IND]
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_do_caps_ind: .
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_peer_event_cb:
Event=E_DSM_CC_CAPS_ACK
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_cap_ind_ack: src_call_id = 2008938,
dst_call_id = 2008937, is_cap_ack = 1
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_process_event:
[state:S_SETUP_REQ_PROC, event:E_CC_CAPS_IND]
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_cap_ind_ack: src_call_id = 2008938,
dst_call_id = 2008937, is_cap_ack = 0
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_ind_negotiation: Caps in caps indication:
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_dump: playout: [ mode:1,init:60, min:40, max:250].
data_mode:2
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_dump: RTP
PT:NTE[101],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],TTY[119],ClearChan[125],PCMu[0],PCMa[8]Codec[2],
TxDynamicPayload[0], RxDynamicPayload[0]
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_dump: passthrough: cap_modem_proto
0,cap_modem_codec 0, cap_modem_redundancy 0, payload 100, modem_relay
0, gw-xid 0, sprt_retries 12, sse pt 118, sprt pt 120, t1 1000,
retries 3, v14_rx_ht 50, v14_tx_ht 20, v14_tx_hc 16, ec 1, phy 1
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_debug_caps_encap: Encap 1, Vad 1, Codec 0x2, CodecBytes
160,
FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051
SignalType 1
DtmfRelay 1, Modem 0, SeqNumStart 0x25C2
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_ind_negotiation: Caps negotiation: TDM-IP
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_ind_negotiation: Caps after caps negotiation:
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_dump: playout: [ mode:1,init:60, min:40, max:250].
data_mode:2
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_dump: RTP
PT:NTE[101],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],TTY[119],ClearChan[125],PCMu[0],PCMa[8]Codec[2],
TxDynamicPayload[0], RxDynamicPayload[0]
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_dump: passthrough: cap_modem_proto
0,cap_modem_codec 0, cap_modem_redundancy 0, payload 100, modem_relay
0, gw-xid 0, sprt_retries 12, sse pt 118, sprt pt 120, t1 1000,
retries 3, v14_rx_ht 50, v14_tx_ht 20, v14_tx_hc 16, ec 1, phy 1
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_debug_caps_encap: Encap 1, Vad 1, Codec 0x2, CodecBytes
160,
FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051
SignalType 1
DtmfRelay 1, Modem 0, SeqNumStart 0x25C2
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_cap_ind_ack: packet streams already created
during bridging
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_ind_negotiation: Caps in caps indication:
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_dump: playout: [ mode:1,init:60, min:40, max:250].
data_mode:2
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_dump: RTP
PT:NTE[101],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],TTY[119],ClearChan[125],PCMu[0],PCMa[8]Codec[2],
TxDynamicPayload[0], RxDynamicPayload[0]
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_dump: passthrough: cap_modem_proto
0,cap_modem_codec 0, cap_modem_redundancy 0, payload 100, modem_relay
0, gw-xid 0, sprt_retries 12, sse pt 118, sprt pt 120, t1 1000,
retries 3, v14_rx_ht 50, v14_tx_ht 20, v14_tx_hc 16, ec 1, phy 1
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_debug_caps_encap: Encap 1, Vad 1, Codec 0x2, CodecBytes
160,
FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051
SignalType 1
DtmfRelay 1, Modem 0, SeqNumStart 0x25C2
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_ind_negotiation: Caps negotiation: TDM-IP
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_ind_negotiation: Caps after caps negotiation:
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_dump: playout: [ mode:1,init:60, min:40, max:250].
data_mode:2
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_dump: RTP
PT:NTE[101],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],TTY[119],ClearChan[125],PCMu[0],PCMa[8]Codec[2],
TxDynamicPayload[0], RxDynamicPayload[0]
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_caps_dump: passthrough: cap_modem_proto
0,cap_modem_codec 0, cap_modem_redundancy 0, payload 100, modem_relay
0, gw-xid 0, sprt_retries 12, sse pt 118, sprt pt 120, t1 1000,
retries 3, v14_rx_ht 50, v14_tx_ht 20, v14_tx_hc 16, ec 1, phy 1
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_debug_caps_encap: Encap 1, Vad 1, Codec 0x2, CodecBytes
160,
FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051
SignalType 1
DtmfRelay 1, Modem 0, SeqNumStart 0x25C2
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_cap_ind_ack:
Feb 6 14:49:21: dsp_stream_mgr_cap_ind_ack Fax CM suppression is
enabled
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_peer_event_cb:
Event=E_DSM_CC_CALL_MODIFY
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmp_dsmapi_reserve_resource_cb: .
Feb 6 14:49:21: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 2680, callID = 2008938, srcCallID = 2008937
Feb 6 14:49:21: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 2680, callID = 2008938, srcCallID = 2008937
Feb 6 14:49:21: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 2680, callID = 2008938, srcCallID = 2008937
Feb 6 14:49:21: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 2680, callID = 2008938, srcCallID = 2008937
Feb 6 14:49:21: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 2680, callID = 2008938, srcCallID = 2008937
Feb 6 14:49:21: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 2680, callID = 2008938, srcCallID = 2008937
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmp_dsmapi_connect_cb: .
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_feature_notify_cb:
Feature ID=0, Feature Status=1
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_reactivate_ringback:
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_reactivate_ringback:exit@959
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmapi_media_modify_ind: .
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmapi_revert_voice_caps_init: .
Feb 6 14:49:21: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_peer_event_cb:
Event=E_DSM_CC_MODIFY_MEDIA_ACK


Feb 6 14:49:23: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_req_stats: .
Feb 6 14:49:23: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmp_dsmapi_stats_cb: .
Feb 6 14:49:25: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:49:25: dsp_stream_mgr_remote_dnld_done: dmgr stmId: 6340
xmitFunc: 0x61E4EA6C
Feb 6 14:49:25: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmp_dsmapi_connect_cb:
Feb 6 14:49:25: evt_id = 9,fax_modem_type 0 mismatch with
current_feature 5
Feb 6 14:49:27: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_req_stats: .
Feb 6 14:49:27: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmp_dsmapi_stats_cb: .


Feb 6 14:49:27: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 3195, callID = 2008948, srcCallID = 2008947
Feb 6 14:49:27: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 3195, callID = 2008948, srcCallID = 2008947
Feb 6 14:49:27: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 3195, callID = 2008948, srcCallID = 2008947
Feb 6 14:49:27: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 3195, callID = 2008948, srcCallID = 2008947

Feb 6 14:49:30: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_process_event:
[state:S_SETUP_REQ_PROC, event:E_TSP_CONNECT]
Feb 6 14:49:30: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/act_setup_pend_connect:
Progress Indication=0
Feb 6 14:49:30: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_ring_noan_timer_stop:
Timer Stop Time=303419063
Feb 6 14:49:34: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:49:34: dsp_stream_mgr_remote_dnld_done: dmgr stmId: 5046
xmitFunc: 0x61E4EA6C
Feb 6 14:49:34: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmp_dsmapi_connect_cb:
Feb 6 14:49:34: evt_id = 9,fax_modem_type 0 mismatch with
current_feature 5
Feb 6 14:49:35: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmp_dsmapi_detection_cb: .
Feb 6 14:49:35: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmapi_fax_modem_initiation_detected:
current_feature:DSM_FEATURE_VOICE previous_feature:DSM_FEATURE_NONE
fallback_status:FALLBACK_NOT_STARTED dsmp event:E_DSMP_DSP_FAX_TONE
Feb 6 14:49:35: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_fax_modem_initiation_detected: stm_id = 2680, nse = 0
Feb 6 14:49:35: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_media_event_cb:
Event=E_DSMP_DSP_FAX_TONE
Feb 6 14:49:35: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_peer_event_cb:
Event=E_DSM_CC_MC_LOCAL_DNLD_DONE
Feb 6 14:49:35: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_media_event_cb:
Event=E_DSMP_DSP_FAX_TONE
Feb 6 14:49:35: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmapi_fax_modem_start: .
Feb 6 14:49:35: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmapi_fax_modem_start: current_feature:DSM_FEATURE_VOICE
previous_feature:DSM_FEATURE_NONE
fallback_status:FALLBACK_T38_PRIMARY_STARTED , fax_modem_type:
FR_T38_PROT_REMOTE
Feb 6 14:49:35: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmapi_store_call_info_type:
Feb 6 14:49:35: dsmapi_store_call_info_type: T.38 Fax Relay started,
H.323 callID = 2008937
Feb 6 14:49:35: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_delete_conn_array_entry: dmgr->connArr.count = 0
Feb 6 14:49:35: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_insert_conn_array_entry: dmgr->connArr.count = 2
Feb 6 14:49:35: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmapi_fax_modem_start: .
Feb 6 14:49:35: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmapi_fax_modem_start: current_feature:DSM_FEATURE_T38_FR
previous_feature:DSM_FEATURE_VOICE
fallback_status:FALLBACK_T38_PRIMARY_STARTED , fax_modem_type:
FR_T38_PROT_REMOTE
Feb 6 14:49:35: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:49:35: dsp_stream_mgr_remote_dnld_done: dmgr stmId: 7005
xmitFunc: 0x616B2570
Feb 6 14:49:36: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmp_dsmapi_connect_cb: .
Feb 6 14:49:36: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_peer_event_cb:
Event=E_DSM_CC_MC_RMT_DNLD_DONE
Feb 6 14:49:36: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_peer_event_cb:
Event=E_DSM_CC_MR_START
Feb 6 14:49:36: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmapi_fax_modem_start: .
Feb 6 14:49:36: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmapi_fax_modem_start: current_feature:DSM_FEATURE_T38_FR
previous_feature:DSM_FEATURE_VOICE
fallback_status:FALLBACK_T38_PRIMARY_STARTED , fax_modem_type:
FR_T38_PROT_REMOTE
Feb 6 14:49:37: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_req_stats: .
Feb 6 14:49:37: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmp_dsmapi_stats_cb: .


Feb 6 14:49:45: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 4192, callID = 2008965, srcCallID = 2008964
Feb 6 14:49:45: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 4192, callID = 2008965, srcCallID = 2008964
Feb 6 14:49:45: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 4192, callID = 2008965, srcCallID = 2008964
Feb 6 14:49:46: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:49:46: dsp_stream_mgr_remote_dnld_done: dmgr stmId: 7904
xmitFunc: 0x61E4EA6C
Feb 6 14:49:46: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmp_dsmapi_connect_cb:
Feb 6 14:49:46: evt_id = 9,fax_modem_type 0 mismatch with
current_feature 5


Feb 6 14:49:47: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:49:47: dsp_stream_mgr_remote_dnld_done: dmgr stmId: 6756
xmitFunc: 0x61E4EA6C
Feb 6 14:49:47: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmp_dsmapi_connect_cb:
Feb 6 14:49:47: evt_id = 9,fax_modem_type 0 mismatch with
current_feature 5
Feb 6 14:49:48: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:49:48: dsp_stream_mgr_remote_dnld_done: dmgr stmId: 21
xmitFunc: 0x61E4EA6C
Feb 6 14:49:48: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmp_dsmapi_connect_cb:
Feb 6 14:49:48: evt_id = 9,fax_modem_type 0 mismatch with
current_feature 5
Feb 6 14:49:54: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 4789, callID = 2008966, srcCallID = 2008967
Feb 6 14:49:54: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 4789, callID = 2008966, srcCallID = 2008967
Feb 6 14:49:54: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 4789, callID = 2008966, srcCallID = 2008967
Feb 6 14:49:54: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 4789, callID = 2008966, srcCallID = 2008967
Feb 6 14:49:54: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 4789, callID = 2008966, srcCallID = 2008967
Feb 6 14:49:54: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 4789, callID = 2008966, srcCallID = 2008967
Feb 6 14:49:54: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 4789, callID = 2008966, srcCallID = 2008967
Feb 6 14:49:54: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:49:54: dsp_stream_mgr_remote_dnld_done: wrong
current_feature =1
Feb 6 14:49:54: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:49:54: dsp_stream_mgr_remote_dnld_done: dmgr stmId: 3018
xmitFunc: 0x61E4EA6C
Feb 6 14:49:55: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmp_dsmapi_connect_cb:
Feb 6 14:49:55: evt_id = 9,fax_modem_type 0 mismatch with
current_feature 5
Feb 6 14:49:55: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:49:55: dsp_stream_mgr_remote_dnld_done: dmgr stmId: 1025
xmitFunc: 0x61E4EA6C
Feb 6 14:49:55: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmp_dsmapi_connect_cb:
Feb 6 14:49:55: evt_id = 9,fax_modem_type 0 mismatch with
current_feature 5
Feb 6 14:50:01: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 3039, callID = 2008982, srcCallID = 2008983
Feb 6 14:50:01: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 3039, callID = 2008982, srcCallID = 2008983
Feb 6 14:50:01: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 3039, callID = 2008982, srcCallID = 2008983


Feb 6 14:50:13: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:50:13: dsp_stream_mgr_remote_dnld_done: dmgr stmId: 7285
xmitFunc: 0x61E4EA6C
Feb 6 14:50:13: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmp_dsmapi_connect_cb:
Feb 6 14:50:13: evt_id = 9,fax_modem_type 0 mismatch with
current_feature 5
Feb 6 14:50:13: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:50:13: dsp_stream_mgr_remote_dnld_done: dmgr stmId: 3695
xmitFunc: 0x61E4EA6C
Feb 6 14:50:14: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmp_dsmapi_connect_cb:
Feb 6 14:50:14: evt_id = 9,fax_modem_type 0 mismatch with
current_feature 5
Feb 6 14:50:16: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:50:16: dsp_stream_mgr_remote_dnld_done: dmgr stmId: 989
xmitFunc: 0x61E4EA6C
Feb 6 14:50:16: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmp_dsmapi_connect_cb:
Feb 6 14:50:16: evt_id = 9,fax_modem_type 0 mismatch with
current_feature 5
Feb 6 14:50:16: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:50:16: dsp_stream_mgr_remote_dnld_done: dmgr stmId: 1029
xmitFunc: 0x61E4EA6C
Feb 6 14:50:16: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmp_dsmapi_connect_cb:
Feb 6 14:50:16: evt_id = 9,fax_modem_type 0 mismatch with
current_feature 5
Feb 6 14:50:20: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 1246, callID = 2008998, srcCallID = 2008999
Feb 6 14:50:20: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 1246, callID = 2008998, srcCallID = 2008999
Feb 6 14:50:20: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmapi_ph_xmit: Error: xmitFunc 61871644, enabled = 0 ,dropping
packet, stm_id = 1246, callID = 2008998, srcCallID = 2008999
Feb 6 14:50:23: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:50:23: dsp_stream_mgr_remote_dnld_done: dmgr stmId: 921
xmitFunc: 0x61E4EA6C
Feb 6 14:50:23: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmp_dsmapi_connect_cb:
Feb 6 14:50:23: evt_id = 9,fax_modem_type 0 mismatch with
current_feature 5

Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_bridge_drop:
Feb 6 14:50:25: dsp_stream_mgr_bridge_drop, src_call_id 2008938,
dst_call_id 2008937
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_get_t38_stats: .
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_delete_conn_array_entry: dmgr->connArr.count = 0
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_dsm_bridge_status_cb:
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_process_event:
[state:S_CONNECT, event:E_CC_DISCONNECT]
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/act_disconnect:
Cause Value=41
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_ring_noan_timer_stop:
Timer Stop Time=303424554
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmp_dsmapi_stats_cb: .
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_get_t38_success:
dsp_stream_mgr_get_t38_success: Fail Pattern 0x22424121
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmp_dsmapi_stats_cb: .
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_process_event:
[state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE]
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/act_dsm_dsp_stats_complete:
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_timer:
Timer Start Time=303424556, Timer Value=60000(ms)
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_process_event:
[state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF]
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/act_wrelease_release:
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_play_busy_timer_stop:
Timer Stop Time=303424559
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_timer_stop:
Timer Stop Time=303424559
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_do_call_history:
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:0:0/vtsp_do_call_history:
Coder Rate=42
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsp_stream_mgr_destroy: .
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsm_delete_all_streams: .
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmapi_accept_modem_passthrough_session: : dmgr: 67E50894,
active sessions 0, max sessions: 10000 rejected sessions till now: 0
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/DSM:
(3/0:D):27/dsmp_dsmapi_reserve_resource_cb: .
Feb 6 14:50:25: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_get_dsp_plt_info:
Feb 6 14:50:25: dsp_stream_mgr_get_dsp_plt_info: can't find stream
descriptor, stm_id = 0, call_id = 2008938
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:-1:-1/vtsp_dsm_closed_cb:
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:-1:-1/vtsp_process_event:
[state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE]
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:-1:-1/act_terminate:
Feb 6 14:50:25: //2008938/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:-1:-1/vtsp_timer_stop:
Timer Stop Time=303424559
Feb 6 14:50:25: //-1/744EE0E4-5EB4-DB11-8D72-003048844A2C/VTSP:
(3/0:D):27:-1:-1/vtsp_free_cdb:
CDB=0x678E8418


Feb 6 14:50:29: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:50:29: dsp_stream_mgr_remote_dnld_done: dmgr stmId: 5299
xmitFunc: 0x61E4EA6C
Feb 6 14:50:29: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsp_stream_mgr_remote_dnld_done:
Feb 6 14:50:29: dsp_stream_mgr_remote_dnld_done: dmgr stmId: 961
xmitFunc: 0x61E4EA6C
Feb 6 14:50:29: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmp_dsmapi_connect_cb:
Feb 6 14:50:29: evt_id = 9,fax_modem_type 0 mismatch with
current_feature 5
Feb 6 14:50:29: //-1/xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx/DSM:():-1/
dsmp_dsmapi_connect_cb:


Posted by www.BradReese.Com on February 7, 2007, 9:19 am
Please log in for more thread options
Hi Tamas,

You may wish to investigate Cisco's AS5350 Gateway Troubleshooting:

http://www.bradreese.com/cisco-big-iron-repair.htm#TROUBLESHOOTING

Sincerely,

Brad Reese
http://www.BradReese.Com


Posted by jalsot on February 7, 2007, 10:45 am
Please log in for more thread options
> Hi Tamas,
>
> You may wish to investigate Cisco's AS5350 Gateway Troubleshooting:
>
> http://www.bradreese.com/cisco-big-iron-repair.htm#TROUBLESHOOTING
>
> Sincerely,
>
> Brad Reesehttp://www.BradReese.Com

Hello Brad,

thank you for the link, I've checked the suggested one and went
through all docs available. I got some ideas to check the NextPort
disconnect reason codes, however as the equipment is not under my
administration, I have to wait till get some information.

Is there any idea I can try out? Right there was a new IOS placed into
the box - IOS 12.4(11)T (AFAIK) but this change didn't help.

Anybody else with an idea?

Thanks in advance,
Tamas


Posted by www.BradReese.Com on February 7, 2007, 4:16 pm
Please log in for more thread options
Tamas,

The AS5350 can work with T.38 only, hence you have to configure AS5300
with T.38

And you also need to configure the incoming dial-peer on the AS5300 as
T.38

The additional configuartions on AS5300 should be:

dial-peer voice 200 voip
incoming called-number .T
fax protocol t38 ls-redundancy 0 hs-redundancy 0

Also check if the codec and the clocking and compression techniques
used are same, on both the ends.

Sincerely,

Brad Reese
Cisco Resumes
http://www.bradreese.com/cisco-resumes.htm


Posted by jalsot on February 7, 2007, 6:11 pm
Please log in for more thread options
> Tamas,
>
> The AS5350 can work with T.38 only, hence you have to configure AS5300
> with T.38
>
> And you also need to configure the incoming dial-peer on the AS5300 as
> T.38
>
> The additional configuartions on AS5300 should be:
>
> dial-peer voice 200 voip
> incoming called-number .T
> fax protocol t38 ls-redundancy 0 hs-redundancy 0
>
> Also check if the codec and the clocking and compression techniques
> used are same, on both the ends.
>
> Sincerely,
>
> Brad Reese
> Cisco Resumeshttp://www.bradreese.com/cisco-resumes.htm

Hello,

the boxes are configured already. However what I'm trying is:
fax softmodems->as5xxx -> PSTN -> fax machine
as5300 part is just for illustration that the softmodem and PSTN parts
are good, they should just work. I also tried configuration:
softmodems-> as5350 -> PBX -> as5300 -> softmodem, where the
transmission starts, modems agree on speed, exchange CSI/TSI and start
fax page transmission. Usually this fails but rarely (once from 10
tries) the fax goes over.
Codec settings are the same, clocking might be OK (I say might because
I have control only over as5300 and I can show information got from
as5350 admins). What is interesting is that as5350 works fine with T.
37 S&F faxing.
as5350, PBX has an external clock source (both the same) which is a
precise clock for the PSTN.

Unfortunately I'm out of ideas now.

Thanks for your time to thinking about this issue.

Regards,
Tamas


Similar ThreadsPosted
Temporary failure on T.38 fax calls February 7, 2007, 5:48 am
PIX with Failover license temporary use as Full PIX September 19, 2005, 10:14 am
Radius Server for temporary account May 28, 2007, 9:18 am
h.323 calls and PIX 525 July 15, 2009, 12:21 pm
SIP incoming calls in CCM September 27, 2007, 12:00 pm
Routing ASYNC calls to IP November 23, 2005, 10:59 am
Calls generator on Cisco October 19, 2005, 5:41 am
single BRI Port calls May 11, 2006, 3:11 pm
accounting for failed calls June 21, 2006, 12:27 pm
ISDN Outgoing calls ! December 12, 2006, 5:52 am
Dialer profile for inbound calls only May 12, 2006, 2:10 pm
Cisco 700 and answering unauthorized calls October 11, 2006, 8:19 am
Make free calls to any phone anywhere November 5, 2006, 9:05 pm
ACD queue reset with outbound calls February 18, 2008, 12:06 pm
how route data and calls over Hwic 3G GSM December 4, 2008, 6:58 am
Residential Cabling Guide

Home Cabling Guide

Finally, an instantly downloadable book that saves you thousands in home improvement dollars! Enjoy living in 21st century technology-advanced home while increasing its selling value and competitive advantage on the real estate market. Whether your cabling is for home office or high-tech leisure, you can wire your home yourself or learn "wirish" to speak with your cabling contractors in their language!

Learn More