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