8945 Directories Button not working

[hankej]

We are running Callmanager 8.6.2.20000-2.  We just upgraded with a new devpack so we could register 8945 IP Phones.  We can now register 8945 phones however we are finding out the the directories button doesn't work at all.  I have see previous postings, but most of them say you need to upgrade your CUCM to a dev pack to be able to register the phone.  Looking at the active phone load on one of these phones, the active load is sccp 9-4-1-9.  If anyone has any ideas as to how to get the directories button to work, that would be great.  Thank you

[George Thomas]

What do you get when you hit the directories button? Can you delete the Security configuration from the phone and retry? Console logs from the phone would be helpful as well.

[hankej]

When I press the Directories button, nothing happens at all.  No flicker or anything on the screen.  Another thing that we just happened to notice is the time is off on all of our 8945 phones.  All of my other phones are fine, just not the 8945.  They are off by 5 hours.

I'm not sure how to delete the Security Config from the phone.  Can you walk me through it?  Here is the Console log from the phone:

(). ipp_mode=0
00:00:16:22.242 - E/AMMU: ammu_proc_accu_save_ipp_audio_gain(). write handset gain
00:00:16:22.242 - E/AMMU: ammu_proc_accu_save_ipp_audio_gain(). write headset gain
00:00:16:22.243 - E/AMMU: ammu_proc_accu_save_ipp_audio_gain(). write handsfree gain
00:00:16:22.243 - E/AMMU: ammu_proc_accu_save_ipp_audio_gain(). write ringer gain
path = /nvdata is exist
FFS Commit to /nvdata/CFG1.
APCtrl skdownhandler
00:00:53:50.774 - E/ACCU: SCCP REG OK(0, -1)
00:00:53:53.798 - E/ACCU: SCCP REG OK(0, 1)
path = /nvdata is exist
FFS Commit to /nvdata/CFG2.
------ Menu proxy get:applications
into subhandler_applications:xmlservice_update
Get xmlservice_update or reg_update event: refresh UI ===> xmlservice_update
------ Menu proxy get:applications
into subhandler_applications:down
into subhandler_applications:down
into subhandler_applications:down
into subhandler_applications:down
------ Menu proxy get:phoneinformation
------ Menu proxy get:phoneinformation
"XsiInfoWidget_Config"
[[false,"{1196}","s_prompt"],[[2,"{625}:","CP-8945"],[2,"{204}:","10.200.126.19"],[2,"{12
2}:","SEP6CFA8973E9C0"],[2,"{1198}:","sccp 9-4-1-9"],[2,"{1200}:","12/20/13 2:36 {2250}"]
[2,"{1201}:","10.200.0.12"],[2,"{1202}:","10.2.17.4"]]]
"XsiInfoWidget_Active"
true
APCtrl skdownhandler
"XsiInfoWidget_Config"
[[true," "," "],[]]
------ Menu proxy get:applications
00:01:27:57.997 - I/kernel: backlight_store_brightness
into subhandler_applications:state_idle
into subhandler_applications:state_idle
into subhandler_applications:state_idle
into subhandler_applications:state_idle
into subhandler_applications:state_idle
into subhandler_applications:state_idle
into subhandler_applications:state_ring_in
00:01:50:37.107 - I/kernel: backlight_store_brightness
into subhandler_applications:media_state
00:01:50:40.345 - I/kernel: ept:ldxEventCb: HAPI_RM_DSP_EGRESS_UNDERRUN_EVT: devId = 48
into subhandler_applications:media_state
into subhandler_applications:media_state
00:01:50:45.472 - E/ACCU: accu_mmi_cmd_volume_save(). ipp_mode=ipp_mode_ringer
00:01:50:45.475 - E/AMMU: ammu_proc_accu_save_ipp_audio_gain(). ipp_mode=6
00:01:50:45.476 - E/AMMU: ammu_proc_accu_save_ipp_audio_gain(). write ringer gain
path = /nvdata is exist
FFS Commit to /nvdata/CFG1.
accu_sccp_line_term_ringin() found dn=1 btn_idx=1
00:01:50:53.104 - I/kernel: ept:ldxEventCb: HAPI_RM_DSP_EGRESS_UNDERRUN_EVT: devId = 48
into subhandler_applications:state_idle
into subhandler_applications:media_state
APCtrl skdownhandler
into subhandler_applications:undefined
"XsiInfoWidget_Active"
false
accu_sccp_line_term_ringin() found dn=1 btn_idx=1
00:02:32:11.130 - I/kernel: ept:ldxEventCb: HAPI_RM_DSP_EGRESS_UNDERRUN_EVT: devId = 48
00:02:36:57.384 - E/ACCU: SCCP REG OK(0, -1)
00:02:37:00.408 - E/ACCU: SCCP REG OK(0, 1)
path = /nvdata is exist
FFS Commit to /nvdata/CFG2.
00:02:51:47.778 - I/kernel: backlight_store_brightness
00:03:07:26.733 - E/js_add_evts(): *********** { "module": "accu", "cmd": "sccp", "event"
"display_prinotify", "params": [ 1, 10, 10, 5, [ -128, 23, "�8" ] ] } not utf8 string
00:03:07:26.857 - I/kernel: backlight_store_brightness
accu_sccp_line_term_ringin() found dn=1 btn_idx=1
00:03:07:42.812 - I/kernel: ept:ldxEventCb: HAPI_RM_DSP_EGRESS_UNDERRUN_EVT: devId = 48
00:03:07:42.812 - I/kernel: backlight_store_brightness
00:03:27:51.344 - E/js_add_evts(): *********** { "module": "accu", "cmd": "sccp", "event"
"display_prinotify", "params": [ 1, 10, 10, 5, [ -128, 23, "�8" ] ] } not utf8 string
00:03:27:51.461 - I/kernel: backlight_store_brightness
00:03:27:59.876 - I/kernel: ept:ldxEventCb: HAPI_RM_DSP_EGRESS_UNDERRUN_EVT: devId = 48
00:03:27:59.876 - I/kernel: backlight_store_brightness
00:03:31:47.416 - I/kernel: backlight_store_brightness
accu_sccp_line_term_ringin() found dn=1 btn_idx=1
00:03:32:03.386 - I/kernel: ept:ldxEventCb: HAPI_RM_DSP_EGRESS_UNDERRUN_EVT: devId = 48
00:03:32:03.386 - I/kernel: backlight_store_brightness
------ Menu proxy get:applications
into subhandler_applications:xmlservice_update
Get xmlservice_update or reg_update event: refresh UI ===> xmlservice_update
------ Menu proxy get:applications
00:03:55:06.491 - I/kernel: backlight_store_brightness
into subhandler_applications:down
into subhandler_applications:down
into subhandler_applications:down
APCtrl skdownhandler
into subhandler_applications:undefined
------ Menu proxy get:phoneinformation
------ Menu proxy get:phoneinformation
"XsiInfoWidget_Config"
[[false,"{1196}","s_prompt"],[[2,"{625}:","CP-8945"],[2,"{204}:","10.200.126.19"],[2,"{12
2}:","SEP6CFA8973E9C0"],[2,"{1198}:","sccp 9-4-1-9"],[2,"{1200}:","12/20/13 2:36 {2250}"]
[2,"{1201}:","10.200.0.12"],[2,"{1202}:","10.2.17.4"]]]
"XsiInfoWidget_Active"
true
APCtrl skdownhandler
"XsiInfoWidget_Config"
[[true," "," "],[]]
------ Menu proxy get:applications
into subhandler_applications:down
into subhandler_applications:down
------ Menu proxy get:administrator
proxy.focus_idx:2
------ Menu proxy get:securitysetup
proxy.focus_idx:1
------ Menu proxy get:authentication
------ Menu proxy get:authenticationselector
APCtrl skdownhandler
------ Menu proxy get:authentication
subhandler_bluetooth proxy.focus_idx:1
APCtrl skdownhandler
------ Menu proxy get:securitysetup
------ Menu proxy get:trustlist
------ Menu proxy get:ctlsignature
"XsiInfoWidget_Config"
[[true,"{1180}","{788}"],[[1,"{1321}",""],[1,"{758}",""]]]
"XsiInfoWidget_Active"
true
APCtrl skdownhandler
"XsiInfoWidget_Config"
[[true," "," "],[]]
------ Menu proxy get:trustlist
APCtrl skdownhandler
------ Menu proxy get:securitysetup
APCtrl skdownhandler
------ Menu proxy get:administrator
proxy.focus_idx:1
------ Menu proxy get:networksetup
proxy.focus_idx:1
APCtrl skdownhandler
------ Menu proxy get:administrator
APCtrl skdownhandler
------ Menu proxy get:applications
proxy.focus_idx:3
00:04:55:43.087 - I/kernel: backlight_store_brightness
into subhandler_applications:state_idle
into subhandler_applications:state_ring_in
00:06:22:46.641 - I/kernel: backlight_store_brightness
into subhandler_applications:media_state
accu_sccp_line_term_ringin() found dn=1 btn_idx=1
00:06:23:02.620 - I/kernel: ept:ldxEventCb: HAPI_RM_DSP_EGRESS_UNDERRUN_EVT: devId = 48
00:06:23:02.620 - I/kernel: backlight_store_brightness
into subhandler_applications:state_idle
into subhandler_applications:media_state
00:14:57:57.028 - I/kernel: backlight_store_brightness
into subhandler_applications:state_ring_in
into subhandler_applications:media_state
accu_sccp_line_term_ringin() found dn=1 btn_idx=1
00:20:34:58.262 - I/kernel: ept:ldxEventCb: HAPI_RM_DSP_EGRESS_UNDERRUN_EVT: devId = 48
into subhandler_applications:state_idle
into subhandler_applications:media_state
into subhandler_applications:state_idle
into subhandler_applications:state_idle
into subhandler_applications:state_idle
into subhandler_applications:state_idle
into subhandler_applications:state_ring_in
into subhandler_applications:media_state
into subhandler_applications:state_idle
00:22:16:28.437 - I/kernel: ept:ldxEventCb: HAPI_RM_DSP_EGRESS_UNDERRUN_EVT: devId = 48
into subhandler_applications:media_state
into subhandler_applications:state_idle
into subhandler_applications:state_ring_in
into subhandler_applications:media_state
accu_sccp_line_term_ringin() found dn=1 btn_idx=1
00:22:45:30.597 - I/kernel: ept:ldxEventCb: HAPI_RM_DSP_EGRESS_UNDERRUN_EVT: devId = 48
into subhandler_applications:state_idle
into subhandler_applications:media_state
into subhandler_applications:state_idle
into subhandler_applications:state_idle
into subhandler_applications:state_idle
into subhandler_applications:state_idle
into subhandler_applications:state_ring_in
into subhandler_applications:media_state
into subhandler_applications:state_idle
00:23:12:26.176 - I/kernel: ept:ldxEventCb: HAPI_RM_DSP_EGRESS_UNDERRUN_EVT: devId = 48
into subhandler_applications:media_state
into subhandler_applications:state_idle
tstone_sessionstatus() cm_down act=-1 bak=-1
provision_task_delete_and_recreate(), recreate= 1
into subhandler_applications:reg_update
Get xmlservice_update or reg_update event: refresh UI ===> reg_update
------ Menu proxy get:applications
01:00:25:23.194 - E/ACCU: SCCP REG OK(0, -1)
01:00:25:23.207 - E/ACCU: SCCP REG FAIL(-1, -1)
01:00:25:23.219 - E/ACCU: accu_tstone_cmd_unconfig_resp(). ep_ref=0x118b640. unconfig_ok=

***********************
* Rate limit enabled *
***********************
16:57:27, 09/04/2014 : CTLSEP6CFA8973E9C0.tlv updating
provision_download_file 1527: HTTP server=10.200.0.11 filename=CTLSEP6CFA8973E9C0.tlv
into subhandler_applications:reg_update
Get xmlservice_update or reg_update event: refresh UI ===> reg_update
------ Menu proxy get:applications
provision_download_file 1575: HTTP download finish, result=0x800
16:57:27, 09/04/2014 : CTLSEP6CFA8973E9C0.tlv not found in 10.200.0.11
CTLSEP6CFA8973E9C0.tlv not found in 10.200.0.1116:57:27, 09/04/2014 : ITLSEP6CFA8973E9C0.
lv updating
provision_download_file 1527: HTTP server=10.200.0.11 filename=ITLSEP6CFA8973E9C0.tlv
into subhandler_applications:reg_update
Get xmlservice_update or reg_update event: refresh UI ===> reg_update
------ Menu proxy get:applications
provision_download_file 1571: HTTP download success
16:57:27, 09/04/2014 : ITLSEP6CFA8973E9C0.tlv (HTTP)
16:57:27, 09/04/2014 : ITLSEP6CFA8973E9C0.tlv updated successfully
Process Trust File /CFG/VC_ITLSEP6CFA8973E9C0.tlv successfully
16:57:27, 09/04/2014 : CTL and ITL installed
provision_download_file 1527: HTTP server=10.200.0.11 filename=SEP6CFA8973E9C0.cnf.xml.sg

provision_download_file 1575: HTTP download finish, result=0x800
download_configuration_process : exit, ret=6
provision_download_file 1527: HTTP server=10.200.0.11 filename=XMLDefault.cnf.xml.sgn
provision_download_file 1571: HTTP download success
16:57:28, 09/04/2014 : XMLDefault.cnf.xml.sgn (HTTP)
Authenticating configuration file XMLDefault.cnf.xml.sgn successfully
restore_vpngroup 2231: file open fail
save_phoneServices(): input data can't find save_srstinfo(): input data can't find Authenticating SCCP8941_8945.9-4-1-9.loads file successfully.
path = /nvdata is exist
FFS Commit to /nvdata/CFG1.
APCtrl skdownhandler
into subhandler_applications:undefined
"XsiInfoWidget_Active"
false
###############################################################
BOOT8941_8945.0-0-2-0.bin.sgn
SCCP8941_8945.9-4-1-9.bin1.sgn
SCCP8941_8945.9-4-1-9.bin2.sgn
SCCP8941_8945.9-4-1-9.bin3.sgn
SCCP8941_8945.9-4-1-9.bin4.sgn
SCCP8941_8945.9-4-1-9.bin5.sgn
SCCP8941_8945.9-4-1-9.bin6.sgn
SCCP8941_8945.9-4-1-9.bin7.sgn
SCCP8941_8945.9-4-1-9.bin8.sgn
SCCP8941_8945.9-4-1-9.bin9.sgn

###############################################################
image_flag = 9
*** cfgmgr_loads_task 738 callback return ***
download_configuration_process : exit, ret=1
[MMI]Not update call ui on unregister
into subhandler_applications:provision_ready
=====> ammu_update_augain_prof_level() ringer_min_level=0
ammu_get_cisco_sccp_ep_prof() video_capability=1
ammu_get_cisco_sccp_ep_prof() rtcp_enable=1
ammu_get_cisco_sccp_ep_prof() video_rtcp_enable=1
ammu_get_cisco_sccp_ep_prof() srtcp_auth_sha1_80=0
ammu_get_cisco_sccp_ep_prof() vdcfg_recording_tone=0
ammu_get_cisco_sccp_ep_prof() vdcfg_recording_tone_duration=65535
ammu_get_cisco_sccp_ep_prof() vdcfg_recording_tone_local_vol=100
ammu_get_cisco_sccp_ep_prof() vdcfg_recording_tone_rmt_vol=50
ammu_get_cisco_sccp_ep_prof() MissedCallLoggingOption=111111111111
(12)25
(12)6
(12)4
(12)2
(12)7
(12)8
(12)86
(12)15
(12)16
(12)11
(12)12
(12)257
(1)103
ffs_readfile 96 fd == ERROR (/CFG/ealarm.data)
Dump Trust List Info :

SEP6cfa8973e9c0
10.200.126.19
Not Installed
N/A
E4 3E 78 DF 08 E9 66 70 7D 75 D7 8A 49 6B C5 13 C1 FE 4D 44<
String>
callmanager1

[springboard] _send_file()@5021: Get /CFG/phoneServices.xml Fail
accu_sccp_guard_term_spy_register_ok() x_miss_call_dn[1]=1103 , btn_dn=1103
accu_sccp_guard_term_spy_register_ok() fk_idx[1] keep the same
accu_sccp_guard_term_spy_register_ok() fk_idx[2] is reset(dn empty).
accu_sccp_guard_term_spy_register_ok() fk_idx[3] is reset(dn empty).
accu_sccp_guard_term_spy_register_ok() fk_idx[4] is reset(dn empty).
accu_sccp_guard_term_spy_register_ok() fk_idx[5] is reset.
accu_sccp_guard_term_spy_register_ok() fk_idx[6] is reset.
accu_sccp_guard_term_spy_register_ok() fk_idx[7] is reset.
accu_sccp_guard_term_spy_register_ok() fk_idx[8] is reset.
accu_sccp_guard_term_spy_register_ok() fk_idx[9] is reset.
accu_sccp_guard_term_spy_register_ok() fk_idx[10] is reset.
accu_sccp_guard_term_spy_register_ok() fk_idx[11] is reset.
16:57:44, 09/04/2014 : VPN Error: VPN is not Configured.
01:00:25:40.560 - E/wallpaperCtrl.useUserDefWp() fail
01:00:25:40.608 - E/AMMU: Checking Protocol: Device=SCCP. Config=SCCP (OK)
01:00:25:40.626 - E/TSTN> 'tstone_proc_msg_accu_cfg()'. CUVA not connected! Reset vidsink
to LOCAL before registration
01:00:25:40.631 - E/ACCU: accu_sccp_ep_proc_tstone_config_resp_ok().
01:00:25:40.795 - E/ACCU: SCCP REG OK(-1, -1)
01:00:25:40.805 - E/ACCU: SCCP REG OK(0, -1)
01:00:25:40.830 - E/ACCU: SCCP REG OK(0, 1)
[MMI]Not update call ui on unregister
[MMI]Not update call ui on unregister
[MMI]Not update call ui on unregister
[MMI]Not update call ui on unregister
[MMI]Not update call ui on unregister
path = /nvdata is exist
FFS Commit to /nvdata/CFG2.
01:00:27:44.037 - E/[XSI][ERR]xsi.errTextTransitionDelegate(): xsictrl wasn't current foc
s app, stop showing any error message

[George Thomas]

Are the phones in the correct Device pool since that is where it gets the time from?

[hankej]

The issue seemed to have been the firmware on the phone.  I just downloaded sccp 9-4-2-8 and everything is working fine now.  My phone was previously on 9-4-1-8.

Thanks for the help.