Tek-Tips is the largest IT community on the Internet today!

Members share and learn making Tek-Tips Forums the best source of peer-reviewed technical information on the Internet!

  • Congratulations TouchToneTommy on being selected by the Tek-Tips community for having the most helpful posts in the forums last week. Way to Go!

IPO 11.0 - Phones Keep Rebooting

Status
Not open for further replies.
Nov 22, 2013
598
US
IPO noob here.

I have a new IPO setup and the phones keep rebooting every 10-15 minutes. I enabled syslog on the phones and I am not sure what is causing the problem since there is so much info in the debug log. I suspect a databsae error with the users but not sure. For simplicity I deleted all users but 1 user and still get the reboots.

One issue that happens a lot is the following.
2024-04-07 18:21:29 Local4.Notice 10.1.1.55 Apr 7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 485 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist

Here is a snip of the phone syslog, perhaps someone might catch something off. Any help is apprecaited.





Code:
2024-04-07 18:18:33	Local4.Notice	10.1.1.55	Apr  7 17:18:29 10.1.1.55 SECURITY: +01:00 2024 902 1 .TEL | 0 CEncryptionUtils::Base64Decode(): EVP_DecodeFinal failed
2024-04-07 18:18:33	Local4.Notice	10.1.1.55	Apr  7 17:18:29 10.1.1.55 CORE: +01:00 2024 948 1 .TEL | 0 CShutdownManager::ShutdownPause(): Reason: CConfigDataAdaptorsManager::OnConfigDataRead. Thread ID 585 (dhcp_eth0)
2024-04-07 18:18:33	Local4.Notice	10.1.1.55	Apr  7 17:18:29 10.1.1.55 SECURITY: +01:00 2024 996 1 .TEL | 0 CEncryptionUtils::Base64Decode(): EVP_DecodeFinal failed
2024-04-07 18:18:33	Local4.Notice	10.1.1.55	Apr  7 17:18:30 10.1.1.55 CORE: +01:00 2024 282 1 .TEL | 0 CFile::Remove(): pszPath = /AvayaDir/application/configcache.xml.tmp, FAILED. 
2024-04-07 18:18:33	Local4.Notice	10.1.1.55	Apr  7 17:18:30 10.1.1.55 CORE: +01:00 2024 286 1 .TEL | 0 CShutdownManager::ShutdownUnPause(): Reason: CConfigDataAdaptorsManager::OnConfigDataRead. Thread ID 585 (dhcp_eth0)
2024-04-07 18:18:33	Local4.Notice	10.1.1.55	Apr  7 17:18:30 10.1.1.55 SECURITY: +01:00 2024 322 1 .TEL | 0 CEncryptionUtils::Base64Decode(): EVP_DecodeFinal failed
2024-04-07 18:18:33	Local4.Notice	10.1.1.55	Apr  7 17:18:30 10.1.1.55 CORE: +01:00 2024 389 1 .TEL | 0 CShutdownManager::ShutdownPause(): Reason: CConfigDataAdaptorsManager::OnConfigDataRead. Thread ID 585 (dhcp_eth0)
2024-04-07 18:18:33	Local4.Notice	10.1.1.55	Apr  7 17:18:30 10.1.1.55 CORE: +01:00 2024 677 1 .TEL | 0 CFile::Remove(): pszPath = /AvayaDir/application/configcache.xml.tmp, FAILED. 
2024-04-07 18:18:33	Local4.Notice	10.1.1.55	Apr  7 17:18:30 10.1.1.55 CORE: +01:00 2024 682 1 .TEL | 0 CShutdownManager::ShutdownUnPause(): Reason: CConfigDataAdaptorsManager::OnConfigDataRead. Thread ID 585 (dhcp_eth0)
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 SECURITY: +01:00 2024 049 1 .TEL | 0 CEncryptionUtils::Base64Decode(): EVP_DecodeFinal failed
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 NETMGR: +01:00 2024 233 1 .TEL | 0 CNetworkManager::UpdateNetworkConfiguration(): New L2Q parameters. Resetting
2024-04-07 18:18:34	Local4.Error	10.1.1.55	Apr  7 17:18:31 10.1.1.55 NETMGR: +01:00 2024 329 1 .TEL | 1 CNetworkManager::UpdateNetworkConfiguration(): .TEL RESET-501 Resetting to change from VLAN 0 to VLAN 2
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 CORE: +01:00 2024 488 1 .TEL | 0 CClientPlatform::OnMessageReceived(): m_bIdle = true, m_bShutdownWhenIdle = true, m_bCertificatesProcessed = true, m_bShutdownWhenCertificatesProcessed = false
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 POWER: +01:00 2024 495 1 .TEL | 0 CPowerManager::OnMessageReceived(): eMSG_SHUTDOWN_REQUEST received
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 POWER: +01:00 2024 499 1 .TEL | 0 CUsbEventMonitor::StopUsbEventMonitor(): tUsbEventMonitor is running. Stop it now!
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 CORE: +01:00 2024 510 1 .TEL | 0 CClientPlatform::Shutdown(): Entry. Reason Received eMSG_SHUTDOWN_REQUEST
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 CORE: +01:00 2024 519 1 .TEL | 0 CShutdownManager::ShutdownPause(): Reason: :IsRegistered(). Logging off first. Thread ID 537 (tPhoneAppMessag)
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 MODMGR: +01:00 2024 524 1 .TEL | 0 CBMModelController::OnBootStateChanged(): pBMInterface is NULL!'
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 SCRIPTDATA: +01:00 2024 615 1 .TEL | 0 CScriptDataAdaptor::StopProcessing(): Adaptor : 'ScriptData'
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 CORE: +01:00 2024 625 1 .TEL | 0 CShutdownManager::InitiateReboot(): ############## Entry from thread 500 (Default) with Reason[Received eMSG_SHUTDOWN_REQUEST]
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 CORE: +01:00 2024 635 1 .TEL | 0 CShutdownManager::InitiateReboot(): Shutdown being paused because 1 threads busy writing. (Active call? true)
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 UI-MODEL: +01:00 2024 679 1 .TEL | 0 CRegistrationStateModelController::SetRegState(): LoggedIn -> LoggingOut
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 ALSIP: +01:00 2024 730 1 .TEL | 0 CLineSet::ByeResponse(): line<64> Invalid refer state [0], delete session context!
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 ALSIP: +01:00 2024 737 1 .TEL | 0 CLineSet::TransactionCompleted(): SM[0]: line <64> could not find session context!
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 UI-MODEL: +01:00 2024 777 1 .TEL | 0 CFeaturesModelController::ExecuteSetFeatureUserInputCommand(): Couldn't find feature [bwcc-acd-unavailable]
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 UI-APP: +01:00 2024 793 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 UI-APP: +01:00 2024 799 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:18:34	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 UI-APP: +01:00 2024 805 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 UI-APP: +01:00 2024 812 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 UI-APP: +01:00 2024 819 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 UI-APP: +01:00 2024 826 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 UI-APP: +01:00 2024 835 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 UI-APP: +01:00 2024 843 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 UI-APP: +01:00 2024 849 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 UI-APP: +01:00 2024 876 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 UI-APP: +01:00 2024 882 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 UI-APP: +01:00 2024 888 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 UI-APP: +01:00 2024 899 1 .TEL | 0 CViewContextManager::CanViewBeActivated(): (login_status): Attempting to activate a secondary view when view [shutdown] is active
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:31 10.1.1.55 NETADAP: +01:00 2024 844 1 .TEL | 0 CUnixEthernetNetworkAdaptor::GetEEEPort(): EEE mode is not supported
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 NETMGR: +01:00 2024 024 1 .TEL | 0 CNetworkManager::CheckEEESupport(): Failed to get EEE from selected net interface Mgr
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 NETMGR: +01:00 2024 028 1 .TEL | 0 CNetworkManager::CheckEEESupport(): m_LastEEEcommand=-1, nEEE_Config=1, nEEE_Current=0
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 NETADAP: +01:00 2024 031 1 .TEL | 0 CUnixEthernetNetworkAdaptor::SetEEEPort(): EEE mode is not supported
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 NETMGR: +01:00 2024 035 1 .TEL | 0 CNetworkManager::CheckEEESupport(): Failed to set EEE to 1 on selected net Interface
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 NETADAP: +01:00 2024 051 1 .TEL | 0 CLinuxNetworkUtils::GetLocalIPAddressFromNetworkAdaptor(): IP address of Adaptor 'eth0' = '10.1.1.55'.
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 NETADAP: +01:00 2024 082 1 .TEL | 0 CLinuxNetworkUtils::GetLocalIPAddressFromNetworkAdaptor(): IP address of Adaptor 'eth0' = '10.1.1.55'.
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 AUDIO: +01:00 2024 090 1 .TEL | 0 IDSPGAudioInterface::EndSession(): RTP session 0 has not been started yet
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 AUDIO: +01:00 2024 094 1 .TEL | 0 IDSPGAudioInterface::ReleaseCallSession(): no channel found for session ID 1
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 SM: +01:00 2024 127 1 .TEL | 0 CSharedLineManager::SetState(): SM[-1] State[Inactive], Changing state from Inactive to Inactive
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 ALSIP: +01:00 2024 141 1 .TEL | 0 CSIPServer::OpenConnection(): ConnectTimeOut - Addr:[10.1.1.50] Port:[5060] Proto:[313] SM Id:[0]
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 ALSIP: +01:00 2024 147 1 .TEL | 0 CLineSet::Register(): line <733>: failed to send register request, delete session context!
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 SIGADAP: +01:00 2024 152 1 .TEL | 0 CALSIPAdaptor::StartUnregistration(): Unregistering user sip:210@digifix.org failed. Error: Line Manager Error: Cannot send sip message..
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 CORE: +01:00 2024 194 1 .TEL | 0 CThread::End(): Thread[tUAClientSocketReceiver] has been ended 0
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 MSM: +01:00 2024 219 1 .TEL | 0 CMasterSignalingManager::ChangeState(): Application is in process of shutdown. Changing state from (CONTROLLER_SHUTDOWN) to (INIT) was aborted.
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 UI-MODEL: +01:00 2024 225 1 .TEL | 0 CRegistrationStateModelController::ProcessLoginStatusEvent(): state = LoggingOut, reg = no, code = 0
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 UI-MODEL: +01:00 2024 229 1 .TEL | 0 CRegistrationStateModelController::SetRegState(): LoggingOut -> ReadyForLogin
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 UI-APP: +01:00 2024 248 1 .TEL | 0 CViewContextManager::CanViewBeActivated(): (login): Attempting to activate a secondary view when view [shutdown] is active
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 CORE: +01:00 2024 304 1 .TEL | 0 CShutdownManager::ShutdownUnPause(): Reason: User is Logged off now. Thread ID 537 (tPhoneAppMessag)
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 SCRIPTDATA: +01:00 2024 307 1 .TEL | 0 CScriptDataAdaptor::StopProcessing(): Adaptor : 'WebScriptData'
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 SCRIPTDATA: +01:00 2024 318 1 .TEL | 0 CScriptDataAdaptor::StopProcessing(): Adaptor : 'ScriptData'
2024-04-07 18:18:35	Local4.Notice	10.1.1.55	Apr  7 17:18:32 10.1.1.55 SCRIPTDATA: +01:00 2024 345 1 .TEL | 0 CScriptDataAdaptor::StopProcessing(): Adaptor : 'WebScriptData'
2024-04-07 18:21:21	Local4.Notice	10.1.1.55	Apr  7 17:19:58 0.0.0.0 EEPROMDATA: +01:00 2024 196 1 .TEL | 0 CAppEndpoint::CompleteInitialization(): Model is J159D01A
2024-04-07 18:21:21	Local4.Notice	10.1.1.55	Apr  7 17:19:59 10.1.1.55 UI: +01:00 2024 724 1 .TEL | 0 CSkinSystemNewUi::FindComponentDefinition(): cannot find CCMSHeaderLine component
2024-04-07 18:21:21	Local4.Notice	10.1.1.55	Apr  7 17:19:59 10.1.1.55 UI: +01:00 2024 800 1 .TEL | 0 CSkinSystemNewUi::FindComponentDefinition(): cannot find CCMSHeaderLine component
2024-04-07 18:21:21	Local4.Notice	10.1.1.55	Apr  7 17:19:59 10.1.1.55 CORE: +01:00 2024 830 1 .TEL | 0 CShutdownManager::ShutdownUnPause(): Reason: CEndpoint::OnNetworkReady. Thread ID 504 (Default)
2024-04-07 18:21:21	Local4.Notice	10.1.1.55	Apr  7 17:19:59 10.1.1.55 UI-APP: +01:00 2024 903 1 .TEL | 0 CViewFactoryJ159::CreateBTNoDeviceConnectedView(): Not Implemented
2024-04-07 18:21:22	Local4.Notice	10.1.1.55	Apr  7 17:20:00 10.1.1.55 UI-APP: +01:00 2024 198 1 .TEL | 0 CViewFactoryJ159::CreateBluetoothMenuView(): Not Implemented
2024-04-07 18:21:22	Local4.Notice	10.1.1.55	Apr  7 17:20:00 10.1.1.55 UI-APP: +01:00 2024 202 1 .TEL | 0 CViewFactoryJ159::CreateBluetoothDevicesView(): Not Implemented
2024-04-07 18:21:22	Local4.Notice	10.1.1.55	Apr  7 17:20:00 10.1.1.55 UI-APP: +01:00 2024 206 1 .TEL | 0 CViewFactoryJ159::CreateBluetoothHelpView(): Not Implemented
2024-04-07 18:21:22	Local4.Notice	10.1.1.55	Apr  7 17:20:00 10.1.1.55 UI-APP: +01:00 2024 209 1 .TEL | 0 CViewFactoryJ159::CreateBTNoAudioDeviceView(): Not Implemented
2024-04-07 18:21:22	Local4.Notice	10.1.1.55	Apr  7 17:20:00 10.1.1.55 NETADAP: +01:00 2024 246 1 .TEL | 0 CLinuxNetworkUtils::GetLocalIPAddressFromNetworkAdaptor(): IP address of Adaptor 'eth0' = '10.1.1.55'.
2024-04-07 18:21:22	Local4.Notice	10.1.1.55	Apr  7 17:20:00 10.1.1.55 UI-MODEL: +01:00 2024 331 1 .TEL | 0 CRegistrationStateModelController::LoginPrimary(): primary user's name or password is empty
2024-04-07 18:21:22	Local4.Notice	10.1.1.55	Apr  7 17:20:00 10.1.1.55 UI-MODEL: +01:00 2024 364 1 .TEL | 0 CRegistrationStateModelController::SetRegState(): Init -> VerifyingCredentials
2024-04-07 18:21:22	Local4.Notice	10.1.1.55	Apr  7 17:20:00 10.1.1.55 CORE: +01:00 2024 748 1 .TEL | 0 CSyncLock::Unlock(): m_nLockId = 522, Unable to UNLOCK: err: 1, Operation not permitted
2024-04-07 18:21:23	Local4.Notice	10.1.1.55	Apr  7 17:20:01 10.1.1.55 UI-MODEL: +01:00 2024 205 1 .TEL | 0 CRegistrationStateModelController::SetRegState(): VerifyingCredentials -> VerifyingCredentials
2024-04-07 18:21:23	Local4.Notice	10.1.1.55	Apr  7 17:20:01 10.1.1.55 POWER: +01:00 2024 715 1 .TEL | 0 CPowerManager::PowerManagerInitialize(): Phone Power Max(224) Cons (140) Typical (167)
2024-04-07 18:21:24	Local4.Notice	10.1.1.55	Apr  7 17:20:02 10.1.1.55 NETADAP: +01:00 2024 767 1 .TEL | 0 CUnixEthernetNetworkAdaptor::GetEEEPort(): EEE mode is not supported
2024-04-07 18:21:24	Local4.Notice	10.1.1.55	Apr  7 17:20:02 10.1.1.55 NETMGR: +01:00 2024 797 1 .TEL | 0 CNetworkManager::CheckEEESupport(): Failed to get EEE from selected net interface Mgr
2024-04-07 18:21:24	Local4.Notice	10.1.1.55	Apr  7 17:20:02 10.1.1.55 NETMGR: +01:00 2024 801 1 .TEL | 0 CNetworkManager::CheckEEESupport(): m_LastEEEcommand=-1, nEEE_Config=1, nEEE_Current=0
2024-04-07 18:21:24	Local4.Notice	10.1.1.55	Apr  7 17:20:02 10.1.1.55 NETADAP: +01:00 2024 805 1 .TEL | 0 CUnixEthernetNetworkAdaptor::SetEEEPort(): EEE mode is not supported
2024-04-07 18:21:24	Local4.Notice	10.1.1.55	Apr  7 17:20:02 10.1.1.55 CONFIG: +01:00 2024 814 1 .TEL | 0 CConfigDataAdaptorsManager::SaveToAdaptor(): to backup adaptor failed - adaptor not initialized.
2024-04-07 18:21:24	Local4.Notice	10.1.1.55	Apr  7 17:20:03 10.1.1.55 CORE: +01:00 2024 107 1 .TEL | 0 CThread::End(): Thread[tLocalDataWriter] has been ended 0
2024-04-07 18:21:25	Local4.Notice	10.1.1.55	Apr  7 17:20:03 10.1.1.55 NETMGR: +01:00 2024 818 1 .TEL | 0 CNetworkManager::CheckEEESupport(): Failed to set EEE to 1 on selected net Interface
2024-04-07 18:21:25	Local4.Notice	10.1.1.55	Apr  7 17:20:03 10.1.1.55 NETADAP: +01:00 2024 164 1 .TEL | 0 CLinuxNetworkUtils::GetLocalIPAddressFromNetworkAdaptor(): IP address of Adaptor 'eth0' = '10.1.1.55'.
2024-04-07 18:21:25	Local4.Notice	10.1.1.55	Apr  7 17:20:03 10.1.1.55 NETADAP: +01:00 2024 200 1 .TEL | 0 CLinuxNetworkUtils::GetLocalIPAddressFromNetworkAdaptor(): IP address of Adaptor 'eth0' = '10.1.1.55'.
2024-04-07 18:21:25	Local4.Notice	10.1.1.55	Apr  7 17:20:03 10.1.1.55 CORE: +01:00 2024 223 1 .TEL | 0 CSyncLock::Unlock(): m_nLockId = 559, Unable to UNLOCK: err: 1, Operation not permitted
2024-04-07 18:21:25	Local4.Notice	10.1.1.55	Apr  7 17:20:03 10.1.1.55 CORE: +01:00 2024 261 1 .TEL | 0 CSyncLock::Unlock(): m_nLockId = 566, Unable to UNLOCK: err: 1, Operation not permitted
2024-04-07 18:21:25	Local4.Notice	10.1.1.55	Apr  7 17:20:03 10.1.1.55 ALSIP: +01:00 2024 305 1 .TEL | 0 CLineSet::Initialize(): LineSet name is Reg_9658!
2024-04-07 18:21:25	Local4.Notice	10.1.1.55	Apr  7 17:20:03 10.1.1.55 ALSIP: +01:00 2024 344 1 .TEL | 0 CLineSet::Initialize(): LineSet name is sip:210@10.1.1.55!
2024-04-07 18:21:25	Local4.Notice	10.1.1.55	Apr  7 17:20:03 10.1.1.55 ALSIP: +01:00 2024 639 1 .TEL | 0 CSipMessage::IsSupportedValue(): Cannot verify option vnd.avaya.ipo
2024-04-07 18:21:25	Local4.Notice	10.1.1.55	Apr  7 17:20:03 10.1.1.55 UI-MODEL: +01:00 2024 929 1 .TEL | 0 CRegistrationStateModelController::SetRegState(): VerifyingCredentials -> LoggingIn
2024-04-07 18:21:25	Local4.Notice	10.1.1.55	Apr  7 17:20:04 10.1.1.55 CORE: +01:00 2024 047 1 .TEL | 0 CFile::Remove(): pszPath = /AvayaDir/application/configcache.xml.tmp, FAILED. 
2024-04-07 18:21:25	Local4.Notice	10.1.1.55	Apr  7 17:20:04 10.1.1.55 CORE: +01:00 2024 061 1 .TEL | 0 CThread::End(): Thread[tTriggerFailedPPMDownloadThread] has been ended 0
2024-04-07 18:21:26	Local4.Notice	10.1.1.55	Apr  7 17:20:04 10.1.1.55 UI-MODEL: +01:00 2024 234 1 .TEL | 0 CFeaturesModelController::ExecuteSetFeatureUserInputCommand(): Couldn't find feature [bwcc-acd-unavailable]
2024-04-07 18:21:26	Local4.Notice	10.1.1.55	Apr  7 17:20:04 10.1.1.55 UI-MODEL: +01:00 2024 360 1 .TEL | 0 CRegistrationStateModelController::SetRegState(): LoggingIn -> LoggedIn
2024-04-07 18:21:26	Local4.Notice	10.1.1.55	Apr  7 17:20:04 10.1.1.55 UI-MODEL: +01:00 2024 603 1 .TEL | 0 CActivityModelController::Subscribe(): unexpected nTimeout[0]
2024-04-07 18:21:26	Local4.Notice	10.1.1.55	Apr  7 17:20:04 10.1.1.55 CCMS: +01:00 2024 952 1 .TEL | 0 CCCMSModelController::StartCCMSSession(): is called twice before create session response is received [1].
2024-04-07 18:21:26	Local4.Notice	10.1.1.55	Apr  7 17:20:04 10.1.1.55 UI-MODEL: +01:00 2024 959 1 .TEL | 0 CRegistrationStateModelController::ProcessLoginStatusEvent(): state = LoggedIn, reg = yes, code = 0
2024-04-07 18:21:27	Local4.Notice	10.1.1.55	Apr  7 17:20:05 10.1.1.55 ALSIP: +01:00 2024 195 1 .TEL | 0 CLineSet::InfoResponse(): Info response received at the middle of other transaction: state=LINESTATE_CONNECTED
2024-04-07 18:21:27	Local4.Notice	10.1.1.55	Apr  7 17:20:05 10.1.1.55 CCMP: +01:00 2024 240 1 .TEL | 0 CCCMPAdaptor::OnNetworkMessageReceived(): Can not find the pending request or observer is NULL.
2024-04-07 18:21:27	Local4.Notice	10.1.1.55	Apr  7 17:20:05 10.1.1.55 ALSIP: +01:00 2024 266 1 .TEL | 0 CLineSet::InfoResponse(): Info response received at the middle of other transaction: state=LINESTATE_CONNECTED
2024-04-07 18:21:27	Local4.Notice	10.1.1.55	Apr  7 17:20:05 10.1.1.55 LMC: +01:00 2024 260 1 .TEL | 0 CLineModel::GetSessionAndValidate(): Session do not Exist. ConnId[1]
2024-04-07 18:21:27	Local4.Notice	10.1.1.55	Apr  7 17:20:05 10.1.1.55 CCMP: +01:00 2024 298 1 .TEL | 0 CCCMPAdaptor::OnNetworkMessageReceived(): Can not find the pending request or observer is NULL.
2024-04-07 18:21:27	Local4.Notice	10.1.1.55	Apr  7 17:20:05 10.1.1.55 LMC: +01:00 2024 301 1 .TEL | 0 CLineModelController::ProcessEstablishedEvent(): ERROR - Can not get a valid session data for Connection Id[1]
2024-04-07 18:21:27	Local4.Notice	10.1.1.55	Apr  7 17:21:26 10.1.1.55 NETADAP: +01:00 2024 160 1 .TEL | 0 CLinuxNetworkUtils::GetLocalIPAddressFromNetworkAdaptor(): IP address of Adaptor 'eth0' = '10.1.1.55'.
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:27 10.1.1.55 UI-APP: +01:00 2024 964 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:27 10.1.1.55 UI-APP: +01:00 2024 975 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:27 10.1.1.55 UI-APP: +01:00 2024 983 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:27 10.1.1.55 UI-APP: +01:00 2024 991 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 000 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 006 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 058 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 067 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 077 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 089 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 008 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 054 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 064 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 071 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 081 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 088 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 094 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 109 1 .TEL | 0 CComponentLine::SetValue(): Field [CCMSField1] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 212 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 220 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 255 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 263 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 270 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 278 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 287 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 298 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 305 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 318 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 353 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 361 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 369 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 378 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 390 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 398 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 405 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 415 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 423 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 458 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 467 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 478 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 485 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 493 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 501 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 509 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 519 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 555 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 566 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:29	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 574 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:30	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 582 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:30	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 590 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:30	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 597 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:30	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 604 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:30	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 613 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:30	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 653 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:30	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 660 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:30	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 668 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:30	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 675 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:30	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 683 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:30	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 690 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:21:30	Local4.Notice	10.1.1.55	Apr  7 17:21:28 10.1.1.55 UI-APP: +01:00 2024 700 1 .TEL | 0 CComponentLine::SetValue(): Field [lineIcon] doesn't exist
2024-04-07 18:22:57	Local4.Notice	10.1.1.55	Apr  7 17:22:54 10.1.1.55 REG: +01:00 2024 771 1 .TEL | 0 CRegistrationManager::SetRegistrationStateOnConnectionAttempt(): Connection attempt failed. 
2024-04-07 18:22:57	Local4.Notice	10.1.1.55	Apr  7 17:22:54 10.1.1.55 ALSIP: +01:00 2024 812 1 .TEL | 0 CSipMessage::IsSupportedValue(): Cannot verify option vnd.avaya.ipo



 
They are SIP phones, so cannot be plugged into IPO, need POE.
And it is on the same switch, a local no management 1gb switch. I have no problems with my aura stuff on the same switch. I have not had much time to dig any deeper into this issue, hoping to get more time soon..




 
I didn't suggest you plug the SIP phone directly into the IP Office.
The suggestion was to move it off your network switch to eliminate that as an issue.

11.0 didn't support all the J Series SIP devices. What version of IPO are you on? What is the phone model? J139, J159, J179.
Need more info.

 
I am using IPO 11.0 GA.with J159, J179 series phones
According to the compatibility matrix, J100 phones are supported from versions 1.0 - 4.0.5 firmware, I am using 4.0.4 firmware which was supplied with IPO on the initial install. The J100 phones connect to the IPO and download the firmware and I am able to login, IPO even detects them as J100 series phones.

Info from the following doc on page 69

However I will try a 9611g SIP as well to see if there is any difference.
 
From the document you linked:

The J139/J159/J169/J179 SIP phones are supported from IP Office Release 11.0 (the J139 from SP1, J159 from FP4 SP1).

For the J159 you need at least Feature Pack 4, Service Pack 1, not the GA release.

I would upgrade to the latest release, 11.0.4.8.0 to see if that resolves it.

“Some humans would do anything to see if it was possible to do it.
If you put a large switch in some cave somewhere, with a sign on it saying 'End-of-the-World Switch. PLEASE DO NOT TOUCH'.
The paint wouldn't even have time to dry.”

Terry Pratchet
 
Found an error in the monitor log, it was complaining that the SD card did not have a valid fingerprint or was no an official Avaya SD Card, so I reformatted it and installed IPO 11.1. It is not complaining of the SD card anymore and the phones have not rebooted yet.

 
Status
Not open for further replies.

Part and Inventory Search

Sponsor

Back
Top