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
08-14-2018 08:56 PM
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
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?
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?
08-16-2018 05:05 AM
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