[cisco-voip] Problems using IP10S H323 Phone with CallManager 4.2(3)
Ratko Dodevski
rade239 at gmail.com
Mon May 12 09:05:58 EDT 2008
Hi, I realy need some help
After migrating from CallManager 4.1 to CallManager 4.2(3), I have some
issues. I have 200 IP10S phones that when dialing out of the IP network
(PSTN, GSM), after two ringbacks, a have disconected and bisy tone, although
other phone just ranged twice and has missed call.
This is what I get from the switch
VG-C2801#
*May 12 08:44:59.742: //-1/80E6A7140B00/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=Phone 1 New
----- ccCallInfo IE subfields -----
cisco-ani=3737
cisco-anitype=0
cisco-aniplan=1
cisco-anipi=0
cisco-anisi=1
dest=9071232330
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
*May 12 08:44:59.742: //-1/80E6A7140B00/CCAPI/cc_api_call_setup_ind_common:
Interface=0x65C3B170, Call Info(
Calling Number=3737,(Calling Name=)(TON=Unknown, NPI=ISDN,
Screening=User, Passed, Presentation=Allowed),
Called Number=9071232330(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown,
FinalDestinationFlag=TRUE,
Incoming Dial-peer=1, Progress Indication=NULL(0), Calling IE
Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID
Transparent=FALSE), Call Id=125056
*May 12 08:44:59.742: //-1/80E6A7140B00/CCAPI/ccCheckClipClir:
In: Calling Number=3737(TON=Unknown, NPI=ISDN, Screening=User, Passed,
Presentation=Allowed)
*May 12 08:44:59.742: //-1/80E6A7140B00/CCAPI/ccCheckClipClir:
Out: Calling Number=3737(TON=Unknown, NPI=ISDN, Screening=User, Passed,
Presentation=Allowed)
*May 12 08:44:59.742: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*May 12 08:44:59.742: :cc_get_feature_vsa malloc success
*May 12 08:44:59.742: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*May 12 08:44:59.742: cc_get_feature_vsa count is 3
*May 12 08:44:59.742: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*May 12 08:44:59.742: :FEATURE_VSA attributes are:
feature_name:0,fearture_time:1705356816,feature_id:125056
*May 12 08:44:59.746:
//125056/80E6A7140B00/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=3737(TON=Unknown, NPI=ISDN, Screening=User,
Passed, Presentation=Allowed),
Called Number=9071232330(TON=Unknown, NPI=Unknown))
*May 12 08:44:59.746: //125056/80E6A7140B00/CCAPI/cc_process_call_setup_ind:
Event=0x65F07588
*May 12 08:44:59.746: //125056/80E6A7140B00/CCAPI/ccCallSetContext:
Context=0x64A9CCD0
*May 12 08:44:59.746: //125056/80E6A7140B00/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 125056 with tag 1 to app
"_ManagedAppProcess_Default"
*May 12 08:44:59.746: //125056/80E6A7140B00/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
*May 12 08:44:59.750: //125056/80E6A7140B00/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=71, Params=0x64AA5FE8, Progress Indication=NULL(0)
*May 12 08:44:59.750: //125056/80E6A7140B00/CCAPI/ccCheckClipClir:
In: Calling Number=3737(TON=Unknown, NPI=ISDN, Screening=User, Passed,
Presentation=Allowed)
*May 12 08:44:59.750: //125056/80E6A7140B00/CCAPI/ccCheckClipClir:
Out: Calling Number=3737(TON=Unknown, NPI=ISDN, Screening=User, Passed,
Presentation=Allowed)
*May 12 08:44:59.750: //125056/80E6A7140B00/CCAPI/ccCallSetupRequest:
Destination Pattern=9071......, Called Number=9071232330, Digit
Strip=TRUE
*May 12 08:44:59.750: //125056/80E6A7140B00/CCAPI/ccCallSetupRequest:
Calling Number=3737(TON=Unknown, NPI=ISDN, Screening=User, Passed,
Presentation=Allowed),
Called Number=9071232330(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=
Account Number=Phone 1 New, Final Destination Flag=TRUE,
Guid=80E6A714-053E-E11D-0B00-0F010A0B3233, Outgoing Dial-peer=71
*May 12 08:44:59.750:
//125056/80E6A7140B00/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=Phone 1 New
----- ccCallInfo IE subfields -----
cisco-ani=3737
cisco-anitype=0
cisco-aniplan=1
cisco-anipi=0
cisco-anisi=1
dest=9071232330
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
*May 12 08:44:59.750:
//125056/80E6A7140B00/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x65A79994, Interface Type=6, Destination=, Mode=0x0,
Call Params(Calling Number=3737,(Calling Name=)(TON=Unknown, NPI=ISDN,
Screening=User, Passed, Presentation=Allowed),
Called Number=9071232330(TON=Unknown, NPI=Unknown), Calling
Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing
Dial-peer=71, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0,
Application Call Id=)
*May 12 08:44:59.750: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*May 12 08:44:59.750: :cc_get_feature_vsa malloc success
*May 12 08:44:59.750: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*May 12 08:44:59.750: cc_get_feature_vsa count is 4
*May 12 08:44:59.750: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:
*May 12 08:44:59.750: :FEATURE_VSA attributes are:
feature_name:0,fearture_time:1705357464,feature_id:125057
*May 12 08:44:59.754:
//125057/80E6A7140B00/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1
*May 12 08:44:59.754: //125057/80E6A7140B00/CCAPI/ccCallSetContext:
Context=0x64AA5F98
*May 12 08:44:59.754: //125056/80E6A7140B00/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=71
*May 12 08:45:00.222: //125057/80E6A7140B00/CCAPI/cc_api_call_proceeding:
Interface=0x65A79994, Progress Indication=NULL(0)
*May 12 08:45:00.262: //125057/80E6A7140B00/CCAPI/cc_api_call_alert:
Interface=0x65A79994, Progress Indication=INBAND(8), Signal
Indication=SIGNAL RINGBACK(1)
*May 12 08:45:00.262: //125057/80E6A7140B00/CCAPI/cc_api_call_alert:
Call Entry(Retry Count=0, Responsed=TRUE)
*May 12 08:45:00.262: //125056/80E6A7140B00/CCAPI/ccCallAlert:
Progress Indication=INBAND(8), Signal Indication=SIGNAL RINGBACK(1)
*May 12 08:45:00.262: //125056/80E6A7140B00/CCAPI/ccCallAlert:
Call Entry(Responsed=TRUE, AlertSent=TRUE)
*May 12 08:45:00.262:
//125057/80E6A7140B00/CCAPI/cc_api_get_called_ccm_detected:
CallInfo(ccm detected=0)
*May 12 08:45:00.262: //125056/80E6A7140B00/CCAPI/ccCallNotify:
Data Bitmask=0x7, Call Id=125056
*May 12 08:45:00.262:
//125057/80E6A7140B00/CCAPI/cc_api_get_called_ccm_detected:
CallInfo(ccm detected=0)
*May 12 08:45:00.262: //125056/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:
(confID=0x6637A1EC, callID1=0x1E880, callID2=0x1E881, tag=0x0)
*May 12 08:45:00.266: //125056/80E6A7140B00/CCAPI/ccConferenceCreate:
Conference Id=0x6637A1EC, Call Id1=125056, Call Id2=125057, Tag=0x0
*May 12 08:45:00.266: //125056/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:
*May 12 08:45:00.266: cc_api_get_xcode_stream : 4368
*May 12 08:45:00.266: //125056/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
Conference Id=0x119FA, Source Interface=0x65C3B170, Source Call
Id=125056,
Destination Call Id=125057, Disposition=0x0, Tag=0x0
*May 12 08:45:00.266: //125057/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:
Conference Id=0x119FA, Source Interface=0x65A79994, Source Call
Id=125057,
Destination Call Id=125056, Disposition=0x0, Tag=0xFFFFFFFF
*May 12 08:45:00.266: //125056/80E6A7140B00/CCAPI/cc_generic_bridge_done:
Conference Id=0x119FA, Source Interface=0x65A79994, Source Call
Id=125057,
Destination Call Id=125056, Disposition=0x0, Tag=0xFFFFFFFF
*May 12 08:45:00.266: //125056/80E6A7140B00/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x119FA, Destination Call Id=125057)
*May 12 08:45:00.266: //125057/80E6A7140B00/CCAPI/ccConferenceCreate:
Call Entry(Conference Id=0x119FA, Destination Call Id=125056)
*May 12 08:45:00.266: //125057/80E6A7140B00/CCAPI/cc_api_caps_ind:
Destination Interface=0x65C3B170, Destination Call Id=125056, Source Call
Id=125057,
Caps(Codec=0x1, Fax Rate=0x1, Vad=0x1,
Modem=0x2, Codec Bytes=20, Signal Type=3)
*May 12 08:45:00.266: //125057/80E6A7140B00/CCAPI/cc_api_caps_ind:
Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),
Playout Max=250(ms), Fax Nom=300(ms))
*May 12 08:45:00.266: //125056/80E6A7140B00/CCAPI/cc_api_get_delay_xport:
CallInfo(delay xport=FALSE)
*May 12 08:45:00.270:
//125056/80E6A7140B00/CCAPI/cc_process_notify_bridge_done:
Conference Id=0x119FA, Call Id1=125056, Call Id2=125057
VG-C2801#
VG-C2801#
VG-C2801#
VG-C2801#
VG-C2801#
*May 12 08:45:10.342: //125056/80E6A7140B00/CCAPI/cc_api_call_disconnected:
Cause Value=47, Interface=0x65C3B170, Call Id=125056
*May 12 08:45:10.342: //125056/80E6A7140B00/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=47, Retry Count=0)
*May 12 08:45:10.346: //125056/80E6A7140B00/CCAPI/ccConferenceDestroy:
Conference Id=0x119FA, Tag=0x0
*May 12 08:45:10.346: //125056/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x119FA, Source Interface=0x65C3B170, Source Call
Id=125056,
Destination Call Id=125057, Disposition=0x0, Tag=0x0
*May 12 08:45:10.346: //125057/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:
Conference Id=0x119FA, Source Interface=0x65A79994, Source Call
Id=125057,
Destination Call Id=125056, Disposition=0x0, Tag=0x0
*May 12 08:45:10.346: //125056/80E6A7140B00/CCAPI/cc_generic_bridge_done:
Conference Id=0x119FA, Source Interface=0x65A79994, Source Call
Id=125057,
Destination Call Id=125056, Disposition=0x0, Tag=0x0
*May 12 08:45:10.350: //125057/80E6A7140B00/CCAPI/ccCallDisconnect:
Cause Value=47, Tag=0x0, Call Entry(Previous Disconnect Cause=0,
Disconnect Cause=0)
*May 12 08:45:10.350: //125057/80E6A7140B00/CCAPI/ccCallDisconnect:
Cause Value=47, Call Entry(Responsed=TRUE, Cause Value=47)
*May 12 08:45:10.350: //125057/80E6A7140B00/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
*May 12 08:45:10.354: //125056/80E6A7140B00/CCAPI/ccCallDisconnect:
Cause Value=47, Tag=0x0, Call Entry(Previous Disconnect Cause=0,
Disconnect Cause=47)
*May 12 08:45:10.354: //125056/80E6A7140B00/CCAPI/ccCallDisconnect:
Cause Value=47, Call Entry(Responsed=TRUE, Cause Value=47)
*May 12 08:45:10.358: //125056/80E6A7140B00/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
*May 12 08:45:10.358:
//125056/80E6A7140B00/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x65C3B170, Tag=0x0, Call Id=125056,
Call Entry(Disconnect Cause=47, Voice Class Cause Code=0, Retry Count=0)
*May 12 08:45:10.358:
//125056/80E6A7140B00/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*May 12 08:45:10.358: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
*May 12 08:45:10.358: :cc_free_feature_vsa freeing 65A5AE08
*May 12 08:45:10.358: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
*May 12 08:45:10.358: vsacount in free is 3
*May 12 08:45:12.586:
//125057/80E6A7140B00/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x65A79994, Tag=0x0, Call Id=125057,
Call Entry(Disconnect Cause=47, Voice Class Cause Code=0, Retry Count=0)
*May 12 08:45:12.586:
//125057/80E6A7140B00/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
*May 12 08:45:12.586: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
*May 12 08:45:12.586: :cc_free_feature_vsa freeing 65A5B090
*May 12 08:45:12.586: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:
*May 12 08:45:12.586: vsacount in free is 2
and this is the SDI trace
05/12/2008 12:55:55.671 10.11.50.5
10.11.50.51 Trace Cisco CallManagerH225D::Incoming H225Setup with
ConnPid=(1,100,139,71), crv=26255 05/12/2008 12:55:55.671 10.11.50.5
10.11.50.51 Trace Cisco CallManagerH225Cdpc::getDefCcSetupInd Overlapflag 1
sendingComplete 0 05/12/2008 12:55:55.687 10.11.50.5
10.11.50.51 Trace Cisco CallManagerDeviceManager::star_DmPidReq -
RequestName={33F6A898-8F99-4C1C-999D-7DBA824B8FB9}
LookupName={33F6A898-8F99-4C1C-999D-7DBA824B8FB9} 05/12/2008
12:55:55.687 10.11.50.5
10.11.50.51 Trace Cisco CallManagerDeviceManager::findDeviceInfoGivenKey -
Key={33F6A898-8F99-4C1C-999D-7DBA824B8FB9} Not In List 05/12/2008
12:55:55.687 10.11.50.5
10.11.50.51 Trace Cisco CallManagerDeviceManager::findDeviceInfoGivenKey -
Name=10.11.11.6 Key={33F6A898-8F99-4C1C-999D-7DBA824B8FB9}
Pid=(1,43,3) 05/12/2008
12:55:55.687 10.11.50.5
10.11.50.51 Trace Cisco CallManagerDigit analysis: wait_DmPidRes -
Name=[{33F6A898-8F99-4C1C-999D-7DBA824B8FB9}] cmDeviceType=[PriGfpDevice]
Pid=H225D(1,100,43,3) 05/12/2008 12:55:55.687 10.11.50.5
Cisco CallManagervalue H323-UU-NonStdInfo ::= 05/12/2008 12:55:55.687
10.11.50.5
Cisco CallManager{* callMgrParam * {* interclusterVersion 1,* enterpriseID
''H* } 05/12/2008 12:55:55.687 10.11.50.5
Cisco CallManager} 05/12/2008 12:55:55.687 10.11.50.5
Cisco CallManagervalue H323-UserInformation ::= 05/12/2008
12:55:55.687 10.11.50.5
Cisco CallManager{* h323-uu-pdu * {* h323-message-body callProceeding : * {*
protocolIdentifier { 0 0 8 2250 0 2 },* destinationInfo * {* terminal * {*
},* mc FALSE,* undefinedNode FALSE* },* callIdentifier * {* guid
'000CAAE78806001F1C22FFCC152A1BB9'H* }* },* h245Tunneling FALSE,*
nonStandardControl * {* {* nonStandardIdentifier h221NonStandard : * {*
t35CountryCode 181,* t35Extension 0,* manufacturerCode 18* },* data
'80C4000400010100'H* }* }* } 05/12/2008 12:55:55.687 10.11.50.5
Cisco CallManager} 05/12/2008 12:55:55.703 10.11.50.5
Trace Cisco CallManagerH225Cdpc::requestConnect_TcpStartSessionRes(51,
16777280): TcpStartSessionRes from H225Handler=0 05/12/2008
12:55:55.703 10.11.50.5
10.11.50.51 Trace Cisco CallManagerH225Cdpc::handleCcSetupReq(51, 16777280):
H225Setup sent to IP=10.11.11.6 05/12/2008 12:55:55.703 10.11.50.5
Trace Cisco CallManagerH225D::restart0_TcpConnectionInfo:
H225Cdpc(1,100,42,51), ConnPid(1,100,139,72), crv=16, mCiCrCrpEntries=1,
mCrtoCiEntries=1 05/12/2008 12:55:55.703 10.11.50.5
Cisco CallManagervalue H323-UU-NonStdInfo ::= 05/12/2008 12:55:55.703
10.11.50.5
Cisco CallManager{* callMgrParam * {* interclusterVersion 1,* enterpriseID
''H* } 05/12/2008 12:55:55.703 10.11.50.5
Cisco CallManager} 05/12/2008 12:55:55.703 10.11.50.5
Cisco CallManagervalue H323-UserInformation ::= 05/12/2008
12:55:55.703 10.11.50.5
Cisco CallManagerSPROCRas - {* h323-uu-pdu * {* h323-message-body setup : *
{* protocolIdentifier { 0 0 8 2250 0 2 },* sourceAddress * {* h323-ID :
"Phone 1 New"* },* sourceInfo * {* terminal * {* },* mc FALSE,*
undefinedNode FALSE* },* destinationAddress * {* e164 : "9071296685"* },*
activeMC FALSE,* conferenceID '00F570FD143EE11D100014010A0B3233'H,*
conferenceGoal create : NULL,* callType pointToPoint : NULL,*
sourceCallSignalAddress ipAddress : * {* ip '0A0B3205'H,* port 1720* },*
callIdentifier * {* guid '00F570FD143EE11D100014010A0B3233'H* },*
mediaWaitForConnect FALSE,* canOverlapSend FALSE* },* h245Tunneling FALSE,*
nonStandardControl * {* {* nonStandardIdentifier h221NonStandard : * {*
t35CountryCode 181,* 05/12/2008 12:55:55.703 10.11.50.5
Cisco CallManagert35Extension 0,* manufacturerCode 18* },* data
'80C4000400010100'H* }* }* } 05/12/2008 12:55:55.703 10.11.50.5
Cisco CallManager} 05/12/2008 12:55:55.718 10.11.50.5
Cisco CallManagervalue H323-UserInformation ::= 05/12/2008
12:55:55.718 10.11.50.5
Cisco CallManager{* 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
'0A0B0B06'H,* port 54423* },* callIdentifier * {* guid
'00F570FD143EE11D100014010A0B3233'H* }* },* h245Tunneling FALSE* } 05/12/2008
12:55:55.718 10.11.50.5
Cisco CallManager} 05/12/2008 12:55:55.718 10.11.50.5
Cisco CallManagervalue H323-UserInformation ::= 05/12/2008
12:55:55.718 10.11.50.5
Cisco CallManager{* h323-uu-pdu * {* h323-message-body releaseComplete : *
{* protocolIdentifier { 0 0 8 2250 0 4 },* callIdentifier * {* guid
'00F570FD143EE11D100014010A0B3233'H* }* },* h245Tunneling FALSE,*
nonStandardControl * {* {* nonStandardIdentifier h221NonStandard : * {*
t35CountryCode 181,* t35Extension 0,* manufacturerCode 18* },* data
'60010100011C231C219E81000367746400000016524C432C0D0A50524E2C6973646E2A
...'H* }* },* tunnelledSignallingMessage * {* tunnelledProtocolID * {* id
tunnelledProtocolAlternateID : * {* protocolType "gtd"* }* },*
messageContent * {* '524C432C0D0A50524E2C6973646E2A2C2C2C0D0A0D0A'H* },*
tunnellingRequired NULL* }* } 05/12/2008 12:55:55.718 10.11.50.5
Cisco CallManager} 05/12/2008 12:55:55.718 10.11.50.5
Cisco CallManagervalue H323-UU-NonStdInfo ::= 05/12/2008 12:55:55.718
10.11.50.5
Cisco CallManager{* version 1,* protoParam qsigNonStdInfo : * {* iei 28,*
rawMesg
'1C219E81000367746400000016524C432C0D0A50524E2C6973646E2A2C2C2C0D0A0D0A'H* }
05/12/2008 12:55:55.718 10.11.50.5
Cisco CallManager} 05/12/2008 12:55:55.734 10.11.50.5
Cisco CallManagervalue H323-UserInformation ::= 05/12/2008
12:55:55.734 10.11.50.5
Cisco CallManager{* h323-uu-pdu * {* h323-message-body releaseComplete : *
{* protocolIdentifier { 0 0 8 2250 0 2 },* callIdentifier * {* guid
'000CAAE78806001F1C22FFCC152A1BB9'H* }* },* h245Tunneling FALSE* } 05/12/2008
12:55:55.734 10.11.50.5
Cisco CallManager}
please can anybody help me, i've tried everything..... Thanks
-------------- next part --------------
An HTML attachment was scrubbed...
URL: https://puck.nether.net/pipermail/cisco-voip/attachments/20080512/53d5c96b/attachment-0001.html
More information about the cisco-voip
mailing list