freepbx + cisco 7942G

пытаюсь подружить телефон cisco 7942 с астериском. Загрузил прошивку SIP42.8-5-4S, настроил телефон конфигом из интернетов:

<?xml version="1.0" encoding="UTF-8"?> <device> <deviceProtocol>SIP</deviceProtocol> <sshUserId></sshUserId> <sshPassword></sshPassword> <devicePool> <dateTimeSetting> <dateTemplate>D.M.Y</dateTemplate> <timeTemplate>HH:MM</timeTemplate> <timeZone>Russian Standard/Daylight Time</timeZone> <ntps> <ntp> <name>172.16.0.7</name> <ntpMode>Broadcast</ntpMode> </ntp> </ntps> </dateTimeSetting> <callManagerGroup> <members> <member priority="0"> <callManager> <ports> <ethernetPhonePort>2000</ethernetPhonePort> <sipPort>5555</sipPort> </ports> <processNodeName>172.16.0.21</processNodeName> </callManager> </member> </members> </callManagerGroup> </devicePool> <sipProfile> <sipProxies> <backupProxy>172.16.0.21</backupProxy> <backupProxyPort>5555</backupProxyPort> <emergencyProxy>172.16.0.21</emergencyProxy> <emergencyProxyPort>5555</emergencyProxyPort> <outboundProxy>172.16.0.21</outboundProxy> <outboundProxyPort>5555</outboundProxyPort> <registerWithProxy>false</registerWithProxy> </sipProxies> <sipCallFeatures> <cnfJoinEnabled>true</cnfJoinEnabled> <callForwardURI>x-cisco-serviceuri-cfwdall</callForwardURI> <callPickupURI>x-cisco-serviceuri-pickup</callPickupURI> <callPickupListURI>x-cisco-serviceuri-opickup</callPickupListURI> <callPickupGroupURI>x-cisco-serviceuri-gpickup</callPickupGroupURI> <meetMeServiceURI>x-cisco-serviceuri-meetme</meetMeServiceURI> <abbreviatedDialURI>x-cisco-serviceuri-abbrdial</abbreviatedDialURI> <rfc2543Hold>false</rfc2543Hold> <callHoldRingback>2</callHoldRingback> <localCfwdEnable>true</localCfwdEnable> <semiAttendedTransfer>true</semiAttendedTransfer> <anonymousCallBlock>2</anonymousCallBlock> <callerIdBlocking>2</callerIdBlocking> <dndControl>0</dndControl> <remoteCcEnable>true</remoteCcEnable> </sipCallFeatures> <sipStack> <sipInviteRetx>6</sipInviteRetx> <sipRetx>10</sipRetx> <timerInviteExpires>180</timerInviteExpires> <timerRegisterExpires>3600</timerRegisterExpires> <timerRegisterDelta>5</timerRegisterDelta> <timerKeepAliveExpires>120</timerKeepAliveExpires> <timerSubscribeExpires>120</timerSubscribeExpires> <timerSubscribeDelta>5</timerSubscribeDelta> <timerT1>500</timerT1> <timerT2>4000</timerT2> <maxRedirects>70</maxRedirects> <remotePartyID>false</remotePartyID> <userInfo>None</userInfo> </sipStack> <autoAnswerTimer>1</autoAnswerTimer> <autoAnswerAltBehavior>false</autoAnswerAltBehavior> <autoAnswerOverride>true</autoAnswerOverride> <transferOnhookEnabled>false</transferOnhookEnabled> <enableVad>false</enableVad> <preferredCodec>g711alaw</preferredCodec> <dtmfAvtPayload>101</dtmfAvtPayload> <dtmfDbLevel>3</dtmfDbLevel> <dtmfOutofBand>avt</dtmfOutofBand> <alwaysUsePrimeLine>false</alwaysUsePrimeLine> <alwaysUsePrimeLineVoiceMail>false</alwaysUsePrimeLineVoiceMail> <kpml>3</kpml> <natReceivedProcessing>false</natReceivedProcessing> <natEnabled>false</natEnabled> <natAddress></natAddress> <phoneLabel>775</phoneLabel> <stutterMsgWaiting>0</stutterMsgWaiting> <callStats>false</callStats> <silentPeriodBetweenCallWaitingBursts>10</silentPeriodBetweenCallWaitingBursts> <disableLocalSpeedDialConfig>false</disableLocalSpeedDialConfig> <startMediaPort>16384</startMediaPort> <stopMediaPort>32766</stopMediaPort> <sipLines> <line button="1"> <featureID>9</featureID> <featureLabel>775</featureLabel> <proxy>172.16.0.21</proxy> <port>5555</port> <name>775</name> <displayName>775</displayName> <autoAnswer> <autoAnswerEnabled>2</autoAnswerEnabled> </autoAnswer> <callWaiting>3</callWaiting> <authName>775</authName> <authPassword>ff89c7cb</authPassword> <sharedLine>false</sharedLine> <messageWaitingLampPolicy>1</messageWaitingLampPolicy> <messagesNumber>*97</messagesNumber> <ringSettingIdle>4</ringSettingIdle> <ringSettingActive>5</ringSettingActive> <contact>775</contact> <forwardCallInfoDisplay> <callerName>true</callerName> <callerNumber>true</callerNumber> <redirectedNumber>true</redirectedNumber> <dialedNumber>true</dialedNumber> </forwardCallInfoDisplay> </line> </sipLines> <voipControlPort>5555</voipControlPort> <dscpForAudio>184</dscpForAudio> <ringSettingBusyStationPolicy>0</ringSettingBusyStationPolicy> <dialTemplate>dialplan.xml</dialTemplate> </sipProfile> <commonProfile> <phonePassword></phonePassword> <backgroundImageAccess>true</backgroundImageAccess> <callLogBlfEnabled>2</callLogBlfEnabled> </commonProfile> <loadInformation>SIP42.8-5-4S</loadInformation> <loadInformation434 model="Cisco 7942">SIP42.8-5-4S</loadInformation434> <vendorConfig> <disableSpeaker>false</disableSpeaker> <disableSpeakerAndHeadset>false</disableSpeakerAndHeadset> <pcPort>0</pcPort> <settingsAccess>0</settingsAccess> <garp>0</garp> <voiceVlanAccess>0</voiceVlanAccess> <videoCapability>0</videoCapability> <autoSelectLineEnable>0</autoSelectLineEnable> <sshAccess>0</sshAccess> <sshPort>22</sshPort> <webAccess>0</webAccess> <spanToPCPort>1</spanToPCPort> <loggingDisplay>1</loggingDisplay> <loadServer></loadServer> </vendorConfig> <versionStamp></versionStamp> <userLocale> <name>Russian_Russian_Federation</name> <uid></uid> <langCode>ru_RU</langCode> <version></version> <winCharSet>utf-8</winCharSet> </userLocale> <networkLocale>Russian_Russian_Federation</networkLocale> <networkLocaleInfo> <name>Russian_Russian_Federation</name> <uid></uid> <version></version> </networkLocaleInfo> <deviceSecurityMode>0</deviceSecurityMode> <authenticationURL></authenticationURL> <directoryURL></directoryURL> <idleURL></idleURL> <informationURL></informationURL> <messagesURL></messagesURL> <proxyServerURL></proxyServerURL> <servicesURL></servicesURL> <dscpForSCCPPhoneConfig>96</dscpForSCCPPhoneConfig> <dscpForSCCPPhoneServices>0</dscpForSCCPPhoneServices> <dscpForCm2Dvce>96</dscpForCm2Dvce> <transportLayerProtocol>4</transportLayerProtocol> <capfAuthMode>0</capfAuthMode> <capfList> <capf> <phonePort>3804</phonePort> </capf> </capfList> <certHash></certHash> <encrConfig>false</encrConfig> </device> 

один телефон сразу заработал, и я от копировал конфиг на второй телефон - но он не регистрируется(поменял только экстеншн). Но исходящие работают. Пробовал загрузить полную копию конфига с рабочего телефона - тоже не конектится к астериску.

лог телефона:

|== Syslogd TNP== Wed Dec 16 02:42:16 2009 ==================================================== 1: NOT 11:42:15.760202 init: Mounting /dev/fd0a... 2: INF 11:42:15.997234 init: Mount succeeded. 3: NOT 11:42:16.032868 init: Mounting /dev/fd1a... 4: INF 11:42:16.049233 init: Mount succeeded. 5: NOT 11:42:16.052448 init: Starting espd... 6: NOT 11:42:16.069758 ESP: pid = 5 7: NOT 11:42:16.352421 init: Loading drivers from '/etc/sysdvrs.def'. 8: NOT 11:42:16.470153 init: Loading drivers from '/usr/local/etc/drivers.def'. 9: DBG 11:42:16.498688 pwr: maxPower = 6300 mwatts, alloc power = 6300 10: DBG 11:42:16.580433 LCDSWInit: LCD initialized 11: DBG 11:42:16.600635 audev: driver INSTALLED 12: DBG 11:42:16.635341 dsp: dirver installed, version 1A0 13: DBG 11:42:16.636433 LED DRIVER: Model is: 7942 14: DBG 11:42:16.730203 linearfs: sysctl name=LFS_SWITCH_TO_FLASH0 15: DBG 11:42:16.730569 linearfs: switch to flash0 16: DBG 11:42:17.162730 linearfs: using existing /flash0/apps 17: DBG 11:42:17.163178 linearfs: lfs_state -> 1 18: NOT 11:42:17.165242 init: Extracting loadID's into environment... 19: NOT 11:42:17.167207 init: Starting applications from /etc/init.tab 20: NOT 11:42:17.169748 init: Sending termination signals to apps for pending restart. 21: DBG 11:42:17.245054 lcd_open: return success! 22: DBG 11:42:18.253767 lcd_close: return success! 23: NOT 11:42:18.255133 init: Starting /bin/cvw 24: NOT 11:42:18.257495 init: /bin/cvw started as pid=8 25: NOT 11:42:18.278005 SYSMSG: pid 8 (/bin/cvw) Normal Exit, status = 1 26: INF 11:42:18.278047 runtime = 0.020 secs 27: INF 11:42:18.278064 user cpu = 0.000585380 secs 28: INF 11:42:18.278082 system cpu = 0.014093620 secs 29: INF 11:42:18.278095 child user cpu = 0.000000000 secs 30: INF 11:42:18.278121 child sys cpu = 0.000000000 secs 31: INF 11:42:18.278141 sys interrupts = 0.000186750 secs for 6 interrupts 32: INF 11:42:18.278164 total cpu = 0.014679000 secs ( 50% utilization ) 33: ERR 11:42:18.280344 SYSMSG: pid 9 ( /bin/cvw) Terminated by Signal->Hangup 34: INF 11:42:18.280382 runtime = 0.000 secs 35: INF 11:42:18.280397 user cpu = 0.000000000 secs 36: INF 11:42:18.280410 system cpu = 0.000000000 secs 37: INF 11:42:18.280424 child user cpu = 0.000000000 secs 38: INF 11:42:18.280437 child sys cpu = 0.000000000 secs 39: INF 11:42:18.280457 sys interrupts = 0.000093230 secs for 4 interrupts 40: INF 11:42:18.280474 total cpu = 0.000000000 secs ( 0% utilization ) 41: NOT 11:42:18.281837 init: Starting /usr/local/etc/tzunpack 42: NOT 11:42:18.284257 init: /usr/local/etc/tzunpack started as pid=10 43: NOT 11:42:18.284794 init: Starting /sbin/inetd 44: NOT 11:42:18.287205 init: /sbin/inetd started as pid=11 45: NOT 11:42:18.395492 INETD: pid = 11 46: NOT 11:42:18.587852 init: Starting /sbin/imgauthd 47: NOT 11:42:18.590336 init: /sbin/imgauthd started as pid=17 48: NOT 11:42:18.590872 init: Starting /sbin/image 49: NOT 11:42:18.593229 init: /sbin/image started as pid=18 50: NOT 11:42:18.601096 CDP-D: pid = 14 51: NOT 11:42:18.609957 DHCP6: COLDBOOT - wait 7 seconds... 52: NOT 11:42:18.613303 PAE: pid = 16 53: NOT 11:42:18.614883 PAE: phone is not single port 54: NOT 11:42:18.617845 PAE: /tmp/pae.stat does not exist 55: WRN 11:42:18.618984 PAE: paeReadPCStatus: empty file 56: NOT 11:42:18.619473 PAE: link-0 is DOWN 57: NOT 11:42:18.619924 PAE: supplicant will start when link-0 is UP 58: WRN 11:42:18.689432 ESP: espInfoUpdate() dropped 59: NOT 11:42:18.762786 IMG_AUTH: Version: 3.6(1.0): TNP REL 60: NOT 11:42:18.763365 IMG_AUTH: model: CP-7942G, check for built-in key 61: NOT 11:42:18.763861 IMG_AUTH: built-in platform RELEASE key is present 62: NOT 11:42:18.764374 IMG_AUTH: built-in common RELEASE key is present 63: NOT 11:42:18.765103 IMG_AUTH: /tmp/midlet.conf does not exist 64: NOT 11:42:18.766273 IMG_AUTH: TNP, built-in keys : Platform (curr), Common (curr) 65: NOT 11:42:18.769450 image: [18] ver 2.0 (0.1) 66: NOT 11:42:18.770654 image: **Legacy TFTP Mode Specified** 67: NOT 11:42:18.773901 image: Performing checksum of software load... 68: NOT 11:42:22.988136 image: Software load SIP42.8-5-4S.loads validated. 69: NOT 11:42:22.989020 image: Image Run Complete: status=21 70: NOT 11:42:22.990080 SYSMSG: pid 18 (/sbin/image) Normal Exit, status = 21 71: INF 11:42:22.990119 runtime = 4.390 secs 72: INF 11:42:22.990140 user cpu = 0.602638600 secs 73: INF 11:42:22.990158 system cpu = 3.613747700 secs 74: INF 11:42:22.990172 child user cpu = 0.000000000 secs 75: INF 11:42:22.990186 child sys cpu = 0.000000000 secs 76: INF 11:42:22.990207 sys interrupts = 0.014532540 secs for 565 interrupts 77: INF 11:42:22.990230 total cpu = 4.216386300 secs ( 95% utilization ) 78: NOT 11:42:23.221046 init: Starting /bin/tftpClient 79: NOT 11:42:23.223449 init: /bin/tftpClient started as pid=21 80: NOT 11:42:23.223992 init: Starting /bin/secd 81: NOT 11:42:23.226312 init: /bin/secd started as pid=22 82: NOT 11:42:23.226845 init: Starting /bin/ntp 83: NOT 11:42:23.229157 init: /bin/ntp started as pid=23 84: NOT 11:42:23.229682 init: Starting /ubin/vieo 85: NOT 11:42:23.232130 init: /ubin/vieo started as pid=24 86: NOT 11:42:23.232670 init: Starting /ubin/dsp 87: NOT 11:42:23.235036 init: /ubin/dsp started as pid=25 88: NOT 11:42:23.235583 init: Starting /bin/mkdir 89: NOT 11:42:23.237922 init: /bin/mkdir started as pid=26 90: NOT 11:42:23.239695 CDP-D: Starting CFG_COMMAND_SMSme...367439872 91: NOT 11:42:23.781227 DHCP: pid = 20 92: NOT 11:42:23.795466 tftpClient: pid = 21 93: NOT 11:42:23.802844 SECD: main: Starting... Ver: 1.4(1.2) [Dec 16 2009, 02:34:53] 94: NOT 11:42:23.805097 SECD: secCreateDir: dir created </usr/tmp/sec> 95: NOT 11:42:23.807429 SECD: initSecMode: initializing to sec mode UNKNOWN (default) 96: WRN 11:42:23.815836 SECD: WARN:loadCert: phone has no LSC cert 97: NOT 11:42:23.816442 SECD: initResources: have cert+pkey: MIC only, no LSC 98: NOT 11:42:23.817354 SECD: ctlInit: No CTL file: /flash0/sec/ctl/CTLFile.tlv 99: NOT 11:42:23.818168 SECD: initCTL: finished CTL initialization 100: WRN 11:42:23.818722 SECD: WARN:initCTL: ** phone has NO CTL ** 101: WRN 11:42:23.819295 SECD: WARN:resetSecMode: ** WARN ** resetting sec mode to NONE (non-secure) 102: NOT 11:42:23.820519 SECD: clearCapfList: CAPF table cleared 103: NOT 11:42:23.821270 SECD: initCapfClnt: CAPF clnt initialized 104: NOT 11:42:23.836411 SECD: main: starting service... 105: NOT 11:42:23.839668 NTP: [23] ver 3.0 (0.3) 106: NOT 11:42:23.845031 vieo: main() initializing 107: NOT 11:42:23.847224 vieo: msg queue MQ_MT_VIEO started 108: NOT 11:42:23.855107 DSP: Version: DSPHOST = 8.3(14.15)PSYL - enable audio output at teh end 109: NOT 11:42:23.855719 DSP: Version: KFactor = 0.95 110: DBG 11:42:23.856723 dsp open flags 2042 111: WRN 11:42:23.861064 DSP: Table Substitution /ubin/dspCoefs, checksum is disabled, actual checksum = 6294 112: WRN 11:42:23.865786 DSP: Table Substitution /ubin/dspParms, checksum is disabled, actual checksum = 38744 113: WRN 11:42:23.868168 DSP: Table Substitution /ubin/dspVols, checksum is disabled, actual checksum = 43264 114: WRN 11:42:23.870107 DSP: Table Substitution /ubin/dspCosts, checksum is disabled, actual checksum = 215 115: NOT 11:42:23.913485 DHCP: COLDBOOT - wait 7 seconds... 116: NOT 11:42:23.915364 init: Starting /bin/mount 117: NOT 11:42:23.917787 init: /bin/mount started as pid=27 118: NOT 11:42:23.939091 init: Starting /ubin/zrun 119: NOT 11:42:23.941583 init: /ubin/zrun started as pid=29 120: NOT 11:42:23.942142 init: Starting /bin/sleep 121: NOT 11:42:23.944484 init: /bin/sleep started as pid=30 122: NOT 11:42:23.965182 zrun: Starting... 123: NOT 11:42:23.966270 zrun: loadCompressedFileandExecute... 124: INF 11:42:27.865594 zrun: execv(/tmp/sunvm.unzip/sunvm.cnu, argv) 125: ERR 11:42:28.107422 CDP-D: calling installHandlers 126: ERR 11:42:28.107987 CDP-D: calling cdpDrvrInit 127: ERR 11:42:28.109261 ETH_SET_IPV6 call in6_if_brcm_reset 128: ERR 11:42:28.109671 ETH_SET_IPV6 call in6_if_brcm_up 129: ERR 11:42:28.110023 in6_ifattach_linklocal 130: ERR 11:42:28.110055 in6_ifattach_linklocal call in6_update_ifa 131: ERR 11:42:28.110098 routeList NULL 132: ERR 11:42:28.110343 in6_ifattach_linklocal call in6ifa_ifpforlinklocal 133: ERR 11:42:28.110393 nd6_ifattach_linklocal ndpr:plen:64 mask:ffff:ffff:ffff:ffff:: prefix:fe80::d257:4cff:fef6:1c80 134: ERR 11:42:28.110455 nd6_ifattach_linklocal return 0 135: ERR 11:42:28.111433 exit in6_if_brcm_up 136: ERR 11:42:28.111719 ETH_SET_IPV6 call in6_if_loopback_up 137: ERR 11:42:28.112046 enter in6_if_loopback_up 138: ERR 11:42:28.112740 in6_ifattach_linklocal 139: ERR 11:42:28.112778 in6_ifattach_linklocal call in6_update_ifa 140: ERR 11:42:28.112938 in6_ifattach_linklocal call in6ifa_ifpforlinklocal 141: ERR 11:42:28.112984 nd6_ifattach_linklocal ndpr:plen:64 mask:ffff:ffff:ffff:ffff:: prefix:fe80::0001 142: ERR 11:42:28.113031 nd6_ifattach_linklocal return 0 143: ERR 11:42:28.113049 exit in6_if_loopback_up 144: ERR 11:42:28.113060 ETH_SET_IPV6 returned from in6_if_loopback_up 145: NOT 11:42:28.115822 CDP-D: NA power detected, max power = 6300 milliwatts 146: NOT 11:42:28.118329 CDP-D: Enable values LLDP:3 CDP:3 147: NOT 11:42:28.120104 CDP-D: cdpSetRepeater 11 148: NOT 11:42:28.130735 CDP-D: PHY 0 --> LINK IS DOWN 149: NOT 11:42:28.131746 CDP-D: delayedCheckLinkTimer 150: NOT 11:42:28.132443 CDP-D: PHY 1 --> LINK IS DOWN 151: WRN 11:42:28.133194 CDP-D: PC port down sending to cdpMsgQue 152: ERR 11:42:28.235427 JVM: sunvm pausing for umount 153: NOT 11:42:28.261711 init: Starting /bin/umount 154: NOT 11:42:28.264205 init: /bin/umount started as pid=3 155: NOT 11:42:28.265277 PAE: SIGIPCFG received... 156: NOT 11:42:28.266082 INETD: inetdSSH tid 76 157: NOT 11:42:28.270154 CDP-D: catchipcfg:getdhcpinfo IP:0 domain: chngVal:0 158: NOT 11:42:28.273007 CDP-D: catchipcfg:getdhcpinfo IP:0 domain: chngVal:0 159: NOT 11:42:28.274568 CDP-D: catchipcfg:getdhcpinfo IP:0 domain: chngVal:0 160: NOT 11:42:28.280771 DNS: pid = 31 161: ERR 11:42:28.283525 init: /bin/umount powerup started 162: WRN 11:42:28.292911 ESP: espInfoUpdate() dropped 163: WRN 11:42:28.293535 ESP: espInfoUpdate() dropped 164: NOT 11:42:28.294089 PAE: SIGIPCFG received... 165: ERR 11:42:28.297621 RTSOLD: opvvlan:-1->4096 advvlan:-1->4096 vvlanState=0 166: ERR 11:42:28.305138 RTSOLD: opvvlan:4096->4096 advvlan:4096->4096 vvlanState=0 167: NOT 11:42:28.381534 init: Starting /bin/rmdir 168: NOT 11:42:28.384004 init: /bin/rmdir started as pid=4 169: NOT 11:42:28.390712 init: Starting /bin/settmask 170: NOT 11:42:28.393224 init: /bin/settmask started as pid=7 171: NOT 11:42:28.528881 init: Starting /bin/login 172: NOT 11:42:28.531425 init: /bin/login started as pid=12 173: NOT 11:42:28.542785 CDP-D: PHY 0 --> 100 MB LINK IS UP 174: NOT 11:42:28.547270 CDP-D: vvlan changed --> New:4096 old:4096 4096 175: ERR 11:42:28.547850 CDP-D: setVVLANConfig VVLAN CHANGED --> NEW:4096 OLD:4096 4096 176: NOT 11:42:28.552390 CDP-D: vlan Configured --> New:4096 old:4096 :4096 177: NOT 11:42:28.553573 CDP-D: Sent SIGNINFO & SIGIPCFG 178: NOT 11:42:28.554299 CDP-D: ----lldpProtoInfo[0].enableState 1 179: NOT 11:42:28.555098 CDP-D: Entering Hold/Trigger Mode.... 180: NOT 11:42:28.555749 CDP-D: ----cdpProtoInfo[0].enableState 1 181: NOT 11:42:28.562808 CDP-D: catchipcfg:getdhcpinfo IP:0 domain: chngVal:0 182: NOT 11:42:28.564299 CDP-D: catchipcfg:getdhcpinfo IP:0 domain: chngVal:0 183: NOT 11:42:28.565790 CDP-D: catchipcfg:getdhcpinfo IP:0 domain: chngVal:0 184: NOT 11:42:28.569962 PAE: SIGIPCFG received... 185: WRN 11:42:28.572134 ESP: espInfoUpdate() dropped 186: NOT 11:42:28.574195 PAE: link-0: DOWN-->UP 187: NOT 11:42:28.574750 PAE: EV_DISABLED, ST_DISCONNECTED->ST_DISABLED 188: NOT 11:42:28.575226 PAE: supplicant is disabled 189: NOT 11:42:28.575657 PAE: paeSetLastSupStatus: LastSupStatus 0 190: ERR 11:42:28.577332 RTSOLD: opvvlan:4096->4096 advvlan:4096->4096 vvlanState=0 191: ERR 11:42:28.578690 RTSOLD: UNLINK -> LINK 192: ERR 11:42:28.579196 RTSOLD: vvlan has not been validated 0 193: ALT 11:42:29.130172 ESP: PC Port is not responding. Assuming link down... ESPD still running but may be degraded... 194: NOT 11:42:29.130838 ESP: sending startup trigger 195: NOT 11:42:29.492677 ESP: send ADMIN, logging = 1, shell = 0, ipconfig = 0 196: ERR 11:42:30.230056 JVM: sunvm unpaused 197: WRN 11:42:31.783399 SECD: WARN:getCTLInfo: ** phone has no CTL 198: WRN 11:42:31.800338 SECD: WARN:getCTLInfo: ** phone has no CTL 199: NOT 11:42:31.841063 tftpClient: request server 0 ---> 172.16.0.7 200: NOT 11:42:31.998123 tftpClient: request server 1 ---> 201: NOT 11:42:32.459743 tftpClient: request server6 0 ---> :: 202: NOT 11:42:32.493213 tftpClient: request server6 1 ---> :: 203: NOT 11:42:35.550146 CDP-D: holdThrd SendingCmd:proto:1 port:0 204: NOT 11:42:35.550925 CDP-D: LLDP - Short HoldTimerExpire Hold/Trigger Mode .... CDPMsg ttl:-1 lldpRcvMsgFlg:0 205: NOT 11:42:35.732524 SECD: setSecMode: sec mode set to NONE (was NONE) 206: WRN 11:42:35.915886 JVM: Setting LCD Contrast/View Angle to 16 before R2D init 207: WRN 11:42:35.917156 JVM: Setting LCD Contrast/View Angle to 16 before R2D init 208: NOT 11:42:36.159447 JVM: Startup Module Loader|cip.midp.rms.h:? - loaded entry: _cip_properties.dbk=/flash0/RMS/appdb/_cip_properties.dbk 209: NOT 11:42:36.160886 JVM: Startup Module Loader|cip.midp.rms.h:? - loaded entry: Cisco_MIDlet_suites.utf=/flash0/RMS/appdb/Cisco_MIDlet_suites.utf 210: NOT 11:42:36.162241 JVM: Startup Module Loader|cip.midp.rms.h:? - loaded entry: _cip_properties.db=/flash0/RMS/appdb/_cip_properties.db 211: NOT 11:42:36.163627 JVM: Startup Module Loader|cip.midp.rms.h:? - loaded entry: _cip_properties.dbk=/flash0/RMS/appdb/_cip_properties.dbk 212: NOT 11:42:36.165022 JVM: Startup Module Loader|cip.midp.rms.h:? - loaded entry: Cisco_MIDlet_suites.utf=/flash0/RMS/appdb/Cisco_MIDlet_suites.utf 213: NOT 11:42:36.166383 JVM: Startup Module Loader|cip.midp.rms.h:? - loaded entry: _cip_properties.db=/flash0/RMS/appdb/_cip_properties.db 214: NOT 11:42:36.304989 CDP-D: dpCheckLinkThrd: check link on port 0 215: ERR 11:42:38.643978 JVM: Startup Module Loader|SystemManager:n/a - Encountered Property Exception while getting: device.system.targetmodules 216: NOT 11:42:38.760350 CDP-D: Cancel Hold/Trigger Mode .... 217: DBG 11:42:39.317807 lcd_open: return success! 218: NOT 11:42:44.065895 JVM: libMT:start SignOn() 219: NOT 11:42:44.068661 JVM: *** VIEO should already be running. NO? Start "/ubin/vieo &" now *** 220: NOT 11:42:44.069728 JVM: libMT: send _MTStart... 221: NOT 11:42:44.075509 JVM: LibMT (vieoProcess.c): registered VIEO callbacks 222: DBG 11:42:45.070509 audev: audioHwInit,,, 223: DBG 11:42:45.070907 audev: audioHwInit. 224: NOT 11:42:45.153388 DSP: _dspdvrStart ok, DSP bootup completed successfully 225: NOT 11:42:45.160365 DSP: DSP Tone/Ring DSP initialization completed successfully 226: NOT 11:42:45.162009 DSP: dspdvrinitEnableAudioOutput enabled = 1 227: NOT 11:42:45.162577 DSP: DSP INIT-*** DSP_STATE_READY*** 228: NOT 11:42:45.167600 JVM: LibMT: MediaTerminationStart OK! 229: NOT 11:42:45.676643 JVM: Startup Module Loader|ComponentController:? - Initializing MVC comp module's trace mechanism 230: NOT 11:42:45.950116 JVM: SECGetAuthInfo: Unrecognized state (0) 231: NOT 11:42:46.243750 JVM: --> HeadSetHandSet Trace: Enter MediaTerminationSetHandsetHeadsetMonitor 232: NOT 11:42:46.245568 DSP: <-- HeadSetHandSet Trace successful, mtSetHandsetHeadsetMonitor - enabled/disabled = 1 233: NOT 11:42:46.246484 JVM: <-- HeadSetHandSet Trace: MediaMerminationSetHandsetHeadsetMonitor successfully 234: WRN 11:42:47.243075 SECD: WARN:getCertInfo: ** phone has no LSC 235: NOT 11:42:48.550381 CDP-D: configSelectVLAN: 1 OP_USE_ADMIN_DFLT:5 oper:4095 cdp:4096 lldp:4096 admin:4096 mac:0:0:0:0:0:0 236: NOT 11:42:48.551027 CDP-D: getCdpExtaInfo: OP:5 Cos:0 Trust:1 lldp.idxOfInterest:-1 237: WRN 11:42:48.551582 CDP-D: cdpSetCos T:1 C:0 238: NOT 11:42:48.552080 CDP-D: CosTrust: 0 1 :configSelectVLAN: 1 239: WRN 11:42:48.552578 CDP-D: VVLAN action: no release oldVVID:4096 NewVVID:4095 240: NOT 11:42:48.553084 CDP-D: Operational Vvlan:4095 Vlan:4096 Src[5]Mac: 0: 0: 0: 0: 0: 0 241: NOT 11:42:48.721177 CDP-D: vvlan changed --> New:4095 old:4096 4095 242: ERR 11:42:48.721749 CDP-D: setVVLANConfig VVLAN CHANGED --> NEW:4095 OLD:4096 4095 243: ERR 11:42:48.722845 CDP-D: setVVLANConfig VVLAN really did not change 244: NOT 11:42:48.726931 CDP-D: vlan Configured --> New:4096 old:4096 :4096 245: NOT 11:42:48.731524 CDP-D: vvlan changed --> New:4095 old:4095 4095 246: ERR 11:42:48.732103 CDP-D: setVVLANConfig VVLAN CHANGED --> NEW:4095 OLD:4095 4095 247: NOT 11:42:48.736739 CDP-D: vlan Configured --> New:4096 old:4096 :4096 248: NOT 11:42:48.737942 CDP-D: Sent SIGNINFO & SIGIPCFG 249: NOT 11:42:48.739054 CDP-D: cdpSetDhcpEnableFlg: SIGNINFO 250: NOT 11:42:48.739569 CDP-D: Update IPv6 stack with link status : 1 251: NOT 11:42:50.081601 DSP: configurationNumber 1, enabled 0 252: NOT 11:42:50.088200 DSP: mtSetDeviceBandwidth: Wideband Headset - deviceWideband=0 253: NOT 11:42:50.090849 DSP: mtSetDeviceBandwidth: Wideband Handset - deviceWideband=2 254: NOT 11:42:50.092796 DSP: mtSetG722Codec: g722enable=0 255: NOT 11:42:50.134096 JVM: --> HeadSetHandSet Trace: Enter MediaTerminationSetHandsetHeadsetMonitor 256: NOT 11:42:50.135891 DSP: <-- HeadSetHandSet Trace successful, mtSetHandsetHeadsetMonitor - enabled/disabled = 1 257: NOT 11:42:50.136811 JVM: <-- HeadSetHandSet Trace: MediaMerminationSetHandsetHeadsetMonitor successfully 258: NOT 11:42:50.137965 DSP: configurationNumber 0, enabled 0 259: NOT 11:42:52.378552 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - ConfigManager PropertyChanged: device.settings.network.swportstatus 260: NOT 11:42:52.379940 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - ConfigManager PropertyChanged: device.kernel.messages 261: NOT 11:42:52.382337 JVM: Startup Module Loader|cip.midp.midlet.MIDletManager:? - Initializing MIDletManager ... 262: NOT 11:42:52.383738 JVM: Startup Module Loader|cip.midp.midlet.MIDletManager:? - isAutomationJarUploaded : false 263: NOT 11:42:52.385181 JVM: Startup Module Loader|cip.midp.midlet.MIDletManager:? - SecurityManager is SET 264: NOT 11:42:52.386581 JVM: Startup Module Loader|cip.midp.midlet.MIDletManager:? - Initializing PushRegistry ... 265: NOT 11:42:54.011472 JVM: Startup Module Loader|cip.midp.pushregistry.f:? - Starting MIDP PushRegistry ... 266: NOT 11:42:54.012826 JVM: Startup Module Loader|cip.midp.pushregistry.f:? - setAcceptConnections - DISABLED 267: NOT 11:42:54.014187 JVM: Startup Module Loader|cip.midp.pushregistry.f:? - MIDP PushRegistry started successfully. 268: NOT 11:42:54.015573 JVM: Startup Module Loader|cip.midp.midlet.MIDletManager:? - Initializing MIDP MemberFilter ... 269: NOT 11:42:54.016931 JVM: Startup Module Loader|cip.midp.midlet.MIDletManager:? - MIDletManager initialized. 270: NOT 11:42:54.399589 PAE: SIGIPCFG received... 271: ERR 11:42:55.024218 JVM: Startup Module Loader|cip.sipcc.FeatureKeyHandler: - scratchpad/default_keyset.xml/usr/cache/default_keyset.xml 272: WRN 11:42:55.026222 JVM: Startup Module Loader|cip.xml.at: - XML Parser Warning: Unknown attribute 'xmlns:xsi' in element '/softKeyCfg' (line=2) 273: WRN 11:42:55.027642 JVM: Startup Module Loader|cip.xml.at: - XML Parser Warning: Unknown attribute 'xsi:noNamespaceSchemaLocation' in element '/softKeyCfg' (line=2) 274: ERR 11:42:55.029019 JVM: Startup Module Loader|cip.sipcc.FeatureKeyHandler: - Create Default : DO NOTHING 275: NOT 11:42:55.149641 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - ConfigManager PropertyChanged: device.system.systemstate 276: NOT 11:42:55.154444 DHCP6: Waiting to receive RA to start protocol 277: NOT 11:42:55.155913 DHCP6: sockTos = 0x60 278: WRN 11:42:55.159381 SECD: WARN:getCTLInfo: ** phone has no CTL 279: WRN 11:42:55.163934 SECD: WARN:getCTLInfo: ** phone has no CTL 280: NOT 11:42:55.175703 DHCP: DHCP enabled... 281: NOT 11:42:55.187399 DHCP: dhcpSendReq: status 0x12300000 282: NOT 11:42:55.187970 DHCP: Sending Request... 283: ERR 11:42:55.189327 RTSOLD: opvvlan:4096->4095 advvlan:4096->4096 vvlanState=1 284: NOT 11:42:55.206997 DHCP6: Enable 285: ERR 11:42:55.207856 RTSOLD: LINK -> LINK 286: NOT 11:42:55.213160 tftpClient: request server 0 ---> 172.16.0.7 287: NOT 11:42:55.214409 PAE: SIGIPCFG received... 288: NOT 11:42:55.216899 CDP-D: catchipcfg:getdhcpinfo IP:0 domain: chngVal:0 289: NOT 11:42:55.218430 CDP-D: catchipcfg:getdhcpinfo IP:0 domain: chngVal:0 290: NOT 11:42:55.250440 DHCP: ACK received 291: NOT 11:42:55.251009 DHCP: Succeeded 292: NOT 11:42:55.252261 ESP: send ADMIN, logging = 1, shell = 0, ipconfig = 0 293: NOT 11:42:55.261740 DHCP: IP Address -- 172.16.0.245 294: NOT 11:42:55.262283 DHCP: Subnet Mask - 255.255.254.0 295: NOT 11:42:55.262740 DHCP: Default Gwy - 172.16.0.7 296: NOT 11:42:55.278618 CDP-D: catchipcfg:getdhcpinfo IP:ac1000f5 domain: chngVal:1 297: NOT 11:42:55.402939 DHCP: Enable 298: NOT 11:42:55.403477 DHCP: Sending Release... 299: NOT 11:42:55.405374 PAE: SIGIPCFG received... 300: ERR 11:42:55.406220 RTSOLD: opvvlan:4095->4095 advvlan:4096->4096 vvlanState=1 301: ERR 11:42:55.408790 RTSOLD: LINK -> LINK 302: ERR 11:42:55.414688 RTSOLD: opvvlan:4095->4095 advvlan:4096->4096 vvlanState=1 303: ERR 11:42:55.415937 RTSOLD: LINK -> LINK 304: NOT 11:42:56.413519 DHCP: dhcpSendReq: status 0x12300000 305: NOT 11:42:56.414075 DHCP: Sending Request... 306: NOT 11:42:56.428302 tftpClient: request server 1 ---> 307: NOT 11:42:56.537174 DHCP: ACK received 308: NOT 11:42:56.537759 DHCP: Succeeded 309: NOT 11:42:56.544213 DHCP: IP Address -- 172.16.0.245 310: NOT 11:42:56.544816 DHCP: Subnet Mask - 255.255.254.0 311: NOT 11:42:56.545304 DHCP: Default Gwy - 172.16.0.7 312: NOT 11:42:56.563320 CDP-D: catchipcfg:getdhcpinfo IP:ac1000f5 domain: chngVal:1 313: NOT 11:42:56.591409 PAE: SIGIPCFG received... 314: NOT 11:42:56.593728 tftpClient: request server 0 ---> 172.16.0.7 315: NOT 11:42:56.595635 ESP: server 0 = 172.16.0.7 316: ERR 11:42:56.628940 RTSOLD: opvvlan:4095->4095 advvlan:4096->4096 vvlanState=1 317: ERR 11:42:56.631359 RTSOLD: LINK -> LINK 318: NOT 11:42:56.633665 tftpClient: request server 1 ---> 319: NOT 11:42:56.634628 ESP: server 1 = 320: NOT 11:42:56.645919 tftpClient: request server6 0 ---> :: 321: NOT 11:42:56.649244 tftpClient: request server6 0 ---> :: 322: NOT 11:42:56.651344 ESP: server 2 = :: 323: NOT 11:42:56.657403 tftpClient: request server6 1 ---> :: 324: NOT 11:42:56.675846 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - ConfigManager PropertyChanged: device.settings.network.ipstacksstate 325: NOT 11:42:56.677247 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - ConfigManager IP_STACKS_STATE changed 326: NOT 11:42:56.678593 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - IPv4 state=DHCP BOUND 327: NOT 11:42:56.679938 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - IPv6 state=DHCP6 WAITING COLDBOOT TIMEOUT 328: NOT 11:42:56.682160 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - tryConfigFileDownload(): 1 IP stack up, starting timer 329: WRN 11:42:56.686685 SECD: WARN:getCTLInfo: ** phone has no CTL 330: WRN 11:42:56.691274 SECD: WARN:getCTLInfo: ** phone has no CTL 331: NOT 11:42:56.701857 tftpClient: request server6 1 ---> :: 332: NOT 11:42:56.702824 ESP: server 3 = :: 333: NOT 11:42:56.705099 ESP: send ADMIN, logging = 1, shell = 0, ipconfig = 0 334: NOT 11:42:56.712790 tftpClient: request server 0 ---> 172.16.0.7 335: NOT 11:42:56.731278 tftpClient: request server 1 ---> 336: NOT 11:42:56.773463 tftpClient: request server 0 ---> 172.16.0.7 337: NOT 11:42:56.799313 ESP: server 0 = 172.16.0.7 338: NOT 11:42:56.806570 tftpClient: request server6 0 ---> :: 339: NOT 11:42:56.809976 tftpClient: request server 1 ---> 340: NOT 11:42:56.811827 ESP: server 1 = 341: NOT 11:42:56.818084 tftpClient: request server6 1 ---> :: 342: NOT 11:42:56.827665 tftpClient: request server6 0 ---> :: 343: NOT 11:42:56.828384 ESP: server 2 = :: 344: NOT 11:42:56.832291 tftpClient: request server6 1 ---> :: 345: NOT 11:42:56.833006 ESP: server 3 = :: 346: NOT 11:42:56.834932 ESP: send ADMIN, logging = 1, shell = 0, ipconfig = 0 347: NOT 11:42:56.898992 tftpClient: request server 0 ---> 172.16.0.7 348: NOT 11:42:56.899742 ESP: server 0 = 172.16.0.7 349: NOT 11:42:56.906123 tftpClient: request server 1 ---> 350: NOT 11:42:56.906882 ESP: server 1 = 351: NOT 11:42:56.910698 tftpClient: request server6 0 ---> :: 352: NOT 11:42:56.911439 ESP: server 2 = :: 353: NOT 11:42:56.915077 tftpClient: request server6 1 ---> :: 354: NOT 11:42:56.915864 ESP: server 3 = :: 355: NOT 11:42:59.179062 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - Starting CTL/config file updating 356: NOT 11:42:59.182435 SECD: handleCTLupdReq: processing ctl update req 357: NOT 11:42:59.183010 SECD: updateCTL: starting CTL update 358: NOT 11:42:59.184882 SECD: ctlRequestFile: Socket 7 connected to /usr/tmp/tftpClientSock 359: NOT 11:42:59.185639 SECD: ctlRequestFile: Request CTLSEPD0574CF61C80.tlv 360: NOT 11:42:59.186794 tftpClient: tftp request rcv'd from /usr/tmp/ctlSock, srcFile = CTLSEPD0574CF61C80.tlv, dstFile = /usr/tmp/CTLFile.tlv 361: NOT 11:42:59.191699 tftpClient: auth server - tftpList[0] = ::ffff:172.16.0.7 362: NOT 11:42:59.192236 tftpClient: look up server - 0 363: WRN 11:42:59.193862 SECD: WARN:lookupCTL: CTL update in progress, no old CTL, assume TFTP NONSECURE 364: NOT 11:42:59.196015 tftpClient: secVal = 0xa 365: NOT 11:42:59.196618 tftpClient: ::ffff:172.16.0.7 is a NONsecure server 366: NOT 11:42:59.197085 tftpClient: temp retval = SRVR_NONSECURE, keep looking 367: NOT 11:42:59.197589 tftpClient: retval = 10 368: NOT 11:42:59.198059 tftpClient: Non secure file requested 369: NOT 11:42:59.223198 TFTP: [18]:Requesting CTLSEPD0574CF61C80.tlv from 172.16.0.7 370: NOT 11:42:59.226542 TFTP: [18]:Error --> file not found 371: NOT 11:42:59.232509 SYSMSG: pid 18 (/sbin/tftpd) Normal Exit, status = 2 372: INF 11:42:59.232549 runtime = 0.030 secs 373: INF 11:42:59.232568 user cpu = 0.001100110 secs 374: INF 11:42:59.232585 system cpu = 0.012917760 secs 375: INF 11:42:59.232598 child user cpu = 0.000000000 secs 376: INF 11:42:59.232622 child sys cpu = 0.000000000 secs 377: INF 11:42:59.232642 sys interrupts = 0.000546750 secs for 7 interrupts 378: INF 11:42:59.232664 total cpu = 0.014017870 secs ( 33% utilization ) 379: NOT 11:42:59.232668 SECD: ctlRequestFile: tftp Status 2 rcv'd 380: NOT 11:42:59.233437 SECD: updateCTL: finished CTL update 381: ERR 11:42:59.234026 SECD: EROR:updateCTL: ** had NO CTL and CTL tftp FAILED** tftp-err 2 382: NOT 11:42:59.284845 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - ConfigManager updateCTL() rc=2 retryCount=0 383: WRN 11:42:59.494943 SECD: WARN:getCTLInfo: ** phone has no CTL 384: NOT 11:42:59.505847 JVM: Startup Module Loader|cip.cfg.h:? - Requesting CONFIG file from TFTP Service(1) 385: ERR 11:42:59.512682 JVM: tftpClient SEPD0574CF61C80.cnf.xml /usr/ram/SEPD0574CF61C80.cnf.xml 550001 1 386: NOT 11:42:59.516215 tftpClient: tftp request rcv'd from /usr/tmp/tftp, srcFile = SEPD0574CF61C80.cnf.xml, dstFile = /usr/ram/SEPD0574CF61C80.cnf.xml max size = 550001 387: NOT 11:42:59.519431 tftpClient: auth server - tftpList[0] = ::ffff:172.16.0.7 388: NOT 11:42:59.519968 tftpClient: look up server - 0 389: WRN 11:42:59.521751 SECD: WARN:lookupCTL: ** no CTL, assume TFTP NONSECURE 390: NOT 11:42:59.523901 tftpClient: secVal = 0xa 391: NOT 11:42:59.524513 tftpClient: ::ffff:172.16.0.7 is a NONsecure server 392: NOT 11:42:59.525028 tftpClient: temp retval = SRVR_NONSECURE, keep looking 393: NOT 11:42:59.525513 tftpClient: retval = 10 394: NOT 11:42:59.525966 tftpClient: Secure file requested 395: NOT 11:42:59.526423 tftpClient: Non secure file approved -- SEPD0574CF61C80.cnf.xml 396: NOT 11:42:59.544850 TFTP: [19]:Requesting SEPD0574CF61C80.cnf.xml from 172.16.0.7 with size limit of 550001 397: NOT 11:42:59.584015 TFTP: [19]:Finished --> rcvd 6990 bytes 398: WRN 11:42:59.595539 SECD: WARN:lookupCTL: ** no CTL, assume TFTP NONSECURE 399: NOT 11:42:59.608745 JVM: Startup Module Loader|cip.cfg.h:? - Config handleTftpResponse, status=0 for file=ram/SEPD0574CF61C80.cnf.xml 400: WRN 11:43:00.394387 JVM: Startup Module Loader|cip.xml.at:parse - Encoding Updated to UTF-8 401: WRN 11:43:00.395757 JVM: Startup Module Loader|cip.xml.at: - XML Parser Warning: Unknown element 'timeTemplate' in element '/device/devicePool/dateTimeSetting' (line=10) 402: WRN 11:43:00.397129 JVM: Startup Module Loader|cip.xml.at: - XML Parser Warning: Unknown element 'loadInformation434' in element '/device' (line=137) 403: WRN 11:43:00.398572 JVM: Startup Module Loader|cip.xml.at: - XML Parser Warning: Unknown element 'sshAccess' in element '/device/vendorConfig' (line=147) 404: WRN 11:43:00.399932 JVM: Startup Module Loader|cip.xml.at: - XML Parser Warning: Unknown element 'sshPort' in element '/device/vendorConfig' (line=148) 405: WRN 11:43:00.401781 JVM: Startup Module Loader|cip.xml.at: - XML Parser Warning: Unknown element 'uid' in element '/device/networkLocaleInfo' (line=166) 406: NOT 11:43:00.406817 SECD: clearTFTPList: cleared all TFTP entries 407: NOT 11:43:00.424179 INETD: Set IP mode 1 408: ERR 11:43:00.430312 enter in6_if_brcm_reset 409: ERR 11:43:00.430713 exit in6_if_brcm_reset 410: NOT 11:43:00.449760 PAE: SIGIPCFG received... 411: NOT 11:43:00.476121 ESP: send ADMIN, logging = 1, shell = 0, ipconfig = 0 412: NOT 11:43:00.478222 CDP-D: catchipcfg:getdhcpinfo IP:ac1000f5 domain: chngVal:1 413: ERR 11:43:00.482419 RTSOLD: opvvlan:4095->4095 advvlan:4096->4096 vvlanState=1 414: ERR 11:43:00.484178 RTSOLD: LINK -> LINK 415: ERR 11:43:00.484695 RTSOLD:address mode changed.. 416: NOT 11:43:00.490942 tftpClient: request server 0 ---> 172.16.0.7 417: NOT 11:43:00.491657 ESP: server 0 = 172.16.0.7 418: NOT 11:43:00.495163 tftpClient: request server 1 ---> 419: NOT 11:43:00.495877 ESP: server 1 = 420: NOT 11:43:00.499411 tftpClient: request server6 0 ---> :: 421: NOT 11:43:00.500606 ESP: server 2 = :: 422: NOT 11:43:00.504213 tftpClient: request server6 1 ---> :: 423: NOT 11:43:00.504944 ESP: server 3 = :: 424: NOT 11:43:00.537512 CDP-D: cdpSetRepeater 11 425: NOT 11:43:00.552404 PAE: SIGIPCFG received... 426: NOT 11:43:00.553353 ESP: send ADMIN, logging = 1, shell = 0, ipconfig = 0 427: WRN 11:43:01.063857 SECD: WARN:getCTLInfo: ** phone has no CTL 428: WRN 11:43:01.067815 SECD: WARN:getCTLInfo: ** phone has no CTL 429: ERR 11:43:01.196827 JVM: Startup Module Loader|cip.setg.ScreenSaveProperty:? - Config file field <displayOnTime> is incorrectly formatted. Defaulting. 430: ERR 11:43:01.198303 JVM: Startup Module Loader|cip.setg.ScreenSaveProperty:? - Config file field <displayIdleTimeout> is incorrectly formatted. Defaulting. 431: NOT 11:43:01.199710 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.localization.userlocale.charset value=utf-8 432: NOT 11:43:01.201252 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.config.localization.userlocale.languagecode value=ru_RU 433: NOT 11:43:01.208268 SECD: setSecMode: sec mode set to NONE (was NONE) 434: NOT 11:43:02.188560 JVM: Startup Module Loader|cip.cfg.h: - Entering setProtocolConfigProperties() at 1260952981865 millisecs....<RemoveItLater> 435: NOT 11:43:02.191755 JVM: Startup Module Loader|cip.cfg.h: - Exiting setProtocolConfigProperties() at 1260952982184 millisecs....<RemoveItLater> 436: NOT 11:43:02.332834 SECD: clearSRSTList: cleared all SRST entries 437: NOT 11:43:02.345427 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.callagent.messages.0 value=0 438: NOT 11:43:02.346789 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.callagent.messages.0 value=0 439: WRN 11:43:02.536882 SECD: WARN:cancelCapfOp: CAPF not in use, user cancel ignored 440: NOT 11:43:02.537494 SECD: clearCapfList: CAPF table cleared 441: NOT 11:43:02.545706 JVM: Startup Module Loader|cip.cfg.h:? - CUCM in config file: #0 XmlCallAgentObject: IPv4-name=[172.16.0.21] IPv6-name=[] port=5555 priority=0 442: NOT 11:43:02.547141 JVM: Startup Module Loader|cip.cfg.h:? - CUCM in config file: #1 XmlCallAgentObject: IPv4-name=[] IPv6-name=[] port=0 priority=32767 443: NOT 11:43:02.548506 JVM: Startup Module Loader|cip.cfg.h:? - CUCM in config file: #2 XmlCallAgentObject: IPv4-name=[] IPv6-name=[] port=0 priority=32767 444: NOT 11:43:02.549871 JVM: Startup Module Loader|cip.cfg.h:? - CUCM in config file: #3 XmlCallAgentObject: IPv4-name=[] IPv6-name=[] port=0 priority=32767 445: NOT 11:43:02.551696 JVM: Startup Module Loader|cip.cfg.h:? - CUCM in config file: #4 XmlCallAgentObject: IPv4-name=[] IPv6-name=[] port=0 priority=32767 446: ERR 11:43:02.553740 JVM: Startup Module Loader|cip.sec.CapfProperty:? - Failed to resolve Capf Ipv4 Address with hostname 447: ERR 11:43:02.555102 JVM: Startup Module Loader|cip.sec.CapfProperty:? - Failed to resolve Capf Ipv6 Address with hostname 448: ERR 11:43:02.557119 JVM: Startup Module Loader|cip.sec.CapfProperty:? - No valid CAPF server 449: NOT 11:43:02.558462 JVM: Startup Module Loader|cip.cfg.h:? - Config processConfigNoError() result code=CONFIG_FILE_NO_CHANGE 450: NOT 11:43:02.560875 SECD: setSecMode: sec mode set to NONE (was NONE) 451: NOT 11:43:02.898611 JVM: Startup Module Loader - Deletion of file Successful/usr/ram/SEPD0574CF61C80.cnf.xml 452: NOT 11:43:02.899960 JVM: Startup Module Loader|cip.cfg.h:? - DELETE ConfigFile:(ram/SEPD0574CF61C80.cnf.xml)WAS SUCCESSFUL 453: NOT 11:43:02.901420 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - ConfigManager PropertyChanged: device.config.io.dscp.config 454: NOT 11:43:03.413674 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.callagent.messages.0 value=0 455: NOT 11:43:03.415036 JVM: Startup Module Loader|cip.sipcc.d: - initializeLinePlane(): Mgmt Interface is in Service now.. 456: ERR 11:43:03.678141 JVM: tftpClient dialplan.xml /usr/cache/DP898053080 550001 1 457: NOT 11:43:04.971130 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.callagent.messages.0 value=0 458: ERR 11:43:04.972470 JVM: Startup Module Loader|cip.sipcc.SipCcAdapter: - Last available line is 2 459: NOT 11:43:05.539990 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.callagent.messages.0 value=0 460: NOT 11:43:05.542934 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.callagent.messages.0 value=0 461: NOT 11:43:05.545998 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.callagent.messages.0 value=0 462: NOT 11:43:05.548883 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.callagent.messages.0 value=0 463: NOT 11:43:05.552265 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - propertyChanged - device.settings.fullyregistered value=true 464: NOT 11:43:05.554643 JVM: Startup Module Loader|cip.midp.midletsuite.InstallerModule:? - FULLY_REGISTERED - Resetting retry installer interval 465: NOT 11:43:05.559466 JVM: Startup Module Loader|cip.midp.pushregistry.f:? - setAcceptConnections - ENABLED 466: NOT 11:43:05.580493 DHCP6: sockTos = 0x60 467: NOT 11:43:05.615721 tftpClient: tftp request rcv'd from /usr/tmp/tftp, srcFile = dialplan.xml, dstFile = /usr/cache/DP898053080 max size = 550001 468: NOT 11:43:05.616641 DNS: SIGHUP received... 469: NOT 11:43:05.622216 CDP-D: catchipcfg:getdhcpinfo IP:ac1000f5 domain: chngVal:1 470: NOT 11:43:05.623245 CDP-D: set pc vvlan access --> 26 471: WRN 11:43:05.638949 JVM: Startup Module Loader|cip.mmgr.dt:? - [MediaMgrSM]: Unhandled Event, State = StateOnHook Event = EventServicesTxStop 472: NOT 11:43:05.644903 tftpClient: auth server - tftpList[0] = ::ffff:172.16.0.7 473: NOT 11:43:05.645425 tftpClient: look up server - 0 474: WRN 11:43:05.647125 SECD: WARN:lookupCTL: ** no CTL, assume TFTP NONSECURE 475: NOT 11:43:05.649415 tftpClient: secVal = 0xa 476: NOT 11:43:05.650245 tftpClient: ::ffff:172.16.0.7 is a NONsecure server 477: NOT 11:43:05.650797 tftpClient: temp retval = SRVR_NONSECURE, keep looking 478: NOT 11:43:05.651268 tftpClient: retval = 10 479: NOT 11:43:05.651734 tftpClient: Secure file requested 480: NOT 11:43:05.652202 tftpClient: Non secure file approved -- dialplan.xml 481: ERR 11:43:05.678246 RTSOLD: opvvlan:4095->4095 advvlan:4096->4096 vvlanState=1 482: NOT 11:43:05.679833 TFTP: [10]:Requesting dialplan.xml from 172.16.0.7 with size limit of 550001 483: ERR 11:43:05.681879 RTSOLD: LINK -> LINK 484: NOT 11:43:05.685415 TFTP: [10]:Finished --> rcvd 91 bytes 485: NOT 11:43:05.937143 tftpClient: request server 0 ---> 172.16.0.7 486: NOT 11:43:05.941283 tftpClient: request server 0 ---> 172.16.0.7 487: NOT 11:43:05.942244 ESP: server 0 = 172.16.0.7 488: NOT 11:43:05.956348 tftpClient: request server 1 ---> 489: NOT 11:43:05.965295 tftpClient: request server 1 ---> 490: NOT 11:43:05.967375 ESP: server 1 = 491: NOT 11:43:05.979874 tftpClient: request server6 0 ---> :: 492: NOT 11:43:05.983029 ESP: server 2 = :: 493: NOT 11:43:05.992770 tftpClient: request server6 1 ---> :: 494: NOT 11:43:05.994987 ESP: server 3 = :: 495: NOT 11:43:06.045134 tftpClient: request server6 0 ---> :: 496: NOT 11:43:06.056210 tftpClient: request server6 1 ---> :: 497: NOT 11:43:06.071325 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - ConfigManager PropertyChanged: device.settings.network.ipstacksstate 498: NOT 11:43:06.072727 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - ConfigManager IP_STACKS_STATE changed 499: NOT 11:43:06.074082 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - IPv4 state=DHCP BOUND 500: NOT 11:43:06.075429 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - IPv6 state=STACK TURNED OFF 501: WRN 11:43:06.080604 SECD: WARN:getCTLInfo: ** phone has no CTL 502: WRN 11:43:06.085089 SECD: WARN:getCTLInfo: ** phone has no CTL 503: NOT 11:43:06.098610 tftpClient: request server 0 ---> 172.16.0.7 504: NOT 11:43:06.113925 tftpClient: request server 1 ---> 505: NOT 11:43:06.184582 tftpClient: request server6 0 ---> :: 506: NOT 11:43:06.193985 tftpClient: request server6 1 ---> :: 

ни на астериске, ни на телефоне не видно упоминай о попытке регистрации.

подскажите в какую сторону копать?