Hi Team,
We performed the AES connector failover test as below.
In this test case, we connected two AESs without any HA configuration.
Therefore both AESs are working as active.
- Case1 : Link down(LAN cable disconnect for Main AES) on Main AES side connection. Then we expected to do failover from Main AES to Sub AES.
- Case2 : Link down(LAN cable disconnect for Sub AES) on Sub AES side connection. Then we expected no effects to Main AES connection and AES connector operation.
For Case1, the Failover function by AES connector worked without any problem.
It is estimated phenomenon(Good Results).
For Case2, AES connector didn't work about 60 sec after detected Linkdown.
We didn't estimate this phenomenon.
Is this normal operation as AES connector?
In this case, when we maked call before Linkdown with IVR, this call(session ID = 12388) was disconnected 40 sec timeout later based on the OD application log
---------------------------------------------------------------
> fsutap10_trace.log
02/03/2017
20:32:25 Established Call
(MPP port 89914, ApplicationServer 10.140.33.71)
20:32:59 Sub AES LAN cable disconnected
20:33:43 issued CTI disconnect command?AES Connector didn't reply to this comamnd)
20:33:43:496 DEBUG - getObserversFromCallId: gathering observers... done waiting on sync list size of list is
20:34:08 AES Connector detect Sub AES link-down
20:34:08:881 DEBUG - ProviderMonitor:AES-EP-B got an exception getting date/time attempt:0 error:no conf event
--------------------------------------------------------------------------------
> fsutap10_eMB_IVR_Member.trace.log
02/03/2017 20:33:43 INFO - ESjNJMoZnWHjMfVX-U463v2:/eMB_IVR_Member : Using SCESession ESjNJMoZnWHjMfVX-U463v2:/eMB_IVR_Member servlet : M_End-M_Disconnect
02/03/2017 20:33:43 DEBUG - ESjNJMoZnWHjMfVX-U463v2:/eMB_IVR_Member : CTICommand.execute: encrypted value is XHLGU0OPUf53ZmNdsPGOdh7bKYbJZNsA
02/03/2017 20:33:43 DEBUG - ESjNJMoZnWHjMfVX-U463v2:/eMB_IVR_Member : VariableUtil.getVariableValue: Variable value is 12388
02/03/2017 20:33:43 DEBUG - ESjNJMoZnWHjMfVX-U463v2:/eMB_IVR_Member : CTICommand.execute: request to manager is
https://urldefense.proofpoint.com/v2/url?u=http-3A__localhost-3A9082_aesconnector_Disconnect-3Fcallid-253D12388-2526extension-253D89114-26k-3DXHLGU0OPUf53ZmNdsPGOdh7bKYbJZNsA&d=DQIFJg&c=BFpWQw8bsuKpl1SgiZH64Q&r=oSKVie1FyZqBNq8gvMFwyA&m=EfZ_in6EZ1JjA9c5XgL8-P5nvyOm1PFZ92drSWorT2o&s=udL-sq2Ehh_E0liS7K9JVmuqqm2wNelfksJbrRAVSpo&e=
02/03/2017 20:33:43 DEBUG - ESjNJMoZnWHjMfVX-U463v2:/eMB_IVR_Member : CTICommand.execute: setting session cookie to ESjNJMoZnWHjMfVX-U463v2:/eMB_IVR_Member
02/03/2017 20:34:23 INFO - ESjNJMoZnWHjMfVX-U463v2:/eMB_IVR_Member : HTTP Session lost removing SCESession ESjNJMoZnWHjMfVX-U463v2
02/03/2017 20:34:23 INFO - ESjNJMoZnWHjMfVX-U463v2:/eMB_IVR_Member : SCESession Removed
02/03/2017 20:34:23 DEBUG - ESjNJMoZnWHjMfVX-U463v2:/eMB_IVR_Member : Removing 1 session stack frames.
02/03/2017 20:34:23 INFO - ESjNJMoZnWHjMfVX-U463v2:/eMB_IVR_Member : ** Popped Stack Frame [/eMB_IVR_Member]
---------------------------------------------------------------
At this time, if we sent CTI disconnect command from OD application, AES connector didn't reply to any those commands.
After 60 sec later, It seems that AES connector could receive this CTI disconnect command.
However this call was already disconnected. Then this CTI disconnect command was failed as "INVALID".
-----
> fsutap10_eMB_IVR_Member.trace.log
02/03/2017 20:33:43 DEBUG - ESjNJMoZnWHjMfVX-U463v2:/eMB_IVR_Member : CTICommand.execute: request to manager is
http://localhost:9082/aesconnector/Disconnect?callid%3D12388%26extension%3D89114&k=XHLGU0OPUf53ZmNdsPGOdh7bKYbJZNsA
(60 sec later)
02/03/2017 20:34:43:577 DEBUG - CTIConnectorManager.dropCall: Call state of call 12388 is INVALID
-----
Based on JTAPI log(fsutap10_tsapi_trace.txt.1), we found below message.
Then we are thinking that AES connector tried to connect to both AES(Main and Sub) after link down to Sub AES.
-----
> fsutap10_tsapi_trace.txt.1
2017-03-02 20:33:41,734 [QuartzScheduler_Worker-1] INFO TSProviderImpl - TSProvider.shutdown - already in shutdown, redundant call, returning.
2017-03-02 20:33:41,734 [QuartzScheduler_Worker-1] INFO TSProviderImpl - TSProvider: version ‘6.1.0.94 [production build]’, for TSProvider[#3]@2aad1637
2017-03-02 20:33:41,734 [QuartzScheduler_Worker-1] INFO TSProviderImpl - TSProvider: calling acsOpenStream serverID=AVAYA#AESSW4#CSTA#msatae4 loginID=vp passwd=******* for TSProvider[#3]@2aad1637
2017-03-02 20:33:41,734 [QuartzScheduler_Worker-1] DEBUG TsapiSessionFactoryOio - Attempting to connect to server </10.140.27.80:450>?* IP address of Main AES
2017-03-02 20:33:41,734 [QuartzScheduler_Worker-1] INFO TsapiChannelOio - browser: GENERIC
2017-03-02 20:33:41,735 [QuartzScheduler_Worker-1] DEBUG TsapiSessionFactoryOio - Successfully connected to server </10.140.27.80:450>
...
2017-03-02 20:33:41,774 [QuartzScheduler_Worker-1] DEBUG TsapiSessionFactoryOio - Attempting to connect to server </10.140.27.90:450>?* IP address of Sub AES
2017-03-02 20:33:41,774 [QuartzScheduler_Worker-1] INFO TsapiChannelOio - browser: GENERIC
Best regards,
K.Yamahara
