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 [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 -~----------~----~----~----~------~----~------~--~---
