[cisco-voip] R: Jabber 10.5 and Remotedestination, disconnected!
Brian Meade
bmeade90 at vt.edu
Thu Oct 16 17:15:45 EDT 2014
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> 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>;tag=d600811c0d11001751400975-00483fd2
>
> To: <sip:72 at 172.20.10.5>
>
> Call-ID: 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="BOT123456789";+u.sip!model.ccm.cisco.com
> ="575";+u.sip!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^*
>
> 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, 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, 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, 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, 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, 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,
> 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,
> 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,
> 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,
> 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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^*^*
>
> 22:21:45.054 |setSIPAoR: aor = sip:72 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, 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, primaryDN=72, buildServiceControl: Sending callid
> from mPrimaryLineNum 1: 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
>
> 22:21:45.060 |//SIP/Stack/Info/0xd242000/Inserting SCB with key
> 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 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>
>
> Call-ID: 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] *Per conto di *Brian
> Meade
> *Inviato:* giovedì 16 ottobre 2014 16.13
> *A:* Mauro Celli
> *Cc:* 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> 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>
> 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 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 scbId=1927
> event.id= 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
> 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
> 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
>
> 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
>
> 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 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
>
> 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 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
>
> 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] *Per conto
> di *Mauro Celli
> *Inviato:* mercoledì 15 ottobre 2014 18.10
> *A:* 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
> 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/e4de310f/attachment.html>
More information about the cisco-voip
mailing list