Buy or Renew
Buy or Renew
Cisco + Splunk: It’s a new day for your data. Learn more.
 
cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 
cancel
Start a conversation
4692
Views
0
Helpful
5
Replies

DIsconnect cause code 17, VOIP call to 3rd party Call manager

fandhikap
Level 1
Level 1

‎08-14-2018 07:50 PM - edited ‎03-17-2019 01:21 PM

Hi,

 

i have problem with call incoming and outgoing to CUCM. when i'm trying to call 3rd party call manager with extension 200 and my extension is 1201, then fast busy appeared.

 

 

here is the dial-peer config:
dial-peer voice 200 voip
description ** to CUCM **
destination-pattern ....
session target ipv4:172.27.199.10
voice-class codec 1
dtmf-relay cisco-rtp h245-signal h245-alphanumeric
no vad
!
dial-peer voice 202 voip
incoming called-number .
voice-class codec 1
dtmf-relay cisco-rtp h245-signal h245-alphanumeric
no vad
!
dial-peer voice 205 voip
destination-pattern [1,2]..
session target ipv4:172.16.10.10
voice-class codec 1
dtmf-relay cisco-rtp h245-signal h245-alphanumeric
no vad
!

 

here is the debug voice ccapi inout:

010796: Aug 15 10:46:57: //-1/00AC76781A00/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=Agent Hinet CC 1
----- ccCallInfo IE subfields -----
cisco-ani=1201
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=200
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=-1
cisco-rdnplan=-1
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

010797: Aug 15 10:46:57: //-1/00AC76781A00/CCAPI/cc_api_call_setup_ind_common:
Interface=0x3EB9438C, Call Info(
Calling Number=1201,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=200(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,
Incoming Dial-peer=202, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=20006
010798: Aug 15 10:46:57: //-1/00AC76781A00/CCAPI/ccCheckClipClir:
In: Calling Number=1201(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
010799: Aug 15 10:46:57: //-1/00AC76781A00/CCAPI/ccCheckClipClir:
Out: Calling Number=1201(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
010800: Aug 15 10:46:57: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

010801: Aug 15 10:46:57: :cc_get_feature_vsa malloc success
010802: Aug 15 10:46:57: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

010803: Aug 15 10:46:57: cc_get_feature_vsa count is 1
010804: Aug 15 10:46:57: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

010805: Aug 15 10:46:57: :FEATURE_VSA attributes are: feature_name:0,feature_time:1065700232,feature_id:214
010806: Aug 15 10:46:57: //20006/00AC76781A00/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=1201(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=200(TON=Unknown, NPI=Unknown))
010807: Aug 15 10:46:57: //20006/00AC76781A00/CCAPI/cc_process_call_setup_ind:
Event=0x3EDF3EF0
010808: Aug 15 10:46:57: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 200
010809: Aug 15 10:46:57: //20006/00AC76781A00/CCAPI/ccCallSetContext:
Context=0x400FE254
010810: Aug 15 10:46:57: //20006/00AC76781A00/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 20006 with tag 202 to app "_ManagedAppProcess_Default"
010811: Aug 15 10:46:57: //20006/00AC76781A00/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
010812: Aug 15 10:46:57: //20006/00AC76781A00/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=205, Params=0x401023B4, Progress Indication=NULL(0)
010813: Aug 15 10:46:57: //20006/00AC76781A00/CCAPI/ccCheckClipClir:
In: Calling Number=1201(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
010814: Aug 15 10:46:57: //20006/00AC76781A00/CCAPI/ccCheckClipClir:
Out: Calling Number=1201(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
010815: Aug 15 10:46:57: //20006/00AC76781A00/CCAPI/ccCallSetupRequest:
Destination Pattern=[1,2].., Called Number=200, Digit Strip=FALSE
010816: Aug 15 10:46:57: //20006/00AC76781A00/CCAPI/ccCallSetupRequest:
Calling Number=1201(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=200(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=
Account Number=Agent Hinet CC 1, Final Destination Flag=TRUE,
Guid=00AC7678-2194-31B7-1A00-3401C0A806CD, Outgoing Dial-peer=205
010817: Aug 15 10:46:57: //20006/00AC76781A00/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=Agent Hinet CC 1
----- ccCallInfo IE subfields -----
cisco-ani=1201
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=200
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=-1
cisco-rdnplan=-1
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

010818: Aug 15 10:46:57: //20006/00AC76781A00/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x3EB9438C, Interface Type=1, Destination=, Mode=0x0,
Call Params(Calling Number=1201,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=200(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=205, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
010819: Aug 15 10:46:57: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

010820: Aug 15 10:46:57: :cc_get_feature_vsa malloc success
010821: Aug 15 10:46:57: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

010822: Aug 15 10:46:57: cc_get_feature_vsa count is 2
010823: Aug 15 10:46:57: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

010824: Aug 15 10:46:57: :FEATURE_VSA attributes are: feature_name:0,feature_time:1065700008,feature_id:215
010825: Aug 15 10:46:57: //20007/00AC76781A00/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=1, FlowMode=1
010826: Aug 15 10:46:57: //20007/00AC76781A00/CCAPI/ccCallSetContext:
Context=0x40102364
010827: Aug 15 10:46:57: //20006/00AC76781A00/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=205
010828: Aug 15 10:46:57: //20007/00AC76781A00/CCAPI/ccGetMediaClassTag:
media class tag 0
010829: Aug 15 10:46:57: //20007/00AC76781A00/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
010830: Aug 15 10:46:57: //20006/00AC76781A00/CCAPI/ccGetMediaClassTag:
media class tag 0
010831: Aug 15 10:46:57: //20006/00AC76781A00/CCAPI/ccSetMediaclassIp2ipTags:
media class tags set: NR 0, ASP 0
010832: Aug 15 10:46:57: //20007/00AC76781A00/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
010833: Aug 15 10:46:57: //20006/00AC76781A00/CCAPI/ccGet_xc_nr_asp_info:
media class tags: NR 0, ASP 0
010834: Aug 15 10:46:57: //20007/00AC76781A00/CCAPI/cc_api_event_indication:
Event=94, Call Id=20007
010835: Aug 15 10:46:57: //20007/00AC76781A00/CCAPI/cc_api_event_indication:
Event Is Sent To Conferenced SPI(s) Directly
010836: Aug 15 10:46:58: //20007/00AC76781A00/CCAPI/cc_api_call_disconnected:
Cause Value=17, Interface=0x3EB9438C, Call Id=20007
010837: Aug 15 10:46:58: //20007/00AC76781A00/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=17, Retry Count=0)
010838: Aug 15 10:46:58: //20006/00AC76781A00/CCAPI/ccCallReleaseResources:
release reserved xcoding resource.
010839: Aug 15 10:46:58: //20007/00AC76781A00/CCAPI/ccCallSetAAA_Accounting:
Accounting=0, Call Id=20007
010840: Aug 15 10:46:58: //20007/00AC76781A00/CCAPI/ccCallDisconnect:
Cause Value=17, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=17)
010841: Aug 15 10:46:58: //20007/00AC76781A00/CCAPI/ccCallDisconnect:
Cause Value=17, Call Entry(Responsed=TRUE, Cause Value=17)
010842: Aug 15 10:46:58: //20007/00AC76781A00/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
010843: Aug 15 10:46:58: //20007/00AC76781A00/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x3EB9438C, Tag=0x0, Call Id=20007,
Call Entry(Disconnect Cause=17, Voice Class Cause Code=0, Retry Count=0)
010844: Aug 15 10:46:58: //20007/00AC76781A00/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
010845: Aug 15 10:46:58: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

010846: Aug 15 10:46:58: :cc_free_feature_vsa freeing 3F854AA0
010847: Aug 15 10:46:58: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

010848: Aug 15 10:46:58: vsacount in free is 1
010849: Aug 15 10:46:58: //20006/00AC76781A00/CCAPI/ccCallDisconnect:
Cause Value=17, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
010850: Aug 15 10:46:58: //20006/00AC76781A00/CCAPI/ccCallDisconnect:
Cause Value=17, Call Entry(Responsed=TRUE, Cause Value=17)
010851: Aug 15 10:46:58: //20006/00AC76781A00/CCAPI/cc_api_get_transfer_info:
Transfer Number=NULL
010852: Aug 15 10:46:58: //20006/00AC76781A00/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x3EB9438C, Tag=0x0, Call Id=20006,
Call Entry(Disconnect Cause=17, Voice Class Cause Code=0, Retry Count=0)
010853: Aug 15 10:46:58: //20006/00AC76781A00/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
010854: Aug 15 10:46:58: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

010855: Aug 15 10:46:58: :cc_free_feature_vsa freeing 3F854B80
010856: Aug 15 10:46:58: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

010857: Aug 15 10:46:58: vsacount in free is 0

Labels:
0 Helpful
5 Replies 5

Sreekanth Narayanan
Cisco Employee
Cisco Employee

‎08-14-2018 08:56 PM

1. Please remove cisco-rtp as a dtmf-type from the dial-peers. It's not recommended to use that any more.
2. Please also enable these debugs so you can see what's happening on the h323 protocol side of the call. The incoming and outgoing dial-peers are being matched for this call.
debug h225 asn1
debug h245 asn1
debug cch323 h225
debug cch323 h245
0 Helpful

fandhikap
Level 1
Level 1
In response to Sreekanth Narayanan

‎08-14-2018 09:09 PM

Hi Sreekanth,

thanks for your reply.

 

 

here is the config:

dial-peer voice 200 voip
 description ** to CUCM **
 destination-pattern ....
 session target ipv4:172.27.199.10
 voice-class codec 1
 no vad
!
dial-peer voice 202 voip
 incoming called-number .
 voice-class codec 1
 no vad
!
dial-peer voice 205 voip
 description ** to Makasar **
 destination-pattern [1,2]..
 session target ipv4:172.16.10.10
 voice-class codec 1
 no vad
!

 

here is the debug:

BE6000S#
010860: Aug 15 12:06:27: H225.0 INCOMING ENCODE BUFFER::= 20B0060008914A000501401F004100670065006E0074002000480069006E0065007400200043004300200031000000000000000000000000000000000000000000000000000000000000000022C0B50000120F436973636F43616C6C4D616E61676572003100010100533080520293C2A631B71B003501C0A806CD00D50D80000700AC1BC70A06B8110080520293C2A631B71B003501C0A806CD010001000100010010A00100140140B50000120D82040020040001030003000101
010861: Aug 15 12:06:27:
010862: Aug 15 12:06:27: H225.0 INCOMING PDU ::=

value H323_UserInformation ::=
    {
      h323-uu-pdu
      {
        h323-message-body setup :
        {
          protocolIdentifier { 0 0 8 2250 0 5 }
          sourceAddress
          {
            h323-ID : {"Agent Hinet CC 1..."}
          }
          sourceInfo
          {
            vendor
            {
              vendor
              {
                t35CountryCode 181
                t35Extension 0
                manufacturerCode 18
              }
              productId '436973636F43616C6C4D616E61676572'H
              versionId '31'H
            }
            terminal
            {
            }
            mc FALSE
            undefinedNode FALSE
          }
          destinationAddress
          {
            dialedDigits : "200"
          }
          activeMC FALSE
          conferenceID '80520293C2A631B71B003501C0A806CD'H
          conferenceGoal create : NULL
          callType pointToPoint : NULL
          sourceCallSignalAddress ipAddress :
          {
            ip 'AC1BC70A'H
            port 1720
          }
          callIdentifier
          {
            guid '80520293C2A631B71B003501C0A806CD'H
          }
          mediaWaitForConnect FALSE
          canOverlapSend FALSE
          multipleCalls FALSE
          maintainConnection FALSE
        }
        h245Tunneling FALSE
        nonStandardControl
        {

          {
            nonStandardIdentifier h221NonStandard :
            {
              t35CountryCode 181
              t35Extension 0
              manufacturerCode 18
            }
            data '82040020040001030003000101'H
          }
        }
      }
    }



010863: Aug 15 12:06:27: H225 NONSTD INCOMING ENCODE BUFFER::= 82040020040001030003000101
010864: Aug 15 12:06:27:
010865: Aug 15 12:06:27: H225 NONSTD INCOMING PDU ::=

value H323_UU_NonStdInfo ::=
    {
      callMgrParam
      {
        interclusterVersion 3
        enterpriseID {}
      }
    }



010866: Aug 15 12:06:27: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type SETUPIND_CHOSEN
010867: Aug 15 12:06:27: //20086/805202931B00/H323/setup_ind: callingNumber[1201] calledNumber[200]
010868: Aug 15 12:06:27: //20086/805202931B00/H323/setup_ind: ---- calling IE present
010869: Aug 15 12:06:27: //20086/805202931B00/H323/setup_ind: ====== PI = 0
010870: Aug 15 12:06:27: //20086/805202931B00/H323/setup_ind: Receive: infoXCap 0
010871: Aug 15 12:06:27: //20086/805202931B00/H323/setup_ind: Receive: infoXCap ccb 0cch323_ip_best_local_address: IP 172.27.199.1 found for bound IDB BVI1
010872: Aug 15 12:06:27: //20086/805202931B00/H323/setup_ind:
setup_ind: is_overlap = 0, info_complete = 0

010873: Aug 15 12:06:27: //20086/805202931B00/H323/setup_ind: Call Manager detected
010874: Aug 15 12:06:27: //20086/805202931B00/H323/cch323_h225_receiver: SETUPIND_CHOSEN: src address = 172.27.199.1; dest address = 172.27.199.10
010875: Aug 15 12:06:27: //20086/805202931B00/H323/run_h225_sm: Received event H225_EV_SETUP_IND while at state H225_IDLE
010876: Aug 15 12:06:27: //20086/805202931B00/H323/common_idle_setupInd_hdlr: full match is found
010877: Aug 15 12:06:27: //20086/805202931B00/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_SETUP state
010878: Aug 15 12:06:27: //20086/805202931B00/H323/cch323_create_incoming_callinfo_block: peer 3D72E5C4, voice_peer_tag 202, ccb: 22C0BF88
010879: Aug 15 12:06:27: //20086/805202931B00/H323/cch323_create_incoming_callinfo_block: Calling Party is CCM
010880: Aug 15 12:06:27: //20086/805202931B00/H323/cch323_h225_handle_deferred_ind: UnBuffering deferred indicationscch323_ip_best_local_address: IP 172.27.199.1 found for bound IDB BVI1
010881: Aug 15 12:06:27: //20087/805202931B00/H323/cch323_call_setup_normal:  SETUP_NTF_TIMER is not running. Continue processing. cch323_ip_best_local_address: IP 172.27.199.1 found for bound IDB BVI1
010882: Aug 15 12:06:27: //20087/805202931B00/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_IDLE state
010883: Aug 15 12:06:27: //20086/805202931B00/H323/run_h225_sm: Received event H225_EV_CALLPROC while at state H225_SETUP
010884: Aug 15 12:06:27: //20086/805202931B00/H323/cch323_h225_set_new_state: Changing from H225_SETUP state to H225_CALLPROC state
010885: Aug 15 12:06:27: //20086/805202931B00/H323/generic_send_callproc: ====== PI = 0
010886: Aug 15 12:06:27: 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
          }
          callIdentifier
          {
            guid '80520293C2A631B71B003501C0A806CD'H
          }
          multipleCalls FALSE
          maintainConnection FALSE
        }
        h245Tunneling FALSE
      }
    }



010887: Aug 15 12:06:27: H225.0 OUTGOING ENCODE BUFFER::= 2180060008914A00042800B500001240013C050100044300110080520293C2A631B71B003501C0A806CD0100010010800100
010888: Aug 15 12:06:27:
010889: Aug 15 12:06:28: //20087/805202931B00/H323/run_h225_sm: Received event H225_EV_SETUP while at state H225_IDLE
010890: Aug 15 12:06:28: //20087/805202931B00/H323/check_qos_and_send_setup: Setup ccb 0x224BCFAC
010891: Aug 15 12:06:28: //20087/805202931B00/H323/generic_send_setup:
generic_send_setup: is_overlap = 0, info_complete = 0
010892: Aug 15 12:06:28: //20087/805202931B00/H323/generic_send_setup: sending calling IE
010893: Aug 15 12:06:28: //20087/805202931B00/H323/generic_send_setup: ====== PI = 0
010894: Aug 15 12:06:28: //20087/805202931B00/H323/generic_send_setup: Send infoXCap=128, infoXRate=16, rateMult=0, xMode=128, info_layer1_prot=163
010895: Aug 15 12:06:28: //20087/805202931B00/H323/generic_send_setup: src address = 172.27.199.1; dest address = 172.16.10.10
010896: Aug 15 12:06:28: H225 NONSTD OUTGOING PDU ::=

value H323_UU_NonStdInfo ::=
    {
      callMgrParam
      {
        interclusterVersion 3
        enterpriseID {}
      }
      rsvpParam rsvpInfo :
      {
        qosIE
        {
          audio-rport 0
          video-rport 0
          audio-lport 0
          video-lport 0
          media-ip-addr 0
          remote-qos-video-bw 0
          remote-qos-audio-bw 0
          remote-qos-video 0
        }
      }
    }



010897: Aug 15 12:06:28: H225 NONSTD OUTGOING ENCODE BUFFER::= 81850004000103001127F8000000000000000000000000000000
010898: Aug 15 12:06:28:
010899: Aug 15 12:06:28: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::=
    {
      h323-uu-pdu
      {
        h323-message-body setup :
        {
          protocolIdentifier { 0 0 8 2250 0 4 }
          sourceInfo
          {
            vendor
            {
              vendor
              {
                t35CountryCode 181
                t35Extension 0
                manufacturerCode 18
              }
            }
            gateway
            {
              protocol
              {
                voice :
                {
                  supportedPrefixes
                  {
                  }
                }
              }
            }
            mc FALSE
            undefinedNode FALSE
          }
          activeMC FALSE
          conferenceID '80520293C2A631B71B003501C0A806CD'H
          conferenceGoal create : NULL
          callType pointToPoint : NULL
          sourceCallSignalAddress ipAddress :
          {
            ip 'AC1BC701'H
            port 11657
          }
          callIdentifier
          {
            guid '80520293C2A631B71B003501C0A806CD'H
          }
          mediaWaitForConnect FALSE
          canOverlapSend FALSE
          multipleCalls TRUE
          maintainConnection TRUE
        }
        h245Tunneling TRUE
        nonStandardControl
        {

          {
            nonStandardIdentifier h221NonStandard :
            {
              t35CountryCode 181
              t35Extension 0
              manufacturerCode 18
            }
            data '81850004000103001127F8000000000000000000...'H
          }
        }
      }
    }



010900: Aug 15 12:06:28: H225.0 OUTGOING ENCODE BUFFER::= 2080060008914A00042800B500001240013C0501000080520293C2A631B71B003501C0A806CD00CD0D80000700AC1BC7012D89110080520293C2A631B71B003501C0A806CD010001000180018010A00180210140B50000121A81850004000103001127F8000000000000000000000000000000
010901: Aug 15 12:06:28:
010902: Aug 15 12:06:28: //20087/805202931B00/H323/run_h225_sm: Received event H225_EV_CONN_LOST while at state H225_IDLE
010903: Aug 15 12:06:28: //20087/805202931B00/H323/run_h225_sm: Received event H225_EV_RELEASE while at state H225_IDLE
010904: Aug 15 12:06:28: //20087/805202931B00/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_IDLE state
010905: Aug 15 12:06:28: //20087/805202931B00/H323/run_h245_iwf_sm: received IWF_EV_H245_DISCONN while at state IWF_IDLE
010906: Aug 15 12:06:28: //20087/805202931B00/H323/defaultHdlr: DEFAULT: Received IWF_EV_H245_DISCONN in state IWF_IDLE
010907: Aug 15 12:06:28: //20086/805202931B00/H323/run_h225_sm: Received event H225_EV_RELEASE while at state H225_CALLPROC
010908: Aug 15 12:06:28: //20086/805202931B00/H323/cch323_h225_send_release: Cause = 38; Location = 0
010909: Aug 15 12:06:28: //20086/805202931B00/H323/cch323_h225_send_release: h225TerminateRequest: src address = -1407465727; dest address = 172.27.199.10
010910: Aug 15 12:06:28: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::=
    {
      h323-uu-pdu
      {
        h323-message-body releaseComplete :
        {
          protocolIdentifier { 0 0 8 2250 0 4 }
          callIdentifier
          {
            guid '80520293C2A631B71B003501C0A806CD'H
          }
        }
        h245Tunneling FALSE
      }
    }



010911: Aug 15 12:06:28: H225.0 OUTGOING ENCODE BUFFER::= 2580060008914A00041100110080520293C2A631B71B003501C0A806CD10800100
010912: Aug 15 12:06:28:
010913: Aug 15 12:06:28: //20086/805202931B00/H323/cch323_h225_set_new_state: Changing from H225_CALLPROC state to H225_IDLE state
010914: Aug 15 12:06:28: //20086/805202931B00/H323/run_h245_iwf_sm: received IWF_EV_H245_DISCONN while at state IWF_IDLE
010915: Aug 15 12:06:28: //20086/805202931B00/H323/defaultHdlr: DEFAULT: Received IWF_EV_H245_DISCONN in state IWF_IDLE
010916: Aug 15 12:06:51: H225.0 INCOMING ENCODE BUFFER::= 20B0060008914A000501401F004100670065006E0074002000480069006E0065007400200043004300200031000000000000000000000000000000000000000000000000000000000000000022C0B50000120F436973636F43616C6C4D616E6167657200310001010053300005E9A1DBA631B71C003601C0A806CD00D50D80000700AC1BC70A06B811000005E9A1DBA631B71C003601C0A806CD010001000100010010A00100140140B50000120D82040020040001030003000101
010917: Aug 15 12:06:51:
010918: Aug 15 12:06:51: H225.0 INCOMING PDU ::=

value H323_UserInformation ::=
    {
      h323-uu-pdu
      {
        h323-message-body setup :
        {
          protocolIdentifier { 0 0 8 2250 0 5 }
          sourceAddress
          {
            h323-ID : {"Agent Hinet CC 1..."}
          }
          sourceInfo
          {
            vendor
            {
              vendor
              {
                t35CountryCode 181
                t35Extension 0
                manufacturerCode 18
              }
              productId '436973636F43616C6C4D616E61676572'H
              versionId '31'H
            }
            terminal
            {
            }
            mc FALSE
            undefinedNode FALSE
          }
          destinationAddress
          {
            dialedDigits : "200"
          }
          activeMC FALSE
          conferenceID '0005E9A1DBA631B71C003601C0A806CD'H
          conferenceGoal create : NULL
          callType pointToPoint : NULL
          sourceCallSignalAddress ipAddress :
          {
            ip 'AC1BC70A'H
            port 1720
          }
          callIdentifier
          {
            guid '0005E9A1DBA631B71C003601C0A806CD'H
          }
          mediaWaitForConnect FALSE
          canOverlapSend FALSE
          multipleCalls FALSE
          maintainConnection FALSE
        }
        h245Tunneling FALSE
        nonStandardControl
        {

          {
            nonStandardIdentifier h221NonStandard :
            {
              t35CountryCode 181
              t35Extension 0
              manufacturerCode 18
            }
            data '82040020040001030003000101'H
          }
        }
      }
    }



010919: Aug 15 12:06:51: H225 NONSTD INCOMING ENCODE BUFFER::= 82040020040001030003000101
010920: Aug 15 12:06:51:
010921: Aug 15 12:06:51: H225 NONSTD INCOMING PDU ::=

value H323_UU_NonStdInfo ::=
    {
      callMgrParam
      {
        interclusterVersion 3
        enterpriseID {}
      }
    }



010922: Aug 15 12:06:51: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type SETUPIND_CHOSEN
010923: Aug 15 12:06:51: //20089/0005E9A11C00/H323/setup_ind: callingNumber[1201] calledNumber[200]
010924: Aug 15 12:06:51: //20089/0005E9A11C00/H323/setup_ind: ---- calling IE present
010925: Aug 15 12:06:51: //20089/0005E9A11C00/H323/setup_ind: ====== PI = 0
010926: Aug 15 12:06:51: //20089/0005E9A11C00/H323/setup_ind: Receive: infoXCap 0
010927: Aug 15 12:06:51: //20089/0005E9A11C00/H323/setup_ind: Receive: infoXCap ccb 0cch323_ip_best_local_address: IP 172.27.199.1 found for bound IDB BVI1
010928: Aug 15 12:06:51: //20089/0005E9A11C00/H323/setup_ind:
setup_ind: is_overlap = 0, info_complete = 0

010929: Aug 15 12:06:51: //20089/0005E9A11C00/H323/setup_ind: Call Manager detected
010930: Aug 15 12:06:51: //20089/0005E9A11C00/H323/cch323_h225_receiver: SETUPIND_CHOSEN: src address = 172.27.199.1; dest address = 172.27.199.10
010931: Aug 15 12:06:51: //20089/0005E9A11C00/H323/run_h225_sm: Received event H225_EV_SETUP_IND while at state H225_IDLE
010932: Aug 15 12:06:51: //20089/0005E9A11C00/H323/common_idle_setupInd_hdlr: full match is found
010933: Aug 15 12:06:51: //20089/0005E9A11C00/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_SETUP state
010934: Aug 15 12:06:51: //20089/0005E9A11C00/H323/cch323_create_incoming_callinfo_block: peer 3D72E5C4, voice_peer_tag 202, ccb: 22C0BF88
010935: Aug 15 12:06:51: //20089/0005E9A11C00/H323/cch323_create_incoming_callinfo_block: Calling Party is CCM
010936: Aug 15 12:06:51: //20089/0005E9A11C00/H323/cch323_h225_handle_deferred_ind: UnBuffering deferred indicationscch323_ip_best_local_address: IP 172.27.199.1 found for bound IDB BVI1
010937: Aug 15 12:06:51: //20090/0005E9A11C00/H323/cch323_call_setup_normal:  SETUP_NTF_TIMER is not running. Continue processing. cch323_ip_best_local_address: IP 172.27.199.1 found for bound IDB BVI1
010938: Aug 15 12:06:51: //20090/0005E9A11C00/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_IDLE state
010939: Aug 15 12:06:51: //20089/0005E9A11C00/H323/run_h225_sm: Received event H225_EV_CALLPROC while at state H225_SETUP
010940: Aug 15 12:06:51: //20089/0005E9A11C00/H323/cch323_h225_set_new_state: Changing from H225_SETUP state to H225_CALLPROC state
010941: Aug 15 12:06:51: //20089/0005E9A11C00/H323/generic_send_callproc: ====== PI = 0
010942: Aug 15 12:06:51: 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
          }
          callIdentifier
          {
            guid '0005E9A1DBA631B71C003601C0A806CD'H
          }
          multipleCalls FALSE
          maintainConnection FALSE
        }
        h245Tunneling FALSE
      }
    }



010943: Aug 15 12:06:51: H225.0 OUTGOING ENCODE BUFFER::= 2180060008914A00042800B500001240013C05010004430011000005E9A1DBA631B71C003601C0A806CD0100010010800100
010944: Aug 15 12:06:51:
010945: Aug 15 12:06:54: //20090/0005E9A11C00/H323/run_h225_sm: Received event H225_EV_SETUP while at state H225_IDLE
010946: Aug 15 12:06:54: //20090/0005E9A11C00/H323/check_qos_and_send_setup: Setup ccb 0x224BCFAC
010947: Aug 15 12:06:54: //20090/0005E9A11C00/H323/generic_send_setup:
generic_send_setup: is_overlap = 0, info_complete = 0
010948: Aug 15 12:06:54: //20090/0005E9A11C00/H323/generic_send_setup: sending calling IE
010949: Aug 15 12:06:54: //20090/0005E9A11C00/H323/generic_send_setup: ====== PI = 0
010950: Aug 15 12:06:54: //20090/0005E9A11C00/H323/generic_send_setup: Send infoXCap=128, infoXRate=16, rateMult=0, xMode=128, info_layer1_prot=163
010951: Aug 15 12:06:54: //20090/0005E9A11C00/H323/generic_send_setup: src address = 172.27.199.1; dest address = 172.16.10.10
010952: Aug 15 12:06:54: H225 NONSTD OUTGOING PDU ::=

value H323_UU_NonStdInfo ::=
    {
      callMgrParam
      {
        interclusterVersion 3
        enterpriseID {}
      }
      rsvpParam rsvpInfo :
      {
        qosIE
        {
          audio-rport 0
          video-rport 0
          audio-lport 0
          video-lport 0
          media-ip-addr 0
          remote-qos-video-bw 0
          remote-qos-audio-bw 0
          remote-qos-video 0
        }
      }
    }



010953: Aug 15 12:06:54: H225 NONSTD OUTGOING ENCODE BUFFER::= 81850004000103001127F8000000000000000000000000000000
010954: Aug 15 12:06:54:
010955: Aug 15 12:06:54: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::=
    {
      h323-uu-pdu
      {
        h323-message-body setup :
        {
          protocolIdentifier { 0 0 8 2250 0 4 }
          sourceInfo
          {
            vendor
            {
              vendor
              {
                t35CountryCode 181
                t35Extension 0
                manufacturerCode 18
              }
            }
            gateway
            {
              protocol
              {
                voice :
                {
                  supportedPrefixes
                  {
                  }
                }
              }
            }
            mc FALSE
            undefinedNode FALSE
          }
          activeMC FALSE
          conferenceID '0005E9A1DBA631B71C003601C0A806CD'H
          conferenceGoal create : NULL
          callType pointToPoint : NULL
          sourceCallSignalAddress ipAddress :
          {
            ip 'AC1BC701'H
            port 60876
          }
          callIdentifier
          {
            guid '0005E9A1DBA631B71C003601C0A806CD'H
          }
          mediaWaitForConnect FALSE
          canOverlapSend FALSE
          multipleCalls TRUE
          maintainConnection TRUE
        }
        h245Tunneling TRUE
        nonStandardControl
        {

          {
            nonStandardIdentifier h221NonStandard :
            {
              t35CountryCode 181
              t35Extension 0
              manufacturerCode 18
            }
            data '81850004000103001127F8000000000000000000...'H
          }
        }
      }
    }



010956: Aug 15 12:06:54: H225.0 OUTGOING ENCODE BUFFER::= 2080060008914A00042800B500001240013C050100000005E9A1DBA631B71C003601C0A806CD00CD0D80000700AC1BC701EDCC11000005E9A1DBA631B71C003601C0A806CD010001000180018010A00180210140B50000121A81850004000103001127F8000000000000000000000000000000
010957: Aug 15 12:06:54:
010958: Aug 15 12:06:54: //20090/0005E9A11C00/H323/run_h225_sm: Received event H225_EV_CONN_LOST while at state H225_IDLE
010959: Aug 15 12:06:54: //20090/0005E9A11C00/H323/run_h225_sm: Received event H225_EV_RELEASE while at state H225_IDLE
010960: Aug 15 12:06:54: //20090/0005E9A11C00/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_IDLE state
010961: Aug 15 12:06:54: //20090/0005E9A11C00/H323/run_h245_iwf_sm: received IWF_EV_H245_DISCONN while at state IWF_IDLE
010962: Aug 15 12:06:54: //20090/0005E9A11C00/H323/defaultHdlr: DEFAULT: Received IWF_EV_H245_DISCONN in state IWF_IDLE
010963: Aug 15 12:06:54: //20089/0005E9A11C00/H323/run_h225_sm: Received event H225_EV_RELEASE while at state H225_CALLPROC
010964: Aug 15 12:06:54: //20089/0005E9A11C00/H323/cch323_h225_send_release: Cause = 38; Location = 0
010965: Aug 15 12:06:54: //20089/0005E9A11C00/H323/cch323_h225_send_release: h225TerminateRequest: src address = -1407465727; dest address = 172.27.199.10
010966: Aug 15 12:06:54: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::=
    {
      h323-uu-pdu
      {
        h323-message-body releaseComplete :
        {
          protocolIdentifier { 0 0 8 2250 0 4 }
          callIdentifier
          {
            guid '0005E9A1DBA631B71C003601C0A806CD'H
          }
        }
        h245Tunneling FALSE
      }
    }



010967: Aug 15 12:06:54: H225.0 OUTGOING ENCODE BUFFER::= 2580060008914A0004110011000005E9A1DBA631B71C003601C0A806CD10800100
010968: Aug 15 12:06:54:
010969: Aug 15 12:06:54: //20089/0005E9A11C00/H323/cch323_h225_set_new_state: Changing from H225_CALLPROC state to H225_IDLE state
010970: Aug 15 12:06:54: //20089/0005E9A11C00/H323/run_h245_iwf_sm: received IWF_EV_H245_DISCONN while at state IWF_IDLE
010971: Aug 15 12:06:54: //20089/0005E9A11C00/H323/defaultHdlr: DEFAULT: Received IWF_EV_H245_DISCONN in state IWF_IDLE
BE6000S#
BE6000S#u all

0 Helpful

kukamara
Cisco Employee
Cisco Employee
In response to fandhikap

‎08-15-2018 01:31 PM

Hi from your CCAPI Log I get the following information.

cisco-ani=1201 [Calling Number]

dest=200 [Called Number]

=

Incoming Dial-peer=202

Outgoing Dial-peer=205

=

Calling side CI 20006 

Called side CI 20007

=

Call Entry(Disconnect Cause=17

 

Cause No. 17 - user busy
This cause is used to indicate that the called party is unable to accept another call because the user busy condition has been encountered. This cause value may be generated by the called user or by the network. In the case of user determined user busy it is noted that the user equipment is compatible with the call.

 

After looking at your H.323 in detail debug logs, I see a H225 setup message to your CUCM2 [172.16.10.10] and we get a Call proceeding from there, but after this the actual TCP connection is lost.

 

20086 – CI to CUCM [172.27.199.1]

20087 – CI to CUCM [172.16.10.10]

H323 Guid - 80520293C2A631B71B003501C0A806CD

 

010874: Aug 15 12:06:27: //20086/805202931B00/H323/cch323_h225_receiver: SETUPIND_CHOSEN: src address = 172.27.199.1; dest address = 172.27.199.10

 

010875: Aug 15 12:06:27: //20086/805202931B00/H323/run_h225_sm: Received event H225_EV_SETUP_IND while at state H225_IDLE

 

010883: Aug 15 12:06:27: //20086/805202931B00/H323/run_h225_sm: Received event H225_EV_CALLPROC while at state H225_SETUP

 

010884: Aug 15 12:06:27: //20086/805202931B00/H323/cch323_h225_set_new_state: Changing from H225_SETUP state to H225_CALLPROC state

 

010902: Aug 15 12:06:28: //20087/805202931B00/H323/run_h225_sm: Received event H225_EV_CONN_LOST while at state H225_IDLE

010903: Aug 15 12:06:28: //20087/805202931B00/H323/run_h225_sm: Received event H225_EV_RELEASE while at state H225_IDLE

 

010905: Aug 15 12:06:28: //20087/805202931B00/H323/run_h245_iwf_sm: received IWF_EV_H245_DISCONN while at state IWF_IDLE

010906: Aug 15 12:06:28: //20087/805202931B00/H323/defaultHdlr: DEFAULT: Received IWF_EV_H245_DISCONN in state IWF_IDLE

 

Can you please double check if we have proper network connection between your end to end call path?

CUCM1 <> H.323 GW <> CUCM2

 

Also is this issue permanent and still valid?

0 Helpful

fandhikap
Level 1
Level 1
In response to kukamara

‎08-16-2018 01:32 AM

Hi Kukamara,

 

 test connection between end to end successfully.

this problem still valid.

 

my network topology like this.

CUCM1 <> H.323 GW <> (PPTP VPN) <> CUCM2

 

problem if i'm using PPTP VPN between CUCM?

0 Helpful

Sreekanth Narayanan
Cisco Employee
Cisco Employee
In response to fandhikap

‎08-16-2018 05:05 AM

You can use any kind of network between the 2 CUCMs. However, you need to ensure that h225 and h245 traffic is allowed through this route and no packets are dropped. It looks like some device in between is disconnecting the TCP connection.
0 Helpful
Customers Also Viewed These Support Documents