07-26-2013 04:07 AM - edited 03-16-2019 06:34 PM
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.
11-07-2013 07:59 AM
I have this issue too. Not sure what is causing it. Any help on this would be greatly appreciated.
12-16-2014 12:50 PM
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
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide