[cisco-voip] issue with 7942 firmware
Andy
andy.carse at gmail.com
Wed Oct 30 10:12:28 EDT 2013
Actually now having looked at all the log files (I assumed that it was 1
file per reboot).
this would seem to suggest the reason why it won't downgrade.
From the console log
3167: NOT 13:51:57.788380 TLoad: ^.idl_protocol.c-doSourceFileSerial
3168: INF 13:51:57.788886 TLoad: Get next entry from /usr/tmp/loadedfiles
3169: NOT 13:51:57.789456 TLoad: fetch complete notifying parent
3170: DBG 13:51:57.806150 image: phone hw-compat value 4
3171: NOT 13:51:57.806714 image: loadhwc=1 myhwc=4
3172: WRN 13:51:57.807221 image: Load rejected - hardware compatibility
mismatch
3173: WRN 13:51:57.807716 image: Must use 9.3(1) SR or later release on
this phone
3174: INF 13:51:57.808289 TLoad: Caught SIGTERM - Ready to exit set
(sockcnt=0)
3175: INF 13:51:57.808813 TLoad: Masking all signals during shutdown
3176: INF 13:51:57.809294 TLoad: Signal 'Terminated'[23] received
I've attached all the log files this time (this snippet is from log58.log)
Regards
Andy
On 30/10/2013 13:40, Andy wrote:
> Brian, not that makes sense to me.
>
> I've attached a console log for the handset and below is the status
> messages
>
> 00:33:22 No Default Router
> 00:33:35 No IPv4 DNS Server
> 00:33:35 Trust List Updated
> 00:33:36 SEP7C95F3C93DD3.cnf.xml.sgn(HTTP)
> 00:34:12 No Default Router
> 00:34:23 No IPv4 DNS Server
> 00:34:23 Trust List Updated
> 00:34:24 SEP7C95F3C93DD3.cnf.xml.sgn(HTTP)
> 00:34:25 Load Rejected HC SCCP42.9-3-1-1S
> 00:34:26 VPN Not Configured
>
> both the console log and the above are to my lab which is on a flat vlan.
>
> The handset is currently running 9-3-1SR2-1S and I was trying to get
> it to load 9-3-1-1S
>
> Regards
>
> Andy
>
> On 30/10/2013 13:29, Brian Meade (brmeade) wrote:
>> See anything interesting in the status messages or console logs?
>>
>> Brian
>>
>> -----Original Message-----
>> From: cisco-voip [mailto:cisco-voip-bounces at puck.nether.net] On
>> Behalf Of Andy
>> Sent: Wednesday, October 30, 2013 9:21 AM
>> To: Cisco VoIP List
>> Subject: [cisco-voip] issue with 7942 firmware
>>
>> Hi,
>> I have a strange issue with some new 7942's, in as much as they won't
>> downgrade!
>> I have approx 5 out of 20 that refuse to downgrade to 9.2.3 or even
>> 9.3.1 from 9.3.1SR1.
>> They come up with Load Reject HC, which sort of suggests that there
>> is a mismatch between the hardware and software, but what is the
>> question!
>> I can't see anything obvious in the handsets bootload or settings via
>> the web.
>>
>> They will however upgrade to 9.3.1SR2
>> I've tried restarting the tftp service and on a different cluster!
>>
>> I guess its a TAC case if no one has any ideas.
>>
>> --
>> Regards
>>
>> Andy
>>
>> _______________________________________________
>> cisco-voip mailing list
>> cisco-voip at puck.nether.net
>> https://puck.nether.net/mailman/listinfo/cisco-voip
>>
>
-------------- next part --------------
|== Syslogd TNP== Wed Oct 30 13:52:33 2013
====================================================
3633: ERR 13:52:34.274521 JVM: TVS server is : IPv4 : 192.168.1.130, IPv6 : , Port : 2445, IPv4 : , IPv6 : , Port : 0, IPv4 : , IPv6 : , Port : 0, IP Mode : 0, IP Preference Mode: 0
3634: NOT 13:52:34.276562 SECD: handleTvsSetReq: IPv6 : , IPv6 : , IPv6 : , IP Mode : 0, IP Pref : 0 DSCP : 96
3635: NOT 13:52:34.278166 JVM: Startup Module Loader|cip.cfg.t:? - ====>123Config handleTftpResponse, status=20 for file=ram/SEP7C95F3C93DD3.cnf.xml
3636: WRN 13:52:34.311451 JVM: Startup Module Loader|cip.xml.ap:parse - Encoding Updated to UTF-8
3637: WRN 13:52:34.312843 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'mlppDomainId' in element '/device' (line=14)
3638: WRN 13:52:34.314266 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'mlppIndicationStatus' in element '/device' (line=15)
3639: WRN 13:52:34.315682 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'preemption' in element '/device' (line=16)
3640: WRN 13:52:34.317099 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'executiveOverridePreemptable' in element '/device' (line=17)
3641: WRN 13:52:34.318522 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'name' in element '/device/devicePool' (line=20)
3642: WRN 13:52:34.319981 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'name' in element '/device/devicePool/dateTimeSetting' (line=22)
3643: WRN 13:52:34.321484 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'name' in element '/device/devicePool/callManagerGroup' (line=28)
3644: WRN 13:52:34.322911 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'tftpDefault' in element '/device/devicePool/callManagerGroup' (line=29)
3645: WRN 13:52:34.324373 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'mgcpPorts' in element '/device/devicePool/callManagerGroup/members/member/callManager/ports' (line=39)
3646: WRN 13:52:34.325798 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'name' in element '/device/devicePool/srstInfo' (line=50)
3647: WRN 13:52:34.327234 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'userModifiable' in element '/device/devicePool/srstInfo' (line=52)
3648: WRN 13:52:34.328703 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'uid' in element '/device/networkLocaleInfo' (line=101)
3649: WRN 13:52:34.334691 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'mobility' in element '/device' (line=141)
3650: WRN 13:52:34.337236 JVM: Startup Module Loader|cip.cfg.XmlConfigParmObject:? - ===getFullConfig return true
3651: WRN 13:52:34.338827 JVM: Startup Module Loader|cip.cfg.XmlConfigParmObject:? - ===getEncrConfig return false
3652: ERR 13:52:34.340812 JVM: Startup Module Loader|cip.cfg.t:? - DirectoryUrl http://192.168.1.130:8080/ccmcip/xmldirectory.jspsecuredirectoryUrl https://192.168.1.130:8443/ccmcip/xmldirectory.jsp
3653: NOT 13:52:34.345175 JVM: Startup Module Loader|cip.cfg.t:? - setConfigTvsProperty IP mode 0IP Pref 0DSCP 96
3654: NOT 13:52:34.346823 JVM: Startup Module Loader|cip.sec.TvsProperty:? - TVS IPv4 - 1 :192.168.1.130TVS IPv6 - 1 :TVS Port - 1:2445TVS IPv4 - 2 :TVS IPv6 - 2 :TVS Port - 2:0TVS IPv4 - 3 :TVS IPv6 - 3 :TVS Port - 3:0IP Address Mode:0IP Preference Mode:0
3655: NOT 13:52:34.348486 JVM: Startup Module Loader|cip.sec.TvsProperty:? - Resolve Tvs Ipv4 Address to 192.168.1.130from hostname 192.168.1.130
3656: NOT 13:52:34.352502 SECD: loadTvsSrvrCfg: Not in EMCC mode.Loading the flash file :/flash0/sec/misc/tvs.conf
3657: NOT 13:52:34.362931 JVM: emccMode=0,localOverride=0, tftpAddr1=, tftpAddr2=,tftpAddr3=,tftpAddr4=
3658: NOT 13:52:34.375874 xxtpClient: request server 0 ---> 192.168.1.130
3659: NOT 13:52:34.376549 xxtpClient: tftp request rcv'd from /usr/tmp/tftp, emccMode =0, emccLocalOverride=0, tempTftp1= , tempTftp2 = , tempTftp3 = , tempTftp4 =
3660: NOT 13:52:34.377966 JVM: setTempTftpAddress, emcc_mode=0,retEmccMode=0,LocalOverride=0,retLocalOverride=0, status=1
3661: NOT 13:52:34.424892 JVM: Startup Module Loader|cip.cfg.t:? - ===not full config or not emcc mode ,so do vendor config
3662: NOT 13:52:34.427777 SECD: clearTFTPList: cleared all TFTP entries
3663: ERR 13:52:34.569049 JVM: Startup Module Loader|cip.mmgr.MediaManager:? -
MediaManager propertyChanged called device.settings.config.ipaddressingmode
3664: NOT 13:52:34.572897 INETD: Set IP mode 1
3665: NOT 13:52:34.573490 INETD: Requestted IP mode is same as current mode
3666: ERR 13:52:34.928544 JVM: Startup Module Loader|cip.cfg.t:? - Delete of sshUserInfo file failed
3667: ERR 13:52:34.929955 JVM: Startup Module Loader|cip.cfg.t:? - informationUrl is https://192.168.1.130:8443/ccmcip/GetTelecasterHelpText.jsp
3668: ERR 13:52:34.931964 JVM: Startup Module Loader|cip.cfg.t:? - directoriesUrl is https://192.168.1.130:8443/ccmcip/xmldirectory.jsp
3669: ERR 13:52:34.933357 JVM: Startup Module Loader|cip.cfg.t:? - messagesUrl is
3670: ERR 13:52:34.934766 JVM: Startup Module Loader|cip.cfg.t:? - servicesUrl is https://192.168.1.130:8443/ccmcip/getservicesmenu.jsp
3671: ERR 13:52:34.936287 JVM: Startup Module Loader|cip.cfg.t:? - authenticationUrl is https://192.168.1.130:8443/ccmcip/authenticate.jsp
3672: ERR 13:52:34.937701 JVM: Startup Module Loader|cip.cfg.t:? - idleUrl is
3673: ERR 13:52:34.939112 JVM: Startup Module Loader|cip.cfg.t:? - messagesUrl is null
3674: ERR 13:52:34.941255 JVM: Startup Module Loader|cip.cfg.t:? - After set servicesUrl device.settings.config.servicesurl value https://192.168.1.130:8443/ccmcip/getservicesmenu.jsp
3675: ERR 13:52:34.942670 JVM: Startup Module Loader|cip.cfg.t:? - After set info url device.settings.config.informationurl value https://192.168.1.130:8443/ccmcip/GetTelecasterHelpText.jsp
3676: ERR 13:52:34.944124 JVM: Startup Module Loader|cip.cfg.t:? - After set dir Url device.settings.config.directoriesurl value https://192.168.1.130:8443/ccmcip/xmldirectory.jsp
3677: ERR 13:52:34.945537 JVM: Startup Module Loader|cip.cfg.t:? - idle Url of null
3678: ERR 13:52:34.946901 JVM: Startup Module Loader|cip.cfg.t:? - After set sec auth Url device.settings.config.authenticationurl value https://192.168.1.130:8443/ccmcip/authenticate.jsp
3679: ERR 13:52:34.948363 JVM: Startup Module Loader|VendorConfig:? - vendorconfig : setConfigProperties
3680: ERR 13:52:34.949721 JVM: Startup Module Loader|VendorConfig:? -
About to set WebAccess
3681: WRN 13:52:34.951103 JVM: Startup Module Loader|DisplayTask:? - resetCurrentPowerState(): Energywise is disabled
3682: WRN 13:52:34.952477 JVM: Startup Module Loader|DisplayTask:? - resetCurrentPowerState(): Energywise null domain
3683: WRN 13:52:34.953886 JVM: Startup Module Loader|DisplayTask:? - resetCurrentPowerState(): Energywise null secret
3684: WRN 13:52:34.955289 JVM: Startup Module Loader|DisplayTask:? - resetCurrentPowerState(): energywise is not available now
3685: ERR 13:52:34.956703 JVM: Startup Module Loader|cip.setg.ScreenSaveProperty:? - Config file field <displayOnTime> is incorrectly formatted. Defaulting.
3686: ERR 13:52:34.958143 JVM: Startup Module Loader|cip.setg.ScreenSaveProperty:? - Config file field <displayIdleTimeout> is incorrectly formatted. Defaulting.
3687: ERR 13:52:34.959568 JVM: Startup Module Loader|VendorConfig:? - WebAccess true
3688: ERR 13:52:34.961560 JVM: Startup Module Loader|VendorConfig:? - WebProtocol 0
3689: NOT 13:52:34.962923 JVM: Startup Module Loader|VendorConfig:? - Setting 80-bit-srtp to false
3690: ERR 13:52:34.964303 JVM: Startup Module Loader|cip.mmgr.MediaManager:? -
MediaManager propertyChanged called device.settings.config.vendorconfig.sidetonelevel
3691: NOT 13:52:34.967031 JVM: Startup Module Loader|VendorConfig:? - Setting RTCP to false
3692: NOT 13:52:34.968564 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.localization.userlocale.charset value=iso-8859-1
3693: NOT 13:52:34.969960 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.localization.userlocale.languagecode value=en_GB
3694: NOT 13:52:34.974444 SECD: setSecMode: sec mode set to NONE (was NONE)
3695: NOT 13:52:35.625416 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.phoneservices value=cip.props.XmlProperty at a38426
3696: NOT 13:52:35.627003 INETD: inetdSetSSHAccess: 0 -> 0
3697: NOT 13:52:35.627582 INETD: sshAccessWriteConf: old sshAccess:0, new sshAccess:0
3698: NOT 13:52:35.630941 INETD: inetdSetSLOGINAccess: 1 -> 1
3699: DBG 13:52:35.634817 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3700: DBG 13:52:35.646453 VPNU: No VPN database change
3701: NOT 13:52:35.650224 SECD: clearSRSTList: cleared all SRST entries
3702: WRN 13:52:35.776602 SECD: WARN:cancelCapfOp: CAPF not in use, user cancel ignored
3703: NOT 13:52:35.777224 SECD: clearCapfList: CAPF table cleared
3704: NOT 13:52:35.786395 JVM: Startup Module Loader|cip.cfg.t:? - CUCM in config file: #0 XmlCallAgentObject: IPv4-name=[192.168.1.130] IPv6-name=[] port=2000 priority=0
3705: NOT 13:52:35.787803 JVM: Startup Module Loader|cip.cfg.t:? - CUCM in config file: #1 XmlCallAgentObject: IPv4-name=[] IPv6-name=[] port=0 priority=32767
3706: NOT 13:52:35.789226 JVM: Startup Module Loader|cip.cfg.t:? - CUCM in config file: #2 XmlCallAgentObject: IPv4-name=[] IPv6-name=[] port=0 priority=32767
3707: NOT 13:52:35.790653 JVM: Startup Module Loader|cip.cfg.t:? - CUCM in config file: #3 XmlCallAgentObject: IPv4-name=[] IPv6-name=[] port=0 priority=32767
3708: NOT 13:52:35.792067 JVM: Startup Module Loader|cip.cfg.t:? - CUCM in config file: #4 XmlCallAgentObject: IPv4-name=[] IPv6-name=[] port=0 priority=32767
3709: ERR 13:52:35.794170 JVM: Startup Module Loader|cip.sec.CapfProperty:? - Failed to resolve Capf Ipv4 Address with hostname
3710: ERR 13:52:35.795566 JVM: Startup Module Loader|cip.sec.CapfProperty:? - Failed to resolve Capf Ipv6 Address with hostname
3711: ERR 13:52:35.797617 JVM: Startup Module Loader|cip.sec.CapfProperty:? - No valid CAPF server
3712: NOT 13:52:35.799005 JVM: Startup Module Loader|cip.cfg.t:? - Config processConfigNoError() result code=CONFIG_FILE_NO_CHANGE
3713: NOT 13:52:35.802168 SECD: setSecMode: sec mode set to NONE (was NONE)
3714: DBG 13:52:35.964193 cffs_journal_commit() change journal base 0x840000
3715: DBG 13:52:35.964939 cffs_journal_create() sec 69
3716: DBG 13:52:35.966311 cffs_journal_commit() new journal base 0x8a0000/hdr:4/data:5
3717: NOT 13:52:36.068339 JVM: Startup Module Loader - Deletion of file Successful/usr/ram/SEP7C95F3C93DD3.cnf.xml
3718: NOT 13:52:36.069749 JVM: Startup Module Loader|cip.cfg.t:? - DELETE ConfigFile:(ram/SEP7C95F3C93DD3.cnf.xml)WAS SUCCESSFUL
3719: NOT 13:52:36.071249 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - --->ConfigManager PropertyChanged: device.config.io.dscp.config
3720: NOT 13:52:36.072649 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - <---ConfigManager PropertyChanged: device.config.io.dscp.config
3721: NOT 13:52:36.074806 JVM: Startup Module Loader|cip.sccp.df:? - SccpListener.statusChanged()=>
resetState=NO_RESET
registrationState=NORMAL
isSoftResetPending=false
isDualStackResetPending=false
isRegistrationStopped=false
dhcpOption=0(DHCP_FAST)
deadIpStack=-1
restartRequested=false
resetRequested=false
shuttingDown=false
3722: ERR 13:52:36.078972 SECD: EROR:lookupCTL: UCM not in TL, NOT_FOUND
3723: WRN 13:52:36.093996 SECD: WARN:lookupSRST: SRST in list, no valid cert, look for some CA
3724: NOT 13:52:36.094624 SECD: lookupSRST: SRST, no CA present, try NONSECURE
3725: WRN 13:52:36.156696 JVM: Startup Module Loader|cip.sccp.CcApi:? - alarm sending failure:25: Name=SEP7C95F3C93DD3 Load= 9.3(1SR2.1S) Last=Initialised
3726: ERR 13:52:36.529200 JVM: SecureSocket non-secure binding to ::
3727: WRN 13:52:37.229298 JVM: Startup Module Loader|cip.sccp.SccpEnhancedAlarmInfo:getLastUnregistrationTimeReason - TimeStamp=1383141055356; Reasons =20
3728: NOT 13:52:37.230795 JVM: Startup Module Loader|cip.sccp.c:? - CcApi.statusChanged()=>
resetState=NO_RESET
registrationState=NORMAL
isSoftResetPending=false
isDualStackResetPending=false
isRegistrationStopped=false
dhcpOption=0(DHCP_FAST)
deadIpStack=-1
3729: DBG 13:52:37.233399 VPNU: State AppsUp --> AppsUp
3730: DBG 13:52:37.234008 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3731: DBG 13:52:37.234567 VPNU: State AppsUp --> AppsUp
3732: NOT 13:52:37.235994 CDP-D: cdpSetRepeater 11
3733: NOT 13:52:37.239460 CDP-D: set pc vvlan access --> 26
3734: NOT 13:52:37.243782 CDP-D: cdpSetSwportCfgRemote 7 timer 30
3735: NOT 13:52:37.244386 CDP-D: cdpSetSwportCfgRemote setting : Type=0 Cfg=7 portval=7
3736: NOT 13:52:37.247474 CDP-D: cdpSetSwportCfgRemote: Value Sent to IOCTL LOCAL[0] 1 1
3737: NOT 13:52:37.248255 CDP-D: cdpSetPcportCfgRemote*** 7
3738: NOT 13:52:37.251072 CDP-D: cdpSetPcportCfgRemote Value Sent to IOCTL LOCAL[0] 1
3739: NOT 13:52:37.255644 JVM: --> Properties JNI: SignalApp() handling SIGIPCFG. msgCode=256 value=0
3740: NOT 13:52:37.270964 JVM: <-- Properties JNI: SignalApp()
3741: NOT 13:52:37.273826 JVM: --> Properties JNI: SignalApp() handling SIGIPCFG. msgCode=256 value=0
3742: NOT 13:52:37.285788 JVM: <-- Properties JNI: SignalApp()
3743: NOT 13:52:37.287569 JVM: --> Properties JNI: SignalApp() handling SIGIPCFG. msgCode=256 value=0
3744: NOT 13:52:37.299421 JVM: <-- Properties JNI: SignalApp()
3745: NOT 13:52:37.304304 DNS: SIGHUP received...
3746: WRN 13:52:37.306546 CDP-D: lldpInetdStatsRsp: port: 0
3747: NOT 13:52:37.308071 PAE: SIGIPCFG received...
3748: NOT 13:52:37.313209 DHCP6: sockTos = 0x60
3749: NOT 13:52:37.316901 ESP: send ADMIN, logging = 1, shell = 0, ipconfig = 1
3750: NOT 13:52:37.331369 xxtpClient: request server 1 --->
3751: DBG 13:52:37.334962 cffs_gc(): runing gc 1
3752: NOT 13:52:37.341074 CDP-D: catchipcfg:getdhcpinfo IP:c0a8010c domain: chngVal:1
3753: NOT 13:52:37.360823 CDP-D: catchipcfg:getdhcpinfo IP:c0a8010c domain: chngVal:1
3754: NOT 13:52:37.380798 CDP-D: catchipcfg:getdhcpinfo IP:c0a8010c domain: chngVal:1
3755: NOT 13:52:37.382004 CDP-D: cdpGetPortCfg SPANTOPC CFG:11
3756: WRN 13:52:37.452305 JVM: Startup Module Loader|cip.sccp.SccpEnhancedAlarmInfo:setLastDeregistrationReason - new reason=LastTimeInitialized current=
3757: ERR 13:52:37.458400 RTSOLD: opvvlan:1->1 advvlan:4095->4095 vvlanState=1
3758: ERR 13:52:37.476188 RTSOLD: LINK -> LINK
3759: WRN 13:52:38.519527 JVM: Startup Module Loader|cip.sccp.SccpEnhancedAlarmInfo:propertyChanged - name=device.settings.fullyregistered value=false
3760: WRN 13:52:38.525502 JVM: Startup Module Loader|cip.sccp.SccpEnhancedAlarmInfo:propertyChanged - name=device.settings.fullyregistered value=true
3761: NOT 13:52:38.527024 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.callagent.messages.0 value=0
3762: NOT 13:52:38.561404 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.fullyregistered value=true
3763: NOT 13:52:38.562842 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - FULLY_REGISTERED - Resetting retry installer interval
3764: NOT 13:52:38.564194 JVM: Startup Module Loader|cip.midp.pushregistry.e:? - setAcceptConnections - ENABLED
3765: NOT 13:52:38.565648 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.fullyregistered value=true
3766: NOT 13:52:38.567035 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - FULLY_REGISTERED - Resetting retry installer interval
3767: NOT 13:52:38.568427 JVM: Startup Module Loader|cip.midp.pushregistry.e:? - setAcceptConnections - ENABLED
3768: NOT 13:52:38.569826 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.callagent.messages.0 value=0
3769: NOT 13:52:38.602196 CDP-D: cdpGetPortCfg SPANTOPC CFG:11
3770: NOT 13:52:38.614275 xxtpClient: request server 0 ---> 192.168.1.130
3771: NOT 13:52:38.615013 ESP: server 0 = 192.168.1.130
3772: NOT 13:52:38.642790 xxtpClient: request server 1 --->
3773: NOT 13:52:38.643744 ESP: server 1 =
3774: WRN 13:52:38.655086 JVM: Startup Module Loader|cip.mmgr.dt:? - [MediaMgrSM]: Unhandled Event, State = StateOnHook Event = EventServicesTxStop
3775: NOT 13:52:38.667240 xxtpClient: request server6 0 ---> ::
3776: NOT 13:52:38.679795 ESP: server 2 = ::
3777: NOT 13:52:38.704924 xxtpClient: request server6 0 ---> ::
3778: NOT 13:52:38.721645 xxtpClient: request server6 1 ---> ::
3779: NOT 13:52:38.722732 ESP: server 3 = ::
3780: NOT 13:52:38.744949 xxtpClient: request server6 1 ---> ::
3781: DBG 13:52:38.745612 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3782: DBG 13:52:38.758425 VPNU: State AppsUp --> AppsUp
3783: DBG 13:52:38.759027 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3784: DBG 13:52:38.759620 VPNU: State AppsUp --> AppsUp
3785: WRN 13:52:38.815882 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3786: NOT 13:52:38.820572 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3787: NOT 13:52:38.821207 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3788: NOT 13:52:38.821762 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3789: WRN 13:52:38.822386 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3790: WRN 13:52:38.828040 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3791: NOT 13:52:38.831527 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3792: NOT 13:52:38.832129 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3793: NOT 13:52:38.832683 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3794: WRN 13:52:38.833317 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3795: WRN 13:52:38.837964 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3796: NOT 13:52:38.841585 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3797: NOT 13:52:38.842185 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3798: NOT 13:52:38.842732 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3799: WRN 13:52:38.843367 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3800: WRN 13:52:38.848444 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3801: NOT 13:52:38.851983 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3802: NOT 13:52:38.852586 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3803: NOT 13:52:38.853140 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3804: WRN 13:52:38.853781 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3805: NOT 13:52:38.894919 xxtpClient: request server 0 ---> 192.168.1.130
3806: NOT 13:52:38.923631 xxtpClient: request server 1 --->
3807: NOT 13:52:38.946094 CDP-D: cdpGetPortCfg SPANTOPC CFG:11
3808: NOT 13:52:39.024888 xxtpClient: request server6 0 ---> ::
3809: NOT 13:52:39.044864 xxtpClient: request server6 1 ---> ::
3810: DBG 13:52:39.045522 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3811: DBG 13:52:39.058021 VPNU: State AppsUp --> AppsUp
3812: DBG 13:52:39.058664 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3813: DBG 13:52:39.059222 VPNU: State AppsUp --> AppsUp
-------------- next part --------------
|== Syslogd TNP== Wed Oct 30 13:51:57 2013
====================================================
3161: NOT 13:51:57.776217 IMG_AUTH: verify: verififed with current RELEASE 7942 (primary built-in) key
3162: NOT 13:51:57.776837 IMG_AUTH: verify: verify SUCCESS ! /dnld/SCCP42.9-3-1-1S.loads
3163: NOT 13:51:57.786219 TLoad: UT:Authentication signal onNotify
3164: NOT 13:51:57.786849 TLoad: XID08002848 Notify authenticated xfer state complete
3165: NOT 13:51:57.787393 TLoad: UT:yetToDo set to 0 (TSTATE_COMPLETE) (active=0)
3166: NOT 13:51:57.787888 TLoad: onNotifyThread - yetToDo is zero
3167: NOT 13:51:57.788380 TLoad: ^.idl_protocol.c-doSourceFileSerial
3168: INF 13:51:57.788886 TLoad: Get next entry from /usr/tmp/loadedfiles
3169: NOT 13:51:57.789456 TLoad: fetch complete notifying parent
3170: DBG 13:51:57.806150 image: phone hw-compat value 4
3171: NOT 13:51:57.806714 image: loadhwc=1 myhwc=4
3172: WRN 13:51:57.807221 image: Load rejected - hardware compatibility mismatch
3173: WRN 13:51:57.807716 image: Must use 9.3(1) SR or later release on this phone
3174: INF 13:51:57.808289 TLoad: Caught SIGTERM - Ready to exit set (sockcnt=0)
3175: INF 13:51:57.808813 TLoad: Masking all signals during shutdown
3176: INF 13:51:57.809294 TLoad: Signal 'Terminated'[23] received
3177: NOT 13:51:57.810510 TLoad: ^.idl_support.c-exitUnmount
3178: NOT 13:51:57.811071 TLoad: @@@HEXIT:DLOAD EXIT UNMOUNT
3179: NOT 13:51:58.211031 imgUI: Signal 'Terminated'[23] received
3180: NOT 13:51:58.224336 SYSMSG: pid 22 (/sbin/image) Normal Exit, status = 32
3181: INF 13:51:58.224367 runtime = 4.650 secs
3182: INF 13:51:58.224386 user cpu = 0.004256360 secs
3183: INF 13:51:58.224406 system cpu = 0.031795720 secs
3184: INF 13:51:58.224420 child user cpu = 0.000000000 secs
3185: INF 13:51:58.224434 child sys cpu = 0.000000000 secs
3186: INF 13:51:58.224456 sys interrupts = 0.019526300 secs for 1046 interrupts
3187: INF 13:51:58.224477 total cpu = 0.036052080 secs ( 0% utilization )
3188: NOT 13:51:58.226872 init: Extracting loadID's into environment...
3189: DBG 13:51:58.228741 lcd_close: return success!
3190: NOT 13:51:58.230111 init: Starting /bin/tftpClient
3191: NOT 13:51:58.232801 init: /bin/tftpClient started as pid=25
3192: NOT 13:51:58.233382 init: Starting /bin/ntp
3193: NOT 13:51:58.236026 init: /bin/ntp started as pid=3
3194: NOT 13:51:58.236602 init: Starting /ubin/vieo
3195: NOT 13:51:58.239209 init: /ubin/vieo started as pid=17
3196: NOT 13:51:58.239795 init: Starting /ubin/dsp
3197: NOT 13:51:58.242512 init: /ubin/dsp started as pid=8
3198: NOT 13:51:58.243093 init: Starting /bin/mkdir
3199: NOT 13:51:58.245697 init: /bin/mkdir started as pid=27
3200: NOT 13:51:58.490140 xxtpClient: pid = 25
3201: NOT 13:51:58.494186 NTP: [3] ver 3.0 (0.3)
3202: NOT 13:51:58.497981 vieo: main() initializing
3203: NOT 13:51:58.501070 vieo: msg queue MQ_MT_VIEO started
3204: ERR 13:51:58.508181 DSP: setrlimit set new soft limit 75
3205: NOT 13:51:58.509209 DSP: Version: DSPHOST = 8.3(14.15)PSYL - enable audio output at teh end
3206: NOT 13:51:58.509784 DSP: Version: KFactor = 0.95
3207: DBG 13:51:58.512313 dsp open flags 2042
3208: NOT 13:51:58.513443 DSP: The phone models is CP-7942G and the hw_version is 10
3209: WRN 13:51:58.518114 DSP: Table Substitution /ubin/dspCoefs, checksum is disabled, actual checksum = 6294
3210: WRN 13:51:58.523159 DSP: Table Substitution /ubin/dspParms, checksum is disabled, actual checksum = 38744
3211: WRN 13:51:58.525634 DSP: Table Substitution /ubin/dspVols, checksum is disabled, actual checksum = 43264
3212: WRN 13:51:58.527609 DSP: Table Substitution /ubin/dspCosts, checksum is disabled, actual checksum = 215
3213: NOT 13:51:58.580654 init: Starting /bin/mount
3214: NOT 13:51:58.583278 init: /bin/mount started as pid=10
3215: NOT 13:51:58.608857 init: Starting /ubin/zrun
3216: NOT 13:51:58.611583 init: /ubin/zrun started as pid=16
3217: NOT 13:51:58.612180 init: Starting /bin/sleep
3218: NOT 13:51:58.614749 init: /bin/sleep started as pid=23
3219: NOT 13:51:58.639789 zrun: Starting...
3220: NOT 13:51:58.641111 zrun: loadCompressedFileandExecute...
3221: INF 13:52:02.064289 zrun: execv(/tmp/sunvm.unzip/sunvm.cnu, argv)
3222: ERR 13:52:02.370696 JVM: sunvm pausing for umount
3223: NOT 13:52:03.302311 init: Starting /bin/umount
3224: NOT 13:52:03.304884 init: /bin/umount started as pid=29
3225: ERR 13:52:03.305844 init: /bin/umount powerup started
3226: NOT 13:52:03.400179 init: Starting /bin/rmdir
3227: NOT 13:52:03.402788 init: /bin/rmdir started as pid=26
3228: DBG 13:52:03.602419 cffs_gc(): runing gc 1
3229: ERR 13:52:04.370052 JVM: sunvm unpaused
3230: ERR 13:52:05.371334 JVM: SystemManager n/a module "Trace Manager"/traceManager(0) is PRELOADING
3231: ERR 13:52:05.379308 JVM: SystemManager n/a module "Trace Manager"/traceManager(0) is LOADING
3232: ERR 13:52:05.463058 JVM: SystemManager n/a module "Trace Manager"/traceManager(0) is LOADED
3233: ERR 13:52:05.472156 JVM: SystemManager n/a module "Trace Service"/traceService(1) is PRELOADING
3234: ERR 13:52:05.487263 JVM: SystemManager n/a module "Trace Service"/traceService(1) is LOADING
3235: ERR 13:52:05.488704 JVM: SystemManager n/a module "Trace Service"/traceService(1) is LOADED
3236: ERR 13:52:05.550946 JVM: SystemManager n/a module "Timer Manager"/timermgr(2) is PRELOADING
3237: ERR 13:52:05.673514 JVM: SystemManager n/a module "Property Manager"/propertymgr(3) is PRELOADING
3238: NOT 13:52:05.827183 JVM: jvmSrvSock_main Created...
3239: WRN 13:52:05.914488 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3240: NOT 13:52:05.919013 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3241: NOT 13:52:05.919618 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3242: NOT 13:52:05.920231 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3243: WRN 13:52:05.920914 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3244: WRN 13:52:05.939200 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3245: NOT 13:52:05.942719 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3246: NOT 13:52:05.943318 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3247: NOT 13:52:05.943873 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3248: WRN 13:52:05.944523 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3249: WRN 13:52:05.956504 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3250: NOT 13:52:05.959936 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3251: NOT 13:52:05.960609 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3252: NOT 13:52:05.961158 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3253: WRN 13:52:05.961792 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3254: WRN 13:52:05.973398 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3255: NOT 13:52:05.976861 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3256: NOT 13:52:05.977469 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3257: NOT 13:52:05.978018 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3258: WRN 13:52:05.978649 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3259: NOT 13:52:06.041232 xxtpClient: request server 0 ---> 192.168.1.130
3260: NOT 13:52:06.227185 xxtpClient: request server 1 --->
3261: NOT 13:52:06.389811 CDP-D: cdpGetPortCfg SPANTOPC CFG:11
3262: NOT 13:52:06.771531 xxtpClient: request server6 0 ---> ::
3263: NOT 13:52:06.825289 xxtpClient: request server6 1 ---> ::
3264: DBG 13:52:06.825946 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3265: DBG 13:52:06.939287 VPNU: State AppsUp --> AppsUp
3266: DBG 13:52:06.939912 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3267: DBG 13:52:06.940526 VPNU: State AppsUp --> AppsUp
3268: DBG 13:52:07.310624 cffs_journal_commit() change journal base 0x1c0000
3269: DBG 13:52:07.311414 cffs_journal_create() sec 66
3270: DBG 13:52:07.312760 cffs_journal_commit() new journal base 0x840000/hdr:4/data:5
3271: NOT 13:52:09.514406 SECD: setSecMode: sec mode set to NONE (was NONE)
3272: WRN 13:52:09.712412 JVM: Setting LCD Contrast/View Angle to 16 before R2D init
3273: WRN 13:52:09.713694 JVM: Setting LCD Contrast/View Angle to 16 before R2D init
3274: ERR 13:52:09.925951 JVM: SystemManager n/a module "Reset Service"/reset(4) is PRELOADING
3275: ERR 13:52:09.947422 JVM: SystemManager n/a module "Localization Manager"/localizationmgr(5) is PRELOADING
3276: ERR 13:52:10.109072 JVM: SystemManager n/a module "Timer Manager"/timermgr(2) is LOADING
3277: ERR 13:52:10.111753 JVM: SystemManager n/a module "Timer Manager"/timermgr(2) is LOADED
3278: ERR 13:52:10.113276 JVM: SystemManager n/a module "Property Manager"/propertymgr(3) is LOADING
3279: NOT 13:52:10.135565 JVM: Startup Module Loader|cip.midp.rms.h:? - loaded entry: _cip_properties.dbk=/flash0/RMS/appdb/_cip_properties.dbk
3280: NOT 13:52:10.136989 JVM: Startup Module Loader|cip.midp.rms.h:? - loaded entry: Cisco_MIDlet_suites.utf=/flash0/RMS/appdb/Cisco_MIDlet_suites.utf
3281: NOT 13:52:10.138362 JVM: Startup Module Loader|cip.midp.rms.h:? - loaded entry: _cip_properties.db=/flash0/RMS/appdb/_cip_properties.db
3282: NOT 13:52:10.139769 JVM: Startup Module Loader|cip.midp.rms.h:? - loaded entry: _cip_properties.dbk=/flash0/RMS/appdb/_cip_properties.dbk
3283: NOT 13:52:10.141281 JVM: Startup Module Loader|cip.midp.rms.h:? - loaded entry: Cisco_MIDlet_suites.utf=/flash0/RMS/appdb/Cisco_MIDlet_suites.utf
3284: NOT 13:52:10.142652 JVM: Startup Module Loader|cip.midp.rms.h:? - loaded entry: _cip_properties.db=/flash0/RMS/appdb/_cip_properties.db
3285: WRN 13:52:10.144041 JVM: Startup Module Loader|RecordStoreImpl:midp.rms.IllegalnextOffset - Illegal nextOffset value in record id#-1 - isn't within dbDataStart to dbDataEnd, record.id=-1
3286: WRN 13:52:10.145455 JVM: Startup Module Loader|RecordStoreImpl:midp.rms.RecordStoreFileCorrupt - RecordStore file is corrupt, db.file=_cip_properties.db
3287: WRN 13:52:10.146924 JVM: Startup Module Loader|RecordStoreImpl:midp.rms.cannotLoadStoreFile - Couldn't load RecordStore file ... attempting restore from backup, db.file=_cip_properties.db, backup.file=_cip_properties.dbk
3288: NOT 13:52:10.317103 JVM: jvmSrvSock_main Created...
3289: ERR 13:52:11.964277 JVM: SystemManager n/a module "Property Manager"/propertymgr(3) is LOADED
3290: ERR 13:52:11.965804 JVM: SystemManager n/a module "Reset Service"/reset(4) is LOADING
3291: ERR 13:52:11.967233 JVM: SystemManager n/a module "Reset Service"/reset(4) is LOADED
3292: ERR 13:52:11.968703 JVM: SystemManager n/a module "Localization Manager"/localizationmgr(5) is LOADING
3293: ERR 13:52:11.973827 JVM: SystemManager n/a module "Localization Manager"/localizationmgr(5) is LOADED
3294: ERR 13:52:12.004507 JVM: SystemManager n/a module "Input Manager"/inputmgr(6) is PRELOADING
3295: DBG 13:52:12.136379 cffs_gc(): runing gc 2
3296: ERR 13:52:12.151041 JVM: SystemManager n/a module "Display"/display(7) is PRELOADING
3297: DBG 13:52:12.153518 lcd_open: return success!
3298: DBG 13:52:12.676843 cffs_gc(): runing gc 1
3299: ERR 13:52:16.615865 JVM: SystemManager n/a module "Application Manager"/appmgr(8) is PRELOADING
3300: DBG 13:52:16.646535 cffs_gc(): runing gc 2
3301: ERR 13:52:16.810099 JVM: SystemManager n/a module "Call Agent"/callagent(9) is PRELOADING
3302: ERR 13:52:16.961419 JVM: SystemManager n/a module "Media Manager"/mediamgr(10) is PRELOADING
3303: NOT 13:52:17.493237 JVM: libMT:start SignOn()
3304: ERR 13:52:17.495521 JVM: -DSP | open /dev/tchscrn Failed (I/O error)
3305: NOT 13:52:17.497187 JVM: *** VIEO should already be running. NO? Start "/ubin/vieo &" now ***
3306: NOT 13:52:17.498341 JVM: libMT: send _MTStart...
3307: NOT 13:52:17.505567 JVM: LibMT (vieoProcess.c): registered VIEO callbacks
3308: DBG 13:52:17.507305 cffs_gc(): runing gc 1
3309: DBG 13:52:18.500684 audev: audioHwInit,,,
3310: DBG 13:52:18.501130 audev: audioHwInit.
3311: NOT 13:52:18.574623 DSP: _dspdvrStart ok, DSP bootup completed successfully
3312: NOT 13:52:18.580533 DSP: DSP Tone/Ring DSP initialization completed successfully
3313: NOT 13:52:18.582034 DSP: dspdvrinitEnableAudioOutput enabled = 1
3314: NOT 13:52:18.582638 DSP: DSP INIT-*** DSP_STATE_READY***
3315: NOT 13:52:18.585706 JVM: LibMT: MediaTerminationStart OK!
3316: ERR 13:52:18.858102 JVM: SystemManager n/a module "Call UI"/callui(11) is PRELOADING
3317: NOT 13:52:18.906811 JVM: Startup Module Loader|ComponentController:? - Initializing MVC comp module's trace mechanism
3318: ERR 13:52:18.920008 JVM: SystemManager n/a module "TFTP Client"/tftp(12) is PRELOADING
3319: ERR 13:52:19.062350 JVM: SystemManager n/a module "Security"/security(13) is PRELOADING
3320: NOT 13:52:19.101229 JVM: SECGetAuthInfo: Unrecognized state (0)
3321: ERR 13:52:19.162486 JVM: SystemManager n/a module "Config Manager"/configmgr(14) is PRELOADING
3322: NOT 13:52:19.257688 JVM: --> HeadSetHandSet Trace: Enter MediaTerminationSetHandsetHeadsetMonitor
3323: NOT 13:52:19.259300 DSP: <-- HeadSetHandSet Trace successful, mtSetHandsetHeadsetMonitor - enabled/disabled = 1
3324: NOT 13:52:19.260325 JVM: <-- HeadSetHandSet Trace: MediaMerminationSetHandsetHeadsetMonitor successfully
3325: NOT 13:52:19.260915 JVM: --> HeadSetRecording Trace: Enter MediaTerminationSetHeadsetRecording
3326: NOT 13:52:19.262459 DSP: <-- HeadSetHandSet Trace successful, mtSetHeadsetRecording - enabled/disabled = 0
3327: NOT 13:52:19.263437 JVM: <-- HeadSetRecording Trace: MediaMerminationSetHeadsetRecording successfully
3328: ERR 13:52:19.346218 JVM: SystemManager n/a module "HTTP"/http(15) is PRELOADING
3329: ERR 13:52:19.420272 JVM: Startup Module Loader|cip.mmgr.MediaManager:? -
MediaManager propertyChanged called device.settings.config.vendorconfig.handsetheadsetmonitor
3330: WRN 13:52:19.422321 JVM: Startup Module Loader|cip.cfg.ConfigManager:onPreload - LAST_RESET_CAUSE is 519
3331: WRN 13:52:19.423755 JVM: Startup Module Loader|cip.cfg.ConfigManager:onPreload - LAST_RESET_CAUSE is reset to Initialized from 519
3332: ERR 13:52:19.726801 JVM: Setting http and https to enabled
3333: ERR 13:52:19.780236 JVM: Calling enable listening for incoming http connections
3334: ERR 13:52:19.780793 JVM: Calling start listen to listen for incoming https connections
3335: ERR 13:52:19.782808 SECD: EROR:handleTlsListenReq: Duplicate TLS Srvr listen request passed for port : 443
3336: ERR 13:52:19.788034 JVM: Created a SSL listen sock, 23
3337: ERR 13:52:19.788669 JVM: Calling enable listening for incoming http connections
3338: ERR 13:52:19.789191 JVM: Calling enable listening for incoming http connections
3339: ERR 13:52:19.789704 JVM: Calling enable listening for incoming http connections
3340: ERR 13:52:19.790276 JVM: Calling enable listening for incoming http connections
3341: ERR 13:52:19.790789 JVM: Calling enable listening for incoming http connections
3342: ERR 13:52:19.791301 JVM: Calling enable listening for incoming http connections
3343: ERR 13:52:19.854228 JVM: SystemManager n/a module "HTTP Server/Microservlet Container"/microservlets(16)
3344: ERR 13:52:19.854866 JVM: is PRELOADING
3345: ERR 13:52:19.924478 JVM: SystemManager n/a module "Push Service"/push(17) is PRELOADING
3346: ERR 13:52:19.958525 JVM: SystemManager n/a module "Settings"/settings(18) is PRELOADING
3347: WRN 13:52:19.964669 SECD: WARN:getCertInfo: ** phone has no LSC
3348: ERR 13:52:20.103298 JVM: SystemManager n/a module "Services"/services(19) is PRELOADING
3349: ERR 13:52:20.117308 JVM: SystemManager n/a module "Directories"/directories(20) is PRELOADING
3350: ERR 13:52:20.194127 JVM: SystemManager n/a module "Messages"/messages(21) is PRELOADING
3351: ERR 13:52:20.204605 JVM: SystemManager n/a module "Go4"/go4(22) is PRELOADING
3352: ERR 13:52:20.230709 JVM: SystemManager n/a module "Help"/help(23) is PRELOADING
3353: ERR 13:52:20.254235 JVM: SystemManager n/a module "AppStatus"/appstatus(24) is PRELOADING
3354: ERR 13:52:20.264662 JVM: SystemManager n/a module "Vieo"/vieo(25) is PRELOADING
3355: ERR 13:52:20.285473 JVM: SystemManager n/a module "Sidecar"/sidecar(26) is PRELOADING
3356: ERR 13:52:20.445743 JVM: SystemManager n/a module "Starts Automation Feature"/automation(27) is PRELOADIN
3357: ERR 13:52:20.446376 JVM: G
3358: ERR 13:52:20.476121 JVM: SystemManager n/a module "SubscriptionManager"/subscriptionman(28) is PRELOADING
3359: ERR 13:52:20.512973 JVM: SystemManager n/a module "NotificationModule"/notification(29) is PRELOADING
3360: ERR 13:52:20.590916 JVM: SystemManager n/a module "InstallerModule"/installer(30) is PRELOADING
3361: ERR 13:52:20.705494 JVM: SystemManager n/a module "Vpn"/vpn(31) is PRELOADING
3362: ERR 13:52:20.763950 JVM: SystemManager n/a module "SCCP Call Control"/sccpccapi(32) is PRELOADING
3363: ERR 13:52:20.772529 JVM: SystemManager n/a module "Input Manager"/inputmgr(6) is LOADING
3364: ERR 13:52:20.776302 JVM: SystemManager n/a module "Input Manager"/inputmgr(6) is LOADED
3365: ERR 13:52:20.777773 JVM: SystemManager n/a module "Display"/display(7) is LOADING
3366: ERR 13:52:20.909460 JVM: SystemManager n/a module "Display"/display(7) is LOADED
3367: ERR 13:52:20.911626 JVM: SystemManager n/a module "Application Manager"/appmgr(8) is LOADING
3368: ERR 13:52:21.226638 JVM: SystemManager n/a module "Application Manager"/appmgr(8) is LOADED
3369: ERR 13:52:21.228124 JVM: SystemManager n/a module "Call Agent"/callagent(9) is LOADING
3370: ERR 13:52:21.282675 JVM: SystemManager n/a module "Call Agent"/callagent(9) is LOADED
3371: ERR 13:52:21.284141 JVM: SystemManager n/a module "Media Manager"/mediamgr(10) is LOADING
3372: WRN 13:52:21.675534 JVM: Startup Module Loader|DisplayTask:? - The EW client do not connected to the switch
3373: ERR 13:52:22.019215 JVM:
setConfig: Config type 5, Enable : 0
3374: NOT 13:52:22.020124 DSP: configurationNumber 1, enabled 0
3375: NOT 13:52:22.026715 DSP: mtSetDeviceBandwidth: Wideband Headset - deviceWideband=0
3376: NOT 13:52:22.029243 DSP: mtSetDeviceBandwidth: Wideband Handset - deviceWideband=2
3377: NOT 13:52:22.031798 DSP: mtSetG722Codec: g722enable=0
3378: NOT 13:52:22.076430 JVM: --> HeadSetHandSet Trace: Enter MediaTerminationSetHandsetHeadsetMonitor
3379: NOT 13:52:22.078057 DSP: <-- HeadSetHandSet Trace successful, mtSetHandsetHeadsetMonitor - enabled/disabled = 1
3380: NOT 13:52:22.079009 JVM: <-- HeadSetHandSet Trace: MediaMerminationSetHandsetHeadsetMonitor successfully
3381: NOT 13:52:22.079575 JVM: --> HeadSetRecording Trace: Enter MediaTerminationSetHeadsetRecording
3382: NOT 13:52:22.081242 DSP: <-- HeadSetHandSet Trace successful, mtSetHeadsetRecording - enabled/disabled = 0
3383: NOT 13:52:22.082174 JVM: <-- HeadSetRecording Trace: MediaMerminationSetHeadsetRecording successfully
3384: ERR 13:52:22.083914 JVM:
setConfig: Config type 4, Enable : 0
3385: NOT 13:52:22.084753 DSP: configurationNumber 0, enabled 0
3386: ERR 13:52:22.086772 JVM:
setConfig: Config type 6, Enable : 0
3387: ERR 13:52:22.087497 JVM: Config type 6, Enable : 0
3388: NOT 13:52:22.088166 DSP: configurationNumber 2, enabled 0
3389: ERR 13:52:22.115953 JVM: SystemManager n/a module "Media Manager"/mediamgr(10) is LOADED
3390: ERR 13:52:22.117563 JVM: SystemManager n/a module "Call UI"/callui(11) is LOADING
3391: ERR 13:52:22.342253 JVM: SystemManager n/a module "Call UI"/callui(11) is LOADED
3392: ERR 13:52:22.343851 JVM: SystemManager n/a module "TFTP Client"/tftp(12) is LOADING
3393: ERR 13:52:22.345780 JVM: SystemManager n/a module "TFTP Client"/tftp(12) is LOADED
3394: ERR 13:52:22.347391 JVM: SystemManager n/a module "Security"/security(13) is LOADING
3395: ERR 13:52:22.355808 JVM: SystemManager n/a module "Security"/security(13) is LOADED
3396: ERR 13:52:22.357370 JVM: SystemManager n/a module "Config Manager"/configmgr(14) is LOADING
3397: ERR 13:52:22.466973 JVM: SystemManager n/a module "Config Manager"/configmgr(14) is LOADED
3398: ERR 13:52:22.468525 JVM: SystemManager n/a module "HTTP"/http(15) is LOADING
3399: ERR 13:52:22.483175 JVM: SystemManager n/a module "HTTP"/http(15) is LOADED
3400: ERR 13:52:22.484830 JVM: SystemManager n/a module "HTTP Server/Microservlet Container"/microservlets(16)
3401: ERR 13:52:22.485451 JVM: is LOADING
3402: ERR 13:52:22.486911 JVM: SystemManager n/a module "HTTP Server/Microservlet Container"/microservlets(16)
3403: ERR 13:52:22.487530 JVM: is LOADED
3404: ERR 13:52:22.488983 JVM: SystemManager n/a module "Push Service"/push(17) is LOADING
-------------- next part --------------
|== Syslogd TNP== Wed Oct 30 13:52:22 2013
====================================================
3405: ERR 13:52:22.492925 JVM: SystemManager n/a module "Push Service"/push(17) is LOADED
3406: ERR 13:52:22.494526 JVM: SystemManager n/a module "Settings"/settings(18) is LOADING
3407: ERR 13:52:22.865404 JVM: SystemManager n/a module "Settings"/settings(18) is LOADED
3408: ERR 13:52:22.866989 JVM: SystemManager n/a module "Services"/services(19) is LOADING
3409: ERR 13:52:24.084750 JVM: Startup Module Loader|cip.mmgr.MediaManager:? - Added listener for 80-bit-srtcp
3410: ERR 13:52:24.086138 JVM: Startup Module Loader|cip.mmgr.MediaManager:? -
MediaManager propertyChanged called device.settings.config.vendorconfig.speakerenable
3411: ERR 13:52:24.087550 JVM: Startup Module Loader|cip.mmgr.MediaManager:? -
MediaManager propertyChanged called device.settings.config.vendorconfig.headsetenable
3412: ERR 13:52:24.088994 JVM: Startup Module Loader|cip.mmgr.MediaManager:? -
MediaManager propertyChanged called device.settings.config.vendorconfig.rtpcontrolprotocol
3413: ERR 13:52:24.091111 JVM: Startup Module Loader|cip.mmgr.MediaManager:? - RTCP false
3414: ERR 13:52:24.092485 JVM: Startup Module Loader|cip.mmgr.MediaManager:? -
MediaManager propertyChanged called device.settings.config.vendorconfig.eightybitsrtcp
3415: ERR 13:52:24.093926 JVM: Startup Module Loader|cip.mmgr.MediaManager:? -
80-Bit-Srtcp value changed to false
3416: NOT 13:52:24.396894 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - --->ConfigManager PropertyChanged: device.settings.network.swportstatus
3417: NOT 13:52:24.398303 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - ConfigManager PropertyChanged: device.settings.network.swportstatus
3418: NOT 13:52:24.399661 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - ConfigManager PropertyChanged: device.settings.network.swportstatus linkState=Link
3419: NOT 13:52:24.401752 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - ConfigManager PropertyChanged: device.settings.network.swportstatus calling linkState.isRPCInvalidConfiguration()
3420: NOT 13:52:24.403797 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - <---ConfigManager PropertyChanged: device.settings.network.swportstatus
3421: NOT 13:52:24.405183 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - --->ConfigManager PropertyChanged: device.kernel.messages
3422: NOT 13:52:24.406592 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - <---ConfigManager PropertyChanged: device.kernel.messages
3423: ERR 13:52:24.407978 JVM: Startup Module Loader|cip.http.HttpTask:? - auth url is https://192.168.1.130:8443/ccmcip/authenticate.jsp
3424: ERR 13:52:24.409306 JVM: Startup Module Loader|HttpTask:? - updateServerMode
3425: ERR 13:52:24.410693 JVM: Startup Module Loader|HttpTask:? - Web Protocol is http and https
3426: NOT 13:52:24.434960 JVM: Startup Module Loader|cip.midp.midlet.MIDletManager:? - Initializing MIDletManager ...
3427: NOT 13:52:24.436347 JVM: Startup Module Loader|cip.midp.midlet.MIDletManager:? - isAutomationJarUploaded : false
3428: NOT 13:52:24.437744 JVM: Startup Module Loader|cip.midp.midlet.MIDletManager:? - SecurityManager is SET
3429: NOT 13:52:24.439125 JVM: Startup Module Loader|cip.midp.midlet.MIDletManager:? - Initializing PushRegistry ...
3430: ERR 13:52:24.446609 JVM: Setting http and https to enabled
3431: ERR 13:52:25.281576 JVM: SystemManager n/a module "Services"/services(19) is LOADED
3432: ERR 13:52:25.283148 JVM: SystemManager n/a module "Directories"/directories(20) is LOADING
3433: ERR 13:52:25.383560 JVM: SystemManager n/a module "Directories"/directories(20) is LOADED
3434: ERR 13:52:25.385110 JVM: SystemManager n/a module "Messages"/messages(21) is LOADING
3435: ERR 13:52:25.467817 JVM: SystemManager n/a module "Messages"/messages(21) is LOADED
3436: ERR 13:52:25.469370 JVM: SystemManager n/a module "Go4"/go4(22) is LOADING
3437: ERR 13:52:25.493966 JVM: SystemManager n/a module "Go4"/go4(22) is LOADED
3438: ERR 13:52:25.495498 JVM: SystemManager n/a module "Help"/help(23) is LOADING
3439: ERR 13:52:25.565142 JVM: SystemManager n/a module "Help"/help(23) is LOADED
3440: ERR 13:52:25.566722 JVM: SystemManager n/a module "AppStatus"/appstatus(24) is LOADING
3441: ERR 13:52:25.645174 JVM: SystemManager n/a module "AppStatus"/appstatus(24) is LOADED
3442: ERR 13:52:25.646769 JVM: SystemManager n/a module "Vieo"/vieo(25) is LOADING
3443: ERR 13:52:25.712499 JVM: SystemManager n/a module "Vieo"/vieo(25) is LOADED
3444: ERR 13:52:25.714060 JVM: SystemManager n/a module "Sidecar"/sidecar(26) is LOADING
3445: ERR 13:52:25.741883 JVM: SystemManager n/a module "Sidecar"/sidecar(26) is LOADED
3446: ERR 13:52:25.743461 JVM: SystemManager n/a module "Starts Automation Feature"/automation(27) is LOADING
3447: ERR 13:52:25.752166 JVM: SystemManager n/a module "Starts Automation Feature"/automation(27) is LOADED
3448: ERR 13:52:25.753812 JVM: SystemManager n/a module "SubscriptionManager"/subscriptionman(28) is LOADING
3449: ERR 13:52:25.773519 JVM: SystemManager n/a module "SubscriptionManager"/subscriptionman(28) is LOADED
3450: ERR 13:52:25.775113 JVM: SystemManager n/a module "NotificationModule"/notification(29) is LOADING
3451: ERR 13:52:25.777032 JVM: SystemManager n/a module "NotificationModule"/notification(29) is LOADED
3452: ERR 13:52:25.778598 JVM: SystemManager n/a module "InstallerModule"/installer(30) is LOADING
3453: NOT 13:52:25.924986 JVM: Startup Module Loader|cip.midp.pushregistry.e:? - Starting MIDP PushRegistry ...
3454: NOT 13:52:25.926397 JVM: Startup Module Loader|cip.midp.pushregistry.e:? - setAcceptConnections - DISABLED
3455: NOT 13:52:25.927792 JVM: Startup Module Loader|cip.midp.pushregistry.e:? - MIDP PushRegistry started successfully.
3456: NOT 13:52:25.929181 JVM: Startup Module Loader|cip.midp.midlet.MIDletManager:? - Initializing MIDP MemberFilter ...
3457: NOT 13:52:25.930683 JVM: Startup Module Loader|cip.midp.midlet.MIDletManager:? - MIDletManager initialized.
3458: ERR 13:52:26.204919 JVM: SystemManager n/a module "InstallerModule"/installer(30) is LOADED
3459: ERR 13:52:26.206461 JVM: SystemManager n/a module "Vpn"/vpn(31) is LOADING
3460: DBG 13:52:26.273854 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3461: DBG 13:52:26.277143 VPNU: No VPN database change
3462: NOT 13:52:26.299810 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.security.vpn.mode value=false
3463: ERR 13:52:26.314870 JVM: SystemManager n/a module "Vpn"/vpn(31) is LOADED
3464: ERR 13:52:26.316518 JVM: SystemManager n/a module "SCCP Call Control"/sccpccapi(32) is LOADING
3465: ERR 13:52:26.445698 JVM: SystemManager n/a module "SCCP Call Control"/sccpccapi(32) is LOADED
3466: DBG 13:52:26.703056 VPNU: State AppsUp --> AppsUp
3467: DBG 13:52:26.703664 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3468: DBG 13:52:26.704217 VPNU: State AppsUp --> AppsUp
3469: ERR 13:52:26.730632 JVM: Startup Module Loader|cip.http.HttpTask:? - auth url is https://192.168.1.130:8443/ccmcip/authenticate.jsp
3470: NOT 13:52:26.732089 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - --->ConfigManager PropertyChanged: device.system.systemstate
3471: WRN 13:52:26.741350 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3472: NOT 13:52:26.745881 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3473: NOT 13:52:26.746483 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3474: NOT 13:52:26.747069 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3475: WRN 13:52:26.747706 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3476: WRN 13:52:26.753345 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3477: NOT 13:52:26.756826 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3478: NOT 13:52:26.757433 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3479: NOT 13:52:26.757983 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3480: WRN 13:52:26.758660 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3481: WRN 13:52:26.763405 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3482: NOT 13:52:26.766872 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3483: NOT 13:52:26.767478 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3484: NOT 13:52:26.768023 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3485: WRN 13:52:26.768703 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3486: WRN 13:52:26.773886 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3487: NOT 13:52:26.777344 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3488: NOT 13:52:26.777943 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3489: NOT 13:52:26.778482 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3490: WRN 13:52:26.779113 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3491: ERR 13:52:26.783775 JVM: Startup Module Loader|cip.cfg.ConfigManager:tryConfigFileDownload - File_FIPS_STATUS exists?false
3492: NOT 13:52:26.824256 xxtpClient: request server 0 ---> 192.168.1.130
3493: NOT 13:52:26.853330 xxtpClient: request server 1 --->
3494: NOT 13:52:26.875903 CDP-D: cdpGetPortCfg SPANTOPC CFG:11
3495: NOT 13:52:26.964282 xxtpClient: request server6 0 ---> ::
3496: NOT 13:52:26.984266 xxtpClient: request server6 1 ---> ::
3497: DBG 13:52:26.984965 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3498: DBG 13:52:26.998170 VPNU: State AppsUp --> AppsUp
3499: DBG 13:52:26.998770 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3500: DBG 13:52:26.999331 VPNU: State AppsUp --> AppsUp
3501: DBG 13:52:32.733292 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3502: NOT 13:52:32.747217 SECD: loadTvsSrvrCfg: Not in EMCC mode.Loading the flash file :/flash0/sec/misc/tvs.conf
3503: DBG 13:52:32.748069 VPNU: State AppsUp --> AppsUp
3504: DBG 13:52:32.748662 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3505: DBG 13:52:32.749263 VPNU: State AppsUp --> AppsUp
3506: NOT 13:52:32.759953 JVM: emccMode=0,localOverride=0, tftpAddr1=, tftpAddr2=,tftpAddr3=,tftpAddr4=
3507: NOT 13:52:32.763531 xxtpClient: tftp request rcv'd from /usr/tmp/tftp, emccMode =0, emccLocalOverride=0, tempTftp1= , tempTftp2 = , tempTftp3 = , tempTftp4 =
3508: NOT 13:52:32.764754 JVM: setTempTftpAddress, emcc_mode=0,retEmccMode=0,LocalOverride=0,retLocalOverride=0, status=1
3509: NOT 13:52:32.776321 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - Starting CTL/config file updating
3510: NOT 13:52:32.777713 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - <---ConfigManager PropertyChanged: device.system.systemstate
3511: NOT 13:52:32.841148 SECD: updateCTL: starting Trust list update
3512: NOT 13:52:32.843738 SECD: tlRequestFile: Socket 9 connected to /usr/tmp/xxtpClientSock
3513: NOT 13:52:32.844541 SECD: tlRequestFile: Request CTLSEP7C95F3C93DD3.tlv
3514: NOT 13:52:32.850702 xxtpClient: xxtp request rcv'd from /usr/tmp/ctlSock, srcFile = CTLSEP7C95F3C93DD3.tlv, dstFile = /usr/tmp/CTLFile.tlv
3515: NOT 13:52:32.871318 xxtpClient: auth server - tftpList[0] = ::ffff:192.168.1.130
3516: NOT 13:52:32.871884 xxtpClient: look up server - 0
3517: NOT 13:52:32.873591 SECD: lookupCTL: TFTP SRVR secure
3518: NOT 13:52:32.876049 xxtpClient: secVal = 0x9
3519: NOT 13:52:32.876686 xxtpClient: ::ffff:192.168.1.130 is a secure server
3520: NOT 13:52:32.877189 xxtpClient: retval = SRVR_SECURE
3521: NOT 13:52:32.877708 xxtpClient: Non secure file requested
3522: ERR 13:52:32.906284 HTTPCL: http get [HTTP/1.1 404 Not Found
Conten]
3523: NOT 13:52:32.914995 SYSMSG: pid 22 (/sbin/httpcl) Normal Exit, status = 101
3524: INF 13:52:32.915035 runtime = 0.030 secs
3525: INF 13:52:32.915054 user cpu = 0.001016600 secs
3526: INF 13:52:32.915073 system cpu = 0.015630700 secs
3527: INF 13:52:32.915087 child user cpu = 0.000000000 secs
3528: INF 13:52:32.915101 child sys cpu = 0.000000000 secs
3529: INF 13:52:32.915121 sys interrupts = 0.000936280 secs for 8 interrupts
3530: INF 13:52:32.915142 total cpu = 0.016647300 secs ( 33% utilization )
3531: WRN 13:52:32.912878 xxtpClient: HTTP failed with code 101
3532: NOT 13:52:32.914051 SECD: tlRequestFile: tftp Status 2 rcv'd
3533: NOT 13:52:32.914850 SECD: updateCTL: finished CTLFile update
3534: NOT 13:52:32.917297 SECD: tlRequestFile: Socket 9 connected to /usr/tmp/xxtpClientSock
3535: NOT 13:52:32.918086 SECD: tlRequestFile: Request ITLSEP7C95F3C93DD3.tlv
3536: NOT 13:52:32.918826 xxtpClient: xxtp request rcv'd from /usr/tmp/ctlSock, srcFile = ITLSEP7C95F3C93DD3.tlv, dstFile = /usr/tmp/ITLFile.tlv
3537: NOT 13:52:32.931627 xxtpClient: auth server - tftpList[0] = ::ffff:192.168.1.130
3538: NOT 13:52:32.932193 xxtpClient: look up server - 0
3539: NOT 13:52:32.933893 SECD: lookupCTL: TFTP SRVR secure
3540: NOT 13:52:32.936257 xxtpClient: secVal = 0x9
3541: NOT 13:52:32.936904 xxtpClient: ::ffff:192.168.1.130 is a secure server
3542: NOT 13:52:32.937457 xxtpClient: retval = SRVR_SECURE
3543: NOT 13:52:32.937935 xxtpClient: Non secure file requested
3544: NOT 13:52:32.964779 HTTPCL: hdr->HTTP/1.1 200 OK
3545: NOT 13:52:32.965344 HTTPCL: hdr->Content-length: 4168
3546: NOT 13:52:32.966095 HTTPCL: hdr->Cache-Control: no-store
3547: NOT 13:52:32.966617 HTTPCL: hdr->Content-type: */*
3548: INF 13:52:32.967392 HTTPCL: initial burst had more than just headers
3549: NOT 13:52:32.989436 SYSMSG: pid 13 (/sbin/httpcl) Normal Exit, status = 100
3550: INF 13:52:32.989475 runtime = 0.050 secs
3551: INF 13:52:32.989495 user cpu = 0.001583950 secs
3552: INF 13:52:32.989513 system cpu = 0.013646270 secs
3553: INF 13:52:32.989527 child user cpu = 0.000000000 secs
3554: INF 13:52:32.989541 child sys cpu = 0.000000000 secs
3555: INF 13:52:32.989562 sys interrupts = 0.003592860 secs for 37 interrupts
3556: INF 13:52:32.989583 total cpu = 0.015230220 secs ( 20% utilization )
3557: NOT 13:52:32.993815 SECD: tlRequestFile: tftp Status 100 rcv'd
3558: NOT 13:52:32.998670 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3559: NOT 13:52:32.999282 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3560: NOT 13:52:33.000492 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3561: NOT 13:52:33.001433 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3562: NOT 13:52:33.002029 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3563: NOT 13:52:33.002880 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3564: NOT 13:52:33.021924 SECD: validate_file_envelope: File sign verify SUCCESS; header length <352>
3565: NOT 13:52:33.022832 SECD: TL_ValidateSignedTL: new TL matches old, not updating
3566: NOT 13:52:33.023729 SECD: updateCTL: finished ITLFile update
3567: NOT 13:52:33.134482 JVM: --> Properties JNI: SignalApp() handling SIGIPCFG. msgCode=256 value=0
3568: NOT 13:52:33.150179 JVM: <-- Properties JNI: SignalApp()
3569: ERR 13:52:33.151828 JVM: Startup Module Loader|cip.sec.t:---------updateCtl,result=0 - -------------invoke proxy
3570: ERR 13:52:33.162705 JVM: Startup Module Loader|cip.sec.t:---------updateCtl,result=0 - -------------invoke dispatcher
3571: NOT 13:52:33.164363 JVM: Startup Module Loader|cip.cfg.k:? - ConfigManager updateCTL() rc=0 retryCount=0
3572: WRN 13:52:33.188248 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3573: NOT 13:52:33.192601 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3574: NOT 13:52:33.193503 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3575: NOT 13:52:33.194118 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3576: WRN 13:52:33.194931 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3577: NOT 13:52:33.240164 JVM: Startup Module Loader|cip.cfg.t:? - Requesting CONFIG file from TFTP Service(1)
3578: ERR 13:52:33.244257 JVM: tftpClient SEP7C95F3C93DD3.cnf.xml /usr/ram/SEP7C95F3C93DD3.cnf.xml 550001 1
3579: NOT 13:52:33.247909 xxtpClient: xxtp request rcv'd from /usr/tmp/tftp, srcFile = SEP7C95F3C93DD3.cnf.xml, dstFile = /usr/ram/SEP7C95F3C93DD3.cnf.xml max size = 550001
3580: NOT 13:52:33.260986 xxtpClient: auth server - tftpList[0] = ::ffff:192.168.1.130
3581: NOT 13:52:33.261545 xxtpClient: look up server - 0
3582: NOT 13:52:33.263828 SECD: lookupCTL: TFTP SRVR secure
3583: NOT 13:52:33.266590 xxtpClient: secVal = 0x9
3584: NOT 13:52:33.267455 xxtpClient: ::ffff:192.168.1.130 is a secure server
3585: NOT 13:52:33.267994 xxtpClient: retval = SRVR_SECURE
3586: NOT 13:52:33.268524 xxtpClient: Secure file requested
3587: NOT 13:52:33.269348 xxtpClient: authenticated file approved - add .sgn -- SEP7C95F3C93DD3.cnf.xml.sgn
3588: WRN 13:52:33.286860 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3589: NOT 13:52:33.290905 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3590: NOT 13:52:33.291706 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3591: NOT 13:52:33.292290 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3592: WRN 13:52:33.293371 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3593: WRN 13:52:33.299429 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3594: NOT 13:52:33.303745 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3595: NOT 13:52:33.304542 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3596: NOT 13:52:33.305094 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3597: WRN 13:52:33.305901 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3598: WRN 13:52:33.312267 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3599: NOT 13:52:33.316314 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3600: NOT 13:52:33.317099 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3601: NOT 13:52:33.317657 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3602: WRN 13:52:33.318473 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3603: WRN 13:52:33.324150 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
3604: NOT 13:52:33.328245 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3605: NOT 13:52:33.328846 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
3606: NOT 13:52:33.329585 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3607: WRN 13:52:33.330320 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
3608: NOT 13:52:33.338101 HTTPCL: downdload will be limited to 537 KB
3609: NOT 13:52:33.342949 HTTPCL: hdr->HTTP/1.1 200 OK
3610: NOT 13:52:33.343891 HTTPCL: hdr->Content-length: 7682
3611: NOT 13:52:33.344437 HTTPCL: hdr->Cache-Control: no-store
3612: NOT 13:52:33.345137 HTTPCL: hdr->Content-type: */*
3613: NOT 13:52:33.371052 SYSMSG: pid 30 (/sbin/httpcl) Normal Exit, status = 100
3614: INF 13:52:33.371091 runtime = 0.090 secs
3615: INF 13:52:33.371110 user cpu = 0.001787730 secs
3616: INF 13:52:33.371129 system cpu = 0.015465230 secs
3617: INF 13:52:33.371143 child user cpu = 0.000000000 secs
3618: INF 13:52:33.371157 child sys cpu = 0.000000000 secs
3619: INF 13:52:33.371178 sys interrupts = 0.006286040 secs for 118 interrupts
3620: INF 13:52:33.371200 total cpu = 0.017252960 secs ( 11% utilization )
3621: NOT 13:52:33.376583 SECD: verifyFile: sgn-verify </usr/ram/SEP7C95F3C93DD3.cnf.xml>, 'name'[SEP7C95F3C93DD3.cnf.xml.sgn]
3622: NOT 13:52:33.378722 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3623: NOT 13:52:33.379312 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3624: NOT 13:52:33.381758 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
3625: NOT 13:52:33.382352 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
3626: NOT 13:52:33.416977 SECD: file sgn verify SUCCESS, hdr 368 byte, </usr/ram/SEP7C95F3C93DD3.cnf.xml>
3627: NOT 13:52:33.417704 SECD: verifyFile: file sgn verified </usr/ram/SEP7C95F3C93DD3.cnf.xml>, hdrlen 368
3628: NOT 13:52:33.418539 SECD: verifyFile: hdr ver [2.0], and file not encr, </usr/ram/SEP7C95F3C93DD3.cnf.xml>
3629: NOT 13:52:33.428870 SECD: verifyFile: 7314 byte after hdr strip, </usr/ram/SEP7C95F3C93DD3.cnf.xml>
3630: NOT 13:52:33.429704 SECD: verifyFile: verify SUCCESS </usr/ram/SEP7C95F3C93DD3.cnf.xml>
3631: NOT 13:52:33.433224 xxtpClient: authorize file = 12, isEncr = 0
3632: NOT 13:52:33.439663 SECD: lookupCTL: TFTP SRVR secure
-------------- next part --------------
|== Syslogd TNP== Wed Oct 30 13:51:45 2013
====================================================
2887: ERR 13:51:45.274862 JVM: SystemManager n/a unloadAllModules ->
2888: ERR 13:51:45.276257 JVM: SystemManager n/a Unload in this order:
2889: ERR 13:51:45.277488 JVM: SystemManager n/a SCCP Call Control
2890: ERR 13:51:45.278759 JVM: SystemManager n/a Vpn
2891: ERR 13:51:45.279889 JVM: SystemManager n/a InstallerModule
2892: ERR 13:51:45.281139 JVM: SystemManager n/a NotificationModule
2893: ERR 13:51:45.282310 JVM: SystemManager n/a SubscriptionManager
2894: ERR 13:51:45.283517 JVM: SystemManager n/a Starts Automation Feature
2895: ERR 13:51:45.284705 JVM: SystemManager n/a Sidecar
2896: ERR 13:51:45.285894 JVM: SystemManager n/a Vieo
2897: ERR 13:51:45.287082 JVM: SystemManager n/a AppStatus
2898: ERR 13:51:45.288253 JVM: SystemManager n/a Help
2899: ERR 13:51:45.289397 JVM: SystemManager n/a Go4
2900: ERR 13:51:45.290660 JVM: SystemManager n/a Messages
2901: ERR 13:51:45.291834 JVM: SystemManager n/a Directories
2902: ERR 13:51:45.293033 JVM: SystemManager n/a Services
2903: ERR 13:51:45.294239 JVM: SystemManager n/a Settings
2904: ERR 13:51:45.295428 JVM: SystemManager n/a Push Service
2905: ERR 13:51:45.296585 JVM: SystemManager n/a HTTP Server/Microservlet Container
2906: ERR 13:51:45.297816 JVM: SystemManager n/a HTTP
2907: ERR 13:51:45.298983 JVM: SystemManager n/a Config Manager
2908: ERR 13:51:45.300277 JVM: SystemManager n/a Security
2909: ERR 13:51:45.301438 JVM: SystemManager n/a TFTP Client
2910: ERR 13:51:45.302630 JVM: SystemManager n/a Call UI
2911: ERR 13:51:45.303842 JVM: SystemManager n/a Media Manager
2912: ERR 13:51:45.304994 JVM: SystemManager n/a Call Agent
2913: ERR 13:51:45.306152 JVM: SystemManager n/a Application Manager
2914: ERR 13:51:45.307327 JVM: SystemManager n/a Display
2915: ERR 13:51:45.308553 JVM: SystemManager n/a Input Manager
2916: ERR 13:51:45.309750 JVM: SystemManager n/a Localization Manager
2917: ERR 13:51:45.310995 JVM: SystemManager n/a Reset Service
2918: ERR 13:51:45.312198 JVM: SystemManager n/a Property Manager
2919: ERR 13:51:45.313351 JVM: SystemManager n/a Timer Manager
2920: ERR 13:51:45.314511 JVM: SystemManager n/a Trace Service
2921: ERR 13:51:45.315665 JVM: SystemManager n/a Trace Manager
2922: ERR 13:51:45.317219 JVM: SystemManager n/a module "SCCP Call Control"/sccpccapi(32) is UNLOADING
2923: ERR 13:51:45.329015 JVM: SystemManager n/a module "SCCP Call Control"/sccpccapi(32) is UNLOADED
2924: ERR 13:51:45.334893 JVM: SystemManager n/a module "Vpn"/vpn(31) is UNLOADING
2925: ERR 13:51:45.358836 enter in6_if_brcm_reset
2926: ERR 13:51:45.359256 exit in6_if_brcm_reset
2927: NOT 13:51:45.363243 JVM: --> Properties JNI: SignalApp() handling SIGIPCFG. msgCode=256 value=0
2928: NOT 13:51:45.377418 JVM: <-- Properties JNI: SignalApp()
2929: DBG 13:51:45.380218 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
2930: ERR 13:51:45.473604 JVM: SystemManager n/a module "Vpn"/vpn(31) is UNLOADED
2931: ERR 13:51:45.481768 JVM: SystemManager n/a module "InstallerModule"/installer(30) is UNLOADING
2932: ERR 13:51:45.491099 JVM: SystemManager n/a module "InstallerModule"/installer(30) is UNLOADED
2933: ERR 13:51:45.492931 JVM: SystemManager n/a cip.props.NoSuchPropertyException: Property "device.callagent.
2934: ERR 13:51:45.493762 JVM: messages.0" does not exist
2935: ERR 13:51:45.495684 JVM: SystemManager n/a unloadAllModules ->
2936: ERR 13:51:45.497244 JVM: SystemManager n/a Unload in this order:
2937: ERR 13:51:45.498654 JVM: SystemManager n/a NotificationModule
2938: ERR 13:51:45.500137 JVM: SystemManager n/a SubscriptionManager
2939: ERR 13:51:45.501490 JVM: SystemManager n/a Starts Automation Feature
2940: ERR 13:51:45.502846 JVM: SystemManager n/a Sidecar
2941: ERR 13:51:45.504199 JVM: SystemManager n/a Vieo
2942: ERR 13:51:45.505745 JVM: SystemManager n/a AppStatus
2943: ERR 13:51:45.507122 JVM: SystemManager n/a Help
2944: ERR 13:51:45.508489 JVM: SystemManager n/a Go4
2945: ERR 13:51:45.509820 JVM: SystemManager n/a Messages
2946: ERR 13:51:45.511196 JVM: SystemManager n/a Directories
2947: ERR 13:51:45.512555 JVM: SystemManager n/a Services
2948: ERR 13:51:45.514030 JVM: SystemManager n/a Settings
2949: ERR 13:51:45.515382 JVM: SystemManager n/a Push Service
2950: ERR 13:51:45.516752 JVM: SystemManager n/a HTTP Server/Microservlet Container
2951: ERR 13:51:45.518138 JVM: SystemManager n/a HTTP
2952: ERR 13:51:45.519493 JVM: SystemManager n/a Config Manager
2953: ERR 13:51:45.520899 JVM: SystemManager n/a Security
2954: ERR 13:51:45.522271 JVM: SystemManager n/a TFTP Client
2955: ERR 13:51:45.523736 JVM: SystemManager n/a Call UI
2956: ERR 13:51:45.525090 JVM: SystemManager n/a Media Manager
2957: ERR 13:51:45.526421 JVM: SystemManager n/a Call Agent
2958: ERR 13:51:45.527804 JVM: SystemManager n/a Application Manager
2959: ERR 13:51:45.529260 JVM: SystemManager n/a Display
2960: ERR 13:51:45.530705 JVM: SystemManager n/a Input Manager
2961: ERR 13:51:45.532084 JVM: SystemManager n/a Localization Manager
2962: ERR 13:51:45.533445 JVM: SystemManager n/a Reset Service
2963: ERR 13:51:45.534892 JVM: SystemManager n/a Property Manager
2964: ERR 13:51:45.536239 JVM: SystemManager n/a Timer Manager
2965: ERR 13:51:45.537607 JVM: SystemManager n/a Trace Service
2966: ERR 13:51:45.538958 JVM: SystemManager n/a Trace Manager
2967: ERR 13:51:45.540792 JVM: SystemManager n/a module "NotificationModule"/notification(29) is UNLOADING
2968: ERR 13:51:45.543294 JVM: SystemManager n/a module "NotificationModule"/notification(29) is UNLOADED
2969: ERR 13:51:45.545530 JVM: SystemManager n/a module "SubscriptionManager"/subscriptionman(28) is UNLOADING
2970: ERR 13:51:45.557261 JVM: SystemManager n/a module "SubscriptionManager"/subscriptionman(28) is UNLOADED
2971: ERR 13:51:45.559570 JVM: SystemManager n/a module "Starts Automation Feature"/automation(27) is UNLOADING
2972: ERR 13:51:45.562611 JVM: SystemManager n/a module "Starts Automation Feature"/automation(27) is UNLOADED
2973: ERR 13:51:45.564487 JVM: SystemManager n/a module "Sidecar"/sidecar(26) is UNLOADING
2974: ERR 13:51:45.572156 JVM: SystemManager n/a module "Vieo"/vieo(25) is UNLOADING
2975: ERR 13:51:45.575214 JVM: SystemManager n/a module "AppStatus"/appstatus(24) is UNLOADING
2976: ERR 13:51:45.578515 JVM: SystemManager n/a module "AppStatus"/appstatus(24) is UNLOADED
2977: ERR 13:51:45.586044 JVM: SystemManager n/a module "Help"/help(23) is UNLOADING
2978: ERR 13:51:45.590923 JVM: SystemManager n/a module "Help"/help(23) is UNLOADED
2979: ERR 13:51:45.598011 JVM: SystemManager n/a module "Go4"/go4(22) is UNLOADING
2980: ERR 13:51:45.604214 JVM: SystemManager n/a module "Go4"/go4(22) is UNLOADED
2981: ERR 13:51:45.606302 JVM: SystemManager n/a module "Messages"/messages(21) is UNLOADING
2982: ERR 13:51:45.661790 JVM: 13:51:45|cip.props.NoSuchPropertyException: Property "device.callagent.messages.0" does not exist
at cip.props.PropertyManager.getProperty(Ljava/lang/String;)Lcip/props/Property;(Unknown Source)
at cip.props.PropertyManager.removeListener(Lcip/props/IPropertyListener;Ljava/lang/String;)V(Unknown Source)
at cip.midp.midletsuite.InstallerModule.onUnload()V(Unknown Source)
at cip.sys.e.unload()V(Unknown Source)
at cip.sys.SystemManager.c()V(Unknown Source)
at cip.sys.SystemManager.k()V(Unknown Source)
at cip.sys.SystemManager.n()V(Unknown Source)
at cip.sys.bb.e()V(Unknown Source)
at cip.sys.l.run()V(Unknown Source)
at java.lang.Thread.startup(Z)V(Unknown Source)
2983: ERR 13:51:45.881961 JVM: SystemManager n/a module "Messages"/messages(21) is UNLOADED
2984: ERR 13:51:45.889362 JVM: SystemManager n/a module "Directories"/directories(20) is UNLOADING
2985: ERR 13:51:45.944602 JVM: SystemManager n/a module "Directories"/directories(20) is UNLOADED
2986: ERR 13:51:45.950279 JVM: SystemManager n/a module "Services"/services(19) is UNLOADING
2987: ERR 13:51:45.991367 JVM: SystemManager n/a module "Services"/services(19) is UNLOADED
2988: ERR 13:51:45.997430 JVM: SystemManager n/a module "Settings"/settings(18) is UNLOADING
2989: ERR 13:51:46.044296 JVM: SystemManager n/a module "Settings"/settings(18) is UNLOADED
2990: ERR 13:51:46.049808 JVM: SystemManager n/a module "Push Service"/push(17) is UNLOADING
2991: ERR 13:51:46.051566 JVM: SystemManager n/a module "Push Service"/push(17) is UNLOADED
2992: ERR 13:51:46.083486 JVM: SystemManager n/a module "HTTP Server/Microservlet Container"/microservlets(16)
2993: ERR 13:51:46.084186 JVM: is UNLOADING
2994: ERR 13:51:46.133489 JVM: SystemManager n/a module "HTTP Server/Microservlet Container"/microservlets(16)
2995: ERR 13:51:46.134131 JVM: is UNLOADED
2996: ERR 13:51:46.135877 JVM: SystemManager n/a module "HTTP"/http(15) is UNLOADING
2997: ERR 13:51:46.137469 JVM: Setting http and https to disabled
2998: ERR 13:51:46.206692 JVM: SystemManager n/a module "HTTP"/http(15) is UNLOADED
2999: ERR 13:51:46.208359 JVM: SystemManager n/a module "Config Manager"/configmgr(14) is UNLOADING
3000: ERR 13:51:46.281593 JVM: SystemManager n/a module "Config Manager"/configmgr(14) is UNLOADED
3001: ERR 13:51:46.283265 JVM: SystemManager n/a module "Security"/security(13) is UNLOADING
3002: DBG 13:51:46.307518 VPNU: State AppsUp --> AppsUp
3003: DBG 13:51:46.308124 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3004: DBG 13:51:46.308674 VPNU: State AppsUp --> AppsUp
3005: NOT 13:51:46.312459 PAE: SIGIPCFG received...
3006: NOT 13:51:46.314152 CDP-D: cdpGetPortCfg SPANTOPC CFG:11
3007: NOT 13:51:46.318081 ESP: send ADMIN, logging = 1, shell = 0, ipconfig = 1
3008: DBG 13:51:46.322195 cffs_gc(): runing gc 2
3009: NOT 13:51:46.330192 CDP-D: catchipcfg:getdhcpinfo IP:c0a8010c domain: chngVal:1
3010: NOT 13:51:46.331201 CDP-D: cdpGetPortCfg SPANTOPC CFG:11
3011: ERR 13:51:46.400971 JVM: SystemManager n/a module "Security"/security(13) is UNLOADED
3012: ERR 13:51:46.402647 JVM: SystemManager n/a module "TFTP Client"/tftp(12) is UNLOADING
3013: ERR 13:51:46.430340 JVM: SystemManager n/a module "TFTP Client"/tftp(12) is UNLOADED
3014: ERR 13:51:46.432013 JVM: SystemManager n/a module "Call UI"/callui(11) is UNLOADING
3015: ERR 13:51:46.547917 JVM: SystemManager n/a module "Call UI"/callui(11) is UNLOADED
3016: ERR 13:51:46.553571 JVM: SystemManager n/a module "Media Manager"/mediamgr(10) is UNLOADING
3017: ERR 13:51:46.650839 JVM: SystemManager n/a module "Media Manager"/mediamgr(10) is UNLOADED
3018: ERR 13:51:46.656375 JVM: SystemManager n/a module "Call Agent"/callagent(9) is UNLOADING
3019: ERR 13:51:46.673841 JVM: SystemManager n/a module "Call Agent"/callagent(9) is UNLOADED
3020: ERR 13:51:46.675409 JVM: SystemManager n/a module "Application Manager"/appmgr(8) is UNLOADING
3021: ERR 13:51:46.711809 JVM: SystemManager n/a module "Application Manager"/appmgr(8) is UNLOADED
3022: ERR 13:51:46.713406 JVM: SystemManager n/a module "Display"/display(7) is UNLOADING
3023: ERR 13:51:47.083340 RTSOLD: opvvlan:1->1 advvlan:4095->4095 vvlanState=1
3024: ERR 13:51:47.085178 RTSOLD: LINK -> LINK
3025: ERR 13:51:47.085770 RTSOLD:address mode changed..
3026: ERR 13:51:47.713412 JVM: SystemManager n/a module "Display"/display(7) is UNLOADED
3027: ERR 13:51:47.714956 JVM: SystemManager n/a module "Input Manager"/inputmgr(6) is UNLOADING
3028: ERR 13:51:47.730170 JVM: SystemManager n/a module "Input Manager"/inputmgr(6) is UNLOADED
3029: ERR 13:51:47.731713 JVM: SystemManager n/a module "Localization Manager"/localizationmgr(5) is UNLOADING
3030: ERR 13:51:47.735305 JVM: SystemManager n/a module "Localization Manager"/localizationmgr(5) is UNLOADED
3031: ERR 13:51:47.736872 JVM: SystemManager n/a module "Reset Service"/reset(4) is UNLOADING
3032: ERR 13:51:47.738382 JVM: SystemManager n/a module "Reset Service"/reset(4) is UNLOADED
3033: ERR 13:51:47.739935 JVM: SystemManager n/a module "Property Manager"/propertymgr(3) is UNLOADING
3034: ERR 13:51:47.741480 JVM: SystemManager n/a module "Property Manager"/propertymgr(3) is UNLOADED
3035: ERR 13:51:47.811580 JVM: Startup Module Loader|cip.app.be:? - Application Manager Thread is Interrupted
3036: WRN 13:51:47.825775 JVM: Startup Module Loader|cip.g3.userio.d:? - Native code returned null event - exiting
3037: NOT 13:51:47.885811 xxtpClient: request server 0 ---> 192.168.1.130
3038: NOT 13:51:47.886821 ESP: server 0 = 192.168.1.130
3039: DBG 13:51:47.889174 cffs_gc(): runing gc 1
3040: NOT 13:51:47.904573 xxtpClient: request server6 0 ---> ::
3041: NOT 13:51:47.921282 xxtpClient: request server 1 --->
3042: NOT 13:51:47.922344 ESP: server 1 =
3043: NOT 13:51:47.947066 xxtpClient: request server6 1 ---> ::
3044: DBG 13:51:47.948022 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3045: DBG 13:51:47.961226 VPNU: State AppsUp --> AppsUp
3046: DBG 13:51:47.961828 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
3047: DBG 13:51:47.962387 VPNU: State AppsUp --> AppsUp
3048: NOT 13:51:47.994274 xxtpClient: request server6 0 ---> ::
3049: NOT 13:51:47.994998 ESP: server 2 = ::
3050: ERR 13:51:48.082437 JVM: SystemManager n/a module "Timer Manager"/timermgr(2) is UNLOADING
3051: ERR 13:51:48.096208 JVM: SystemManager n/a module "Timer Manager"/timermgr(2) is UNLOADED
3052: ERR 13:51:48.097757 JVM: SystemManager n/a module "Trace Service"/traceService(1) is UNLOADING
3053: ERR 13:51:48.099318 JVM: SystemManager n/a module "Trace Service"/traceService(1) is UNLOADED
3054: ERR 13:51:48.101046 JVM: SystemManager n/a module "Trace Manager"/traceManager(0) is UNLOADING
3055: ERR 13:51:48.117783 JVM: SystemManager n/a module "Trace Manager"/traceManager(0) is UNLOADED
3056: ERR 13:51:48.119086 JVM: SystemManager n/a <- unloadAllModules
3057: ERR 13:51:48.469796 JVM: SystemManager n/a *systemStateProperty.setValue( SYSTEM_UNLOADED )*
3058: ERR 13:51:48.471569 JVM: SystemManager n/a *<-- systemUnload*
3059: ERR 13:51:48.472604 JVM: StaticInitializer.onUpgrade(),Unloaded and Shutting down for load update
3060: NOT 13:51:48.487498 xxtpClient: request server6 1 ---> ::
3061: DBG 13:51:48.488645 lcd_close: return success!
3062: NOT 13:51:48.533143 SYSMSG: pid 10 (/tmp/sunvm.unzip/sunvm.cnu) Normal Exit, status = 101
3063: INF 13:51:48.533181 runtime = 41.010 secs
3064: INF 13:51:48.533202 user cpu = 20.168327980 secs
3065: INF 13:51:48.533221 system cpu = 6.626819740 secs
3066: INF 13:51:48.533235 child user cpu = 0.000000000 secs
3067: INF 13:51:48.533249 child sys cpu = 0.000000000 secs
3068: INF 13:51:48.533273 sys interrupts = 0.378575410 secs for 10096 interrupts
3069: INF 13:51:48.533297 total cpu = 26.795147720 secs ( 65% utilization )
3070: NOT 13:51:48.492058 ESP: server 3 = ::
3071: DBG 13:51:48.570597 lcd_open: return success!
3072: NOT 13:51:49.572152 init: Sending termination signals to apps for reimage prep.
3073: NOT 13:51:49.572811 DSP: DSP INIT- dspKill entered, dvrState 8
3074: DBG 13:51:49.770122 dsp close
3075: NOT 13:51:49.780343 SYSMSG: pid 29 (/ubin/dsp) Normal Exit, status = 1
3076: INF 13:51:49.780381 runtime = 43.630 secs
3077: INF 13:51:49.780401 user cpu = 0.058021510 secs
3078: INF 13:51:49.780420 system cpu = 0.261742330 secs
3079: INF 13:51:49.780434 child user cpu = 0.000000000 secs
3080: INF 13:51:49.780448 child sys cpu = 0.000000000 secs
3081: INF 13:51:49.780472 sys interrupts = 0.407479310 secs for 10856 interrupts
3082: INF 13:51:49.780494 total cpu = 0.319763840 secs ( 0% utilization )
3083: NOT 13:51:53.570290 init: exec /sbin/image -a 0x6 -vf SCCP42.9-3-1-1S.loads -p
3084: NOT 13:51:53.601225 image: [22] ver 2.0 (0.1)
3085: ERR 13:51:53.601831 image: image: current euid: 0
3086: ERR 13:51:53.602371 image: image: current uid: 0
3087: ERR 13:51:53.602863 image: image: current gid: 0
3088: ERR 13:51:53.603350 image: image: current egid: 0
3089: ERR 13:51:53.603810 image: image: current pid: 22
3090: ERR 13:51:53.604326 image: image: current ppid: 1
3091: NOT 13:51:53.604941 image: Starting imgui...
3092: DBG 13:51:53.620447 Opened cmd pipe '/tmp/imguicmd'
3093: NOT 13:51:53.623228 imgUI: [13] ver 0.1 (0.0)
3094: NOT 13:51:53.707737 image: **Legacy TFTP Mode Specified**
3095: NOT 13:51:53.715913 image: Starting dload...
3096: ERR 13:51:53.718473 image: exec /sbin/dload -inrv -a 0x6 -p
3097: NOT 13:51:53.753377 TLoad: Restart source file signal
3098: NOT 13:51:53.757454 TLoad: ^.idl_inetd.c-waitForNetworkUp
3099: DBG 13:51:53.758281 TLoad: getportcfg: msgsnd ( inetdMsgQue ) success : pid=30,cmd=6,qname=cdp_rsp_que30
3100: INF 13:51:53.764599 TLoad: Opened cmd pipe '/tmp/idlcmd'
3101: DBG 13:51:53.765950 TLoad: getportcfg: [30] 6=1
3102: NOT 13:51:56.780078 TLoad: ^.idl_inetd.c-parseDhcpInfoIntoTftpList
3103: NOT 13:51:56.800205 TLoad: ^.idl_inetd.c-inet_network
3104: ERR 13:51:56.800788 TLoad: TFTP Svr set by opt150
3105: ERR 13:51:56.801260 TLoad: TFTPv6 Svr set by no IPv6 TFTP
3106: ERR 13:51:56.801836 TLoad: TFTP Svr0 --> ::ffff:192.168.1.130
3107: ERR 13:51:56.802357 TLoad: TFTP Svr1 --> ::
3108: ERR 13:51:56.802885 TLoad: TFTP Svr2 --> ::
3109: ERR 13:51:56.803406 TLoad: TFTP Svr3 --> ::
3110: NOT 13:51:56.814309 TLoad: UT:yetToDo set to 0 on (re)startProcessing
3111: NOT 13:51:56.814903 TLoad: ^.idl_protocol.c-doSourceFileSerial
3112: NOT 13:51:56.815409 TLoad: UT:serial pfile to /usr/tmp/binfiles
3113: INF 13:51:56.816099 TLoad: Get next entry from /usr/tmp/binfiles
3114: NOT 13:51:56.816764 TLoad: UT:/usr/tmp/binfiles: SCCP42.9-3-1-1S.loads get
3115: NOT 13:51:56.817304 TLoad: serial gross Timeout set for 3660 seconds
3116: NOT 13:51:56.817894 TLoad: ^.idl_protocol.c-doOfferInit
3117: NOT 13:51:56.818433 TLoad: UT:yetToDo set to 1 (doOffer++)
3118: NOT 13:51:56.819040 TLoad: checking for existance of /dnld/SCCP42.9-3-1-1S.loads
3119: NOT 13:51:56.819717 TLoad: /dnld/SCCP42.9-3-1-1S.loads not found
3120: NOT 13:51:56.820306 TLoad: @@@HCREA:XID08002848 SCCP42.9-3-1-1S.loads
3121: INF 13:51:56.820817 TLoad: XID08002848 Root TFTP XfrCmd (NODIST)
3122: NOT 13:51:56.821345 TLoad: ^.idl_support.c-startupTransferThread
3123: NOT 13:51:56.821865 TLoad: UT:Starting RX Xfer Thread cmdID=00AAA001
3124: NOT 13:51:56.822610 TLoad: UT:(ser)results file = /usr/tmp/loadedfiles
3125: DBG 13:51:56.823732 TLoad: (ser)result_file_open
3126: NOT 13:51:56.824272 TLoad: TLOAD main thread startup version 2.0 (06.28d)
3127: NOT 13:51:56.824852 TLoad: ^.idl_rx_xfer.c-idlRx_SCCP42.9-3-1-1S.loads
3128: NOT 13:51:56.825368 TLoad: ^.idl_rx_xfer.c-doTFTP
3129: NOT 13:51:56.831798 TLoad: ^.idl_inetd.c-idl_makeXXTPrequest
3130: NOT 13:51:56.832363 TLoad: ^.idl_inetd.c-getlastTftp
3131: ERR 13:51:56.833623 TLoad: Last successful tftp server at index 0
3132: NOT 13:51:56.834193 TLoad: Making tftp request to Svr0 --> 192.168.1.130
3133: NOT 13:51:56.859345 HTTPCL: hdr->HTTP/1.1 200 OK
3134: NOT 13:51:56.859903 HTTPCL: hdr->Content-length: 674
3135: NOT 13:51:56.860851 HTTPCL: hdr->Content-type: */*
3136: NOT 13:51:56.865248 SYSMSG: pid 26 (/sbin/httpcl) Normal Exit, status = 100
3137: INF 13:51:56.865287 runtime = 0.030 secs
3138: INF 13:51:56.865306 user cpu = 0.001272080 secs
3139: INF 13:51:56.865325 system cpu = 0.016677180 secs
3140: INF 13:51:56.865339 child user cpu = 0.000000000 secs
3141: INF 13:51:56.865353 child sys cpu = 0.000000000 secs
3142: INF 13:51:56.865373 sys interrupts = 0.001253470 secs for 10 interrupts
3143: INF 13:51:56.865396 total cpu = 0.017949260 secs ( 33% utilization )
3144: NOT 13:51:56.867657 TLoad: caught child signal
3145: NOT 13:51:56.868185 TLoad: ^.idl_inetd.c-setTftpStatus
3146: NOT 13:51:56.868735 TLoad: TFTP/HTTP reported status = 100
3147: NOT 13:51:56.869248 TLoad: ^.idl_inetd.c-putlastTftp
3148: NOT 13:51:56.870344 TLoad: ^.idl_rx_xfer.c-checkFileSize
3149: CRT 13:51:56.872140 TLoad: cmd->arg after doXXread is 0
3150: NOT 13:51:56.872671 TLoad: UT:RX Xfer Thrd signal onNotify
3151: NOT 13:51:56.873237 TLoad: XID08002848 Notify xfr complete (auth)
3152: CRT 13:51:56.873824 TLoad: cmd->arg after hNmsg send 0
3153: NOT 13:51:56.874333 TLoad: UT:RX Xfer Thrd signal for authentication
3154: ALT 13:51:56.874895 TLoad: got authentication request
3155: NOT 13:51:56.875388 TLoad: ^.idl_authenticate.c-doauthenticate
3156: NOT 13:51:56.876560 TLoad: Authenticating loaded file /dnld/SCCP42.9-3-1-1S.loads
3157: NOT 13:51:56.877092 TLoad: ^.idl_authenticate.c-isSigned
3158: NOT 13:51:57.760173 IMG_AUTH: verify: verifying, /dnld/SCCP42.9-3-1-1S.loads
3159: NOT 13:51:57.760954 IMG_AUTH: verify: verify with current RELEASE 7942 (primary built-in) key
3160: NOT 13:51:57.761910 IMG_AUTH: parseHdr(): skipping 2 trail bytes (pad and/or unknown TLVs)
-------------- next part --------------
|== Syslogd TNP== Wed Oct 30 13:51:34 2013
====================================================
2680: WRN 13:51:34.895004 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
2681: NOT 13:51:34.898435 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
2682: NOT 13:51:34.899074 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
2683: NOT 13:51:34.899626 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
2684: WRN 13:51:34.900357 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
2685: WRN 13:51:34.905426 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
2686: NOT 13:51:34.908867 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
2687: NOT 13:51:34.909474 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
2688: NOT 13:51:34.910136 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
2689: WRN 13:51:34.910781 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
2690: NOT 13:51:34.984772 xxtpClient: request server 0 ---> 192.168.1.130
2691: NOT 13:51:35.016645 xxtpClient: request server 1 --->
2692: NOT 13:51:35.055167 CDP-D: cdpGetPortCfg SPANTOPC CFG:11
2693: NOT 13:51:35.134189 xxtpClient: request server6 0 ---> ::
2694: NOT 13:51:35.158252 xxtpClient: request server6 1 ---> ::
2695: DBG 13:51:35.158982 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
2696: DBG 13:51:35.171652 VPNU: State AppsUp --> AppsUp
2697: DBG 13:51:35.172264 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
2698: DBG 13:51:35.172844 VPNU: State AppsUp --> AppsUp
2699: ERR 13:51:35.237983 JVM: Startup Module Loader|cip.mmgr.MediaManager:? -
MediaManager propertyChanged called device.settings.network.ipstacksstate
2700: ERR 13:51:35.239426 JVM: Startup Module Loader|cip.http.HttpTask:? - auth url is https://192.168.1.130:8443/ccmcip/authenticate.jsp
2701: NOT 13:51:35.251343 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - --->ConfigManager PropertyChanged: device.settings.network.ipstacksstate
2702: NOT 13:51:35.252789 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - ConfigManager IP_STACKS_STATE changed
2703: NOT 13:51:35.254228 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - IPv4 state=DHCP BOUND
2704: NOT 13:51:35.255632 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - IPv6 state=DHCP6 WAITING COLDBOOT TIMEOUT
2705: ERR 13:51:35.257105 JVM: Startup Module Loader|cip.cfg.ConfigManager:tryConfigFileDownload - File_FIPS_STATUS exists?false
2706: DBG 13:51:40.263394 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
2707: NOT 13:51:40.275792 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - tryConfigFileDownload(): 1 IP stack up, starting timer
2708: NOT 13:51:40.277256 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - <---ConfigManager PropertyChanged: device.settings.network.ipstacksstate
2709: DBG 13:51:40.278309 VPNU: State AppsUp --> AppsUp
2710: DBG 13:51:40.278909 VPNU: SM wakeup - chld=0 tmr=0 io=1 res=0
2711: DBG 13:51:40.279469 VPNU: State AppsUp --> AppsUp
2712: NOT 13:51:42.786875 SECD: loadTvsSrvrCfg: Not in EMCC mode.Loading the flash file :/flash0/sec/misc/tvs.conf
2713: NOT 13:51:42.795654 JVM: emccMode=0,localOverride=0, tftpAddr1=, tftpAddr2=,tftpAddr3=,tftpAddr4=
2714: NOT 13:51:42.799051 xxtpClient: tftp request rcv'd from /usr/tmp/tftp, emccMode =0, emccLocalOverride=0, tempTftp1= , tempTftp2 = , tempTftp3 = , tempTftp4 =
2715: NOT 13:51:42.800975 JVM: setTempTftpAddress, emcc_mode=0,retEmccMode=0,LocalOverride=0,retLocalOverride=0, status=1
2716: NOT 13:51:42.811933 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - Starting CTL/config file updating
2717: NOT 13:51:42.883149 SECD: updateCTL: starting Trust list update
2718: NOT 13:51:42.885800 SECD: tlRequestFile: Socket 9 connected to /usr/tmp/xxtpClientSock
2719: NOT 13:51:42.886605 SECD: tlRequestFile: Request CTLSEP7C95F3C93DD3.tlv
2720: NOT 13:51:42.892492 xxtpClient: xxtp request rcv'd from /usr/tmp/ctlSock, srcFile = CTLSEP7C95F3C93DD3.tlv, dstFile = /usr/tmp/CTLFile.tlv
2721: NOT 13:51:42.910697 xxtpClient: auth server - tftpList[0] = ::ffff:192.168.1.130
2722: NOT 13:51:42.911287 xxtpClient: look up server - 0
2723: NOT 13:51:42.913018 SECD: lookupCTL: TFTP SRVR secure
2724: NOT 13:51:42.915545 xxtpClient: secVal = 0x9
2725: NOT 13:51:42.916203 xxtpClient: ::ffff:192.168.1.130 is a secure server
2726: NOT 13:51:42.916785 xxtpClient: retval = SRVR_SECURE
2727: NOT 13:51:42.917272 xxtpClient: Non secure file requested
2728: ERR 13:51:42.947412 HTTPCL: http get [HTTP/1.1 404 Not Found
Conten]
2729: NOT 13:51:42.956212 SYSMSG: pid 17 (/sbin/httpcl) Normal Exit, status = 101
2730: INF 13:51:42.956252 runtime = 0.030 secs
2731: INF 13:51:42.956271 user cpu = 0.001017730 secs
2732: INF 13:51:42.956290 system cpu = 0.015303210 secs
2733: INF 13:51:42.956304 child user cpu = 0.000000000 secs
2734: INF 13:51:42.956318 child sys cpu = 0.000000000 secs
2735: INF 13:51:42.956338 sys interrupts = 0.001196750 secs for 15 interrupts
2736: INF 13:51:42.956360 total cpu = 0.016320940 secs ( 33% utilization )
2737: WRN 13:51:42.952307 xxtpClient: HTTP failed with code 101
2738: NOT 13:51:42.953432 SECD: tlRequestFile: tftp Status 2 rcv'd
2739: NOT 13:51:42.954224 SECD: updateCTL: finished CTLFile update
2740: NOT 13:51:42.956647 SECD: tlRequestFile: Socket 9 connected to /usr/tmp/xxtpClientSock
2741: NOT 13:51:42.957510 SECD: tlRequestFile: Request ITLSEP7C95F3C93DD3.tlv
2742: NOT 13:51:42.958228 xxtpClient: xxtp request rcv'd from /usr/tmp/ctlSock, srcFile = ITLSEP7C95F3C93DD3.tlv, dstFile = /usr/tmp/ITLFile.tlv
2743: NOT 13:51:42.971074 xxtpClient: auth server - tftpList[0] = ::ffff:192.168.1.130
2744: NOT 13:51:42.971643 xxtpClient: look up server - 0
2745: NOT 13:51:42.973343 SECD: lookupCTL: TFTP SRVR secure
2746: NOT 13:51:42.975695 xxtpClient: secVal = 0x9
2747: NOT 13:51:42.976384 xxtpClient: ::ffff:192.168.1.130 is a secure server
2748: NOT 13:51:42.976896 xxtpClient: retval = SRVR_SECURE
2749: NOT 13:51:42.977376 xxtpClient: Non secure file requested
2750: NOT 13:51:43.005764 HTTPCL: hdr->HTTP/1.1 200 OK
2751: NOT 13:51:43.006511 HTTPCL: hdr->Content-length: 4168
2752: NOT 13:51:43.007028 HTTPCL: hdr->Cache-Control: no-store
2753: NOT 13:51:43.007548 HTTPCL: hdr->Content-type: */*
2754: NOT 13:51:43.030160 SYSMSG: pid 8 (/sbin/httpcl) Normal Exit, status = 100
2755: INF 13:51:43.030200 runtime = 0.050 secs
2756: INF 13:51:43.030219 user cpu = 0.001502150 secs
2757: INF 13:51:43.030238 system cpu = 0.013481710 secs
2758: INF 13:51:43.030252 child user cpu = 0.000000000 secs
2759: INF 13:51:43.030266 child sys cpu = 0.000000000 secs
2760: INF 13:51:43.030286 sys interrupts = 0.003652550 secs for 17 interrupts
2761: INF 13:51:43.030308 total cpu = 0.014983860 secs ( 20% utilization )
2762: NOT 13:51:43.033272 SECD: tlRequestFile: tftp Status 100 rcv'd
2763: NOT 13:51:43.037772 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
2764: NOT 13:51:43.038385 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
2765: NOT 13:51:43.038932 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
2766: NOT 13:51:43.039606 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
2767: NOT 13:51:43.040568 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
2768: NOT 13:51:43.041132 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
2769: NOT 13:51:43.058019 SECD: validate_file_envelope: File sign verify SUCCESS; header length <352>
2770: NOT 13:51:43.058665 SECD: TL_ValidateSignedTL: new TL matches old, not updating
2771: NOT 13:51:43.059514 SECD: updateCTL: finished ITLFile update
2772: NOT 13:51:43.128024 JVM: --> Properties JNI: SignalApp() handling SIGIPCFG. msgCode=256 value=0
2773: NOT 13:51:43.142890 JVM: <-- Properties JNI: SignalApp()
2774: ERR 13:51:43.145436 JVM: Startup Module Loader|cip.sec.t:---------updateCtl,result=0 - -------------invoke proxy
2775: ERR 13:51:43.182412 JVM: Startup Module Loader|cip.sec.t:---------updateCtl,result=0 - -------------invoke dispatcher
2776: NOT 13:51:43.184019 JVM: Startup Module Loader|cip.cfg.k:? - ConfigManager updateCTL() rc=0 retryCount=0
2777: WRN 13:51:43.187681 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
2778: NOT 13:51:43.193417 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
2779: NOT 13:51:43.194021 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
2780: NOT 13:51:43.194936 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
2781: WRN 13:51:43.195771 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
2782: NOT 13:51:43.391553 JVM: Startup Module Loader|cip.cfg.t:? - Requesting CONFIG file from TFTP Service(1)
2783: ERR 13:51:43.395035 JVM: tftpClient SEP7C95F3C93DD3.cnf.xml /usr/ram/SEP7C95F3C93DD3.cnf.xml 550001 1
2784: NOT 13:51:43.398239 xxtpClient: xxtp request rcv'd from /usr/tmp/tftp, srcFile = SEP7C95F3C93DD3.cnf.xml, dstFile = /usr/ram/SEP7C95F3C93DD3.cnf.xml max size = 550001
2785: WRN 13:51:43.402815 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
2786: NOT 13:51:43.406351 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
2787: NOT 13:51:43.406949 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
2788: NOT 13:51:43.407529 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
2789: WRN 13:51:43.408154 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
2790: WRN 13:51:43.412845 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
2791: NOT 13:51:43.416379 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
2792: NOT 13:51:43.416979 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
2793: NOT 13:51:43.417550 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
2794: WRN 13:51:43.418181 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
2795: WRN 13:51:43.423539 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
2796: NOT 13:51:43.427099 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
2797: NOT 13:51:43.427697 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
2798: NOT 13:51:43.428250 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
2799: WRN 13:51:43.428904 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
2800: WRN 13:51:43.433625 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv
2801: NOT 13:51:43.437213 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
2802: NOT 13:51:43.437805 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
2803: NOT 13:51:43.438389 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
2804: WRN 13:51:43.439020 SECD: WARN:getTLInfoFromFile: TL signer's issuer name too big, may truncate
2805: NOT 13:51:43.451621 xxtpClient: auth server - tftpList[0] = ::ffff:192.168.1.130
2806: NOT 13:51:43.452214 xxtpClient: look up server - 0
2807: NOT 13:51:43.453992 SECD: lookupCTL: TFTP SRVR secure
2808: NOT 13:51:43.456414 xxtpClient: secVal = 0x9
2809: NOT 13:51:43.457056 xxtpClient: ::ffff:192.168.1.130 is a secure server
2810: NOT 13:51:43.457569 xxtpClient: retval = SRVR_SECURE
2811: NOT 13:51:43.458073 xxtpClient: Secure file requested
2812: NOT 13:51:43.458560 xxtpClient: authenticated file approved - add .sgn -- SEP7C95F3C93DD3.cnf.xml.sgn
2813: NOT 13:51:43.486658 HTTPCL: downdload will be limited to 537 KB
2814: NOT 13:51:43.491180 HTTPCL: hdr->HTTP/1.1 200 OK
2815: NOT 13:51:43.491771 HTTPCL: hdr->Content-length: 7682
2816: NOT 13:51:43.492282 HTTPCL: hdr->Cache-Control: no-store
2817: NOT 13:51:43.492825 HTTPCL: hdr->Content-type: */*
2818: INF 13:51:43.493329 HTTPCL: initial burst had more than just headers
2819: NOT 13:51:43.510583 SYSMSG: pid 27 (/sbin/httpcl) Normal Exit, status = 100
2820: INF 13:51:43.510623 runtime = 0.050 secs
2821: INF 13:51:43.510642 user cpu = 0.001815860 secs
2822: INF 13:51:43.510661 system cpu = 0.022464590 secs
2823: INF 13:51:43.510675 child user cpu = 0.000000000 secs
2824: INF 13:51:43.510689 child sys cpu = 0.000000000 secs
2825: INF 13:51:43.510709 sys interrupts = 0.002966060 secs for 49 interrupts
2826: INF 13:51:43.510731 total cpu = 0.024280450 secs ( 40% utilization )
2827: NOT 13:51:43.514495 SECD: verifyFile: sgn-verify </usr/ram/SEP7C95F3C93DD3.cnf.xml>, 'name'[SEP7C95F3C93DD3.cnf.xml.sgn]
2828: NOT 13:51:43.516253 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
2829: NOT 13:51:43.516891 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
2830: NOT 13:51:43.518731 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
2831: NOT 13:51:43.519337 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
2832: NOT 13:51:43.561347 SECD: file sgn verify SUCCESS, hdr 368 byte, </usr/ram/SEP7C95F3C93DD3.cnf.xml>
2833: NOT 13:51:43.562256 SECD: verifyFile: file sgn verified </usr/ram/SEP7C95F3C93DD3.cnf.xml>, hdrlen 368
2834: NOT 13:51:43.563142 SECD: verifyFile: hdr ver [2.0], and file not encr, </usr/ram/SEP7C95F3C93DD3.cnf.xml>
2835: NOT 13:51:43.573599 SECD: verifyFile: 7314 byte after hdr strip, </usr/ram/SEP7C95F3C93DD3.cnf.xml>
2836: NOT 13:51:43.574539 SECD: verifyFile: verify SUCCESS </usr/ram/SEP7C95F3C93DD3.cnf.xml>
2837: NOT 13:51:43.577986 xxtpClient: authorize file = 12, isEncr = 0
2838: NOT 13:51:43.584545 SECD: lookupCTL: TFTP SRVR secure
2839: ERR 13:51:44.410980 JVM: TVS server is : IPv4 : 192.168.1.130, IPv6 : , Port : 2445, IPv4 : , IPv6 : , Port : 0, IPv4 : , IPv6 : , Port : 0, IP Mode : 0, IP Preference Mode: 0
2840: NOT 13:51:44.413004 SECD: handleTvsSetReq: IPv6 : , IPv6 : , IPv6 : , IP Mode : 0, IP Pref : 0 DSCP : 96
2841: NOT 13:51:44.414627 JVM: Startup Module Loader|cip.cfg.t:? - ====>123Config handleTftpResponse, status=20 for file=ram/SEP7C95F3C93DD3.cnf.xml
2842: WRN 13:51:44.454678 JVM: Startup Module Loader|cip.xml.ap:parse - Encoding Updated to UTF-8
2843: WRN 13:51:44.456099 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'mlppDomainId' in element '/device' (line=14)
2844: WRN 13:51:44.457533 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'mlppIndicationStatus' in element '/device' (line=15)
2845: WRN 13:51:44.458954 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'preemption' in element '/device' (line=16)
2846: WRN 13:51:44.460483 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'executiveOverridePreemptable' in element '/device' (line=17)
2847: WRN 13:51:44.461960 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'name' in element '/device/devicePool' (line=20)
2848: WRN 13:51:44.463369 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'name' in element '/device/devicePool/dateTimeSetting' (line=22)
2849: WRN 13:51:44.464815 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'name' in element '/device/devicePool/callManagerGroup' (line=28)
2850: WRN 13:51:44.466320 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'tftpDefault' in element '/device/devicePool/callManagerGroup' (line=29)
2851: WRN 13:51:44.467791 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'mgcpPorts' in element '/device/devicePool/callManagerGroup/members/member/callManager/ports' (line=39)
2852: WRN 13:51:44.469241 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'name' in element '/device/devicePool/srstInfo' (line=50)
2853: WRN 13:51:44.471355 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'userModifiable' in element '/device/devicePool/srstInfo' (line=52)
2854: WRN 13:51:44.472768 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'uid' in element '/device/networkLocaleInfo' (line=101)
2855: WRN 13:51:44.474191 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'mobility' in element '/device' (line=141)
2856: WRN 13:51:44.476279 JVM: Startup Module Loader|cip.cfg.XmlConfigParmObject:? - ===getFullConfig return true
2857: WRN 13:51:44.477653 JVM: Startup Module Loader|cip.cfg.XmlConfigParmObject:? - ===getEncrConfig return false
2858: ERR 13:51:44.479053 JVM: Startup Module Loader|cip.cfg.t:? - DirectoryUrl http://192.168.1.130:8080/ccmcip/xmldirectory.jspsecuredirectoryUrl https://192.168.1.130:8443/ccmcip/xmldirectory.jsp
2859: NOT 13:51:44.482571 JVM: Startup Module Loader|cip.cfg.t:? - setConfigTvsProperty IP mode 0IP Pref 0DSCP 96
2860: NOT 13:51:44.483994 JVM: Startup Module Loader|cip.sec.TvsProperty:? - TVS IPv4 - 1 :192.168.1.130TVS IPv6 - 1 :TVS Port - 1:2445TVS IPv4 - 2 :TVS IPv6 - 2 :TVS Port - 2:0TVS IPv4 - 3 :TVS IPv6 - 3 :TVS Port - 3:0IP Address Mode:0IP Preference Mode:0
2861: NOT 13:51:44.485459 JVM: Startup Module Loader|cip.sec.TvsProperty:? - Resolve Tvs Ipv4 Address to 192.168.1.130from hostname 192.168.1.130
2862: NOT 13:51:44.488142 SECD: loadTvsSrvrCfg: Not in EMCC mode.Loading the flash file :/flash0/sec/misc/tvs.conf
2863: NOT 13:51:44.497206 JVM: emccMode=0,localOverride=0, tftpAddr1=, tftpAddr2=,tftpAddr3=,tftpAddr4=
2864: NOT 13:51:44.514807 xxtpClient: request server 0 ---> 192.168.1.130
2865: NOT 13:51:44.515487 xxtpClient: tftp request rcv'd from /usr/tmp/tftp, emccMode =0, emccLocalOverride=0, tempTftp1= , tempTftp2 = , tempTftp3 = , tempTftp4 =
2866: NOT 13:51:44.516641 JVM: setTempTftpAddress, emcc_mode=0,retEmccMode=0,LocalOverride=0,retLocalOverride=0, status=1
2867: NOT 13:51:44.552210 JVM: Startup Module Loader|cip.cfg.t:? - ===not full config or not emcc mode ,so do vendor config
2868: NOT 13:51:44.554652 SECD: clearTFTPList: cleared all TFTP entries
2869: ERR 13:51:44.608735 init: Received image/load request from pid 10, svr = , svr2 = , mtype=10, load = SCCP42.9-3-1-1S.loads
2870: NOT 13:51:44.716957 xxtpClient: request server 1 --->
2871: DBG 13:51:45.022261 cffs_journal_commit() change journal base 0xd60000
2872: DBG 13:51:45.023064 cffs_journal_create() sec 14
2873: DBG 13:51:45.024407 cffs_journal_commit() new journal base 0x1c0000/hdr:4/data:5
2874: ERR 13:51:45.153197 JVM: Startup Module Loader|cip.mmgr.MediaManager:? -
MediaManager propertyChanged called device.settings.config.ipaddressingmode
2875: NOT 13:51:45.154591 JVM: Startup Module Loader|cip.cfg.t:? - Config processConfigNoError() result code=CONFIG_NEED_LOAD_UPDATE
2876: NOT 13:51:45.155924 JVM: Startup Module Loader - Deletion of file Successful/usr/ram/SEP7C95F3C93DD3.cnf.xml
2877: NOT 13:51:45.157351 JVM: Startup Module Loader|cip.cfg.t:? - DELETE ConfigFile:(ram/SEP7C95F3C93DD3.cnf.xml)WAS SUCCESSFUL
2878: NOT 13:51:45.167495 JVM: Startup Module Loader|cip.sccp.c:? - CcApi.statusChanged()=>
resetState=LOAD_UPDATE_RESET_REQUEST
registrationState=NORMAL
isSoftResetPending=false
isDualStackResetPending=false
isRegistrationStopped=false
dhcpOption=0(DHCP_FAST)
deadIpStack=-1
2879: NOT 13:51:45.169032 JVM: Startup Module Loader|cip.sccp.df:? - SccpListener.statusChanged()=>
resetState=LOAD_UPDATE_RESET_REQUEST
registrationState=NORMAL
isSoftResetPending=false
isDualStackResetPending=false
isRegistrationStopped=false
dhcpOption=0(DHCP_FAST)
deadIpStack=-1
restartRequested=false
resetRequested=false
shuttingDown=false
2880: NOT 13:51:45.172040 JVM: Startup Module Loader|cip.sccp.c:? - CcApi.statusChanged()=>
resetState=LOAD_UPDATE_RESET_PENDING
registrationState=NORMAL
isSoftResetPending=false
isDualStackResetPending=false
isRegistrationStopped=false
dhcpOption=0(DHCP_FAST)
deadIpStack=-1
2881: NOT 13:51:45.173601 JVM: Startup Module Loader|cip.sccp.df:? - SccpListener.statusChanged()=>
resetState=LOAD_UPDATE_RESET_PENDING
registrationState=NORMAL
isSoftResetPending=false
isDualStackResetPending=false
isRegistrationStopped=false
dhcpOption=0(DHCP_FAST)
deadIpStack=-1
restartRequested=false
resetRequested=false
shuttingDown=false
2882: NOT 13:51:45.178870 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - --->ConfigManager PropertyChanged: device.system.systemstate
2883: NOT 13:51:45.180395 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - <---ConfigManager PropertyChanged: device.system.systemstate
2884: ERR 13:51:45.181802 JVM: SystemManager n/a SYSTEM_UPGRADING
2885: NOT 13:51:45.256782 INETD: Set IP mode 1
2886: ERR 13:51:45.271874 JVM: SystemManager n/a *systemUnload -->*
More information about the cisco-voip
mailing list