12-13-2011 11:09 PM - edited 03-16-2019 08:31 AM
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 |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 |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/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 )
02-16-2012 11:52 AM
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
07-11-2017 01:10 AM
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.
11-12-2017 09:35 PM
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
02-17-2012 11:02 PM
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.
11-15-2017 08:47 AM - edited 11-15-2017 08:55 AM
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.
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide