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 ---> ::

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

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