RedTech443
MIS
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.
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