cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
2599
Views
0
Helpful
2
Replies

Slow Registration of 7941, 7942 and 7961 IP Phones

Martin Lowes
Level 1
Level 1

Hi,

Since upgrading to CUCM version 9.1.1a we are experiencing slow registration of IP Phones to CUCM server. I have collected a log from the IP Phone and it seems to show there is a 30 runtime. I have shown below :-

6269: NOT 11:24:59.904674 SECD: tlRequestFile: Socket 9 connected to /usr/tmp/xxtpClientSock

6270: NOT 11:24:59.905978 SECD: tlRequestFile: Request ITLSEP001E7AC4B968.tlv

6271: NOT 11:24:59.907484 xxtpClient: xxtp request rcv'd from /usr/tmp/ctlSock, srcFile = ITLSEP001E7AC4B968.tlv, dstFile = /usr/tmp/ITLFile.tlv

6272: NOT 11:24:59.921971 xxtpClient: auth server - tftpList[0] = ::ffff:10.15.11.2

6273: NOT 11:24:59.922934 xxtpClient: look up server - 0

6274: NOT 11:24:59.926076 SECD: lookupCTL: TFTP SRVR secure

6275: NOT 11:24:59.932188 xxtpClient: secVal = 0x9

6276: NOT 11:24:59.933311 xxtpClient: ::ffff:10.15.11.2 is a secure server

6277: NOT 11:24:59.934156 xxtpClient: retval = SRVR_SECURE

6278: NOT 11:24:59.935084 xxtpClient: Non secure file requested

6279: ERR 11:25:30.501705 HTTPCL: connect() failed

6280: NOT 11:25:30.514297 SYSMSG: pid 24 (/sbin/httpcl) Normal Exit, status = 102

6281: INF 11:25:30.514371          runtime = 30.570 secs

6282: INF 11:25:30.514417         user cpu = 0.001773031 secs

6283: INF 11:25:30.514461       system cpu = 0.035310162 secs

6284: INF 11:25:30.514497   child user cpu = 0.000000000 secs

6285: INF 11:25:30.514534     child sys cpu = 0.000000000 secs

6286: INF 11:25:30.514582   sys interrupts = 0.233415672 secs for 3250 interrupts

6287: INF 11:25:30.514629 total cpu = 0.037083193 secs ( 0% utilization )

6288: WRN 11:25:30.514049 xxtpClient: HTTP failed with code 102

6289: NOT 11:25:30.560748 TFTP: [18]:Requesting ITLSEP001E7AC4B968.tlv from 10.15.11.2

6290: NOT 11:25:30.578507 TFTP: [18]:Finished --> rcvd 4125 bytes

6291: NOT 11:25:30.581597 SECD: tlRequestFile: tftp Status 0 rcv'd

6292: NOT 11:25:30.587548 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6293: NOT 11:25:30.588549 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)

6294: NOT 11:25:30.589447 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6295: NOT 11:25:30.591362 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6296: NOT 11:25:30.592261 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)

6297: NOT 11:25:30.593158 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6298: NOT 11:25:30.621445 SECD: validate_file_envelope: File sign verify SUCCESS; header length <232>

6299: NOT 11:25:30.622474 SECD: TL_ValidateSignedTL: new TL matches old, not updating

6300: NOT 11:25:30.624063 SECD: updateCTL: finished ITLFile update

6301: NOT 11:25:30.675036 JVM: --> Properties JNI: SignalApp() handling SIGIPCFG. msgCode=256 value=0

6302: NOT 11:25:30.703651 JVM: <-- Properties JNI: SignalApp()

6303: ERR 11:25:30.707359 JVM: Startup Module Loader|cip.sec.t:---------updateCtl,result=0 - -------------invoke proxy

6304: ERR 11:25:30.734295 JVM: Startup Module Loader|cip.sec.t:---------updateCtl,result=0 - -------------invoke dispatcher

6305: NOT 11:25:30.736560 JVM: Startup Module Loader|cip.cfg.k:? - ConfigManager updateCTL() rc=0 retryCount=0

6306: WRN 11:25:30.749935 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv

6307: NOT 11:25:30.757821 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6308: NOT 11:25:30.758764 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)

6309: NOT 11:25:30.759707 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6310: NOT 11:25:30.839047 JVM: Startup Module Loader|cip.cfg.t:? - Requesting CONFIG file from TFTP Service(1)

6311: WRN 11:25:30.846450 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv

6312: NOT 11:25:30.852591 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6313: NOT 11:25:30.853604 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)

6314: NOT 11:25:30.854499 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6315: WRN 11:25:30.863850 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv

6316: NOT 11:25:30.869481 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6317: NOT 11:25:30.870612 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)

6318: NOT 11:25:30.871495 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6319: WRN 11:25:30.879269 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv

6320: NOT 11:25:30.885143 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6321: NOT 11:25:30.886138 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)

6322: NOT 11:25:30.887037 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6323: WRN 11:25:30.904509 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv

6324: NOT 11:25:30.910618 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6325: NOT 11:25:30.911571 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)

6326: NOT 11:25:30.912518 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6327: ERR 11:25:30.922532 JVM: tftpClient SEP001E7AC4B968.cnf.xml /usr/ram/SEP001E7AC4B968.cnf.xml 550001 1

6328: NOT 11:25:30.928561 xxtpClient: xxtp request rcv'd from /usr/tmp/tftp, srcFile = SEP001E7AC4B968.cnf.xml, dstFile = /usr/ram/SEP001E7AC4B968.cnf.xml max size = 550001

6329: NOT 11:25:30.947568 xxtpClient: auth server - tftpList[0] = ::ffff:10.15.11.2

6330: NOT 11:25:30.948478 xxtpClient: look up server - 0

6331: NOT 11:25:30.953529 SECD: lookupCTL: TFTP SRVR secure

6332: NOT 11:25:30.957828 xxtpClient: secVal = 0x9

6333: NOT 11:25:30.958896 xxtpClient: ::ffff:10.15.11.2 is a secure server

6334: NOT 11:25:30.959819 xxtpClient: retval = SRVR_SECURE

6335: NOT 11:25:30.960996 xxtpClient: Secure file requested

6336: NOT 11:25:30.961845 xxtpClient: authenticated file approved - add .sgn -- SEP001E7AC4B968.cnf.xml.sgn

6337: NOT 11:25:30.992193 HTTPCL: downdload will be limited to 537 KB

6338: DBG 11:25:34.756457 cffs_journal_commit() change journal base 0xea0000

6339: DBG 11:25:34.758241 cffs_journal_create() sec 21

6340: DBG 11:25:34.760764 cffs_journal_commit() new journal base 0x150000/hdr:4/data:5

6341: DBG 11:25:34.809890 cffs_gc(): runing gc 1

6342: ERR 11:26:01.500467 HTTPCL: connect() failed

6343: NOT 11:26:01.511392 SYSMSG: pid 25 (/sbin/httpcl) Normal Exit, status = 102

6344: INF 11:26:01.511467          runtime = 30.540 secs

6345: INF 11:26:01.511514         user cpu = 0.001933971 secs

6346: INF 11:26:01.511558       system cpu = 0.024278612 secs

6347: INF 11:26:01.511596   child user cpu = 0.000000000 secs

6348: INF 11:26:01.511634     child sys cpu = 0.000000000 secs

6349: INF 11:26:01.511684   sys interrupts = 0.215862597 secs for 3306 interrupts

6350: INF 11:26:01.511731 total cpu = 0.026212583 secs ( 0% utilization )

6351: WRN 11:26:01.515341 xxtpClient: HTTP failed with code 102

6352: NOT 11:26:01.560555 TFTP: [10]:Requesting SEP001E7AC4B968.cnf.xml.sgn from 10.15.11.2 with size limit of 550001

6353: NOT 11:26:01.595481 TFTP: [10]:Finished --> rcvd 8777 bytes

6354: NOT 11:26:01.604376 SECD: verifyFile: sgn-verify </usr/ram/SEP001E7AC4B968.cnf.xml>, 'name'[SEP001E7AC4B968.cnf.xml.sgn]

6355: NOT 11:26:01.607523 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6356: NOT 11:26:01.608449 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6357: NOT 11:26:01.612209 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6358: NOT 11:26:01.613112 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6359: NOT 11:26:01.664599 SECD: file sgn verify SUCCESS, hdr 248 byte, </usr/ram/SEP001E7AC4B968.cnf.xml>

6360: NOT 11:26:01.665830 SECD: verifyFile: file sgn verified </usr/ram/SEP001E7AC4B968.cnf.xml>, hdrlen 248

6361: NOT 11:26:01.666892 SECD: verifyFile: hdr ver [2.0], and file not encr, </usr/ram/SEP001E7AC4B968.cnf.xml>

6362: NOT 11:26:01.684797 SECD: verifyFile: 8529 byte after hdr strip, </usr/ram/SEP001E7AC4B968.cnf.xml>

6363: NOT 11:26:01.685831 SECD: verifyFile: verify SUCCESS </usr/ram/SEP001E7AC4B968.cnf.xml>

6364: NOT 11:26:01.699630 xxtpClient: authorize file = 12, isEncr = 0

6365: NOT 11:26:01.708178 SECD: lookupCTL: TFTP SRVR secure

6366: ERR 11:26:02.738045 JVM: TVS server is : IPv4 : 10.15.11.2, IPv6 : , Port : 2445, IPv4 : 10.15.11.3, IPv6 : , Port : 2445, IPv4 : , IPv6 : , Port : 0, IP Mode : 0, IP Preference Mode: 0

6367: NOT 11:26:02.742813 SECD: handleTvsSetReq: IPv6 : , IPv6 : , IPv6 : , IP Mode : 0, IP Pref : 0 DSCP : 96

6368: NOT 11:26:02.745383 JVM: Startup Module Loader|cip.cfg.t:? - ====>123Config handleTftpResponse, status=0 for file=ram/SEP001E7AC4B968.cnf.xml

6369: WRN 11:26:02.776510 JVM: Startup Module Loader|cip.xml.ap:parse - Encoding Updated to UTF-8

6370: WRN 11:26:02.778697 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'mlppDomainId' in element '/device' (line=14)

6371: WRN 11:26:02.782322 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'mlppIndicationStatus' in element '/device' (line=15)

6372: WRN 11:26:02.784508 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'preemption' in element '/device' (line=16)

6373: WRN 11:26:02.786711 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'executiveOverridePreemptable' in element '/device' (line=17)

6374: WRN 11:26:02.788921 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'name' in element '/device/devicePool' (line=20)

6375: WRN 11:26:02.791122 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'name' in element '/device/devicePool/dateTimeSetting' (line=22)

6376: WRN 11:26:02.793341 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'name' in element '/device/devicePool/callManagerGroup' (line=34)

6377: WRN 11:26:02.795555 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'tftpDefault' in element '/device/devicePool/callManagerGroup' (line=35)

6378: WRN 11:26:02.797763 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'mgcpPorts' in element '/device/devicePool/callManagerGroup/members/member/callManager/ports' (line=45)

6379: WRN 11:26:02.801512 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'mgcpPorts' in element '/device/devicePool/callManagerGroup/members/member/callManager/ports' (line=61)

6380: WRN 11:26:02.803686 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'name' in element '/device/devicePool/srstInfo' (line=72)

6381: WRN 11:26:02.805883 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'userModifiable' in element '/device/devicePool/srstInfo' (line=74)

6382: WRN 11:26:02.808097 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'uid' in element '/device/networkLocaleInfo' (line=127)

6383: WRN 11:26:02.810350 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'mobility' in element '/device' (line=167)

6384: WRN 11:26:02.812477 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown attribute 'serviceProfileFile' in element '/device/userId' (line=177)

6385: WRN 11:26:02.815698 JVM: Startup Module Loader|cip.cfg.XmlConfigParmObject:? - ===getFullConfig return true

6386: WRN 11:26:02.817781 JVM: Startup Module Loader|cip.cfg.XmlConfigParmObject:? - ===getEncrConfig return false

6387: ERR 11:26:02.819868 JVM: Startup Module Loader|cip.cfg.t:? - DirectoryUrl http://10.15.11.2:8080/ccmcip/xmldirectory.jspsecuredirectoryUrl http://10.15.11.2:8080/ccmcip/xmldirectory.jsp

6388: NOT 11:26:02.826579 JVM: Startup Module Loader|cip.cfg.t:? - setConfigTvsProperty IP mode 0IP Pref 0DSCP 96

6389: NOT 11:26:02.828791 JVM: Startup Module Loader|cip.sec.TvsProperty:? - TVS IPv4 - 1 :10.15.11.2TVS IPv6 - 1 :TVS Port - 1:2445TVS IPv4 - 2 :10.15.11.3TVS IPv6 - 2 :TVS Port - 2:2445TVS IPv4 - 3 :TVS IPv6 - 3 :TVS Port - 3:0IP Address Mode:0IP Preference Mode:0

6390: NOT 11:26:02.831099 JVM: Startup Module Loader|cip.sec.TvsProperty:? - Resolve Tvs Ipv4 Address to 10.15.11.2from hostname 10.15.11.2

6391: NOT 11:26:02.833305 JVM: Startup Module Loader|cip.sec.TvsProperty:? - Resolve Tvs Ipv4 Address to 10.15.11.3from hostname 10.15.11.3

6392: NOT 11:26:02.853718 SECD: loadTvsSrvrCfg: Not in EMCC mode.Loading the flash file :/flash0/sec/misc/tvs.conf

6393: NOT 11:26:02.869179 JVM: emccMode=0,localOverride=0, tftpAddr1=, tftpAddr2=,tftpAddr3=,tftpAddr4=

6394: NOT 11:26:02.881678 xxtpClient: request server 0 ---> 10.15.11.2

6395: NOT 11:26:02.882831 xxtpClient: tftp request rcv'd from /usr/tmp/tftp, emccMode =0, emccLocalOverride=0, tempTftp1= , tempTftp2 = , tempTftp3 = , tempTftp4 =

6396: NOT 11:26:02.884756 JVM: setTempTftpAddress, emcc_mode=0,retEmccMode=0,LocalOverride=0,retLocalOverride=0, status=1

6397: NOT 11:26:02.989176 JVM: Startup Module Loader|cip.cfg.t:? - ===not full config or not emcc mode ,so do vendor config

6398: NOT 11:26:02.994962 SECD: clearTFTPList: cleared all TFTP entries

6399: ERR 11:26:03.255663 JVM: Startup Module Loader|cip.mmgr.MediaManager:? -

MediaManager propertyChanged called device.settings.config.ipaddressingmode

6400: ERR 11:26:03.896806 JVM: Startup Module Loader|cip.cfg.t:? - Delete of sshUserInfo file failed

6401: ERR 11:26:03.898954 JVM: Startup Module Loader|cip.cfg.t:? - informationUrl is http://10.15.11.2:8080/ccmcip/GetTelecasterHelpText.jsp

6402: ERR 11:26:03.902494 JVM: Startup Module Loader|cip.cfg.t:? - directoriesUrl is http://10.15.11.2:8080/ccmcip/xmldirectory.jsp

6403: ERR 11:26:03.904578 JVM: Startup Module Loader|cip.cfg.t:? - messagesUrl is

6404: ERR 11:26:03.906724 JVM: Startup Module Loader|cip.cfg.t:? - servicesUrl is http://10.15.11.2:8080/ccmcip/getservicesmenu.jsp

6405: ERR 11:26:03.908828 JVM: Startup Module Loader|cip.cfg.t:? - authenticationUrl is http://10.15.11.2:8080/ccmcip/authenticate.jsp

6406: ERR 11:26:03.910989 JVM: Startup Module Loader|cip.cfg.t:? - idleUrl is

6407: ERR 11:26:03.913024 JVM: Startup Module Loader|cip.cfg.t:? - messagesUrl is null

6408: ERR 11:26:03.915101 JVM: Startup Module Loader|cip.cfg.t:? - After set servicesUrl device.settings.config.servicesurl value http://10.15.11.2:8080/ccmcip/getservicesmenu.jsp

6409: ERR 11:26:03.917301 JVM: Startup Module Loader|cip.cfg.t:? - After set info url device.settings.config.informationurl value http://10.15.11.2:8080/ccmcip/GetTelecasterHelpText.jsp

6410: ERR 11:26:03.919456 JVM: Startup Module Loader|cip.cfg.t:? - After set dir Url device.settings.config.directoriesurl value http://10.15.11.2:8080/ccmcip/xmldirectory.jsp

6411: ERR 11:26:03.922925 JVM: Startup Module Loader|cip.cfg.t:? - idle Url of null

6412: ERR 11:26:03.925108 JVM: Startup Module Loader|cip.cfg.t:? - After set sec auth Url device.settings.config.authenticationurl value http://10.15.11.2:8080/ccmcip/authenticate.jsp

6413: ERR 11:26:03.927265 JVM: Startup Module Loader|VendorConfig:? - vendorconfig : setConfigProperties

6414: ERR 11:26:03.929322 JVM: Startup Module Loader|VendorConfig:? -

About to set WebAccess

6415: WRN 11:26:03.931539 JVM: Startup Module Loader|DisplayTask:? - resetCurrentPowerState(): Energywise is disabled

6416: WRN 11:26:03.933744 JVM: Startup Module Loader|DisplayTask:? - resetCurrentPowerState(): Energywise null domain

6417: WRN 11:26:03.935811 JVM: Startup Module Loader|DisplayTask:? - resetCurrentPowerState(): Energywise null secret

6418: WRN 11:26:03.937944 JVM: Startup Module Loader|DisplayTask:? - resetCurrentPowerState(): no time changed, so needn't reschedule existing timer

6419: ERR 11:26:03.941456 JVM: Startup Module Loader|cip.setg.ScreenSaveProperty:? - Config file field <displayOnTime> is incorrectly formatted. Defaulting.

6420: ERR 11:26:03.943628 JVM: Startup Module Loader|cip.setg.ScreenSaveProperty:? - Config file field <displayIdleTimeout> is incorrectly formatted. Defaulting.

6421: ERR 11:26:03.945755 JVM: Startup Module Loader|VendorConfig:? - WebAccess true

6422: ERR 11:26:03.947759 JVM: Startup Module Loader|VendorConfig:? - WebProtocol 0

6423: NOT 11:26:03.949755 JVM: Startup Module Loader|VendorConfig:? - Setting 80-bit-srtp to false

6424: ERR 11:26:03.963461 JVM: Startup Module Loader|cip.mmgr.MediaManager:? -

MediaManager propertyChanged called device.settings.config.vendorconfig.sidetonelevel

6425: NOT 11:26:03.969394 JVM: Startup Module Loader|VendorConfig:? - Setting RTCP to false

6426: NOT 11:26:03.973337 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.localization.userlocale.charset value=iso-8859-1

6427: NOT 11:26:03.976339 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.localization.userlocale.languagecode value=en_GB

6428: NOT 11:26:03.988815 SECD: setSecMode: sec mode set to NONE (was NONE)

6429: NOT 11:26:05.131452 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.userid value=3423

6430: NOT 11:26:05.306058 SECD: clearSRSTList: cleared all SRST entries

6431: NOT 11:26:05.313118 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.userid value=3423

6432: NOT 11:26:05.315509 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.phoneservices value=cip.props.XmlProperty@bc3626

6433: WRN 11:26:05.531645 SECD: WARN:cancelCapfOp: CAPF not in use, user cancel ignored

6434: NOT 11:26:05.532768 SECD: clearCapfList: CAPF table cleared

6435: NOT 11:26:05.544999 SECD: clearCapfList: CAPF table cleared

6436: NOT 11:26:05.546034 SECD: addCapfEntry: adding new CAPF entry from Cfg:

6437: NOT 11:26:05.547025 SECD:   CAPF tbl idx : 0

6438: ERR 11:26:05.547972 SECD: EROR:addCapfEntry: DSCP VALUE 96

6439: NOT 11:26:05.548855 SECD:   new entry from Cfg :

6440: NOT 11:26:05.549734 SECD:       ipv4 addr     : 10.15.11.2

6441: NOT 11:26:05.550676 SECD:       ipv6 addr     : n/a

6442: NOT 11:26:05.551578 SECD:       port         : 3804

6443: NOT 11:26:05.552494 SECD:       priority     : 146915332

6444: NOT 11:26:05.553386 SECD:       ipMode     : 0 (default)

6445: NOT 11:26:05.554336 SECD:       DSCP     : 96

6446: NOT 11:26:05.637648 JVM: Startup Module Loader|cip.cfg.t:? - CUCM in config file: #0 XmlCallAgentObject: IPv4-name=[10.15.11.2] IPv6-name=[] port=2000 priority=0

6447: NOT 11:26:05.639864 JVM: Startup Module Loader|cip.cfg.t:? - CUCM in config file: #1 XmlCallAgentObject: IPv4-name=[10.15.11.3] IPv6-name=[] port=2000 priority=1

6448: NOT 11:26:05.643524 JVM: Startup Module Loader|cip.cfg.t:? - CUCM in config file: #2 XmlCallAgentObject: IPv4-name=[] IPv6-name=[] port=0 priority=32767

6449: NOT 11:26:05.645754 JVM: Startup Module Loader|cip.cfg.t:? - CUCM in config file: #3 XmlCallAgentObject: IPv4-name=[] IPv6-name=[] port=0 priority=32767

6450: NOT 11:26:05.647911 JVM: Startup Module Loader|cip.cfg.t:? - CUCM in config file: #4 XmlCallAgentObject: IPv4-name=[] IPv6-name=[] port=0 priority=32767

6451: ERR 11:26:05.653401 JVM: Startup Module Loader|cip.sec.CapfProperty:? - Failed to resolve Capf Ipv6 Address with hostname

6452: NOT 11:26:05.656579 JVM: Startup Module Loader|cip.sec.CapfProperty:? - CapfProp 96

6453: NOT 11:26:05.658761 JVM: Startup Module Loader|cip.cfg.t:? - Config processConfigNoError() result code=CONFIG_FILE_NO_CHANGE

6454: NOT 11:26:05.663965 SECD: setSecMode: sec mode set to NONE (was NONE)

6455: NOT 11:26:05.905586 JVM: Startup Module Loader - Deletion of file Successful/usr/ram/SEP001E7AC4B968.cnf.xml

6456: NOT 11:26:05.907681 JVM: Startup Module Loader|cip.cfg.t:? - DELETE ConfigFile:(ram/SEP001E7AC4B968.cnf.xml)WAS SUCCESSFUL

6457: NOT 11:26:05.909835 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - --->ConfigManager PropertyChanged: device.config.io.dscp.config

6458: NOT 11:26:05.913267 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - <---ConfigManager PropertyChanged: device.config.io.dscp.config

6459: NOT 11:26:05.916498 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=true

shuttingDown=true

6460: WRN 11:26:05.919529 JVM: Startup Module Loader|cip.sccp.SccpEnhancedAlarmInfo:propertyChanged - name=device.settings.fullyregistered value=false

6461: ERR 11:26:05.928210 SECD: EROR:lookupCTL: UCM not in TL, NOT_FOUND

6462: WRN 11:26:05.956742 SECD: WARN:lookupSRST: SRST in list, no valid cer

6269: NOT 11:24:59.904674 SECD: tlRequestFile: Socket 9 connected to /usr/tmp/xxtpClientSock

6270: NOT 11:24:59.905978 SECD: tlRequestFile: Request ITLSEP001E7AC4B968.tlv

6271: NOT 11:24:59.907484 xxtpClient: xxtp request rcv'd from /usr/tmp/ctlSock, srcFile = ITLSEP001E7AC4B968.tlv, dstFile = /usr/tmp/ITLFile.tlv

6272: NOT 11:24:59.921971 xxtpClient: auth server - tftpList[0] = ::ffff:10.15.11.2

6273: NOT 11:24:59.922934 xxtpClient: look up server - 0

6274: NOT 11:24:59.926076 SECD: lookupCTL: TFTP SRVR secure

6275: NOT 11:24:59.932188 xxtpClient: secVal = 0x9

6276: NOT 11:24:59.933311 xxtpClient: ::ffff:10.15.11.2 is a secure server

6277: NOT 11:24:59.934156 xxtpClient: retval = SRVR_SECURE

6278: NOT 11:24:59.935084 xxtpClient: Non secure file requested 

6279: ERR 11:25:30.501705 HTTPCL: connect() failed

6280: NOT 11:25:30.514297 SYSMSG: pid 24 (/sbin/httpcl) Normal Exit, status = 102

6281: INF 11:25:30.514371           runtime = 30.570 secs

6282: INF 11:25:30.514417          user cpu = 0.001773031 secs

6283: INF 11:25:30.514461        system cpu = 0.035310162 secs

6284: INF 11:25:30.514497    child user cpu = 0.000000000 secs

6285: INF 11:25:30.514534     child sys cpu = 0.000000000 secs

6286: INF 11:25:30.514582    sys interrupts = 0.233415672 secs for 3250 interrupts

6287: INF 11:25:30.514629 total cpu = 0.037083193 secs ( 0% utilization )

6288: WRN 11:25:30.514049 xxtpClient: HTTP failed with code 102

6289: NOT 11:25:30.560748 TFTP: [18]:Requesting ITLSEP001E7AC4B968.tlv from 10.15.11.2

6290: NOT 11:25:30.578507 TFTP: [18]:Finished --> rcvd 4125 bytes

6291: NOT 11:25:30.581597 SECD: tlRequestFile: tftp Status 0 rcv'd

6292: NOT 11:25:30.587548 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6293: NOT 11:25:30.588549 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)

6294: NOT 11:25:30.589447 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6295: NOT 11:25:30.591362 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6296: NOT 11:25:30.592261 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)

6297: NOT 11:25:30.593158 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6298: NOT 11:25:30.621445 SECD: validate_file_envelope: File sign verify SUCCESS; header length <232>

6299: NOT 11:25:30.622474 SECD: TL_ValidateSignedTL: new TL matches old, not updating

6300: NOT 11:25:30.624063 SECD: updateCTL: finished ITLFile update

6301: NOT 11:25:30.675036 JVM:  --> Properties JNI: SignalApp() handling SIGIPCFG. msgCode=256 value=0

6302: NOT 11:25:30.703651 JVM:  <-- Properties JNI: SignalApp()

6303: ERR 11:25:30.707359 JVM: Startup Module Loader|cip.sec.t:---------updateCtl,result=0 - -------------invoke proxy

6304: ERR 11:25:30.734295 JVM: Startup Module Loader|cip.sec.t:---------updateCtl,result=0 - -------------invoke dispatcher

6305: NOT 11:25:30.736560 JVM: Startup Module Loader|cip.cfg.k:? - ConfigManager updateCTL() rc=0 retryCount=0

6306: WRN 11:25:30.749935 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv

6307: NOT 11:25:30.757821 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6308: NOT 11:25:30.758764 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)

6309: NOT 11:25:30.759707 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6310: NOT 11:25:30.839047 JVM: Startup Module Loader|cip.cfg.t:? - Requesting CONFIG file from TFTP Service(1)

6311: WRN 11:25:30.846450 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv

6312: NOT 11:25:30.852591 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6313: NOT 11:25:30.853604 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)

6314: NOT 11:25:30.854499 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6315: WRN 11:25:30.863850 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv

6316: NOT 11:25:30.869481 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6317: NOT 11:25:30.870612 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)

6318: NOT 11:25:30.871495 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6319: WRN 11:25:30.879269 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv

6320: NOT 11:25:30.885143 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6321: NOT 11:25:30.886138 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)

6322: NOT 11:25:30.887037 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6323: WRN 11:25:30.904509 SECD: WARN:getTLInfoFromFile: ** phone has no TL file /flash0/sec/ctl//CTLFile.tlv

6324: NOT 11:25:30.910618 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6325: NOT 11:25:30.911571 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)

6326: NOT 11:25:30.912518 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6327: ERR 11:25:30.922532 JVM: tftpClient SEP001E7AC4B968.cnf.xml /usr/ram/SEP001E7AC4B968.cnf.xml 550001 1

6328: NOT 11:25:30.928561 xxtpClient: xxtp request rcv'd from /usr/tmp/tftp, srcFile = SEP001E7AC4B968.cnf.xml, dstFile = /usr/ram/SEP001E7AC4B968.cnf.xml max size = 550001

6329: NOT 11:25:30.947568 xxtpClient: auth server - tftpList[0] = ::ffff:10.15.11.2

6330: NOT 11:25:30.948478 xxtpClient: look up server - 0

6331: NOT 11:25:30.953529 SECD: lookupCTL: TFTP SRVR secure

6332: NOT 11:25:30.957828 xxtpClient: secVal = 0x9

6333: NOT 11:25:30.958896 xxtpClient: ::ffff:10.15.11.2 is a secure server

6334: NOT 11:25:30.959819 xxtpClient: retval = SRVR_SECURE

6335: NOT 11:25:30.960996 xxtpClient: Secure file requested

6336: NOT 11:25:30.961845 xxtpClient: authenticated file approved - add .sgn -- SEP001E7AC4B968.cnf.xml.sgn 

6337: NOT 11:25:30.992193 HTTPCL: downdload will be limited to 537 KB

6338: DBG 11:25:34.756457 cffs_journal_commit() change journal base 0xea0000

6339: DBG 11:25:34.758241 cffs_journal_create() sec 21

6340: DBG 11:25:34.760764 cffs_journal_commit() new journal base 0x150000/hdr:4/data:5

6341: DBG 11:25:34.809890 cffs_gc(): runing gc 1

6342: ERR 11:26:01.500467 HTTPCL: connect() failed

6343: NOT 11:26:01.511392 SYSMSG: pid 25 (/sbin/httpcl) Normal Exit, status = 102

6344: INF 11:26:01.511467           runtime = 30.540 secs

6345: INF 11:26:01.511514          user cpu = 0.001933971 secs

6346: INF 11:26:01.511558        system cpu = 0.024278612 secs

6347: INF 11:26:01.511596    child user cpu = 0.000000000 secs

6348: INF 11:26:01.511634     child sys cpu = 0.000000000 secs

6349: INF 11:26:01.511684    sys interrupts = 0.215862597 secs for 3306 interrupts

6350: INF 11:26:01.511731 total cpu = 0.026212583 secs ( 0% utilization )

6351: WRN 11:26:01.515341 xxtpClient: HTTP failed with code 102

6352: NOT 11:26:01.560555 TFTP: [10]:Requesting SEP001E7AC4B968.cnf.xml.sgn from 10.15.11.2 with size limit of 550001

6353: NOT 11:26:01.595481 TFTP: [10]:Finished --> rcvd 8777 bytes

6354: NOT 11:26:01.604376 SECD: verifyFile: sgn-verify </usr/ram/SEP001E7AC4B968.cnf.xml>, 'name'[SEP001E7AC4B968.cnf.xml.sgn]

6355: NOT 11:26:01.607523 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6356: NOT 11:26:01.608449 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6357: NOT 11:26:01.612209 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15

6358: NOT 11:26:01.613112 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)

6359: NOT 11:26:01.664599 SECD: file sgn verify SUCCESS, hdr 248 byte, </usr/ram/SEP001E7AC4B968.cnf.xml>

6360: NOT 11:26:01.665830 SECD: verifyFile: file sgn verified </usr/ram/SEP001E7AC4B968.cnf.xml>, hdrlen 248

6361: NOT 11:26:01.666892 SECD: verifyFile: hdr ver [2.0], and file not encr, </usr/ram/SEP001E7AC4B968.cnf.xml>

6362: NOT 11:26:01.684797 SECD: verifyFile: 8529 byte after hdr strip, </usr/ram/SEP001E7AC4B968.cnf.xml>

6363: NOT 11:26:01.685831 SECD: verifyFile: verify SUCCESS </usr/ram/SEP001E7AC4B968.cnf.xml>

6364: NOT 11:26:01.699630 xxtpClient: authorize file = 12, isEncr = 0

6365: NOT 11:26:01.708178 SECD: lookupCTL: TFTP SRVR secure

6366: ERR 11:26:02.738045 JVM: TVS server is : IPv4 : 10.15.11.2, IPv6 : , Port : 2445, IPv4 : 10.15.11.3, IPv6 : , Port : 2445, IPv4 : , IPv6 : , Port : 0, IP Mode : 0, IP Preference Mode: 0

6367: NOT 11:26:02.742813 SECD: handleTvsSetReq: IPv6 : , IPv6 : , IPv6 : , IP Mode : 0, IP Pref : 0 DSCP : 96

6368: NOT 11:26:02.745383 JVM: Startup Module Loader|cip.cfg.t:? - ====>123Config handleTftpResponse, status=0 for file=ram/SEP001E7AC4B968.cnf.xml

6369: WRN 11:26:02.776510 JVM: Startup Module Loader|cip.xml.ap:parse - Encoding Updated to UTF-8

6370: WRN 11:26:02.778697 JVM: Startup Module Loader|cip.xml.ap:  - XML Parser Warning: Unknown element 'mlppDomainId' in element '/device' (line=14)

6371: WRN 11:26:02.782322 JVM: Startup Module Loader|cip.xml.ap:  - XML Parser Warning: Unknown element 'mlppIndicationStatus' in element '/device' (line=15)

6372: WRN 11:26:02.784508 JVM: Startup Module Loader|cip.xml.ap:  - XML Parser Warning: Unknown element 'preemption' in element '/device' (line=16)

6373: WRN 11:26:02.786711 JVM: Startup Module Loader|cip.xml.ap:  - XML Parser Warning: Unknown element 'executiveOverridePreemptable' in element '/device' (line=17)

6374: WRN 11:26:02.788921 JVM: Startup Module Loader|cip.xml.ap:  - XML Parser Warning: Unknown element 'name' in element '/device/devicePool' (line=20)

6375: WRN 11:26:02.791122 JVM: Startup Module Loader|cip.xml.ap:  - XML Parser Warning: Unknown element 'name' in element '/device/devicePool/dateTimeSetting' (line=22)

6376: WRN 11:26:02.793341 JVM: Startup Module Loader|cip.xml.ap:  - XML Parser Warning: Unknown element 'name' in element '/device/devicePool/callManagerGroup' (line=34)

6377: WRN 11:26:02.795555 JVM: Startup Module Loader|cip.xml.ap: - XML Parser Warning: Unknown element 'tftpDefault' in element '/device/devicePool/callManagerGroup' (line=35)

6378: WRN 11:26:02.797763 JVM: Startup Module Loader|cip.xml.ap:  - XML Parser Warning: Unknown element 'mgcpPorts' in element '/device/devicePool/callManagerGroup/members/member/callManager/ports' (line=45)

6379: WRN 11:26:02.801512 JVM: Startup Module Loader|cip.xml.ap:  - XML Parser Warning: Unknown element 'mgcpPorts' in element '/device/devicePool/callManagerGroup/members/member/callManager/ports' (line=61)

6380: WRN 11:26:02.803686 JVM: Startup Module Loader|cip.xml.ap:  - XML Parser Warning: Unknown element 'name' in element '/device/devicePool/srstInfo' (line=72)

6381: WRN 11:26:02.805883 JVM: Startup Module Loader|cip.xml.ap:  - XML Parser Warning: Unknown element 'userModifiable' in element '/device/devicePool/srstInfo' (line=74)

6382: WRN 11:26:02.808097 JVM: Startup Module Loader|cip.xml.ap:  - XML Parser Warning: Unknown element 'uid' in element '/device/networkLocaleInfo' (line=127)

6383: WRN 11:26:02.810350 JVM: Startup Module Loader|cip.xml.ap:  - XML Parser Warning: Unknown element 'mobility' in element '/device' (line=167)

6384: WRN 11:26:02.812477 JVM: Startup Module Loader|cip.xml.ap:  - XML Parser Warning: Unknown attribute 'serviceProfileFile' in element '/device/userId' (line=177)

6385: WRN 11:26:02.815698 JVM: Startup Module Loader|cip.cfg.XmlConfigParmObject:? - ===getFullConfig return true

6386: WRN 11:26:02.817781 JVM: Startup Module Loader|cip.cfg.XmlConfigParmObject:? - ===getEncrConfig return false

6387: ERR 11:26:02.819868 JVM: Startup Module Loader|cip.cfg.t:? - DirectoryUrl http://10.15.11.2:8080/ccmcip/xmldirectory.jspsecuredirectoryUrl http://10.15.11.2:8080/ccmcip/xmldirectory.jsp

6388: NOT 11:26:02.826579 JVM: Startup Module Loader|cip.cfg.t:? - setConfigTvsProperty IP mode 0IP Pref 0DSCP 96

6389: NOT 11:26:02.828791 JVM: Startup Module Loader|cip.sec.TvsProperty:? - TVS IPv4 - 1 :10.15.11.2TVS IPv6 - 1 :TVS Port - 1:2445TVS IPv4 - 2 :10.15.11.3TVS IPv6 - 2 :TVS Port - 2:2445TVS IPv4 - 3 :TVS IPv6 - 3 :TVS Port - 3:0IP Address Mode:0IP Preference Mode:0

6390: NOT 11:26:02.831099 JVM: Startup Module Loader|cip.sec.TvsProperty:? - Resolve Tvs Ipv4 Address to 10.15.11.2from hostname 10.15.11.2

6391: NOT 11:26:02.833305 JVM: Startup Module Loader|cip.sec.TvsProperty:? - Resolve Tvs Ipv4 Address to 10.15.11.3from hostname 10.15.11.3

6392: NOT 11:26:02.853718 SECD: loadTvsSrvrCfg: Not in EMCC mode.Loading the flash file :/flash0/sec/misc/tvs.conf

6393: NOT 11:26:02.869179 JVM: emccMode=0,localOverride=0, tftpAddr1=, tftpAddr2=,tftpAddr3=,tftpAddr4=

6394: NOT 11:26:02.881678 xxtpClient: request server 0 ---> 10.15.11.2

6395: NOT 11:26:02.882831 xxtpClient: tftp request rcv'd from /usr/tmp/tftp, emccMode =0, emccLocalOverride=0, tempTftp1= , tempTftp2 = , tempTftp3 = , tempTftp4 = 

6396: NOT 11:26:02.884756 JVM: setTempTftpAddress, emcc_mode=0,retEmccMode=0,LocalOverride=0,retLocalOverride=0, status=1

6397: NOT 11:26:02.989176 JVM: Startup Module Loader|cip.cfg.t:? - ===not full config or not emcc mode ,so do vendor config

6398: NOT 11:26:02.994962 SECD: clearTFTPList: cleared all TFTP entries

6399: ERR 11:26:03.255663 JVM: Startup Module Loader|cip.mmgr.MediaManager:? -

MediaManager propertyChanged called  device.settings.config.ipaddressingmode

6400: ERR 11:26:03.896806 JVM: Startup Module Loader|cip.cfg.t:? - Delete of sshUserInfo file failed

6401: ERR 11:26:03.898954 JVM: Startup Module Loader|cip.cfg.t:? - informationUrl is http://10.15.11.2:8080/ccmcip/GetTelecasterHelpText.jsp

6402: ERR 11:26:03.902494 JVM: Startup Module Loader|cip.cfg.t:? - directoriesUrl is http://10.15.11.2:8080/ccmcip/xmldirectory.jsp

6403: ERR 11:26:03.904578 JVM: Startup Module Loader|cip.cfg.t:? - messagesUrl is

6404: ERR 11:26:03.906724 JVM: Startup Module Loader|cip.cfg.t:? - servicesUrl is http://10.15.11.2:8080/ccmcip/getservicesmenu.jsp

6405: ERR 11:26:03.908828 JVM: Startup Module Loader|cip.cfg.t:? - authenticationUrl is http://10.15.11.2:8080/ccmcip/authenticate.jsp

6406: ERR 11:26:03.910989 JVM: Startup Module Loader|cip.cfg.t:? - idleUrl is

6407: ERR 11:26:03.913024 JVM: Startup Module Loader|cip.cfg.t:? - messagesUrl is null

6408: ERR 11:26:03.915101 JVM: Startup Module Loader|cip.cfg.t:? - After set servicesUrl device.settings.config.servicesurl value http://10.15.11.2:8080/ccmcip/getservicesmenu.jsp

6409: ERR 11:26:03.917301 JVM: Startup Module Loader|cip.cfg.t:? - After set info url device.settings.config.informationurl value http://10.15.11.2:8080/ccmcip/GetTelecasterHelpText.jsp

6410: ERR 11:26:03.919456 JVM: Startup Module Loader|cip.cfg.t:? - After set  dir Url device.settings.config.directoriesurl value http://10.15.11.2:8080/ccmcip/xmldirectory.jsp

6411: ERR 11:26:03.922925 JVM: Startup Module Loader|cip.cfg.t:? -  idle Url of null

6412: ERR 11:26:03.925108 JVM: Startup Module Loader|cip.cfg.t:? - After set  sec auth Url device.settings.config.authenticationurl value http://10.15.11.2:8080/ccmcip/authenticate.jsp

6413: ERR 11:26:03.927265 JVM: Startup Module Loader|VendorConfig:? - vendorconfig : setConfigProperties

6414: ERR 11:26:03.929322 JVM: Startup Module Loader|VendorConfig:? -

About to set WebAccess

6415: WRN 11:26:03.931539 JVM: Startup Module Loader|DisplayTask:? - resetCurrentPowerState(): Energywise is disabled

6416: WRN 11:26:03.933744 JVM: Startup Module Loader|DisplayTask:? - resetCurrentPowerState(): Energywise null domain

6417: WRN 11:26:03.935811 JVM: Startup Module Loader|DisplayTask:? - resetCurrentPowerState(): Energywise null secret

6418: WRN 11:26:03.937944 JVM: Startup Module Loader|DisplayTask:? -  resetCurrentPowerState(): no time changed, so needn't reschedule existing timer

6419: ERR 11:26:03.941456 JVM: Startup Module Loader|cip.setg.ScreenSaveProperty:? - Config file field <displayOnTime> is incorrectly formatted. Defaulting.

6420: ERR 11:26:03.943628 JVM: Startup Module Loader|cip.setg.ScreenSaveProperty:? - Config file field <displayIdleTimeout> is incorrectly formatted. Defaulting.

6421: ERR 11:26:03.945755 JVM: Startup Module Loader|VendorConfig:? - WebAccess true

6422: ERR 11:26:03.947759 JVM: Startup Module Loader|VendorConfig:? - WebProtocol 0

6423: NOT 11:26:03.949755 JVM: Startup Module Loader|VendorConfig:? - Setting 80-bit-srtp to false

6424: ERR 11:26:03.963461 JVM: Startup Module Loader|cip.mmgr.MediaManager:? -

MediaManager propertyChanged called  device.settings.config.vendorconfig.sidetonelevel

6425: NOT 11:26:03.969394 JVM: Startup Module Loader|VendorConfig:? - Setting RTCP to false

6426: NOT 11:26:03.973337 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.localization.userlocale.charset value=iso-8859-1

6427: NOT 11:26:03.976339 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.localization.userlocale.languagecode value=en_GB

6428: NOT 11:26:03.988815 SECD: setSecMode: sec mode set to NONE (was NONE)

6429: NOT 11:26:05.131452 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.userid value=3423

6430: NOT 11:26:05.306058 SECD: clearSRSTList: cleared all SRST entries

6431: NOT 11:26:05.313118 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.userid value=3423

6432: NOT 11:26:05.315509 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.phoneservices value=cip.props.XmlProperty@bc3626

6433: WRN 11:26:05.531645 SECD: WARN:cancelCapfOp: CAPF not in use, user cancel ignored

6434: NOT 11:26:05.532768 SECD: clearCapfList: CAPF table cleared

6435: NOT 11:26:05.544999 SECD: clearCapfList: CAPF table cleared

6436: NOT 11:26:05.546034 SECD: addCapfEntry: adding new CAPF entry from Cfg:

6437: NOT 11:26:05.547025 SECD:    CAPF tbl idx : 0

6438: ERR 11:26:05.547972 SECD: EROR:addCapfEntry: DSCP VALUE 96

6439: NOT 11:26:05.548855 SECD:    new entry from Cfg :

6440: NOT 11:26:05.549734 SECD:        ipv4 addr      : 10.15.11.2

6441: NOT 11:26:05.550676 SECD:        ipv6 addr      : n/a

6442: NOT 11:26:05.551578 SECD:        port         : 3804

6443: NOT 11:26:05.552494 SECD:        priority     : 146915332

6444: NOT 11:26:05.553386 SECD:        ipMode     : 0 (default)

6445: NOT 11:26:05.554336 SECD:        DSCP     : 96

6446: NOT 11:26:05.637648 JVM: Startup Module Loader|cip.cfg.t:? - CUCM in config file: #0 XmlCallAgentObject: IPv4-name=[10.15.11.2] IPv6-name=[] port=2000 priority=0

6447: NOT 11:26:05.639864 JVM: Startup Module Loader|cip.cfg.t:? - CUCM in config file: #1 XmlCallAgentObject: IPv4-name=[10.15.11.3] IPv6-name=[] port=2000 priority=1

6448: NOT 11:26:05.643524 JVM: Startup Module Loader|cip.cfg.t:? - CUCM in config file: #2 XmlCallAgentObject: IPv4-name=[] IPv6-name=[] port=0 priority=32767

6449: NOT 11:26:05.645754 JVM: Startup Module Loader|cip.cfg.t:? - CUCM in config file: #3 XmlCallAgentObject: IPv4-name=[] IPv6-name=[] port=0 priority=32767

6450: NOT 11:26:05.647911 JVM: Startup Module Loader|cip.cfg.t:? - CUCM in config file: #4 XmlCallAgentObject: IPv4-name=[] IPv6-name=[] port=0 priority=32767

6451: ERR 11:26:05.653401 JVM: Startup Module Loader|cip.sec.CapfProperty:? - Failed to resolve Capf Ipv6 Address with hostname

6452: NOT 11:26:05.656579 JVM: Startup Module Loader|cip.sec.CapfProperty:? - CapfProp 96

6453: NOT 11:26:05.658761 JVM: Startup Module Loader|cip.cfg.t:? - Config processConfigNoError() result code=CONFIG_FILE_NO_CHANGE

6454: NOT 11:26:05.663965 SECD: setSecMode: sec mode set to NONE (was NONE)

6455: NOT 11:26:05.905586 JVM: Startup Module Loader - Deletion of file Successful/usr/ram/SEP001E7AC4B968.cnf.xml

6456: NOT 11:26:05.907681 JVM: Startup Module Loader|cip.cfg.t:? - DELETE ConfigFile:(ram/SEP001E7AC4B968.cnf.xml)WAS SUCCESSFUL

6457: NOT 11:26:05.909835 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - --->ConfigManager PropertyChanged: device.config.io.dscp.config

6458: NOT 11:26:05.913267 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - <---ConfigManager PropertyChanged: device.config.io.dscp.config

6459: NOT 11:26:05.916498 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=true

shuttingDown=true

6460: WRN 11:26:05.919529 JVM: Startup Module Loader|cip.sccp.SccpEnhancedAlarmInfo:propertyChanged - name=device.settings.fullyregistered value=false

6461: ERR 11:26:05.928210 SECD: EROR:lookupCTL: UCM not in TL, NOT_FOUND

6462: WRN 11:26:05.956742 SECD: WARN:lookupSRST: SRST in list, no valid cer

Is the reason we are getting slow registration anything to do with ITL or CTL Files ?

Many Thanks,

Martin.

2 Replies 2

Debra Chittick
Level 4
Level 4

I have this issue too.  Not sure what is causing it.  Any help on this would be greatly appreciated.

Any update do on how to fix this: I have, (on a 7971G)


1043: NOT 05:36:04.680002 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
1044: WRN 05:36:04.716601 SECD: WARN:lookupCTL: ** no CTL, assume UCM NONSECURE
1045: WRN 05:36:04.744209 SECD: WARN:lookupSRST: no CTL, treat SRST as non-secure
1046: WRN 05:36:04.835442 JVM: Startup Module Loader|cip.sccp.CcApi:? - alarm sending failure:25: Name=SEP0*******1DB4 Load= 9.4(2.1S) Last=Initialized
1047: NOT 05:36:05.453976 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