7942G IP phone does not upgrade and does not accept locale

[DamianPodourovski]

Hello everyone!

I stumbled upon a rather strange behavior on a CP-7942G IP phone. It is a new phone, which I have myself unpacked from the box, along with 2 others.

Now, the problem is, this particular phone did not load the user-locale, specified in the telephony-services config on the CUCME and did not display the time correctly:

cnf-file location flash:
 cnf-file perphone
 user-locale RU load CME-locale-ru_RU-Russian-7.0.1.1.tar
 user-locale 2 U1 RU
 network-locale RU

While trying to debug what's going on, I eventually unpacked a second phone (same model) and got the same result: the user-locale was English, ASCII. I have downloaded the latest firmware update from the Cisco.com website (version 42.9.3.1.SR2.1S as opposed to previous 42.8.4.2S). Changed the config and restarted the second phone. I has updated it's firmware, restarted and got all settings correctly (Russian, UTF-8, display time).

The first phone though - the one it has all started with - still has the same problem. I tried to factory-reset it during it's power-on cycle, it tried to update, but looks like it didn't. This is what is shows in the Device Information section on the web-interface:

 App Load ID  jar42sccp.8-4-1-23.sbn 
 Boot Load ID  tnp42.9-3-1-57.bin 
 Version  SCCP42.8-4-2S.bin 
 Version  SCCP42.8-4-2S 

I'm not a Cisco pro, but looking in the console logs it seems that the phone does try to apply the locale settings, but reverts to default for some reason (look for text in BOLD):

|=== Syslogd === Thu Sep 26 10:13:42 2013
====================================================
NOT 10:13:42.736527 JVM: Startup Module Loader|cip.midp.rms.l:? -   loaded entry: _cip_properties.db=/flash0/RMS/appdb/_cip_properties.db
NOT 10:13:42.737816 JVM: Startup Module Loader - Deletion of file Successful/flash0/RMS/appdb/_cip_properties.dbk
NOT 10:13:42.739051 JVM: Startup Module Loader - Deletion of file Successful/flash0/RMS/appdb/_cip_properties.dbk
ERR 10:13:44.409247 JVM: Startup Module Loader|SystemManager:n/a - Encountered Property Exception while getting: device.system.targetmodules
NOT 10:13:44.483161 JVM: NativeIO assumed to be integrated into the VM
DBG 10:13:45.112916 lcd_open: return success!
NOT 10:13:47.776918 JVM: Font.addRes() owner=class cip.g3.r2d.y this=Font name=Monospace.font size=13 sty
NOT 10:13:47.777555 JVM: leFlags=0 tabWidth=0 tabTol=0
NOT 10:13:47.803214 JVM: Font.addRes() owner=class cip.g3.r2d.y this=Font name=Monospace.font size=17 sty
NOT 10:13:47.803814 JVM: leFlags=0 tabWidth=0 tabTol=0
NOT 10:13:47.855490 JVM: Font.addRes() owner=class cip.g3.r2d.y this=Font name=Unicode.font size=19 style
NOT 10:13:47.856090 JVM: Flags=0 tabWidth=0 tabTol=0
NOT 10:13:47.906921 JVM: Font.addRes() owner=class cip.g3.r2d.y this=Font name=Unicode.font size=17 style
NOT 10:13:47.907565 JVM: Flags=0 tabWidth=0 tabTol=0
NOT 10:13:47.958760 JVM: Font.addRes() owner=class cip.g3.r2d.y this=Font name=Unicode.font size=18 style
NOT 10:13:47.959358 JVM: Flags=0 tabWidth=0 tabTol=0
NOT 10:13:48.009978 JVM: Font.addRes() owner=class cip.g3.r2d.y this=Font name=Unicode.font size=15 style
NOT 10:13:48.010602 JVM: Flags=0 tabWidth=0 tabTol=0
WRN 10:13:48.160504 JVM: Startup Module Loader|Skin:? - Font reference not found for UIComponent=kemtext
NOT 10:13:48.317399 JVM: MediaTermination assumed to be integrated into the VM
NOT 10:13:48.821965 JVM: libMT:start SignOn()
NOT 10:13:48.824926 JVM: *** VIEO should already be running. NO? Start "/ubin/vieo &" now ***
NOT 10:13:48.825972 JVM: libMT: send _MTStart...
NOT 10:13:48.831661 JVM: LibMT (vieoProcess.c): registered VIEO callbacks
DBG 10:13:49.820548 audev: audioHwInit,,,
DBG 10:13:49.820933 audev: audioHwInit.
NOT 10:13:49.886879 DSP: _dspdvrStart ok, DSP bootup completed successfully

NOT 10:13:49.894065 DSP: DSP Tone/Ring DSP initialization completed successfully
NOT 10:13:49.895646 DSP: dspdvrinitEnableAudioOutput enabled = 1
NOT 10:13:49.896197 DSP: DSP INIT-*** DSP_STATE_READY***
NOT 10:13:49.898130 JVM: LibMT: MediaTerminationStart OK!
NOT 10:13:50.010519 JVM: Startup Module Loader|ComponentController:? - Initializing MVC comp module's trace mechanism 
NOT 10:13:50.123210 JVM: TftpNative assumed to be integrated into the VM
NOT 10:13:50.574189 JVM: HTTPService assumed to be integrated into the VM
WRN 10:13:50.879507 SECD: WARN:getCertInfo: ** phone has no LSC
NOT 10:13:52.372683 DSP: configurationNumber 1, enabled 0
NOT 10:13:52.379534 DSP: mtSetDeviceBandwidth: Wideband Headset - deviceWideband=0
NOT 10:13:52.382165 DSP: mtSetDeviceBandwidth: Wideband Handset - deviceWideband=2
NOT 10:13:52.384033 DSP: mtSetG722Codec: g722enable=0
NOT 10:13:52.402407 DSP: configurationNumber 0, enabled 0
NOT 10:13:53.353620 JVM: ENTERING loadSystemProperties
NOT 10:13:53.355397 JVM: EXITING loadSystemProperties
WRN 10:13:53.451585 JVM: Startup Module Loader - *******MIDlets Not allowed in REL load*******
NOT 10:13:53.452824 JVM: Startup Module Loader|cip.midp.midlet.MIDletManager:? - Initializing MIDletManager ...
NOT 10:13:53.454023 JVM: Startup Module Loader|cip.midp.midlet.MIDletManager:? - Initializing PushRegistry ...
NOT 10:13:53.950401 CDP-D: configSelectVLAN: 3 OP_USE_CDP:2 oper:101 cdp:101 lldp:4096 admin:4096 mac:0:25:83:85:73:18
NOT 10:13:53.951035 CDP-D: CDP OP: clear cross ref
NOT 10:13:53.951487 CDP-D: getCdpExtaInfo: OP:2 Cos:0 Trust:0 lldp.idxOfInterest:-1 
WRN 10:13:53.952071 CDP-D: cdpSetCos T:0 C:0 
NOT 10:13:53.952540 CDP-D: CosTrust:  0 0
NOT 10:13:53.958093 CDP-D: vvlan changed --> New:101 old:4096 101
ERR 10:13:53.958658 CDP-D: setVVLANConfig VVLAN CHANGED --> NEW:101 OLD:4096 101
NOT 10:13:53.963511 CDP-D: vlan Configured --> New:100 old:4096 :100
NOT 10:13:53.964616 CDP-D: Sent SIGNINFO & SIGIPCFG
NOT 10:13:53.965261 CDP-D: cdpSetDhcpEnableFlg: SIGNINFO
NOT 10:13:53.967656 JVM:  setSystemProperty() name=microedition.hostname value=SEPD0C789154FDC
NOT 10:13:53.969538 JVM:  setSystemProperty() name=microedition.locale value=en
NOT 10:13:53.971370 JVM:  setSystemProperty() name=microedition.encoding value=ASCII
NOT 10:13:53.972995 JVM:  setSystemProperty() name=microedition.platform value=CP-7942G/7.0.1
NOT 10:13:53.974623 JVM:  setSystemProperty() name=com.cisco.device.settings.config.userid value=
NOT 10:13:54.031701 JVM: Startup Module Loader|cip.midp.pushregistry.f:? - Starting MIDP PushRegistry ...
NOT 10:13:54.032873 JVM: Startup Module Loader - Deletion of file Successful/flash0/RMS/appdb/MAP1
NOT 10:13:54.034091 JVM: Startup Module Loader|cip.midp.pushregistry.f:? - MIDP PushRegistry started successfully.
NOT 10:13:54.035283 JVM: Startup Module Loader|cip.midp.midlet.MIDletManager:? - Initializing MIDP MemberFilter ...
NOT 10:13:54.036494 JVM: Startup Module Loader|cip.midp.midlet.MIDletManager:? - MIDletManager initialized.
NOT 10:13:54.259788 JVM: Creating new StateMachineThread
NOT 10:13:54.409562 PAE: SIGIPCFG received...
WRN 10:13:54.484755 SECD: WARN:getCTLInfo: ** phone has no CTL
WRN 10:13:54.488679 SECD: WARN:getCTLInfo: ** phone has no CTL
NOT 10:13:54.497820 DHCP: DHCP enabled... 
NOT 10:13:54.499483 DHCP:  dhcpSendReq: status 0x12300000 
NOT 10:13:54.500511 DHCP: Sending Request... 
ERR 10:13:54.503878 INETD: Local connect failed: 2 

NOT 10:13:54.526871 CDP-D: catchipcfg:getdhcpinfo IP:0 domain: chngVal:0 
NOT 10:13:54.528288 CDP-D: catchipcfg:getdhcpinfo IP:0 domain: chngVal:0 
NOT 10:13:54.529090 PAE: SIGIPCFG received...
NOT 10:13:54.530615 DHCP: Enable 
NOT 10:13:54.537357 DHCP:  dhcpSendReq: status 0x12300000 
NOT 10:13:54.537887 DHCP: Sending Request... 
NOT 10:13:54.538902 DHCP: ACK received 
NOT 10:13:54.539424 DHCP: Succeeded 
NOT 10:13:54.541514 ESP: send ADMIN, logging = 1, shell = 0, ipconfig = 1 
NOT 10:13:54.545277 tftpClient: request server 0 ---> 10.80.129.1 
NOT 10:13:54.552395 DHCP: IP Address -- 10.80.129.12 
NOT 10:13:54.552928 DHCP: Subnet Mask - 255.255.255.128 
NOT 10:13:54.553372 DHCP: Default Gwy - 10.80.129.1 
NOT 10:13:54.576800 CDP-D: catchipcfg:getdhcpinfo IP:a50810c domain: chngVal:1 
NOT 10:13:54.578452 PAE: SIGIPCFG received...
NOT 10:13:54.592996 tftpClient: request server 1 --->  
NOT 10:13:54.665767 tftpClient: request server 0 ---> 10.80.129.1 
NOT 10:13:54.666679 ESP: server 0 = 10.80.129.1 
NOT 10:13:54.670348 tftpClient: request server6 0 ---> :: 
NOT 10:13:54.674687 tftpClient: request server 1 --->  
NOT 10:13:54.675609 ESP: server 1 =  
NOT 10:13:54.677936 ESP: send ADMIN, logging = 1, shell = 0, ipconfig = 1 
NOT 10:13:54.683752 tftpClient: request server6 1 ---> :: 
ERR 10:13:54.689776 JVM: GETHOSTBYNAME: Empty Parameter
WRN 10:13:54.719514 JVM: Startup Module Loader|NetUtil.traceIPv4DNSErrors:? - DNS Unknown Host 
NOT 10:13:54.724673 SECD: updateCTL: starting CTL update
NOT 10:13:54.726696 SECD: ctlRequestFile: Socket 7 connected to /usr/tmp/tftpClientSock 
NOT 10:13:54.727408 SECD: ctlRequestFile: Request CTLSEPD0C789154FDC.tlv 
NOT 10:13:54.729751 tftpClient: tftp request rcv'd from /usr/tmp/ctlSock, srcFile = CTLSEPD0C789154FDC.tlv, dstFile = /usr/tmp/CTLFile.tlv 
NOT 10:13:54.734219 tftpClient: auth server - tftpList[0] = ::ffff:10.80.129.1 
NOT 10:13:54.734742 tftpClient: look up server - 0 
WRN 10:13:54.736310 SECD: WARN:lookupCTL: CTL update in progress, no old CTL, assume TFTP NONSECURE
NOT 10:13:54.738353 tftpClient: secVal = 0xa 
NOT 10:13:54.738948 tftpClient: ::ffff:10.80.129.1 is a NONsecure server 
NOT 10:13:54.739405 tftpClient: temp retval = SRVR_NONSECURE, keep looking 
NOT 10:13:54.739866 tftpClient: retval = 10 
NOT 10:13:54.740746 tftpClient: Non secure file requested  
NOT 10:13:54.749039 TFTP: [31]:Requesting CTLSEPD0C789154FDC.tlv from 10.80.129.1 
NOT 10:13:54.752921 TFTP: [31]:Error --> No such file 
NOT 10:13:54.758291 SYSMSG: pid 31 (/sbin/tftpd) Normal Exit, status = 2
INF 10:13:54.758326           runtime = 0.010 secs

INF 10:13:54.758344          user cpu = 0.001067950 secs

INF 10:13:54.758361        system cpu = 0.003620740 secs

INF 10:13:54.758374    child user cpu = 0.000000000 secs

INF 10:13:54.758388     child sys cpu = 0.000000000 secs

INF 10:13:54.758406    sys interrupts = 0.000427430 secs for 6 interrupts

INF 10:13:54.758427 total cpu = 0.004688690 secs ( 0% utilization )

NOT 10:13:54.761297 SECD: ctlRequestFile: tftp Status 2 rcv'd 
NOT 10:13:54.762017 SECD: updateCTL: finished CTL update
ERR 10:13:54.762581 SECD: EROR:updateCTL: ** had NO CTL and CTL tftp FAILED** tftp-err 2
NOT 10:13:54.765755 tftpClient: request server 0 ---> 10.80.129.1 
NOT 10:13:54.766486 ESP: server 0 = 10.80.129.1 
NOT 10:13:54.768900 tftpClient: request server 1 --->  
NOT 10:13:54.769575 ESP: server 1 =  
WRN 10:13:54.788934 SECD: WARN:getCTLInfo: ** phone has no CTL
NOT 10:13:54.801182 tftpClient: tftp request rcv'd from /usr/tmp/tftp, srcFile = SEPD0C789154FDC.cnf.xml, dstFile = /usr/ram/SEPD0C789154FDC.cnf.xml 
NOT 10:13:54.803072 tftpClient: auth server - tftpList[0] = ::ffff:10.80.129.1 
NOT 10:13:54.803598 tftpClient: look up server - 0 
WRN 10:13:54.805232 SECD: WARN:lookupCTL: ** no CTL, assume TFTP NONSECURE
NOT 10:13:54.807172 tftpClient: secVal = 0xa 
NOT 10:13:54.807773 tftpClient: ::ffff:10.80.129.1 is a NONsecure server 
NOT 10:13:54.808235 tftpClient: temp retval = SRVR_NONSECURE, keep looking 
NOT 10:13:54.808678 tftpClient: retval = 10 
NOT 10:13:54.809110 tftpClient: Secure file requested 
NOT 10:13:54.809523 tftpClient: Non secure file approved  -- SEPD0C789154FDC.cnf.xml  
NOT 10:13:54.816938 TFTP: [7]:Requesting SEPD0C789154FDC.cnf.xml from 10.80.129.1 
WRN 10:13:54.822003 SECD: WARN:getCTLInfo: ** phone has no CTL
WRN 10:13:54.825845 SECD: WARN:getCTLInfo: ** phone has no CTL
NOT 10:13:54.835047 TFTP: [7]:Finished --> rcvd 1456 bytes 
NOT 10:13:55.010336 SECD: clearTFTPList: cleared all TFTP entries
NOT 10:13:55.046228 JVM: VendorConfig - logging display == 1
NOT 10:13:55.047554 JVM: VendorConfig - logging display, theValue == 17
NOT 10:13:55.169337 JVM:  setSystemProperty() name=microedition.encoding value=utf-8
NOT 10:13:55.171804 JVM:  setSystemProperty() name=microedition.locale value=RU
ERR 10:13:55.237375 JVM: Startup Module Loader|cip.cfg.r:? - Delete of sshUserInfo file failed 
ERR 10:13:55.238704 JVM: Startup Module Loader|cip.setg.ScreenSaveProperty:? - Config file field <displayOnTime> is incorrectly formatted. Defaulting.
ERR 10:13:55.240553 JVM: Startup Module Loader|cip.setg.ScreenSaveProperty:? - Config file field <displayIdleTimeout> is incorrectly formatted. Defaulting.
NOT 10:13:55.246502 SECD: setSecMode: sec mode set to NONE (was NONE)
NOT 10:13:55.263415 JVM: setTimeZone tz:Russian Standard/Daylight Time ctz:Europe/Moscow
NOT 10:13:55.595116 SECD: clearSRSTList: cleared all SRST entries
ERR 10:13:55.646423 JVM: GETHOSTBYNAME: Empty Parameter
WRN 10:13:55.699101 SECD: WARN:cancelCapfOp: CAPF not in use, user cancel ignored
NOT 10:13:55.699692 SECD: clearCapfList: CAPF table cleared
ERR 10:13:55.702675 JVM: GETHOSTBYNAME: Empty Parameter
ERR 10:13:55.711673 SECD: EROR:addCapfEntry: phone has no CTL, can't use CAPF
WRN 10:13:55.724535 JVM: Startup Module Loader|NetUtil.traceIPv4DNSErrors:? - DNS Unknown Host 
ERR 10:13:55.725747 JVM: Startup Module Loader|cip.sec.CapfProperty:? - Failed to resolve Capf Ipv4 Address with hostname 
ERR 10:13:55.727617 JVM: Startup Module Loader|cip.sec.CapfProperty:? - dns failed previously.will resolve it now
WRN 10:13:55.728964 JVM: Startup Module Loader|NetUtil.traceIPv4DNSErrors:? - DNS Unknown Host 
ERR 10:13:55.730207 JVM: Startup Module Loader|cip.sec.CapfProperty:? - Failed to resolve Capf Ipv4 Address with hostname 
NOT 10:13:55.753975 SECD: setSecMode: sec mode set to NONE (was NONE)
NOT 10:13:55.785279 JVM: Startup Module Loader - Deletion of file Successful/usr/ram/SEPD0C789154FDC.cnf.xml
NOT 10:13:55.786489 JVM: Startup Module Loader|cip.cfg.r:? - DELETE ConfigFile:(ram/SEPD0C789154FDC.cnf.xml)WAS SUCCESSFUL
NOT 10:13:55.788171 INETD: Set IP mode 1 
NOT 10:13:55.788705 INETD: Requestted IP mode is same as current mode
NOT 10:13:55.789870 CDP-D: cdpSetRepeater 11 
NOT 10:13:55.790704 CDP-D: set pc vvlan access --> 26 
NOT 10:13:55.823622 CDP-D: catchipcfg:getdhcpinfo IP:a50810c domain: chngVal:1 
NOT 10:13:55.828991 ESP: send ADMIN, logging = 1, shell = 0, ipconfig = 1 
NOT 10:13:55.830112 DNS: SIGHUP received... 
NOT 10:13:55.832373 PAE: SIGIPCFG received...
NOT 10:13:55.841392 tftpClient: request server 0 ---> 10.80.129.1 
NOT 10:13:55.842016 tftpClient: tftp request rcv'd from /usr/tmp/tftp, srcFile = User_Define_1/mk-sccp.jar, dstFile = /usr/ram/L10N-1584653765 
NOT 10:13:55.847011 tftpClient: auth server - tftpList[0] = ::ffff:10.80.129.1 
NOT 10:13:55.847529 tftpClient: look up server - 0 
WRN 10:13:55.849117 SECD: WARN:lookupCTL: ** no CTL, assume TFTP NONSECURE
NOT 10:13:55.851181 tftpClient: secVal = 0xa 
NOT 10:13:55.851781 tftpClient: ::ffff:10.80.129.1 is a NONsecure server 
NOT 10:13:55.852235 tftpClient: temp retval = SRVR_NONSECURE, keep looking 
NOT 10:13:55.852654 tftpClient: retval = 10 
NOT 10:13:55.853080 tftpClient: Secure file requested 
NOT 10:13:55.853491 tftpClient: Non secure file approved  -- User_Define_1/mk-sccp.jar  
NOT 10:13:55.861589 TFTP: [4]:Requesting User_Define_1/mk-sccp.jar from 10.80.129.1 
NOT 10:13:55.865280 TFTP: [4]:Error --> No such file 
NOT 10:13:55.870648 SYSMSG: pid 4 (/sbin/tftpd) Normal Exit, status = 2
INF 10:13:55.870685           runtime = 0.010 secs

INF 10:13:55.870703          user cpu = 0.001025390 secs

INF 10:13:55.870720        system cpu = 0.002738420 secs

INF 10:13:55.870733    child user cpu = 0.000000000 secs

INF 10:13:55.870747     child sys cpu = 0.000000000 secs

INF 10:13:55.870765    sys interrupts = 0.000473360 secs for 6 interrupts

INF 10:13:55.870786 total cpu = 0.003763810 secs ( 0% utilization )

NOT 10:13:55.907442 JVM:  setSystemProperty() name=microedition.encoding value=ASCII
NOT 10:13:55.909263 JVM:  setSystemProperty() name=microedition.encoding value=ASCII
NOT 10:13:55.954262 JVM:  setSystemProperty() name=microedition.locale value=en
NOT 10:13:55.956112 JVM:  setSystemProperty() name=microedition.locale value=en
WRN 10:13:55.974314 JVM: Startup Module Loader|cip.l10n.UserLocaleProperty:? - Unable to process LocaleProperty 'device.settings.config.localization.userlocale'
NOT 10:13:55.993687 tftpClient: request server 1 --->  
NOT 10:13:56.012450 tftpClient: request server 0 ---> 10.80.129.1 
NOT 10:13:56.013069 tftpClient: tftp request rcv'd from /usr/tmp/tftp, srcFile = United_States/g3-tones.xml, dstFile = /usr/ram/L10N-61391065 
NOT 10:13:56.025036 ESP: server 0 = 10.80.129.1 
NOT 10:13:56.036772 tftpClient: auth server - tftpList[0] = ::ffff:10.80.129.1 
NOT 10:13:56.037342 tftpClient: look up server - 0 
WRN 10:13:56.039157 SECD: WARN:lookupCTL: ** no CTL, assume TFTP NONSECURE
NOT 10:13:56.041548 tftpClient: secVal = 0xa 
NOT 10:13:56.042149 tftpClient: ::ffff:10.80.129.1 is a NONsecure server 
NOT 10:13:56.042608 tftpClient: temp retval = SRVR_NONSECURE, keep looking 
NOT 10:13:56.043031 tftpClient: retval = 10 
NOT 10:13:56.043464 tftpClient: Secure file requested 
NOT 10:13:56.043876 tftpClient: Non secure file approved  -- United_States/g3-tones.xml  
NOT 10:13:56.075611 TFTP: [3]:Requesting United_States/g3-tones.xml from 10.80.129.1 
NOT 10:13:56.079347 TFTP: [3]:Error --> No such file 
NOT 10:13:56.084690 SYSMSG: pid 3 (/sbin/tftpd) Normal Exit, status = 2
INF 10:13:56.084726           runtime = 0.030 secs

INF 10:13:56.084744          user cpu = 0.001016140 secs

INF 10:13:56.084761        system cpu = 0.004127440 secs

INF 10:13:56.084774    child user cpu = 0.000000000 secs

INF 10:13:56.084788     child sys cpu = 0.000000000 secs

INF 10:13:56.084806    sys interrupts = 0.000648400 secs for 10 interrupts

INF 10:13:56.084827 total cpu = 0.005143580 secs ( 0% utilization )

WRN 10:13:56.152126 JVM: Startup Module Loader|cip.l10n.NetworkLocaleProperty:? - Unable to process LocaleProperty 'device.settings.config.localization.networklocale'
WRN 10:13:56.185037 SECD: WARN:lookupCTL: ** no CTL, assume UCM NONSECURE
NOT 10:13:56.201903 JVM: Adding SRST .. #valid : 1
WRN 10:13:56.205891 SECD: WARN:lookupSRST: no CTL, treat SRST as non-secure
NOT 10:13:56.211795 JVM: Done with SRST: 1
NOT 10:13:56.274791 tftpClient: request server 1 --->  
NOT 10:13:56.275707 ESP: server 1 =  
NOT 10:13:56.352535 tftpClient: request server6 0 ---> :: 
NOT 10:13:56.359771 tftpClient: request server6 1 ---> :: 
NOT 10:13:56.407633 JVM: SecureSocketImpl.create() fd=java.io.FileDescriptor@111f4 valid=true
WRN 10:13:57.135911 JVM: Startup Module Loader|cip.mmgr.ds:? - [MediaMgrSM]: Unhandled Event, State = StateOnHook Event = EventServicesTxStop
WRN 10:13:57.149565 SECD: WARN:getCTLInfo: ** phone has no CTL
WRN 10:13:57.153827 SECD: WARN:getCTLInfo: ** phone has no CTL
NOT 10:13:57.165025 tftpClient: request server 0 ---> 10.80.129.1 
NOT 10:13:57.178308 tftpClient: request server 1 --->  
NOT 10:13:57.243532 tftpClient: request server6 0 ---> :: 
NOT 10:13:57.249934 tftpClient: request server6 1 ---> :: 
ERR 10:14:39.590147 NTP: Thu Sep 26 09:14:39 2013
ERR 10:14:57.374505 JVM: cvmDebug: executing - 5. Dump total obj counts in java heap
ERR 10:14:57.386075 JVM: Counted 27546 objects
ERR 10:14:57.386815 JVM: Total Heap Size:3800064
ERR 10:14:57.387491 JVM:  Free Heap Size:2771660
ERR 10:14:58.370281 JVM: cvmDebug: executing - 7. Dump string value of [C instances in java heap
ERR 10:16:48.404683 JVM: cvmDebug: executing - 5. Dump total obj counts in java heap
ERR 10:16:48.419137 JVM: Counted 34953 objects
ERR 10:16:48.419359 JVM: Total Heap Size:3800064
ERR 10:16:48.419512 JVM:  Free Heap Size:2329208
ERR 10:16:49.400355 JVM: cvmDebug: executing - 7. Dump string value of [C instances in java heap
WRN 10:16:52.068040 CDP-D: lldpInetdStatsRsp: port: 0 
WRN 10:16:53.007959 CDP-D: lldpInetdStatsRsp: port: 1 
WRN 10:16:53.947877 CDP-D: lldpInetdStatsRsp: port: 0 
WRN 10:18:53.378017 CDP-D: lldpInetdStatsRsp: port: 0 
WRN 10:18:55.337826 CDP-D: lldpInetdStatsRsp: port: 1 

Any ideas what can be done? The phone now displays the time correctly, but the second button shows empty squares where the "Login" button should be. All the rest of the phone inteface is in English.

[Leo Laohoo]

Are the phones getting a valid IP address?

[DamianPodourovski]

Leo,

Yes, both phones received their addresses from the router DHCP, seemingly with the correct Option 150 setting. Both have been accessible over HTTP as well.

[DamianPodourovski]

Anyone?

[DamianPodourovski]

For some veru strange reason the problem... resolved itself.

I had to remove the phone from the Ethernet line today, because I needed it. I then re-plugged decided to re-plug it back(in about 10 minutes from the initial power off) and voila: the phone sucessfully updated itself and received the right locale...

I wish I knew what was the real problem behind this.

I did change the config on CUCME today, but I have just added a "Text to display on phones" (command "system message"). I then re-created CNF files (but I have tried that already last week).

Maybe the stars aligned in a different way today, I don't know...

[Arthur Luke]

I had a very similar problem after moving a site from a CUCM 6.X cluster to an 8.6 cluster. The phones moved over OK, but were showing the wrong time. My problem turned out to be related to the fact that I had installed a device pack which updated my device defaults to 9.3.1SR31S, but I had yet to reboot the cluster (which strangely seems to be required after this device pack - PUB and TFTP had been restarted already)

Anyway, after updating the phone load name on the device itself to SCCP42.9-3-1SR2-1S, the phone came up and got the 9-3-1SR2-1S firmware, and registered to the new cluster.