Failed to open image file

mdru2000 · Wed Oct 09, 2013 2:19 pm

I have 2 X 3TB drive that is configured in a hardware mirror configuration. I can successfully create the image file but it show a red status. The console displays the message "Failed to open image file". When the same drive is acting in a non mirrored configuration, the issue does not exists. The hard disk are Seagate ST3000DM001-9YN166. Logs entries posted below. Appreciate any feedback into this issue.

-----Log Begins-------
10/9 9:43:42.863 7ec Ssc: *** SscScsi_InquiryHandler: INQUIRY VPD page 0xb0 is not supported!
10/9 9:45:01.110 1118 FileBrowser: Return param: 'FileSize' = 'Failed: invalid path provided'
10/9 9:45:09.674 1118 FileBrowser: Return param: 'FileSize' = 'Failed: invalid path provided'
10/9 9:45:09.752 1118 FileBrowser: Return param: 'FileSize' = 'Failed: invalid path provided'
10/9 9:45:10.017 1118 IMG: ImageFile_CreateRawFile: File 'E:\starwind.img', size: 512000 MB, flags 0x240...
10/9 9:45:10.220 1118 IMG: *** ImageFile_CreateRawFile: SetEndOfFile() failed. Status (0) !
10/9 9:45:10.220 1118 IMG: ImageFile_CreateRawFile: Zero out only the first and the last 1MB of the image.
10/9 9:45:10.220 1118 IMG: ImageFile_CreateRawFile: WARNING: This can damage security if the file image is exported directly to untrusted clients!
10/9 9:45:10.454 1118 IMG: ImageFile_CreateRawFile: Progress: 99.9% (511999 MB, elapsed 0 sec, remain ~0 sec)...
10/9 9:45:10.532 1118 IMG: ImageFile_CreateRawFile: File 'E:\starwind.img' created. Total elapsed time = 0 sec.
10/9 9:45:11.702 1118 FileBrowser: Return param: 'FileSize' = '536870912000'
10/9 9:45:11.764 1118 FileBrowser: Return param: 'FileSize' = '536870912000'
10/9 9:45:11.826 1118 FileBrowser: Return param: 'FileSize' = '536870912000'
10/9 9:45:21.482 1118 Srv: *LICENSE*: Cache size for the device is limited to 512 MBs.
10/9 9:45:21.482 1118 Tgt: : parameter 'file', value 'My Computer\E\starwind.img'.
10/9 9:45:21.482 1118 Tgt: : parameter 'asyncmode', value 'yes'.
10/9 9:45:21.482 1118 Tgt: : parameter 'CacheMode', value 'wb'.
10/9 9:45:21.482 1118 Tgt: : parameter 'CacheSizeMB', value '512'.
10/9 9:45:21.482 1118 IMG: SscPort_Create: Opening device 'ImageFile4', image file 'My Computer\E\starwind.img', buffering=0, async=1, readonly=0.
10/9 9:45:21.748 1118 IMG: ImageFile_Create: 'E:\starwind.img': type 0, geometry: sct 16, trks 32, cyl 2048000, TotalSectors: 1048576000
10/9 9:45:21.748 1118 Ssc: *** SscScsi_GetVolumeSectorSize: '\\.\E:' IOCTL_STORAGE_QUERY_PROPERTY(StorageAccessAlignmentProperty) failed (error 50)
10/9 9:45:21.748 1118 IMG: ImageFile_Create: VendorId 'ROCKET ', ProductId 'IMAGEFILE ', Revision '0001', Serial 'F52A3EFD788D92FC'
10/9 9:45:21.748 1118 CACHE: *** SWCache_Create: Cache parameters: Mode = 1, Flags = 0, Size = 512 MB, SectorSize = 512, BlockExpiryPeriod = 0, DiskSizeInSectors = 1048576000
10/9 9:45:22.138 1118 IMG: *** ImageFile_CheckIsFileInitialized: ReadFile(0,512) failed (87).
10/9 9:45:22.138 1118 IMG: *** ImageFile_Create: Error checking if disk is initialized with data: error code '-1073741823'.
10/9 9:45:22.496 1118 IMG: *** SscPort_Create: Create('My Computer\E\starwind.img') error (0xc0000001)!
10/9 9:45:22.496 1118 SCSI: Failed to create 'ImageFile4' device.
10/9 9:45:22.496 1118 Srv: Device 'ImageFile4' - open failed!
10/9 9:45:22.496 1118 conf: Device [0x0000000043E9E6D0] 'ImageFile4' has been created.
10/9 9:45:22.528 1118 Srv: Updating target 00000000021ED440 'iqn.2008-08.com.starwindsoftware:mysan.mysan.local', device list 'ImageFile1,ImageFile3,ImageFile4'...
10/9 9:45:22.528 1118 Tgt: : parameter 'file', value 'My Computer\E\starwind.img'.
10/9 9:45:22.528 1118 Tgt: : parameter 'asyncmode', value 'yes'.
10/9 9:45:22.528 1118 Tgt: : parameter 'CacheMode', value 'wb'.
10/9 9:45:22.528 1118 Tgt: : parameter 'CacheSizeMB', value '512'.
10/9 9:45:22.528 1118 Tgt: : parameter '#_TargetName_#', value 'iqn.2008-08.com.starwindsoftware:mysan.mysan.local'.
10/9 9:45:22.528 1118 IMG: SscPort_Create: Opening device 'ImageFile4', image file 'My Computer\E\starwind.img', buffering=0, async=1, readonly=0.
10/9 9:45:22.528 1118 IMG: ImageFile_Create: 'E:\starwind.img': type 0, geometry: sct 16, trks 32, cyl 2048000, TotalSectors: 1048576000
10/9 9:45:22.528 1118 Ssc: *** SscScsi_GetVolumeSectorSize: '\\.\E:' IOCTL_STORAGE_QUERY_PROPERTY(StorageAccessAlignmentProperty) failed (error 50)
10/9 9:45:22.528 1118 IMG: ImageFile_Create: VendorId 'ROCKET ', ProductId 'IMAGEFILE ', Revision '0001', Serial 'F52A3EFD788D92FC'
10/9 9:45:22.528 1118 CACHE: *** SWCache_Create: Cache parameters: Mode = 1, Flags = 0, Size = 512 MB, SectorSize = 512, BlockExpiryPeriod = 0, DiskSizeInSectors = 1048576000
10/9 9:45:23.183 1118 IMG: *** ImageFile_CheckIsFileInitialized: ReadFile(0,512) failed (87).
10/9 9:45:23.183 1118 IMG: *** ImageFile_Create: Error checking if disk is initialized with data: error code '-1073741823'.
10/9 9:45:23.838 1118 IMG: *** SscPort_Create: Create('My Computer\E\starwind.img') error (0xc0000001)!
10/9 9:45:23.838 1118 SCSI: Failed to create 'ImageFile4' device.
10/9 9:45:23.838 1118 Srv: Device 'ImageFile4' - open failed!
10/9 9:45:23.838 1118 Tgt: : * cannot open the device 'ImageFile4'. It is not active.
10/9 9:45:23.838 1118 IMG: SscPort_Create: Opening device 'ImageFile4', image file 'My Computer\E\starwind.img', buffering=0, async=1, readonly=0.
10/9 9:45:23.838 1118 IMG: ImageFile_Create: 'E:\starwind.img': type 0, geometry: sct 16, trks 32, cyl 2048000, TotalSectors: 1048576000
10/9 9:45:23.838 1118 Ssc: *** SscScsi_GetVolumeSectorSize: '\\.\E:' IOCTL_STORAGE_QUERY_PROPERTY(StorageAccessAlignmentProperty) failed (error 50)
10/9 9:45:23.838 1118 IMG: ImageFile_Create: VendorId 'ROCKET ', ProductId 'IMAGEFILE ', Revision '0001', Serial 'F52A3EFD788D92FC'
10/9 9:45:23.838 1118 CACHE: *** SWCache_Create: Cache parameters: Mode = 1, Flags = 0, Size = 512 MB, SectorSize = 512, BlockExpiryPeriod = 0, DiskSizeInSectors = 1048576000
10/9 9:45:24.228 1118 IMG: *** ImageFile_CheckIsFileInitialized: ReadFile(0,512) failed (87).
10/9 9:45:24.228 1118 IMG: *** ImageFile_Create: Error checking if disk is initialized with data: error code '-1073741823'.
10/9 9:45:24.509 1118 IMG: *** SscPort_Create: Create('My Computer\E\starwind.img') error (0xc0000001)!
10/9 9:45:24.509 1118 SCSI: Failed to create 'ImageFile4' device.
10/9 9:45:24.509 1118 Tgt: iqn.2008-08.com.starwindsoftware:mysan.mysan.local: attached device ImageFile4 (LUNs found: 0).
10/9 9:45:24.524 1118 conf: Target [0x00000000021ED440] 'iqn.2008-08.com.starwindsoftware:mysan.mysan.local' has been updated, 3 devices attached.
----End Log----

Sun Oct 13, 2013 10:34 am

Can I ask you to doublecheck if the image file that you are trying to use is not reserve by anything else?

mdru2000 · Sun Oct 13, 2013 6:40 pm

Update..
Issue was narrowed down to the size of the "Bytes Per Sector" For some reason when the disk are configured as RAID 1, the raid controller shows the Bytes Per Sector as 1024 compare to 512 when they are configured as independent disk. I was not able to find a way to change this in the RAID controller settings. I'm using the on-board AMD controller in the HP Proliant MicroServer. As a workaround, I use Windows to create a mirror volume using the same Seagate ST3000DM001-9YN166 hard disks, interestingly enough Windows set the Bytes Per Sector size to 512. Will need to see what the performance difference is with hardware raid vs software raid.

Tue Oct 15, 2013 3:01 pm

Well... that`s interesting.
Anyway I want to say that we all here appeciate your heads up, and I`m pretty sure that this information will definitelly be usefull for someone.