I need some assist with setting up CHAP with Stawind v8_12658 and VMware 6.5. I've set a golbal CHAP PERMISSION (user & secret, 16 chars a-Z,0-9, no mutal) in starwind. When I try to connect with VMware I can't get authenticated. You can assume that I do not have used a mismatched secret!
Further I don't have set an explicit CHAP challange at target level because I assume it's inherited from the global CHAP PERMISSION (Note: I would prefer a hint in starwind at the target level that a CHAP is inherited instead of show "no chap configured").
Below are the results from 3 CHAP authentication methods in VMware 6.5.
If I set no CHAP nothing happens at all. I can't see any errors in the starwind log (e.g. missing CHAP). Anyway, no storage device is detected in VMware.
If I set to use CHAP if requried starind accepts "none" instead of "CHAP" but then states an error because authentication is required. (???)
If I set prefer CHAP I get the error that Authentication failed. Wrong response returned.
Could someone explain this behaviour?
Code: Select all
### VMware NO CHAP ###
12/9 12:42:00.118 1e0 Srv: iScsiServer::listenConnections: Accepted iSCSI connection from 172.16.1.5:45950 to 172.16.1.150:3260. (Id = 0x2f8)
12/9 12:42:00.118 1e0 S[2f8]: iScsiSession::iScsiSession: Session (000000FCEE829E40)
12/9 12:42:00.118 1e0 C[2f8], FREE: iScsiConnection::doTransition: Event - CONNECTED.
12/9 12:42:00.118 1e0 C[2f8], XPT_UP: iScsiConnection::fsmT3: T3.
12/9 12:42:00.118 8dc C[2f8], XPT_UP: iScsiConnection::handleFirstLogin: Login request: ISID 0x00023d000000, TSIH 0x0000.
12/9 12:42:00.118 8dc C[2f8], XPT_UP: iScsiConnection::doTransition: Event - LOGIN.
12/9 12:42:00.118 8dc C[2f8], IN_LOGIN: iScsiConnection::fsmT4: T4.
12/9 12:42:00.118 8dc Params: iScsiParameter::update: <<< String param 'InitiatorName': received 'iqn.1998-01.com.vmware:esx-rz05-2cd6406f', accepted 'iqn.1998-01.com.vmware:esx-rz05-2cd6406f'
12/9 12:42:00.118 8dc Params: iScsiParameter::update: <<< String param 'InitiatorAlias': received 'esx-rz05', accepted 'esx-rz05'
12/9 12:42:00.118 8dc Params: iScsiParameter::update: <<< Enum param 'SessionType': received 'Discovery', accepted 'Discovery'
12/9 12:42:00.118 8dc Params: iScsiParameter::update: <<< Enum param 'HeaderDigest': received 'None', accepted 'None'
12/9 12:42:00.118 8dc Params: iScsiParameter::update: <<< Enum param 'DataDigest': received 'None', accepted 'None'
12/9 12:42:00.118 8dc Params: iScsiParameter::update: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 0
12/9 12:42:00.118 8dc Params: iScsiParameter::update: <<< Numeric param 'DefaultTime2Retain': received 0, accepted 0
12/9 12:42:00.118 8dc Params: iScsiParameter::update: <<< Boolean param 'IFMarker': received No, accepted 0
12/9 12:42:00.118 8dc Params: iScsiParameter::update: <<< Boolean param 'OFMarker': received No, accepted 0
12/9 12:42:00.118 8dc Params: iScsiParameter::update: <<< Numeric param 'ErrorRecoveryLevel': received 0, accepted 0
12/9 12:42:00.118 8dc Params: iScsiParameter::update: <<< Numeric param 'MaxRecvDataSegmentLength': received 32768, accepted 32768
12/9 12:42:00.118 8dc Params: iScsiParams::createKeys: >>> ErrorRecoveryLevel=0.
12/9 12:42:00.118 8dc Params: iScsiParams::createKeys: >>> HeaderDigest=None.
12/9 12:42:00.118 8dc Params: iScsiParams::createKeys: >>> DataDigest=None.
12/9 12:42:00.118 8dc Params: iScsiParams::createKeys: >>> OFMarker=No.
12/9 12:42:00.118 8dc Params: iScsiParams::createKeys: >>> IFMarker=No.
12/9 12:42:00.118 8dc Params: iScsiParams::createKeys: >>> MaxRecvDataSegmentLength=262144.
12/9 12:42:00.118 8dc Params: iScsiParams::createKeys: >>> DefaultTime2Wait=0.
12/9 12:42:00.118 8dc Params: iScsiParams::createKeys: >>> DefaultTime2Retain=0.
12/9 12:42:00.118 1030 T[2f8,1]: iScsiTask::execLoginReq: session 0x2f8, connection 0x2f8 : end of stage 1, next stage 3.
12/9 12:42:00.118 1030 C[2f8], IN_LOGIN: iScsiConnection::doTransition: Event - LOGIN_ACCEPT.
12/9 12:42:00.118 1030 C[2f8], LIN: iScsiConnection::fsmT5: T5.
12/9 12:42:00.118 8dc Params: iScsiParameter::update: <<< String param 'SendTargets': received 'All', accepted 'All'
12/9 12:42:00.118 8dc Srv: iScsiServer::sendTargets: <no target>: 'SendTargets=All' for a Discovery session...
### VMware CHAP IF REQUIRED ###
12/9 12:46:08.690 1e0 Srv: iScsiServer::listenConnections: Accepted iSCSI connection from 172.16.1.5:44968 to 172.16.1.150:3260. (Id = 0x2fa)
12/9 12:46:08.690 1e0 S[2fa]: iScsiSession::iScsiSession: Session (000000FE1F9B3400)
12/9 12:46:08.690 1e0 C[2fa], FREE: iScsiConnection::doTransition: Event - CONNECTED.
12/9 12:46:08.690 1e0 C[2fa], XPT_UP: iScsiConnection::fsmT3: T3.
12/9 12:46:08.690 324 C[2fa], XPT_UP: iScsiConnection::handleFirstLogin: Login request: ISID 0x00023d000000, TSIH 0x0000.
12/9 12:46:08.690 324 C[2fa], XPT_UP: iScsiConnection::doTransition: Event - LOGIN.
12/9 12:46:08.690 324 C[2fa], IN_LOGIN: iScsiConnection::fsmT4: T4.
12/9 12:46:08.690 324 Params: iScsiParameter::update: <<< String param 'InitiatorName': received 'iqn.1998-01.com.vmware:esx-rz05-2cd6406f', accepted 'iqn.1998-01.com.vmware:esx-rz05-2cd6406f'
12/9 12:46:08.690 324 Params: iScsiParameter::update: <<< String param 'InitiatorAlias': received 'esx-rz05', accepted 'esx-rz05'
12/9 12:46:08.690 324 Params: iScsiParameter::update: <<< Enum param 'SessionType': received 'Discovery', accepted 'Discovery'
12/9 12:46:08.690 324 Params: iScsiParameter::update: <<< Enum param 'AuthMethod': received 'None,CHAP', accepted 'None'
12/9 12:46:08.690 324 Params: iScsiParameter::update: <<< Enum param 'AuthMethod': received 'None,CHAP', accepted 'None'
12/9 12:46:08.690 324 Params: iScsiParams::createKeys: >>> AuthMethod=None.
12/9 12:46:08.690 11a8 T[2fa,1]: iScsiTask::execLoginReq: session 0x2fa, connection 0x2fa : end of stage 0, next stage 1.
12/9 12:46:08.690 324 Params: iScsiParameter::update: <<< Enum param 'HeaderDigest': received 'None', accepted 'None'
12/9 12:46:08.690 324 Params: iScsiParameter::update: <<< Enum param 'DataDigest': received 'None', accepted 'None'
12/9 12:46:08.690 324 Params: iScsiParameter::update: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 2
12/9 12:46:08.690 324 Params: iScsiParameter::update: <<< Numeric param 'DefaultTime2Retain': received 0, accepted 0
12/9 12:46:08.690 324 Params: iScsiParameter::update: <<< Boolean param 'IFMarker': received No, accepted 0
12/9 12:46:08.690 324 Params: iScsiParameter::update: <<< Boolean param 'OFMarker': received No, accepted 0
12/9 12:46:08.690 324 Params: iScsiParameter::update: <<< Numeric param 'ErrorRecoveryLevel': received 0, accepted 0
12/9 12:46:08.690 324 Params: iScsiParameter::update: <<< Numeric param 'MaxRecvDataSegmentLength': received 32768, accepted 32768
12/9 12:46:08.690 324 Params: iScsiParams::createKeys: >>> ErrorRecoveryLevel=0.
12/9 12:46:08.690 324 Params: iScsiParams::createKeys: >>> HeaderDigest=None.
12/9 12:46:08.690 324 Params: iScsiParams::createKeys: >>> DataDigest=None.
12/9 12:46:08.690 324 Params: iScsiParams::createKeys: >>> OFMarker=No.
12/9 12:46:08.690 324 Params: iScsiParams::createKeys: >>> IFMarker=No.
12/9 12:46:08.690 324 Params: iScsiParams::createKeys: >>> MaxRecvDataSegmentLength=8192.
12/9 12:46:08.690 324 Params: iScsiParams::createKeys: >>> DefaultTime2Wait=2.
12/9 12:46:08.690 324 Params: iScsiParams::createKeys: >>> DefaultTime2Retain=0.
12/9 12:46:08.690 11a8 T[2fa,1]: iScsiTask::execLoginReq: session 0x2fa, connection 0x2fa : end of stage 1, next stage 3.
12/9 12:46:08.690 11a8 C[2fa], IN_LOGIN: iScsiConnection::doTransition: Event - LOGIN_ACCEPT.
12/9 12:46:08.690 11a8 C[2fa], LIN: iScsiConnection::fsmT5: T5.
12/9 12:46:08.690 324 Params: iScsiParameter::update: <<< String param 'SendTargets': received 'All', accepted 'All'
12/9 12:46:08.690 324 Srv: iScsiServer::sendTargets: <no target>: 'SendTargets=All' for a Discovery session...
12/9 12:46:08.690 11a8 C[2fa], LIN: iScsiConnection::doTransition: Event - LOGOUT.
12/9 12:46:08.690 11a8 C[2fa], IN_LOGOUT: iScsiConnection::fsmT9T10: T9/10.
12/9 12:46:08.690 111c C[2fa], IN_LOGOUT: iScsiConnection::doTransition: Event - LOGOUT_ACCEPT.
12/9 12:46:08.690 111c C[2fa], CLEANUP_WAIT: iScsiConnection::fsmT13: T13.
12/9 12:46:08.690 324 Sw: *** Swn_SocketRecv: WSARecv() failed with error 10054 (0x2746)!
12/9 12:46:08.690 324 Srv: *** SwSocket::Recv: Swn_SocketRecv() failed with error 10054 (0x2746)!
12/9 12:46:08.690 324 C[2fa], CLEANUP_WAIT: iScsiConnection::receive: recvData returned 10054 (0x2746)!
12/9 12:46:08.690 11a8 S[2fa]: iScsiSession::~iScsiSession: ~Session
12/9 12:46:08.705 1e0 Srv: iScsiServer::listenConnections: Accepted iSCSI connection from 172.16.1.5:11614 to 172.16.1.150:3260. (Id = 0x2fb)
12/9 12:46:08.705 1e0 S[2fb]: iScsiSession::iScsiSession: Session (000000FE1F9B2E40)
12/9 12:46:08.705 1e0 C[2fb], FREE: iScsiConnection::doTransition: Event - CONNECTED.
12/9 12:46:08.705 1e0 C[2fb], XPT_UP: iScsiConnection::fsmT3: T3.
12/9 12:46:08.955 614 C[2fb], XPT_UP: iScsiConnection::handleFirstLogin: Login request: ISID 0x00023d000003, TSIH 0x0000.
12/9 12:46:08.955 614 C[2fb], XPT_UP: iScsiConnection::doTransition: Event - LOGIN.
12/9 12:46:08.955 614 C[2fb], IN_LOGIN: iScsiConnection::fsmT4: T4.
12/9 12:46:08.955 614 Params: iScsiParameter::update: <<< String param 'InitiatorName': received 'iqn.1998-01.com.vmware:esx-rz05-2cd6406f', accepted 'iqn.1998-01.com.vmware:esx-rz05-2cd6406f'
12/9 12:46:08.955 614 Params: iScsiParameter::update: <<< String param 'InitiatorAlias': received 'esx-rz05', accepted 'esx-rz05'
12/9 12:46:08.955 614 Params: iScsiParameter::update: <<< String param 'TargetName': received 'iqn.2008-08.com.starwindsoftware:san-rz01.bex.lan-san-rz-lun01', accepted 'iqn.2008-08.com.starwindsoftware:san-rz01.bex.lan-san-rz-lun01'
12/9 12:46:08.955 614 Params: iScsiParameter::update: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
12/9 12:46:08.955 614 Params: iScsiParameter::update: <<< Enum param 'AuthMethod': received 'None,CHAP', accepted 'None'
12/9 12:46:08.955 614 HA: CHADevice::register_session: Client initiator iqn.1998-01.com.vmware:esx-rz05-2cd6406f is trying to register a session within the 'iqn.2008-08.com.starwindsoftware:san-rz01.bex.lan-san-rz-lun01' target... (sessId = 0x2fb, initiatorNameIsid = iqn.1998-01.com.vmware:esx-rz05-2cd6406f,00023D000003)
12/9 12:46:08.955 614 HA: CHADevice::register_session: Client session (IP: "172.16.1.5"; Initiator: "iqn.1998-01.com.vmware:esx-rz05-2cd6406f") has been allowed!
12/9 12:46:08.955 614 HA: CHADevice::register_session: Registering the external client session.
12/9 12:46:08.955 614 PR: ResLunSessionEngine::registerSession: LUN 0: existing record for session 0x2fb from iqn.1998-01.com.vmware:esx-rz05-2cd6406f,00023D000003 iqn.2008-08.com.starwindsoftware:san-rz01.bex.lan-san-rz-lun01
12/9 12:46:08.955 614 PR: ResLunSession::setUnitAttention: Set UA 0x2901 (0x0) for session 0x2fb from iqn.1998-01.com.vmware:esx-rz05-2cd6406f,00023D000003.
12/9 12:46:08.955 614 ScsiFlt: ALUA_Filter::register_session: registering session 0x2fb
12/9 12:46:08.955 614 HA: CHADevice::register_session: Return code 0.
12/9 12:46:08.955 614 PR: ResLunSessionEngine::registerSession: LUN 0: existing record for session 0x2fb from iqn.1998-01.com.vmware:esx-rz05-2cd6406f,00023D000003
12/9 12:46:08.955 614 Tgt: iScsiTarget::openSession: open 'iqn.2008-08.com.starwindsoftware:san-rz01.bex.lan-san-rz-lun01': 5 session(s) opened, 65531 more allowed.
12/9 12:46:08.955 614 Params: iScsiParameter::update: <<< Enum param 'AuthMethod': received 'None,CHAP', accepted 'None'
12/9 12:46:08.955 614 T[2fb,1]: ***iScsiTask::handleAuth: *ERROR* Authentication required.
12/9 12:46:08.955 11a4 C[2fb], IN_LOGIN: iScsiConnection::doTransition: Event - LOGIN_REJECT.
12/9 12:46:08.955 11a4 C[2fb], IN_LOGIN: iScsiConnection::fsmT7: T7.
12/9 12:46:09.221 614 C[2fb], IN_LOGIN: iScsiConnection::receive: recvData returned 10058 (0x274a)!
12/9 12:46:09.221 5a8 HA: CHADevice::unregister_session: Client initiator iqn.1998-01.com.vmware:esx-rz05-2cd6406f has unregistered a session.
12/9 12:46:09.221 5a8 ScsiFlt: ALUA_Filter::unregister_session: unregistering session 0x2fb
12/9 12:46:09.221 5a8 Tgt: iScsiTarget::closeSession: close 'iqn.2008-08.com.starwindsoftware:san-rz01.bex.lan-san-rz-lun01': 4 session(s) opened, 65532 more allowed.
12/9 12:46:09.221 5a8 S[2fb]: iScsiSession::~iScsiSession: ~Session
### VMware CHAP PREFERED ###
12/9 12:51:53.144 1e0 Srv: iScsiServer::listenConnections: Accepted iSCSI connection from 172.16.1.5:55975 to 172.16.1.150:3260. (Id = 0x2fc)
12/9 12:51:53.144 1e0 S[2fc]: iScsiSession::iScsiSession: Session (000000FCEE685500)
12/9 12:51:53.144 1e0 C[2fc], FREE: iScsiConnection::doTransition: Event - CONNECTED.
12/9 12:51:53.144 1e0 C[2fc], XPT_UP: iScsiConnection::fsmT3: T3.
12/9 12:51:53.144 f9c C[2fc], XPT_UP: iScsiConnection::handleFirstLogin: Login request: ISID 0x00023d000000, TSIH 0x0000.
12/9 12:51:53.144 f9c C[2fc], XPT_UP: iScsiConnection::doTransition: Event - LOGIN.
12/9 12:51:53.144 f9c C[2fc], IN_LOGIN: iScsiConnection::fsmT4: T4.
12/9 12:51:53.144 f9c Params: iScsiParameter::update: <<< String param 'InitiatorName': received 'iqn.1998-01.com.vmware:esx-rz05-2cd6406f', accepted 'iqn.1998-01.com.vmware:esx-rz05-2cd6406f'
12/9 12:51:53.144 f9c Params: iScsiParameter::update: <<< String param 'InitiatorAlias': received 'esx-rz05', accepted 'esx-rz05'
12/9 12:51:53.144 f9c Params: iScsiParameter::update: <<< Enum param 'SessionType': received 'Discovery', accepted 'Discovery'
12/9 12:51:53.144 f9c Params: iScsiParameter::update: <<< Enum param 'AuthMethod': received 'CHAP,None', accepted 'CHAP'
12/9 12:51:53.144 f9c Params: iScsiParameter::update: <<< Enum param 'AuthMethod': received 'CHAP,None', accepted 'None'
12/9 12:51:53.144 f9c Params: iScsiParams::createKeys: >>> AuthMethod=None.
12/9 12:51:53.144 b78 T[2fc,1]: iScsiTask::execLoginReq: session 0x2fc, connection 0x2fc : end of stage 0, next stage 1.
12/9 12:51:53.144 f9c Params: iScsiParameter::update: <<< Enum param 'HeaderDigest': received 'None', accepted 'None'
12/9 12:51:53.144 f9c Params: iScsiParameter::update: <<< Enum param 'DataDigest': received 'None', accepted 'None'
12/9 12:51:53.144 f9c Params: iScsiParameter::update: <<< Numeric param 'DefaultTime2Wait': received 0, accepted 2
12/9 12:51:53.144 f9c Params: iScsiParameter::update: <<< Numeric param 'DefaultTime2Retain': received 0, accepted 0
12/9 12:51:53.144 f9c Params: iScsiParameter::update: <<< Boolean param 'IFMarker': received No, accepted 0
12/9 12:51:53.144 f9c Params: iScsiParameter::update: <<< Boolean param 'OFMarker': received No, accepted 0
12/9 12:51:53.144 f9c Params: iScsiParameter::update: <<< Numeric param 'ErrorRecoveryLevel': received 0, accepted 0
12/9 12:51:53.144 f9c Params: iScsiParameter::update: <<< Numeric param 'MaxRecvDataSegmentLength': received 32768, accepted 32768
12/9 12:51:53.144 f9c Params: iScsiParams::createKeys: >>> ErrorRecoveryLevel=0.
12/9 12:51:53.144 f9c Params: iScsiParams::createKeys: >>> HeaderDigest=None.
12/9 12:51:53.144 f9c Params: iScsiParams::createKeys: >>> DataDigest=None.
12/9 12:51:53.144 f9c Params: iScsiParams::createKeys: >>> OFMarker=No.
12/9 12:51:53.144 f9c Params: iScsiParams::createKeys: >>> IFMarker=No.
12/9 12:51:53.144 f9c Params: iScsiParams::createKeys: >>> MaxRecvDataSegmentLength=8192.
12/9 12:51:53.144 f9c Params: iScsiParams::createKeys: >>> DefaultTime2Wait=2.
12/9 12:51:53.144 f9c Params: iScsiParams::createKeys: >>> DefaultTime2Retain=0.
12/9 12:51:53.144 b78 T[2fc,1]: iScsiTask::execLoginReq: session 0x2fc, connection 0x2fc : end of stage 1, next stage 3.
12/9 12:51:53.144 b78 C[2fc], IN_LOGIN: iScsiConnection::doTransition: Event - LOGIN_ACCEPT.
12/9 12:51:53.144 b78 C[2fc], LIN: iScsiConnection::fsmT5: T5.
12/9 12:51:53.144 f9c Params: iScsiParameter::update: <<< String param 'SendTargets': received 'All', accepted 'All'
12/9 12:51:53.144 f9c Srv: iScsiServer::sendTargets: <no target>: 'SendTargets=All' for a Discovery session...
12/9 12:51:53.144 b78 C[2fc], LIN: iScsiConnection::doTransition: Event - LOGOUT.
12/9 12:51:53.144 b78 C[2fc], IN_LOGOUT: iScsiConnection::fsmT9T10: T9/10.
12/9 12:51:53.144 338 C[2fc], IN_LOGOUT: iScsiConnection::doTransition: Event - LOGOUT_ACCEPT.
12/9 12:51:53.144 338 C[2fc], CLEANUP_WAIT: iScsiConnection::fsmT13: T13.
12/9 12:51:53.144 f9c Sw: *** Swn_SocketRecv: WSARecv() failed with error 10054 (0x2746)!
12/9 12:51:53.144 f9c Srv: *** SwSocket::Recv: Swn_SocketRecv() failed with error 10054 (0x2746)!
12/9 12:51:53.144 f9c C[2fc], CLEANUP_WAIT: iScsiConnection::receive: recvData returned 10054 (0x2746)!
12/9 12:51:53.144 b78 S[2fc]: iScsiSession::~iScsiSession: ~Session
12/9 12:51:53.160 1e0 Srv: iScsiServer::listenConnections: Accepted iSCSI connection from 172.16.1.5:26239 to 172.16.1.150:3260. (Id = 0x2fd)
12/9 12:51:53.160 1e0 S[2fd]: iScsiSession::iScsiSession: Session (000000FCEE684F00)
12/9 12:51:53.160 1e0 C[2fd], FREE: iScsiConnection::doTransition: Event - CONNECTED.
12/9 12:51:53.160 1e0 C[2fd], XPT_UP: iScsiConnection::fsmT3: T3.
12/9 12:51:53.425 12c4 C[2fd], XPT_UP: iScsiConnection::handleFirstLogin: Login request: ISID 0x00023d000003, TSIH 0x0000.
12/9 12:51:53.425 12c4 C[2fd], XPT_UP: iScsiConnection::doTransition: Event - LOGIN.
12/9 12:51:53.425 12c4 C[2fd], IN_LOGIN: iScsiConnection::fsmT4: T4.
12/9 12:51:53.425 12c4 Params: iScsiParameter::update: <<< String param 'InitiatorName': received 'iqn.1998-01.com.vmware:esx-rz05-2cd6406f', accepted 'iqn.1998-01.com.vmware:esx-rz05-2cd6406f'
12/9 12:51:53.425 12c4 Params: iScsiParameter::update: <<< String param 'InitiatorAlias': received 'esx-rz05', accepted 'esx-rz05'
12/9 12:51:53.425 12c4 Params: iScsiParameter::update: <<< String param 'TargetName': received 'iqn.2008-08.com.starwindsoftware:san-rz01.bex.lan-san-rz-lun01', accepted 'iqn.2008-08.com.starwindsoftware:san-rz01.bex.lan-san-rz-lun01'
12/9 12:51:53.425 12c4 Params: iScsiParameter::update: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
12/9 12:51:53.425 12c4 Params: iScsiParameter::update: <<< Enum param 'AuthMethod': received 'CHAP,None', accepted 'CHAP'
12/9 12:51:53.425 12c4 HA: CHADevice::register_session: Client initiator iqn.1998-01.com.vmware:esx-rz05-2cd6406f is trying to register a session within the 'iqn.2008-08.com.starwindsoftware:san-rz01.bex.lan-san-rz-lun01' target... (sessId = 0x2fd, initiatorNameIsid = iqn.1998-01.com.vmware:esx-rz05-2cd6406f,00023D000003)
12/9 12:51:53.425 12c4 HA: CHADevice::register_session: Client session (IP: "172.16.1.5"; Initiator: "iqn.1998-01.com.vmware:esx-rz05-2cd6406f") has been allowed!
12/9 12:51:53.425 12c4 HA: CHADevice::register_session: Registering the external client session.
12/9 12:51:53.425 12c4 PR: ResLunSessionEngine::registerSession: LUN 0: existing record for session 0x2fd from iqn.1998-01.com.vmware:esx-rz05-2cd6406f,00023D000003 iqn.2008-08.com.starwindsoftware:san-rz01.bex.lan-san-rz-lun01
12/9 12:51:53.425 12c4 PR: ResLunSession::setUnitAttention: Set UA 0x2901 (0x0) for session 0x2fd from iqn.1998-01.com.vmware:esx-rz05-2cd6406f,00023D000003.
12/9 12:51:53.425 12c4 ScsiFlt: ALUA_Filter::register_session: registering session 0x2fd
12/9 12:51:53.425 12c4 HA: CHADevice::register_session: Return code 0.
12/9 12:51:53.425 12c4 PR: ResLunSessionEngine::registerSession: LUN 0: existing record for session 0x2fd from iqn.1998-01.com.vmware:esx-rz05-2cd6406f,00023D000003
12/9 12:51:53.425 12c4 Tgt: iScsiTarget::openSession: open 'iqn.2008-08.com.starwindsoftware:san-rz01.bex.lan-san-rz-lun01': 5 session(s) opened, 65531 more allowed.
12/9 12:51:53.425 12c4 Params: iScsiParameter::update: <<< Enum param 'AuthMethod': received 'CHAP,None', accepted 'CHAP'
12/9 12:51:53.425 12c4 Params: iScsiParams::createKeys: >>> AuthMethod=CHAP.
12/9 12:51:53.425 12c4 Params: iScsiParams::createKeys: >>> TargetAlias=san-rz-lun01.
12/9 12:51:53.425 12c4 Params: iScsiParams::createKeys: >>> TargetPortalGroupTag=1.
12/9 12:51:53.675 12c4 Params: iScsiParameter::update: <<< Enum param 'CHAP_A': received '5', accepted '5'
12/9 12:51:53.675 12c4 Params: iScsiParams::createKeys: >>> CHAP_A=5.
12/9 12:51:53.675 12c4 Params: iScsiParams::createKeys: >>> CHAP_I=2.
12/9 12:51:53.675 12c4 Params: iScsiParams::createKeys: >>> CHAP_C=0bN3Vvf8FhC/7IxxePcQ8HrnlYrtCbQWEAU/FD88AoODk=.
12/9 12:51:53.925 12c4 Params: iScsiParameter::update: <<< String param 'CHAP_N': received 'GlobalSecret', accepted 'GlobalSecret'
12/9 12:51:53.925 12c4 Params: iScsiParameter::update: <<< String param 'CHAP_R': received '0x2b8d255bb0e2da54b85d0067f36088af', accepted '0x2b8d255bb0e2da54b85d0067f36088af'
12/9 12:51:53.925 12c4 T[2fd,1]: ***iScsiTask::handleAuth: *ERROR* Authentication failed. Wrong response returned.
12/9 12:51:53.925 ba4 C[2fd], IN_LOGIN: iScsiConnection::doTransition: Event - LOGIN_REJECT.
12/9 12:51:53.925 ba4 C[2fd], IN_LOGIN: iScsiConnection::fsmT7: T7.
12/9 12:51:54.426 12c4 C[2fd], IN_LOGIN: iScsiConnection::receive: recvData returned 10058 (0x274a)!
12/9 12:51:54.426 1380 HA: CHADevice::unregister_session: Client initiator iqn.1998-01.com.vmware:esx-rz05-2cd6406f has unregistered a session.
12/9 12:51:54.426 1380 ScsiFlt: ALUA_Filter::unregister_session: unregistering session 0x2fd
12/9 12:51:54.426 1380 Tgt: iScsiTarget::closeSession: close 'iqn.2008-08.com.starwindsoftware:san-rz01.bex.lan-san-rz-lun01': 4 session(s) opened, 65532 more allowed.
12/9 12:51:54.426 1380 S[2fd]: iScsiSession::~iScsiSession: ~Session