outgoing call from CIPC not working with ilbc codecusing SIP trunk

devraj123 · ‎11-07-2016

Hi All,

Phone CIPC----------------------CUCM----------------------SIP Trunk------------------------------3rd partyUCCX

when using g711 alwaw working fine

we want to use ilbc from CIPC(region) configured with two different region with 16kbps ilbc g722 configured.

working-----logs

12:43:30.669 |//SIP/SIPTcp/wait_SdlSPISignal: Outgoing SIP TCP message to 10.10.102.151 on port 5060 index 653654
[38463569,NET]
INVITE sip:5101@10.10.102.151:5060 SIP/2.0
Via: SIP/2.0/TCP 10.10.10.17:5060;branch=z9hG4bK2d2f6955fd5b5e
From: <sip:1188@10.10.10.17>;tag=9121031~47d2ff17-3b80-42be-9295-c979ef8581ea-39403371
To: <sip:5101@10.10.102.151>
Date: Mon, 07 Nov 2016 01:43:30 GMT
Call-ID: 94761280-81f1dc42-16c474-110a0a0a@10.10.10.17
Supported: 100rel,timer,resource-priority,replaces
Min-SE: 1800
User-Agent: Cisco-CUCM8.6
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
CSeq: 101 INVITE
Expires: 180
Allow-Events: presence
Supported: X-cisco-srtp-fallback,X-cisco-original-called
Cisco-Guid: 2490765952-0000065536-0000163742-0285870602
Session-Expires: 1800
P-Asserted-Identity: <sip:1188@10.10.10.17>
Remote-Party-ID: <sip:1188@10.10.10.17>;party=calling;screen=yes;privacy=off
Contact: <sip:1188@10.10.10.17:5060;transport=tcp>
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 349

v=0
o=CiscoSystemsCCM-SIP 9121031 1 IN IP4 10.10.10.17
s=SIP Call
c=IN IP4 172.25.3.55
b=TIAS:64000
b=AS:64
t=0 0
m=audio 24576 RTP/AVP 8 116 18 101
a=rtpmap:8 PCMA/8000
a=ptime:20
a=rtpmap:116 iLBC/8000
a=ptime:20
a=maxptime:60
a=fmtp:116 mode=20
a=rtpmap:18 G729/8000
a=ptime:20
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
|2,100,13,540929.2^*^*
12:43:30.669 |SIPSocketProtocol(2,100,13,540929)::handleWriteComplete|*^*^*
12:43:30.675 |SIPSocketProtocol(2,100,13,540929)::handleReadComplete send SdlReadRsp: size 477|*^*^*
12:43:30.675 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=477|2,100,63,1.12751011^10.10.102.151^*
12:43:30.675 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 10.10.102.151 on port 5060 index 653654 with 477 bytes:
[38463570,NET]
SIP/2.0 100 Trying
From: <sip:1188@10.10.10.17>;tag=9121031~47d2ff17-3b80-42be-9295-c979ef8581ea-39403371
To: <sip:5101@10.10.102.151>
Call-ID: 94761280-81f1dc42-16c474-110a0a0a@10.10.10.17
CSeq: 101 INVITE
Via: SIP/2.0/TCP 10.10.10.17:5060;branch=z9hG4bK2d2f6955fd5b5e
Supported: replaces,timer
User-Agent: Salmat-FirstContact-10.3
Contact: <sip:10.10.102.151;transport=tcp>
Allow: INVITE, CANCEL, ACK, BYE, OPTIONS, INFO, REFER, NOTIFY, UPDATE
Content-Length: 0

|2,100,63,1.12751011^10.10.102.151^*
12:43:30.675 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 12751009|2,100,63,1.12751011^10.10.102.151^*
12:43:30.675 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_TRYING value=500 retries=6|2,100,63,1.12751011^10.10.102.151^*
12:43:30.675 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_EXPIRES value=180000 retries=0|2,100,63,1.12751011^10.10.102.151^*
12:43:30.675 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_EXPIRES value=180000 retries=0|2,100,63,1.12751011^10.10.102.151^*
12:43:30.675 |SIPSocketProtocol(2,100,13,540929)::handleReadComplete send SdlReadRsp: size 852|*^*^*
12:43:30.675 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=852|2,100,63,1.12751012^10.10.102.151^*
12:43:30.675 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 10.10.102.151 on port 5060 index 653654 with 852 bytes:
[38463571,NET]
SIP/2.0 180 Ringing
From: <sip:1188@10.10.10.17>;tag=9121031~47d2ff17-3b80-42be-9295-c979ef8581ea-39403371
To: <sip:5101@10.10.102.151>;tag=6adb708-97660a0a-13c4-65014-90769a-78d5ddbd-90769a
Call-ID: 94761280-81f1dc42-16c474-110a0a0a@10.10.10.17
CSeq: 101 INVITE
Via: SIP/2.0/TCP 10.10.10.17:5060;branch=z9hG4bK2d2f6955fd5b5e
Supported: replaces,timer
User-Agent: Salmat-FirstContact-10.3
Contact: <sip:10.10.102.151;transport=tcp>
Allow: INVITE, CANCEL, ACK, BYE, OPTIONS, INFO, REFER, NOTIFY, UPDATE
Content-Type: application/sdp
Content-Length: 286

v=0
o=Dialogic_SIP_CCLIB 112453432 112453433 IN IP4 10.10.102.151
s=Dialogic_SIP_CCLIB
c=IN IP4 10.10.102.151
b=TIAS:64000
b=AS:64
t=0 0
m=audio 49168 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=maxptime:60
a=sendrecv
a=ptime:20
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
|2,100,63,1.12751012^10.10.102.151^*
12:43:30.675 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 12751010|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPHandler/ccbId=9121031/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPHandler/ccbId=0/scbId=0/extractAssertedInfo: parseResult[1]|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPHandler/ccbId=0/scbId=0/convertStackSDPtoCmSDP: ***SIPSdp=4738 bytes|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPHandler/ccbId=0/scbId=0/copyConnectedInfoAdjustParseResult: !asserted && !rpidHdr|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPHandler/ccbId=9121031/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPHandler/ccbId=0/scbId=0/parseContactHeader: Incoming Contact=[<sip:10.10.102.151;transport=tcp>]|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPHandler/ccbId=9121031/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPD(2,73,12)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 39403371 mapKey is 39403371|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPD(2,73,12)/ccbId=0/scbId=0/getCdpcPidGivenCcbidAndCi: found Cdpc Pid (2,100,74,932828) for mapKey 39403371|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPD(2,73,12)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 39403371 mapKey is 39403371|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPD(2,73,12)/ccbId=0/scbId=0/getCdpcPidGivenCcbidAndCi: found Cdpc Pid (2,100,74,932828) for mapKey 39403371|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPD(2,73,12)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 39403371 mapKey is 39403371|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPD(2,73,12)/ccbId=0/scbId=0/getCdpcPidGivenCcbidAndCi: found Cdpc Pid (2,100,74,932828) for mapKey 39403371|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/setSIPUpdateFlags: mIsUpdateForSignalingAllowed = 1 mIsUpdateForMediaAllowed = 0 mPendingOutgoingUpdate = 0|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/handleSecureRec: enforce srtp flag: 0, remote end srtp support: 0|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/updateAllowedMethods: mEndpointAllowedMethods=05bf|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |setEndpointsDtmfCaps: Detected inband DTMF support.|*^*^*
12:43:30.676 |SIP DTMF Info: mLocalDtmfCaps...UNSOL=0, KPML=0, Inband=1(0) mEndppointsDtmfCaps...UNSOL=0, KPML=0, Inband=1(101) mDefaultTelephonyEvent=101, mDtmfPreference=3, mMtpAllocated=0|*^*^*
12:43:30.676 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/getDefCcRegister: Secure status=1, mSrtpPresent=0|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/compareAndUpdateMedia: sdpStatus=0, CMEndPointSDP role=1, SIPEndPointSdpRole=2|2,100,63,1.12751012^10.10.102.151^*
12:43:30.676 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/getIdentityIncomingSIPMsg: fIdParseRes=[0], fRemoteNum[5101], fRemoteNam[], fRemoteHostname[], fRemoteNumPi[1], fRemoteNamPi[1], fRemoteSi[0]|2,100,63,1.12751012^10.10.102.151^*
12:43:30.677 |ARBTRY-ConnectionManager-wait_AuConnectRequest(39403370,39403371)|2,100,63,1.12751012^10.10.102.151^*
12:43:30.677 |ARBTRY-ConnectionManager- storeMediaInfo(CI=39403370): ADD NEW ENTRY, size=622|2,100,63,1.12751012^10.10.102.151^*
12:43:30.677 |ARBTRY-ConnectionManager- storeMediaInfo(CI=39403371): ADD NEW ENTRY, size=623|2,100,63,1.12751012^10.10.102.151^*
12:43:30.677 |SIG-MediaCoordinator-wait_AuConnectRequest - new MediaManager(130,932777) started|2,100,63,1.12751012^10.10.102.151^*
12:43:30.678 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/handleSDPAnswerInd: isMediaInactive = 0 ,transId=0, SDP:

SDP:: isPresent=1, role=2
numAudiomLines=1 numVideomLines=0
numAppplicationmLines=0 numBFCPAppmLines=0 numT38faxmLines=0
anatPresent=0
Bandwidth:: enabledMask=0x00000005 as=64 ct=0 tias=64000 maxprate=0
SdpSetupType=0, DTLS Fingerprint=, ektInd=0
audiomLines[0] =
    Media_mLine:: remoteIpAddress=0x97660a0a remoteRtpPortNumber=49168 mSDPMode=0 mediaAttr=0x00000000 - idle=0
     mLineStackIdx=1 telephonyEvent=101 silenceSuppressionFlag=0 midID=-1
    SdpSetupType=0, DTLS Fingerprint=, ektInd=0
    Bandwidth:: enabledMask=0x00000000 as=0 ct=0 tias=0 maxprate=0
    rtp=1,srtp=0,rtcpmux=0
    mLine:enabledMask=0x00000000
    mLine:mContent:mContentTypeCount=0
    caps[0]:: payloadCapability=2 maxFramesPerPacket=20 reportInitialOfferSDP 1|2,100,68,1595191.1^*^*
12:43:30.678 |(isHeldOrHolding): holder=0,holdee=0,mh=0|2,100,68,1595191.1^*^*
12:43:30.678 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/getDefCcRegister: Secure status=1, mSrtpPresent=0|2,100,68,1595191.1^*^*
12:43:30.678 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/subscribeForKpml: (outCall_180Rcvd_SIPBeginMedia)|2,100,68,1595191.1^*^*
12:43:30.678 |getOobScbId: Created scbId. mOobScbId=9121032, ccbId=9121031|*^*^*
12:43:30.679 |ARBTRY-ConnectionManager- wait_AuConnectReply(39403370,39403371)|2,100,68,1595191.1^*^*
12:43:30.693 |SIPSocketProtocol(2,100,13,540929)::handleReadComplete send SdlReadRsp: size 884|*^*^*
12:43:30.693 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=884|2,100,63,1.12751013^10.10.102.151^*
12:43:30.693 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 10.10.102.151 on port 5060 index 653654 with 884 bytes:
[38463572,NET]
SIP/2.0 200 OK
From: <sip:1188@10.10.10.17>;tag=9121031~47d2ff17-3b80-42be-9295-c979ef8581ea-39403371
To: <sip:5101@10.10.102.151>;tag=6adb708-97660a0a-13c4-65014-90769a-78d5ddbd-90769a
Call-ID: 94761280-81f1dc42-16c474-110a0a0a@10.10.10.17
CSeq: 101 INVITE
Via: SIP/2.0/TCP 10.10.10.17:5060;branch=z9hG4bK2d2f6955fd5b5e
Supported: replaces,timer
User-Agent: Salmat-FirstContact-10.3
Contact: <sip:10.10.102.151;transport=tcp>
Session-Expires: 1800;refresher=uas
Allow: INVITE, CANCEL, ACK, BYE, OPTIONS, INFO, REFER, NOTIFY, UPDATE
Content-Type: application/sdp
Content-Length: 286

v=0
o=Dialogic_SIP_CCLIB 112453432 112453433 IN IP4 10.10.102.151
s=Dialogic_SIP_CCLIB
c=IN IP4 10.10.102.151
b=TIAS:64000
b=AS:64
t=0 0
m=audio 49168 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=maxptime:60
a=sendrecv
a=ptime:20
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
|2,100,63,1.12751013^10.10.102.151^*
12:43:30.693 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 12751011|2,100,63,1.12751013^10.10.102.151^*
12:43:30.693 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_EXPIRES value=180000 retries=0|2,100,63,1.12751013^10.10.102.151^*
12:43:30.693 |//SIP/SIPHandler/ccbId=0/scbId=0/extractAssertedInfo: parseResult[1]|2,100,63,1.12751013^10.10.102.151^*
12:43:30.693 |//SIP/SIPHandler/ccbId=0/scbId=0/convertStackSDPtoCmSDP: ***SIPSdp=4738 bytes|2,100,63,1.12751013^10.10.102.151^*
12:43:30.693 |//SIP/SIPHandler/ccbId=0/scbId=0/copyConnectedInfoAdjustParseResult: !asserted && !rpidHdr|2,100,63,1.12751013^10.10.102.151^*
12:43:30.693 |//SIP/SIPHandler/ccbId=9121031/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|2,100,63,1.12751013^10.10.102.151^*
12:43:30.693 |//SIP/SIPHandler/ccbId=0/scbId=0/parseContactHeader: Incoming Contact=[<sip:10.10.102.151;transport=tcp>]|2,100,63,1.12751013^10.10.102.151^*
12:43:30.693 |//SIP/SIPHandler/ccbId=9121031/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|2,100,63,1.12751013^10.10.102.151^*
12:43:30.693 |//SIP/SIPD(2,73,12)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 39403371 mapKey is 39403371|2,100,63,1.12751013^10.10.102.151^*
12:43:30.693 |//SIP/SIPD(2,73,12)/ccbId=0/scbId=0/getCdpcPidGivenCcbidAndCi: found Cdpc Pid (2,100,74,932828) for mapKey 39403371|2,100,63,1.12751013^10.10.102.151^*
12:43:30.693 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|2,100,63,1.12751013^10.10.102.151^*
12:43:30.693 |//SIP/SIPTcp/wait_SdlSPISignal: Outgoing SIP TCP message to 10.10.102.151 on port 5060 index 653654
[38463573,NET]
ACK sip:10.10.102.151;transport=tcp SIP/2.0
Via: SIP/2.0/TCP 10.10.10.17:5060;branch=z9hG4bK2d2f6a17d0e278
From: <sip:1188@10.10.10.17>;tag=9121031~47d2ff17-3b80-42be-9295-c979ef8581ea-39403371
To: <sip:5101@10.10.102.151>;tag=6adb708-97660a0a-13c4-65014-90769a-78d5ddbd-90769a
Date: Mon, 07 Nov 2016 01:43:30 GMT
Call-ID: 94761280-81f1dc42-16c474-110a0a0a@10.10.10.17
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: presence
Content-Length: 0

|2,100,63,1.12751013^10.10.102.151^*
12:43:30.693 |//SIP/SIPD(2,73,12)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 39403371 mapKey is 39403371|2,100,63,1.12751013^10.10.102.151^*
12:43:30.693 |//SIP/SIPD(2,73,12)/ccbId=0/scbId=0/getCdpcPidGivenCcbidAndCi: found Cdpc Pid (2,100,74,932828) for mapKey 39403371|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/getIdentityIncomingSIPMsg: fIdParseRes=[0], fRemoteNum[5101], fRemoteNam[], fRemoteHostname[], fRemoteNumPi[1], fRemoteNamPi[1], fRemoteSi[0]|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |setEndpointsDtmfCaps: Detected inband DTMF support.|*^*^*
12:43:30.694 |SIP DTMF Info: mLocalDtmfCaps...UNSOL=0, KPML=0, Inband=1(0) mEndppointsDtmfCaps...UNSOL=0, KPML=0, Inband=1(101) mDefaultTelephonyEvent=101, mDtmfPreference=3, mMtpAllocated=0|*^*^*
12:43:30.694 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/getXCiscoViPRFallbackIDAndDTMFKey: Device type 8, Pstn Fallback is not enabled|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/compareAndUpdateMedia: sdpStatus=1, CMEndPointSDP role=1, SIPEndPointSdpRole=2|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/getDefCcRegister: Secure status=1, mSrtpPresent=0|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/handleSIPUACSessionExpires: isMidCall[0], response[200], method[102]|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/parseSessionExpires: refresh_interval[1800], refresher[uas]|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/setSIPSessionExpiresTimer: interval[1768] secs|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/handleSecureRec: enforce srtp flag: 0, remote end srtp support: 0|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/updateCNToCC: identityCngFlag[0x0], isConnInfoInd[1], ccContactHeader[]|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/handleSIPConnectInd: Exit with state = outCall_200Rcvd|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/setSIPUpdateFlags: mIsUpdateForSignalingAllowed = 1 mIsUpdateForMediaAllowed = 1 mPendingOutgoingUpdate = 0|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPD(2,73,12)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 39403371 mapKey is 39403371|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPD(2,73,12)/ccbId=0/scbId=0/getCdpcPid: found Cdpc Pid (2,100,74,932828) for mapKey 39403371|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/processOutgoingUserAgentHeader: UAPT: passthrough value is not present|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/transformConnectedNumber: device Call_Signature , CSS = , useDPCss[cg=1, conn=1], connPartyNum=1188|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/processLocalIdentityInfo: identityFlag=[Unchanged]|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/getCcSignalCnNameAndNumberInfo: identityFlag=0x0 host:|2,100,63,1.12751013^10.10.102.151^*
12:43:30.694 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/getCcSignalCnNameAndNumberInfo: URLPT-getCC: host: lHost: uri-pt:0 trunk:1|2,100,63,1.12751013^10.10.102.151^*
12:43:30.695 |SIPSocketProtocol(2,100,13,540929)::handleWriteComplete|*^*^*
12:43:30.713 |//SIP/SIPD(2,73,12)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 39403371 mapKey is 39403371|2,100,13,540916.52^172.25.3.55^SEPfeedfeedfeed
12:43:30.713 |//SIP/SIPD(2,73,12)/ccbId=0/scbId=0/getCdpcPid: found Cdpc Pid (2,100,74,932828) for mapKey 39403371|2,100,13,540916.52^172.25.3.55^SEPfeedfeedfeed
12:43:30.713 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/processOutgoingUserAgentHeader: UAPT: passthrough value is not present|2,100,13,540916.52^172.25.3.55^SEPfeedfeedfeed
12:43:30.713 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/getLocalNotifyReq: |2,100,13,540916.52^172.25.3.55^SEPfeedfeedfeed
12:43:30.714 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/transformConnectedNumber: device Call_Signature , CSS = , useDPCss[cg=1, conn=1], connPartyNum=|2,100,13,540916.52^172.25.3.55^SEPfeedfeedfeed
12:43:30.714 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/processLocalIdentityInfo: num=,localCnNum=1188;numPi=0,localCnNumPi=1;name=,localCnName=;NamePi=0,localCnNamPi=1;|2,100,13,540916.52^172.25.3.55^SEPfeedfeedfeed
12:43:30.714 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/processLocalIdentityInfo: identityFlag=[Changed:- Num;Num PI;Name;Name PI;]|2,100,13,540916.52^172.25.3.55^SEPfeedfeedfeed
12:43:30.714 |(isHeldOrHolding): holder=0,holdee=0,mh=0|2,100,13,540916.52^172.25.3.55^SEPfeedfeedfeed
12:43:30.714 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/updateDisplayInfoAndSendMsg: mSrtpPresent 0 prevSecurity 0 mSecurity 1 media-hold 0 mDialogDirection 1 Configured status 1 msgType 4 devType 8|2,100,13,540916.52^172.25.3.55^SEPfeedfeedfeed
12:43:30.714 |//SIP/SIPCdpc(2,74,932828)/ci=39403371/ccbId=9121031/scbId=0/processOutgoingUserAgentHeader: UAPT: passthrough value is not present|2,100,13,540916.52^172.25.3.55^SEPfeedfeedfeed
12:43:30.746 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=875|2,100,63,1.12751014^10.10.66.174^*
12:43:30.746 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 10.10.66.174 on port 51735 index 219640 with 875 bytes:
[0,NET]

Non-working Logs

INVITE sip:5101@10.10.102.151:5060 SIP/2.0
Via: SIP/2.0/TCP 10.10.10.17:5060;branch=z9hG4bK2d2ff0463f4f3d
From: <sip:1188@10.10.10.17>;tag=9121248~47d2ff17-3b80-42be-9295-c979ef8581ea-39403513
To: <sip:5101@10.10.102.151>
Date: Mon, 07 Nov 2016 01:46:33 GMT
Call-ID: 189a800-81f1dcf9-16c491-110a0a0a@10.10.10.17
Supported: 100rel,timer,resource-priority,replaces
Min-SE: 1800
User-Agent: Cisco-CUCM8.6
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
CSeq: 101 INVITE
Expires: 180
Allow-Events: presence
Supported: X-cisco-srtp-fallback,X-cisco-original-called
Cisco-Guid: 0025798656-0000065536-0000163756-0285870602
Session-Expires: 1800
P-Asserted-Identity: <sip:1188@10.10.10.17>
Remote-Party-ID: <sip:1188@10.10.10.17>;party=calling;screen=yes;privacy=off
Contact: <sip:1188@10.10.10.17:5060;transport=tcp>
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 313

v=0
o=CiscoSystemsCCM-SIP 9121248 1 IN IP4 10.10.10.17
s=SIP Call
c=IN IP4 172.25.3.55
b=TIAS:16000
b=AS:16
t=0 0
m=audio 24586 RTP/AVP 116 18 101
a=rtpmap:116 iLBC/8000
a=ptime:20
a=maxptime:60
a=fmtp:116 mode=20
a=rtpmap:18 G729/8000
a=ptime:20
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
|2,100,13,540933.116^172.25.3.55^SEPfeedfeedfeed
12:46:33.561 |setEndpointsDtmfCaps: Detected inband DTMF support.|*^*^*
12:46:33.561 |SIP DTMF Info: mLocalDtmfCaps...UNSOL=1, KPML=0, Inband=0(0) mEndppointsDtmfCaps...UNSOL=0, KPML=0, Inband=1(101) mDefaultTelephonyEvent=101, mDtmfPreference=3, mMtpAllocated=0|*^*^*
12:46:33.561 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/getDefAe: SIPCdpc=932883, nodeId=2, processNumber=73 ci=39403516, branch=0|2,100,63,1.12751427^10.2.255.137^*
12:46:33.561 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/getIdentityIncomingSIPMsg: fIdParseRes=[1], fRemoteNum[anonymous], fRemoteNam[Interaction Center], fRemoteHostname[], fRemoteNumPi[1], fRemoteNamPi[1], fRemoteSi[0]|2,100,63,1.12751427^10.2.255.137^*
12:46:33.561 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/preProcessOffer: audiomLines[0]: rtp=1,srtp=0,deviceNOTSrtpCapable=1,numCrypto=0,isOfferFromCCM=0,sipEndpointCanRcvSrtpOffers=0,deviceSupportsSrtpFB=0,telephone-event=101|2,100,63,1.12751427^10.2.255.137^*
12:46:33.561 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/getDefCcRegister: Secure status=1, mSrtpPresent=0|2,100,63,1.12751427^10.2.255.137^*
12:46:33.561 |//SIP/SIPCdpc(0,0,0)/ci=0/ccbId=0/scbId=0/globalize: Performing stripAndPrependDigits --- Prefix data = , Strip Data = 0|*^*^*
12:46:33.561 |//SIP/SIPCdpc(0,0,0)/ci=0/ccbId=0/scbId=0/globalize: CallingNumber after stripAndPrependDigits anonymous|*^*^*
12:46:33.561 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/getDefCcSetupInd: No Replaces header is Present|2,100,63,1.12751427^10.2.255.137^*
12:46:33.561 |CMSIPUtility::decodeRPHdr - no RP Header found, defaulting to to -000000.0|*^*^*
12:46:33.561 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/extractGuidHeader: guid Header = 0189A8000001000000027FAD110A0A0A|2,100,63,1.12751427^10.2.255.137^*
12:46:33.561 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/inCall_waitRSVPRes_PolicyAndRSVPRegisterRes: offAns: sip/cm=1/-1|2,100,63,1.12751427^10.2.255.137^*
12:46:33.562 |SIPSocketProtocol(2,100,13,540936)::handleWriteComplete|*^*^*
12:46:33.563 |SMDMSharedData::findAliasRegInfo - AliasName = 3c09a0a8-8c82-4aed-0444-ba0943c39ec6 not in AliasInfo hashmap|2,100,63,1.12751427^10.2.255.137^*
12:46:33.563 |DeviceManager::star_DmPidReq - RequestedName=3c09a0a8-8c82-4aed-0444-ba0943c39ec6 LookupName=3c09a0a8-8c82-4aed-0444-ba0943c39ec6|2,100,63,1.12751427^10.2.255.137^*
12:46:33.563 |SMDMSharedData::findLocalDevice - Name=1362:9ef70f16-3a61-3d65-c079-1ab62b6717c2 Key=3c09a0a8-8c82-4aed-0444-ba0943c39ec6 isActvie=1 Pid=(2,164,459) found|2,100,63,1.12751427^10.2.255.137^*
12:46:33.563 |//SIP/SIPD(2,73,2)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 39403516 mapKey is 39403516|2,100,63,1.12751427^10.2.255.137^*
12:46:33.563 |//SIP/SIPD(2,73,2)/ccbId=0/scbId=0/getCdpcPid: found Cdpc Pid (2,100,74,932883) for mapKey 39403516|2,100,63,1.12751427^10.2.255.137^*
12:46:33.563 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/transformConnectedNumber: device ININ_CaaS , CSS = , useDPCss[cg=1, conn=1], connPartyNum=1362|2,100,63,1.12751427^10.2.255.137^*
12:46:33.563 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/getCcSignalCnNameAndNumberInfo: identityFlag=0xff host:|2,100,63,1.12751427^10.2.255.137^*
12:46:33.563 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/setCurrentLocalHostname: URLPT-setLHost: host: lHost: uri-pt:0 trunk:1|2,100,63,1.12751427^10.2.255.137^*
12:46:33.563 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/setCurrentLocalHostname: URLPT-setLHost: host: lHost: uri-pt:0 trunk:1|2,100,63,1.12751427^10.2.255.137^*
12:46:33.563 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/getCcSignalCnNameAndNumberInfo: URLPT-getCC: host: lHost: uri-pt:0 trunk:1|2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPTcp/wait_SdlSPISignal: Outgoing SIP TCP message to 10.2.255.137 on port 58942 index 652787
[38464966,NET]
SIP/2.0 100 Trying
Via: SIP/2.0/TCP 10.2.255.137;branch=z9hG4bKOzncqngcl6tcfnO75A6z
From: "Interaction Center" <sip:anonymous@caas.local:5060>;tag=psx5yby
To: <sip:1362@10.10.10.17:5060>
Date: Mon, 07 Nov 2016 01:46:33 GMT
Call-ID: 17f04e84dc2c2687ef2d59040c544fc7@10.2.255.137
CSeq: 1 INVITE
Allow-Events: presence
Content-Length: 0

|2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPD(2,73,2)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 39403516 mapKey is 39403516|2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPD(2,73,2)/ccbId=0/scbId=0/getCdpcPid: found Cdpc Pid (2,100,74,932883) for mapKey 39403516|2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/transformConnectedNumber: device ININ_CaaS , CSS = , useDPCss[cg=1, conn=1], connPartyNum=1362|2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/getCcSignalCnNameAndNumberInfo: identityFlag=0xff host:|2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/setCurrentLocalHostname: URLPT-setLHost: host: lHost: uri-pt:0 trunk:1|2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/setCurrentLocalHostname: URLPT-setLHost: host: lHost: uri-pt:0 trunk:1|2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/getCcSignalCnNameAndNumberInfo: URLPT-getCC: host: lHost: uri-pt:0 trunk:1|2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPCdpc(2,74,932883)/ci=0/ccbId=9121249/scbId=0/processGeolocationInfoFromCC: result of compareGeolocations complete[4], filter[4]|2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/recordOriginalCn:
mOriginalCnName: mOriginalCnNumber: 1362 featureId: 0
|2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/getCcAlertReqInfo: CcCallInfo Amber Purnell|2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/processOutgoingServerHeader: SRVPT: passthrough value is not present|2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/setCnNameAndNumber: connectedData - Name Amber Purnell Number 1362 host NumberPi 1 NamePi 1 Si 1, LocalData - NumberPi 1 NamePi 0 Host |2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/setIdentityOutgoingSIPMsg: identityFlag=[PAI;RPID;], privacyType[0]|2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/addTransparencyInfo: Transparency info is NULL. Not attaching anything.|2,100,63,1.12751427^10.2.255.137^*
12:46:33.567 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/appendRPIDHdrForOriginalCalledParty: SIP device does not Support Orig Dialled Phone nego: 0|2,100,63,1.12751427^10.2.255.137^*
12:46:33.568 |//SIP/SIPHandler/ccbId=0/scbId=0/setPresentationIndication: namePi[1], numPi[1]|2,100,63,1.12751427^10.2.255.137^*
12:46:33.568 |//SIP/SIPHandler/ccbId=0/scbId=0/setScreeningIndication: si[1], oct3a[81]|2,100,63,1.12751427^10.2.255.137^*
12:46:33.568 |//SIP/SIPHandler/ccbId=0/scbId=0/setIdentityHeaders: ccb[ae4e3f50], currLocalNum[1362], currLocalNam[Amber Purnell], callbackNumber[], localNumPi[1], localNamPi[1], id.flag[10], id.privacy[0] host[]|2,100,63,1.12751427^10.2.255.137^*
12:46:33.568 |//SIP/SIPHandler/ccbId=0/scbId=0/setRpidHeader: rpidHdrStr["Amber Purnell" <sip:1362@10.10.10.17>;party=called;screen=yes;privacy=off]|2,100,63,1.12751427^10.2.255.137^*
12:46:33.568 |//SIP/SIPHandler/ccbId=9121249/scbId=0/addContactHeader: Outgoing Contact=[<sip:1362@10.10.10.17:5060;transport=tcp>]|2,100,63,1.12751427^10.2.255.137^*
12:46:33.568 |//SIP/SIPHandler/ccbId=9121249/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|2,100,63,1.12751427^10.2.255.137^*
12:46:33.568 |//SIP/SIPHandler/ccbId=9121249/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|2,100,63,1.12751427^10.2.255.137^*
12:46:33.568 |//SIP/SIPD(2,73,2)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 39403516 mapKey is 39403516|2,100,63,1.12751427^10.2.255.137^*
12:46:33.568 |//SIP/SIPD(2,73,2)/ccbId=0/scbId=0/getCdpcPidGivenCcbidAndCi: found Cdpc Pid (2,100,74,932883) for mapKey 39403516|2,100,63,1.12751427^10.2.255.137^*
12:46:33.568 |//SIP/SIPD(2,73,2)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 39403516 mapKey is 39403516|2,100,63,1.12751427^10.2.255.137^*
12:46:33.568 |//SIP/SIPD(2,73,2)/ccbId=0/scbId=0/getCdpcPidGivenCcbidAndCi: found Cdpc Pid (2,100,74,932883) for mapKey 39403516|2,100,63,1.12751427^10.2.255.137^*
12:46:33.568 |//SIP/SIPCdpc(2,74,932883)/ci=39403516/ccbId=9121249/scbId=0/setSIPUpdateFlags: mIsUpdateForSignalingAllowed = 1 mIsUpdateForMediaAllowed = 0 mPendingOutgoingUpdate = 0|2,100,63,1.12751427^10.2.255.137^*
12:46:33.568 |SIPSocketProtocol(2,100,13,540936)::handleReadComplete send SdlReadRsp: size 473|*^*^*
12:46:33.568 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=473|2,100,63,1.12751428^10.10.102.151^*
12:46:33.568 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 10.10.102.151 on port 5060 index 653660 with 473 bytes:
[38464969,NET]
SIP/2.0 488 Not Acceptable Here
From: <sip:1188@10.10.10.17>;tag=9121248~47d2ff17-3b80-42be-9295-c979ef8581ea-39403513
To: <sip:5101@10.10.102.151>;tag=6add848-97660a0a-13c4-65014-907751-713266ca-907751
Call-ID: 189a800-81f1dcf9-16c491-110a0a0a@10.10.10.17
CSeq: 101 INVITE
Via: SIP/2.0/TCP 10.10.10.17:5060;branch=z9hG4bK2d2ff0463f4f3d
Supported: replaces,timer
User-Agent: Salmat-FirstContact-10.3
Contact: <sip:10.10.102.151;transport=tcp>
Content-Length: 0

|2,100,63,1.12751428^10.10.102.151^*
12:46:33.568 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 12751426|2,100,63,1.12751428^10.10.102.151^*
12:46:33.568 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|2,100,63,1.12751427^10.2.255.137^*
12:46:33.568 |//SIP/SIPTcp/wait_SdlSPISignal: Outgoing SIP TCP message to 10.2.255.137 on port 58942 index 652787
[38464968,NET]

Thanks to all.