Cisco Support Community
cancel
Showing results for 
Search instead for 
Did you mean: 
Announcements

Welcome to Cisco Support Community. We would love to have your feedback.

For an introduction to the new site, click here. And see here for current known issues.

New Member

Cisco 8.6 direct trunk to Exchange 2010 - number unassigned

Trying to integrated MSEX 2010 for UM with CUCM 8.6.  So far the trunk works in that the auto attendant and VM subscriber prompts can be reached from ip phone on Cisco cluster.  But any outdialing over the trunk from the MSEX to the CUCM cluster fails (captured via RTMT) with:

(1) Unallocated (unassigned) number. Explanation: The number requested by the calling number cannot be reached because it is not currently assigned to any user in the network.

While the human readability of this error message is appreciated, I don't know what to do from here.  This particular message was elicited from the CUCM by placing a test SIP call on the MSEX 2010 box using Microsoft's UM troubleshooting tool:

http://blogs.technet.com/b/exchange/archive/2011/02/10/3411979.aspx

While the dialed extension is most certainly alive and ready as a skinny extension in the Callmanager cluster and visible in the CSS assigned to the trunk, it obviously isn't happy with something.  Any help is appreciated.

Thanks,

-mike

6 REPLIES

Cisco 8.6 direct trunk to Exchange 2010 - number unassigned

Do you know what Callmanager sees as the Called number? CallManager traces should tell you what it receives from Exchange as far as called number and please make sure this is available in the CSS that is assigned under the "Inbound calls" section of the trunk.

Please rate useful posts.

Please rate useful posts.
New Member

Cisco 8.6 direct trunk to Exchange 2010 - number unassigned

George,

Sure.  I am watching the traces in RTMT.  The called DN is 1500 (My IPC).  It is in a partition that is in the inbound call CSS on the trunk.  That was the first thing I checked when I saw this message.

Re:Cisco 8.6 direct trunk to Exchange 2010 - number unassigned

Will you be able to post the SIP messages from CUCM or even better the CM traces.


Sent from Cisco Technical Support Android App

Please rate useful posts.
New Member

Re:Cisco 8.6 direct trunk to Exchange 2010 - number unassigned

17:20:10.921 |//SIP/SIPTcp/wait_SdlConnectionInd: Connection Indication - Listen Port = 5060, Peer Port = 41231|1,100,13,1555.2^*^*

17:20:10.921 |SdiStats: #Lines: 61 Free HWM: 49 Free HWM(total): 207|*^*^*

17:20:10.921 |//SIP/SIPTcp/wait_SdlConnectionInd: New Connection - Index = 1517, Listen Port = 5060, Peer Port = 41231|1,100,13,1555.2^*^*

17:20:10.921 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 56|1,100,13,1555.2^*^*

17:20:10.921 |//SIP/Stack/Transport/0x0/context=(nil)|1,100,13,1555.2^*^*

17:20:10.921 |//SIP/Stack/Transport/0x0/gConnTab=0xf2479b8, addr=10.121.2.23, port=41231, connid=1517, transport=TCP|1,100,13,1555.2^*^*

17:20:10.921 |//SIP/Stack/Transport/0x0/Created new accptd conn=0xf248db8, connid=1517, addr=10.121.2.23, port=41231, transport=TCP|1,100,13,1555.2^*^*

17:20:10.926 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=1502|1,100,63,1.2257^10.121.2.23^*

17:20:10.926 |//SIP/Stack/Error/0x0/DROPPING unregistered header PRIORITY: Normal|1,100,71,1.1^*^*

17:20:10.926 |//SIP/Stack/Error/0x0/DROPPING unregistered header msexum-diagtool: DiagnosticToolCall|1,100,71,1.1^*^*

17:20:10.926 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 10.121.2.23 on port 41231 index 1517 with 1502 bytes:

[4600,NET]

INVITE sip:Anonymous@10.121.30.10 SIP/2.0

FROM: "";epid=EE49EAF11C;tag=9b396feb31

TO:

CSEQ: 1 INVITE

CALL-ID: 474db23a-4868-4e12-b5ba-82197ac21cbf

MAX-FORWARDS: 70

VIA: SIP/2.0/TCP 10.121.2.23:41231;branch=z9hG4bKad874fdf

CONTACT: ;automata;actor="attendant";text;audio;video;image

CONTENT-LENGTH: 551

EXPIRES: 180

PRIORITY: Normal

SUPPORTED: Replaces

SUPPORTED: timer

SUPPORTED: 100rel

USER-AGENT: RTCC/3.5.0.0 MSExchangeUM-Diagnostics

CONTENT-TYPE: application/sdp

ALLOW: ACK

P-ASSERTED-IDENTITY:

Diversion: <1500>

msexum-diagtool: DiagnosticToolCall

Content-ID: 85188756-09b5-4e6f-9c35-8f6a7ae92314

Session-Expires: 1800

Min-SE: 90

Allow: CANCEL,BYE,INVITE,MESSAGE,INFO,SERVICE,OPTIONS,BENOTIFY,NOTIFY,PRACK,UPDATE

v=0

o=- 2 0 IN IP4 10.121.2.23

s=session

c=IN IP4 10.121.2.23

b=CT:1000

t=0 0

m=audio 1066 RTP/AVP 112 111 116 3 4 0 8 13 118 97 101

c=IN IP4 10.121.2.23

a=rtcp:1067

a=sendrecv

a=label:main-audio

a=rtpmap:112 G7221/16000

a=fmtp:112 bitrate=24000

a=rtpmap:111 SIREN/16000

a=fmtp:111 bitrate=16000

a=rtpmap:116 AAL2-G726-32/8000

a=rtpmap:3 GSM/8000

a=rtpmap:4 G723/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:13 CN/8000

a=rtpmap:118 CN/16000

a=rtpmap:97 RED/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16,36

|1,100,63,1.2257^10.121.2.23^*

17:20:10.926 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 2255|1,100,63,1.2257^10.121.2.23^*

17:20:10.926 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|1,100,63,1.2257^10.121.2.23^*

17:20:10.926 |//SIP/Stack/Transport/0x0/context=(nil)|1,100,63,1.2257^10.121.2.23^*

17:20:10.926 |//SIP/Stack/Transport/0x0/gConnTab=0xf2479b8, addr=10.121.2.23, port=41231, connid=1517, transport=TCP|1,100,63,1.2257^10.121.2.23^*

17:20:10.926 |//SIP/Stack/Info/0x0/Return existing connection for port 41231 connId 1517|1,100,63,1.2257^10.121.2.23^*

17:20:10.926 |//SIP/Stack/Info/0x0/Checking Invite Dialog|1,100,63,1.2257^10.121.2.23^*

17:20:10.926 |//SIP/Stack/Info/0x0/Added context(0xf2a2540) with key=[1637] to table|1,100,63,1.2257^10.121.2.23^*

17:20:10.926 |//SIP/Stack/Transport/0x0/Dialog Transaction Address 10.121.2.23,Port 41231, Transport 2, SentBy Port 41231|1,100,63,1.2257^10.121.2.23^*

17:20:10.926 |//SIP/Stack/States/0xf2a2540/0xf2a2540 : State change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_NONE)|1,100,63,1.2257^10.121.2.23^*

17:20:10.926 |//SIP/Stack/Transport/0x0/Dialog Transaction Address 10.121.2.23,Port 41231, Transport 2, SentBy Port 5060|1,100,63,1.2257^10.121.2.23^*

17:20:10.926 |//SIP/Stack/Transport/0x0/Dialog Transaction Address 10.121.2.23,Port 41231, Transport 2, SentBy Port 41231|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/Stack/Info/0xf2a2540/calling host comes from From: BSDEMAIL02.brinkleyschools.com|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/Stack/Info/0x0/No GTD found in inbound container|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/Stack/Info/0x0/No CSTA found in inbound container|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/Stack/Info/0xf2a2540/****Adding to UAS Request table.|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/Stack/Info/0xf2a2540/Added to table. ccb=0xf2a2540 key=474db23a-4868-4e12-b5ba-82197ac21cbfAnonymous|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/Stack/Info/0xf2a2540/Calling name , number , Calling oct3 0x00, oct_3a 0x80, ext_priv 0x00, Called numbe|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/Stack/Info/0xf2a2540/Carrier id code , prev_cid NONE, next_cid NONE, prev_tgrp NONE, next_tgrp NONE|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/SIPHandler/ccbId=0/scbId=0/sipSPIGetCallExtensionSupported: SIPRel1xxEnabledServiceParamSetting=0 , ccb->pld.outboundRel1xx=1|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_EXPIRES value=180000 retries=0|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_EXPIRES value=180000 retries=0|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/SIPHandler/ccbId=0/scbId=0/checkSyntaxAndSelectAssertedHeader: PAI result [9], total[1], sipUri[1], sipsUri[0], telUri[0]|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/SIPHandler/ccbId=0/scbId=0/printAssertedInfo: remoteNum[], remoteName[], remoteHost[BSDEMAIL02.brinkleyschools.com], remotePort[5060], remoteTransport[1], remoteSchema[1]|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/SIPHandler/ccbId=0/scbId=0/extractAssertedInfo: parseResult[13]|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/SIPHandler/ccbId=0/scbId=0/getCodecAndFramesFromSDP: -- Error!!!!! Cannot convert SDP pt=13 to CM payload type.|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/SIPHandler/ccbId=0/scbId=0/convertStackSDPtoCmSDP: ***SIPSdp=4738 bytes|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/SIPHandler/ccbId=0/scbId=0/_ccsip_api_call_setup_ind: sdppresent? 1 1|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/SIPHandler/ccbId=0/scbId=0/getCalledNumberUri: SIP URL Anonymous@10.121.30.10:5060|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/SIPHandler/ccbId=0/scbId=0/sendSetupInd: bearerless? 1 1|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/SIPHandler/ccbId=0/scbId=0/parseContactHeader: Incoming Contact=[;automata;actor="attendant";text;audio;video;image]|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/SIPD(1,73,28)/ccbId=1652/scbId=0/restart0_SIPSetupInd: mTsp.deviceName[MSEX_trunk]|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |//SIP/SIPD(1,73,28)/ccbId=1652/scbId=0/restart0_SIPSetupInd: mTsp.deviceName[MSEX_trunk], transformDn Anonymous|1,100,63,1.2257^10.121.2.23^*

17:20:10.927 |SPROC:transformViprIncomingNumber -  Number length is zero|*^*^*

17:20:10.928 |DbMobility: getMatchedRemDest starts: cnumber = |*^*^*

17:20:10.928 |DbMobility: getMatchedRemDest: full match case|*^*^*

17:20:10.928 |DbMobility: no cnumber is specified|*^*^*

17:20:10.928 |//SIP/SIPD(1,73,28)/ccbId=1652/scbId=0/restart0_SIPSetupInd: Trunk Type [0] - The matched isIMSCapable =[0]|1,100,63,1.2257^10.121.2.23^*

17:20:10.928 |resetLocalDtmfCaps: mLocalDtmfCaps.oob.kpmlAvail = 0|*^*^*

17:20:10.928 |//SIP/SIPCdpc(1,74,93)/ci=0/ccbId=1652/scbId=0/getDefAe: SIPCdpc=93, nodeId=1, processNumber=73  ci=0, branch=0|1,100,74,93.1^*^*

17:20:10.928 |//SIP/SIPCdpc(1,74,93)/ci=0/ccbId=1652/scbId=0/StartTransition: requireInactiveSDPForMidcallMediaChange=0, isTrunkEnabledForVoiceEO=0, mSipMcuControl 0|1,100,74,93.1^*^*

17:20:10.928 |//SIP/SIPCdpc(1,74,93)/ci=0/ccbId=1652/scbId=0/handleSIPUASSessionExpires: isMidCall[0], method[102]|1,100,63,1.2257^10.121.2.23^*

17:20:10.928 |//SIP/SIPCdpc(1,74,93)/ci=0/ccbId=1652/scbId=0/setSIPSessionExpiresTimer: interval[900] secs|1,100,63,1.2257^10.121.2.23^*

17:20:10.928 |//SIP/SIPCdpc(1,74,93)/ci=0/ccbId=1652/scbId=0/processIncomingGeolocationInfo: no Geolocation support|1,100,63,1.2257^10.121.2.23^*

17:20:10.928 |//SIP/SIPCdpc(1,74,93)/ci=0/ccbId=1652/scbId=0/preProcessOffer: audiomLines[0]: rtp=1,srtp=0,deviceNOTSrtpCapable=1,numCrypto=0,isOfferFromCCM=0,sipEndpointCanRcvSrtpOffers=0,deviceSupportsSrtpFB=0,telephone-event=101|1,100,63,1.2257^10.121.2.23^*

17:20:10.928 |//SIP/SIPCdpc(1,74,93)/ci=0/ccbId=1652/scbId=0/updateAllowedMethods: mEndpointAllowedMethods=04ff|1,100,63,1.2257^10.121.2.23^*

17:20:10.928 |setEndpointsDtmfCaps: Detected inband DTMF support.|*^*^*

17:20:10.928 |SIP DTMF Info: mLocalDtmfCaps...UNSOL=1, KPML=0, Inband=0(0) mEndppointsDtmfCaps...UNSOL=0, KPML=0, Inband=1(101) mDefaultTelephonyEvent=101, mDtmfPreference=1, mMtpAllocated=0|*^*^*

17:20:10.928 |//SIP/SIPD(1,73,28)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 31126269  mapKey is 31126269|1,100,63,1.2257^10.121.2.23^*

17:20:10.928 |//SIP/SIPD(1,73,28)/ccbId=1652/scbId=0/restart0_CcCiRes: Adding Cdpc Pid (1,100,74,93) with  mapKey 31126269 and branch 0 to mCiToPid table|1,100,63,1.2257^10.121.2.23^*

17:20:10.928 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/isMidCallFallbackSupported:  getXCiscoViPRFallbackID returned false, proceed without PSTN fallback|1,100,63,1.2257^10.121.2.23^*

17:20:10.928 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/getVideoAndDataCapable: updated videocap=1|1,100,63,1.2257^10.121.2.23^*

17:20:10.928 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/getDefAe: SIPCdpc=93, nodeId=1, processNumber=73  ci=31126269, branch=0|1,100,63,1.2257^10.121.2.23^*

17:20:10.928 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/sendPolicyAndRSVPRegisterReq: capCount[5], videoCap[1], dataCap[2], earlyOffer[2], latCaps[NULL]|1,100,63,1.2257^10.121.2.23^*

17:20:10.928 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/sendPolicyAndRSVPRegisterReq: preconditions: Not using preconditions, rsvpStatus[34] sdp[1]|1,100,63,1.2257^10.121.2.23^*

17:20:10.929 |setEndpointsDtmfCaps: Detected inband DTMF support.|*^*^*

17:20:10.929 |SIP DTMF Info: mLocalDtmfCaps...UNSOL=1, KPML=0, Inband=0(0) mEndppointsDtmfCaps...UNSOL=0, KPML=0, Inband=1(101) mDefaultTelephonyEvent=101, mDtmfPreference=1, mMtpAllocated=0|*^*^*

17:20:10.929 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/getDefAe: SIPCdpc=93, nodeId=1, processNumber=73  ci=31126269, branch=0|1,100,63,1.2257^10.121.2.23^*

17:20:10.929 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/getIdentityIncomingSIPMsg: fIdParseRes=[13], fRemoteNum[], fRemoteNam[], fRemoteHostname[], fRemoteNumPi[1], fRemoteNamPi[1], fRemoteSi[3]|1,100,63,1.2257^10.121.2.23^*

17:20:10.929 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/processRemoteIdentityInfo: identityFlag=[Unchanged]|1,100,63,1.2257^10.121.2.23^*

17:20:10.929 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/preProcessOffer: audiomLines[0]: rtp=1,srtp=0,deviceNOTSrtpCapable=1,numCrypto=0,isOfferFromCCM=0,sipEndpointCanRcvSrtpOffers=0,deviceSupportsSrtpFB=0,telephone-event=101|1,100,63,1.2257^10.121.2.23^*

17:20:10.929 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/getDefCcRegister: Secure status=1, mSrtpPresent=0|1,100,63,1.2257^10.121.2.23^*

17:20:10.929 |CMSIPUtility: applyNumberTransformation SigDigitsLen 99 IsSigDigitsEnabled 0 |*^*^*

17:20:10.929 |CMSIPUtility: applyNumberTransformation PrefixDigits  |*^*^*

17:20:10.929 |CMSIPUtility: applyNumberTransformation the number before transformation is 1500|*^*^*

17:20:10.929 |CMSIPUtility: applyNumberTransformation the number after transformation is 1500|*^*^*

17:20:10.929 |CMSIPUtility: convertAndApplyTransformation: Tel Url transformation is done|*^*^*

17:20:10.929 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/getDefCcSetupInd: Original diversion cdpn 1500|1,100,63,1.2257^10.121.2.23^*

17:20:10.929 |CMSIPUtility: applyNumberTransformation SigDigitsLen 99 IsSigDigitsEnabled 0 |*^*^*

17:20:10.929 |CMSIPUtility: applyNumberTransformation PrefixDigits  |*^*^*

17:20:10.929 |CMSIPUtility: applyNumberTransformation the number before transformation is 1500|*^*^*

17:20:10.929 |CMSIPUtility: applyNumberTransformation the number after transformation is 1500|*^*^*

17:20:10.929 |CMSIPUtility: convertAndApplyTransformation: Tel Url transformation is done|*^*^*

17:20:10.929 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/getDefCcSetupInd: Last diversion cdpn 1500|1,100,63,1.2257^10.121.2.23^*

17:20:10.929 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/getDefCcSetupInd: No Replaces header is Present|1,100,63,1.2257^10.121.2.23^*

17:20:10.929 |//SIP/SIPCdpc(1,74,93)/ci=0/ccbId=1652/scbId=0/populateIVRFeatureData: SIPCdpc - before parsing IVRDN. cdpn=Anonymous cgpn= rdn=|1,100,63,1.2257^10.121.2.23^*

17:20:10.929 |CMSIPUtility::decodeRPHdr - no RP Header found, defaulting to to -000000.0|*^*^*

17:20:10.929 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/extractGuidHeader: guid Header = B04BCE80000100000000005D0A1E790A|1,100,63,1.2257^10.121.2.23^*

17:20:10.929 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/inCall_waitRSVPRes_PolicyAndRSVPRegisterRes: offAns: sip/cm=1/-1|1,100,63,1.2257^10.121.2.23^*

17:20:10.930 |//SIP/SIPD(1,73,28)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 31126269  mapKey is 31126269|1,100,63,1.2257^10.121.2.23^*

17:20:10.930 |//SIP/SIPD(1,73,28)/ccbId=0/scbId=0/getCdpcPid: found Cdpc Pid (1,100,74,93) for mapKey 31126269|1,100,63,1.2257^10.121.2.23^*

17:20:10.930 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/appendReasonHdr: appendReasonHdr - Invalid Disconnect Cause(cause=1), No Reason Header Appended|1,100,63,1.2257^10.121.2.23^*

17:20:10.930 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/addTransparencyInfo: Transparency info is NULL.  Not attaching anything.|1,100,63,1.2257^10.121.2.23^*

17:20:10.930 |//SIP/Stack/Info/0xf2a2540/ccsip_api_call_setup_ind returned: SIP_SUCCESS|1,100,63,1.2257^10.121.2.23^*

17:20:10.930 |//SIP/Stack/Info/0xf2a2540/Adding call id 674 to table|1,100,63,1.2257^10.121.2.23^*

17:20:10.930 |//SIP/Stack/Transport/0xf2a2540/msg=0xb141e6c8, addr=10.121.2.23, port=41231, sentBy_port=41231, is_req=0, transport=2, switch=0, callBack=|1,100,63,1.2257^10.121.2.23^*

17:20:10.930 |//SIP/Stack/Transport/0xf2a2540/Proceedable for sending msg immediately|1,100,63,1.2257^10.121.2.23^*

17:20:10.930 |//SIP/Stack/Transport/0xf2a2540/switch transport is 0|1,100,63,1.2257^10.121.2.23^*

17:20:10.930 |//SIP/Stack/Transport/0xf2a2540/Trying to send resp=0xb141e6c8 to default port=41231|1,100,63,1.2257^10.121.2.23^*

17:20:10.930 |//SIP/Stack/Transport/0x0/Registering gcb=0xf2a2540 with connection=0xf248db8 context list|1,100,63,1.2257^10.121.2.23^*

17:20:10.930 |//SIP/Stack/Transport/0xf2a2540/Connection obtained...sending msg=0xb141e6c8|1,100,63,1.2257^10.121.2.23^*

17:20:10.930 |//SIP/Stack/Transport/0x0/Posting send for msg=0xb141e6c8, addr=10.121.2.23, port=41231, connId=1517 for TCP|1,100,63,1.2257^10.121.2.23^*

17:20:10.930 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|1,100,63,1.2257^10.121.2.23^*

17:20:10.930 |//SIP/SIPTcp/wait_SdlSPISignal: Outgoing SIP TCP message to 10.121.2.23 on port 41231 index 1517

[4601,NET]

SIP/2.0 100 Trying

Via: SIP/2.0/TCP 10.121.2.23:41231;branch=z9hG4bKad874fdf

From: "";epid=EE49EAF11C;tag=9b396feb31

To:

Date: Fri, 02 Aug 2013 22:20:10 GMT

Call-ID: 474db23a-4868-4e12-b5ba-82197ac21cbf

CSeq: 1 INVITE

Allow-Events: presence

Content-Length: 0

|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/Stack/States/0xf2a2540/0xf2a2540 : State change from (STATE_IDLE, SUBSTATE_NONE)  to (STATE_RECD_INVITE, SUBSTATE_NONE)|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/Stack/Info/0xf2a2540/Previous Hop 10.121.2.23:41230|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 3 for event 8|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/Stack/Info/0xf2a2540/Performing disconnect|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_EXPIRES value=180000 retries=0|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/Stack/Info/0xf2a2540/****Adding to UAS Response table.|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/Stack/Info/0xf2a2540/Added to table. ccb=0xf2a2540 key=474db23a-4868-4e12-b5ba-82197ac21cbf1652~F8719F4F-D5E4-41FD-8A67-CB670F6D515A-31126269|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/Stack/Info/0xf2a2540/Associated container=0xb169d630 to Invite Response 404|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/Stack/Transport/0xf2a2540/msg=0xb141e6c8, addr=10.121.2.23, port=41231, sentBy_port=41231, is_req=0, transport=2, switch=0, callBack=|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/Stack/Transport/0xf2a2540/Proceedable for sending msg immediately|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/Stack/Transport/0xf2a2540/switch transport is 0|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/Stack/Transport/0xf2a2540/Trying to send resp=0xb141e6c8 to default port=41231|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/Stack/Transport/0x0/gcb=0xf2a2540 is already on connection=0xf248db8 context_list|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/Stack/Transport/0xf2a2540/Connection obtained...sending msg=0xb141e6c8|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/Stack/Transport/0x0/Posting send for msg=0xb141e6c8, addr=10.121.2.23, port=41231, connId=1517 for TCP|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/SIPTcp/wait_SdlSPISignal: Outgoing SIP TCP message to 10.121.2.23 on port 41231 index 1517

[4602,NET]

SIP/2.0 404 Not Found

Via: SIP/2.0/TCP 10.121.2.23:41231;branch=z9hG4bKad874fdf

From: "";epid=EE49EAF11C;tag=9b396feb31

To: ;tag=1652~f8719f4f-d5e4-41fd-8a67-cb670f6d515a-31126269

Date: Fri, 02 Aug 2013 22:20:10 GMT

Call-ID: 474db23a-4868-4e12-b5ba-82197ac21cbf

CSeq: 1 INVITE

Allow-Events: presence

Reason: Q.850;cause=1

Content-Length: 0

|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/Stack/Info/0xf2a2540/Sent an 3456XX Error Response|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/Stack/States/0xf2a2540/0xf2a2540 : State change from (STATE_RECD_INVITE, SUBSTATE_NONE)  to (STATE_DISCONNECTING, SUBSTATE_NONE)|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_CONNECT value=500 retries=6|1,100,63,1.2257^10.121.2.23^*

17:20:10.931 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_CONNECT value=500 retries=6|1,100,63,1.2257^10.121.2.23^*

17:20:10.932 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=364|1,100,63,1.2258^10.121.2.23^*

17:20:10.932 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 10.121.2.23 on port 41231 index 1517 with 364 bytes:

[4603,NET]

ACK sip:Anonymous@10.121.30.10 SIP/2.0

FROM: "";tag=9b396feb31;epid=EE49EAF11C

TO: ;tag=1652~f8719f4f-d5e4-41fd-8a67-cb670f6d515a-31126269

CSEQ: 1 ACK

CALL-ID: 474db23a-4868-4e12-b5ba-82197ac21cbf

MAX-FORWARDS: 70

VIA: SIP/2.0/TCP 10.121.2.23:41231;branch=z9hG4bKad874fdf

CONTENT-LENGTH: 0

|1,100,63,1.2258^10.121.2.23^*

17:20:10.932 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 2256|1,100,63,1.2258^10.121.2.23^*

17:20:10.932 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|1,100,63,1.2258^10.121.2.23^*

17:20:10.932 |//SIP/Stack/Transport/0x0/context=(nil)|1,100,63,1.2258^10.121.2.23^*

17:20:10.932 |//SIP/Stack/Transport/0x0/gConnTab=0xf2479b8, addr=10.121.2.23, port=41231, connid=1517, transport=TCP|1,100,63,1.2258^10.121.2.23^*

17:20:10.932 |//SIP/Stack/Info/0x0/Return existing connection for port 41231 connId 1517|1,100,63,1.2258^10.121.2.23^*

17:20:10.932 |//SIP/Stack/Info/0x0/Checking Invite Dialog|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/Stack/Info/0xf2a2540/*****CCB found in UAS Response table. ccb=0xf2a2540|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/Stack/Transport/0x0/Subsq Transaction Address 10.121.2.23,Port 41231, Transport 2, SentBy Port 5060|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/Stack/Transport/0x0/Subsq Transaction Address 10.121.2.23,Port 41231, Transport 2, SentBy Port 41231|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/Stack/Info/0xf2a2540/calling host comes from From: BSDEMAIL02.brinkleyschools.com|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/SIPHandler/ccbId=1652/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/SIPD(1,73,28)/ccbId=0/scbId=0/restart0_SIPRelCompInd: Ccbid 1652 and Ci 31126269|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |ViprUtils::uploadVCRifRequired VcrUploadNeeded[1] FeatureData NOT added|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/SIPCdpc(1,74,93)/ci=0/ccbId=1652/scbId=0/uploadVCRifRequired: VcrUploadNeeded 1|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/updateCallStatsForCallCompletion: SIPCdpc=93, nodeId=1, processNumber=73|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/SIPCdpc(1,74,93)/ci=31126269/ccbId=1652/scbId=0/terminateOobDtmf: Termination not required.|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/SIPD(1,73,28)/ccbId=1652/scbId=0/restart0_DStopInd: Removing Cdpc Pid (1,100,74,93) with  mapKey 0 from mCiToPid table|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/Stack/States/0xf2a2540/0xf2a2540 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE)  to (STATE_DEAD, SUBSTATE_NONE)|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/Stack/Info/0xf2a2540/Removing call id 674|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/Stack/Info/0x0/Context for key=[1637] removed.|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_CONNECT value=500 retries=6|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/Stack/Info/0xf2a2540/****Deleting from UAS Request table.|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/Stack/Info/0xf2a2540/Deleting from table. ccb=0xf2a2540 key=474db23a-4868-4e12-b5ba-82197ac21cbfAnonymous|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/Stack/Info/0xf2a2540/****Deleting from UAS Response table.|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/Stack/Info/0xf2a2540/Deleting from table. ccb=0xf2a2540 key=474db23a-4868-4e12-b5ba-82197ac21cbf1652~F8719F4F-D5E4-41FD-8A67-CB670F6D515A-31126269|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/Stack/Info/0xf2a2540/There are 0 events on the internal queue that are going to be free'd|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/Stack/Transport/0x0/gConnTab=0xf2479b8, addr=10.121.2.23, port=41231, unregistering context=0xf2a2540|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/Stack/Transport/0x0/Purging context gcb=0xf2a2540 from the connection=0xf248db8 context list|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/Stack/Info/0xf2a2540/Freeing ccb f2a2540|1,100,63,1.2258^10.121.2.23^*

17:20:10.933 |//SIP/Stack/Info/0x0/NO context for key[1637]|1,100,63,1.2258^10.121.2.23^*

17:20:10.998 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 57|1,100,63,1.2258^10.121.2.23^*

17:20:10.998 |//SIP/Stack/Transport/0x0/context=(nil)|1,100,63,1.2258^10.121.2.23^*

17:20:10.998 |//SIP/Stack/Transport/0x0/gConnTab=0xf2479b8, addr=10.121.2.23, port=41231, connid=1517, transport=TCP|1,100,63,1.2258^10.121.2.23^*

17:20:10.998 |//SIP/Stack/Info/0x0/Handling TCP reset for all contexts on connection|1,100,63,1.2258^10.121.2.23^*

17:20:10.998 |//SIP/Stack/Info/0xf248db8/Found gcbs on connection waitq|1,100,63,1.2258^10.121.2.23^*

17:20:10.998 |//SIP/Stack/Transport/0x0/Posting TCP conn close for addr=10.121.2.23, port=41231, connid=1517|1,100,63,1.2258^10.121.2.23^*

17:20:10.998 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|1,100,63,1.2258^10.121.2.23^*

17:20:10.998 |//SIP/SIPTcp/wait_SdlSPISignal: Did not find table entry in Close Msg,Index = 1517|1,100,63,1.2258^10.121.2.23^*

17:20:10.998 |//SIP/Stack/Transport/0x0/Deleted conn=0xf248db8, connid=1517, addr=10.121.2.23, port=41231, transport=TCP|1,100,63,1.2258^10.121.2.23^*

17:20:10.998 |//SIP/SIPHandler/ccbId=0/scbId=0/getRemoteAddrInfo: RemoteAddrInfo = 10.121.2.23:41231|1,100,63,1.2258^10.121.2.23^*

17:20:10.998 |//SIP/SIPD(1,73,28)/ccbId=0/scbId=0/restart0_SIPConnControlInd: mTsp.DeviceName[MSEX_trunk] - Rcvd ConnControl with operation = 1, connIdx = 1517, X.509 Name = |1,100,63,1.2258^10.121.2.23^*

17:20:10.998 |//SIP/SIPD(1,73,28)/ccbId=0/scbId=0/restart0_SIPConnControlInd: mTsp.DeviceName[MSEX_trunk] - Cannot find TLSConnTbl entry, connIdx = 1517, X.509 Name = |1,100,63,1.2258^10.121.2.23^*

Re:Cisco 8.6 direct trunk to Exchange 2010 - number unassigned

Hi Mike,

If you look at the SIP invite, the To address is sip:Anonymous@10.121.30.10 which is not going to work. It should be something like sip:1500@10.121.30.10. The Exchange dial plan will have to be changed appropriately to get this fixed.

Please rate useful posts.

Please rate useful posts.
New Member

Re:Cisco 8.6 direct trunk to Exchange 2010 - number unassigned

Yeah this was generated with the cmd line diag tool.  The production dial plan seems to work fine.  Looks like my collegue may have simply neglected to register a phone with one of the target DNs in the auto attendant.  Thanks for your time.

Cheers,

-mike

435
Views
5
Helpful
6
Replies