none
DPM 2012 R2 and Dell 124T Tape RRS feed

  • Question

  • Okay Colleagues/Geniuses -- 

    I'm runnning a physical Server 2012 R2 with DPM 2012 R2 with a large back to disk library and a Dell 124T Autooader LTO5 tape backup. 

    I cannot for the life of me to get the tape backups running. I start a job, it loads a tape succesfully then stops at .13 megabytes and shutsdown. 

    Latest firmware from Dell, Lastest drivers etc. i'm at a loss where to start troubleshooting. 

    All failures are accompanied with "cannot find recovery point on disk" or "Tape Library is not functioning"

    Thoughts on where to begin? 

    Wednesday, February 11, 2015 8:41 PM

All replies

  • Hi,

    The error "cannot find recovery point on disk" means that there is not a valid / new disk based recovery point to copy to tape.  If you have a daily long term tape backup scheduled at 8:00PM and there is not a new disk recovery point made before that time, then you will receive that error. Review your disk based recovery point jobs for failures and fix those problems.

    For the tape library issue, it sounds like a driver or connectivity issue -  please download and run the tape compatibility test tool and see if it passes the tests without errors. 

    Verify tape library compatibility  

    You can bypass the tape cleaner test by just running the following:

    DPMLibraryTest.exe /CERTIFY /TL \\.\Changer0 /ST 1 2 4 5


    Please remember to click “Mark as Answer” on the post that helps you, and to click “Unmark as Answer” if a marked post does not actually answer your question. This can be beneficial to other community members reading the thread. Regards, Mike J. [MSFT] This posting is provided "AS IS" with no warranties, and confers no rights.

    Thursday, February 12, 2015 8:13 PM
    Moderator
  • Mike  -- 

    Thank you. 

    I resolved the recovery point on disk - but the tape still failed. Here's the results of the library test: 

    0F18 1108 02/12 20:21:37.594 30 dpmlibrarytesttool.cpp(954) NORMAL CMDLINE: dpmlibrarytest.exe /CERTIFY /TL \\.\Changer0 /ST 1 2 4 5 
    0F18 1108 02/12 20:21:37.594 30 utility.cpp(273) WARNING Failed: Hr: = [0x80070424] QueryServiceStatus failed
    0F18 1108 02/12 20:21:37.594 30 utility.cpp(349) WARNING Failed: Hr: = [0x80070424] GetServiceStatus for NtmsSvc service failed, ignoring the failure
    0F18 1108 02/12 20:21:37.594 30 utility.cpp(266) WARNING Failed: Hr: = [0x80070424] : F: lVal : (HANDLE)(schService = OpenServiceW( schSCManager, strServiceName.PeekStr(), 0x0004))
    0F18 1108 02/12 20:21:37.594 30 utility.cpp(195) WARNING Failed: Hr: = [0x80070002] : F: lVal : HRESULT_FROM_WIN32(2)
    0F18 1108 02/12 20:21:37.594 30 cfgclass.cpp(1104) [00000000014651F0] NORMAL We got 1  devices for class 0
    0F18 1108 02/12 20:21:37.594 30 cfgclass.cpp(176) [0000000002CEBE58] NORMAL Initializing Device with path \\?\scsi#sequential&ven_quantum&prod_ultrium_5#5&342d286c&0&000300#{53f5630b-b6bf-11d0-94f2-00a0c91efb8b}
    0F18 1108 02/12 20:21:37.594 30 system.cpp(1183) WARNING Failed: Hr: = [0x80070002] Failed to initialize IOCTL timeout from registry. Initializing with default timeout [900000 ms].
    0F18 1108 02/12 20:21:37.594 30 system.cpp(1168) WARNING Failed: Hr: = [0x80070002] : F: lVal : r.GetValue(L"LibraryIoctlTimeout", &s_dwIoctlTimeoutInMs)
    0F18 1108 02/12 20:21:37.594 30 cfgclass.cpp(1104) [0000000001464F70] NORMAL We got 1  devices for class 1
    0F18 1108 02/12 20:21:37.594 30 cfgclass.cpp(176) [0000000002CECF38] NORMAL Initializing Device with path \\?\scsi#changer&ven_dell&prod_pv-124t#5&342d286c&0&000301#{53f56310-b6bf-11d0-94f2-00a0c91efb8b}
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1061) [000000000107F510] NORMAL Found a device with following details
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1062) [000000000107F510] NORMAL          Device Name         : \\.\Changer0
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1063) [000000000107F510] NORMAL          Device Serialnumber : CJ4HH70329
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1064) [000000000107F510] NORMAL          Device Vendor Name  : Dell Inc
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1065) [000000000107F510] NORMAL          Device Product Name : Dell (tm) PowerVault (tm) 124T Autoloader
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1066) [000000000107F510] NORMAL          FirmWare Revision   : 0091
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1068) [000000000107F510] NORMAL          Device SCSI Props   : (P,B,T,L)2,0,3,1
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1076) [000000000107F510] NORMAL          Driver Name   : Pv124X64
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1002) WARNING Failed: Hr: = [0x80070002] : F: lVal : versionBufSize = GetFileVersionInfoSizeW(path.PeekStr(), &dummyHandle)
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1102) [000000000107F510] NORMAL          Driver Version   : Failed to get
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1061) [000000000107F510] NORMAL Found a device with following details
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1062) [000000000107F510] NORMAL          Device Name         : \\.\Tape2147483646
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1063) [000000000107F510] NORMAL          Device Serialnumber : HUJ43017A0
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1064) [000000000107F510] NORMAL          Device Vendor Name  : Quantum
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1065) [000000000107F510] NORMAL          Device Product Name : QUANTUM ULTRIUM 5 SCSI Sequential Device
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1066) [000000000107F510] NORMAL          FirmWare Revision   : 3180
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1068) [000000000107F510] NORMAL          Device SCSI Props   : (P,B,T,L)2,0,3,0
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1076) [000000000107F510] NORMAL          Driver Name   : QLTOx64
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1002) WARNING Failed: Hr: = [0x80070002] : F: lVal : versionBufSize = GetFileVersionInfoSizeW(path.PeekStr(), &dummyHandle)
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1102) [000000000107F510] NORMAL          Driver Version   : Failed to get
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(975) [000000000107F510] NORMAL Rescan Operation completed with hr - 0x0
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1377) NORMAL Opening handle for device \\.\Changer0
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(1390) NORMAL Open Handle Operation completed with hr - 0x0
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(744) [000000000107F510] NORMAL Doing Fast Inventory
    0F18 1108 02/12 20:21:38.516 30 dpmdevicelayer.cpp(747) [000000000107F510] NORMAL Calling IOCTL_CHANGER_GET_PARAMETERS
    0F18 1108 02/12 20:21:39.141 30 dpmdevicelayer.cpp(760) [000000000107F510] NORMAL Library information Number of Slots - 8, Number of Drives  - 1, Number of I/E ports - 0
    0F18 1108 02/12 20:21:39.141 30 dpmdevicelayer.cpp(773) [000000000107F510] NORMAL Doing Slot Inventory
    0F18 1108 02/12 20:21:39.141 30 dpmdevicelayer.cpp(1144) [000000000107F510] NORMAL Calling InitializeElementStatus for element type - 2, first element - 0, Number of elements
    0F18 1108 02/12 20:21:39.141 30 system.cpp(141) WARNING Failed: Hr: = [0x80070001] IoControl 304018 failed!
    0F18 1108 02/12 20:21:39.141 30 dpmdevicelayer.cpp(1164) [000000000107F510] NORMAL Hr: = [0x80070001] InitializeElementStatus failed with non critical error, ignoring failure
    0F18 1108 02/12 20:21:39.141 30 dpmdevicelayer.cpp(1189) [000000000107F510] NORMAL Calling IOCTL_CHANGER_GET_ELEMENT_STATUS for element type - 2, first element - 0, Number of elements - 5
    0F18 1108 02/12 20:21:39.688 30 dpmdevicelayer.cpp(1203) [000000000107F510] NORMAL IOCTL_CHANGER_GET_ELEMENT_STATUS Succeeded
    0F18 1108 02/12 20:21:39.688 30 dpmdevicelayer.cpp(1189) [000000000107F510] NORMAL Calling IOCTL_CHANGER_GET_ELEMENT_STATUS for element type - 2, first element - 5, Number of elements - 3
    0F18 1108 02/12 20:21:40.079 30 dpmdevicelayer.cpp(1203) [000000000107F510] NORMAL IOCTL_CHANGER_GET_ELEMENT_STATUS Succeeded
    0F18 1108 02/12 20:21:40.079 30 dpmdevicelayer.cpp(1327) [000000000107F510] NORMAL Get Element Status Operation completed with hr - 0x0
    0F18 1108 02/12 20:21:40.079 30 dpmdevicelayer.cpp(781) [000000000107F510] NORMAL Doing I/E port Inventory
    0F18 1108 02/12 20:21:40.079 30 dpmdevicelayer.cpp(789) [000000000107F510] NORMAL Doing Drive Inventory
    0F18 1108 02/12 20:21:40.079 30 dpmdevicelayer.cpp(1144) [000000000107F510] NORMAL Calling InitializeElementStatus for element type - 4, first element - 0, Number of elements
    0F18 1108 02/12 20:21:40.095 30 system.cpp(141) WARNING Failed: Hr: = [0x80070001] IoControl 304018 failed!
    0F18 1108 02/12 20:21:40.095 30 dpmdevicelayer.cpp(1164) [000000000107F510] NORMAL Hr: = [0x80070001] InitializeElementStatus failed with non critical error, ignoring failure
    0F18 1108 02/12 20:21:40.095 30 dpmdevicelayer.cpp(1189) [000000000107F510] NORMAL Calling IOCTL_CHANGER_GET_ELEMENT_STATUS for element type - 4, first element - 0, Number of elements - 1
    0F18 1108 02/12 20:21:40.376 30 dpmdevicelayer.cpp(1203) [000000000107F510] NORMAL IOCTL_CHANGER_GET_ELEMENT_STATUS Succeeded
    0F18 1108 02/12 20:21:40.376 30 dpmdevicelayer.cpp(1327) [000000000107F510] NORMAL Get Element Status Operation completed with hr - 0x0
    0F18 1108 02/12 20:21:40.376 30 dpmdevicelayer.cpp(840) [000000000107F510] NORMAL Fast Inventory Operation completed with hr - 0x0
    0F18 1108 02/12 20:21:40.376 30 dpmlibrarytesttool.cpp(416) NORMAL TEST CONFIGURATION:
    0F18 1108 02/12 20:21:40.376 30 dpmlibrarytesttool.cpp(417) NORMAL Testing library                           : \\.\Changer0
    0F18 1108 02/12 20:21:40.376 30 dpmlibrarytesttool.cpp(418) NORMAL Number of drives                          : 1
    0F18 1108 02/12 20:21:40.376 30 dpmlibrarytesttool.cpp(419) NORMAL Number of slots                           : 8
    0F18 1108 02/12 20:21:40.376 30 dpmlibrarytesttool.cpp(420) NORMAL Number of I/E ports                       : 0
    0F18 1108 02/12 20:21:40.376 30 dpmlibrarytesttool.cpp(421) NORMAL Number of media present in library        : 8
    0F18 1108 02/12 20:21:40.376 30 dpmlibrarytesttool.cpp(425) NORMAL Library supports barcode                  : Yes
    0F18 1108 02/12 20:21:40.376 30 dpmlibrarytesttool.cpp(351) NORMAL Using Non-Cleaner tape from slot         : 0
    0F18 1108 02/12 20:21:40.376 30 dpmlibrarytesttool.cpp(354) NORMAL Barcode for the Non-Cleaner tape         : GZ9306L5
    0F18 1108 02/12 20:21:40.376 30 dpmlibrarytesttool.cpp(357) NORMAL WARNING: Above mentioned media may get overwritten by this tool, You want to continue?[Y/N :Default(Y)]:
    0F18 1108 02/12 20:21:44.221 30 dpmlibrarytesttool.cpp(379) NORMAL Using Cleaner tape from slot             : 1
    0F18 1108 02/12 20:21:44.221 30 dpmlibrarytesttool.cpp(382) NORMAL Barcode for the Cleaner tape             : GZ9305L5
    0F18 1108 02/12 20:21:44.221 30 dpmlibrarytesttool.cpp(385) NORMAL WARNING: Cleaner tape is not having CLN barcode
    0F18 1108 02/12 20:21:44.221 30 dpmdevicelayer.cpp(1377) NORMAL Opening handle for device \\.\Tape2147483646
    0F18 1108 02/12 20:21:44.221 30 dpmdevicelayer.cpp(1390) NORMAL Open Handle Operation completed with hr - 0x0
    0F18 1108 02/12 20:21:44.221 30 dpmlibrarytesttool.cpp(568) NORMAL Drive to be used for testing              : \\.\Tape2147483646
    0F18 1108 02/12 20:21:44.221 30 dpmlibrarytesttool.cpp(569) NORMAL Drive bay index of drive to be used        : 0
    0F18 1108 02/12 20:21:44.221 30 dpmlibrarytesttool.cpp(572) NORMAL RUNNING TEST CASES:
    0F18 1108 02/12 20:21:44.221 30 dpmlibrarytesttool.cpp(583) NORMAL Running DPM Library Detection Test ...
    0F18 1108 02/12 20:21:44.221 30 utility.cpp(590) NORMAL Found 1 Tape Libraries
    0F18 1108 02/12 20:21:44.221 30 utility.cpp(591) NORMAL Found 0 Standalone Drives

    Thursday, February 12, 2015 8:31 PM
  • Part 2: 

    0F18 1108 02/12 20:21:44.221 30 utility.cpp(595) NORMAL --------------------- Tape Library Information ----------------
    0F18 1108 02/12 20:21:44.221 30 utility.cpp(601) NORMAL LIBRARY (Drive bays - 1, Drives - 1)
    0F18 1108 02/12 20:21:44.221 30 utility.cpp(550) NORMAL          Device Name         : \\.\Changer0
    0F18 1108 02/12 20:21:44.221 30 utility.cpp(551) NORMAL          Device Serialnumber : CJ4HH70329
    0F18 1108 02/12 20:21:44.221 30 utility.cpp(552) NORMAL          Device Vendor Name  : Dell (tm) PowerVault (tm) 124T Autoloader
    0F18 1108 02/12 20:21:44.236 30 utility.cpp(553) NORMAL          Device Product Name : Dell Inc
    0F18 1108 02/12 20:21:44.236 30 utility.cpp(554) NORMAL          FirmWare Revision     : 0091
    0F18 1108 02/12 20:21:44.236 30 utility.cpp(559) NORMAL          Device SCSI Props   : (P,B,T,L)2,0,3,1
    0F18 1108 02/12 20:21:44.236 30 utility.cpp(606) NORMAL DRIVE - 0, DRIVE Bay Index - 0
    0F18 1108 02/12 20:21:44.236 30 utility.cpp(550) NORMAL          Device Name         : \\.\Tape2147483646
    0F18 1108 02/12 20:21:44.236 30 utility.cpp(551) NORMAL          Device Serialnumber : HUJ43017A0
    0F18 1108 02/12 20:21:44.236 30 utility.cpp(552) NORMAL          Device Vendor Name  : QUANTUM ULTRIUM 5 SCSI Sequential Device
    0F18 1108 02/12 20:21:44.236 30 utility.cpp(553) NORMAL          Device Product Name : Quantum
    0F18 1108 02/12 20:21:44.236 30 utility.cpp(554) NORMAL          FirmWare Revision     : 3180
    0F18 1108 02/12 20:21:44.236 30 utility.cpp(559) NORMAL          Device SCSI Props   : (P,B,T,L)2,0,3,0
    0F18 1108 02/12 20:21:44.236 30 utility.cpp(611) NORMAL ------------------- End of Tape Library Information -----------
    0F18 1108 02/12 20:21:44.236 30 dpmlibrarytesttool.cpp(585) NORMAL Does the rescan result match you hardware configuration? [Y/N Default(Y)]:
    0F18 1108 02/12 20:22:44.608 30 dpmlibrarytesttool.cpp(596) NORMAL DPM Library Detection Test ...
    0F18 1108 02/12 20:22:44.608 30 dpmlibrarytesttool.cpp(597) NORMAL Succeeded
    0F18 1108 02/12 20:22:44.608 30 dpmlibrarytesttool.cpp(605) NORMAL Running DPM Mount/Dismount Test ...
    0F18 1108 02/12 20:22:44.608 30 dpmdevicelayer.cpp(130) [000000000107F510] NORMAL Doing Move media source elment type 2, source element number,destination element type - 0, destination element number - 4
    0F18 1108 02/12 20:22:44.608 30 dpmdevicelayer.cpp(288) [000000000107F510] NORMAL Calling IOCTL_CHANGER_GET_PARAMETERS
    0F18 1108 02/12 20:22:45.233 30 dpmdevicelayer.cpp(303) [000000000107F510] NORMAL Operation IOCTL_CHANGER_GET_PARAMETERS completed with hr - 0x0
    0F18 1108 02/12 20:22:45.233 30 dpmdevicelayer.cpp(1144) [000000000107F510] NORMAL Calling InitializeElementStatus for element type - 2, first element - 0, Number of elements
    0F18 1108 02/12 20:22:45.233 30 system.cpp(141) WARNING Failed: Hr: = [0x80070001] IoControl 304018 failed!
    0F18 1108 02/12 20:22:45.233 30 dpmdevicelayer.cpp(1164) [000000000107F510] NORMAL Hr: = [0x80070001] InitializeElementStatus failed with non critical error, ignoring failure
    0F18 1108 02/12 20:22:45.233 30 dpmdevicelayer.cpp(1189) [000000000107F510] NORMAL Calling IOCTL_CHANGER_GET_ELEMENT_STATUS for element type - 2, first element - 0, Number of elements - 1
    0F18 1108 02/12 20:22:45.499 30 dpmdevicelayer.cpp(1203) [000000000107F510] NORMAL IOCTL_CHANGER_GET_ELEMENT_STATUS Succeeded
    0F18 1108 02/12 20:22:45.499 30 dpmdevicelayer.cpp(1327) [000000000107F510] NORMAL Get Element Status Operation completed with hr - 0x0
    0F18 1108 02/12 20:22:45.499 30 dpmdevicelayer.cpp(151) [000000000107F510] NORMAL Source element is full before move
    0F18 1108 02/12 20:22:45.499 30 dpmdevicelayer.cpp(1144) [000000000107F510] NORMAL Calling InitializeElementStatus for element type - 4, first element - 0, Number of elements
    0F18 1108 02/12 20:22:45.499 30 system.cpp(141) WARNING Failed: Hr: = [0x80070001] IoControl 304018 failed!
    0F18 1108 02/12 20:22:45.499 30 dpmdevicelayer.cpp(1164) [000000000107F510] NORMAL Hr: = [0x80070001] InitializeElementStatus failed with non critical error, ignoring failure
    0F18 1108 02/12 20:22:45.499 30 dpmdevicelayer.cpp(1189) [000000000107F510] NORMAL Calling IOCTL_CHANGER_GET_ELEMENT_STATUS for element type - 4, first element - 0, Number of elements - 1
    0F18 1108 02/12 20:22:45.780 30 dpmdevicelayer.cpp(1203) [000000000107F510] NORMAL IOCTL_CHANGER_GET_ELEMENT_STATUS Succeeded
    0F18 1108 02/12 20:22:45.780 30 dpmdevicelayer.cpp(1327) [000000000107F510] NORMAL Get Element Status Operation completed with hr - 0x0
    0F18 1108 02/12 20:22:45.780 30 dpmdevicelayer.cpp(167) [000000000107F510] NORMAL Destination element is empty before move
    0F18 1108 02/12 20:22:45.780 30 dpmdevicelayer.cpp(171) [000000000107F510] NORMAL Calling Move Media
    0F18 1108 02/12 20:22:45.780 30 dpmdevicelayer.cpp(246) [000000000107F510] NORMAL Calling IOCTL_CHANGER_MOVE_MEDIUM with Transport.ElementType - 1, Transport.ElementAddress - 0Source.ElementType - 2, Source.ElementAddress - 0, Destination.ElementType - 4, Destination.ElementAddress - 0
    0F18 1108 02/12 20:24:00.040 30 dpmdevicelayer.cpp(266) [000000000107F510] NORMAL Operation IOCTL_CHANGER_MOVE_MEDIUM completed with hr - 0x0
    0F18 1108 02/12 20:24:00.040 30 dpmdevicelayer.cpp(1144) [000000000107F510] NORMAL Calling InitializeElementStatus for element type - 2, first element - 0, Number of elements
    0F18 1108 02/12 20:24:00.040 30 system.cpp(141) WARNING Failed: Hr: = [0x80070001] IoControl 304018 failed!

    Thursday, February 12, 2015 8:31 PM
  • Part 3: 

    0F18 1108 02/12 20:26:19.558 30 dpmdevicelayer.cpp(1144) [000000000107F510] NORMAL Calling InitializeElementStatus for element type - 2, first element - 0, Number of elements
    0F18 1108 02/12 20:26:19.558 30 system.cpp(141) WARNING Failed: Hr: = [0x80070001] IoControl 304018 failed!
    0F18 1108 02/12 20:26:19.558 30 dpmdevicelayer.cpp(1164) [000000000107F510] NORMAL Hr: = [0x80070001] InitializeElementStatus failed with non critical error, ignoring failure
    0F18 1108 02/12 20:26:19.558 30 dpmdevicelayer.cpp(1189) [000000000107F510] NORMAL Calling IOCTL_CHANGER_GET_ELEMENT_STATUS for element type - 2, first element - 0, Number of elements - 1
    0F18 1108 02/12 20:26:19.823 30 dpmdevicelayer.cpp(1203) [000000000107F510] NORMAL IOCTL_CHANGER_GET_ELEMENT_STATUS Succeeded
    0F18 1108 02/12 20:26:19.823 30 dpmdevicelayer.cpp(1327) [000000000107F510] NORMAL Get Element Status Operation completed with hr - 0x0
    0F18 1108 02/12 20:26:19.823 30 dpmdevicelayer.cpp(190) [000000000107F510] NORMAL Source element is empty after move
    0F18 1108 02/12 20:26:19.823 30 dpmdevicelayer.cpp(1144) [000000000107F510] NORMAL Calling InitializeElementStatus for element type - 4, first element - 0, Number of elements
    0F18 1108 02/12 20:26:19.823 30 system.cpp(141) WARNING Failed: Hr: = [0x80070001] IoControl 304018 failed!
    0F18 1108 02/12 20:26:19.823 30 dpmdevicelayer.cpp(1164) [000000000107F510] NORMAL Hr: = [0x80070001] InitializeElementStatus failed with non critical error, ignoring failure
    0F18 1108 02/12 20:26:19.823 30 dpmdevicelayer.cpp(1189) [000000000107F510] NORMAL Calling IOCTL_CHANGER_GET_ELEMENT_STATUS for element type - 4, first element - 0, Number of elements - 1
    0F18 1108 02/12 20:26:20.120 30 dpmdevicelayer.cpp(1203) [000000000107F510] NORMAL IOCTL_CHANGER_GET_ELEMENT_STATUS Succeeded
    0F18 1108 02/12 20:26:20.120 30 dpmdevicelayer.cpp(1327) [000000000107F510] NORMAL Get Element Status Operation completed with hr - 0x0
    0F18 1108 02/12 20:26:20.120 30 dpmdevicelayer.cpp(204) [000000000107F510] NORMAL Destination element is full after move
    0F18 1108 02/12 20:26:20.120 30 dpmdevicelayer.cpp(207) [000000000107F510] NORMAL Move Media operation complted successfully
    0F18 1108 02/12 20:26:20.120 30 dpmdevicelayer.cpp(214) [000000000107F510] NORMAL Move media between slot and drive Operation completed with hr - 0x0
    0F18 1108 02/12 20:26:20.120 30 dpmdevicelayer.cpp(1624) [000000000107F510] NORMAL Performing Read/Write test
    0F18 1108 02/12 20:26:20.120 30 dpmdevicelayer.cpp(1672) [000000000107F510] NORMAL Hr: = [0x00000000] Doing Read/Write test
    0F18 1108 02/12 20:26:20.120 30 dpmdevicelayer.cpp(1675) [000000000107F510] NORMAL Hr: = [0x00000000] Doing Load and Lock on drive
    0F18 1108 02/12 20:26:20.120 30 system.cpp(513) NORMAL FAILED PrepareTape [TAPE_LOAD] on drive result=456
    0F18 1108 02/12 20:26:20.120 30 system.cpp(505) NORMAL PrepareTape operation 3 returned 0, failing 
    0F18 1108 02/12 20:26:20.120 30 system.cpp(508) WARNING Failed: Hr: = [0x80070456] : F: lVal : HRESULT_FROM_WIN32(result)
    0F18 1108 02/12 20:26:20.120 30 dpmdevicelayer.cpp(1682) [000000000107F510] NORMAL Hr: = [0x00000000] Rewinding Tape
    0F18 1108 02/12 20:26:20.136 30 dpmdevicelayer.cpp(1688) [000000000107F510] NORMAL Hr: = [0x00000000] Make sure Drive supportes 64k blocksize
    0F18 1108 02/12 20:26:20.136 30 dpmdevicelayer.cpp(1701) [000000000107F510] NORMAL Hr: = [0x00000000] Drive supportes 64k blocksize
    0F18 1108 02/12 20:26:20.136 30 dpmdevicelayer.cpp(1707) [000000000107F510] NORMAL Setting blockzie to 64k
    0F18 1108 02/12 20:26:20.136 30 dpmdevicelayer.cpp(1723) [000000000107F510] NORMAL Hr: = [0x00000000] Writting Data onto tape
    0F18 1108 02/12 20:26:20.277 30 dpmdevicelayer.cpp(1729) [000000000107F510] NORMAL Hr: = [0x00000000] Data Written successfully
    0F18 1108 02/12 20:26:20.277 30 dpmdevicelayer.cpp(1733) [000000000107F510] NORMAL Hr: = [0x00000000] Rewinding Tape
    0F18 1108 02/12 20:26:25.871 30 dpmdevicelayer.cpp(1742) [000000000107F510] NORMAL Hr: = [0x00000000] Read and compare data
    0F18 1108 02/12 20:26:25.902 30 dpmdevicelayer.cpp(1751) [000000000107F510] NORMAL Hr: = [0x00000000] Data from tape verified
    0F18 1108 02/12 20:26:25.902 30 dpmdevicelayer.cpp(1755) [000000000107F510] NORMAL Hr: = [0x00000000] Doing Unlock  and Unload tape
    0F18 1108 02/12 20:26:25.902 30 system.cpp(505) NORMAL PrepareTape operation 4 returned 0, failing 
    0F18 1108 02/12 20:26:44.639 30 system.cpp(505) NORMAL PrepareTape operation 1 returned 0, failing 
    0F18 1108 02/12 20:26:44.639 30 system.cpp(505) NORMAL PrepareTape operation 4 returned 0, failing 
    0F18 1108 02/12 20:26:44.639 30 system.cpp(505) NORMAL PrepareTape operation 1 returned 0, failing 
    0F18 1108 02/12 20:26:44.639 30 dpmdevicelayer.cpp(1776) [000000000107F510] NORMAL Read/Write test completed with hr - 0x0
    0F18 1108 02/12 20:26:44.639 30 dpmdevicelayer.cpp(1638) [000000000107F510] NORMAL Performing dismount to Slot - 0 from Drive Bay - 0
    0F18 1108 02/12 20:26:44.639 30 dpmdevicelayer.cpp(130) [000000000107F510] NORMAL Doing Move media source elment type 4, source element number,destination element type - 0, destination element number - 2
    0F18 1108 02/12 20:26:44.639 30 dpmdevicelayer.cpp(288) [000000000107F510] NORMAL Calling IOCTL_CHANGER_GET_PARAMETERS
    0F18 1108 02/12 20:26:45.264 30 dpmdevicelayer.cpp(303) [000000000107F510] NORMAL Operation IOCTL_CHANGER_GET_PARAMETERS completed with hr - 0x0
    0F18 1108 02/12 20:26:45.264 30 dpmdevicelayer.cpp(1144) [000000000107F510] NORMAL Calling InitializeElementStatus for element type - 4, first element - 0, Number of elements
    0F18 1108 02/12 20:26:45.264 30 system.cpp(141) WARNING Failed: Hr: = [0x80070001] IoControl 304018 failed!
    0F18 1108 02/12 20:26:45.264 30 dpmdevicelayer.cpp(1164) [000000000107F510] NORMAL Hr: = [0x80070001] InitializeElementStatus failed with non critical error, ignoring failure
    0F18 1108 02/12 20:26:45.264 30 dpmdevicelayer.cpp(1189) [000000000107F510] NORMAL Calling IOCTL_CHANGER_GET_ELEMENT_STATUS for element type - 4, first element - 0, Number of elements - 1
    0F18 1108 02/12 20:26:45.561 30 dpmdevicelayer.cpp(1203) [000000000107F510] NORMAL IOCTL_CHANGER_GET_ELEMENT_STATUS Succeeded
    0F18 1108 02/12 20:26:45.561 30 dpmdevicelayer.cpp(1327) [000000000107F510] NORMAL Get Element Status Operation completed with hr - 0x0
    0F18 1108 02/12 20:26:45.561 30 dpmdevicelayer.cpp(151) [000000000107F510] NORMAL Source element is full before move
    0F18 1108 02/12 20:26:45.561 30 dpmdevicelayer.cpp(1144) [000000000107F510] NORMAL Calling InitializeElementStatus for element type - 2, first element - 0, Number of elements
    0F18 1108 02/12 20:26:45.561 30 system.cpp(141) WARNING Failed: Hr: = [0x80070001] IoControl 304018 failed!
    0F18 1108 02/12 20:26:45.561 30 dpmdevicelayer.cpp(1164) [000000000107F510] NORMAL Hr: = [0x80070001] InitializeElementStatus failed with non critical error, ignoring failure
    0F18 1108 02/12 20:26:45.561 30 dpmdevicelayer.cpp(1189) [000000000107F510] NORMAL Calling IOCTL_CHANGER_GET_ELEMENT_STATUS for element type - 2, first element - 0, Number of elements - 1
    0F18 1108 02/12 20:26:45.827 30 dpmdevicelayer.cpp(1203) [000000000107F510] NORMAL IOCTL_CHANGER_GET_ELEMENT_STATUS Succeeded
    0F18 1108 02/12 20:26:45.827 30 dpmdevicelayer.cpp(1327) [000000000107F510] NORMAL Get Element Status Operation completed with hr - 0x0
    0F18 1108 02/12 20:26:45.827 30 dpmdevicelayer.cpp(167) [000000000107F510] NORMAL Destination element is empty before move
    0F18 1108 02/12 20:26:45.827 30 dpmdevicelayer.cpp(171) [000000000107F510] NORMAL Calling Move Media
    0F18 1108 02/12 20:26:45.827 30 dpmdevicelayer.cpp(246) [000000000107F510] NORMAL Calling IOCTL_CHANGER_MOVE_MEDIUM with Transport.ElementType - 1, Transport.ElementAddress - 0Source.ElementType - 4, Source.ElementAddress - 0, Destination.ElementType - 2, Destination.ElementAddress - 0
    0F18 1108 02/12 20:27:30.269 30 dpmdevicelayer.cpp(266) [000000000107F510] NORMAL Operation IOCTL_CHANGER_MOVE_MEDIUM completed with hr - 0x0
    0F18 1108 02/12 20:27:30.269 30 dpmdevicelayer.cpp(1144) [000000000107F510] NORMAL Calling InitializeElementStatus for element type - 4, first element - 0, Number of elements
    0F18 1108 02/12 20:27:30.269 30 system.cpp(141) WARNING Failed: Hr: = [0x80070001] IoControl 304018 failed!
    0F18 1108 02/12 20:27:30.269 30 dpmdevicelayer.cpp(1164) [000000000107F510] NORMAL Hr: = [0x80070001] InitializeElementStatus failed with non critical error, ignoring failure
    0F18 1108 02/12 20:27:30.269 30 dpmdevicelayer.cpp(1189) [000000000107F510] NORMAL Calling IOCTL_CHANGER_GET_ELEMENT_STATUS for element type - 4, first element - 0, Number of elements - 1
    0F18 1108 02/12 20:27:30.566 30 dpmdevicelayer.cpp(1203) [000000000107F510] NORMAL IOCTL_CHANGER_GET_ELEMENT_STATUS Succeeded
    0F18 1108 02/12 20:27:30.566 30 dpmdevicelayer.cpp(1327) [000000000107F510] NORMAL Get Element Status Operation completed with hr - 0x0
    0F18 1108 02/12 20:27:30.566 30 dpmdevicelayer.cpp(190) [000000000107F510] NORMAL Source element is empty after move
    0F18 1108 02/12 20:27:30.566 30 dpmdevicelayer.cpp(1144) [000000000107F510] NORMAL Calling InitializeElementStatus for element type - 2, first element - 0, Number of elements
    0F18 1108 02/12 20:27:30.566 30 system.cpp(141) WARNING Failed: Hr: = [0x80070001] IoControl 304018 failed!
    0F18 1108 02/12 20:27:30.566 30 dpmdevicelayer.cpp(1164) [000000000107F510] NORMAL Hr: = [0x80070001] InitializeElementStatus failed with non critical error, ignoring failure
    0F18 1108 02/12 20:27:30.566 30 dpmdevicelayer.cpp(1189) [000000000107F510] NORMAL Calling IOCTL_CHANGER_GET_ELEMENT_STATUS for element type - 2, first element - 0, Number of elements - 1
    0F18 1108 02/12 20:27:30.832 30 dpmdevicelayer.cpp(1203) [000000000107F510] NORMAL IOCTL_CHANGER_GET_ELEMENT_STATUS Succeeded
    0F18 1108 02/12 20:27:30.832 30 dpmdevicelayer.cpp(1327) [000000000107F510] NORMAL Get Element Status Operation completed with hr - 0x0
    0F18 1108 02/12 20:27:30.832 30 dpmdevicelayer.cpp(204) [000000000107F510] NORMAL Destination element is full after move
    0F18 1108 02/12 20:27:30.832 30 dpmdevicelayer.cpp(207) [000000000107F510] NORMAL Move Media operation complted successfully
    0F18 1108 02/12 20:27:30.832 30 dpmdevicelayer.cpp(214) [000000000107F510] NORMAL Move media between slot and drive Operation completed with hr - 0x0
    0F18 1108 02/12 20:27:30.832 30 dpmdevicelayer.cpp(1648) [000000000107F510] NORMAL DPM Mount-Write-Read-Dismount Operation completed with hr - 0x0
    0F18 1108 02/12 20:27:30.832 30 dpmlibrarytesttool.cpp(727) NORMAL Succeeded
    0F18 1108 02/12 20:27:30.832 30 dpmlibrarytesttool.cpp(753) NORMAL Result: 4 tests completed successfully, 0 failed
    0F18 1108 02/12 20:27:30.832 30 dpmlibrarytesttool.cpp(1004) NORMAL Operation completed successfully....
    0F18 1108 02/12 20:27:30.832 30 dpmlibrarytesttool.cpp(1005) NORMAL VERBOSE INFORMATION FILE:
    0F18 1108 02/12 20:27:30.832 30 dpmlibrarytesttool.cpp(1005) NORMAL C:\DPMTapeLibraryCompatibilityCheckerTool-Beta\amd64\LibraryTestTool-5-Curr.errlog

    Thursday, February 12, 2015 8:33 PM
  • Hi,

    OK - the tool results were good - so basic library functionality seems to work ok.  Would need details from dpmra logs after a tape backup failure to see why the backup failing with the tape library not functioning error. If you perform ad-hoc tape backup (right-click a data source and make recovery point to tape) does that fail consistently ?


    Please remember to click “Mark as Answer” on the post that helps you, and to click “Unmark as Answer” if a marked post does not actually answer your question. This can be beneficial to other community members reading the thread. Regards, Mike J. [MSFT] This posting is provided "AS IS" with no warranties, and confers no rights.

    Thursday, February 12, 2015 9:13 PM
    Moderator