gigabit ethernet more hassle than help :)

galonga · Sun Dec 02, 2007 11:03 am

Hi

We have a starwind system we are testing on an IBM enclosure. We tried over 10 and 100 Mb networks and it seemed to work well.

When we did move it to a 1 Gb network we started to have troubles. It's just impossible to format a drive: the enclosure's HD light do not even blink, the CPU usage in the host goes to 100% (when we check who's doing it is the starwind service), and the whole thing just throws an error message at the end. Worse, it keeps corrupting the images we use (we almost lost a database because of that)

We tried that several times and always got the same result. We tried using and not jumbo frames with the same result, but we have not altered the TCP/IP stack as recommended by current StarWind manuals.
We are going to do that now and we'll keep you posted.

Sun Dec 02, 2007 2:13 pm

I'm afraid the troubles are deeper then just TCP/IP stack settings. Would you please send us StarWind logs from the machine failing to do I/O (server). Also *complete* hardware and software configuration is required. Thank you!

galonga · Sun Dec 02, 2007 2:44 pm

We configured the TCP/IP stack as shown and it now seems to work: we were able to format a couple of times.

However:
1 - The CPU count of the webserver is up to 100% (so it does not serve pages).
2 - The SQL server is ok now but when we tried to install starport it crashed.

Actually, we couldn't install starport in ANY server (even in an XP machine) so we put back microsoft's iscsi.

The servers are all AMD K2-550 with 500Mb RAM each and running 2003 SP1 (the starwind server is running R2).

Below an example of the log:

12/2 12:36:38.645 68c Srv: StarWind iSCSI Target v3.5 (Build 20070809, Win32)
12/2 12:36:38.645 68c Srv: Built Aug 10 2007 00:00:56
12/2 12:36:38.655 68c Srv: System CPU count: 1
12/2 12:36:38.655 68c Srv: Licensed to: 'Al Costa', 'Private', (#2007112801, 0, 0).
12/2 12:36:44.674 68c Srv: Unlimited number of client hosts allowed.
12/2 12:36:44.694 68c Srv: Config file: 'StarWind.cfg'
12/2 12:36:44.814 68c conf: 'LogLevel' = '1'
12/2 12:36:44.814 68c conf: 'LogMask' = '0x8fffffff'
12/2 12:36:44.814 68c conf: 'Port' = '3260'
12/2 12:36:44.814 68c conf: 'Interface' = '0.0.0.0'
12/2 12:36:44.814 68c conf: 'CtlPort' = '3261'
12/2 12:36:44.814 68c conf: 'CtlInterface' = '0.0.0.0'
12/2 12:36:44.814 68c conf: 'Login' = 'test'
12/2 12:36:44.814 68c conf: 'Password' = '##CY9rzUYh03PK3k6DJie09g=='
12/2 12:36:44.824 68c conf: 'Echo' = 'no'
12/2 12:36:44.824 68c conf: 'MinBufferSize' = '65536'
12/2 12:36:44.824 68c conf: 'AlignmentMask' = '0x0000'
12/2 12:36:44.824 68c conf: 'MaxPendingRequests' = '64'
12/2 12:36:44.824 68c conf: read command: addplugin -module:"RamDisk.dll" -symlink:"RamDrive" -type:"RAM disk"
12/2 12:36:44.834 68c conf: read command: addplugin -module:"ImageFile.dll" -symlink:"ImageFile" -type:"Image file" -imagedir:"*|cmdfv|My Computer|img" -volumes:"no"
12/2 12:36:44.894 68c conf: read command: addplugin -module:"VirtualDvd.dll" -symlink:"VirtualDvd" -type:"Virtual DVD" -imagedir:"*|mv|My Computer|mds,iso"
12/2 12:36:44.904 68c conf: read command: addplugin -module:"IBVolume.dll" -symlink:"IBV" -type:"IBV" -imagedir:"*|cdmfv|My Computer|ibv" -imagedir:"*|dmfv|Snapshots|ibvss" -imagedir:"*|v|Image Files|img,dat"
12/2 12:36:44.914 68c conf: read command: addplugin -module:"Mirror.dll" -symlink:"Mirror" -type:"Mirror" -imagedir:"*|cdmfv|Mirror Volumes|mir" -imagedir:"*|v|Image Files|img,dat"
12/2 12:36:44.914 68c conf: read command: addplugin -module:"DiskBridge.dll" -symlink:"DiskBridge" -type:"DiskBridge"
12/2 12:36:44.924 68c conf: read command: add "ImageFile1" "sql" -file:"My Computer\S\sql.img" -asyncmode:"no" -clustered:"yes" -readonly:"no"
12/2 12:36:44.924 68c conf: read command: add "ImageFile0" "iis" -file:"My Computer\I\iis.img" -asyncmode:"no" -clustered:"yes" -readonly:"no"
12/2 12:36:44.924 68c conf: read command: add "ImageFile0" "sql" -file:"My Computer\S\sql.img" -asyncmode:"no" -clustered:"yes" -readonly:"no"
12/2 12:36:44.924 68c conf: read command: add "ImageFile1" "iis" -file:"My Computer\I\iis.img" -asyncmode:"no" -clustered:"yes" -readonly:"no"
12/2 12:36:44.934 68c conf: read command: add "IBV0" "sql" -file:"My Computer\S\sql.ibv" -mode:"1" -asyncmode:"yes" -clustered:"yes"
12/2 12:36:44.934 68c conf: read command: add "IBV1" "iis" -file:"My Computer\I\iis.ibv" -mode:"1" -asyncmode:"yes" -clustered:"yes"
12/2 12:36:44.934 68c conf: read command: add "IBV0" "iis" -file:"My Computer\I\iis.ibv" -mode:"1" -asyncmode:"yes" -clustered:"yes"
12/2 12:36:44.944 68c conf: Variable 'LogLevel' is set to '1'.
12/2 12:36:44.944 68c conf: Variable 'LogMask' is set to '0x8fffffff'.
12/2 12:36:44.944 68c conf: Variable 'Port' is set to '3260'.
12/2 12:36:44.944 68c conf: Variable 'Interface' is set to '0.0.0.0'.
12/2 12:36:44.944 68c conf: Variable 'CtlPort' is set to '3261'.
12/2 12:36:44.944 68c conf: Variable 'CtlInterface' is set to '0.0.0.0'.
12/2 12:36:44.944 68c conf: Variable 'Login' is set to 'test'.
12/2 12:36:44.944 68c conf: Variable 'Password' is set to '##CY9rzUYh03PK3k6DJie09g=='.
12/2 12:36:44.944 68c conf: Variable 'Echo' is set to 'no'.
12/2 12:36:44.944 68c conf: Variable 'MinBufferSize' is set to '65536'.
12/2 12:36:44.944 68c conf: Variable 'AlignmentMask' is set to '0x0000'.
12/2 12:36:44.944 68c conf: Variable 'MaxPendingRequests' is set to '64'.
12/2 12:36:44.964 68c conf: 'RamDisk.dll' loaded successfully.
12/2 12:36:45.335 68c IMG: SscPort_Init: Parse ImageDir: '*|cmdfv|My Computer|img'
12/2 12:36:45.335 68c IMG: *SscPort_Init:
Dirname: '*'
Rights: 0x1f
Alias: 'My Computer'
Extensions: 'img'
12/2 12:36:45.335 68c IMG: SscPort_Init: Comment : ''
12/2 12:36:45.335 68c IMG: SscPort_Init: (build 20070809, built Aug 10 2007 00:00:47)
VendorId = ' '
ProductId = ' '
Revision = '0001'
DefImageDir = '*\'
Volumes = 0
ExeDir = 'C:\Program Files\Rocket Division Software\StarWind\'
12/2 12:36:45.335 68c conf: 'ImageFile.dll' loaded successfully.
12/2 12:36:45.345 68c VDVD: *SscPort_Init:
Dirname: '*'
Rights: 0x18
Alias: 'My Computer'
Extensions: 'mds,iso'
12/2 12:36:45.345 68c conf: 'VirtualDvd.dll' loaded successfully.
12/2 12:36:45.355 68c IBV: *SscPort_Init:
Dirname: '*'
Rights: 0x1f
Alias: 'My Computer'
Extensions: 'ibv'
12/2 12:36:45.355 68c IBV: *SscPort_Init:
Dirname: '*'
Rights: 0x1e
Alias: 'Snapshots'
Extensions: 'ibvss'
12/2 12:36:45.355 68c IBV: *SscPort_Init:
Dirname: '*'
Rights: 0x8
Alias: 'Image Files'
Extensions: 'img,dat'
12/2 12:36:45.355 68c conf: 'IBVolume.dll' loaded successfully.
12/2 12:36:45.365 68c MIR: SscPort_Init: Parse ImageDir: '*|cdmfv|Mirror Volumes|mir'
12/2 12:36:45.365 68c MIR: *SscPort_Init:
Dirname: '*'
Rights: 0x1f
Alias: 'Mirror Volumes'
Extensions: 'mir'
12/2 12:36:45.365 68c MIR: SscPort_Init: Comment : ''
12/2 12:36:45.365 68c MIR: SscPort_Init: Parse ImageDir: '*|v|Image Files|img,dat'
12/2 12:36:45.365 68c Sw: Browser_DirCreate: Mask mismatch (8 / 1)
12/2 12:36:45.365 68c Sw: Browser_DirDelete: Mask mismatch (8 / 2)
12/2 12:36:45.365 68c Sw: Browser_DirCreateFolder: Mask mismatch (8 / 4)
12/2 12:36:45.365 68c MIR: *SscPort_Init:
Dirname: '*'
Rights: 0x8
Alias: 'Image Files'
Extensions: 'img,dat'
12/2 12:36:45.365 68c MIR: SscPort_Init: Comment : ''
12/2 12:36:45.365 68c conf: 'Mirror.dll' loaded successfully.
12/2 12:36:45.375 68c DSKBR: SscPort_Init: (built Aug 9 2007 22:24:38)
VendorId = ' '
ProductId = ' '
Revision = '0001'
ExeDir = 'C:\Program Files\Rocket Division Software\StarWind\'
12/2 12:36:45.375 68c conf: 'DiskBridge.dll' loaded successfully.
12/2 12:36:45.375 68c SCSI: Device 'ImageFile1': Clustered = 1
12/2 12:36:45.375 68c SCSI: Device 'ImageFile1': Reservation = 1
12/2 12:36:45.375 68c IMG: SscPort_Create: Opening device 'ImageFile1', image file 'My Computer\S\sql.img', buffering=0, async=0, readonly=0.
12/2 12:36:45.375 68c IMG: *** ImageFile_Create: 'S:\sql.img': can't open the file (2).
12/2 12:36:45.385 68c IMG: *** SscPort_Create: Create('My Computer\S\sql.img') error (0xc0000001)!
12/2 12:36:45.385 68c SCSI: Failed to create 'ImageFile1' device.
12/2 12:36:45.385 68c Tgt: : *ERROR* cannot open the device 'ImageFile1'.
12/2 12:36:45.385 68c Srv: * Device test failed for 'ImageFile1'.
12/2 12:36:45.385 68c conf: Device test failed for 'ImageFile1'.
12/2 12:36:45.385 68c SCSI: Device 'ImageFile0': Clustered = 1
12/2 12:36:45.385 68c SCSI: Device 'ImageFile0': Reservation = 1
12/2 12:36:45.385 68c IMG: SscPort_Create: Opening device 'ImageFile0', image file 'My Computer\I\iis.img', buffering=0, async=0, readonly=0.
12/2 12:36:45.385 68c IMG: *** ImageFile_Create: 'I:\iis.img': can't open the file (2).
12/2 12:36:45.385 68c IMG: *** SscPort_Create: Create('My Computer\I\iis.img') error (0xc0000001)!
12/2 12:36:45.385 68c SCSI: Failed to create 'ImageFile0' device.
12/2 12:36:45.385 68c Tgt: : *ERROR* cannot open the device 'ImageFile0'.
12/2 12:36:45.385 68c Srv: * Device test failed for 'ImageFile0'.
12/2 12:36:45.385 68c conf: Device test failed for 'ImageFile0'.
12/2 12:36:45.385 68c SCSI: Device 'ImageFile0': Clustered = 1
12/2 12:36:45.385 68c SCSI: Device 'ImageFile0': Reservation = 1
12/2 12:36:45.385 68c IMG: SscPort_Create: Opening device 'ImageFile0', image file 'My Computer\S\sql.img', buffering=0, async=0, readonly=0.
12/2 12:36:45.395 68c IMG: *** ImageFile_Create: 'S:\sql.img': can't open the file (2).
12/2 12:36:45.395 68c IMG: *** SscPort_Create: Create('My Computer\S\sql.img') error (0xc0000001)!
12/2 12:36:45.395 68c SCSI: Failed to create 'ImageFile0' device.
12/2 12:36:45.395 68c Tgt: : *ERROR* cannot open the device 'ImageFile0'.
12/2 12:36:45.395 68c Srv: * Device test failed for 'ImageFile0'.
12/2 12:36:45.395 68c conf: Device test failed for 'ImageFile0'.
12/2 12:36:45.395 68c SCSI: Device 'ImageFile1': Clustered = 1
12/2 12:36:45.395 68c SCSI: Device 'ImageFile1': Reservation = 1
12/2 12:36:45.395 68c IMG: SscPort_Create: Opening device 'ImageFile1', image file 'My Computer\I\iis.img', buffering=0, async=0, readonly=0.
12/2 12:36:45.395 68c IMG: *** ImageFile_Create: 'I:\iis.img': can't open the file (2).
12/2 12:36:45.395 68c IMG: *** SscPort_Create: Create('My Computer\I\iis.img') error (0xc0000001)!
12/2 12:36:45.395 68c SCSI: Failed to create 'ImageFile1' device.
12/2 12:36:45.395 68c Tgt: : *ERROR* cannot open the device 'ImageFile1'.
12/2 12:36:45.395 68c Srv: * Device test failed for 'ImageFile1'.
12/2 12:36:45.405 68c conf: Device test failed for 'ImageFile1'.
12/2 12:36:45.405 68c SCSI: Device 'IBV0': Clustered = 1
12/2 12:36:45.405 68c SCSI: Device 'IBV0': Reservation = 1
12/2 12:36:45.425 68c S: Assigned target name: 'sql', SymId: 'IBV0'.
12/2 12:36:45.425 68c Tgt: : parameter 'file', value 'My Computer\S\sql.ibv'.
12/2 12:36:45.425 68c Tgt: : parameter 'mode', value '1'.
12/2 12:36:45.425 68c Tgt: : parameter 'JournalSwitchPeriod', value '0'.
12/2 12:36:45.425 68c Tgt: : parameter 'MaxStoredSessions', value '0'.
12/2 12:36:45.425 68c Tgt: : parameter 'ImageSizeLow', value '2164260864'.
12/2 12:36:45.425 68c Tgt: : parameter 'ImageSizeHigh', value '2'.
12/2 12:36:45.425 68c Tgt: : parameter 'CurrentMode', value '1'.
12/2 12:36:45.425 68c Tgt: : parameter 'asyncmode', value 'yes'.
12/2 12:36:45.425 68c Tgt: : parameter 'LastSessionId', value '1'.
12/2 12:36:45.425 68c S: sql: 65536 session(s) allowed.
12/2 12:36:45.425 68c conf: Target [0x000C0D38] has been created. Device 'IBV0' is accesible as target 'sql'.
12/2 12:36:45.425 68c SCSI: Device 'IBV1': Clustered = 1
12/2 12:36:45.425 68c SCSI: Device 'IBV1': Reservation = 1
12/2 12:36:45.435 68c S: Assigned target name: 'iis', SymId: 'IBV1'.
12/2 12:36:45.435 68c Tgt: : parameter 'file', value 'My Computer\I\iis.ibv'.
12/2 12:36:45.435 68c Tgt: : parameter 'mode', value '1'.
12/2 12:36:45.435 68c Tgt: : parameter 'JournalSwitchPeriod', value '0'.
12/2 12:36:45.435 68c Tgt: : parameter 'MaxStoredSessions', value '0'.
12/2 12:36:45.435 68c Tgt: : parameter 'ImageSizeLow', value '2164260864'.
12/2 12:36:45.435 68c Tgt: : parameter 'ImageSizeHigh', value '2'.
12/2 12:36:45.435 68c Tgt: : parameter 'CurrentMode', value '1'.
12/2 12:36:45.435 68c Tgt: : parameter 'asyncmode', value 'yes'.
12/2 12:36:45.435 68c Tgt: : parameter 'LastSessionId', value '1'.
12/2 12:36:45.435 68c S: iis: 65536 session(s) allowed.
12/2 12:36:45.435 68c conf: Target [0x000C4478] has been created. Device 'IBV1' is accesible as target 'iis'.
12/2 12:36:45.435 68c Srv: * Device name 'IBV0' is already mapped as target 'iis'.
12/2 12:36:45.435 68c conf: Device name 'IBV0' is already mapped as target 'iis'.
12/2 12:36:45.435 68c Srv: Refreshing device list (plugins)...
12/2 12:36:45.545 348 conf: Waiting for Control connections at 0.0.0.0:3261 ...
12/2 12:36:45.595 68c Srv: Waiting for iSCSI connections at 0.0.0.0:3260 ...
12/2 12:36:45.775 68c Srv: Accepted iSCSI connection from 192.168.0.2:1743. (Id = 0x1)
12/2 12:36:45.775 68c C[1], FREE: Event - CONNECTED.
12/2 12:36:45.775 68c C[1], XPT_UP: T3.
12/2 12:36:45.785 68c Srv: Accepted iSCSI connection from 192.168.0.2:1745. (Id = 0x2)
12/2 12:36:45.785 68c C[2], FREE: Event - CONNECTED.
12/2 12:36:45.785 68c C[2], XPT_UP: T3.
12/2 12:36:45.805 68c Srv: Accepted iSCSI connection from 192.168.0.2:1746. (Id = 0x3)
12/2 12:36:45.805 68c C[3], FREE: Event - CONNECTED.
12/2 12:36:45.805 68c C[3], XPT_UP: T3.
12/2 12:36:45.815 68c Srv: Accepted iSCSI connection from 192.168.0.2:1744. (Id = 0x4)
12/2 12:36:45.815 68c C[4], FREE: Event - CONNECTED.
12/2 12:36:45.815 68c C[4], XPT_UP: T3.
12/2 12:36:45.825 68c Srv: Accepted iSCSI connection from 192.168.0.2:1747. (Id = 0x5)
12/2 12:36:45.825 68c C[5], FREE: Event - CONNECTED.
12/2 12:36:45.825 68c C[5], XPT_UP: T3.
12/2 12:36:47.748 e80 C[1], XPT_UP: Login request: ISID 0x400001370001, TSIH 0x0000.
12/2 12:36:47.758 e80 C[1], XPT_UP: Event - LOGIN.
12/2 12:36:47.758 e80 C[1], IN_LOGIN: T4.
12/2 12:36:47.758 e80 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:sql.dominio.kviar', accepted 'iqn.1991-05.com.microsoft:sql.dominio.kviar'
12/2 12:36:47.758 e80 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
12/2 12:36:47.758 e80 Params: <<< String param 'TargetName': received 'sql', accepted 'sql'
12/2 12:36:47.758 e80 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:36:47.758 e80 Tgt: : open 'sql': 1 session(s) opened, 65535 more allowed.
12/2 12:36:47.758 e80 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:36:47.758 e80 T[1]: Anonymous connection allowed.
12/2 12:36:47.758 e80 Params: >>> AuthMethod=None.
12/2 12:36:47.758 e80 Params: >>> TargetPortalGroupTag=1.
12/2 12:36:47.758 b98 T[1]: session 0x1, connection 0x1 : end of stage 0, next stage 1.
12/2 12:36:47.758 b80 C[2], XPT_UP: Login request: ISID 0x400001370005, TSIH 0x0000.
12/2 12:36:47.768 e80 Params: <<< Enum param 'HeaderDigest': received 'None,CRC32C', accepted 'None'
12/2 12:36:47.768 e80 Params: <<< Enum param 'DataDigest': received 'None,CRC32C', accepted 'None'
12/2 12:36:47.768 e80 Params: <<< Numeric param 'ErrorRecoveryLevel': received 2, accepted 0
12/2 12:36:47.768 e80 Params: <<< Boolean param 'InitialR2T': received No, accepted 0
12/2 12:36:47.768 e80 Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
12/2 12:36:47.768 e80 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 65536, accepted 65536
12/2 12:36:47.768 e80 Params: <<< Numeric param 'MaxBurstLength': received 262144, accepted 262144
12/2 12:36:47.768 e80 Params: <<< Numeric param 'FirstBurstLength': received 65536, accepted 65536
12/2 12:36:47.768 e80 Params: <<< Numeric param 'MaxConnections': received 8, accepted 1
12/2 12:36:47.768 e80 Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
12/2 12:36:47.768 e80 Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
12/2 12:36:47.768 e80 Params: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 2
12/2 12:36:47.768 e80 Params: <<< Numeric param 'DefaultTime2Retain': received 60, accepted 20
12/2 12:36:47.768 e80 Params: <<< Numeric param 'MaxOutstandingR2T': received 16, accepted 1
12/2 12:36:47.768 e80 Params: >>> ErrorRecoveryLevel=0.
12/2 12:36:47.768 e80 Params: >>> MaxConnections=1.
12/2 12:36:47.768 e80 Params: >>> HeaderDigest=None.
12/2 12:36:47.768 e80 Params: >>> DataDigest=None.
12/2 12:36:47.768 e80 Params: >>> InitialR2T=No.
12/2 12:36:47.768 e80 Params: >>> ImmediateData=Yes.
12/2 12:36:47.768 e80 Params: >>> MaxRecvDataSegmentLength=262144.
12/2 12:36:47.768 e80 Params: >>> MaxBurstLength=262144.
12/2 12:36:47.768 e80 Params: >>> FirstBurstLength=65536.
12/2 12:36:47.768 e80 Params: >>> DefaultTime2Wait=2.
12/2 12:36:47.768 e80 Params: >>> DefaultTime2Retain=20.
12/2 12:36:47.768 e80 Params: >>> MaxOutstandingR2T=1.
12/2 12:36:47.768 e80 Params: >>> DataPDUInOrder=Yes.
12/2 12:36:47.768 e80 Params: >>> DataSequenceInOrder=Yes.
12/2 12:36:47.768 b98 T[1]: session 0x1, connection 0x1 : end of stage 1, next stage 3.
12/2 12:36:47.768 b98 C[1], IN_LOGIN: Event - LOGIN_ACCEPT.
12/2 12:36:47.768 b98 C[1], LIN: T5.
12/2 12:36:47.778 b80 C[2], XPT_UP: Event - LOGIN.
12/2 12:36:47.778 b80 C[2], IN_LOGIN: T4.
12/2 12:36:47.778 b80 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:sql.dominio.kviar', accepted 'iqn.1991-05.com.microsoft:sql.dominio.kviar'
12/2 12:36:47.778 b80 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
12/2 12:36:47.778 b80 Params: <<< String param 'TargetName': received 'sql', accepted 'sql'
12/2 12:36:47.778 b80 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:36:47.788 b80 T[1]: An old session <1> from the same Initiator/ISID found. Session reinstatement starting...
12/2 12:36:47.798 b80 C[1], LIN: Close socket.
12/2 12:36:47.798 e80 C[1], LIN: recvData() error (10053)
12/2 12:36:47.798 e80 C[1], LIN: *** 'recv' thread: recv failed 10058.
12/2 12:36:47.798 b80 S[1]: Connection <1> stat (1)
12/2 12:36:47.808 9c8 C[3], XPT_UP: Login request: ISID 0x400001370004, TSIH 0x0000.
12/2 12:36:47.818 9c8 C[3], XPT_UP: Event - LOGIN.
12/2 12:36:47.818 9c8 C[3], IN_LOGIN: T4.
12/2 12:36:47.818 9c8 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:sql.dominio.kviar', accepted 'iqn.1991-05.com.microsoft:sql.dominio.kviar'
12/2 12:36:47.818 9c8 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
12/2 12:36:47.818 9c8 Params: <<< String param 'TargetName': received 'sql', accepted 'sql'
12/2 12:36:47.818 9c8 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:36:47.818 5a4 C[4], XPT_UP: Login request: ISID 0x400001370003, TSIH 0x0000.
12/2 12:36:47.828 5a4 C[4], XPT_UP: Event - LOGIN.
12/2 12:36:47.828 5a4 C[4], IN_LOGIN: T4.
12/2 12:36:47.828 5a4 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:sql.dominio.kviar', accepted 'iqn.1991-05.com.microsoft:sql.dominio.kviar'
12/2 12:36:47.828 5a4 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
12/2 12:36:47.828 5a4 Params: <<< String param 'TargetName': received 'sql', accepted 'sql'
12/2 12:36:47.828 5a4 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:36:47.828 478 C[5], XPT_UP: Login request: ISID 0x400001370002, TSIH 0x0000.
12/2 12:36:47.838 478 C[5], XPT_UP: Event - LOGIN.
12/2 12:36:47.838 478 C[5], IN_LOGIN: T4.
12/2 12:36:47.838 478 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:sql.dominio.kviar', accepted 'iqn.1991-05.com.microsoft:sql.dominio.kviar'
12/2 12:36:47.838 478 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
12/2 12:36:47.838 478 Params: <<< String param 'TargetName': received 'sql', accepted 'sql'
12/2 12:36:47.838 478 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:36:47.968 b98 Tgt: : close 'sql': 0 session(s) opened, 65536 more allowed.
12/2 12:36:47.968 478 T[1]: An old session <2> from the same Initiator/ISID found. Session reinstatement starting...
12/2 12:36:47.968 478 C[2], IN_LOGIN: Close socket.
12/2 12:36:47.968 478 S[2]: Connection <2> stat (1)
12/2 12:36:47.978 5a4 T[1]: An old session <2> from the same Initiator/ISID found. Session reinstatement starting...
12/2 12:36:47.978 5a4 S[2]: Connection <2> stat (0)
12/2 12:36:47.978 9c8 T[1]: An old session <2> from the same Initiator/ISID found. Session reinstatement starting...
12/2 12:36:47.978 9c8 S[2]: Connection <2> stat (0)
12/2 12:36:48.319 b80 T[1]: Waiting for the old session <3> to shut down...
12/2 12:36:48.319 b80 T[1]: Old session <3> is still present. Force clean up...
12/2 12:36:48.319 b80 Tgt: : close 'sql': -1 session(s) opened, 65537 more allowed.
12/2 12:36:48.319 b80 T[1]: An old session <4> from the same Initiator/ISID found. Session reinstatement starting...
12/2 12:36:48.319 b80 C[4], IN_LOGIN: Close socket.
12/2 12:36:48.319 b80 S[4]: Connection <4> stat (1)
12/2 12:36:48.469 478 T[1]: Waiting for the old session <2> to shut down...
12/2 12:36:48.469 478 T[1]: Old session <2> is still present. Force clean up...
12/2 12:36:48.469 478 Tgt: : close 'sql': -2 session(s) opened, 65538 more allowed.
12/2 12:36:48.469 478 T[1]: An old session <4> from the same Initiator/ISID found. Session reinstatement starting...
12/2 12:36:48.469 478 S[4]: Connection <4> stat (0)
12/2 12:36:48.479 5a4 T[1]: Waiting for the old session <5> to shut down...
12/2 12:36:48.479 5a4 T[1]: Old session <5> is still present. Force clean up...
12/2 12:36:48.479 5a4 Tgt: : close 'sql': -3 session(s) opened, 65539 more allowed.
12/2 12:36:48.479 5a4 Tgt: : open 'sql': -2 session(s) opened, 65538 more allowed.
12/2 12:36:48.479 5a4 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:36:48.479 5a4 T[1]: Anonymous connection allowed.
12/2 12:36:48.479 5a4 Params: >>> AuthMethod=None.
12/2 12:36:48.479 5a4 Params: >>> TargetPortalGroupTag=1.
12/2 12:36:48.479 464 T[1]: session 0x4, connection 0x4 : end of stage 0, next stage 1.
12/2 12:36:48.479 d44 C[4], IN_LOGIN: WSASend() returned 10038!
12/2 12:36:48.479 5a4 C[4], IN_LOGIN: recvData() error (10038)
12/2 12:36:48.479 5a4 C[4], IN_LOGIN: *** 'recv' thread: recv failed 10058.
12/2 12:36:48.479 464 Tsession 0x4, Task 0x1 aborted.
12/2 12:36:48.479 464 T[1]: state 0x2, immediate 1, ITT 0x15, CmdSn 21, TTT 0x2
12/2 12:36:48.479 464 T[1]: read/write 11, read length 39, read done 0, write length 108, write done 0, DATA-IN PDUs 0
12/2 12:36:48.479 464 T[1]: DataSN 0, R2TSN 0, status 0, status class 0, status detail 0, response 0, counter 1, authStage 4
12/2 12:36:48.479 464 T[1]: CDB
0000 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
12/2 12:36:48.479 464 Tgt: : close 'sql': -3 session(s) opened, 65539 more allowed.
12/2 12:36:48.499 9c8 Tgt: : open 'sql': -2 session(s) opened, 65538 more allowed.
12/2 12:36:48.499 9c8 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:36:48.499 9c8 T[1]: Anonymous connection allowed.
12/2 12:36:48.499 9c8 Params: >>> AuthMethod=None.
12/2 12:36:48.499 9c8 Params: >>> TargetPortalGroupTag=1.
12/2 12:36:48.499 a44 T[1]: session 0x3, connection 0x3 : end of stage 0, next stage 1.
12/2 12:36:48.499 9c8 Params: <<< Enum param 'HeaderDigest': received 'None,CRC32C', accepted 'None'
12/2 12:36:48.509 9c8 Params: <<< Enum param 'DataDigest': received 'None,CRC32C', accepted 'None'
12/2 12:36:48.509 9c8 Params: <<< Numeric param 'ErrorRecoveryLevel': received 2, accepted 0
12/2 12:36:48.509 9c8 Params: <<< Boolean param 'InitialR2T': received No, accepted 0
12/2 12:36:48.509 9c8 Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
12/2 12:36:48.509 9c8 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 65536, accepted 65536
12/2 12:36:48.509 9c8 Params: <<< Numeric param 'MaxBurstLength': received 262144, accepted 262144
12/2 12:36:48.509 9c8 Params: <<< Numeric param 'FirstBurstLength': received 65536, accepted 65536
12/2 12:36:48.509 9c8 Params: <<< Numeric param 'MaxConnections': received 8, accepted 1
12/2 12:36:48.509 9c8 Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
12/2 12:36:48.509 9c8 Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
12/2 12:36:48.509 9c8 Params: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 2
12/2 12:36:48.509 9c8 Params: <<< Numeric param 'DefaultTime2Retain': received 60, accepted 20
12/2 12:36:48.509 9c8 Params: <<< Numeric param 'MaxOutstandingR2T': received 16, accepted 1
12/2 12:36:48.509 9c8 Params: >>> ErrorRecoveryLevel=0.
12/2 12:36:48.509 9c8 Params: >>> MaxConnections=1.
12/2 12:36:48.509 9c8 Params: >>> HeaderDigest=None.
12/2 12:36:48.509 9c8 Params: >>> DataDigest=None.
12/2 12:36:48.509 9c8 Params: >>> InitialR2T=No.
12/2 12:36:48.509 9c8 Params: >>> ImmediateData=Yes.
12/2 12:36:48.509 9c8 Params: >>> MaxRecvDataSegmentLength=262144.
12/2 12:36:48.509 9c8 Params: >>> MaxBurstLength=262144.
12/2 12:36:48.509 9c8 Params: >>> FirstBurstLength=65536.
12/2 12:36:48.509 9c8 Params: >>> DefaultTime2Wait=2.
12/2 12:36:48.509 9c8 Params: >>> DefaultTime2Retain=20.
12/2 12:36:48.509 9c8 Params: >>> MaxOutstandingR2T=1.
12/2 12:36:48.509 9c8 Params: >>> DataPDUInOrder=Yes.
12/2 12:36:48.509 9c8 Params: >>> DataSequenceInOrder=Yes.
12/2 12:36:48.509 a44 T[1]: session 0x3, connection 0x3 : end of stage 1, next stage 3.
12/2 12:36:48.509 a44 C[3], IN_LOGIN: Event - LOGIN_ACCEPT.
12/2 12:36:48.509 a44 C[3], LIN: T5.
12/2 12:36:48.820 b80 Tgt: : open 'sql': -1 session(s) opened, 65537 more allowed.
12/2 12:36:48.820 b80 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:36:48.820 b80 T[1]: Anonymous connection allowed.
12/2 12:36:48.820 b80 Params: >>> AuthMethod=None.
12/2 12:36:48.820 b80 Params: >>> TargetPortalGroupTag=1.
12/2 12:36:48.820 b9c T[1]: session 0x2, connection 0x2 : end of stage 0, next stage 1.
12/2 12:36:48.820 718 C[2], IN_LOGIN: WSASend() returned 10038!
12/2 12:36:48.820 b80 C[2], IN_LOGIN: recvData() error (10038)
12/2 12:36:48.820 b80 C[2], IN_LOGIN: *** 'recv' thread: recv failed 10058.
12/2 12:36:48.820 b9c Tsession 0x2, Task 0x1 aborted.
12/2 12:36:48.820 b9c T[1]: state 0x2, immediate 1, ITT 0x24, CmdSn 36, TTT 0x2
12/2 12:36:48.820 b9c T[1]: read/write 11, read length 39, read done 0, write length 108, write done 0, DATA-IN PDUs 0
12/2 12:36:48.820 b9c T[1]: DataSN 0, R2TSN 0, status 0, status class 0, status detail 0, response 0, counter 1, authStage 4
12/2 12:36:48.820 b9c T[1]: CDB
0000 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
12/2 12:36:49.100 478 Tgt: : open 'sql': 0 session(s) opened, 65536 more allowed.
12/2 12:36:49.100 478 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:36:49.100 478 T[1]: Anonymous connection allowed.
12/2 12:36:49.100 478 Params: >>> AuthMethod=None.
12/2 12:36:49.100 478 Params: >>> TargetPortalGroupTag=1.
12/2 12:36:49.100 7f8 T[1]: session 0x5, connection 0x5 : end of stage 0, next stage 1.
12/2 12:36:49.110 478 Params: <<< Enum param 'HeaderDigest': received 'None,CRC32C', accepted 'None'
12/2 12:36:49.110 478 Params: <<< Enum param 'DataDigest': received 'None,CRC32C', accepted 'None'
12/2 12:36:49.110 478 Params: <<< Numeric param 'ErrorRecoveryLevel': received 2, accepted 0
12/2 12:36:49.110 478 Params: <<< Boolean param 'InitialR2T': received No, accepted 0
12/2 12:36:49.110 478 Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
12/2 12:36:49.110 478 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 65536, accepted 65536
12/2 12:36:49.110 478 Params: <<< Numeric param 'MaxBurstLength': received 262144, accepted 262144
12/2 12:36:49.110 478 Params: <<< Numeric param 'FirstBurstLength': received 65536, accepted 65536
12/2 12:36:49.110 478 Params: <<< Numeric param 'MaxConnections': received 8, accepted 1
12/2 12:36:49.110 478 Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
12/2 12:36:49.110 478 Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
12/2 12:36:49.110 478 Params: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 2
12/2 12:36:49.110 478 Params: <<< Numeric param 'DefaultTime2Retain': received 60, accepted 20
12/2 12:36:49.110 478 Params: <<< Numeric param 'MaxOutstandingR2T': received 16, accepted 1
12/2 12:36:49.110 478 Params: >>> ErrorRecoveryLevel=0.
12/2 12:36:49.110 478 Params: >>> MaxConnections=1.
12/2 12:36:49.110 478 Params: >>> HeaderDigest=None.
12/2 12:36:49.110 478 Params: >>> DataDigest=None.
12/2 12:36:49.110 478 Params: >>> InitialR2T=No.
12/2 12:36:49.110 478 Params: >>> ImmediateData=Yes.
12/2 12:36:49.110 478 Params: >>> MaxRecvDataSegmentLength=262144.
12/2 12:36:49.120 478 Params: >>> MaxBurstLength=262144.
12/2 12:36:49.120 478 Params: >>> FirstBurstLength=65536.
12/2 12:36:49.120 478 Params: >>> DefaultTime2Wait=2.
12/2 12:36:49.120 478 Params: >>> DefaultTime2Retain=20.
12/2 12:36:49.120 478 Params: >>> MaxOutstandingR2T=1.
12/2 12:36:49.120 478 Params: >>> DataPDUInOrder=Yes.
12/2 12:36:49.120 478 Params: >>> DataSequenceInOrder=Yes.
12/2 12:36:49.120 7f8 T[1]: session 0x5, connection 0x5 : end of stage 1, next stage 3.
12/2 12:36:49.120 7f8 C[5], IN_LOGIN: Event - LOGIN_ACCEPT.
12/2 12:36:49.120 7f8 C[5], LIN: T5.
12/2 12:36:57.091 68c Srv: Accepted iSCSI connection from 192.168.0.2:1748. (Id = 0x6)
12/2 12:36:57.091 68c C[6], FREE: Event - CONNECTED.
12/2 12:36:57.091 68c C[6], XPT_UP: T3.
12/2 12:36:57.172 c38 C[6], XPT_UP: Login request: ISID 0x400001370001, TSIH 0x0000.
12/2 12:36:57.182 c38 C[6], XPT_UP: Event - LOGIN.
12/2 12:36:57.182 c38 C[6], IN_LOGIN: T4.
12/2 12:36:57.182 c38 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:sql.dominio.kviar', accepted 'iqn.1991-05.com.microsoft:sql.dominio.kviar'
12/2 12:36:57.182 c38 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
12/2 12:36:57.182 c38 Params: <<< String param 'TargetName': received 'sql', accepted 'sql'
12/2 12:36:57.182 c38 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:36:57.182 c38 Tgt: : open 'sql': 1 session(s) opened, 65535 more allowed.
12/2 12:36:57.182 c38 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:36:57.182 c38 T[1]: Anonymous connection allowed.
12/2 12:36:57.182 c38 Params: >>> AuthMethod=None.
12/2 12:36:57.182 c38 Params: >>> TargetPortalGroupTag=1.
12/2 12:36:57.182 c0c T[1]: session 0x6, connection 0x6 : end of stage 0, next stage 1.
12/2 12:36:57.192 c38 Params: <<< Enum param 'HeaderDigest': received 'None,CRC32C', accepted 'None'
12/2 12:36:57.192 c38 Params: <<< Enum param 'DataDigest': received 'None,CRC32C', accepted 'None'
12/2 12:36:57.192 c38 Params: <<< Numeric param 'ErrorRecoveryLevel': received 2, accepted 0
12/2 12:36:57.192 c38 Params: <<< Boolean param 'InitialR2T': received No, accepted 0
12/2 12:36:57.192 c38 Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
12/2 12:36:57.192 c38 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 65536, accepted 65536
12/2 12:36:57.192 c38 Params: <<< Numeric param 'MaxBurstLength': received 262144, accepted 262144
12/2 12:36:57.192 c38 Params: <<< Numeric param 'FirstBurstLength': received 65536, accepted 65536
12/2 12:36:57.192 c38 Params: <<< Numeric param 'MaxConnections': received 8, accepted 1
12/2 12:36:57.192 c38 Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
12/2 12:36:57.192 c38 Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
12/2 12:36:57.192 c38 Params: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 2
12/2 12:36:57.192 c38 Params: <<< Numeric param 'DefaultTime2Retain': received 60, accepted 20
12/2 12:36:57.192 c38 Params: <<< Numeric param 'MaxOutstandingR2T': received 16, accepted 1
12/2 12:36:57.192 c38 Params: >>> ErrorRecoveryLevel=0.
12/2 12:36:57.192 c38 Params: >>> MaxConnections=1.
12/2 12:36:57.192 c38 Params: >>> HeaderDigest=None.
12/2 12:36:57.192 c38 Params: >>> DataDigest=None.
12/2 12:36:57.192 c38 Params: >>> InitialR2T=No.
12/2 12:36:57.192 c38 Params: >>> ImmediateData=Yes.
12/2 12:36:57.192 c38 Params: >>> MaxRecvDataSegmentLength=262144.
12/2 12:36:57.192 c38 Params: >>> MaxBurstLength=262144.
12/2 12:36:57.192 c38 Params: >>> FirstBurstLength=65536.
12/2 12:36:57.202 c38 Params: >>> DefaultTime2Wait=2.
12/2 12:36:57.202 c38 Params: >>> DefaultTime2Retain=20.
12/2 12:36:57.202 c38 Params: >>> MaxOutstandingR2T=1.
12/2 12:36:57.202 c38 Params: >>> DataPDUInOrder=Yes.
12/2 12:36:57.202 c38 Params: >>> DataSequenceInOrder=Yes.
12/2 12:36:57.202 c0c T[1]: session 0x6, connection 0x6 : end of stage 1, next stage 3.
12/2 12:36:57.202 c0c C[6], IN_LOGIN: Event - LOGIN_ACCEPT.
12/2 12:36:57.202 c0c C[6], LIN: T5.
12/2 12:37:16.389 68c Srv: Accepted iSCSI connection from 192.168.0.2:1749. (Id = 0x7)
12/2 12:37:16.389 68c C[7], FREE: Event - CONNECTED.
12/2 12:37:16.389 68c C[7], XPT_UP: T3.
12/2 12:37:16.399 68c Srv: Accepted iSCSI connection from 192.168.0.2:1750. (Id = 0x8)
12/2 12:37:16.399 68c C[8], FREE: Event - CONNECTED.
12/2 12:37:16.399 68c C[8], XPT_UP: T3.
12/2 12:37:16.419 80c C[7], XPT_UP: Login request: ISID 0x400001370005, TSIH 0x0000.
12/2 12:37:16.429 80c C[7], XPT_UP: Event - LOGIN.
12/2 12:37:16.429 80c C[7], IN_LOGIN: T4.
12/2 12:37:16.439 80c Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:sql.dominio.kviar', accepted 'iqn.1991-05.com.microsoft:sql.dominio.kviar'
12/2 12:37:16.439 80c Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
12/2 12:37:16.439 80c Params: <<< String param 'TargetName': received 'sql', accepted 'sql'
12/2 12:37:16.439 80c Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:37:16.439 80c T[1]: An old session <6> from the same Initiator/ISID found. Session reinstatement starting...
12/2 12:37:16.439 80c C[6], LIN: Close socket.
12/2 12:37:16.439 c38 C[6], LIN: recvData() error (10053)
12/2 12:37:16.439 c38 C[6], LIN: *** 'recv' thread: recv failed 10058.
12/2 12:37:16.439 80c S[6]: Connection <6> stat (1)
12/2 12:37:16.459 c0c Tgt: : close 'sql': 0 session(s) opened, 65536 more allowed.
12/2 12:37:16.459 68c Srv: Accepted iSCSI connection from 192.168.0.2:1751. (Id = 0x9)
12/2 12:37:16.459 68c C[9], FREE: Event - CONNECTED.
12/2 12:37:16.459 68c C[9], XPT_UP: T3.
12/2 12:37:16.489 4d8 C[8], XPT_UP: Login request: ISID 0x400001370003, TSIH 0x0000.
12/2 12:37:16.499 4d8 C[8], XPT_UP: Event - LOGIN.
12/2 12:37:16.499 4d8 C[8], IN_LOGIN: T4.
12/2 12:37:16.499 4d8 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:sql.dominio.kviar', accepted 'iqn.1991-05.com.microsoft:sql.dominio.kviar'
12/2 12:37:16.499 4d8 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
12/2 12:37:16.499 4d8 Params: <<< String param 'TargetName': received 'sql', accepted 'sql'
12/2 12:37:16.499 4d8 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:37:16.499 4d8 T[1]: An old session <7> from the same Initiator/ISID found. Session reinstatement starting...
12/2 12:37:16.499 4d8 C[7], IN_LOGIN: Close socket.
12/2 12:37:16.499 4d8 S[7]: Connection <7> stat (1)
12/2 12:37:16.509 ed0 C[9], XPT_UP: Login request: ISID 0x400001370001, TSIH 0x0000.
12/2 12:37:16.519 ed0 C[9], XPT_UP: Event - LOGIN.
12/2 12:37:16.519 ed0 C[9], IN_LOGIN: T4.
12/2 12:37:16.519 ed0 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:sql.dominio.kviar', accepted 'iqn.1991-05.com.microsoft:sql.dominio.kviar'
12/2 12:37:16.519 ed0 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
12/2 12:37:16.519 ed0 Params: <<< String param 'TargetName': received 'sql', accepted 'sql'
12/2 12:37:16.519 ed0 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:37:16.519 ed0 T[1]: An old session <7> from the same Initiator/ISID found. Session reinstatement starting...
12/2 12:37:16.519 ed0 S[7]: Connection <7> stat (0)
12/2 12:37:16.990 80c T[1]: Waiting for the old session <8> to shut down...
12/2 12:37:16.990 80c T[1]: Old session <8> is still present. Force clean up...
12/2 12:37:16.990 80c Tgt: : close 'sql': -1 session(s) opened, 65537 more allowed.
12/2 12:37:16.990 80c T[1]: An old session <9> from the same Initiator/ISID found. Session reinstatement starting...
12/2 12:37:16.990 80c C[9], IN_LOGIN: Close socket.
12/2 12:37:16.990 80c S[9]: Connection <9> stat (1)
12/2 12:37:17.000 4d8 T[1]: Waiting for the old session <7> to shut down...
12/2 12:37:17.000 4d8 T[1]: Old session <7> is still present. Force clean up...
12/2 12:37:17.000 4d8 Tgt: : close 'sql': -2 session(s) opened, 65538 more allowed.
12/2 12:37:17.000 4d8 T[1]: An old session <9> from the same Initiator/ISID found. Session reinstatement starting...
12/2 12:37:17.000 4d8 S[9]: Connection <9> stat (0)
12/2 12:37:17.020 ed0 Tgt: : open 'sql': -1 session(s) opened, 65537 more allowed.
12/2 12:37:17.020 ed0 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:37:17.020 ed0 T[1]: Anonymous connection allowed.
12/2 12:37:17.020 ed0 Params: >>> AuthMethod=None.
12/2 12:37:17.020 ed0 Params: >>> TargetPortalGroupTag=1.
12/2 12:37:17.020 cf8 T[1]: session 0x9, connection 0x9 : end of stage 0, next stage 1.
12/2 12:37:17.020 a4c C[9], IN_LOGIN: WSASend() returned 10038!
12/2 12:37:17.020 ed0 C[9], IN_LOGIN: recvData() error (10038)
12/2 12:37:17.020 ed0 C[9], IN_LOGIN: *** 'recv' thread: recv failed 10058.
12/2 12:37:17.020 cf8 Tsession 0x9, Task 0x1 aborted.
12/2 12:37:17.020 cf8 T[1]: state 0x2, immediate 1, ITT 0xd, CmdSn 13, TTT 0x2
12/2 12:37:17.020 cf8 T[1]: read/write 11, read length 39, read done 0, write length 108, write done 0, DATA-IN PDUs 0
12/2 12:37:17.020 cf8 T[1]: DataSN 0, R2TSN 0, status 0, status class 0, status detail 0, response 0, counter 1, authStage 4
12/2 12:37:17.020 cf8 T[1]: CDB
0000 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
12/2 12:37:17.020 cf8 Tgt: : close 'sql': -2 session(s) opened, 65538 more allowed.
12/2 12:37:17.491 80c Tgt: : open 'sql': -1 session(s) opened, 65537 more allowed.
12/2 12:37:17.491 80c Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:37:17.491 80c T[1]: Anonymous connection allowed.
12/2 12:37:17.491 80c Params: >>> AuthMethod=None.
12/2 12:37:17.491 80c Params: >>> TargetPortalGroupTag=1.
12/2 12:37:17.491 f68 T[1]: session 0x7, connection 0x7 : end of stage 0, next stage 1.
12/2 12:37:17.491 a94 C[7], IN_LOGIN: WSASend() returned 10038!
12/2 12:37:17.491 80c C[7], IN_LOGIN: recvData() error (10038)
12/2 12:37:17.491 80c C[7], IN_LOGIN: *** 'recv' thread: recv failed 10058.
12/2 12:37:17.491 f68 Tsession 0x7, Task 0x1 aborted.
12/2 12:37:17.491 f68 T[1]: state 0x2, immediate 1, ITT 0x25, CmdSn 36, TTT 0x2
12/2 12:37:17.491 f68 T[1]: read/write 11, read length 39, read done 0, write length 108, write done 0, DATA-IN PDUs 0
12/2 12:37:17.491 f68 T[1]: DataSN 0, R2TSN 0, status 0, status class 0, status detail 0, response 0, counter 1, authStage 4
12/2 12:37:17.491 f68 T[1]: CDB
0000 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
12/2 12:37:17.501 4d8 Tgt: : open 'sql': 0 session(s) opened, 65536 more allowed.
12/2 12:37:17.501 4d8 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:37:17.501 4d8 T[1]: Anonymous connection allowed.
12/2 12:37:17.511 4d8 Params: >>> AuthMethod=None.
12/2 12:37:17.511 4d8 Params: >>> TargetPortalGroupTag=1.
12/2 12:37:17.511 cd0 T[1]: session 0x8, connection 0x8 : end of stage 0, next stage 1.
12/2 12:37:17.511 4d8 Params: <<< Enum param 'HeaderDigest': received 'None,CRC32C', accepted 'None'
12/2 12:37:17.511 4d8 Params: <<< Enum param 'DataDigest': received 'None,CRC32C', accepted 'None'
12/2 12:37:17.511 4d8 Params: <<< Numeric param 'ErrorRecoveryLevel': received 2, accepted 0
12/2 12:37:17.511 4d8 Params: <<< Boolean param 'InitialR2T': received No, accepted 0
12/2 12:37:17.511 4d8 Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
12/2 12:37:17.511 4d8 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 65536, accepted 65536
12/2 12:37:17.511 4d8 Params: <<< Numeric param 'MaxBurstLength': received 262144, accepted 262144
12/2 12:37:17.511 4d8 Params: <<< Numeric param 'FirstBurstLength': received 65536, accepted 65536
12/2 12:37:17.511 4d8 Params: <<< Numeric param 'MaxConnections': received 8, accepted 1
12/2 12:37:17.511 4d8 Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
12/2 12:37:17.511 4d8 Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
12/2 12:37:17.511 4d8 Params: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 2
12/2 12:37:17.511 4d8 Params: <<< Numeric param 'DefaultTime2Retain': received 60, accepted 20
12/2 12:37:17.521 4d8 Params: <<< Numeric param 'MaxOutstandingR2T': received 16, accepted 1
12/2 12:37:17.521 4d8 Params: >>> ErrorRecoveryLevel=0.
12/2 12:37:17.521 4d8 Params: >>> MaxConnections=1.
12/2 12:37:17.521 4d8 Params: >>> HeaderDigest=None.
12/2 12:37:17.521 4d8 Params: >>> DataDigest=None.
12/2 12:37:17.521 4d8 Params: >>> InitialR2T=No.
12/2 12:37:17.521 4d8 Params: >>> ImmediateData=Yes.
12/2 12:37:17.521 4d8 Params: >>> MaxRecvDataSegmentLength=262144.
12/2 12:37:17.521 4d8 Params: >>> MaxBurstLength=262144.
12/2 12:37:17.521 4d8 Params: >>> FirstBurstLength=65536.
12/2 12:37:17.521 4d8 Params: >>> DefaultTime2Wait=2.
12/2 12:37:17.521 4d8 Params: >>> DefaultTime2Retain=20.
12/2 12:37:17.521 4d8 Params: >>> MaxOutstandingR2T=1.
12/2 12:37:17.521 4d8 Params: >>> DataPDUInOrder=Yes.
12/2 12:37:17.521 4d8 Params: >>> DataSequenceInOrder=Yes.
12/2 12:37:17.521 cd0 T[1]: session 0x8, connection 0x8 : end of stage 1, next stage 3.
12/2 12:37:17.521 cd0 C[8], IN_LOGIN: Event - LOGIN_ACCEPT.
12/2 12:37:17.521 cd0 C[8], LIN: T5.
12/2 12:37:28.236 68c Srv: Accepted iSCSI connection from 192.168.0.2:1753. (Id = 0xa)
12/2 12:37:28.236 68c C[a], FREE: Event - CONNECTED.
12/2 12:37:28.236 68c C[a], XPT_UP: T3.
12/2 12:37:28.246 860 C[a], XPT_UP: Login request: ISID 0x400001370000, TSIH 0x0000.
12/2 12:37:28.256 860 C[a], XPT_UP: Event - LOGIN.
12/2 12:37:28.256 860 C[a], IN_LOGIN: T4.
12/2 12:37:28.256 860 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:sql.dominio.kviar', accepted 'iqn.1991-05.com.microsoft:sql.dominio.kviar'
12/2 12:37:28.256 860 Params: <<< Enum param 'SessionType': received 'Discovery', accepted 'Discovery'
12/2 12:37:28.256 860 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:37:28.256 860 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:37:28.256 860 T[1]: Anonymous connection allowed.
12/2 12:37:28.256 860 Params: >>> AuthMethod=None.
12/2 12:37:28.256 db8 T[1]: session 0xa, connection 0xa : end of stage 0, next stage 1.
12/2 12:37:28.266 860 Params: <<< Enum param 'HeaderDigest': received 'None,CRC32C', accepted 'None'
12/2 12:37:28.266 860 Params: <<< Enum param 'DataDigest': received 'None,CRC32C', accepted 'None'
12/2 12:37:28.266 860 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 65536, accepted 65536
12/2 12:37:28.266 860 Params: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 2
12/2 12:37:28.266 860 Params: <<< Numeric param 'DefaultTime2Retain': received 60, accepted 20
12/2 12:37:28.266 860 Params: >>> HeaderDigest=None.
12/2 12:37:28.266 860 Params: >>> DataDigest=None.
12/2 12:37:28.266 860 Params: >>> InitialR2T=No.
12/2 12:37:28.266 860 Params: >>> ImmediateData=Yes.
12/2 12:37:28.266 860 Params: >>> MaxRecvDataSegmentLength=65536.
12/2 12:37:28.266 860 Params: >>> MaxBurstLength=262144.
12/2 12:37:28.266 860 Params: >>> FirstBurstLength=65536.
12/2 12:37:28.266 860 Params: >>> DefaultTime2Wait=2.
12/2 12:37:28.266 860 Params: >>> DefaultTime2Retain=20.
12/2 12:37:28.266 860 Params: >>> MaxOutstandingR2T=1.
12/2 12:37:28.266 860 Params: >>> DataPDUInOrder=Yes.
12/2 12:37:28.266 860 Params: >>> DataSequenceInOrder=Yes.
12/2 12:37:28.266 db8 T[1]: session 0xa, connection 0xa : end of stage 1, next stage 3.
12/2 12:37:28.266 db8 C[a], IN_LOGIN: Event - LOGIN_ACCEPT.
12/2 12:37:28.266 db8 C[a], LIN: T5.
12/2 12:37:28.286 db8 C[a], LIN: Event - LOGOUT.
12/2 12:37:28.286 db8 C[a], IN_LOGOUT: T9/10.
12/2 12:37:28.286 14c C[a], IN_LOGOUT: Event - LOGOUT_ACCEPT.
12/2 12:37:28.286 14c C[a], FREE: T13.
12/2 12:37:28.286 860 C[a], FREE: recvData() error (10058)
12/2 12:37:47.434 68c Srv: Accepted iSCSI connection from 192.168.0.2:1754. (Id = 0xb)
12/2 12:37:47.434 68c C, FREE: Event - CONNECTED.
12/2 12:37:47.434 68c C, XPT_UP: T3.
12/2 12:37:47.444 68c Srv: Accepted iSCSI connection from 192.168.0.2:1755. (Id = 0xc)
12/2 12:37:47.444 68c C[c], FREE: Event - CONNECTED.
12/2 12:37:47.454 68c C[c], XPT_UP: T3.
12/2 12:37:47.464 a78 C, XPT_UP: Login request: ISID 0x400001370005, TSIH 0x0000.
12/2 12:37:47.474 a78 C, XPT_UP: Event - LOGIN.
12/2 12:37:47.474 a78 C, IN_LOGIN: T4.
12/2 12:37:47.474 a78 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:sql.dominio.kviar', accepted 'iqn.1991-05.com.microsoft:sql.dominio.kviar'
12/2 12:37:47.474 a78 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
12/2 12:37:47.474 a78 Params: <<< String param 'TargetName': received 'sql', accepted 'sql'
12/2 12:37:47.474 a78 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:37:47.474 a78 Tgt: : open 'sql': 1 session(s) opened, 65535 more allowed.
12/2 12:37:47.474 a78 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:37:47.474 a78 T[1]: Anonymous connection allowed.
12/2 12:37:47.474 a78 Params: >>> AuthMethod=None.
12/2 12:37:47.474 a78 Params: >>> TargetPortalGroupTag=1.
12/2 12:37:47.474 258 T[1]: session 0xb, connection 0xb : end of stage 0, next stage 1.
12/2 12:37:47.484 9b4 C[c], XPT_UP: Login request: ISID 0x400001370001, TSIH 0x0000.
12/2 12:37:47.484 a78 Params: <<< Enum param 'HeaderDigest': received 'None,CRC32C', accepted 'None'
12/2 12:37:47.484 a78 Params: <<< Enum param 'DataDigest': received 'None,CRC32C', accepted 'None'
12/2 12:37:47.484 a78 Params: <<< Numeric param 'ErrorRecoveryLevel': received 2, accepted 0
12/2 12:37:47.484 a78 Params: <<< Boolean param 'InitialR2T': received No, accepted 0
12/2 12:37:47.484 a78 Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
12/2 12:37:47.484 a78 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 65536, accepted 65536
12/2 12:37:47.484 a78 Params: <<< Numeric param 'MaxBurstLength': received 262144, accepted 262144
12/2 12:37:47.484 a78 Params: <<< Numeric param 'FirstBurstLength': received 65536, accepted 65536
12/2 12:37:47.484 a78 Params: <<< Numeric param 'MaxConnections': received 8, accepted 1
12/2 12:37:47.484 a78 Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
12/2 12:37:47.484 a78 Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
12/2 12:37:47.484 a78 Params: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 2
12/2 12:37:47.484 a78 Params: <<< Numeric param 'DefaultTime2Retain': received 60, accepted 20
12/2 12:37:47.484 a78 Params: <<< Numeric param 'MaxOutstandingR2T': received 16, accepted 1
12/2 12:37:47.484 a78 Params: >>> ErrorRecoveryLevel=0.
12/2 12:37:47.484 a78 Params: >>> MaxConnections=1.
12/2 12:37:47.484 a78 Params: >>> HeaderDigest=None.
12/2 12:37:47.484 a78 Params: >>> DataDigest=None.
12/2 12:37:47.484 a78 Params: >>> InitialR2T=No.
12/2 12:37:47.484 a78 Params: >>> ImmediateData=Yes.
12/2 12:37:47.484 a78 Params: >>> MaxRecvDataSegmentLength=262144.
12/2 12:37:47.484 a78 Params: >>> MaxBurstLength=262144.
12/2 12:37:47.484 a78 Params: >>> FirstBurstLength=65536.
12/2 12:37:47.484 a78 Params: >>> DefaultTime2Wait=2.
12/2 12:37:47.484 a78 Params: >>> DefaultTime2Retain=20.
12/2 12:37:47.484 a78 Params: >>> MaxOutstandingR2T=1.
12/2 12:37:47.484 a78 Params: >>> DataPDUInOrder=Yes.
12/2 12:37:47.484 a78 Params: >>> DataSequenceInOrder=Yes.
12/2 12:37:47.484 258 T[1]: session 0xb, connection 0xb : end of stage 1, next stage 3.
12/2 12:37:47.484 258 C, IN_LOGIN: Event - LOGIN_ACCEPT.
12/2 12:37:47.484 258 C, LIN: T5.
12/2 12:37:47.514 9b4 C[c], XPT_UP: Event - LOGIN.
12/2 12:37:47.514 9b4 C[c], IN_LOGIN: T4.
12/2 12:37:47.514 9b4 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:sql.dominio.kviar', accepted 'iqn.1991-05.com.microsoft:sql.dominio.kviar'
12/2 12:37:47.514 9b4 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
12/2 12:37:47.514 9b4 Params: <<< String param 'TargetName': received 'sql', accepted 'sql'
12/2 12:37:47.514 9b4 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:37:47.514 9b4 T[1]: An old session <b> from the same Initiator/ISID found. Session reinstatement starting...
12/2 12:37:47.514 9b4 C, LIN: Close socket.
12/2 12:37:47.514 a78 C, LIN: recvData() error (10053)
12/2 12:37:47.514 a78 C, LIN: *** 'recv' thread: recv failed 10058.
12/2 12:37:47.514 9b4 S[b]: Connection <b> stat (1)
12/2 12:37:47.534 258 Tgt: : close 'sql': 0 session(s) opened, 65536 more allowed.
12/2 12:37:47.544 68c Srv: Accepted iSCSI connection from 192.168.0.2:1756. (Id = 0xd)
12/2 12:37:47.544 68c C[d], FREE: Event - CONNECTED.
12/2 12:37:47.544 68c C[d], XPT_UP: T3.
12/2 12:37:47.584 d08 C[d], XPT_UP: Login request: ISID 0x400001370005, TSIH 0x0000.
12/2 12:37:47.594 d08 C[d], XPT_UP: Event - LOGIN.
12/2 12:37:47.594 d08 C[d], IN_LOGIN: T4.
12/2 12:37:47.594 d08 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:sql.dominio.kviar', accepted 'iqn.1991-05.com.microsoft:sql.dominio.kviar'
12/2 12:37:47.594 d08 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
12/2 12:37:47.594 d08 Params: <<< String param 'TargetName': received 'sql', accepted 'sql'
12/2 12:37:47.594 d08 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:37:47.594 d08 T[1]: An old session <c> from the same Initiator/ISID found. Session reinstatement starting...
12/2 12:37:47.594 d08 C[c], IN_LOGIN: Close socket.
12/2 12:37:47.594 d08 S[c]: Connection <c> stat (1)
12/2 12:37:48.075 9b4 T[1]: Waiting for the old session <d> to shut down...
12/2 12:37:48.075 9b4 T[1]: Old session <d> is still present. Force clean up...
12/2 12:37:48.075 9b4 Tgt: : close 'sql': -1 session(s) opened, 65537 more allowed.
12/2 12:37:48.075 9b4 Tgt: : open 'sql': 0 session(s) opened, 65536 more allowed.
12/2 12:37:48.075 9b4 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:37:48.075 9b4 T[1]: Anonymous connection allowed.
12/2 12:37:48.075 9b4 Params: >>> AuthMethod=None.
12/2 12:37:48.075 9b4 Params: >>> TargetPortalGroupTag=1.
12/2 12:37:48.075 d24 T[1]: session 0xc, connection 0xc : end of stage 0, next stage 1.
12/2 12:37:48.075 d0c C[c], IN_LOGIN: WSASend() returned 10038!
12/2 12:37:48.075 9b4 C[c], IN_LOGIN: recvData() error (10038)
12/2 12:37:48.075 9b4 C[c], IN_LOGIN: *** 'recv' thread: recv failed 10058.
12/2 12:37:48.075 d24 Tsession 0xc, Task 0x1 aborted.
12/2 12:37:48.075 d24 T[1]: state 0x2, immediate 1, ITT 0xe, CmdSn 13, TTT 0x2
12/2 12:37:48.075 d24 T[1]: read/write 11, read length 39, read done 0, write length 108, write done 0, DATA-IN PDUs 0
12/2 12:37:48.075 d24 T[1]: DataSN 0, R2TSN 0, status 0, status class 0, status detail 0, response 0, counter 1, authStage 4
12/2 12:37:48.075 d24 T[1]: CDB
0000 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
12/2 12:37:48.075 d24 Tgt: : close 'sql': -1 session(s) opened, 65537 more allowed.
12/2 12:37:48.175 d08 Tgt: : open 'sql': 0 session(s) opened, 65536 more allowed.
12/2 12:37:48.175 d08 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:37:48.175 d08 T[1]: Anonymous connection allowed.
12/2 12:37:48.175 d08 Params: >>> AuthMethod=None.
12/2 12:37:48.175 d08 Params: >>> TargetPortalGroupTag=1.
12/2 12:37:48.175 f34 T[1]: session 0xd, connection 0xd : end of stage 0, next stage 1.
12/2 12:37:48.185 d08 Params: <<< Enum param 'HeaderDigest': received 'None,CRC32C', accepted 'None'
12/2 12:37:48.185 d08 Params: <<< Enum param 'DataDigest': received 'None,CRC32C', accepted 'None'
12/2 12:37:48.185 d08 Params: <<< Numeric param 'ErrorRecoveryLevel': received 2, accepted 0
12/2 12:37:48.185 d08 Params: <<< Boolean param 'InitialR2T': received No, accepted 0
12/2 12:37:48.185 d08 Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
12/2 12:37:48.185 d08 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 65536, accepted 65536
12/2 12:37:48.185 d08 Params: <<< Numeric param 'MaxBurstLength': received 262144, accepted 262144
12/2 12:37:48.185 d08 Params: <<< Numeric param 'FirstBurstLength': received 65536, accepted 65536
12/2 12:37:48.185 d08 Params: <<< Numeric param 'MaxConnections': received 8, accepted 1
12/2 12:37:48.185 d08 Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
12/2 12:37:48.185 d08 Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
12/2 12:37:48.185 d08 Params: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 2
12/2 12:37:48.185 d08 Params: <<< Numeric param 'DefaultTime2Retain': received 60, accepted 20
12/2 12:37:48.185 d08 Params: <<< Numeric param 'MaxOutstandingR2T': received 16, accepted 1
12/2 12:37:48.185 d08 Params: >>> ErrorRecoveryLevel=0.
12/2 12:37:48.185 d08 Params: >>> MaxConnections=1.
12/2 12:37:48.185 d08 Params: >>> HeaderDigest=None.
12/2 12:37:48.185 d08 Params: >>> DataDigest=None.
12/2 12:37:48.185 d08 Params: >>> InitialR2T=No.
12/2 12:37:48.185 d08 Params: >>> ImmediateData=Yes.
12/2 12:37:48.185 d08 Params: >>> MaxRecvDataSegmentLength=262144.
12/2 12:37:48.185 d08 Params: >>> MaxBurstLength=262144.
12/2 12:37:48.185 d08 Params: >>> FirstBurstLength=65536.
12/2 12:37:48.185 d08 Params: >>> DefaultTime2Wait=2.
12/2 12:37:48.185 d08 Params: >>> DefaultTime2Retain=20.
12/2 12:37:48.185 d08 Params: >>> MaxOutstandingR2T=1.
12/2 12:37:48.185 d08 Params: >>> DataPDUInOrder=Yes.
12/2 12:37:48.185 d08 Params: >>> DataSequenceInOrder=Yes.
12/2 12:37:48.185 f34 T[1]: session 0xd, connection 0xd : end of stage 1, next stage 3.
12/2 12:37:48.185 f34 C[d], IN_LOGIN: Event - LOGIN_ACCEPT.
12/2 12:37:48.185 f34 C[d], LIN: T5.
12/2 12:38:18.489 68c Srv: Accepted iSCSI connection from 192.168.0.2:1757. (Id = 0xe)
12/2 12:38:18.489 68c C[e], FREE: Event - CONNECTED.
12/2 12:38:18.489 68c C[e], XPT_UP: T3.
12/2 12:38:18.499 a40 C[e], XPT_UP: Login request: ISID 0x400001370001, TSIH 0x0000.
12/2 12:38:18.539 a40 C[e], XPT_UP: Event - LOGIN.
12/2 12:38:18.549 a40 C[e], IN_LOGIN: T4.
12/2 12:38:18.549 a40 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:sql.dominio.kviar', accepted 'iqn.1991-05.com.microsoft:sql.dominio.kviar'
12/2 12:38:18.549 a40 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
12/2 12:38:18.549 a40 Params: <<< String param 'TargetName': received 'sql', accepted 'sql'
12/2 12:38:18.559 a40 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:38:18.559 a40 Tgt: : open 'sql': 1 session(s) opened, 65535 more allowed.
12/2 12:38:18.559 a40 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
12/2 12:38:18.569 a40 T[1]: Anonymous connection allowed.
12/2 12:38:18.569 a40 Params: >>> AuthMethod=None.
12/2 12:38:18.569 a40 Params: >>> TargetPortalGroupTag=1.
12/2 12:38:18.579 aa0 T[1]: session 0xe, connection 0xe : end of stage 0, next stage 1.
12/2 12:38:18.589 a40 Params: <<< Enum param 'HeaderDigest': received 'None,CRC32C', accepted 'None'
12/2 12:38:18.589 a40 Params: <<< Enum param 'DataDigest': received 'None,CRC32C', accepted 'None'
12/2 12:38:18.589 a40 Params: <<< Numeric param 'ErrorRecoveryLevel': received 2, accepted 0
12/2 12:38:18.589 a40 Params: <<< Boolean param 'InitialR2T': received No, accepted 0
12/2 12:38:18.589 a40 Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
12/2 12:38:18.589 a40 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 65536, accepted 65536
12/2 12:38:18.589 a40 Params: <<< Numeric param 'MaxBurstLength': received 262144, accepted 262144
12/2 12:38:18.589 a40 Params: <<< Numeric param 'FirstBurstLength': received 65536, accepted 65536
12/2 12:38:18.589 a40 Params: <<< Numeric param 'MaxConnections': received 8, accepted 1
12/2 12:38:18.589 a40 Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
12/2 12:38:18.589 a40 Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
12/2 12:38:18.589 a40 Params: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 2
12/2 12:38:18.589 a40 Params: <<< Numeric param 'DefaultTime2Retain': received 60, accepted 20
12/2 12:38:18.589 a40 Params: <<< Numeric param 'MaxOutstandingR2T': received 16, accepted 1
12/2 12:38:18.589 a40 Params: >>> ErrorRecoveryLevel=0.
12/2 12:38:18.589 a40 Params: >>> MaxConnections=1.
12/2 12:38:18.589 a40 Params: >>> HeaderDigest=None.
12/2 12:38:18.599 a40 Params: >>> DataDigest=None.
12/2 12:38:18.599 a40 Params: >>> InitialR2T=No.
12/2 12:38:18.599 a40 Params: >>> ImmediateData=Yes.
12/2 12:38:18.599 a40 Params: >>> MaxRecvDataSegmentLength=262144.
12/2 12:38:18.599 a40 Params: >>> MaxBurstLength=262144.
12/2 12:38:18.599 a40 Params: >>> FirstBurstLength=65536.
12/2 12:38:18.599 a40 Params: >>> DefaultTime2Wait=2.
12/2 12:38:18.599 a40 Params: >>> DefaultTime2Retain=20.
12/2 12:38:18.599 a40 Params: >>> MaxOutstandingR2T=1.
12/2 12:38:18.599 a40 Params: >>> DataPDUInOrder=Yes.
12/2 12:38:18.599 a40 Params: >>> DataSequenceInOrder=Yes.
12/2 12:38:18.599 aa0 T[1]: session 0xe, connection 0xe : end of stage 1, next stage 3.
12/2 12:38:18.599 aa0 C[e], IN_LOGIN: Event - LOGIN_ACCEPT.
12/2 12:38:18.599 aa0 C[e], LIN: T5.

Sun Dec 02, 2007 3:53 pm

Machines are a bit too slow but they should not crash in any case. Please for now upgrade to the 3.5.1 and I'll make technicians take a closer look to what you have tomorrow morning. I don't see anything "alien" in the log so far

galonga · Sun Dec 02, 2007 3:58 pm

Yes, those machines are indeed old and below standard, I know.

It's just that if we are to sell this we have to be able to prove that it does NOT require expensive setups as it would happen with regular fiber.

Anywaysm, we uninstalled the TCP/IP stack in the webserver and it now seems to work ok.

One thing is that the NIC's do not allow for 9K jumbo frames: it only goes up to 7: I wonder if that has any impact in performance?

Val (staff) · Sun Dec 02, 2007 4:12 pm

Hi,

1) As Anton suggested, upgrade to the recent StarWind v3.5.1

2) Did you edited the StarWind.cfg file manually? There are several duplicated target names and device names in the file that leads to several devices failed to start.

3) Don't use StarPort on the client machines if it crashes. It's possible to be a third-party driver there that does not compatible with our driver...

Sun Dec 02, 2007 5:59 pm

Any hardware should work but you need to have 1) fast memory 2) not PCI-based (slow) NIC attachment. K2 machines will suffer from both...

9K frames are recommended. We've actually never tried 7K ones

galonga wrote:Yes, those machines are indeed old and below standard, I know.

It's just that if we are to sell this we have to be able to prove that it does NOT require expensive setups as it would happen with regular fiber.

Anywaysm, we uninstalled the TCP/IP stack in the webserver and it now seems to work ok.

One thing is that the NIC's do not allow for 9K jumbo frames: it only goes up to 7: I wonder if that has any impact in performance?

galonga · Tue Dec 04, 2007 1:03 am

we installed the new version of starport as told

we also had to reinstall the whole 2003 windows for the webserver, as the cpu was on 100% for no reason and we couldn't reinstall neither starport nor microsoft's

so far is ok, although it crashes sometimes. we are trying to install SP2 but we can't seem to make it go to the end

I wonder if those lan boards (trendnet) are part of the problem: we did not have all those problems before

Tue Dec 04, 2007 7:39 am

1) What really crashes? StarWind? OS? Third party component?

2) What software do you have installed? Can you provide exact list?

galonga wrote:we installed the new version of starport as told

we also had to reinstall the whole 2003 windows for the webserver, as the cpu was on 100% for no reason and we couldn't reinstall neither starport nor microsoft's

so far is ok, although it crashes sometimes. we are trying to install SP2 but we can't seem to make it go to the end

I wonder if those lan boards (trendnet) are part of the problem: we did not have all those problems before

galonga · Wed Dec 05, 2007 11:44 am

what crashed was the whole windows

we managed to install SP2 however and now is fine

however, we are now having problems with the image corrupting all the time.

isn't there a way for the system to take a snapshot of the image automatically from time to time?

Wed Dec 05, 2007 2:21 pm

1) StarWind does not anything marking Windows crash.

2) It's also absolutely abnormal. What container do you use? Image file? Incremental volume backup?

galonga wrote:what crashed was the whole windows

we managed to install SP2 however and now is fine

however, we are now having problems with the image corrupting all the time.

isn't there a way for the system to take a snapshot of the image automatically from time to time?

galonga · Wed Dec 05, 2007 8:46 pm

we are using a growing .ibv image

actually, we used an .img image before and did not seem to have problems.

this .ibv image for example keeps corrupting .jpg and .gif files all the time just by itself (even .asp pages!), so we have to keep reformating it almost everyday

actually, it'd be great if we could just serve a volume mounted by veritas (which is controlling the SCSI system) instead of this image business, but the system will not allow us that: only shows independent hard-disks of the RAID-5 system instead of the RAID volume

Wed Dec 05, 2007 10:35 pm

1) So IMG works file where IBV does not, right?

2) You may use DiskBridge and map entire hard disk instead of the image file on it.

galonga wrote:we are using a growing .ibv image

actually, we used an .img image before and did not seem to have problems.

this .ibv image for example keeps corrupting .jpg and .gif files all the time just by itself (even .asp pages!), so we have to keep reformating it almost everyday

actually, it'd be great if we could just serve a volume mounted by veritas (which is controlling the SCSI system) instead of this image business, but the system will not allow us that: only shows independent hard-disks of the RAID-5 system instead of the RAID volume

galonga · Wed Dec 05, 2007 11:53 pm

1 - yes: IMG seems to work much better
2 - problem is that diskbridge does not map a VOLUME, but only hard disks separately, and that's useless since they are grouped in a RAID 5 volume

Val (staff) · Thu Dec 06, 2007 5:09 pm

galonga wrote:1 - yes: IMG seems to work much better
2 - problem is that diskbridge does not map a VOLUME, but only hard disks separately, and that's useless since they are grouped in a RAID 5 volume :)

It's possible to export a logical volume as an iSCSI target with the ImageFile plugin.
This options is an obsolete and disabled by default.

To export a drive you have to edit StarWind.cfg manually and restart the StarWindService.

Change the following line after the tag <plugin module="ImageFile.dll">:
<volumes value="yes"/>

And add a new ImageFile device definition like this:
<device name="ImageFile0" target="myvolume" file="\\?\Volume{0e224804-3ec8-11dc-b7c5-806d6172696f}" asyncmode="yes" clustered="no"/>

Existing volume names can be shown with MOUNTVOL.exe (don't forget to remove the trailing '\' from the names).

Please let us know whether it helps.