[cisco-voip] R: R: Jabber 10.5 and Remotedestination, disconnected!
Mauro Celli
mauro.celli at 2000net.it
Thu Oct 16 17:25:42 EDT 2014
Ok,
i have attached all, sdi,sdl and jabber trace.
If I enable mobility identity in my phone, cucm send
sipio-recv<--- SUBSCRIBE sip:120683ae-0fca-8bc2-bd00-35e584c45cdc at 172.20.250.60:38229 SIP/2.0
and jabber respond with
Line 71785: 10-16 23:14:43.623 23450 23803 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 500 Internal Server Error
Note, this is with jabber 10.5 and older version, cucm 8.5 and cucm9, nexus5 and other phone (lg)
Huao is a difficult problem…
Da: bmeade90 at gmail.com [mailto:bmeade90 at gmail.com] Per conto di Brian Meade
Inviato: giovedì 16 ottobre 2014 23.16
A: Mauro Celli
Cc: cisco-voip at puck.nether.net
Oggetto: Re: [cisco-voip] R: Jabber 10.5 and Remotedestination, disconnected!
That doesn't show the 100 Trying sent or the 500 Internal Server Error. Can you send the full log as an attachment?
On Thu, Oct 16, 2014 at 4:39 PM, Mauro Celli <mauro.celli at 2000net.it<mailto:mauro.celli at 2000net.it>> wrote:
This is detailed sdi cucm and sip log
[740334,NET]
REGISTER sip:172.20.10.5 SIP/2.0
Via: SIP/2.0/TCP 172.20.250.60:52993;branch=z9hG4bK7255336e
From: <sip:72 at 172.20.10.5<mailto:sip%3A72 at 172.20.10.5>>;tag=d600811c0d11001751400975-00483fd2
To: <sip:72 at 172.20.10.5<mailto:sip%3A72 at 172.20.10.5>>
Call-ID: d600811c-0d11000c-4c93ea29-78e643b7 at 172.20.250.60<mailto:d600811c-0d11000c-4c93ea29-78e643b7 at 172.20.250.60>
Max-Forwards: 70
Date: Thu, 16 Oct 2014 20:21:43 GMT
CSeq: 121 REGISTER
User-Agent: Cisco-SOUNDWAVE
Contact: <sip:120683ae-0fca-8bc2-bd00-35e584c45cdc at 172.20.250.60:52993;transport=tcp>;+sip.instance="<urn:uuid:00000000-0000-0000-0000-d600811c0d11>";+u.sip!devicename.ccm.cisco.com<http://devicename.ccm.cisco.com>="BOT123456789";+u.sip!model.ccm.cisco.com<http://model.ccm.cisco.com>="575";+u.sip!mtpreferredmode.ccm.cisco.com<http://mtpreferredmode.ccm.cisco.com>="cellular";video
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,X-cisco-graceful-reg,X-cisco-duplicate-reg
Reason: SIP;cause=200;text="cisco-alarm:111 Name=BOT123456789 ActiveLoad=Jabber_for_Android-10.5.1 InactiveLoad=Jabber_for_Android-10.5.1 Last=Application-Requested-Destroy"
Expires: 3600
Content-Length: 1044
Content-Type: application/x-cisco-remotecc-request+xml
Content-Disposition: session;handling=optional
<?xml version="1.0" encoding="UTF-8"?>
<x-cisco-remotecc-request>
<optionsind>
<supportedMobileExtensionFeatures>
<DynamicDVOSelection/>
<MediaConnectionReuse/>
<Redial-DVOF/>
<HoldResume/>
<Transfer/>
<Conference/>
<Park/>
<Dpark/>
<ManualHandoff/>
<SessionHandoff/>
<MobileStatusUpdate/>
<ManualLCRHandoff/>
<SingleRegistration/>
</supportedMobileExtensionFeatures>
<combine max="6">
<remotecc>
<status></status>
</remotecc>
<service-control></service-control>
</combine>
<dialog usage="shared line">
<unot></unot>
<sub></sub>
</dialog>
<presence usage="blf speed dial">
<unot></unot>
<sub></sub>
</presence>
<joinreq></joinreq>
<cfwdall-anyline></cfwdall-anyline>
<coaching></coaching>
<oosalarm></oosalarm>
<x-cisco-number></x-cisco-number>
<bfcp></bfcp>
<ix></ix>
<gatewayrecording></gatewayrecording>
</optionsind>
</x-cisco-remotecc-request>|1,100,63,1.73806^172.20.250.60^*
0128.046 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 73804|1,100,63,1.73806^172.20.250.60^*
22:21:45.046 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|1,100,63,1.73806^172.20.250.60^*
22:21:45.046 |//SIP/Stack/Transport/0x0/context=(nil)|1,100,63,1.73806^172.20.250.60^*
22:21:45.046 |//SIP/Stack/Transport/0x0/gConnTab=0xff55338, addr=172.20.250.60, port=52993, connid=8067, transport=TCP|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x0/Return existing connection for port 52993 connId 8067|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x0/Checking Invite Dialog|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x0/*****CCB NOT found in UAS Request table. ccb=(nil)|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x0/Added context(0x10aaa8b8) with key=[125069] to table|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x0/Dialog Transaction Address 172.20.250.60,Port 52993, Transport 2, SentBy Port 52993|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/States/0x10aaa8b8/0x10aaa8b8 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x0/Subsq Transaction Address 172.20.250.60,Port 52993, Transport 2, SentBy Port 5060|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x0/Subsq Transaction Address 172.20.250.60,Port 52993, Transport 2, SentBy Port 52993|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x0/sipConstructContainerContext #### Created container=0x10c52e88|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x0/httpish_msg_lock, Increment msg (0xb204ad18) counter, current msg->refCount = 2|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x0/httpish_msg_create, #### created msg=0x10bcb6e0 with refCount = 1|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x10aaa8b8/Sending INFO Response to the transport layer|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x10aaa8b8/msg=0x10bcb6e0, addr=172.20.250.60, port=52993, sentBy_port=52993, is_req=0, transport=2, switch=0, callBack=|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x10aaa8b8/Proceedable for sending msg immediately|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x10aaa8b8/switch transport is 0|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x10aaa8b8/Trying to send resp=0x10bcb6e0 to default port=52993|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x0/Registering gcb=0x10aaa8b8 with connection=0xffd1d38 context list|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x10aaa8b8/Connection obtained...sending msg=0x10bcb6e0|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x0/Posting send for msg=0x10bcb6e0, addr=172.20.250.60, port=52993, connId=8067 for TCP|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x0/sipSPIAssignCcbLastRequest, #### Current ccb(0x10aaa8b8)->last_request = 0xb204ad18, refCount = 2|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x10aaa8b8/****Adding to UAS Request table.|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x10aaa8b8/Added to table. ccb=0x10aaa8b8 key=d600811c-0d11000c-4c93ea29-78e643b7 at 172.20.250.6072d600811c0d11001751400975-00483fd2|1,100,63,1.73806^172.20.250.60^*<mailto:key=d600811c-0d11000c-4c93ea29-78e643b7 at 172.20.250.6072d600811c0d11001751400975-00483fd2|1,100,63,1.73806%5e172.20.250.60%5e*>
22:21:45.047 |//SIP/SIPHandler/ccbId=128990/scbId=0/findDevicePID: Routed to SIPStationInit|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Event/0x10aaa8b8/
ccsip_api_register_ind return value : SIP_SUCCESS
|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x10aaa8b8/Adding call id 1f7de to table|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/States/0x10aaa8b8/0x10aaa8b8 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE)|1,100,63,1.73806^172.20.250.60^*
22:21:45.048 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|1,100,63,1.73806^172.20.250.60^*
22:21:45.048 |//SIP/Stack/Info/0x0/httpish_msg_free, #### Freed msg=0x10bcb6e0|1,100,71,1.1^*^*
22:21:45.049 |SIPRegistrationTable::addToConnectionIndexTableInternal: Adding BOT123456789 to connectionIndexTable|*^*^*
22:21:45.049 |SIPStationInit: connId=8067, BOT123456789, 120683ae-0fca-8bc2-bd00-35e584c45cdc at 172.20.250.60:52993<http://120683ae-0fca-8bc2-bd00-35e584c45cdc@172.20.250.60:52993>, New [static] device registering over (Non-Shared) TCP transport|1,100,63,1.73806^172.20.250.60^*
22:21:45.049 |SIPStationInit: connID=8067, BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, DevStat-InitState: STATIC_IDLE --> REGISTERED|1,100,63,1.73806^172.20.250.60^*
22:21:45.049 |SIPStationInit: connID=8067, BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, checkTcpConnection: Connection index 8067 matches|1,100,63,1.73806^172.20.250.60^*
22:21:45.049 |SIPStationInit: connID=8067, BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, Routing to D (1,100,201,46), regCount=1|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.049 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:59007<http://172.20.250.60:59007>, primaryDN=72, parseRegisterRemoteccBody: X-cisco-callinfo=F X-cisco-serviceuri=F X-cisco-escapecodes=F X-cisco-service-control=F X-cisco-duplicate-reg=F X-cisco-srtp-fallback=F X-cisco-monrec=F X-cisco-xsi=F xsi-version=8765.217030272.195060966 X-cisco-sis=F sis-version=195066871.3069999229.3069999244 extended-refer=F norefersub=F join=F cso[rcc=T,sc=T,max=6] slNotify=3 hsNotify=283537204 blfNotify=3 fi[joinReq=T,cfwdAny=T,coaching=T,oosAlarm=T,joinDXferPolicy=2,OrigCalled=F]MobilityInd[dynamicDVOSelection=T,mediaConnectionReuse=T,holdResume=T,transfer=T,conference=T,park=T,dPark=T,manualHandoff=T,sessionHandoff=T,redial_DVOF=T,singleRegistration=T,manualLCRHandoff=T]|0,0,0,0.0^*^*
22:21:45.049 |DMMS: (0000046) checkSingleRegRequest -- new single registration message|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.049 |DMMS: (0000046) SIPRegisterInd, single registration case. MtPreferredMode = 1, oldMtPreferredMode = 0|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.049 |DMMSStationD-SD(1,100,201,46), , UNKNOWN:0, primaryDN=UNKNOWN, Primary expires 120, secondary expires 3600|0,0,0,0.0^*^*
22:21:45.049 |DMMSStationD-SD(1,100,201,46), , 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, setUaTypeAndCepn: uaType is CISCO_ENHANCED_PHONE|0,0,0,0.0^*^*
22:21:45.049 |DMMSStationD-SD(1,100,201,46), , 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, wait_register_SIPRegisterInd: model=575, mUaType=1|0,0,0,0.0^*^*
22:21:45.049 |DMMSStationD-SD(1,100,201,46), , 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, Register instanceId (MAC addr) =D600811C0D11|0,0,0,0.0^*^*
22:21:45.049 |DMMSStationD-SD(1,100,201,46), , 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, Register deviceName (database key) =BOT123456789|0,0,0,0.0^*^*
22:21:45.049 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, DevStat-Start : transport TCP, model 575|0,0,0,0.0^*^*
22:21:45.049 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseSupportedHeader: X-cisco-callinfo=T X-cisco-serviceuri=T X-cisco-escapecodes=T X-cisco-service-control=T X-cisco-duplicate-reg=T X-cisco-srtp-fallback=T X-cisco-monrec=T X-cisco-xsi=T xsi-version=8.5.1 X-cisco-sis=T sis-version=7.0.0 extended-refer=T norefersub=T join=T cso[rcc=F,sc=F,max=0] slNotify=0 hsNotify=0 blfNotify=0 fi[joinReq=F,cfwdAny=F,coaching=F,oosAlarm=F,joinDXferPolicy=2,OrigCalled=F]MobilityExtension Feature Indication is empty|0,0,0,0.0^*^*
22:21:45.050 |CcmCcmdbHelper::getProcessConfigGivenProcessnodeParamnameService - Value of DeviceMobilityMode from process config table is (0)|*^*^*
22:21:45.050 |CcmCcmdbHelper::getProcessConfigGivenProcessnodeParamnameService - Value of DeviceMobilityMode from process config table is (0)|*^*^*
22:21:45.050 |GetMaxCallForDevice: cannot find entry in ProductSupportsFeature table for product 462 and protocol 11feature SUPPORTS_FEATURE_RESTRICT_MAX_CALLS|*^*^*
22:21:45.050 |GetMaxCallForDevice: cannot find entry in ProductSupportsFeature table for product 462 and protocol 99feature SUPPORTS_FEATURE_RESTRICT_MAX_CALLS|*^*^*
22:21:45.050 |getLiteDnAndMwiData #### device name(BOT123456789): old maxLineIndexSeen = 0, new maxLineIndexSeen = 1|*^*^*
22:21:45.050 |getLiteDnAndMwiData #### device name(BOT123456789): maxLineIndexSeen changed!|*^*^*
22:21:45.051 |getMaxAuthnameLenFromModel: cannot find entry in ProductSupportsFeature table for model 575 and protocol 11 feature SUPPORTS_FEATURE_MAXIMUM_AUTHNAME_LENGTH|*^*^*
22:21:45.051 |getMaxAuthnameLenFromModel: cannot find entry in ProductSupportsFeature table for model 575 and protocol 99 feature SUPPORTS_FEATURE_MAXIMUM_AUTHNAME_LENGTH|*^*^*
22:21:45.051 |getMaxCallForDeviceFromModel: ProductSupportsFeature table entry for model 575 and feature SUPPORTS_FEATURE_MULTIPLE_CALL_DISPLAY has deviceprotocol = 11 maxcalls = 3|*^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, trustedDevice == 1, deviceSecurityMode == 1, clusterSecurityMode == 0, exp = 3/1|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, VerFeatMask=0x00000000, hasCrypto=0|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, mtpRequired = 0 useTRP = 0 mtpPrefOrgCodec = 1 unAttendedPort = 0 requireDTMFReception = 0 packetCaptureMode = 0 packetCaptureDuration = 0|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, securityProfileName = Cisco Dual Mode for Android - Standard SIP Non-Secure Profile, nonceValidityTime = 600 transportTypeValue = 4 digestAuthorization = 0|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, deviceSecurityMode = 1|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, subscribeCSS = 4c877f5e-e0e5-5e50-67c6-626856dc2eb7 css_reroute = 4c877f5e-e0e5-5e50-67c6-626856dc2eb7 css_ood = presenceGroup = ad243d17-98b4-4118-8feb-5ff2e1b781ac dialRules = |0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, versionStamp = 1413457250-0647b8c0-5ef2-4e32-9d74-34d021a27da9, softkeyTemplateVersionStamp = 1334731085-f729f55a-7710-4d08-8190-f8bb9cf9bc9f, dialRulesVersionStamp = , featureControlVersionStamp = 1372623678-fd2190e8-290d-4d32-a98b-c2e959753428|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, SIPDigestUserEntry: userName = credentials = SIPDevice = BOT123456789 enduserPresenceGroup = |0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, SIPProfile: SIPProfileInfoName = Jabber-Android rerouteType = 0 timeInviteExpires = 180 timerRegDelta = 30 timerRegExpires = 660|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, SIPProfile: timerT1 = 500, timerT2 = 4000, retryInvite = 6, tryOnInvite = 10, startMediaPort = 16384, ringing180 = 0 defaultTelephonyEvent = 101|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, callPickupURI = [x-cisco-serviceuri-pickup] callPickupGroupURI = [x-cisco-serviceuri-gpickup]|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, callPickupOtherGroupURI = [x-cisco-serviceuri-opickup] meetMeURI = [x-cisco-serviceuri-meetme]|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, callForwardURI = [x-cisco-serviceuri-cfwdall] abbrdialURI = [x-cisco-serviceuri-abbrdial]|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, blfpickupURI = [x-cisco-serviceuri-blfpickup]|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, Device Mobility: MobileDevice = false DevicePoolPkid = 20775d43-2f7d-55e1-c1c7-6a0573c11257 RoamingDevicePoolPkid = PhysicalLocation = AARNeighborHood = |0,0,0,0.0^*^*
22:21:45.051 |<MN::Directory Number><MV::72>|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, initLineStruct: Initializing index 1, DN 72|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, initLineStruct: MonRec data: recType=0, recDN=, recCSS=, monCSS=|0,0,0,0.0^*^*
22:21:45.051 |setIsWildcardDN: digits 72, isWildcardDN false|*^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, isPrivacyButtonConfigured:false isPrivacyOn:true|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, checkDNsReceived: TotalCount=1|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, sendLineRegisterReq: mLineRegisterReqsOutstanding=1|0,0,0,0.0^*^*
22:21:45.053 |checkTCPReconnectEnabled: TCP Reconnect feature is enabled for device model (575) and protocal (11)|*^*^*
22:21:45.053 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, transport TCP security None, model 575|0,0,0,0.0^*^*
22:21:45.053 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, setOptionsIndicationDefaultOptions: INFO - user agent 1|0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseRegisterRemoteccBody: X-cisco-callinfo=T X-cisco-serviceuri=T X-cisco-escapecodes=T X-cisco-service-control=T X-cisco-duplicate-reg=T X-cisco-srtp-fallback=T X-cisco-monrec=T X-cisco-xsi=T xsi-version=8.5.1 X-cisco-sis=T sis-version=7.0.0 extended-refer=T norefersub=T join=T cso[rcc=T,sc=T,max=6] slNotify=3 hsNotify=1 blfNotify=3 fi[joinReq=T,cfwdAny=T,coaching=T,oosAlarm=T,joinDXferPolicy=2,OrigCalled=F]MobilityInd[dynamicDVOSelection=T,mediaConnectionReuse=T,holdResume=T,transfer=T,conference=T,park=T,dPark=T,manualHandoff=T,sessionHandoff=T,redial_DVOF=T,singleRegistration=T,manualLCRHandoff=T]|0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, wait_register_SIPRegisterInd Supports Original Called 0 |0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseReasonHeader: SIP;cause=200;text="cisco-alarm:111 Name=BOT123456789 ActiveLoad=Jabber_for_Android-10.5.1 InactiveLoad=Jabber_for_Android-10.5.1 Last=Application-Requested-Destroy"|0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseFirmwareLoadId: Name=BOT123456789 ActiveLoad=Jabber_for_Android-10.5.1 InactiveLoad=Jabber_for_Android-10.5.1 Last=Application-Requested-Destroy|0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseLoad: ActiveLoad= name is: [Jabber_for_Android-10.5.1]|0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseLoad: InactiveLoad= name is: [Jabber_for_Android-10.5.1]|0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, line_register_LineRegisterRes: mLineRegisterReqsOutstanding=0|0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, storeContactHeader: Request-URI is sip:120683ae-0fca-8bc2-bd00-35e584c45cdc at 172.20.250.60:52993;transport=tcp|0,0,0,0.0^*^*<sip:120683ae-0fca-8bc2-bd00-35e584c45cdc at 172.20.250.60:52993;transport=tcp|0,0,0,0.0%5e*%5e*>
22:21:45.054 |setSIPAoR: aor = sip:72 at 172.20.10.5<mailto:sip%3A72 at 172.20.10.5>|*^*^*
22:21:45.054 |setIsWildcardDN: digits 72, isWildcardDN false|*^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, Started 1 second partially-registered alarm timer.|0,0,0,0.0^*^*
22:21:45.054 |getRestrictMaxCallForDevice: cannot find entry in ProductSupportsFeature table for model 575 and protocol 0feature SUPPORTS_FEATURE_RESTRICT_MAX_CALLS|*^*^*
22:21:45.054 |getRestrictMaxCallForDevice: cannot find entry in ProductSupportsFeature table for model 575 and protocol 99feature SUPPORTS_FEATURE_RESTRICT_MAX_CALLS|*^*^*
22:21:45.054 |StationCtiUtils::GetLoginStatus profilePkid is EMPTY|*^*^*
22:21:45.054 |StationCtiUtils::GetLoginStatus Incorrect EM Info|*^*^*
22:21:45.054 |getRestrictMaxCallForDevice: cannot find entry in ProductSupportsFeature table for model 575 and protocol 0feature SUPPORTS_FEATURE_RESTRICT_MAX_CALLS|*^*^*
22:21:45.054 |getRestrictMaxCallForDevice: cannot find entry in ProductSupportsFeature table for model 575 and protocol 99feature SUPPORTS_FEATURE_RESTRICT_MAX_CALLS|*^*^*
22:21:45.054 |StationCtiUtils::GetLoginStatus profilePkid is EMPTY|*^*^*
22:21:45.054 |StationCtiUtils::GetLoginStatus Incorrect EM Info|*^*^*
22:21:45.054 |StationCtiD::getDeviceAndLineInfo featureID=9|*^*^*
22:21:45.054 |CcmCcmdbHelper::getPickupGroupInfoGivenLinePkid - 120683ae-0fca-8bc2-bd00-35e584c45cdc : : |*^*^*
22:21:45.054 |CcmCcmdbHelper::getPickupGroupInfoGivenLinePkid - built list from numplan pkid 120683ae-0fca-8bc2-bd00-35e584c45cdc |*^*^*
22:21:45.054 |CcmCcmdbHelper::getPickupGroupInfoGivenLinePkid - found pickuptable entry|*^*^*
22:21:45.054 |CcmCcmdbHelper::getPickupGroupInfoGivenLinePkid - pattern is = 02071, Partition is = _XXXnet-0207_PhonePT|*^*^*
22:21:45.054 |StationCtiD::getDeviceAndLineInfo PickupDN=02071|*^*^*
22:21:45.054 |StationCtiD::getDeviceAndLineInfo PickupPartition=_XXXnet-0207_PhonePT|*^*^*
22:21:45.054 |//SIP/Stack/Info/0x0/sipConstructContainerContext #### Created container=0xb270dc00|1,100,71,1.1^*^*
22:21:45.054 |DMMS (0000046): singleRegCallBack_VoIP, resp=200, expires = 660|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.054 |MobileClientD(1,100,201,46) - state[wait] signal[SIPRegisterInd]|0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseSupportedHeader: X-cisco-callinfo=T X-cisco-serviceuri=T X-cisco-escapecodes=T X-cisco-service-control=T X-cisco-duplicate-reg=T X-cisco-srtp-fallback=T X-cisco-monrec=T X-cisco-xsi=T xsi-version=8.5.1 X-cisco-sis=T sis-version=7.0.0 extended-refer=T norefersub=T join=T cso[rcc=T,sc=T,max=6] slNotify=3 hsNotify=0 blfNotify=3 fi[joinReq=T,cfwdAny=T,coaching=T,oosAlarm=T,joinDXferPolicy=2,OrigCalled=F]MobilityInd[dynamicDVOSelection=T,mediaConnectionReuse=T,holdResume=T,transfer=T,conference=T,park=T,dPark=T,manualHandoff=T,sessionHandoff=T,redial_DVOF=T,singleRegistration=T,manualLCRHandoff=T]|0,0,0,0.0^*^*
22:21:45.055 |MobileClientD( 46) -- star_SIPRegisterInd: Setting the mPrimaryLineOptions|0,0,0,0.0^*^*
22:21:45.055 |MobileClientD( 46) -- star_SIPRegisterInd: Keep Alive timer got from CUMA = 3600|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseRegisterRemoteccBody: X-cisco-callinfo=T X-cisco-serviceuri=T X-cisco-escapecodes=T X-cisco-service-control=T X-cisco-duplicate-reg=T X-cisco-srtp-fallback=T X-cisco-monrec=T X-cisco-xsi=T xsi-version=8.5.1 X-cisco-sis=T sis-version=7.0.0 extended-refer=T norefersub=T join=T cso[rcc=T,sc=T,max=6] slNotify=3 hsNotify=0 blfNotify=3 fi[joinReq=T,cfwdAny=T,coaching=T,oosAlarm=T,joinDXferPolicy=2,OrigCalled=F]MobilityInd[dynamicDVOSelection=T,mediaConnectionReuse=T,holdResume=T,transfer=T,conference=T,park=T,dPark=T,manualHandoff=T,sessionHandoff=T,redial_DVOF=T,singleRegistration=T,manualLCRHandoff=T]|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseReasonHeader: SIP;cause=200;text="cisco-alarm:111 Name=BOT123456789 ActiveLoad=Jabber_for_Android-10.5.1 InactiveLoad=Jabber_for_Android-10.5.1 Last=Application-Requested-Destroy"|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseFirmwareLoadId: Name=BOT123456789 ActiveLoad=Jabber_for_Android-10.5.1 InactiveLoad=Jabber_for_Android-10.5.1 Last=Application-Requested-Destroy|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseLoad: ActiveLoad= name is: [Jabber_for_Android-10.5.1]|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseLoad: InactiveLoad= name is: [Jabber_for_Android-10.5.1]|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, Warning: Configured transport is unknown, cannot verify transport match.|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, setUaTypeAndCepn: uaType is CISCO_ENHANCED_PHONE|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, Duplicate device instance detected, terminating previous instance|0,0,0,0.0^*^*
22:21:45.055 |MobileClientD( 46) - sendDeviceUnregisteredAlarm ignored due to dual mode phone.|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, buildServiceControl: primary line (1) registration callID is null|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, sendServiceControlNotify: setting servicecontrol NOTIFY timer|0,0,0,0.0^*^*
22:21:45.055 |//SIP/Stack/Info/0x0/sipConstructContainerContext #### Created container=0xb2978c50|1,100,71,1.1^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, sendServiceControlNotify: sending service-control NOTIFY|0,0,0,0.0^*^*
22:21:45.055 |MobileClientD: ( 46) - shutdownDevice: , Name=BOT123456789 , shutting down the SIP side|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, DevStat-StopSoft : New device instance registering, terminating previous instance|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, Removing subscription for scbId 128991, pkgId 6|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, Terminating transaction for scbId 128991, pkgId 6|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, retryRegister: Caching REGISTER to be sent to SIPStationInit after shutdown complete|0,0,0,0.0^*^*
22:21:45.055 |//SIP/Stack/Info/0x0/sipDestructContainerContext #### Deleted container=0xb2b39758|1,100,71,1.1^*^*
22:21:45.055 |//SIP/Stack/Info/0x0/httpish_msg_free, #### Freed msg=0xb27c8db8|1,100,71,1.1^*^*
22:21:45.055 |//SIP/Stack/Info/0x0/sipDestructContainerContext #### Deleted container=0xb270dc00|1,100,71,1.1^*^*
22:21:45.055 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, DevStat-NewState : line 72: Null ==> Registered|0,0,0,0.0^*^*
22:21:45.056 |-->RISCMAccess::DeviceRegister(...)|*^*^*
22:21:45.056 |Device Register deviceName : BOT123456789, IPAdress : 172.20.250.60, IPv6Address : not shown, IPv4Attribute : 0, IPv6Attribute : 0, LoadID : , ActiveLoadID : Jabber_for_Android-10.5.1, InactiveLoadID : Jabber_for_Android-10.5.1, DeviceType : 575, Protocol : 2|*^*^*
22:21:45.056 |DebugMsg deviceName : BOT123456789, DeviceType : 575, risClass: 1|*^*^*
22:21:45.056 |SIP Device Register from Unregister: deviceName(BOT123456789), Protocol(2), RegisteredSCCP(203), RegisteredSIP(25)|*^*^*
22:21:45.056 |<--RISCMAccess::DeviceRegister(...)|*^*^*
22:21:45.058 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, sendDeviceRegisteredAlarm: Raised Device Registered Alarm with Serviceability|0,0,0,0.0^*^*
22:21:45.058 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, VerFeatMask=0xC1000015, hasCrypto=0|0,0,0,0.0^*^*
22:21:45.059 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, establishDialogSubscription: Added subscription for line 72 with scbId = 128992, pkgId = 4|0,0,0,0.0^*^*
22:21:45.059 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, buildServiceControl: Sending callid from mPrimaryLineNum 1: d600811c-0d11000c-4c93ea29-78e643b7 at 172.20.250.60<mailto:d600811c-0d11000c-4c93ea29-78e643b7 at 172.20.250.60>|0,0,0,0.0^*^*
22:21:45.059 |//SIP/Stack/Info/0x0/sipConstructContainerContext #### Created container=0xb270dc00|1,100,71,1.1^*^*
22:21:45.059 |MobileClientD(1,100,201,46) - state[wait] signal[LineRegisterRes]|0,0,0,0.0^*^*
22:21:45.059 |SIPEventPkg::subscribeReq address list is NOT empty|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.059 |//SIP/Stack/Info/0x0/sipConstructContainerContext #### Created container=0xb23c5850|1,100,71,1.1^*^*
22:21:45.059 |getCcdSafRouteFromAddrList SafTransAddrList is empty|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.059 |SIPEventPkg::subscribeReq exp = 28100 t = 26300 caller = 72, callee = 120683ae-0fca-8bc2-bd00-35e584c45cdc|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |DeviceManager::star_DmUpdateRoamingInfo - DeviceMobility is disabled for the device = [BOT123456789], Checking if we need to de-associate Roaming Device Pool.|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |DeviceManager::star_DmUpdateRoamingInfo - Roaming Device pool is empty or null|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Searching for SCB in subID table with key 128991|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Added context(0xd242000) with key=[125070] to table|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/SIPHandler/ccbId=0/scbId=128991/ccsip_platform_init_scb: scb mgmt added 0xd242000 (context_id=125070)|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0xd242000/Created new scb: 0xd242000 with id: 128991|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/SIPHandler/ccbId=0/scbId=0/populateTargetInfo: port=52993, isIPAddr=1, type=2, target_port=5060, outboundProxyAddr.isIPAddr=0, target_type=0|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 7 for event 204|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Event [SIPSPI_EV_CC_NOTIFY] received in State [SUBSCRIBE_STATE_IDLE]|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0xd242000/Inserting SCB with key 128991 into SubIDTable|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Created TCB: 0xb2221030|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/TCB 0xb2221030's parent is 0xd242000|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Transport/0x0/Dialog Transaction Address 172.20.250.60,Port 52993, Port Present TRUE, Transport 2, FQDN 183963413|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Generated Subscribe Key : b24d800-440128d9-4d27-50a14ac at 172.20.10.51330246167|1,100,63,1.73806^172.20.250.60^BOT123456789<mailto:b24d800-440128d9-4d27-50a14ac at 172.20.10.51330246167|1,100,63,1.73806%5e172.20.250.60%5eBOT123456789>
22:21:45.060 |//SIP/Stack/Info/0xd242000/Inserting SCB with key b24d800-440128d9-4d27-50a14ac at 172.20.10.51330246167<mailto:b24d800-440128d9-4d27-50a14ac at 172.20.10.51330246167> into ClientTable|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/httpish_msg_create, #### created msg=0xd1ea088 with refCount = 1|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/TCB 0xb2221030's method is 111|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/sipSPIUpdateTCBPackage, ##== Current tcb(0xb2221030)->package = 0xd1ea088, refCount = 1|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Client TCB's Marker Updated to: z9hG4bKaeefed130a3|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0xd242000/Entering ...|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Created new edb(0xb29c4ca0)|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/EDB updated with Event Name: service-control Event ID: |1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0xd242000/Q'd EDB(0xb29c4ca0) on SCB(0xd242000)|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0xd242000/There are 1 EDB's on edbQ|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Q'd TCB(0xb2221030) on EDB(0xb29c4ca0)|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0xd242000/There are 1 TCB's on tcbQ|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Entering ...|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Sending Package on tcb: 0xb2221030 and parent: 0xd242000
|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/httpish_msg_lock, Increment msg (0xd1ea088) counter, current msg->refCount = 2|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Transport/0xd242000/msg=0xd1ea088, addr=172.20.250.60, port=52993, sentBy_port=0, is_req=1, transport=2, switch=0, callBack=|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Transport/0xd242000/Proceedable for sending msg immediately|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Transport/0xd242000/switch transport is 0|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Transport/0x0/Registering gcb=0xd242000 with connection=0xffd1d38 context list|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Transport/0xd242000/Set to send the msg=0xd1ea088|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Transport/0x0/Posting send for msg=0xd1ea088, addr=172.20.250.60, port=52993, connId=8067 for TCP|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/Entering sipSPISentTCBClient...|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/Kicking off ReTx Timer for Client TCB (0xb2221030) with 0 retries and 32000 milliseconds initial retx |1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/Created timer context(0x10b65698)|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_CLIENT_TCB value=32000 retries=0|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_CLIENT_TCB value=32000 retries=0|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/Changing from State: SUBSCRIBE_STATE_IDLE to state SUBSCRIBE_STATE_ACTIVE|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/Searching for SCB in subID table with key 128991|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/SIPHandler/ccbId=0/scbId=0/wait_SIPSubTerminated: inDialogSubReferCounter=0 scbid=128991|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 7 for event 210|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/Event [SIPSPI_EV_SUBSCRIPTION_TERMINATED] received in State [SUBSCRIBE_STATE_ACTIVE]|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0xd242000/Resetting all EDB flags|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0xd242000/1 Transactions still active on Event:service-control ID:|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/Searching for SCB in subID table with key 128993|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/Added context(0x10d92e68) with key=[125071] to table|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/SIPHandler/ccbId=0/scbId=128993/ccsip_platform_init_scb: scb mgmt added 0x10d92e68 (context_id=125071)|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x10d92e68/Created new scb: 0x10d92e68 with id: 128993|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/SIPHandler/ccbId=0/scbId=0/populateTargetInfo: port=52993, isIPAddr=1, type=2, target_port=5060, outboundProxyAddr.isIPAddr=0, target_type=0|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/SIPTcp/wait_SdlSPISignal: Outgoing SIP TCP message to 172.20.250.60 on port 52993 index 8067
[740336,NET]
NOTIFY sip:72 at 172.20.250.60:52993<http://sip:72@172.20.250.60:52993> SIP/2.0
Via: SIP/2.0/TCP 172.20.10.5:5060;branch=z9hG4bKaeefed130a3
From: <sip:172.20.10.5>;tag=1330246167
To: <sip:72 at 172.20.250.60<mailto:sip%3A72 at 172.20.250.60>>
Call-ID: b24d800-440128d9-4d27-50a14ac at 172.20.10.5<mailto:b24d800-440128d9-4d27-50a14ac at 172.20.10.5>
CSeq: 101 NOTIFY
Max-Forwards: 70
Date: Thu, 16 Oct 2014 20:21:45 GMT
User-Agent: Cisco-CUCM8.6
Event: service-control
Subscription-State: active
Contact: <sip:172.20.10.5:5060;transport=tcp>
Content-Type: text/plain
Content-Length: 37
action=terminate
RegisterCallId={}
Da: bmeade90 at gmail.com<mailto:bmeade90 at gmail.com> [mailto:bmeade90 at gmail.com<mailto:bmeade90 at gmail.com>] Per conto di Brian Meade
Inviato: giovedì 16 ottobre 2014 16.13
A: Mauro Celli
Cc: cisco-voip at puck.nether.net<mailto:cisco-voip at puck.nether.net>
Oggetto: Re: [cisco-voip] R: Jabber 10.5 and Remotedestination, disconnected!
Nevermind, those seem to be 9.x traces but you mentioned CUCM version 8.5. Is this the full trace?
On Thu, Oct 16, 2014 at 10:10 AM, Brian Meade <bmeade90 at vt.edu<mailto:bmeade90 at vt.edu>> wrote:
Can you provide the SDI traces as well?
On Thu, Oct 16, 2014 at 6:16 AM, Mauro Celli <mauro.celli at 2000net.it<mailto:mauro.celli at 2000net.it>> wrote:
I have same problem in one cucm 8.5, and same problem if I install one older version of jabber 4 android.
When I enable Dial Via Office and I put one number in Mobility Identity, jabber stay offline in cucm.
If I install jabber voice, all work.
At sip I get
366 9.803706 172.16.2.32 10.0.8.10 SIP 1423 Request: REGISTER sip:10.0.8.10 (remove 1 binding) |
367 9.806513 10.0.8.10 172.16.2.32 SIP 375 Status: 100 Trying |
368 9.806590 10.0.8.10 172.16.2.32 SIP 403 Status: 500 Internal Server Error |
At cucm I get
00426426.000 |11:59:36.442 |SdlSig-I |CtiDeviceRegisterNotifyWithLineInfo |wait |CTIRegistrar(1,100,223,1) | |1,100,13,4805.3^172.16.2.32^BOT12345678 |[R:N-H:0,N:2,L:1,V:0,Z:0,D:0] deviceName=BOT12345678 EventContent=14 DeviceConfigInfo= Locale=7 AltScrpt= DNDOption=1 ConfigIpAddrMode=3 Hotelling=F Restricted=T OutboundRO=0 BIB=F DNDStatus=F LoginStatus=0 DeviceRegistrationInfo= TerminateMedia=5 ActiveIPAddrMode=0 IPv4=537006252 IPv6= LoginUser=409 ProfilePkid= Encoding=3 CtiControllable=2 AppCapability=20 UserCapability=20Protocol=2 TotalLines=1 LineCount=1 MoreLines=F DN = 409 Part = Phone-0305_PhonePT PkGpDN = PkGpPart = num RD = 0 StationPid(0,0,0,0) CTIHandlerId(0) LoginStatus=0
00426426.001 |11:59:36.442 |AppError |wait_CtiDeviceRegisterNotifyWithLineInfo ENTER Device=BOT12345678
00426426.002 |11:59:36.442 |AppError | senderPid=(0,0) StationPid=(0,0)
00426427.000 |11:59:36.442 |SdlSig |SNFSubscribeReq |init |Subscriber(1,100,56,295) |DMMSStationD(1,100,206,11) |1,100,13,4805.3^172.16.2.32^BOT12345678 |[R:N-H:0,N:1,L:1,V:0,Z:0,D:0] SNFSubscriptionId = 1|0|912, SNFSubscriptionMsg contents: SNFSubscriptionType = , duration = 35910, routeFlag = true, authFlag = true, forwardFlag = true, cacheStateFlag = false, bulkUnSubscribeFlag = false, subscriber = 409, subscribee = 3dc4b3e1-cec9-9f52-eb16-d56d4b3de81f, subscriberUrl = , subscribeeUrl = , subCSS = , srcPresGrpPkid = , destinationPid = (0,0,0), destinationCepn = , pretransformTagsList = , pretransformPositionalMatchList = FDataType=0opId=0ssType=0 SsKey=0invokeId=0resultExp=Fbpda=F
00426428.000 |11:59:36.442 |SdlSig |DeviceLineRegister |restart0 |LineControl(1,100,167,235) |DMMSStationD(1,100,206,11) |1,100,13,4805.3^172.16.2.32^BOT12345678 |[R:L-H:0,N:0,L:0,V:0,Z:0,D:0] PID=(1, 100, 206, 11) Type=575 BusyTrigger=2 Privacy=T Limit=3 UserMOH=0 NetMOH=0 PresGrp=ad243d17-98b4-4118-8feb-5ff2e1b781ac DNDStatus=F DNDOption=1 users.size()=1 users=409; model=Cisco-SOUNDWAVE Mac=D600811C0D11 HLoggedStatus= T
00426429.000 |11:59:36.442 |SdlSig |PublishInd |wait |PublishManager(1,100,105,1) |LineControl(1,100,167,235) |1,100,13,4805.3^172.16.2.32^BOT12345678 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] users.size()=1 users=409; pattern=409 numPlanPkid=3dc4b3e1-cec9-9f52-eb16-d56d4b3de81f devicePkid=c6b22498-d169-56b1-d7ca-b8432236a9e0 mobileNumber=3473499480 model=Cisco-SOUNDWAVE state=1 isDnd=F firstRegisterDevice=T deviceMac=D600811C0D11
00426430.000 |11:59:36.442 |SdlSig |PublishInd |restart0 |PublishEPA(1,100,106,14) |PublishManager(1,100,105,1) |1,100,13,4805.3^172.16.2.32^BOT12345678 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] users.size()=1 users=409; pattern=409 numPlanPkid=3dc4b3e1-cec9-9f52-eb16-d56d4b3de81f devicePkid=c6b22498-d169-56b1-d7ca-b8432236a9e0 mobileNumber=3473499480 model=Cisco-SOUNDWAVE state=1 isDnd=F firstRegisterDevice=T deviceMac=D600811C0D11
00426431.000 |11:59:36.443 |SdlSig |SIPNotifyReq |wait |SIPHandler(1,100,72,1) |DMMSStationD(1,100,206,11) |1,100,13,4805.3^172.16.2.32^BOT12345678 |[T:N-H:0,N:4,L:0,V:0,Z:0,D:0] --TransType=1 --TransSecurity=0 PeerAddr = 172.16.2.32:51237<http://172.16.2.32:51237> addrList: ||
00426432.000 |11:59:36.443 |SdlSig |SIPSubTerminated |wait |SIPHandler(1,100,72,1) |DMMSStationD(1,100,206,11) |1,100,13,4805.3^172.16.2.32^BOT12345678 |[T:N-H:0,N:3,L:0,V:0,Z:0,D:0] --TransType=1 --TransSecurity=0 PeerAddr=172.16.2.32:51237<http://172.16.2.32:51237> scbId=1927 event.id<http://event.id>= event.name<http://event.name>=service-control eventType=0
00426433.000 |11:59:36.443 |SdlSig |DmUpdateRoamingInfo |initialized |DeviceManager(1,100,199,1) |DMMSStationD(1,100,206,11) |1,100,13,4805.3^172.16.2.32^BOT12345678 |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] Name=BOT12345678 Cepn=c6b22498-d169-56b1-d7ca-b8432236a9e0 Type=575 ccmType=4 IsActive=T Pid=1,100,206,11 RoamIP= RoamInfoAction=0
00426434.000 |11:59:36.443 |SdlSig |SIPReferReq |wait |SIPHandler(1,100,72,1) |DMMSStationD(1,100,206,11) |1,100,13,4805.3^172.16.2.32^BOT12345678 |[T:N-H:0,N:2,L:0,V:0,Z:0,D:0] ccbID= 0 --TransType=1 --TransSecurity=0 PeerAddr = 172.16.2.32:51237monitorCall=F
00426435.000 |11:59:36.443 |SdlSig |SIPSubscribeReq |wait |SIPHandler(1,100,72,1) |Subscriber(1,100,56,295) |1,100,13,4805.3^172.16.2.32^BOT12345678 |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] ccbID=0 --TransType=1 --TransSecurity=0 PeerAddr=172.16.2.32:51237<http://172.16.2.32:51237> scbId=1928 addrList: ||
00426436.000 |11:59:36.443 |SdlSig |SIPPublishReq |wait |SIPHandler(1,100,72,1) |PublishEPA(1,100,106,14) |1,100,13,4805.3^172.16.2.32^BOT12345678 |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] scbId=1930 event=presence duration=3600 sip_if_match=4b71e8ef-0001-4356-b8f5-86fa90516da6 callingNumber=409 calledNumber=409 TransType=1 --TransSecurity=0 PeerAddr = 10.0.8.11:5070<http://10.0.8.11:5070> addrList: ||
00426437.000 |11:59:36.444 |SdlSig |SdlConnectReq |started |SdlTCPService(1,100,10,1) |SIPTcp(1,100,64,1) |1,100,13,4805.3^172.16.2.32^BOT12345678 |*TraceFlagOverrode
00426437.001 |11:59:36.444 |Created | | |SdlTCPConnector(1,100,12,82) |SdlTCPService(1,100,10,1) | |NumOfCurrentInstances: 1
00426438.000 |11:59:36.447 |Created | | |SdlTCPConnection(1,100,13,4806) |SdlTCPConnector(1,100,12,82) | |NumOfCurrentInstances: 181
00426439.000 |11:59:36.447 |Stopping | | |SdlTCPConnector(1,100,12,82) |SdlTCPConnector(1,100,12,82) | |NumOfCurrentInstances: 1
00426440.000 |11:59:36.568 |SdlSig |SIPSubscribeResult |gsm |DMMSStationD(1,100,206,11) |SIPHandler(1,100,72,1) |1,100,13,4805.5^172.16.2.32^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] --TransType=1 --TransSecurity=0 PeerAddr = 172.16.2.32:51237<http://172.16.2.32:51237>
00426440.001 |11:59:36.568 |AppInfo |DMMS: (0000011) fireSignalBySIPConnIndex, can't find connection index for this SIP signal. This could be an old signal.
00426441.000 |11:59:36.574 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPTcp(1,100,64,1) |1,100,13,4805.6^172.16.2.32^* |*TraceFlagOverrode
00426441.001 |11:59:36.574 |AppInfo |//SIP/Stack/Error/0xb41a32d8/sipSPICheckFromToRequest:
Failed FROM/TO Request check - IGNORE IF HAIRPIN CALL
old_from
00426441.002 |11:59:36.574 |AppInfo |//SIP/Stack/Error/0xb41a6248/sipSPIUaddCcbToTable: Could not add ccb to table. ccb=0xb41a6248 key=d600811c-0d11001c-3d913449-0601d4e4 at 172.16.2.32409d600811c0d11004037f1f478-06249c20<mailto:key=d600811c-0d11001c-3d913449-0601d4e4 at 172.16.2.32409d600811c0d11004037f1f478-06249c20>
00426441.003 |11:59:36.574 |AppInfo |//SIP/Stack/Error/0xb41a6248/sact_idle_new_message_register: CCSIP_REGISTER:: Problem adding CCB to UAS Table
00426442.000 |11:59:36.611 |SdlSig |SIPReferResult |gsm |DMMSStationD(1,100,206,11) |SIPHandler(1,100,72,1) |1,100,13,4805.7^172.16.2.32^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] ccbID= 0 --TransType=1 --TransSecurity=0 PeerAddr= 172.16.2.32:51237<http://172.16.2.32:51237> respCode=200 scbID= 1929
00426442.001 |11:59:36.611 |AppInfo |DMMS: (0000011) fireSignalBySIPConnIndex, can't find connection index for this SIP signal. This could be an old signal.
00426443.000 |11:59:36.908 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00426444.000 |11:59:37.016 |SdlSig |ReapOldTokenRegistrationsTimer |wait |SIPStationInit(1,100,65,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:1,N:0,L:0,V:0,Z:0,D:0]
00426445.000 |11:59:37.916 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,202,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0
00426446.000 |11:59:38.264 |SdlSig |DeviceEventReceiptMonitoringTimer |wait |StationInit(1,100,57,1) |SdlTimerService(1,100,3,1) |1,100,139,1.1^*^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00426447.000 |11:59:38.264 |SdlStat |Period: 6s #Lines: 60 #Bytes: 16329 Total Number of Buffers: 10000 Free LWM: 9972 Free LWM(total): 9721
00426448.000 |11:59:38.294 |SdlSig |SIPPublishResult |waitForResponse |PublishEPA(1,100,106,14) |SIPHandler(1,100,72,1) |1,100,13,4806.2^10.0.8.11^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] scbId=1930 respCode=200 event=presence TransType=1 --TransSecurity=0 PeerAddr=10.0.8.11:5070<http://10.0.8.11:5070>
00426449.000 |11:59:38.599 |SdlSig |SdlCloseAck |wfCloseAck |SdlTCPConnection(1,100,13,4805) |SIPTcp(1,100,64,1) |1,100,13,4805.8^*^* |*TraceFlagOverrode
00426449.001 |11:59:38.599 |Stopping | | |SdlTCPConnection(1,100,13,4805) |SdlTCPConnection(1,100,13,4805) | |NumOfCurrentInstances: 181
00426450.000 |11:59:38.599 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPTcp(1,100,64,1) |1,100,13,4805.8^*^* |*TraceFlagOverrode
00426450.001 |11:59:38.599 |AppInfo |//SIP/Stack/Error/0xb41a32d8/sipTransportPostSendFailure: Posting send failure msg with tcb:(nil) reason=0
00426451.000 |11:59:38.599 |SdlSig |SIPConnControlInd |wait |SIPHandler(1,100,72,1) |SIPTcp(1,100,64,1) |1,100,13,4805.8^*^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] controlOperation=1 IP=172.16.2.32 connIndex=192
00426452.000 |11:59:38.599 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,72,1) |SIPHandler(1,100,72,1) |1,100,13,4805.8^*^* |*TraceFlagOverrode
00426452.001 |11:59:38.599 |AppInfo |//SIP/Stack/Error/0x0/act_incoming_register_send_msg_failure: Send Error to 172.16.2.32:51237<http://172.16.2.32:51237> for transport TCP
00426453.000 |11:59:38.599 |SdlSig |SIPConnControlInd |wait |SIPStationInit(1,100,65,1) |SIPHandler(1,100,72,1) |1,100,13,4805.8^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] controlOperation=1 IP=172.16.2.32 connIndex=192
00426452.002 |11:59:38.599 |AppInfo |//SIP/Stack/Error/0xb41a32d8/sipSPIUdeleteCcbFromTable: Entry not found for search key d600811c-0d11001c-3d913449-0601d4e4 at 172.16.2.32409d600811c0d11004037f1f478-06249c20<mailto:d600811c-0d11001c-3d913449-0601d4e4 at 172.16.2.32409d600811c0d11004037f1f478-06249c20>
00426454.000 |11:59:38.599 |SdlSig |SIPConnControlInd |gsm |DMMSStationD(1,100,206,11) |SIPStationInit(1,100,65,1) |1,100,13,4805.8^*^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] controlOperation=1 IP=172.16.2.32 connIndex=192
00426454.001 |11:59:38.599 |AppInfo |DMMS: (0000011) fireSignalBySIPConnIndex, can't find connection index for this SIP signal. This could be an old signal.
00426452.003 |11:59:38.604 |AppInfo |//SIP/Stack/Error/0x0/sipConnectionManagerUnregisterCtxtInCon: Connection not found for addr=172.16.2.32, port=51237
00426452.004 |11:59:38.604 |AppInfo |//SIP/Stack/Error/0x0/httpish_msg_free: Freeing NULL pointer!
00426455.0
Da: cisco-voip [mailto:cisco-voip-bounces at puck.nether.net<mailto:cisco-voip-bounces at puck.nether.net>] Per conto di Mauro Celli
Inviato: mercoledì 15 ottobre 2014 18.10
A: cisco-voip at puck.nether.net<mailto:cisco-voip at puck.nether.net>
Oggetto: [cisco-voip] Jabber 10.5 and Remotedestination, disconnected!
Hi,
I have installed a server cups on a CUCM 9.1 and everything seemed to work properly.
Now as soon as I enable a "remote destination" in the jabber for android phone,
the phone is logged on the server cups but remains unregistered on the CUCM server.
Just take off the remote destination from the phone and reconnects.
Ideas?
thanks
_______________________________________________
cisco-voip mailing list
cisco-voip at puck.nether.net<mailto:cisco-voip at puck.nether.net>
https://puck.nether.net/mailman/listinfo/cisco-voip
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://puck.nether.net/pipermail/cisco-voip/attachments/20141016/19b12457/attachment.html>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: capData.zip
Type: application/x-zip-compressed
Size: 1115053 bytes
Desc: capData.zip
URL: <https://puck.nether.net/pipermail/cisco-voip/attachments/20141016/19b12457/attachment.bin>
More information about the cisco-voip
mailing list