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

SIP Call Failing from Lync to CUCM

Can anyone let me know cause of call failure.

Form CUCM to Lync ..call not going

From Lync to CUCM ---going ..

Call Failure Logs :

INVITE sip:5005@192.168.59.241:5060 SIP/2.0

Via: SIP/2.0/TCP 10.131.20.116:5060;branch=z9hG4bKd0550d3e93

From: <sip:1008@10.131.20.116>;tag=37920~e9a11a5c-9713-4aec-bb7c-e8142e2ff402-24023303

To: <sip:5005@192.168.59.241>

Date: Mon, 05 Aug 2013 07:25:14 GMT

Call-ID: 2a39ae80-1ff1535a-96-7414830a@10.131.20.116

Supported: 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

Supported: Geolocation

Cisco-Guid: 0708423296-0000065536-0000000301-1947501322

Session-Expires:  1800

P-Asserted-Identity: <sip:1008@10.131.20.116>

Remote-Party-ID: <sip:1008@10.131.20.116>;party=calling;screen=yes;privacy=off

Contact: <sip:1008@10.131.20.116:5060;transport=tcp>

Max-Forwards: 70

Content-Type: application/sdp

Content-Length: 215

v=0

o=CiscoSystemsCCM-SIP 37920 1 IN IP4 10.131.20.116

s=SIP Call

c=IN IP4 10.131.20.116

t=0 0

m=audio 25404 RTP/AVP 0 101

a=rtpmap:0 PCMU/8000

a=ptime:20

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

|1,100,56,1.467195^10.131.20.116^MTP_2

12:55:14.247 |SIPSocketProtocol(1,100,13,38255)::handleWriteComplete|*^*^*

12:55:14.251 |SIPSocketProtocol(1,100,13,38255)::handleReadComplete send SdlReadRsp: size 294|*^*^*

12:55:14.251 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=294|1,100,63,1.38322^192.168.59.241^*

12:55:14.251 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 192.168.59.241 on port 5060 index 37680 with 294 bytes:

[76685,NET]

SIP/2.0 100 Trying

FROM: <sip:1008@10.131.20.116>;tag=37920~e9a11a5c-9713-4aec-bb7c-e8142e2ff402-24023303

TO: <sip:5005@192.168.59.241>

CSEQ: 101 INVITE

CALL-ID: 2a39ae80-1ff1535a-96-7414830a@10.131.20.116

VIA: SIP/2.0/TCP 10.131.20.116:5060;branch=z9hG4bKd0550d3e93

CONTENT-LENGTH: 0

|1,100,63,1.38322^192.168.59.241^*

12:55:14.251 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 38320|1,100,63,1.38322^192.168.59.241^*

12:55:14.251 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|1,100,63,1.38322^192.168.59.241^*

12:55:14.251 |//SIP/Stack/Transport/0x0/context=0xb7ee84a0|1,100,63,1.38322^192.168.59.241^*

12:55:14.251 |//SIP/Stack/Transport/0x0/gConnTab=0xb7ee84a0, addr=192.168.59.241, port=5060, connid=37680, transport=TCP|1,100,63,1.38322^192.168.59.241^*

12:55:14.251 |//SIP/Stack/Info/0x0/Return existing connection for port 5060 connId 37680|1,100,63,1.38322^192.168.59.241^*

12:55:14.251 |//SIP/Stack/Info/0x0/Checking Invite Dialog|1,100,63,1.38322^192.168.59.241^*

12:55:14.251 |//SIP/Stack/Info/0xb5a8de68/INVITE response with no RSEQ - disable IS_REL1XX|1,100,63,1.38322^192.168.59.241^*

12:55:14.251 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_TRYING value=500 retries=6|1,100,63,1.38322^192.168.59.241^*

12:55:14.251 |//SIP/Stack/States/0xb5a8de68/0xb5a8de68 : State change from (STATE_SENT_INVITE, SUBSTATE_NONE)  to (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)|1,100,63,1.38322^192.168.59.241^*

12:55:14.251 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_EXPIRES value=180000 retries=0|1,100,63,1.38322^192.168.59.241^*

12:55:14.251 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_EXPIRES value=180000 retries=0|1,100,63,1.38322^192.168.59.241^*

12:55:15.385 |SIPSocketProtocol(1,100,13,38255)::handleReadComplete send SdlReadRsp: size 429|*^*^*

12:55:15.386 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=429|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 192.168.59.241 on port 5060 index 37680 with 429 bytes:

[76686,NET]

SIP/2.0 404 No matching rule has been found in the dial plan for the called number.

FROM: <sip:1008@10.131.20.116>;tag=37920~e9a11a5c-9713-4aec-bb7c-e8142e2ff402-24023303

TO: <sip:5005@192.168.59.241>;epid=B50EB446B4;tag=84445380f4

CSEQ: 101 INVITE

CALL-ID: 2a39ae80-1ff1535a-96-7414830a@10.131.20.116

VIA: SIP/2.0/TCP 10.131.20.116:5060;branch=z9hG4bKd0550d3e93

CONTENT-LENGTH: 0

SERVER: RTCC/5.0.0.0 MediationServer

|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 38321|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/Transport/0x0/context=0xb7ee84a0|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/Transport/0x0/gConnTab=0xb7ee84a0, addr=192.168.59.241, port=5060, connid=37680, transport=TCP|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/Info/0x0/Return existing connection for port 5060 connId 37680|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/Info/0x0/Checking Invite Dialog|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/Info/0xb5a8de68/INVITE response with no RSEQ - disable IS_REL1XX|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_EXPIRES value=180000 retries=0|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/Info/0x0/No GTD found in inbound container|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/Transport/0xb5a8de68/Sending ACK to the transport layer|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/Transport/0xb5a8de68/msg=0xb35459f8, addr=192.168.59.241, port=5060, sentBy_port=0, is_req=1, transport=2, switch=0, callBack=|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/Transport/0xb5a8de68/Proceedable for sending msg immediately|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/Transport/0xb5a8de68/switch transport is 0|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/Transport/0x0/gcb=0xb5a8de68 is already on connection=0xb72c03e8 context_list|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/Transport/0xb5a8de68/Set to send the msg=0xb35459f8|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/Transport/0x0/Posting send for msg=0xb35459f8, addr=192.168.59.241, port=5060, connId=37680 for TCP|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/Info/0xb5a8de68/Received a 4/5/6xx message with StatusCode: 404|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/Info/0xb5a8de68/Initiate call disconnect(1) for outgoing call|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/SIPHandler/ccbId=0/scbId=0/ccsip_api_call_disconnected: ccb->cc_disc_cause (1); ccb->sip_disc_cause(404)|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/SIPHandler/ccbId=37920/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/Stack/States/0xb5a8de68/0xb5a8de68 : State change from (STATE_RECD_PROCEEDING, SUBSTATE_PROCEEDING_PROCEEDING)  to (STATE_DISCONNECTING, SUBSTATE_NONE)|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/SIPTcp/wait_SdlSPISignal: Outgoing SIP TCP message to 192.168.59.241 on port 5060 index 37680

[76687,NET]

ACK sip:5005@192.168.59.241:5060 SIP/2.0

Via: SIP/2.0/TCP 10.131.20.116:5060;branch=z9hG4bKd0550d3e93

From: <sip:1008@10.131.20.116>;tag=37920~e9a11a5c-9713-4aec-bb7c-e8142e2ff402-24023303

To: <sip:5005@192.168.59.241>;epid=B50EB446B4;tag=84445380f4

Date: Mon, 05 Aug 2013 07:25:14 GMT

Call-ID: 2a39ae80-1ff1535a-96-7414830a@10.131.20.116

Max-Forwards: 70

CSeq: 101 ACK

Allow-Events: presence

Content-Length: 0

|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/SIPD(1,73,112)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 24023303  mapKey is 24023303|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/SIPD(1,73,112)/ccbId=0/scbId=0/getCdpcPidGivenCcbidAndCi: found Cdpc Pid (1,100,74,301) for mapKey 24023303|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/SIPCdpc(1,74,301)/ci=24023303/ccbId=37920/scbId=0/tryAnotherDynamicAddr: isDynamicTrunk 0|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |ViprUtils::uploadVCRifRequired VcrUploadNeeded[0] FeatureData NOT added|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/SIPCdpc(1,74,301)/ci=0/ccbId=37920/scbId=0/uploadVCRifRequired: VcrUploadNeeded 0|1,100,63,1.38323^192.168.59.241^*

12:55:15.386 |//SIP/SIPCdpc(1,74,301)/ci=24023303/ccbId=37920/scbId=0/getDefAe: SIPCdpc=301, nodeId=1, processNumber=73  ci=24023303, branch=0|1,100,63,1.38323^192.168.59.241^*

12:55:15.387 |!!ERROR!! -ConnectionManager- wait_AuDisconnectRequest :CI NOT FOUND IN TABLE,CI(24023302,24023303),discType=1,IFCreated(0,0),PID(0-0,0-0),IFHandling(0,0),MCNode(0,0)|1,100,63,1.38323^192.168.59.241^*

12:55:15.387 |MatrixControl:updatePartyMediaCoordinatorNodeId: party1 videoCapable=0, party 2 videocapable=0|1,100,63,1.38323^192.168.59.241^*

12:55:15.387 |//SIP/SIPD(1,73,112)/ccbId=0/scbId=0/getKeyBasedOnCiAndBranch: AddressingElement branch is 0 and ci is 24023303  mapKey is 24023303|1,100,63,1.38323^192.168.59.241^*

12:55:15.387 |//SIP/SIPD(1,73,112)/ccbId=0/scbId=0/getCdpcPid: found Cdpc Pid (1,100,74,301) for mapKey 24023303|1,100,63,1.38323^192.168.59.241^*

12:55:15.387 |StationD:    (0000487) StopTone.|1,100,63,1.38323^192.168.59.241^*

12:55:15.387 |StationD:    (0000487) SelectSoftKeys instance=1 reference=24023302 softKeySetIndex=8 validKeyMask=fffeffff.|1,100,63,1.38323^192.168.59.241^*

12:55:15.387 |StationD:    (0000487) DEBUG- star_DSetCallState(11) State of cdpc(261) is 6.|1,100,63,1.38323^192.168.59.241^*

12:55:15.387 |MatrixControl::await_command_InsertMediaReq - Holding Party CI = 24023303|1,100,63,1.38323^192.168.59.241^*

12:55:15.387 |SIPSocketProtocol(1,100,13,38255)::handleWriteComplete|*^*^*

12:55:15.388 |SMDMSharedData::findAliasRegInfo - AliasName = 463ed4c9-65c9-4c8f-9661-d59f8af92212 not in AliasInfo hashmap|1,100,63,1.38323^192.168.59.241^*

12:55:15.388 |DeviceManager::star_DmPidReq - RequestedName=463ed4c9-65c9-4c8f-9661-d59f8af92212 LookupName=463ed4c9-65c9-4c8f-9661-d59f8af92212|1,100,63,1.38323^192.168.59.241^*

12:55:15.388 |SMDMSharedData::findLocalDevice - Name=ANN_2 Key=463ed4c9-65c9-4c8f-9661-d59f8af92212 isActvie=1 Pid=(1,221,4) found|1,100,63,1.38323^192.168.59.241^*

12:55:15.388 |ARBTRY-ConnectionManager-wait_AuConnectRequest(24023302,24023305)|1,100,63,1.38323^192.168.59.241^*

12:55:15.388 |ARBTRY-ConnectionManager- storeMediaInfo(CI=24023302): ADD NEW ENTRY, size=3|1,100,63,1.38323^192.168.59.241^*

12:55:15.388 |ARBTRY-ConnectionManager- storeMediaInfo(CI=24023305): ADD NEW ENTRY, size=4|1,100,63,1.38323^192.168.59.241^*

12:55:15.388 |SIG-MediaCoordinator-wait_AuConnectRequest - new MediaManager(130,290) started|1,100,63,1.38323^192.168.59.241^*

12:55:15.389 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 3 for event 8|1,100,63,1.38323^192.168.59.241^*

12:55:15.389 |//SIP/SIPHandler/ccbId=37920/scbId=0/findDevicePID: Routed to SIPD by ccbId/scbId|1,100,63,1.38323^192.168.59.241^*

12:55:15.389 |//SIP/Stack/States/0xb5a8de68/0xb5a8de68 : State change from (STATE_DISCONNECTING, SUBSTATE_NONE)  to (STATE_DEAD, SUBSTATE_NONE)|1,100,63,1.38323^192.168.59.241^*

12:55:15.389 |//SIP/Stack/Info/0xb5a8de68/Removing call id 9420|1,100,63,1.38323^192.168.59.241^*

12:55:15.389 |//SIP/Stack/Info/0x0/Context for key=[37794] removed.|1,100,63,1.38323^192.168.59.241^*

12:55:15.389 |//SIP/Stack/Info/0xb5a8de68/****Deleting from UAC table.|1,100,63,1.38323^192.168.59.241^*

12:55:15.389 |//SIP/Stack/Info/0xb5a8de68/Deleting from table. ccb=0xb5a8de68 key=2a39ae80-1ff1535a-96-7414830a@10.131.20.116|1,100,63,1.38323^192.168.59.241^*

12:55:15.389 |//SIP/Stack/Info/0xb5a8de68/There are 0 events on the internal queue that are going to be free'd|1,100,63,1.38323^192.168.59.241^*

12:55:15.389 |//SIP/Stack/Transport/0x0/gConnTab=0xb7ee84a0, addr=192.168.59.241, port=5060, unregistering context=0xb5a8de68|1,100,63,1.38323^192.168.59.241^*

12:55:15.389 |//SIP/Stack/Transport/0x0/Purging context gcb=0xb5a8de68 from the connection=0xb72c03e8 context list|1,100,63,1.38323^192.168.59.241^*

12:55:15.389 |//SIP/Stack/Info/0xb5a8de68/Freeing ccb b5a8de68|1,100,63,1.38323^192.168.59.241^*

12:55:15.389 |//SIP/Stack/Info/0x0/NO context for key[37794]|1,100,63,1.38323^192.168.59.241^*

12:55:15.389 |ARBTRY-ConnectionManager- wait_AuConnectReply(24023302,24023305)|1,100,223,266.1^*^*

12:55:15.389 |//SIP/SIPD(1,73,112)/ccbId=0/scbId=0/restart0_SIPRelCompInd: Ccbid 37920 and Ci 0|1,100,63,1.38323^192.168.59.241^*

12:55:15.389 |//SIP/SIPCdpc(1,74,301)/ci=24023303/ccbId=37920/scbId=0/updateCallStatsForCallCompletion: SIPCdpc=301, nodeId=1, processNumber=73|1,100,63,1.38323^192.168.59.241^*

12:55:15.389 |//SIP/SIPCdpc(1,74,301)/ci=24023303/ccbId=37920/scbId=0/terminateOobDtmf: Termination not required.|1,100,63,1.38323^192.168.59.241^*

12:55:15.390 |//SIP/SIPD(1,73,112)/ccbId=37920/scbId=0/restart0_DStopInd: Removing Cdpc Pid (1,100,74,301) with  mapKey 0 from mCiToPid table|1,100,63,1.38323^192.168.59.241^*

12:55:15.390 |StationD:    (0000487) SEPDC7B94F9C5F3 , star_MediaExchangeAgenaOpenLogicalChannel packetSize=20, codec=4, ci=24023302|1,100,223,265.1^*^*

12:55:15.390 |StationD:    (0000487) StopTone.|1,100,223,265.1^*^*

12:55:15.391 |StationD:    (0000487) OpenReceiveChannel conferenceID=24023302 passThruPartyID=16777850 millisecondPacketSize=20 compressionType=4(Media_Payload_G711Ulaw64k) RFC2833PayloadType=0 qualifierIn=? sourceIpAddr=IpAddr.type:0 ipAddr:0x0a831474000000000000000000000000(10.131.20.116). myIP: IpAddr.type:0 ipv4Addr:0xc0a83d89(192.168.61.137) |1,100,223,265.1^*^*

12:55:15.432 |StationInit: (0000487) OpenReceiveChannelAck Status=0, IpAddr=IpAddr.type:0 ipAddr:0xc0a83d89000000000000000000000000(192.168.61.137), Port=25584, PartyID=16777850|1,100,56,1.467196^192.168.61.137^SEPDC7B94F9C5F3

12:55:15.432 | StationCdpc: star_CcNotifyReq - CallSecurityStatus = 1 |1,100,56,1.467196^192.168.61.137^SEPDC7B94F9C5F3

12:55:15.432 |StationD:    (0000487) (1,100,13,38155) CallInfo callingPartyName='' callingParty=1008 cgpnVoiceMailbox=             alternateCallingParty=              calledPartyName='' calledParty=5005 cdpnVoiceMailbox=             originalCalledPartyName='' originalCalledParty=5005 originalCdpnVoiceMailbox= originalCdpnRedirectReason=0             lastRedirectingPartyName='' lastRedirectingParty=5005 lastRedirectingVoiceMailbox= lastRedirectingReason=0             callType=2(OutBound) lineInstance=1 callReference=24023302. version: 85720014|1,100,56,1.467196^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.390 |StationInit: (0000487) SoftKeyEvent softKeyEvent=9(EndCall) lineInstance=1 callReference=24023302.|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |StationD:    (0000487) StopTone.|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |LineControl TEST DEBUGS: Number of entries in CallTable is =  1

|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |StationD:    (0000487) DEBUG- star_DSetCallPhase updateACall=24023302 from Phase=0 to  callPhase=3.|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |StationD:    (0000487) DEBUG- star_DSetCallState(15) State of cdpc(261) is 11.|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |StationD:    (0000487) SetLamp mode=1, stim=9 stimInst=1.|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |StationD:    (0000487) ClearPromptStatus lineInstance=1 callReference=24023302.|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |StationD:    (0000487) CloseReceiveChannel conferenceID=24023302 passThruPartyID=16777850.  myIP: IpAddr.type:0 ipv4Addr:0xc0a83d89(192.168.61.137) |1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |StationD:    (0000487) CallState callState=2 lineInstance=1 callReference=24023302 privacy=0 sccp_precedenceLv=4 precedenceDm=0|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |StationD:    (0000487) SelectSoftKeys instance=0 reference=0 softKeySetIndex=0 validKeyMask=ffffffff.|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |StationD:    (0000487) DefineTimeDate timeDateInfo=8/5/2013 7:25:18,1 systemTime=1375687518.|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |StationD:    (0000487) restart0_DStopInd: No Linked StationCdpc.|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |StationD:    (0000487) INFO  restart0_DStopInd: Enable NewCall on line=1 limit=4 listSize=0|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |StationD:    (0000487) restart0_DStopInd: DEBUG: StationCdpc(261) removed. Calltable contains 0 calls.|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |StationD:    (0000487) SetSpeakerMode speakermode=2(Off).|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |ARBTRY-ConnectionManager- wait_AuDisconnectRequest CI(24023302,24023305),disconnectType(0), IFHandling(0,0)|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |ARBTRY-ConnectionManager- storeMediaInfo(CI=24023302): EXISTING ENTRY DISCOVERED, size=4|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |ARBTRY-ConnectionManager- storeMediaInfo(CI=24023305): EXISTING ENTRY DISCOVERED, size=4|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |SIG-MediaCoordinator-wait_AuDisconnectRequest,CI(24023302,24023305),IFCreated(1,1)|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.391 |SIG-MediaCoordinator-wait_AuDisconnectRequest - sending disconnect to MediaManager(290)|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |StationD:    (0000487) SEPDC7B94F9C5F3 , INFO star_MediaExchangeAgenaCloseLogicalChannel can't find call=24023302 partyId= 100027a|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |SIG-MediaCoordinator-wait_AuDisconnectReply - removing MediaManager(290) from connection list|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |DET-MediaCoordinator-wait_AuDisconnectReply, sizeof MMMap=0,MMHalfMap=0|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |ARBTRY-ConnectionManager- wait_AuDisconnectReply CI(24023302,24023305),disconnectType(0),IFHandling(0,0)|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |ARBTRY-ConnectionManager- wait_AuDisconnectRequest CI(0,24023305),disconnectType(1), IFHandling(0,0)|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |ARBTRY-ConnectionManager- storeMediaInfo(0): NULL CI IGNORED, tail=4|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |ARBTRY-ConnectionManager- storeMediaInfo(CI=24023305): EXISTING ENTRY DISCOVERED, size=4|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |SIG-MediaCoordinator-wait_AuDisconnectRequest,CI(0,24023305),IFCreated(0,1)|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |SIG-MediaCoordinator-wait_AuDisconnectRequest - no parties found|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |SIG-MediaCoordinator-wait_AuDisconnectRequest, extending disconnect to partyA interface instance(266)|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |ARBTRY-ConnectionManager- wait_AuDisconnectRequest CI(24023302,24023303),disconnectType(1), IFHandling(0,0)|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |ARBTRY-ConnectionManager- storeMediaInfo(CI=24023302): EXISTING ENTRY DISCOVERED, size=4|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |ARBTRY-ConnectionManager- storeMediaInfo(CI=24023303): ADD NEW ENTRY, size=5|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |ARBTRY-ConnectionManager- wait_AuDisconnectReply CI(0,24023305),disconnectType(1),IFHandling(0,0)|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |!!ERROR!! -ConnectionManager- ERROR  deleteMediaInfoEntry(CI=0): ENTRY NOT FOUND, size=5|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |ARBTRY-ConnectionManager- deleteMediaInfoEntry(CI=24023305): ENTRY DELETED, size=4|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |SIG-MediaCoordinator-wait_AuDisconnectRequest,CI(24023302,24023303),IFCreated(1,0)|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |SIG-MediaCoordinator-wait_AuDisconnectRequest - no parties found|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |SIG-MediaCoordinator-wait_AuDisconnectRequest, extending disconnect to partyA interface instance(265)|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |MatrixControl:updatePartyMediaCoordinatorNodeId: party1 videoCapable=0, party 2 videocapable=0|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |ARBTRY-ConnectionManager- wait_AuDisconnectReply CI(24023302,24023303),disconnectType(1),IFHandling(0,0)|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |ARBTRY-ConnectionManager- deleteMediaInfoEntry(CI=24023302): ENTRY DELETED, size=3|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.392 |ARBTRY-ConnectionManager- deleteMediaInfoEntry(CI=24023303): ENTRY DELETED, size=2|1,100,56,1.467198^192.168.61.137^SEPDC7B94F9C5F3

12:55:18.466 |StationInit: (0000487) StationMediaPathEvt Speaker (3) = Off (2)|1,100,56,1.467199^192.168.61.137^SEPDC7B94F9C5F3

12:55:22.883 |<MN::MGCPEndPoint><MV::*@CUBE.cisco.com>|1,100,157,1.158741^*^*

12:55:37.883 |<MN::MGCPEndPoint><MV::*@CUBE.cisco.com>|1,100,157,1.158742^*^*

12:55:41.650 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 57|1,100,63,1.38320^192.168.59.241^*

12:55:41.650 |//SIP/Stack/Transport/0x0/context=(nil)|1,100,63,1.38320^192.168.59.241^*

12:55:41.650 |//SIP/Stack/Transport/0x0/gConnTab=0xb7ee84a0, addr=192.168.59.241, port=64371, connid=37687, transport=TCP|1,100,63,1.38320^192.168.59.241^*

12:55:41.650 |//SIP/Stack/Info/0x0/Handling TCP reset for all contexts on connection|1,100,63,1.38320^192.168.59.241^*

12:55:41.650 |//SIP/Stack/Info/0xb7b462d0/Found gcbs on connection waitq|1,100,63,1.38320^192.168.59.241^*

12:55:41.650 |//SIP/Stack/Transport/0x0/Posting TCP conn close for addr=192.168.59.241, port=64371, connid=37687|1,100,63,1.38320^192.168.59.241^*

12:55:41.650 |//SIP/Stack/Transport/0x0/Deleted conn=0xb7b462d0, connid=37687, addr=192.168.59.241, port=64371, transport=TCP|1,100,63,1.38320^192.168.59.241^*

12:55:41.650 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|1,100,63,1.38320^192.168.59.241^*

12:55:41.650 |//SIP/SIPTcp/wait_SdlSPISignal: Did not find table entry in Close Msg,Index = 37687|1,100,63,1.38320^192.168.59.241^*

12:55:41.650 |//SIP/SIPHandler/ccbId=0/scbId=0/getRemoteAddrInfo: RemoteAddrInfo = 192.168.59.241:64371|1,100,63,1.38320^192.168.59.241^*

12:55:41.650 |//SIP/SIPD(1,73,112)/ccbId=0/scbId=0/restart0_SIPConnControlInd: mTsp.DeviceName[Lync241] - Rcvd ConnControl with operation = 1, connIdx = 37687, X.509 Name = |1,100,63,1.38320^192.168.59.241^*

12:55:41.650 |//SIP/SIPD(1,73,112)/ccbId=0/scbId=0/restart0_SIPConnControlInd: mTsp.DeviceName[Lync241] - Cannot find TLSConnTbl entry, connIdx = 37687, X.509 Name = |1,100,63,1.38320^192.168.59.241^*

12:55:41.653 |//SIP/SIPTcp/wait_SdlConnectionInd: Connection Indication - Listen Port = 5060, Peer Port = 64406|1,100,13,38264.2^*^*

12:55:41.653 |//SIP/SIPTcp/wait_SdlConnectionInd: New Connection - Index = 37689, Listen Port = 5060, Peer Port = 64406|1,100,13,38264.2^*^*

12:55:41.653 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 56|1,100,13,38264.2^*^*

12:55:41.653 |//SIP/Stack/Transport/0x0/context=(nil)|1,100,13,38264.2^*^*

12:55:41.653 |//SIP/Stack/Transport/0x0/gConnTab=0xb7ee84a0, addr=192.168.59.241, port=64406, connid=37689, transport=TCP|1,100,13,38264.2^*^*

12:55:41.653 |//SIP/Stack/Transport/0x0/Created new accptd conn=0xb72c02d8, connid=37689, addr=192.168.59.241, port=64406, transport=TCP|1,100,13,38264.2^*^*

12:55:41.662 |//SIP/SIPTcp/wait_SdlReadRsp: SdlRead bufferLen=460|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 192.168.59.241 on port 64406 index 37689 with 460 bytes:

[76688,NET]

OPTIONS sip:10.131.20.116 SIP/2.0

FROM: <sip:CHSICOEEXLY01.casper.com:5060;transport=Tcp;ms-opaque=35f14f70bfff764e>;epid=B50EB446B4;tag=68edaa16ef

TO: <sip:10.131.20.116>

CSEQ: 40 OPTIONS

CALL-ID: 814424f84f494a26a7979c42f9572277

MAX-FORWARDS: 70

VIA: SIP/2.0/TCP 192.168.59.241:64406;branch=z9hG4bKdd48689b

CONTACT: <sip:CHSICOEEXLY01.casper.com:5060;transport=Tcp;maddr=192.168.59.241>

CONTENT-LENGTH: 0

USER-AGENT: RTCC/5.0.0.0 MediationServer

|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 38322|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Transport/0x0/context=(nil)|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Transport/0x0/gConnTab=0xb7ee84a0, addr=192.168.59.241, port=64406, connid=37689, transport=TCP|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0x0/Return existing connection for port 64406 connId 37689|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0x0/Checking Invite Dialog|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0x0/Added context(0xb5a67ab0) with key=[37795] to table|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Transport/0x0/Dialog Transaction Address 192.168.59.241,Port 64406, Transport 2, SentBy Port 64406|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/States/0xb5a67ab0/0xb5a67ab0 : State change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_NONE)|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Transport/0x0/Subsq Transaction Address 192.168.59.241,Port 64406, Transport 2, SentBy Port 5060|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Transport/0x0/Subsq Transaction Address 192.168.59.241,Port 64406, Transport 2, SentBy Port 64406|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/SIPHandler/ccbId=37921/scbId=0/findDevicePID: Routed to SIPD by sipAddr|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0xb5a67ab0/ccsip_api_options_ind returned: SIP_SUCCESS|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/States/0xb5a67ab0/0xb5a67ab0 : State change from (STATE_IDLE, SUBSTATE_NONE)  to (SIP_STATE_OPTIONS_WAIT, SUBSTATE_NONE)|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0xb5a67ab0/****Adding to UAS Request table.|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0xb5a67ab0/Added to table. ccb=0xb5a67ab0 key=814424f84f494a26a7979c42f9572277|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0xb5a67ab0/Adding call id 9421 to table|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 3 for event 39|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0xb5a67ab0/Associated container=0xb3527238 to Options Response|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Transport/0xb5a67ab0/msg=0xb355f048, addr=192.168.59.241, port=64406, sentBy_port=64406, is_req=0, transport=2, switch=0, callBack=|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Transport/0xb5a67ab0/Proceedable for sending msg immediately|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Transport/0xb5a67ab0/switch transport is 0|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Transport/0xb5a67ab0/Trying to send resp=0xb355f048 to default port=64406|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Transport/0x0/Registering gcb=0xb5a67ab0 with connection=0xb72c02d8 context list|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Transport/0xb5a67ab0/Connection obtained...sending msg=0xb355f048|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Transport/0x0/Posting send for msg=0xb355f048, addr=192.168.59.241, port=64406, connId=37689 for TCP|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0xb5a67ab0/Removing call id 9421|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0x0/Context for key=[37795] removed.|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0xb5a67ab0/****Deleting from UAS Request table.|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0xb5a67ab0/Deleting from table. ccb=0xb5a67ab0 key=814424f84f494a26a7979c42f9572277|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0xb5a67ab0/****Deleting from UAS Response table.|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0xb5a67ab0/There are 0 events on the internal queue that are going to be free'd|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Transport/0x0/gConnTab=0xb7ee84a0, addr=192.168.59.241, port=64406, unregistering context=0xb5a67ab0|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Transport/0x0/Purging context gcb=0xb5a67ab0 from the connection=0xb72c02d8 context list|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0xb5a67ab0/Freeing ccb b5a67ab0|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0x0/NO context for key[37795]|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Transport/0x0/gcb has cleanedup, Done with send operations for this context|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/Stack/Info/0x0/NO context for key[37795]|1,100,63,1.38324^192.168.59.241^*

12:55:41.662 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|1,100,63,1.38324^192.168.59.241^*

12:55:41.663 |//SIP/SIPTcp/wait_SdlSPISignal: Outgoing SIP TCP message to 192.168.59.241 on port 64406 index 37689

[76689,NET]

SIP/2.0 200 OK

Via: SIP/2.0/TCP 192.168.59.241:64406;branch=z9hG4bKdd48689b

From: <sip:CHSICOEEXLY01.casper.com:5060;transport=Tcp;ms-opaque=35f14f70bfff764e>;epid=B50EB446B4;tag=68edaa16ef

To: <sip:10.131.20.116>;tag=957488018

Date: Mon, 05 Aug 2013 07:25:41 GMT

Call-ID: 814424f84f494a26a7979c42f9572277

CSeq: 40 OPTIONS

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

Content-Length: 0

|1,100,63,1.38324^192.168.59.241^*

12:55:52.883 |<MN::MGCPEndPoint><MV::*@CUBE.cisco.com>|1,100,157,1.158743^*^*

“If you have knowledge, let others light their candles in it.”
1 ACCEPTED SOLUTION

Accepted Solutions

SIP Call Failing from Lync to CUCM

Hi,

you have got the unassigned number as cause code for this call failure.

ccsip_api_call_disconnected: ccb->cc_disc_cause (1); ccb->sip_disc_cause(404)

{CC_CAUSE_UANUM = 0x1 Unassigned number (1) }

From Lync side, you have received the below message

12:55:15.386 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 192.168.59.241 on port 5060 index 37680 with 429 bytes:

[76686,NET]

SIP/2.0 404 No matching rule has been found in the dial plan for the called number.

FROM: <1008>;tag=37920~e9a11a5c-9713-4aec-bb7c-e8142e2ff402-24023303

TO: <5005>;epid=B50EB446B4;tag=84445380f4

CSEQ: 101 INVITE

CALL-ID: 2a39ae80-1ff1535a-96-7414830a@10.131.20.116

VIA: SIP/2.0/TCP 10.131.20.116:5060;branch=z9hG4bKd0550d3e93

CONTENT-LENGTH: 0

SERVER: RTCC/5.0.0.0 MediationServer

--> could you please check the Lync side if the dialed number is configured properly?

//Suresh Please rate all the useful posts.
1 REPLY

SIP Call Failing from Lync to CUCM

Hi,

you have got the unassigned number as cause code for this call failure.

ccsip_api_call_disconnected: ccb->cc_disc_cause (1); ccb->sip_disc_cause(404)

{CC_CAUSE_UANUM = 0x1 Unassigned number (1) }

From Lync side, you have received the below message

12:55:15.386 |//SIP/SIPTcp/wait_SdlReadRsp: Incoming SIP TCP message from 192.168.59.241 on port 5060 index 37680 with 429 bytes:

[76686,NET]

SIP/2.0 404 No matching rule has been found in the dial plan for the called number.

FROM: <1008>;tag=37920~e9a11a5c-9713-4aec-bb7c-e8142e2ff402-24023303

TO: <5005>;epid=B50EB446B4;tag=84445380f4

CSEQ: 101 INVITE

CALL-ID: 2a39ae80-1ff1535a-96-7414830a@10.131.20.116

VIA: SIP/2.0/TCP 10.131.20.116:5060;branch=z9hG4bKd0550d3e93

CONTENT-LENGTH: 0

SERVER: RTCC/5.0.0.0 MediationServer

--> could you please check the Lync side if the dialed number is configured properly?

//Suresh Please rate all the useful posts.
1463
Views
0
Helpful
1
Replies
CreatePlease login to create content