I'm running a vanilla 2.6.18 kernel with openvz patched in (stab039)
and using iscsi as root.
I have iscsistart in the initrd and it boots fine, but after a brief
period of time, it disconnects from the server. (under a minute.)
Using revision 866 from berlios

When I run iscsistart for root, I get the following error:
1316:913:demo01:MgmtExec: 7-Dec-2007 18:25:24.160914:targetAttr.cc:
933:INFO:7.2.15:iSCSI session to target '10.1.13.21:3260, iqn.
2001-05.com.equallogic:0-8a0906-4c0233602-7fbfb1cde0a4759d-dl.ut1-eris-
base' from initiator '10.1.4.24:49498, iqn.1997-01.net.verio:ut1-eris'
was closed.
        iSCSI initiator connection failure.
        No response on connection for 6 seconds.

I've tried running iscsid and when I do, it disconnects the iscsi root
drive and tties to reconnect, but attempts to do so with CHAP turned
on. (It is not set on in the configuration on the client or equallogic
server)
These are the messages on the server:
1274:879:demo01:MgmtExec: 7-Dec-2007 18:06:16.490880:targetAttr.cc:
688:INFO:7.2.14:iSCSI login to target '10.1.13.21:3260, iqn.
2001-05.com.equallogic:0-8a0906-da3233602-1fbfb1cddf64759b-dl.ut1-eris-
base' from initiator '10.1.4.24:51567, iqn.1997-01.net.verio:ut1-eris'
successful, using Jumbo Frame length.
1275:880:demo01:MgmtExec: 7-Dec-2007 18:07:21.760881:targetAttr.cc:
933:INFO:7.2.15:iSCSI session to target '10.1.13.21:3260, iqn.
2001-05.com.equallogic:0-8a0906-da3233602-1fbfb1cddf64759b-dl.ut1-eris-
base' from initiator '10.1.4.24:51567, iqn.1997-01.net.verio:ut1-eris'
was closed.
        iSCSI initiator connection failure.
        Connection was closed by peer.
1276:881:demo01:MgmtExec: 7-Dec-2007 18:07:22.510882:targetAttr.cc:
498:ERROR:7.4.3:iSCSI login to target '10.1.13.21:3260, iqn.
2001-05.com.equallogic:0-8a0906-da3233602-1fbfb1cddf64759b-dl.ut1-eris-
base' from initiator '10.1.4.24:44170, iqn.1997-01.net.verio:ut1-luna'
failed for the following reason:
        Initiator attempted to authenticate but volume access control
not configured for CHAP.

The sequence I use to run this is:
modprobe iscsi_tcp
iscsistart -i iqn.1997-01.net.verio:ut1-eris -t iqn.
2001-05.com.equallogic:0-8a0906-da3233602-1fbfb1cddf64759b-dl.ut1-eris-
base -g 1 -a 10.1.13.20
iscsid -f -d 9

The iscsid output follows:
iscsid: transport class version 2.0-865. iscsid version 2.0-865
iscsid: in ctldev_open
iscsid: created NETLINK_ISCSI socket...
iscsid: InitiatorName==iqn.1997-01.net.verio:ut1-luna
iscsid: InitiatorAlias==iqn.1997-01.net.verio:ut1-luna
iscsid: InitiatorName=iqn.1997-01.net.verio:ut1-luna
iscsid: InitiatorAlias=iqn.1997-01.net.verio:ut1-luna
iscsid: in read_transports
iscsid: Adding new transport tcp
iscsid: found targetname iqn.2001-05.com.equallogic:
0-8a0906-4c0233602-7fbfb1cde0a4759d-dl.ut1-eris-base address
10.1.13.21 pers address 10.1.13.20 port 3260 pers port 3260 driver tcp
iface ipaddress 10.1.4.24 netdev default hwaddress default
iscsid: sync session [1][iqn.2001-05.com.equallogic:
0-8a0906-4c0233602-7fbfb1cde0a4759d-dl.ut1-eris-base,10.1.13.20.3260]
[default]

iscsid: in read_transports
iscsid: iface_for_each_iface found iface0
iscsid: looking for iface conf /etc/iscsi/ifaces/iface0
iscsid: updated 'iface.transport_name', 'tcp' => 'tcp'
iscsid: updated 'iface.net_ifacename', 'default' => 'eth0'
iscsid: rec read looking for config file /etc/iscsi/nodes/iqn.
2001-05.com.equallogic:0-8a0906-4c0233602-7fbfb1cde0a4759d-dl.ut1-eris-
base/10.1.13.20,3260.
iscsid: rec read looking for config file /etc/iscsi/nodes/iqn.
2001-05.com.equallogic:0-8a0906-4c0233602-7fbfb1cde0a4759d-dl.ut1-eris-
base/10.1.13.20,3260,1/default.
iscsid: Could not stat /etc/iscsi/nodes/iqn.2001-05.com.equallogic:
0-8a0906-4c0233602-7fbfb1cde0a4759d-dl.ut1-eris-base/10.1.13.20,3260,1/
default err 2.
iscsid: Could not read data from db. Using default and currently
negotiated values

iscsid: updating defaults from '/etc/iscsi/iscsid.conf'
iscsid: updated
'discovery.sendtargets.iscsi.MaxRecvDataSegmentLength', '32768' =>
'262144'
iscsid: updated 'node.startup', 'manual' => 'manual'
iscsid: updated 'node.session.timeo.replacement_timeout', '120' =>
'120'
iscsid: updated 'node.conn[0].timeo.login_timeout', '15' => '15'
iscsid: updated 'node.conn[0].timeo.logout_timeout', '15' => '15'
iscsid: updated 'node.conn[0].timeo.noop_out_interval', '10' => '10'
iscsid: updated 'node.conn[0].timeo.noop_out_timeout', '15' => '15'
iscsid: updated 'node.session.iscsi.InitialR2T', 'No' => 'No'
iscsid: updated 'node.session.iscsi.ImmediateData', 'Yes' => 'Yes'
iscsid: updated 'node.session.iscsi.FirstBurstLength', '262144' =>
'262144'
iscsid: updated 'node.session.iscsi.MaxBurstLength', '16776192' =>
'16776192'
iscsid: updated 'node.conn[0].iscsi.MaxRecvDataSegmentLength',
'131072' => '262144'
iscsid: match session [iqn.2001-05.com.equallogic:
0-8a0906-4c0233602-7fbfb1cde0a4759d-dl.ut1-eris-base,10.1.13.20,3260]
[ tcp,default,10.1.4.24]
iscsid: to [iqn.2001-05.com.equallogic:
0-8a0906-4c0233602-7fbfb1cde0a4759d-dl.ut1-eris-base,10.1.13.20,3260]
[ tcp,default,10.1.4.24]
iscsid: poll result 1
iscsid: in read_transports
iscsid: Adding new transport tcp
iscsid: Matched transport tcp

iscsid: Allocted session 0x8073e50
iscsid: thread 0807af14 schedule: delay 0 state 2
iscsid: authentication setup complete...
iscsid: resolved 10.1.13.20 to 10.1.13.20
iscsid: re-opening session 1 (reopen_cnt 1)
iscsid: thread 08079014 delete: state 0
iscsid: deleting a scheduled/waiting thread!
iscsid: thread 0807908c delete: state 0
iscsid: deleting a scheduled/waiting thread!
iscsid: thread 080790b4 delete: state 0
iscsid: deleting a scheduled/waiting thread!
iscsid: conn noop out timer 0x807908c stopped

iscsid: in kstop_conn
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: connection 1:0 is stopped for recovery
iscsid: set TCP recv window size to 524288, actually got 262142
iscsid: set TCP send window size to 524288, actually got 262142
iscsid: connecting to 10.1.13.20:3260
iscsid: event_type: 2 adding to the tail:
0x0x807af50:0x0x807af50:0x0x807af68:0x0x807ef50 elem 0x0x807af50
length 24
iscsid: thread 0807af14 schedule: delay 0 state 1
iscsid: thread 08079014 schedule: delay 60 state 2
iscsid: Started sync iSCSI session 1
iscsid: exec thread 0807af14 callback
iscsid: event_type: 2 removing from the head:
0x0x807af50:0x0x807af68:0x0x807af68:0x0x807ef50 elem 0x0x807af50
length 24
iscsid: connected local port 59074 to 10.1.13.20:3260
iscsid: thread 08079014 delete: state 0
iscsid: deleting a scheduled/waiting thread!
iscsid: in kbind_conn
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: bound iSCSI connection 1:0 to session 1
iscsid: sending login PDU with current stage 0, next stage 0, transit
0x0, isid 0x00023d010000 exp_statsn 16
iscsid: >    InitiatorName=iqn.1997-01.net.verio:ut1-luna
iscsid: >    InitiatorAlias=iqn.1997-01.net.verio:ut1-luna
iscsid: >    TargetName=iqn.2001-05.com.equallogic:
0-8a0906-4c0233602-7fbfb1cde0a4759d-dl.ut1-eris-base
iscsid: >    SessionType=Normal
iscsid: >    AuthMethod=CHAP
iscsid: in ksend_pdu_begin
iscsid: send PDU began for hdr 48 bytes and data 220 bytes
iscsid: thread 0807903c 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 220 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 1:0
iscsid: thread removed

iscsid: poll result 1
iscsid: in ctldev_handle
iscsid: in nl_read
iscsid: ctldev_handle got event type 101

iscsid: message real length is 152 bytes, recv_handle 0x807ef58
iscsid: in nlpayload_read
iscsid: event_type: 1 adding to the tail:
0x0x807af50:0x0x807af50:0x0x807af6c:0x0x807ef50 elem 0x0x807af50
length 28
iscsid: thread 0807af14 schedule: delay 0 state 2
iscsid: exec thread 0807af14 callback
iscsid: event_type: 1 removing from the head: 0x0x807af50:0x0x807af6c:
0x0x807af6c:0x0x807ef50 elem 0x0x807af50 length 28
iscsid: in krecv_pdu_begin
iscsid: recv PDU began, pdu handle 0x0x807ef58
iscsid: in kread
iscsid: read 48 bytes of PDU header
iscsid: read 48 PDU header bytes, opcode 0x23, dlength 30, data
0x8076c0c, max 8192
iscsid: in kread
iscsid: read 30 bytes of PDU data
iscsid: finished reading login PDU, 48 hdr, 0 ah, 30 data, 2 pad
iscsid: login current stage 0, next stage 0, transit 0x0
iscsid: >    TargetAddress=10.1.13.21:3260
iscsid: in krecv_pdu_end
iscsid: recv PDU finished for pdu handle 0x0x807ef58
iscsid: thread 0807903c delete: state 0
iscsid: deleting a scheduled/waiting thread!
iscsid: send_pdu timer removed
iscsid: login response status 0101
iscsid: Login authentication failed with target iqn.
2001-05.com.equallogic:0-8a0906-4c0233602-7fbfb1cde0a4759d-dl.ut1-eris-
base
iscsid: login_rsp ret (9)
iscsid: login redirect ...

iscsid: re-opening session 1 (reopen_cnt 1)
iscsid: thread 08079014 delete: state 2
iscsid: thread 0807908c delete: state 2
iscsid: thread 080790b4 delete: state 2
iscsid: conn noop out timer 0x807908c stopped

iscsid: in kstop_conn
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: connection 1:0 is stopped for recovery
iscsid: disconnecting conn 0x8076b8c, fd 6
iscsid: set TCP recv window size to 524288, actually got 262142
iscsid: set TCP send window size to 524288, actually got 262142
iscsid: connecting to 10.1.13.21:3260
iscsid: event_type: 2 adding to the tail:
0x0x807af50:0x0x807af50:0x0x807af68:0x0x807ef50 elem 0x0x807af50
length 24
iscsid: thread 0807af14 schedule: delay 0 state 2
iscsid: thread 08079014 schedule: delay 60 state 2
iscsid: thread removed

iscsid: thread 0807af14 removed from poll_list
iscsid: thread 08079014 wait some more
iscsid: exec thread 0807af14 callback
iscsid: event_type: 2 removing from the head:
0x0x807af50:0x0x807af68:0x0x807af68:0x0x807ef50 elem 0x0x807af50
length 24
iscsid: connected local port 58723 to 10.1.13.21:3260
iscsid: thread 08079014 delete: state 0
iscsid: deleting a scheduled/waiting thread!
iscsid: in kbind_conn
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: bound iSCSI connection 1:0 to session 1
iscsid: sending login PDU with current stage 0, next stage 0, transit
0x0, isid 0x00023d010000 exp_statsn 16
iscsid: >    InitiatorName=iqn.1997-01.net.verio:ut1-luna
iscsid: >    InitiatorAlias=iqn.1997-01.net.verio:ut1-luna
iscsid: >    TargetName=iqn.2001-05.com.equallogic:
0-8a0906-4c0233602-7fbfb1cde0a4759d-dl.ut1-eris-base
iscsid: >    SessionType=Normal
iscsid: >    AuthMethod=CHAP
iscsid: in ksend_pdu_begin
iscsid: send PDU began for hdr 48 bytes and data 220 bytes
iscsid: thread 0807903c 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 220 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 1:0
iscsid: thread removed

iscsid: poll result 1
iscsid: in ctldev_handle
iscsid: in nl_read
iscsid: ctldev_handle got event type 101

iscsid: message real length is 120 bytes, recv_handle 0x807ef58
iscsid: in nlpayload_read
iscsid: event_type: 1 adding to the tail:
0x0x807af50:0x0x807af50:0x0x807af6c:0x0x807ef50 elem 0x0x807af50
length 28
iscsid: thread 0807af14 schedule: delay 0 state 2
iscsid: exec thread 0807af14 callback
iscsid: event_type: 1 removing from the head: 0x0x807af50:0x0x807af6c:
0x0x807af6c:0x0x807ef50 elem 0x0x807af50 length 28
iscsid: in krecv_pdu_begin
iscsid: recv PDU began, pdu handle 0x0x807ef58
iscsid: in kread
iscsid: read 48 bytes of PDU header
iscsid: read 48 PDU header bytes, opcode 0x23, dlength 0, data
0x8076c0c, max 8192
iscsid: in krecv_pdu_end
iscsid: recv PDU finished for pdu handle 0x0x807ef58
iscsid: thread 0807903c delete: state 0
iscsid: deleting a scheduled/waiting thread!
iscsid: send_pdu timer removed
iscsid: login response status 0200
iscsid: login_rsp ret (0)
iscsid: thread 0807908c delete: state 2
iscsid: thread 080790b4 delete: state 2
iscsid: conn noop out timer 0x807908c stopped

iscsid: thread 08079014 delete: state 2
iscsid: in kdestroy_conn
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: disconnecting conn 0x8076b8c, fd 6
iscsid: in kdestroy_session
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: mgmt_ipc_write_rsp: rsp to fd 5
iscsid: thread 08079014 delete: state 2
iscsid: destroying session

iscsid: Releasing session 0x8073e50
iscsid: thread 0807af14 delete: state 2
iscsid: thread removed

iscsid: iscsid_exit
iscsid: in ctldev_close
iscsid: reaped pid 17144, reap_count now 0


/etc/iscsi/iscsid.conf follows:
node.active_cnx = 1
node.startup = manual
node.session.timeo.replacement_timeout = 120
node.conn[0].timeo.login_timeout = 15
node.conn[0].timeo.logout_timeout = 15
node.conn[0].timeo.noop_out_interval = 10
node.conn[0].timeo.noop_out_timeout = 15
node.session.iscsi.InitialR2T = No
node.session.iscsi.ImmediateData = Yes
node.session.iscsi.FirstBurstLength = 262144
node.session.iscsi.MaxBurstLength = 16776192
node.conn[0].iscsi.MaxRecvDataSegmentLength = 262144
discovery.sendtargets.iscsi.MaxRecvDataSegmentLength = 262144


Any ideas would be greatly appreciated.

Thanks, John.
--~--~---------~--~----~------------~-------~--~----~
You received this message because you are subscribed to the Google Groups 
"open-iscsi" group.
To post to this group, send email to open-iscsi@googlegroups.com
To unsubscribe from this group, send email to [EMAIL PROTECTED]
For more options, visit this group at http://groups.google.com/group/open-iscsi
-~----------~----~----~----~------~----~------~--~---

Reply via email to