cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
2271
Views
0
Helpful
5
Replies

Calls disconnected after 2 minutes

hugomdbaez
Level 1
Level 1

     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

5 Replies 5

Jagpreet Singh
Cisco Employee
Cisco Employee

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

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

Good afternoon,

Can you post the port configuration? And country to check parms.

Kind regards

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

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

Getting Started

Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community: