Crosscheck Performance is Slow for Tape Backups (Doc ID 1500349.1)

Last updated on OCTOBER 27, 2016

Applies to:

Oracle Server - Enterprise Edition - Version 10.2.0.4 and later
Information in this document applies to any platform.

Symptoms

Crosscheck is taking too long.

Debug trace shows we are spending from 17 to 21 seconds validating each backuppiece.

Validation of the backuppiece requires media manager to confirm that backuppiece is present in the media manager catalog:

 

export NLS_DATE_FORMAT='dd-mon-rr hh24:mi:ss
rman target log debug.log trace debug.trc debug
RMAN>crosscheck backup;

debug.trc extract:

DBGPLSQL:    channel a:  processing (file/handle=al_58430_1_778479826,recid=58173, old_status=X, hdl_isdisk=0, devicetype=SBT_TAPE) [13:02:43.990] (change)<====
DBGPLSQL:    channel a:  force: 0 [13:02:43.991] (change)
DBGRPC:      krmxrpc: xc=4569928000 kpurpc2 rc=0 db=target proc=DBMS_BACKUP_RESTORE.VALIDATEBACKUPPIECE
DBGRPC:      krmxrpc: xc=4569928000 chid=a rpc count(non-blocking)=402
DBGRPC:      krmxrpc: xc=4569928000 RPC #402 completed immediately
DBGPLSQL:    channel a: file al_58430_1_778479826, rc=5 [13:03:04.398] (change)  <=====================21 secs
DBGPLSQL:    channel a: file al_58430_1_778479826, Modified rc=4 [13:03:04.398] (change)
DBGPLSQL:    channel a: file al_58430_1_778479826, found=FALSE [13:03:04.398] (change)
DBGPLSQL:    channel a:  processing (file/handle=al_58431_1_778479906,recid=58174, old_status=X, hdl_isdisk=0, devicetype=SBT_TAPE) [13:03:04.399] (change)
DBGPLSQL:    channel a:  force: 0 [13:03:04.399] (change)
DBGRPC:      krmxrpc: xc=4569928000 kpurpc2 rc=0 db=target proc=DBMS_BACKUP_RESTORE.VALIDATEBACKUPPIECE
DBGRPC:      krmxrpc: xc=4569928000 chid=a rpc count(non-blocking)=403
DBGRPC:      krmxrpc: xc=4569928000 RPC #403 completed immediately
DBGPLSQL:    channel a: file al_58431_1_778479906, rc=5 [13:03:21.456] (change) <=====================17 secs
DBGPLSQL:    channel a: file al_58431_1_778479906, Modified rc=4 [13:03:21.456] (change)
DBGPLSQL:    channel a: file al_58431_1_778479906, found=FALSE [13:03:21.456] (change)
DBGPLSQL:    channel a:  processing (file/handle=al_58434_1_778486677,recid=58176, old_status=X, hdl_isdisk=0, devicetype=SBT_TAPE) [13:03:21.456] (change)
DBGPLSQL:    channel a:  force: 0 [13:03:21.456] (change)
DBGRPC:      krmxrpc: xc=4569928000 kpurpc2 rc=0 db=target proc=DBMS_BACKUP_RESTORE.VALIDATEBACKUPPIECE
DBGRPC:      krmxrpc: xc=4569928000 chid=a rpc count(non-blocking)=404
DBGRPC:      krmxrpc: xc=4569928000 RPC #404 completed immediately
DBGPLSQL:    channel a: file al_58434_1_778486677, rc=5 [13:03:38.165] (change) <=====================17 secs
DBGPLSQL:    channel a: file al_58434_1_778486677, Modified rc=4 [13:03:38.165] (change)
DBGPLSQL:    channel a: file al_58434_1_778486677, found=FALSE [13:03:38.165] (change)

 





Cause

Sign In with your My Oracle Support account

Don't have a My Oracle Support account? Click to get started

My Oracle Support provides customers with access to over a
Million Knowledge Articles and hundreds of Community platforms