cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
Announcements
2001
Views
0
Helpful
10
Replies
danplacek
Enthusiast

DX-650 - Screen Flashing

We have a DX-650 deployed for one of our executives. The phone screen flashes every couple minutes. It sort of looks like an application opens then closes instantly -- this causes the screen to brighten and then dim. As you might imagine this becomes quite obnoxious.

 

Is anyone else experiencing this?

Any thoughts on what I could check to see what is causing it?

 

Thank you.

10 REPLIES 10
Brian Meade
Rising star

Pulling the console logs from the phone's webpage may give some input into which application is launching causing the issue.  Logcat log file is what would have that info.

Any idea what I might be looking for? The log contains quite a bit of data - nothing is immediately jumping out at me.

You'll probably need to correspond timestamps of when the flickering happens to help narrow it down.

I compared when it happened the the syslogs and I believe I have located the relevant section. If I am reading this right it either has something to do with Extension Mobility or UDS --  we are not using EM at all in this environment.
 
Can you help interpret?
 
Thank you.
 
--
 
07194 07-25 13:38:07.859  1847  1910 INF ccservice-j: AlarmService: : setAlarm: ms=0
07195 07-25 13:38:07.859  1847  1910 INF ccservice-j: CcService: : found Class interface cip.sessionmgr.api.AlarmIF
07196 07-25 13:38:07.859  1847  1910 INF ccservice-j: AlarmService: : setAlarm: ms=47500
07197 07-25 13:38:07.859  1847  1910 INF ccservice-j: CcService: : found Class interface cip.sessionmgr.api.AlarmIF
07198 07-25 13:38:07.859  1847  1910 INF ccservice-j: AlarmService: : setAlarm: ms=0
07199 07-25 13:38:07.859  1847  1910 INF ccservice-j: CcService: : found Class interface cip.sessionmgr.api.AlarmIF
07200 07-25 13:38:07.859  1847  1965 DEB ccservice: SIPCC-SIP_MSG_SEND: ccsip_register_send_msg: cmd=86=SIP_REG_CANCEL ndx=401
07201 07-25 13:38:07.859  1847  1965 DEB ccservice: SIPCC-SIP_STATE: 1/0, sip_stop_ack_timer:  ccb->index=401 ack_timer_index=200 
07202 07-25 13:38:07.859  1847  1965 DEB ccservice: SIPCC-SIP_STATE: 1/0, sip_start_ack_timer:  ccb->index=401 ack_timer_index=200 
07203 07-25 13:38:07.859  1847  1910 INF ccservice-j: AlarmService: : setAlarm: ms=47500
07204 07-25 13:38:07.859  1847  1910 INF ccservice-j: CcService: : found Class interface cip.sessionmgr.api.AlarmIF
07205 07-25 13:38:07.859  1847  1910 INF ccservice-j: AlarmService: : setAlarm: ms=0
07206 07-25 13:38:07.859  1847  1910 INF ccservice-j: CcService: : found Class interface cip.sessionmgr.api.AlarmIF
07207 07-25 13:38:07.859  1847  1965 INF ccservice: ccsip_messaging: sipSPIAddContactHeader: CFGID_DEVICE_NAME = SEPC4143C96FF65
07208 07-25 13:38:07.859  1847  1965 INF ccservice: ccsip_messaging: sipSPIAddContactHeader: ccb->call_mode = 0, display_name = 402
07209 07-25 13:38:07.859  1847  1965 DEB ccservice: SIP : sipTransportCreateSendMessage : sippmh_write() with message size=[861] 
07210 07-25 13:38:07.859  1847  1965 DEB ccservice: SIPCC-SIP_TRANS: sipTransportSendMessage: bypassing ccm check
07211 07-25 13:38:07.859  1847  1965 DEB ccservice: SIPCC-SIP_TRANS: sipTransportSendMessage: Sip msg sent handle=<44>,length=<861>, message=
07212 07-25 13:38:07.859  1847  1965 DEB ccservice: 
07213 07-25 13:38:07.859  1847  1965 DEB ccservice: sipio-sent---> REGISTER sip:10.1.1.10 SIP/2.0
07213 07-25 13:38:07.859  1847  1965 DEB ccservice: Via: SIP/2.0/UDP 10.1.1.81:5060;branch=z9hG4bK7370480e
07213 07-25 13:38:07.859  1847  1965 DEB ccservice: From: <sip:402@10.1.1.10>;tag=c4143c96ff6563aa626ad6cb-318d6696
07213 07-25 13:38:07.859  1847  1965 DEB ccservice: To: <sip:402@10.1.1.10>
07213 07-25 13:38:07.859  1847  1965 DEB ccservice: Call-ID: c4143c96-ff650067-675b6dc2-6f5311e2@10.1.1.81
07213 07-25 13:38:07.859  1847  1965 DEB ccservice: Max-Forwards: 70
07213 07-25 13:38:07.859  1847  1965 DEB ccservice: Date: Fri, 25 Jul 2014 18:38:07 GMT
07213 07-25 13:38:07.859  1847  1965 DEB ccservice: CSeq: 26765 REGISTER
07213 07-25 13:38:07.859  1847  1965 DEB ccservice: User-Agent: Cisco-CP-DX650/10.1.2
07213 07-25 13:38:07.859  1847  1965 DEB ccservice: Contact: <sip:7c8ea5ad-d01c-f631-a79c-af61a128aef0@10.1.1.81:5060;transport=udp>;+sip.instance="<urn:uuid:00000000-0000-0000-0000-c4143c96ff65>";+u.sip!devicename.ccm.cisco.com="SEPC4143C96FF65";+u.sip!model.ccm.cisco.com="647";video
07213 07-25 13:38:07.859  1847  1965 DEB ccservice: 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
07213 07-25 13:38:07.859  1847  1965 DEB ccservice: Content-Length: 0
07213 07-25 13:38:07.859  1847  1965 DEB ccservice: Expires: 0
07213 07-25 13:38:07.859  1847  1965 DEB ccservice: 
07214 07-25 13:38:07.859  1847  1910 INF ccservice-j: AlarmService: : setAlarm: ms=33000
07215 07-25 13:38:07.859  1847  1910 INF ccservice-j: CcService: : found Class interface cip.sessionmgr.api.AlarmIF
07216 07-25 13:38:07.859  1847  1910 INF ccservice-j: AlarmService: : setAlarm: ms=0
07217 07-25 13:38:07.859  1847  1910 INF ccservice-j: CcService: : found Class interface cip.sessionmgr.api.AlarmIF
07218 07-25 13:38:07.859  1847  1965 DEB ccservice: SIPCC-SIP_MSG_SEND: ccsip_store_send_msg_for_alarm: Sent:REGISTER sip:10.1.1.10 SIP/2.0  Cseq:26765 REGISTER CallId:c4143c96-ff650067-675b6dc2-6f5311e2@10.1.1.81
07219 07-25 13:38:07.859  1847  1965 DEB ccservice: SIPCC-SIP_STATE: 401/1, sip_reg_sm_change_state: Registration state change: SIP_REG_STATE_UNREGISTERING ---> SIP_REG_STATE_UNREGISTERING
07220 07-25 13:38:07.859  1847  1910 INF ccservice-j: AlarmService: : setAlarm: ms=1500
07221 07-25 13:38:07.859  1847  1910 INF ccservice-j: CcService: : found Class interface cip.sessionmgr.api.AlarmIF
07222 07-25 13:38:07.859  1847  1965 DEB ccservice: SIPCC-SIP_SOCK: sip_platform_udp_channel_read: Recvd 509 bytes on fd 44
07223 07-25 13:38:07.859  1847  1965 DEB ccservice: SIPCC-SIP_MSG_RECV: SIPProcessUDPMessage: UDP Message rcv: from <10.1.1.10>:<63797>, length=<509>, message=
07224 07-25 13:38:07.859  1847  1965 DEB ccservice: 
07225 07-25 13:38:07.859  1847  1965 DEB ccservice: sipio-recv<--- SIP/2.0 503 Service Unavailable - registrar unavail or not enabled
07225 07-25 13:38:07.859  1847  1965 DEB ccservice: Via: SIP/2.0/UDP 10.1.1.81:5060;branch=z9hG4bK7370480e
07225 07-25 13:38:07.859  1847  1965 DEB ccservice: From: <sip:402@10.1.1.10>;tag=c4143c96ff6563aa626ad6cb-318d6696
07225 07-25 13:38:07.859  1847  1965 DEB ccservice: To: <sip:402@10.1.1.10>;tag=B65B04FC-ACF
07225 07-25 13:38:07.859  1847  1965 DEB ccservice: Date: Fri, 25 Jul 2014 18:36:27 GMT
07225 07-25 13:38:07.859  1847  1965 DEB ccservice: Call-ID: c4143c96-ff650067-675b6dc2-6f5311e2@10.1.1.81
07225 07-25 13:38:07.859  1847  1965 DEB ccservice: Server: Cisco-SIPGateway/IOS-15.4.1.T1
07225 07-25 13:38:07.859  1847  1965 DEB ccservice: Warning: 399 10.1.1.10 "Registrar is not enabled"
07225 07-25 13:38:07.859  1847  1965 DEB ccservice: CSeq: 26765 REGISTER
07225 07-25 13:38:07.859  1847  1965 DEB ccservice: Warning: 399 10.1.1.10 "Registrar is not enabled"
07225 07-25 13:38:07.859  1847  1965 DEB ccservice: Content-Length: 0
07225 07-25 13:38:07.859  1847  1965 DEB ccservice: 
07226 07-25 13:38:07.859  1847  1965 DEB ccservice: SIPCC-SIP_RESP: sipSPICheckResponse: Response match: callid=c4143c96-ff650067-675b6dc2-6f5311e2@10.1.1.81, cseq=26765, cseq_method=REGISTER
07227 07-25 13:38:07.859  1847  1910 INF ccservice-j: AlarmService: : setAlarm: ms=0
07228 07-25 13:38:07.859  1847  1910 INF ccservice-j: CcService: : found Class interface cip.sessionmgr.api.AlarmIF
07229 07-25 13:38:07.859  1847  1965 ERR ccservice: SIPCC-REG: LINE 401/1: ccsip_handle_ev_failure_response   : SIP failure 503 resp
07230 07-25 13:38:07.859  1847  1965 ERR ccservice: %Credentials cannot be empty
07231 07-25 13:38:07.859  1847  1965 DEB ccservice: SIPCC-SIP_EVT: 401/1, sip_regmgr_ev_failure_response: Received event
07232 07-25 13:38:07.859  1847  1965 DEB ccservice: SIPCC-SIP_STATE: 1/0, sip_stop_ack_timer:  ccb->index=401 ack_timer_index=200 
07233 07-25 13:38:07.859  1847  1910 INF ccservice-j: AlarmService: : setAlarm: ms=33000
07234 07-25 13:38:07.859  1847  1910 INF ccservice-j: CcService: : found Class interface cip.sessionmgr.api.AlarmIF
07235 07-25 13:38:07.859  1847  1910 INF ccservice-j: AlarmService: : setAlarm: ms=0
07236 07-25 13:38:07.867  1847  1910 INF ccservice-j: CcService: : found Class interface cip.sessionmgr.api.AlarmIF
07237 07-25 13:38:07.867  1847  1965 INF ccservice: SIPCC-UI_API: ui_set_ccm_conn_status: ***********CUCM 10.1.1.10 STAND BY***********
07238 07-25 13:38:07.867  1847  1965 DEB ccservice: SIPCC-SIP_TIMER: sip_regmgr_ev_failure_response: Starting keep alive timer 120 sec
07239 07-25 13:38:07.867  1847  1964 INF ccservice: SIPCC-SIP_CC_PROV: ccsnap_gen_deviceEvent: event type : SERVER_STATUS
07240 07-25 13:38:07.867  1847  1964 INF ccservice-j: ccservice-j: onDeviceEvent: CUCM_CONN_STATUS, device: 0
07241 07-25 13:38:07.867  1847  1964 INF ccservice-j: TelephonyManagerData: : fetchCallServerInfos svrInfos length=2 serverInfos=[Lcom.cisco.telephony.CallServerInfo;@418866f8 nativeDeviceRef=1579539912
07242 07-25 13:38:07.867  1847  1964 INF ccservice-j: TelephonyManagerData: : fetchCallServerInfos svrHndls[0]=1577787544 mode=CCM status=ACTIVE CallServerInfo=[10.1.1.5, CCM, ACTIVE]
07243 07-25 13:38:07.867  1847  1964 INF ccservice-j: TelephonyManagerData: : fetchCallServerInfos svrHndls[1]=1577787624 mode=SRST status=STANDBY CallServerInfo=[10.1.1.10, SRST, STANDBY]
07244 07-25 13:38:07.867  1847  1964 INF ccservice-j: TelephonyManagerData: : notifyCallServerChangeListeners found 3 ICallServerInfoChangeListener to call onCallServerInfoChanged()
07245 07-25 13:38:07.867  1847  1964 INF ccservice-j: ccservice-j: Sending Non-XSI Device Event To Listener...  DeviceInfoRef= [1579539912]
07246 07-25 13:38:07.867  1847  1964 INF ccservice-j: ccservice-j: onDeviceEvent: Dispatched device event CUCM_CONN_STATUS to listener com.cisco.telephony.ICCDeviceListener$Stub$Proxy@418be8d8
07247 07-25 13:38:07.867  1847  1964 INF ccservice-j: ccservice-j: onDeviceEvent: Dispatched device event CUCM_CONN_STATUS to listener com.cisco.telephony.ICCDeviceListener$Stub$Proxy@418942f0
07248 07-25 13:38:07.867 26738 28210 DEB EMService: onCallServerInfoChanged():  num of cucms=2
07249 07-25 13:38:07.867 26738 28210 DEB EMService:  registered value is 1
07250 07-25 13:38:07.867  1847  5917 INF ccservice: SNAPSHOT-failed: CCAPI_DeviceInfo_getServiceState:  reference pointer=5e25ddc8
07251 07-25 13:38:07.867  1372 18916 INF Phone   : Phone:  [DeviceEventHandler]: onDeviceEvent: CUCM_CONN_STATUS
07252 07-25 13:38:07.867 26738 28210 DEB EMService:  registered value is 1
07253 07-25 13:38:07.867  1847  1858 INF ccservice: SNAPSHOT-failed: CCAPI_DeviceInfo_getServiceState:  reference pointer=5e25ddc8
07254 07-25 13:38:07.867 26738 28210 DEB EMService: CUCM server is : 10.1.1.5
07255 07-25 13:38:07.867 26738 28210 DEB EMService: getConfigInfo()
07256 07-25 13:38:07.867  1372 18916 INF Phone   : Phone:  [DeviceEventHandler]: onDeviceEvent: CUCM_CONN_STATUS - mode=CCM state=INS
07257 07-25 13:38:07.867  1847  7241 INF ccservice: SNAPSHOT-failed: CCAPI_DeviceInfo_getServiceState:  reference pointer=5e25ddc8
07258 07-25 13:38:07.867 26738 28210 DEB EMService: CUCM server is : 10.1.1.5
07259 07-25 13:38:07.867 16858  5190 INF SyncAdapter: Adding Feature = HUNTGROUPLOGOUT, state = DEV_FEATURE_OFF, lineIndex = 2
07260 07-25 13:38:07.867 16858  5190 DEB SyncAdapter: CTP in service.
07261 07-25 13:38:07.867 16858  5190 DEB SyncAdapter: Get account.
07262 07-25 13:38:07.867 16858  5190 DEB SyncAdapter: request sync
07263 07-25 13:38:07.875 26738 28210 DEB EMService: Config change - User not changed, user: Username
07264 07-25 13:38:07.875 26738 28210 DEB EMService: mac Address is:SEPC4143C96FF65
07265 07-25 13:38:07.875 26738 28210 DEB EMService: Current owner is Username
07266 07-25 13:38:07.875 26738 28210 DEB EMService: EM URL is 
07267 07-25 13:38:07.875 26738 28210 DEB EMService: launchEM MAC: SEPC4143C96FF65
07268 07-25 13:38:07.875 26738 28210 DEB EMService: launchEM CUCM IP: 10.1.1.5
07269 07-25 13:38:07.875 26738 28210 DEB EMService: launchEM Phone Number: 2622896402
07270 07-25 13:38:07.875 26738 28210 DEB EMService: launchEM URL is: 
07271 07-25 13:38:07.875 26738 28210 DEB EMService: Lockscreen up! Acquring wakelock, Disabling keyguard
07272 07-25 13:38:07.875  1847  1910 INF ccservice-j: AlarmService: : setAlarm: ms=47500
07273 07-25 13:38:07.875  1847  1910 INF ccservice-j: CcService: : found Class interface cip.sessionmgr.api.AlarmIF
07274 07-25 13:38:07.875  1847  1910 INF ccservice-j: AlarmService: : setAlarm: ms=0
07275 07-25 13:38:07.875  1847  1910 INF ccservice-j: CcService: : found Class interface cip.sessionmgr.api.AlarmIF
07276 07-25 13:38:07.875  1847  1910 INF ccservice-j: AlarmService: : setAlarm: ms=47500
07277 07-25 13:38:07.875  1847  1910 INF ccservice-j: CcService: : found Class interface cip.sessionmgr.api.AlarmIF
07278 07-25 13:38:07.882   684   933 DEB KeyguardViewMediator: setKeyguardEnabled(false)
07279 07-25 13:38:07.882   684   933 DEB KeyguardViewMediator: remembering to reshow, hiding keyguard, disabling status bar expansion
07280 07-25 13:38:07.882   684   933 DEB KeyguardViewMediator: hideLocked
07281 07-25 13:38:07.882   684   934 DEB KeyguardViewMediator: handleHide
07282 07-25 13:38:07.890   684   934 DEB KeyguardViewManager: hide()
07283 07-25 13:38:07.890   183   183 INF EnterpriseAudResMgr: setAudioPath: client_type = 1 stream_type = 1 device_type = 4
07284 07-25 13:38:07.890   183   183 INF EnterpriseAudResMgr: applyDeviceRules - enter
07285 07-25 13:38:07.890   183   183 INF EnterpriseAudResMgr: applyDeviceRules - exit
07286 07-25 13:38:07.890   183   183 INF EnterpriseAudResMgr: outputNoneDisable - enter
07287 07-25 13:38:07.890   183   183 INF EnterpriseAudResMgr: OUTPUT_NONE Disabled client_type = 1
07288 07-25 13:38:07.890   183   183 INF EnterpriseAudResMgr: outputNoneDisable - exit
07289 07-25 13:38:07.890   183   183 INF EnterpriseAudResMgr: builtinSpeakerEnable - enter
07290 07-25 13:38:07.890   183   183 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<DL2 Mixer Multimedia> value:1
07291 07-25 13:38:07.890   684   934 DEB KeyguardViewMediator: adjustUserActivityLocked mShowing: false mHidden: false
07292 07-25 13:38:07.890   183   183 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<DL2 Media Playback Volume> value:130
07293 07-25 13:38:07.890   684   934 DEB KeyguardViewMediator: adjustStatusBarLocked: mShowing=false mHidden=false isSecure=true --> flags=0x0
07294 07-25 13:38:07.890   684   934 DEB StatusBarManagerService: disableLocked(), vis flags=0x600000
07295 07-25 13:38:07.898   183   183 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<DL2 Mono Mixer> value:1
07296 07-25 13:38:07.898  1033  1033 DEB TabletStatusBar: ICON_MISSING_ISSUE SystemUI::setNavigationVisibility disableHome: true  disableRecent: false  disableBack:true
07297 07-25 13:38:07.898  1033  1033 DEB TabletStatusBar: ICON_MISSING_ISSUE SystemUI::setNavigationVisibility disablePhone:false  visibility:6291456
07298 07-25 13:38:07.898   183   183 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<Handsfree Playback Volume> value:29
07299 07-25 13:38:07.898 26738 28210 DEB EMService: launchEM EM State: em-set-state
07300 07-25 13:38:07.898 26738 28210 ERR ContextImpl: startActivity of ContextImpl...intent =>Intent { flg=0x10000000 cmp=com.cisco.em/.LockedScreenEMDialog (has extras) }
07301 07-25 13:38:07.898 26738 28210 ERR ContextImpl: startActivity of ContextImpl...displayId from context =>0
07302 07-25 13:38:07.898 26738 28210 DEB ContextImpl: startActivity of ContextImpl...componentName =>ComponentInfo{com.cisco.em/com.cisco.em.LockedScreenEMDialog}
07303 07-25 13:38:07.898   183   183 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<Handsfree Right Switch> value:1
07304 07-25 13:38:07.898   183   183 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<Handsfree Left Switch> value:1
07305 07-25 13:38:07.898   183   183 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<SDT DL Volume> value:120
07306 07-25 13:38:07.898   183   183 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<Sidetone Mixer Playback> value:1
07307 07-25 13:38:07.898   183   183 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<Handsfree Playback Volume> value:26
07308 07-25 13:38:07.898   183   183 INF EnterpriseAudResMgr: Builtin Speaker Enabled client_type = 1, cc = 1
07309 07-25 13:38:07.898   183   183 INF EnterpriseAudResMgr: builtinSpeakerEnable - exit
07310 07-25 13:38:07.898   684   684 INF ActivityStack: START {flg=0x10000000 cmp=com.cisco.em/.LockedScreenEMDialog (has extras) u=0} from pid 26738
07311 07-25 13:38:07.898   684   684 WRN WindowManager: Device booted.............. calling isDIDInitializedOnce-1
07312 07-25 13:38:07.898   684   684 WRN WindowManager: inside else cond-1
07313 07-25 13:38:07.953   684   933 DEB KeyguardViewMediator: setHidden false
07314 07-25 13:38:07.968   684   934 DEB KeyguardViewMediator: setHidden false
07315 07-25 13:38:07.968   684  1271 INF WindowManager: Set app display size: 1024 x 552
07316 07-25 13:38:08.000   684   933 DEB KeyguardViewMediator: setHidden false
07317 07-25 13:38:08.000   684   933 DEB KeyguardViewMediator: setHidden false
07318 07-25 13:38:08.007   684   933 DEB KeyguardViewMediator: setHidden false
07319 07-25 13:38:08.007 26738 26738 DEB Activity: In Attach()...application.getPackageName()com.cisco.em
07320 07-25 13:38:08.015 26738 26738 ERR ConfigManagerService: EMService:  Using emURL:  https://10.1.1.5:8443/emapp/EMAppServlet?device=SEPC4143C96FF65
07321 07-25 13:38:08.023   684  1271 DEB KeyguardViewMediator: setHidden false
07322 07-25 13:38:08.023 26738 26738 INF ConfigManagerService: XSI:  invokeUrl url=https://10.1.1.5:8443/emapp/EMAppServlet?device=SEPC4143C96FF65 responseHandler=cip.xsi.XSIHttpResponseHandler@41996480 responseListener=com.cisco.em.LockedScreenEMDialog@4186e0c8
07323 07-25 13:38:08.023   684   933 DEB KeyguardViewMediator: setHidden false
07324 07-25 13:38:08.046  1208  1208 DEB WebProxy: Active Interface type eth name eth
07325 07-25 13:38:08.062  1033  1033 DEB TabletStatusBar: ICON_MISSING_ISSUE SystemUI::setNavigationVisibility disableHome: false  disableRecent: false  disableBack:false
07326 07-25 13:38:08.062  1033  1033 DEB TabletStatusBar: ICON_MISSING_ISSUE SystemUI::setNavigationVisibility disablePhone:false  visibility:0
07327 07-25 13:38:08.062   684  1246 DEB KeyguardViewMediator: setHidden true
07328 07-25 13:38:08.062   684  1246 DEB KeyguardViewMediator: setHidden true
07329 07-25 13:38:08.062  1208  1208 DEB WebProxy: user =  proxyType =  host =  port =  pacLocation =  auth =  isAdmin= 
07330 07-25 13:38:08.062   684   934 DEB StatusBarManagerService: setSystemUiVisibility, vis flags=0x0
07331 07-25 13:38:08.062   684   934 DEB StatusBarManagerService: disableLocked(), vis flags=0x0
07332 07-25 13:38:08.070 26738 23700 INF WebProxy: Proxy service returned direct connection
07333 07-25 13:38:08.070 26738 23700 DEB WebProxy: androidProxy [DIRECT@]
07334 07-25 13:38:08.070 26738 23700 INF WebProxy: Try default AndroidProxy [DIRECT@]
07335 07-25 13:38:08.085 26738 23700 VBS TrustStoreJNI: Validating certificate
07336 07-25 13:38:08.085   684   934 DEB KeyguardViewMediator: adjustUserActivityLocked mShowing: false mHidden: true
07337 07-25 13:38:08.085   684   934 DEB KeyguardViewMediator: adjustStatusBarLocked: mShowing=false mHidden=true isSecure=true --> flags=0x0
07338 07-25 13:38:08.085  1033  1033 DEB TabletStatusBar: --->updateActiveCallButton
07339 07-25 13:38:08.093  1033  1033 DEB TabletStatusBar: getTopMostActivity: com.cisco.em
07340 07-25 13:38:08.093  1033  1033 DEB TabletStatusBar: isPhoneAppShowing=false
07341 07-25 13:38:08.093  1033  1033 DEB TabletStatusBar: --->showPhonebutton(): show=true, disablePhone=false
07342 07-25 13:38:08.093   219 23705 ERR secureapp: No match found in trust list against the item
07343 07-25 13:38:08.132   684  1291 DEB KeyguardViewMediator: setHidden true
07344 07-25 13:38:08.140   684   933 INF ActivityManager: Displayed com.cisco.em/.LockedScreenEMDialog: +168ms
07345 07-25 13:38:08.140   684   933 DEB KeyguardViewMediator: setHidden true
07346 07-25 13:38:08.140   684   933 DEB KeyguardViewMediator: setHidden true
07347 07-25 13:38:08.156 16858 23710 DEB SyncAdapter: onPerformSync: Perform Sync automatically.
07348 07-25 13:38:08.156 16858 23710 INF ContactManager: *** Looking for local dirty contacts
07349 07-25 13:38:08.156  1454  1478 DEB dalvikvm: WAIT_FOR_CONCURRENT_GC blocked 0ms
07350 07-25 13:38:08.187   684   933 DEB KeyguardViewMediator: setHidden true
07351 07-25 13:38:08.210  1454  1478 DEB dalvikvm: GC_EXPLICIT freed 308K, 9% free 6960K/7623K, paused 2ms+14ms, total 51ms
07352 07-25 13:38:08.226   684  1193 DEB KeyguardViewMediator: setHidden true
07353 07-25 13:38:08.242 16858 23710 DEB UdsClient: get HTTPS Connection URL=https://10.1.1.5:8443/cucm-uds/user/Username/devices?max=500
07354 07-25 13:38:08.242 16858 23710 INF UdsClient: Setting HttpsURLConnection properties
07355 07-25 13:38:08.242  1208  1208 DEB WebProxy: Active Interface type eth name eth
07356 07-25 13:38:08.257  1208  1208 DEB WebProxy: user =  proxyType =  host =  port =  pacLocation =  auth =  isAdmin= 
07357 07-25 13:38:08.257 16858 23710 INF WebProxy: Proxy service returned direct connection
07358 07-25 13:38:08.257 16858 23710 DEB WebProxy: androidProxy [DIRECT@]
07359 07-25 13:38:08.257 16858 23710 INF WebProxy: Try default AndroidProxy [DIRECT@]
07360 07-25 13:38:08.257  1208  1208 DEB WebProxy: Active Interface type eth name eth
07361 07-25 13:38:08.273  1208  1208 DEB WebProxy: user =  proxyType =  host =  port =  pacLocation =  auth =  isAdmin= 
07362 07-25 13:38:08.273 16858 23710 INF WebProxy: Proxy service returned direct connection
07363 07-25 13:38:08.273 16858 23710 DEB WebProxy: androidProxy [DIRECT@]
07364 07-25 13:38:08.273 16858 23710 INF WebProxy: Try default AndroidProxy [DIRECT@]
07365 07-25 13:38:08.273 16858 23710 ERR SyncAdapter: IOException
07365 07-25 13:38:08.273 16858 23710 ERR SyncAdapter: javax.net.ssl.SSLException: Unable to create application data
07365 07-25 13:38:08.273 16858 23710 ERR SyncAdapter: at org.apache.harmony.xnet.provider.jsse.NativeCrypto.SSL_do_handshake(Native Method)
07365 07-25 13:38:08.273 16858 23710 ERR SyncAdapter: at org.apache.harmony.xnet.provider.jsse.OpenSSLSocketImpl.startHandshake(OpenSSLSocketImpl.java:395)
07365 07-25 13:38:08.273 16858 23710 ERR SyncAdapter: at libcore.net.http.HttpConnection.setupSecureSocket(HttpConnection.java:215)
07365 07-25 13:38:08.273 16858 23710 ERR SyncAdapter: at libcore.net.http.HttpsURLConnectionImpl$HttpsEngine.makeSslConnection(HttpsURLConnectionImpl.java:481)
07365 07-25 13:38:08.273 16858 23710 ERR SyncAdapter: at libcore.net.http.HttpsURLConnectionImpl$HttpsEngine.connect(HttpsURLConnectionImpl.java:445)
07365 07-25 13:38:08.273 16858 23710 ERR SyncAdapter: at libcore.net.http.HttpEngine.sendSocketRequest(HttpEngine.java:291)
07365 07-25 13:38:08.273 16858 23710 ERR SyncAdapter: at libcore.net.http.HttpEngine.sendRequest(HttpEngine.java:241)
07365 07-25 13:38:08.273 16858 23710 ERR SyncAdapter: at libcore.net.http.HttpURLConnectionImpl.getResponse(HttpURLConnectionImpl.java:294)
07365 07-25 13:38:08.273 16858 23710 ERR SyncAdapter: at libcore.net.http.HttpURLConnectionImpl.getResponseCode(HttpURLConnectionImpl.java:562)
07365 07-25 13:38:08.273 16858 23710 ERR SyncAdapter: at libcore.net.http.HttpsURLConnectionImpl.getResponseCode(HttpsURLConnectionImpl.java:137)
07365 07-25 13:38:08.273 16858 23710 ERR SyncAdapter: at com.cisco.contacts.udssync.client.UdsClient.getDevicesUri(UdsClient.java:371)
07365 07-25 13:38:08.273 16858 23710 ERR SyncAdapter: at com.cisco.contacts.udssync.client.UdsClient.syncSpeedDials(UdsClient.java:238)
07365 07-25 13:38:08.273 16858 23710 ERR SyncAdapter: at com.cisco.contacts.udssync.udsadapter.SyncAdapter.onPerformSync(SyncAdapter.java:110)
07365 07-25 13:38:08.273 16858 23710 ERR SyncAdapter: at android.content.AbstractThreadedSyncAdapter$SyncThread.run(AbstractThreadedSyncAdapter.java:254)
07366 07-25 13:38:08.281   684   921 DEB SyncManager: failed sync operation Username (com.cisco.contacts.udssync), com.android.contacts, USER, earliestRunTime 3208585559, EXPEDITED, SyncResult: stats [ numIoExceptions: 1]
07367 07-25 13:38:08.320   684   933 DEB KeyguardViewMediator: setHidden true
07368 07-25 13:38:08.320   684   933 DEB KeyguardViewMediator: setHidden true
07369 07-25 13:38:08.390   684   934 DEB PresenceBar: onDetachedFromWindow(): unregistering ContentObserver
07370 07-25 13:38:08.390   684   934 DEB PresenceBar: unregisterReceiver()
07371 07-25 13:38:08.421   684   933 DEB KeyguardViewMediator: setHidden true
07372 07-25 13:38:08.429   684   933 DEB KeyguardViewMediator: setHidden true
07373 07-25 13:38:08.640 26738 23700 INF ConfigManagerService: CiscoTrustManager:  Successfully verified server certificate
07374 07-25 13:38:08.742 26738 23700 WRN ConfigManagerService: XSI:  XML Parser warning:Encoding Updated
07375 07-25 13:38:08.750 26738 23700 INF ConfigManagerService: XSI:  processHttpResponse document=<CiscoIPPhoneText keypadTarget="application"><SoftKeyItem><Position>1</Position><URL>SoftKey:Exit</URL><Name>Exit</Name></SoftKeyItem><Title>Login response</Title><Prompt>Login is unavailable (208)</Prompt><Text>Login Unsuccessful</Text></CiscoIPPhoneText>
07376 07-25 13:38:08.750 26738 23700 ERR ConfigManagerService: EMService:  responseReceived expires=true refreshTime=0 refreshStr= document=cip.xsi.XmlTextObject PID=26738 TID=23700
07377 07-25 13:38:08.750 26738 23700 ERR ConfigManagerService: EMService:  getResponseCode responseCode=208 prompt=Login is unavailable (208)
07378 07-25 13:38:08.750 26738 23700 ERR ConfigManagerService: EMService:  Xml Text object ReponseCode = 208 Softkeylength = 1
07379 07-25 13:38:08.750 26738 23700 ERR ConfigManagerService: EMService:   Inavlid response in EM_STATE_SET
07380 07-25 13:38:08.750 26738 23700 DEB EMService: setEMState EM State: em-state-unset
07381 07-25 13:38:08.750   684   933 DEB KeyguardViewMediator: setKeyguardEnabled(true)
07382 07-25 13:38:08.750   684   933 DEB KeyguardViewMediator: previously hidden, reshowing, reenabling status bar expansion
07383 07-25 13:38:08.750   684   933 DEB KeyguardViewMediator: showLocked
07384 07-25 13:38:08.750   684   933 DEB KeyguardViewMediator: waiting until mWaitingUntilKeyguardVisible is false
07385 07-25 13:38:08.750   684   934 DEB KeyguardViewMediator: handleShow
07386 07-25 13:38:08.750   684   934 DEB KeyguardViewManager: show(); mKeyguardView==null
07387 07-25 13:38:08.750   684   934 DEB KeyguardViewManager: Rotation sensor for lock screen Off!
07388 07-25 13:38:08.750   684   934 DEB KeyguardViewManager: keyguard view is null, creating it...
07389 07-25 13:38:08.750   684   934 VBS LockPatternKeyguardView: Set visibility on com.android.internal.policy.impl.LockPatternKeyguardView$4@41b6d318 to 0
07390 07-25 13:38:08.765   684   934 DEB PasswordUnlockScreenManager: registerWithConfigBroadcastReceiver()
07391 07-25 13:38:08.765   684   773 DEB PasswordUnlockScreenManager: PIN/Password Screen: intent=com.cisco.em.STATE, emLoginState=em-state-unset
07392 07-25 13:38:08.765   684   773 DEB PasswordUnlockScreenManager: updateMultiUserUI
07393 07-25 13:38:08.781  4892  4892 VBS ActivityThread: DID_IMP Creating service com.google.android.gsf.login
07394 07-25 13:38:08.789   684   934 DEB PresenceBar: onAttachedToWindow(): registering ContentObserver
07395 07-25 13:38:08.789   684   934 DEB PresenceBar: registerReceiver()
07396 07-25 13:38:08.796   684   773 DEB PresenceBar: PresenceBar intent:Intent { act=cip.cfg.CONFIG_STATUS_INTENT flg=0x10 (has extras) }
07397 07-25 13:38:08.796   684   773 DEB PresenceBar: PresenceBar action:cip.cfg.CONFIG_STATUS_INTENT received, status=5
07398 07-25 13:38:08.796   684   773 DEB PresenceBar: PresenceBar intent:Intent { act=com.cisco.phone.ui.lineinfo flg=0x10 (has extras) }
07399 07-25 13:38:08.796   684   773 DEB PresenceBar: Line Info - Call Forward Target:, Line Number:2622896402
07400 07-25 13:38:08.796   684   934 VBS KeyguardViewManager: KGVM: Set visibility on com.android.internal.policy.impl.KeyguardViewManager$KeyguardViewHost@41d399d0 to 6291456
07401 07-25 13:38:08.796   684   934 DEB KeyguardViewManager: New SystemUiVisibility flags=0x600000
07402 07-25 13:38:08.796   684   934 DEB KeyguardViewMediator: adjustUserActivityLocked mShowing: true mHidden: true
07403 07-25 13:38:08.796   684   934 DEB KeyguardViewMediator: adjustStatusBarLocked: mShowing=true mHidden=true isSecure=true --> flags=0x1090000
07404 07-25 13:38:08.796   684   934 DEB StatusBarManagerService: disableLocked(), vis flags=0x1090000
07405 07-25 13:38:08.796  1033  1033 INF TabletStatusBar: DISABLE_EXPAND: yes
07406 07-25 13:38:08.804  1033  1033 DEB TabletStatusBar: ICON_MISSING_ISSUE SystemUI::setNavigationVisibility disableHome: false  disableRecent: true  disableBack:false
07407 07-25 13:38:08.804  1033  1033 DEB TabletStatusBar: ICON_MISSING_ISSUE SystemUI::setNavigationVisibility disablePhone:true  visibility:17367040
07408 07-25 13:38:08.804  1033  1033 DEB TabletStatusBar: getTopMostActivity: com.cisco.em
07409 07-25 13:38:08.804  1033  1033 INF TabletStatusBar: Device is locked but unidentified app - com.cisco.em showing. Show home button instead of lock button.
07410 07-25 13:38:08.820  1033  1033 DEB TabletStatusBar: --->showClock()=true
07411 07-25 13:38:08.820   684   934 DEB KeyguardViewMediator: ScreenLock...HDMISettings.getDisplayMode(mContext)=>1
07412 07-25 13:38:08.820   684   934 DEB KeyguardViewMediator: KVM:: Lock screen is displayed, so broadcasting the lock screen intent
07413 07-25 13:38:08.820   684   934 DEB PasswordUnlockScreenManager: Now emLoginState is - 'em-state-unset'
07414 07-25 13:38:08.820   684   934 DEB PasswordUnlockScreenManager: isMultiUserEnabled()=false
07415 07-25 13:38:08.820   684   934 DEB PasswordUnlockScreenManager: Now emLoginState is - 'em-state-unset'
07416 07-25 13:38:08.820   684   934 DEB PasswordUnlockScreenManager: isEmUserLoggedIn()=false
07417 07-25 13:38:08.843   684   934 DEB KeyguardViewMediator: setHidden true
07418 07-25 13:38:08.851 16858 16858 DEB SyncService: Device is locked. action=com.cisco.phone.devicelocked
07419 07-25 13:38:08.867   684   934 DEB PasswordUnlockScreenManager: updateMultiUserUI
07420 07-25 13:38:08.867   684   934 DEB PasswordUnlockScreen: setPasswordEntryLayout state=0
07421 07-25 13:38:08.867   684   934 DEB PasswordUnlockScreen: setPasswordEntryLayout state=0
07422 07-25 13:38:08.921   684   934 DEB KeyguardViewMediator: setHidden true
07423 07-25 13:38:08.921   684   934 DEB PasswordUnlockScreenManager: Now emLoginState is - 'em-state-unset'
07424 07-25 13:38:08.921   684   934 DEB PasswordUnlockScreenManager: isMultiUserEnabled()=false
07425 07-25 13:38:08.921   684   934 DEB PasswordUnlockScreenManager: Now emLoginState is - 'em-state-unset'
07426 07-25 13:38:08.921   684   934 DEB PasswordUnlockScreenManager: isEmUserLoggedIn()=false
07427 07-25 13:38:08.921   684   934 DEB KeyguardViewMediator: handleKeyguardDoneDrawing: notifying mWaitingUntilKeyguardVisible
07428 07-25 13:38:08.921   684   933 DEB KeyguardViewMediator: done waiting for mWaitingUntilKeyguardVisible
07429 07-25 13:38:08.929   684   933 DEB KeyguardViewMediator: setHidden true
07430 07-25 13:38:08.960 26738 23700 INF ConfigManagerService: HttpRequestThread:  run PID=26738 TID=23700
07431 07-25 13:38:08.960   684  1271 DEB KeyguardViewMediator: setHidden false
07432 07-25 13:38:08.960   684  1271 INF ActivityStack: moveTaskToBack: 26742
07433 07-25 13:38:08.968   684  1271 DEB KeyguardViewMediator: setHidden false
07434 07-25 13:38:08.968   684   933 DEB KeyguardViewMediator: setHidden false
07435 07-25 13:38:08.968   684   934 DEB KeyguardViewMediator: adjustUserActivityLocked mShowing: true mHidden: false
07436 07-25 13:38:08.968   684   934 DEB KeyguardViewMediator: adjustUserActivityLocked: NOT calling pokeWakeLock()--Screen just dimmed.
07437 07-25 13:38:08.968   684   934 DEB KeyguardViewMediator: adjustStatusBarLocked: mShowing=true mHidden=false isSecure=true --> flags=0x1090000
07438 07-25 13:38:08.968   684   934 DEB StatusBarManagerService: setSystemUiVisibility, vis flags=0x600000
07439 07-25 13:38:08.976   684   934 DEB StatusBarManagerService: disableLocked(), vis flags=0x1690000
07440 07-25 13:38:08.976   684  1193 WRN ActivityStack: Duplicate finish request for ActivityRecord{41ad81b0 com.cisco.em/.LockedScreenEMDialog}
07441 07-25 13:38:08.984   684   933 DEB KeyguardViewMediator: setHidden false
07442 07-25 13:38:08.984  1033  1033 DEB TabletStatusBar: ICON_MISSING_ISSUE SystemUI::setNavigationVisibility disableHome: true  disableRecent: true  disableBack:true
07443 07-25 13:38:08.984  1033  1033 DEB TabletStatusBar: ICON_MISSING_ISSUE SystemUI::setNavigationVisibility disablePhone:true  visibility:23658496
07444 07-25 13:38:08.992   684  1237 DEB KeyguardViewMediator: setHidden false
07445 07-25 13:38:08.992   684 17022 DEB PresenceBar: UpdatePresenceTask.doInBackground: returning cursor=android.content.ContentResolver$CursorWrapperInner@41f3ccb8
07446 07-25 13:38:08.992  1033  1033 DEB TabletStatusBar: --->updateActiveCallButton
07447 07-25 13:38:08.992   684  1237 INF WindowManager: Set app display size: 1024 x 552
07448 07-25 13:38:09.000   684   773 DEB PresenceBar: OwnerId/UserId=Username
07449 07-25 13:38:09.000   684   773 DEB PresenceBar: --->UpdatePresenceTask.onPostExecute(): Name=First Last
07450 07-25 13:38:09.000   684  1115 DEB KeyguardViewMediator: setHidden false
07451 07-25 13:38:09.000   684   934 DEB PresenceBar: UpdatePresenceTask.onPostExecute().run(): Name=First Last
07452 07-25 13:38:09.000  1033  1033 DEB TabletStatusBar: getTopMostActivity: com.android.launcher
07453 07-25 13:38:09.000  1033  1033 DEB TabletStatusBar: isPhoneAppShowing=false
07454 07-25 13:38:09.000  1033  1033 DEB TabletStatusBar: --->showPhonebutton(): show=true, disablePhone=true
07455 07-25 13:38:09.093   684  1118 DEB KeyguardViewMediator: setHidden false
07456 07-25 13:38:09.093   684   933 DEB KeyguardViewMediator: setHidden false
07457 07-25 13:38:09.164   684  1115 DEB KeyguardViewMediator: setHidden false
07458 07-25 13:38:09.171   684   933 DEB KeyguardViewMediator: setHidden false
07459 07-25 13:38:09.171   684   933 DEB KeyguardViewMediator: setHidden false
07460 07-25 13:38:09.195   684  1246 DEB KeyguardViewMediator: setHidden false
07461 07-25 13:38:09.203   684   933 DEB KeyguardViewMediator: setHidden false
07462 07-25 13:38:09.218   684   933 DEB KeyguardViewMediator: setHidden false
07463 07-25 13:38:09.570   684   694 WRN ActivityStack: Duplicate finish request for ActivityRecord{41ad81b0 com.cisco.em/.LockedScreenEMDialog}
07464 07-25 13:38:09.570   684  1238 WRN ActivityStack: Duplicate finish request for ActivityRecord{41ad81b0 com.cisco.em/.LockedScreenEMDialog}
07465 07-25 13:38:09.578   684  1271 DEB KeyguardViewMediator: setHidden false
07466 07-25 13:38:09.578   684  1194 DEB APP     :  ===>>>> ActivityStack : removeActivityFromHistoryLocked() isActivityFinished = false
07467 07-25 13:38:09.578   684   933 DEB KeyguardViewMediator: setHidden false
07468 07-25 13:38:09.578   684   933 DEB KeyguardViewMediator: setHidden false
07469 07-25 13:38:12.070   183   272 INF EnterpriseAudResMgr: setAudioPath: client_type = 1 stream_type = 1 device_type = 5
07470 07-25 13:38:12.070   183   272 INF EnterpriseAudResMgr: applyDeviceRules - enter
07471 07-25 13:38:12.070   183   272 INF EnterpriseAudResMgr: applyDeviceRules - exit
07472 07-25 13:38:12.070   183   272 INF EnterpriseAudResMgr: builtinSpeakerDisable - enter
07473 07-25 13:38:12.070   183   272 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<DL2 Media Playback Volume> value:0
07474 07-25 13:38:12.070   183   272 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<DL2 Mono Mixer> value:0
07475 07-25 13:38:12.078   183   272 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<Handsfree Playback Volume> value:0
07476 07-25 13:38:12.078   183   272 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<Handsfree Right Switch> value:0
07477 07-25 13:38:12.078   183   272 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<Handsfree Left Switch> value:0
07478 07-25 13:38:12.078   183   272 INF EnterpriseAudResMgr: Builtin Speaker Disabled client_type = 1, cc = 0
07479 07-25 13:38:12.078   183   272 INF EnterpriseAudResMgr: builtinSpeakerDisable - exit
07480 07-25 13:38:12.078   183   272 INF EnterpriseAudResMgr: outputNoneEnable - enter
07481 07-25 13:38:12.078   183   272 INF EnterpriseAudResMgr: OUTPUT_NONE Enabled client_type = 1
07482 07-25 13:38:12.078   183   272 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<DL2 Mixer Multimedia> value:0
07483 07-25 13:38:12.078   183   272 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<DL2 Media Playback Volume> value:0
07484 07-25 13:38:12.078   183   272 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<DL1 Mixer Multimedia> value:0
07485 07-25 13:38:12.078   183   272 DEB AudioControl: SetValue, mControl = 1091775232 ctl:<DL1 Media Playback Volume> value:0
07486 07-25 13:38:12.078   183   272 INF EnterpriseAudResMgr: outputNoneEnable - exit

Do you have multi-user enabled on the device?

 

These look like the normal console log files.  I think there should be a file named logcat as well.  You may have to try creating a problem report on the phone right after the issue occurs- http://www.cisco.com/c/en/us/support/docs/voice-unified-communications/unified-communications-manager-version-91/116457-qanda-dx650-00.html

 

Brian

I believe multi-user is enabled (I remember setting that), but there is no second user configured. I am not seeing any log named logcat - any idea where I can locate that?

I think it's in the ZIP created by the problem report tool.