ADSM-L

Re: ANR9999D messages

2004-07-21 02:59:48
Subject: Re: ANR9999D messages
From: "Loon, E.J. van - SPLXM" <Eric-van.Loon AT KLM DOT COM>
To: ADSM-L AT VM.MARIST DOT EDU
Date: Wed, 21 Jul 2004 08:59:52 +0200
Hi Richard!
My server also shows the "ANR9999D dsutil.c(264): ThreadId<0> Pool id x not
found." messages during startup. I do not see migrations starting for the
non-existing pools however.
In my case it started when I deleted disk storage pools, way back when we
were running ADSM 3.1.
I tested an audit of my production database on a test server and I found
that the quickest (or better yet, least slowest) way to solve this error is
to audit the database with the storage parameter. So, you should issue the
command: dsmserv auditdb storage
Kindest regards,
Eric van Loon
KLM Royal Dutch Airlines


-----Original Message-----
From: Richard Rhodes [mailto:rrhodes AT FIRSTENERGYCORP DOT COM]
Sent: Tuesday, July 20, 2004 17:05
To: ADSM-L AT VM.MARIST DOT EDU
Subject: ANR9999D messages


We have a old tsm v4.2 server (on hpux) that we are trying to migrate over
to AIX.  Of couse, this process is taking
much longer than anyone ever thought it would.  We're stuck with this
server for at least another couple months.

Recently, and I'm not sure when, we started receiving ANR9999D messages.
It seems like anytime a migration tries to start on some of the primary
pools that these messages occur, and the migration fails.

When TSM is first  starts up, we receive the following messages right as
storage pool files are brought online  . . . .

07/19/04   20:10:45      ANR9999D dsutil.c(264): ThreadId<0> Pool id 4 not
found.
07/19/04   20:10:47      ANR9999D asutil.c(237): ThreadId<0> Pool id -12
not found.
07/19/04   20:10:47      ANR9999D asutil.c(237): ThreadId<0> Pool id -11
not found.
07/19/04   20:10:48      ANR9999D dsutil.c(264): ThreadId<84> Pool id 4 not
found.
07/19/04   20:10:48      ANR9999D dfmigr.c(1279): ThreadId<84> Error
obtaining
                          attributes for pool (4).
07/19/04   20:10:48      ANR1117W Error initiating migration for storage
pool  -
                          internal server error detected.
07/19/04   20:10:49      ANR9999D asutil.c(237): ThreadId<0> Pool id 19 not
found.


All the files for all the pools are online.  From what I can tell,
everything looks ok.
Then, when a migration tries to start for certain pools, we get more nasty
messages.  The messages go on, and on, and on.  This is just a small
sample.

Any help with this is greatly appreciated.


. . . . nasty messages . . . .

07/20/04   02:20:50      ANR2017I Administrator G2559 issued command:
UPDATE
                          STGPOOL mgobackuppool hi=0 lo=0

07/20/04   02:20:50      ANR9999D dsutil.c(264): ThreadId<84> Pool id 4 not
found.
07/20/04   02:20:50      ANR0984I Process 6 for MIGRATION started in the
BACKGROUND
                          at 02:20:50 AM.
07/20/04   02:20:50      ANR1000I Migration process 6 started for storage
pool
                          MGOBACKUPPOOL.
07/20/04   02:20:50      (84) Context report
07/20/04   02:20:50      (84) Thread DfMigrationThread (24) is a child
thread
                          related to: 84
07/20/04   02:20:50      (24) Generating TM Context Report:
(struct=tmTxnDesc)
                          (slots=256)
07/20/04   02:20:50      (24) slot -> 167:
07/20/04   02:20:50      (24) Tsn=0:618278823, Resurrected=False,
InFlight=True,
                          Distributed=False
07/20/04   02:20:50      (24)  Participants=1, summaryVote=ReadOnly
07/20/04   02:20:50          Participant DB: voteReceived=False,
ackReceived=False
07/20/04   02:20:51      (24) slot -> 169:
07/20/04   02:20:51      (24) Tsn=0:618278825, Resurrected=False,
InFlight=True,
                          Distributed=False
07/20/04   02:20:51      (24)  Participants=3, summaryVote=ReadOnly
07/20/04   02:20:51          Participant DB: voteReceived=False,
ackReceived=False
07/20/04   02:20:51          Participant BF: voteReceived=False,
ackReceived=False
07/20/04   02:20:51          Participant SS: voteReceived=False,
ackReceived=False
07/20/04   02:20:51        Locks held by Tsn=0:618278825 :
07/20/04   02:20:51          Type=34040, NameSpace=17462, SummMode=xLock,
                          Mode=xLock, Key='35959.0'
07/20/04   02:20:51      (24) Generating Database Transaction Table
Context:
07/20/04   02:20:51      (24)  *** no transactions found ***
07/20/04   02:20:51      (24) Generating SM Context Report:
07/20/04   02:20:51      (24)  *** no sessions found ***
07/20/04   02:20:51      (24) Generating AS Vol Context Report:
07/20/04   02:20:51      (24)  No mounted (or mount in progress) volumes.
07/20/04   02:20:51      (24) Failed attempt #1 to get SSV->mutex
07/20/04   02:20:51      (24) Generating ssSession Context Report:
07/20/04   02:20:51      (24)  Storage Service Sessions:
07/20/04   02:20:51      (24)  Session 114 --> BufConfig=XLarge,
                          TransBufSize=262144, SplitBuf=False, WrCount=0,
                          WrBufIsEmpty=False, WrBufIsFull=False,
SourceRc=0,
                          SinkRc=0, AuxCreated=True, AuxBegin=False,
                          AuxIsSink=True, AuxIdle=False, AuxTerminate=False
07/20/04   02:20:51      (24)     Leased Volumes:
07/20/04   02:20:51      (24)   (none)
07/20/04   02:20:51      (24)     Excluded VolIds:
07/20/04   02:20:51      (24)   (none)
07/20/04   02:20:51      (24)     AS Extension: WrMpClassId=3,
RdMpClassId=-1,
                          ForceRelease=False.
07/20/04   02:20:51
07/20/04   02:20:51      (24) Generating ssOpenSeg Context Report:
07/20/04   02:20:51      (24)  Storage Service Segments:
07/20/04   02:20:51      (24)  VolId=17462, Start=35959, Offset=0,
SessId=114,
                          Locked=False, Deallocated=False
07/20/04   02:20:51      (24) Generating BF Copy Control Context Report:
07/20/04   02:20:51      (24)  No global copy control blocks.
07/20/04   02:20:51      (24)   procNum=6, status=Disk Storage Pool
MGOBACKUPPOOL,
                          Moved Files: 0, Moved Bytes: 0, Unreadable Files:
0,
                          Unreadable Bytes: 0. Current Physical File
(bytes):
                          565,248 Waiting for mount of output volume 030022
(0
                          seconds). , cancelInProgress=False
07/20/04   02:20:51      (24)   descr=Migration, name=MIGRATION,
cancelled=False
07/20/04   02:20:51
07/20/04   02:20:51      (84) DfMigrationAgent : ANR9999D calling thread
07/20/04   02:20:51      (84) Generating TM Context Report:
(struct=tmTxnDesc)
                          (slots=256)
07/20/04   02:20:51      (84)  *** no transactions found ***
07/20/04   02:20:51      (84) Failed attempt #1 to get DBV->mutex
07/20/04   02:20:51      (84) Failed attempt #2 to get DBV->mutex
07/20/04   02:20:51      (84) Failed attempt #3 to get DBV->mutex
07/20/04   02:20:52      (84) Generating Database Transaction Table
Context:
07/20/04   02:20:52      (84)  *** no transactions found ***
07/20/04   02:20:52      (84) Generating SM Context Report:
07/20/04   02:20:52      (84)  *** no sessions found ***
07/20/04   02:20:52      (84) Generating AS Vol Context Report:
07/20/04   02:20:52      (84)  No mounted (or mount in progress) volumes.
07/20/04   02:20:52      (84) Generating ssSession Context Report:
07/20/04   02:20:52      (84)  No storage service sessions active.
07/20/04   02:20:52      (84) Generating ssOpenSeg Context Report:
07/20/04   02:20:52      (84)  No storage service segments found.
07/20/04   02:20:52      (84) Generating BF Copy Control Context Report:
07/20/04   02:20:52      (84)  No global copy control blocks.
07/20/04   02:20:52
07/20/04   02:20:52      (84) End Context report

07/20/04   02:20:52      ANR9999D dfmigr.c(1279): ThreadId<84> Error
obtaining
                          attributes for pool (4).
07/20/04   02:20:52      (84) Context report
07/20/04   02:20:52      (84) Thread DfMigrationThread (24) is a child
thread
                          related to: 84
07/20/04   02:20:52      (24) Generating TM Context Report:
(struct=tmTxnDesc)
                          (slots=256)
07/20/04   02:20:52      (24) slot -> 167:
07/20/04   02:20:52      (24) Tsn=0:618278823, Resurrected=False,
InFlight=True,
                          Distributed=False
07/20/04   02:20:52      (24)  Participants=1, summaryVote=ReadOnly
07/20/04   02:20:52          Participant DB: voteReceived=False,
ackReceived=False
07/20/04   02:20:52      (24) slot -> 169:
07/20/04   02:20:52      (24) Tsn=0:618278825, Resurrected=False,
InFlight=True,
                          Distributed=False
07/20/04   02:20:52      (24)  Participants=3, summaryVote=ReadOnly
07/20/04   02:20:52          Participant DB: voteReceived=False,
ackReceived=False
07/20/04   02:20:52          Participant BF: voteReceived=False,
ackReceived=False
07/20/04   02:20:52          Participant SS: voteReceived=False,
ackReceived=False
07/20/04   02:20:52        Locks held by Tsn=0:618278825 :
07/20/04   02:20:52          Type=34040, NameSpace=17462, SummMode=xLock,
                          Mode=xLock, Key='35959.0'

07/20/04   02:20:52      ANR1117W Error initiating migration for storage
pool  -
                          internal server error detected.

07/20/04   02:20:53      (24) Generating Database Transaction Table
Context:
07/20/04   02:20:53      (24)  *** no transactions found ***
07/20/04   02:20:53      (24) Generating SM Context Report:
07/20/04   02:20:53      (24)  *** no sessions found ***
07/20/04   02:20:53      (24) Generating AS Vol Context Report:
07/20/04   02:20:53      (24)  No mounted (or mount in progress) volumes.
07/20/04   02:20:53      (24) Generating ssSession Context Report:
07/20/04   02:20:53      (24)  Storage Service Sessions:
07/20/04   02:20:53      (24)  Session 114 --> BufConfig=XLarge,
                          TransBufSize=262144, SplitBuf=False, WrCount=0,
                          WrBufIsEmpty=False, WrBufIsFull=False,
SourceRc=0,
                          SinkRc=0, AuxCreated=True, AuxBegin=False,
                          AuxIsSink=True, AuxIdle=False, AuxTerminate=False
07/20/04   02:20:53      (24)     Leased Volumes:
07/20/04   02:20:53      (24)   (none)
07/20/04   02:20:53      (24)     Excluded VolIds:
07/20/04   02:20:53      (24)   (none)
07/20/04   02:20:53      (24)     AS Extension: WrMpClassId=3,
RdMpClassId=-1,
                          ForceRelease=False.
07/20/04   02:20:53
07/20/04   02:20:53      (24) Generating ssOpenSeg Context Report:
07/20/04   02:20:53      (24)  Storage Service Segments:
07/20/04   02:20:53      (24)  VolId=17462, Start=35959, Offset=0,
SessId=114,
                          Locked=False, Deallocated=False
07/20/04   02:20:53      (24) Generating BF Copy Control Context Report:
07/20/04   02:20:53      (24)  No global copy control blocks.
07/20/04   02:21:13      (24)   procNum=6, status=Disk Storage Pool
MGOBACKUPPOOL,
                          Moved Files: 0, Moved Bytes: 0, Unreadable Files:
0,
                          Unreadable Bytes: 0. Current Physical File
(bytes):
                          565,248 Waiting for mount of output volume 030022
(2
                          seconds). , cancelInProgress=False
07/20/04   02:21:13      (24)   descr=Migration, name=MIGRATION,
cancelled=False
07/20/04   02:21:13
07/20/04   02:21:13      (84) Thread SsAuxThread (20) is a grandchild
thread
                          related to: 24
07/20/04   02:21:13      (20) Generating TM Context Report:
(struct=tmTxnDesc)
                          (slots=256)
07/20/04   02:21:13      (20)  *** no transactions found ***
07/20/04   02:21:13      (20) Generating Database Transaction Table
Context:
07/20/04   02:21:13      (20)  *** no transactions found ***
07/20/04   02:21:13      (20) Generating SM Context Report:
07/20/04   02:21:13      (20)  *** no sessions found ***
07/20/04   02:21:13      (20) Generating AS Vol Context Report:
07/20/04   02:21:13      (20)  Mounted (or mount in progress) volumes:
07/20/04   02:21:13      (20)  Volume 030022(19736) --> SessId=114,
Mode=Output,
                          Use=Bitfile, ClassId=3, ClassName=MGOTAPE,
                          IsScratch=True, VolSeqNum=0,
Pool=MGOBACKUPTAPE(5),
                          Allocated=False, NextSeqNum=736,
PosUncertain=True,
                          Open=True, OpenInProg=True, MountMode=Read/Write,
                          Reuse=Keep, IsFirstMount=False, IsEmpty=False,
                          IsNewScratch=False, PreemptAccess=False,
Waiters=0,
                          TwoSided=False, SideSeqNum=-1
07/20/04   02:21:13      (20) Generating ssSession Context Report:
07/20/04   02:21:13      (20)  No storage service sessions active.
07/20/04   02:21:13      (20) Generating ssOpenSeg Context Report:
07/20/04   02:21:13      (20)  No storage service segments found.
07/20/04   02:21:13      (20) Generating BF Copy Control Context Report:
07/20/04   02:21:13      (20)  No global copy control blocks.
07/20/04   02:21:13      (20)   procNum=5, status=ANR6902I Number of plan
stanzas
                          processed: 2. Currently processing stanza:
                          SERVER.REQUIREMENTS., cancelInProgress=False
07/20/04   02:21:13      (20)   descr=PREPARE, name=PREPARE,
cancelled=False
07/20/04   02:21:13
07/20/04   02:21:13      (84) Thread AgentThread (29) is a great-grandchild
thread
                          related to: 20
07/20/04   02:21:13      (29) Generating TM Context Report:
(struct=tmTxnDesc)
                          (slots=256)
07/20/04   02:21:13      (29)  *** no transactions found ***
07/20/04   02:21:13      (29) Generating Database Transaction Table
Context:
07/20/04   02:21:14      (29)  *** no transactions found ***
07/20/04   02:21:14      (29) Generating SM Context Report:
07/20/04   02:21:14      (29)  *** no sessions found ***
07/20/04   02:21:14      (29) Generating AS Vol Context Report:
07/20/04   02:21:14      (29)  No mounted (or mount in progress) volumes.
07/20/04   02:21:14      (29) Generating ssSession Context Report:
07/20/04   02:21:14      (29)  No storage service sessions active.
07/20/04   02:21:14      (29) Generating ssOpenSeg Context Report:
07/20/04   02:21:14      (29)  No storage service segments found.
07/20/04   02:21:14      (29) Generating BF Copy Control Context Report:
07/20/04   02:21:14      (29)  No global copy control blocks.
07/20/04   02:21:14
07/20/04   02:21:14      (84) DfMigrationAgent : ANR9999D calling thread
07/20/04   02:21:14      (84) Generating TM Context Report:
(struct=tmTxnDesc)
                          (slots=256)
07/20/04   02:21:14      (84)  *** no transactions found ***
07/20/04   02:21:14      (84) Generating Database Transaction Table
Context:
07/20/04   02:21:14      (84)  *** no transactions found ***
07/20/04   02:21:14      (84) Generating SM Context Report:
07/20/04   02:21:14      (84)  *** no sessions found ***
07/20/04   02:21:14      (84) Generating AS Vol Context Report:
07/20/04   02:21:14      (84)  No mounted (or mount in progress) volumes.
07/20/04   02:21:14      (84) Generating ssSession Context Report:
07/20/04   02:21:14      (84)  No storage service sessions active.
07/20/04   02:21:14      (84) Generating ssOpenSeg Context Report:
07/20/04   02:21:14      (84)  No storage service segments found.
07/20/04   02:21:14      (84) Generating BF Copy Control Context Report:
07/20/04   02:21:14      (84)  No global copy control blocks.
07/20/04   02:21:14
07/20/04   02:21:14      (84) End Context report


07/20/04   02:26:00      ANR0984I Process 7 for MIGRATION started in the
BACKGROUND
                          at 02:26:00 AM.
07/20/04   02:26:00      ANR9999D dsutil.c(264): ThreadId<84> Pool id 4 not
found.
07/20/04   02:26:00      ANR1000I Migration process 7 started for storage
pool
                          MGOBACKUPPOOL.
07/20/04   02:26:00      (84) Context report
07/20/04   02:26:00      (84) Thread DfMigrationThread (24) is a child
thread
                          related to: 84
07/20/04   02:26:00      (24) Generating TM Context Report:
(struct=tmTxnDesc)
                          (slots=256)
07/20/04   02:26:00      (24) slot -> 29:
07/20/04   02:26:00      (24) Tsn=0:618279197, Resurrected=False,
InFlight=True,
                          Distributed=False
07/20/04   02:26:00      (24)  Participants=1, summaryVote=ReadOnly
07/20/04   02:26:00          Participant DB: voteReceived=False,
ackReceived=False
07/20/04   02:26:00        Locks held by Tsn=0:618279197 :
07/20/04   02:26:00          Type=47052, NameSpace=0, SummMode=sLock,
Mode=sLock,
                          Key='0.171788416'
07/20/04   02:26:00          Type=46001, NameSpace=0, SummMode=sLock,
Mode=sLock,
                          Key='0.171788416'
07/20/04   02:26:00          Type=47051, NameSpace=0, SummMode=isLock,
Mode=isLock,
                          Key=''
07/20/04   02:26:00      ANR9999D dfmigr.c(1279): ThreadId<84> Error
obtaining
                          attributes for pool (4).
07/20/04   02:26:00      ANR1117W Error initiating migration for storage
pool  -
                          internal server error detected.
07/20/04   02:26:01      (24) Generating Database Transaction Table
Context:
07/20/04   02:26:01      (24)  *** no transactions found ***
07/20/04   02:26:01      (24) Generating SM Context Report:
07/20/04   02:26:01      (24)  *** no sessions found ***
07/20/04   02:26:01      (24) Generating AS Vol Context Report:
07/20/04   02:26:01      (24)  No mounted (or mount in progress) volumes.
07/20/04   02:26:01      (24) Generating ssSession Context Report:
07/20/04   02:26:01      (24)  Storage Service Sessions:
07/20/04   02:26:01      (24)  Session 115 --> BufConfig=XLarge,
                          TransBufSize=262144, SplitBuf=False, WrCount=0,
                          WrBufIsEmpty=False, WrBufIsFull=False,
SourceRc=0,
                          SinkRc=0, AuxCreated=True, AuxBegin=False,
                          AuxIsSink=False, AuxIdle=True, AuxTerminate=False
07/20/04   02:26:01      (24)     Leased Volumes:
07/20/04   02:26:01      (24)   (none)
07/20/04   02:26:01      (24)     Excluded VolIds:
07/20/04   02:26:01      (24)   (none)
07/20/04   02:26:01      (24)     AS Extension: WrMpClassId=-1,
RdMpClassId=-1,
                          ForceRelease=False.
07/20/04   02:26:01
07/20/04   02:26:01      (24) Generating ssOpenSeg Context Report:
07/20/04   02:26:01      (24)  No storage service segments found.
07/20/04   02:26:01      (24) Generating BF Copy Control Context Report:
07/20/04   02:26:01      (24)  No global copy control blocks.
07/20/04   02:26:01      (24)   procNum=7, status=Disk Storage Pool
MGOBACKUPPOOL,
                          Moved Files: 0, Moved Bytes: 0, Unreadable Files:
0,
                          Unreadable Bytes: 0. Current Physical File
(bytes):
                          2,306,048, cancelInProgress=False
07/20/04   02:26:01      (24)   descr=Migration, name=MIGRATION,
cancelled=False
07/20/04   02:26:01
07/20/04   02:26:01      (84) DfMigrationAgent : ANR9999D calling thread
07/20/04   02:26:01      (84) Generating TM Context Report:
(struct=tmTxnDesc)
                          (slots=256)
07/20/04   02:26:01      (84)  *** no transactions found ***
07/20/04   02:26:01      (84) Generating Database Transaction Table
Context:
07/20/04   02:26:01      (84)  *** no transactions found ***
07/20/04   02:26:01      (84) Generating SM Context Report:
07/20/04   02:26:01      (84)  *** no sessions found ***
07/20/04   02:26:01      (84) Generating AS Vol Context Report:
07/20/04   02:26:01      (84)  No mounted (or mount in progress) volumes.
07/20/04   02:26:01      (84) Generating ssSession Context Report:
07/20/04   02:26:01      (84)  No storage service sessions active.
07/20/04   02:26:01      (84) Generating ssOpenSeg Context Report:
07/20/04   02:26:01      (84)  No storage service segments found.
07/20/04   02:26:01      (84) Generating BF Copy Control Context Report:
07/20/04   02:26:01      (84)  No global copy control blocks.
07/20/04   02:26:01
07/20/04   02:26:01      (84) End Context report




-----------------------------------------
The information contained in this message is intended only for the personal
and confidential use of the recipient(s) named above. If the reader of this
message is not the intended recipient or an agent responsible for delivering
it to the intended recipient, you are hereby notified that you have received
this document in error and that any review, dissemination, distribution, or
copying of this message is strictly prohibited. If you have received this
communication in error, please notify us immediately, and delete the
original message.


**********************************************************************
For information, services and offers, please visit our web site: 
http://www.klm.com. This e-mail and any attachment may contain confidential and 
privileged material intended for the addressee only. If you are not the 
addressee, you are notified that no part of the e-mail or any attachment may be 
disclosed, copied or distributed, and that any other action related to this 
e-mail or attachment is strictly prohibited, and may be unlawful. If you have 
received this e-mail by error, please notify the sender immediately by return 
e-mail, and delete this message. Koninklijke Luchtvaart Maatschappij NV (KLM), 
its subsidiaries and/or its employees shall not be liable for the incorrect or 
incomplete transmission of this e-mail or any attachments, nor responsible for 
any delay in receipt.
**********************************************************************

<Prev in Thread] Current Thread [Next in Thread>