Sun May 04, 2008 3:33 pm
I edited the log showing ms above and linux below, but looks like 10058 error. After this error, I have to cycle the Starwind service on the windows target device to reconnect via the windows initiator - most likely due to the connection still active.
5/4 12:00:05.500 11f0 Srv: StarWind iSCSI Target v3.5.3 (Build 20080229, Win32)
5/4 12:00:05.500 11f0 Srv: Built Feb 29 2008 12:20:05
5/4 12:00:05.500 11f0 Srv: System CPU count: 2
5/4 12:00:05.500 11f0 Srv: Licensed to: 'Unknown', 'Unknown', (#1978, 1, 0).
5/4 12:00:11.500 11f0 Srv: Max number of client hosts: 1.
5/4 12:00:11.546 11f0 Srv: Config file: 'StarWind.cfg'
5/4 12:00:11.812 11f0 conf: 'LogLevel' = '1'
5/4 12:00:11.812 11f0 conf: 'LogMask' = '0x8fffffff'
5/4 12:00:11.812 11f0 conf: 'Port' = '3260'
5/4 12:00:11.812 11f0 conf: 'Interface' = '0.0.0.0'
5/4 12:00:11.812 11f0 conf: 'Login' = 'test'
5/4 12:00:11.812 11f0 conf: 'Password' = '##CY9rzUYh03PK3k6DJie09g=='
5/4 12:00:11.812 11f0 conf: 'MinBufferSize' = '65536'
5/4 12:00:11.812 11f0 conf: 'AlignmentMask' = '0x0000'
5/4 12:00:11.812 11f0 conf: 'MaxPendingRequests' = '64'
5/4 12:00:11.812 11f0 conf: 'iScsiPingPeriod' = '0'
5/4 12:00:11.812 11f0 conf: read command: addplugin -module:"RamDisk.dll" -symlink:"RamDrive" -type:"RAM disk"
5/4 12:00:11.812 11f0 conf: read command: addplugin -module:"ImageFile.dll" -symlink:"ImageFile" -type:"Image file" -imagedir:"*|cmdfv|My Computer|img" -volumes:"no"
5/4 12:00:11.812 11f0 conf: read command: addplugin -module:"VirtualDvd.dll" -symlink:"VirtualDvd" -type:"Virtual DVD" -imagedir:"*|mv|My Computer|mds,iso"
5/4 12:00:11.812 11f0 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"
5/4 12:00:11.812 11f0 conf: read command: addplugin -module:"Mirror.dll" -symlink:"Mirror" -type:"Mirror" -imagedir:"*|cdmfv|Mirror Volumes|mir" -imagedir:"*|v|Image Files|img,dat"
5/4 12:00:11.812 11f0 conf: read command: addplugin -module:"DiskBridge.dll" -symlink:"DiskBridge" -type:"DiskBridge"
5/4 12:00:11.812 11f0 conf: read command: add "ImageFile0" "LAPTOP" -file:"My Computer\F\Main.img" -asyncmode:"yes" -clustered:"yes" -readonly:"no"
5/4 12:00:11.812 11f0 conf: Variable 'LogLevel' is set to '1'.
5/4 12:00:11.812 11f0 conf: Variable 'LogMask' is set to '0x8fffffff'.
5/4 12:00:11.812 11f0 conf: Variable 'Port' is set to '3260'.
5/4 12:00:11.812 11f0 conf: Variable 'Interface' is set to '0.0.0.0'.
5/4 12:00:11.812 11f0 conf: Variable 'Login' is set to 'test'.
5/4 12:00:11.812 11f0 conf: Variable 'Password' is set to '##CY9rzUYh03PK3k6DJie09g=='.
5/4 12:00:11.812 11f0 conf: Variable 'MinBufferSize' is set to '65536'.
5/4 12:00:11.812 11f0 conf: Variable 'AlignmentMask' is set to '0x0000'.
5/4 12:00:11.812 11f0 conf: Variable 'MaxPendingRequests' is set to '64'.
5/4 12:00:11.812 11f0 conf: Variable 'iScsiPingPeriod' is set to '0'.
5/4 12:00:11.812 11f0 conf: 'RamDisk.dll' loaded successfully.
5/4 12:00:11.890 11f0 IMG: SscPort_Init: Parse ImageDir: '*|cmdfv|My Computer|img'
5/4 12:00:11.890 11f0 IMG: *SscPort_Init:
Dirname: '*'
Rights: 0x1f
Alias: 'My Computer'
Extensions: 'img'
5/4 12:00:11.890 11f0 IMG: SscPort_Init: Comment : ''
5/4 12:00:11.890 11f0 IMG: SscPort_Init: (build 20080229, built Feb 29 2008 12:20:10)
VendorId = ' '
ProductId = ' '
Revision = '0001'
DefImageDir = '*\'
Volumes = 0
ExeDir = 'C:\Program Files\Rocket Division Software\StarWind\'
5/4 12:00:11.890 11f0 conf: 'ImageFile.dll' loaded successfully.
5/4 12:00:11.906 11f0 VDVD: *SscPort_Init:
Dirname: '*'
Rights: 0x18
Alias: 'My Computer'
Extensions: 'mds,iso'
5/4 12:00:11.906 11f0 conf: 'VirtualDvd.dll' loaded successfully.
5/4 12:00:11.906 11f0 IBV: *SscPort_Init:
Dirname: '*'
Rights: 0x1f
Alias: 'My Computer'
Extensions: 'ibv'
5/4 12:00:11.906 11f0 IBV: *SscPort_Init:
Dirname: '*'
Rights: 0x1e
Alias: 'Snapshots'
Extensions: 'ibvss'
5/4 12:00:11.906 11f0 IBV: *SscPort_Init:
Dirname: '*'
Rights: 0x8
Alias: 'Image Files'
Extensions: 'img,dat'
5/4 12:00:11.906 11f0 IBV: SscPort_Init: (build 20080229, built Feb 29 2008 12:20:19)
VendorId = ' '
ProductId = ' '
Revision = '0001'
ImageDir = '*\'
ExeDir = 'C:\Program Files\Rocket Division Software\StarWind\'
5/4 12:00:11.906 11f0 conf: 'IBVolume.dll' loaded successfully.
5/4 12:00:11.906 11f0 MIR: *SscPort_Init:
Dirname: '*'
Rights: 0x1f
Alias: 'Mirror Volumes'
Extensions: 'mir'
5/4 12:00:11.906 11f0 MIR: *SscPort_Init:
Dirname: '*'
Rights: 0x8
Alias: 'Image Files'
Extensions: 'img,dat'
5/4 12:00:11.906 11f0 conf: 'Mirror.dll' loaded successfully.
5/4 12:00:11.937 11f0 DSKBR: SscPort_Init: (built Feb 29 2008 12:20:29)
VendorId = ' '
ProductId = ' '
Revision = '0001'
ExeDir = 'C:\Program Files\Rocket Division Software\StarWind\'
5/4 12:00:11.937 11f0 conf: 'DiskBridge.dll' loaded successfully.
5/4 12:00:11.937 11f0 IMG: SscPort_Create: Opening device 'ImageFile0', image file 'My Computer\F\Main.img', buffering=0, async=1, readonly=0.
5/4 12:00:11.937 11f0 IMG: ImageFile_Create: 'F:\Main.img': type 0, geometry: sct 16, trks 32, cyl 61424, TotalSectors: 31449088
5/4 12:00:11.937 11f0 IMG: ImageFile_Create: VendorId 'ROCKET ', ProductId 'IMAGEFILE ', Revision '0001', Serial 'E53F5274CC05FF48'
5/4 12:00:11.937 11f0 S: Assigned target name: 'laptop', SymId: 'ImageFile0'.
5/4 12:00:11.937 11f0 Tgt: : parameter 'header', value '0'.
5/4 12:00:11.937 11f0 Tgt: : parameter 'file', value 'My Computer\F\Main.img'.
5/4 12:00:11.937 11f0 Tgt: : parameter 'buffering', value 'no'.
5/4 12:00:11.937 11f0 Tgt: : parameter 'asyncmode', value 'yes'.
5/4 12:00:11.937 11f0 Tgt: : parameter 'ImageSizeLow', value '3217031168'.
5/4 12:00:11.937 11f0 Tgt: : parameter 'ImageSizeHigh', value '3'.
5/4 12:00:11.937 11f0 Tgt: : parameter 'readonly', value 'no'.
5/4 12:00:11.937 11f0 S: laptop: 65536 session(s) allowed.
5/4 12:00:11.937 11f0 conf: Target [0x000C5700] has been created. Device 'ImageFile0' is accesible as target 'laptop'.
5/4 12:00:11.937 11f0 Srv: Refreshing device list (plugins)...
5/4 12:00:12.015 1278 conf: Waiting for Control connections at 0.0.0.0:3261 ...
5/4 12:00:12.015 11f0 Srv: Waiting for iSCSI connections at 0.0.0.0:3260 ...
5/4 12:00:29.781 11f0 Srv: Accepted iSCSI connection from 10.0.1.100:60828. (Id = 0x1)
5/4 12:00:29.781 11f0 C[1], FREE: Event - CONNECTED.
5/4 12:00:29.781 11f0 C[1], XPT_UP: T3.
5/4 12:00:29.781 16d0 C[1], XPT_UP: Login request: ISID 0x400001370000, TSIH 0x0000.
5/4 12:00:29.781 16d0 C[1], XPT_UP: Event - LOGIN.
5/4 12:00:29.781 16d0 C[1], IN_LOGIN: T4.
5/4 12:00:29.781 16d0 Params: <<< String param 'InitiatorName': received 'iqn.1991-05.com.microsoft:scantlen', accepted 'iqn.1991-05.com.microsoft:scantlen'
5/4 12:00:29.781 16d0 Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
5/4 12:00:29.781 16d0 Params: <<< String param 'TargetName': received 'laptop', accepted 'laptop'
5/4 12:00:29.781 16d0 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
5/4 12:00:29.781 16d0 Tgt: : open 'laptop': 1 session(s) opened, 0 more allowed.
5/4 12:00:29.781 16d0 Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
5/4 12:00:29.781 16d0 T[1]: Anonymous connection allowed.
5/4 12:00:29.781 16d0 Params: >>> AuthMethod=None.
5/4 12:00:29.781 16d0 Params: >>> TargetPortalGroupTag=1.
5/4 12:00:29.781 1560 T[1]: session 0x1, connection 0x1 : end of stage 0, next stage 1.
5/4 12:00:29.781 16d0 Params: <<< Enum param 'HeaderDigest': received 'None,CRC32C', accepted 'None'
5/4 12:00:29.781 16d0 Params: <<< Enum param 'DataDigest': received 'None,CRC32C', accepted 'None'
5/4 12:00:29.781 16d0 Params: <<< Numeric param 'ErrorRecoveryLevel': received 2, accepted 0
5/4 12:00:29.781 16d0 Params: <<< Boolean param 'InitialR2T': received No, accepted 0
5/4 12:00:29.781 16d0 Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
5/4 12:00:29.781 16d0 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 65536, accepted 65536
5/4 12:00:29.781 16d0 Params: <<< Numeric param 'MaxBurstLength': received 262144, accepted 262144
5/4 12:00:29.781 16d0 Params: <<< Numeric param 'FirstBurstLength': received 65536, accepted 65536
5/4 12:00:29.781 16d0 Params: <<< Numeric param 'MaxConnections': received 4, accepted 1
5/4 12:00:29.781 16d0 Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
5/4 12:00:29.781 16d0 Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
5/4 12:00:29.781 16d0 Params: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 0
5/4 12:00:29.781 16d0 Params: <<< Numeric param 'DefaultTime2Retain': received 60, accepted 20
5/4 12:00:29.781 16d0 Params: <<< Numeric param 'MaxOutstandingR2T': received 16, accepted 1
5/4 12:00:29.781 16d0 Params: >>> ErrorRecoveryLevel=0.
5/4 12:00:29.781 16d0 Params: >>> MaxConnections=1.
5/4 12:00:29.781 16d0 Params: >>> HeaderDigest=None.
5/4 12:00:29.781 16d0 Params: >>> DataDigest=None.
5/4 12:00:29.781 16d0 Params: >>> InitialR2T=No.
5/4 12:00:29.781 16d0 Params: >>> ImmediateData=Yes.
5/4 12:00:29.781 16d0 Params: >>> MaxRecvDataSegmentLength=262144.
5/4 12:00:29.781 16d0 Params: >>> MaxBurstLength=262144.
5/4 12:00:29.781 16d0 Params: >>> FirstBurstLength=65536.
5/4 12:00:29.781 16d0 Params: >>> DefaultTime2Wait=0.
5/4 12:00:29.781 16d0 Params: >>> DefaultTime2Retain=20.
5/4 12:00:29.781 16d0 Params: >>> MaxOutstandingR2T=1.
5/4 12:00:29.781 16d0 Params: >>> DataPDUInOrder=Yes.
5/4 12:00:29.781 16d0 Params: >>> DataSequenceInOrder=Yes.
5/4 12:00:29.781 1560 T[1]: session 0x1, connection 0x1 : end of stage 1, next stage 3.
5/4 12:00:29.781 1560 C[1], IN_LOGIN: Event - LOGIN_ACCEPT.
5/4 12:00:29.781 1560 C[1], LIN: T5.
5/4 12:00:46.859 1560 C[1], LIN: Event - LOGOUT.
5/4 12:00:46.859 1560 C[1], IN_LOGOUT: T9/10.
5/4 12:00:46.859 15b8 C[1], IN_LOGOUT: Event - LOGOUT_ACCEPT.
5/4 12:00:46.859 15b8 C[1], FREE: T13.
5/4 12:00:46.859 16d0 C[1], FREE: recvData() error (10058)
5/4 12:00:46.859 1560 Tgt: : close 'laptop': 0 session(s) opened, 1 more allowed.
Above is with Microsoft iSCSI Initiator
Below is with Linux iSCSI Initiator via XenServer trying to Discover IQN's
5/4 12:01:45.046 11f0 Srv: Accepted iSCSI connection from 10.0.1.68:59887. (Id = 0x2)
5/4 12:01:45.046 11f0 C[2], FREE: Event - CONNECTED.
5/4 12:01:45.046 11f0 C[2], XPT_UP: T3.
5/4 12:01:45.046 135c C[2], XPT_UP: recvData() error (10058)
5/4 12:01:45.046 135c C[2], XPT_UP: *** 'recv' thread: recv failed 10058.
5/4 12:01:45.078 11f0 Srv: Accepted iSCSI connection from 10.0.1.68:59888. (Id = 0x3)
5/4 12:01:45.078 11f0 C[3], FREE: Event - CONNECTED.
5/4 12:01:45.078 11f0 C[3], XPT_UP: T3.
5/4 12:01:45.078 13b0 C[3], XPT_UP: Login request: ISID 0x00023d000000, TSIH 0x0000.
5/4 12:01:45.078 13b0 C[3], XPT_UP: Event - LOGIN.
5/4 12:01:45.078 13b0 C[3], IN_LOGIN: T4.
5/4 12:01:45.078 13b0 Params: <<< String param 'InitiatorName': received 'iqn.2008-05.com.example:1b20a88b', accepted 'iqn.2008-05.com.example:1b20a88b'
5/4 12:01:45.078 13b0 Params: <<< String param 'InitiatorAlias': received 'xenserver1', accepted 'xenserver1'
5/4 12:01:45.078 13b0 Params: <<< Enum param 'SessionType': received 'Discovery', accepted 'Discovery'
5/4 12:01:45.078 13b0 Params: <<< Enum param 'HeaderDigest': received 'None', accepted 'None'
5/4 12:01:45.078 13b0 Params: <<< Enum param 'DataDigest': received 'None', accepted 'None'
5/4 12:01:45.078 13b0 Params: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 0
5/4 12:01:45.078 13b0 Params: <<< Numeric param 'DefaultTime2Retain': received 0, accepted 0
5/4 12:01:45.078 13b0 Params: <<< Boolean param 'IFMarker': received No, accepted 0
5/4 12:01:45.078 13b0 Params: <<< Boolean param 'OFMarker': received No, accepted 0
5/4 12:01:45.078 13b0 Params: <<< Numeric param 'ErrorRecoveryLevel': received 0, accepted 0
5/4 12:01:45.078 13b0 Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 32768, accepted 32768
5/4 12:01:45.078 13b0 T[1]: Anonymous connection allowed.
5/4 12:01:45.078 13b0 Params: >>> ErrorRecoveryLevel=0.
5/4 12:01:45.078 13b0 Params: >>> HeaderDigest=None.
5/4 12:01:45.078 13b0 Params: >>> DataDigest=None.
5/4 12:01:45.078 13b0 Params: >>> OFMarker=No.
5/4 12:01:45.078 13b0 Params: >>> IFMarker=No.
5/4 12:01:45.078 13b0 Params: >>> InitialR2T=No.
5/4 12:01:45.078 13b0 Params: >>> ImmediateData=Yes.
5/4 12:01:45.078 13b0 Params: >>> MaxRecvDataSegmentLength=65536.
5/4 12:01:45.078 13b0 Params: >>> MaxBurstLength=262144.
5/4 12:01:45.078 13b0 Params: >>> FirstBurstLength=65536.
5/4 12:01:45.078 13b0 Params: >>> DefaultTime2Wait=0.
5/4 12:01:45.078 13b0 Params: >>> DefaultTime2Retain=0.
5/4 12:01:45.078 13b0 Params: >>> MaxOutstandingR2T=1.
5/4 12:01:45.078 13b0 Params: >>> DataPDUInOrder=Yes.
5/4 12:01:45.078 13b0 Params: >>> DataSequenceInOrder=Yes.
5/4 12:01:45.078 13c0 T[1]: session 0x3, connection 0x3 : end of stage 1, next stage 3.
5/4 12:01:45.078 13c0 C[3], IN_LOGIN: Event - LOGIN_ACCEPT.
5/4 12:01:45.078 13c0 C[3], LIN: T5.
5/4 12:01:45.078 13b0 C[3], LIN: recvData() error (10058)
5/4 12:01:45.078 13b0 C[3], LIN: *** 'recv' thread: recv failed 10058.
5/4 12:01:50.046 bf8 C[2], XPT_UP: *ERROR* 'recv' thread (135c): failed to close the socket gracefully.