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.
|
