[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]
Google
  Web www.spinics.net

RE: open-iscsi-0.3rc7-383 fails to connectup,but linux-iscsi-3.6.3 connects up fine.



[root@co-sanbs10 usr]# ./iscsiadm -m node
[0db902] 172.18.64.182:3260,1 iqn.2001-04.com.pillar:co-sanbs7.hdb
[root@co-sanbs10 usr]# ./iscsiadm -m node --record 0db902 --login                             iscsiadm: iscsid reported error (5 - encountered iSCSI login failure)



iscsid -f -d8 &
[root@co-sanbs10 usr]# iscsid: IPC socket is listening...
iscsid: in ctldev_open
iscsid: created NETLINK_ISCSI socket...
iscsid: InitiatorName=iqn.2005-08.com.pillar:co-sanbs10.linux.FC4
iscsid: InitiatorName=iqn.2005-08.com.pillar:co-sanbs10.linux.FC4
iscsid: InitiatorAlias=co-sanbs10.eng.trans.corp
iscsid: version 0.3 variant (22-Apr-2005)
iscsid: in ktrans_list
iscsid: synced 1 transport(s)
iscsid: detected poll event 1
iscsid: updating defaults from '/etc/iscsid.conf'
iscsid: updated 'discovery.sendtargets.auth.authmethod', 'None' => 'CHAP'
iscsid: updated 'discovery.sendtargets.auth.username', '<empty>' => 'iqn.2005-08.com.pillar:co-sanbs10.linux.FC4'
iscsid: updated 'discovery.sendtargets.auth.password', '<empty>' => 'iscsipillardata'
iscsid: updated 'discovery.sendtargets.auth.password_length', '0' => '15'
iscsid: updated 'node.startup', 'manual' => 'manual'
iscsid: updated 'node.session.auth.username', '<empty>' => 'iqn.2005-08.com.pillar:co-sanbs10.linux.FC4'
iscsid: updated 'node.session.auth.password', '<empty>' => 'iscsipillardata'
iscsid: updated 'node.session.auth.password_length', '0' => '15'
iscsid: updated 'node.session.timeo.replacement_timeout', '0' => '0'
iscsid: updated 'node.session.err_timeo.abort_timeout', '10' => '10'
iscsid: updated 'node.session.err_timeo.reset_timeout', '30' => '30'
iscsid: updated 'node.session.iscsi.InitialR2T', 'No' => 'Yes'
iscsid: updated 'node.session.iscsi.ImmediateData', 'Yes' => 'No'
iscsid: updated 'node.session.iscsi.FirstBurstLength', '262144' => '262144'
iscsid: updated 'node.session.iscsi.MaxBurstLength', '16776192' => '16776192'
iscsid: updated 'node.session.iscsi.DefaultTime2Wait', '0' => '0'
iscsid: updated 'node.session.iscsi.DefaultTime2Retain', '0' => '0'
iscsid: updated 'node.session.iscsi.MaxConnections', '1' => '1'
iscsid: searching for key '172.18.64.182:3260#172.18.64.182:3260,1#iqn.2001-04.com.pillar:co-sanbs7.hdb'
iscsid: in ktrans_list
iscsid: thread b7f62fc4 schedule: delay 0 state 2
iscsid: authentication setup complete...
iscsid: resolved 172.18.64.182 to 172.18.64.182
iscsid: set TCP recv window size to 524288, actually got 221184
iscsid: set TCP send window size to 524288, actually got 221184
iscsid: connecting to 172.18.64.182:3260
iscsid: event_type: 2 adding to the tail: 0x0x806a800:0x0x806a800:0x0x806a814:0x0x806e800 elem 0x0x806a800 length 20
iscsid: thread b7f62fc4 schedule: delay 0 state 1
iscsid: thread b7f42478 schedule: delay 60 state 2
iscsid: event_type: 2 removing from the head: 0x0x806a800:0x0x806a814:0x0x806a814:0x0x806e800 elem 0x0x806a800 length 20
iscsid: connected local port 56961 to 172.18.64.182:3260
iscsid: thread b7f42478 delete: state 0
iscsid: deleting a scheduled/waiting thread!
iscsid: in kcreate_session
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: created new iSCSI session, handle 0x0xc1f71664
iscsid: in kcreate_conn
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: created new iSCSI connection, handle 0x0xcabe9400
iscsid: in kbind_conn
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: bound iSCSI connection (handle 0x0xcabe9400) to session (handle 0x0xc1f71664)
iscsid: sending login PDU with current stage 0, next stage 1, transit 0x80, isid 0x00023d000000
iscsid: >    InitiatorName=iqn.2005-08.com.pillar:co-sanbs10.linux.FC4
iscsid: >    InitiatorAlias=co-sanbs10.eng.trans.corp
iscsid: >    TargetName=iqn.2001-04.com.pillar:co-sanbs7.hdb
iscsid: >    SessionType=Normal
iscsid: >    AuthMethod=CHAP,None
iscsid: in ksend_pdu_begin
iscsid: send PDU began for hdr 48 bytes and data 188 bytes
iscsid: thread b7f42494 schedule: delay 60 state 2
iscsid: send_pdu timer added 15 secs
iscsid: in kwritev
iscsid: wrote 48 bytes of PDU header
iscsid: in kwritev
iscsid: wrote 188 bytes of PDU data
iscsid: in ksend_pdu_end
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: send PDU finished for conn (handle 0xcabe9400)
iscsid: thread b7f62fc4 schedule: delay 0 state 2
iscsid: thread b7f62fc4 removed from actor_list
iscsid: thread b7f62fc4 removed from poll_list
iscsid: detected poll event 1
iscsid: in ctldev_handle
iscsid: in nl_read
iscsid: message real length is 160 bytes, recv_handle 0x806e808
iscsid: in nlpayload_read
iscsid: event_type: 1 adding to the tail: 0x0x806a800:0x0x806a800:0x0x806a818:0x0x806e800 elem 0x0x806a800 length 24
iscsid: thread b7f62fc4 schedule: delay 0 state 1
iscsid: event_type: 1 removing from the head: 0x0x806a800:0x0x806a818:0x0x806a818:0x0x806e800 elem 0x0x806a800 length 24
iscsid: in krecv_pdu_begin
iscsid: recv PDU began, pdu handle 0x0x806e808
iscsid: in kread
iscsid: read 48 bytes of PDU header
iscsid: read 48 PDU header bytes, opcode 0x23, dlength 39, data 0xb7f40474, max 8192
iscsid: in kread
iscsid: read 39 bytes of PDU data
iscsid: finished reading login PDU, 48 hdr, 0 ah, 39 data, 3 pad
iscsid: login current stage 0, next stage 1, transit 0x80
iscsid: >    TargetPortalGroupTag=1
iscsid: >    AuthMethod=None
iscsid: in krecv_pdu_end
iscsid: recv PDU finished for pdu handle 0x0x806e808
iscsid: thread b7f42494 delete: state 0
iscsid: deleting a scheduled/waiting thread!
iscsid: send_pdu timer removed
iscsid: login response status 0000
iscsid: sending login PDU with current stage 1, next stage 3, transit 0x80, isid 0x00023d000000
iscsid: >    HeaderDigest=None,CRC32C
iscsid: >    DataDigest=None
iscsid: >    MaxRecvDataSegmentLength=131072
iscsid: >    DefaultTime2Wait=0
iscsid: >    DefaultTime2Retain=0
iscsid: >    IFMarker=No
iscsid: >    OFMarker=No
iscsid: >    ErrorRecoveryLevel=0
iscsid: >    InitialR2T=Yes
iscsid: >    ImmediateData=No
iscsid: >    MaxBurstLength=16776192
iscsid: >    FirstBurstLength=262144
iscsid: >    MaxOutstandingR2T=1
iscsid: >    MaxConnections=1
iscsid: >    DataPDUInOrder=Yes
iscsid: >    DataSequenceInOrder=Yes
iscsid: >    X-com.cisco.PingTimeout=5
iscsid: >    X-com.cisco.sendAsyncText=No
iscsid: >    X-com.cisco.protocol=draft20
iscsid: in ksend_pdu_begin
iscsid: send PDU began for hdr 48 bytes and data 404 bytes
iscsid: thread b7f42494 schedule: delay 60 state 2
iscsid: send_pdu timer added 15 secs
iscsid: in kwritev
iscsid: wrote 48 bytes of PDU header
iscsid: in kwritev
iscsid: wrote 404 bytes of PDU data
iscsid: in ksend_pdu_end
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: send PDU finished for conn (handle 0xcabe9400)
iscsid: thread b7f62fc4 removed from actor_list
iscsid: detected poll event 1
iscsid: in ctldev_handle
iscsid: in nl_read
iscsid: message real length is 536 bytes, recv_handle 0x806e808
iscsid: in nlpayload_read
iscsid: event_type: 1 adding to the tail: 0x0x806a800:0x0x806a800:0x0x806a818:0x0x806e800 elem 0x0x806a800 length 24
iscsid: thread b7f62fc4 schedule: delay 0 state 2
iscsid: thread b7f42494 wait some more
iscsid: event_type: 1 removing from the head: 0x0x806a800:0x0x806a818:0x0x806a818:0x0x806e800 elem 0x0x806a800 length 24
iscsid: in krecv_pdu_begin
iscsid: recv PDU began, pdu handle 0x0x806e808
iscsid: in kread
iscsid: read 48 bytes of PDU header
iscsid: read 48 PDU header bytes, opcode 0x23, dlength 415, data 0xb7f40474, max 8192
iscsid: in kread
iscsid: read 415 bytes of PDU data
iscsid: finished reading login PDU, 48 hdr, 0 ah, 415 data, 3 pad
iscsid: login current stage 1, next stage 3, transit 0x80
iscsid: >    HeaderDigest=CRC32C
iscsid: >    DataDigest=None
iscsid: >    MaxRecvDataSegmentLength=512
iscsid: >    DefaultTime2Wait=2
iscsid: >    DefaultTime2Retain=0
iscsid: >    IFMarker=No
iscsid: >    OFMarker=No
iscsid: >    ErrorRecoveryLevel=0
iscsid: >    InitialR2T=Yes
iscsid: >    ImmediateData=No
iscsid: >    MaxBurstLength=512
iscsid: >    FirstBurstLength=512
iscsid: >    MaxOutstandingR2T=1
iscsid: >    MaxConnections=1
iscsid: >    DataPDUInOrder=Yes
iscsid: >    DataSequenceInOrder=Yes
iscsid: >    X-com.cisco.PingTimeout=NotUnderstood
iscsid: >    X-com.cisco.sendAsyncText=NotUnderstood
iscsid: >    X-com.cisco.protocol=NotUnderstood
iscsid: in krecv_pdu_end
iscsid: recv PDU finished for pdu handle 0x0x806e808
iscsid: thread b7f42494 delete: state 0
iscsid: deleting a scheduled/waiting thread!
iscsid: send_pdu timer removed
iscsid: login response status 0000
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 0 to 131072
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 1 to 512
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: can't set operational parameter 2 for conn with id = 0, retcode -12 (115)
iscsid: disconnecting conn 0xb7f403f4, fd 6
iscsid: in kdestroy_conn
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: in kdestroy_session
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: thread b7f62fc4 delete: state 2
iscsid: thread b7f62fc4 removed from actor_list

-----Original Message-----
From: Ming Zhang [mailto:mingz@xxxxxxxxxxx]
Sent: Thursday, August 11, 2005 10:38 AM
To: Nate Iverson
Cc: Linux-Iscsi-Users (E-mail)
Subject: RE:  open-iscsi-0.3rc7-383 fails to
connectup,but linux-iscsi-3.6.3 connects up fine.


On Thu, 2005-08-11 at 10:21 -0600, Nate Iverson wrote:
> That got me a little farther, but the login is still failing.
> 
> 
> [root@co-sanbs10 usr]# rm -rf /var/db/iscsi/*
> 
> [root@co-sanbs10 usr]# ./iscsiadm -m discovery --type sendtargets --portal 172.18.64.182:3260
> [0db902] 172.18.64.182:3260,1 iqn.2001-04.com.pillar:co-sanbs7.hdb
> 
> [root@co-sanbs10 usr]# ./iscsiadm -m node
> [0db902] 172.18.64.182:3260,1 iqn.2001-04.com.pillar:co-sanbs7.hdb
> 
> [root@co-sanbs10 usr]# ./iscsiadm -m node --record 0db902 --login
> iscsiadm: iscsid reported error (5 - encountered iSCSI login failure)

when u run iscsid, pls run with iscsid -f -d8 &, then it will have more
information.



> 
> The target side ethereal trace is showing 2 iSCSI logins from 172.18.64.185(co-sanbs10) to 172.18.64.182(co-sanbs7) and both complete with Login Response Success.
> 
> Thanks for your help,
> Nate
> 
> 
> -----Original Message-----
> From: Ming Zhang [mailto:mingz@xxxxxxxxxxx]
> Sent: Thursday, August 11, 2005 10:10 AM
> To: Nate Iverson
> Cc: Linux-Iscsi-Users (E-mail)
> Subject: Re:  open-iscsi-0.3rc7-383 fails to connect
> up,but linux-iscsi-3.6.3 connects up fine.
> 
> 
> i am not sure but they might use different db format.
> 
> so remove /var/db/iscsi/* and try again.
> 
> ming
> 
> On Thu, 2005-08-11 at 10:06 -0600, Nate Iverson wrote:
> > I'm using open-iscsi-0.3rc7-383 on fedora core 4, this is my iscsid.conf file
> > #
> > # Open-iSCSI default configuration.
> > # Could be located at /etc/iscsid.conf or ~/.iscsid.conf
> > #
> > node.active_cnx = 1
> > node.startup = manual
> > node.session.auth.username = iqn.2005-08.com.pillar:co-sanbs10.linux.FC4
> > node.session.auth.password = iscsipillardata
> > node.session.timeo.replacement_timeout = 0
> > node.session.err_timeo.abort_timeout = 10
> > node.session.err_timeo.reset_timeout = 30
> > node.session.iscsi.InitialR2T = Yes
> > node.session.iscsi.ImmediateData = No
> > node.session.iscsi.FirstBurstLength = 262144
> > node.session.iscsi.MaxBurstLength = 16776192
> > node.session.iscsi.DefaultTime2Wait = 0
> > node.session.iscsi.DefaultTime2Retain = 0
> > node.session.iscsi.MaxConnections = 1
> > node.cnx[0].iscsi.HeaderDigest = None
> > node.cnx[0].iscsi.DataDigest = None
> > node.cnx[0].iscsi.MaxRecvDataSegmentLength = 16776192
> > discovery.sendtargets.auth.authmethod = CHAP
> > discovery.sendtargets.auth.username = iqn.2005-08.com.pillar:co-sanbs10.linux.FC4
> > discovery.sendtargets.auth.password = iscsipillardata
> > 
> > 
> > Starting with no records.
> > [root@co-sanbs10 usr]# ./iscsiadm -m node
> > iscsiadm: no records found!
> > 
> > [root@co-sanbs10 usr]# ./iscsiadm -m discovery
> > iscsiadm: no records found!
> > 
> > Anyone know why this is failing to update?
> > [root@co-sanbs10 usr]# ./iscsiadm -m discovery --type sendtargets --portal 172.18.64.182:3260
> > iscsiadm: can not write record with hash-key '172.18.64.182:3260'
> > iscsiadm: can not update discovery record.
> > 
> > [root@co-sanbs10 usr]# ./iscsiadm -m node --op new --portal 172.18.64.182:3260
> > new iSCSI node record added: [086ade]
> > 
> > [root@co-sanbs10 usr]# ./iscsiadm -m discovery
> > iscsiadm: no records found!
> > 
> > [root@co-sanbs10 usr]# ./iscsiadm -m node
> > [086ade] 172.18.64.182:3260,1 <not specified>
> > 
> > [root@co-sanbs10 usr]# ./iscsiadm -m node --record 086ade --login
> > iscsiadm: iscsid reported error (5 - encountered iSCSI login failure)
> > 
> > I captured logins on the target side using ethereal.
> > The "-m discovery --type ..." login completes successfully, but doesn't create a record on the host.
> > The "-m node --op new ..." login is being rejected with a Target not found response. 
> > Any ideas on what I need to configure differently?
> > 
> > 
> > Target details from lk2.4 host connection
> > [root@co-sanbs3 root]# iscsi-ls
> > *******************************************************************************
> >         SFNet iSCSI Driver Version ... 3.6.3 (27-Jun-2005 )
> > *******************************************************************************
> > TARGET NAME             : iqn.2001-04.com.pillar:co-sanbs7.hdb
> > TARGET ALIAS            :
> > HOST NO                 : 2
> > BUS NO                  : 0
> > TARGET ID               : 0
> > TARGET ADDRESS          : 172.18.64.182:3260
> > SESSION STATUS          : ESTABLISHED AT Wed Aug 10 14:23:11 2005
> > NO. OF PORTALS          : 1
> > PORTAL ADDRESS 1        : 172.18.64.182:3260,1
> > SESSION ID              : ISID 00023d000001 TSIH 300
> > *******************************************************************************
> > 
> > For a target, I'm using Linux iSCSI Enterprise target 0.4.11 on 172.18.64.182.
> > 
> > This is what I have setup in ietd.conf on 172.18.64.182. I have also commented out all the IncomingUser and restarted the target, this should allow any iSCSI host to connect up to the target. co-sanbs10 fails with the same message as before.
> > 
> > Target iqn.2001-04.com.pillar:co-sanbs7.hdb
> >         # Users, who can access this target
> >         # (no users means anyone can access the target)
> >         IncomingUser iqn.1991-05.com.microsoft:co-sanbs3 iscsipillardata
> >         IncomingUser iqn.2005-08.com.pillar:co-sanbs3.linux.RH9 iscsipillardata
> >         IncomingUser iqn.2005-08.com.pillar:co-sanbs10.linux.FC4 iscsipillardata
> >         # Logical Unit definition
> >         # You must define one logical unit at least.
> >         # Block devices, regular files, LVM, and RAID can be offered
> >         # to the initiators as a block device.
> >         Lun 0 Path=/dev/hdb,Type=fileio
> >         # Alias name for this target
> >         Alias CO.SANBS7.HDB
> >         # various iSCSI parameters
> >         # (not all are used right now, see also iSCSI spec for details)
> >         MaxConnections          4
> >         InitialR2T              Yes
> >         ImmediateData           No
> >         MaxRecvDataSegmentLength 16777216
> >         MaxBurstLength          16777216
> >         FirstBurstLength        512 # ignored since Immed=0
> >         DefaultTime2Wait        2
> >         DefaultTime2Retain      20
> >         MaxOutstandingR2T       8
> >         DataPDUInOrder          Yes
> >         DataSequenceInOrder     Yes
> >         ErrorRecoveryLevel      0
> >         HeaderDigest            CRC32C,None
> >         DataDigest              CRC32C,None
> >         # various target parameters
> >         Wthreads                8
> > 
> > 
> > -------------------------------------------------------
> > SF.Net email is Sponsored by the Better Software Conference & EXPO
> > September 19-22, 2005 * San Francisco, CA * Development Lifecycle Practices
> > Agile & Plan-Driven Development * Managing Projects & Teams * Testing & QA
> > Security * Process Improvement & Measurement * http://www.sqe.com/bsce5sf
> > _______________________________________________
> > linux-iscsi-users mailing list
> > linux-iscsi-users@xxxxxxxxxxxxxxxxxxxxx
> > https://lists.sourceforge.net/lists/listinfo/linux-iscsi-users
> 



-------------------------------------------------------
SF.Net email is Sponsored by the Better Software Conference & EXPO
September 19-22, 2005 * San Francisco, CA * Development Lifecycle Practices
Agile & Plan-Driven Development * Managing Projects & Teams * Testing & QA
Security * Process Improvement & Measurement * http://www.sqe.com/bsce5sf
_______________________________________________
linux-iscsi-users mailing list
linux-iscsi-users@xxxxxxxxxxxxxxxxxxxxx
https://lists.sourceforge.net/lists/listinfo/linux-iscsi-users


[IP Storage]     [IETF]     [Linux SCSI]     [iSCSI Book]     [Linux Resources]     [Yosemite News]     [Photo]     [Home]     [IETF Announcements]     [IETF Discussion]     [SCSI Hardware]