--- Begin Message ---
Package: open-iscsi
Version: 2.0.871.3-6
Severity: normal
I have root on iscsi, so the connection already exists by the time
iscsid starts. Regardless of the value of
node.session.timeo.replacement_timeout in my /etc/iscsi/iscsid.conf,
iscsid prints:
iscsid: Cannot set replacement_timeout to zero. Setting 120 seconds
and I see:
# cat /sys/class/iscsi_session/session1/recovery_tmo
120
If I change it manually, and restart iscsid, it still gets reset:
# echo 31536000 > /sys/class/iscsi_session/session1/recovery_tmo
# cat /sys/class/iscsi_session/session1/recovery_tmo
31536000
# killall iscsid
# iscsid
# cat /sys/class/iscsi_session/session1/recovery_tmo
120
Which makes things very unhappy if the network ever gets disconnected
for a few minutes.
My guess is that iscsid is pulling the connection parameters from /sys
and leaving replacement_timeout as zero in its own data structures,
whereas it should really be filling things in with the default values
specified in iscsid.conf.
I'll attach a (slightly sanitized) log of
iscsid -f -d 8
-jim
-- System Information:
Debian Release: 6.0
APT prefers testing
APT policy: (300, 'testing'), (50, 'unstable'), (1, 'experimental')
Architecture: amd64 (x86_64)
Kernel: Linux 2.6.39-2-amd64 (SMP w/2 CPU cores)
Locale: LANG=POSIX, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/bash
Versions of packages open-iscsi depends on:
ii libc6 2.11.2-10 Embedded GNU C Library: Shared lib
ii udev 164-3 /dev/ and hotplug management daemo
open-iscsi recommends no packages.
open-iscsi suggests no packages.
-- Configuration Files:
/etc/iscsi/initiatorname.iscsi [Errno 13] Permission denied:
u'/etc/iscsi/initiatorname.iscsi'
/etc/iscsi/iscsid.conf changed:
node.startup = manual
node.session.timeo.replacement_timeout = 31536000
node.conn[0].timeo.login_timeout = 15
node.conn[0].timeo.logout_timeout = 15
node.conn[0].timeo.noop_out_interval = 0
node.conn[0].timeo.noop_out_timeout = 0
node.session.err_timeo.abort_timeout = 15
node.session.err_timeo.lu_reset_timeout = 20
node.session.initial_login_retry_max = 8
node.session.cmds_max = 128
node.session.queue_depth = 32
node.session.xmit_thread_priority = -20
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 = 32768
node.session.iscsi.FastAbort = Yes
-- no debconf information
iscsid: sysfs_init: sysfs_path='/sys'
iscsid: sysfs_attr_get_value: open '/module/scsi_transport_iscsi'/'version'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/module/scsi_transport_iscsi/version'
iscsid: sysfs_attr_get_value: add to cache '/sys/module/scsi_transport_iscsi/version'
iscsid: sysfs_attr_get_value: cache '/sys/module/scsi_transport_iscsi/version' with attribute value '2.0-870'
iscsid: transport class version 2.0-870. iscsid version 2.0-871
iscsid: in ctldev_open
iscsid: created NETLINK_ISCSI socket...
iscsid: InitiatorName==iqn.1993-08.org.debian:01:1bc68d43ac4
iscsid: InitiatorName=iqn.1993-08.org.debian:01:1bc68d43ac4
iscsid: InitiatorAlias=client
iscsid: sysfs_attr_get_value: open '/class/iscsi_session/session1'/'targetname'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_session/session1/targetname'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_session/session1/targetname'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_session/session1/targetname' with attribute value 'iqn.2010-01.com.example:server.client'
iscsid: sysfs_attr_get_value: open '/class/iscsi_session/session1'/'tpgt'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_session/session1/tpgt'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_session/session1/tpgt'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_session/session1/tpgt' with attribute value '1'
iscsid: sysfs_attr_get_value: open '/class/iscsi_connection/connection1:0'/'persistent_address'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_connection/connection1:0/persistent_address'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_connection/connection1:0/persistent_address'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_connection/connection1:0/persistent_address' with attribute value '10.0.1.1'
iscsid: sysfs_attr_get_value: open '/class/iscsi_connection/connection1:0'/'address'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_connection/connection1:0/address'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_connection/connection1:0/address'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_connection/connection1:0/address' with attribute value '10.0.1.1'
iscsid: sysfs_attr_get_value: open '/class/iscsi_connection/connection1:0'/'persistent_port'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_connection/connection1:0/persistent_port'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_connection/connection1:0/persistent_port'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_connection/connection1:0/persistent_port' with attribute value '3260'
iscsid: sysfs_attr_get_value: open '/class/iscsi_connection/connection1:0'/'port'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_connection/connection1:0/port'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_connection/connection1:0/port'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_connection/connection1:0/port' with attribute value '3260'
iscsid: sysfs_device_get: open '/class/iscsi_session/session1'
iscsid: sysfs_resolve_link: path link '/class/iscsi_session/session1' points to '../../devices/platform/host8/session1/iscsi_session/session1'
iscsid: sysfs_resolve_link: base '/class/iscsi_session/session1', tail 'devices/platform/host8/session1/iscsi_session/session1', back 2
iscsid: sysfs_resolve_link: after moving back ''
iscsid: sysfs_device_get: new uncached device '/devices/platform/host8/session1/iscsi_session/session1'
iscsid: sysfs_device_set_values: kernel='session1'
iscsid: sysfs_device_set_values: kernel_number='1'
iscsid: sysfs_device_get: subsystem link '/sys/devices/platform/host8/session1/iscsi_session/session1/subsystem' points to '../../../../../../class/iscsi_session'
iscsid: sysfs_device_get: add to cache 'devpath=/devices/platform/host8/session1/iscsi_session/session1', subsystem='iscsi_session', driver=''
iscsid: sysfs_device_get_parent: open '/devices/platform/host8/session1/iscsi_session/session1'
iscsid: sysfs_device_get_parent: '/devices/platform/host8/session1/iscsi_session/session1'
iscsid: sysfs_device_get: open '/devices/platform/host8/session1/iscsi_session'
iscsid: sysfs_device_get: new uncached device '/devices/platform/host8/session1/iscsi_session'
iscsid: sysfs_device_set_values: kernel='iscsi_session'
iscsid: sysfs_device_set_values: kernel_number=''
iscsid: Max file limits 1024 1024
che 'devpath=/devices/platform/host8/session1/iscsi_session', subsystem='', driver=''
iscsid: sysfs_device_get_parent: open '/devices/platform/host8/session1/iscsi_session'
iscsid: sysfs_device_get_parent: '/devices/platform/host8/session1/iscsi_session'
iscsid: sysfs_device_get: open '/devices/platform/host8/session1'
iscsid: sysfs_device_get: new uncached device '/devices/platform/host8/session1'
iscsid: sysfs_device_set_values: kernel='session1'
iscsid: sysfs_device_set_values: kernel_number='1'
iscsid: sysfs_device_get: add to cache 'devpath=/devices/platform/host8/session1', subsystem='', driver=''
iscsid: sysfs_device_get_parent: open '/devices/platform/host8/session1'
iscsid: sysfs_device_get_parent: '/devices/platform/host8/session1'
iscsid: sysfs_device_get: open '/devices/platform/host8'
iscsid: sysfs_device_get: new uncached device '/devices/platform/host8'
iscsid: sysfs_device_set_values: kernel='host8'
iscsid: sysfs_device_set_values: kernel_number='8'
iscsid: sysfs_device_get: subsystem link '/sys/devices/platform/host8/subsystem' points to '../../../bus/scsi'
iscsid: sysfs_device_get: add to cache 'devpath=/devices/platform/host8', subsystem='scsi', driver=''
iscsid: sysfs_attr_get_value: open '/class/scsi_host/host8'/'proc_name'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/scsi_host/host8/proc_name'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/scsi_host/host8/proc_name'
iscsid: sysfs_attr_get_value: cache '/sys/class/scsi_host/host8/proc_name' with attribute value 'iscsi_tcp'
iscsid: in read_transports
iscsid: Adding new transport iser
iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/iser'/'handle'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_transport/iser/handle'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_transport/iser/handle'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_transport/iser/handle' with attribute value '18446744072104849424'
iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/iser'/'caps'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_transport/iser/caps'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_transport/iser/caps'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_transport/iser/caps' with attribute value '0x9'
iscsid: Adding new transport tcp
iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/tcp'/'handle'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_transport/tcp/handle'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_transport/tcp/handle'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_transport/tcp/handle' with attribute value '18446744072099454992'
iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/tcp'/'caps'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_transport/tcp/caps'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_transport/tcp/caps'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_transport/tcp/caps' with attribute value '0x39'
iscsid: sysfs_attr_get_value: open '/class/iscsi_host/host8'/'hwaddress'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_host/host8/hwaddress'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_host/host8/hwaddress'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_host/host8/hwaddress' with attribute value 'default'
iscsid: sysfs_attr_get_value: open '/class/iscsi_host/host8'/'ipaddress'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_host/host8/ipaddress'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_host/host8/ipaddress'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_host/host8/ipaddress' with attribute value '10.0.1.2'
iscsid: sysfs_attr_get_value: open '/class/iscsi_host/host8'/'netdev'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_host/host8/netdev'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_host/host8/netdev'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_host/host8/netdev' with attribute value 'default'
iscsid: sysfs_attr_get_value: open '/class/iscsi_session/session1'/'initiatorname'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_session/session1/initiatorname'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_session/session1/initiatorname'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_session/session1/initiatorname' with attribute value 'iqn.1993-08.org.debian:01:1bc68d43ac4'
iscsid: sysfs_attr_get_value: open '/class/iscsi_session/session1'/'ifacename'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_session/session1/ifacename'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_session/session1/ifacename'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_session/session1/ifacename' with attribute value 'default'
iscsid: found targetname iqn.2010-01.com.example:server.client address 10.0.1.1 pers address 10.0.1.1 port 3260 pers port 3260 driver tcp iface name default ipaddress 10.0.1.2 netdev default hwaddress default iname iqn.1993-08.org.debian:01:1bc68d43ac4
iscsid: sync session [1][iqn.2010-01.com.example:server.client,10.0.1.1.3260][default]
iscsid: sysfs_device_get: open '/class/iscsi_session/session1'
iscsid: sysfs_resolve_link: path link '/class/iscsi_session/session1' points to '../../devices/platform/host8/session1/iscsi_session/session1'
iscsid: sysfs_resolve_link: base '/class/iscsi_session/session1', tail 'devices/platform/host8/session1/iscsi_session/session1', back 2
iscsid: sysfs_resolve_link: after moving back ''
iscsid: sysfs_device_get: found in cache '/devices/platform/host8/session1/iscsi_session/session1'
iscsid: sysfs_device_get_parent: open '/devices/platform/host8/session1/iscsi_session/session1'
iscsid: sysfs_device_get_parent: open '/devices/platform/host8/session1/iscsi_session'
iscsid: sysfs_device_get_parent: open '/devices/platform/host8/session1'
iscsid: sysfs_attr_get_value: open '/class/scsi_host/host8'/'proc_name'
iscsid: sysfs_attr_get_value: found in cache '/class/scsi_host/host8/proc_name'
iscsid: in read_transports
iscsid: Updating transport iser
iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/iser'/'handle'
iscsid: sysfs_attr_get_value: found in cache '/class/iscsi_transport/iser/handle'
iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/iser'/'caps'
iscsid: sysfs_attr_get_value: found in cache '/class/iscsi_transport/iser/caps'
iscsid: Updating transport tcp
iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/tcp'/'handle'
iscsid: sysfs_attr_get_value: found in cache '/class/iscsi_transport/tcp/handle'
iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/tcp'/'caps'
iscsid: sysfs_attr_get_value: found in cache '/class/iscsi_transport/tcp/caps'
iscsid: Matched transport tcp
iscsid: rec read looking for config file /etc/iscsi/nodes/iqn.2010-01.com.example:server.client/10.0.1.1,3260.
iscsid: rec read looking for config file /etc/iscsi/nodes/iqn.2010-01.com.example:server.client/10.0.1.1,3260,1/default.
iscsid: updated 'node.name', '' => 'iqn.2010-01.com.example:server.client'
iscsid: updated 'node.tpgt', '-1' => '1'
iscsid: updated 'node.startup', 'manual' => 'manual'
iscsid: updated 'iface.hwaddress', '' => 'default'
iscsid: updated 'iface.iscsi_ifacename', 'default' => 'default'
iscsid: updated 'iface.net_ifacename', '' => 'default'
iscsid: updated 'iface.transport_name', 'tcp' => 'tcp'
iscsid: updated 'node.discovery_address', '' => '10.0.1.1'
iscsid: updated 'node.discovery_port', '0' => '3260'
iscsid: updated 'node.discovery_type', 'static' => 'send_targets'
iscsid: updated 'node.session.initial_cmdsn', '0' => '0'
iscsid: updated 'node.session.initial_login_retry_max', '4' => '8'
iscsid: updated 'node.session.cmds_max', '128' => '128'
iscsid: updated 'node.session.queue_depth', '32' => '32'
iscsid: updated 'node.session.auth.authmethod', 'None' => 'None'
iscsid: updated 'node.session.timeo.replacement_timeout', '120' => '0'
iscsid: updated 'node.session.err_timeo.abort_timeout', '15' => '15'
iscsid: updated 'node.session.err_timeo.lu_reset_timeout', '30' => '20'
iscsid: updated 'node.session.err_timeo.host_reset_timeout', '60' => '60'
iscsid: updated 'node.session.iscsi.FastAbort', 'Yes' => 'Yes'
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.session.iscsi.DefaultTime2Retain', '0' => '0'
iscsid: updated 'node.session.iscsi.DefaultTime2Wait', '2' => '2'
iscsid: updated 'node.session.iscsi.MaxConnections', '1' => '1'
iscsid: updated 'node.session.iscsi.MaxOutstandingR2T', '1' => '1'
iscsid: updated 'node.session.iscsi.ERL', '0' => '0'
iscsid: updated 'node.conn[0].address', '' => '10.0.1.1'
iscsid: updated 'node.conn[0].port', '3260' => '3260'
iscsid: updated 'node.conn[0].startup', 'manual' => 'manual'
iscsid: updated 'node.conn[0].tcp.window_size', '524288' => '524288'
iscsid: updated 'node.conn[0].tcp.type_of_service', '0' => '0'
iscsid: updated 'node.conn[0].timeo.logout_timeout', '15' => '15'
iscsid: updated 'node.conn[0].timeo.login_timeout', '30' => '15'
iscsid: updated 'node.conn[0].timeo.auth_timeout', '45' => '45'
iscsid: updated 'node.conn[0].timeo.noop_out_interval', '5' => '0'
iscsid: updated 'node.conn[0].timeo.noop_out_timeout', '5' => '0'
iscsid: updated 'node.conn[0].iscsi.MaxRecvDataSegmentLength', '262144' => '131072'
iscsid: updated 'node.conn[0].iscsi.HeaderDigest', 'None' => 'None'
iscsid: updated 'node.conn[0].iscsi.DataDigest', 'None' => 'None'
iscsid: updated 'node.conn[0].iscsi.IFMarker', 'No' => 'No'
iscsid: updated 'node.conn[0].iscsi.OFMarker', 'No' => 'No'
iscsid: updating defaults from '/etc/iscsi/iscsid.conf'
iscsid: updated 'discovery.sendtargets.iscsi.MaxRecvDataSegmentLength', '32768' => '32768'
iscsid: updated 'node.startup', 'manual' => 'manual'
iscsid: updated 'node.session.timeo.replacement_timeout', '120' => '31536000'
iscsid: updated 'node.conn[0].timeo.login_timeout', '30' => '15'
iscsid: updated 'node.conn[0].timeo.logout_timeout', '15' => '15'
iscsid: updated 'node.conn[0].timeo.noop_out_interval', '5' => '0'
iscsid: updated 'node.conn[0].timeo.noop_out_timeout', '5' => '0'
iscsid: updated 'node.session.err_timeo.abort_timeout', '15' => '15'
iscsid: updated 'node.session.err_timeo.lu_reset_timeout', '30' => '20'
iscsid: updated 'node.session.initial_login_retry_max', '4' => '8'
iscsid: updated 'node.session.cmds_max', '128' => '128'
iscsid: updated 'node.session.queue_depth', '32' => '32'
iscsid: updated 'node.session.xmit_thread_priority', '-20' => '-20'
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', '262144' => '262144'
iscsid: updated 'node.session.iscsi.FastAbort', 'Yes' => 'Yes'
iscsid: sysfs_attr_get_value: open '/class/iscsi_session/session1'/'data_pdu_in_order'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_session/session1/data_pdu_in_order'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_session/session1/data_pdu_in_order'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_session/session1/data_pdu_in_order' with attribute value '1'
iscsid: sysfs_attr_get_value: open '/class/iscsi_session/session1'/'data_seq_in_order'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_session/session1/data_seq_in_order'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_session/session1/data_seq_in_order'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_session/session1/data_seq_in_order' with attribute value '1'
iscsid: sysfs_attr_get_value: open '/class/iscsi_session/session1'/'erl'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_session/session1/erl'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_session/session1/erl'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_session/session1/erl' with attribute value '0'
iscsid: sysfs_attr_get_value: open '/class/iscsi_session/session1'/'first_burst_len'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_session/session1/first_burst_len'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_session/session1/first_burst_len'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_session/session1/first_burst_len' with attribute value '65536'
iscsid: sysfs_attr_get_value: open '/class/iscsi_session/session1'/'max_burst_len'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_session/session1/max_burst_len'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_session/session1/max_burst_len'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_session/session1/max_burst_len' with attribute value '262144'
iscsid: sysfs_attr_get_value: open '/class/iscsi_session/session1'/'immediate_data'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_session/session1/immediate_data'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_session/session1/immediate_data'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_session/session1/immediate_data' with attribute value '1'
iscsid: sysfs_attr_get_value: open '/class/iscsi_session/session1'/'initial_r2t'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_session/session1/initial_r2t'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_session/session1/initial_r2t'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_session/session1/initial_r2t' with attribute value '1'
iscsid: sysfs_attr_get_value: open '/class/iscsi_session/session1'/'max_outstanding_r2t'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_session/session1/max_outstanding_r2t'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_session/session1/max_outstanding_r2t'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_session/session1/max_outstanding_r2t' with attribute value '1'
iscsid: sysfs_attr_get_value: open '/class/iscsi_connection/connection1:0'/'data_digest'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_connection/connection1:0/data_digest'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_connection/connection1:0/data_digest'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_connection/connection1:0/data_digest' with attribute value '0'
iscsid: sysfs_attr_get_value: open '/class/iscsi_connection/connection1:0'/'header_digest'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_connection/connection1:0/header_digest'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_connection/connection1:0/header_digest'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_connection/connection1:0/header_digest' with attribute value '0'
iscsid: sysfs_attr_get_value: open '/class/iscsi_connection/connection1:0'/'max_xmit_dlength'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_connection/connection1:0/max_xmit_dlength'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_connection/connection1:0/max_xmit_dlength'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_connection/connection1:0/max_xmit_dlength' with attribute value '8192'
iscsid: sysfs_attr_get_value: open '/class/iscsi_connection/connection1:0'/'max_recv_dlength'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_connection/connection1:0/max_recv_dlength'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_connection/connection1:0/max_recv_dlength'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_connection/connection1:0/max_recv_dlength' with attribute value '131072'
iscsid: sysfs_attr_get_value: open '/class/iscsi_session/session1'/'username'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_session/session1/username'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_session/session1/username'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_session/session1/username' with attribute value '(null)'
iscsid: sysfs_attr_get_value: open '/class/iscsi_session/session1'/'username_in'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_session/session1/username_in'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_session/session1/username_in'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_session/session1/username_in' with attribute value '(null)'
iscsid: sysfs_attr_get_value: open '/class/iscsi_session/session1'/'password'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_session/session1/password'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_session/session1/password'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_session/session1/password' with attribute value '(null)'
iscsid: sysfs_attr_get_value: open '/class/iscsi_session/session1'/'password_in'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_session/session1/password_in'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_session/session1/password_in'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_session/session1/password_in' with attribute value '(null)'
iscsid: match session [iqn.2010-01.com.example:server.client,10.0.1.1,3260][default tcp,default,]
iscsid: to [iqn.2010-01.com.example:server.client,10.0.1.1,3260][default tcp,default,]
iscsid: poll result 1
iscsid: in read_transports
iscsid: Adding new transport iser
iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/iser'/'handle'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_transport/iser/handle'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_transport/iser/handle'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_transport/iser/handle' with attribute value '18446744072104849424'
iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/iser'/'caps'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_transport/iser/caps'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_transport/iser/caps'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_transport/iser/caps' with attribute value '0x9'
iscsid: Adding new transport tcp
iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/tcp'/'handle'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_transport/tcp/handle'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_transport/tcp/handle'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_transport/tcp/handle' with attribute value '18446744072099454992'
iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/tcp'/'caps'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_transport/tcp/caps'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_transport/tcp/caps'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_transport/tcp/caps' with attribute value '0x39'
iscsid: Matched transport tcp
iscsid: Allocted session 0xd85990
iscsid: Cannot set replacement_timeout to zero. Setting 120 seconds
iscsid: no authentication configured...
iscsid: sysfs_device_get: open '/class/iscsi_session/session1'
iscsid: sysfs_resolve_link: path link '/class/iscsi_session/session1' points to '../../devices/platform/host8/session1/iscsi_session/session1'
iscsid: sysfs_resolve_link: base '/class/iscsi_session/session1', tail 'devices/platform/host8/session1/iscsi_session/session1', back 2
iscsid: sysfs_resolve_link: after moving back ''
iscsid: sysfs_device_get: new uncached device '/devices/platform/host8/session1/iscsi_session/session1'
iscsid: sysfs_device_set_values: kernel='session1'
iscsid: sysfs_device_set_values: kernel_number='1'
iscsid: sysfs_device_get: subsystem link '/sys/devices/platform/host8/session1/iscsi_session/session1/subsystem' points to '../../../../../../class/iscsi_session'
iscsid: sysfs_device_get: add to cache 'devpath=/devices/platform/host8/session1/iscsi_session/session1', subsystem='iscsi_session', driver=''
iscsid: sysfs_device_get_parent: open '/devices/platform/host8/session1/iscsi_session/session1'
iscsid: sysfs_device_get_parent: '/devices/platform/host8/session1/iscsi_session/session1'
iscsid: sysfs_device_get: open '/devices/platform/host8/session1/iscsi_session'
iscsid: sysfs_device_get: new uncached device '/devices/platform/host8/session1/iscsi_session'
iscsid: sysfs_device_set_values: kernel='iscsi_session'
iscsid: sysfs_device_set_values: kernel_number=''
iscsid: sysfs_device_get: add to cache 'devpath=/devices/platform/host8/session1/iscsi_session', subsystem='', driver=''
iscsid: sysfs_device_get_parent: open '/devices/platform/host8/session1/iscsi_session'
iscsid: sysfs_device_get_parent: '/devices/platform/host8/session1/iscsi_session'
iscsid: sysfs_device_get: open '/devices/platform/host8/session1'
iscsid: sysfs_device_get: new uncached device '/devices/platform/host8/session1'
iscsid: sysfs_device_set_values: kernel='session1'
iscsid: sysfs_device_set_values: kernel_number='1'
iscsid: sysfs_device_get: add to cache 'devpath=/devices/platform/host8/session1', subsystem='', driver=''
iscsid: sysfs_device_get_parent: open '/devices/platform/host8/session1'
iscsid: sysfs_device_get_parent: '/devices/platform/host8/session1'
iscsid: sysfs_device_get: open '/devices/platform/host8'
iscsid: sysfs_device_get: new uncached device '/devices/platform/host8'
iscsid: sysfs_device_set_values: kernel='host8'
iscsid: sysfs_device_set_values: kernel_number='8'
iscsid: sysfs_device_get: subsystem link '/sys/devices/platform/host8/subsystem' points to '../../../bus/scsi'
iscsid: sysfs_device_get: add to cache 'devpath=/devices/platform/host8', subsystem='scsi', driver=''
iscsid: resolved 10.0.1.1 to 10.0.1.1
iscsid: re-opening session 1 (reopen_cnt 0)
iscsid: thread 00d8ae88 delete: state 0
iscsid: thread 00d8aec0 delete: state 0
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: Waiting 2 seconds before trying to reconnect.
iscsid: Requeue reopen attempt in 2 secs
iscsid: thread 00d8ae88 delete: state 3
iscsid: thread 0xd8ae88 schedule: delay 8 state 3
iscsid: Started sync iSCSI session 1
iscsid: thread 00d8ae88 wait some more
iscsid: thread 00d8ae88 wait some more
iscsid: thread 00d8ae88 was scheduled at 0:8, curtime 8 q_forw 0x6304a0 &pend_list 0x6304a0
iscsid: thread 00d8ae88 now in actor_list
iscsid: exec thread 00d8ae88 callback
iscsid: iscsi_login_eh
iscsid: login failed STATE_XPT_WAIT/R_STAGE_SESSION_REOPEN 0
iscsid: re-opening session 1 (reopen_cnt 0)
iscsid: thread 00d8ae88 delete: state 3
iscsid: thread 00d8aec0 delete: state 3
iscsid: get conn context 0xd8e870
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.0.1.1:3260
iscsid: sched conn context 0xd8e870 event 2, tmo 0
iscsid: thread 0xd8e870 schedule: delay 0 state 3
iscsid: Setting login timer 0xd8ae88 timeout 15
iscsid: thread 0xd8ae88 schedule: delay 60 state 3
iscsid: thread removed
iscsid: thread 00d8e870 removed from poll_list
iscsid: exec thread 00d8e870 callback
iscsid: put conn context 0xd8e870
iscsid: connected local port 47112 to 10.0.1.1:3260
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: sysfs_attr_get_value: open '/class/iscsi_connection/connection1:0'/'exp_statsn'
iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_connection/connection1:0/exp_statsn'
iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_connection/connection1:0/exp_statsn'
iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_connection/connection1:0/exp_statsn' with attribute value '70'
iscsid: sending login PDU with current stage 1, next stage 3, transit 0x80, isid 0x00023d010000 exp_statsn 70
iscsid: > InitiatorName=iqn.1993-08.org.debian:01:1bc68d43ac4
iscsid: > InitiatorAlias=client
iscsid: > TargetName=iqn.2010-01.com.example:server.client
iscsid: > SessionType=Normal
iscsid: > HeaderDigest=None
iscsid: > DataDigest=None
iscsid: > DefaultTime2Wait=2
iscsid: > DefaultTime2Retain=0
iscsid: > IFMarker=No
iscsid: > OFMarker=No
iscsid: > ErrorRecoveryLevel=0
iscsid: > InitialR2T=No
iscsid: > ImmediateData=Yes
iscsid: > MaxBurstLength=16776192
iscsid: > FirstBurstLength=262144
iscsid: > MaxOutstandingR2T=1
iscsid: > MaxConnections=1
iscsid: > DataPDUInOrder=Yes
iscsid: > DataSequenceInOrder=Yes
iscsid: > MaxRecvDataSegmentLength=131072
iscsid: in ksend_pdu_begin
iscsid: send PDU began for hdr 48 bytes and data 456 bytes
iscsid: in kwritev
iscsid: wrote 48 bytes of PDU header
iscsid: in kwritev
iscsid: wrote 456 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: get conn context 0xd8e870
iscsid: message real length is 420 bytes, recv_handle 0xd8e8c0
iscsid: in nlpayload_read
iscsid: sched conn context 0xd8e870 event 1, tmo 0
iscsid: thread 0xd8e870 schedule: delay 0 state 3
iscsid: exec thread 00d8e870 callback
iscsid: in krecv_pdu_begin
iscsid: recv PDU began, pdu handle 0x0xd8e8f8
iscsid: in kread
iscsid: read 48 bytes of PDU header
iscsid: read 48 PDU header bytes, opcode 0x23, dlength 300, data 0xd88a68, max 8192
iscsid: in kread
iscsid: read 300 bytes of PDU data
iscsid: finished reading login PDU, 48 hdr, 0 ah, 300 data, 0 pad
iscsid: login current stage 1, next stage 3, transit 0x80
iscsid: > TargetPortalGroupTag=1
iscsid: > HeaderDigest=None
iscsid: > DataDigest=None
iscsid: > DefaultTime2Wait=2
iscsid: > DefaultTime2Retain=0
iscsid: > IFMarker=No
iscsid: > OFMarker=No
iscsid: > ErrorRecoveryLevel=0
iscsid: > InitialR2T=Yes
iscsid: > ImmediateData=Yes
iscsid: > MaxBurstLength=262144
iscsid: > FirstBurstLength=65536
iscsid: > MaxOutstandingR2T=1
iscsid: > MaxConnections=1
iscsid: > DataPDUInOrder=Yes
iscsid: > DataSequenceInOrder=Yes
iscsid: in krecv_pdu_end
iscsid: recv PDU finished for pdu handle 0x0xd8e8f8
iscsid: put conn context 0xd8e870
iscsid: login response status 0000
iscsid: thread 00d8ae88 delete: state 1
iscsid: deleting a scheduled/waiting thread!
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:
iscsid: 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:
iscsid: 8192
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 2 to:
iscsid: 0
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 3 to:
iscsid: 0
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 4 to:
iscsid: 1
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 5 to:
iscsid: 1
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 6 to:
iscsid: 1
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 7 to:
iscsid: 65536
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 8 to:
iscsid: 262144
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 9 to:
iscsid: 1
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 10 to:
iscsid: 1
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 11 to:
iscsid: 0
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 14 to:
iscsid: 1
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 15 to:
iscsid: iqn.2010-01.com.example:server.client
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 16 to:
iscsid: 1
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 17 to:
iscsid: 10.0.1.1
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 18 to:
iscsid: 3260
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 19 to:
iscsid: 120
iscsid: in kset_param
iscsid: set operational parameter 22 to:
iscsid:
iscsid: in kset_param
iscsid: set operational parameter 23 to:
iscsid:
iscsid: in kset_param
iscsid: set operational parameter 24 to:
iscsid:
iscsid: in kset_param
iscsid: set operational parameter 25 to:
iscsid:
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 26 to:
iscsid: 1
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 27 to:
iscsid: 15
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 28 to:
iscsid: 20
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 30 to:
iscsid: 0
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 31 to:
iscsid: 0
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 32 to:
iscsid: default
iscsid: in kset_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 34 to:
iscsid: iqn.1993-08.org.debian:01:1bc68d43ac4
iscsid: in kset_host_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 2 to:
iscsid: default
iscsid: in kset_host_param
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: set operational parameter 0 to:
iscsid: default
iscsid: in kstart_conn
iscsid: in __kipc_call
iscsid: in kwritev
iscsid: in nlpayload_read
iscsid: in nlpayload_read
iscsid: online device 8:0:0:0
iscsid: online device 8:0:0:1
iscsid: mgmt_ipc_write_rsp: rsp to fd 5
iscsid: connection1:0 is operational after recovery (1 attempts)
iscsid: thread removed
iscsid: in ctldev_close
iscsid: reaped pid 24157, reap_count now 0
--- End Message ---