The log file name: 'logs\starwind-20120921-230836.log'
9/21 23:08:36.171 cac Srv: StarWind iSCSI SAN Software v6.0.0 (Build 20120828, [SwSAN], Win64)
9/21 23:08:36.171 cac Srv: Built Aug 28 2012 14:01:15
9/21 23:08:36.171 cac Srv: Windows Server 2008 Unknown Edition Service Pack 2 (Build 6002)
9/21 23:08:36.171 cac Srv: System CPU count: 4
9/21 23:08:36.171 cac Srv: CPU affinity mask 0xf from 0xf.
9/21 23:08:36.171 cac Srv: Licensed to: 'Oliver Richter', 'AlphaSoft Computer Security GmbH', (#2, 0, 80200000, 0).
9/21 23:08:36.171 cac Srv: Config file: 'StarWind.cfg'
9/21 23:08:36.186 cac conf: Variable 'LogLevel' is set to '1'.
9/21 23:08:36.186 cac conf: Variable 'LogMask' is set to '0x8fffffff'.
9/21 23:08:36.186 cac conf: Variable 'LogRotateSize' is set to '100'.
9/21 23:08:36.264 cac conf: Variable 'LogRotateKeepLastFiles' is set to '5'.
9/21 23:08:36.342 cac conf: Variable 'UpdatePeriod' is set to '7'.
9/21 23:08:36.420 cac conf: Variable 'UpdateHost' is set to 'www.starwindsoftware.com'.
9/21 23:08:36.561 cac conf: Variable 'UpdatePage' is set to '/updatetracker/index.php'.
9/21 23:08:36.639 cac conf: Variable 'UpdatePort' is set to '80'.
9/21 23:08:36.717 cac conf: Variable 'UpdateCopyId' is set to '4A9E5B3F-CDFF-4D4A-8CFD326EDB238A2C'.
9/21 23:08:36.795 cac conf: Variable 'UpdateLastRequest' is set to '20120917'.
9/21 23:08:36.873 cac conf: Variable 'Port' is set to '3260'.
9/21 23:08:37.575 cac conf: Variable 'Interface' is set to '0.0.0.0'.
9/21 23:08:38.277 cac conf: Variable 'BCastEnable' is set to 'yes'.
9/21 23:08:38.979 cac conf: Variable 'BCastInterface' is set to '0.0.0.0'.
9/21 23:08:39.681 cac conf: Variable 'BCastPort' is set to '3261'.
9/21 23:08:40.383 cac conf: Variable 'nolisten' is set to '192.168.100.7:3260'.
9/21 23:08:40.773 cac conf: Variable 'Login' is set to 'root'.
9/21 23:08:40.851 cac conf: Variable 'Password' is set to '##evVRsIJtRmAEEd2sCslZDg=='.
9/21 23:08:40.929 cac conf: Variable 'MinBufferSize' is set to '65536'.
9/21 23:08:41.007 cac conf: Variable 'AlignmentMask' is set to '0x0000'.
9/21 23:08:41.085 cac conf: Variable 'MaxPendingRequests' is set to '64'.
9/21 23:08:41.163 cac conf: Variable 'iScsiPingPeriod' is set to '0'.
9/21 23:08:41.303 cac conf: Variable 'LocalizationDir' is set to 'Localizations'.
9/21 23:08:41.381 cac conf: Variable 'DataBaseRoot' is set to '.\NotifyDB'.
9/21 23:08:41.459 cac conf: Invalid variable 'DBRotationDays'.
9/21 23:08:41.537 cac conf: Variable 'DBFileSizeDays' is set to '1'.
9/21 23:08:41.615 cac conf: Variable 'PerformanceMonitorEnabled' is set to 'yes'.
9/21 23:08:41.756 cac conf: Variable 'PerformanceRoot' is set to '.\PerformanceDB'.
9/21 23:08:41.834 cac conf: Variable 'FSMThresholdPercent' is set to '30'.
9/21 23:08:41.912 cac conf: Variable 'FSMCheckPeriodSeconds' is set to '30'.
9/21 23:08:41.990 cac conf: Variable 'FSMEnabled' is set to 'yes'.
9/21 23:08:42.068 cac EventDB: wsRootDir = .\NotifyDB
9/21 23:08:42.146 cac EventDB: wsRootDir = .\NotifyDB
9/21 23:08:42.271 cac FileBrowser: Directory loaded: *|cdmfv|Event Logs|txt,log
9/21 23:08:42.349 cac FileBrowser: Directory loaded: *|cdmfv|Headers|swdsk
9/21 23:08:42.427 cac FileBrowser: Directory loaded: *|v|My Computer|*
9/21 23:08:42.505 cac FileBrowser: Directory loaded: *|v|Image Files|img,dat
9/21 23:08:42.614 cac Sw: DllMain: Initialize CDDS task descriptor...
9/21 23:08:42.770 cac Sw: DllMain: CDDS task has has been reset: IsTaskScheduled: 0, SPStatus: 0 
9/21 23:08:42.926 cac Sw: DllMain: CDDS task descriptor has been initialized!
9/21 23:08:43.082 cac Sw -> Browser_InitDirInfo: >>
9/21 23:08:43.238 cac Sw: Browser_InitDirInfo: Parse ImageDir: '*|cmdfv|My Computer|spbitmap'
9/21 23:08:44.579 cac Sw: *Browser_InitDirInfo: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'My Computer'
	Extensions: 'spbitmap'
9/21 23:08:44.829 cac Sw <- Browser_InitDirInfo: return (0x1)
9/21 23:08:44.985 cac Sw -> Browser_InitDirInfo: >>
9/21 23:08:45.266 cac Sw: Browser_InitDirInfo: Parse ImageDir: '*|cmdfv|Metadata|spmetadata'
9/21 23:08:45.484 cac Sw: *Browser_InitDirInfo: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'Metadata'
	Extensions: 'spmetadata'
9/21 23:08:45.625 cac Sw <- Browser_InitDirInfo: return (0x1)
9/21 23:08:45.843 cac Sw -> Browser_InitDirInfo: >>
9/21 23:08:46.061 cac Sw: Browser_InitDirInfo: Parse ImageDir: '*|cmdfv|Data|spdata'
9/21 23:08:46.217 cac Sw: *Browser_InitDirInfo: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'Data'
	Extensions: 'spdata'
9/21 23:08:46.373 cac Sw <- Browser_InitDirInfo: return (0x1)
9/21 23:08:46.592 cac Sw: SscPort_Init: (build 20120828, built Aug 28 2012 14:01:38)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '0001'
9/21 23:08:46.748 cac conf: 'DDDisk.dll' loaded successfully.
9/21 23:08:46.841 cac Sw: DllMain: Initialize CDDS task descriptor...
9/21 23:08:46.997 cac Sw: DllMain: CDDS task has has been reset: IsTaskScheduled: 0, SPStatus: 0 
9/21 23:08:47.153 cac Sw: DllMain: CDDS task descriptor has been initialized!
9/21 23:08:47.309 cac Sw -> Browser_InitDirInfo: >>
9/21 23:08:47.465 cac Sw: Browser_InitDirInfo: Parse ImageDir: '*|cmdfv|My Computer|spdata'
9/21 23:08:47.621 cac Sw: *Browser_InitDirInfo: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'My Computer'
	Extensions: 'spdata'
9/21 23:08:47.777 cac Sw <- Browser_InitDirInfo: return (0x1)
9/21 23:08:47.933 cac Sw -> Browser_InitDirInfo: >>
9/21 23:08:48.089 cac Sw: Browser_InitDirInfo: Parse ImageDir: '*|cmdfv|Metadata|spmetadata'
9/21 23:08:48.245 cac Sw: *Browser_InitDirInfo: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'Metadata'
	Extensions: 'spmetadata'
9/21 23:08:48.401 cac Sw <- Browser_InitDirInfo: return (0x1)
9/21 23:08:48.557 cac Sw: SscPort_Init: (build 20120828, built Aug 28 2012 14:01:38)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '0001'
9/21 23:08:48.776 cac conf: 'DDDiskEx.dll' loaded successfully.
9/21 23:08:48.916 cac conf: Failed to load 'DDDiskLFS.dll' (error 126).
9/21 23:08:49.010 cac RAM: SscPort_Init: (build 20120828, built Aug 28 2012 14:00:50)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '0001'
9/21 23:08:49.166 cac conf: 'RamDisk.dll' loaded successfully.
9/21 23:08:49.259 cac Sw -> Browser_InitDirInfo: >>
9/21 23:08:49.478 cac Sw: Browser_InitDirInfo: Parse ImageDir: '*|cmdfv|My Computer|img'
9/21 23:08:49.634 cac Sw: *Browser_InitDirInfo: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'My Computer'
	Extensions: 'img'
9/21 23:08:49.790 cac Sw <- Browser_InitDirInfo: return (0x1)
9/21 23:08:49.946 cac IMG: SscPort_Init: (build 20120828, built Aug 28 2012 14:00:51)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '0001'
	Volumes   = 0
9/21 23:08:50.102 cac conf: 'ImageFile.dll' loaded successfully.
9/21 23:08:50.180 cac Sw: *Browser_InitDirInfo: 
	Dirname: '*'
	Rights: 0x18
	Alias: 'My Computer'
	Extensions: 'mds,iso'
9/21 23:08:50.398 cac conf: 'VirtualDvd.dll' loaded successfully.
9/21 23:08:50.554 cac IBV: *SscPort_Init: Unused parameter: 'module'
9/21 23:08:50.710 cac IBV: *SscPort_Init: Unused parameter: 'type'
9/21 23:08:50.866 cac Sw: *Browser_InitDirInfo: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'My Computer'
	Extensions: 'ibv'
9/21 23:08:51.022 cac Sw: *Browser_InitDirInfo: 
	Dirname: '*'
	Rights: 0x1e
	Alias: 'Snapshots'
	Extensions: 'ibvss'
9/21 23:08:51.178 cac Sw: *Browser_InitDirInfo: 
	Dirname: '*'
	Rights: 0x8
	Alias: 'Database'
	Extensions: 'ibvdb'
9/21 23:08:51.334 cac Sw: *Browser_InitDirInfo: 
	Dirname: '*'
	Rights: 0x9
	Alias: 'Image Files'
	Extensions: 'img,dat'
9/21 23:08:51.490 cac IBV: SscPort_Init: (build 20120828, built Aug 28 2012 14:00:55)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '0001'
	ImageDir  = '*\'
	ExeDir    = 'C:\Program Files\StarWind Software\StarWind\'
9/21 23:08:51.646 cac conf: 'IBVolume.dll' loaded successfully.
9/21 23:08:51.740 cac Sw: *Browser_InitDirInfo: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'Mirror Volumes'
	Extensions: 'mir'
9/21 23:08:51.958 cac Sw: *Browser_InitDirInfo: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'Image Files'
	Extensions: 'img,dat'
9/21 23:08:52.114 cac conf: 'Mirror.dll' loaded successfully.
9/21 23:08:52.114 cac DSKBR: SscPort_Init: (built Aug 28 2012 14:00:53)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '0001'
	ExeDir    = 'C:\Program Files\StarWind Software\StarWind\'
9/21 23:08:52.270 cac conf: 'DiskBridge.dll' loaded successfully.
9/21 23:08:52.379 cac VTAPE: SscPort_Init: Parse ImageDir: '*|cmdfv|My Computer|vtl'
9/21 23:08:52.535 cac VTAPE: *SscPort_Init: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'My Computer'
	Extensions: 'vtl'
9/21 23:08:52.691 cac VTAPE: SscPort_Init: 	Comment : ''
9/21 23:08:52.847 cac VTAPE: *** SscPort_Init: Logging level is set to 1.
9/21 23:08:53.003 cac VTAPE: *** SscPort_Init: DBG_ERROR   statements are ON.
9/21 23:08:53.159 cac VTAPE: SscPort_Init: (built Sep  9 2010 11:29:15)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '    '
	DefImageDir  = '*\'
	Volumes   = 0
	ExeDir    = 'C:\Program Files\StarWind Software\StarWind\'
9/21 23:08:53.315 cac VTAPE: *** TapeEmulCtrlApiStart: StarWind VTL License Type is 3
9/21 23:08:53.471 cac VTAPE: *** TapeEmulCtrlApiStart: This Enterprise Edition supports a maximum of 64 devices, each up to 2097152 MB in length.
9/21 23:08:53.627 cac conf: 'VirtualTape.dll' loaded successfully.
9/21 23:08:53.737 cac HA: *SscPort_InitEx: Unused parameter: 'module'
9/21 23:08:53.955 cac HA: *SscPort_InitEx: Unused parameter: 'type'
9/21 23:08:54.111 cac Sw -> Browser_InitDirInfo: >>
9/21 23:08:54.267 cac Sw: Browser_InitDirInfo: Parse ImageDir: '*|cmdfv|My Computer|img'
9/21 23:08:54.423 cac Sw: *Browser_InitDirInfo: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'My Computer'
	Extensions: 'img'
9/21 23:08:54.579 cac Sw <- Browser_InitDirInfo: return (0x1)
9/21 23:08:54.735 cac HA: SscPort_InitEx: OS major version more then 5, then will be used internal transport.9/21 23:08:54.953 cac Sw -> Browser_InitDirInfo: >>
9/21 23:08:55.109 cac Sw: Browser_InitDirInfo: Parse ImageDir: '*|cdmfv|Headers|swdsk'
9/21 23:08:55.265 cac Sw: *Browser_InitDirInfo: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'Headers'
	Extensions: 'swdsk'
9/21 23:08:55.421 cac Sw <- Browser_InitDirInfo: return (0x1)
9/21 23:08:55.577 cac Sw -> Browser_InitDirInfo: >>
9/21 23:08:55.733 cac Sw: Browser_InitDirInfo: Parse ImageDir: 'Device Headers|cdmfv|DefaultHeaderPath|swdsk'
9/21 23:08:55.952 cac Sw: *Browser_InitDirInfo: 
	Dirname: 'C:\Program Files\StarWind Software\StarWind\Device Headers'
	Rights: 0x1f
	Alias: 'DefaultHeaderPath'
	Extensions: 'swdsk'
9/21 23:08:56.108 cac Sw <- Browser_InitDirInfo: return (0x1)
9/21 23:08:56.264 cac HA: SscPort_InitEx: (build 20120828, built Aug 28 2012 14:00:52)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '0001'
	DefImageDir  = '*\'
	Volumes   = 0
	ExeDir    = 'C:\Program Files\StarWind Software\StarWind\'
9/21 23:08:56.420 cac HA: CHADevice::InitializeModule: Params(p_hCxxLogger = 0x0000000000AC37E0, p_lDbgLevel = 1, p_pCallback = 0x0000000000A317F0, p_hPluginHandle = 0x00000000023DE1C0, p_ScsiTransportType = 3) ENTERed
9/21 23:08:56.498 cac conf: 'HAImage.dll' loaded successfully.
9/21 23:08:56.591 cac Tgt: : parameter 'file', value 'My Computer\D\ELG\ELG-DC1-LWC.img'.
9/21 23:08:56.669 cac Tgt: : parameter 'asyncmode', value 'yes'.
9/21 23:08:56.747 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:08:56.825 cac Tgt: : parameter 'CacheMode', value 'none'.
9/21 23:08:56.966 cac IMG: SscPort_Create: Opening device 'ImageFile2', image file 'My Computer\D\ELG\ELG-DC1-LWC.img', buffering=0, async=1, readonly=0.
9/21 23:08:57.122 cac IMG: ImageFile_Create: 'D:\ELG\ELG-DC1-LWC.img': type 0, geometry: sct 16, trks 32, cyl 282624, TotalSectors: 144703488
9/21 23:08:57.278 cac Ssc: *** SscScsi_GetVolumeSectorSize: '\\.\D:' IOCTL_STORAGE_QUERY_PROPERTY(StorageAccessAlignmentProperty) failed (error 1)
9/21 23:08:57.434 cac IMG: ImageFile_Create: VendorId 'ROCKET  ', ProductId 'IMAGEFILE       ', Revision '0001', Serial '5B80D6A57CB6B0B1'
9/21 23:08:57.590 cac conf: Device [0x0000000002405130] 'ImageFile2' has been created.
9/21 23:08:57.668 cac Tgt: : parameter 'file', value 'My Computer\D\ELG\ELG-DC1-DriveU.img'.
9/21 23:08:57.746 cac Tgt: : parameter 'asyncmode', value 'yes'.
9/21 23:08:57.824 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:08:57.964 cac Tgt: : parameter 'CacheMode', value 'none'.
9/21 23:08:58.042 cac IMG: SscPort_Create: Opening device 'ImageFile3', image file 'My Computer\D\ELG\ELG-DC1-DriveU.img', buffering=0, async=1, readonly=0.
9/21 23:08:58.198 cac IMG: ImageFile_Create: 'D:\ELG\ELG-DC1-DriveU.img': type 0, geometry: sct 16, trks 32, cyl 819200, TotalSectors: 419430400
9/21 23:08:58.354 cac Ssc: *** SscScsi_GetVolumeSectorSize: '\\.\D:' IOCTL_STORAGE_QUERY_PROPERTY(StorageAccessAlignmentProperty) failed (error 1)
9/21 23:08:58.510 cac IMG: ImageFile_Create: VendorId 'ROCKET  ', ProductId 'IMAGEFILE       ', Revision '0001', Serial '6E95693DFED8A255'
9/21 23:08:58.666 cac conf: Device [0x0000000002405F70] 'ImageFile3' has been created.
9/21 23:08:58.744 cac Tgt: : parameter 'file', value 'My Computer\D\ELG\SQL01-SRV-LWD.img'.
9/21 23:08:58.822 cac Tgt: : parameter 'asyncmode', value 'yes'.
9/21 23:08:58.900 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:08:58.978 cac Tgt: : parameter 'CacheMode', value 'wb'.
9/21 23:08:59.056 cac Tgt: : parameter 'CacheSizeMB', value '1024'.
9/21 23:08:59.134 cac Tgt: : parameter 'CacheBlockExpiryPeriodMS', value '5000'.
9/21 23:08:59.212 cac IMG: SscPort_Create: Opening device 'ImageFile4', image file 'My Computer\D\ELG\SQL01-SRV-LWD.img', buffering=0, async=1, readonly=0.
9/21 23:08:59.368 cac IMG: ImageFile_Create: 'D:\ELG\SQL01-SRV-LWD.img': type 0, geometry: sct 16, trks 32, cyl 819200, TotalSectors: 419430400
9/21 23:08:59.524 cac Ssc: *** SscScsi_GetVolumeSectorSize: '\\.\D:' IOCTL_STORAGE_QUERY_PROPERTY(StorageAccessAlignmentProperty) failed (error 1)
9/21 23:08:59.680 cac IMG: ImageFile_Create: VendorId 'ROCKET  ', ProductId 'IMAGEFILE       ', Revision '0001', Serial 'C2EB97D839CB1C32'
9/21 23:08:59.836 cac CACHE: *** SWCache_Create: Cache parameters: Mode = 1, Flags = 0, Size = 1024 MB, SectorSize = 512, BlockExpiryPeriod = 5000, DiskSizeInSectors = 419430400
9/21 23:09:00.351 cac conf: Device [0x00000000024073E0] 'ImageFile4' has been created.
9/21 23:09:00.429 cac Tgt: : parameter 'file', value 'My Computer\D\ELG\SQL01-SRV.img'.
9/21 23:09:00.507 cac Tgt: : parameter 'asyncmode', value 'yes'.
9/21 23:09:00.585 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:00.663 cac Tgt: : parameter 'CacheMode', value 'wb'.
9/21 23:09:00.741 cac Tgt: : parameter 'CacheSizeMB', value '1024'.
9/21 23:09:00.819 cac Tgt: : parameter 'CacheBlockExpiryPeriodMS', value '5000'.
9/21 23:09:00.897 cac IMG: SscPort_Create: Opening device 'ImageFile5', image file 'My Computer\D\ELG\SQL01-SRV.img', buffering=0, async=1, readonly=0.
9/21 23:09:01.053 cac IMG: ImageFile_Create: 'D:\ELG\SQL01-SRV.img': type 0, geometry: sct 16, trks 32, cyl 716800, TotalSectors: 367001600
9/21 23:09:01.209 cac Ssc: *** SscScsi_GetVolumeSectorSize: '\\.\D:' IOCTL_STORAGE_QUERY_PROPERTY(StorageAccessAlignmentProperty) failed (error 1)
9/21 23:09:01.365 cac IMG: ImageFile_Create: VendorId 'ROCKET  ', ProductId 'IMAGEFILE       ', Revision '0001', Serial 'A447C1C3C56DBA0B'
9/21 23:09:01.521 cac CACHE: *** SWCache_Create: Cache parameters: Mode = 1, Flags = 0, Size = 1024 MB, SectorSize = 512, BlockExpiryPeriod = 5000, DiskSizeInSectors = 367001600
9/21 23:09:02.051 cac conf: Device [0x000000000241A840] 'ImageFile5' has been created.
9/21 23:09:02.129 cac Tgt: : parameter 'file', value 'My Computer\D\ELG\VID01-SRV.img'.
9/21 23:09:02.207 cac Tgt: : parameter 'asyncmode', value 'yes'.
9/21 23:09:02.285 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:02.363 cac Tgt: : parameter 'CacheMode', value 'wt'.
9/21 23:09:02.441 cac Tgt: : parameter 'CacheSizeMB', value '512'.
9/21 23:09:02.519 cac Tgt: : parameter 'CacheBlockExpiryPeriodMS', value '5000'.
9/21 23:09:02.597 cac IMG: SscPort_Create: Opening device 'ImageFile6', image file 'My Computer\D\ELG\VID01-SRV.img', buffering=0, async=1, readonly=0.
9/21 23:09:02.675 cac IMG: ImageFile_Create: 'D:\ELG\VID01-SRV.img': type 0, geometry: sct 16, trks 32, cyl 307208, TotalSectors: 157290496
9/21 23:09:02.831 cac Ssc: *** SscScsi_GetVolumeSectorSize: '\\.\D:' IOCTL_STORAGE_QUERY_PROPERTY(StorageAccessAlignmentProperty) failed (error 1)
9/21 23:09:02.987 cac IMG: ImageFile_Create: VendorId 'ROCKET  ', ProductId 'IMAGEFILE       ', Revision '0001', Serial '838FD190A57BEEBD'
9/21 23:09:03.143 cac CACHE: *** SWCache_Create: Cache parameters: Mode = 3, Flags = 0, Size = 512 MB, SectorSize = 512, BlockExpiryPeriod = 5000, DiskSizeInSectors = 157290496
9/21 23:09:03.518 cac conf: Device [0x0000000002408C70] 'ImageFile6' has been created.
9/21 23:09:03.596 cac Tgt: : parameter 'file', value 'My Computer\D\ELG\VID01-SRV-DATA.img'.
9/21 23:09:03.674 cac Tgt: : parameter 'asyncmode', value 'yes'.
9/21 23:09:03.752 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:03.830 cac Tgt: : parameter 'CacheMode', value 'wt'.
9/21 23:09:03.908 cac Tgt: : parameter 'CacheSizeMB', value '512'.
9/21 23:09:03.986 cac Tgt: : parameter 'CacheBlockExpiryPeriodMS', value '5000'.
9/21 23:09:04.064 cac IMG: SscPort_Create: Opening device 'ImageFile7', image file 'My Computer\D\ELG\VID01-SRV-DATA.img', buffering=0, async=1, readonly=0.
9/21 23:09:04.220 cac IMG: ImageFile_Create: 'D:\ELG\VID01-SRV-DATA.img': type 0, geometry: sct 16, trks 32, cyl 819200, TotalSectors: 419430400
9/21 23:09:04.376 cac Ssc: *** SscScsi_GetVolumeSectorSize: '\\.\D:' IOCTL_STORAGE_QUERY_PROPERTY(StorageAccessAlignmentProperty) failed (error 1)
9/21 23:09:04.532 cac IMG: ImageFile_Create: VendorId 'ROCKET  ', ProductId 'IMAGEFILE       ', Revision '0001', Serial '67AFA7C8326A7657'
9/21 23:09:04.688 cac CACHE: *** SWCache_Create: Cache parameters: Mode = 3, Flags = 0, Size = 512 MB, SectorSize = 512, BlockExpiryPeriod = 5000, DiskSizeInSectors = 419430400
9/21 23:09:05.156 cac conf: Device [0x0000000002409AB0] 'ImageFile7' has been created.
9/21 23:09:05.234 cac Tgt: : parameter 'file', value 'My Computer\D\Windows 7\BUHA01-PC1.img'.
9/21 23:09:05.312 cac Tgt: : parameter 'asyncmode', value 'yes'.
9/21 23:09:05.390 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:05.468 cac Tgt: : parameter 'CacheMode', value 'wt'.
9/21 23:09:05.546 cac Tgt: : parameter 'CacheSizeMB', value '512'.
9/21 23:09:05.624 cac Tgt: : parameter 'CacheBlockExpiryPeriodMS', value '5000'.
9/21 23:09:05.702 cac IMG: SscPort_Create: Opening device 'ImageFile8', image file 'My Computer\D\Windows 7\BUHA01-PC1.img', buffering=0, async=1, readonly=0.
9/21 23:09:05.858 cac IMG: ImageFile_Create: 'D:\Windows 7\BUHA01-PC1.img': type 0, geometry: sct 16, trks 32, cyl 266656, TotalSectors: 136527872
9/21 23:09:06.014 cac Ssc: *** SscScsi_GetVolumeSectorSize: '\\.\D:' IOCTL_STORAGE_QUERY_PROPERTY(StorageAccessAlignmentProperty) failed (error 1)
9/21 23:09:06.170 cac IMG: ImageFile_Create: VendorId 'ROCKET  ', ProductId 'IMAGEFILE       ', Revision '0001', Serial '38ABBAC914D33EB8'
9/21 23:09:06.326 cac CACHE: *** SWCache_Create: Cache parameters: Mode = 3, Flags = 0, Size = 512 MB, SectorSize = 512, BlockExpiryPeriod = 5000, DiskSizeInSectors = 136527872
9/21 23:09:06.716 cac conf: Device [0x000000000240B160] 'ImageFile8' has been created.
9/21 23:09:06.794 cac Srv: Creating target 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv', device list 'ImageFile2,ImageFile3,ImageFile4,ImageFile5,ImageFile6,ImageFile7,ImageFile8'...
9/21 23:09:06.887 cac Tgt: : parameter 'header', value '0'.
9/21 23:09:06.965 cac Tgt: : parameter 'file', value 'My Computer\D\ELG\ELG-DC1-LWC.img'.
9/21 23:09:07.106 cac Tgt: : parameter 'buffering', value 'no'.
9/21 23:09:07.184 cac Tgt: : parameter 'asyncmode', value 'yes'.
9/21 23:09:07.262 cac Tgt: : parameter 'ImageSizeLow', value '1073741824'.
9/21 23:09:07.340 cac Tgt: : parameter 'ImageSizeHigh', value '17'.
9/21 23:09:07.418 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:07.496 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:07.574 cac Tgt: : parameter 'DeviceState', value '1'.
9/21 23:09:07.652 cac Tgt: : parameter 'CacheMode', value 'none'.
9/21 23:09:07.730 cac Tgt: : parameter 'CacheSizeMB', value '0'.
9/21 23:09:07.808 cac Tgt: : parameter 'CacheBlockExpiryPeriodMS', value '0'.
9/21 23:09:07.886 cac Tgt: : parameter '#_TargetName_#', value 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv'.
9/21 23:09:07.964 ea8 IMG: *** ImageFile_ScsiExec: COMPARE_AND_WRITE (0x89) is not supported.
9/21 23:09:07.964 cac SCSI: SCSIOP_COMPARE_AND_WRITE test failed for LUN. We will emulate the SCSIOP.
9/21 23:09:08.042 ea8 Ssc: *** SscScsi_InquiryHandler: INQUIRY VPD page 0xb0 is not supported!
9/21 23:09:08.042 cac SCSI: SCSIOP_WRITE_SAME test failed for LUN. We will emulate the SCSIOP.
9/21 23:09:08.120 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: device ImageFile2: new LUN 0 mapped to target LUN 0.
9/21 23:09:08.198 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: attached device ImageFile2 (found 1 LUNs).
9/21 23:09:08.291 cac Tgt: : parameter 'header', value '0'.
9/21 23:09:08.369 cac Tgt: : parameter 'file', value 'My Computer\D\ELG\ELG-DC1-DriveU.img'.
9/21 23:09:08.447 cac Tgt: : parameter 'buffering', value 'no'.
9/21 23:09:08.525 cac Tgt: : parameter 'asyncmode', value 'yes'.
9/21 23:09:08.603 cac Tgt: : parameter 'ImageSizeLow', value '0'.
9/21 23:09:08.681 cac Tgt: : parameter 'ImageSizeHigh', value '50'.
9/21 23:09:08.759 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:08.837 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:08.915 cac Tgt: : parameter 'DeviceState', value '1'.
9/21 23:09:08.993 cac Tgt: : parameter 'CacheMode', value 'none'.
9/21 23:09:09.134 cac Tgt: : parameter 'CacheSizeMB', value '0'.
9/21 23:09:09.212 cac Tgt: : parameter 'CacheBlockExpiryPeriodMS', value '0'.
9/21 23:09:09.290 cac Tgt: : parameter '#_TargetName_#', value 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv'.
9/21 23:09:09.368 d10 IMG: *** ImageFile_ScsiExec: COMPARE_AND_WRITE (0x89) is not supported.
9/21 23:09:09.368 cac SCSI: SCSIOP_COMPARE_AND_WRITE test failed for LUN. We will emulate the SCSIOP.
9/21 23:09:09.446 d10 Ssc: *** SscScsi_InquiryHandler: INQUIRY VPD page 0xb0 is not supported!
9/21 23:09:09.446 cac SCSI: SCSIOP_WRITE_SAME test failed for LUN. We will emulate the SCSIOP.
9/21 23:09:09.524 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: device ImageFile3: new LUN 0 mapped to target LUN 1.
9/21 23:09:09.602 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: attached device ImageFile3 (found 1 LUNs).
9/21 23:09:09.680 cac Tgt: : parameter 'header', value '0'.
9/21 23:09:09.758 cac Tgt: : parameter 'file', value 'My Computer\D\ELG\SQL01-SRV-LWD.img'.
9/21 23:09:09.836 cac Tgt: : parameter 'buffering', value 'no'.
9/21 23:09:09.914 cac Tgt: : parameter 'asyncmode', value 'yes'.
9/21 23:09:09.992 cac Tgt: : parameter 'ImageSizeLow', value '0'.
9/21 23:09:10.070 cac Tgt: : parameter 'ImageSizeHigh', value '50'.
9/21 23:09:10.148 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:10.226 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:10.304 cac Tgt: : parameter 'DeviceState', value '1'.
9/21 23:09:10.382 cac Tgt: : parameter 'CacheMode', value 'wb'.
9/21 23:09:10.460 cac Tgt: : parameter 'CacheSizeMB', value '1024'.
9/21 23:09:10.538 cac Tgt: : parameter 'CacheBlockExpiryPeriodMS', value '5000'.
9/21 23:09:10.616 cac Tgt: : parameter '#_TargetName_#', value 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv'.
9/21 23:09:10.694 d2c IMG: *** ImageFile_ScsiExec: COMPARE_AND_WRITE (0x89) is not supported.
9/21 23:09:10.694 cac SCSI: SCSIOP_COMPARE_AND_WRITE test failed for LUN. We will emulate the SCSIOP.
9/21 23:09:10.772 d2c Ssc: *** SscScsi_InquiryHandler: INQUIRY VPD page 0xb0 is not supported!
9/21 23:09:10.772 cac SCSI: SCSIOP_WRITE_SAME test failed for LUN. We will emulate the SCSIOP.
9/21 23:09:10.850 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: device ImageFile4: new LUN 0 mapped to target LUN 2.
9/21 23:09:10.928 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: attached device ImageFile4 (found 1 LUNs).
9/21 23:09:11.006 cac Tgt: : parameter 'header', value '0'.
9/21 23:09:11.146 cac Tgt: : parameter 'file', value 'My Computer\D\ELG\SQL01-SRV.img'.
9/21 23:09:11.224 cac Tgt: : parameter 'buffering', value 'no'.
9/21 23:09:11.302 cac Tgt: : parameter 'asyncmode', value 'yes'.
9/21 23:09:11.380 cac Tgt: : parameter 'ImageSizeLow', value '3221225472'.
9/21 23:09:11.458 cac Tgt: : parameter 'ImageSizeHigh', value '43'.
9/21 23:09:11.536 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:11.614 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:11.692 cac Tgt: : parameter 'DeviceState', value '1'.
9/21 23:09:11.770 cac Tgt: : parameter 'CacheMode', value 'wb'.
9/21 23:09:11.848 cac Tgt: : parameter 'CacheSizeMB', value '1024'.
9/21 23:09:11.926 cac Tgt: : parameter 'CacheBlockExpiryPeriodMS', value '5000'.
9/21 23:09:12.004 cac Tgt: : parameter '#_TargetName_#', value 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv'.
9/21 23:09:12.082 d28 IMG: *** ImageFile_ScsiExec: COMPARE_AND_WRITE (0x89) is not supported.
9/21 23:09:12.082 cac SCSI: SCSIOP_COMPARE_AND_WRITE test failed for LUN. We will emulate the SCSIOP.
9/21 23:09:12.160 d28 Ssc: *** SscScsi_InquiryHandler: INQUIRY VPD page 0xb0 is not supported!
9/21 23:09:12.160 cac SCSI: SCSIOP_WRITE_SAME test failed for LUN. We will emulate the SCSIOP.
9/21 23:09:12.238 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: device ImageFile5: new LUN 0 mapped to target LUN 3.
9/21 23:09:12.316 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: attached device ImageFile5 (found 1 LUNs).
9/21 23:09:12.394 cac Tgt: : parameter 'header', value '0'.
9/21 23:09:12.472 cac Tgt: : parameter 'file', value 'My Computer\D\ELG\VID01-SRV.img'.
9/21 23:09:12.550 cac Tgt: : parameter 'buffering', value 'no'.
9/21 23:09:12.628 cac Tgt: : parameter 'asyncmode', value 'yes'.
9/21 23:09:12.706 cac Tgt: : parameter 'ImageSizeLow', value '3223322624'.
9/21 23:09:12.784 cac Tgt: : parameter 'ImageSizeHigh', value '18'.
9/21 23:09:12.862 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:12.940 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:13.018 cac Tgt: : parameter 'DeviceState', value '1'.
9/21 23:09:13.096 cac Tgt: : parameter 'CacheMode', value 'wt'.
9/21 23:09:13.174 cac Tgt: : parameter 'CacheSizeMB', value '512'.
9/21 23:09:13.252 cac Tgt: : parameter 'CacheBlockExpiryPeriodMS', value '5000'.
9/21 23:09:13.330 cac Tgt: : parameter '#_TargetName_#', value 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv'.
9/21 23:09:13.408 d30 IMG: *** ImageFile_ScsiExec: COMPARE_AND_WRITE (0x89) is not supported.
9/21 23:09:13.408 cac SCSI: SCSIOP_COMPARE_AND_WRITE test failed for LUN. We will emulate the SCSIOP.
9/21 23:09:13.486 d30 Ssc: *** SscScsi_InquiryHandler: INQUIRY VPD page 0xb0 is not supported!
9/21 23:09:13.486 cac SCSI: SCSIOP_WRITE_SAME test failed for LUN. We will emulate the SCSIOP.
9/21 23:09:13.564 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: device ImageFile6: new LUN 0 mapped to target LUN 4.
9/21 23:09:13.642 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: attached device ImageFile6 (found 1 LUNs).
9/21 23:09:13.720 cac Tgt: : parameter 'header', value '0'.
9/21 23:09:13.798 cac Tgt: : parameter 'file', value 'My Computer\D\ELG\VID01-SRV-DATA.img'.
9/21 23:09:13.939 cac Tgt: : parameter 'buffering', value 'no'.
9/21 23:09:14.017 cac Tgt: : parameter 'asyncmode', value 'yes'.
9/21 23:09:14.095 cac Tgt: : parameter 'ImageSizeLow', value '0'.
9/21 23:09:14.173 cac Tgt: : parameter 'ImageSizeHigh', value '50'.
9/21 23:09:14.251 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:14.329 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:14.407 cac Tgt: : parameter 'DeviceState', value '1'.
9/21 23:09:14.485 cac Tgt: : parameter 'CacheMode', value 'wt'.
9/21 23:09:14.563 cac Tgt: : parameter 'CacheSizeMB', value '512'.
9/21 23:09:14.641 cac Tgt: : parameter 'CacheBlockExpiryPeriodMS', value '5000'.
9/21 23:09:14.719 cac Tgt: : parameter '#_TargetName_#', value 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv'.
9/21 23:09:14.797 ce8 IMG: *** ImageFile_ScsiExec: COMPARE_AND_WRITE (0x89) is not supported.
9/21 23:09:14.797 cac SCSI: SCSIOP_COMPARE_AND_WRITE test failed for LUN. We will emulate the SCSIOP.
9/21 23:09:14.875 ce8 Ssc: *** SscScsi_InquiryHandler: INQUIRY VPD page 0xb0 is not supported!
9/21 23:09:14.875 cac SCSI: SCSIOP_WRITE_SAME test failed for LUN. We will emulate the SCSIOP.
9/21 23:09:14.953 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: device ImageFile7: new LUN 0 mapped to target LUN 5.
9/21 23:09:15.031 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: attached device ImageFile7 (found 1 LUNs).
9/21 23:09:15.109 cac Tgt: : parameter 'header', value '0'.
9/21 23:09:15.249 cac Tgt: : parameter 'file', value 'My Computer\D\Windows 7\BUHA01-PC1.img'.
9/21 23:09:15.327 cac Tgt: : parameter 'buffering', value 'no'.
9/21 23:09:15.405 cac Tgt: : parameter 'asyncmode', value 'yes'.
9/21 23:09:15.483 cac Tgt: : parameter 'ImageSizeLow', value '1182793728'.
9/21 23:09:15.561 cac Tgt: : parameter 'ImageSizeHigh', value '16'.
9/21 23:09:15.639 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:15.717 cac Tgt: : parameter 'readonly', value 'no'.
9/21 23:09:15.795 cac Tgt: : parameter 'DeviceState', value '1'.
9/21 23:09:15.873 cac Tgt: : parameter 'CacheMode', value 'wt'.
9/21 23:09:15.951 cac Tgt: : parameter 'CacheSizeMB', value '512'.
9/21 23:09:16.029 cac Tgt: : parameter 'CacheBlockExpiryPeriodMS', value '5000'.
9/21 23:09:16.107 cac Tgt: : parameter '#_TargetName_#', value 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv'.
9/21 23:09:16.248 718 IMG: *** ImageFile_ScsiExec: COMPARE_AND_WRITE (0x89) is not supported.
9/21 23:09:16.248 cac SCSI: SCSIOP_COMPARE_AND_WRITE test failed for LUN. We will emulate the SCSIOP.
9/21 23:09:16.326 718 Ssc: *** SscScsi_InquiryHandler: INQUIRY VPD page 0xb0 is not supported!
9/21 23:09:16.326 cac SCSI: SCSIOP_WRITE_SAME test failed for LUN. We will emulate the SCSIOP.
9/21 23:09:16.404 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: device ImageFile8: new LUN 0 mapped to target LUN 6.
9/21 23:09:16.482 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: attached device ImageFile8 (found 1 LUNs).
9/21 23:09:16.560 cac conf: Target [0x0000000002413200] 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv' has been created, 7 devices attached.
9/21 23:09:16.638 cac conf: Reaction of type 'eventlog' [0xFFFFFFFF, 0x0C] loaded successfully.
9/21 23:09:16.716 cac conf: Variable 'DefaultAccessPolicy' is set to 'allow'.
9/21 23:09:16.794 cac Srv: Update tracker options: UpdatePeriod = 7, UpdateLastRequest = 20120917, UpdateHost = www.starwindsoftware.com, UpdatePage = /updatetracker/index.php, UpdatePort = 80, UpdateCopyId = 4A9E5B3F-CDFF-4D4A-8CFD326EDB238A2C
9/21 23:09:16.872 cac Srv: Refreshing device list (complete)...
9/21 23:09:16.950 cac SPTI: '\\?\scsi#disk&ven_adaptec&prod_array#5&448368e&0&040000#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}': 'Adaptec  Array            V1.0': adapter 0, bus 4, target 0, LUN 0; maxTransferLength 262144, alignmentMask 0x0
9/21 23:09:17.028 cac PerformanceDB: CPerformanceCounter::WriteOperationalData called on uninitialized counter(DB entry: 0000000000000000, Record: 0000000000000000).
9/21 23:09:17.106 cac PerformanceDB: CPerformanceCounter::Close exiting with error: WriteOperationalData failed.
9/21 23:09:17.184 cac SPTI: '\\?\scsi#disk&ven_adaptec&prod_array#5&448368e&0&040100#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}': 'Adaptec  Array            V1.0': adapter 0, bus 4, target 1, LUN 0; maxTransferLength 262144, alignmentMask 0x0
9/21 23:09:17.262 cac PerformanceDB: CPerformanceCounter::WriteOperationalData called on uninitialized counter(DB entry: 0000000000000000, Record: 0000000000000000).
9/21 23:09:17.340 cac PerformanceDB: CPerformanceCounter::Close exiting with error: WriteOperationalData failed.
9/21 23:09:17.433 cac SPTI: '\\?\scsi#cdrom&ven_slimtype&prod_dvd_a__ds8a3s#4&3b049e88&0&010100#{53f56308-b6bf-11d0-94f2-00a0c91efb8b}': 'Slimtype DVD A  DS8A3S    HP53': adapter 5, bus 1, target 1, LUN 0; maxTransferLength 131072, alignmentMask 0x1
9/21 23:09:17.433 cac Srv: Found SPTI device '\\?\scsi#cdrom&ven_slimtype&prod_dvd_a__ds8a3s#4&3b049e88&0&010100#{53f56308-b6bf-11d0-94f2-00a0c91efb8b}': 'Slimtype DVD A  DS8A3S    HP53'
9/21 23:09:17.511 d84 bc: Waiting for broadcast UDP packets at 0.0.0.0:3261...
9/21 23:09:17.511 d90 conf: Waiting for Control connections at 0.0.0.0:3261 ...
9/21 23:09:17.511 cac Srv: Waiting for iSCSI connections at 169.254.75.223:3260 ...
9/21 23:09:17.511 cac Srv: Waiting for iSCSI connections at 192.168.99.1:3260 ...
9/21 23:09:17.511 cac Srv: Waiting for iSCSI connections at 192.168.98.1:3260 ...
9/21 23:09:17.511 cac Srv: Waiting for iSCSI connections at 127.0.0.1:3260 ...
9/21 23:09:23.158 cac Srv: Accepted iSCSI connection from 192.168.99.2:56759 to 192.168.99.1:3260. (Id = 0x1)
9/21 23:09:23.158 cac C[1], FREE: Event - CONNECTED.
9/21 23:09:23.158 cac C[1], XPT_UP: T3.
9/21 23:09:23.158 dc8 C[1], XPT_UP: Login request: ISID 0x400001370000, TSIH 0x0000.
9/21 23:09:23.158 dc8 C[1], XPT_UP: Event - LOGIN.
9/21 23:09:23.158 dc8 C[1], IN_LOGIN: T4.
9/21 23:09:23.158 dc8 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:hyp01-srv', accepted 'iqn.1991-05.com.microsoft:hyp01-srv'
9/21 23:09:23.158 dc8 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
9/21 23:09:23.158 dc8 Params: <<< String param 'TargetName': received 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv', accepted 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv'
9/21 23:09:23.158 dc8 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
9/21 23:09:23.158 dc8 Tgt: open 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv': 1 session(s) opened, 0 more allowed.
9/21 23:09:23.158 dc8 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
9/21 23:09:23.158 dc8 Params: >>> AuthMethod=None.
9/21 23:09:23.158 dc8 Params: >>> TargetAlias=HYP01-SRV.
9/21 23:09:23.158 dc8 Params: >>> TargetPortalGroupTag=1.
9/21 23:09:23.158 dcc T[1,1]: session 0x1, connection 0x1 : end of stage 0, next stage 1.
9/21 23:09:23.158 dc8 Params: <<< Enum param 'HeaderDigest': received 'None,CRC32C', accepted 'None'
9/21 23:09:23.158 dc8 Params: <<< Enum param 'DataDigest': received 'None,CRC32C', accepted 'None'
9/21 23:09:23.158 dc8 Params: <<< Numeric param 'ErrorRecoveryLevel': received 2, accepted 0
9/21 23:09:23.158 dc8 Params: <<< Boolean param 'InitialR2T': received No, accepted 0
9/21 23:09:23.158 dc8 Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
9/21 23:09:23.158 dc8 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 65536, accepted 65536
9/21 23:09:23.158 dc8 Params: <<< Numeric param 'MaxBurstLength': received 262144, accepted 262144
9/21 23:09:23.158 dc8 Params: <<< Numeric param 'FirstBurstLength': received 65536, accepted 65536
9/21 23:09:23.158 dc8 Params: <<< Numeric param 'MaxConnections': received 32, accepted 1
9/21 23:09:23.158 dc8 Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
9/21 23:09:23.158 dc8 Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
9/21 23:09:23.158 dc8 Params: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 0
9/21 23:09:23.158 dc8 Params: <<< Numeric param 'DefaultTime2Retain': received 60, accepted 20
9/21 23:09:23.158 dc8 Params: <<< Numeric param 'MaxOutstandingR2T': received 16, accepted 1
9/21 23:09:23.158 dc8 Params: >>> ErrorRecoveryLevel=0.
9/21 23:09:23.158 dc8 Params: >>> MaxConnections=1.
9/21 23:09:23.158 dc8 Params: >>> HeaderDigest=None.
9/21 23:09:23.158 dc8 Params: >>> DataDigest=None.
9/21 23:09:23.158 dc8 Params: >>> InitialR2T=No.
9/21 23:09:23.158 dc8 Params: >>> ImmediateData=Yes.
9/21 23:09:23.158 dc8 Params: >>> MaxRecvDataSegmentLength=262144.
9/21 23:09:23.158 dc8 Params: >>> MaxBurstLength=262144.
9/21 23:09:23.158 dc8 Params: >>> FirstBurstLength=65536.
9/21 23:09:23.158 dc8 Params: >>> DefaultTime2Wait=0.
9/21 23:09:23.158 dc8 Params: >>> DefaultTime2Retain=20.
9/21 23:09:23.158 dc8 Params: >>> MaxOutstandingR2T=1.
9/21 23:09:23.158 dc8 Params: >>> DataPDUInOrder=Yes.
9/21 23:09:23.158 dc8 Params: >>> DataSequenceInOrder=Yes.
9/21 23:09:23.158 dcc T[1,1]: session 0x1, connection 0x1 : end of stage 1, next stage 3.
9/21 23:09:23.158 dcc C[1], IN_LOGIN: Event - LOGIN_ACCEPT.
9/21 23:09:23.158 dcc C[1], LIN: T5.
9/21 23:09:27.979 d90 conf: Accepted control connection from 127.0.0.1:49189.
9/21 23:11:02.125 cac Srv: Interface list has been changed. Refreshing...
9/21 23:11:02.125 cac Srv: Waiting for iSCSI connections at 169.254.75.223:3260 ...
9/21 23:11:02.125 cac Srv: Waiting for iSCSI connections at 192.168.98.1:3260 ...
9/21 23:11:02.125 cac Srv: Waiting for iSCSI connections at 127.0.0.1:3260 ...
9/21 23:11:05.776 cac Srv: Interface list has been changed. Refreshing...
9/21 23:11:05.776 cac Srv: Waiting for iSCSI connections at 169.254.75.223:3260 ...
9/21 23:11:05.776 cac Srv: Waiting for iSCSI connections at 192.168.99.1:3260 ...
9/21 23:11:05.776 cac Srv: Waiting for iSCSI connections at 192.168.98.1:3260 ...
9/21 23:11:05.776 cac Srv: Waiting for iSCSI connections at 127.0.0.1:3260 ...
9/21 23:11:07.897 dc8 C[1], LIN: recvData returned 10054
9/21 23:11:07.897 dc8 C[1], LIN: *** 'recv' thread: recv failed 10058.
9/21 23:11:07.897 dcc Tgt: close 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv': 0 session(s) opened, 1 more allowed.
9/21 23:13:05.023 cac Srv: Accepted iSCSI connection from 192.168.99.2:49152 to 192.168.99.1:3260. (Id = 0x2)
9/21 23:13:05.023 cac C[2], FREE: Event - CONNECTED.
9/21 23:13:05.023 cac C[2], XPT_UP: T3.
9/21 23:13:05.023 c80 C[2], XPT_UP: Login request: ISID 0x400001370001, TSIH 0x0000.
9/21 23:13:05.023 c80 C[2], XPT_UP: Event - LOGIN.
9/21 23:13:05.023 c80 C[2], IN_LOGIN: T4.
9/21 23:13:05.023 c80 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:hyp01-srv', accepted 'iqn.1991-05.com.microsoft:hyp01-srv'
9/21 23:13:05.023 c80 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
9/21 23:13:05.023 c80 Params: <<< String param 'TargetName': received 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv', accepted 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv'
9/21 23:13:05.023 c80 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
9/21 23:13:05.023 c80 Tgt: open 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv': 1 session(s) opened, 0 more allowed.
9/21 23:13:05.023 c80 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
9/21 23:13:05.023 c80 Params: >>> AuthMethod=None.
9/21 23:13:05.023 c80 Params: >>> TargetAlias=HYP01-SRV.
9/21 23:13:05.023 c80 Params: >>> TargetPortalGroupTag=1.
9/21 23:13:05.023 ac4 T[2,1]: session 0x2, connection 0x2 : end of stage 0, next stage 1.
9/21 23:13:05.023 c80 Params: <<< Enum param 'HeaderDigest': received 'None,CRC32C', accepted 'None'
9/21 23:13:05.023 c80 Params: <<< Enum param 'DataDigest': received 'None,CRC32C', accepted 'None'
9/21 23:13:05.023 c80 Params: <<< Numeric param 'ErrorRecoveryLevel': received 2, accepted 0
9/21 23:13:05.023 c80 Params: <<< Boolean param 'InitialR2T': received No, accepted 0
9/21 23:13:05.023 c80 Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
9/21 23:13:05.023 c80 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 65536, accepted 65536
9/21 23:13:05.023 c80 Params: <<< Numeric param 'MaxBurstLength': received 262144, accepted 262144
9/21 23:13:05.023 c80 Params: <<< Numeric param 'FirstBurstLength': received 65536, accepted 65536
9/21 23:13:05.023 c80 Params: <<< Numeric param 'MaxConnections': received 32, accepted 1
9/21 23:13:05.023 c80 Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
9/21 23:13:05.023 c80 Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
9/21 23:13:05.023 c80 Params: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 0
9/21 23:13:05.023 c80 Params: <<< Numeric param 'DefaultTime2Retain': received 60, accepted 20
9/21 23:13:05.023 c80 Params: <<< Numeric param 'MaxOutstandingR2T': received 16, accepted 1
9/21 23:13:05.023 c80 Params: >>> ErrorRecoveryLevel=0.
9/21 23:13:05.023 c80 Params: >>> MaxConnections=1.
9/21 23:13:05.023 c80 Params: >>> HeaderDigest=None.
9/21 23:13:05.023 c80 Params: >>> DataDigest=None.
9/21 23:13:05.023 c80 Params: >>> InitialR2T=No.
9/21 23:13:05.023 c80 Params: >>> ImmediateData=Yes.
9/21 23:13:05.023 c80 Params: >>> MaxRecvDataSegmentLength=262144.
9/21 23:13:05.023 c80 Params: >>> MaxBurstLength=262144.
9/21 23:13:05.023 c80 Params: >>> FirstBurstLength=65536.
9/21 23:13:05.023 c80 Params: >>> DefaultTime2Wait=0.
9/21 23:13:05.023 c80 Params: >>> DefaultTime2Retain=20.
9/21 23:13:05.023 c80 Params: >>> MaxOutstandingR2T=1.
9/21 23:13:05.023 c80 Params: >>> DataPDUInOrder=Yes.
9/21 23:13:05.023 c80 Params: >>> DataSequenceInOrder=Yes.
9/21 23:13:05.023 ac4 T[2,1]: session 0x2, connection 0x2 : end of stage 1, next stage 3.
9/21 23:13:05.023 ac4 C[2], IN_LOGIN: Event - LOGIN_ACCEPT.
9/21 23:13:05.023 ac4 C[2], LIN: T5.
9/21 23:13:07.160 cac Srv: Accepted iSCSI connection from 192.168.99.2:49156 to 192.168.99.1:3260. (Id = 0x3)
9/21 23:13:07.160 cac C[3], FREE: Event - CONNECTED.
9/21 23:13:07.160 cac C[3], XPT_UP: T3.
9/21 23:13:07.160 c3c C[3], XPT_UP: Login request: ISID 0x400001370000, TSIH 0x0000.
9/21 23:13:07.160 c3c C[3], XPT_UP: Event - LOGIN.
9/21 23:13:07.160 c3c C[3], IN_LOGIN: T4.
9/21 23:13:07.160 c3c Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:hyp01-srv', accepted 'iqn.1991-05.com.microsoft:hyp01-srv'
9/21 23:13:07.160 c3c Params: <<< Enum param 'SessionType': received 'Discovery', accepted 'Discovery'
9/21 23:13:07.160 c3c Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
9/21 23:13:07.160 c3c Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
9/21 23:13:07.160 c3c Params: >>> AuthMethod=None.
9/21 23:13:07.160 2f4 T[3,1]: session 0x3, connection 0x3 : end of stage 0, next stage 1.
9/21 23:13:07.160 c3c Params: <<< Enum param 'HeaderDigest': received 'None,CRC32C', accepted 'None'
9/21 23:13:07.160 c3c Params: <<< Enum param 'DataDigest': received 'None,CRC32C', accepted 'None'
9/21 23:13:07.160 c3c Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 65536, accepted 65536
9/21 23:13:07.160 c3c Params: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 0
9/21 23:13:07.160 c3c Params: <<< Numeric param 'DefaultTime2Retain': received 60, accepted 20
9/21 23:13:07.160 c3c Params: >>> HeaderDigest=None.
9/21 23:13:07.160 c3c Params: >>> DataDigest=None.
9/21 23:13:07.160 c3c Params: >>> InitialR2T=No.
9/21 23:13:07.160 c3c Params: >>> ImmediateData=Yes.
9/21 23:13:07.160 c3c Params: >>> MaxRecvDataSegmentLength=262144.
9/21 23:13:07.160 c3c Params: >>> MaxBurstLength=262144.
9/21 23:13:07.160 c3c Params: >>> FirstBurstLength=262144.
9/21 23:13:07.160 c3c Params: >>> DefaultTime2Wait=0.
9/21 23:13:07.160 c3c Params: >>> DefaultTime2Retain=20.
9/21 23:13:07.160 c3c Params: >>> MaxOutstandingR2T=1.
9/21 23:13:07.160 c3c Params: >>> DataPDUInOrder=Yes.
9/21 23:13:07.160 c3c Params: >>> DataSequenceInOrder=Yes.
9/21 23:13:07.160 2f4 T[3,1]: session 0x3, connection 0x3 : end of stage 1, next stage 3.
9/21 23:13:07.160 2f4 C[3], IN_LOGIN: Event - LOGIN_ACCEPT.
9/21 23:13:07.160 2f4 C[3], LIN: T5.
9/21 23:13:07.160 2f4 C[3], LIN: Event - LOGOUT.
9/21 23:13:07.160 2f4 C[3], IN_LOGOUT: T9/10.
9/21 23:13:07.160 524 C[3], IN_LOGOUT: Event - LOGOUT_ACCEPT.
9/21 23:13:07.160 524 C[3], FREE: T13.
9/21 23:13:07.160 c3c C[3], FREE: recvData returned 10058
9/21 23:24:21.475 cac Srv: Interface list has been changed. Refreshing...
9/21 23:24:21.475 cac Srv: Waiting for iSCSI connections at 192.168.99.1:3260 ...
9/21 23:24:21.475 cac Srv: Waiting for iSCSI connections at 192.168.98.1:3260 ...
9/21 23:24:21.475 cac Srv: Waiting for iSCSI connections at 127.0.0.1:3260 ...
9/21 23:24:24.922 cac Srv: Interface list has been changed. Refreshing...
9/21 23:24:24.922 cac Srv: Waiting for iSCSI connections at 192.168.99.1:3260 ...
9/21 23:24:24.922 cac Srv: Waiting for iSCSI connections at 192.168.98.1:3260 ...
9/21 23:24:24.922 cac Srv: Waiting for iSCSI connections at 127.0.0.1:3260 ...
10/10 21:42:06.194 f48 C[2], LIN: ** ExpCmdSN >= MaxCmdSN (124760396 >= 124760396), no tasks (63 active) in 'recv' or 'exec' state, ping...
10/31 10:31:28.470 cac Srv: Interface list has been changed. Refreshing...
10/31 10:31:28.470 cac Srv: Waiting for iSCSI connections at 192.168.98.1:3260 ...
10/31 10:31:28.470 cac Srv: Waiting for iSCSI connections at 127.0.0.1:3260 ...
10/31 10:31:32.931 c80 C[2], LIN: recvData returned 10054
10/31 10:31:32.931 c80 C[2], LIN: *** 'recv' thread: recv failed 10058.
10/31 10:31:32.931 ac4 Tgt: close 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv': 0 session(s) opened, 1 more allowed.
10/31 13:11:02.953 cac Srv: Interface list has been changed. Refreshing...
10/31 13:11:02.953 cac Srv: Waiting for iSCSI connections at 192.168.99.1:3260 ...
10/31 13:11:02.953 cac Srv: Waiting for iSCSI connections at 192.168.98.1:3260 ...
10/31 13:11:02.953 cac Srv: Waiting for iSCSI connections at 127.0.0.1:3260 ...
10/31 13:18:10.427 cac Srv: Accepted iSCSI connection from 192.168.99.2:49192 to 192.168.99.1:3260. (Id = 0x4)
10/31 13:18:10.427 cac C[4], FREE: Event - CONNECTED.
10/31 13:18:10.427 cac C[4], XPT_UP: T3.
10/31 13:18:10.427 df4 C[4], XPT_UP: Login request: ISID 0x400001370000, TSIH 0x0000.
10/31 13:18:10.427 df4 C[4], XPT_UP: Event - LOGIN.
10/31 13:18:10.427 df4 C[4], IN_LOGIN: T4.
10/31 13:18:10.427 df4 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:hyp01-srv', accepted 'iqn.1991-05.com.microsoft:hyp01-srv'
10/31 13:18:10.427 df4 Params: <<< Enum param 'SessionType': received 'Discovery', accepted 'Discovery'
10/31 13:18:10.427 df4 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
10/31 13:18:10.427 df4 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
10/31 13:18:10.427 df4 Params: >>> AuthMethod=None.
10/31 13:18:10.427 84c T[4,1]: session 0x4, connection 0x4 : end of stage 0, next stage 1.
10/31 13:18:10.427 df4 Params: <<< Enum param 'HeaderDigest': received 'None,CRC32C', accepted 'None'
10/31 13:18:10.427 df4 Params: <<< Enum param 'DataDigest': received 'None,CRC32C', accepted 'None'
10/31 13:18:10.427 df4 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 65536, accepted 65536
10/31 13:18:10.427 df4 Params: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 0
10/31 13:18:10.427 df4 Params: <<< Numeric param 'DefaultTime2Retain': received 60, accepted 20
10/31 13:18:10.427 df4 Params: >>> HeaderDigest=None.
10/31 13:18:10.427 df4 Params: >>> DataDigest=None.
10/31 13:18:10.427 df4 Params: >>> InitialR2T=No.
10/31 13:18:10.427 df4 Params: >>> ImmediateData=Yes.
10/31 13:18:10.442 df4 Params: >>> MaxRecvDataSegmentLength=262144.
10/31 13:18:10.442 df4 Params: >>> MaxBurstLength=262144.
10/31 13:18:10.442 df4 Params: >>> FirstBurstLength=262144.
10/31 13:18:10.442 df4 Params: >>> DefaultTime2Wait=0.
10/31 13:18:10.442 df4 Params: >>> DefaultTime2Retain=20.
10/31 13:18:10.442 df4 Params: >>> MaxOutstandingR2T=1.
10/31 13:18:10.442 df4 Params: >>> DataPDUInOrder=Yes.
10/31 13:18:10.442 df4 Params: >>> DataSequenceInOrder=Yes.
10/31 13:18:10.442 84c T[4,1]: session 0x4, connection 0x4 : end of stage 1, next stage 3.
10/31 13:18:10.442 84c C[4], IN_LOGIN: Event - LOGIN_ACCEPT.
10/31 13:18:10.442 84c C[4], LIN: T5.
10/31 13:18:10.442 84c C[4], LIN: Event - LOGOUT.
10/31 13:18:10.442 84c C[4], IN_LOGOUT: T9/10.
10/31 13:18:10.442 d80 C[4], IN_LOGOUT: Event - LOGOUT_ACCEPT.
10/31 13:18:10.442 d80 C[4], FREE: T13.
10/31 13:18:10.442 df4 C[4], FREE: recvData returned 10058
10/31 13:18:29.865 cac Srv: Accepted iSCSI connection from 192.168.99.2:49193 to 192.168.99.1:3260. (Id = 0x5)
10/31 13:18:29.865 cac C[5], FREE: Event - CONNECTED.
10/31 13:18:29.865 cac C[5], XPT_UP: T3.
10/31 13:18:29.865 c14 C[5], XPT_UP: Login request: ISID 0x400001370000, TSIH 0x0000.
10/31 13:18:29.865 c14 C[5], XPT_UP: Event - LOGIN.
10/31 13:18:29.865 c14 C[5], IN_LOGIN: T4.
10/31 13:18:29.865 c14 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:hyp01-srv', accepted 'iqn.1991-05.com.microsoft:hyp01-srv'
10/31 13:18:29.865 c14 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
10/31 13:18:29.865 c14 Params: <<< String param 'TargetName': received 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv', accepted 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv'
10/31 13:18:29.865 c14 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
10/31 13:18:29.865 c14 PR: Reusing old session 0x1 (new 0x5) from iqn.1991-05.com.microsoft:hyp01-srv,400001370000 
10/31 13:18:29.865 c14 PR: Reusing old session 0x1 (new 0x5) from iqn.1991-05.com.microsoft:hyp01-srv,400001370000 
10/31 13:18:29.865 c14 PR: Reusing old session 0x1 (new 0x5) from iqn.1991-05.com.microsoft:hyp01-srv,400001370000 
10/31 13:18:29.865 c14 PR: Reusing old session 0x1 (new 0x5) from iqn.1991-05.com.microsoft:hyp01-srv,400001370000 
10/31 13:18:29.865 c14 PR: Reusing old session 0x1 (new 0x5) from iqn.1991-05.com.microsoft:hyp01-srv,400001370000 
10/31 13:18:29.865 c14 PR: Reusing old session 0x1 (new 0x5) from iqn.1991-05.com.microsoft:hyp01-srv,400001370000 
10/31 13:18:29.865 c14 PR: Reusing old session 0x1 (new 0x5) from iqn.1991-05.com.microsoft:hyp01-srv,400001370000 
10/31 13:18:29.865 c14 Tgt: open 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv': 1 session(s) opened, 0 more allowed.
10/31 13:18:29.865 c14 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
10/31 13:18:29.865 c14 Params: >>> AuthMethod=None.
10/31 13:18:29.865 c14 Params: >>> TargetAlias=HYP01-SRV.
10/31 13:18:29.865 c14 Params: >>> TargetPortalGroupTag=1.
10/31 13:18:29.865 d74 T[5,1]: session 0x5, connection 0x5 : end of stage 0, next stage 1.
10/31 13:18:29.865 c14 Params: <<< Enum param 'HeaderDigest': received 'None,CRC32C', accepted 'None'
10/31 13:18:29.865 c14 Params: <<< Enum param 'DataDigest': received 'None,CRC32C', accepted 'None'
10/31 13:18:29.865 c14 Params: <<< Numeric param 'ErrorRecoveryLevel': received 2, accepted 0
10/31 13:18:29.865 c14 Params: <<< Boolean param 'InitialR2T': received No, accepted 0
10/31 13:18:29.865 c14 Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
10/31 13:18:29.865 c14 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 65536, accepted 65536
10/31 13:18:29.865 c14 Params: <<< Numeric param 'MaxBurstLength': received 262144, accepted 262144
10/31 13:18:29.865 c14 Params: <<< Numeric param 'FirstBurstLength': received 65536, accepted 65536
10/31 13:18:29.865 c14 Params: <<< Numeric param 'MaxConnections': received 32, accepted 1
10/31 13:18:29.865 c14 Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
10/31 13:18:29.865 c14 Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
10/31 13:18:29.865 c14 Params: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 0
10/31 13:18:29.865 c14 Params: <<< Numeric param 'DefaultTime2Retain': received 60, accepted 20
10/31 13:18:29.865 c14 Params: <<< Numeric param 'MaxOutstandingR2T': received 16, accepted 1
10/31 13:18:29.865 c14 Params: >>> ErrorRecoveryLevel=0.
10/31 13:18:29.865 c14 Params: >>> MaxConnections=1.
10/31 13:18:29.865 c14 Params: >>> HeaderDigest=None.
10/31 13:18:29.865 c14 Params: >>> DataDigest=None.
10/31 13:18:29.865 c14 Params: >>> InitialR2T=No.
10/31 13:18:29.865 c14 Params: >>> ImmediateData=Yes.
10/31 13:18:29.865 c14 Params: >>> MaxRecvDataSegmentLength=262144.
10/31 13:18:29.865 c14 Params: >>> MaxBurstLength=262144.
10/31 13:18:29.865 c14 Params: >>> FirstBurstLength=65536.
10/31 13:18:29.865 c14 Params: >>> DefaultTime2Wait=0.
10/31 13:18:29.865 c14 Params: >>> DefaultTime2Retain=20.
10/31 13:18:29.865 c14 Params: >>> MaxOutstandingR2T=1.
10/31 13:18:29.865 c14 Params: >>> DataPDUInOrder=Yes.
10/31 13:18:29.865 c14 Params: >>> DataSequenceInOrder=Yes.
10/31 13:18:29.865 d74 T[5,1]: session 0x5, connection 0x5 : end of stage 1, next stage 3.
10/31 13:18:29.865 d74 C[5], IN_LOGIN: Event - LOGIN_ACCEPT.
10/31 13:18:29.865 d74 C[5], LIN: T5.
10/31 14:39:28.578 cac Srv: Interface list has been changed. Refreshing...
10/31 14:39:28.578 cac Srv: Waiting for iSCSI connections at 192.168.98.1:3260 ...
10/31 14:39:28.578 cac Srv: Waiting for iSCSI connections at 127.0.0.1:3260 ...
10/31 14:39:31.308 c14 C[5], LIN: recvData returned 10054
10/31 14:39:31.308 c14 C[5], LIN: *** 'recv' thread: recv failed 10058.
10/31 14:39:31.308 d74 Tgt: close 'iqn.2008-08.com.starwindsoftware:san01-hyp01-srv': 0 session(s) opened, 1 more allowed.
10/31 14:41:05.892 eb0 conf: Control connection closed.
10/31 14:41:07.545 c74 ntsrv: *** SERVICE_CONTROL_PRESHUTDOWN signal. ***
10/31 14:41:07.748 cac Srv: iSCSI listener stopped.
10/31 14:41:07.748 d90 conf: Stop listening to control connections.
10/31 14:41:07.748 d84 bc: Socket error: 10004
10/31 14:41:07.748 d84 bc: Stop listening to UDP packets.
10/31 14:41:07.748 cac Srv: Shutting down...
10/31 14:41:07.748 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: device 'ImageFile2' is detached.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: device 'ImageFile3' is detached.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: device 'ImageFile4' is detached.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: device 'ImageFile5' is detached.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: device 'ImageFile6' is detached.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: device 'ImageFile7' is detached.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x5 from iqn.1991-05.com.microsoft:hyp01-srv,400001370000.
10/31 14:41:07.748 cac PR: Set Unit attention 0x3f/0xe for session 0x2 from iqn.1991-05.com.microsoft:hyp01-srv,400001370001.
10/31 14:41:07.748 cac Tgt: iqn.2008-08.com.starwindsoftware:san01-hyp01-srv: device 'ImageFile8' is detached.
10/31 14:41:07.748 cac error: assertion failed: c:\_starwind\_v5\starwind\service\common\fn_common.cpp:28 (isEmpty())
10/31 14:41:07.748 cac PerformanceDB: CPerformanceCounter::WriteOperationalData called on uninitialized counter(DB entry: 0000000000000000, Record: 0000000000000000).
10/31 14:41:07.748 cac PerformanceDB: CPerformanceCounter::Close exiting with error: WriteOperationalData failed.
10/31 14:41:07.748 cac error: assertion failed: c:\_starwind\_v5\starwind\service\common\fn_common.cpp:28 (isEmpty())
10/31 14:41:07.748 cac Srv: Waiting for 5 seconds for SSC devices to close...
10/31 14:41:07.748 e3c SCSI: ImageFile2: releasing stop guard
10/31 14:41:07.748 e3c error: assertion failed: c:\_starwind\_v5\starwind\service\common\fn_common.cpp:28 (isEmpty())
10/31 14:41:07.748 280 SCSI: ImageFile3: releasing stop guard
10/31 14:41:07.748 280 error: assertion failed: c:\_starwind\_v5\starwind\service\common\fn_common.cpp:28 (isEmpty())
10/31 14:41:08.013 22c SCSI: ImageFile4: releasing stop guard
10/31 14:41:08.013 22c error: assertion failed: c:\_starwind\_v5\starwind\service\common\fn_common.cpp:28 (isEmpty())
10/31 14:41:08.310 d08 SCSI: ImageFile5: releasing stop guard
10/31 14:41:08.310 d08 error: assertion failed: c:\_starwind\_v5\starwind\service\common\fn_common.cpp:28 (isEmpty())
10/31 14:41:08.419 b70 SCSI: ImageFile6: releasing stop guard
10/31 14:41:08.419 b70 error: assertion failed: c:\_starwind\_v5\starwind\service\common\fn_common.cpp:28 (isEmpty())
10/31 14:41:08.544 e3c SCSI: ImageFile7: releasing stop guard
10/31 14:41:08.544 e3c error: assertion failed: c:\_starwind\_v5\starwind\service\common\fn_common.cpp:28 (isEmpty())
10/31 14:41:08.762 dcc SCSI: ImageFile8: releasing stop guard
10/31 14:41:08.762 dcc error: assertion failed: c:\_starwind\_v5\starwind\service\common\fn_common.cpp:28 (isEmpty())
10/31 14:41:08.762 cac Srv: All devices closed.
10/31 14:41:08.762 cac EventDB: Closing data file
10/31 14:41:08.762 cac EventDB: Closing strings file
10/31 14:41:08.762 cac EventDB: Closing data file
10/31 14:41:08.762 cac EventDB: Closing strings file
10/31 14:41:08.762 cac EventDB: Closing data file
10/31 14:41:08.762 cac EventDB: Closing strings file
10/31 14:41:08.762 cac EventDB: Closing data file
10/31 14:41:08.762 cac EventDB: Closing strings file
10/31 14:41:08.778 cac Srv: Shutdown completed.
