1. Home
  2. Cisco Systems
  3. Temporary failure on T.38 fax calls

Temporary failure on T.38 fax calls

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:

Reply to
jalsot
Loading thread data ...

Hi Tamas,

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

formatting link
Sincerely,

Brad Reese

formatting link

Reply to
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

Reply to
jalsot

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

formatting link

Reply to
www.BradReese.Com

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

Reply to
jalsot

Is there any particular reason you are using 12.4.9T code? Could be an IOS bug and since you know this works with 12.3.15 on the AS5300, why not use this same version of IOS on the AS5350?

Scott

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],

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],

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],

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],

Reply to
Thrill5

Dear Scott,

unfortunately the as5350 box is not in our administration, ownership. It is owned by the carrier we would like to send calls to. They explained that they upgraded to 12.4.9T because of some ugly bugs in previous version so they cannot downgrade - it would break another customer's service.

Thanks for your comment!

Regards, Tamas

Reply to
jalsot

I found one thing which might be interesting. The CONNECT messages send back from the Cisco gateways differ:

AS5350 sends Q.931 Protocol discriminator: Q.931 Call reference value length: 2 Call reference flag: Message sent to originating side Call reference value: 11BE Message type: CONNECT (0x07) Bearer capability Information element: Bearer capability Length: 3 1... .... = Extension indicator: last octet .00. .... = Coding standard: ITU-T standardized coding (0x00) ...0 1000 = Information transfer capability: Unrestricted digital information (0x08) 1... .... = Extension indicator: last octet .00. .... = Transfer mode: Circuit mode (0x00) ...1 0011 = Information transfer rate: 384 kbit/s (0x13) 1... .... = Extension indicator: last octet ...0 0101 = User information layer 1 protocol: Recommendation H.221 and H.242 (0x05) Display Information element: Display Length: 0 Progress indicator Information element: Progress indicator Length: 2 .00. .... = Coding standard: ITU-T standardized coding (0x00) Location: User (U) Progress description: Call is not end-to-end ISDN - progress information available in-band User-user Information element: User-user Length: 188 Protocol discriminator: X.208 and X.209 coded user information

AS5300 sends Q.931 Protocol discriminator: Q.931 Call reference value length: 2 Call reference flag: Message sent to originating side Call reference value: 11C1 Message type: CONNECT (0x07) Bearer capability Information element: Bearer capability Length: 3 1... .... = Extension indicator: last octet

.00. .... = Coding standard: ITU-T standardized coding (0x00) ...0 1000 = Information transfer capability: Unrestricted digital information (0x08) 1... .... = Extension indicator: last octet .00. .... = Transfer mode: Circuit mode (0x00) ...1 0000 = Information transfer rate: 64 kbit/s (0x10) 1... .... = Extension indicator: last octet ...0 0011 = User information layer 1 protocol: Recommendation G.711 A-law (0x03) User-user Information element: User-user Length: 169 Protocol discriminator: X.208 and X.209 coded user information

As I got informed from guys (as5350 admins), bearer-cap speech is set under voice-port already. What I don't know is why as5350 sends different CONNECT messages and if this can cause issues later. I also see H.225 NOTIFY messages from as5350 while nothing from as5300.

What do you think, can it relate to my issue? Unfortunately I'm not an expert in Q.931 signaling.

Regards, Tamas

Reply to
jalsot

Cabling-Design.com Forums website is not affiliated with any of the manufacturers or service providers discussed here. All logos and trade names are the property of their respective owners.