Documents to collect for ANR0102E Error 1202 inserting row in table "AF.Bitfiles".

Troubleshooting

Problem

This technote describes the documents to collect to investigate ANR0102E errors seen for AF.Bitfiles insertions during a data movement operation.

Symptom

The following might be reported for a data movement operation, in the example a MOVE DATA operation:

ANR2017I Administrator <ADMIN> issued command: MOVE DATA <INPUTVOL>
ANR2232W This command will move all of the data stored on volume <INPUTVOL> to other volumes within the same storage pool; the data will be inaccessible to users until the operation completes.~
ANR1157I Removable volume <INPUTVOL> is required for move process.~
ANR0984I Process 130 for MOVE DATA started in the BACKGROUND at 17:26:42.~
ANR1140I Move data process started for volume <INPUTVOL> (process ID 130).~
ANR0513I Process 130 opened output volume <OUTPUTVOL>.~
ANR8337I LTO volume <INPUTVOL> mounted in drive <DRIVE> (\\.\Tape3).~
ANR0512I Process 130 opened input volume <INPUTVOL>.~
..
ANR0102E afcreate.c(2595): Error 1202 inserting row in table "AF.Bitfiles".~
ANR1181E aftxn.c(367): Data storage transaction <TRANSACTION> was aborted.~
..
ANR1141I Move data process ended for volume <INPUTVOL>.~
ANR0515I Process 130 closed volume <INPUTVOL>.~
ANR0515I Process 130 closed volume <OUTPUTVOL>.~
ANR0985I Process 130 for MOVE DATA running in the BACKGROUND completed with completion state FAILURE at <TIME>.~
ANR1893E Process 130 for MOVE DATA completed with a completion state of FAILURE.~

Cause

At the time of the writing, the root cause that introduces the symptom is not identified. The docs requested here will allow to compile a recovery action plan.

Diagnosing The Problem

Collect a server trace with the following traceflags for disk volumes:

DFMOVE BFAGGR

and for sequential volumes:

AFMOVE BFAGGR

You can use the server monitoring script from the related URL section and specify the traceflags when you get the

Enter traceflags
prompt, for the stop event prompt, you can specify ANR0102E

Resolving The Problem

In the trace, search for the object that is failing:
17:29:22.344 [179][bfaggrut.c][3798][BfListDeletedAggregate]:Entering for superBfId 120235213
17:29:22.344 [179][bfaggrut.c][3835][BfListDeletedAggregate]:Exiting with 1 items in the deletion list
17:29:22.344 [179][bftxn.c][511][bfPrepareTxn]:Preparing txnDesc 000000001EFD3720.
17:29:22.344 [179][bftxn.c][565][bfPrepareTxn]:summaryVote is 2, createList is 0000000000000000
17:29:22.352 [179][bfcreate.c][6435][BfPrepareRecon]:Entering for txnP 000000001EFD3720
17:29:22.352 [179][bfcreate.c][7031][BfPrepareRecon]:Exiting, rc 0
17:29:22.352 [179][afcreate.c][2467][AfPrepareNew]:logOccConst is False for bitfile 121007828
17:29:22.352 [179][afcreate.c][2484][AfPrepareNew]:Aggr Attributes: rc: 0, aggrSize: 3170871, logSize: 3170871, rptSize: 3170871, files: 18, chunks: 0
17:29:22.352 [179][aftxn.c][614][AfUpdateClusterEx]:Called from afcreate.c[2542]
17:29:22.352 [179][aftxn.c][621][AfUpdateClusterEx]:Updating cluster (1,1) in pool -8 with direction: up, numFiles: 18, numChunks: 0, amount: 0 logAmount: 3171319, rptAmount: 3171319
17:29:22.352 [179][aftxn.c][751][AfUpdateClusterEx]:Increasing incrNumFiles (0) by 18, incrNumChunks (0) by 0
17:29:22.353 [179][output.c][7647][PutConsoleMsg]:ANR0102E afcreate.c(2595): Error 1202 inserting row in table "AF.Bitfiles".~
17:29:22.353 [179][output.c][7647][PutConsoleMsg]:ANR1181E aftxn.c(367): Data storage transaction 0:185330897 was aborted.~

Now search the trace backwards for where the processing of that object is initiated:

17:26:32.442 [179][bfcreate.c][4007][BfReconstruct]:Entering for bfId 120235213 from non-dedup pool -8 to destBfId 0 in non-dedup pool -8, srcDeleted: F, cataloged: F, analyzed: F, ignore chunks: F, offsite dedup op: F, thisFragInfo 0000000000000000, copyFragInfo 0000000000000000
17:26:32.485 [179][bfcreate.c][4078][BfReconstruct]:Using statement id 16777232 for aggregate id 120235213.
17:26:32.485 [179][bfcreate.c][4125][BfReconstruct]:numFiles is 18
17:26:32.485 [179][bfcreate.c][4126][BfReconstruct]:blockSize is 4216
17:26:32.491 [179][bfaggrut.c][3462][BfGetAliases]:Entering for 120235213.
17:26:32.500 [179][bfaggrut.c][3585][BfGetAliases]:Fetched sub-bitfile 114526408 as part of 120235213.
17:26:32.502 [179][bfaggrut.c][3632][BfGetAliases]:Fetched super-bitfile 119923209.
17:26:32.502 [179][bfaggrut.c][3632][BfGetAliases]:Fetched super-bitfile 120235213.
17:26:32.502 [179][bfaggrut.c][3632][BfGetAliases]:Fetched super-bitfile 120432749.
17:26:32.502 [179][bfaggrut.c][3632][BfGetAliases]:Fetched super-bitfile 121007828.
17:26:32.502 [179][bfcreate.c][4196][BfReconstruct]:Found 4 aliases for bitfile 120235213

Collect SHOW INVO for all super-bitfiles listed:

SHOW INVO 119923209 > 119923209.txt
SHOW INVO 120235213 > 120235213.txt
SHOW INVO 120432749 > 120432749.txt
SHOW INVO 121007828 > 121007828.txt

Contact IBM support with the trace and the SHOW INVO files collected.

Related Information

Server monitoring

[{"Product":{"code":"SSGSG7","label":"Tivoli Storage Manager"},"Business Unit":{"code":"BU058","label":"IBM Infrastructure w\/TPS"},"Component":"Server","Platform":[{"code":"PF025","label":"Platform Independent"}],"Version":"All Supported Versions","Edition":"","Line of Business":{"code":"LOB26","label":"Storage"}}]

Product Synonym

TSM

Was this topic helpful?

Document Information

Modified date:
17 June 2018

UID

swg21980306

Tips