Cisco Support Community
cancel
Showing results for 
Search instead for 
Did you mean: 
Announcements

Welcome to Cisco Support Community. We would love to have your feedback.

For an introduction to the new site, click here. If you'd prefer to explore, try our test area to get started. And see here for current known issues.

New Member

Debug on Voice GW for a Fax Call

Hi Everyone,

I have a problem very strange,

My configuration,

Site A = GW 3845 (MGCP with CUCM) with PRI (E1) / ATA SIP Linksys with a fax behind,

Site B = GW 3845 (MGCP with CUCM) with BRI (T0) / FXS with a fax

On a fax call, site A => site B, the fax don't arrived on site B.

When a do a test Site X with an other E1 send a fax to site B, the fax arrived correctly.

A use several debugs on site B because site A receives too much call to use a debug command on it.

On a debug with fax KO, one of the differences with the fax calls which work is this,

Feb 10 14:10:12.311: htsp_process_event: [1/0/1, FXSLS_CONNECT, E_DSP_SIG_0100]fxsls_offhook_onhook, HF duration Max=1000, HF duration Min=150

Feb 10 14:10:12.311: htsp_timer - 1000 msec

Feb 10 14:10:13.311: htsp_process_event: [1/0/1, FXSLS_CONNECT, E_HTSP_EVENT_TIMER]fxsls_connect_wait_release_req

Feb 10 14:10:13.311: htsp_timer_stop

Somebody knows this command ?

it's a timer on the FXS because what ??

  • Video Over IP
12 REPLIES

Re: Debug on Voice GW for a Fax Call

Your logs are generated from an Hook Flash event.

Hookflash Duration

Analog phones use hookflash to access a second dial tone to initiate  certain SCCP phone features such as transfer and conference. Hookflash  is an on-hook condition of short duration that is usually generated when  a phone user presses the Flash button on a phone. The duration of an  on-hook condition generated by a Flash button varies for different phone  makes and models. Cisco voice gateways measure the duration of detected  on-hook conditions to determine whether they should be interpreted as  hookflash or not. The duration of a detected on-hook condition is  interpreted by Cisco IOS software as follows:

An on-hook condition that lasts for a time period that falls inside the hookflash duration range is considered a hookflash.

An on-hook condition that lasts for a shorter period than the lower limit of the range is ignored.

An on-hook condition that lasts for a longer period than the higher limit of the range is considered a disconnect.

The hookflash duration range for FXS ports is defined as follows:

The  lower limit of the range is set in software at 150 ms, although there  is also a hardware-imposed lower limit that is typically about 20 ms,  depending on platform type. An on-hook condition that lasts for a  shorter time than this hardware-imposed lower limit is not reported to  the Cisco IOS software.

The  upper limit of the range is set in software at 1000 ms by default,  although this value can be changed on the voice gateway. The upper limit  can be set to any value from 50 to 1550 ms.

If  the upper limit of the hookflash duration range is X, a value greater  than 150, then any on-hook duration between 150 and X is interpreted as a  hookflash. For example, if X is 1550, the hookflash duration range is  150 to 1550 ms. An on-hook signal that lasts for 1250 ms is interpreted  as a hookflash, and an on-hook signal of 55 ms is ignored.

If  the upper limit of the hookflash duration range is X, a value less than  150, then any on-hook duration between Y, the hardware lower limit, and  X is interpreted as a hookflash. For example, if X is 65, the hookflash  duration range is Y to 65 ms (assume Y is 20 ms). An on-hook signal  that lasts for 1250 ms is interpreted as a disconnect, and an on-hook  signal of 55 ms is interpreted as a hookflash. An on-hook signal of less  than Y is ignored.

In your case the message

htsp_process_event: [1/0/1, FXSLS_CONNECT, E_HTSP_EVENT_TIMER]fxsls_connect_wait_release_req

means that the router received an hook flash event via FXS and disconnected the call from the voice port 1/0/1.

It's a normal message in an MGCP controlled gateway.

What is the transport method of the fax? RTP or T.38?

Regards.


New Member

Re: Debug on Voice GW for a Fax Call

Hi Daniele

Thanks for your answer !

We use T38, when i compare with a fax call which work at the begining of the call is discuss at the same way...

Sent from Cisco Technical Support iPhone App

Re: Debug on Voice GW for a Fax Call

Try this debug: debug fax relay t30.

Post the output.

Regard.

New Member

Debug on Voice GW for a Fax Call

Hi Daniele,

You find just after the log of the debug : debug fax relay T30 all-level-1

Feb 13 09:09:38.012: 0/1/1 (66165)  344444144 fr-entered=10(ms)

Feb 13 09:09:38.012: 1/0/1 (66167)  345121637 fr-entered=10(ms)

    timestamp=345122647 fr-msg-det NSF

    timestamp=344445464 fr-msg-tx NSF

    timestamp=345123817 fr-msg-det CSI

    timestamp=344446644 fr-msg-tx CSI

    timestamp=345124567 fr-msg-det DIS

    timestamp=344447384 fr-msg-tx DIS

    timestamp=344449254 fr-msg-det TSI

    timestamp=345127057 fr-msg-tx TSI

    timestamp=344449944 fr-msg-det DCS

    timestamp=345127747 fr-msg-tx DCS

    timestamp=345131327 fr-msg-det CFR

    timestamp=344454144 fr-msg-tx CFR

    timestamp=344601614 fr-msg-det MPS

    timestamp=345279427 fr-msg-tx MPS

    timestamp=345280957 fr-msg-det MCF

    timestamp=344603784 fr-msg-tx MCF

The fax was not tranmis,

Your debug are several choices, do you want me to use an other debug ?

debug fax relay t30 ?

  all-level-1          Level-1 debug for all fax relay sessions

  called-number   Level-1 debug for fax reception from this called number

  calling-number  Level-1 debug for fax transmission to this number

  level-2              Level-2 debug for all fax relay sessions

New Member

Debug on Voice GW for a Fax Call

To be more clear, it's a new debug with 3 debug commands,

debug vpm signal

debug voice ccapi inout

debug fax relay t30 all-level-1

=======================================================================================

Feb 13 10:47:23.777: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:

   Interface=0x705E61AC, Interface Type=6, Destination=, Mode=0x9,

   Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

   Called Number=(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

   Subscriber Type Str=, FinalDestinationFlag=FALSE, Outgoing Dial-peer=0, Call Count On=FALSE,

   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=D000000002abffeb000000F500000026)

Feb 13 10:47:23.777: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 13 10:47:23.777: :cc_get_feature_vsa malloc success

Feb 13 10:47:23.777: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 13 10:47:23.777:  cc_get_feature_vsa count is 5

Feb 13 10:47:23.777: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 13 10:47:23.777: :FEATURE_VSA attributes are: feature_name:0,feature_time:1722572728,feature_id:66271

Feb 13 10:47:23.777: //66281/90857BF3807C/CCAPI/ccIFCallSetupRequestPrivate:

   SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1

Feb 13 10:47:23.777: //66281/90857BF3807C/CCAPI/ccCallSetContext:

   Context=0x65F232A8

Feb 13 10:47:23.777: //-1/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate:

   Interface=0x70515E80, Interface Type=9, Destination=0.0.0.0, Mode=0x9,

   Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

   Called Number=(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

   Subscriber Type Str=, FinalDestinationFlag=FALSE, Outgoing Dial-peer=0, Call Count On=TRUE,

   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=D000000002abffeb000000F500000026)

Feb 13 10:47:23.777: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 13 10:47:23.777: :cc_get_feature_vsa malloc success

Feb 13 10:47:23.777: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 13 10:47:23.777:  cc_get_feature_vsa count is 6

Feb 13 10:47:23.777: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

Feb 13 10:47:23.777: :FEATURE_VSA attributes are: feature_name:0,feature_time:1722574024,feature_id:66272

Feb 13 10:47:23.777: //66282/90857BF3807C/CCAPI/ccIFCallSetupRequestPrivate:

   SPI Call Setup Request Is Success; Interface Type=9, FlowMode=1

Feb 13 10:47:23.777: //66282/90857BF3807C/CCAPI/ccCallSetContext:

   Context=0x65F22D28

Feb 13 10:47:23.777: //66282/90857BF3807C/CCAPI/cc_api_call_connected:

   Interface=0x70515E80, Data Bitmask=0x0, Progress Indication=NULL(0),

   Connection Handle=0

Feb 13 10:47:23.777: //66282/90857BF3807C/CCAPI/cc_api_call_connected:

   Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)

Feb 13 10:47:23.781: //66281/90857BF3807C/CCAPI/cc_api_call_proceeding:

   Interface=0x705E61AC, Progress Indication=NULL(0)

Feb 13 10:47:23.785: //66281/90857BF3807C/CCAPI/cc_api_call_connected:

   Interface=0x705E61AC, Data Bitmask=0x1, Progress Indication=DESTINATION IS NON ISDN(2),

   Connection Handle=0

Feb 13 10:47:23.785: //66281/90857BF3807C/CCAPI/cc_api_call_connected:

   Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)

Feb 13 10:47:23.785: //66281/90857BF3807C/CCAPI/ccCallModify:

   Nominator=0x1000, Params=0x715D4B78, Call Id=66281

Feb 13 10:47:23.785: //66281/xxxxxxxxxxxx/CCAPI/ccCallReportDigits:

   (callID=0x102E9, digit_event=0x1, enable=TRUE, consume=FALSE)

Feb 13 10:47:23.785: //66281/90857BF3807C/CCAPI/ccCallReportDigits:

   Enabled=TRUE, Call Id=66281

Feb 13 10:47:23.785: //66281/xxxxxxxxxxxx/CCAPI/cc_api_call_report_digits_done:

   (vdbPtr=0x705E61AC, callID=0x102E9, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)

Feb 13 10:47:23.785: //66281/90857BF3807C/CCAPI/cc_api_call_report_digits_done:

   Enabled=TRUE, Disposition=0x0, Interface=0x705E61AC, Call Id=66281

Feb 13 10:47:23.785: //66281/90857BF3807C/CCAPI/cc_api_call_report_digits_done:

   Call Entry(Initial Digit Timeout=10000(ms), Inter Digit Timeout=10000(ms))

Feb 13 10:47:23.785: //66281/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:

   (confID=0x715D4D1C, callID1=0x102E9, callID2=0x102EA, tag=0x0)

Feb 13 10:47:23.785: //66281/90857BF3807C/CCAPI/ccConferenceCreate:

   Conference Id=0x715D4D1C, Call Id1=66281, Call Id2=66282, Tag=0x0

Feb 13 10:47:23.785: //66281/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:

   Conference Id=0x8101, Source Interface=0x705E61AC, Source Call Id=66281,

   Destination Call Id=66282, Disposition=0x0, Tag=0xFFFFFFFF

Feb 13 10:47:23.785: //66282/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:

   Conference Id=0x8101, Source Interface=0x70515E80, Source Call Id=66282,

   Destination Call Id=66281, Disposition=0x0, Tag=0x0

Feb 13 10:47:23.785: //66281/90857BF3807C/CCAPI/cc_generic_bridge_done:

   Conference Id=0x8101, Source Interface=0x70515E80, Source Call Id=66282,

   Destination Call Id=66281, Disposition=0x0, Tag=0x0

Feb 13 10:47:23.785: //66281/90857BF3807C/CCAPI/ccConferenceCreate:

   Call Entry(Conference Id=0x8101, Destination Call Id=66282)

Feb 13 10:47:23.785: //66282/90857BF3807C/CCAPI/ccConferenceCreate:

   Call Entry(Conference Id=0x8101, Destination Call Id=66281)

Feb 13 10:47:23.785: //66281/90857BF3807C/CCAPI/cc_api_caps_ind:

   Destination Interface=0x70515E80, Destination Call Id=66282, Source Call Id=66281,

   Caps(Codec=0x1, Fax Rate=0x1, Vad=0x1,

   Modem=0x2, Codec Bytes=20, Signal Type=3)

Feb 13 10:47:23.785: //66281/90857BF3807C/CCAPI/cc_api_caps_ind:

   Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),

   Playout Max=200(ms), Fax Nom=300(ms))

Feb 13 10:47:23.785: //66282/90857BF3807C/CCAPI/cc_api_caps_ind:

   Destination Interface=0x705E61AC, Destination Call Id=66281, Source Call Id=66282,

   Caps(Codec=0x1, Fax Rate=0x2, Vad=0x1,

   Modem=0x0, Codec Bytes=160, Signal Type=2)

Feb 13 10:47:23.785: //66282/90857BF3807C/CCAPI/cc_api_caps_ind:

   Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),

   Playout Max=250(ms), Fax Nom=300(ms))

Feb 13 10:47:23.785: //66282/90857BF3807C/CCAPI/cc_api_caps_ack:

   Destination Interface=0x705E61AC, Destination Call Id=66281, Source Call Id=66282,

   Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),

   Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=609)

Feb 13 10:47:23.785: //66281/90857BF3807C/CCAPI/cc_api_caps_ack:

   Destination Interface=0x70515E80, Destination Call Id=66282, Source Call Id=66281,

   Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),

   Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=609)

Feb 13 10:47:23.785: //66281/90857BF3807C/CCAPI/cc_api_call_modify_done:

   Result=0, Interface=0x705E61AC, Call Id=66281

Feb 13 10:47:23.789: //66281/90857BF3807C/CCAPI/cc_api_voice_mode_event:

   Call Id=66281

Feb 13 10:47:23.789: //66281/90857BF3807C/CCAPI/cc_api_voice_mode_event:

   Call Entry(Context=0x65F232A8)

Feb 13 10:47:23.789: //66281/90857BF3807C/CCAPI/cc_process_notify_bridge_done:

   Conference Id=0x8101, Call Id1=66281, Call Id2=66282

Feb 13 10:47:23.849: //66281/90857BF3807C/CCAPI/ccSetDigitTimeouts:

   Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms)

Feb 13 10:47:23.849: //66281/90857BF3807C/CCAPI/ccSetDigitTimeouts:

   Call Entry(Inter Digit Timeout=4000(ms), Initial Digit Timeout=4000(ms))

Feb 13 10:47:23.849: //66281/90857BF3807C/CCAPI/ccRestartDigitTimeoutMsec:

   Digit Timeout=0, Call Id=66281

Feb 13 10:47:23.849: //66281/xxxxxxxxxxxx/CCAPI/ccCallReportDigits:

   (callID=0x102E9, digit_event=0x1, enable=TRUE, consume=FALSE)

Feb 13 10:47:23.849: //66281/90857BF3807C/CCAPI/ccCallReportDigits:

   Enabled=TRUE, Call Id=66281

Feb 13 10:47:23.849: //66281/xxxxxxxxxxxx/CCAPI/cc_api_call_report_digits_done:

   (vdbPtr=0x705E61AC, callID=0x102E9, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)

Feb 13 10:47:23.849: //66281/90857BF3807C/CCAPI/cc_api_call_report_digits_done:

   Enabled=TRUE, Disposition=0x0, Interface=0x705E61AC, Call Id=66281

Feb 13 10:47:23.849: //66281/90857BF3807C/CCAPI/cc_api_call_report_digits_done:

   Call Entry(Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms))

Feb 13 10:47:23.849: //66281/90857BF3807C/CCAPI/ccCallModifyExtended:

   Nominator=0x715D48B0, Params=0x715D3D48, Call Id=66281

Feb 13 10:47:23.849: //66282/90857BF3807C/CCAPI/ccCallModify:

   Nominator=0x28018830, Params=0x715D3ED8, Call Id=66282

Feb 13 10:47:23.849: //66281/90857BF3807C/CCAPI/cc_api_call_modify_done:

   Result=0, Interface=0x705E61AC, Call Id=66281

Feb 13 10:47:23.849: //66282/90857BF3807C/CCAPI/cc_api_call_modify_done:

   Result=0, Interface=0x70515E80, Call Id=66282

Feb 13 10:47:23.982: //66281/90857BF3807C/CCAPI/ccCallModifyExtended:

   Nominator=0x715D48B0, Params=0x715D3D48, Call Id=66281

Feb 13 10:47:23.982: //66282/90857BF3807C/CCAPI/ccCallModify:

   Nominator=0x18E30, Params=0x715D3ED8, Call Id=66282

Feb 13 10:47:23.982: //66281/90857BF3807C/CCAPI/cc_api_call_modify_done:

   Result=0, Interface=0x705E61AC, Call Id=66281

GW3845#

Feb 13 10:47:23.982: //66282/90857BF3807C/CCAPI/cc_api_call_modify_done:

   Result=0, Interface=0x70515E80, Call Id=66282

Feb 13 10:47:24.102: [1/0/1] set signal state = 0x4 timestamp = 0

Feb 13 10:47:24.102: [1/0/1] set signal state = 0x0 timestamp = 0

GW3845#

Feb 13 10:47:25.602: [1/0/1] set signal state = 0x4 timestamp = 0

Feb 13 10:47:26.546: htsp_process_event: [1/0/1, FXSLS_WAIT_OFFHOOK, E_DSP_SIG_1100]fxsls_waitoff_offhook

Feb 13 10:47:26.546: [1/0/1] set signal state = 0x4 timestamp = 0

Feb 13 10:47:26.554: [1/0/1] set signal state = 0x6 timestamp = 200

Feb 13 10:47:26.554: htsp_timer2 - 200 msec

Feb 13 10:47:26.754: htsp_process_event: [1/0/1, FXSLS_WAIT_OFFHOOK, E_HTSP_EVENT_TIMER2]fxsls_offhook_dial htsp_dial

Feb 13 10:47:26.754: //66279/8DB5DDBF807B/CCAPI/cc_api_call_connected:

   Interface=0x70990EB4, Data Bitmask=0x1, Progress Indication=DESTINATION IS NON ISDN(2),

   Connection Handle=0

Feb 13 10:47:26.754: //66279/8DB5DDBF807B/CCAPI/cc_api_call_connected:

   Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)

Feb 13 10:47:26.754: htsp_process_event: [1/0/1, FXSLS_CONNECT, E_DSP_DIALING_DONE]

Feb 13 10:47:26.754: //66279/8DB5DDBF807B/CCAPI/ccGenerateToneInfo:

   Stop Tone On Digit=FALSE, Tone=Null,

   Tone Direction=Host, Params=0x0, Call Id=66279

Feb 13 10:47:26.754: //66279/8DB5DDBF807B/CCAPI/ccCallFeature:

   Feature Type=34, Call Id=66279

Feb 13 10:47:26.754: //66279/8DB5DDBF807B/CCAPI/ccGenerateToneInfo:

   Stop Tone On Digit=FALSE, Tone=Null,

   Tone Direction=Network, Params=0x0, Call Id=66279htsp_call_feature:feature 34

Feb 13 10:47:26.754: htsp_process_event: [1/0/1, FXSLS_CONNECT, E_HTSP_STOP_RINGING]

Feb 13 10:47:26.758: //66277/8DB4A4D6807A/CCAPI/ccGenerateToneInfo:

   Stop Tone On Digit=FALSE, Tone=Null,

   Tone Direction=Network, Params=0x0, Call Id=66277

Feb 13 10:47:26.758: //66277/8DB4A4D6807A/CCAPI/ccCallModifyExtended:

   Nominator=0x715D48B0, Params=0x715D3D48, Call Id=66277

Feb 13 10:47:26.758: //66278/8DB4A4D6807A/CCAPI/ccCallModify:

   Nominator=0x28018830, Params=0x715D3ED8, Call Id=66278

Feb 13 10:47:26.762: //66279/8DB5DDBF807B/CCAPI/ccCallModifyExtended:

   Nominator=0x715D48B0, Params=0x715D3D48, Call Id=66279

Feb 13 10:47:26.762: //66280/8DB5DDBF807B/CCAPI/ccCallModify:

   Nominator=0x28018830, Params=0x715D3ED8, Call Id=66280

Feb 13 10:47:26.762: //66279/8DB5DDBF807B/CCAPI/cc_api_call_modify_done:

   Result=0, Interface=0x70990EB4, Call Id=66279

Feb 13 10:47:26.762: //66278/8DB4A4D6807A/CCAPI/cc_api_call_modify_done:

   Result=0, Interface=0x70515E80, Call Id=66278

Feb 13 10:47:26.762: //66280/8DB5DDBF807B/CCAPI/cc_api_call_modify_done:

   Result=0, Interface=0x70515E80, Call Id=66280

Feb 13 10:47:26.762: //66277/8DB4A4D6807A/CCAPI/cc_api_call_modify_done:

   Result=0, Interface=0x705F57B8, Call Id=66277

Feb 13 10:47:26.766: //66277/8DB4A4D6807A/CCAPI/ccCallModifyExtended:

   Nominator=0x715D48B0, Params=0x715D3D48, Call Id=66277

Feb 13 10:47:26.766: //66278/8DB4A4D6807A/CCAPI/ccCallModify:

   Nominator=0x18E30, Params=0x715D3ED8, Call Id=66278

Feb 13 10:47:26.766: //66277/8DB4A4D6807A/CCAPI/cc_api_call_modify_done:

   Result=0, Interface=0x705F57B8, Call Id=66277

Feb 13 10:47:26.766: //66278/8DB4A4D6807A/CCAPI/cc_api_call_modify_done:

   Result=0, Interface=0x70515E80, Call Id=66278

Feb 13 10:47:26.766: //66279/8DB5DDBF807B/CCAPI/ccSetDigitTimeouts:

   Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms)

Feb 13 10:47:26.766: //66279/8DB5DDBF807B/CCAPI/ccSetDigitTimeouts:

   Call Entry(Inter Digit Timeout=4000(ms), Initial Digit Timeout=4000(ms))

Feb 13 10:47:26.766: //66279/8DB5DDBF807B/CCAPI/ccRestartDigitTimeoutMsec:

   Digit Timeout=0, Call Id=66279

Feb 13 10:47:26.766: //66279/xxxxxxxxxxxx/CCAPI/ccCallReportDigits:

   (callID=0x102E7, digit_event=0x1, enable=TRUE, consume=FALSE)

Feb 13 10:47:26.766: //66279/8DB5DDBF807B/CCAPI/ccCallReportDigits:

   Enabled=TRUE, Call Id=66279

Feb 13 10:47:26.766: //66279/xxxxxxxxxxxx/CCAPI/cc_api_call_report_digits_done:

   (vdbPtr=0x70990EB4, callID=0x102E7, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)

Feb 13 10:47:26.766: //66279/8DB5DDBF807B/CCAPI/cc_api_call_report_digits_done:

   Enabled=TRUE, Disposition=0x0, Interface=0x70990EB4, Call Id=66279

Feb 13 10:47:26.766: //66279/8DB5DDBF807B/CCAPI/cc_api_call_report_digits_done:

   Call Entry(Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms))

Feb 13 10:47:26.766: //66279/8DB5DDBF807B/CCAPI/ccCallModifyExtended:

GW3845#Nominator=0x715D48B0, Params=0x715D3D48, Call Id=66279

Feb 13 10:47:26.770: //66280/8DB5DDBF807B/CCAPI/ccCallModify:

   Nominator=0x18E30, Params=0x715D3ED8, Call Id=66280

Feb 13 10:47:26.770: //66279/8DB5DDBF807B/CCAPI/cc_api_call_modify_done:

   Result=0, Interface=0x70990EB4, Call Id=66279

Feb 13 10:47:26.770: //66280/8DB5DDBF807B/CCAPI/cc_api_call_modify_done:

   Result=0, Interface=0x70515E80, Call Id=66280

GW3845#

Feb 13 10:47:27.850: //66281/90857BF3807C/CCAPI/cc_handle_inter_digit_timer:

   Generate inter-digit timeout CC_EV_CALL_DIGIT_END event

GW3845#

Feb 13 10:47:30.766: //66279/8DB5DDBF807B/CCAPI/cc_handle_inter_digit_timer:

   Generate inter-digit timeout CC_EV_CALL_DIGIT_END event

GW3845#

Feb 13 10:47:32.642: //66279/8DB5DDBF807B/CCAPI/cc_api_call_feature:

   Feature Type=1, Interface=0x70990EB4, Call Id=66279

Feb 13 10:47:32.642: //66279/8DB5DDBF807B/CCAPI/cc_api_t38_fax_start:

   Destination Interface=0x70515E80, Destination Call Id=66280, Source Call Id=66279,

   Caps(Codec=T38Fax(0x10000), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),

   Modem=OFF(0x0), Codec Bytes=160, Signal Type=2)

Feb 13 10:47:32.642: //66280/8DB5DDBF807B/CCAPI/cc_api_call_feature:

   Feature Type=38, Interface=0x70515E80, Call Id=66280

Feb 13 10:47:32.642: //66279/8DB5DDBF807B/CCAPI/cc_api_call_feature:

   Feature Type=1, Interface=0x70990EB4, Call Id=66279

Feb 13 10:47:32.646: //66278/8DB4A4D6807A/CCAPI/cc_api_t38_fax_start:

   Destination Interface=0x705F57B8, Destination Call Id=66277, Source Call Id=66278,

   Caps(Codec=T38Fax(0x10000), Fax Rate=FAX_RATE_VOICE(0x2), Vad=ON(0x2),

   Modem=OFF(0x0), Codec Bytes=20, Signal Type=2)

Feb 13 10:47:32.646: //66277/xxxxxxxxxxxx/CCAPI/cc_api_local_codec_dnld_done:

   (dstVdbPtr=0x70515E80, dstCallId=0x102E6, srcCallId=0x102E5)

Feb 13 10:47:32.646: //66278/8DB4A4D6807A/CCAPI/cc_api_local_codec_dnld_done:

   Destination Interface=0x70515E80, Destination Call Id=66278, Source Call Id=66277

Feb 13 10:47:32.646: //66278/8DB4A4D6807A/CCAPI/cc_api_call_feature:

   Feature Type=39, Interface=0x70515E80, Call Id=66278

Feb 13 10:47:32.646: //66278/8DB4A4D6807A/CCAPI/cc_api_set_fax_mode:

   Destination Interface=0x70515E80, Destination Call Id=66278, Source Call Id=66277

Feb 13 10:47:32.650: //66280/8DB5DDBF807B/CCAPI/cc_api_t38_fax_start:

   Destination Interface=0x70990EB4, Destination Call Id=66279, Source Call Id=66280,

   Caps(Codec=T38Fax(0x10000), Fax Rate=FAX_RATE_VOICE(0x2), Vad=ON(0x2),

   Modem=OFF(0x0), Codec Bytes=20, Signal Type=2)

Feb 13 10:47:32.650: //66277/8DB4A4D6807A/CCAPI/cc_api_remote_codec_dnld_done:

   Destination Interface=0x705F57B8, Destination Call Id=66277, Source Call Id=66278, Xmit Function=0x629E293C

Feb 13 10:47:32.650: //66279/xxxxxxxxxxxx/CCAPI/cc_api_local_codec_dnld_done:

   (dstVdbPtr=0x70515E80, dstCallId=0x102E8, srcCallId=0x102E7)

Feb 13 10:47:32.650: //66280/8DB5DDBF807B/CCAPI/cc_api_local_codec_dnld_done:

   Destination Interface=0x70515E80, Destination Call Id=66280, Source Call Id=66279

Feb 13 10:47:32.650: //66280/8DB5DDBF807B/CCAPI/cc_api_call_feature:

   Feature Type=39, Interface=0x70515E80, Call Id=66280

Feb 13 10:47:32.650: //66280/8DB5DDBF807B/CCAPI/cc_api_set_fax_mode:

   Destination Interface=0x70515E80, Destination Call Id=66280, Source Call Id=66279

Feb 13 10:47:32.650: //66279/8DB5DDBF807B/CCAPI/cc_api_remote_codec_dnld_done:

GW3845#Destination Interface=0x70990EB4, Destination Call Id=66279, Source Call Id=66280, Xmit Function=0x629E293C

Feb 13 10:47:32.666: 0/1/1 (66277)  350318798 fr-entered=10(ms)

Feb 13 10:47:32.666: 1/0/1 (66279)  350996292 fr-entered=10(ms)

GW3845#

    timestamp=350997292 fr-msg-det NSF

    timestamp=350320118 fr-msg-tx NSF

GW3845#

    timestamp=350998472 fr-msg-det CSI

    timestamp=350321288 fr-msg-tx CSI

    timestamp=350999212 fr-msg-det DIS

GW3845#

    timestamp=350322038 fr-msg-tx DIS

GW3845#

    timestamp=350324988 fr-msg-det TSI

    timestamp=351002782 fr-msg-tx TSI

    timestamp=350325758 fr-msg-det DCS

GW3845#

    timestamp=351003562 fr-msg-tx DCS

GW3845#

    timestamp=351007142 fr-msg-det CFR

    timestamp=350329968 fr-msg-tx CFR

GW3845#

Feb 13 10:47:49.638: htsp_process_event: [1/0/1, FXSLS_CONNECT, E_DSP_SIG_0100]fxsls_offhook_onhook, HF duration Max=1000, HF duration Min=150

Feb 13 10:47:49.638: htsp_timer - 1000 msec

Feb 13 10:47:50.638: htsp_process_event: [1/0/1, FXSLS_CONNECT, E_HTSP_EVENT_TIMER]fxsls_connect_wait_release_req

Feb 13 10:47:50.638: htsp_timer_stop

Feb 13 10:47:50.638: //66279/8DB5DDBF807B/CCAPI/cc_api_call_disconnected:

   Cause Value=16, Interface=0x70990EB4, Call Id=66279

Feb 13 10:47:50.638: //66279/8DB5DDBF807B/CCAPI/cc_api_call_disconnected:

   Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)

Feb 13 10:47:50.638: //66279/xxxxxxxxxxxx/CCAPI/ccCallReportDigits:

   (callID=0x102E7, digit_event=0x0, enable=FALSE, consume=FALSE)

Feb 13 10:47:50.638: //66279/8DB5DDBF807B/CCAPI/ccCallReportDigits:

   Enabled=TRUE, Call Id=66279

Feb 13 10:47:50.638: //66279/xxxxxxxxxxxx/CCAPI/cc_api_call_report_digits_done:

   (vdbPtr=0x70990EB4, callID=0x102E7, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)

Feb 13 10:47:50.638: //66279/8DB5DDBF807B/CCAPI/cc_api_call_report_digits_done:

   Enabled=TRUE, Disposition=0x0, Interface=0x70990EB4, Call Id=66279

Feb 13 10:47:50.638: //66279/8DB5DDBF807B/CCAPI/cc_api_call_report_digits_done:

   Call Entry(Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms))

Feb 13 10:47:50.638: //66280/8DB5DDBF807B/CCAPI/ccGetCallStatistics:

   Call Stats=0x715CF9E8, Call Id=66280

Feb 13 10:47:50.638: //66279/8DB5DDBF807B/CCAPI/ccConferenceDestroy:

   Conference Id=0x8100, Tag=0x0

Feb 13 10:47:50.638: //66279/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:

   Conference Id=0x8100, Source Interface=0x70990EB4, Source Call Id=66279,

   Destination Call Id=66280, Disposition=0x0, Tag=0x0

Feb 13 10:47:50.638: //66280/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:

   Conference Id=0x8100, Source Interface=0x70515E80, Source Call Id=66280,

   Destination Call Id=66279, Disposition=0x0, Tag=0x0

Feb 13 10:47:50.638: //66279/8DB5DDBF807B/CCAPI/cc_generic_bridge_done:

   Conference Id=0x8100, Source Interface=0x70515E80, Source Call Id=66280,

   Destination Call Id=66279, Disposition=0x0, Tag=0x0

Feb 13 10:47:50.642: //66279/8DB5DDBF807B/CCAPI/ccCallDisconnect:

   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)

Feb 13 10:47:50.642: //66279/8DB5DDBF807B/CCAPI/ccCallDisconnect:

   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)

Feb 13 10:47:50.642: //66279/8DB5DDBF807B/CCAPI/cc_api_get_transfer_info:

   Transfer Number Is Null

Feb 13 10:47:50.642: //66280/8DB5DDBF807B/CCAPI/ccCallDisconnect:

   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

Feb 13 10:47:50.642: //66280/8DB5DDBF807B/CCAPI/ccCallDisconnect:

   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)

Feb 13 10:47:50.642: htsp_timer_stop3

Feb 13 10:47:50.642: //66280/8DB5DDBF807B/CCAPI/cc_api_call_disconnect_done:

   Disposition=0, Interface=0x70515E80, Tag=0x0, Call Id=66280,

   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

Feb 13 10:47:50.642: //66280/8DB5DDBF807B/CCAPI/cc_api_call_disconnect_done:

   Call Disconnect Event Sent

Feb 13 10:47:50.642: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 13 10:47:50.642: :cc_free_feature_vsa freeing 66AC6310

Feb 13 10:47:50.642: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 13 10:47:50.642:  vsacount in free is 5

Feb 13 10:47:50.646: htsp_process_event: [1/0/1, FXSLS_WAIT_RELEASE_REQ, E_HTSP_RELEASE_REQ]fxsls_waitrls_req_rls

Feb 13 10:47:50.646: [1/0/1] set signal state = 0x4 timestamp = 0

Feb 13 10:47:50.646: [1/0/1] set signal state = 0x4 timestamp = 0

Feb 13 10:47:50.650: //66279/8DB5DDBF807B/CCAPI/cc_api_call_disconnect_done:

   Disposition=0, Interface=0x70990EB4, Tag=0x0, Call Id=66279,

   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

Feb 13 10:47:50.650: //66279/8DB5DDBF807B/CCAPI/cc_api_call_disconnect_done:

   Call Disconnect Event Sent

Feb 13 10:47:50.650: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 13 10:47:50.650: :cc_free_feature_vsa freeing 66AC6160

Feb 13 10:47:50.650: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 13 10:47:50.650:  vsacount in free is 4

Feb 13 10:47:50.650: htsp_process_event: [1/0/1, FXSLS_ONHOOK, E_DSP_SIG_0100]fxsls_onhook_onhook

Feb 13 10:47:50.766: //66277/xxxxxxxxxxxx/CCAPI/ccCallReportDigits:

   (callID=0x102E5, digit_event=0x0, enable=FALSE, consume=FALSE)

Feb 13 10:47:50.766: //66277/8DB4A4D6807A/CCAPI/ccCallReportDigits:

   Enabled=TRUE, Call Id=66277

Feb 13 10:47:50.766: //66277/xxxxxxxxxxxx/CCAPI/cc_api_call_report_digits_done:

   (vdbPtr=0x705F57B8, callID=0x102E5, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)

Feb 13 10:47:50.766: //66277/8DB4A4D6807A/CCAPI/cc_api_call_report_digits_done:

   Enabled=TRUE, Disposition=0x0, Interface=0x705F57B8, Call Id=66277

Feb 13 10:47:50.766: //66277/8DB4A4D6807A/CCAPI/cc_api_call_report_digits_done:

   Call Entry(Initial Digit Timeout=4000(ms), Inter Digit Timeout=4000(ms))

Feb 13 10:47:50.766: //66278/8DB4A4D6807A/CCAPI/ccGetCallStatistics:

   Call Stats=0x715D0D80, Call Id=66278

Feb 13 10:47:50.766: //66277/8DB4A4D6807A/CCAPI/ccConferenceDestroy:

   Conference Id=0x80FF, Tag=0x0

Feb 13 10:47:50.766: //66277/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:

   Conference Id=0x80FF, Source Interface=0x705F57B8, Source Call Id=66277,

   Destination Call Id=66278, Disposition=0x0, Tag=0x0

Feb 13 10:47:50.766: //66278/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:

   Conference Id=0x80FF, Source Interface=0x70515E80, Source Call Id=66278,

   Destination Call Id=66277, Disposition=0x0, Tag=0x0

Feb 13 10:47:50.766: //66277/8DB4A4D6807A/CCAPI/cc_generic_bridge_done:

   Conference Id=0x80FF, Source Interface=0x70515E80, Source Call Id=66278,

   Destination Call Id=66277, Disposition=0x0, Tag=0x0

Feb 13 10:47:50.766: //66277/8DB4A4D6807A/CCAPI/ccCallDisconnect:

   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

Feb 13 10:47:50.766: //66277/8DB4A4D6807A/CCAPI/ccCallDisconnect:

   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)

Feb 13 10:47:50.770: //66277/8DB4A4D6807A/CCAPI/cc_api_get_transfer_info:

   Transfer Number Is Null

Feb 13 10:47:50.770: //66278/8DB4A4D6807A/CCAPI/ccCallDisconnect:

   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

Feb 13 10:47:50.770: //66278/8DB4A4D6807A/CCAPI/ccCallDisconnect:

   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)

Feb 13 10:47:50.770: //66278/8DB4A4D6807A/CCAPI/cc_api_call_disconnect_done:

   Disposition=0, Interface=0x70515E80, Tag=0x0, Call Id=66278,

   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

Feb 13 10:47:50.770: //66278/8DB4A4D6807A/CCAPI/cc_api_call_disconnect_done:

   Call Disconnect Event Sent

Feb 13 10:47:50.770: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 13 10:47:50.770: :cc_free_feature_vsa freeing 66AC6238

GW3845#

Feb 13 10:47:50.770: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 13 10:47:50.770:  vsacount in free is 3

Feb 13 10:47:50.778: //66277/8DB4A4D6807A/CCAPI/cc_api_call_disconnect_done:

   Disposition=0, Interface=0x705F57B8, Tag=0x0, Call Id=66277,

   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

Feb 13 10:47:50.778: //66277/8DB4A4D6807A/CCAPI/cc_api_call_disconnect_done:

   Call Disconnect Event Sent

Feb 13 10:47:50.778: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 13 10:47:50.778: :cc_free_feature_vsa freeing 66AC5ED8

Feb 13 10:47:50.778: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

Feb 13 10:47:50.778:  vsacount in free is 2

Debug on Voice GW for a Fax Call

The hook flash event duration sames 1000 msec. In your case the HF duration Max=1000.

You can try to increase HF duration Max to 1500 ms using timing hookflash-in command. In this case a value of 1000 ms is interpreted  as an hookflash.

Regards.

New Member

Debug on Voice GW for a Fax Call

With the debug command : debug fax relay t30 level-2, i look that the fax data stop at one moment before the hook flash.

So i think that we have a part of the fax data which is drop somewhere...

Tomorrow, i will take a network trace.

Debug on Voice GW for a Fax Call

In general, is useful remove Non Standard Facilities NSF from fax transaction, disable Error Correction or ECM and decrease speed to 9600 or 14400 bps.

In case of MGCP gateway I use this configuration:

mgcp fax rate 9600

no mgcp fax t38 ecm

mgcp fax t38 ls_redundancy 2

mgcp fax t38 hs_redundancy 2

mgcp fax t38 nsf 000000

Regards.

New Member

Debug on Voice GW for a Fax Call

Hi Daniele,

I resolved my problem.

With several traces, i seen that the data in T38 stop during the transmission. The T38 negotiation was between the GW with T0 and the ATA Linksys behind the GW with T2.

On the GW with T2, the T38 wasn't used. When i deleted the T38 on the GW with T0, the faxes are correctly transmitted.

The MGCP commands which corrected my problem,

  • mgcp fax t38 inhibit
  • mgcp default-package mt-package
  • no ccm-manager      fax protocol cisco

What is the MGCP package MT-PACKAGE ???

I find nothing on the web....

Thank you for your help Daniele

3220
Views
0
Helpful
12
Replies
This widget could not be displayed.