EXITing with failure(failed), valid channel is not found!

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

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

Post Reply
Kishore CA
Posts: 26
Joined: Wed Jan 04, 2023 10:38 am

Thu Feb 09, 2023 9:44 am

Dear Team,
I have made setup for 2 Node Starwind vSPhere configuration.

Setup ESXi Server1 & 2:
Starwindvsa_Setup.png
Starwindvsa_Setup.png (20.77 KiB) Viewed 2611 times
Management (VM) Port Connected to 1G Copper Switch.
ISCSI/Heartbeat and Synchronization is connected directly back to back at 10G-SFP Ports of servers using fiber cables.
StarwindVSA (preconfigured VM) is deployed in both ESXi Server.

I'm trying with Starwind Free version. Im using powershell comands for configuring.

During creation of HA configuration it is throwing an error in both servers.
Error in ESXi Server1 - HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
Error in ESXi Server2 - HA: *** HAPartnerISCSIChannelManager::sendCustomControlScsiCommand: EXITing with failure(failed), valid channel is not found!

I have attached the Code and Logs.

CreateHA_2.ps1

Code: Select all

param($addr="10.10.184.234", $port=3261, $user="root", $password="starwind",
   $addr2="10.10.184.235", $port2=$port, $user2=$user, $password2=$password,
#common
   $initMethod="Clear",
   $size=10240,
   $sectorSize=512,
   $failover=0,
   $bmpType=1,
   $bmpStrategy=0,
#primary node
   $imagePath="/mnt/vSANPool",
   $imageName="primaryimage",
   $createImage=$true,
   $storageName="",
   $targetAlias="vSANPool",
   $autoSynch=$true,
   $poolName="vSANPool",
   $syncSessionCount=1,
   $aluaOptimized=$true,
   $cacheMode="",
   $cacheSize=128,
   $syncInterface="#p2=10.10.10.200:3260" -f $addr2,
   $hbInterface="#p2=20.20.20.200:3260,10.10.184.235:3260" -f $addr2,
   $createTarget=$true,
   $bmpFolderPath="",
#secondary node
   $imagePath2="/mnt/vSANPool",
   $imageName2="secondaryimage",
   $createImage2=$true,
   $storageName2="",
   $targetAlias2="vSANPool",
   $autoSynch2=$true,
   $poolName2="vSANPool",
   $syncSessionCount2=1,
   $aluaOptimized2=$false,
   $cacheMode2=$cacheMode,
   $cacheSize2=$cacheSize,
   $syncInterface2="#p1=10.10.10.200:3260" -f $addr,
   $hbInterface2="#p1=20.20.20.100:3260,10.10.184.234:3260" -f $addr,
   $createTarget2=$true,
   $bmpFolderPath2=""
   )
   
Import-Module StarWindX
Logs:
ServerLog in ESXi Server1

Code: Select all

2/9 8:15:50.065898 122 HA: HADeviceEventTask::execute: Event: HA_NN_ALL_CHANNELS_BECOME_VALID, Partner node: 'iqn.2008-08.com.starwindsoftware:10.10.184.235-vsanpool', ulChannelManagerId = 2.
2/9 8:15:50.070644 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:15:50.890432 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:15:50.890479 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:15:51.895873 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:15:51.895934 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:15:52.911335 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:15:52.911393 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:15:53.927662 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:15:53.927729 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:15:54.943316 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:15:54.943378 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:15:55.958506 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:15:55.958572 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:15:56.073467 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:15:56.974178 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:15:56.974238 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:15:57.990060 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:15:57.990123 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:15:59.005437 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:15:59.005497 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:00.021148 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:00.021215 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:01.036788 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:01.036853 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:02.052251 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:02.052321 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:02.076608 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:16:03.068047 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:03.068109 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:04.083628 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:04.083692 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:05.099086 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:05.099150 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:06.114561 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:06.114623 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:07.130552 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:07.130617 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:08.079938 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:16:08.146070 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:08.146130 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:09.161600 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:09.161667 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:10.177050 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:10.177113 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:11.193133 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:11.193200 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:12.208692 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:12.208759 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:13.224388 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:13.224452 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:14.083128 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:16:14.239930 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:14.240027 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:15.256685 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:15.256753 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:16.271266 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:16.271331 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:17.286850 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:17.286916 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:18.302105 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:18.302167 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:19.317920 119 HA: SscPort_ControlRequest: Event: Received initial synchronization command from console (sync_type: 3, synchronizer: '(null)')
2/9 8:16:19.318016 119 HA: HANode::controlReq_InitialSynchronization: Params(syncType = 3, autoMount = 0, synchronizerTargetName = '(null)') ENTERed
2/9 8:16:19.905433 135 HA: *** HASyncNode::threadSyncAfterCreation: Connection to the iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool partner was not establised in time.
2/9 8:16:20.086645 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:16:26.090716 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:16:32.095022 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:16:38.099936 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:16:44.103101 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:16:50.105516 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:16:56.108950 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:17:02.111964 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:17:08.116158 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:17:14.119661 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:17:20.122222 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:17:26.125542 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:17:32.129225 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:17:38.132794 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:17:44.137367 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:17:50.140489 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:17:56.144016 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:18:02.147193 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:18:08.150928 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:18:14.154629 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:18:20.158195 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:18:26.161424 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:18:32.165650 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:18:38.168917 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:18:44.172244 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:18:50.175466 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:18:56.179093 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:19:02.183115 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:19:08.186813 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:19:14.189536 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:19:20.193225 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:19:26.196621 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:19:32.200840 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:19:38.205449 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:19:44.209290 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:19:50.212703 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:19:56.216346 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:20:02.219225 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:20:08.222158 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:20:14.225402 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:20:20.230142 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:20:26.233870 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:20:32.237511 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:20:38.241451 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
2/9 8:20:44.244570 128 HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Waiting 6 sec before retry sending backward connection command ...
ServerLog in ESXi Server2

Code: Select all

2/9 8:15:50.044071 1b0 Params: iScsiParameter::update: <<< Enum param 'DataDigest': received 'None', accepted 'None'
2/9 8:15:50.044086 1b0 Params: iScsiParameter::update: <<< Boolean param 'InitialR2T': received Yes, accepted 1
2/9 8:15:50.044103 1b0 Params: iScsiParameter::update: <<< Boolean param 'ImmediateData': received Yes, accepted 1
2/9 8:15:50.044119 1b0 Params: iScsiParameter::update: <<< Numeric param 'MaxBurstLength': received 262144, accepted 262144
2/9 8:15:50.044137 1b0 Params: iScsiParameter::update: <<< Numeric param 'FirstBurstLength': received 262144, accepted 262144
2/9 8:15:50.044154 1b0 Params: iScsiParameter::update: <<< Numeric param 'MaxRecvDataSegmentLength': received 262144, accepted 262144
2/9 8:15:50.044171 1b0 Params: iScsiParameter::update: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
2/9 8:15:50.044188 1b0 Params: iScsiParameter::update: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
2/9 8:15:50.044203 1b0 Params: iScsiParameter::update: <<< Boolean param 'IFMarker': received No, accepted 0
2/9 8:15:50.044217 1b0 Params: iScsiParameter::update: <<< Boolean param 'OFMarker': received No, accepted 0
2/9 8:15:50.044232 1b0 Params: iScsiParameter::update: <<< Numeric param 'DefaultTime2Wait': received 2, accepted 2
2/9 8:15:50.044249 1b0 Params: iScsiParameter::update: <<< Numeric param 'DefaultTime2Retain': received 0, accepted 0
2/9 8:15:50.044274 1b0 Params: iScsiParameter::update: <<< Numeric param 'MaxOutstandingR2T': received 1, accepted 1
2/9 8:15:50.044288 1b0 Params: iScsiParameter::update: <<< Numeric param 'MaxConnections': received 1, accepted 1
2/9 8:15:50.044305 1b0 Params: iScsiParameter::update: <<< Numeric param 'ErrorRecoveryLevel': received 0, accepted 0
2/9 8:15:50.045020 9 Srv: iScsiServer::listenConnections: Accepted iSCSI connection from 20.20.20.100:22938 to 20.20.20.200:3260. (Id = 0x42)
2/9 8:15:50.053348 1b0 HA: HASyncNode::registerSession: Partner initiator iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool has registered a new session.
2/9 8:15:50.053391 1b0 HA: HASyncNode::registerSession: Return code 0.
2/9 8:15:50.053408 1b0 PR: ResLunSessionEngine::registerSession: LUN 0: registering session 0x41 from iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool,8021010A06BC 
2/9 8:15:50.053428 1b0 Tgt: iScsiTarget::openSession: open 'iqn.2008-08.com.starwindsoftware:10.10.184.235-vsanpool': 5 session(s) opened, 65531 more allowed.
2/9 8:15:50.053479 1b0 Params: iScsiParams::createKeys: >>> ErrorRecoveryLevel=0.
2/9 8:15:50.053499 1b0 Params: iScsiParams::createKeys: >>> MaxConnections=1.
2/9 8:15:50.053516 1b0 Params: iScsiParams::createKeys: >>> HeaderDigest=None.
2/9 8:15:50.053480 9 S[42]: iScsiSession::iScsiSession: Session (0000000000FA3C40)
2/9 8:15:50.053549 9 C[42], FREE: iScsiConnection::doTransition: Event - CONNECTED.
2/9 8:15:50.053529 1b0 Params: iScsiParams::createKeys: >>> DataDigest=None.
2/9 8:15:50.053617 1b0 Params: iScsiParams::createKeys: >>> TargetAlias=vSANPool.
2/9 8:15:50.053633 1b0 Params: iScsiParams::createKeys: >>> OFMarker=No.
2/9 8:15:50.053645 1b0 Params: iScsiParams::createKeys: >>> IFMarker=No.
2/9 8:15:50.053657 1b0 Params: iScsiParams::createKeys: >>> InitialR2T=Yes.
2/9 8:15:50.053671 1b0 Params: iScsiParams::createKeys: >>> ImmediateData=Yes.
2/9 8:15:50.053683 1b0 Params: iScsiParams::createKeys: >>> MaxRecvDataSegmentLength=262144.
2/9 8:15:50.053696 1b0 Params: iScsiParams::createKeys: >>> MaxBurstLength=262144.
2/9 8:15:50.053707 1b0 Params: iScsiParams::createKeys: >>> FirstBurstLength=262144.
2/9 8:15:50.053719 1b0 Params: iScsiParams::createKeys: >>> DefaultTime2Wait=2.
2/9 8:15:50.053733 1b0 Params: iScsiParams::createKeys: >>> DefaultTime2Retain=0.
2/9 8:15:50.053747 1b0 Params: iScsiParams::createKeys: >>> MaxOutstandingR2T=1.
2/9 8:15:50.053759 1b0 Params: iScsiParams::createKeys: >>> DataPDUInOrder=Yes.
2/9 8:15:50.053774 1b0 Params: iScsiParams::createKeys: >>> DataSequenceInOrder=Yes.
2/9 8:15:50.053785 1b0 Params: iScsiParams::createKeys: >>> TargetPortalGroupTag=1.
2/9 8:15:50.053774 1b0 Params: iScsiParams::createKeys: >>> DataSequenceInOrder=Yes.
2/9 8:15:50.053785 1b0 Params: iScsiParams::createKeys: >>> TargetPortalGroupTag=1.
2/9 8:15:50.053997 1b0 Srv: SwServerNode::bindThread: 0x1af (431) to group 0, affinity 0xff
2/9 8:15:50.054065 1b0 S[41]: iScsiSession::bindToServerNode: execThread handle = 0x00000000000002F4, id = 431 
2/9 8:15:50.054319 1b0 Srv: SwServerNode::bindThread: 0x1b0 (432) to group 0, affinity 0xff
2/9 8:15:50.054482 1b0 Srv: SwServerNode::bindThread: 0x1ae (430) to group 0, affinity 0xff
2/9 8:15:50.054568 1af T[41,1]: iScsiTask::execLoginReq: session 0x41, connection 0x41 : end of stage 1, next stage 3.
2/9 8:15:50.054615 1af C[41], IN_LOGIN: iScsiConnection::doTransition: Event - LOGIN_ACCEPT.
2/9 8:15:50.055161 1b3 C[42], XPT_UP: iScsiConnection::handleFirstLogin: Login request: ISID 0x8023020afdd8, TSIH 0x0000.
2/9 8:15:50.055195 1b3 C[42], XPT_UP: iScsiConnection::doTransition: Event - LOGIN.
2/9 8:15:50.055243 1b3 Params: iScsiParameter::update: <<< String param 'InitiatorName': received 'iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool', accepted 'iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool'
2/9 8:15:50.055259 1b3 Params: iScsiParameter::update: <<< String param 'InitiatorAlias': received 'starwind.iscsi.transport.v8.0', accepted 'starwind.iscsi.transport.v8.0'
2/9 8:15:50.055269 1b3 Params: iScsiParameter::update: <<< String param 'TargetName': received 'iqn.2008-08.com.starwindsoftware:10.10.184.235-vsanpool', accepted 'iqn.2008-08.com.starwindsoftware:10.10.184.235-vsanpool'
2/9 8:15:50.055282 1b3 Params: iScsiParameter::update: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
2/9 8:15:50.055293 1b3 Params: iScsiParameter::update: <<< Enum param 'HeaderDigest': received 'None', accepted 'None'
2/9 8:15:50.055301 1b3 Params: iScsiParameter::update: <<< Enum param 'DataDigest': received 'None', accepted 'None'
2/9 8:15:50.055309 1b3 Params: iScsiParameter::update: <<< Boolean param 'InitialR2T': received Yes, accepted 1
2/9 8:15:50.055318 1b3 Params: iScsiParameter::update: <<< Boolean param 'ImmediateData': received Yes, accepted 1
2/9 8:15:50.055326 1b3 Params: iScsiParameter::update: <<< Numeric param 'MaxBurstLength': received 262144, accepted 262144
2/9 8:15:50.055336 1b3 Params: iScsiParameter::update: <<< Numeric param 'FirstBurstLength': received 262144, accepted 262144
2/9 8:15:50.055345 1b3 Params: iScsiParameter::update: <<< Numeric param 'MaxRecvDataSegmentLength': received 262144, accepted 262144
2/9 8:15:50.055354 1b3 Params: iScsiParameter::update: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
2/9 8:15:50.055363 1b3 Params: iScsiParameter::update: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
2/9 8:15:50.055371 1b3 Params: iScsiParameter::update: <<< Boolean param 'IFMarker': received No, accepted 0
2/9 8:15:50.055378 1b3 Params: iScsiParameter::update: <<< Boolean param 'OFMarker': received No, accepted 0
2/9 8:15:50.055386 1b3 Params: iScsiParameter::update: <<< Numeric param 'DefaultTime2Wait': received 2, accepted 2
2/9 8:15:50.055395 1b3 Params: iScsiParameter::update: <<< Numeric param 'DefaultTime2Retain': received 0, accepted 0
2/9 8:15:50.055404 1b3 Params: iScsiParameter::update: <<< Numeric param 'MaxOutstandingR2T': received 1, accepted 1
2/9 8:15:50.055411 1b3 Params: iScsiParameter::update: <<< Numeric param 'MaxConnections': received 1, accepted 1
2/9 8:15:50.055419 1b3 Params: iScsiParameter::update: <<< Numeric param 'ErrorRecoveryLevel': received 0, accepted 0
2/9 8:15:50.055602 9 Srv: iScsiServer::listenConnections: Accepted iSCSI connection from 10.10.10.100:50830 to 10.10.10.200:3260. (Id = 0x43)
2/9 8:15:50.061573 1b3 HA: HASyncNode::registerSession: Partner initiator iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool has registered a new session.
2/9 8:15:50.061606 1b3 HA: HASyncNode::registerSession: Return code 0.
2/9 8:15:50.061618 1b3 PR: ResLunSessionEngine::registerSession: LUN 0: registering session 0x42 from iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool,8023020AFDD8 
2/9 8:15:50.061633 1b3 Tgt: iScsiTarget::openSession: open 'iqn.2008-08.com.starwindsoftware:10.10.184.235-vsanpool': 6 session(s) opened, 65530 more allowed.
2/9 8:15:50.061664 1b3 Params: iScsiParams::createKeys: >>> ErrorRecoveryLevel=0.
2/9 8:15:50.061675 1b3 Params: iScsiParams::createKeys: >>> MaxConnections=1.
2/9 8:15:50.061689 1b3 Params: iScsiParams::createKeys: >>> HeaderDigest=None.
2/9 8:15:50.061675 1b3 Params: iScsiParams::createKeys: >>> MaxConnections=1.
2/9 8:15:50.061689 1b3 Params: iScsiParams::createKeys: >>> HeaderDigest=None.
2/9 8:15:50.061699 1b3 Params: iScsiParams::createKeys: >>> DataDigest=None.
2/9 8:15:50.061664 9 S[43]: iScsiSession::iScsiSession: Session (0000000000FE28C0)
2/9 8:15:50.061708 1b3 Params: iScsiParams::createKeys: >>> TargetAlias=vSANPool.
2/9 8:15:50.061776 1b3 Params: iScsiParams::createKeys: >>> OFMarker=No.
2/9 8:15:50.061786 1b3 Params: iScsiParams::createKeys: >>> IFMarker=No.
2/9 8:15:50.061797 1b3 Params: iScsiParams::createKeys: >>> InitialR2T=Yes.
2/9 8:15:50.061808 1b3 Params: iScsiParams::createKeys: >>> ImmediateData=Yes.
2/9 8:15:50.061819 1b3 Params: iScsiParams::createKeys: >>> MaxRecvDataSegmentLength=262144.
2/9 8:15:50.061829 1b3 Params: iScsiParams::createKeys: >>> MaxBurstLength=262144.
2/9 8:15:50.061840 1b3 Params: iScsiParams::createKeys: >>> FirstBurstLength=262144.
2/9 8:15:50.061849 1b3 Params: iScsiParams::createKeys: >>> DefaultTime2Wait=2.
2/9 8:15:50.061862 1b3 Params: iScsiParams::createKeys: >>> DefaultTime2Retain=0.
2/9 8:15:50.061873 1b3 Params: iScsiParams::createKeys: >>> MaxOutstandingR2T=1.
2/9 8:15:50.061888 1b3 Params: iScsiParams::createKeys: >>> DataPDUInOrder=Yes.
2/9 8:15:50.061758 9 C[43], FREE: iScsiConnection::doTransition: Event - CONNECTED.
2/9 8:15:50.061900 1b3 Params: iScsiParams::createKeys: >>> DataSequenceInOrder=Yes.
2/9 8:15:50.061985 1b3 Params: iScsiParams::createKeys: >>> TargetPortalGroupTag=1.
2/9 8:15:50.062212 1b3 Srv: SwServerNode::bindThread: 0x1b2 (434) to group 0, affinity 0xff
2/9 8:15:50.062281 1b3 S[42]: iScsiSession::bindToServerNode: execThread handle = 0x0000000000000304, id = 434 
2/9 8:15:50.062429 1b3 Srv: SwServerNode::bindThread: 0x1b3 (435) to group 0, affinity 0xff
2/9 8:15:50.062541 1b3 Srv: SwServerNode::bindThread: 0x1b1 (433) to group 0, affinity 0xff
2/9 8:15:50.062683 1b2 T[42,1]: iScsiTask::execLoginReq: session 0x42, connection 0x42 : end of stage 1, next stage 3.
2/9 8:15:50.062736 1b2 C[42], IN_LOGIN: iScsiConnection::doTransition: Event - LOGIN_ACCEPT.
2/9 8:15:50.063657 1b6 C[43], XPT_UP: iScsiConnection::handleFirstLogin: Login request: ISID 0x801f01111be3, TSIH 0x0000.
2/9 8:15:50.063696 1b6 C[43], XPT_UP: iScsiConnection::doTransition: Event - LOGIN.
2/9 8:15:50.063747 1b6 Params: iScsiParameter::update: <<< String param 'InitiatorName': received 'iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool', accepted 'iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool'
2/9 8:15:50.063767 1b6 Params: iScsiParameter::update: <<< String param 'InitiatorAlias': received 'starwind.iscsi.transport.v8.0', accepted 'starwind.iscsi.transport.v8.0'
2/9 8:15:50.063779 1b6 Params: iScsiParameter::update: <<< String param 'TargetName': received 'iqn.2008-08.com.starwindsoftware:10.10.184.235-vsanpool', accepted 'iqn.2008-08.com.starwindsoftware:10.10.184.235-vsanpool'
2/9 8:15:50.063791 1b6 Params: iScsiParameter::update: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
2/9 8:15:50.063805 1b6 Params: iScsiParameter::update: <<< Enum param 'HeaderDigest': received 'None', accepted 'None'
2/9 8:15:50.063816 1b6 Params: iScsiParameter::update: <<< Enum param 'DataDigest': received 'None', accepted 'None'
2/9 8:15:50.063827 1b6 Params: iScsiParameter::update: <<< Boolean param 'InitialR2T': received Yes, accepted 1
2/9 8:15:50.063907 1b6 Params: iScsiParameter::update: <<< Boolean param 'ImmediateData': received Yes, accepted 1
2/9 8:15:50.063939 1b6 Params: iScsiParameter::update: <<< Numeric param 'MaxBurstLength': received 262144, accepted 262144
2/9 8:15:50.063951 1b6 Params: iScsiParameter::update: <<< Numeric param 'FirstBurstLength': received 262144, accepted 262144
2/9 8:15:50.063963 1b6 Params: iScsiParameter::update: <<< Numeric param 'MaxRecvDataSegmentLength': received 262144, accepted 262144
2/9 8:15:50.063976 1b6 Params: iScsiParameter::update: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
2/9 8:15:50.063987 1b6 Params: iScsiParameter::update: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
2/9 8:15:50.063997 1b6 Params: iScsiParameter::update: <<< Boolean param 'IFMarker': received No, accepted 0
2/9 8:15:50.064007 1b6 Params: iScsiParameter::update: <<< Boolean param 'OFMarker': received No, accepted 0
2/9 8:15:50.063997 1b6 Params: iScsiParameter::update: <<< Boolean param 'IFMarker': received No, accepted 0
2/9 8:15:50.064007 1b6 Params: iScsiParameter::update: <<< Boolean param 'OFMarker': received No, accepted 0
2/9 8:15:50.064018 1b6 Params: iScsiParameter::update: <<< Numeric param 'DefaultTime2Wait': received 2, accepted 2
2/9 8:15:50.064030 1b6 Params: iScsiParameter::update: <<< Numeric param 'DefaultTime2Retain': received 0, accepted 0
2/9 8:15:50.064042 1b6 Params: iScsiParameter::update: <<< Numeric param 'MaxOutstandingR2T': received 1, accepted 1
2/9 8:15:50.064052 1b6 Params: iScsiParameter::update: <<< Numeric param 'MaxConnections': received 1, accepted 1
2/9 8:15:50.064064 1b6 Params: iScsiParameter::update: <<< Numeric param 'ErrorRecoveryLevel': received 0, accepted 0
2/9 8:15:50.067863 1b6 HA: HASyncNode::registerSession: Partner initiator iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool has registered a new session.
2/9 8:15:50.067881 1b6 HA: HASyncNode::registerSession: Return code 0.
2/9 8:15:50.067887 1b6 PR: ResLunSessionEngine::registerSession: LUN 0: registering session 0x43 from iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool,801F01111BE3 
2/9 8:15:50.067896 1b6 Tgt: iScsiTarget::openSession: open 'iqn.2008-08.com.starwindsoftware:10.10.184.235-vsanpool': 7 session(s) opened, 65529 more allowed.
2/9 8:15:50.067906 1b6 Params: iScsiParams::createKeys: >>> ErrorRecoveryLevel=0.
2/9 8:15:50.067920 1b6 Params: iScsiParams::createKeys: >>> MaxConnections=1.
2/9 8:15:50.067925 1b6 Params: iScsiParams::createKeys: >>> HeaderDigest=None.
2/9 8:15:50.067929 1b6 Params: iScsiParams::createKeys: >>> DataDigest=None.
2/9 8:15:50.067932 1b6 Params: iScsiParams::createKeys: >>> TargetAlias=vSANPool.
2/9 8:15:50.067937 1b6 Params: iScsiParams::createKeys: >>> OFMarker=No.
2/9 8:15:50.067940 1b6 Params: iScsiParams::createKeys: >>> IFMarker=No.
2/9 8:15:50.067944 1b6 Params: iScsiParams::createKeys: >>> InitialR2T=Yes.
2/9 8:15:50.067948 1b6 Params: iScsiParams::createKeys: >>> ImmediateData=Yes.
2/9 8:15:50.067951 1b6 Params: iScsiParams::createKeys: >>> MaxRecvDataSegmentLength=262144.
2/9 8:15:50.067955 1b6 Params: iScsiParams::createKeys: >>> MaxBurstLength=262144.
2/9 8:15:50.067958 1b6 Params: iScsiParams::createKeys: >>> FirstBurstLength=262144.
2/9 8:15:50.067962 1b6 Params: iScsiParams::createKeys: >>> DefaultTime2Wait=2.
2/9 8:15:50.067966 1b6 Params: iScsiParams::createKeys: >>> DefaultTime2Retain=0.
2/9 8:15:50.067970 1b6 Params: iScsiParams::createKeys: >>> MaxOutstandingR2T=1.
2/9 8:15:50.067974 1b6 Params: iScsiParams::createKeys: >>> DataPDUInOrder=Yes.
2/9 8:15:50.067978 1b6 Params: iScsiParams::createKeys: >>> DataSequenceInOrder=Yes.
2/9 8:15:50.067981 1b6 Params: iScsiParams::createKeys: >>> TargetPortalGroupTag=1.
2/9 8:15:50.067981 1b6 Params: iScsiParams::createKeys: >>> TargetPortalGroupTag=1.
2/9 8:15:50.068095 1b6 Srv: SwServerNode::bindThread: 0x1b5 (437) to group 0, affinity 0xff
2/9 8:15:50.068138 1b6 S[43]: iScsiSession::bindToServerNode: execThread handle = 0x0000000000000314, id = 437 
2/9 8:15:50.068250 1b6 Srv: SwServerNode::bindThread: 0x1b6 (438) to group 0, affinity 0xff
2/9 8:15:50.068348 1b6 Srv: SwServerNode::bindThread: 0x1b4 (436) to group 0, affinity 0xff
2/9 8:15:50.068398 1b5 T[43,1]: iScsiTask::execLoginReq: session 0x43, connection 0x43 : end of stage 1, next stage 3.
2/9 8:15:50.068437 1b5 C[43], IN_LOGIN: iScsiConnection::doTransition: Event - LOGIN_ACCEPT.
2/9 8:15:55.889576 19b HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Event: Established connection - Device(0x000000000101E1D8), IP(10.10.184.234), InitiatorName(iqn.2008-08.com.starwindsoftware:10.10.184.235-vsanpool), Target(iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool)!
2/9 8:15:55.889968 195 func: >>> HANode::reportEvent
2/9 8:15:55.890048 195 func: >>> iScsiServer::sendNotification
2/9 8:15:55.890064 195 func: >>> CEventDataBase::AddRecord
2/9 8:15:55.890075 195 EventDB: CEventDataBase::AddRecord: Code 777, severity 1, additional strings: 4
2/9 8:15:55.890123 195 func: <<< CEventDataBase::AddRecord
2/9 8:15:55.890135 195 Srv: iScsiServer::sendNotification: Send reaction start (reaction type 2, msg code 777).
2/9 8:15:55.890162 195 Srv: iScsiServer::sendNotification: Send reaction finish.
2/9 8:15:55.890173 195 Srv: iScsiServer::sendNotification: Send reaction start (reaction type 1, msg code 777).
2/9 8:15:55.890601 195 Srv: iScsiServer::sendNotification: Send reaction finish.
2/9 8:15:55.890624 195 func: <<< iScsiServer::sendNotification
2/9 8:15:55.890624 195 func: <<< iScsiServer::sendNotification
2/9 8:15:55.890638 195 func: <<< HANode::reportEvent
2/9 8:15:55.890651 195 HA: HADeviceEventTask::execute: Event: HA_NN_CHANNEL_BECOME_VALID, Partner node: 'iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool', ulChannelManagerId = 2, ulChannelId = 1.
2/9 8:15:55.890675 195 HA: HADeviceEventTask::execute: Event: HA_NN_FIRST_VALID_CONNECTION_ESTABLISHED, Partner node: 'iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool', ulChannelManagerId = 2, ulChannelId = 1.
2/9 8:15:55.907520 19c HA: HAPartneriScsiChannel::permanentConnectionPollingThread: Event: Established connection - Device(0x0000000001053F08), IP(20.20.20.100), InitiatorName(iqn.2008-08.com.starwindsoftware:10.10.184.235-vsanpool), Target(iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool)!
2/9 8:15:55.907869 195 func: >>> HANode::reportEvent
2/9 8:15:55.907909 195 func: >>> iScsiServer::sendNotification
2/9 8:15:55.907952 195 func: >>> CEventDataBase::AddRecord
2/9 8:15:55.907963 195 EventDB: CEventDataBase::AddRecord: Code 777, severity 1, additional strings: 4
2/9 8:15:55.908006 195 func: <<< CEventDataBase::AddRecord
2/9 8:15:55.908017 195 Srv: iScsiServer::sendNotification: Send reaction start (reaction type 2, msg code 777).
2/9 8:15:55.908042 195 Srv: iScsiServer::sendNotification: Send reaction finish.
2/9 8:15:55.908054 195 Srv: iScsiServer::sendNotification: Send reaction start (reaction type 1, msg code 777).
2/9 8:15:55.908470 195 Srv: iScsiServer::sendNotification: Send reaction finish.
2/9 8:15:55.908489 195 func: <<< iScsiServer::sendNotification
2/9 8:15:55.908501 195 func: <<< HANode::reportEvent
2/9 8:15:55.908511 195 HA: HADeviceEventTask::execute: Event: HA_NN_CHANNEL_BECOME_VALID, Partner node: 'iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool', ulChannelManagerId = 2, ulChannelId = 2.
2/9 8:15:55.908532 195 func: >>> HANode::reportEvent
2/9 8:15:55.908544 195 func: >>> iScsiServer::sendNotification
2/9 8:15:55.908555 195 func: >>> CEventDataBase::AddRecord
2/9 8:15:55.908565 195 EventDB: CEventDataBase::AddRecord: Code 837, severity 1, additional strings: 3
2/9 8:15:55.908595 195 func: <<< CEventDataBase::AddRecord
2/9 8:15:55.908605 195 Srv: iScsiServer::sendNotification: Send reaction start (reaction type 2, msg code 837).
2/9 8:15:55.908626 195 Srv: iScsiServer::sendNotification: Send reaction finish.
2/9 8:15:55.908638 195 Srv: iScsiServer::sendNotification: Send reaction start (reaction type 1, msg code 837).
2/9 8:15:55.908958 195 Srv: iScsiServer::sendNotification: Send reaction finish.
2/9 8:15:55.908981 195 func: <<< iScsiServer::sendNotification
2/9 8:15:55.908993 195 func: <<< HANode::reportEvent
2/9 8:15:55.909003 195 HA: HADeviceEventTask::execute: Event: HA_NN_ALL_CHANNELS_BECOME_VALID, Partner node: 'iqn.2008-08.com.starwindsoftware:10.10.184.234-vsanpool', ulChannelManagerId = 2.
2/9 8:15:59.849855 19d HA: *** HAPartnerISCSIChannelManager::sendCustomControlScsiCommand: EXITing with failure(failed), valid channel is not found!
2/9 8:15:59.849934 19d HA: *** CHAPartnerNode::SendPartnerNodeVersionRequestCommand: SendCustomControlScsiCommand(HA_CHANNEL_TYPE_SYNC) failed, error code 0x490, scsi status = 0x0!
2/9 8:15:59.849962 19d HA: CHAPartnerNode::SendPartnerNodeVersionRequestCommand: Try to get partner node version through heartbeat channel.
2/9 8:15:59.851442 19d HA: *** HAPartnerISCSIChannelManager::sendCustomControlScsiCommand: EXITing with failure(failed), valid channel is not found!
2/9 8:16:04.853794 19d HA: *** HAPartnerISCSIChannelManager::sendCustomControlScsiCommand: EXITing with failure(failed), valid channel is not found!
2/9 8:16:09.857287 19d HA: *** HAPartnerISCSIChannelManager::sendCustomControlScsiCommand: EXITing with failure(failed), valid channel is not found!
2/9 8:16:14.861029 19d HA: *** HAPartnerISCSIChannelManager::sendCustomControlScsiCommand: EXITing with failure(failed), valid channel is not found!
2/9 8:16:19.846492 193 HA: *** HAPartnerISCSIChannelManager::sendCustomControlScsiCommand: EXITing with failure(failed), valid channel is not found!
2/9 8:16:19.846492 193 HA: *** HAPartnerISCSIChannelManager::sendCustomControlScsiCommand: EXITing with failure(failed), valid channel is not found!
2/9 8:16:19.846559 193 HA: *** CHAPartnerNode::SendGetPartnerNodeInfoCommand: EXITing with failure, SendCustomControlScsiCommand() failed, error code 1168, scsi status = 0!
2/9 8:16:19.864386 19d HA: *** HAPartnerISCSIChannelManager::sendCustomControlScsiCommand: EXITing with failure(failed), valid channel is not found!
2/9 8:16:24.867863 19d HA: *** HAPartnerISCSIChannelManager::sendCustomControlScsiCommand: EXITing with failure(failed), valid channel is not found!
2/9 8:16:29.871709 19d HA: *** HAPartnerISCSIChannelManager::sendCustomControlScsiCommand: EXITing with failure(failed), valid channel is not found!
2/9 8:16:34.874857 19d HA: *** HAPartnerISCSIChannelManager::sendCustomControlScsiCommand: EXITing with failure(failed), valid channel is not found!
2/9 8:16:39.878039 19d HA: *** HAPartnerISCSIChannelManager::sendCustomControlScsiCommand: EXITing with failure(failed), valid channel is not found!
Kindly Help me out.

Regards,
Kishore CA
Top
yaroslav (staff)
Staff
Posts: 2361
Joined: Mon Nov 18, 2019 11:11 am

Fri Feb 10, 2023 11:36 am

The sync IP address is wrong: you are using 10.10.10.200 for both servers.
Top
Kishore CA
Posts: 26
Joined: Wed Jan 04, 2023 10:38 am

Mon Feb 13, 2023 4:32 am

yaroslav (staff) wrote:The sync IP address is wrong: you are using 10.10.10.200 for both servers.
Thanks for that sir.

But even after correcting this synchronisation is not happening. When i check the network speed in starwindvsa VM web browser it is showing data transfer in kbps speed.

I have connected 10G ports back to back, should i want to use any switch in between??.

any other things should i want to check??

Kindly help.
Top
yaroslav (staff)
Staff
Posts: 2361
Joined: Mon Nov 18, 2019 11:11 am

Mon Feb 13, 2023 5:07 am

Were you able to ping the servers via that link?
Try setting MTU to 1500 for the entire network stack (vSwitches and inside the VMs).
Top
Kishore CA
Posts: 26
Joined: Wed Jan 04, 2023 10:38 am

Mon Feb 13, 2023 7:42 am

yaroslav (staff) wrote:Were you able to ping the servers via that link?
Try setting MTU to 1500 for the entire network stack (vSwitches and inside the VMs).

I’m able to ping sir .. yes I have tried with MTU 1500 also..

But even then it’s same problem.
Top
yaroslav (staff)
Staff
Posts: 2361
Joined: Mon Nov 18, 2019 11:11 am

Mon Feb 13, 2023 1:26 pm

Can I have the logs, and the new script, please?
Top
Kishore CA
Posts: 26
Joined: Wed Jan 04, 2023 10:38 am

Tue Feb 14, 2023 10:08 am

yaroslav (staff) wrote:Can I have the logs, and the new script, please?
Latest Code and Logs are attached. Kindly highlight my mistake sir.

Setup
Starwindvsa_Setup.png
Starwindvsa_Setup.png (20.89 KiB) Viewed 2545 times
Latest Code

Code: Select all

param($addr="10.10.184.234", $port=3261, $user="root", $password="starwind",
	$addr2="10.10.184.235", $port2=$port, $user2=$user, $password2=$password,
#common
	$initMethod="Clear",
	$size=10240,
	$sectorSize=512,
	$failover=0,
	$bmpType=1,
	$bmpStrategy=0,
#primary node
	$imagePath="/mnt",
	$imageName="primaryimage",
	$createImage=$true,
	$storageName="",
	$targetAlias="vSANPool",
	$autoSynch=$true,
	$poolName="vSANPool",
	$syncSessionCount=1,
	$aluaOptimized=$true,
	$cacheMode="",
	$cacheSize=128,
	$syncInterface="#p2=172.20.20.200:3260" -f $addr2,
	$hbInterface="#p2=172.10.10.200:3260,10.10.184.235:3260" -f $addr2,
	$createTarget=$true,
	$bmpFolderPath="",
#secondary node
	$imagePath2="/mnt",
	$imageName2="secondaryimage",
	$createImage2=$true,
	$storageName2="",
	$targetAlias2="vSANPool",
	$autoSynch2=$true,
	$poolName2="vSANPool",
	$syncSessionCount2=1,
	$aluaOptimized2=$false,
	$cacheMode2=$cacheMode,
	$cacheSize2=$cacheSize,
	$syncInterface2="#p1=172.20.20.100:3260" -f $addr,
	$hbInterface2="#p1=172.10.10.100:3260,10.10.184.234:3260" -f $addr,
	$createTarget2=$true,
	$bmpFolderPath2=""
	)
	
Import-Module StarWindX

try
{
	Enable-SWXLog -level SW_LOG_LEVEL_DEBUG

	$server = New-SWServer -host $addr -port $port -user $user -password $password

	$server.Connect()

	$firstNode = new-Object Node

	$firstNode.HostName = $addr
	$firstNode.HostPort = $port
	$firstNode.Login = $user
	$firstNode.Password = $password
	$firstNode.ImagePath = $imagePath
	$firstNode.ImageName = $imageName
	$firstNode.Size = $size
	$firstNode.CreateImage = $createImage
	$firstNode.StorageName = $storageName
	$firstNode.TargetAlias = $targetAlias
	$firstNode.AutoSynch = $autoSynch
	$firstNode.SyncInterface = $syncInterface
	$firstNode.HBInterface = $hbInterface
	$firstNode.PoolName = $poolName
	$firstNode.SyncSessionCount = $syncSessionCount
	$firstNode.ALUAOptimized = $aluaOptimized
	$firstNode.CacheMode = $cacheMode
	$firstNode.CacheSize = $cacheSize
	$firstNode.FailoverStrategy = $failover
	$firstNode.CreateTarget = $createTarget
	$firstNode.BitmapStoreType = $bmpType
	$firstNode.BitmapStrategy = $bmpStrategy
	$firstNode.BitmapFolderPath = $bmpFolderPath
    
	#
	# device sector size. Possible values: 512 or 4096(May be incompatible with some clients!) bytes. 
	#
	$firstNode.SectorSize = $sectorSize
    
	$secondNode = new-Object Node

	$secondNode.HostName = $addr2
	$secondNode.HostPort = $port2
	$secondNode.Login = $user2
	$secondNode.Password = $password2
	$secondNode.ImagePath = $imagePath2
	$secondNode.ImageName = $imageName2
	$secondNode.CreateImage = $createImage2
	$secondNode.StorageName = $storageName2
	$secondNode.TargetAlias = $targetAlias2
	$secondNode.AutoSynch = $autoSynch2
	$secondNode.SyncInterface = $syncInterface2
	$secondNode.HBInterface = $hbInterface2
	$secondNode.SyncSessionCount = $syncSessionCount2
	$secondNode.ALUAOptimized = $aluaOptimized2
	$secondNode.CacheMode = $cacheMode2
	$secondNode.CacheSize = $cacheSize2
	$secondNode.FailoverStrategy = $failover
	$secondNode.CreateTarget = $createTarget2
	$secondNode.BitmapFolderPath = $bmpFolderPath2
        
	$device = Add-HADevice -server $server -firstNode $firstNode -secondNode $secondNode -initMethod $initMethod
    
	while ($device.SyncStatus -ne [SwHaSyncStatus]::SW_HA_SYNC_STATUS_SYNC)
	{
		$syncPercent = $device.GetPropertyValue("ha_synch_percent")
	        Write-Host "Synchronizing: $($syncPercent)%" -foreground yellow

		Start-Sleep -m 2000

		$device.Refresh()
	}
}
catch
{
	Write-Host $_ -foreground red 
}
finally
{
	$server.Disconnect()
}
Logs

ESXi Server #1 - Server Log
10.10.184.234-3261-service-StarWindVSA-20230214-083123.zip
(16.41 KiB) Downloaded 88 times
ESXi Server #2 - Server Log
10.10.184.235-3261-service-StarWindVSA-20230214-083125.zip
(16.83 KiB) Downloaded 86 times
Top
yaroslav (staff)
Staff
Posts: 2361
Joined: Mon Nov 18, 2019 11:11 am

Mon Feb 20, 2023 6:02 am

Sincere apologies. Will check the logs in good order.
Top
Kishore CA
Posts: 26
Joined: Wed Jan 04, 2023 10:38 am

Fri Feb 24, 2023 3:41 pm

For the same setup it is working when I configure in GUI mode using trial license but when I’m trying to configure using free license it is throwing an error “not synchronised”.


Kindly help.
Top
yaroslav (staff)
Staff
Posts: 2361
Joined: Mon Nov 18, 2019 11:11 am

Fri Feb 24, 2023 3:59 pm

Thank you for your update.
It means that the script is not set up correctly then.
Top
Kishore CA
Posts: 26
Joined: Wed Jan 04, 2023 10:38 am

Sat Feb 25, 2023 6:21 am

Kishore CA wrote:
yaroslav (staff) wrote:Can I have the logs, and the new script, please?
Latest Code and Logs are attached. Kindly highlight my mistake sir.

Setup
Starwindvsa_Setup.png
Latest Code

Code: Select all

param($addr="10.10.184.234", $port=3261, $user="root", $password="starwind",
	$addr2="10.10.184.235", $port2=$port, $user2=$user, $password2=$password,
#common
	$initMethod="Clear",
	$size=10240,
	$sectorSize=512,
	$failover=0,
	$bmpType=1,
	$bmpStrategy=0,
#primary node
	$imagePath="/mnt",
	$imageName="primaryimage",
	$createImage=$true,
	$storageName="",
	$targetAlias="vSANPool",
	$autoSynch=$true,
	$poolName="vSANPool",
	$syncSessionCount=1,
	$aluaOptimized=$true,
	$cacheMode="",
	$cacheSize=128,
	$syncInterface="#p2=172.20.20.200:3260" -f $addr2,
	$hbInterface="#p2=172.10.10.200:3260,10.10.184.235:3260" -f $addr2,
	$createTarget=$true,
	$bmpFolderPath="",
#secondary node
	$imagePath2="/mnt",
	$imageName2="secondaryimage",
	$createImage2=$true,
	$storageName2="",
	$targetAlias2="vSANPool",
	$autoSynch2=$true,
	$poolName2="vSANPool",
	$syncSessionCount2=1,
	$aluaOptimized2=$false,
	$cacheMode2=$cacheMode,
	$cacheSize2=$cacheSize,
	$syncInterface2="#p1=172.20.20.100:3260" -f $addr,
	$hbInterface2="#p1=172.10.10.100:3260,10.10.184.234:3260" -f $addr,
	$createTarget2=$true,
	$bmpFolderPath2=""
	)
	
Import-Module StarWindX

try
{
	Enable-SWXLog -level SW_LOG_LEVEL_DEBUG

	$server = New-SWServer -host $addr -port $port -user $user -password $password

	$server.Connect()

	$firstNode = new-Object Node

	$firstNode.HostName = $addr
	$firstNode.HostPort = $port
	$firstNode.Login = $user
	$firstNode.Password = $password
	$firstNode.ImagePath = $imagePath
	$firstNode.ImageName = $imageName
	$firstNode.Size = $size
	$firstNode.CreateImage = $createImage
	$firstNode.StorageName = $storageName
	$firstNode.TargetAlias = $targetAlias
	$firstNode.AutoSynch = $autoSynch
	$firstNode.SyncInterface = $syncInterface
	$firstNode.HBInterface = $hbInterface
	$firstNode.PoolName = $poolName
	$firstNode.SyncSessionCount = $syncSessionCount
	$firstNode.ALUAOptimized = $aluaOptimized
	$firstNode.CacheMode = $cacheMode
	$firstNode.CacheSize = $cacheSize
	$firstNode.FailoverStrategy = $failover
	$firstNode.CreateTarget = $createTarget
	$firstNode.BitmapStoreType = $bmpType
	$firstNode.BitmapStrategy = $bmpStrategy
	$firstNode.BitmapFolderPath = $bmpFolderPath
    
	#
	# device sector size. Possible values: 512 or 4096(May be incompatible with some clients!) bytes. 
	#
	$firstNode.SectorSize = $sectorSize
    
	$secondNode = new-Object Node

	$secondNode.HostName = $addr2
	$secondNode.HostPort = $port2
	$secondNode.Login = $user2
	$secondNode.Password = $password2
	$secondNode.ImagePath = $imagePath2
	$secondNode.ImageName = $imageName2
	$secondNode.CreateImage = $createImage2
	$secondNode.StorageName = $storageName2
	$secondNode.TargetAlias = $targetAlias2
	$secondNode.AutoSynch = $autoSynch2
	$secondNode.SyncInterface = $syncInterface2
	$secondNode.HBInterface = $hbInterface2
	$secondNode.SyncSessionCount = $syncSessionCount2
	$secondNode.ALUAOptimized = $aluaOptimized2
	$secondNode.CacheMode = $cacheMode2
	$secondNode.CacheSize = $cacheSize2
	$secondNode.FailoverStrategy = $failover
	$secondNode.CreateTarget = $createTarget2
	$secondNode.BitmapFolderPath = $bmpFolderPath2
        
	$device = Add-HADevice -server $server -firstNode $firstNode -secondNode $secondNode -initMethod $initMethod
    
	while ($device.SyncStatus -ne [SwHaSyncStatus]::SW_HA_SYNC_STATUS_SYNC)
	{
		$syncPercent = $device.GetPropertyValue("ha_synch_percent")
	        Write-Host "Synchronizing: $($syncPercent)%" -foreground yellow

		Start-Sleep -m 2000

		$device.Refresh()
	}
}
catch
{
	Write-Host $_ -foreground red 
}
finally
{
	$server.Disconnect()
}
Logs

ESXi Server #1 - Server Log
10.10.184.234-3261-service-StarWindVSA-20230214-083123.zip
ESXi Server #2 - Server Log
10.10.184.235-3261-service-StarWindVSA-20230214-083125.zip
This is the code I’m using sir.

Can u please highlight my mistake in the script.
Top
yaroslav (staff)
Staff
Posts: 2361
Joined: Mon Nov 18, 2019 11:11 am

Mon Feb 27, 2023 11:24 am

1) cacheMode not selected.
2) poolName, leave it unchecked.
4) Bitmap folder not selected.
3) For simplicity, use the previous build.
Top
Post Reply

Return to “StarWind Virtual SAN (VSAN) [+Free], HCI Appliance (HCA), Virtual HCI Appliance (VHCA) [+Free]”