1 Reply Latest reply: Dec 30, 2012 7:29 AM by rdoogan RSS

    Backups are not being started....volumes do not exist?

    user12619701
      I am having issues where backup jobs are not getting started/completed.

      I can see in the "jobs" report that there are many attempts (sometimes 50+) to run the job, but it never completes.

      I believe the OSB software is looking for a volume that does not exist in the tape library. It's possible that they may have existed at one time (though I'm not sure), but I've run inventory several times and for what ever reason it does not exist anymore. I do not see anything in the "manage->location report ->missing" reports. I've looked in obcleanup and nothing is listed there either.

      Is OSB really looking for a volume that doesn't exist, or is something else going on?


      About Oracle Secure Backup
      Oracle Secure Backup Release      10.4.0.1.0
      System      SunOS


      Oracle Corporation

      Info:      02:35 Info: volume in HPLTO4_2 is usable for this operation.


           Apply Closed

      Job Transcript Viewer

      2012/12/27.22:00:42 ______________________________________________________________________
      2012/12/27.22:00:42
      2012/12/27.22:00:42 Transcript for job 38721.1 running on cores2-dm-1
      2012/12/27.22:00:42
      2012/12/27.22:00:43 (amh) qdv__automount_in_mh entered
      2012/12/27.22:00:43 (amh) qdv__automount_in_mh HPLTO4_5 at 2012/12/27.22:00:43, flags 0x100
      2012/12/27.22:00:43 (amh) mount volume options list contains:
      2012/12/27.22:00:43 (amh) vtype 3 (app), vid (null), vs_create 0, family cores2-default, retain (null), size 0, mediainfo 2, scratch 0
      2012/12/27.22:00:44 (amh) don't preserve previous mh automount state
      2012/12/27.22:00:45 (gep) getting reservation for element 0x6 (dte)
      2012/12/27.22:00:45 (amh) loaded volume has no barcode
      2012/12/27.22:00:45 (una) unload_anywhere entered
      2012/12/27.22:00:45 (amh) couldn't unload current volume - device reported incorrect serial number (OB tools)
      2012/12/27.22:00:45 (amh) end of automount at 2012/12/27.22:00:45 (0x20009934)
      2012/12/27.22:00:45 (amh) returning from qdv__automount_in_mh
      2012/12/27.22:00:45 ---
      2012/12/27.22:00:45 No volume could be found in SL500 (for drive HPLTO4_5) to use for job 38721.1.
      2012/12/27.22:00:45 The problem using HPLTO4_5 and the volume it contains, if any, is
      2012/12/27.22:00:45 device reported incorrect serial number (OB tools)
      2012/12/27.22:01:42 ______________________________________________________________________
      2012/12/27.22:01:42
      2012/12/27.22:01:42 Transcript for job 38721.1 running on cores2-dm-1
      2012/12/27.22:01:42
      2012/12/27.22:01:42 ***0 wst__set_mode: bus 7, target 500104, lun 0: HP,Ultrium 4-SCSI,H64S
      2012/12/27.22:01:42 ***0 wst__get_sn: code=0x0
      2012/12/27.22:01:42 ***0 wst__info: infolen=0xd8, info=0xffffffff7fffecc8
      2012/12/27.22:01:42 ***0 wst__dev_state...
      2012/12/27.22:01:42 ***0 wst__exec: op=0 (nop), buf=0x0, count=1 (0x1), parm=0x0
      2012/12/27.22:01:42 ioctl_op=0x7703, to=30, datalen=0x0, buf=0x0
      2012/12/27.22:01:42 cdb: 00 00 00 00 00 00 tur
      2012/12/27.22:01:42 ***0 wst__get_sense(int) cmd = 0, sense data:
      2012/12/27.22:01:42 70 00 02 00 00 00 00 10 00 00 00 00 3A 00 00 00
      2012/12/27.22:01:42 94 50 00 00 00 00 00 00
      2012/12/27.22:01:42 ec=0, sk=not ready, asc=3A, ascq=0
      2012/12/27.22:01:42 error is: device offline (OB scsi device driver)
      2012/12/27.22:01:42 flags: (none)
      2012/12/27.22:01:42 ***0 wst__exec: rval=-1, status.code/resid/checks=0x20008116/0x0/0x0
      2012/12/27.22:01:42 ***0 wst__dev_state: state=0x10000000 (offline, not at bot)
      2012/12/27.22:01:42 ***0 wst__encryption_is_tape_encryptable...
      2012/12/27.22:01:42 ***0 wst__encryption_check...
      2012/12/27.22:01:42 ***0 wst__exec: op=17 (raw_op), buf=0x1002418c0, count=44 (0x2c), parm=0xffffffff7fffe488
      2012/12/27.22:01:42 ioctl_op=0x7705, to=30, datalen=0x2c, buf=0x1002418c0
      2012/12/27.22:01:42 cdb: A2 20 00 10 00 00 00 00 00 2C 00 00 security protocol in
      2012/12/27.22:01:42 i/p data:
      2012/12/27.22:01:42 0000: 00 10 00 28 09 00 00 00 00 00 00 00 00 00 00 00 ...(............
      2012/12/27.22:01:42 0010: 00 00 00 00 01 00 00 14 3A 14 00 20 00 0C 00 20 ........:.. ...
      2012/12/27.22:01:42 0020: 09 00 00 00 00 00 00 00 00 01 00 14 ............
      2012/12/27.22:01:42 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:01:42 ***0 wst__encryption_check - have hardware encryption.
      2012/12/27.22:01:42 ***0 wst__encryption_is_tape_encryptable - no
      2012/12/27.22:01:42 ***0 wst__exec: op=6 (modesen), buf=0x100242794, count=12 (0xc), parm=0x0
      2012/12/27.22:01:42 ioctl_op=0x7705, to=30, datalen=0xc, buf=0x100242794
      2012/12/27.22:01:42 cdb: 1A 00 00 00 0C 00 mode_sen, cnt=12, pc=0, pcode=0
      2012/12/27.22:01:42 i/p data:
      2012/12/27.22:01:42 0000: 0B 00 10 08 46 00 00 00 00 00 00 00 ....F.......
      2012/12/27.22:01:42 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:01:42 ***0 wst__exec: op=6 (modesen), buf=0x100242794, count=28 (0x1c), parm=0xf
      2012/12/27.22:01:42 ioctl_op=0x7705, to=30, datalen=0x1c, buf=0x100242794
      2012/12/27.22:01:42 cdb: 1A 00 0F 00 1C 00 mode_sen, cnt=28, pc=0, pcode=f
      2012/12/27.22:01:42 i/p data:
      2012/12/27.22:01:42 0000: 1B 00 10 08 46 00 00 00 00 00 00 00 0F 0E C0 80 ....F...........
      2012/12/27.22:01:42 0010: 00 00 00 01 00 00 00 01 00 00 00 00 ............
      2012/12/27.22:01:42 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:01:42 ***0 wst__exec: op=18 (log_sense), buf=0x100242794, count=76 (0x4c), parm=0x71
      2012/12/27.22:01:42 ioctl_op=0x7705, to=180, datalen=0x4c, buf=0x100242794
      2012/12/27.22:01:42 cdb: 4D 00 71 00 00 00 00 00 4C 00 log_sense, cnt=76, pc=1, page_code=0x31
      2012/12/27.22:01:42 i/p data:
      2012/12/27.22:01:42 0000: 31 00 00 20 00 01 40 04 00 00 00 00 00 02 60 04 1.. ..@.......`.
      2012/12/27.22:01:42 0010: 00 00 00 00 00 03 60 04 00 00 00 00 00 04 60 04 ......`.......`.
      2012/12/27.22:01:42 0020: 00 00 00 00 00 00 00 00 01 00 00 00 00 00 00 00 ................
      2012/12/27.22:01:42 0030: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
      2012/12/27.22:01:42 0040: 00 00 00 00 00 00 00 00 00 00 00 00 ............
      2012/12/27.22:01:42 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:01:42 ***0 wst__info: code=0x0
      2012/12/27.22:01:42 ***0 wst__close: wh=0x0
      2012/12/27.22:01:42 (amh) qdv__automount_in_mh entered
      2012/12/27.22:01:42 (amh) qdv__automount_in_mh HPLTO4_2 at 2012/12/27.22:01:42, flags 0x100
      2012/12/27.22:01:42 (amh) mount volume options list contains:
      2012/12/27.22:01:42 (amh) vtype 3 (app), vid (null), vs_create 0, family cores2-default, retain (null), size 0, mediainfo 2, scratch 0
      2012/12/27.22:01:43 (amh) don't preserve previous mh automount state
      2012/12/27.22:01:44 (gep) getting reservation for element 0x3 (dte)
      2012/12/27.22:01:44 ***0 wst__set_mode: bus 7, target 500104, lun 0: HP,Ultrium 4-SCSI,H64S
      2012/12/27.22:01:44 ***0 wst__get_sn: code=0x0
      2012/12/27.22:01:44 ***0 wst__exec: op=0 (nop), buf=0x0, count=0 (0x0), parm=0x0
      2012/12/27.22:01:44 ioctl_op=0x7703, to=30, datalen=0x0, buf=0x0
      2012/12/27.22:01:44 cdb: 00 00 00 00 00 00 tur
      2012/12/27.22:01:44 ***0 wst__get_sense(int) cmd = 0, sense data:
      2012/12/27.22:01:44 70 00 02 00 00 00 00 10 00 00 00 00 3A 00 00 00
      2012/12/27.22:01:44 94 50 00 00 00 00 00 00
      2012/12/27.22:01:44 ec=0, sk=not ready, asc=3A, ascq=0
      2012/12/27.22:01:44 error is: device offline (OB scsi device driver)
      2012/12/27.22:01:44 flags: (none)
      2012/12/27.22:01:44 ***0 wst__exec: rval=-1, status.code/resid/checks=0x20008116/0x0/0x0
      2012/12/27.22:01:44 ***0 wst__close: wh=0x0
      2012/12/27.22:01:44 (amh) loaded volume has no barcode
      2012/12/27.22:01:44 (una) unload_anywhere entered
      2012/12/27.22:01:44 (amh) beginning pass 1, mediainfo pass 1
      2012/12/27.22:01:44 (gep) getting reservation for element 0x1 (se)
      2012/12/27.22:01:44 (mmr) family cores2-default != OSB-CATALOG-MF
      2012/12/27.22:01:44 (mmr) oid 5406 (vid OSB-CATALOG-MF-000057, tag 100003, create_time 1326110623, mediainfo 4) fails mount criteria
      2012/12/27.22:01:44 (amh) 1 oid 5406 doesn't meet mount requirements - volume doesn't meet mount criteria (OB device mgr)
      2012/12/27.22:01:44 (gep) getting reservation for element 0x2 (se)
      2012/12/27.22:01:44 (amh) 2 vacant
      2012/12/27.22:01:44 (gep) getting reservation for element 0x3 (se)
      2012/12/27.22:01:44 (mmr) oid 7150 (vid VOL001267, tag 100000, create_time 1356321775, mediainfo 4) passes criteria
      2012/12/27.22:01:44 (amh) 3 loading
      2012/12/27.22:02:30 (atv) qdv__automount_this_vol entered
      2012/12/27.22:02:30 ***0 wst__set_mode: bus 7, target 500104, lun 0: HP,Ultrium 4-SCSI,H64S
      2012/12/27.22:02:30 ***0 wst__get_sn: code=0x0
      2012/12/27.22:02:30 ***0 wst__exec: op=0 (nop), buf=0x0, count=0 (0x0), parm=0x0
      2012/12/27.22:02:30 ioctl_op=0x7703, to=30, datalen=0x0, buf=0x0
      2012/12/27.22:02:30 cdb: 00 00 00 00 00 00 tur
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:02:30 ***0 wst__dev_state...
      2012/12/27.22:02:30 ***0 wst__exec: op=0 (nop), buf=0x0, count=1 (0x1), parm=0x0
      2012/12/27.22:02:30 ioctl_op=0x7703, to=30, datalen=0x0, buf=0x0
      2012/12/27.22:02:30 cdb: 00 00 00 00 00 00 tur
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:02:30 ***0 wst__dev_state: no sense, status 0x0
      2012/12/27.22:02:30 ***0 wst__exec: op=2 (sense), buf=0x1002606d4, count=24 (0x18), parm=0x0
      2012/12/27.22:02:30 ioctl_op=0x7705, to=30, datalen=0x18, buf=0x1002606d4
      2012/12/27.22:02:30 cdb: 03 00 00 00 18 00 sense, cnt=24
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:02:30 ***0 wst__get_sense(int) cmd = 0, sense data:
      2012/12/27.22:02:30 70 00 00 00 00 00 00 10 00 00 00 00 00 00 00 00
      2012/12/27.22:02:30 00 00 00 00 00 00 00 00
      2012/12/27.22:02:30 ec=0, sk=no sense, asc=0, ascq=0
      2012/12/27.22:02:30 flags: (none)
      2012/12/27.22:02:30 ***0 wst__exec: op=7 (readpos), buf=0xffffffff7fffdd60, count=20 (0x14), parm=0x0
      2012/12/27.22:02:30 ioctl_op=0x7705, to=180, datalen=0x14, buf=0x1002607d8
      2012/12/27.22:02:30 cdb: 34 00 00 00 00 00 00 00 00 00 read_pos
      2012/12/27.22:02:30 raw position:
      2012/12/27.22:02:30 0000: F0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
      2012/12/27.22:02:30 0010: 00 00 00 00 ....
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x10/0x0
      2012/12/27.22:02:30 ***0 wst__dev_state: state=0xd0000000 (online, at bot)
      2012/12/27.22:02:30 ***0 wst__info: infolen=0xd8, info=0xffffffff7fffe108
      2012/12/27.22:02:30 ***0 wst__dev_state...
      2012/12/27.22:02:30 ***0 wst__exec: op=0 (nop), buf=0x0, count=1 (0x1), parm=0x0
      2012/12/27.22:02:30 ioctl_op=0x7703, to=30, datalen=0x0, buf=0x0
      2012/12/27.22:02:30 cdb: 00 00 00 00 00 00 tur
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:02:30 ***0 wst__dev_state: no sense, status 0x0
      2012/12/27.22:02:30 ***0 wst__exec: op=2 (sense), buf=0x1002606d4, count=24 (0x18), parm=0x0
      2012/12/27.22:02:30 ioctl_op=0x7705, to=30, datalen=0x18, buf=0x1002606d4
      2012/12/27.22:02:30 cdb: 03 00 00 00 18 00 sense, cnt=24
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:02:30 ***0 wst__get_sense(int) cmd = 0, sense data:
      2012/12/27.22:02:30 70 00 00 00 00 00 00 10 00 00 00 00 00 00 00 00
      2012/12/27.22:02:30 00 00 00 00 00 00 00 00
      2012/12/27.22:02:30 ec=0, sk=no sense, asc=0, ascq=0
      2012/12/27.22:02:30 flags: (none)
      2012/12/27.22:02:30 ***0 wst__exec: op=7 (readpos), buf=0xffffffff7fffdbb0, count=20 (0x14), parm=0x0
      2012/12/27.22:02:30 ioctl_op=0x7705, to=180, datalen=0x14, buf=0x1002607d8
      2012/12/27.22:02:30 cdb: 34 00 00 00 00 00 00 00 00 00 read_pos
      2012/12/27.22:02:30 raw position:
      2012/12/27.22:02:30 0000: F0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
      2012/12/27.22:02:30 0010: 00 00 00 00 ....
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x10/0x0
      2012/12/27.22:02:30 ***0 wst__dev_state: state=0xd0000000 (online, at bot)
      2012/12/27.22:02:30 ***0 wst__encryption_is_tape_encryptable...
      2012/12/27.22:02:30 ***0 wst__encryption_check...
      2012/12/27.22:02:30 ***0 wst__exec: op=17 (raw_op), buf=0x10025fca0, count=44 (0x2c), parm=0xffffffff7fffd5f8
      2012/12/27.22:02:30 ioctl_op=0x7705, to=30, datalen=0x2c, buf=0x10025fca0
      2012/12/27.22:02:30 cdb: A2 20 00 10 00 00 00 00 00 2C 00 00 security protocol in
      2012/12/27.22:02:30 i/p data:
      2012/12/27.22:02:30 0000: 00 10 00 28 09 00 00 00 00 00 00 00 00 00 00 00 ...(............
      2012/12/27.22:02:30 0010: 00 00 00 00 01 00 00 14 BA 14 00 20 00 0C 00 20 ........... ...
      2012/12/27.22:02:30 0020: 09 00 00 00 00 00 00 00 00 01 00 14 ............
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:02:30 ***0 wst__encryption_check - have hardware encryption.
      2012/12/27.22:02:30 ***0 wst__encryption_is_tape_encryptable - yes
      2012/12/27.22:02:30 ***0 wst__exec: op=6 (modesen), buf=0x100260754, count=12 (0xc), parm=0x0
      2012/12/27.22:02:30 ioctl_op=0x7705, to=30, datalen=0xc, buf=0x100260754
      2012/12/27.22:02:30 cdb: 1A 00 00 00 0C 00 mode_sen, cnt=12, pc=0, pcode=0
      2012/12/27.22:02:30 i/p data:
      2012/12/27.22:02:30 0000: 0B 00 10 08 46 00 00 00 00 00 00 00 ....F.......
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:02:30 ***0 wst__exec: op=6 (modesen), buf=0x100260754, count=28 (0x1c), parm=0xf
      2012/12/27.22:02:30 ioctl_op=0x7705, to=30, datalen=0x1c, buf=0x100260754
      2012/12/27.22:02:30 cdb: 1A 00 0F 00 1C 00 mode_sen, cnt=28, pc=0, pcode=f
      2012/12/27.22:02:30 i/p data:
      2012/12/27.22:02:30 0000: 1B 00 10 08 46 00 00 00 00 00 00 00 0F 0E C0 80 ....F...........
      2012/12/27.22:02:30 0010: 00 00 00 01 00 00 00 01 00 00 00 00 ............
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:02:30 ***0 wst__exec: op=18 (log_sense), buf=0x100260754, count=76 (0x4c), parm=0x71
      2012/12/27.22:02:30 ioctl_op=0x7705, to=180, datalen=0x4c, buf=0x100260754
      2012/12/27.22:02:30 cdb: 4D 00 71 00 00 00 00 00 4C 00 log_sense, cnt=76, pc=1, page_code=0x31
      2012/12/27.22:02:30 i/p data:
      2012/12/27.22:02:30 0000: 31 00 00 20 00 01 40 04 00 00 12 F6 00 02 60 04 1.. ..@.......`.
      2012/12/27.22:02:30 0010: 00 00 00 00 00 03 60 04 00 0C 35 E2 00 04 60 04 ......`...5...`.
      2012/12/27.22:02:30 0020: 00 00 00 00 00 00 00 00 01 00 00 00 00 00 00 00 ................
      2012/12/27.22:02:30 0030: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
      2012/12/27.22:02:30 0040: 00 00 00 00 00 00 00 00 00 00 00 00 ............
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:02:30 ***0 wst__info: code=0x0
      2012/12/27.22:02:30 (atv) calling qdv__mount
      2012/12/27.22:02:30 (mt) qdv__read_mount_db() succeeded, found vol_oid 0
      2012/12/27.22:02:30 ***0 wst__exec: op=0 (nop), buf=0x0, count=0 (0x0), parm=0x0
      2012/12/27.22:02:30 ioctl_op=0x7703, to=30, datalen=0x0, buf=0x0
      2012/12/27.22:02:30 cdb: 00 00 00 00 00 00 tur
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:02:30 ***0 wst__dev_state...
      2012/12/27.22:02:30 ***0 wst__exec: op=0 (nop), buf=0x0, count=1 (0x1), parm=0x0
      2012/12/27.22:02:30 ioctl_op=0x7703, to=30, datalen=0x0, buf=0x0
      2012/12/27.22:02:30 cdb: 00 00 00 00 00 00 tur
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:02:30 ***0 wst__dev_state: no sense, status 0x0
      2012/12/27.22:02:30 ***0 wst__exec: op=2 (sense), buf=0x1002606d4, count=24 (0x18), parm=0x0
      2012/12/27.22:02:30 ioctl_op=0x7705, to=30, datalen=0x18, buf=0x1002606d4
      2012/12/27.22:02:30 cdb: 03 00 00 00 18 00 sense, cnt=24
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:02:30 ***0 wst__get_sense(int) cmd = 0, sense data:
      2012/12/27.22:02:30 70 00 00 00 00 00 00 10 00 00 00 00 00 00 00 00
      2012/12/27.22:02:30 00 00 00 00 00 00 00 00
      2012/12/27.22:02:30 ec=0, sk=no sense, asc=0, ascq=0
      2012/12/27.22:02:30 flags: (none)
      2012/12/27.22:02:30 ***0 wst__exec: op=7 (readpos), buf=0xffffffff7fffc650, count=20 (0x14), parm=0x0
      2012/12/27.22:02:30 ioctl_op=0x7705, to=180, datalen=0x14, buf=0x1002607d8
      2012/12/27.22:02:30 cdb: 34 00 00 00 00 00 00 00 00 00 read_pos
      2012/12/27.22:02:30 raw position:
      2012/12/27.22:02:30 0000: F0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
      2012/12/27.22:02:30 0010: 00 00 00 00 ....
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x10/0x0
      2012/12/27.22:02:30 ***0 wst__dev_state: state=0xd0000000 (online, at bot)
      2012/12/27.22:02:30 ***0 wst__info: infolen=0xd8, info=0xffffffff7fffd930
      2012/12/27.22:02:30 ***0 wst__dev_state...
      2012/12/27.22:02:30 ***0 wst__exec: op=0 (nop), buf=0x0, count=1 (0x1), parm=0x0
      2012/12/27.22:02:30 ioctl_op=0x7703, to=30, datalen=0x0, buf=0x0
      2012/12/27.22:02:30 cdb: 00 00 00 00 00 00 tur
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:02:30 ***0 wst__dev_state: no sense, status 0x0
      2012/12/27.22:02:30 ***0 wst__exec: op=2 (sense), buf=0x1002606d4, count=24 (0x18), parm=0x0
      2012/12/27.22:02:30 ioctl_op=0x7705, to=30, datalen=0x18, buf=0x1002606d4
      2012/12/27.22:02:30 cdb: 03 00 00 00 18 00 sense, cnt=24
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:02:30 ***0 wst__get_sense(int) cmd = 0, sense data:
      2012/12/27.22:02:30 70 00 00 00 00 00 00 10 00 00 00 00 00 00 00 00
      2012/12/27.22:02:30 00 00 00 00 00 00 00 00
      2012/12/27.22:02:30 ec=0, sk=no sense, asc=0, ascq=0
      2012/12/27.22:02:30 flags: (none)
      2012/12/27.22:02:30 ***0 wst__exec: op=7 (readpos), buf=0xffffffff7fffc4a0, count=20 (0x14), parm=0x0
      2012/12/27.22:02:30 ioctl_op=0x7705, to=180, datalen=0x14, buf=0x1002607d8
      2012/12/27.22:02:30 cdb: 34 00 00 00 00 00 00 00 00 00 read_pos
      2012/12/27.22:02:30 raw position:
      2012/12/27.22:02:30 0000: F0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
      2012/12/27.22:02:30 0010: 00 00 00 00 ....
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x10/0x0
      2012/12/27.22:02:30 ***0 wst__dev_state: state=0xd0000000 (online, at bot)
      2012/12/27.22:02:30 ***0 wst__encryption_is_tape_encryptable...
      2012/12/27.22:02:30 ***0 wst__encryption_check...
      2012/12/27.22:02:30 ***0 wst__exec: op=17 (raw_op), buf=0x100254260, count=44 (0x2c), parm=0xffffffff7fffbee8
      2012/12/27.22:02:30 ioctl_op=0x7705, to=30, datalen=0x2c, buf=0x100254260
      2012/12/27.22:02:30 cdb: A2 20 00 10 00 00 00 00 00 2C 00 00 security protocol in
      2012/12/27.22:02:30 i/p data:
      2012/12/27.22:02:30 0000: 00 10 00 28 09 00 00 00 00 00 00 00 00 00 00 00 ...(............
      2012/12/27.22:02:30 0010: 00 00 00 00 01 00 00 14 BA 14 00 20 00 0C 00 20 ........... ...
      2012/12/27.22:02:30 0020: 09 00 00 00 00 00 00 00 00 01 00 14 ............
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:02:30 ***0 wst__encryption_check - have hardware encryption.
      2012/12/27.22:02:30 ***0 wst__encryption_is_tape_encryptable - yes
      2012/12/27.22:02:30 ***0 wst__exec: op=6 (modesen), buf=0x100260754, count=12 (0xc), parm=0x0
      2012/12/27.22:02:30 ioctl_op=0x7705, to=30, datalen=0xc, buf=0x100260754
      2012/12/27.22:02:30 cdb: 1A 00 00 00 0C 00 mode_sen, cnt=12, pc=0, pcode=0
      2012/12/27.22:02:30 i/p data:
      2012/12/27.22:02:30 0000: 0B 00 10 08 46 00 00 00 00 00 00 00 ....F.......
      2012/12/27.22:02:30 ***0 wst__exec: rval=0, status.code/resid/checks=0x0/0x0/0x0
      2012/12/27.22:02:30 ***0 wst__exec: op=6 (modesen), buf=0x100260754, count=28 (0x1c), parm=0xf
      2012/12/27.22:02:30 ioctl_op=0x7705, to=30, datalen=0x1c, buf=0x100260754
      2012/12/27.22:02:30 cdb: 1A 00 0F 00 1C 00 mode_sen, cnt=28, pc=0, pcode=f
      2012/12/27.22:02:30 i/p data:
        • 1. Re: Backups are not being started....volumes do not exist?
          rdoogan
          "device reported incorrect serial number" is the key here.

          Your device is misconfigured and the entry for its serial number doesn't match the serial number being reported by the scsi enquiry. Has a drive been replaced? If the drives have swtiched order due to a reboot then I suggest you use /dev/tape/by-id as your attach points rather than direct /dev/sg entries.

          Thanks

          Rich