StarWind Crash

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

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

Post Reply
muhfugen
Posts: 10
Joined: Wed Nov 23, 2016 11:10 pm

Wed Nov 29, 2017 1:22 am

I'm trying to connect to a target on a 2 node VSAN 8.0.0 build 9996 cluster from a Linux VM. After mounting the partition on the iSCSI target from the Linux VM, the StarWindService immediately crashes. If you can give me a place to upload a 500+ MB file I can provide the minidump. Any idea why this is happening?

StarWind Log
11/28 19:14:31.634 74 Srv: Accepted iSCSI connection from 10.1.2.129:39006 to 10.1.1.30:3260. (Id = 0x11d)
11/28 19:14:31.634 74 C[11d], FREE: TCP recvBuf: 131072, sendBuf 131072.
11/28 19:14:31.634 74 S[11d]: Session (00000000034F3350)
11/28 19:14:31.634 74 C[11d], FREE: Event - CONNECTED.
11/28 19:14:31.634 74 C[11d], XPT_UP: T3.
11/28 19:14:31.650 9c C[11d], XPT_UP: Login request: ISID 0x00023d000003, TSIH 0x0000.
11/28 19:14:31.650 9c C[11d], XPT_UP: Event - LOGIN.
11/28 19:14:31.650 9c C[11d], IN_LOGIN: T4.
11/28 19:14:31.650 9c Params: <<< String param 'InitiatorName': received 'iqn.2005-03.org.open-iscsi:55a29b093a7', accepted 'iqn.2005-03.org.open-iscsi:55a29b093a7'
11/28 19:14:31.650 9c Params: <<< String param 'InitiatorAlias': received 'photon', accepted 'photon'
11/28 19:14:31.650 9c Params: <<< String param 'TargetName': received 'iqn.2008-08.com.starwindsoftware:vsan1.ad.my.domain-minecraft', accepted 'iqn.2008-08.com.starwindsoftware:vsan1.ad.my.domain-minecraft'
11/28 19:14:31.650 9c Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
11/28 19:14:31.650 9c Params: <<< Enum param 'HeaderDigest': received 'None', accepted 'None'
11/28 19:14:31.650 9c Params: <<< Enum param 'DataDigest': received 'None', accepted 'None'
11/28 19:14:31.650 9c Params: <<< Numeric param 'DefaultTime2Wait': received 2, accepted 2
11/28 19:14:31.650 9c Params: <<< Numeric param 'DefaultTime2Retain': received 0, accepted 0
11/28 19:14:31.650 9c Params: <<< Boolean param 'IFMarker': received No, accepted 0
11/28 19:14:31.650 9c Params: <<< Boolean param 'OFMarker': received No, accepted 0
11/28 19:14:31.650 9c Params: <<< Numeric param 'ErrorRecoveryLevel': received 0, accepted 0
11/28 19:14:31.650 9c Params: <<< Boolean param 'InitialR2T': received No, accepted 0
11/28 19:14:31.650 9c Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
11/28 19:14:31.650 9c Params: <<< Numeric param 'MaxBurstLength': received 16776192, accepted 262144
11/28 19:14:31.650 9c Params: <<< Numeric param 'FirstBurstLength': received 262144, accepted 262144
11/28 19:14:31.650 9c Params: <<< Numeric param 'MaxOutstandingR2T': received 1, accepted 1
11/28 19:14:31.650 9c Params: <<< Numeric param 'MaxConnections': received 1, accepted 1
11/28 19:14:31.650 9c Params: <<< Boolean param 'DataPDUInOrder': received Yes, accepted 1
11/28 19:14:31.650 9c Params: <<< Boolean param 'DataSequenceInOrder': received Yes, accepted 1
11/28 19:14:31.650 9c Params: <<< Numeric param 'MaxRecvDataSegmentLength': received 262144, accepted 262144
11/28 19:14:31.650 9c HA: CHADevice::RegisterSession: Client initiator iqn.2005-03.org.open-iscsi:55a29b093a7 is trying to register a session within the 'iqn.2008-08.com.starwindsoftware:vsan1.ad.my.domain-minecraft' target... (sessId = 0x11d, initiatorNameIsid = iqn.2005-03.org.open-iscsi:55a29b093a7,00023D000003)
11/28 19:14:31.650 9c HA: CHADevice::RegisterSession: Client session (IP: "10.1.2.129"; Initiator: "iqn.2005-03.org.open-iscsi:55a29b093a7") has been allowed!
11/28 19:14:31.650 9c HA: CHADevice::RegisterSession: Registering the external client session.
11/28 19:14:31.650 9c PR: LUN 0: registering session 0x11d from iqn.2005-03.org.open-iscsi:55a29b093a7,00023D000003 iqn.2008-08.com.starwindsoftware:vsan1.ad.my.domain-minecraft
11/28 19:14:31.650 9c PR: Set UA 0x2901 (0x0) for session 0x11d from iqn.2005-03.org.open-iscsi:55a29b093a7,00023D000003.
11/28 19:14:31.650 9c HA: CHADevice::RegisterSession: Return code 0.
11/28 19:14:31.650 9c PR: LUN 0: registering session 0x11d from iqn.2005-03.org.open-iscsi:55a29b093a7,00023D000003
11/28 19:14:31.650 9c Tgt: open 'iqn.2008-08.com.starwindsoftware:vsan1.ad.my.domain-minecraft': 3 session(s) opened, 65533 more allowed.
11/28 19:14:31.650 9c Params: >>> ErrorRecoveryLevel=0.
11/28 19:14:31.650 9c Params: >>> MaxConnections=1.
11/28 19:14:31.650 9c Params: >>> HeaderDigest=None.
11/28 19:14:31.650 9c Params: >>> DataDigest=None.
11/28 19:14:31.650 9c Params: >>> TargetAlias=Minecraft.
11/28 19:14:31.650 9c Params: >>> OFMarker=No.
11/28 19:14:31.650 9c Params: >>> IFMarker=No.
11/28 19:14:31.650 9c Params: >>> InitialR2T=No.
11/28 19:14:31.650 9c Params: >>> ImmediateData=Yes.
11/28 19:14:31.650 9c Params: >>> MaxRecvDataSegmentLength=262144.
11/28 19:14:31.650 9c Params: >>> MaxBurstLength=262144.
11/28 19:14:31.650 9c Params: >>> FirstBurstLength=262144.
11/28 19:14:31.650 9c Params: >>> DefaultTime2Wait=2.
11/28 19:14:31.650 9c Params: >>> DefaultTime2Retain=0.
11/28 19:14:31.650 9c Params: >>> MaxOutstandingR2T=1.
11/28 19:14:31.650 9c Params: >>> DataPDUInOrder=Yes.
11/28 19:14:31.650 9c Params: >>> DataSequenceInOrder=Yes.
11/28 19:14:31.650 9c Params: >>> TargetPortalGroupTag=1.
11/28 19:14:31.650 634 T[11d,1]: session 0x11d, connection 0x11d : end of stage 1, next stage 3.
11/28 19:14:31.650 634 C[11d], IN_LOGIN: Event - LOGIN_ACCEPT.
11/28 19:14:31.650 634 C[11d], LIN: T5.
11/28 19:14:31.650 634 PR: UA 0x2901 returned to opcode 0x0 for session 0x11d from iqn.2005-03.org.open-iscsi:55a29b093a7,00023D000003.
11/28 19:14:31.650 634 SCSI: VPD_SUPPORTED_PAGES: no room in the list for page 0xB0.
0000 00 00 00 05 00 80 83 86 ........
11/28 19:14:31.650 634 SCSI: VPD_SUPPORTED_PAGES: no room in the list for page 0xB1.
0000 00 00 00 06 00 80 83 86 ........
11/28 19:14:31.650 634 SCSI: VPD_SUPPORTED_PAGES: no room in the list for page 0xB2.
0000 00 00 00 07 00 80 83 86 ........
11/28 19:14:31.650 634 SCSI: VPD_SUPPORTED_PAGES: partial data returned (8 of 12).
11/28 19:14:31.666 634 HA: CHADevice::SscRequestTaskExecute: Received SCSIOP_MAINTENANCE_IN (service 0xC)
11/28 19:14:31.666 634 SCSI: VPD_SUPPORTED_PAGES: no room in the list for page 0xB0.
0000 00 00 00 05 00 80 83 86 ........
11/28 19:14:31.666 634 SCSI: VPD_SUPPORTED_PAGES: no room in the list for page 0xB1.
0000 00 00 00 06 00 80 83 86 ........
11/28 19:14:31.666 634 SCSI: VPD_SUPPORTED_PAGES: no room in the list for page 0xB2.
0000 00 00 00 07 00 80 83 86 ........
11/28 19:14:31.666 634 SCSI: VPD_SUPPORTED_PAGES: partial data returned (8 of 12).
11/28 19:14:31.666 634 SCSI: VPD_SUPPORTED_PAGES: no room in the list for page 0xB0.
0000 00 00 00 05 00 80 83 86 ........
11/28 19:14:31.666 634 SCSI: VPD_SUPPORTED_PAGES: no room in the list for page 0xB1.
0000 00 00 00 06 00 80 83 86 ........
11/28 19:14:31.666 634 SCSI: VPD_SUPPORTED_PAGES: no room in the list for page 0xB2.
0000 00 00 00 07 00 80 83 86 ........
11/28 19:14:31.666 634 SCSI: VPD_SUPPORTED_PAGES: partial data returned (8 of 12).
11/28 19:15:04.502 634 debug: The program encountered a serious error and may be closed. Crash dump will be created.
Please, save the log file and the crash dump and report the problem to support@starwindsoftware.com
11/28 19:15:07.260 634 debug: Minidump 'C:\Program Files\StarWind Software\StarWind\starwind.20171128.191504.mdmp' created successfully.
11/28 19:15:07.260 634 S[11d]: 'exec' thread: Initiating shutdown sequence because of exception.
Last edited by muhfugen on Tue Dec 19, 2017 5:33 pm, edited 1 time in total.
muhfugen
Posts: 10
Joined: Wed Nov 23, 2016 11:10 pm

Wed Nov 29, 2017 2:13 am

I tried updating to VSAN 8.0.0 Build 11456 and the StarWindService still crashes when the Linux VM tries to mount the partition.
Oleg(staff)
Staff
Posts: 568
Joined: Fri Nov 24, 2017 7:52 am

Thu Nov 30, 2017 11:43 am

Hi muhfugen,

Please, PM me the link where I can download the StarWind logs with time covering the issue and minidump files related.
Oleg(staff)
Staff
Posts: 568
Joined: Fri Nov 24, 2017 7:52 am

Tue Dec 05, 2017 12:16 pm

Hi muhfugen,
Looks like that it falls on the processing of extended commands VAAi.
Try to disable hardware acceleration. It can be done by stopping StarWind service and then changing the parameter <HardwareAccelerationEnabled value="yes"/> to <HardwareAccelerationEnabled value="no"/> on StarWind.cfg file.
Post Reply