Re: Outbound SIP call from Cisco EPABX connected IP phone disconnect

ciscolalit · ‎12-13-2011

Hi Team,

I am new to this community but I am stuck in a strange situation.The calls from the Motorola TIG ( Telephone Interconnect Gateway ) to the Cisco EPABX

connected IP phone is absolutely ok and going as per the call duration restrictions of 8 min.And hence disconnects after the set duration of 8 min.

But while calling from the IP phone to the Motorola TIG the call duration is only 30 sec and disconnects after 30 sec flat every time.Motorola TIG is connected to the RF basestation to which the Motorola MTP800 radio are connected in the wide trunking mode.Since there is no limitation in the

Motorola MTS ( Base Station ) regarding the private incoming call ( EPABX to Motorola Handset ) hence we are in a jeoprady.

We have tried our level best to verify from our end but of little help.The call logs are mentioned below for the reference..Also the connection diagram is attached in the file.Also the wireshark logs are uploaded on the URL mentioned below

http://www.mediafire.com/file/kiynkstt3ad3oa8/hmel.rar

|*^*^*

16:08:26.763 ||PretransformCallingPartyNumber=2205

|CallingPartyNumber=2205 ( IP Phone )

|DialingPartition=PT-INTERNAL-CALL-HMEL-BTI

|DialingPattern=7[1-7]XXX#

|FullyQualifiedCalledPartyNumber=71702# ( 1702 is the Motorola handphone number )

|DialingPatternRegularExpression=(7[1-7][0-9][0-9][0-9])(#)

|DialingWhere=

|PatternType=Enterprise

|PotentialMatches=NoPotentialMatchesExist

|DialingSdlProcessId=(0,0,0)

|PretransformDigitString=71702#

|PretransformTagsList=SUBSCRIBER:END-OF-DIALING

|PretransformPositionalMatchList=71702:#

|CollectedDigits=71702#

|UnconsumedDigits=

|TagsList=SUBSCRIBER:END-OF-DIALING

|PositionalMatchList=71702:#

|VoiceMailbox=

|VoiceMailCallingSearchSpace=PT-INTERNAL-CALL-HMEL-BTI:PT-ISD-CALL-HMEL-BTI:PT-STD-CALL-HMEL-BTI:PT-LOCAL-CALL-HMEL-BTI:PT-VM-PILOTNO:PT-INTERNAL-CALL-HMEL-BTI_6921 2nd line:PT-CALLPARK-HMEL-BTI:PT-MEETME-HMEL-BTI:PT-STD-CALL-HMEL-BTI-FAC

|VoiceMailPilotNumber=80002000

|RouteBlockFlag=RouteThisPattern

|RouteBlockCause=0

|AlertingName=

|UnicodeDisplayName=

|DisplayNameLocale=1

|OverlapSendingFlagEnabled=0

|WithTags=

|WithValues=

|CallingPartyNumberPi=NotSelected

|ConnectedPartyNumberPi=NotSelected

|CallingPartyNamePi=NotSelected

|ConnectedPartyNamePi=NotSelected

|CallManagerDeviceType=NoDeviceType

|PatternPrecedenceLevel=Routine

|CallableEndPointName=[1c1f32f5-9c66-70be-531e-ea41848b6283]

|PatternNodeId=[0e568bfc-4b03-7c3d-dc3e-7faa83ee59b8]

|AARNeighborhood=[]

|AARDestinationMask=[]

|AARKeepCallHistory=true

|AARVoiceMailEnabled=false

|NetworkLocation=OnNet

|Calling Party Number Type=Cisco Unified CallManager

|Calling Party Numbering Plan=Cisco Unified CallManager

|Called Party Number Type=Cisco Unified CallManager

|Called Party Numbering Plan=Cisco Unified CallManager

|ProvideOutsideDialtone=true

|AllowDeviceOverride=false

|AlternateMatches= Information Not Available

|TranslationPatternDetails= Information Not Available

|ResourcePriorityNamespace=

|PatternRouteClass=RouteClassDefault|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.763 |SMDMSharedData::findAliasRegInfo - AliasName = 1c1f32f5-9c66-70be-531e-ea41848b6283 not in AliasInfo hashmap|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.763 |DeviceManager::star_DmPidReq - RequestedName=1c1f32f5-9c66-70be-531e-ea41848b6283 LookupName=1c1f32f5-9c66-70be-531e-ea41848b6283|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.763 |SMDMSharedData::findLocalDevice - Name=TetraSystem Key=1c1f32f5-9c66-70be-531e-ea41848b6283 isActvie=1 Pid=(2,65,27) found|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.763 |Digit analysis: wait_DmPidRes- Partition=[56105ab3-a690-ad9b-3280-fa654a6a17b5] Pattern=[7[1-7]XXX#] Where=[],cmDeviceType=[Unknown], OutsideDialtone =[1], DeviceOverride=[0], PID=SIPD(2,100,65,27)|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.763 |processCCMFeatureData: operationIeIdd=0|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.763 |findUnfiredInterceptOnPattern numOfPatterns = 0|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.763 |LineControl - restert0_CcProceedReq updated precedence of CI=48569697 to 5|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.764 |LineCdpc(239968): -dispatchToAllDevices-, sigName=CcProceedReq, device=SEPC471FE910815|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.764 |LPSession -await_associate_PolicyAssociateReq, aCi[48569697], bCi[48569698]|*^*^*

16:08:26.764 |Cdcc::sendCcSetupReq: precLvl=5|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.764 |CcSetupReq Vipr : cgpnCepn[4528081c-9609-4083-1b85-42ba97b222e9]|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.764 |ViprUtils:isViprAllowed Device =SEPC471FE910815 UseIMEForOutboundCall=true|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.764 |SIPD:checkPstnCcSetupReqForViprReroute - viprCgpnE164[2205], viprCdpnE164[71702#], viprE164TransformationPkid[]|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.764 |ViprUtils::findViprRoute - Striping the # e164CalledNumber = 71702|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.764 |ViprUtils::findViprRoute - ViprValidatedDidTable entry not found for e164CalledNumber = 71702|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.764 |SIPD:checkPstnCcSetupReqForViprReroute - viprCgpnE164=[2205], viprCdpnE164=[71702#], vcrUploadNeeded=[t]|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.764 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/updatePassingLocation: SIP trunk is not in phantom location.|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.764 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/restart0_CcSetupReq: videopreferred not case - before reserveBW|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.764 |Locations_reserveBandwidth -- cdccPID=(2.194.195374) Orig=1=Dest=1 no need to reserve bw.|*^*^*

16:08:26.764 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/updatePassingLocation: SIP trunk is not in phantom location.|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.764 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 48569698 mapKey is 48569698|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.764 |//SIP/SIPD(2,65,27)/ccbId=125610/scbId=0/restart0_CcSetupReq: Adding Cdpc Pid (2,100,66,5996) with mapKey 48569698 with branch 0 to mCiToPid table|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.764 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/getDefAe: SIPCdpc=5996, nodeId=2, processNumber=65 ci=48569698, branch=0|2,100,66,5996.1^*^*

16:08:26.765 |CCM_PI: CPIClass::incValue(), Caller's error, inappropriate InstanceIndex[26] > m_nMaxCreatedInstanceIndex[10] or m_nMaxCreatedInstanceIndex == -1. PIClass(Enum=22)|*^*^*

16:08:26.765 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/StartTransition: requireInactiveSDPForMidcallMediaChange=0|2,100,66,5996.1^*^*

16:08:26.765 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/getDefAe: SIPCdpc=5996, nodeId=2, processNumber=65 ci=48569698, branch=0|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.765 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/applyDeviceCdpnTransform: applyDeviceCdpnTransform() devicePool(1b1b9eb6-7803-11d3-bdf0-00108302ead1)'s cdpnCssPkid=, cgpnCssPkid=|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.765 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/LocalizeOutpulsedNumber: SIPCdpc on device TetraSystem , CSS = ,useDevicePoolCgpnCss =1 AlternateCgpn(global)=1655272205 cgpn=2205, cdpn=71702#, mUpdateInstruction=0|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.765 |setLocalDtmfCaps: supportedDTMFMethod=3, mWantDtmfReception=1, mPeersWantDtmfReceptionFlag=0, mDtmfPreference=1|*^*^*

16:08:26.765 |SIP DTMF Info: mLocalDtmfCaps...UNSOL=1, KPML=1, Inband=1(0) mEndppointsDtmfCaps...UNSOL=1, KPML=0, Inband=0(0) mDefaultTelephonyEvent=101, mDtmfPreference=1, mMtpAllocated=0|*^*^*

16:08:26.765 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/sendPolicyAndRSVPRegisterReq: capCount[1], videoCap[0], dataCap[2], earlyOffer[0]|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.765 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/sendPolicyAndRSVPRegisterReq: preconditions: Not using preconditions, rsvpStatus[0]|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.765 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/star_SdlLinkISV: SDL Link In-Service received|0,0,0,0.0^*^*

16:08:26.766 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/outCall_waitRSVPRes_PolicyAndRSVPRegisterRes: policy[1], resvStatus[1], video[0]|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.766 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/handleCcSetupReq: mtpRequired=1, mGClearCall=0|2,100,49,1.45302566^10.60.57.221^SEPC471FE910815

16:08:26.767 |StationInit: (0000001) OpenReceiveChannelAck Status=0, IpAddr=IpAddr.type:0 ipAddr:0x0a3c2511000000000000000000000000(10.60.37.17), Port=24738, PartyID=33736837|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/getDefCcRegister: Secure status=1, mSrtpPresent=0|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/getDefSetup: NumberPi 1 , NamePi 1|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/setIdentityOutgoingSIPMsg: identityFlag=[PAI;RPID;], privacyType[0]|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |(setHoldeeState): er=0,ee=0,mh=0|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/getDefSetup: ReqURI is not present|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/appendRPHdr: appendRPHdr - no RPHeader Network Domain set and not precedence call, return|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/appendGuidHeader: adding Guid to sipContainerWrapper: 4206C28000010000000029F211253C0A|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/getRedirectingNumIE: outboundRedirectingNumIEDeliveryFlag is not enabled, no Diversion header will be sent|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |Cdcc - (0195374) - updateDchanCrp - secure capability on side 1 is (1,1)|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/Stack/Info/0x0/Added context(0xffe65b8) with key=[120526] to table|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/SIPHandler/ccbId=0/scbId=0/populateTargetInfo: port=5060, isIPAddr=1, type=2, target_port=5060, outboundProxyAddr.isIPAddr=0, target_type=0|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/SIPHandler/ccbId=0/scbId=0/setPresentationIndication: namePi[1], numPi[1]|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/SIPHandler/ccbId=0/scbId=0/setScreeningIndication: si[1], oct3a[81]|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/SIPHandler/ccbId=0/scbId=0/setFromUriInContainer: selectedIdentity.flag[10], privacy[0], namePi[1], numPi[1]|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/SIPHandler/ccbId=0/scbId=0/setFromUriInContainer: outCallingName[2205], uri[sip:2205@10.60.37.17]|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/SIPHandler/ccbId=0/scbId=0/setIdentityHeaders: ccb[ffe65b8], currLocalNum[2205], currLocalNam[2205], callbackNumber[], localNumPi[1], localNamPi[1], id.flag[10], id.privacy[0]|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/SIPHandler/ccbId=0/scbId=0/setRpidHeader: rpidHdrStr["2205" <sip:2205@10.60.37.17>;party=calling;screen=yes;privacy=off]|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/SIPHandler/ccbId=125610/scbId=0/ccsip_call_setup_request: reqUriPresent=0|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/SIPHandler/ccbId=0/scbId=0/ccsip_call_setup_request: session target type = 2|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 3 for event 2|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/Stack/Info/0xffe65b8/Adding call id 1eaaa to table|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.767 |//SIP/Stack/Info/0xffe65b8/|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/SIPHandler/ccbId=0/scbId=0/getRel1xxType: rel1xxConfig is 0 from the ccb->pld|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/SIPHandler/ccbId=0/scbId=0/sipSPIGetCallExtensionSupported: SIPRel1xxEnabledServiceParamSetting=0 , ccb->pld.outboundRel1xx=1|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Info/0xffe65b8/****Adding to UAC table.|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Info/0xffe65b8/Added to table. ccb=0xffe65b8 key=4206c280-edc19f22-2e1c-11253c0a@10.60.37.17|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/SIPHandler/ccbId=125610/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Transport/0x0/Dialog Transaction Address 10.128.101.10,Port 5060, Transport 2, SentBy Port 5060|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/SIPHandler/ccbId=0/scbId=0/getRel1xxType: rel1xxConfig is 0 from the ccb->pld|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/SIPHandler/ccbId=0/scbId=0/sipSPIGetCallExtensionSupported: SIPRel1xxEnabledServiceParamSetting=0 , ccb->pld.outboundRel1xx=1|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Info/0xffe65b8/Guid value not set - not adding header.|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Info/0xffe65b8/Associated container=0xb3442b70 to Invite|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Transport/0xffe65b8/Sending Invite to the transport layer|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Transport/0xffe65b8/msg=0x103065a8, addr=10.128.101.10, port=5060, sentBy_port=0, is_req=1, transport=2, switch=0, callBack=|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Transport/0xffe65b8/Proceedable for sending msg immediately|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Transport/0xffe65b8/switch transport is 0|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Transport/0x0/Aging timer initiated for holder=0xf5d1a98,addr=10.128.101.10|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_AGING value=840000 retries=0|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_AGING value=840000 retries=0|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Transport/0x0/Created new holder=0xf5d1a98, addr=10.128.101.10; nailed=FALSE|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Transport/0x0/Posting TCP conn create request for addr=10.128.101.10, port=5060, context=0xf9d1278|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Transport/0x0/Wait timer set for connection=0xf9d2498,addr=10.128.101.10, port=5060|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Transport/0x0/Wait Conn Timer started for 5000 msec|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Transport/0x0/Created new initiated conn=0xf9d2498, connid=-1, addr=10.128.101.10, port=5060, transport=TCP|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Transport/0x0/Registering gcb=0xffe65b8 with connection=0xf9d2498|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Transport/0xffe65b8/Deferred sending msg=0x103065a8|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 48569698 mapKey is 48569698|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getCdpcPidGivenCcbidAndCi: found Cdpc Pid (2,100,66,5996) for mapKey 48569698|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.768 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 58|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.769 |//SIP/Stack/Transport/0x0/context=0xf9d1278|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.769 |//SIP/Stack/Transport/0x0/gConnTab=0xf9d1278, addr=10.128.101.10, port=5060, connid=633, transport=TCP|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.769 |//SIP/Stack/Transport/0x0/Moving connection=0xf9d2498, connid=633state to pending|2,100,49,1.45302567^10.60.37.17^MTP_3

16:08:26.770 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 58|0,0,0,0.0^*^*

16:08:26.770 |//SIP/Stack/Transport/0x0/context=0xf9d1278|0,0,0,0.0^*^*

16:08:26.770 |//SIP/Stack/Transport/0x0/gConnTab=0xf9d1278, addr=10.128.101.10, port=5060, connid=633, transport=TCP|0,0,0,0.0^*^*

16:08:26.770 |//SIP/Stack/Transport/0x0/Wait timer stopped for connection=0xf9d2498,addr=10.128.101.10, port=5060|0,0,0,0.0^*^*

16:08:26.770 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0|0,0,0,0.0^*^*

16:08:26.770 |//SIP/Stack/Transport/0x0/Moving connection=0xf9d2498, connid=633state to established|0,0,0,0.0^*^*

16:08:26.770 |//SIP/Stack/Transport/0xffe65b8/Posting Internal Msg type=0|0,0,0,0.0^*^*

16:08:26.770 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 63|0,0,0,0.0^*^*

16:08:26.770 |//SIP/Stack/Transport/0x0/Posting send for msg=0x103065a8, addr=10.128.101.10, port=5060, connId=633 for TCP|0,0,0,0.0^*^*

16:08:26.770 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|0,0,0,0.0^*^*

16:08:26.770 |//SIP/SIPTcp/wait_SdlSPISignal: Outgoing SIP TCP message to 10.128.101.10 on port 5060 index 633

INVITE sip:71702@10.128.101.10:5060 SIP/2.0

Via: SIP/2.0/TCP 10.60.37.17:5060;branch=z9hG4bK5dd11a14a78b

From: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

To: <sip:71702@10.128.101.10>

Date: Mon, 05 Dec 2011 10:38:26 GMT

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

Supported: timer,resource-priority,replaces

Min-SE: 1800

User-Agent: Cisco-CUCM8.0

Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY

CSeq: 101 INVITE

Contact: <sip:2205@10.60.37.17:5060;transport=tcp>

Expires: 180

Allow-Events: presence, kpml

Supported: X-cisco-srtp-fallback

Supported: Geolocation

Call-Info: <urn:x-cisco-remotecc:callinfo>; security= NotAuthenticated; gci= 2-713221; policy= chaperone

Cisco-Guid: 1107739264-0000065536-0000010738-0287652874

Session-Expires: 1800

P-Asserted-Identity: "2205" <sip:2205@10.60.37.17>

Remote-Party-ID: "2205" <sip:2205@10.60.37.17>;party=calling;screen=yes;privacy=off

Max-Forwards: 70

Content-Type: application/sdp

Content-Length: 210

v=0

o=CiscoSystemsCCM-SIP 2000 1 IN IP4 10.60.37.17

s=SIP Call

c=IN IP4 10.60.37.17

t=0 0

m=audio 24738 RTP/AVP 0 101

a=rtpmap:0 PCMU/8000

a=ptime:20

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

|0,0,0,0.0^*^*

16:08:26.770 |SIPSocketProtocol(2,100,9,13026)::handleWriteComplete|*^*^*

16:08:26.770 |//SIP/Stack/Info/0xffe65b8/Sent Invite in state STATE_IDLE|0,0,0,0.0^*^*

16:08:26.770 |//SIP/Stack/Info/0x0/Transaction active. Facilities will be queued.|0,0,0,0.0^*^*

16:08:26.770 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_TRYING value=500 retries=6|0,0,0,0.0^*^*

16:08:26.770 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_TRYING value=500 retries=6|0,0,0,0.0^*^*

16:08:26.770 |//SIP/Stack/States/0xffe65b8/0xffe65b8 : State change from (STATE_IDLE, SUBSTATE_NONE) to (STATE_SENT_INVITE, SUBSTATE_NONE)|0,0,0,0.0^*^*

16:08:26.772 |SIPSocketProtocol(2,100,9,13026)::handleReadComplete send SdlReadRsp: size 301|*^*^*

16:08:26.772 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=301|2,100,56,1.239800^10.128.101.10^*

16:08:26.772 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 10.128.101.10 on port 5060 index 633 with 301 bytes:

SIP/2.0 100 Trying - CPSIP

Via: SIP/2.0/TCP 10.60.37.17:5060;branch=z9hG4bK5dd11a14a78b

From: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

To: <sip:71702@10.128.101.10>

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

CSeq: 101 INVITE

Content-Length: 0

|2,100,56,1.239800^10.128.101.10^*

16:08:26.772 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 239798|2,100,56,1.239800^10.128.101.10^*

16:08:26.772 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|2,100,56,1.239800^10.128.101.10^*

16:08:26.772 |//SIP/Stack/Transport/0x0/context=0xf9d1278|2,100,56,1.239800^10.128.101.10^*

16:08:26.772 |//SIP/Stack/Transport/0x0/gConnTab=0xf9d1278, addr=10.128.101.10, port=5060, connid=633, transport=TCP|2,100,56,1.239800^10.128.101.10^*

16:08:26.772 |//SIP/Stack/Info/0x0/Checking Invite Dialog|2,100,56,1.239800^10.128.101.10^*

16:08:26.772 |//SIP/Stack/Info/0xffe65b8/INVITE response with no RSEQ - disable IS_REL1XX|2,100,56,1.239800^10.128.101.10^*

16:08:26.772 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_TRYING value=500 retries=6|2,100,56,1.239800^10.128.101.10^*

16:08:26.772 |//SIP/Stack/States/0xffe65b8/0xffe65b8 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE) to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)|2,100,56,1.239800^10.128.101.10^*

16:08:26.772 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_EXPIRES value=180000 retries=0|2,100,56,1.239800^10.128.101.10^*

16:08:26.772 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_EXPIRES value=180000 retries=0|2,100,56,1.239800^10.128.101.10^*

16:08:26.966 |SIPSocketProtocol(2,100,9,13026)::handleReadComplete send SdlReadRsp: size 350|*^*^*

16:08:26.966 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=350|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 10.128.101.10 on port 5060 index 633 with 350 bytes:

SIP/2.0 180 Ringing

Via: SIP/2.0/TCP 10.60.37.17:5060;branch=z9hG4bK5dd11a14a78b

From: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

To: <sip:71702@10.128.101.10>;tag=14e42f0d29e65ae0

Contact: <sip:10.128.101.11:5060>

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

CSeq: 101 INVITE

Content-Length: 0

|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 239799|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/Stack/Transport/0x0/context=0xf9d1278|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/Stack/Transport/0x0/gConnTab=0xf9d1278, addr=10.128.101.10, port=5060, connid=633, transport=TCP|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/Stack/Info/0x0/Checking Invite Dialog|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/Stack/Info/0xffe65b8/INVITE response with no RSEQ - disable IS_REL1XX|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/Stack/Info/0x0/No GTD found in inbound container|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/SIPHandler/ccbId=0/scbId=0/extract_sdp: sipAppGetParticularContent failed to obtain SDP - result=2 len=0|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/SIPHandler/ccbId=0/scbId=0/extractAssertedInfo: parseResult[1]|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/SIPHandler/ccbId=0/scbId=0/copyConnectedInfoAdjustParseResult: !asserted && !rpidHdr|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/SIPHandler/ccbId=125610/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/Stack/Info/0xffe65b8/ccsip_api_call_alert returned: SIP_SUCCESS|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/Stack/States/0xffe65b8/0xffe65b8 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/SIPHandler/ccbId=125610/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/Stack/Info/0xffe65b8/Transaction Complete. Lock on Facilities released.|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 48569698 mapKey is 48569698|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getCdpcPidGivenCcbidAndCi: found Cdpc Pid (2,100,66,5996) for mapKey 48569698|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/SIPD(2,65,27)/ccbId=125610/scbId=0/updateSrtpFallbackSupport: mTsp.deviceName[TetraSystem] - sRTP fallback supported = False|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 48569698 mapKey is 48569698|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getCdpcPidGivenCcbidAndCi: found Cdpc Pid (2,100,66,5996) for mapKey 48569698|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |SIP DTMF Info: mLocalDtmfCaps...UNSOL=0, KPML=1, Inband=1(101) mEndppointsDtmfCaps...UNSOL=0, KPML=0, Inband=0(0) mDefaultTelephonyEvent=101, mDtmfPreference=1, mMtpAllocated=1|*^*^*

16:08:26.966 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/getIdentityIncomingSIPMsg: fIdParseRes=[0], fRemoteNum[71702], fRemoteNam[], fRemoteNumPi[1], fRemoteNamPi[1], fRemoteSi[0]|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |processCCMFeatureData: operationIeIdd=0|2,100,56,1.239801^10.128.101.10^*

16:08:26.966 |LineCdpc(239968): -dispatchToAllDevices-, sigName=CcAlertReq, device=SEPC471FE910815|2,100,56,1.239801^10.128.101.10^*

16:08:31.896 |SIPSocketProtocol(2,100,9,13026)::handleReadComplete send SdlReadRsp: size 660|*^*^*

16:08:31.897 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=660|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 10.128.101.10 on port 5060 index 633 with 660 bytes:

SIP/2.0 200 OK

Via: SIP/2.0/TCP 10.60.37.17:5060;branch=z9hG4bK5dd11a14a78b

From: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

To: <sip:71702@10.128.101.10>;tag=14e42f0d29e65ae0

Contact: <sip:10.128.101.11:5060>

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

CSeq: 101 INVITE

Allow: ACK, BYE, CANCEL, OPTIONS, PRACK, UPDATE

Session-Expires: 1800;refresher=uas

Content-Type: application/sdp

Content-Length: 196

v=0

o=- 15347 15347 IN IP4 10.128.101.11

s=SIP Call

c=IN IP4 10.128.101.11

t=0 0

m=audio 33156 RTP/AVP 0

c=IN IP4 10.128.101.11

a=sendrecv

a=rtpmap:0 PCMU/8000

a=ptime:20

a=maxptime:20

|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 239800|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/Stack/Transport/0x0/context=0xf9d1278|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/Stack/Transport/0x0/gConnTab=0xf9d1278, addr=10.128.101.10, port=5060, connid=633, transport=TCP|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/Stack/Info/0x0/Checking Invite Dialog|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/Stack/Info/0xffe65b8/INVITE response with no RSEQ - disable IS_REL1XX|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/Stack/Info/0x0/No GTD found in inbound container|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_EXPIRES value=180000 retries=0|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/Stack/Info/0xffe65b8/Transaction active. Facilities will be queued.|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/Stack/Info/0xffe65b8/ccsip_200OK_fork_check not implemented on this platform. |2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/Stack/Info/0xffe65b8/*** This ccb is the parent|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPHandler/ccbId=0/scbId=0/extractAssertedInfo: parseResult[1]|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/Stack/Info/0xffe65b8/ccsip_api_call_connect_media returned: SIP_SUCCESS|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/Stack/States/0xffe65b8/0xffe65b8 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING) to (STATE_RECD_PROCEEDING, SUBSTATE_NONE)|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPHandler/ccbId=0/scbId=0/copyConnectedInfoAdjustParseResult: !asserted && !rpidHdr|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPHandler/ccbId=125610/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 48569698 mapKey is 48569698|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getCdpcPidGivenCcbidAndCi: found Cdpc Pid (2,100,66,5996) for mapKey 48569698|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPD(2,65,27)/ccbId=125610/scbId=0/updateSrtpFallbackSupport: mTsp.deviceName[TetraSystem] - sRTP fallback supported = False|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/updateAllowedMethods: mEndpointAllowedMethods=00fa|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/getIdentityIncomingSIPMsg: fIdParseRes=[0], fRemoteNum[71702], fRemoteNam[], fRemoteNumPi[1], fRemoteNamPi[1], fRemoteSi[0]|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |setEndpointsDtmfCaps: Detected NO inband DTMF support.|*^*^*

16:08:31.897 |SIP DTMF Info: mLocalDtmfCaps...UNSOL=0, KPML=1, Inband=1(101) mEndppointsDtmfCaps...UNSOL=0, KPML=0, Inband=0(0) mDefaultTelephonyEvent=101, mDtmfPreference=1, mMtpAllocated=1|*^*^*

16:08:31.897 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/getXCiscoViPRFallbackIDAndDTMFKey: Device type 8, Pstn Fallback is not enabled|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/preProcessOffer: audiomLines[0]: rtp=1,srtp=0,deviceNOTSrtpCapable=1,numCrypto=0,isOfferFromCCM=0,sipEndpointCanRcvSrtpOffers=0,deviceSupportsSrtpFB=0|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPCdpc(2,66,5996)/ci=0/ccbId=0/scbId=0/compareAndUpdateMedia: IPV6 REMOVE ipAddrMode IpAddrMode_v4|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPCdpc(2,66,5996)/ci=0/ccbId=0/scbId=0/updateSDPOfferToCC: IPV6 REMOVE ipAddrMode IpAddrMode_v4|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/getDefCcRegister: Secure status=1, mSrtpPresent=0|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/compareAndUpdateMedia: sdpStatus=0|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/handleSIPUACSessionExpires: isMidCall[0], response[200], method[102]|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/parseSessionExpires: refresh_interval[1800], refresher[uas]|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/setSIPSessionExpiresTimer: interval[1768] secs|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/handleSecureRec: enforce srtp flag: 0, remote end srtp support: 0|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/updateCNToCC: identityCngFlag[0x0], isConnInfoInd[1]|2,100,56,1.239802^10.128.101.10^*

16:08:31.897 |SIPCdpc:setVcrStartTime StartTime[3532070311]|2,100,56,1.239802^10.128.101.10^*

16:08:31.899 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/handleSDPAnswerInd: SDP:

16:08:31.899 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/getDefCcRegister: Secure status=1, mSrtpPresent=0|2,100,61,5276.1^*^*

16:08:31.899 |getOobScbId: Created scbId. mOobScbId=125611, ccbId=125610|*^*^*

16:08:31.899 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/setCnNameAndNumber: connectedData - Name 2205 Number 2205 NumberPi 1 NamePi 1 Si 1, LocalData - NumberPi 1 NamePi 1|2,100,61,5276.1^*^*

16:08:31.899 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/setIdentityOutgoingSIPMsg: identityFlag=[PAI;RPID;], privacyType[0]|2,100,61,5276.1^*^*

16:08:31.899 |waitSDPResponse_SDPAnswerInd, RSVPLastCollab=0, IsSessionJoined=0|2,100,61,5276.1^*^*

16:08:31.899 |SIPINterface(5276)::setTIAS, isAnswer=1, audioKbps=64000|2,100,61,5276.1^*^*

16:08:31.899 |SDPMsg getVideoMLine - Warning video line (size=0,idx=0) not found, returning System Default |*^*^*

16:08:31.899 |SIPInterface(5276)::sendMXConnectInfoIndtoMX,-Sending audioMXConnectInfoIndtoMX, info(0 0),vIdx=0|2,100,61,5276.1^*^*

16:08:31.899 |SDPMsg getAudioMLine - Warning audio line (size=0,idx=0) not found, returning System Default |*^*^*

16:08:31.899 |SIPInterface(5276)-Sending audioMXConnectInfoIndtoMX, mIsSRTP=0, isSecureAudioVideo=0|2,100,61,5276.1^*^*

16:08:31.900 |Cdcc - (0195374) - dumpSecureStatus - sideA=(cap=1,1, media=0,0, feature=0,0), sideB=(cap=1,1 media=0,0, feature=0,0)|2,100,61,5276.1^*^*

16:08:31.900 |//SIP/Stack/Info/0xffe65b8/ccsip_api_call_connected returned: SIP_SUCCESS|2,100,56,1.239802^10.128.101.10^*

16:08:31.900 |//SIP/Stack/Transport/0xffe65b8/Sending ACK to the transport layer|2,100,56,1.239802^10.128.101.10^*

16:08:31.900 |//SIP/Stack/Transport/0xffe65b8/msg=0xe8c6be8, addr=10.128.101.11, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=|2,100,56,1.239802^10.128.101.10^*

16:08:31.900 |//SIP/Stack/Transport/0xffe65b8/Proceedable for sending msg immediately|2,100,56,1.239802^10.128.101.10^*

16:08:31.900 |//SIP/Stack/Transport/0xffe65b8/switch transport is 0|2,100,56,1.239802^10.128.101.10^*

16:08:31.900 |//SIP/Stack/Transport/0xffe65b8/Set to send the msg=0xe8c6be8|2,100,56,1.239802^10.128.101.10^*

16:08:31.900 |//SIP/Stack/Transport/0x0/Posting send for msg=0xe8c6be8, addr=10.128.101.11, port=5060, connId=0 for UDP|2,100,56,1.239802^10.128.101.10^*

16:08:31.900 |//SIP/Stack/States/0xffe65b8/0xffe65b8 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_NONE) to (STATE_ACTIVE, SUBSTATE_NONE)|2,100,56,1.239802^10.128.101.10^*

16:08:31.900 |//SIP/SIPHandler/ccbId=125610/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|2,100,56,1.239802^10.128.101.10^*

16:08:31.900 |//SIP/Stack/Info/0xffe65b8/Transaction Complete. Lock on Facilities released.|2,100,56,1.239802^10.128.101.10^*

16:08:31.900 |//SIP/Stack/Info/0xffe65b8/There are 0 events on the internal queue that are going to be free'd|2,100,56,1.239802^10.128.101.10^*

16:08:31.900 |//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 10.128.101.11:[5060]:

ACK sip:10.128.101.11:5060 SIP/2.0

Via: SIP/2.0/UDP 10.60.37.17:5060;branch=z9hG4bK5dd2b86ff24

From: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

To: <sip:71702@10.128.101.10>;tag=14e42f0d29e65ae0

Date: Mon, 05 Dec 2011 10:38:26 GMT

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: presence, kpml

Content-Length: 0

|2,100,56,1.239802^10.128.101.10^*

16:08:31.900 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 48569698 mapKey is 48569698|2,100,56,1.239802^10.128.101.10^*

16:08:31.900 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getCdpcPidGivenCcbidAndCi: found Cdpc Pid (2,100,66,5996) for mapKey 48569698|2,100,56,1.239802^10.128.101.10^*

16:08:31.901 |MediaExchange(121671)::handleInterfaceVisited, allowReConnect(1) partyAHasCapsorACE(1)partybHasCapsorACE (1)|2,100,211,175772.1^*^*

16:08:31.901 |//SIP/SIPHandler/ccbId=0/scbId=0/setPresentationIndication: namePi[1], numPi[1]|2,100,61,5276.1^*^*

16:08:31.901 |//SIP/SIPHandler/ccbId=0/scbId=0/setScreeningIndication: si[1], oct3a[81]|2,100,61,5276.1^*^*

16:08:31.901 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 3 for event 30|2,100,61,5276.1^*^*

16:08:31.901 |//SIP/Stack/Info/0xffe65b8/method = 107 state = 7|2,100,61,5276.1^*^*

16:08:31.901 |//SIP/Stack/Info/0xffe65b8/Received media sip event SIP_REQUEST_ANSWER|2,100,61,5276.1^*^*

16:08:31.901 |//SIP/Stack/Error/0xffe65b8/ignoring ACK request answer|2,100,61,5276.1^*^*

16:08:31.901 |EnvProcessUdpPort - EnvProcessUdpHandler::fireSignal() varId = 0|2,100,56,1.239802^10.128.101.10^*

16:08:31.901 |EnvProcessUdpHandler::fireSignal - SEND: index = 0, handler = 0xb4022ac0|*^*^*

16:08:31.901 |EnvProcessUdpPort::fireSignal - SEND, destination = 10.128.101.11:5060|*^*^*

16:08:31.901 |EnvProcessUdpPort - EnvProcessUdpHandler::send(buff, 411, 10.128.101.11:5060)|*^*^*

16:08:31.901 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/star_SIPUpdateAllowed: mIsUpdateForSignalingAllowed = 1 mIsUpdateForMediaAllowed = 1 mPendingOutgoingUpdate = 0|2,100,56,1.239802^10.128.101.10^*

16:08:31.902 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 48569698 mapKey is 48569698|2,100,211,175773.1^*^*

16:08:31.902 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getCdpcPid: found Cdpc Pid (2,100,66,5996) for mapKey 48569698|2,100,211,175773.1^*^*

16:08:32.026 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 48569698 mapKey is 48569698|2,100,49,1.45302678^10.60.57.221^SEPC471FE910815

16:08:32.026 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getCdpcPid: found Cdpc Pid (2,100,66,5996) for mapKey 48569698|2,100,49,1.45302678^10.60.57.221^SEPC471FE910815

16:08:32.026 |LineCdpc(239968): -dispatchToAllDevices-, sigName=CcNotifyReq, device=SEPC471FE910815|2,100,49,1.45302678^10.60.57.221^SEPC471FE910815

16:08:32.026 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/transformConnectedNumber: device TetraSystem , CSS = , useDPCss[cg=1, conn=1], connPartyNum=|2,100,49,1.45302678^10.60.57.221^SEPC471FE910815

16:08:32.026 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/processLocalIdentityInfo: num=,localCnNum=2205;numPi=0,localCnNumPi=1;name=,localCnName=2205;NamePi=0,localCnNamPi=1;|2,100,49,1.45302678^10.60.57.221^SEPC471FE910815

16:08:32.026 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/processLocalIdentityInfo: identityFlag=[Changed:- Num;Num PI;Name;Name PI;]|2,100,49,1.45302678^10.60.57.221^SEPC471FE910815

16:08:32.026 |(isHeldOrHolding): er=0,ee=0,mh=0|2,100,49,1.45302678^10.60.57.221^SEPC471FE910815

16:08:32.026 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/updateDisplayInfoAndSendMsg: mSrtpPresent 0 prevSecurity 1 mSecurity 1 media-hold 0 mDialogDirection 1 Configured status 1 msgType 4 devType 8|2,100,49,1.45302678^10.60.57.221^SEPC471FE910815

16:08:32.397 |SIPSocketProtocol(2,100,9,13026)::handleReadComplete send SdlReadRsp: size 646|*^*^*

16:08:32.397 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=646|2,100,56,1.239803^10.128.101.10^*

16:08:32.397 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 10.128.101.10 on port 5060 index 633 with 646 bytes:

SIP/2.0 200 OK

Via: SIP/2.0/TCP 10.60.37.17:5060;branch=z9hG4bK5dd11a14a78b

From: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

To: <sip:71702@10.128.101.10>;tag=14e42f0d29e65ae0

Contact: <sip:10.128.101.11:5060>

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

CSeq: 101 INVITE

Allow: ACK, BYE, CANCEL, OPTIONS, PRACK, UPDATE

Session-Expires: 1800

Content-Type: application/sdp

Content-Length: 196

v=0

o=- 15347 15347 IN IP4 10.128.101.11

s=SIP Call

c=IN IP4 10.128.101.11

t=0 0

m=audio 33156 RTP/AVP 0

c=IN IP4 10.128.101.11

a=sendrecv

a=rtpmap:0 PCMU/8000

a=ptime:20

a=maxptime:20

|2,100,56,1.239803^10.128.101.10^*

16:08:32.397 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 239801|2,100,56,1.239803^10.128.101.10^*

16:08:32.397 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|2,100,56,1.239803^10.128.101.10^*

16:08:32.397 |//SIP/Stack/Transport/0x0/context=0xf9d1278|2,100,56,1.239803^10.128.101.10^*

16:08:32.397 |//SIP/Stack/Transport/0x0/gConnTab=0xf9d1278, addr=10.128.101.10, port=5060, connid=633, transport=TCP|2,100,56,1.239803^10.128.101.10^*

16:08:32.397 |//SIP/Stack/Info/0x0/Checking Invite Dialog|2,100,56,1.239803^10.128.101.10^*

16:08:32.397 |//SIP/Stack/Info/0xffe65b8/INVITE response with no RSEQ - disable IS_REL1XX|2,100,56,1.239803^10.128.101.10^*

16:08:32.397 |//SIP/Stack/Transport/0xffe65b8/Sending ACK to the transport layer|2,100,56,1.239803^10.128.101.10^*

16:08:32.397 |//SIP/Stack/Transport/0xffe65b8/msg=0x10162a10, addr=10.128.101.11, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=|2,100,56,1.239803^10.128.101.10^*

16:08:32.397 |//SIP/Stack/Transport/0xffe65b8/Proceedable for sending msg immediately|2,100,56,1.239803^10.128.101.10^*

16:08:32.397 |//SIP/Stack/Transport/0xffe65b8/switch transport is 0|2,100,56,1.239803^10.128.101.10^*

16:08:32.397 |//SIP/Stack/Transport/0xffe65b8/Set to send the msg=0x10162a10|2,100,56,1.239803^10.128.101.10^*

16:08:32.397 |//SIP/Stack/Transport/0x0/Posting send for msg=0x10162a10, addr=10.128.101.11, port=5060, connId=0 for UDP|2,100,56,1.239803^10.128.101.10^*

16:08:32.397 |//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 10.128.101.11:[5060]:

ACK sip:10.128.101.11:5060 SIP/2.0

Via: SIP/2.0/UDP 10.60.37.17:5060;branch=z9hG4bK5dd2b86ff24

From: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

To: <sip:71702@10.128.101.10>;tag=14e42f0d29e65ae0

Date: Mon, 05 Dec 2011 10:38:26 GMT

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: presence, kpml

Content-Length: 0

|2,100,56,1.239803^10.128.101.10^*

16:08:32.397 |EnvProcessUdpPort - EnvProcessUdpHandler::fireSignal() varId = 0|2,100,56,1.239803^10.128.101.10^*

16:08:32.397 |EnvProcessUdpHandler::fireSignal - SEND: index = 0, handler = 0xb4022ac0|*^*^*

16:08:32.397 |EnvProcessUdpPort::fireSignal - SEND, destination = 10.128.101.11:5060|*^*^*

16:08:32.397 |EnvProcessUdpPort - EnvProcessUdpHandler::send(buff, 411, 10.128.101.11:5060)|*^*^*

16:08:33.396 |SIPSocketProtocol(2,100,9,13026)::handleReadComplete send SdlReadRsp: size 646|*^*^*

16:08:33.396 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=646|2,100,56,1.239804^10.128.101.10^*

16:08:33.396 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 10.128.101.10 on port 5060 index 633 with 646 bytes:

SIP/2.0 200 OK

Via: SIP/2.0/TCP 10.60.37.17:5060;branch=z9hG4bK5dd11a14a78b

From: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

To: <sip:71702@10.128.101.10>;tag=14e42f0d29e65ae0

Contact: <sip:10.128.101.11:5060>

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

CSeq: 101 INVITE

Allow: ACK, BYE, CANCEL, OPTIONS, PRACK, UPDATE

Session-Expires: 1800

Content-Type: application/sdp

Content-Length: 196

v=0

o=- 15347 15347 IN IP4 10.128.101.11

s=SIP Call

c=IN IP4 10.128.101.11

t=0 0

m=audio 33156 RTP/AVP 0

c=IN IP4 10.128.101.11

a=sendrecv

a=rtpmap:0 PCMU/8000

a=ptime:20

a=maxptime:20

|2,100,56,1.239804^10.128.101.10^*

16:08:33.396 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 239802|2,100,56,1.239804^10.128.101.10^*

16:08:33.396 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|2,100,56,1.239804^10.128.101.10^*

16:08:33.396 |//SIP/Stack/Transport/0x0/context=0xf9d1278|2,100,56,1.239804^10.128.101.10^*

16:08:33.396 |//SIP/Stack/Transport/0x0/gConnTab=0xf9d1278, addr=10.128.101.10, port=5060, connid=633, transport=TCP|2,100,56,1.239804^10.128.101.10^*

16:08:33.396 |//SIP/Stack/Info/0x0/Checking Invite Dialog|2,100,56,1.239804^10.128.101.10^*

16:08:33.396 |//SIP/Stack/Info/0xffe65b8/INVITE response with no RSEQ - disable IS_REL1XX|2,100,56,1.239804^10.128.101.10^*

16:08:33.396 |//SIP/Stack/Transport/0xffe65b8/Sending ACK to the transport layer|2,100,56,1.239804^10.128.101.10^*

16:08:33.396 |//SIP/Stack/Transport/0xffe65b8/msg=0x100d6900, addr=10.128.101.11, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=|2,100,56,1.239804^10.128.101.10^*

16:08:33.396 |//SIP/Stack/Transport/0xffe65b8/Proceedable for sending msg immediately|2,100,56,1.239804^10.128.101.10^*

16:08:33.396 |//SIP/Stack/Transport/0xffe65b8/switch transport is 0|2,100,56,1.239804^10.128.101.10^*

16:08:33.396 |//SIP/Stack/Transport/0xffe65b8/Set to send the msg=0x100d6900|2,100,56,1.239804^10.128.101.10^*

16:08:33.396 |//SIP/Stack/Transport/0x0/Posting send for msg=0x100d6900, addr=10.128.101.11, port=5060, connId=0 for UDP|2,100,56,1.239804^10.128.101.10^*

16:08:33.397 |//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 10.128.101.11:[5060]:

ACK sip:10.128.101.11:5060 SIP/2.0

Via: SIP/2.0/UDP 10.60.37.17:5060;branch=z9hG4bK5dd2b86ff24

From: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

To: <sip:71702@10.128.101.10>;tag=14e42f0d29e65ae0

Date: Mon, 05 Dec 2011 10:38:26 GMT

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: presence, kpml

Content-Length: 0

|2,100,56,1.239804^10.128.101.10^*

16:08:33.397 |EnvProcessUdpPort - EnvProcessUdpHandler::fireSignal() varId = 0|2,100,56,1.239804^10.128.101.10^*

16:08:33.397 |EnvProcessUdpHandler::fireSignal - SEND: index = 0, handler = 0xb4022ac0|*^*^*

16:08:33.397 |EnvProcessUdpPort::fireSignal - SEND, destination = 10.128.101.11:5060|*^*^*

16:08:33.397 |EnvProcessUdpPort - EnvProcessUdpHandler::send(buff, 411, 10.128.101.11:5060)|*^*^*

16:08:35.396 |SIPSocketProtocol(2,100,9,13026)::handleReadComplete send SdlReadRsp: size 646|*^*^*

16:08:35.396 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=646|2,100,56,1.239805^10.128.101.10^*

16:08:35.396 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 10.128.101.10 on port 5060 index 633 with 646 bytes:

SIP/2.0 200 OK

Via: SIP/2.0/TCP 10.60.37.17:5060;branch=z9hG4bK5dd11a14a78b

From: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

To: <sip:71702@10.128.101.10>;tag=14e42f0d29e65ae0

Contact: <sip:10.128.101.11:5060>

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

CSeq: 101 INVITE

Allow: ACK, BYE, CANCEL, OPTIONS, PRACK, UPDATE

Session-Expires: 1800

Content-Type: application/sdp

Content-Length: 196

v=0

o=- 15347 15347 IN IP4 10.128.101.11

s=SIP Call

c=IN IP4 10.128.101.11

t=0 0

m=audio 33156 RTP/AVP 0

c=IN IP4 10.128.101.11

a=sendrecv

a=rtpmap:0 PCMU/8000

a=ptime:20

a=maxptime:20

|2,100,56,1.239805^10.128.101.10^*

16:08:35.396 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 239803|2,100,56,1.239805^10.128.101.10^*

16:08:35.396 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|2,100,56,1.239805^10.128.101.10^*

16:08:35.396 |//SIP/Stack/Transport/0x0/context=0xf9d1278|2,100,56,1.239805^10.128.101.10^*

16:08:35.396 |//SIP/Stack/Transport/0x0/gConnTab=0xf9d1278, addr=10.128.101.10, port=5060, connid=633, transport=TCP|2,100,56,1.239805^10.128.101.10^*

16:08:35.396 |//SIP/Stack/Info/0x0/Checking Invite Dialog|2,100,56,1.239805^10.128.101.10^*

16:08:35.396 |//SIP/Stack/Info/0xffe65b8/INVITE response with no RSEQ - disable IS_REL1XX|2,100,56,1.239805^10.128.101.10^*

16:08:35.397 |//SIP/Stack/Transport/0xffe65b8/Sending ACK to the transport layer|2,100,56,1.239805^10.128.101.10^*

16:08:35.397 |//SIP/Stack/Transport/0xffe65b8/msg=0x10175e70, addr=10.128.101.11, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=|2,100,56,1.239805^10.128.101.10^*

16:08:35.397 |//SIP/Stack/Transport/0xffe65b8/Proceedable for sending msg immediately|2,100,56,1.239805^10.128.101.10^*

16:08:35.397 |//SIP/Stack/Transport/0xffe65b8/switch transport is 0|2,100,56,1.239805^10.128.101.10^*

16:08:35.397 |//SIP/Stack/Transport/0xffe65b8/Set to send the msg=0x10175e70|2,100,56,1.239805^10.128.101.10^*

16:08:35.397 |//SIP/Stack/Transport/0x0/Posting send for msg=0x10175e70, addr=10.128.101.11, port=5060, connId=0 for UDP|2,100,56,1.239805^10.128.101.10^*

16:08:35.397 |//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 10.128.101.11:[5060]:

ACK sip:10.128.101.11:5060 SIP/2.0

Via: SIP/2.0/UDP 10.60.37.17:5060;branch=z9hG4bK5dd2b86ff24

From: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

To: <sip:71702@10.128.101.10>;tag=14e42f0d29e65ae0

Date: Mon, 05 Dec 2011 10:38:26 GMT

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: presence, kpml

Content-Length: 0

|2,100,56,1.239805^10.128.101.10^*

16:08:35.397 |EnvProcessUdpPort - EnvProcessUdpHandler::fireSignal() varId = 0|2,100,56,1.239805^10.128.101.10^*

16:08:35.397 |EnvProcessUdpHandler::fireSignal - SEND: index = 0, handler = 0xb4022ac0|*^*^*

16:08:35.397 |EnvProcessUdpPort::fireSignal - SEND, destination = 10.128.101.11:5060|*^*^*

16:08:35.397 |EnvProcessUdpPort - EnvProcessUdpHandler::send(buff, 411, 10.128.101.11:5060)|*^*^*

16:08:39.396 |SIPSocketProtocol(2,100,9,13026)::handleReadComplete send SdlReadRsp: size 646|*^*^*

16:08:39.396 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=646|2,100,56,1.239806^10.128.101.10^*

16:08:39.396 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 10.128.101.10 on port 5060 index 633 with 646 bytes:

SIP/2.0 200 OK

Via: SIP/2.0/TCP 10.60.37.17:5060;branch=z9hG4bK5dd11a14a78b

From: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

To: <sip:71702@10.128.101.10>;tag=14e42f0d29e65ae0

Contact: <sip:10.128.101.11:5060>

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

CSeq: 101 INVITE

Allow: ACK, BYE, CANCEL, OPTIONS, PRACK, UPDATE

Session-Expires: 1800

Content-Type: application/sdp

Content-Length: 196

v=0

o=- 15347 15347 IN IP4 10.128.101.11

s=SIP Call

c=IN IP4 10.128.101.11

t=0 0

m=audio 33156 RTP/AVP 0

c=IN IP4 10.128.101.11

a=sendrecv

a=rtpmap:0 PCMU/8000

a=ptime:20

a=maxptime:20

|2,100,56,1.239806^10.128.101.10^*

16:08:39.396 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 239804|2,100,56,1.239806^10.128.101.10^*

16:08:39.396 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|2,100,56,1.239806^10.128.101.10^*

16:08:39.396 |//SIP/Stack/Transport/0x0/context=0xf9d1278|2,100,56,1.239806^10.128.101.10^*

16:08:39.396 |//SIP/Stack/Transport/0x0/gConnTab=0xf9d1278, addr=10.128.101.10, port=5060, connid=633, transport=TCP|2,100,56,1.239806^10.128.101.10^*

16:08:39.396 |//SIP/Stack/Info/0x0/Checking Invite Dialog|2,100,56,1.239806^10.128.101.10^*

16:08:39.397 |//SIP/Stack/Info/0xffe65b8/INVITE response with no RSEQ - disable IS_REL1XX|2,100,56,1.239806^10.128.101.10^*

16:08:39.397 |//SIP/Stack/Transport/0xffe65b8/Sending ACK to the transport layer|2,100,56,1.239806^10.128.101.10^*

16:08:39.397 |//SIP/Stack/Transport/0xffe65b8/msg=0x101522d8, addr=10.128.101.11, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=|2,100,56,1.239806^10.128.101.10^*

16:08:39.397 |//SIP/Stack/Transport/0xffe65b8/Proceedable for sending msg immediately|2,100,56,1.239806^10.128.101.10^*

16:08:39.397 |//SIP/Stack/Transport/0xffe65b8/switch transport is 0|2,100,56,1.239806^10.128.101.10^*

16:08:39.397 |//SIP/Stack/Transport/0xffe65b8/Set to send the msg=0x101522d8|2,100,56,1.239806^10.128.101.10^*

16:08:39.397 |//SIP/Stack/Transport/0x0/Posting send for msg=0x101522d8, addr=10.128.101.11, port=5060, connId=0 for UDP|2,100,56,1.239806^10.128.101.10^*

16:08:39.397 |//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 10.128.101.11:[5060]:

ACK sip:10.128.101.11:5060 SIP/2.0

Via: SIP/2.0/UDP 10.60.37.17:5060;branch=z9hG4bK5dd2b86ff24

From: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

To: <sip:71702@10.128.101.10>;tag=14e42f0d29e65ae0

Date: Mon, 05 Dec 2011 10:38:26 GMT

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: presence, kpml

Content-Length: 0

|2,100,56,1.239806^10.128.101.10^*

16:08:39.397 |EnvProcessUdpPort - EnvProcessUdpHandler::fireSignal() varId = 0|2,100,56,1.239806^10.128.101.10^*

16:08:39.397 |EnvProcessUdpHandler::fireSignal - SEND: index = 0, handler = 0xb4022ac0|*^*^*

16:08:39.397 |EnvProcessUdpPort::fireSignal - SEND, destination = 10.128.101.11:5060|*^*^*

16:08:39.397 |EnvProcessUdpPort - EnvProcessUdpHandler::send(buff, 411, 10.128.101.11:5060)|*^*^*

16:08:47.397 |SIPSocketProtocol(2,100,9,13026)::handleReadComplete send SdlReadRsp: size 646|*^*^*

16:08:47.397 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=646|2,100,56,1.239807^10.128.101.10^*

16:08:47.397 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 10.128.101.10 on port 5060 index 633 with 646 bytes:

SIP/2.0 200 OK

Via: SIP/2.0/TCP 10.60.37.17:5060;branch=z9hG4bK5dd11a14a78b

From: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

To: <sip:71702@10.128.101.10>;tag=14e42f0d29e65ae0

Contact: <sip:10.128.101.11:5060>

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

CSeq: 101 INVITE

Allow: ACK, BYE, CANCEL, OPTIONS, PRACK, UPDATE

Session-Expires: 1800

Content-Type: application/sdp

Content-Length: 196

v=0

o=- 15347 15347 IN IP4 10.128.101.11

s=SIP Call

c=IN IP4 10.128.101.11

t=0 0

m=audio 33156 RTP/AVP 0

c=IN IP4 10.128.101.11

a=sendrecv

a=rtpmap:0 PCMU/8000

a=ptime:20

a=maxptime:20

|2,100,56,1.239807^10.128.101.10^*

16:08:47.397 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 239805|2,100,56,1.239807^10.128.101.10^*

16:08:47.397 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|2,100,56,1.239807^10.128.101.10^*

16:08:47.397 |//SIP/Stack/Transport/0x0/context=0xf9d1278|2,100,56,1.239807^10.128.101.10^*

16:08:47.397 |//SIP/Stack/Transport/0x0/gConnTab=0xf9d1278, addr=10.128.101.10, port=5060, connid=633, transport=TCP|2,100,56,1.239807^10.128.101.10^*

16:08:47.397 |//SIP/Stack/Info/0x0/Checking Invite Dialog|2,100,56,1.239807^10.128.101.10^*

16:08:47.397 |//SIP/Stack/Info/0xffe65b8/INVITE response with no RSEQ - disable IS_REL1XX|2,100,56,1.239807^10.128.101.10^*

16:08:47.397 |//SIP/Stack/Transport/0xffe65b8/Sending ACK to the transport layer|2,100,56,1.239807^10.128.101.10^*

16:08:47.397 |//SIP/Stack/Transport/0xffe65b8/msg=0xb3402218, addr=10.128.101.11, port=5060, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=|2,100,56,1.239807^10.128.101.10^*

16:08:47.397 |//SIP/Stack/Transport/0xffe65b8/Proceedable for sending msg immediately|2,100,56,1.239807^10.128.101.10^*

16:08:47.397 |//SIP/Stack/Transport/0xffe65b8/switch transport is 0|2,100,56,1.239807^10.128.101.10^*

16:08:47.397 |//SIP/Stack/Transport/0xffe65b8/Set to send the msg=0xb3402218|2,100,56,1.239807^10.128.101.10^*

16:08:47.397 |//SIP/Stack/Transport/0x0/Posting send for msg=0xb3402218, addr=10.128.101.11, port=5060, connId=0 for UDP|2,100,56,1.239807^10.128.101.10^*

16:08:47.397 |//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 10.128.101.11:[5060]:

ACK sip:10.128.101.11:5060 SIP/2.0

Via: SIP/2.0/UDP 10.60.37.17:5060;branch=z9hG4bK5dd2b86ff24

From: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

To: <sip:71702@10.128.101.10>;tag=14e42f0d29e65ae0

Date: Mon, 05 Dec 2011 10:38:26 GMT

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: presence, kpml

Content-Length: 0

|2,100,56,1.239807^10.128.101.10^*

16:08:47.397 |EnvProcessUdpPort - EnvProcessUdpHandler::fireSignal() varId = 0|2,100,56,1.239807^10.128.101.10^*

16:08:47.397 |EnvProcessUdpHandler::fireSignal - SEND: index = 0, handler = 0xb4022ac0|*^*^*

16:08:47.397 |EnvProcessUdpPort::fireSignal - SEND, destination = 10.128.101.11:5060|*^*^*

16:08:47.397 |EnvProcessUdpPort - EnvProcessUdpHandler::send(buff, 411, 10.128.101.11:5060)|*^*^*

16:08:54.264 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=4|2,100,56,1.239808^10.60.57.39^*

16:08:54.264 |//SIP/Stack/Info/0x0/MSG LINE READ FAILURE DUE TO RS->EOF|2,100,63,1.1^*^*

16:08:54.264 |//SIP/Stack/Info/0x0/process_network_msg: not complete|2,100,63,1.1^*^*

16:08:54.264 |//SIP/SIPTcp/wait_SdlReadRsp: Saving 4 bytes of incomplete message data.|2,100,56,1.239808^10.60.57.39^*

16:08:54.436 |StationD - adding linestruct at index 1

|*^*^*

16:09:03.397 |SIPSocketProtocol(2,100,9,13026)::handleReadComplete send SdlReadRsp: size 371|*^*^*

16:09:03.397 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=371|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 10.128.101.10 on port 5060 index 633 with 371 bytes:

BYE sip:2205@10.60.37.17:5060;transport=tcp SIP/2.0

Via: SIP/2.0/TCP 10.128.101.11:5060;branch=z9hG4bK638c1e301a41648;rport

From: <sip:71702@10.128.101.10>;tag=14e42f0d29e65ae0

To: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

CSeq: 1 BYE

Max-Forwards: 70

Content-Length: 0

|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 239807|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |//SIP/Stack/Transport/0x0/context=0xf9d1278|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |//SIP/Stack/Transport/0x0/gConnTab=0xf9d1278, addr=10.128.101.10, port=5060, connid=633, transport=TCP|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |//SIP/Stack/Info/0x0/Checking Invite Dialog|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |//SIP/Stack/Info/0xffe65b8/Found matching CB ffe65b8|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |//SIP/Stack/Info/0xffe65b8/****Found CCB in UAC table

|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |//SIP/Stack/Transport/0x0/Subsq Transaction Address 10.128.101.10,Port 5060, Transport 2, SentBy Port 5060|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |//SIP/Stack/Info/0xffe65b8/Stopping hold timer|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |//SIP/Stack/Info/0x0/No GTD found in inbound container|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |//SIP/Stack/Info/0xffe65b8/Initiate call disconnect(16) for outgoing call|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |//SIP/SIPHandler/ccbId=125610/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |//SIP/Stack/States/0xffe65b8/0xffe65b8 : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 48569698 mapKey is 48569698|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getCdpcPidGivenCcbidAndCi: found Cdpc Pid (2,100,66,5996) for mapKey 48569698|2,100,56,1.239809^10.128.101.10^*

16:09:03.397 |(isHeldOrHolding): er=0,ee=0,mh=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |ViprUtils: Number has no +, not a valid E164 [71702#]|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |ViprUtils::uploadVCRifRequired VcrUploadNeeded[1] FeatureData NOT added|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |//SIP/SIPCdpc(2,66,5996)/ci=0/ccbId=125610/scbId=0/uploadVCRifRequired: VcrUploadNeeded 1|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |cdrWrite PER RR, orig = 0, lrn = 0, current = 0|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |Cdcc::isStaticTransactionApplicable |2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |processCCMFeatureData: operationIeIdd=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |EnvProcessCdr::wait_DbCdrReq|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |ConnectionManager - wait_AuDisconnectRequest(48569697,48569698),disconnectType(1), IFHandling(0,0)|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |ConnectionManager - storeMediaInfo(48569697): EXISTING ENTRY DISCOVERED, size=46|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |ConnectionManager - storeMediaInfo(48569698): EXISTING ENTRY DISCOVERED, size=46|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaCoordinator - wait_AuDisconnectRequest,CI(48569697,48569698),IFCreated(1,1)|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaCoordinator - wait_AuDisconnectRequest - sending disconnect to MediaManager(116674)|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaManager(116674)::wait_AuDisconnectRequest, mCleanupPreallocatedMTP=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaManager(116674)::wait_AuDisconnectRequest, mrid(0,48569699) ci(4856969748569698) size(2), dt(1)|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaManager(116674)::wait_AuDisconnectRequest, StopSession,disconn MX(127,121670) mrid (48569699 48569699), IFHandling(0,0)|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaManager(116674)::wait_AuDisconnectRequest, StopSession,disconn MX(127,121671) mrid (0 0), IFHandling(0,0)|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaExchange(121670)::wait_Disconnect, dt=1,stReason=0,IFHandling(0,0)|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaExchange(121671)::wait_Disconnect, dt=1,stReason=0,IFHandling(0,0)|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |AgenaInterface(175771)::closeRecvForAllAudioChannels, mAudioIncomingLC2AGIDMap size = 1|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |AgenaInterface(175771)::needToPassThru, isMTP=1, mediaType=1, aPT=2, vPT=2, switching to Fax=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |AgenaInterface(175771)::can2833BeNegotiated, PeerCapsSupport2833=0 shouldCheckPeerDTMFProfile=1|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |AgenaInterface(175771)::can2833BeNegotiated, is false|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |AgenaInterface(175772)::closeRecvForAllAudioChannels, mAudioIncomingLC2AGIDMap size = 1|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |AgenaInterface(175772)::needToPassThru, isMTP=0, mediaType=1, aPT=2, vPT=2, switching to Fax=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |AgenaInterface(175772)::can2833BeNegotiated, PeerCapsSupport2833=1 shouldCheckPeerDTMFProfile=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |AgenaInterface(175773)::closeRecvForAllAudioChannels, mAudioIncomingLC2AGIDMap size = 1|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |AgenaInterface(175773)::needToPassThru, isMTP=1, mediaType=1, aPT=2, vPT=2, switching to Fax=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |AgenaInterface(175773)::can2833BeNegotiated, PeerCapsSupport2833=1 shouldCheckPeerDTMFProfile=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaTerminationPointControl(1)::star_MediaExchangeAgenaCloseLogicalChannel - PartyId = 33736837|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaTerminationPointControl(1)::star_MediaExchangeAgenaCloseLogicalChannel - confernceId=33584511 removed AgenaInstance=175771|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaTerminationPointControl(1)::star_MediaExchangeAgenaCloseLogicalChannel - Sending Close Channel for Party id =33736837|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaTerminationPointControl(1)::star_MediaExchangeStopTalking - Sending Stop Talking for Party id =33736837|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaTerminationPointControl(1)::removeAudioPartyIdtoMediaPidEntry - Close Channel and Stop Talking both received for PartyId = 33736837. Removing it from our tables.|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaTerminationPointControl(1) - MediaExchangeAgenaDeassociateReq confID=33584511 current AIF(0,175773)|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaTerminationPointControl(1)::star_MediaExchangeAgenaCloseLogicalChannel - PartyId = 33736838|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaTerminationPointControl(1)::star_MediaExchangeAgenaCloseLogicalChannel - confernceId=33584511 removed AgenaInstance=175773|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaTerminationPointControl(1)::star_MediaExchangeAgenaCloseLogicalChannel - Sending Close Channel for Party id =33736838|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaTerminationPointControl(1)::star_MediaExchangeStopTalking - Sending Stop Talking for Party id =33736838|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaTerminationPointControl(1)::removeAudioPartyIdtoMediaPidEntry - Close Channel and Stop Talking both received for PartyId = 33736838. Removing it from our tables.|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaTerminationPointControl(1) - MediaExchangeAgenaDeassociateReq confID=33584511 is removed|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaTerminationPointControl(1)::star_StationOutputCloseReceiveChannel - TCPPid = [2.100.9.6] myIP: 0x11253c0a (10.60.37.17) ConferenceID: 33584511, MediaPartyId: 33736837|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaTerminationPointControl(1)::star_StationOutputStopMediaTransmission - TCPPid = [2.100.9.6] myIP: 0x11253c0a (10.60.37.17) ConferenceID: 33584511 MediaPartyId: 33736837|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaTerminationPointControl(1)::star_StationOutputCloseReceiveChannel - TCPPid = [2.100.9.6] myIP: 0x11253c0a (10.60.37.17) ConferenceID: 33584511, MediaPartyId: 33736838|2,100,56,1.239809^10.128.101.10^*

16:09:03.398 |MediaTerminationPointControl(1)::star_StationOutputStopMediaTransmission - TCPPid = [2.100.9.6] myIP: 0x11253c0a (10.60.37.17) ConferenceID: 33584511 MediaPartyId: 33736838|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MediaManager(116674)::wait_AuDisconnectReply, CI(48569697,48569699), disconnType(1), stopStreamingReason(0) DTMFMethod(3 3),MC(0,0),rf(0), nD(2,1)|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MediaManager(116674)::wait_AuDisconnectReply, videoCap (0, 0), CallType=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MediaCoordinator - wait_MediaCoordinatorDelResource - CI=48569699 count=1 mrmNodeId=2|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MediaManager(116674)::wait_AuDisconnectReply, CI(48569698,48569699), disconnType(1), stopStreamingReason(0) DTMFMethod(0 3),MC(2,0),rf(0), nD(2,2)|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MediaManager(116674)::wait_AuDisconnectReply, videoCap (0, 0), CallType=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MediaManager(116674)::populateAuDisconnectReply, recv all disconn replies, forward reply for party(48569697,48569698) mcNodeId(0,0) mrid(0 48569699) capcounts(7 1), videoCapable(0, 0)|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MediaCoordinator - wait_MediaCoordinatorDelResource - CI=48569699 count=0 mrmNodeId=2|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MediaCoordinator - wait_AuDisconnectReply - removing MediaManager(116674) from connection list|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MediaCoordinator - wait_AuDisconnectReply,MMList=22,1/2List=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MRM::waiting_MrmDeallocateMtpResourceReq- Deallocate received for CI=48569699 count=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MRM::updateMtpCounter devName=MTP_3, countChange=-1|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MRM::updateXcodeCounter devName=MTP_3, countChange=-1|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |ConnectionManager - wait_AuDisconnectReply(48569697,48569698),disconnectType(1),IFHandling(0,0)|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |ConnectionManager - deleteMediaInfoEntry(48569697): ENTRY DELETED, size=45|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |ConnectionManager - deleteMediaInfoEntry(48569698): ENTRY DELETED, size=44|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MediaTerminationPointControl(1)::waiting_DeallocateMtpResourceReq CI=48569699|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MediaTerminationPointControl(1)::waiting_DeallocateMtpResourceReq Allocated = 1 CI=48569699 Count=1|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MediaTerminationPointControl(1)::logResourceStatusinTrace -- Device Name=MTP_3 ResourceAvailable=23 ResourceUsed=1|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MediaTerminationPointControl(1)::decActiveCounter - Count=1|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MediaTerminationPointControl(1)::incAvailableCounter Count=1|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MediaTerminationPointControl(1)::logResourceStatusinTrace -- Device Name=MTP_3 ResourceAvailable=24 ResourceUsed=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |MatrixControl:updatePartyMediaCoordinatorNodeId: party1 videoCapable=0, party 2 videocapable=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |Cdcc - (0195374) - resetMediaSecurity|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 48569698 mapKey is 48569698|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/getCdpcPid: found Cdpc Pid (2,100,66,5996) for mapKey 48569698|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |processCCMFeatureData: operationIeIdd=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |StationD: (0011762) restart0_DStopInd: No Linked StationCdpc.|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |StationD: (0011762) restart0_DStopInd: DEBUG: StationCdpc(239583) removed. Calltable contains 0 calls.|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |LineCdpc(239968)dispatchKeyReleaseReq - mDevicePid(2, 50, 11762); mSelectedDPid(0, 0, 0), mOnBehalfOf(Device), rfr(0)|2,100,56,1.239809^10.128.101.10^*

16:09:03.399 |LPSession -wait_PolicyUnregisterReq, ci[48569697]|*^*^*

16:09:03.400 |LineControl(8762) - Release call instance=1 for CI=48569697|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |LineControl::sendSNFNotifyIndForPresenceWithAlerting mPrecenceWithAlertingChangeNotifySubscribed=0, calllist#=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |LineControl (8762) - DStopInd - Line become idle|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |LineControl(8762) - 0 calls, 0 CiReq, busyTrigger=1, maxCall=2|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |processCCMFeatureData: operationIeIdd=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |Locations_releaseBandwidth -- cdccPID=(2.194.195374) no entry.|*^*^*

16:09:03.400 |Locations_broadcastSignalToAllActiveNodes -- broadcast location table update to all active nodes: remote node=1.|*^*^*

16:09:03.400 |LPSession -star_PolicyUnassociateReq, ci[48569697]|*^*^*

16:09:03.400 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 3 for event 8|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |//SIP/Stack/Transport/0xffe65b8/Sending BYE Response to the transport layer|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |//SIP/Stack/Transport/0xffe65b8/msg=0xb3e25f70, addr=10.128.101.10, port=5060, sentBy_port=5060, is_req=0, transport=2, switch=0, callBack=|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |//SIP/Stack/Transport/0xffe65b8/Proceedable for sending msg immediately|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |//SIP/Stack/Transport/0xffe65b8/switch transport is 0|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |//SIP/Stack/Transport/0xffe65b8/Trying to send resp=0xb3e25f70 to default port=5060|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |//SIP/Stack/Transport/0xffe65b8/Connection obtained...sending msg=0xb3e25f70|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |//SIP/Stack/Transport/0x0/Posting send for msg=0xb3e25f70, addr=10.128.101.10, port=5060, connId=633 for TCP|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |//SIP/Stack/Info/0xffe65b8/Sent 200ok to the BYE, tearing down the call|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |//SIP/SIPHandler/ccbId=125610/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |//SIP/Stack/States/0xffe65b8/0xffe65b8 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |//SIP/Stack/Info/0xffe65b8/Removing call id 1eaaa|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |//SIP/SIPTcp/wait_SdlSPISignal: Outgoing SIP TCP message to 10.128.101.10 on port 5060 index 633

SIP/2.0 200 OK

Via: SIP/2.0/TCP 10.128.101.11:5060;branch=z9hG4bK638c1e301a41648;rport;received=10.128.101.10

From: <sip:71702@10.128.101.10>;tag=14e42f0d29e65ae0

To: "2205" <sip:2205@10.60.37.17>;tag=6ad943a3-9a10-40ff-a265-594a1a8338ea-48569698

Date: Mon, 05 Dec 2011 10:39:03 GMT

Call-ID: 4206c280-edc19f22-2e1c-11253c0a@10.60.37.17

CSeq: 1 BYE

Content-Length: 0

|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |CCM_PI: CPIClass::decValue(), Caller's error, inappropriate InstanceIndex[26] > m_nMaxCreatedInstanceIndex[10] or m_nMaxCreatedInstanceIndex == -1. PIClass(Enum=22)|*^*^*

16:09:03.400 |//SIP/SIPCdpc(2,66,5996)/ci=48569698/ccbId=125610/scbId=0/updateCallStatsForCallCompletion: SIPCdpc=5996, nodeId=2, processNumber=65|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |CCM_PI: CPIClass::incValue(), Caller's error, inappropriate InstanceIndex[26] > m_nMaxCreatedInstanceIndex[10] or m_nMaxCreatedInstanceIndex == -1. PIClass(Enum=22)|*^*^*

16:09:03.400 |CCM_PI: CPIClass::decValue(), Caller's error, inappropriate InstanceIndex[26] > m_nMaxCreatedInstanceIndex[10] or m_nMaxCreatedInstanceIndex == -1. PIClass(Enum=22)|*^*^*

16:09:03.400 |RSVPSessionMgr::wait_PolicyAndRSVPUnregisterReq, cannot find LPSession, ci= 48569698 |2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |RSVPSessionMgr::wait_PolicyAndRSVPUnregisterReq, cannot find RSVPSession, ci= 48569698 |2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |//SIP/SIPD(2,65,27)/ccbId=125610/scbId=0/restart0_DStopInd: Removing Cdpc Pid (2,100,66,5996) with mapKey 48569698 from mCiToPid table|2,100,56,1.239809^10.128.101.10^*

16:09:03.400 |SIPSocketProtocol(2,100,9,13026)::handleWriteComplete|*^*^*

16:09:03.402 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 57|2,100,56,1.239809^10.128.101.10^*

16:09:03.402 |//SIP/Stack/Transport/0x0/context=0xf9d1278|2,100,56,1.239809^10.128.101.10^*

16:09:03.402 |//SIP/Stack/Transport/0x0/gConnTab=0xf9d1278, addr=10.128.101.10, port=5060, connid=633, transport=TCP|2,100,56,1.239809^10.128.101.10^*

16:09:03.402 |//SIP/Stack/Transport/0x0/Posting TCP conn close for addr=10.128.101.10, port=5060, connid=633|2,100,56,1.239809^10.128.101.10^*

16:09:03.402 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|2,100,56,1.239809^10.128.101.10^*

16:09:03.402 |//SIP/SIPTcp/wait_SdlSPISignal: Did not find table entry in Close Msg,Index = 633|2,100,56,1.239809^10.128.101.10^*

16:09:03.402 |//SIP/Stack/Transport/0x0/Deleted conn=0xf9d2498, connid=633, addr=10.128.101.10, port=5060, transport=TCP|2,100,56,1.239809^10.128.101.10^*

16:09:03.402 |//SIP/SIPHandler/ccbId=0/scbId=0/getRemoteAddrInfo: RemoteAddrInfo = 10.128.101.10:5060|2,100,56,1.239809^10.128.101.10^*

16:09:03.402 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/restart0_SIPConnControlInd: mTsp.DeviceName[TetraSystem] - Rcvd ConnControl with operation = 1, connIdx = 633, X.509 Name = |2,100,56,1.239809^10.128.101.10^*

16:09:03.402 |//SIP/SIPD(2,65,27)/ccbId=0/scbId=0/restart0_SIPConnControlInd: mTsp.DeviceName[TetraSystem] - Cannot find TLSConnTbl entry, connIdx = 633, X.509 Name = |2,100,56,1.239809^10.128.101.10^*

16:09:03.470 |CiCcp table has 75 entries|2,100,49,1.45302710^10.60.57.154^SEPC471FE90DC35

Thanks & Regards,

Lalit Tyagi

Mobile: +91-9810343666 ( New Delhi)

Mobile: +91-9855326612 ( Bathinda )

Systems Engineer-MSO for-

luisfernandomartinez · ‎02-16-2012

Hi Lalit, I've the same issue with a CUCM R8.0 conected with a sip trunk to a GSM gateway hypermedia. Whe I do an outbound call it releses after 30 seconds.

did you salve your issue?

Regards,

Luis

joseph_bantog · ‎07-11-2017

hi ciscolalit,

I happen to be a support engineer of Motorola TETRA which uses TIG for telephone interconnect service.

Regarding your problem. Please check the codeplug of your MTP radio and the configuration of BTS. Call time restriction can be on those two equipment. Is the BTS connected to a MSO or SwMI?

If it is, you can modify the call timer from a User Configuration Manager.

jeffqcy@gmail.com · ‎11-12-2017

Hi

I have the similar setup and I could not get the full duplex communication successful when call from DImetra Portable to Cisco phone. On the vice versa is working. The dimetra portable radio keep ringing and cisco phone despite pick up the call , the communication could not be established. From my wireshark, there is no ACK send from Dimetra to Cisco. Can anyone help?

Thanks in advance.

Jeff

Gajanan Pande · ‎02-17-2012

Lalit,

To receive better responses to your queries, always include logs, debug outputs, configurations etc in a file & attach them. Appending them in the notes, would make them too long which readers dont find interesting.

To find solution about your query, search this forum for " disconnects after 30 sec " & you should get many many great solutions, I am sure.

GP.

kevin.vines · ‎11-15-2017

Edit: I did not notice that this was a 6 year old post... at least I got some practice log reading in for the day...

The CUCM sends the Invite to 10.128.101.10 but the responses (trying, ringing, and 200 OK) have 10.128.101.11 in the contact header so the UCM sends the ACK to 10.128.101.11 instead of 10.128.101.10. Also, when the CUCM sends it's responses to the .11 address it switches from TCP to UDP. If the .11 server is sharing state with the .10 server (ie they are aliases or VIPs for the same server) but the .10/.11 server(s) are not listening on UDP 5060, then they will never be able to complete the call and will use the default retry timers to resend the 200 OK until finally, after about 32 seconds (retry 1 after 500 ms, retry 2 after 1000 ms, retry 3 after 2000 ms, retry 4 after 4000ms, retry 5 after 8000ms, call fail after previous retries + 16,000 ms = total time to call failure = 32,000 ms).

If you have a SIP trunk configured for 10.128.101.11, you may need to ensure that the configured SIP Trunk Security Profile for it is configured for TCP and not UDP.

Outbound SIP call from Cisco EPABX connected IP phone disconnects after 30 sec