StarWind error log
Posted: Tue Sep 15, 2009 7:13 am
9/15 15:13:30.397 738 C[1], FREE: Event - CONNECTED.
9/15 15:13:30.397 738 C[1], XPT_UP: T3.
9/15 15:13:30.397 6fc C[1], XPT_UP: Login request: ISID 0x001234000000, TSIH 0x0000.
9/15 15:13:30.457 6fc C[1], XPT_UP: Event - LOGIN.
9/15 15:13:30.457 6fc C[1], IN_LOGIN: T4.
9/15 15:13:30.457 6fc Params: <<< String param 'InitiatorName': received 'iqn.2009-09.yushang.com.uSCSI:vDisk.0001', accepted 'iqn.2009-09.yushang.com.uSCSI:vDisk.0001'
9/15 15:13:30.457 6fc Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
9/15 15:13:30.477 6fc Params: <<< String param 'TargetName': received 'iqn.yushang.com:vDisk.0001', accepted 'iqn.yushang.com:vDisk.0001'
9/15 15:13:30.477 6fc Params: <<< String param 'InitiatorAlias': received 'Personal Disk of yushang', accepted 'Personal Disk of yushang'
9/15 15:13:30.487 6fc Params: <<< Enum param 'AuthMethod': received 'CHAP,KRB5,SPKM1,SPKM2,SRP,None', accepted 'CHAP'
9/15 15:13:30.507 6fc Tgt: : open 'iqn.yushang.com:vdisk.0001': 1 session(s) opened, 0 more allowed.
9/15 15:13:30.518 6fc Params: <<< Enum param 'AuthMethod': received 'CHAP,KRB5,SPKM1,SPKM2,SRP,None', accepted 'None'
9/15 15:13:30.518 6fc Params: >>> AuthMethod=None.
9/15 15:13:30.518 6fc Params: >>> TargetPortalGroupTag=1.
9/15 15:13:30.598 6fc Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
9/15 15:13:30.608 6fc Params: >>> AuthMethod=None.
9/15 15:13:30.608 6c4 T[1,1]: session 0x1, connection 0x1 : end of stage 0, next stage 1.
9/15 15:13:30.668 6fc Params: >>> InitialR2T=No.
9/15 15:13:30.668 6fc Params: >>> ImmediateData=Yes.
9/15 15:13:30.668 6fc Params: >>> MaxRecvDataSegmentLength=262144.
9/15 15:13:30.668 6fc Params: >>> MaxBurstLength=262144.
9/15 15:13:30.668 6fc Params: >>> FirstBurstLength=262144.
9/15 15:13:30.668 6fc Params: >>> DefaultTime2Wait=0.
9/15 15:13:30.668 6fc Params: >>> MaxOutstandingR2T=1.
9/15 15:13:30.668 6fc Params: >>> DataPDUInOrder=Yes.
9/15 15:13:30.668 6fc Params: >>> DataSequenceInOrder=Yes.
9/15 15:13:30.808 6fc Params: <<< Boolean param 'InitialR2T': received Yes, accepted 1
9/15 15:13:30.808 6fc Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
9/15 15:13:30.818 6fc Params: <<< Numeric out of bounds! MaxBurstLength = (0)
9/15 15:13:30.818 6fc T[1,1]: Updating of parameters failed.
9/15 15:13:30.828 664 C[1], IN_LOGIN: Event - LOGIN_REJECT.
9/15 15:13:30.828 664 C[1], FREE: T7.
9/15 15:13:37.217 6fc C[1], FREE: recvData returned 10054
9/15 15:13:37.237 6c4 Tgt: : close 'iqn.yushang.com:vdisk.0001': 0 session(s) opened, 1 more allowed.
It seems MaxBurstLength is incorrect , but I do see initiator send MaxBurstLength=0x40000 on the wire . Any help is appreciated .
9/15 15:13:30.397 738 C[1], XPT_UP: T3.
9/15 15:13:30.397 6fc C[1], XPT_UP: Login request: ISID 0x001234000000, TSIH 0x0000.
9/15 15:13:30.457 6fc C[1], XPT_UP: Event - LOGIN.
9/15 15:13:30.457 6fc C[1], IN_LOGIN: T4.
9/15 15:13:30.457 6fc Params: <<< String param 'InitiatorName': received 'iqn.2009-09.yushang.com.uSCSI:vDisk.0001', accepted 'iqn.2009-09.yushang.com.uSCSI:vDisk.0001'
9/15 15:13:30.457 6fc Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
9/15 15:13:30.477 6fc Params: <<< String param 'TargetName': received 'iqn.yushang.com:vDisk.0001', accepted 'iqn.yushang.com:vDisk.0001'
9/15 15:13:30.477 6fc Params: <<< String param 'InitiatorAlias': received 'Personal Disk of yushang', accepted 'Personal Disk of yushang'
9/15 15:13:30.487 6fc Params: <<< Enum param 'AuthMethod': received 'CHAP,KRB5,SPKM1,SPKM2,SRP,None', accepted 'CHAP'
9/15 15:13:30.507 6fc Tgt: : open 'iqn.yushang.com:vdisk.0001': 1 session(s) opened, 0 more allowed.
9/15 15:13:30.518 6fc Params: <<< Enum param 'AuthMethod': received 'CHAP,KRB5,SPKM1,SPKM2,SRP,None', accepted 'None'
9/15 15:13:30.518 6fc Params: >>> AuthMethod=None.
9/15 15:13:30.518 6fc Params: >>> TargetPortalGroupTag=1.
9/15 15:13:30.598 6fc Params: <<< Enum param 'AuthMethod': received 'None', accepted 'None'
9/15 15:13:30.608 6fc Params: >>> AuthMethod=None.
9/15 15:13:30.608 6c4 T[1,1]: session 0x1, connection 0x1 : end of stage 0, next stage 1.
9/15 15:13:30.668 6fc Params: >>> InitialR2T=No.
9/15 15:13:30.668 6fc Params: >>> ImmediateData=Yes.
9/15 15:13:30.668 6fc Params: >>> MaxRecvDataSegmentLength=262144.
9/15 15:13:30.668 6fc Params: >>> MaxBurstLength=262144.
9/15 15:13:30.668 6fc Params: >>> FirstBurstLength=262144.
9/15 15:13:30.668 6fc Params: >>> DefaultTime2Wait=0.
9/15 15:13:30.668 6fc Params: >>> MaxOutstandingR2T=1.
9/15 15:13:30.668 6fc Params: >>> DataPDUInOrder=Yes.
9/15 15:13:30.668 6fc Params: >>> DataSequenceInOrder=Yes.
9/15 15:13:30.808 6fc Params: <<< Boolean param 'InitialR2T': received Yes, accepted 1
9/15 15:13:30.808 6fc Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
9/15 15:13:30.818 6fc Params: <<< Numeric out of bounds! MaxBurstLength = (0)
9/15 15:13:30.818 6fc T[1,1]: Updating of parameters failed.
9/15 15:13:30.828 664 C[1], IN_LOGIN: Event - LOGIN_REJECT.
9/15 15:13:30.828 664 C[1], FREE: T7.
9/15 15:13:37.217 6fc C[1], FREE: recvData returned 10054
9/15 15:13:37.237 6c4 Tgt: : close 'iqn.yushang.com:vdisk.0001': 0 session(s) opened, 1 more allowed.
It seems MaxBurstLength is incorrect , but I do see initiator send MaxBurstLength=0x40000 on the wire . Any help is appreciated .