DeviceIoControl failed Error 87

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

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

Post Reply
muenther
Posts: 13
Joined: Thu Oct 28, 2010 1:18 pm

Mon Jan 17, 2011 10:15 am

Hello all together,

after I connected the HA iSCSI storages in XenCenter, the ha nodes was no longer in sync.
I try to make a fast sync, but it doesnt work. And I've got the following error messages.

- CMSInitiatorDevice::SendCDB2Device: DeviceIoControl (IOCTL_SCSI_PASS_THROUGH_DIRECT) failed (ERROR = 87, SCSI STAUTUS == 0).

After 3 retrys i've got the next error messages!

- CMSInitiatorDevice::SendCDB2Device: EXITing with failure, DeviceIoControl( IOCTL_SCSI_PASS_THROUGH_DIRECT ) failed, ERROR = 87, SCSI STAUTUS = 0!
- CMSInitiatorDevice::SendCustomWriteScsiCommand: EXITing with failure, SendCDB2Device(...) failed!
- CMSInitiatorDevice::FastSynchronizationThread: EXITing with failure, write block to destination device failed, error code 87!

Then I made a full synchronization. Till now it looks fine, but it takes long time for only 2 TB
what could it be?
Top
User avatar
Max (staff)
Staff
Posts: 533
Joined: Tue Apr 20, 2010 9:03 am

Mon Jan 17, 2011 10:43 am

This issue has been described multiple times, the most common scenario is to fix the iSCSI frame size between the SW boxes.
You need to change the registry on both StarWind servers in order to make it transmit Xen packets correctly.

Code: Select all

HKEY_LOCAL_MACHINE\SYSTEM\ControlSet001\Control\Class\{4D36E97B-E325-11CE-BFC1-08002BE10318}\0001\Parameters
and change values of paramets MaxBurstLength and MaxTransferLength from 40000 to 80000
Max Kolomyeytsev
StarWind Software
Top
muenther
Posts: 13
Joined: Thu Oct 28, 2010 1:18 pm

Mon Jan 17, 2011 11:02 am

In my XenGuide which I get from the StarWind Support, is written that I only must adjust the MaxTransferLenght from 40000 to 80000 but I will change the value of MaxBurstLenght now.

Thanks for helping!
Top
User avatar
Max (staff)
Staff
Posts: 533
Joined: Tue Apr 20, 2010 9:03 am

Mon Jan 17, 2011 11:21 am

You're welcome:) please keep us updated about the process.
Max Kolomyeytsev
StarWind Software
Top
muenther
Posts: 13
Joined: Thu Oct 28, 2010 1:18 pm

Tue Jan 18, 2011 2:29 pm

I try to find the values of MaxBurstLenght and MaxTransferLenght under the reg path you posted but there isn't a folder "Parameters" and no regs like MaxBurstLenght and MaxTransferLenght.

But I can find the values under the following path.

- HKEY_LOCAL_MACHINE\SYSTEM\ControlSet001\Control\Class\{4D36E97B-E325-11CE-BFC1-08002BE10318}\0002\Parameters

Is this the same?
Top
User avatar
Max (staff)
Staff
Posts: 533
Joined: Tue Apr 20, 2010 9:03 am

Tue Jan 18, 2011 3:04 pm

Yes, this is the iSCSI initiator properties list.
Max Kolomyeytsev
StarWind Software
Top
muenther
Posts: 13
Joined: Thu Oct 28, 2010 1:18 pm

Wed Jan 19, 2011 4:23 pm

Ok both values are changed.
If I install a VM, the secondery target (HA) is immediately out of sync!
Is this normal?
Top
User avatar
Max (staff)
Staff
Posts: 533
Joined: Tue Apr 20, 2010 9:03 am

Wed Jan 19, 2011 4:41 pm

This is not normal, the reason for this behaviour is Xen who's sending 128k packets instead of 64k which are native for MS initiator which is transferring the data between the HA nodes. Can you verify that the second node is accepting 128k packets?
By the way, if you're using hardware iSCSI initiators this value may vary so you may need to set the transmit value even higher (256k)
Max Kolomyeytsev
StarWind Software
Top
muenther
Posts: 13
Joined: Thu Oct 28, 2010 1:18 pm

Wed Jan 19, 2011 5:49 pm

Sorry, but I dont understand!
Do you mean the stripe size for the Array or must I have to change a nic configuration?
Or maybe you can explain me how I cant adjust the packet size?
Top
User avatar
Max (staff)
Staff
Posts: 533
Joined: Tue Apr 20, 2010 9:03 am

Wed Jan 19, 2011 6:42 pm

I mean the size of the packet which Xen does use to transfer via iSCSI and the default packet size for iSCSI initiator.
The initiator's packet size is customized in the registry like I've described in my previous posts.
In case you have Xen sending packets larger then 64k - the iSCSI initiators properties should be changed in the registry on both machines (requires a reboot for apply)
Max Kolomyeytsev
StarWind Software
Top
muenther
Posts: 13
Joined: Thu Oct 28, 2010 1:18 pm

Wed Jan 19, 2011 7:59 pm

The values of MaxBurstLength and MaxTransferLenght are changed on both machines.
After changing the values I have rebooting both machines.
But If I install a VM, the second node is out of sync!
Top
Constantin (staff)

Thu Jan 20, 2011 12:15 pm

Tell me please, do you use any HBA adapters in your servers?
Top
muenther
Posts: 13
Joined: Thu Oct 28, 2010 1:18 pm

Thu Jan 20, 2011 6:54 pm

Ok, everything works fine now.
I don't know why, but it works.

After I installed the VM the both nodes were synchronous for a while.
So I copy some datas on the VM with pretty well performance and after about 45 minutes the first node got an
yellow exclamation mark. In my opinion this means, that the second node are out of sync! Correct?
But why? When does the synchronization starts again?
Because the synchronization don't starts after 2 hours, I decided to make a manuell fast sync.
But it doesn't work and now runs a full sync automaticly.
Top
muenther
Posts: 13
Joined: Thu Oct 28, 2010 1:18 pm

Fri Jan 21, 2011 8:15 am

Hey constantin.
Yes, I use the HP NC550SFP Dual Port 10Gbe Server Adapter.
Top
muenther
Posts: 13
Joined: Thu Oct 28, 2010 1:18 pm

Mon Jan 24, 2011 9:09 am

What the fuck!!! Sorry!
But Iam really angry......

I install a VM and when windows is expanding files, the second target goes out of sync.
A full synchronization starts and after one day, the targets are in sync. But this is crap.
If I copy some datas on a running VM all works fine. The targets are in sync!
Only when I install a VM.

There are no error messages in the event log.

Log file from the first node.:

1/24 9:37:06.062 12bc HA: CMSInitiatorDevice::SendCustomWriteScsiCommand: EXITing with failure, SendCDB2Device(...) failed!
1/24 9:37:06.062 12e4 HA: CSynchBarrier::EnterSynchBarrier: Intersects exist:
1/24 9:37:06.062 12e4 HA: CSynchBarrier::EnterSynchBarrier: CurrentThreadId=0x50C, ullStartSector = 0x2E572877, ulLength = 1
1/24 9:37:06.062 12e4 HA: CSynchBarrier::EnterSynchBarrier: nIndex = 8, OtherThreadId=0xCD4, ullStartSector = 0x2E572870, ulLength = 8
1/24 9:37:06.078 180 HA: CSynchBarrier::EnterSynchBarrier: Intersects exist:
1/24 9:37:06.078 180 HA: CSynchBarrier::EnterSynchBarrier: CurrentThreadId=0x180, ullStartSector = 0x2E29108A, ulLength = 1
1/24 9:37:06.078 180 HA: CSynchBarrier::EnterSynchBarrier: nIndex = 11, OtherThreadId=0x12F0, ullStartSector = 0x2E29108A, ulLength = 1
1/24 9:37:06.078 12e4 HA: CSynchBarrier::EnterSynchBarrier: Intersects exist:
1/24 9:37:06.078 12e4 HA: CSynchBarrier::EnterSynchBarrier: CurrentThreadId=0x50C, ullStartSector = 0x2E572877, ulLength = 1
1/24 9:37:06.078 12e4 HA: CSynchBarrier::EnterSynchBarrier: nIndex = 8, OtherThreadId=0xCD4, ullStartSector = 0x2E572870, ulLength = 8
1/24 9:37:06.093 180 HA: CSynchBarrier::EnterSynchBarrier: Intersects exist:
1/24 9:37:06.093 180 HA: CSynchBarrier::EnterSynchBarrier: CurrentThreadId=0x180, ullStartSector = 0x2E29108A, ulLength = 1
1/24 9:37:06.093 180 HA: CSynchBarrier::EnterSynchBarrier: nIndex = 11, OtherThreadId=0x12F0, ullStartSector = 0x2E29108A, ulLength = 1
1/24 9:37:06.093 12e4 HA: CSynchBarrier::EnterSynchBarrier: Intersects exist:
1/24 9:37:06.093 12e4 HA: CSynchBarrier::EnterSynchBarrier: CurrentThreadId=0x50C, ullStartSector = 0x2E572877, ulLength = 1
1/24 9:37:06.093 12e4 HA: CSynchBarrier::EnterSynchBarrier: nIndex = 8, OtherThreadId=0xCD4, ullStartSector = 0x2E572870, ulLength = 8
1/24 9:37:06.109 180 HA: CSynchBarrier::EnterSynchBarrier: Intersects exist:
1/24 9:37:06.109 180 HA: CSynchBarrier::EnterSynchBarrier: CurrentThreadId=0x180, ullStartSector = 0x2E29108A, ulLength = 1
1/24 9:37:06.109 180 HA: CSynchBarrier::EnterSynchBarrier: nIndex = 11, OtherThreadId=0x12F0, ullStartSector = 0x2E29108A, ulLength = 1
1/24 9:37:06.109 12e4 HA: CSynchBarrier::EnterSynchBarrier: Intersects exist:
1/24 9:37:06.109 12e4 HA: CSynchBarrier::EnterSynchBarrier: CurrentThreadId=0x50C, ullStartSector = 0x2E572877, ulLength = 1
1/24 9:37:06.109 12e4 HA: CSynchBarrier::EnterSynchBarrier: nIndex = 8, OtherThreadId=0xCD4, ullStartSector = 0x2E572870, ulLength = 8
1/24 9:37:06.125 180 HA: CSynchBarrier::EnterSynchBarrier: Intersects exist:
1/24 9:37:06.125 180 HA: CSynchBarrier::EnterSynchBarrier: CurrentThreadId=0x180, ullStartSector = 0x2E29108A, ulLength = 1
1/24 9:37:06.125 180 HA: CSynchBarrier::EnterSynchBarrier: nIndex = 11, OtherThreadId=0x12F0, ullStartSector = 0x2E29108A, ulLength = 1
1/24 9:37:06.125 12e4 HA: CSynchBarrier::EnterSynchBarrier: Intersects exist:
1/24 9:37:06.125 12e4 HA: CSynchBarrier::EnterSynchBarrier: CurrentThreadId=0x50C, ullStartSector = 0x2E572877, ulLength = 1
1/24 9:37:06.125 12e4 HA: CSynchBarrier::EnterSynchBarrier: nIndex = 8, OtherThreadId=0xCD4, ullStartSector = 0x2E572870, ulLength = 8
1/24 9:37:06.140 180 HA: CSynchBarrier::EnterSynchBarrier: Intersects exist:
1/24 9:37:06.140 180 HA: CSynchBarrier::EnterSynchBarrier: CurrentThreadId=0x180, ullStartSector = 0x2E29108A, ulLength = 1
1/24 9:37:06.140 180 HA: CSynchBarrier::EnterSynchBarrier: nIndex = 11, OtherThreadId=0x12F0, ullStartSector = 0x2E29108A, ulLength = 1
1/24 9:37:06.140 12e4 HA: CSynchBarrier::EnterSynchBarrier: Intersects exist:
1/24 9:37:06.140 12e4 HA: CSynchBarrier::EnterSynchBarrier: CurrentThreadId=0x1028, ullStartSector = 0x2E572877, ulLength = 1
1/24 9:37:06.140 12e4 HA: CSynchBarrier::EnterSynchBarrier: nIndex = 0, OtherThreadId=0x50C, ullStartSector = 0x2E572877, ulLength = 1
1/24 9:37:06.156 180 HA: CSynchBarrier::EnterSynchBarrier: Intersects exist:
1/24 9:37:06.156 180 HA: CSynchBarrier::EnterSynchBarrier: CurrentThreadId=0x180, ullStartSector = 0x2E29108A, ulLength = 1
1/24 9:37:06.156 180 HA: CSynchBarrier::EnterSynchBarrier: nIndex = 11, OtherThreadId=0x12F0, ullStartSector = 0x2E29108A, ulLength = 1
1/24 9:37:06.156 12e4 HA: CSynchBarrier::EnterSynchBarrier: Intersects exist:
1/24 9:37:06.156 12e4 HA: CSynchBarrier::EnterSynchBarrier: CurrentThreadId=0x1028, ullStartSector = 0x2E572877, ulLength = 1
1/24 9:37:06.156 12e4 HA: CSynchBarrier::EnterSynchBarrier: nIndex = 0, OtherThreadId=0x50C, ullStartSector = 0x2E572877, ulLength = 1
1/24 9:37:06.171 180 HA: CSynchBarrier::EnterSynchBarrier: Intersects exist:
1/24 9:37:06.171 180 HA: CSynchBarrier::EnterSynchBarrier: CurrentThreadId=0x180, ullStartSector = 0x2E29108A, ulLength = 1
1/24 9:37:06.171 180 HA: CSynchBarrier::EnterSynchBarrier: nIndex = 11, OtherThreadId=0x12F0, ullStartSector = 0x2E29108A, ulLength = 1
1/24 9:37:06.171 12e4 HA: CSynchBarrier::EnterSynchBarrier: Intersects exist:
1/24 9:37:06.171 12e4 HA: CSynchBarrier::EnterSynchBarrier: CurrentThreadId=0x1028, ullStartSector = 0x2E572877, ulLength = 1
1/24 9:37:06.171 12e4 HA: CSynchBarrier::EnterSynchBarrier: nIndex = 0, OtherThreadId=0x50C, ullStartSector = 0x2E572877, ulLength = 1
1/24 9:37:06.187 12e4 HA: CMSInitiatorDevice::FastSynchronizationThread: EXITing with failure, write block to destination device failed, error code 87!
1/24 9:37:06.203 12bc HA: CMSInitiatorDevice::SynchronizationWorkerThread: Thread was terminated by caller!
1/24 9:37:06.203 12e4 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x113C is not found!
1/24 9:37:06.203 12e4 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1138 is not found!
1/24 9:37:06.203 12e4 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1008 is not found!
1/24 9:37:06.203 12e4 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x11EC is not found!
1/24 9:37:06.203 12e4 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xFC0 is not found!
1/24 9:37:06.203 12e4 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x133C is not found!
1/24 9:37:06.203 12e4 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x10B0 is not found!
1/24 9:37:06.203 12e4 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xCD4 is not found!
1/24 9:37:06.203 12e4 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x11D4 is not found!
1/24 9:37:06.203 12e4 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1130 is not found!
1/24 9:37:06.203 12e4 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x12F0 is not found!
1/24 9:37:06.203 12e4 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x50C is not found!
1/24 9:37:06.203 12e4 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1268 is not found!
1/24 9:37:06.203 12e4 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x12E8 is not found!
1/24 9:37:06.203 12e4 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1028 is not found!
1/24 9:37:06.203 12e4 HA: CMSInitiatorDevice::FastSynchronizationThread: Event:Fast_Synchronization_Complete ErrorCode:87 InitiatorName:iqn.2011-01.sf4u.de:swbox01-ha01 IP:10.0.66.25 Port:3260 TargetName:iqn.2011-01.sf4u.de:swbox02-ha01partner
1/24 9:37:35.437 4b0 HA: ImageFile_SW_HA_Control_Command: Received COMMAND_HA_SET_DEVICE_SYNCH_STATUS command
1/24 9:37:35.437 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:37:35.437 4b0 HA: HA_CancelSynchronization: EXITing with failure, pDevice->CancelSynchronization(..) failed!
1/24 9:37:35.437 4b0 HA: ImageFile_SW_HA_Control_Command: Event:Received COMMAND_HA_SYNCHRONIZATION_REQUEST command (Partner requests synchronization)
1/24 9:37:35.437 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:37:35.437 4b0 HA: ImageFile_SW_HA_Control_Command: Synchronization type: 2
1/24 9:37:35.437 4b0 HA: CMSInitiatorDevice::Synchronize: Waiting on thread start event...
1/24 9:39:45.527 ee8 HA: CMSInitiatorDevice::SendCDB2Device: DeviceIoControl(IOCTL_SCSI_PASS_THROUGH_DIRECT) failed (ERROR = 87, SCSI STAUTUS == 0).
1/24 9:39:45.527 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Waiting 1000 msec ...
1/24 9:39:46.541 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Retry(#1 from 1) send cdb ....
1/24 9:39:46.541 ee8 HA: CMSInitiatorDevice::SendCDB2Device: EXITing with failure, DeviceIoControl( IOCTL_SCSI_PASS_THROUGH_DIRECT ) failed, ERROR = 87, SCSI STAUTUS = 0!
1/24 9:39:46.541 ee8 HA: CMSInitiatorDevice::SendCustomWriteScsiCommand: EXITing with failure, SendCDB2Device(...) failed!
1/24 9:39:46.541 ee8 HA: HA_SendCustomWriteScsiCommand: EXITing with failure, pDevice->SendCustomWriteScsiCommand(..) failed!
1/24 9:39:46.541 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Check is partner heartbeat device mounted...
1/24 9:39:46.541 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Try to send heartbeat ping to partner...
1/24 9:39:46.541 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Heartbeat ping sent succesfully!
1/24 9:39:46.541 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: HAPartnerSynchStatus = 2, HAOwnSynchStatus = 1
1/24 9:39:46.541 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Exiting with flages: bHeartbeatAlive = 1, bIsPartnerNodeShutdowned = 1, bIsCurrentNodeShutdowned = 0
1/24 9:41:38.878 1134 HA: CMSInitiatorDevice::FullSynchronizationThread: Event:Full_Synchronization_Complete ErrorCode:1067 InitiatorName:iqn.2011-01.sf4u.de:swbox01-ha01 IP:10.0.66.25 Port:3260 TargetName:iqn.2011-01.sf4u.de:swbox02-ha01partner
1/24 9:41:38.894 4b0 HA: ImageFile_SW_HA_Control_Command: Event:Received COMMAND_HA_SYNCHRONIZATION_REQUEST command (Partner requests synchronization)
1/24 9:41:38.894 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:41:38.894 4b0 HA: ImageFile_SW_HA_Control_Command: Synchronization type: 2
1/24 9:41:38.894 4b0 HA: CMSInitiatorDevice::Synchronize: Waiting on thread start event...
1/24 9:43:35.131 ee8 HA: CMSInitiatorDevice::SendCDB2Device: DeviceIoControl(IOCTL_SCSI_PASS_THROUGH_DIRECT) failed (ERROR = 87, SCSI STAUTUS == 0).
1/24 9:43:35.131 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Waiting 1000 msec ...
1/24 9:43:36.145 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Retry(#1 from 1) send cdb ....
1/24 9:43:36.145 ee8 HA: CMSInitiatorDevice::SendCDB2Device: EXITing with failure, DeviceIoControl( IOCTL_SCSI_PASS_THROUGH_DIRECT ) failed, ERROR = 87, SCSI STAUTUS = 0!
1/24 9:43:36.145 ee8 HA: CMSInitiatorDevice::SendCustomWriteScsiCommand: EXITing with failure, SendCDB2Device(...) failed!
1/24 9:43:36.145 ee8 HA: HA_SendCustomWriteScsiCommand: EXITing with failure, pDevice->SendCustomWriteScsiCommand(..) failed!
1/24 9:43:36.145 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Check is partner heartbeat device mounted...
1/24 9:43:36.145 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Try to send heartbeat ping to partner...
1/24 9:43:36.145 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Heartbeat ping sent succesfully!
1/24 9:43:36.145 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: HAPartnerSynchStatus = 2, HAOwnSynchStatus = 1
1/24 9:43:36.145 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Exiting with flages: bHeartbeatAlive = 1, bIsPartnerNodeShutdowned = 1, bIsCurrentNodeShutdowned = 0
1/24 9:43:40.576 4b0 HA: ImageFile_SW_HA_Control_Command: Received COMMAND_HA_SET_DEVICE_SYNCH_STATUS command
1/24 9:43:40.576 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:43:40.591 488 HA: CMSInitiatorDevice::FullSynchronizationThread: Thread was terminated by caller!
1/24 9:43:40.591 13a8 HA: CMSInitiatorDevice::SynchronizationWorkerThread: Thread was terminated by caller!
1/24 9:43:40.591 488 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1084 is not found!
1/24 9:43:40.591 488 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xF34 is not found!
1/24 9:43:40.591 488 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x117C is not found!
1/24 9:43:40.591 488 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1068 is not found!
1/24 9:43:40.591 488 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x10CC is not found!
1/24 9:43:40.591 488 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1248 is not found!
1/24 9:43:40.591 488 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x604 is not found!
1/24 9:43:40.591 488 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1370 is not found!
1/24 9:43:40.591 488 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x428 is not found!
1/24 9:43:40.591 488 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xF80 is not found!
1/24 9:43:40.591 488 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1078 is not found!
1/24 9:43:40.591 488 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1394 is not found!
1/24 9:43:40.591 488 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x137C is not found!
1/24 9:43:40.591 488 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x4D4 is not found!
1/24 9:43:40.591 488 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x115C is not found!
1/24 9:43:40.591 488 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1250 is not found!
1/24 9:43:40.591 488 HA: CMSInitiatorDevice::FullSynchronizationThread: Event:Full_Synchronization_Complete ErrorCode:1067 InitiatorName:iqn.2011-01.sf4u.de:swbox01-ha01 IP:10.0.66.25 Port:3260 TargetName:iqn.2011-01.sf4u.de:swbox02-ha01partner
1/24 9:43:40.591 4b0 HA: ImageFile_SW_HA_Control_Command: Event:Received COMMAND_HA_SYNCHRONIZATION_REQUEST command (Partner requests synchronization)
1/24 9:43:40.591 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:43:40.591 4b0 HA: ImageFile_SW_HA_Control_Command: Synchronization type: 2
1/24 9:43:40.591 4b0 HA: CMSInitiatorDevice::Synchronize: Waiting on thread start event...
1/24 9:45:27.702 ee8 HA: CMSInitiatorDevice::SendCDB2Device: DeviceIoControl(IOCTL_SCSI_PASS_THROUGH_DIRECT) failed (ERROR = 87, SCSI STAUTUS == 0).
1/24 9:45:27.702 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Waiting 1000 msec ...
1/24 9:45:28.716 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Retry(#1 from 1) send cdb ....
1/24 9:45:28.716 ee8 HA: CMSInitiatorDevice::SendCDB2Device: EXITing with failure, DeviceIoControl( IOCTL_SCSI_PASS_THROUGH_DIRECT ) failed, ERROR = 87, SCSI STAUTUS = 0!
1/24 9:45:28.716 ee8 HA: CMSInitiatorDevice::SendCustomWriteScsiCommand: EXITing with failure, SendCDB2Device(...) failed!
1/24 9:45:28.716 ee8 HA: HA_SendCustomWriteScsiCommand: EXITing with failure, pDevice->SendCustomWriteScsiCommand(..) failed!
1/24 9:45:28.716 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Check is partner heartbeat device mounted...
1/24 9:45:28.716 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Try to send heartbeat ping to partner...
1/24 9:45:28.716 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Heartbeat ping sent succesfully!
1/24 9:45:28.716 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: HAPartnerSynchStatus = 2, HAOwnSynchStatus = 1
1/24 9:45:28.716 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Exiting with flages: bHeartbeatAlive = 1, bIsPartnerNodeShutdowned = 1, bIsCurrentNodeShutdowned = 0
1/24 9:45:42.273 4b0 HA: ImageFile_SW_HA_Control_Command: Received COMMAND_HA_SET_DEVICE_SYNCH_STATUS command
1/24 9:45:42.273 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:45:42.273 1324 HA: CMSInitiatorDevice::FullSynchronizationThread: Thread was terminated by caller!
1/24 9:45:42.288 10c8 HA: CMSInitiatorDevice::SynchronizationWorkerThread: Thread was terminated by caller!
1/24 9:45:42.288 1324 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x13E8 is not found!
1/24 9:45:42.288 1324 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x308 is not found!
1/24 9:45:42.288 1324 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x10A8 is not found!
1/24 9:45:42.288 1324 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x13D8 is not found!
1/24 9:45:42.288 1324 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1318 is not found!
1/24 9:45:42.288 1324 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x12B0 is not found!
1/24 9:45:42.288 1324 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x104C is not found!
1/24 9:45:42.288 1324 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xD40 is not found!
1/24 9:45:42.288 1324 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x10D0 is not found!
1/24 9:45:42.288 1324 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x11B4 is not found!
1/24 9:45:42.288 1324 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1390 is not found!
1/24 9:45:42.288 1324 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xE74 is not found!
1/24 9:45:42.288 1324 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x126C is not found!
1/24 9:45:42.288 1324 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x12B8 is not found!
1/24 9:45:42.288 1324 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x13B4 is not found!
1/24 9:45:42.288 1324 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x12F4 is not found!
1/24 9:45:42.288 1324 HA: CMSInitiatorDevice::FullSynchronizationThread: Event:Full_Synchronization_Complete ErrorCode:1067 InitiatorName:iqn.2011-01.sf4u.de:swbox01-ha01 IP:10.0.66.25 Port:3260 TargetName:iqn.2011-01.sf4u.de:swbox02-ha01partner
1/24 9:45:42.288 4b0 HA: ImageFile_SW_HA_Control_Command: Event:Received COMMAND_HA_SYNCHRONIZATION_REQUEST command (Partner requests synchronization)
1/24 9:45:42.288 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:45:42.288 4b0 HA: ImageFile_SW_HA_Control_Command: Synchronization type: 2
1/24 9:45:42.304 4b0 HA: CMSInitiatorDevice::Synchronize: Waiting on thread start event...
1/24 9:49:20.878 ee8 HA: CMSInitiatorDevice::SendCDB2Device: DeviceIoControl(IOCTL_SCSI_PASS_THROUGH_DIRECT) failed (ERROR = 87, SCSI STAUTUS == 0).
1/24 9:49:20.878 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Waiting 1000 msec ...
1/24 9:49:21.892 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Retry(#1 from 1) send cdb ....
1/24 9:49:21.892 ee8 HA: CMSInitiatorDevice::SendCDB2Device: EXITing with failure, DeviceIoControl( IOCTL_SCSI_PASS_THROUGH_DIRECT ) failed, ERROR = 87, SCSI STAUTUS = 0!
1/24 9:49:45.683 1ec HA: CMSInitiatorDevice::FullSynchronizationThread: Event:Full_Synchronization_Complete ErrorCode:1067 InitiatorName:iqn.2011-01.sf4u.de:swbox01-ha01 IP:10.0.66.25 Port:3260 TargetName:iqn.2011-01.sf4u.de:swbox02-ha01partner
1/24 9:49:45.683 4b0 HA: ImageFile_SW_HA_Control_Command: Event:Received COMMAND_HA_SYNCHRONIZATION_REQUEST command (Partner requests synchronization)
1/24 9:49:45.683 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:49:45.683 4b0 HA: ImageFile_SW_HA_Control_Command: Synchronization type: 2
1/24 9:49:45.683 4b0 HA: CMSInitiatorDevice::Synchronize: Waiting on thread start event...
1/24 9:51:12.981 ee8 HA: CMSInitiatorDevice::SendCDB2Device: DeviceIoControl(IOCTL_SCSI_PASS_THROUGH_DIRECT) failed (ERROR = 87, SCSI STAUTUS == 0).
1/24 9:51:12.981 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Waiting 1000 msec ...
1/24 9:51:13.995 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Retry(#1 from 1) send cdb ....
1/24 9:51:13.995 ee8 HA: CMSInitiatorDevice::SendCDB2Device: EXITing with failure, DeviceIoControl( IOCTL_SCSI_PASS_THROUGH_DIRECT ) failed, ERROR = 87, SCSI STAUTUS = 0!
1/24 9:51:13.995 ee8 HA: CMSInitiatorDevice::SendCustomWriteScsiCommand: EXITing with failure, SendCDB2Device(...) failed!
1/24 9:51:13.995 ee8 HA: HA_SendCustomWriteScsiCommand: EXITing with failure, pDevice->SendCustomWriteScsiCommand(..) failed!
1/24 9:51:13.995 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Check is partner heartbeat device mounted...
1/24 9:51:13.995 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Try to send heartbeat ping to partner...
1/24 9:51:13.995 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Heartbeat ping sent succesfully!
1/24 9:51:13.995 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: HAPartnerSynchStatus = 2, HAOwnSynchStatus = 1
1/24 9:51:13.995 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Exiting with flages: bHeartbeatAlive = 1, bIsPartnerNodeShutdowned = 1, bIsCurrentNodeShutdowned = 0
1/24 9:51:16.959 4b0 HA: ImageFile_SW_HA_Control_Command: Received COMMAND_HA_SET_DEVICE_SYNCH_STATUS command
1/24 9:51:16.959 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:51:16.991 13fc HA: CMSInitiatorDevice::FullSynchronizationThread: Thread was terminated by caller!
1/24 9:51:16.991 1124 HA: CMSInitiatorDevice::SynchronizationWorkerThread: Thread was terminated by caller!
1/24 9:51:16.991 13fc HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x17C is not found!
1/24 9:51:16.991 13fc HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x11FC is not found!
1/24 9:51:16.991 13fc HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1330 is not found!
1/24 9:51:16.991 13fc HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x11DC is not found!
1/24 9:51:16.991 13fc HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1234 is not found!
1/24 9:51:16.991 13fc HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x13C4 is not found!
1/24 9:51:16.991 13fc HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x12D0 is not found!
1/24 9:51:16.991 13fc HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1034 is not found!
1/24 9:51:16.991 13fc HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1150 is not found!
1/24 9:51:16.991 13fc HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x2E8 is not found!
1/24 9:51:16.991 13fc HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x13A0 is not found!
1/24 9:51:16.991 13fc HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1108 is not found!
1/24 9:51:16.991 13fc HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x13E0 is not found!
1/24 9:51:16.991 13fc HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x6A4 is not found!
1/24 9:51:16.991 13fc HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xFF0 is not found!
1/24 9:51:16.991 13fc HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x101C is not found!
1/24 9:51:16.991 13fc HA: CMSInitiatorDevice::FullSynchronizationThread: Event:Full_Synchronization_Complete ErrorCode:1067 InitiatorName:iqn.2011-01.sf4u.de:swbox01-ha01 IP:10.0.66.25 Port:3260 TargetName:iqn.2011-01.sf4u.de:swbox02-ha01partner
1/24 9:51:16.991 4b0 HA: ImageFile_SW_HA_Control_Command: Event:Received COMMAND_HA_SYNCHRONIZATION_REQUEST command (Partner requests synchronization)
1/24 9:51:16.991 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:51:16.991 4b0 HA: ImageFile_SW_HA_Control_Command: Synchronization type: 2
1/24 9:51:16.991 4b0 HA: CMSInitiatorDevice::Synchronize: Waiting on thread start event...
1/24 9:51:17.942 ee8 HA: CMSInitiatorDevice::SendCDB2Device: DeviceIoControl(IOCTL_SCSI_PASS_THROUGH_DIRECT) failed (ERROR = 87, SCSI STAUTUS == 0).
1/24 9:51:17.942 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Waiting 1000 msec ...
1/24 9:51:18.956 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Retry(#1 from 1) send cdb ....
1/24 9:51:18.956 ee8 HA: CMSInitiatorDevice::SendCDB2Device: EXITing with failure, DeviceIoControl( IOCTL_SCSI_PASS_THROUGH_DIRECT ) failed, ERROR = 87, SCSI STAUTUS = 0!
1/24 9:51:18.956 ee8 HA: CMSInitiatorDevice::SendCustomWriteScsiCommand: EXITing with failure, SendCDB2Device(...) failed!
1/24 9:51:18.956 ee8 HA: HA_SendCustomWriteScsiCommand: EXITing with failure, pDevice->SendCustomWriteScsiCommand(..) failed!
1/24 9:51:18.956 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Check is partner heartbeat device mounted...
1/24 9:51:18.956 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Try to send heartbeat ping to partner...
1/24 9:51:18.956 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Heartbeat ping sent succesfully!
1/24 9:51:18.956 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: HAPartnerSynchStatus = 2, HAOwnSynchStatus = 1
1/24 9:51:18.956 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Exiting with flages: bHeartbeatAlive = 1, bIsPartnerNodeShutdowned = 1, bIsCurrentNodeShutdowned = 0
1/24 9:51:47.395 4b0 HA: ImageFile_SW_HA_Control_Command: Received COMMAND_HA_SET_DEVICE_SYNCH_STATUS command
1/24 9:51:47.395 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:51:47.395 1354 HA: CMSInitiatorDevice::FullSynchronizationThread: Thread was terminated by caller!
1/24 9:51:47.411 1294 HA: CMSInitiatorDevice::SynchronizationWorkerThread: Thread was terminated by caller!
1/24 9:51:47.411 1354 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x854 is not found!
1/24 9:51:47.411 1354 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1278 is not found!
1/24 9:51:47.411 1354 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x10E8 is not found!
1/24 9:51:47.411 1354 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x106C is not found!
1/24 9:51:47.411 1354 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1160 is not found!
1/24 9:51:47.411 1354 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x680 is not found!
1/24 9:51:47.411 1354 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xD90 is not found!
1/24 9:51:47.411 1354 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x13AC is not found!
1/24 9:51:47.411 1354 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1338 is not found!
1/24 9:51:47.411 1354 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x11E0 is not found!
1/24 9:51:47.411 1354 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1018 is not found!
1/24 9:51:47.411 1354 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x248 is not found!
1/24 9:51:47.411 1354 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x10AC is not found!
1/24 9:51:47.411 1354 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1288 is not found!
1/24 9:51:47.411 1354 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xD98 is not found!
1/24 9:51:47.411 1354 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x118C is not found!
1/24 9:51:47.411 1354 HA: CMSInitiatorDevice::FullSynchronizationThread: Event:Full_Synchronization_Complete ErrorCode:1067 InitiatorName:iqn.2011-01.sf4u.de:swbox01-ha01 IP:10.0.66.25 Port:3260 TargetName:iqn.2011-01.sf4u.de:swbox02-ha01partner
1/24 9:51:47.411 4b0 HA: ImageFile_SW_HA_Control_Command: Event:Received COMMAND_HA_SYNCHRONIZATION_REQUEST command (Partner requests synchronization)
1/24 9:51:47.411 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:51:47.411 4b0 HA: ImageFile_SW_HA_Control_Command: Synchronization type: 2
1/24 9:51:47.411 4b0 HA: CMSInitiatorDevice::Synchronize: Waiting on thread start event...
1/24 9:52:53.774 ee8 HA: CMSInitiatorDevice::SendCDB2Device: DeviceIoControl(IOCTL_SCSI_PASS_THROUGH_DIRECT) failed (ERROR = 87, SCSI STAUTUS == 0).
1/24 9:52:53.774 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Waiting 1000 msec ...
1/24 9:52:54.788 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Retry(#1 from 1) send cdb ....
1/24 9:52:54.788 ee8 HA: CMSInitiatorDevice::SendCDB2Device: EXITing with failure, DeviceIoControl( IOCTL_SCSI_PASS_THROUGH_DIRECT ) failed, ERROR = 87, SCSI STAUTUS = 0!
1/24 9:52:54.788 ee8 HA: CMSInitiatorDevice::SendCustomWriteScsiCommand: EXITing with failure, SendCDB2Device(...) failed!
1/24 9:52:54.788 ee8 HA: HA_SendCustomWriteScsiCommand: EXITing with failure, pDevice->SendCustomWriteScsiCommand(..) failed!
1/24 9:52:54.788 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Check is partner heartbeat device mounted...
1/24 9:52:54.788 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Try to send heartbeat ping to partner...
1/24 9:52:54.788 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Heartbeat ping sent succesfully!
1/24 9:52:54.788 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: HAPartnerSynchStatus = 2, HAOwnSynchStatus = 1
1/24 9:52:54.788 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Exiting with flages: bHeartbeatAlive = 1, bIsPartnerNodeShutdowned = 1, bIsCurrentNodeShutdowned = 0
1/24 9:53:18.657 4b0 HA: ImageFile_SW_HA_Control_Command: Received COMMAND_HA_SET_DEVICE_SYNCH_STATUS command
1/24 9:53:18.657 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:53:18.688 13c0 HA: CMSInitiatorDevice::FullSynchronizationThread: Thread was terminated by caller!
1/24 9:53:18.703 1248 HA: CMSInitiatorDevice::SynchronizationWorkerThread: Thread was terminated by caller!
1/24 9:53:18.703 13c0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x604 is not found!
1/24 9:53:18.703 13c0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x117C is not found!
1/24 9:53:18.703 13c0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x428 is not found!
1/24 9:53:18.703 13c0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1154 is not found!
1/24 9:53:18.703 13c0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1068 is not found!
1/24 9:53:18.703 13c0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xF34 is not found!
1/24 9:53:18.703 13c0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xF18 is not found!
1/24 9:53:18.703 13c0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1038 is not found!
1/24 9:53:18.703 13c0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x5AC is not found!
1/24 9:53:18.703 13c0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1178 is not found!
1/24 9:53:18.703 13c0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xFAC is not found!
1/24 9:53:18.703 13c0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xFCC is not found!
1/24 9:53:18.703 13c0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x105C is not found!
1/24 9:53:18.703 13c0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1308 is not found!
1/24 9:53:18.703 13c0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1314 is not found!
1/24 9:53:18.703 13c0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x112C is not found!
1/24 9:53:18.703 13c0 HA: CMSInitiatorDevice::FullSynchronizationThread: Event:Full_Synchronization_Complete ErrorCode:1067 InitiatorName:iqn.2011-01.sf4u.de:swbox01-ha01 IP:10.0.66.25 Port:3260 TargetName:iqn.2011-01.sf4u.de:swbox02-ha01partner
1/24 9:53:18.703 4b0 HA: ImageFile_SW_HA_Control_Command: Event:Received COMMAND_HA_SYNCHRONIZATION_REQUEST command (Partner requests synchronization)
1/24 9:53:18.703 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:53:18.703 4b0 HA: ImageFile_SW_HA_Control_Command: Synchronization type: 2
1/24 9:53:18.703 4b0 HA: CMSInitiatorDevice::Synchronize: Waiting on thread start event...
1/24 9:53:41.761 ee8 HA: CMSInitiatorDevice::SendCDB2Device: DeviceIoControl(IOCTL_SCSI_PASS_THROUGH_DIRECT) failed (ERROR = 87, SCSI STAUTUS == 0).
1/24 9:53:41.761 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Waiting 1000 msec ...
1/24 9:53:42.775 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Retry(#1 from 1) send cdb ....
1/24 9:53:42.775 ee8 HA: CMSInitiatorDevice::SendCDB2Device: EXITing with failure, DeviceIoControl( IOCTL_SCSI_PASS_THROUGH_DIRECT ) failed, ERROR = 87, SCSI STAUTUS = 0!
1/24 9:53:42.775 ee8 HA: CMSInitiatorDevice::SendCustomWriteScsiCommand: EXITing with failure, SendCDB2Device(...) failed!
1/24 9:53:42.775 ee8 HA: HA_SendCustomWriteScsiCommand: EXITing with failure, pDevice->SendCustomWriteScsiCommand(..) failed!
1/24 9:53:42.775 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Check is partner heartbeat device mounted...
1/24 9:53:42.775 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Try to send heartbeat ping to partner...
1/24 9:53:42.775 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Heartbeat ping sent succesfully!
1/24 9:53:42.775 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: HAPartnerSynchStatus = 2, HAOwnSynchStatus = 1
1/24 9:53:42.775 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Exiting with flages: bHeartbeatAlive = 1, bIsPartnerNodeShutdowned = 1, bIsCurrentNodeShutdowned = 0
1/24 9:53:49.139 4b0 HA: ImageFile_SW_HA_Control_Command: Received COMMAND_HA_SET_DEVICE_SYNCH_STATUS command
1/24 9:53:49.139 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:53:49.139 13e8 HA: CMSInitiatorDevice::FullSynchronizationThread: Thread was terminated by caller!
1/24 9:53:49.171 13d8 HA: CMSInitiatorDevice::SynchronizationWorkerThread: Thread was terminated by caller!
1/24 9:53:49.171 13e8 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x12B0 is not found!
1/24 9:53:49.171 13e8 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1318 is not found!
1/24 9:53:49.171 13e8 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x104C is not found!
1/24 9:53:49.171 13e8 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xD40 is not found!
1/24 9:53:49.171 13e8 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x11B4 is not found!
1/24 9:53:49.171 13e8 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x10D0 is not found!
1/24 9:53:49.171 13e8 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1130 is not found!
1/24 9:53:49.171 13e8 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xFF8 is not found!
1/24 9:53:49.171 13e8 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1384 is not found!
1/24 9:53:49.171 13e8 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1088 is not found!
1/24 9:53:49.171 13e8 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x126C is not found!
1/24 9:53:49.171 13e8 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xE74 is not found!
1/24 9:53:49.171 13e8 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x5C4 is not found!
1/24 9:53:49.171 13e8 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1030 is not found!
1/24 9:53:49.171 13e8 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1138 is not found!
1/24 9:53:49.171 13e8 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x10EC is not found!
1/24 9:53:49.171 13e8 HA: CMSInitiatorDevice::FullSynchronizationThread: Event:Full_Synchronization_Complete ErrorCode:1067 InitiatorName:iqn.2011-01.sf4u.de:swbox01-ha01 IP:10.0.66.25 Port:3260 TargetName:iqn.2011-01.sf4u.de:swbox02-ha01partner
1/24 9:53:49.171 4b0 HA: ImageFile_SW_HA_Control_Command: Event:Received COMMAND_HA_SYNCHRONIZATION_REQUEST command (Partner requests synchronization)
1/24 9:53:49.171 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:53:49.171 4b0 HA: ImageFile_SW_HA_Control_Command: Synchronization type: 2
1/24 9:53:49.171 4b0 HA: CMSInitiatorDevice::Synchronize: Waiting on thread start event...
1/24 9:57:49.742 ee8 HA: CMSInitiatorDevice::SendCDB2Device: DeviceIoControl(IOCTL_SCSI_PASS_THROUGH_DIRECT) failed (ERROR = 87, SCSI STAUTUS == 0).
1/24 9:57:49.742 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Waiting 1000 msec ...
1/24 9:57:50.757 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Retry(#1 from 1) send cdb ....
1/24 9:57:50.757 ee8 HA: CMSInitiatorDevice::SendCDB2Device: EXITing with failure, DeviceIoControl( IOCTL_SCSI_PASS_THROUGH_DIRECT ) failed, ERROR = 87, SCSI STAUTUS = 0!
1/24 9:57:50.757 ee8 HA: CMSInitiatorDevice::SendCustomWriteScsiCommand: EXITing with failure, SendCDB2Device(...) failed!
1/24 9:57:50.757 ee8 HA: HA_SendCustomWriteScsiCommand: EXITing with failure, pDevice->SendCustomWriteScsiCommand(..) failed!
1/24 9:57:50.757 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Check is partner heartbeat device mounted...
1/24 9:57:50.757 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Try to send heartbeat ping to partner...
1/24 9:57:50.757 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Heartbeat ping sent succesfully!
1/24 9:57:50.757 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: HAPartnerSynchStatus = 2, HAOwnSynchStatus = 1
1/24 9:57:50.757 180 HA: ImageFile_RemoteSendFailSolveThroughHeartbeat: Exiting with flages: bHeartbeatAlive = 1, bIsPartnerNodeShutdowned = 1, bIsCurrentNodeShutdowned = 0
1/24 9:57:52.567 4b0 HA: ImageFile_SW_HA_Control_Command: Received COMMAND_HA_SET_DEVICE_SYNCH_STATUS command
1/24 9:57:52.567 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:57:52.582 eb0 HA: CMSInitiatorDevice::FullSynchronizationThread: Thread was terminated by caller!
1/24 9:57:52.582 1050 HA: CMSInitiatorDevice::SynchronizationWorkerThread: Thread was terminated by caller!
1/24 9:57:52.582 eb0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1E0 is not found!
1/24 9:57:52.582 eb0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x129C is not found!
1/24 9:57:52.582 eb0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x11E8 is not found!
1/24 9:57:52.582 eb0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x11D4 is not found!
1/24 9:57:52.582 eb0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xF24 is not found!
1/24 9:57:52.582 eb0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x108C is not found!
1/24 9:57:52.582 eb0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x12C0 is not found!
1/24 9:57:52.582 eb0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x127C is not found!
1/24 9:57:52.582 eb0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1118 is not found!
1/24 9:57:52.582 eb0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x13F0 is not found!
1/24 9:57:52.582 eb0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xD9C is not found!
1/24 9:57:52.582 eb0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1054 is not found!
1/24 9:57:52.582 eb0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x12E0 is not found!
1/24 9:57:52.582 eb0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x1028 is not found!
1/24 9:57:52.582 eb0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0xE90 is not found!
1/24 9:57:52.582 eb0 HA: CSynchBarrier::LeaveSynchBarrier: WARNING: Barrier block with ThreadId 0x12E8 is not found!
1/24 9:57:52.582 eb0 HA: CMSInitiatorDevice::FullSynchronizationThread: Event:Full_Synchronization_Complete ErrorCode:1067 InitiatorName:iqn.2011-01.sf4u.de:swbox01-ha01 IP:10.0.66.25 Port:3260 TargetName:iqn.2011-01.sf4u.de:swbox02-ha01partner
1/24 9:57:52.598 4b0 HA: ImageFile_SW_HA_Control_Command: Event:Received COMMAND_HA_SYNCHRONIZATION_REQUEST command (Partner requests synchronization)
1/24 9:57:52.598 4b0 HA: ImageFile_SW_HA_Control_Command: Partner target name: 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:57:52.598 4b0 HA: ImageFile_SW_HA_Control_Command: Synchronization type: 2
1/24 9:57:52.598 4b0 HA: CMSInitiatorDevice::Synchronize: Waiting on thread start event...
1/24 9:59:06.363 ee8 HA: CMSInitiatorDevice::SendCDB2Device: DeviceIoControl(IOCTL_SCSI_PASS_THROUGH_DIRECT) failed (ERROR = 87, SCSI STAUTUS == 0).
1/24 9:59:06.363 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Waiting 1000 msec ...
1/24 9:59:07.377 ee8 HA: CMSInitiatorDevice::SendCDB2Device: Retry(#1 from 1) send cdb ....
1/24 9:59:07.377 ee8 HA: CMSInitiatorDevice::SendCDB2Device: EXITing with failure, DeviceIoControl( IOCTL_SCSI_PASS_THROUGH_DIRECT ) failed, ERROR = 87, SCSI STAUTUS = 0!


Log file from the second node.:
1/24 9:54:20.861 1198 Params: <<< Boolean param 'OFMarker': received No, accepted 0
1/24 9:54:20.861 1198 Params: <<< Numeric param 'ErrorRecoveryLevel': received 0, accepted 0
1/24 9:54:20.861 1198 Params: <<< Boolean param 'InitialR2T': received No, accepted 0
1/24 9:54:20.861 1198 Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
1/24 9:54:20.861 113c Params: <<< String param 'InitiatorName': received 'iqn.2011-01.sf4u.de:xensrv02', accepted 'iqn.2011-01.sf4u.de:xensrv02'
1/24 9:54:20.861 1198 Params: <<< Numeric param 'MaxBurstLength': received 16776192, accepted 262144
1/24 9:54:20.861 113c Params: <<< String param 'InitiatorAlias': received 'XenSrv02', accepted 'XenSrv02'
1/24 9:54:20.861 1198 Params: <<< Numeric param 'FirstBurstLength': received 262144, accepted 262144
1/24 9:54:20.861 113c Params: <<< String param 'TargetName': received 'iqn.2011-01.sf4u.de:swbox02-ha01partner', accepted 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:54:20.861 1198 Params: <<< Numeric param 'MaxOutstandingR2T': received 1, accepted 1
1/24 9:54:20.861 113c Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
1/24 9:54:20.861 1198 Params: <<< Numeric param 'MaxConnections': received 1, accepted 1
1/24 9:54:20.861 113c Params: <<< Enum param 'HeaderDigest': received 'None', accepted 'None'
1/24 9:54:20.861 1198 Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
1/24 9:54:20.861 113c Params: <<< Enum param 'DataDigest': received 'None', accepted 'None'
1/24 9:54:20.861 1198 Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
1/24 9:54:20.861 113c Params: <<< Numeric param 'DefaultTime2Wait': received 2, accepted 2
1/24 9:54:20.861 1198 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 131072, accepted 131072
1/24 9:54:20.861 113c Params: <<< Numeric param 'DefaultTime2Retain': received 0, accepted 0
1/24 9:54:20.861 113c Params: <<< Boolean param 'IFMarker': received No, accepted 0
1/24 9:54:20.861 113c Params: <<< Boolean param 'OFMarker': received No, accepted 0
1/24 9:54:20.861 1198 HA: *** ImageFile_RegisterSession: 1/24 9:54:20.861 113c Params: <<< Numeric param 'ErrorRecoveryLevel': received 0, accepted 0
External initiator try to connect to the shutdowned target!
1/24 9:54:20.861 113c Params: <<< Boolean param 'InitialR2T': received No, accepted 0
1/24 9:54:20.861 1198 HA: *** ImageFile_RegisterSession: 1/24 9:54:20.861 113c Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
Initiator name = iqn.2011-01.sf4u.de:xensrv02, Target name = iqn.2011-01.sf4u.de:swbox01-ha01, Is shutdowned = 1!
1/24 9:54:20.861 113c Params: <<< Numeric param 'MaxBurstLength': received 16776192, accepted 262144
1/24 9:54:20.861 1198 Tgt: *ERROR* 'iqn.2011-01.sf4u.de:swbox02-ha01partner' can't register session. The device 'HAImage1' may be owned by a local process!
1/24 9:54:20.861 113c Params: <<< Numeric param 'FirstBurstLength': received 262144, accepted 262144
1/24 9:54:20.861 1198 T[d40b,1]: *ERROR* Login request: device open failed.
1/24 9:54:20.861 113c Params: <<< Numeric param 'MaxOutstandingR2T': received 1, accepted 1
1/24 9:54:20.861 113c Params: <<< Numeric param 'MaxConnections': received 1, accepted 1
1/24 9:54:20.861 113c Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
1/24 9:54:20.861 113c Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
1/24 9:54:20.861 113c Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 131072, accepted 131072
1/24 9:54:20.861 113c HA: *** ImageFile_RegisterSession: External initiator try to connect to the shutdowned target!
1/24 9:54:20.861 113c HA: *** ImageFile_RegisterSession: Initiator name = iqn.2011-01.sf4u.de:xensrv02, Target name = iqn.2011-01.sf4u.de:swbox01-ha01, Is shutdowned = 1!
1/24 9:54:20.861 113c Tgt: *ERROR* 'iqn.2011-01.sf4u.de:swbox02-ha01partner' can't register session. The device 'HAImage1' may be owned by a local process!
1/24 9:54:20.861 113c T[d40c,1]: *ERROR* Login request: device open failed.
1/24 9:54:20.861 1128 C[d40b], IN_LOGIN: Event - LOGIN_REJECT.
1/24 9:54:20.861 126c C[d40c], IN_LOGIN: Event - LOGIN_REJECT.
1/24 9:54:20.861 1128 C[d40b], FREE: T7.
1/24 9:54:20.861 126c C[d40c], FREE: T7.
1/24 9:54:21.126 113c C[d40c], FREE: recvData returned 10054
1/24 9:54:21.126 1198 C[d40b], FREE: recvData returned 10054
1/24 9:54:21.313 fb4 Srv: Accepted iSCSI connection from 10.0.66.10:49983 to 10.0.66.23:3260. (Id = 0xd40d)
1/24 9:54:21.313 fb4 C[d40d], FREE: Event - CONNECTED.
1/24 9:54:21.313 fb4 C[d40d], XPT_UP: T3.
1/24 9:54:21.313 fb4 Srv: Accepted iSCSI connection from 10.0.66.10:44625 to 10.0.66.24:3260. (Id = 0xd40e)
1/24 9:54:21.313 fb4 C[d40e], FREE: Event - CONNECTED.
1/24 9:54:21.313 fb4 C[d40e], XPT_UP: T3.
1/24 9:54:21.563 5ec C[d40d], XPT_UP: Login request: ISID 0x00023d030000, TSIH 0x0000.
1/24 9:54:21.563 1248 C[d40e], XPT_UP: Login request: ISID 0x00023d040000, TSIH 0x0000.
1/24 9:54:21.563 5ec C[d40d], XPT_UP: Event - LOGIN.
1/24 9:54:21.563 5ec C[d40d], IN_LOGIN: T4.
1/24 9:54:21.563 5ec Params: <<< String param 'InitiatorName': received 'iqn.2011-01.sf4u.de:xensrv01', accepted 'iqn.2011-01.sf4u.de:xensrv01'
1/24 9:54:21.563 5ec Params: <<< String param 'InitiatorAlias': received 'XenSrv01', accepted 'XenSrv01'
1/24 9:54:21.563 5ec Params: <<< String param 'TargetName': received 'iqn.2011-01.sf4u.de:swbox02-ha01partner', accepted 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:54:21.563 5ec Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
1/24 9:54:21.563 5ec Params: <<< Enum param 'HeaderDigest': received 'None', accepted 'None'
1/24 9:54:21.563 5ec Params: <<< Enum param 'DataDigest': received 'None', accepted 'None'
1/24 9:54:21.563 1248 C[d40e], XPT_UP: Event - LOGIN.
1/24 9:54:21.563 5ec Params: <<< Numeric param 'DefaultTime2Wait': received 2, accepted 2
1/24 9:54:21.563 1248 C[d40e], IN_LOGIN: T4.
1/24 9:54:21.563 5ec Params: <<< Numeric param 'DefaultTime2Retain': received 0, accepted 0
1/24 9:54:21.563 5ec Params: <<< Boolean param 'IFMarker': received No, accepted 0
1/24 9:54:21.563 5ec Params: <<< Boolean param 'OFMarker': received No, accepted 0
1/24 9:54:21.563 1248 Params: <<< String param 'InitiatorName': received 'iqn.2011-01.sf4u.de:xensrv01', accepted 'iqn.2011-01.sf4u.de:xensrv01'
1/24 9:54:21.563 5ec Params: <<< Numeric param 'ErrorRecoveryLevel': received 0, accepted 0
1/24 9:54:21.563 1248 Params: <<< String param 'InitiatorAlias': received 'XenSrv01', accepted 'XenSrv01'
1/24 9:54:21.563 5ec Params: <<< Boolean param 'InitialR2T': received No, accepted 0
1/24 9:54:21.563 1248 Params: <<< String param 'TargetName': received 'iqn.2011-01.sf4u.de:swbox02-ha01partner', accepted 'iqn.2011-01.sf4u.de:swbox02-ha01partner'
1/24 9:54:21.563 5ec Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
1/24 9:54:21.563 1248 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
1/24 9:54:21.563 5ec Params: <<< Numeric param 'MaxBurstLength': received 16776192, accepted 262144
1/24 9:54:21.563 1248 Params: <<< Enum param 'HeaderDigest': received 'None', accepted 'None'
1/24 9:54:21.563 5ec Params: <<< Numeric param 'FirstBurstLength': received 262144, accepted 262144
1/24 9:54:21.563 1248 Params: <<< Enum param 'DataDigest': received 'None', accepted 'None'
1/24 9:54:21.563 5ec Params: <<< Numeric param 'MaxOutstandingR2T': received 1, accepted 1
1/24 9:54:21.563 1248 Params: <<< Numeric param 'DefaultTime2Wait': received 2, accepted 2
1/24 9:54:21.563 5ec Params: <<< Numeric param 'MaxConnections': received 1, accepted 1
1/24 9:54:21.563 1248 Params: <<< Numeric param 'DefaultTime2Retain': received 0, accepted 0
1/24 9:54:21.563 5ec Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
1/24 9:54:21.563 1248 Params: <<< Boolean param 'IFMarker': received No, accepted 0
1/24 9:54:21.563 5ec Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
1/24 9:54:21.563 1248 Params: <<< Boolean param 'OFMarker': received No, accepted 0
1/24 9:54:21.563 5ec Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 131072, accepted 131072
1/24 9:54:21.563 1248 Params: <<< Numeric param 'ErrorRecoveryLevel': received 0, accepted 0
1/24 9:54:21.563 1248 Params: <<< Boolean param 'InitialR2T': received No, accepted 0
1/24 9:54:21.563 1248 Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
1/24 9:54:21.563 5ec HA: *** ImageFile_RegisterSession: 1/24 9:54:21.563 1248 Params: <<< Numeric param 'MaxBurstLength': received 16776192, accepted 262144
External initiator try to connect to the shutdowned target!
1/24 9:54:21.563 5ec HA: *** ImageFile_RegisterSession: 1/24 9:54:21.563 1248 Params: <<< Numeric param 'FirstBurstLength': received 262144, accepted 262144
Initiator name = iqn.2011-01.sf4u.de:xensrv01, Target name = iqn.2011-01.sf4u.de:swbox01-ha01, Is shutdowned = 1!
1/24 9:54:21.563 1248 Params: <<< Numeric param 'MaxOutstandingR2T': received 1, accepted 1
1/24 9:54:21.563 5ec Tgt: *ERROR* 'iqn.2011-01.sf4u.de:swbox02-ha01partner' can't register session. The device 'HAImage1' may be owned by a local process!
1/24 9:54:21.563 1248 Params: <<< Numeric param 'MaxConnections': received 1, accepted 1
1/24 9:54:21.563 5ec T[d40d,1]: *ERROR* Login request: device open failed.
1/24 9:54:21.563 1248 Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
1/24 9:54:21.563 1248 Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
1/24 9:54:21.563 1248 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 131072, accepted 131072
1/24 9:54:21.563 1248 HA: *** ImageFile_RegisterSession: External initiator try to connect to the shutdowned target!
1/24 9:54:21.563 1248 HA: *** ImageFile_RegisterSession: Initiator name = iqn.2011-01.sf4u.de:xensrv01, Target name = iqn.2011-01.sf4u.de:swbox01-ha01, Is shutdowned = 1!
1/24 9:54:21.563 1248 Tgt: *ERROR* 'iqn.2011-01.sf4u.de:swbox02-ha01partner' can't register session. The device 'HAImage1' may be owned by a local process!
1/24 9:54:21.563 1248 T[d40e,1]: *ERROR* Login request: device open failed.
1/24 9:54:21.563 1298 C[d40d], IN_LOGIN: Event - LOGIN_REJECT.
1/24 9:54:21.563 1298 C[d40d], FREE: T7.
1/24 9:54:21.563 132c C[d40e], IN_LOGIN: Event - LOGIN_REJECT.
1/24 9:54:21.563 132c C[d40e], FREE: T7.
1/24 9:54:21.828 5ec C[d40d], FREE: recvData returned 10054
1/24 9:54:21.828 1248 C[d40e], FREE: recvData returned 10054


Isn't there a solution for this issue???
Top
Post Reply

Return to “StarWind Virtual SAN (VSAN) [+Free], HCI Appliance (HCA), Virtual HCI Appliance (VHCA) [+Free], StarWind x Proxmox VE SAN Integration Services”