enumDevicesTargets.ps1 error on Dell T5400

Software-based VM-centric and flash-friendly VM storage + free version

Moderators: anton (staff), art (staff), Max (staff), Anatoly (staff)

Post Reply
mohsin
Posts: 2
Joined: Tue Jan 22, 2019 3:59 am

Tue Jan 22, 2019 9:17 am

I know I shouldn't expect any answer from using Free version. But I am puzzled by this installation. Am I missing anything?

Test Environment: Windows 2012 Server R2
Starwind VSAN Free V8.

I installed VSAN Free Full installation on Dell T5400 dual socket xeon processor machine with 2x1GB NIC, 16GB RAM with no error.
Everything is default. No parameter change (i.e. port, login, password ...)
Using Starwind Management Console, I can connect to server and see that I have license.
Then, Just to test I run the enumDevicesTargets.ps1 in Powershell.

I get this error:
Exception calling "Connect" with "0" argument(s): "Exception of type 'System.OutOfMemoryException' was thrown.
"
very likely, Error happens on line $server.Connect()

A little background:
All test installation are with Windows Server 2012 R2.

I installed VSAN Free version on 2 different single socket processor machine with 1GB NIC, 8GB RAM and followed 2 HA setup
and everything works just fine.

When I installed on Dell T5400 dual socket xeon processor machine with 2x1GB NIC, 16GB RAM and run the enumDevicesTargets.ps1 script, I get the above error.

Am I missing anything? Any help would be highly appreciated.

Thanks.
Boris (staff)
Staff
Posts: 805
Joined: Fri Jul 28, 2017 8:18 am

Wed Jan 23, 2019 3:42 pm

Could you provide me with the StarWind log file that covers the time when the issue happened?
Edit: Additionally, make sure that the StarWind VSAN service is running on that server.
mohsin
Posts: 2
Joined: Tue Jan 22, 2019 3:59 am

Fri Jan 25, 2019 8:18 am

Hello Boris,
Hope this helps. Please let me know if you need any other info.
I could connect to VSAN using iScsi initiator, it just says, no device found. because I haven't / couldn't created any device.
Appreciate very much for your response.

Thanks.
StarWind Services that are Running
StarWind Services that are Running
StarWind.jpg (29.63 KiB) Viewed 12440 times
StarWind_MMC.jpg
StarWind_MMC.jpg (114.94 KiB) Viewed 12440 times
enumDevicesTargets Error
enumDevicesTargets Error
enumDevicesTargets.jpg (179.2 KiB) Viewed 12440 times
*****************************************************************************************************
Here is the Server Log file:
++++++++++++++++++++++++++

The log file name: 'logs\starwind-20190124-134442.log' (PID 324)
1/24 13:44:42.575 19c Srv: StarWind Virtual SAN v8.0.0 (Build 12658, [SwSAN], Win64)
1/24 13:44:42.575 19c Srv: Built Nov 21 2018 19:08:14
1/24 13:44:42.575 19c Srv: Windows Server 2012 R2 Standard Edition (v6.3 Build 9600)
1/24 13:44:42.575 19c Srv: Processor packages/cores/logical: 2/8/8
1/24 13:46:29.848 19c Srv: Licensed to: 'MD MOHSIN Ali', 'Miswar Group', (#2, 0, 0).
1/24 13:46:29.848 19c conf: ServerSetup::ApplyLicense: License has a FREE flag.
1/24 13:46:29.848 19c Srv: Unlimited number of client hosts allowed.
1/24 13:46:29.848 19c Srv: Config file: 'StarWind.cfg'
1/24 13:46:29.863 19c General: DllMain: NetDirect providers are not found.
1/24 13:46:29.863 19c Srv: *** iScsiServer::iScsiServer: iSER_DM is not loaded.
1/24 13:46:29.863 19c conf: 'LogLevel' = '1'
1/24 13:46:29.863 19c conf: 'LogMask' = '0xbfffffffffffffff'
1/24 13:46:29.863 19c conf: 'LogRotateSize' = '100'
1/24 13:46:29.863 19c conf: 'LogRotateKeepLastFiles' = '5'
1/24 13:46:29.863 19c conf: 'UpdatePeriod' = '7'
1/24 13:46:29.863 19c conf: 'UpdateHost' = 'www.starwindsoftware.com'
1/24 13:46:29.863 19c conf: 'UpdatePage' = '/updatetracker/index.php'
1/24 13:46:29.863 19c conf: 'UpdatePort' = '80'
1/24 13:46:29.863 19c conf: 'UpdateCopyId' = '871944EB-9977-44E0-B8C2-BD76F121E089'
1/24 13:46:29.863 19c conf: 'UpdateLastRequest' = '20190124'
1/24 13:46:29.863 19c conf: 'WUSCEnabled' = 'yes'
1/24 13:46:29.863 19c conf: 'SrvWasDisabled' = 'no'
1/24 13:46:29.863 19c conf: 'SrvRestoreStartType' = '2'
1/24 13:46:29.863 19c conf: 'VaaiExCopyEnabled' = 'yes'
1/24 13:46:29.863 19c conf: 'VaaiCawEnabled' = 'yes'
1/24 13:46:29.863 19c conf: 'VaaiWriteSameEnabled' = 'yes'
1/24 13:46:29.863 19c conf: 'OdxEnabled' = 'no'
1/24 13:46:29.863 19c conf: 'OdxOptimalRodSizeMB' = '64'
1/24 13:46:29.863 19c conf: 'OdxMaximumRodSizeMB' = '256'
1/24 13:46:29.863 19c conf: 'OdxRodTokenDefaultTimeoutSec' = '10'
1/24 13:46:29.863 19c conf: 'OdxRodTokenMaximumTimeoutSec' = '30'
1/24 13:46:29.863 19c conf: 'Port' = '3260'
1/24 13:46:29.863 19c conf: 'Interface' = '0.0.0.0'
1/24 13:46:29.863 19c conf: 'BCastEnable' = 'yes'
1/24 13:46:29.863 19c conf: 'BCastInterface' = '0.0.0.0'
1/24 13:46:29.863 19c conf: 'BCastPort' = '3261'
1/24 13:46:29.863 19c conf: 'Login' = 'root'
1/24 13:46:29.863 19c conf: 'Password' = '##evVRsIJtRmAEEd2sCslZDg=='
1/24 13:46:29.863 19c conf: 'MinBufferSize' = '65536'
1/24 13:46:29.863 19c conf: 'AlignmentMask' = '0x0000'
1/24 13:46:29.863 19c conf: 'MaxPendingRequests' = '256'
1/24 13:46:29.863 19c conf: 'iScsiPingPeriod' = '0'
1/24 13:46:29.863 19c conf: 'iScsiDiscoveryListInterfaces' = '0'
1/24 13:46:29.863 19c conf: 'ServerIoWorkersCount' = '0'
1/24 13:46:29.863 19c conf: 'ServerIoWorkersConcurency' = '0'
1/24 13:46:29.863 19c conf: 'CmdExecTimeWarningLimitInSec' = '10'
1/24 13:46:29.863 19c conf: 'iScisCmdSendCmdTimeoutInSec' = '10'
1/24 13:46:29.863 19c conf: 'iSerListen' = ''
1/24 13:46:29.863 19c conf: 'LocalizationDir' = 'Localizations'
1/24 13:46:29.863 19c conf: 'DefaultStoragePoolPath' = 'My Computer\D\VSAN_SP\'
1/24 13:46:29.863 19c conf: 'ExperimentalLSFS' = 'no'
1/24 13:46:29.863 19c conf: 'ClusterName' = ''
1/24 13:46:29.863 19c conf: 'ClusterGUID' = ''
1/24 13:46:29.863 19c conf: 'ClusterSettingsVersion' = '0'
1/24 13:46:29.863 19c conf: 'ClusterNodes' = ''
1/24 13:46:29.863 19c conf: 'ClusterSync' = ''
1/24 13:46:29.863 19c conf: 'ClusterHeartbeat' = ''
1/24 13:46:29.863 19c conf: 'DataBaseRoot' = '.\NotifyDB'
1/24 13:46:29.863 19c conf: 'DBRotationDays' = '5'
1/24 13:46:29.863 19c conf: 'DBFileSizeDays' = '1'
1/24 13:46:29.863 19c conf: 'PerformanceMonitorEnabled' = 'yes'
1/24 13:46:29.863 19c conf: 'PerformanceRoot' = '.\PerformanceDB'
1/24 13:46:29.863 19c conf: 'FSMThresholdPercent' = '30'
1/24 13:46:29.863 19c conf: 'FSMCheckPeriodSeconds' = '30'
1/24 13:46:29.863 19c conf: 'FSMEnabled' = 'yes'
1/24 13:46:29.863 19c conf: read command: addimagedir -path:"*" -flags:"cdmfv" -alias:"My Computer" -extensions:"*"
1/24 13:46:29.863 19c conf: read command: addimagedir -path:"*" -flags:"cdmfv" -alias:"Image Files" -extensions:"img,dat"
1/24 13:46:29.863 19c conf: read command: addimagedir -path:"*" -flags:"cmdfv" -alias:"VTLs" -extensions:"swdsk"
1/24 13:46:29.863 19c conf: read command: addimagedir -path:"*" -flags:"cmdfv" -alias:"VTapes" -extensions:"VTape"
1/24 13:46:29.863 19c conf: read command: addimagedir -path:"*" -flags:"cdmfv" -alias:"Event Logs" -extensions:"txt,log"
1/24 13:46:29.863 19c conf: read command: addimagedir -path:"*" -flags:"cdmfv" -alias:"Headers" -extensions:"swdsk"
1/24 13:46:29.863 19c conf: read command: reaction -maskSeverity:"14" -maskCode:"-1" -type:"eventlog"
1/24 13:46:29.863 19c conf: read command: addplugin -module:"DDDisk.dll" -symlink:"DDDisk" -type:"Deduplicated disk" -imagedir:"*|cmdfv|My Computer|spbitmap" -imagedir:"*|cmdfv|Metadata|spmetadata"
imagedir:"*|cmdfv|Data|spdata" -volumes:"no"
1/24 13:46:29.863 19c conf: read command: addplugin -module:"DDDiskEx.dll" -symlink:"DDDiskEx" -type:"Deduplicated disk Ex" -imagedir:"*|cmdfv|My Computer|spdata" -imagedir:"*|cmdfv|Metadata|spmetad
ta" -volumes:"no"
1/24 13:46:29.863 19c conf: read command: addplugin -module:"lsfs.dll" -symlink:"lsfs" -type:"LSFS Disk" -imagedir:"*|cmdfv|My Computer|spsp,swdsk" -volumes:"no" -CacheFlags:"131080" -L2CacheFlags:"
31080"
1/24 13:46:29.863 19c conf: read command: addplugin -module:"RamDisk.dll" -symlink:"RamDrive" -type:"RAM disk"
1/24 13:46:29.863 19c conf: read command: addplugin -module:"ImageFile.dll" -symlink:"ImageFile" -type:"Image file" -imagedir:"*|cmdfv|My Computer|img,swdsk" -volumes:"no" -QueueFlags:"0" -QueueWork
rsCount:"0" -QueueWorkersActive:"0" -CacheFlags:"0" -L2CacheFlags:"0"
1/24 13:46:29.863 19c conf: read command: addplugin -module:"VirtualDvd.dll" -symlink:"VirtualDvd" -type:"Virtual DVD" -imagedir:"*|mv|My Computer|mds,iso"
1/24 13:46:29.863 19c conf: read command: addplugin -module:"IBVolume.dll" -symlink:"IBV" -type:"IBV" -imagedir:"*|cdmfv|My Computer|ibv" -imagedir:"*|dmfv|Snapshots|ibvss" -imagedir:"*|v|Database|i
vdb" -imagedir:"*|cv|Image Files|img,dat"
1/24 13:46:29.863 19c conf: read command: addplugin -module:"Mirror.dll" -symlink:"Mirror" -type:"Mirror" -imagedir:"*|cdmfv|Mirror Volumes|mir" -imagedir:"*|cdmfv|Image Files|img,dat"
1/24 13:46:29.863 19c conf: read command: addplugin -module:"DiskBridge.dll" -symlink:"DiskBridge" -type:"DiskBridge"
1/24 13:46:29.863 19c conf: read command: addplugin -module:"VirtualTape.dll" -symlink:"VirtualTape" -type:"Virtual tape" -imagedir:"*|cmdfv|My Computer|vtl" -volumes:"no" -LogLevel:"1"
1/24 13:46:29.863 19c conf: read command: addplugin -module:"HAImage.dll" -symlink:"HAImage" -type:"HA Image" -imagedir:"*|cmdfv|My Computer|img,swdsk,swcbt" -imagedir:"*|cdmfv|Headers|swdsk" -image
ir:"Device Headers|cdmfv|DefaultHeaderPath|swdsk" -transport:"auto" -rpl_threshold:"4" -node_shutdown_type:"close_clients_conn" -MaxSyncQueueSize:"16"
1/24 13:46:29.863 19c conf: read command: addplugin -module:"LWC.dll" -symlink:"LWC" -type:"LWC" -imagedir:"*|cmdfv|My Computer|swdsk,swcbt,swcbm" -imagedir:"*|cdmfv|Headers|swdsk" -imagedir:"Device
Headers|cdmfv|DefaultHeaderPath|swdsk" -transport:"auto"
1/24 13:46:29.863 19c conf: read command: addplugin -module:"VTL.dll" -symlink:"VTL" -type:"VTL" -LogLevel:"3" -replicator:"119" -closefiles:"0" -imagedir:"*|cmdfv|My Computer|swdsk" -imagedir:"*|cm
fv|VTapes|VTape" -imagedir:"VTL\|cmdfv|Patterns|VTLP"
1/24 13:46:29.863 19c conf: read command: addplugin -module:"SysMan.dll" -symlink:"SysMan" -type:"System Manager" -LogLevel:"1"
1/24 13:46:29.863 19c conf: read command: addplugin -module:"Azure.dll" -symlink:"Azure" -type:"Azure Manager" -LogLevel:"3"
1/24 13:46:29.863 19c conf: read command: addplugin -module:"NVMfTarget.dll" -symlink:"NVMfTarget" -type:"NVMf Target" -imagedir:"*|cmdfv|My Computer|img,conf" -volumes:"no"
1/24 13:46:29.863 19c conf: 'DefaultAccessPolicy' = 'allow'
1/24 13:46:29.863 19c conf: Variable 'LogLevel' is set to '1'.
1/24 13:46:29.863 19c conf: Variable 'LogMask' is set to '0xbfffffffffffffff'.
1/24 13:46:29.863 19c conf: Variable 'LogRotateSize' is set to '100'.
1/24 13:46:29.863 19c conf: Variable 'LogRotateKeepLastFiles' is set to '5'.
1/24 13:46:29.863 19c conf: Variable 'UpdatePeriod' is set to '7'.
1/24 13:46:29.863 19c conf: Variable 'UpdateHost' is set to 'www.starwindsoftware.com'.
1/24 13:46:29.863 19c conf: Variable 'UpdatePage' is set to '/updatetracker/index.php'.
1/24 13:46:29.863 19c conf: Variable 'UpdatePort' is set to '80'.
1/24 13:46:29.863 19c conf: Variable 'UpdateCopyId' is set to '871944EB-9977-44E0-B8C2-BD76F121E089'.
1/24 13:46:29.863 19c conf: Variable 'UpdateLastRequest' is set to '20190124'.
1/24 13:46:29.863 19c conf: Variable 'WUSCEnabled' is set to 'yes'.
1/24 13:46:29.863 19c conf: Variable 'SrvWasDisabled' is set to 'no'.
1/24 13:46:29.863 19c conf: Variable 'SrvRestoreStartType' is set to '2'.
1/24 13:46:29.863 19c conf: Variable 'VaaiExCopyEnabled' is set to 'yes'.
1/24 13:46:29.863 19c conf: Variable 'VaaiCawEnabled' is set to 'yes'.
1/24 13:46:29.863 19c conf: Variable 'VaaiWriteSameEnabled' is set to 'yes'.
1/24 13:46:29.863 19c conf: Variable 'OdxEnabled' is set to 'no'.
1/24 13:46:29.863 19c conf: Variable 'OdxOptimalRodSizeMB' is set to '64'.
1/24 13:46:29.863 19c conf: Variable 'OdxMaximumRodSizeMB' is set to '256'.
1/24 13:46:29.863 19c conf: Variable 'OdxRodTokenDefaultTimeoutSec' is set to '10'.
1/24 13:46:29.863 19c conf: Variable 'OdxRodTokenMaximumTimeoutSec' is set to '30'.
1/24 13:46:29.863 19c conf: Variable 'Port' is set to '3260'.
1/24 13:46:29.863 19c conf: Variable 'Interface' is set to '0.0.0.0'.
1/24 13:46:29.863 19c conf: Variable 'BCastEnable' is set to 'yes'.
1/24 13:46:29.863 19c conf: Variable 'BCastInterface' is set to '0.0.0.0'.
1/24 13:46:29.863 19c conf: Variable 'BCastPort' is set to '3261'.
1/24 13:46:29.863 19c conf: Variable 'Login' is set to 'root'.
1/24 13:46:29.863 19c conf: Variable 'Password' is set to '##evVRsIJtRmAEEd2sCslZDg=='.
1/24 13:46:29.863 19c conf: Variable 'MinBufferSize' is set to '65536'.
1/24 13:46:29.863 19c conf: Variable 'AlignmentMask' is set to '0x0000'.
1/24 13:46:29.863 19c conf: Variable 'MaxPendingRequests' is set to '256'.
1/24 13:46:29.863 19c conf: Variable 'iScsiPingPeriod' is set to '0'.
1/24 13:46:29.863 19c conf: Variable 'iScsiDiscoveryListInterfaces' is set to '0'.
1/24 13:46:29.863 19c conf: Variable 'ServerIoWorkersCount' is set to '0'.
1/24 13:46:29.863 19c conf: Variable 'ServerIoWorkersConcurency' is set to '0'.
1/24 13:46:29.863 19c conf: Variable 'CmdExecTimeWarningLimitInSec' is set to '10'.
1/24 13:46:29.863 19c conf: Variable 'iScisCmdSendCmdTimeoutInSec' is set to '10'.
1/24 13:46:29.863 19c conf: Variable 'iSerListen' is set to ''.
1/24 13:46:29.863 19c conf: Variable 'LocalizationDir' is set to 'Localizations'.
1/24 13:46:29.863 19c conf: Variable 'DefaultStoragePoolPath' is set to 'My Computer\D\VSAN_SP\'.
1/24 13:46:29.863 19c conf: Variable 'ExperimentalLSFS' is set to 'no'.
1/24 13:46:29.863 19c conf: Variable 'ClusterName' is set to ''.
1/24 13:46:29.863 19c conf: Variable 'ClusterGUID' is set to ''.
1/24 13:46:29.863 19c conf: Variable 'ClusterSettingsVersion' is set to '0'.
1/24 13:46:29.863 19c conf: Variable 'ClusterNodes' is set to ''.
1/24 13:46:29.863 19c conf: Variable 'ClusterSync' is set to ''.
1/24 13:46:29.863 19c conf: Variable 'ClusterHeartbeat' is set to ''.
1/24 13:46:29.863 19c conf: Variable 'DataBaseRoot' is set to '.\NotifyDB'.
1/24 13:46:29.863 19c conf: Variable 'DBRotationDays' is set to '5'.
1/24 13:46:29.863 19c conf: Variable 'DBFileSizeDays' is set to '1'.
1/24 13:46:29.863 19c conf: Variable 'PerformanceMonitorEnabled' is set to 'yes'.
1/24 13:46:29.863 19c conf: Variable 'PerformanceRoot' is set to '.\PerformanceDB'.
1/24 13:46:29.863 19c conf: Variable 'FSMThresholdPercent' is set to '30'.
1/24 13:46:29.863 19c conf: Variable 'FSMCheckPeriodSeconds' is set to '30'.
1/24 13:46:29.863 19c conf: Variable 'FSMEnabled' is set to 'yes'.
1/24 13:46:29.863 19c EventDB: CEventDataBase::Init: wsRootDir = .\NotifyDB
1/24 13:46:29.863 19c EventDB: CEventDataBase::InternalInit: wsRootDir = .\NotifyDB
1/24 13:46:29.863 19c Srv: StarWindService::checkWorkingSet: Process working set: minimum 204800, maximum 1413120, flags 0xa
1/24 13:46:29.863 19c Srv: iScsiServer::additionalWorkingSet: New working set min: 67313664, max: 68521984, flags: 0x9
1/24 13:46:29.863 19c Srv: iScsiServer::startWorkers: ServerNodesPerNumaNode = 1
1/24 13:46:29.863 19c Srv: iScsiServer::startWorkers: Worker threads per server node: total 8, active 8
1/24 13:46:29.863 19c Srv: SwServerNode::SwServerNode: Node 0: NUMA node 0 (8 cores)
1/24 13:46:29.863 19c Srv: iScsiServer::startWorkers: ServerDefaultNode = 0
1/24 13:46:29.863 19c Srv: SwThreadPool::start: TP[0]: preparing 8 worker threads (8 active)...
1/24 13:46:29.863 eb0 Srv: SwThreadPool::workerThread: TP[0]: worker started at cpu 0 (numa 0, mask 0xff), maxEntries 1, priority: 0.
1/24 13:46:29.863 eac Srv: SwThreadPool::workerThread: TP[0]: worker started at cpu 1 (numa 0, mask 0xff), maxEntries 1, priority: 0.
1/24 13:46:29.863 eb8 Srv: SwThreadPool::workerThread: TP[0]: worker started at cpu 6 (numa 0, mask 0xff), maxEntries 1, priority: 0.
1/24 13:46:29.863 eb4 Srv: SwThreadPool::workerThread: TP[0]: worker started at cpu 2 (numa 0, mask 0xff), maxEntries 1, priority: 0.
1/24 13:46:29.863 ebc Srv: SwThreadPool::workerThread: TP[0]: worker started at cpu 4 (numa 0, mask 0xff), maxEntries 1, priority: 0.
1/24 13:46:29.863 ec0 Srv: SwThreadPool::workerThread: TP[0]: worker started at cpu 7 (numa 0, mask 0xff), maxEntries 1, priority: 0.
1/24 13:46:29.863 ec4 Srv: SwThreadPool::workerThread: TP[0]: worker started at cpu 0 (numa 0, mask 0xff), maxEntries 1, priority: 0.
1/24 13:46:29.863 ec8 Srv: SwThreadPool::workerThread: TP[0]: worker started at cpu 5 (numa 0, mask 0xff), maxEntries 1, priority: 0.
1/24 13:46:29.863 19c conf: *** ServerSetup::CheckSptd: Unable to detect SPTD.
1/24 13:46:29.863 19c FileBrowser: CFileBrowser::addImageDir: Directory loaded: *|cdmfv|My Computer|*
1/24 13:46:29.863 19c FileBrowser: CFileBrowser::addImageDir: Directory loaded: *|cdmfv|Image Files|img,dat
1/24 13:46:29.863 19c FileBrowser: CFileBrowser::addImageDir: Directory loaded: *|cmdfv|VTLs|swdsk
1/24 13:46:29.863 19c FileBrowser: CFileBrowser::addImageDir: Directory loaded: *|cmdfv|VTapes|VTape
1/24 13:46:29.863 19c FileBrowser: CFileBrowser::addImageDir: Directory loaded: *|cdmfv|Event Logs|txt,log
1/24 13:46:29.863 19c FileBrowser: CFileBrowser::addImageDir: Directory loaded: *|cdmfv|Headers|swdsk
1/24 13:46:29.863 19c conf: *** ConfCommand::processNew: Failed to load 'DDDisk.dll' (error 126).
1/24 13:46:29.863 19c conf: *** ConfCommand::processNew: Failed to load 'DDDiskEx.dll' (error 126).
1/24 13:46:29.879 19c General: SscPort_Init: (build 12658, built Nov 21 2018 19:06:50)
VendorId = ' '
ProductId = ' '
Revision = '0001'
1/24 13:46:29.879 19c conf: ConfCommand::processNew: 'lsfs.dll' loaded successfully.
1/24 13:46:29.879 19c General: SscPort_Init: (build 12658, built Nov 21 2018 19:04:22)
VendorId = ' '
ProductId = ' '
Revision = '0001'
1/24 13:46:29.879 19c conf: ConfCommand::processNew: 'RamDisk.dll' loaded successfully.
1/24 13:46:29.895 19c IMG: SscPort_Init: (build 12658, built Nov 21 2018 19:04:35)
VendorId = ' '
ProductId = ' '
Revision = '0001'
Volumes = 0
1/24 13:46:29.895 19c conf: ConfCommand::processNew: 'ImageFile.dll' loaded successfully.
1/24 13:46:29.895 19c conf: ConfCommand::processNew: 'VirtualDvd.dll' loaded successfully.
1/24 13:46:29.895 19c conf: *** ConfCommand::processNew: Failed to load 'IBVolume.dll' (error 126).
1/24 13:46:29.895 19c conf: *** ConfCommand::processNew: Failed to load 'Mirror.dll' (error 126).
1/24 13:46:29.895 19c General: SscPort_Init: (built Nov 21 2018 19:05:40)
VendorId = ' '
ProductId = ' '
Revision = '0001'
ExeDir = 'C:\Program Files\StarWind Software\StarWind\'
1/24 13:46:29.895 19c conf: ConfCommand::processNew: 'DiskBridge.dll' loaded successfully.
1/24 13:46:29.895 19c conf: *** ConfCommand::processNew: Failed to load 'VirtualTape.dll' (error 126).
1/24 13:46:29.895 19c HA: SscPort_InitEx: OS major version more then 5, then will be used internal transport.
1/24 13:46:29.895 19c HA: SscPort_InitEx: (build 12658, built Nov 21 2018 19:06:50)
VendorId = ' '
ProductId = ' '
Revision = '0001'
DefImageDir = '*\'
Volumes = 0
ExeDir = 'C:\Program Files\StarWind Software\StarWind\'
1/24 13:46:29.895 19c HA: CHADevice::initialize_module: Params(callback = 0x00007FF7153DC200, plugin_handle = 0x000000406EB46280, scsi_transport_type = 3, node_shutdown_type = close_connections) ENT
Red
1/24 13:46:29.910 19c General: DllMain: NetDirect providers are not found.
1/24 13:46:29.910 19c conf: ConfCommand::processNew: 'HAImage.dll' loaded successfully.
1/24 13:46:29.910 19c conf: ConfCommand::processNew: 'LWC.dll' loaded successfully.
1/24 13:46:29.910 19c conf: ConfCommand::processNew: 'VTL.dll' loaded successfully.
1/24 13:46:29.910 19c Plugin: SscPort_InitEx: (build 12658, built Nov 21 2018 19:03:55)
VendorId = ' '
ProductId = ' '
Revision = '0001'
ExeDir = 'C:\Program Files\StarWind Software\StarWind\'
1/24 13:46:29.910 19c Plugin: CSysMan::InitializeModule: Params(p_pCallback = 0x00007FF7153DC200, p_hPluginHandle = 0x00000040701F7B80) ENTERed
1/24 13:46:29.926 19c conf: ConfCommand::processNew: 'SysMan.dll' loaded successfully.
1/24 13:46:29.926 ed4 Plugin: SW_WMIServerManagerDeploymentTasks::RefreshThreadProc: Gather Server Components info
1/24 13:46:29.926 19c conf: *** ConfCommand::processNew: Failed to load 'Azure.dll' (error 126).
1/24 13:46:29.926 19c conf: ConfCommand::processNew: 'NVMfTarget.dll' loaded successfully.
1/24 13:46:29.926 19c SCHED: Sw_Scheduler::open: The Scheduler is starting.
1/24 13:46:29.926 19c conf: Variable 'DefaultAccessPolicy' is set to 'allow'.
1/24 13:46:29.926 19c conf: ConfCommand::processNew: Reaction of type 'eventlog' [0xFFFFFFFF, 0x0E] loaded successfully.
1/24 13:46:29.926 19c General: CWUServiceControl::RollbackChanges: Service wasn't disabled.
1/24 13:46:29.926 19c Srv: *** StarWindService::doRun: Update tracker options: UpdatePeriod = 7, UpdateLastRequest = 20190124, UpdateHost = http://www.starwindsoftware.com, UpdatePage = /updatetracker/inde
.php, UpdatePort = 80, UpdateCopyId = 871944EB-9977-44E0-B8C2-BD76F121E089
1/24 13:46:29.926 19c Srv: iScsiServer::refreshDeviceList: Refreshing device list (complete)...
1/24 13:46:29.941 19c SPTI: '\\?\scsi#disk&ven_samsung&prod_ssd_850_evo_250g#4&303cba2d&0&010000#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}': iScsiSptiDevice::updateDeviceIds: DeviceType 0, VendorId '',
ProductId 'Samsung SSD 850 ', ProductRevision 'EMT0', SerialNumber 'S21NNXAG908540N', BusType 11.
1/24 13:46:29.941 19c SPTI: '\\?\scsi#disk&ven_samsung&prod_ssd_850_evo_250g#4&303cba2d&0&010000#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}': iScsiSptiDevice::openDevice: ' Samsung SSD 850 EMT0': adapt
r 2, bus 1, target 0, LUN 0; maxTransferLength 131072, alignmentMask 0x1
1/24 13:46:29.941 19c Srv: iScsiServer::tryToAddDevice: Found SPTI device '\\?\scsi#disk&ven_samsung&prod_ssd_850_evo_250g#4&303cba2d&0&010000#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}': ' Samsung SSD
50 EMT0'
1/24 13:46:29.941 19c SPTI: '\\?\usbstor#disk&ven_jetflash&prod_transcend_16gb&rev_8.07#j162frjk&0#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}': iScsiSptiDevice::updateDeviceIds: DeviceType 0, VendorId '
etFlash', ProductId 'Transcend 16GB ', ProductRevision '8.07', SerialNumber 'J', BusType 7.
1/24 13:46:29.941 19c SPTI: '\\?\usbstor#disk&ven_jetflash&prod_transcend_16gb&rev_8.07#j162frjk&0#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}': iScsiSptiDevice::openDevice: 'JetFlash Transcend 16GB 8.
7': adapter 0, bus 0, target 0, LUN 0; maxTransferLength 65536, alignmentMask 0x0
1/24 13:46:29.941 19c Srv: iScsiServer::tryToAddDevice: Found SPTI device '\\?\usbstor#disk&ven_jetflash&prod_transcend_16gb&rev_8.07#j162frjk&0#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}': 'JetFlash Tr
nscend 16GB 8.07'
1/24 13:46:29.941 ee8 bc: BCastListener::listenConnections: Waiting for broadcast UDP packets at 0.0.0.0:3261...
1/24 13:46:29.941 ee4 conf: TelnetListener::listenConnections: Waiting for Control connections at 0.0.0.0:3261 ...
1/24 13:46:30.098 ed4 Plugin: SW_WMIServerManagerDeploymentTasks::RefreshThreadProc: GetServerComponentsAsync RequestState - 100 of 100, 1, 0
1/24 13:46:32.910 19c Srv: iScsiServer::useFlb: Flb driver has been started.
1/24 13:46:32.910 19c Srv: iScsiServer::useFlb: Flb has been activated.
1/24 13:46:32.910 19c Srv: iScsiServer::listenConnections: Waiting for iSCSI connections at 127.0.0.1:3260 ...
1/24 13:46:32.910 19c Srv: iScsiServer::listenConnections: Waiting for iSCSI connections at 192.168.0.39:3260 ...
1/24 13:54:02.017 ee4 conf: TelnetListener::listenConnections: Accepted control connection from 127.0.0.1:49235.
1/24 13:54:06.876 158c Srv: Failed: one of experimental feature module file not found.
Boris (staff)
Staff
Posts: 805
Joined: Fri Jul 28, 2017 8:18 am

Fri Jan 25, 2019 4:24 pm

Use https://www.starwindsoftware.com/support-form to submit a support case for further investigation of the issue.
Boris (staff)
Staff
Posts: 805
Joined: Fri Jul 28, 2017 8:18 am

Tue Feb 05, 2019 7:44 am

The reason appeared to be a USB stick present in the server. Once removed the software started working as expected. The team has already started looking into this.
Davis
Posts: 24
Joined: Tue Jan 23, 2018 10:12 am

Wed Jun 17, 2020 12:22 pm

Really thank you!!!
You saved my mind.
I've stuck with same error on StarWind Virtual SAN v8.0.0 (Build 12767, [SwSAN], Win64) when I mounted Virtual CDROM via IPMI on my Supermicro server.
Unmounted and fixed!
yaroslav (staff)
Staff
Posts: 2279
Joined: Mon Nov 18, 2019 11:11 am

Wed Jun 17, 2020 12:46 pm

Hi Davis,

Happy to know that you were able to resolve your issue!
Davis
Posts: 24
Joined: Tue Jan 23, 2018 10:12 am

Wed Jun 17, 2020 1:58 pm

BTW,
If you interesting, I compared good and bad logs and found these differences:

1. This block is only in bad log:

Code: Select all

func: <<< CHADevice::CreateDevice
HA: *** CHAPartnerISCSIChannelManager::SendCustomControlScsiCommand: Valid channel not found!
conf: ConfAclCommand::processNew: Acl record 'allow for partner(s) of iqn.2008-08.com.starwindsoftware:hvc1-1-storage1' loaded successfully.
HA: *** CHAPartnerNode::SendPartnerNodeVersionRequestCommand: SendCustomControlScsiCommand(HA_CHANNEL_TYPE_SYNC) failed, error code 1168, scsi status = 0!
HA: CHAPartnerNode::SendPartnerNodeVersionRequestCommand: Try to get partner node version through heartbeat channel.
HA: *** CHAPartnerISCSIChannelManager::SendCustomControlScsiCommand: Valid channel not found!
HA: *** CHAPartnerNode::SendPartnerNodeVersionRequestCommand: EXITing with failure, SendCustomControlScsiCommand(HA_CHANNEL_TYPE_HEARTBEAT) failed, error code 1168, scsi status = 0!
HA: *** CHAPartnerNode::SendGetPartnerNodeInfoCommandV6: EXITing with failure, partner node version update failed!
2. This also in bad log

Code: Select all

SPTI: '\\?\usbstor#disk&ven_ipmi&prod_virtual_disk&rev_3000#8&306961b&0#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}': iScsiSptiDevice::updateDeviceIds: DeviceType 0, VendorId 'IPMI    ', ProductId 'Virtual Disk    ', ProductRevision '3000', SerialNumber '', BusType 7.
SPTI: '\\?\usbstor#disk&ven_ipmi&prod_virtual_disk&rev_3000#8&306961b&0#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}': iScsiSptiDevice::openDevice: 'IPMI     Virtual Disk     3000': adapter 0, bus 0, target 0, LUN 0; maxTransferLength 65536, alignmentMask 0x0
Srv: iScsiServer::tryToAddDevice: Found SPTI device '\\?\usbstor#disk&ven_ipmi&prod_virtual_disk&rev_3000#8&306961b&0#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}': 'IPMI     Virtual Disk     3000'
SPTI: '\\?\usbstor#cdrom&ven_ipmi&prod_virtual_cdrom&rev_ys0j#8&36f8ef92&0#{53f56308-b6bf-11d0-94f2-00a0c91efb8b}': iScsiSptiDevice::updateDeviceIds: DeviceType 5, VendorId 'IPMI    ', ProductId 'Virtual CDROM   ', ProductRevision 'YS0J', SerialNumber '', BusType 7.
SPTI: '\\?\usbstor#cdrom&ven_ipmi&prod_virtual_cdrom&rev_ys0j#8&36f8ef92&0#{53f56308-b6bf-11d0-94f2-00a0c91efb8b}': iScsiSptiDevice::openDevice: 'IPMI     Virtual CDROM    YS0J': adapter 0, bus 0, target 0, LUN 0; maxTransferLength 65536, alignmentMask 0x0
Srv: iScsiServer::tryToAddDevice: Found SPTI device '\\?\usbstor#cdrom&ven_ipmi&prod_virtual_cdrom&rev_ys0j#8&36f8ef92&0#{53f56308-b6bf-11d0-94f2-00a0c91efb8b}': 'IPMI     Virtual CDROM    YS0J'
yaroslav (staff)
Staff
Posts: 2279
Joined: Mon Nov 18, 2019 11:11 am

Fri Jun 19, 2020 10:57 am

Hi Davis,

I would be happy to see all the logs collected with log collector https://knowledgebase.starwindsoftware. ... collector/. Could you share them via Google Drive?
And, I'd like to recommend updating to the latest StarWind VSAN build. See the update procedure here https://knowledgebase.starwindsoftware. ... d-version/.
You can get the installer here https://www.starwindsoftware.com/tmplin ... ind-v8.exe.
Post Reply