Hi Mike, Thanks for the speedy reply!
I've set the initiator name the same in the bios and in the OS. Do they *have* to be different? I can see the logic in having each transport have a different name, but it seems not to be the case with the other qlogic iscsi HBAs i'm using. As for Log output, there's a whole load of iscsid output which repeats several times until timeout. I've attached the last iteration below. The target is an HP/Lefthand P4300, which as far as I can tell is just a linux box with a fancy frontend and lots of SAS disks attached. It seems to just be a wrapper around open-iscsi, but obviously I can't tell for sure as it's locked down and fiddling with it would invalidate the warranty. It is not forthcoming with any log output. I use this little snippet in the initramfs to get the initial connection to the boot device defined in the HBA bios: ifconfig eth0 10.10.12.70 netmask 255.255.255.0 modprobe iscsi_tcp iscsistart -i `cat /sys/firmware/iscsi_boot0/initiator/initiator-name` -t `cat /sys/firmware/iscsi_boot0/target0/target-name` -g 1 -a `cat /sys/firmware/iscsi_boot0/target0/ip-addr` I'm going to try different initiator names for iscsi_tcp and be2iscsi and see what happens. Jun 9 08:23:44 testserver iscsid: bound iSCSI connection 25:0 to session 25 Jun 9 08:23:44 testserver iscsid: sysfs_attr_get_value: open '/class/iscsi_connection/connection25:0'/'exp_statsn' Jun 9 08:23:44 testserver iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_connection/connection25:0/exp_statsn' Jun 9 08:23:44 testserver iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_connection/connection25:0/exp_statsn' Jun 9 08:23:44 testserver iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_connection/connection25:0/exp_statsn' with attribute value '0' Jun 9 08:23:44 testserver iscsid: sending login PDU with current stage 1, next stage 3, transit 0x80, isid 0x00023d190000 exp_statsn 0 Jun 9 08:23:44 testserver iscsid: > InitiatorName=iqn.2011-05.com.travelfusion.dc.testserver Jun 9 08:23:44 testserver iscsid: > InitiatorAlias=testserver Jun 9 08:23:44 testserver iscsid: > TargetName=iqn.2003-10.com.lefthandnetworks:thm-san:25:testserver-root Jun 9 08:23:44 testserver iscsid: > SessionType=Normal Jun 9 08:23:44 testserver iscsid: > HeaderDigest=None Jun 9 08:23:44 testserver iscsid: > DataDigest=None Jun 9 08:23:44 testserver iscsid: > DefaultTime2Wait=2 Jun 9 08:23:44 testserver iscsid: > DefaultTime2Retain=0 Jun 9 08:23:44 testserver iscsid: > IFMarker=No Jun 9 08:23:44 testserver iscsid: > OFMarker=No Jun 9 08:23:44 testserver iscsid: > ErrorRecoveryLevel=0 Jun 9 08:23:44 testserver iscsid: > InitialR2T=Yes Jun 9 08:23:44 testserver iscsid: > ImmediateData=Yes Jun 9 08:23:44 testserver iscsid: > MaxBurstLength=262144 Jun 9 08:23:44 testserver iscsid: > FirstBurstLength=8192 Jun 9 08:23:44 testserver iscsid: > MaxOutstandingR2T=1 Jun 9 08:23:44 testserver iscsid: > MaxConnections=1 Jun 9 08:23:44 testserver iscsid: > DataPDUInOrder=Yes Jun 9 08:23:44 testserver iscsid: > DataSequenceInOrder=Yes Jun 9 08:23:44 testserver iscsid: > MaxRecvDataSegmentLength=65536 Jun 9 08:23:44 testserver iscsid: in ksend_pdu_begin Jun 9 08:23:44 testserver iscsid: send PDU began for hdr 48 bytes and data 488 bytes Jun 9 08:23:44 testserver iscsid: in kwritev Jun 9 08:23:44 testserver iscsid: wrote 48 bytes of PDU header Jun 9 08:23:44 testserver iscsid: in kwritev Jun 9 08:23:44 testserver iscsid: wrote 488 bytes of PDU data Jun 9 08:23:44 testserver iscsid: in ksend_pdu_end Jun 9 08:23:44 testserver iscsid: in __kipc_call Jun 9 08:23:44 testserver iscsid: in kwritev Jun 9 08:23:44 testserver iscsid: in nlpayload_read Jun 9 08:23:44 testserver iscsid: in nlpayload_read Jun 9 08:23:44 testserver iscsid: send PDU finished for conn 25:0 Jun 9 08:23:44 testserver iscsid: thread removed Jun 9 08:23:44 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:45 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:46 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:46 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:47 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:48 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:49 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:49 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:50 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:51 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:52 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:52 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:53 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:54 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:55 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:55 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:55 testserver kernel: [56402.003584] session24: session recovery timed out after 120 secs Jun 9 08:23:56 testserver kernel: [56402.514212] session25: session recovery timed out after 120 secs Jun 9 08:23:56 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:57 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:58 testserver iscsid: thread 024bdd90 wait some more Jun 9 08:23:58 testserver iscsid: thread 024bdd90 was scheduled at 203639:60, curtime 203699 q_forw 0x2508cd0 &pend_list 0x6624b0 Jun 9 08:23:58 testserver iscsid: thread 024bdd90 now in actor_list Jun 9 08:23:58 testserver iscsid: thread 02508cd0 was scheduled at 203639:60, curtime 203699 q_forw 0x6624b0 &pend_list 0x6624b0 Jun 9 08:23:58 testserver iscsid: thread 02508cd0 now in actor_list Jun 9 08:23:58 testserver iscsid: exec thread 024bdd90 callback Jun 9 08:23:58 testserver iscsid: iscsi_login_eh Jun 9 08:23:58 testserver iscsid: login failed STATE_IN_LOGIN/R_STAGE_NO_CHANGE 6 Jun 9 08:23:58 testserver iscsid: Giving up on initial login attempt after 120 seconds. Jun 9 08:23:58 testserver iscsid: disconnect conn Jun 9 08:23:58 testserver iscsid: in ktransport_ep_disconnect Jun 9 08:23:58 testserver iscsid: in __kipc_call Jun 9 08:23:58 testserver iscsid: in kwritev Jun 9 08:23:58 testserver iscsid: in nlpayload_read Jun 9 08:23:58 testserver iscsid: in nlpayload_read Jun 9 08:23:58 testserver iscsid: stop conn (conn state 2) Jun 9 08:23:58 testserver iscsid: in kstop_conn Jun 9 08:23:58 testserver iscsid: in __kipc_call Jun 9 08:23:58 testserver iscsid: in kwritev Jun 9 08:23:58 testserver iscsid: in nlpayload_read Jun 9 08:23:58 testserver iscsid: in nlpayload_read Jun 9 08:23:58 testserver iscsid: kdestroy conn Jun 9 08:23:58 testserver iscsid: in kdestroy_conn Jun 9 08:23:58 testserver iscsid: in __kipc_call Jun 9 08:23:58 testserver iscsid: in kwritev Jun 9 08:23:58 testserver iscsid: in nlpayload_read Jun 9 08:23:58 testserver iscsid: in nlpayload_read Jun 9 08:23:58 testserver iscsid: kdestroy session 24 Jun 9 08:23:58 testserver iscsid: in kdestroy_session Jun 9 08:23:58 testserver iscsid: in __kipc_call Jun 9 08:23:58 testserver iscsid: in kwritev Jun 9 08:23:58 testserver iscsid: in nlpayload_read Jun 9 08:23:58 testserver iscsid: expecting event 12, got 105, handling... Jun 9 08:23:58 testserver iscsid: in ctldev_handle Jun 9 08:23:58 testserver iscsid: in nl_read Jun 9 08:23:58 testserver iscsid: ctldev_handle got event type 105 Jun 9 08:23:58 testserver iscsid: get ev context 0x24c0b40 Jun 9 08:23:58 testserver iscsid: message real length is 72 bytes, recv_handle 0x24c0b90 Jun 9 08:23:58 testserver iscsid: in nlpayload_read Jun 9 08:23:58 testserver iscsid: sched conn context 0x24c0b40 event 5, tmo 0 Jun 9 08:23:58 testserver iscsid: thread 0x24c0b40 schedule: delay 0 state 3 Jun 9 08:23:58 testserver iscsid: in nlpayload_read Jun 9 08:23:58 testserver iscsid: expecting event 12, got 104, handling... Jun 9 08:23:58 testserver iscsid: in ctldev_handle Jun 9 08:23:58 testserver iscsid: in nl_read Jun 9 08:23:58 testserver iscsid: ctldev_handle got event type 104 Jun 9 08:23:58 testserver iscsid: in nlpayload_read Jun 9 08:23:58 testserver iscsid: session destroyed sid 24 host no 0 Jun 9 08:23:58 testserver iscsid: in nlpayload_read Jun 9 08:23:58 testserver iscsid: in nlpayload_read Jun 9 08:23:58 testserver iscsid: Connection24:0 to [target: iqn.2003-10.com.lefthandnetworks:thm-san:27:testserver-nfs, portal: 10.10.12.100,3260] through [iface: be2iscsi.d4:85:64:56:90:c9] is shutdown. Jun 9 08:23:58 testserver iscsid: mgmt_ipc_write_rsp: rsp to fd 8 Jun 9 08:23:58 testserver iscsid: thread 024bdd90 delete: state 3 Jun 9 08:23:58 testserver iscsid: thread 024bddc8 delete: state 3 Jun 9 08:23:58 testserver iscsid: destroying session Jun 9 08:23:58 testserver iscsid: thread 024c0b40 delete: state 4 Jun 9 08:23:58 testserver iscsid: deleting a scheduled/waiting thread! Jun 9 08:23:58 testserver iscsid: put ev context 0x24c0b40 Jun 9 08:23:58 testserver iscsid: Releasing session 0x24b88c0 Jun 9 08:23:58 testserver iscsid: thread removed Jun 9 08:23:58 testserver iscsid: exec thread 02508cd0 callback Jun 9 08:23:58 testserver iscsid: iscsi_login_eh Jun 9 08:23:58 testserver iscsid: login failed STATE_IN_LOGIN/R_STAGE_NO_CHANGE 6 Jun 9 08:23:58 testserver iscsid: Giving up on initial login attempt after 120 seconds. Jun 9 08:23:58 testserver iscsid: disconnect conn Jun 9 08:23:58 testserver iscsid: in ktransport_ep_disconnect Jun 9 08:23:58 testserver iscsid: in __kipc_call Jun 9 08:23:58 testserver iscsid: in kwritev Jun 9 08:23:59 testserver iscsid: in nlpayload_read Jun 9 08:23:59 testserver iscsid: in nlpayload_read Jun 9 08:23:59 testserver iscsid: stop conn (conn state 2) Jun 9 08:23:59 testserver iscsid: in kstop_conn Jun 9 08:23:59 testserver iscsid: in __kipc_call Jun 9 08:23:59 testserver iscsid: in kwritev Jun 9 08:23:59 testserver iscsid: in nlpayload_read Jun 9 08:23:59 testserver iscsid: in nlpayload_read Jun 9 08:23:59 testserver iscsid: kdestroy conn Jun 9 08:23:59 testserver iscsid: in kdestroy_conn Jun 9 08:23:59 testserver iscsid: in __kipc_call Jun 9 08:23:59 testserver iscsid: in kwritev Jun 9 08:23:59 testserver iscsid: in nlpayload_read Jun 9 08:23:59 testserver iscsid: in nlpayload_read Jun 9 08:23:59 testserver iscsid: kdestroy session 25 Jun 9 08:23:59 testserver iscsid: in kdestroy_session Jun 9 08:23:59 testserver iscsid: in __kipc_call Jun 9 08:23:59 testserver iscsid: in kwritev Jun 9 08:23:59 testserver iscsid: in nlpayload_read Jun 9 08:23:59 testserver iscsid: expecting event 12, got 105, handling... Jun 9 08:23:59 testserver iscsid: in ctldev_handle Jun 9 08:23:59 testserver iscsid: in nl_read Jun 9 08:23:59 testserver iscsid: ctldev_handle got event type 105 Jun 9 08:23:59 testserver iscsid: get ev context 0x250ba80 Jun 9 08:23:59 testserver iscsid: message real length is 72 bytes, recv_handle 0x250bad0 Jun 9 08:23:59 testserver iscsid: in nlpayload_read Jun 9 08:23:59 testserver iscsid: sched conn context 0x250ba80 event 5, tmo 0 Jun 9 08:23:59 testserver iscsid: thread 0x250ba80 schedule: delay 0 state 3 Jun 9 08:23:59 testserver iscsid: in nlpayload_read Jun 9 08:23:59 testserver iscsid: expecting event 12, got 104, handling... Jun 9 08:23:59 testserver iscsid: in ctldev_handle Jun 9 08:23:59 testserver iscsid: in nl_read Jun 9 08:23:59 testserver iscsid: ctldev_handle got event type 104 Jun 9 08:23:59 testserver iscsid: in nlpayload_read Jun 9 08:23:59 testserver iscsid: session destroyed sid 25 host no 0 Jun 9 08:23:59 testserver iscsid: in nlpayload_read Jun 9 08:23:59 testserver iscsid: in nlpayload_read Jun 9 08:23:59 testserver iscsid: Connection25:0 to [target: iqn.2003-10.com.lefthandnetworks:thm-san:25:testserver-root, portal: 10.10.12.100,3260] through [iface: be2iscsi.d4:85:64:56:90:c9] is shutdown. Jun 9 08:23:59 testserver iscsid: mgmt_ipc_write_rsp: rsp to fd 11 Jun 9 08:23:59 testserver iscsid: thread 02508cd0 delete: state 3 Jun 9 08:23:59 testserver iscsid: thread 02508d08 delete: state 3 Jun 9 08:23:59 testserver iscsid: destroying session Jun 9 08:23:59 testserver iscsid: thread 0250ba80 delete: state 4 Jun 9 08:23:59 testserver iscsid: deleting a scheduled/waiting thread! Jun 9 08:23:59 testserver iscsid: put ev context 0x250ba80 Jun 9 08:23:59 testserver iscsid: Releasing session 0x2503800 Jun 9 08:23:59 testserver iscsid: thread removed -- You received this message because you are subscribed to the Google Groups "open-iscsi" group. To post to this group, send email to [email protected]. To unsubscribe from this group, send email to [email protected]. For more options, visit this group at http://groups.google.com/group/open-iscsi?hl=en.
