05-03-2013 10:24 AM - edited 03-16-2019 05:08 PM
Hello to all ,.
I have some routers Cisco 2921 , with FXO ports and analog phones connected to it , the problem ( in 3 sites ) is about the calls , there are down after 2 minutes
The version ( in 3 routers ) is
Cisco IOS Software, C2900 Software (C2900-UNIVERSALK9-M), Version 15.2(2)T
Cisco CISCO2921/K9 (revision 1.0) with 475136K/49152K bytes of memory
Do you know about some bug in this IOS ?
Thanks a lot !
Hugo
05-05-2013 03:22 AM
Hi Hugo,
Can you please provide the ouput for the following debugs from one of the gateways for one such call which fails after 2 minutes:
> In case the gateway is MGCP controlled:
++ debug vpm signal
++ debug voice vtsp events
++ debug mgcp packets
> In case the gateway is H.323 controlled:
++ debug vpm signal
++ debug voice vtsp events
++ debug h225 asn1
++ debug h245 asn1
In case the call volume is high, please configure the gateway for buffer logging. Also include the calling and the called number for the call. If possible, please try the call when there are no other active calls.
Regards,
Jagpreet
05-09-2013 06:39 AM
Here is the log
the call is completed in this case
sh voice call stat
CallID CID ccVdb Port Slot/DSP:Ch Called # Codec MLPP Dial-peers
0x3901 DEF 0x31B6BB40 1/0/13 0/1:2 9000 g711ulaw 213/2
1 active call found
Suc#debug vpm signal
Voice Port Module signaling debugging is enabled
Suc#
Suc#debug voice vtsp events
^
% Invalid input detected at '^' marker.
Suc#
Suc#debug h225 asn1
H.225 ASN1 Messages debugging is on
Suc#
Suc#debug h245 asn1
H.245 ASN1 Messages debugging is on
Suc#debug vou uce ice vtsp
voip vtsp default debugging is on
Suc#terminal monitor
Suc#sh debug
H.225:
H.225 ASN1 Messages debugging is on
H.245:
H.245 ASN1 Messages debugging is on
VTSP:
debug voip vtsp event is ON (filter is OFF)
debug voip vtsp error software is ON
debug voip vtsp error call is ON (filter is OFF)
debug voip vtsp inout is ON (filter is OFF)
Voice Port Module signaling debugging is on
Suc#sh voice call stat
CallID CID ccVdb Port Slot/DSP:Ch Called # Codec MLPP Dial-peers
0x3901 DEF 0x31B6BB40 1/0/13 0/1:2 9000 g711ulaw 213/2
1 active call found
Suc#
May 8 10:06:24: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 5
May 8 10:06:24: H245 MSC INCOMING ENCODE BUFFER::= 0400000000
May 8 10:06:24:
May 8 10:06:24: H245 MSC INCOMING PDU ::=
value MultimediaSystemControlMessage ::= request : closeLogicalChannel :
{
forwardLogicalChannelNumber 1
source user : NULL
}
May 8 10:06:24: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
May 8 10:06:24: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
May 8 10:06:24: H245 MSC OUTGOING PDU ::=
value MultimediaSystemControlMessage ::= response : closeLogicalChannelAck :
{
forwardLogicalChannelNumber 1
}
May 8 10:06:24: H245 MSC OUTGOING ENCODE BUFFER::= 23800000
May 8 10:06:24:
May 8 10:06:24: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 2
May 8 10:06:24: H245 MSC INCOMING ENCODE BUFFER::= 4A40
May 8 10:06:24:
May 8 10:06:24: H245 MSC INCOMING PDU ::=
value MultimediaSystemControlMessage ::= command : endSessionCommand : disconnect : NULL
May 8 10:06:24: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
May 8 10:06:24: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0sh voice call stat
May 8 10:06:24: H245 MSC OUTGOING PDU ::=
value MultimediaSystemControlMessage ::= command : endSessionCommand : disconnect : NULL
May 8 10:06:24: H245 MSC OUTGOING ENCODE BUFFER::= 4A40
May 8 10:06:24:
May 8 10:06:24: H225.0 INCOMING ENCODE BUFFER::= 2580060008914A0005110011001B30E990B71611E2BC0FB189A8A0FF5710800100
May 8 10:06:24:
May 8 10:06:24: H225.0 INCOMING PDU ::=
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body releaseComplete :
{
protocolIdentifier { 0 0 8 2250 0 5 }
callIdentifier
{
guid '1B30E990B71611E2BC0FB189A8A0FF57'H
}
}
h245Tunneling FALSE
}
}
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:1:2/vtsp_process_event:
[state:S_CONNECT, event:E_CC_DISCONNECT]
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:1:2/act_disconnect:
Cause Value=16
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:1:2/vtsp_ring_noan_timer_stop:
Timer Stop Time=282606620
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:1:2/vtsp_pcm_cap_conf_tone_timer_stop:
Timer Stop Time=282606620
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:1:2/vtsp_dsm_save_fax_config:
Fax Relay=ENABLED
Primary Fax Protocol=T38_FAX_RELAY, Fallback Fax Protocol=FAXPASSTHROUGH_FAX
Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED
Fax Parameters Set By=Dialpeer, Peer=2
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:1:2/vtsp_dsm_save_fax_config:
Fax Version:=0, LS Red=0, HS Red=0
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:1:2/vtsp_dsm_save_fax_config:
Passthrough Codec=g711ulaw
May 8 10:06:24: htsp_timer_stop3
May 8 10:06:24: H225.0 OUTGOING PDU ::=
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body releaseComplete :
{
protocolIdentifier { 0 0 8 2250 0 4 }
callIdentifier
{
guid '1B30E990B71611E2BC0FB189A8A0FF57'H
}
}
h245Tunneling FALSE
}
}
May 8 10:06:24: H225.0 OUTGOING ENCODE BUFFER::= 2580060008914A0004110011001B30E990B71611E2BC0FB189A8A0FF5710800100
May 8 10:06:24:
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:1:2/vtsp_process_event:
[state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE]
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:1:2/act_dsm_dsp_stats_complete:
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:1:2/vtsp_timer:
Timer Start Time=282606622, Timer Value=2000(ms)
May 8 10:06:24: htsp_process_event: [1/0/13, FXOLS_CONNECT, E_HTSP_RELEASE_REQ]fxols_offhook_release
May 8 10:06:24: htsp_timer_stop
May 8 10:06:24: htsp_timer_stop2
May 8 10:06:24: htsp_timer_stop3
May 8 10:06:24: [1/0/13] set signal state = 0x4 timestamp = 0
May 8 10:06:24: htsp_timer - 2000 msec
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:1:2/vtsp_process_event:
[state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF]
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:1:2/act_wrelease_release:
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:1:2/vtsp_play_busy_timer_stop:
Timer Stop Time=282606622
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:1:2/vtsp_timer_stop:
Timer Stop Time=282606622
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:1:2/vtsp_is_valid_dsm_handle:
DMGR=0x357F49DC, VTSP CDB=0x2BF42AF0
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:-1:-1/vtsp_process_event:
[state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE]
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:-1:-1/act_terminate:
May 8 10:06:24: //14593/1B30E990BC0D/VTSP:(1/0/13):-1:-1:-1/vtsp_timer_stop:
Timer Stop Time=282606623
May 8 10:06:24: //-1/1B30E990BC0D/VTSP:(1/0/13):-1:-1:-1/vtsp_free_cdb:
CDB=0x2BF42AF0
May 8 10:06:24: htsp_process_event: [1/0/13, FXOLS_GUARD_OUT, E_DSP_SIG_0110]
May 8 10:06:26: htsp_process_event: [1/0/13, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
May 8 10:06:26: htsp_process_event: [1/0/13, FXOLS_ONHOOK, E_DSP_SIG_0100]
CallID CID ccVdb Port Slot/DSP:Ch Called # Codec MLPP Dial-peers
No active calls found
Suc#sh voice call stat
May 8 10:06:45: H225.0 INCOMING ENCODE BUFFER::= 20B0060008914A000501401F0047006500720065006E00740065000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000022C0B50000120F436973636F43616C6C4D616E616765720031000104003489BB79A000660C21E54DA11826043502AC1DBB8A00D50D80000700AC1F1A2506B8110000660C21E54DA11826043502AC1DBB8A010001000100010010A00100140140B50000120D82040020040001030003000103
May 8 10:06:45:
May 8 10:06:45: H225.0 INCOMING PDU ::=
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body setup :
{
protocolIdentifier { 0 0 8 2250 0 5 }
sourceAddress
{
h323-ID : {"Gerente..."}
}
sourceInfo
{
vendor
{
vendor
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
productId '436973636F43616C6C4D616E61676572'H
versionId '31'H
}
terminal
{
}
mc FALSE
undefinedNode FALSE
}
destinationAddress
{
dialedDigits : "015688467"
}
activeMC FALSE
conferenceID '00660C21E54DA11826043502AC1DBB8A'H
conferenceGoal create : NULL
callType pointToPoint : NULL
sourceCallSignalAddress ipAddress :
{
ip 'AC1F1A25'H
port 1720
}
callIdentifier
{
guid '00660C21E54DA11826043502AC1DBB8A'H
}
mediaWaitForConnect FALSE
canOverlapSend FALSE
multipleCalls FALSE
maintainConnection FALSE
}
h245Tunneling FALSE
nonStandardControl
{
{
nonStandardIdentifier h221NonStandard :
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
data '82040020040001030003000103'H
}
}
}
}
May 8 10:06:45: H225 NONSTD INCOMING ENCODE BUFFER::= 82040020040001030003000103
May 8 10:06:45:
May 8 10:06:45: H225 NONSTD INCOMING PDU ::=
value H323_UU_NonStdInfo ::=
{
callMgrParam
{
interclusterVersion 3
enterpriseID {}
}
}
May 8 10:06:45: //-1/xxxxxxxxxxxx/VTSP:(1/0/8):-1:-1:-1/vtsp_allocate_cdb:
CDB=0x2BF42810
May 8 10:06:45: //-1/xxxxxxxxxxxx/VTSP:(1/0/8):-1:-1:-1/vtsp_insert_cdb:
May 8 10:06:45: //-1/xxxxxxxxxxxx/VTSP:(1/0/8):-1:-1:-1/vtsp_process_event:
[state:INVALID STATE MACHINE, event:E_CC_SETUP_REQ]
May 8 10:06:45: htsp_timer_stop3
May 8 10:06:45: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:-1:-1/vtsp_og_call_req:
Echo Cancel=TRUE, Gain Control=0
Auto Gain Control=FALSE, Dual Tone Detect Mask=0x0, Seq Tone Detect=FALSE
May 8 10:06:45: H225.0 OUTGOING PDU ::=
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body callProceeding :
{
protocolIdentifier { 0 0 8 2250 0 4 }
destinationInfo
{
vendor
{
vendor
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
}
gateway
{
protocol
{
voice :
{
supportedPrefixes
{
}
}
}
}
mc FALSE
undefinedNode FALSE
}
h245Address ipAddress :
{
ip 'AC191DBB'H
port 32861
}
callIdentifier
{
guid '00660C21E54DA11826043502AC1DBB8A'H
}
multipleCalls FALSE
maintainConnection FALSE
}
h245Tunneling FALSE
}
}
May 8 10:06:45: H225.0 OUTGOING ENCODE BUFFER::= 21C0060008914A00042800B500001240013C05010000AC191DBB805D110C110000660C21E54DA11826043502AC1DBB8A0100010010800100
May 8 10:06:45:
May 8 10:06:45: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_process_event:
[state:S_SETUP_REQ_PEND, event:E_VTSP_DSM_OPEN_SUCCESS]
May 8 10:06:45: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/act_setup_req_pend_succ:
May 8 10:06:45: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_request_call:
Mode=0, Peer=102htsp_setup_req
May 8 10:06:45: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_request_call:
Progress Indication=0, Info Trans Capability=0
Calling Number=9001, TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed
Called Number=015688467, TON=Unknown, NPI=Unknown
May 8 10:06:45: htsp_process_event: [1/0/8, FXOLS_ONHOOK, E_HTSP_SETUP_REQ]fxols_onhook_setup
May 8 10:06:45: [1/0/8] set signal state = 0xC timestamp = 0
May 8 10:06:45: htsp_timer - 1300 msec
May 8 10:06:45: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_process_event:
[state:S_SETUP_REQUEST, event:E_TSP_PROCEEDING]
May 8 10:06:45: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/act_setup_pend_proceeding:
CallID CID ccVdb Port Slot/DSP:Ch Called # Codec MLPP Dial-peers
0x390C 71C 0x2CA56A9C 1/0/8 0/1:1 *015688467 None 1/102
1 active call found
Suc#
May 8 10:06:45: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_timer:
Timer Start Time=282608707, Timer Value=25000(ms)
May 8 10:06:45: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/act_setup_pend_proceeding:
Peer=102, Progress Indication=0, Return Code=2
May 8 10:06:45: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_process_event:
[state:S_NULL, event:E_VTSP_DSM_OPEN_SUCCESS]
May 8 10:06:45: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/act_setup_req_wait_pend_success:
Event=76
May 8 10:06:45: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_timer_stop:
Timer Stop Time=282608707
May 8 10:06:46: htsp_process_event: [1/0/8, FXOLS_WAIT_DIAL_TONE, E_HTSP_EVENT_TIMER]fxols_wait_dial_timer htsp_dial
May 8 10:06:46: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_process_event:
[state:S_SETUP_REQ_PROC, event:E_TSP_DIAL]
May 8 10:06:46: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/act_setup_pend_dial:
Digits=15688467, Tone Mode=0
May 8 10:06:46: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/ds_do_dial:
Digits To Dial=15688467
May 8 10:06:46: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_timer_stop:
Timer Stop Time=282608852
May 8 10:06:46: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_timer_stop:
Timer Stop Time=282602
May 8 10:06:46: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_timer_stop:
Timer Stop Time=282608892
May 8 10:06:47: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_timer_stop:
Timer Stop Time=282608912
May 8 10:06:47: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_timer_stop:
Timer Stop Time=282608932
May 8 10:06:47: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_timer_stop:
Timer Stop Time=282608952
May 8 10:06:47: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_timer_stop:
Timer Stop Time=282608972
May 8 10:06:47: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_timer_stop:
Timer Stop Time=282608992
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_process_event:
[state:S_DS_DIALING, event:E_VTSP_DSM_DIALING_COMPLETE]
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/ds_dialing:
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/ds_do_dial:
Digits To Dial=
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/act_setup_pend_dial_comp:
May 8 10:06:48: htsp_process_event: [1/0/8, FXOLS_WAIT_DIAL_DONE, E_DSP_DIALING_DONE]fxols_wait_dial_done htsp_alert
May 8 10:06:48: htsp_timer - 350 msec
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_process_event:
[state:S_SETUP_REQ_PROC, event:E_TSP_ALERT]
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/act_setup_pend_alert:
Progress Indication=8, Signal Indication=1
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_ring_noan_timer_start:
Timer Start Time=282608999, No Answer Timer Value=180000(ms)
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/act_setup_pend_alert:
Ringback Indication=FALSE, Ring Timeout=180(s)
May 8 10:06:48: htsp_call_bridged invoked
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_dsm_save_fax_config:
Fax Relay=ENABLED
Primary Fax Protocol=T38_FAX_RELAY, Fallback Fax Protocol=FAXPASSTHROUGH_FAX
Fax Relay CM Suppression :=ENABLED, Fax Relay ANS Suppression :=DISABLED
Fax Parameters Set By=Dialpeer, Peer=1
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_dsm_save_fax_config:
Fax Version:=0, LS Red=0, HS Red=0
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_dsm_save_fax_config:
Passthrough Codec=g711ulaw
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_process_event:
[state:S_SETUP_REQ_PROC, event:E_CC_DO_CAPS_IND]
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_dsm_peer_event_cb:
Event=E_DSM_CC_CAPS_IND
May 8 10:06:48: H450 OUTGOING PDU ::=
value CmnArg ::=
{
featureList
{
ssCFreRoutingSupported NULL
ssCTreRoutingSupported NULL
}
}
May 8 10:06:48: H450 OUTGOING ENCODE BUFFER::= 43000000
May 8 10:06:48:
May 8 10:06:48: H450 OUTGOING PDU ::=
value H4501SupplementaryService ::=
{
networkFacilityExtension
{
sourceEntity endpoint : NULL
destinationEntity endpoint : NULL
}
interpretationApdu discardAnyUnrecognizedInvokePdu : NULL
serviceApdu rosApdus :
{
invoke :
{
invokeId 3625
opcode local : 85
argument {"43000000"}
}
}
}
May 8 10:06:48: H450 OUTGOING ENCODE BUFFER::= 600001100E290001550443000000
May 8 10:06:48:
May 8 10:06:48: H225.0 OUTGOING PDU ::=
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body alerting :
{
protocolIdentifier { 0 0 8 2250 0 4 }
destinationInfo
{
vendor
{
vendor
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
}
gateway
{
protocol
{
voice :
{
supportedPrefixes
{
}
}
}
}
mc FALSE
undefinedNode FALSE
}
h245Address ipAddress :
{
ip 'AC191DBB'H
port 32861
}
callIdentifier
{
guid '00660C21E54DA11826043502AC1DBB8A'H
}
multipleCalls FALSE
maintainConnection FALSE
}
h4501SupplementaryService
{
'600001100E290001550443000000'H
}
h245Tunneling FALSE
}
}
May 8 10:06:48: H225.0 OUTGOING ENCODE BUFFER::= 23C0060008914A00042800B500001240013C05010000AC191DBB805D1B0C00110000660C21E54DA11826043502AC1DBB8A01000100118010010E600001100E2900015504430000000100
May 8 10:06:48:
May 8 10:06:48: H225.0 OUTGOING PDU ::=
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body notify :
{
protocolIdentifier { 0 0 8 2250 0 4 }
callIdentifier
{
guid '00660C21E54DA11826043502AC1DBB8A'H
}
}
h245Tunneling FALSE
}
}
May 8 10:06:48: H225.0 OUTGOING ENCODE BUFFER::= 28501900060008914A00040000660C21E54DA11826043502AC1DBB8A10800100
May 8 10:06:48:
May 8 10:06:48: H245 MSC OUTGOING PDU ::=
value MultimediaSystemControlMessage ::= request : terminalCapabilitySet :
{
sequenceNumber 1
protocolIdentifier { 0 0 8 245 0 7 }
multiplexCapability h2250Capability :
{
maximumAudioDelayJitter 20
receiveMultipointCapability
{
multicastCapability FALSE
multiUniCastConference FALSE
mediaDistributionCapability
{
{
centralizedControl FALSE
distributedControl FALSE
centralizedAudio FALSE
distributedAudio FALSE
centralizedVideo FALSE
distributedVideo FALSE
}
}
}
transmitMultipointCapability
{
multicastCapability FALSE
multiUniCastConference FALSE
mediaDistributionCapability
{
{
centralizedControl FALSE
distributedControl FALSE
centralizedAudio FALSE
distributedAudio FALSE
centralizedVideo FALSE
distributedVideo FALSE
}
}
}
receiveAndTransmitMultipointCapability
{
multicastCapability FALSE
multiUniCastConference FALSE
mediaDistributionCapability
{
{
centralizedControl FALSE
distributedControl FALSE
centralizedAudio FALSE
distributedAudio FALSE
centralizedVideo FALSE
distributedVideo FALSE
}
}
}
mcCapability
{
centralizedConferenceMC FALSE
decentralizedConferenceMC FALSE
}
rtcpVideoControlCapability FALSE
mediaPacketizationCapability
{
h261aVideoPacketization FALSE
}
logicalChannelSwitchingCapability FALSE
t120DynamicPortCapability FALSE
}
capabilityTable
{
{
capabilityTableEntryNumber 32
capability receiveAndTransmitDataApplicationCapability :
{
application t38fax :
{
t38FaxProtocol udp : NULL
t38FaxProfile
{
fillBitRemoval FALSE
transcodingJBIG FALSE
transcodingMMR FALSE
version 0
t38FaxRateManagement transferredTCF : NULL
t38FaxUdpOptions
{
t38FaxMaxBuffer 200
t38FaxMaxDatagram 320
t38FaxUdpEC t38UDPRedundancy : NULL
}
}
}
maxBitRate 144
}
},
{
capabilityTableEntryNumber 30
capability receiveUserInputCapability : dtmf : NULL
},
{
capabilityTableEntryNumber 27
capability receiveUserInputCapability : basicString : NULL
},
{
capabilityTableEntryNumber 6
capability receiveAudioCapability : g729AnnexA : 2
},
{
capabilityTableEntryNumber 5
capability receiveAudioCapability : g729 : 2
},
{
capabilityTableEntryNumber 3
capability receiveAudioCapability : g711Ulaw64k : 20
},
{
capabilityTableEntryNumber 4
capability receiveAudioCapability : g711Alaw64k : 20
}
}
capabilityDescriptors
{
{
capabilityDescriptorNumber 1
simultaneousCapabilities
{
{
32,
5,
6,
3,
4
},
{
30,
27
}
}
}
}
}
May 8 10:06:48: H245 MSC OUTGOING ENCODE BUFFER::= 027001060008817500078013800014000100000100000100000CC0010001000680001F4A0812860100807C0100014008C00200C802014040009080001D83014080001A83011080000522C00180000422800180000220C0138000032040130080010104001F000400050002000301001D001A
May 8 10:06:48:
May 8 10:06:48: H245 MSC OUTGOING PDU ::=
value MultimediaSystemControlMessage ::= request : masterSlaveDetermination :
{
terminalType 60
statusDeterminationNumber 3411
}
May 8 10:06:48: H245 MSC OUTGOING ENCODE BUFFER::= 01003C400D53
May 8 10:06:48:
May 8 10:06:48: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 86
May 8 10:06:48: H245 MSC INCOMING ENCODE BUFFER::= 0270010600088175000A801380003C000100000100000101000CC00100010004800000860A0000070008824301030180000120C01D80000285014080000385011080002B85015000800002000001010002000300002B
May 8 10:06:48:
May 8 10:06:48: H245 MSC INCOMING PDU ::=
value MultimediaSystemControlMessage ::= request : terminalCapabilitySet :
{
sequenceNumber 1
protocolIdentifier { 0 0 8 245 0 10 }
multiplexCapability h2250Capability :
{
maximumAudioDelayJitter 60
receiveMultipointCapability
{
multicastCapability FALSE
multiUniCastConference FALSE
mediaDistributionCapability
{
{
centralizedControl FALSE
distributedControl FALSE
centralizedAudio FALSE
distributedAudio FALSE
centralizedVideo FALSE
distributedVideo FALSE
}
}
}
transmitMultipointCapability
{
multicastCapability FALSE
multiUniCastConference FALSE
mediaDistributionCapability
{
{
centralizedControl FALSE
distributedControl FALSE
centralizedAudio FALSE
distributedAudio FALSE
centralizedVideo FALSE
distributedVideo FALSE
}
}
}
receiveAndTransmitMultipointCapability
{
multicastCapability FALSE
multiUniCastConference FALSE
mediaDistributionCapability
{
{
centralizedControl FALSE
distributedControl FALSE
centralizedAudio FALSE
distributedAudio FALSE
centralizedVideo TRUE
distributedVideo FALSE
}
}
}
mcCapability
{
centralizedConferenceMC FALSE
decentralizedConferenceMC FALSE
}
rtcpVideoControlCapability FALSE
mediaPacketizationCapability
{
h261aVideoPacketization FALSE
}
logicalChannelSwitchingCapability FALSE
t120DynamicPortCapability FALSE
}
capabilityTable
{
{
capabilityTableEntryNumber 1
capability genericControlCapability :
{
capabilityIdentifier standard : { 0 0 8 323 1 3 1 }
}
},
{
capabilityTableEntryNumber 2
capability receiveAudioCapability : g711Ulaw64k : 30
},
{
capabilityTableEntryNumber 3
capability receiveAndTransmitUserInputCapability : dtmf : NULL
},
{
capabilityTableEntryNumber 4
capability receiveAndTransmitUserInputCapability : basicString : NULL
},
{
capabilityTableEntryNumber 44
capability receiveAndTransmitUserInputCapability : hookflash : NULL
}
}
capabilityDescriptors
{
{
capabilityDescriptorNumber 0
simultaneousCapabilities
{
{
2
},
{
3,
4
},
{
44
}
}
}
}
}
May 8 10:06:48: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
May 8 10:06:48: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
May 8 10:06:48: H245 MSC OUTGOING PDU ::=
value MultimediaSystemControlMessage ::= response : terminalCapabilitySetAck :
{
sequenceNumber 1
}
May 8 10:06:48: H245 MSC OUTGOING ENCODE BUFFER::= 218001
May 8 10:06:48:
May 8 10:06:48: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 3
May 8 10:06:48: H245 MSC INCOMING ENCODE BUFFER::= 218001
May 8 10:06:48:
May 8 10:06:48: H245 MSC INCOMING PDU ::=
value MultimediaSystemControlMessage ::= response : terminalCapabilitySetAck :
{
sequenceNumber 1
}
May 8 10:06:48: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
May 8 10:06:48: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
May 8 10:06:48: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 2
May 8 10:06:48: H245 MSC INCOMING ENCODE BUFFER::= 2080
May 8 10:06:48:
May 8 10:06:48: H245 MSC INCOMING PDU ::=
value MultimediaSystemControlMessage ::= response : masterSlaveDeterminationAck :
{
decision master : NULL
}
May 8 10:06:48: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
May 8 10:06:48: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
May 8 10:06:48: H245 MSC OUTGOING PDU ::=
value MultimediaSystemControlMessage ::= response : masterSlaveDeterminationAck :
{
decision slave : NULL
}
May 8 10:06:48: H245 MSC OUTGOING ENCODE BUFFER::= 20A0
May 8 10:06:48:
May 8 10:06:48: H245 MSC OUTGOING PDU ::=
value MultimediaSystemControlMessage ::= request : openLogicalChannel :
{
forwardLogicalChannelNumber 1
forwardLogicalChannelParameters
{
dataType audioData : g711Ulaw64k : 20
multiplexParameters h2250LogicalChannelParameters :
{
sessionID 1
mediaControlChannel unicastAddress : iPAddress :
{
network 'AC191DBB'H
tsapIdentifier 24055
}
silenceSuppression FALSE
}
}
}
May 8 10:06:48: H245 MSC OUTGOING ENCODE BUFFER::= 030000000C6013800B05000100AC191DBB5DF700
May 8 10:06:48:
May 8 10:06:48: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 20
May 8 10:06:48: H245 MSC INCOMING ENCODE BUFFER::= 030000000C6013800B05000100AC1F1A250FA100
May 8 10:06:48:
May 8 10:06:48: H245 MSC INCOMING PDU ::=
value MultimediaSystemControlMessage ::= request : openLogicalChannel :
{
forwardLogicalChannelNumber 1
forwardLogicalChannelParameters
{
dataType audioData : g711Ulaw64k : 20
multiplexParameters h2250LogicalChannelParameters :
{
sessionID 1
mediaControlChannel unicastAddress : iPAddress :
{
network 'AC1F1A25'H
tsapIdentifier 4001
}
silenceSuppression FALSE
}
}
}
May 8 10:06:48: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
May 8 10:06:48: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
May 8 10:06:48: H245 MSC OUTGOING PDU ::=
value MultimediaSystemControlMessage ::= response : openLogicalChannelAck :
{
forwardLogicalChannelNumber 1
forwardMultiplexAckParameters h2250LogicalChannelAckParameters :
{
mediaChannel unicastAddress : iPAddress :
{
network 'AC191DBB'H
tsapIdentifier 24054
}
mediaControlChannel unicastAddress : iPAddress :
{
network 'AC191DBB'H
tsapIdentifier 24055
}
flowControlToZero FALSE
}
}
May 8 10:06:48: H245 MSC OUTGOING ENCODE BUFFER::= 22C000000680134C00AC191DBB5DF600AC191DBB5DF703000100
May 8 10:06:48:
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_process_event:
[state:S_SETUP_REQ_PROC, event:E_CC_CAPS_IND]
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_dsm_peer_event_cb:
Event=E_DSM_CC_CAPS_ACK
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_dsm_feature_notify_cb:
Feature ID=0, Feature Status=1
May 8 10:06:48: htsp_process_event: [1/0/8, FXOLS_WAIT_CUT_THRU, E_HTSP_VOICE_CUT_THROUGH]fxols_handle_cut_thru
May 8 10:06:48: htsp_timer_stop
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_process_event:
[state:S_SETUP_REQ_PROC, event:E_TSP_CONNECT]
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/act_setup_pend_connect:
Progress Indication=2
May 8 10:06:48: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_ring_noan_timer_stop:
Timer Stop Time=282609024
May 8 10:06:48: H450 OUTGOING PDU ::=
value CmnArg ::=
{
featureList
{
ssCFreRoutingSupported NULL
ssCTreRoutingSupported NULL
}
}
May 8 10:06:48: H450 OUTGOING ENCODE BUFFER::= 43000000
May 8 10:06:48:
May 8 10:06:48: H450 OUTGOING PDU ::=
value H4501SupplementaryService ::=
{
networkFacilityExtension
{
sourceEntity endpoint : NULL
destinationEntity endpoint : NULL
}
interpretationApdu discardAnyUnrecognizedInvokePdu : NULL
serviceApdu rosApdus :
{
invoke :
{
invokeId 3626
opcode local : 85
argument {"43000000"}
}
}
}
May 8 10:06:48: H450 OUTGOING ENCODE BUFFER::= 600001100E2A0001550443000000
May 8 10:06:48:
May 8 10:06:48: H225 NONSTD OUTGOING PDU ::=
value H323_UU_NonStdInfo ::=
{
version 0
progIndParam progIndIEinfo :
{
progIndIE '00000002'H
}
}
May 8 10:06:48: H225 NONSTD OUTGOING ENCODE BUFFER::= C0010018800006000400000002
May 8 10:06:48:
May 8 10:06:48: H225.0 OUTGOING PDU ::=
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body connect :
{
protocolIdentifier { 0 0 8 2250 0 4 }
h245Address ipAddress :
{
ip 'AC191DBB'H
port 32861
}
destinationInfo
{
vendor
{
vendor
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
}
gateway
{
protocol
{
voice :
{
supportedPrefixes
{
}
}
}
}
mc FALSE
undefinedNode FALSE
}
conferenceID '00660C21E54DA11826043502AC1DBB8A'H
callIdentifier
{
guid '00660C21E54DA11826043502AC1DBB8A'H
}
multipleCalls FALSE
maintainConnection FALSE
}
h4501SupplementaryService
{
'600001100E2A0001550443000000'H
}
h245Tunneling FALSE
nonStandardControl
{
{
nonStandardIdentifier h221NonStandard :
{
t35CountryCode 181
t35Extension 0
manufacturerCode 18
}
data 'C0010018800006000400000002'H
}
}
}
}
May 8 10:06:48: H225.0 OUTGOING ENCODE BUFFER::= 22C0060008914A000400AC191DBB805D2800B500001240013C0501000000660C21E54DA11826043502AC1DBB8A1D0C00110000660C21E54DA11826043502AC1DBB8A0100010011A010010E600001100E2A00015504430000000100140140B50000120DC0010018800006000400000002
May 8 10:06:48:
May 8 10:06:48: H225.0 OUTGOING PDU ::=
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body notify :
{
protocolIdentifier { 0 0 8 2250 0 4 }
callIdentifier
{
guid '00660C21E54DA11826043502AC1DBB8A'H
}
}
h245Tunneling FALSE
}
}
May 8 10:06:48: H225.0 OUTGOING ENCODE BUFFER::= 28501900060008914A00040000660C21E54DA11826043502AC1DBB8A10800100
May 8 10:06:48:
May 8 10:06:48: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 23
May 8 10:06:48: H245 MSC INCOMING ENCODE BUFFER::= 22C000000480101C0000AC191DBB7DC200AC191DBB7DC3
May 8 10:06:48:
May 8 10:06:48: H245 MSC INCOMING PDU ::=
value MultimediaSystemControlMessage ::= response : openLogicalChannelAck :
{
forwardLogicalChannelNumber 1
forwardMultiplexAckParameters h2250LogicalChannelAckParameters :
{
sessionID 1
mediaChannel unicastAddress : iPAddress :
{
network 'AC191DBB'H
tsapIdentifier 32194
}
mediaControlChannel unicastAddress : iPAddress :
{
network 'AC191DBB'H
tsapIdentifier 32195
}
}
}
May 8 10:06:48: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
May 8 10:06:48: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
Suc#sh voice call stat
CallID CID ccVdb Port Slot/DSP:Ch Called # Codec MLPP Dial-peers
0x390C 71C 0x2CA56A9C 1/0/8 0/1:1 *015688467 g711ulaw 1/102
1 active call found
Suc#sh voice call stat
debug
H.225:
H.225 ASN1 Messages debugging is on
H.245:
H.245 ASN1 Messages debugging is on
VTSP:
debug voip vtsp event is ON (filter is OFF)
debug voip vtsp error software is ON
debug voip vtsp error call is ON (filter is OFF)
debug voip vtsp inout is ON (filter is OFF)
Voice Port Module signaling debugging is on
Suc#sh voice call stat
CallID CID ccVdb Port Slot/DSP:Ch Called # Codec MLPP Dial-peers
0x390C 71C 0x2CA56A9C 1/0/8 0/1:1 *015688467 g711ulaw 1/102
1 active call found
Suc#sh voice call stat
May 8 10:10:40: H225.0 INCOMING ENCODE BUFFER::= 2480060008914A00050B00110000660C21E54DA11826043502AC1DBB8A10800100
May 8 10:10:40:
May 8 10:10:40: H225.0 INCOMING PDU ::=
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body information :
{
protocolIdentifier { 0 0 8 2250 0 5 }
callIdentifier
{
guid '00660C21E54DA11826043502AC1DBB8A'H
}
}
h245Tunneling FALSE
}
}
May 8 10:10:40: H225.0 INCOMING ENCODE BUFFER::= 2480060008914A00050B00110000660C21E54DA11826043502AC1DBB8A10800100
May 8 10:10:40:
May 8 10:10:40: H225.0 INCOMING PDU ::=
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body information :
{
protocolIdentifier { 0 0 8 2250 0 5 }
callIdentifier
{
guid '00660C21E54DA11826043502AC1DBB8A'H
}
}
h245Tunneling FALSE
}
}
May 8 10:10:40: H225.0 INCOMING ENCODE BUFFER::= 28501900060008914A00050000660C21E54DA11826043502AC1DBB8A10800100
May 8 10:10:40:
May 8 10:10:40: H225.0 INCOMING PDU ::=
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body notify :
{
protocolIdentifier { 0 0 8 2250 0 5 }
callIdentifier
{
guid '00660C21E54DA11826043502AC1DBB8A'H
}
}
h245Tunneling FALSE
}
}
May 8 10:10:40: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_process_event:
[state:S_CONNECT, event:E_CC_SERVICE_MSG]
May 8 10:10:40: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/act_service_msg_down:
May 8 10:10:40: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_timer_stop:
Timer Stop Time=282632230htsp_call_service_msg
CallID CID ccVdb Port Slot/DSP:Ch Called # Codec MLPP Dial-peers
0x390C 71C 0x2CA56A9C 1/0/8 0/1:1 *015688467 g711ulaw 1/102
1 active call found
Suc#htsp_call_service_msg not EFXS (2)
Suc#
May 8 10:11:01: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 5
May 8 10:11:01: H245 MSC INCOMING ENCODE BUFFER::= 0400000000
May 8 10:11:01:
May 8 10:11:01: H245 MSC INCOMING PDU ::=
value MultimediaSystemControlMessage ::= request : closeLogicalChannel :
{
forwardLogicalChannelNumber 1
source user : NULL
}
May 8 10:11:01: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
May 8 10:11:01: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
May 8 10:11:01: H245 MSC OUTGOING PDU ::=
value MultimediaSystemControlMessage ::= response : closeLogicalChannelAck :
{
forwardLogicalChannelNumber 1
}
May 8 10:11:01: H245 MSC OUTGOING ENCODE BUFFER::= 23800000
May 8 10:11:01:
May 8 10:11:01: h245_decode_one_pdu: more_pdus = 0, bytesLeftToDecode = 2
May 8 10:11:01: H245 MSC INCOMING ENCODE BUFFER::= 4A40
May 8 10:11:01:
May 8 10:11:01: H245 MSC INCOMING PDU ::=
value MultimediaSystemControlMessage ::= command : endSessionCommand : disconnect : NULL
May 8 10:11:01: h245_decode_one_pdu: H245ASNDecodePdu rc = 0, bytesLeftToDecode = 0
May 8 10:11:01: h245_decode_one_pdu: Read Pkt body: more_pdus:0 rc:0 asn_rc:0
May 8 10:11:01: H245 MSC OUTGOING PDU ::=
value MultimediaSystemControlMessage ::= command : endSessionCommand : disconnect : NULL
May 8 10:11:01: H245 MSC OUTGOING ENCODE BUFFER::= 4A40
May 8 10:11:01:
May 8 10:11:01: H225.0 INCOMING ENCODE BUFFER::= 2580060008914A00051100110000660C21E54DA11826043502AC1DBB8A10800100
May 8 10:11:01:
May 8 10:11:01: H225.0 INCOMING PDU ::=
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body releaseComplete :
{
protocolIdentifier { 0 0 8 2250 0 5 }
callIdentifier
{
guid '00660C21E54DA11826043502AC1DBB8A'H
}
}
h245Tunneling FALSE
}
}
May 8 10:11:01: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_process_event:
[state:S_CONNECT, event:E_CC_DISCONNECT]
May 8 10:11:01: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/act_disconnect:
Cause Value=16
May 8 10:11:01: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_ring_noan_timer_stop:
Timer Stop Time=282634340
May 8 10:11:01: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_pcm_cap_conf_tone_timer_stop:
Timer Stop Time=282634340
May 8 10:11:01: htsp_timer_stop3
May 8 10:11:01: H225.0 OUTGOING PDU ::=
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body releaseComplete :
{
protocolIdentifier { 0 0 8 2250 0 4 }
callIdentifier
{
guid '00660C21E54DA11826043502AC1DBB8A'H
}
}
h245Tunneling FALSE
}
}
May 8 10:11:01: H225.0 OUTGOING ENCODE BUFFER::= 2580060008914A00041100110000660C21E54DA11826043502AC1DBB8A10800100
May 8 10:11:01:
May 8 10:11:01: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_process_event:
[state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE]
May 8 10:11:01: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/act_dsm_dsp_stats_complete:
May 8 10:11:01: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_timer:
Timer Start Time=282634342, Timer Value=2000(ms)
May 8 10:11:01: htsp_process_event: [1/0/8, FXOLS_OFFHOOK, E_HTSP_RELEASE_REQ]fxols_offhook_release
May 8 10:11:01: htsp_timer_stop
May 8 10:11:01: htsp_timer_stop2
May 8 10:11:01: htsp_timer_stop3
May 8 10:11:01: [1/0/8] set signal state = 0x4 timestamp = 0
May 8 10:11:01: htsp_timer - 2000 msec
May 8 10:11:01: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_process_event:
[state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF]
May 8 10:11:01: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/act_wrelease_release:
May 8 10:11:01: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_play_busy_timer_stop:
Timer Stop Time=282634342
May 8 10:11:01: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_timer_stop:
Timer Stop Time=282634342
May 8 10:11:01: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:1:1/vtsp_is_valid_dsm_handle:
DMGR=0x357F49DC, VTSP CDB=0x2BF42810
May 8 10:11:01: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:-1:-1/vtsp_process_event:
[state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE]
May 8 10:11:01: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:-1:-1/act_terminate:
May 8 10:11:01: //14604/xxxxxxxxxxxx/VTSP:(1/0/8):-1:-1:-1/vtsp_timer_stop:
Timer Stop Time=282634342
May 8 10:11:01: //-1/xxxxxxxxxxxx/VTSP:(1/0/8):-1:-1:-1/vtsp_free_cdb:
CDB=0x2BF42810
May 8 10:11:03: htsp_process_event: [1/0/8, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
May 8 10:11:03: htsp_process_event: [1/0/8, FXOLS_ONHOOK, E_DSP_SIG_0100]
Suc#sh voice call stat
CallID CID ccVdb Port Slot/DSP:Ch Called # Codec MLPP Dial-peers
No active calls found
Suc#und all
All possible debugging has been turned off
Suc#term no mon
Suc#sh debug
05-09-2013 06:43 AM
Good afternoon,
Can you post the port configuration? And country to check parms.
Kind regards
05-09-2013 09:44 AM
Hello Daniel , the country is Argentina , and here is the conf of the voice port
voice-port 1/0/8
trunk-group PSTN
supervisory disconnect dualtone mid-call
supervisory custom-cptone country-ar
supervisory dualtone-detect-params 1
cptone AR
timeouts interdigit 4
timeouts call-disconnect 2
impedance complex2
05-13-2013 02:56 PM
Hi Hugo,
I went over the debugs which you had provided, I can see that the CLC and the Release complete came from the CUCM:
May 8 10:11:01: H245 MSC INCOMING PDU ::=
value MultimediaSystemControlMessage ::= request : closeLogicalChannel :
{
forwardLogicalChannelNumber 1
source user : NULL
}
May 8 10:11:01: H225.0 INCOMING PDU ::=
value H323_UserInformation ::=
{
h323-uu-pdu
{
h323-message-body releaseComplete :
{
protocolIdentifier { 0 0 8 2250 0 5 }
callIdentifier
{
guid '00660C21E54DA11826043502AC1DBB8A'H
}
}
h245Tunneling FALSE
}
}
Any chance you can send across a CCM trace for one such call. And you can attach the file to the post.
Regards,
Jagpreet
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide