Cisco Jabber for Windows unable to make calls in softphone mode

[hassanalirazi]

Hi,

I am facing an issue when I make a call from jabber in softphone mode to another jabber client my call fails and using wireshark traces I see SIP2.0 503 Service Unavailable.

I have followed the guide and was able to deploy it once before successfully. This is a lab environment and we don't have any CSS or partitions.

If someone can please guide me :)

Regards

Hassan

[Atul Gaba]

Hi Hassan,

Can you share the CCM traces for a test call.

-Atul

[hassanalirazi]

Hi Atul,

Thank you! The answer you provided was spot on I managed to fix the network related issues and got the call working.

On another note I have moved this machine from our local lab to the cloud and after the ip address change all the services are stuck on starting. Do you have any idea how I can fix that?

Regards

Hassan

[Atul Gaba]

Hi Hassan,

Good to know it worked.

Regarding the re-ip, did you follow the step-by-step guide for changing IP address:-

http://www.cisco.com/c/en/us/td/docs/voice_ip_comm/cucm/install/10_0_1/ipchange/CUCM_BK_C3782AAB_00_change-ipaddress-hostname-100.html

If not then, then you'll probably need root access to correct it and work with TAC.
The hosts and sqlhost file might not have been updated.

Regards,

Atul

[hassanalirazi]

Hi Atul,

I am attaching the SDL logs along with email. The IM&P ip is 192.168.201.104 CUCM 192.168.201.101 and jabber client has the ip 192.168.1.60.

I hope these are the logs you are looking for

[Atul Gaba · Accepted solution]

Hi Hassan,

I found four calls in the trace file with the same calling/called numbers. I analyzed one of them.

Calling party - 42001

Called party - 42002

-----------

00267676.002 |17:15:14.335 |AppInfo  |SIPTcp - wait_SdlReadRsp: Incoming SIP TCP message from 122.129.75.131 on port 23600 index 689 with 2610 bytes:
[7525,NET]
INVITE sip:42002@192.168.201.101;user=phone SIP/2.0
Via: SIP/2.0/TCP 192.168.1.60:54441;branch=z9hG4bK00005d94
From: "42001" <sip:42001@192.168.201.101>;tag=3c77e6734e8e00180000789e-000014f2
To: <sip:42002@192.168.201.101>
Call-ID: 3c77e673-4e8e0008-00003b42-00007ab7@192.168.1.60
Max-Forwards: 70
Date: Tue, 21 Apr 2015 11:29:56 GMT
CSeq: 101 INVITE
User-Agent: Cisco-CSF
Contact: <sip:5c3aae77-4c62-4e0c-95ed-1d1444e9be4f@192.168.1.60:54441;transport=tcp>;video;bfcp
Expires: 180
Accept: application/sdp
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE,INFO
Remote-Party-ID: "42001" <sip:42001@192.168.201.101>;party=calling;id-type=subscriber;privacy=off;screen=yes
Supported: replaces,join,sdp-anat,norefersub,resource-priority,extended-refer,X-cisco-callinfo,X-cisco-serviceuri,X-cisco-escapecodes,X-cisco-service-control,X-cisco-srtp-fallback,X-cisco-monrec,X-cisco-config,X-cisco-sis-7.0.0,X-cisco-xsi-8.5.1
Allow-Events: kpml,dialog
Recv-Info: conference
Recv-Info: x-cisco-conference
Content-Length: 1469
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 5552 0 IN IP4 192.168.1.60
s=SIP Call
b=AS:4000
t=0 0
m=audio 21996 RTP/AVP 9 104 105 0 8 18 101
c=IN IP4 192.168.1.60
a=rtpmap:9 G722/8000
a=rtpmap:104 G7221/16000
a=fmtp:104 bitrate=32000
a=rtpmap:105 G7221/16000
a=fmtp:105 bitrate=24000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
m=video 25898 RTP/AVP 126 97
c=IN IP4 192.168.1.60
b=TIAS:4000000
a=rtpmap:126 H264/90000
a=fmtp:126 profile-level-id=42E01F;packetization-mode=1;level-asymmetry-allowed=1;max-fs=3601;max-rcmd-nalu-size=32000
a=imageattr:126 recv [x=[32:1:1280],y=[18:1:720],par=1.7778,q=1.00]
a=content:main
a=label:11
a=rtpmap:97 H264/90000
a=fmtp:97 profile-level-id=42E01F;packetization-mode=0;level-asymmetry-allowed=1;max-fs=3601
a=imageattr:97 recv [x=[32:1:1280],y=[18:1:720],par=1.7778,q=1.00]
a=sendrecv
m=video 32652 RTP/AVP 126 97
c=IN IP4 192.168.1.60
b=TIAS:4000000
a=rtpmap:126 H264/90000
a=fmtp:126 profile-level-id=42E01F;packetization-mode=1;level-asymmetry-allowed=1;max-fs=4099;max-rcmd-nalu-size=32000
a=content:slides
a=label:12
a=rtpmap:97 H264/90000
a=fmtp:97 profile-level-id=42E01F;packetization-mode=0;level-asymmetry-allowed=1;max-fs=4099
a=sendrecv
m=application 5070 UDP/BFCP *
c=IN IP4 192.168.1.60
a=floorctrl:c-s
a=confid:4
a=floorid:2 mstrm:12
a=userid:4
a=setup:actpass
a=connection:new
a=sendrecv

00267702.007 |17:15:14.342 |AppInfo  |Digit analysis: match(pi="2", fqcn="42001", cn="42001",plv="5", pss="", TodFilteredPss="", dd="42002",dac="0")
00267702.008 |17:15:14.342 |AppInfo  |Digit analysis: analysis results
00267702.009 |17:15:14.342 |AppInfo  ||PretransformCallingPartyNumber=42001
|CallingPartyNumber=42001
|DialingPartition=
|DialingPattern=42002

00267709.001 |17:15:14.343 |AppInfo  |LBMIF: CI: 30582939 ASSOC    30582940
00267709.002 |17:15:14.343 |AppInfo  |LBMIF: CI: 30582940 ASSOC'   30582939

00267715.000 |17:15:14.343 |SdlSig   |CcSetupReq                             |restart0                       |LineControl(1,100,174,27)        |Cdcc(1,100,219,42)               |1,100,14,10382.36^122.129.75.131^CSFHASSANRAZI |[R:N-H:0,N:2,L:0,V:0,Z:0,D:0] CI=30582940 CI.branch=0  sBPL.plid=65 sBPL.l=1 sBPL.pl=5 sBPL.msd=0  FDataType=0opId=0ssType=0 SsKey=0invokeId=0resultExp=Fbpda=F pi.piid=30 pi.l=0 pi2.piid=30 pi2.l=0 pi3.piid=30 pi3.l=0 FQCGPN=ti=1nd=42001pi=0si1 preXCgpn=tn=0npi=0ti=1nd=42001pi=0si1 cgPart= cgPat=42001 cgpn=tn=0npi=0ti=1nd=42001pi=1si1 cgpnVM= unXCgpn=tn=0npi=0ti=1nd=42001pi=1si1 cName=locale: 1 Name:  UnicodeName:  pi: 1 DD=tn=0npi=1ti=1nd=42002User=42002Host=192.168.201.101Port=5060PassWord=Madder=Transport=4mDisplayName=RawUrl=sip:42002@192.168.201.101;user=phoneOrigPort=0pi=0si1 origDD=tn=0npi=1ti=1nd=42002User=42002Host=192.168.201.101Port=5060PassWord=Madder=Transport=4mDisplayName=RawUrl=sip:42002@192.168.201.101;user=phoneOrigPort=0pi=0si1 preXCdpn=tn=0npi=0ti=1nd=42002pi=0si0 preXTagsList=SUBSCRIBER preXPosMatchList=42002 cdPart= cdPat=42002 cdpn=tn=0npi=0ti=1nd=42002pi=1si1 cdpnVMbox= localPatternUsage=2 connectedPatternUsage=2 itrPart= itrPat= LRPart= LRPat=42002 LR=tn=0npi=0ti=1nd=42002pi=0si1 LRVM= LRName=locale: 1 Name:  UnicodeName:  pi: 0 FQOCpdn=ti=1nd=42002pi=0si1 fFQLRNum=ti=1nd=42002pi=0si1 oPart= oPat=42002 oCpdn=tn=0npi=0ti=1nd=42002pi=0si1 oCdpnVM= oRFR=0 oName=locale: 1 Name:  UnicodeName:  pi: 0 ts=SUBSCRIBER posMatches=42002 withTags= withValues= rdn.l=0IpAddrMode=0 ipAddrType=0 ipv4=122.129.75.131:23600 region=Default capCount=7 ctiActive=F ctiFarEndDev=1 ctiCCMId=1 cgPtyDev=CSFHASSANRAZI callInst=0 confCallInst=0 OLF=1Supp DTMF=3DTMF Cfg=1DTMF Payload=101isOffNetDev=F bc.l=4 bc.itr=8 bc.itc=8 bc.trm=6 bc.tm=16 maxForwards=69 cgpnMaskedByRedirect=F callingDP=1b1b9eb6-7803-11d3-bdf0-00108302ead1 featCallType=0 callingUserId= UnicodeName:  muteEnabled=0 associatedCallCI=0 featurePriority=1 nonTargetPolicy=0 unconsumedDigits= suppressMOH=F numPlanPkid =6807b55c-7ab5-4f8d-8fba-db383ecfaaa4 networkDomain= bitMask=0 SetupReason=0 routeClass=1 sideACmDeviceType=4 protected=1 ControlProcessType=0 tokens=0 isPresent=F transitCount=0 geolocInfo={geolocPkid=, filterPkid=, geolocVal=, devType=4} locPkid=29c5c1c4-8871-4d1e-8394-0b9181e8c54d locName=Hub_None deductBW=F fateShareId=StandAloneCluster:30582939 videoTrafficClass=Desktop oFromAnalogDvc=F bridgeParticipantID= callingUsr= remoteClusterID= isEMCCDevice=F lHPMemCEPN= cHPMemCEPN= uri=ti=1User=Host=Port=0PassWord=Madder=Transport=4mDisplayName=RawUrl=<sip:5c3aae77-4c62-4e0c-95ed-1d1444e9be4f@192.168.1.60:54441;transport=tcp>OrigPort=0pi=0si1 param=;video;bfcp M=Unknown ;rc=26624 Hdrs= CanSupportSIPTandN=true TransId=0 AllowBitMask=0x7bf UserAgentOrServer=Cisco-CSF OrigDDName=locale: 1 Name:  UnicodeName:  pi: 0 mCallerId= mCallerName=LatentCaps=null icidVal= icidGenAddr= oioi= tioi= ptParams= receivedPAID= routeHdr= routeCepn= requestURI= PCVFlag=F originallyHadISUP=F isIMSFinalRoute=F IMSMode=0 SideABibEnabled= 0 isCgpnNonPreemptable=F isCdpnNonPreemptable=F origDP=1b1b9eb6-7803-11d3-bdf0-00108302ead1 lastRedirectingDP=1b1b9eb6-7803-11d3-bdf0-00108302ead1 originalLRG= lastRedirectingLRG= nwLoc=0 rstr= FarEndDeviceName=CSFHASSANRAZI hdrMOH=0 CAL={v=ffffffff, m=ffffffff, tDev=F, res=F, devType=0}
00267715.001 |17:15:14.343 |AppInfo  |LineControl(27) - 0 calls, 0 CiReq, busyTrigger=2, maxCall=6
00267715.002 |17:15:14.343 |Created  |                                       |                               |LineCdpc(1,100,175,74)           |LineControl(1,100,174,27)        |                                         |NumOfCurrentInstances: 2

00267758.001 |17:15:19.364 |AppInfo  |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 55 (SIPSPI_EV_SEND_FAILURE_MSG)
00267758.002 |17:15:19.364 |AppInfo  |//SIP/Stack/Info/0x0xe5136ad8/ccsip_spi_process_event: Send Error for event(0xe5139420)
00267758.003 |17:15:19.364 |AppInfo  |//SIP/Stack/Error/0x0/act_idle_send_msg_failure: Send Error to 192.168.1.172:54901 for transport TCP
00267758.004 |17:15:19.364 |AppInfo  |//SIP/Stack/Info/0x0xe5136ad8/ccsip_set_cc_cause_for_spi_err: Categorized cause:38, category:186
00267758.005 |17:15:19.364 |AppInfo  |//SIP/Stack/Info/0x0xe5136ad8/sipSPIInitiateDisconnect: Initiate call disconnect(38) for outgoing call
00267758.006 |17:15:19.364 |AppInfo  |//SIP/SIPHandler/ccbId=4212/scbId=0/ccsip_api_call_disconnected: ccb->cc_disc_cause (38); ccb->sip_disc_cause(503)

00267794.001 |17:15:19.367 |AppInfo  |SIPTcp - wait_SdlSPISignal: Outgoing SIP TCP message to 122.129.75.131 on port 23600 index 689
[7527,NET]
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/TCP 192.168.1.60:54441;branch=z9hG4bK00005d94;received=122.129.75.131
From: "42001" <sip:42001@192.168.201.101>;tag=3c77e6734e8e00180000789e-000014f2
To: <sip:42002@192.168.201.101>;tag=4211~132b515c-f941-ca80-8023-9423185ada2d-30582939
Date: Tue, 21 Apr 2015 12:15:14 GMT
Call-ID: 3c77e673-4e8e0008-00003b42-00007ab7@192.168.1.60
CSeq: 101 INVITE
Allow-Events: presence
Server: Cisco-CUCM10.5
Reason: Q.850; cause=41
Content-Length: 0

Seems like a network issue. TCP connection can't be established with the called jabber client, which as per the trace is 192.168.1.172.

Check for firewalls blocking the connection.

You may take a pcap on the called jabber client side or probably from the call manager, and check for TCP issues.

You mentioned the jabber client's IP as 192.168.1.60 but I see incoming invite from 122.129.75.131. What this IP?

HTH,

Atul