0 Replies Latest reply: May 16, 2012 5:58 AM by 937664 RSS

    OSB is not able append  on the same volume

    937664
      Please help.
      Latest OSB is installed on IBM AIX 6.1 TL7 along with Oracle 11.2.0.3, the backup library is FC(fiber) HP MSL6000 series with 1 SCSI ULTRIUM drive
      OSB is integrated with EM. (Tape library and drive have latests firmware upgraded)
      From Wen inteface it is possible to implement all librrary/tape commands (inventory, load, clean, etc)
      It is prepared several media families and data sets with write window quite big and append enable

      When performing dataset first time the unlabeled volume is succesfuly labeled and backup is performed.
      But when it is perfoirmed the second time, OSB finds appropruiate media family volume (already labeled) but refuses to append on it with error

      Error: this tape is not labeled. You cannot mix labeled and unlabeled backup images

      and waits for another resources (tape, it is possible to unload tape
      from library and load to DTE other one (unlabeled, foreign), the it will be labeled again with new sequence number and backup will be implemented)
      The lack of OSB finctionality to append on volumes makes it unusable, since there are lot of scenariuos/situations when it is needed.
      Thera is not possible to get other backup device (other vendor, model) to check that is because of HW or something is wrong is OSB configuration.
      Can anybody have an idea what is wrong ?, I put debug optiions in operation policies, so below is the transcript of unsuccseful backup


      admin/19.1: 2012/05/16.13:16:06 ______________________________________________________________________
      admin/19.1: 2012/05/16.13:16:06
      admin/19.1: 2012/05/16.13:16:06 Transcript for job admin/19.1 running on hprs1
      admin/19.1: 2012/05/16.13:16:06
      admin/19.1: 2012/05/16.13:16:06 (amh) qdv__automount_in_mh entered
      admin/19.1: 2012/05/16.13:16:06 (amh) qdv__automount_in_mh hprs1_tape1 at 2012/05/16.13:16:06, flags 0x100
      admin/19.1: 2012/05/16.13:16:06 (amh) mount volume options list contains:
      admin/19.1: 2012/05/16.13:16:06 (amh) vtype 3 (app), vid (null), vs_create 0, family HPRS-FILE, retain (null), size 0, mediainfo 2, scratch 0
      admin/19.1: 2012/05/16.13:16:08 (amh) don't preserve previous mh automount state
      admin/19.1: 2012/05/16.13:16:08 (gep) getting reservation for element 0x1 (dte)
      admin/19.1: 2012/05/16.13:16:08 (una) unload_anywhere entered
      admin/19.1: 2012/05/16.13:16:09 (amh) beginning pass 1, mediainfo pass 1
      admin/19.1: 2012/05/16.13:16:09 (gep) getting reservation for element 0x1 (se)
      admin/19.1: 2012/05/16.13:16:09 (amh) 1 vacant
      admin/19.1: 2012/05/16.13:16:09 (gep) getting reservation for element 0x2 (se)
      admin/19.1: 2012/05/16.13:16:09 (amh) 2 no vid
      admin/19.1: 2012/05/16.13:16:09 (gep) getting reservation for element 0x3 (se)
      admin/19.1: 2012/05/16.13:16:09 (mmr) family HPRS-FILE != HIPREGIS_INC
      admin/19.1: 2012/05/16.13:16:09 (mmr) oid 145 (vid HIPREGIS_INC-000001, tag PAA830L2, create_time 1337025595, mediainfo 0) fails mount criteria
      admin/19.1: 2012/05/16.13:16:09 (amh) 3 oid 145 doesn't meet mount requirements - volume doesn't meet mount criteria (OB device mgr)
      admin/19.1: 2012/05/16.13:16:09 (gep) getting reservation for element 0x4 (se)
      admin/19.1: 2012/05/16.13:16:09 (mmr) family HPRS-FILE != RMAN-DEFAULT
      admin/19.1: 2012/05/16.13:16:09 (mmr) oid 146 (vid RMAN-DEFAULT-000004, tag PAA833L2, create_time 1337026560, mediainfo 0) fails mount criteria
      admin/19.1: 2012/05/16.13:16:09 (amh) 4 oid 146 doesn't meet mount requirements - volume doesn't meet mount criteria (OB device mgr)
      admin/19.1: 2012/05/16.13:16:09 (gep) getting reservation for element 0x5 (se)
      admin/19.1: 2012/05/16.13:16:09 (mmr) family HPRS-FILE != HIPREGIS_FULL
      admin/19.1: 2012/05/16.13:16:09 (mmr) oid 147 (vid HIPREGIS_FULL-000003, tag PAA838L2, create_time 1337062459, mediainfo 0) fails mount criteria
      admin/19.1: 2012/05/16.13:16:09 (amh) 5 oid 147 doesn't meet mount requirements - volume doesn't meet mount criteria (OB device mgr)
      admin/19.1: 2012/05/16.13:16:09 (gep) getting reservation for element 0x6 (se)
      admin/19.1: 2012/05/16.13:16:09 (mmr) family HPRS-FILE != HIPREGIS_FULL
      admin/19.1: 2012/05/16.13:16:09 (mmr) oid 148 (vid HIPREGIS_FULL-000004, tag PAA843L2, create_time 1337071895, mediainfo 0) fails mount criteria
      admin/19.1: 2012/05/16.13:16:09 (amh) 6 oid 148 doesn't meet mount requirements - volume doesn't meet mount criteria (OB device mgr)
      admin/19.1: 2012/05/16.13:16:09 (gep) getting reservation for element 0x7 (se)
      admin/19.1: 2012/05/16.13:16:09 (mmr) family HPRS-FILE != HIPREGIS_FULL
      admin/19.1: 2012/05/16.13:16:09 (mmr) oid 149 (vid HIPREGIS_FULL-000005, tag PAA840L2, create_time 1337072850, mediainfo 0) fails mount criteria
      admin/19.1: 2012/05/16.13:16:09 (amh) 7 oid 149 doesn't meet mount requirements - volume doesn't meet mount criteria (OB device mgr)
      admin/19.1: 2012/05/16.13:16:09 (gep) getting reservation for element 0x8 (se)
      admin/19.1: 2012/05/16.13:16:09 (mmr) family HPRS-FILE != HIPREGIS_FULL
      admin/19.1: 2012/05/16.13:16:09 (mmr) oid 151 (vid HIPREGIS_FULL-000006, tag PAA835L2, create_time 1337073405, mediainfo 0) fails mount criteria
      admin/19.1: 2012/05/16.13:16:09 (amh) 8 oid 151 doesn't meet mount requirements - volume doesn't meet mount criteria (OB device mgr)
      admin/19.1: 2012/05/16.13:16:09 (gep) getting reservation for element 0x9 (se)
      admin/19.1: 2012/05/16.13:16:09 (mmr) oid 164 (vid HPRS-FILE-000004, tag PAA839L2, create_time 1337162205, mediainfo 0) passes criteria
      admin/19.1: 2012/05/16.13:16:09 (amh) 9 loading
      admin/19.1: 2012/05/16.13:16:38 (atv) qdv__automount_this_vol entered
      admin/19.1: 2012/05/16.13:16:38 (atv) calling qdv__mount
      admin/19.1: 2012/05/16.13:16:38 (mt) qdv__read_mount_db() succeeded, found vol_oid 0
      admin/19.1: 2012/05/16.13:16:45 (mt) qdv__read_label() succeeded; read 65536 bytes
      admin/19.1: 2012/05/16.13:16:45 (mt) exp time obtained from label
      admin/19.1: 2012/05/16.13:16:45 (mt) qdb__label_event() returned vol_oid 164
      admin/19.1: 2012/05/16.13:16:45 (mt) setting vol_oid in mount_info to 164
      admin/19.1: 2012/05/16.13:16:45 (mt) updated volume close time from db
      admin/19.1: 2012/05/16.13:16:46 (atv) qdv__mount succeeded
      admin/19.1: 2012/05/16.13:16:46 (atv) automount worked
      admin/19.1: 2012/05/16.13:16:46 (atv) qdv__automount_this_vol exited
      admin/19.1: 2012/05/16.13:16:46 (gep) getting reservation for element 0x1 (dte)
      admin/19.1: 2012/05/16.13:16:46 (amh) 9 automount worked - returning
      admin/19.1: 2012/05/16.13:16:46 (amh) end of automount at 2012/05/16.13:16:46 (0x0)
      admin/19.1: 2012/05/16.13:16:46 (amh) returning from qdv__automount_in_mh
      admin/19.1: 2012/05/16.13:16:46 Info: volume in hprs1_tape1 is usable for this operation.
      admin/19.1: 2012/05/16.13:16:46 (pvfw) at BOT
      admin/19.1: 2012/05/16.13:16:46 (pvfw) previous state is invalid
      admin/19.1: 2012/05/16.13:16:46 (alv) backup image label is valid, file 1, section 1
      admin/19.1: 2012/05/16.13:16:46 (pvfw) invalidating tape position in mount db
      admin/19.1: 2012/05/16.13:16:46 (ial) invalidate backup image label (was valid)
      admin/19.1: 2012/05/16.13:16:57 (pvfw) space to EOD
      admin/19.1: 2012/05/16.13:16:57 (pvfw) inspect_recs BSR: rtypes [0] = filemark
      admin/19.1: 2012/05/16.13:16:57 (pvfw) inspect_recs BSR: rtypes [1] = filemark
      admin/19.1: 2012/05/16.13:16:57 (pvfw) inspect_recs BSR: rtypes [2] = data
      admin/19.1: 2012/05/16.13:16:57 (pvfw) inspect_recs BSR: rtypes [3] = filemark
      admin/19.1: 2012/05/16.13:16:57 (pvfw) inspect_recs FSF
      admin/19.1: 2012/05/16.13:16:57 (pvfw) inspect_recs ready to mount
      admin/19.1: 2012/05/16.13:16:57 (pvfw) mounting at inspect_rec's request in rw_mode 2
      admin/19.1: 2012/05/16.13:17:03 (pvfw) mounted ok
      admin/19.1: 2012/05/16.13:17:03 (pvfw) at OB EOD, returning (2)
      admin/19.1: 2012/05/16.13:17:03 (pvfw) pos_vol_cleanup not returning pstate
      admin/19.1: 13:17:04 OBTR: obtar version 10.4.0.1.0 (AIX) -- Fri Sep 23 23:54:04 PDT 2011
      admin/19.1: Copyright (c) 1992, 2011, Oracle. All rights reserved.
      admin/19.1: 13:17:04 OBTR: obtar -Xjob:admin/19.1 -Xob:10.4 -Xstat -Xbga:admin/19.1 -y /u02/tmp/admin@19.1.obstat -g /u02/tmp/admin@19.1.obbdf -JJJJv -F2 -f hprs1_tape1 -Xrescookie:0xCDD1A02F -H hprs1 -L 0 -zR
      admin/19.1: 13:17:04 OBTR: running as root/system
      admin/19.1: 13:17:04 OBTR: record storage set to internal memory
      admin/19.1: 13:17:04 ATAL: reserved drive hprs1_tape1, cookie 0xCDD1A02F
      admin/19.1: 13:17:04 OBTR: obsd=1, is_job=1, is_priv=0, os=5
      admin/19.1: 13:17:04 OBTR: rights established for user admin, class admin
      admin/19.1: 13:17:04 SUUI: user info root/root, ??/??
      admin/19.1: 13:17:04 PBDF: +/u02/app/oracle/imp
      admin/19.1: 13:17:04 PBDF: !core
      admin/19.1: 13:17:04 PBDF: !*~
      admin/19.1: 13:17:06 MAIN: using blocking factor 128 from media defaults/policies
      admin/19.1: 13:17:06 STTY: background terminal I/O or is a tty
      admin/19.1: 13:17:06 MAIN: interactive
      admin/19.1: 13:17:06 SET: volume has no expiration time
      admin/19.1: 13:17:08 CNPC: data host reports this butype_info:
      admin/19.1: 13:17:08 CNPC: tar (attr 0x2C78: B_DIRECT, R_DIRECT, B_INCR, R_INCR, B_FH_DIR)
      admin/19.1: 13:17:08 CNPC: DIRECT = y
      admin/19.1: 13:17:08 CNPC: HISTORY = y
      admin/19.1: 13:17:08 CNPC: LEVEL = 0
      admin/19.1: 13:17:10 DOLM: nop (for hprs1_tape1 (raw device "/dev/obt0"))
      admin/19.1: 13:17:10 DOLM: ok
      admin/19.1: 13:17:12 RLE: connecting to volume/archive database host
      admin/19.1: 13:17:13 RLE: device hprs1_tape1 (raw device "/dev/obt0")
      admin/19.1: 13:17:13 RLE: mount_info is valid
      admin/19.1: 13:17:13 RLE: qdb__device_spec_se reports vol_oid 0, arch_oid 0
      admin/19.1: 13:17:13 A_O: using max blocking factor 4096 from device object
      admin/19.1: 13:17:13 A_O: resetting read and write error counters to 0
      admin/19.1: 13:17:13 A_O: tape device is local
      admin/19.1: 13:17:13 A_O: Devname: HP,Ultrium 2-SCSI,F6AW
      admin/19.1: 13:17:13 Info version: 11
      admin/19.1: 13:17:13 WS version: 10.4
      admin/19.1: 13:17:13 Driver version: 10.4
      admin/19.1: 13:17:13 Max DMA: 2097152
      admin/19.1: 13:17:13 Blocksize in use: 65536
      admin/19.1: 13:17:13 Query frequency: 134217728
      admin/19.1: 13:17:13 Rewind on close: false
      admin/19.1: 13:17:13 Can compress: true
      admin/19.1: 13:17:13 Compression enabled: true
      admin/19.1: 13:17:13 Device supports encryption: false
      admin/19.1: 13:17:13 8200 media: false
      admin/19.1: 13:17:13 Error threshold: 8%
      admin/19.1: 13:17:13 Remaining tape: 205015040
      admin/19.1:
      admin/19.1: 13:17:13 A_GB: ar_block at 0x11064C000, size=2097152
      admin/19.1: 13:17:13 A_GB: ar_block_enc at 0x11084E000, size=2097152
      admin/19.1: 13:17:13 ADMS: reset library tape selection state
      admin/19.1: 13:17:13 ADMS: reset complete
      admin/19.1: 13:17:13 VLBR: not at bot: 0x90000000
      admin/19.1: Warning: read of 2097152 (0x200000) bytes failed - I/O error (NDMP client)
      admin/19.1: Warning: (device code/resid/checks = 0x20002607/0x0/0x0)
      admin/19.1: Warning: read of 2097152 (0x200000) bytes failed - I/O error (NDMP client)
      admin/19.1: Warning: (device code/resid/checks = 0x20002607/0x0/0x0)
      admin/19.1: 13:17:13 RLE: noticed nil label
      admin/19.1: 13:17:13 RLE: qdb__noticed_se reports vol_oid 0, arch_oid 0
      admin/19.1: 13:17:22 ACFD: positioning (SCSI LOCATE) is available for this device
      admin/19.1: 13:17:22 GLMT: returning "PAA839L2", code = 0x0
      admin/19.1: 13:17:22 VLBR: from chk_lm_tag: "PAA839L2", code = 0x0
      admin/19.1: 13:17:22 VLBR: master tag set to "PAA839L2"
      admin/19.1: Warning: read of 2097152 (0x200000) bytes failed - I/O error (NDMP client)
      admin/19.1: Warning: (device code/resid/checks = 0x20002607/0x0/0x0)
      admin/19.1: 13:17:25 BT: resid is 1
      admin/19.1: Warning: read of 2097152 (0x200000) bytes failed - I/O error (NDMP client)
      admin/19.1: Warning: (device code/resid/checks = 0x20002607/0x0/0x0)
      admin/19.1: 13:17:25 RLE: noticed nil label
      admin/19.1: 13:17:25 RLE: qdb__noticed_se reports vol_oid 0, arch_oid 0
      admin/19.1: 13:17:25 PF: no label found
      admin/19.1: Error: this tape is not labeled. You cannot mix labeled and unlabeled backup images
      admin/19.1: on the same volume set
      admin/19.1: 13:17:25 QREX: exit status upon entry is 53
      admin/19.1: 13:17:25 RBTR: trouble reporting time used: handle not open (OB library mgr)
      admin/19.1: 13:17:25 QREX: released reservation on tape drive hprs1_tape1
      admin/19.1: 13:17:25 QREX: [17170488] connecting to hprs1 to import and/or delete ascii index file for client hprs1
      admin/19.1:
      admin/19.1: Backup statistics:
      admin/19.1: status 53
      admin/19.1: devices hprs1_tape1
      admin/19.1: devices 1
      admin/19.1: volumes <none>
      admin/19.1: voltags <none>
      admin/19.1: file 2
      admin/19.1: host hprs1
      admin/19.1: encryption off
      admin/19.1: start_time Wed May 16 2012 at 13:17:04 (1337163424)
      admin/19.1: end_time Wed May 16 2012 at 13:17:25 (1337163445)
      admin/19.1: backup_time Wed May 16 2012 at 13:17:04 (1337163424)
      admin/19.1: entries_scanned 0
      admin/19.1: kbytes_scanned 0
      admin/19.1: entries_excluded 0
      admin/19.1: entries_skipped 0
      admin/19.1: mount_points_skipped 0
      admin/19.1: files 0
      admin/19.1: directories 0
      admin/19.1: hardlinks 0
      admin/19.1: symlinks 0
      admin/19.1: sparse_files 0
      admin/19.1: filesys_errors 0
      admin/19.1: unknown_type 0
      admin/19.1: file_kbytes 0
      admin/19.1: dev_kbytes 0
      admin/19.1: dev_iosecs 0
      admin/19.1: dev_iorate 1.0 KB/S
      admin/19.1: wrt_iosecs 21
      admin/19.1: wrt_iorate 48.8 B/S
      admin/19.1: physical_blks_written 0
      admin/19.1: write_errors 0
      admin/19.1: physical_blks_read 0
      admin/19.1: read_errors 0
      admin/19.1: error_rate 0%
      admin/19.1: path /u02/app/oracle/imp not started
      admin/19.1: 13:17:25 RLYX: exit status 53; checking allocs...
      admin/19.1: 13:17:25 RLYX: from mm__check_all: 1
      admin/19.1: 2012/05/16.13:20:06 ______________________________________________________________________
      admin/19.1: 2012/05/16.13:20:06
      admin/19.1: 2012/05/16.13:20:06 Transcript for job admin/19.1 running on hprs1
      admin/19.1: 2012/05/16.13:20:06
      admin/19.1: 2012/05/16.13:20:17 Info: mount data verified.
      admin/19.1: 2012/05/16.13:20:17 Info: volume in hprs1_tape1 is usable for this operation.
      admin/19.1: 2012/05/16.13:20:17 (pvfw) at BOT
      admin/19.1: 2012/05/16.13:20:17 (pvfw) previous state is invalid
      admin/19.1: 2012/05/16.13:20:17 (alv) backup image label is valid, file 1, section 1
      admin/19.1: 2012/05/16.13:20:17 (pvfw) invalidating tape position in mount db
      admin/19.1: 2012/05/16.13:20:17 (ial) invalidate backup image label (was valid)
      admin/19.1: 2012/05/16.13:20:27 (pvfw) space to EOD
      admin/19.1: 2012/05/16.13:20:27 (pvfw) inspect_recs BSR: rtypes [0] = filemark
      admin/19.1: 2012/05/16.13:20:27 (pvfw) inspect_recs BSR: rtypes [1] = filemark
      admin/19.1: 2012/05/16.13:20:27 (pvfw) inspect_recs BSR: rtypes [2] = data
      admin/19.1: 2012/05/16.13:20:27 (pvfw) inspect_recs BSR: rtypes [3] = filemark
      admin/19.1: 2012/05/16.13:20:27 (pvfw) inspect_recs FSF
      admin/19.1: 2012/05/16.13:20:27 (pvfw) inspect_recs ready to mount
      admin/19.1: 2012/05/16.13:20:27 (pvfw) mounting at inspect_rec's request in rw_mode 2
      admin/19.1: 2012/05/16.13:20:33 (pvfw) mounted ok
      admin/19.1: 2012/05/16.13:20:33 (pvfw) at OB EOD, returning (2)
      admin/19.1: 2012/05/16.13:20:33 (pvfw) pos_vol_cleanup not returning pstate
      admin/19.1: 13:20:34 OBTR: obtar version 10.4.0.1.0 (AIX) -- Fri Sep 23 23:54:04 PDT 2011
      admin/19.1: Copyright (c) 1992, 2011, Oracle. All rights reserved.
      admin/19.1: 13:20:34 OBTR: obtar -Xjob:admin/19.1 -Xob:10.4 -Xstat -Xbga:admin/19.1 -y /u02/tmp/admin@19.1.obstat -g /u02/tmp/admin@19.1.obbdf -JJJJv -F2 -f hprs1_tape1 -Xrescookie:0xCDD1A036 -H hprs1 -L 0 -zR
      admin/19.1: 13:20:34 OBTR: running as root/system
      admin/19.1: 13:20:34 OBTR: record storage set to internal memory
      admin/19.1: 13:20:34 ATAL: reserved drive hprs1_tape1, cookie 0xCDD1A036
      admin/19.1: 13:20:34 OBTR: obsd=1, is_job=1, is_priv=0, os=5
      admin/19.1: 13:20:34 OBTR: rights established for user admin, class admin
      admin/19.1: 13:20:34 SUUI: user info root/root, ??/??
      admin/19.1: 13:20:34 PBDF: +/u02/app/oracle/imp
      admin/19.1: 13:20:34 PBDF: !core
      admin/19.1: 13:20:34 PBDF: !*~
      admin/19.1: 13:20:35 MAIN: using blocking factor 128 from media defaults/policies
      admin/19.1: 13:20:35 STTY: background terminal I/O or is a tty
      admin/19.1: 13:20:35 MAIN: interactive
      admin/19.1: 13:20:35 SET: volume has no expiration time
      admin/19.1: 13:20:37 CNPC: data host reports this butype_info:
      admin/19.1: 13:20:37 CNPC: tar (attr 0x2C78: B_DIRECT, R_DIRECT, B_INCR, R_INCR, B_FH_DIR)
      admin/19.1: 13:20:37 CNPC: DIRECT = y
      admin/19.1: 13:20:37 CNPC: HISTORY = y
      admin/19.1: 13:20:37 CNPC: LEVEL = 0
      admin/19.1: 13:20:39 DOLM: nop (for hprs1_tape1 (raw device "/dev/obt0"))
      admin/19.1: 13:20:39 DOLM: ok
      admin/19.1: 13:20:42 RLE: connecting to volume/archive database host
      admin/19.1: 13:20:43 RLE: device hprs1_tape1 (raw device "/dev/obt0")
      admin/19.1: 13:20:43 RLE: mount_info is valid
      admin/19.1: 13:20:43 RLE: qdb__device_spec_se reports vol_oid 0, arch_oid 0
      admin/19.1: 13:20:43 A_O: using max blocking factor 4096 from device object
      admin/19.1: 13:20:43 A_O: resetting read and write error counters to 0
      admin/19.1: 13:20:43 A_O: tape device is local
      admin/19.1: 13:20:43 A_O: Devname: HP,Ultrium 2-SCSI,F6AW
      admin/19.1: 13:20:43 Info version: 11
      admin/19.1: 13:20:43 WS version: 10.4
      admin/19.1: 13:20:43 Driver version: 10.4
      admin/19.1: 13:20:43 Max DMA: 2097152
      admin/19.1: 13:20:43 Blocksize in use: 65536
      admin/19.1: 13:20:43 Query frequency: 134217728
      admin/19.1: 13:20:43 Rewind on close: false
      admin/19.1: 13:20:43 Can compress: true
      admin/19.1: 13:20:43 Compression enabled: true
      admin/19.1: 13:20:43 Device supports encryption: false
      admin/19.1: 13:20:43 8200 media: false
      admin/19.1: 13:20:43 Error threshold: 8%
      admin/19.1: 13:20:43 Remaining tape: 205015040
      admin/19.1:
      admin/19.1: 13:20:43 A_GB: ar_block at 0x11064C000, size=2097152
      admin/19.1: 13:20:43 A_GB: ar_block_enc at 0x11084E000, size=2097152
      admin/19.1: 13:20:43 ADMS: reset library tape selection state
      admin/19.1: 13:20:43 ADMS: reset complete
      admin/19.1: 13:20:43 VLBR: not at bot: 0x90000000
      admin/19.1: Warning: read of 2097152 (0x200000) bytes failed - I/O error (NDMP client)
      admin/19.1: Warning: (device code/resid/checks = 0x20002607/0x0/0x0)
      admin/19.1: Warning: read of 2097152 (0x200000) bytes failed - I/O error (NDMP client)
      admin/19.1: Warning: (device code/resid/checks = 0x20002607/0x0/0x0)
      admin/19.1: 13:20:43 RLE: noticed nil label
      admin/19.1: 13:20:43 RLE: qdb__noticed_se reports vol_oid 0, arch_oid 0
      admin/19.1: 13:20:52 ACFD: positioning (SCSI LOCATE) is available for this device
      admin/19.1: 13:20:52 GLMT: returning "PAA839L2", code = 0x0
      admin/19.1: 13:20:52 VLBR: from chk_lm_tag: "PAA839L2", code = 0x0
      admin/19.1: 13:20:52 VLBR: master tag set to "PAA839L2"
      admin/19.1: Warning: read of 2097152 (0x200000) bytes failed - I/O error (NDMP client)
      admin/19.1: Warning: (device code/resid/checks = 0x20002607/0x0/0x0)
      admin/19.1: 13:20:55 BT: resid is 1
      admin/19.1: Warning: read of 2097152 (0x200000) bytes failed - I/O error (NDMP client)
      admin/19.1: Warning: (device code/resid/checks = 0x20002607/0x0/0x0)
      admin/19.1: 13:20:55 RLE: noticed nil label
      admin/19.1: 13:20:55 RLE: qdb__noticed_se reports vol_oid 0, arch_oid 0
      admin/19.1: 13:20:55 PF: no label found
      admin/19.1: Error: this tape is not labeled. You cannot mix labeled and unlabeled backup images
      admin/19.1: on the same volume set
      admin/19.1: 13:20:55 QREX: exit status upon entry is 53
      admin/19.1: 13:20:55 RBTR: trouble reporting time used: handle not open (OB library mgr)
      admin/19.1: 13:20:55 QREX: released reservation on tape drive hprs1_tape1
      admin/19.1: 13:20:55 QREX: [14811562] connecting to hprs1 to import and/or delete ascii index file for client hprs1
      admin/19.1:
      admin/19.1: Backup statistics:
      admin/19.1: status 53
      admin/19.1: devices hprs1_tape1
      admin/19.1: devices 1
      admin/19.1: volumes <none>
      admin/19.1: voltags <none>
      admin/19.1: file 2
      admin/19.1: host hprs1
      admin/19.1: encryption off
      admin/19.1: start_time Wed May 16 2012 at 13:20:34 (1337163634)
      admin/19.1: end_time Wed May 16 2012 at 13:20:56 (1337163656)
      admin/19.1: backup_time Wed May 16 2012 at 13:20:34 (1337163634)
      admin/19.1: entries_scanned 0
      admin/19.1: kbytes_scanned 0
      admin/19.1: entries_excluded 0
      admin/19.1: entries_skipped 0
      admin/19.1: mount_points_skipped 0
      admin/19.1: files 0
      admin/19.1: directories 0
      admin/19.1: hardlinks 0
      admin/19.1: symlinks 0
      admin/19.1: sparse_files 0
      admin/19.1: filesys_errors 0
      admin/19.1: unknown_type 0
      admin/19.1: file_kbytes 0
      admin/19.1: dev_kbytes 0
      admin/19.1: dev_iosecs 0
      admin/19.1: dev_iorate 1.0 KB/S
      admin/19.1: wrt_iosecs 22
      admin/19.1: wrt_iorate 46.5 B/S
      admin/19.1: physical_blks_written 0
      admin/19.1: write_errors 0
      admin/19.1: physical_blks_read 0
      admin/19.1: read_errors 0
      admin/19.1: error_rate 0%
      admin/19.1: path /u02/app/oracle/imp not started
      admin/19.1: 13:20:56 RLYX: exit status 53; checking allocs...
      admin/19.1: 13:20:56 RLYX: from mm__check_all: 1
      admin/19.1: 2012/05/16.13:23:06 ______________________________________________________________________
      admin/19.1: 2012/05/16.13:23:06
      admin/19.1: 2012/05/16.13:23:06 Transcript for job admin/19.1 running on hprs1
      admin/19.1: 2012/05/16.13:23:06
      admin/19.1: 2012/05/16.13:23:16 Info: mount data verified.
      admin/19.1: 2012/05/16.13:23:16 Info: volume in hprs1_tape1 is usable for this operation.
      admin/19.1: 2012/05/16.13:23:16 (pvfw) at BOT
      admin/19.1: 2012/05/16.13:23:16 (pvfw) previous state is invalid
      admin/19.1: 2012/05/16.13:23:16 (alv) backup image label is valid, file 1, section 1
      admin/19.1: 2012/05/16.13:23:16 (pvfw) invalidating tape position in mount db
      admin/19.1: 2012/05/16.13:23:16 (ial) invalidate backup image label (was valid)
      admin/19.1: 2012/05/16.13:23:27 (pvfw) space to EOD
      admin/19.1: 2012/05/16.13:23:27 (pvfw) inspect_recs BSR: rtypes [0] = filemark
      admin/19.1: 2012/05/16.13:23:27 (pvfw) inspect_recs BSR: rtypes [1] = filemark
      admin/19.1: 2012/05/16.13:23:27 (pvfw) inspect_recs BSR: rtypes [2] = data
      admin/19.1: 2012/05/16.13:23:27 (pvfw) inspect_recs BSR: rtypes [3] = filemark
      admin/19.1: 2012/05/16.13:23:27 (pvfw) inspect_recs FSF
      admin/19.1: 2012/05/16.13:23:27 (pvfw) inspect_recs ready to mount
      admin/19.1: 2012/05/16.13:23:27 (pvfw) mounting at inspect_rec's request in rw_mode 2
      admin/19.1: 2012/05/16.13:23:33 (pvfw) mounted ok
      admin/19.1: 2012/05/16.13:23:33 (pvfw) at OB EOD, returning (2)
      admin/19.1: 2012/05/16.13:23:33 (pvfw) pos_vol_cleanup not returning pstate
      admin/19.1: 13:23:33 OBTR: obtar version 10.4.0.1.0 (AIX) -- Fri Sep 23 23:54:04 PDT 2011
      admin/19.1: Copyright (c) 1992, 2011, Oracle. All rights reserved.
      admin/19.1: 13:23:33 OBTR: obtar -Xjob:admin/19.1 -Xob:10.4 -Xstat -Xbga:admin/19.1 -y /u02/tmp/admin@19.1.obstat -g /u02/tmp/admin@19.1.obbdf -JJJJv -F2 -f hprs1_tape1 -Xrescookie:0xCDD1A03C -H hprs1 -L 0 -zR
      admin/19.1: 13:23:33 OBTR: running as root/system
      admin/19.1: 13:23:33 OBTR: record storage set to internal memory
      admin/19.1: 13:23:33 ATAL: reserved drive hprs1_tape1, cookie 0xCDD1A03C
      admin/19.1: 13:23:34 OBTR: obsd=1, is_job=1, is_priv=0, os=5
      admin/19.1: 13:23:34 OBTR: rights established for user admin, class admin
      admin/19.1: 13:23:34 SUUI: user info root/root, ??/??
      admin/19.1: 13:23:34 PBDF: +/u02/app/oracle/imp
      admin/19.1: 13:23:34 PBDF: !core
      admin/19.1: 13:23:34 PBDF: !*~
      admin/19.1: 13:23:35 MAIN: using blocking factor 128 from media defaults/policies
      admin/19.1: 13:23:35 STTY: background terminal I/O or is a tty
      admin/19.1: 13:23:35 MAIN: interactive
      admin/19.1: 13:23:35 SET: volume has no expiration time
      admin/19.1: 13:23:37 CNPC: data host reports this butype_info:
      admin/19.1: 13:23:37 CNPC: tar (attr 0x2C78: B_DIRECT, R_DIRECT, B_INCR, R_INCR, B_FH_DIR)
      admin/19.1: 13:23:37 CNPC: DIRECT = y
      admin/19.1: 13:23:37 CNPC: HISTORY = y
      admin/19.1: 13:23:37 CNPC: LEVEL = 0
      admin/19.1: 13:23:39 DOLM: nop (for hprs1_tape1 (raw device "/dev/obt0"))
      admin/19.1: 13:23:39 DOLM: ok
      admin/19.1: 13:23:41 RLE: connecting to volume/archive database host
      admin/19.1: 13:23:42 RLE: device hprs1_tape1 (raw device "/dev/obt0")
      admin/19.1: 13:23:42 RLE: mount_info is valid
      admin/19.1: 13:23:42 RLE: qdb__device_spec_se reports vol_oid 0, arch_oid 0
      admin/19.1: 13:23:42 A_O: using max blocking factor 4096 from device object
      admin/19.1: 13:23:42 A_O: resetting read and write error counters to 0
      admin/19.1: 13:23:42 A_O: tape device is local
      admin/19.1: 13:23:42 A_O: Devname: HP,Ultrium 2-SCSI,F6AW
      admin/19.1: 13:23:42 Info version: 11
      admin/19.1: 13:23:42 WS version: 10.4
      admin/19.1: 13:23:42 Driver version: 10.4
      admin/19.1: 13:23:42 Max DMA: 2097152
      admin/19.1: 13:23:42 Blocksize in use: 65536
      admin/19.1: 13:23:42 Query frequency: 134217728
      admin/19.1: 13:23:42 Rewind on close: false
      admin/19.1: 13:23:42 Can compress: true
      admin/19.1: 13:23:42 Compression enabled: true
      admin/19.1: 13:23:42 Device supports encryption: false
      admin/19.1: 13:23:42 8200 media: false
      admin/19.1: 13:23:42 Error threshold: 8%
      admin/19.1: 13:23:42 Remaining tape: 205015040
      admin/19.1:
      admin/19.1: 13:23:42 A_GB: ar_block at 0x11064C000, size=2097152
      admin/19.1: 13:23:42 A_GB: ar_block_enc at 0x11084E000, size=2097152
      admin/19.1: 13:23:42 ADMS: reset library tape selection state
      admin/19.1: 13:23:42 ADMS: reset complete
      admin/19.1: 13:23:42 VLBR: not at bot: 0x90000000
      admin/19.1: Warning: read of 2097152 (0x200000) bytes failed - I/O error (NDMP client)
      admin/19.1: Warning: (device code/resid/checks = 0x20002607/0x0/0x0)
      admin/19.1: Warning: read of 2097152 (0x200000) bytes failed - I/O error (NDMP client)
      admin/19.1: Warning: (device code/resid/checks = 0x20002607/0x0/0x0)
      admin/19.1: 13:23:42 RLE: noticed nil label
      admin/19.1: 13:23:42 RLE: qdb__noticed_se reports vol_oid 0, arch_oid 0
      admin/19.1: 13:23:51 ACFD: positioning (SCSI LOCATE) is available for this device
      admin/19.1: 13:23:51 GLMT: returning "PAA839L2", code = 0x0
      admin/19.1: 13:23:51 VLBR: from chk_lm_tag: "PAA839L2", code = 0x0
      admin/19.1: 13:23:51 VLBR: master tag set to "PAA839L2"
      admin/19.1: Warning: read of 2097152 (0x200000) bytes failed - I/O error (NDMP client)
      admin/19.1: Warning: (device code/resid/checks = 0x20002607/0x0/0x0)
      admin/19.1: 13:23:54 BT: resid is 1
      admin/19.1: Warning: read of 2097152 (0x200000) bytes failed - I/O error (NDMP client)
      admin/19.1: Warning: (device code/resid/checks = 0x20002607/0x0/0x0)
      admin/19.1: 13:23:54 RLE: noticed nil label
      admin/19.1: 13:23:54 RLE: qdb__noticed_se reports vol_oid 0, arch_oid 0
      admin/19.1: 13:23:54 PF: no label found
      admin/19.1: Error: this tape is not labeled. You cannot mix labeled and unlabeled backup images
      admin/19.1: on the same volume set
      admin/19.1: 13:23:54 QREX: exit status upon entry is 53
      admin/19.1: 13:23:54 RBTR: trouble reporting time used: handle not open (OB library mgr)
      admin/19.1: 13:23:54 QREX: released reservation on tape drive hprs1_tape1
      admin/19.1: 13:23:54 QREX: [7536928] connecting to hprs1 to import and/or delete ascii index file for client hprs1
      admin/19.1:
      admin/19.1: Backup statistics:
      admin/19.1: status 53
      admin/19.1: devices hprs1_tape1
      admin/19.1: devices 1
      admin/19.1: volumes <none>
      admin/19.1: voltags <none>
      admin/19.1: file 2
      admin/19.1: host hprs1
      admin/19.1: encryption off
      admin/19.1: start_time Wed May 16 2012 at 13:23:33 (1337163813)
      admin/19.1: end_time Wed May 16 2012 at 13:23:54 (1337163834)
      admin/19.1: backup_time Wed May 16 2012 at 13:23:33 (1337163813)
      admin/19.1: entries_scanned 0
      admin/19.1: kbytes_scanned 0
      admin/19.1: entries_excluded 0
      admin/19.1: entries_skipped 0
      admin/19.1: mount_points_skipped 0
      admin/19.1: files 0
      admin/19.1: directories 0
      admin/19.1: hardlinks 0
      admin/19.1: symlinks 0
      admin/19.1: sparse_files 0
      admin/19.1: filesys_errors 0
      admin/19.1: unknown_type 0
      admin/19.1: file_kbytes 0
      admin/19.1: dev_kbytes 0
      admin/19.1: dev_iosecs 0
      admin/19.1: dev_iorate 1.0 KB/S
      admin/19.1: wrt_iosecs 21
      admin/19.1: wrt_iorate 48.8 B/S
      admin/19.1: physical_blks_written 0
      admin/19.1: write_errors 0
      admin/19.1: physical_blks_read 0
      admin/19.1: read_errors 0
      admin/19.1: error_rate 0%
      admin/19.1: path /u02/app/oracle/imp not started
      admin/19.1: 13:23:54 RLYX: exit status 53; checking allocs...
      admin/19.1: 13:23:54 RLYX: from mm__check_all: 1