Genesys CTI User Forum
Genesys CTI User Forum => Genesys CTI Technical Discussion => Topic started by: rht.jedhe on October 09, 2013, 03:40:32 PM
-
Hi Team,
This is to inform you that we are every month facing issue like unable to connect configuration server (unable to login CME) however the issue has been resolved post restarting config services.
As we are user Genesys 7.6 versions.
OS windows 2003 32 bit
SQL Server 2005.
last time as per TS suggestion we have been updated to Config server 7.6.000.67 version.
however the issue still occuring every month at once
still no clue why the issue is coming without any alarm when the service is running status.
kindly help us.
Thanks
-
Can you connect locally on the same server?
What does CfgServer logs indicate when a user tries to connect?
DBServer?
DB?
-
Thanks for your response.
even i am unable to login CME from local server when the services running status.
Please find the Config DB server logs
Before config service restarting.
16:20:51.167 Dbg 10741 'App: 1' has put request id='465708' into queue
16:20:51.167 Dbg 10737 Forwarding request '465708' from 'App: 1' to 'MS SQL: 1.1'
16:20:51.167 Dbg 10743 'MS SQL: 1.1' is spare
16:20:51.167 Dbg 10749 Forwarding response '465708.1' from 'MS SQL: 1.1' to 'App: 1'
16:20:51.167 Dbg 10739 ODBC: id='1.1' req='465708' MSG_DONE status='DBM_SUCCESS' text='BEGIN TRANSACTION'
16:20:51.183 Dbg 10741 'App: 1' has put request id='465709' into queue
16:20:51.183 Dbg 10737 Forwarding request '465709' from 'App: 1' to 'MS SQL: 1.1'
16:20:51.183 Dbg 10743 'MS SQL: 1.1' is spare
16:20:51.198 Dbg 10749 Forwarding response '465709.1' from 'MS SQL: 1.1' to 'App: 1'
16:20:51.198 Dbg 10739 ODBC: id='1.1' req='465709' SQL: delete from cfg_flex_prop where dbid = 257994
+0016 MSG_SQLEXECUTED status='DBM_SUCCESS'
16:20:51.198 Dbg 10741 'App: 1' has put request id='465710' into queue
16:20:51.198 Dbg 10737 Forwarding request '465710' from 'App: 1' to 'MS SQL: 1.1'
16:20:51.198 Dbg 10743 'MS SQL: 1.1' is spare
16:20:51.214 Dbg 10749 Forwarding response '465710.1' from 'MS SQL: 1.1' to 'App: 1'
16:20:51.214 Dbg 10739 ODBC: id='1.1' req='465710' SQL: insert into cfg_flex_prop (dbid, object_dbid, object_type, parent_dbid, prop_name, prop_value, prop_type, part, csid, object_csid, parent_csid) values(257994, 2668, 2, 257993, 'contact', 'sip:7041@10.51.97.54:11471', 0, 0, 0, 0, 0)
+0016 MSG_SQLEXECUTED status='DBM_SUCCESS'
16:20:51.214 Dbg 10741 'App: 1' has put request id='465711' into queue
16:20:51.214 Dbg 10737 Forwarding request '465711' from 'App: 1' to 'MS SQL: 1.1'
16:20:51.214 Dbg 10743 'MS SQL: 1.1' is spare
16:20:51.214 Dbg 10749 Forwarding response '465711.1' from 'MS SQL: 1.1' to 'App: 1'
16:20:51.214 Dbg 10739 ODBC: id='1.1' req='465711' SQL: update cfg_refresh set notify_id = 17109174
+0000 MSG_SQLEXECUTED status='DBM_SUCCESS'
16:20:51.230 Dbg 10741 'App: 1' has put request id='465712' into queue
16:20:51.230 Dbg 10737 Forwarding request '465712' from 'App: 1' to 'MS SQL: 1.1'
16:20:51.230 Dbg 10743 'MS SQL: 1.1' is spare
16:20:51.261 Dbg 10749 Forwarding response '465712.1' from 'MS SQL: 1.1' to 'App: 1'
16:20:51.261 Dbg 10739 ODBC: id='1.1' req='465712' MSG_COMMITED status='DBM_SUCCESS' text='COMMIT TRANSACTION'
16:20:54.026 Dbg 10741 'App: 2' has put request id='465713' into queue
16:20:54.026 Dbg 10737 Forwarding request '465713' from 'App: 2' to 'MS SQL: 2.1'
16:20:54.026 Dbg 10743 'MS SQL: 2.1' is spare
16:20:54.026 Dbg 10749 Forwarding response '465713.1' from 'MS SQL: 2.1' to 'App: 2'
16:20:54.026 Dbg 10749 Forwarding response '465713.2' from 'MS SQL: 2.1' to 'App: 2'
16:20:54.026 Dbg 10739 ODBC: id='2.1' req='465713' SQL: select refresh, notify_id from cfg_refresh
+0000 Executed SQL statement 'SELECT', start retrieve records...
+0000 MSG_RETRIEVED2 status='DBM_SUCCESS'
+0000 rows retrieved (1)
+0000 MSG_RETRIEVED status='DBM_NOMORE'
16:20:59.026 Dbg 10741 'App: 2' has put request id='465714' into queue
16:20:59.026 Dbg 10737 Forwarding request '465714' from 'App: 2' to 'MS SQL: 2.1'
16:20:59.026 Dbg 10743 'MS SQL: 2.1' is spare
16:20:59.026 Dbg 10749 Forwarding response '465714.1' from 'MS SQL: 2.1' to 'App: 2'
16:20:59.026 Dbg 10749 Forwarding response '465714.2' from 'MS SQL: 2.1' to 'App: 2'
16:20:59.026 Dbg 10739 ODBC: id='2.1' req='465714' SQL: select refresh, notify_id from cfg_refresh
+0000 Executed SQL statement 'SELECT', start retrieve records...
+0000 MSG_RETRIEVED2 status='DBM_SUCCESS'
+0000 rows retrieved (1)
+0000 MSG_RETRIEVED status='DBM_NOMORE'
Please find the Confg Db Server Post restarting Config service
16:21:01.495 Dbg 10741 'App: 1' has put request id='465718' into queue
16:21:01.495 Dbg 10737 Forwarding request '465718' from 'App: 1' to 'MS SQL: 1.1'
16:21:01.495 Dbg 10743 'MS SQL: 1.1' is spare
16:21:01.495 Dbg 10749 Forwarding response '465718.1' from 'MS SQL: 1.1' to 'App: 1'
16:21:01.495 Dbg 10739 ODBC: id='1.1' req='465718' SQL: update cfg_refresh set notify_id = 17109176
+0000 MSG_SQLEXECUTED status='DBM_SUCCESS'
16:21:01.511 Dbg 10741 'App: 1' has put request id='465719' into queue
16:21:01.511 Dbg 10737 Forwarding request '465719' from 'App: 1' to 'MS SQL: 1.1'
16:21:01.511 Dbg 10743 'MS SQL: 1.1' is spare
16:21:01.526 Dbg 10749 Forwarding response '465719.1' from 'MS SQL: 1.1' to 'App: 1'
16:21:01.526 Dbg 10739 ODBC: id='1.1' req='465719' MSG_COMMITED status='DBM_SUCCESS' text='COMMIT TRANSACTION'
16:21:02.057 Std 04523 Connection to client 'App: 1' closed, reason ''client disconnected''
16:21:02.057 Dbg 10734 Send message 'MSG_DISCONNECTED' to 'MS SQL: 1.1', request id '-1'
/Statistics
name = MS SQL: 1.1
app_name = App: 1
req_rcvd = 947673
req_sent = 315095
data_rcvd = 89651656
data_sent = 28624948
msg_error = 0
msg_log = 315114
Client MS SQL: 1.1 is being closed. Current numofclients=0
Listener (port id = internal-0-0 fd = 292) is being closed. Checking for clients on listener...
Listener with port id = internal-0-0 has been closed and deleted from list of current ports. Number of db app connections affected: 0
Client App: 1 is being closed. Current numofclients=2
16:21:02.057 Std 04523 Connection to client 'App: 2' closed, reason ''client disconnected''
16:21:02.057 Dbg 10734 Send message 'MSG_DISCONNECTED' to 'MS SQL: 2.1', request id '-1'
/Statistics
name = MS SQL: 2.1
app_name = App: 2
req_rcvd = 602488
req_sent = 150624
data_rcvd = 63668477
data_sent = 10844768
msg_error = 0
msg_log = 150623
Client MS SQL: 2.1 is being closed. Current numofclients=0
Listener (port id = internal-0-0 fd = 328) is being closed. Checking for clients on listener...
Listener with port id = internal-0-0 has been closed and deleted from list of current ports. Number of db app connections affected: 0
Client App: 2 is being closed. Current numofclients=1
16:21:02.151 Std 05023 Process './dbclient_msql' stopped
/./dbclient_msql
pid = 1952
id = 0
expiration_time = 2013-10-09T16:21:32
client copy = 0
16:21:02.151 Std 05023 Process './dbclient_msql' stopped
/./dbclient_msql
pid = 2256
id = 1
expiration_time = 2013-10-09T16:21:32
client copy = 0
GServer: new_client: fd=340, found server=272
16:21:02.995 Trc 04521 New client 3821 connected from '10.57.8.33'
16:21:02.995 Dbg 10745 'App: 10.57.8.33:3821' registered; protocol version '1.3' id '3'
16:21:02.995 Dbg 10734 Send message 'MSG_EXTPACKETS' to 'App: 3', request id '0'
16:21:02.995 Dbg 10750 Start client request, dbprocesses per client '1'
16:21:02.995 Dbg 10734 Send message 'MSG_CLIENTSTARTED' to 'App: 3', request id '2'
16:21:02.995 Std 05022 Process './dbclient_msql' started
16:21:02.995 Dbg 10741 'App: 3' has put request id='4' into queue
GServer: new_client: fd=292, found server=344
16:21:03.042 Trc 04521 New client 3824 connected from '10.57.8.33'
16:21:03.042 Dbg 10745 './dbclient_msql' registered; protocol version '1.3' id '3.1'
16:21:03.042 Dbg 10734 Send message 'MSG_OPENDB' to 'MS SQL: 3.1', request id '3'
16:21:03.042 Dbg 10734 Send message 'MSG_EXTPACKETS' to 'App: 3', request id '0'
16:21:03.042 Dbg 10734 Send message 'MSG_EXTPACKET' to 'MS SQL: 3.1', request id '0'
16:21:03.042 Dbg 10743 'MS SQL: 3.1' is spare
16:21:03.167 Dbg 10749 Forwarding response '3.1' from 'MS SQL: 3.1' to 'App: 3'
16:21:03.167 Dbg 10739 ODBC: id='3.1' req='-1'
DBClient ODBC, Copyright (c) 1997-2007 Genesys Telecommunications Labs, Inc.
All Rights Reserved
Db Client version: 7.6.000.14
DB Common part version: 7.6.000.14
Vendor supported versions: 1.0
Build on 'i686-winnt'
+0031 Connection to multiserver created, host 'SRCGGN17FM', port '3822', socket '136'
+0031 MSG_STARTDB status='3'
ODBC: id='3.1' req='3' Protocols versions: dbclient='00010003' dbserver='00010003' dblib='00010003'
+0000 00329CD0,hDBC=6824768,6824768
+0000 Open DB: username = 'sa', database = 'ConfigDB', dbserver = '10.57.8.31'
+0000 Could not connect to SQL Server using SQL Native Client driver. Attemting default MS SQL Server driver...
+0125 Connected to MSSQL with SQL Server driver.
+0125 MSG_DBOPENED status='DBM_SUCCESS' msg='Open DB success'
16:21:03.167 Dbg 10743 'MS SQL: 3.1' is spare
16:21:03.167 Dbg 10739 ODBC: id='3.1' req='0' Chmode to extended packet on sock 136
16:21:03.167 Dbg 10743 'MS SQL: 3.1' is spare
16:21:03.167 Dbg 10749 Forwarding response '4.1' from 'MS SQL: 3.1' to 'App: 3'
16:21:03.167 Dbg 10739 ODBC: id='3.1' req='4' SET COMMIT FLAG 0
+0000 MSG_COMMITOFF status='DBM_SUCCESS'
Please find the Config server log before restarting
16:20:51.292 Trc 04542 Message MSGCFG_OBJECTCHANGED2 sent to 49400 (CCView 'Dominos_CCPulse')
MSGCFG_OBJECTCHANGED2
attr: SATRCFG_OBJECT value: " 26680000000000000 TServer contactsip:7041@10.51.97.54:11471"
attr: IATRCFG_HISTORYLOG value: 0
attr: IATRCFG_OBJECTTYPE value: 2 [CfgDN]
attr: IATRCFG_TIMESTAMP value: 1381315851
attr: IATRCFG_UNSOLEVENTNUM value: 17109174
attr: IATRCFG_REQUESTID value: 0
16:20:51.292 [HISTORYDB]: Data record 17109174 has been written to history database
16:20:51.292 Std 24201 Object: [CfgDN], name [7041], DBID: [2668] is changed by client, type [TServer], name: [Dominos_SIPServer], user: [SYSTEM]
16:20:51.339 Trc 04542 Message MSGCFG_OBJECTCHANGED2 sent to 708 (TServer 'Dominos_SIPServer')
MSGCFG_OBJECTCHANGED2
attr: IATRCFG_HISTORYLOG value: 0
attr: IATRCFG_OBJECTTYPE value: 2 [CfgDN]
attr: IATRCFG_REQUESTID value: 184301
16:20:51.339 Trc 24218 Transaction [119445] is sent on execution
16:20:51.339 Trc 24217 Transaction [119445] is rolled back
16:20:51.339 Std 23500 Configuration Server Error : Error [CFGUniquenessViolation], object [CfgDN], property [routeDNs] Description Validation error : [CFGUniquenessViolation] , Object [CfgDN] , DBID [2668] , Property [Unknown attribute]
16:20:51.339 Trc 04542 Message MSGCFG_ERROR sent to 708 (TServer 'Dominos_SIPServer')
MSGCFG_ERROR
attr: IATRCFG_ERRORCODE value: 524
attr: SATRCFG_DESCRIPTION value: "Validation error : [CFGUniquenessViolation] , Object [CfgDN] , DBID [2668] , Property [Unknown attribute] "
attr: IATRCFG_REQUESTID value: 184302
16:20:57.136 Trc 04520 New client 49940 connected
16:20:57.136 Trc 24300 Extended info : New client [49940] connected, protocol [cfglib]
16:20:57.136 Total number of clients: 4110
16:20:59.698 Trc 04520 New client 40632 connected
16:20:59.698 Trc 24300 Extended info : New client [40632] connected, protocol [cfglib]
16:20:59.698 Total number of clients: 4111
however above config server error still i am getting in new logs as well and no issue occured.
overall no clues why its happening.....
-
Provided logs do not show moment when You try to log in and fail. Quick question do You use by any chance external authentication?
-
thanks for your response,
there is no external authentication all servers are located in locally as well agents login from same location.
this is an strange issue and no error occurred in the logs and config service status showing as running
thanks
-
Post logs...
For both, ConfigServer and ConfigServerDBServer at the exact moment you hit LOGIN. If you did it at 11:30:05 search corresponding logs for 11:30:00 up to 11:30:10 so so.
If you don't see anything how you expect us to help? Magic?