On Thu, May 14, 2009 at 6:25 PM, Mike Christie <micha...@cs.wisc.edu> wrote:
>
> Erez Zilber wrote:
>>> Could you try my linux-2.6-iscsi git tree and turn on debugging?
>>
>> Moving to another kernel will be harder for me, but I can add a printk
>> in the eh handler.
>>
>
> Can you recompile the libiscsi module with debugging?
> uncomment this:
>
> /* #define DEBUG_SCSI */
>
> in libiscsi.h
>
>
> You might also want to add some debugs in iscsi_eh_abort. We might
> hitting one of those early return statements (some do not have
> printks/debug statements).
>
>
>>>
>>>> open-iscsi logs in again after 10 seconds and sends the command again.
>>> Do you see something about a target reset or host reset?
>>
>> No
>>
>>>> SCST also cleans up the session.
>>> If the initiator thinks the abort failed (actually it is more like we
>>> will return failed if we think it is possible that someone could still
>>> be accessing the commands buffers, because we do not want scsi-ml to
>>> start using them again) it would return FAILED to the scsi-eh which for
>>> us would end up running the host/target reset, which we just drop the
>>> session for.
>>>
>>> We discussed before we need to modify how we decided when to return
>>> failed and we need to send a target reset for the host/target reset
>>> handler because the target reset and session relogin have different
>>> clearing effects. Vlad also has concerns about the tcp/ip connection
>>> teardown and buildup.
>>>
>>>> Can anyone explain the reason for this behavior? I would expect that
>>> iser and iscsi_tcp both hooked in the same libiscsi.c eh code. You
>>> should know this :)
>>
>> I suspect that scsi-ml doesn't call the eh handler at all. Anyway, I
>> will add this printk and retest.
>>
>
> Yeah, that is what it is looking like. If the initiator dropped the
> session then you should see the conn error 1011 or a "target reset
> succeeded" or "host reset succeeded" message or a failure message if we
> did not log back in.
>
> >
>

I enabled open-iscsi logging + added some printk calls when the abort
handler returns.
Here's the log. I see that iscsi_eh_cmd_timed_out gets called, but
there's no abort.

May 17 10:59:30 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Sending nopout as ping
May 17 10:59:30 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526696621
May 17 10:59:30 kpc36 kernel:  session1: iscsi_prep_mgmt_task mgmtpdu
[op 0x0 hdr->itt 0x40000009 datalen 0]
May 17 10:59:30 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x20
cid 0 itt 0x9 len 0]
May 17 10:59:30 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x9 state 3 sc 0000000000000000
May 17 10:59:30 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x9 state 1 sc 0000000000000000
May 17 10:59:30 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526696621
May 17 10:59:33 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [read cid 0 sc ffff8101e5e1e6c0 cdb 0x12 itt 0xa len 36 bidi_len
0 cmdsn 81 win 32]
May 17 10:59:33 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x25
cid 0 itt 0xa len 0]
May 17 10:59:33 kpc36 kernel:  session1: iscsi_data_in_rsp data in
with status done [sc ffff8101e5e1e6c0 res 0 itt 0xa]
May 17 10:59:33 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0xa state 3 sc ffff8101e5e1e6c0
May 17 10:59:33 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0xa state 1 sc ffff8101e5e1e6c0
May 17 10:59:33 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [read cid 0 sc ffff8101dcca4080 cdb 0x25 itt 0xb len 8 bidi_len 0
cmdsn 82 win 32]
May 17 10:59:33 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x25
cid 0 itt 0xb len 0]
May 17 10:59:33 kpc36 kernel:  session1: iscsi_data_in_rsp data in
with status done [sc ffff8101dcca4080 res 0 itt 0xb]
May 17 10:59:33 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0xb state 3 sc ffff8101dcca4080
May 17 10:59:33 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0xb state 1 sc ffff8101dcca4080
May 17 10:59:33 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [read cid 0 sc ffff8101d8cd7980 cdb 0x28 itt 0xc len 512 bidi_len
0 cmdsn 83 win 32]
May 17 10:59:33 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x25
cid 0 itt 0xc len 0]
May 17 10:59:33 kpc36 kernel:  session1: iscsi_data_in_rsp data in
with status done [sc ffff8101d8cd7980 res 0 itt 0xc]
May 17 10:59:33 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0xc state 3 sc ffff8101d8cd7980
May 17 10:59:33 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0xc state 1 sc ffff8101d8cd7980
May 17 10:59:33 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff81023eff3680 cdb 0x2a itt 0xd len 512
bidi_len 0 cmdsn 84 win 32]
May 17 10:59:33 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x21
cid 0 itt 0xd len 0]
May 17 10:59:33 kpc36 kernel:  session1: iscsi_scsi_cmd_rsp cmd rsp
done [sc ffff81023eff3680 res 0 itt 0xd]
May 17 10:59:33 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0xd state 3 sc ffff81023eff3680
May 17 10:59:33 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0xd state 1 sc ffff81023eff3680
May 17 10:59:35 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526700238
May 17 10:59:38 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Sending nopout as ping
May 17 10:59:38 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526705238
May 17 10:59:38 kpc36 kernel:  session1: iscsi_prep_mgmt_task mgmtpdu
[op 0x0 hdr->itt 0x4000000e datalen 0]
May 17 10:59:38 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x20
cid 0 itt 0xe len 0]
May 17 10:59:38 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0xe state 3 sc 0000000000000000
May 17 10:59:38 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0xe state 1 sc 0000000000000000
May 17 10:59:38 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526705239
May 17 10:59:43 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Sending nopout as ping
May 17 10:59:43 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526710239
May 17 10:59:43 kpc36 kernel:  session1: iscsi_prep_mgmt_task mgmtpdu
[op 0x0 hdr->itt 0x4000000f datalen 0]
May 17 10:59:43 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x20
cid 0 itt 0xf len 0]
May 17 10:59:43 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0xf state 3 sc 0000000000000000
May 17 10:59:43 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0xf state 1 sc 0000000000000000
May 17 10:59:43 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526710241
May 17 10:59:44 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff8101d8cd7200 cdb 0x2a itt 0x10 len 512
bidi_len 0 cmdsn 85 win 32]
May 17 10:59:45 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff8101e30efe40 cdb 0x2a itt 0x11 len 512
bidi_len 0 cmdsn 86 win 32]
May 17 10:59:48 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Sending nopout as ping
May 17 10:59:48 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526715241
May 17 10:59:48 kpc36 kernel:  session1: iscsi_prep_mgmt_task mgmtpdu
[op 0x0 hdr->itt 0x40000012 datalen 0]
May 17 10:59:50 kpc36 kernel:  session1: iscsi_eh_cmd_timed_out scsi
cmd ffff8101e30efe40 timedout
May 17 10:59:50 kpc36 kernel:  session1: iscsi_eh_cmd_timed_out return
timer reset
May 17 10:59:53 kpc36 kernel:  connection1:0: ping timeout of 5 secs
expired, recv timeout 5, last rx 4526705241, last ping 4526710241, now
4526715241
May 17 10:59:53 kpc36 kernel:  connection1:0: detected conn error (1011)
May 17 10:59:53 kpc36 kernel:  session1: iscsi_start_session_recovery
blocking session
May 17 10:59:53 kpc36 kernel:  session1: fail_scsi_tasks failing sc
ffff8101d8cd7200 itt 0x10 state 3
May 17 10:59:53 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x10 state 3 sc ffff8101d8cd7200
May 17 10:59:53 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x10 state 5 sc ffff8101d8cd7200
May 17 10:59:53 kpc36 kernel:  session1: fail_scsi_tasks failing sc
ffff8101e30efe40 itt 0x11 state 3
May 17 10:59:53 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x11 state 3 sc ffff8101e30efe40
May 17 10:59:53 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x11 state 5 sc ffff8101e30efe40
May 17 10:59:53 kpc36 kernel:  session1: fail_mgmt_tasks failing mgmt
itt 0x12 state 3
May 17 10:59:53 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x12 state 3 sc 0000000000000000
May 17 10:59:53 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x12 state 5 sc 0000000000000000
May 17 10:59:54 kpc36 iscsid: Kernel reported iSCSI connection 1:0
error (1011) state (3)
May 17 10:59:56 kpc36 kernel:  session1: iscsi_prep_mgmt_task mgmtpdu
[op 0x3 hdr->itt 0x40000000 datalen 480]
May 17 10:59:56 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x23
cid 0 itt 0x0 len 334]
May 17 10:59:56 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x0 state 3 sc 0000000000000000
May 17 10:59:56 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x0 state 1 sc 0000000000000000
May 17 10:59:56 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff8101e30efe40 cdb 0x2a itt 0x13 len 512
bidi_len 0 cmdsn 87 win 30]
May 17 10:59:56 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff8101d8cd7200 cdb 0x2a itt 0x14 len 512
bidi_len 0 cmdsn 88 win 30]
May 17 10:59:57 kpc36 iscsid: connection1:0 is operational after
recovery (1 attempts)
May 17 11:00:01 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Sending nopout as ping
May 17 11:00:01 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526728494
May 17 11:00:01 kpc36 kernel:  session1: iscsi_prep_mgmt_task mgmtpdu
[op 0x0 hdr->itt 0x50000015 datalen 0]
May 17 11:00:01 kpc36 kernel:  session1: iscsi_eh_cmd_timed_out scsi
cmd ffff8101e30efe40 timedout
May 17 11:00:01 kpc36 kernel:  session1: iscsi_eh_cmd_timed_out return
timer reset
May 17 11:00:06 kpc36 kernel:  connection1:0: ping timeout of 5 secs
expired, recv timeout 5, last rx 4526718494, last ping 4526723494, now
4526728494
May 17 11:00:06 kpc36 kernel:  connection1:0: detected conn error (1011)
May 17 11:00:06 kpc36 kernel:  session1: iscsi_eh_cmd_timed_out scsi
cmd ffff8101e30efe40 timedout
May 17 11:00:06 kpc36 kernel:  session1: iscsi_eh_cmd_timed_out return
timer reset
May 17 11:00:07 kpc36 kernel:  session1: iscsi_start_session_recovery
blocking session
May 17 11:00:07 kpc36 kernel:  session1: fail_scsi_tasks failing sc
ffff8101e30efe40 itt 0x13 state 3
May 17 11:00:07 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x13 state 3 sc ffff8101e30efe40
May 17 11:00:07 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x13 state 5 sc ffff8101e30efe40
May 17 11:00:07 kpc36 kernel:  session1: fail_scsi_tasks failing sc
ffff8101d8cd7200 itt 0x14 state 3
May 17 11:00:07 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x14 state 3 sc ffff8101d8cd7200
May 17 11:00:07 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x14 state 5 sc ffff8101d8cd7200
May 17 11:00:07 kpc36 kernel:  session1: fail_mgmt_tasks failing mgmt
itt 0x15 state 3
May 17 11:00:07 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x15 state 3 sc 0000000000000000
May 17 11:00:07 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x15 state 5 sc 0000000000000000
May 17 11:00:08 kpc36 iscsid: Kernel reported iSCSI connection 1:0
error (1011) state (3)
May 17 11:00:09 kpc36 kernel:  session1: iscsi_prep_mgmt_task mgmtpdu
[op 0x3 hdr->itt 0x50000000 datalen 480]
May 17 11:00:09 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x23
cid 0 itt 0x0 len 334]
May 17 11:00:09 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x0 state 3 sc 0000000000000000
May 17 11:00:09 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x0 state 1 sc 0000000000000000
May 17 11:00:09 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff8101d8cd7200 cdb 0x2a itt 0x16 len 512
bidi_len 0 cmdsn 89 win 28]
May 17 11:00:09 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff8101e30efe40 cdb 0x2a itt 0x17 len 512
bidi_len 0 cmdsn 90 win 28]
May 17 11:00:10 kpc36 iscsid: connection1:0 is operational after
recovery (1 attempts)
May 17 11:00:14 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x21
cid 0 itt 0x16 len 98]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_scsi_cmd_rsp copied 96
bytes of sense
May 17 11:00:14 kpc36 kernel:  session1: iscsi_scsi_cmd_rsp cmd rsp
done [sc ffff8101d8cd7200 res 2 itt 0x16]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x16 state 3 sc ffff8101d8cd7200
May 17 11:00:14 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x16 state 1 sc ffff8101d8cd7200
May 17 11:00:14 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff8101d8cd7200 cdb 0x2a itt 0x18 len 512
bidi_len 0 cmdsn 91 win 31]
May 17 11:00:14 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x21
cid 0 itt 0x17 len 0]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_scsi_cmd_rsp cmd rsp
done [sc ffff8101e30efe40 res 0 itt 0x17]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x17 state 3 sc ffff8101e30efe40
May 17 11:00:14 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x17 state 1 sc ffff8101e30efe40
May 17 11:00:14 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x21
cid 0 itt 0x18 len 0]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_scsi_cmd_rsp cmd rsp
done [sc ffff8101d8cd7200 res 0 itt 0x18]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x18 state 3 sc ffff8101d8cd7200
May 17 11:00:14 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x18 state 1 sc ffff8101d8cd7200
May 17 11:00:14 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff8101d8cd7680 cdb 0x2a itt 0x19 len 512
bidi_len 0 cmdsn 92 win 32]
May 17 11:00:14 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x21
cid 0 itt 0x19 len 0]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_scsi_cmd_rsp cmd rsp
done [sc ffff8101d8cd7680 res 0 itt 0x19]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x19 state 3 sc ffff8101d8cd7680
May 17 11:00:14 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x19 state 1 sc ffff8101d8cd7680
May 17 11:00:14 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff8101e30ef0c0 cdb 0x2a itt 0x1a len 512
bidi_len 0 cmdsn 93 win 32]
May 17 11:00:14 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x21
cid 0 itt 0x1a len 0]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_scsi_cmd_rsp cmd rsp
done [sc ffff8101e30ef0c0 res 0 itt 0x1a]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x1a state 3 sc ffff8101e30ef0c0
May 17 11:00:14 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x1a state 1 sc ffff8101e30ef0c0
May 17 11:00:14 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff8101e30ef240 cdb 0x2a itt 0x1b len 512
bidi_len 0 cmdsn 94 win 32]
May 17 11:00:14 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x21
cid 0 itt 0x1b len 0]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_scsi_cmd_rsp cmd rsp
done [sc ffff8101e30ef240 res 0 itt 0x1b]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x1b state 3 sc ffff8101e30ef240
May 17 11:00:14 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x1b state 1 sc ffff8101e30ef240
May 17 11:00:14 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff8101e30ef3c0 cdb 0x2a itt 0x1c len 512
bidi_len 0 cmdsn 95 win 32]
May 17 11:00:14 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x21
cid 0 itt 0x1c len 0]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_scsi_cmd_rsp cmd rsp
done [sc ffff8101e30ef3c0 res 0 itt 0x1c]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x1c state 3 sc ffff8101e30ef3c0
May 17 11:00:14 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x1c state 1 sc ffff8101e30ef3c0
May 17 11:00:14 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff8101e30ef540 cdb 0x2a itt 0x1d len 512
bidi_len 0 cmdsn 96 win 32]
May 17 11:00:14 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x21
cid 0 itt 0x1d len 0]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_scsi_cmd_rsp cmd rsp
done [sc ffff8101e30ef540 res 0 itt 0x1d]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x1d state 3 sc ffff8101e30ef540
May 17 11:00:14 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x1d state 1 sc ffff8101e30ef540
May 17 11:00:14 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff8101e30ef6c0 cdb 0x2a itt 0x1e len 512
bidi_len 0 cmdsn 97 win 32]
May 17 11:00:14 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x21
cid 0 itt 0x1e len 0]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_scsi_cmd_rsp cmd rsp
done [sc ffff8101e30ef6c0 res 0 itt 0x1e]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x1e state 3 sc ffff8101e30ef6c0
May 17 11:00:14 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x1e state 1 sc ffff8101e30ef6c0
May 17 11:00:14 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff8101e30ef840 cdb 0x2a itt 0x1f len 512
bidi_len 0 cmdsn 98 win 32]
May 17 11:00:14 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x21
cid 0 itt 0x1f len 0]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_scsi_cmd_rsp cmd rsp
done [sc ffff8101e30ef840 res 0 itt 0x1f]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x1f state 3 sc ffff8101e30ef840
May 17 11:00:14 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x1f state 1 sc ffff8101e30ef840
May 17 11:00:14 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff8101e30ef9c0 cdb 0x2a itt 0x20 len 512
bidi_len 0 cmdsn 99 win 32]
May 17 11:00:14 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x21
cid 0 itt 0x20 len 0]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_scsi_cmd_rsp cmd rsp
done [sc ffff8101e30ef9c0 res 0 itt 0x20]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x20 state 3 sc ffff8101e30ef9c0
May 17 11:00:14 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x20 state 1 sc ffff8101e30ef9c0
May 17 11:00:14 kpc36 kernel:  session1: iscsi_prep_scsi_cmd_pdu iscsi
prep [write cid 0 sc ffff8101e30efb40 cdb 0x2a itt 0x21 len 512
bidi_len 0 cmdsn 100 win 32]
May 17 11:00:14 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x21
cid 0 itt 0x21 len 0]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_scsi_cmd_rsp cmd rsp
done [sc ffff8101e30efb40 res 0 itt 0x21]
May 17 11:00:14 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x21 state 3 sc ffff8101e30efb40
May 17 11:00:14 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x21 state 1 sc ffff8101e30efb40
May 17 11:00:14 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526741080
May 17 11:00:19 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Sending nopout as ping
May 17 11:00:19 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526746080
May 17 11:00:19 kpc36 kernel:  session1: iscsi_prep_mgmt_task mgmtpdu
[op 0x0 hdr->itt 0x60000022 datalen 0]
May 17 11:00:19 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x20
cid 0 itt 0x22 len 0]
May 17 11:00:19 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x22 state 3 sc 0000000000000000
May 17 11:00:19 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x22 state 1 sc 0000000000000000
May 17 11:00:19 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526746081
May 17 11:00:24 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Sending nopout as ping
May 17 11:00:24 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526751081
May 17 11:00:24 kpc36 kernel:  session1: iscsi_prep_mgmt_task mgmtpdu
[op 0x0 hdr->itt 0x60000023 datalen 0]
May 17 11:00:24 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x20
cid 0 itt 0x23 len 0]
May 17 11:00:24 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x23 state 3 sc 0000000000000000
May 17 11:00:24 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x23 state 1 sc 0000000000000000
May 17 11:00:24 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526751082
May 17 11:00:29 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Sending nopout as ping
May 17 11:00:29 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526756082
May 17 11:00:29 kpc36 kernel:  session1: iscsi_prep_mgmt_task mgmtpdu
[op 0x0 hdr->itt 0x60000024 datalen 0]
May 17 11:00:29 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x20
cid 0 itt 0x24 len 0]
May 17 11:00:29 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x24 state 3 sc 0000000000000000
May 17 11:00:29 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x24 state 1 sc 0000000000000000
May 17 11:00:29 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526756083
May 17 11:00:34 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Sending nopout as ping
May 17 11:00:34 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526761083
May 17 11:00:34 kpc36 kernel:  session1: iscsi_prep_mgmt_task mgmtpdu
[op 0x0 hdr->itt 0x60000025 datalen 0]
May 17 11:00:34 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x20
cid 0 itt 0x25 len 0]
May 17 11:00:34 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x25 state 3 sc 0000000000000000
May 17 11:00:34 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x25 state 1 sc 0000000000000000
May 17 11:00:34 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526761086
May 17 11:00:39 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Sending nopout as ping
May 17 11:00:39 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526766086
May 17 11:00:39 kpc36 kernel:  session1: iscsi_prep_mgmt_task mgmtpdu
[op 0x0 hdr->itt 0x60000026 datalen 0]
May 17 11:00:39 kpc36 kernel:  session1: __iscsi_complete_pdu [op 0x20
cid 0 itt 0x26 len 0]
May 17 11:00:39 kpc36 kernel:  session1: iscsi_complete_task complete
task itt 0x26 state 3 sc 0000000000000000
May 17 11:00:39 kpc36 kernel:  session1: iscsi_free_task freeing task
itt 0x26 state 1 sc 0000000000000000
May 17 11:00:39 kpc36 kernel:  connection1:0:
iscsi_check_transport_timeouts Setting next tmo 4526766088

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

Reply via email to