Hello open-iscsi-Group!

I have a running iSCSI over IPoIB setup with Mellanox Infiniband HCAs.

Now I like to run iSCSI over Iser.

hades:~# iscsi_discovery 10.6.0.2 -tiser
iscsiadm: No active sessions.
iscsiadm: Could not read node record for iqn.2001-04.com.poseidon-
de.inqbus.poseidon:disk1 10.6.0.2 3260 default
Cannot login over iser to portal 10.6.0.2:3260
Set target de.inqbus.poseidon:disk1 to automatic login over tcp to
portal 10.6.0.2:3260
Logging out of session [sid: 6, target: de.inqbus.poseidon:disk1,
portal: 10.6.0.2,3260]
Logout of [sid: 6, target: de.inqbus.poseidon:disk1, portal:
10.6.0.2,3260]: successful
discovered 1 targets at 10.6.0.2

The TCP path is found without problems and runs well. But the iser
handshake fails and
the following error is logged on the initiator side:

Aug 20 01:17:52 hades kernel: [45569.481501] iser:
iser_cma_handler:event: 3, error: -110

Can anybody give me a hint which component is the problem? The kernel,
the modules,
the initiator or the target? Feeling a bit helpless. :-(

Any help welcome.

Best Regards

Volker

Here the log on  initiator and target:

Initiator: Stock debian Lenny open-iscsi 2.0.869.2-2.1, using the
modules from stock 2.6.26 debian lenny kernel.

Aug 20 01:17:51 hades kernel: [45568.433252] iser:
iser_connect:connecting to: 10.6.0.2, port 0xbc0c
Aug 20 01:17:51 hades kernel: [45568.436498] iser:
iser_cma_handler:event 0 conn ffff81000dc98280 id ffff81000dc8b200
Aug 20 01:17:51 hades kernel: [45568.689519] iser:
iscsi_iser_ep_poll:ib conn ffff81000dc98280 rc = 0
Aug 20 01:17:51 hades kernel: [45568.949516] iser:
iscsi_iser_ep_poll:ib conn ffff81000dc98280 rc = 0
Aug 20 01:17:51 hades kernel: [45569.209529] iser:
iscsi_iser_ep_poll:ib conn ffff81000dc98280 rc = 0
Aug 20 01:17:52 hades kernel: [45569.481501] iser:
iser_cma_handler:event 3 conn ffff81000dc98280 id ffff81000dc8b200
Aug 20 01:17:52 hades kernel: [45569.481501] iser:
iser_cma_handler:event: 3, error: -110
Aug 20 01:17:52 hades kernel: [45569.537493] iser:
iscsi_iser_ep_poll:ib conn ffff81000dc98280 rc = -1
Aug 20 01:17:55 hades kernel: [45572.869534] iser:
iscsi_iser_ep_disconnect:ib conn ffff81000dc98280 state 4
Aug 20 01:17:55 hades kernel: [45572.869565] iser:
iser_conn_terminate:Failed to disconnect, conn: 0xffff81000dc98280 err
-22
Aug 20 01:17:55 hades kernel: [45572.869617] iser:
iser_free_ib_conn_res:freeing conn ffff81000dc98280 cma_id
ffff81000dc8b200 fmr pool 0000000000000000 qp 00000
00000000000

==================================================================


Target : tgt 20080805 builded on debian lenny.

Aug 20 01:17:31 poseidon tgtd: Target daemon logger with pid=28900
started!
Aug 20 01:17:32 poseidon tgtd: accept_connection(99) 8
Aug 20 01:17:40 poseidon tgtd: tgt_mgmt(332) 155 0 1 0 1 0 0
targetname=de.inqbus.poseidon:disk1 28899
Aug 20 01:17:40 poseidon tgtd: tgt_device_create(421) 1 0
Aug 20 01:17:40 poseidon tgtd: tgt_device_create(540) Add a logical
unit 0 to the target 1
Aug 20 01:17:40 poseidon tgtd: tgt_target_create(1708) Succeed to
create a new target 1
Aug 20 01:17:48 poseidon tgtd: tgt_mgmt(332) 146 0 1 3 1 0 0 initiator-
address=10.6.0.1 28899
Aug 20 01:17:49 poseidon tgtd: accept_connection(99) 8
Aug 20 01:17:49 poseidon tgtd: cmnd_exec_login(631) Login request
(operational negotiation): 0
Aug 20 01:17:49 poseidon tgtd: login_start(491) exp_cmd_sn: 0,0
Aug 20 01:17:49 poseidon tgtd: it_nexus_create(231) 1 10
Aug 20 01:17:49 poseidon tgtd: session_create(135) session_create:
0x10000063d0200
Aug 20 01:17:49 poseidon tgtd: conn_take_fd(178) 0 1 0 10000063d0200
Aug 20 01:17:50 poseidon tgtd: iscsi_scsi_cmd_rx_start(1454) 1 12 0 0
36 1 10000000
Aug 20 01:17:50 poseidon tgtd: iscsi_task_queue(1399) 0 0 1
Aug 20 01:17:50 poseidon tgtd: target_cmd_queue(818) 0x1235650 12 0
Aug 20 01:17:50 poseidon tgtd: target_cmd_queue(837) 0x1235650 12 0 1
Aug 20 01:17:50 poseidon tgtd: spc_inquiry(164) 0 0
Aug 20 01:17:50 poseidon tgtd: target_cmd_queue(848) 10000000 12 (nil)
0x1236000 0 0 36 0 0
Aug 20 01:17:50 poseidon tgtd: iscsi_scsi_cmd_done(1092) shrunk too
big device read len 66 > 36
Aug 20 01:17:50 poseidon tgtd: iscsi_task_tx_start(1745) found a task
10000000 36 0 0
Aug 20 01:17:50 poseidon tgtd: iscsi_data_rsp_build(962) 36 36 36 8192
10000000
Aug 20 01:17:50 poseidon tgtd: __cmd_done(909) 0 (nil) 0x1236000 0 36
0
Aug 20 01:17:50 poseidon tgtd: iscsi_task_tx_start(1770) no more data
Aug 20 01:17:50 poseidon tgtd: iscsi_scsi_cmd_rx_start(1454) 1 12 0 0
68 1 10000001
Aug 20 01:17:50 poseidon tgtd: iscsi_task_queue(1399) 1 1 1
Aug 20 01:17:50 poseidon tgtd: target_cmd_queue(818) 0x1235650 12 0
Aug 20 01:17:50 poseidon tgtd: target_cmd_queue(837) 0x1235650 12 0 1
Aug 20 01:17:50 poseidon tgtd: spc_inquiry(164) 0 0
Aug 20 01:17:50 poseidon tgtd: target_cmd_queue(848) 10000001 12 (nil)
0x1236000 0 0 66 0 0
Aug 20 01:17:50 poseidon tgtd: iscsi_task_tx_start(1745) found a task
10000001 66 0 0
Aug 20 01:17:50 poseidon tgtd: iscsi_data_rsp_build(962) 66 66 66 8192
10000001
Aug 20 01:17:50 poseidon tgtd: __cmd_done(909) 0 (nil) 0x1236000 0 66
0
Aug 20 01:17:50 poseidon tgtd: iscsi_task_tx_start(1770) no more data
Aug 20 01:17:50 poseidon tgtd: iscsi_scsi_cmd_rx_start(1454) 1 a0 0 0
4096 1 10000002
Aug 20 01:17:50 poseidon tgtd: iscsi_task_queue(1399) 2 2 1
Aug 20 01:17:50 poseidon tgtd: target_cmd_queue(818) 0x1235650 a0 0
Aug 20 01:17:50 poseidon tgtd: target_cmd_queue(837) 0x1235650 a0 0 1
Aug 20 01:17:50 poseidon tgtd: target_cmd_queue(848) 10000002 a0 (nil)
0x1236000 0 0 4096 0 0
Aug 20 01:17:50 poseidon tgtd: iscsi_task_tx_start(1745) found a task
10000002 4096 0 0
Aug 20 01:17:50 poseidon tgtd: iscsi_data_rsp_build(962) 16 4096 16
8192 10000002
Aug 20 01:17:50 poseidon tgtd: __cmd_done(909) 0 (nil) 0x1236000 0
4096 0
Aug 20 01:17:50 poseidon tgtd: iscsi_task_tx_start(1770) no more data
Aug 20 01:17:50 poseidon tgtd: iscsi_task_queue(1399) 3 3 46
Aug 20 01:17:50 poseidon tgtd: iscsi_task_tx_start(1745) found a task
0 0 0 0
Aug 20 01:17:50 poseidon tgtd: conn_close(88) connection closed
0x122c9c8 1
Aug 20 01:17:50 poseidon tgtd: it_nexus_destroy(283) 1 1
Aug 20 01:17:50 poseidon tgtd: iscsi_tcp_event_handler(168) connection
closed
Aug 20 01:17:51 poseidon tgtd: tgt_mgmt(332) 138 0 2 0 1 0 1 path=/dev/
vg1/test 28899
Aug 20 01:17:51 poseidon tgtd: tgt_device_create(421) 1 1
Aug 20 01:17:51 poseidon tgtd: tgt_device_create(540) Add a logical
unit 1 to the target 1



--~--~---------~--~----~------------~-------~--~----~
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 [EMAIL PROTECTED]
For more options, visit this group at http://groups.google.com/group/open-iscsi
-~----------~----~----~----~------~----~------~--~---

Reply via email to