ADSM-L

Re: Retrieves failing on files with future archive attributes

2005-03-08 18:19:36
Subject: Re: Retrieves failing on files with future archive attributes
From: Andrew Raibeck <storman AT US.IBM DOT COM>
To: ADSM-L AT VM.MARIST DOT EDU
Date: Tue, 8 Mar 2005 16:19:20 -0700
Hmmm..... it seems odd to me that you'd get ANS1301E without a
corresponding message on the server. That message should derive from a
transaction that the server aborted, and I'd think the server would show
some evidence of a problem as well.

I don't think there's anything more I can do on this forum to help. I'd
recommend running a SERVICE trace of the RETRIEVE operation, contacting
IBM support, and sending them the trace along with an explanation of your
recreate scenario.

Regards,

Andy

Andy Raibeck
IBM Software Group
Tivoli Storage Manager Client Development
Internal Notes e-mail: Andrew Raibeck/Tucson/IBM@IBMUS
Internet e-mail: storman AT us.ibm DOT com

The only dumb question is the one that goes unasked.
The command line is your friend.
"Good enough" is the enemy of excellence.

"ADSM: Dist Stor Manager" <ADSM-L AT VM.MARIST DOT EDU> wrote on 2005-03-08
16:04:50:

> Thank you Andy for the reply.
>
> Here's the server's activity log during an archive and retrieve with a
> failure:
>
> 03/08/2005 10:16:11   ANR0402I Session 27276 started for administrator
> AFERRIS (WebConsole) (Tcp/Ip). (SESSION: 27276)
> 03/08/2005 10:20:16   ANR0406I Session 27277 started for node
> HOME_SERVER (NetWare) (Tcp/Ip 192.168.x.x(-27347)). (SESSION: 27277)
>
> 03/08/2005 10:22:39   ANR8337I LTO volume ICA046L2 mounted in drive
> F1R2 (mt1.0.0.4). (SESSION: 27277)
> 03/08/2005 10:22:39   ANR0511I Session 27277 opened output volume
> ICA046L2. (SESSION: 27277)
> 03/08/2005 10:25:26   ANE4952I (Session: 27277, Node: HOME_SERVER)
> Total number of objects inspected: 1,739 (SESSION: 27277)
> 03/08/2005 10:25:26   ANE4953I (Session: 27277, Node: HOME_SERVER)
> Total number of objects archived: 1,739 (SESSION: 27277)
> 03/08/2005 10:25:26   ANE4958I (Session: 27277, Node: HOME_SERVER)
> Total number of objects updated: 0 (SESSION: 27277)
> 03/08/2005 10:25:26   ANE4960I (Session: 27277, Node: HOME_SERVER)
> Total number of objects rebound: 0 (SESSION: 27277)
> 03/08/2005 10:25:26   ANE4957I (Session: 27277, Node: HOME_SERVER)
> Total number of objects deleted: 0 (SESSION: 27277)
> 03/08/2005 10:25:26   ANE4970I (Session: 27277, Node: HOME_SERVER)
> Total number of objects expired: 0 (SESSION: 27277)
> 03/08/2005 10:25:26   ANE4959I (Session: 27277, Node: HOME_SERVER)
> Total number of objects failed: 0 (SESSION: 27277)
> 03/08/2005 10:25:26   ANE4961I (Session: 27277, Node: HOME_SERVER)
> Total number of bytes transferred: 315.98 MB (SESSION: 27277)
> 03/08/2005 10:25:26   ANE4963I (Session: 27277, Node: HOME_SERVER)
> Data transfer time: 95.07 sec (SESSION: 27277)
>
> 03/08/2005 10:25:26   ANE4966I (Session: 27277, Node: HOME_SERVER)
> Network data transfer rate: 3,403.51 KB/sec (SESSION: 27277)
> 03/08/2005 10:25:26   ANE4967I (Session: 27277, Node: HOME_SERVER)
> Aggregate data transfer rate: 1,659.51 KB/sec (SESSION: 27277)
> 03/08/2005 10:25:26   ANE4968I (Session: 27277, Node: HOME_SERVER)
> Objects compressed by: 0% (SESSION: 27277)
> 03/08/2005 10:25:26   ANE4964I (Session: 27277, Node: HOME_SERVER)
> Elapsed processing time: 00:03:14 (SESSION: 27277)
> 03/08/2005 10:25:27   ANR0514I Session 27277 closed volume ICA046L2.
> (SESSION: 27277)
> 03/08/2005 10:25:27   ANR0403I Session 27277 ended for node HOME_SERVER
> (NetWare). (SESSION: 27277)
> 03/08/2005 10:25:36   ANR0406I Session 27278 started for node
> HOME_SERVER (NetWare) (Tcp/Ip 192.168.x.x(-27346)). (SESSION: 27278)
>
> 03/08/2005 10:26:09   ANR0510I Session 27278 opened input volume
> ICA046L2. (SESSION: 27278)
> 03/08/2005 10:27:16   ANE4956I (Session: 27278, Node: HOME_SERVER)
> Total number of objects retrieved:    1,707 (SESSION: 27278)
> 03/08/2005 10:27:16   ANE4959I (Session: 27278, Node: HOME_SERVER)
> Total number of objects failed: 1 (SESSION: 27278)
> 03/08/2005 10:27:16   ANE4961I (Session: 27278, Node: HOME_SERVER)
> Total number of bytes transferred: 305.07 MB (SESSION: 27278)
> 03/08/2005 10:27:16   ANE4963I (Session: 27278, Node: HOME_SERVER)
> Data transfer time: 8.26 sec (SESSION: 27278)
>
> 03/08/2005 10:27:16   ANE4966I (Session: 27278, Node: HOME_SERVER)
> Network data transfer rate: 37,820.51 KB/sec (SESSION: 27278)
> 03/08/2005 10:27:16   ANE4967I (Session: 27278, Node: HOME_SERVER)
> Aggregate data transfer rate: 4,594.08 KB/sec (SESSION: 27278)
> 03/08/2005 10:27:16   ANE4964I (Session: 27278, Node: HOME_SERVER)
> Elapsed processing time: 00:01:08 (SESSION: 27278)
> 03/08/2005 10:27:25   ANR0407I Session 27279 started for administrator
> AFERRIS (WebBrowser) (HTTP 192.168.x.x(3528)). (SESSION: 27279)
> 03/08/2005 10:27:25   ANR0405I Session 27279 ended for administrator
> AFERRIS (WebBrowser). (SESSION: 27279)
> 03/08/2005 10:27:31   ANR2017I Administrator AFERRIS issued command:
> QUERY ACTLOG BEGINDATE=03/08/2005 BEGINTIME=NOW-1:00 ENDDATE=03/08/2005
> ENDTIME=NOW ORIGINATOR=ALL (SESSION: 27307)
>
> Nothing stands out to me. I have tested a bunch of files with varying
> ages and the only that seem to fail are the ones with the postdated
> archive attribute. I will look into the NetWare TSAs as they could be
> the problem too.
>
> Andrew Ferris
> Network Support Analyst
> iCAPTURE Research Centre
> University of British Columbia
>
> >>> storman AT US.IBM DOT COM 3/8/2005 5:26:35 AM >>>
> I'm not convinced the archive attribute is the problem. If you look up
> the
> ANS1301E message, you will see it is apprising you of a problem on
> your
> TSM server, which is almost certainly not related to the archive
> attribute.
>
> Regards,
>
> Andy
>
> Andy Raibeck
> IBM Software Group
> Tivoli Storage Manager Client Development
> Internal Notes e-mail: Andrew Raibeck/Tucson/IBM@IBMUS
> Internet e-mail: storman AT us.ibm DOT com
>
> The only dumb question is the one that goes unasked.
> The command line is your friend.
> "Good enough" is the enemy of excellence.
>
> "ADSM: Dist Stor Manager" <ADSM-L AT VM.MARIST DOT EDU> wrote on 2005-03-07
> 13:02:22:
>
> > I'm seeing Retrieves fail on certain older files. The error is
> ANS1301E
> > Server detected system error.
> >
> > These are files from 2003 or earlier on NetWare file servers and
> they
> > have odd archive date attributes as a result of fixed McAfee
> anti-virus
> > issue. The file's Last Archive value will be something like:
> > Last Archive: Thursday April 27, 2795 5:33:40 AM
> > In other words, these files are several hundred years in the future
> as
> > far as the archive attribute is concerned.
> >
> > Restores of the same files work fine, presumably because
> > backups/restores ignore the archive attribute.
> >
> > The TSM client levels I have tried are 5.2.3.0 and 5.2.3.13 on
> NetWare
> > 6.5 SP2
> > The TSM Server is at 5.2.3.2 on Windows 2000
> >
> > I've read the client manual on what TSM looks at to determine if a
> file
> > has changed for backup purposes but there isn't the same info for
> > archive considerations. Would I be correct in believing that the
> > excessive archive attribute is what's causing the errors? How does
> TSM
> > look at/use the archive attribute for archives/retrieves?
> >
> > We can reset the archive attribute using NetWare utilities but the
> > certainty that the problem is correctly diagnosed would help.
> >
> > thanks,
> >
> >
> > Andrew Ferris
> > Network Support Analyst
> > iCAPTURE Research Centre
> > University of British Columbia