Here is an excerpt from the dsmerror.log for last night, it appears we
have some kind of comms issue..
I havent been able to find errno 232 or 131 in /usr/include/errno.h, nor
anything on IBM's site.
We can match the ANS1005E messages to ANR0480 messages on the TSM
server, indicating the client ended the session...
05/24/05 05:12:44 ANS1809W Session is lost; initializing session
reopen procedure.
05/24/05 05:12:47 ANS1005E TCP/IP read error on socket = 9, errno =
232, reason : 'Connection reset by peer'.
05/24/05 05:12:47 ANS1809W Session is lost; initializing session
reopen procedure.
05/24/05 05:12:48 ANS1809W Session is lost; initializing session
reopen procedure.
05/24/05 05:12:50 ANS1005E TCP/IP read error on socket = 9, errno =
232, reason : 'Connection reset by peer'.
05/24/05 05:12:50 cuConfirm: Received rc: -72 trying to receive
ConfirmResp verb
05/24/05 05:12:50 ANS1809W Session is lost; initializing session
reopen procedure.
05/24/05 05:12:51 ANS1809W Session is lost; initializing session
reopen procedure.
05/24/05 05:13:01 ANS1810E TSM session has been reestablished.
05/24/05 05:13:03 ANS1810E TSM session has been reestablished.
05/24/05 05:13:05 cuIdentifyResp: Error 131 reading IdentifyResp from
server.
05/24/05 05:13:05 sessOpen: Error 131 receiving IdentifyResp verb from
server
05/24/05 05:13:05 cuIdentifyResp: Error 131 reading IdentifyResp from
server.
05/24/05 05:13:05 cuIdentifyResp: Error 131 reading IdentifyResp from
server.
05/24/05 05:13:05 sessOpen: Error 131 receiving IdentifyResp verb from
server
05/24/05 05:13:05 sessOpen: Error 131 receiving IdentifyResp verb from
server
05/24/05 05:13:06 cuIdentifyResp: Error 131 reading IdentifyResp from
server.
05/24/05 05:13:06 sessOpen: Failure in communications open call. rc:
-1
05/24/05 05:13:06 sessOpen: Error 131 receiving IdentifyResp verb from
server
05/24/05 05:13:39 cuIdentifyResp: Error 131 reading IdentifyResp from
server.
05/24/05 05:13:39 sessOpen: Error 131 receiving IdentifyResp verb from
server
05/24/05 05:13:39 cuIdentifyResp: Error 131 reading IdentifyResp from
server.
05/24/05 05:13:39 sessOpen: Error 131 receiving IdentifyResp verb from
server
05/24/05 05:14:05 ANS1809W Session is lost; initializing session
reopen procedure.
05/24/05 05:14:05 ANS1809W Session is lost; initializing session
reopen procedure.
05/24/05 05:14:05 ANS1809W Session is lost; initializing session
reopen procedure.
05/24/05 05:14:06 ANS1809W Session is lost; initializing session
reopen procedure.
05/24/05 05:14:07 cuIdentifyResp: Error 131 reading IdentifyResp from
server.
05/24/05 05:14:07 sessOpen: Error 131 receiving IdentifyResp verb from
server
05/24/05 05:14:07 cuIdentifyResp: Error 131 reading IdentifyResp from
server.
05/24/05 05:14:07 sessOpen: Error 131 receiving IdentifyResp verb from
server
05/24/05 05:14:08 unknown thread, fatal error, signal 6
05/24/05 05:14:08 unknown thread, fatal error, signal 6
05/24/05 05:15:11 cuIdentifyResp: Error 131 reading IdentifyResp from
server.
05/24/05 05:15:11 sessOpen: Error 131 receiving IdentifyResp verb from
server
05/24/05 05:15:11 ANS1028S Internal program error. Please see your
service representative.
05/24/05 05:15:11 cuIdentifyResp: Error 131 reading IdentifyResp from
server.
05/24/05 05:15:11 sessOpen: Error 131 receiving IdentifyResp verb from
server
05/24/05 05:15:11 ANS1028S Internal program error. Please see your
service representative.
05/24/05 05:15:12 cuIdentifyResp: Error 131 reading IdentifyResp from
server.
05/24/05 05:15:12 sessOpen: Error 131 receiving IdentifyResp verb from
server
Computer says 'No'..
-----Original Message-----
From: ADSM: Dist Stor Manager [mailto:ADSM-L AT VM.MARIST DOT EDU] On Behalf Of
David E Ehresman
Sent: Friday, May 20, 2005 8:21 PM
To: ADSM-L AT VM.MARIST DOT EDU
Subject: Re: Poor LanFree performance / mixed LAN xfer??
Any clues in the dsmerror.log?
>>> Matthew.Warren AT POWERGEN.CO DOT UK 05/20/05 11:41 AM >>>
Hello *SM'ers,
Has anyone observed the following;
We are experienceing performance problems with LanFree backups. The
issue goes away with a retsart of the storage agent, but at some point
in the future comes back, requiring another restart of the stoarge agent
etc..
Now, when the backups are going OK, the following steps are observed,
take note of what happens to the ByteCount (IE: the small cumulative
indicator in the square brackets at the command line, '[12.3 G]') ;
Backup started
First directory entry sent
Backup pauses, while byte count increments
Backup generates 'unsuccesful' line, and then 'waiting for mount of
offline media'
ByteCount PAUSES
When tape is mounted and wound, BYTECOUNT increases Very Fast. (+100 -
200MB / 'tick' )
Then end stats show simlar to;
Total number of objects inspected: 3
Total number of objects backed up: 3
Total number of objects updated: 0
Total number of objects rebound: 0
Total number of objects deleted: 0
Total number of objects expired: 0
Total number of objects failed: 0
Total number of bytes transferred: 4.70 GB
LanFree data bytes: 4.70 GB
Data transfer time: 153.13 sec
Network data transfer rate: 32,229.74 KB/sec
Aggregate data transfer rate: 28,567.48 KB/sec
Objects compressed by: 0%
Elapsed processing time: 00:02:52
..NOTE, ALL data has gone LanFree
Now, when the backups are performing slowly, the following occurrs;
Backup started
First directory entry sent
Backup pauses, while byte count increments
Backup generates 'unsuccesful' line, and then 'waiting for mount of
offline media'
ByteCount CONTINUES TO INCREMENT SLOWLY (+15-20 MB / 'tick')
When tape is mounted and wound, BYTECOUNT INCREMENTS SLOWLY (+15 - 20MB
/ 'tick')
At the end of backup, we see this;
Total number of objects inspected: 6
Total number of objects backed up: 2
Total number of objects updated: 0
Total number of objects rebound: 0
Total number of objects deleted: 0
Total number of objects expired: 0
Total number of objects failed: 0
Total number of bytes transferred: 4.18 GB
LanFree data bytes: 2.37 GB
Data transfer time: 42.01 sec
Network data transfer rate: 104,402.77 KB/sec
Aggregate data transfer rate: 15,331.31 KB/sec
Objects compressed by: 0%
Elapsed processing time: 00:04:46
Does this indicate, that in this instance some data went LanFree while
some went LAN?? .. and what significance is the fact that the BYTECOUNT
increases slowly while waiting for a mediamount, but it doesn't when the
backup performs well?
Puzzled;
Matthew.
Computer says 'No'..
___________________________ Disclaimer Notice __________________________
This message and any attachments are confidential and should only be
read by those to whom they are addressed. If you are not the intended
recipient, please contact us, delete the message from your computer and
destroy any copies. Any distribution or copying without our prior
permission is prohibited.
Internet communications are not always secure and therefore Powergen
Retail Limited does not accept legal responsibility for this message.
The recipient is responsible for verifying its authenticity before
acting on the contents. Any views or opinions presented are solely those
of the author and do not necessarily represent those of Powergen Retail
Limited.
Registered addresses:
Powergen Retail Limited, Westwood Way, Westwood Business Park, Coventry,
CV4 8LG.
Registered in England and Wales No: 3407430
Telephone +44 (0) 2476 42 4000
Fax +44 (0) 2476 42 5432
|
