cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
2054
Views
0
Helpful
2
Replies
Martin Lowes
Beginner

Slow Registration of 7941, 7942 and 7961 IP Phones

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
Enthusiast

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

Content for Community-Ad

Spotlight Awards 2021