Jay, The trace was taken by mirroring the port on the initiator's switch port was connected. Surely this is the same as tracing both ways? I'll upload another trace from each end separately in a bit.
Another thing i've tried is booting the OS off a non-iscsi drive (external USB), so that I can try logging with the be2iscsi transport from an already-booted machine. I get the following output (trace and iscsid debug output attached): # iscsiadm -P 3 -m discovery -t st -p 10.20.128.100 -I be2iscsi.d4:85:64:56:90:c9 iscsiadm: discovery login to 10.20.128.100 rejected: initiator failed authorization iscsiadm: Could not perform SendTargets discovery: iSCSI login failed due to authorization failure ...which is *totally* a different error message than before. iscsid debug: Jun 24 13:22:31 thm-vmutil01 kernel: [76617.028052] (beiscsi_process_cq():1953):CQ Error 13, reset CID 0x400... Jun 24 13:22:31 thm-vmutil01 kernel: [76617.087731] connection12:0: detected conn error (1011) Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: mgmt_ipc_write_rsp: rsp to fd 8 Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: mgmt_ipc_write_rsp: rsp to fd 8 Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: mgmt_ipc_write_rsp: rsp to fd 8 Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: in ctldev_handle Jun 24 13:22:32 thm-vmutil01 iscsid: in nl_read Jun 24 13:22:32 thm-vmutil01 iscsid: ctldev_handle got event type 29 Jun 24 13:22:32 thm-vmutil01 iscsid: Got unknwon event 29. Dropping. Jun 24 13:22:32 thm-vmutil01 iscsid: in nlpayload_read Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: in ctldev_handle Jun 24 13:22:32 thm-vmutil01 iscsid: in nl_read Jun 24 13:22:32 thm-vmutil01 iscsid: ctldev_handle got event type 23 Jun 24 13:22:32 thm-vmutil01 iscsid: Got unknwon event 23. Dropping. Jun 24 13:22:32 thm-vmutil01 iscsid: in nlpayload_read Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: in ctldev_handle Jun 24 13:22:32 thm-vmutil01 iscsid: in nl_read Jun 24 13:22:32 thm-vmutil01 iscsid: ctldev_handle got event type 106 Jun 24 13:22:32 thm-vmutil01 iscsid: in nlpayload_read Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: open '/class/scsi_host/host1'/'proc_name' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/scsi_host/host1/proc_name' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: add to cache '/sys/class/scsi_host/host1/proc_name' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: cache '/sys/class/scsi_host/host1/proc_name' with attribute value 'be2iscsi' Jun 24 13:22:32 thm-vmutil01 iscsid: in read_transports Jun 24 13:22:32 thm-vmutil01 iscsid: Updating transport be2iscsi Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/be2iscsi'/'handle' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_transport/be2iscsi/handle' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_transport/be2iscsi/handle' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_transport/be2iscsi/handle' with attribute value '18446744072099803584' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/be2iscsi'/'caps' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_transport/be2iscsi/caps' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_transport/be2iscsi/caps' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_transport/be2iscsi/caps' with attribute value '0x8b9' Jun 24 13:22:32 thm-vmutil01 iscsid: Updating transport iser Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/iser'/'handle' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_transport/iser/handle' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_transport/iser/handle' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_transport/iser/handle' with attribute value '18446744072101247520' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/iser'/'caps' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_transport/iser/caps' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_transport/iser/caps' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_transport/iser/caps' with attribute value '0x9' Jun 24 13:22:32 thm-vmutil01 iscsid: Updating transport tcp Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/tcp'/'handle' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_transport/tcp/handle' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_transport/tcp/handle' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_transport/tcp/handle' with attribute value '18446744072100877392' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: open '/class/iscsi_transport/tcp'/'caps' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: new uncached attribute '/sys/class/iscsi_transport/tcp/caps' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: add to cache '/sys/class/iscsi_transport/tcp/caps' Jun 24 13:22:32 thm-vmutil01 iscsid: sysfs_attr_get_value: cache '/sys/class/iscsi_transport/tcp/caps' with attribute value '0x39' Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: in ctldev_handle Jun 24 13:22:32 thm-vmutil01 iscsid: in nl_read Jun 24 13:22:32 thm-vmutil01 iscsid: ctldev_handle got event type 28 Jun 24 13:22:32 thm-vmutil01 iscsid: Got unknwon event 28. Dropping. Jun 24 13:22:32 thm-vmutil01 iscsid: in nlpayload_read Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: in ctldev_handle Jun 24 13:22:32 thm-vmutil01 iscsid: in nl_read Jun 24 13:22:32 thm-vmutil01 iscsid: ctldev_handle got event type 13 Jun 24 13:22:32 thm-vmutil01 iscsid: Got unknwon event 13. Dropping. Jun 24 13:22:32 thm-vmutil01 iscsid: in nlpayload_read Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: in ctldev_handle Jun 24 13:22:32 thm-vmutil01 iscsid: in nl_read Jun 24 13:22:32 thm-vmutil01 iscsid: ctldev_handle got event type 15 Jun 24 13:22:32 thm-vmutil01 iscsid: Got unknwon event 15. Dropping. Jun 24 13:22:32 thm-vmutil01 iscsid: in nlpayload_read Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: in ctldev_handle Jun 24 13:22:32 thm-vmutil01 iscsid: in nl_read Jun 24 13:22:32 thm-vmutil01 iscsid: ctldev_handle got event type 19 Jun 24 13:22:32 thm-vmutil01 iscsid: Got unknwon event 19. Dropping. Jun 24 13:22:32 thm-vmutil01 iscsid: in nlpayload_read Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: in ctldev_handle Jun 24 13:22:32 thm-vmutil01 iscsid: in nl_read Jun 24 13:22:32 thm-vmutil01 iscsid: ctldev_handle got event type 101 Jun 24 13:22:32 thm-vmutil01 iscsid: Could not verify connection 12:0. Dropping event. Jun 24 13:22:32 thm-vmutil01 iscsid: in nlpayload_read Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: in ctldev_handle Jun 24 13:22:32 thm-vmutil01 iscsid: in nl_read Jun 24 13:22:32 thm-vmutil01 iscsid: ctldev_handle got event type 102 Jun 24 13:22:32 thm-vmutil01 iscsid: Could not verify connection 12:0. Dropping event. Jun 24 13:22:32 thm-vmutil01 iscsid: in nlpayload_read Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: in ctldev_handle Jun 24 13:22:32 thm-vmutil01 iscsid: in nl_read Jun 24 13:22:32 thm-vmutil01 iscsid: ctldev_handle got event type 24 Jun 24 13:22:32 thm-vmutil01 iscsid: Got unknwon event 24. Dropping. Jun 24 13:22:32 thm-vmutil01 iscsid: in nlpayload_read Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: in ctldev_handle Jun 24 13:22:32 thm-vmutil01 iscsid: in nl_read Jun 24 13:22:32 thm-vmutil01 iscsid: ctldev_handle got event type 18 Jun 24 13:22:32 thm-vmutil01 iscsid: Got unknwon event 18. Dropping. Jun 24 13:22:32 thm-vmutil01 iscsid: in nlpayload_read Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: in ctldev_handle Jun 24 13:22:32 thm-vmutil01 iscsid: in nl_read Jun 24 13:22:32 thm-vmutil01 iscsid: ctldev_handle got event type 14 Jun 24 13:22:32 thm-vmutil01 iscsid: Got unknwon event 14. Dropping. Jun 24 13:22:32 thm-vmutil01 iscsid: in nlpayload_read Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: in ctldev_handle Jun 24 13:22:32 thm-vmutil01 iscsid: in nl_read Jun 24 13:22:32 thm-vmutil01 iscsid: ctldev_handle got event type 105 Jun 24 13:22:32 thm-vmutil01 iscsid: Could not verify connection 12:0. Dropping event. Jun 24 13:22:32 thm-vmutil01 iscsid: in nlpayload_read Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: in ctldev_handle Jun 24 13:22:32 thm-vmutil01 iscsid: in nl_read Jun 24 13:22:32 thm-vmutil01 iscsid: ctldev_handle got event type 104 Jun 24 13:22:32 thm-vmutil01 iscsid: in nlpayload_read Jun 24 13:22:32 thm-vmutil01 iscsid: session destroyed sid 12 host no 1 Jun 24 13:22:32 thm-vmutil01 iscsid: poll result 1 Jun 24 13:22:32 thm-vmutil01 iscsid: in ctldev_handle Jun 24 13:22:32 thm-vmutil01 iscsid: in nl_read Jun 24 13:22:32 thm-vmutil01 iscsid: ctldev_handle got event type 12 Jun 24 13:22:32 thm-vmutil01 iscsid: Got unknwon event 12. Dropping. Jun 24 13:22:32 thm-vmutil01 iscsid: in nlpayload_read On 17 June 2011 02:09, <[email protected]> wrote: > > > -----Original Message----- > From: Mike Christie [mailto:[email protected]] > Sent: Thursday, June 16, 2011 3:22 PM > To: [email protected] > Cc: Seth Simons; Kallickal, Jayamohan > Subject: Re: unable to login to targets with be2iscsi > > On 06/16/2011 02:31 PM, Seth Simons wrote: >> ...and here's some wireshark dumps showing this happen, from server >> reboot until the initramfs prompt. From this point, i ran iscsistart >> -f followed by iscsistart -b. >> > > Jay, > > Did you see the wireshark trace in the previous mail? The problem starts > in packet 8687. This is open-iscsi logging in using be2iscsi (I can tell > by the isid). The other logins in the trace seem to be emulex bios > initiator ones or something else (I do not know your isid off the top of > my head). > > Yes, Pkt 8687 is login from open-iscsi. > > I would assume the login response came as the Ack wentup from 1 to 86 (pkt > 8688). > Looking at the trace, I am unable to say who (iniitaor/target) initiated the > Reset > Is it possible to get a trace in both directions? > > > A couple packets after we login the RSTs start happening. Packet 8690 > is the first one we see the initiator send a RST,ACK. This is in > response to the target's RST right? You cannot see the target packets in > the trace so I am just assuming this is what the initiator does and does > not do it for some other reason. > > It does not seem to be iscsi related, because after that initial > sequence we see other tcp connections get created and reset, but no > iscsi commands even sent on them. > -- 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.
be2iscsi-scantargets
Description: Binary data
