IBM Support

IC94886: ERROR 1202 INSERTING ROW IN TABLE "AF.BITFILES" DURING SERVER DATA MOVEMENT OPERATION

Subscribe

You can track all active APARs for this component.

APAR status

  • Closed as program error.

Error description

  • The Tivoli Storage Manager server might report
    ANR0102E afcreate.c(2573): Error 1202 inserting row in table
    "AF.Bitfiles"
    if migrate and backup for the same storage pool is run in
    parallel and the migrate storage pool is configured for
    simultaneous write to the same storage pool the backup storage
    pool is done to.
    
    Example: BKPPOOL's (id=8) next storage pool is TAPEPRIMARY
    (id=10)
    TAPEPRIMARY's copy pool is TAPECOPY (id=-4), AUTOCOPY is ALL or
    MIGRATION
    
    Now if backup and migration are run in parallel, the backup
    storage pool might want to back up an object which was already
    processed by migration and because of the AUTOCOPY setting is
    unexpectedly already available in the target copy storage pool.
    ANR2017I Administrator <ADMIN> issued command: BACKUP STGPOOL
    BKPPOOL TAPECOPY wait=yes maxpro=2
    and in parallel
    ANR0984I Process <PROCESSID> for MIGRATION started in the
    BACKGROUND at <TIME>. (PROCESS: <PROCESSID>)
    
    At some point the ANR messages is reported
    ANR0102E afcreate.c(2573): Error 1202 inserting row in table
    "AF.Bitfiles"
    And finally all processes involved report successful completion:
    
    ANR0986I Process <ID> for BACKUP STORAGE POOL running in
     the FOREGROUND processed ##### items for a total of
     ##### bytes with a completion state of SUCCESS
     at 07:51:50. (SESSION: <SESSID>, PROCESS: <PROCESSID>)
    ANR1214I Backup of primary storage pool BKPPOOL to
     copy storage pool TAPECOPY has ended.  Files
     Backed Up: #####, Bytes Backed Up: #####,
     Unreadable Files: 0, Unreadable Bytes: 0. (SESSION:
     <SESSID>)
    
    ANR1916I Autocopy process <PROCESSID> ended successfully for
     storage pool TAPECOPY. Files Transferred: #####,
     Bytes Transferred: #####. (SESSION: <SESSID>,
     PROCESS: <PROCESSID>)
    ANR0986I Process <PROCESSID> for MIGRATION running in the
     BACKGROUND processed ##### items for a total of
     ##### bytes with a completion state of SUCCESS
     at <TIME>. (SESSION: <SESSID>, PROCESS: <PROCESSID>)
      Tivoli Storage Manager Versions Affected: all supported
      Customer/L2 Diagnostics (If Applicable)
    Use the following traceflags to investigate the error 1202
    condition:
    trace dis *
    trace ena BF AF DF
    trace begin <valid_path_and_filename> bufsize=4096
    You can use the information from the following technote to stop
    tracing once the symptom is seen:
    http://www.ibm.com/support/docview.wss?uid=swg21167024
    A review of the trace shows thread 59 is migration operation, it
    migrates data from pool 8 to pool 10 and pool -4.
    02:39:48.101
    &#65422;59&#65434;&#65422;afmigr.c&#65434;&#65422;1064&#65434;&#65422;AfMigrVolumeThread&#65434;:AfMigrVolumeThread
    (procNum=126) started for pool BKPPOOL(8).
    02:39:48.101 &#65422;59&#65434;&#65422;afmigr.c&#65434;&#65422;1232&#65434;&#65422;AfMigrVolumeThread&#65434;:Proc 126
    starting migration for pool BKPPOOL(8).
    ..
    04:10:09.687 &#65422;59&#65434;&#65422;afcreate.c&#65434;&#65422;2444&#65434;&#65422;AfPrepareNew&#65434;:logOccConst is
    True for bitfile 89985092
    04:10:09.687
    &#65422;59&#65434;&#65422;afcreate.c&#65434;&#65422;2752&#65434;&#65422;AfInsertSegments&#65434;:Incrementing logical
    occupancy for volume 48818 by 30496084. logSizeRatio=1.000000,
    srvId=0, bfId=89985092.
    04:10:09.690 &#65422;59&#65434;&#65422;aftxn.c&#65434;&#65422;1212&#65434;&#65422;PrepareClusters&#65434;:Preparing
    cluster (25,3) in pool -4.
    04:10:09.691 &#65422;59&#65434;&#65422;afcreate.c&#65434;&#65422;2444&#65434;&#65422;AfPrepareNew&#65434;:logOccConst is
    True for bitfile 89985092
    04:10:09.694 &#65422;59&#65434;&#65422;aftxn.c&#65434;&#65422;1212&#65434;&#65422;PrepareClusters&#65434;:Preparing
    cluster (25,3) in pool 8.
    
    Thread 55 is backup storage operation, backup from pool 8 to
    pool -4.
    02:46:48.107
    &#65422;55&#65434;&#65422;afbackup.c&#65434;&#65422;1236&#65434;&#65422;AfBackupPoolThread&#65434;:Beginning backup
    thread for sequential pool BKPPOOL(8) to copy pool TAPECOPY(-4).
    ..
    04:10:37.224 &#65422;55&#65434;&#65422;aftxn.c&#65434;&#65422;600&#65434;&#65422;AfUpdateClusterEx&#65434;:Updating
    cluster (25,3) in pool -4 with direction: up, numFiles: 1,
    numChunks: 0, logAmount: 30496084, rptAmount: 30496084
    04:10:37.225 &#65422;55&#65434;&#65422;afcreate.c&#65434;&#65422;2444&#65434;&#65422;AfPrepareNew&#65434;:logOccConst is
    True for bitfile 89985092
    04:10:37.225 &#65422;55&#65434;&#65422;output.c&#65434;&#65422;7271&#65434;&#65422;PutConsoleMsg&#65434;:ANR0102E
    afcreate.c(2573): Error 1202 inserting row in table
    "AF.Bitfiles".
    04:10:37.225 &#65422;55&#65434;&#65422;output.c&#65434;&#65422;7271&#65434;&#65422;PutConsoleMsg&#65434;:ANR1181E
    aftxn.c(346): Data storage transaction 0:49441841 was aborted.
    
      Initial Impact: Low
      Additional Keywords: TSM SDMO GRC_KEY_EXISTS
    

Local fix

  • if the condition for this APAR is met, you can ignore the
    ANR0102E Error 1202 message.
    
    To prevent from the symptom, do not schedule parallel backup
    and migration for the same storage pool if simultaneous write
    is configured to write data to the backup copy storage pool
    during migration.
    

Problem summary

  • ****************************************************************
    * USERS AFFECTED: All Tivoli Storage Manager server users.     *
    ****************************************************************
    * PROBLEM DESCRIPTION: See error description.                  *
    ****************************************************************
    * RECOMMENDATION: Apply fixing level when available. This      *
    *                 problem is currently projected to be fixed   *
    *                 in level 6.3.6. Note that this is            *
    *                 subject to change at the discretion of IBM.  *
    ****************************************************************
    *
    

Problem conclusion

  • This problem was fixed.
    
    Affected platforms:
    AIX, HP-UX, Solaris, Linux, and Windows.
    

Temporary fix

Comments

APAR Information

  • APAR number

    IC94886

  • Reported component name

    TSM SERVER

  • Reported component ID

    5698ISMSV

  • Reported release

    63W

  • Status

    CLOSED PER

  • PE

    NoPE

  • HIPER

    NoHIPER

  • Special Attention

    NoSpecatt

  • Submitted date

    2013-08-14

  • Closed date

    2013-09-09

  • Last modified date

    2015-05-06

  • APAR is sysrouted FROM one or more of the following:

  • APAR is sysrouted TO one or more of the following:

Fix information

  • Fixed component name

    TSM SERVER

  • Fixed component ID

    5698ISMSV

Applicable component levels

  • R63A PSY

       UP

  • R63H PSY

       UP

  • R63L PSY

       UP

  • R63S PSY

       UP

  • R63W PSY

       UP

  • R63Z PSY

       UP



Document information

More support for: Tivoli Storage Manager

Software version: 63W

Reference #: IC94886

Modified date: 06 May 2015