cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
1809
Views
0
Helpful
1
Replies

Cisco Jabber CTI connection

Daniov1
Level 1
Level 1

Hi ,
I have a problem with one cisco jabber client version 12.7.1.
Every morning jabber client CTI connection to CUCM is failed.
After logout manually and login the CTI connection success.


I found the following logs:

Screenshot_1.png

2020-01-07 09:21:19,357 DEBUG [0x0000440c] [c\callcontrol\ServicesLifeCycle.cpp(823)] [csf.ecc] [csf::ecc::ServicesLifeCycle::interrupt] - << Interrupt current actioneSleep
2020-01-07 09:21:19,357 DEBUG [0x0000440c] [cc\src\common\thread\ThreadEvent.cpp(38)] [csf.ecc] [ThreadEvent::set] - -->
2020-01-07 09:21:19,357 DEBUG [0x0000440c] [cc\src\common\thread\ThreadEvent.cpp(51)] [csf.ecc] [ThreadEvent::set] - Give condition a signal
2020-01-07 09:21:19,357 DEBUG [0x0000440c] [cc\src\common\thread\ThreadEvent.cpp(38)] [csf.ecc] [ThreadEvent::set] - -->
2020-01-07 09:21:19,357 DEBUG [0x0000440c] [cc\src\common\thread\ThreadEvent.cpp(42)] [csf.ecc] [ThreadEvent::set] - m_isSignalled is true, just return
2020-01-07 09:21:19,358 ERROR [0x00006b84] [c\callcontrol\ServicesLifeCycle.cpp(115)] [csf.ecc] [csf::ecc::ServicesLifeCycle::runActionLoop] - isStoping is true, skip currentAction, eSleep
2020-01-07 09:21:19,358 INFO [0x00006b84] [\src\common\thread\WorkerThread.cpp(151)] [csf.ecc] [WorkerThread::run] - Finishing thread: ServicesLifeCycle::runActionLoop
2020-01-07 09:21:19,358 INFO [0x0000440c] [c\src\common\thread\WorkerThread.cpp(60)] [csf.ecc] [WorkerThread::~WorkerThread] - Delete thread data of ServicesLifeCycle::runActionLoop
2020-01-07 09:21:19,358 DEBUG [0x0000440c] [\ServicesLifeCycleStateMachine.cpp(1537)] [csf.ecc] [csf::ecc::ServicesLifeCycleStateMachine::status_onSetRetryTimer] - Status: onSetRetryTimer(-1)
2020-01-07 09:21:19,358 INFO [0x0000440c] [l\ServicesLifeCycleStateMachine.cpp(279)] [csf.ecc] [csf::ecc::ServicesLifeCycleStateMachineData::calculateActions] - cache_nextAction: eSleep
2020-01-07 09:21:19,358 INFO [0x0000440c] [control\CallControlManagerImpl.cpp(2890)] [csf.ecc.evt] [csf::ecc::CallControlManagerImpl::notifyCucmActiveConnectionStatusChange] - ACTIVE_CONNECTION_STATUS_CHANGE: eIdle
2020-01-07 09:21:19,358 INFO [0x0000440c] [control\CallControlManagerImpl.cpp(2918)] [csf.ecc.evt] [csf::ecc::CallControlManagerImpl::notifyAuthActiveConnectionStatusChange] - AUTH_ACTIVE_CONNECTION_STATUS_CHANGE: eIdle
2020-01-07 09:21:19,358 DEBUG [0x00006af0] [ontrol\TelephonyCallControlImpl.cpp(842)] [jcf.tel.callcontrol] [CSFUnified::TelephonyCallControlImpl::onCucmActiveConnectionStatusChange] - CUCM Active Connection Status Change to: [eIdle]
2020-01-07 09:21:19,358 INFO [0x0000440c] [\callcontrol\ServicesLifeCycle.cpp(1273)] [csf.ecc] [csf::ecc::ServicesLifeCycle::stopService] - mode eDeskPhone
2020-01-07 09:21:19,358 DEBUG [0x00006af0] [elephonyservice\BackgroundWorker.cpp(45)] [jcf.tel.worker] [CSFUnified::BackgroundWorker::doTask] - Adding new task to BackgroundWorker. Task name is: TelephonyCallControlImpl::onCucmActiveConnectionStatusChangeImpl
2020-01-07 09:21:19,358 INFO [0x0000440c] [src\callcontrol\ServicesManager.cpp(618)] [csf.ecc] [csf::ecc::ServicesManager::onServiceDestroyed] - onServiceDestroyed (eDeskPhone)
2020-01-07 09:21:19,358 DEBUG [0x0000440c] [\src\callcontrol\ServicesBroker.cpp(131)] [csf.ecc] [csf::ecc::ServicesBroker::setDPServiceMode] - deskphoneMode:0
2020-01-07 09:21:19,358 DEBUG [0x00006af0] [common\processing\TaskDispatcher.cpp(20)] [TaskDispatcher] [CSFUnified::Processing::TaskDispatcher::enqueue] - Enqueue TelephonyCallControlImpl::onCucmActiveConnectionStatusChangeImpl - Queue Size: 0
2020-01-07 09:21:19,358 DEBUG [0x0000440c] [src\callcontrol\CallBrokerStates.cpp(85)] [csf.ecc] [csf::ecc::CallBrokerStates::State::onAllCUCMCallsEnded] - State: NoCalls. Calling default implementation
2020-01-07 09:21:19,358 INFO [0x0000440c] [\callcontrol\ServicesLifeCycle.cpp(1288)] [csf.ecc] [csf::ecc::ServicesLifeCycle::stopService] - About to disconnect phones...
2020-01-07 09:21:19,358 INFO [0x0000440c] [pper\DeskPhoneConnectionContext.cpp(276)] [csf.ecc] [csf::ecc::DeskPhoneConnectionContext::stop] - [id=0] DeskPhoneConnectionContext::stop
2020-01-07 09:21:19,358 DEBUG [0x0000440c] [per\DeskPhoneConnectionContext.cpp(1029)] [csf.ecc] [csf::ecc::DeskPhoneConnectionContext::DPLogoutWorkItem::DPLogoutWorkItem] - [id=0] create DPLogoutWorkItem
2020-01-07 09:21:19,358 DEBUG [0x0000440c] [per\DeskPhoneConnectionContext.cpp(1030)] [csf.ecc] [csf::ecc::DeskPhoneConnectionContext::DPLogoutWorkItem::DPLogoutWorkItem] - [id=0] Setting _loginState = LOGINSTATE_DISCONNECTING
2020-01-07 09:21:19,359 INFO [0x00000bac] [nts\ecc\src\common\thread\Timer.cpp(193)] [csf.ecc] [Timer::run] - Finishing thread: DeskPhoneConnectionContext_LoginTimer
2020-01-07 09:21:19,359 INFO [0x00000bac] [nts\ecc\src\common\thread\Timer.cpp(199)] [csf.ecc] [Timer::run] - Signal work is done
2020-01-07 09:21:19,359 DEBUG [0x000046dc] [per\DeskPhoneConnectionContext.cpp(1036)] [csf.ecc] [csf::ecc::DeskPhoneConnectionContext::DPLogoutWorkItem::run] - [id=0] DPLogoutWorkItem::run()
2020-01-07 09:21:19,359 INFO [0x000046dc] [rc\deskphoneprovider\DPProvider.cpp(270)] [csf.ecc] [CDPProvider::Close] - [id=0] CDPProvider::Close
2020-01-07 09:21:19,359 DEBUG [0x000046dc] [c\deskphoneprovider\DPProvider.cpp(1070)] [csf.ecc] [CDPProvider::SendRequest] - [id=0] CDPProvider::SendRequest, msg.sequenceNumber=81, pObject=1920FDCC, pdunames[msg.msgID]=PROVIDER_CLOSE_REQUEST
2020-01-07 09:21:19,359 DEBUG [0x000046dc] [c\deskphoneprovider\DPProvider.cpp(1080)] [csf.ecc] [CDPProvider::SendRequest] - [id=0] SendRequest setting msg.sequenceNumber to , msg.sequenceNumber=98
2020-01-07 09:21:19,359 DEBUG [0x000046dc] [oneprovider\QBE\ProtocolElement.cpp(332)] [csf.ecc] [Message::encode] - Message::encode: , msg.protoVersion=0x000f0000, msg.msgID=9, sizeof(Header)=32, fixedSize=4, varSize=0
2020-01-07 09:21:19,359 DEBUG [0x000046dc] [c\deskphoneprovider\DPProvider.cpp(1086)] [csf.ecc] [CDPProvider::SendRequest] - [id=0] SendRequest Succeed., pdunames[msg.msgID]=PROVIDER_CLOSE_REQUEST, msg.sequenceNumber=98
2020-01-07 09:21:19,359 INFO [0x000046dc] [\deskphoneprovider\CtiConnection.cpp(60)] [csf.ecc] [CtiConnection::stop] - CtiConnection::stop
2020-01-07 09:21:19,359 INFO [0x000046dc] [\deskphoneprovider\CtiConnection.cpp(74)] [csf.ecc] [CtiConnection::stop] - CtiConnection: deleting connection thread!
2020-01-07 09:21:19,359 WARN [0x00003f7c] [deskphoneprovider\CtiConnection.cpp(228)] [csf.ecc] [CtiConnection::SocketWorkItem::run] - CtiConnection: CTI connection has shut down unexpectedly
2020-01-07 09:21:19,359 ERROR [0x00003f7c] [deskphoneprovider\CtiConnection.cpp(260)] [csf.ecc] [CtiConnection::SocketWorkItem::run] - CtiConnection: Socket disconnect failed!
2020-01-07 09:21:19,359 ERROR [0x00003f7c] [c\deskphoneprovider\DPProvider.cpp(1243)] [csf.ecc] [CDPProvider::onDisconnected] - onDisconnected
2020-01-07 09:21:19,359 INFO [0x00003f7c] [\src\common\thread\WorkerThread.cpp(151)] [csf.ecc] [WorkerThread::run] - Finishing thread: CtiConnection
2020-01-07 09:21:19,359 INFO [0x00007508] [\deskphoneprovider\CtiConnection.cpp(60)] [csf.ecc] [CtiConnection::stop] - CtiConnection::stop
2020-01-07 09:21:19,360 INFO [0x000046dc] [c\src\common\thread\WorkerThread.cpp(60)] [csf.ecc] [WorkerThread::~WorkerThread] - Delete thread data of CtiConnection

 

I need to investigate this issue.
Any suggest?

 

Danielo

 

1 Reply 1

If I'm reading the debug correctly, it looks like the Jabber client is trying to use (or at least connect to) a deskphone rather than using the CSF softphone.

  • Are the phone, the line, and the user all enabled for "Allow Control from CTI"?
  • If a deskphone is associated with this user, does the user control the phone in their end-user account as well as "owning" the phone in the phone configuration page?
  • If the Jabber client is supposed to use the CSF softphone, are all the appropriate associations (like the the previous questions) in place?

Let us know what you find.

Maren