Google Groups no longer supports new Usenet posts or subscriptions. Historical content remains viewable.
Dismiss

Dropping connections?

550 views
Skip to first unread message

Jen

unread,
Apr 2, 2009, 1:29:04 PM4/2/09
to
We're having some trouble with our database connections
dropping. After looking at some other posts here I saw that
ODBC logging might help, but the information we're getting
in the logs doesn't make sense to me.

Here's an excerpt around the time the disconnections took
place. Our questions are
1) Could the prefetch buffer error cause any problems?
2) What is this stuff all about?
11:50:22 [ 1483] Communication function
i_cs_HandleSQLPresError code 5
11:50:22 [ 1483] Communication function StrmGetInd code 2

--- log snip! ---
Sat Mar 28 2009 11:38:20
11:38:20 Attempting to connect using:
UID=user;PWD=********;DBN=Hub;ENG=****server;CON=SQL_DBC_10da7e0;
DBG=YES;LOG=c:\Progra~1\***\Log

s\ODBC.log;DMRF=NO;LINKS=TCPIP{}
11:38:20 Attempting to connect to a running server...
11:38:20 Attempting TCPIP connection (address
192.168.1.15:2638 found in sasrv.ini

cache)11:38:20 Trying to find server at cached address
192.168.1.15:2638 without broadcasting
11:38:20 Found server, verifying server name
11:38:20 Found database server at address 192.168.1.15
11:38:20 Found database server ****server on TCPIP link
11:38:20 Connected using client address 192.168.1.15:1520
11:38:20 Connected to server over TCPIP at address
192.168.1.15:263811:38:20 Connected to SQL

Anywhere Server version 10.0.1.3415
11:38:20 Application information:
11:38:20 IP=192.168.1.15;HOST=****server;OS='Windows XP
Build 2600 Service Pack

3';PID=0xcfc;THREAD=0xd1c;EXE=c:\Progra~1\***\common\app.exe;
VERSION=10.0.1.3415;API=ODBC;TIMEZO

NEADJUSTMENT=-240
11:38:20 Connected to the server, attempting to connect to a
running database...
11:38:20 [ 1478] Connected to database successfully
11:38:20 [ 1478] Client disconnected

Sat Mar 28 2009 11:38:25
11:38:25 Attempting to connect using:
UID=user;PWD=********;DBN=Hub;ENG=****server;CON=SQL_DBC_10da7e0;
DBG=YES;LOG=c:\Progra~1\***\Log

s\ODBC.log;DMRF=NO;LINKS=TCPIP{}
11:38:25 Attempting to connect to a running server...
11:38:25 Attempting TCPIP connection (address
192.168.1.15:2638 found in sasrv.ini

cache)11:38:25 Trying to find server at cached address
192.168.1.15:2638 without broadcasting
11:38:25 Found server, verifying server name
11:38:25 Found database server at address 192.168.1.15
11:38:25 Found database server ****server on TCPIP link
11:38:25 Connected using client address 192.168.1.15:1521
11:38:25 Connected to server over TCPIP at address
192.168.1.15:263811:38:25 Connected to SQL

Anywhere Server version 10.0.1.3415
11:38:25 Application information:
11:38:25 IP=192.168.1.15;HOST=****server;OS='Windows XP
Build 2600 Service Pack

3';PID=0xcfc;THREAD=0xd1c;EXE=c:\Progra~1\***\common\app.exe;
VERSION=10.0.1.3415;API=ODBC;TIMEZO

NEADJUSTMENT=-240
11:38:25 Connected to the server, attempting to connect to a
running database...
11:38:25 [ 1479] Connected to database successfully
11:38:27 [ 1479] Client disconnected
11:49:03 Attempting to connect using:
UID=REMOTE_DBA;PWD=********;DBN=Hub;ENG=****server;CON=DBMLsync_main;
DSN=hub;DBG=YES;LOG=c:\Prog

ra~1\***\Logs\ODBC.log;DMRF=NO;LINKS=TCPIP{};CS=unknown
11:49:03 Attempting to connect to a running server...
11:49:03 Attempting TCPIP connection (address
192.168.1.15:2638 found in sasrv.ini

cache)11:49:03 Trying to find server at cached address
192.168.1.15:2638 without broadcasting
11:49:03 Found server, verifying server name
11:49:03 Found database server at address 192.168.1.15
11:49:03 Found database server ****server on TCPIP link
11:49:03 Connected using client address 192.168.1.15:1643
11:49:03 Connected to server over TCPIP at address
192.168.1.15:263811:49:03 Connected to SQL

Anywhere Server version 10.0.1.3415
11:49:03 Application information:
11:49:03 IP=192.168.1.15;HOST=****server;OS='Windows XP
Build 2600 Service Pack

3';PID=0xbc;THREAD=0x108;EXE=C:\Progra~1\***\Asa\win32\dbmlsync.exe;
VERSION=10.0.1.3415;API=DBLI

B;TIMEZONEADJUSTMENT=-240
11:49:03 Connected to the server, attempting to connect to a
running database...
11:49:03 [ 1480] Connected to database successfully
11:49:03 [ 1480] The number of prefetch rows has been
reduced to 0 due to the prefetch buffer
11:49:03 [ 1480] limit. Consider using the PrefetchBuffer
connection parameter.
11:49:05 [ 1480] Client disconnected
11:49:05 Attempting to connect using:
UID=REMOTE_DBA;PWD=********;DBN=Hub;ENG=****server;CON=DBMLsync_main;
DSN=hub;DBG=YES;LOG=c:\Prog

ra~1\***\Logs\ODBC.log;DMRF=NO;LINKS=TCPIP{};CS=unknown
11:49:05 Attempting to connect to a running server...
11:49:05 Attempting TCPIP connection (address
192.168.1.15:2638 found in sasrv.ini

cache)11:49:05 Trying to find server at cached address
192.168.1.15:2638 without broadcasting
11:49:05 Found server, verifying server name
11:49:05 Found database server at address 192.168.1.15
11:49:05 Found database server ****server on TCPIP link
11:49:05 Connected using client address 192.168.1.15:1645
11:49:05 Connected to server over TCPIP at address
192.168.1.15:263811:49:05 Connected to SQL

Anywhere Server version 10.0.1.3415
11:49:05 Application information:
11:49:05 IP=192.168.1.15;HOST=****server;OS='Windows XP
Build 2600 Service Pack

3';PID=0xbc;THREAD=0x108;EXE=C:\Progra~1\***\Asa\win32\dbmlsync.exe;
VERSION=10.0.1.3415;API=DBLI

B;TIMEZONEADJUSTMENT=-240
11:49:05 Connected to the server, attempting to connect to a
running database...
11:49:05 [ 1481] Connected to database successfully
11:49:05 [ 1481] The number of prefetch rows has been
reduced to 0 due to the prefetch buffer
11:49:05 [ 1481] limit. Consider using the PrefetchBuffer
connection parameter.
11:49:05 [ 1481] Client disconnected

Sat Mar 28 2009 11:49:22
11:49:22 Attempting to connect using:
UID=user;PWD=********;DBN=Hub;ENG=****server;CON=SQL_DBC_10da7e0;
DBG=YES;LOG=c:\Progra~1\***\Log

s\ODBC.log;DMRF=NO;LINKS=TCPIP{}
11:49:22 Attempting to connect to a running server...
11:49:22 Attempting TCPIP connection (address
192.168.1.15:2638 found in sasrv.ini

cache)11:49:22 Trying to find server at cached address
192.168.1.15:2638 without broadcasting
11:49:22 Found server, verifying server name
11:49:22 Found database server at address 192.168.1.15
11:49:22 Found database server ****server on TCPIP link
11:49:22 Connected using client address 192.168.1.15:1667
11:49:22 Connected to server over TCPIP at address
192.168.1.15:263811:49:22 Connected to SQL

Anywhere Server version 10.0.1.3415
11:49:22 Application information:
11:49:22 IP=192.168.1.15;HOST=****server;OS='Windows XP
Build 2600 Service Pack

3';PID=0xcfc;THREAD=0xd1c;EXE=c:\Progra~1\***\common\app.exe;
VERSION=10.0.1.3415;API=ODBC;TIMEZO

NEADJUSTMENT=-240
11:49:22 Connected to the server, attempting to connect to a
running database...
11:49:22 [ 1482] Connected to database successfully
11:49:22 [ 1482] Client disconnected

Sat Mar 28 2009 11:49:27
11:49:27 Attempting to connect using:
UID=user;PWD=********;DBN=Hub;ENG=****server;CON=SQL_DBC_10da7e0;
DBG=YES;LOG=c:\Progra~1\***\Log

s\ODBC.log;DMRF=NO;LINKS=TCPIP{}
11:49:27 Attempting to connect to a running server...
11:49:27 Attempting TCPIP connection (address
192.168.1.15:2638 found in sasrv.ini

cache)11:49:27 Trying to find server at cached address
192.168.1.15:2638 without broadcasting
11:49:27 Found server, verifying server name
11:49:27 Found database server at address 192.168.1.15
11:49:27 Found database server ****server on TCPIP link
11:49:27 Connected using client address 192.168.1.15:1672
11:49:27 Connected to server over TCPIP at address
192.168.1.15:263811:49:27 Connected to SQL

Anywhere Server version 10.0.1.3415
11:49:27 Application information:
11:49:27 IP=192.168.1.15;HOST=****server;OS='Windows XP
Build 2600 Service Pack

3';PID=0xcfc;THREAD=0xd1c;EXE=c:\Progra~1\***\common\app.exe;
VERSION=10.0.1.3415;API=ODBC;TIMEZO

NEADJUSTMENT=-240
11:49:27 Connected to the server, attempting to connect to a
running database...
11:49:27 [ 1483] Connected to database successfully
11:50:22 [ 1483] Connection terminated abnormally; server
socket shut down
11:50:22 [ 893] Connection terminated abnormally; server
socket shut down
11:50:22 [ 1427] Connection terminated abnormally; server
socket shut down
11:50:22 [ 1483] Communication function
i_cs_HandleSQLPresError code 5
11:50:22 [ 1483] Communication function StrmGetInd code 2
11:50:22 [ 1483] Client disconnected


After this, the user has to restart the services for the
application to get their connection back.

Ian McHardy [Sybase iAnywhere]

unread,
Apr 6, 2009, 9:48:27 AM4/6/09
to
1) no. These are just performance warnings
2) these are return codes from internal functions on an abnormal disconnect.
The interesting line is:

Connection terminated abnormally; server socket shut down

So the client is reporting that the other end of the socket was closed.
Using -z -o <file> on the server and finding the matching connection may
give more information on why the socket was closed on the server end.

--

Ian McHardy (Sybase iAnywhere)

Please reply only to the newsgroup.

SQL Anywhere Developer Community -
http://www.sybase.com/developer/library/sql-anywhere-techcorner
SQL Anywhere Blog Center - http://www.sybase.com/sqlanyblogs

<Jen> wrote in message news:49d4f5e0.195...@sybase.com...

Jen

unread,
Apr 9, 2009, 1:24:52 PM4/9/09
to

> So the client is reporting that the other end of the
> socket was closed. Using -z -o <file> on the server and
> finding the matching connection may give more information
> on why the socket was closed on the server end.

The contents of the ODBC log:
13:08:04 [ 15] Connected to database successfully
13:08:04 [ 15] The number of prefetch rows has been


reduced to 0 due to the prefetch buffer

13:08:04 [ 15] limit. Consider using the PrefetchBuffer
connection parameter.
13:08:04 [ 15] Client disconnected
13:19:18 [ 8] 13:19:18 [ 12] ated abnormally; server
socket shut down

Connection terminated abnormally; server socket shut down

Connection terminated abnormally; server socket shut down

13:19:18 [ 10] Connection terminated abnormally; server
socket shut down

13:19:27 [ 1] Communication function
i_cs_HandleSQLPresError code 5
13:19:27 [ 1] Communication function StrmGetInd code 2
13:19:27 [ 1] Client disconnected

The contents of the log generated by -z -o:
I. 04/08 13:08:04. Connection ID 15: Client disconnected
I. 04/08 13:15:27. Labor For=13:15:00 Start=2009-04-08
13:15:27.203000
I. 04/08 13:19:18. Connection ID 6: connection "REMOTE_DBA"
terminated abnormally; client socket shut down
I. 04/08 13:19:18. Connection ID 6: Disconnected TCPIP
client's AppInfo is
IP=192.168.1.15;HOST=WASHSERVER;OS='Windows XP Build 2600
Service Pack
3';PID=0xb28;THREAD=0xdc0;EXE=C:\Progra~1\ISI\Asa\win32\dbmlsync.exe;
VERSION=10.0.1.3415;API=DBLIB;TIMEZONEADJUSTMENT=-240
I. 04/08 13:19:18. Connection ID 8: connection "user"
terminated abnormally; client socket shut down
I. 04/08 13:19:18. Connection ID 6: "REMOTE_DBA"
disconnected from database "hub"
I. 04/08 13:19:18. Connection ID 8: Disconnected TCPIP
client's AppInfo is
IP=192.168.1.15;HOST=WASHSERVER;OS='Windows XP Build 2600
Service Pack 3';PID=0xc1c;THREAD=0x4b4;EXE='C:\Program
Files\ISI\Common\EventNet.exe';VERSION=10.0.1.3415;API=ODBC;TIMEZONEADJUSTMENT=-240
I. 04/08 13:19:18. Connection ID 9: connection "user"
terminated abnormally; client socket shut down
I. 04/08 13:19:18. Connection ID 8: "user" disconnected from
database "hub"
I. 04/08 13:19:18. Connection ID 9: Disconnected TCPIP
client's AppInfo is
IP=192.168.1.15;HOST=WASHSERVER;OS='Windows XP Build 2600
Service Pack 3';PID=0xc1c;THREAD=0x4b4;EXE='C:\Program
Files\ISI\Common\EventNet.exe';VERSION=10.0.1.3415;API=ODBC;TIMEZONEADJUSTMENT=-240
I. 04/08 13:19:18. Connection ID 8: Client disconnected
I. 04/08 13:19:18. Connection ID 1: connection "user"
terminated abnormally; client socket shut down
I. 04/08 13:19:18. Connection ID 9: "user" disconnected from
database "hub"
I. 04/08 13:19:18. Connection ID 1: Disconnected TCPIP
client's AppInfo is
IP=192.168.1.15;HOST=WASHSERVER;OS='Windows XP Build 2600
Service Pack 3';PID=0x96c;THREAD=0x978;EXE='C:\Program
Files\ISI\Common\wssyscom.exe';VERSION=10.0.1.3415;API=ODBC;TIMEZONEADJUSTMENT=-240
I. 04/08 13:19:18. Connection ID 9: Client disconnected
I. 04/08 13:19:18. Connection ID 10: connection "user"
terminated abnormally; client socket shut down
I. 04/08 13:19:18. Connection ID 1: "user" disconnected from
database "hub"
I. 04/08 13:19:18. Connection ID 10: Disconnected TCPIP
client's AppInfo is
IP=192.168.1.15;HOST=WASHSERVER;OS='Windows XP Build 2600
Service Pack 3';PID=0xc1c;THREAD=0x4b4;EXE='C:\Program
Files\ISI\Common\EventNet.exe';VERSION=10.0.1.3415;API=ODBC;TIMEZONEADJUSTMENT=-240
I. 04/08 13:19:18. Connection ID 1: Client disconnected
I. 04/08 13:19:18. Connection ID 11: connection "DBA"
terminated abnormally; client socket shut down
I. 04/08 13:19:18. Connection ID 10: "user" disconnected
from database "hub"
I. 04/08 13:19:18. Connection ID 11: Disconnected TCPIP
client's AppInfo is
IP=192.168.1.15;HOST=WASHSERVER;OS='Windows XP Build 2600
Service Pack 3';PID=0xf54;THREAD=0x940;EXE='C:\Program
Files\ISI\WashSoft\washsoft.exe';VERSION=10.0.1.3415;API=ODBC;
TIMEZONEADJUSTMENT=-2
I. 04/08 13:19:18. Connection ID 10: Client disconnected
I. 04/08 13:19:18. Connection ID 12: connection "user"
terminated abnormally; client socket shut down
I. 04/08 13:19:18. Connection ID 11: "DBA" disconnected from
database "pfc"
I. 04/08 13:19:18. Connection ID 12: Disconnected TCPIP
client's AppInfo is
IP=192.168.1.15;HOST=WASHSERVER;OS='Windows XP Build 2600
Service Pack 3';PID=0xf54;THREAD=0x940;EXE='C:\Program
Files\ISI\WashSoft\washsoft.exe';VERSION=10.0.1.3415;API=ODBC;
TIMEZONEADJUSTMENT=-2
I. 04/08 13:19:18. Connection ID 11: Client disconnected
I. 04/08 13:19:18. Connection ID 12: "user" disconnected
from database "hub"
I. 04/08 13:19:18. Connection ID 6: Client disconnected
I. 04/08 13:19:18. Connection ID 12: Client disconnected

So, the socket is being shut down, but no more information,
really.

I read under another topic that
>If both the client and server report "... socket shut down"
>as the cause of the disconnect and neither report timeouts,
>this may indicate a problem at the network/OS layer.
http://groups.google.com/group/sybase.public.sqlanywhere.general/browse_thread/thread/ada8bf2e0d6ebf73/00bb476d6750a994?hl=en&lnk=gst&q=server+socker+shut+down#00bb476d6750a994

Any idea how I could start troubleshooting what those O/S /
network issues are? Or would different options on the second
log possibly shed more light?

0 new messages