Hi Alex,
I've added now the timeout parm. here the log from the startup:
11:56:59:265 (768) conf: Reading '[parameters]'
11:56:59:265 (768) conf: 'LogLevel' = '1'
11:56:59:265 (768) conf: 'LogMask' = '0x8000ffff'
11:56:59:265 (768) conf: 'Port' = '3260'
11:56:59:265 (768) conf: 'Interface' = 'xxx.xxx.xxx.xxx'
11:56:59:265 (768) conf: 'DefTargetName' = 'iqn.2003-06.com.rocketdivision.starwind:$(host).$(symid)'
11:56:59:265 (768) conf: 'Login' = 'xxxxxx'
11:56:59:265 (768) conf: 'Password' = 'xxxxxx'
11:56:59:265 (768) conf: 'Echo' = 'no'
11:56:59:265 (768) conf: 'MinBufferSize' = '65536'
11:56:59:265 (768) conf: 'AlignmentMask' = '0x0000'
11:56:59:265 (768) conf: 'MaxPendingRequests' = '64'
11:56:59:265 (768) conf: Reading '[plugins]'
11:56:59:265 (768) conf: Reading '[devices]'
11:56:59:296 (768) conf: Reading '[permissions]'
11:56:59:296 (768) S: Assigned target name: 'iqn.2003-06.com.rocketdivision.starwind:xxx.xxx.xx.changer0', SymId: 'Changer0'.
11:56:59:328 (768) S: the device '\\.\Changer0' is opened successfully.
11:56:59:328 (768) S: parameter 'timeout', value '108000'.
11:56:59:328 (768) S: iqn.2003-06.com.rocketdivision.starwind:xxx.xxx.xx.changer0: 1 session(s) allowed.
11:56:59:328 (768) conf: Target [0] has been created. Device '\\.\Changer0' is accesible as target 'iqn.2003-06.com.rocketdivision.starwind:xxx.xxx.xx.changer0'.
11:56:59:328 (768) S: Assigned target name: 'iqn.2003-06.com.rocketdivision.starwind:xxx.xxx.xx.cdrom0', SymId: 'Cdrom0'.
11:56:59:593 (768) S: the device '\\.\Cdrom0' is opened successfully.
11:56:59:593 (768) S: parameter 'timeout', value '108000'.
11:56:59:593 (768) S: iqn.2003-06.com.rocketdivision.starwind:xxx.xxx.xx.cdrom0: 1 session(s) allowed.
11:56:59:593 (768) conf: Target [1] has been created. Device '\\.\Cdrom0' is accesible as target 'iqn.2003-06.com.rocketdivision.starwind:xxx.xxx.xx.cdrom0'.
This looks good to me. Than attaching to the target is working fine :
11:58:44:903 (768) Srv: Accepted connection from xxx.xxx.xxx.xxx:32778. id assigned - 4370
11:58:44:903 (768) C[0x1112], FREE: Event - CONNECTED.
11:58:44:903 (768) C[0x1112], XPT_UP: T3.
11:58:44:903 (1248) C[0x1112], XPT_UP: Login request: ISID 0x801234567800, TSIH 0x0000.
11:58:44:903 (1248) C[0x1112], XPT_UP: Event - LOGIN.
11:58:44:903 (1248) C[0x1112], IN_LOGIN: T4.
11:58:44:903 (1248) Params: <<< String param 'TargetName': received 'iqn.2003-06.com.rocketdivision.starwind:xxxx.xxx.xx.changer0', accepted 'iqn.2003-06.com.rocketdivision.starwind:xxxx.xxx.xx.changer0'
11:58:44:903 (1248) Params: <<< String param 'InitiatorName': received 'xxxx.xxx.xx', accepted 'xxxx.xxx.xx'
11:58:44:903 (1248) Params: <<< Enum param 'SessionType': received 'Normal', accepted 'Normal'
11:58:44:903 (1248) Params: Update OFMarkInt according to the value of OFMarker.
11:58:44:903 (1248) Params: Update IFMarkInt according to the value of IFMarker.
11:58:44:903 (1248) S: iqn.2003-06.com.rocketdivision.starwind:xxxx.xxx.xx.changer0: 1 session(s) are opened, 0 more allowed.
11:58:44:903 (1248) T[0x15b38]: Anonimous connection allowed.
11:58:44:903 (1248) Params: >>> InitialR2T=No.
11:58:44:903 (1248) Params: >>> ImmediateData=Yes.
11:58:44:903 (1248) Params: >>> MaxRecvDataSegmentLength=65536.
11:58:44:903 (1248) Params: >>> MaxBurstLength=65536.
11:58:44:903 (1248) Params: >>> FirstBurstLength=65536.
11:58:44:903 (1248) Params: >>> MaxOutstandingR2T=1.
11:58:44:903 (1248) Params: >>> DataPDUInOrder=Yes.
11:58:44:903 (1248) Params: >>> DataSequenceInOrder=Yes.
11:58:44:903 (1248) Params: >>> TargetPortalGroupTag=1.
11:58:44:919 (1248) Params: <<< Boolean param 'InitialR2T': received Yes, accepted 1
11:58:44:919 (1248) Params: <<< Boolean param 'ImmediateData': received Yes, accepted 1
11:58:44:919 (1248) Params: <<< Numeric param 'MaxOutstandingR2T': received 1, accepted 1
11:58:44:919 (1248) Params: <<< Numeric param 'FirstBurstLength': received 65536, accepted 65536
11:58:44:919 (1248) Params: <<< Numeric param 'MaxBurstLength': received 65536, accepted 65536
11:58:44:919 (840) T[0x15b38]: The end of stage 1, next stage is 3.
11:58:44:919 (840) C[0x1112], IN_LOGIN: Event - LOGIN_ACCEPT.
11:58:44:919 (840) C[0x1112], LOGGED_IN: T5.
but when I load the kernel module on linux to control and therefore first initialize the changer I get this on the StarWind side:
11:59:48:684 (840) SPTI: '\\.\Changer0': DeviceIoControl failed: Die Anforderung konnte wegen eines E/A-Gerôtefehlers nicht ausgef¹hrt werden. (code: 1117).
11:59:48:684 (840) SPTI: '\\.\Changer0': IOCTL_SCSI_PASS_THROUGH_DIRECT (before):
0000 2c 00 00 00 00 00 06 12 02 00 00 00 00 00 00 00 ,...............
0010 02 00 00 00 00 00 00 00 2c 00 00 00 07 00 00 00 ........,.......
0020 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
0030 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
11:59:48:684 (840) SPTI: '\\.\Changer0': IOCTL_SCSI_PASS_THROUGH_DIRECT (after):
0000 2c 00 00 00 00 00 06 12 02 00 00 00 00 00 00 00 ,...............
0010 02 00 00 00 00 00 00 00 2c 00 00 00 07 00 00 00 ........,.......
0020 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
0030 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
on Linux side (if you care) it looks like this :
called with :
TARGET1="iqn.2003-06.com.rocketdivision.starwind:xxxx.xxx.xx.cdrom0"
TARGET2="iqn.2003-06.com.rocketdivision.starwind:xxxx.xxx.xx.changer0"
IP="xxx.xxx.xxx.xxx"
./iscsi_manage init restore host=0
./iscsi_manage init set InitiatorName=`uname -n` host=0
./iscsi_manage init set TargetName=$TARGET2 host=0
./iscsi_manage init set SessionType=Normal host=0
./iscsi_config up ip=$IP port=3260 host=0 lun=0
dmesg:
scsi0 : UNH-IOL iSCSI Initiator. RPM: unh_iscsi-1.4-02_dev. Kernel: 2.6.8-rc3. Built on Do Aug 5 22:47:09 CEST 2004.
iscsi connected to target at xxx.xxx.xxx.xxx:3260
iscsi socket cc637ba0 tcp Nagle Algorithm is off
iscsi rx-0-00 started, pid 12959
iscsi tx-0-00 started, pid 12960
blk_queue_max_hw_segments: set to minimum 1
Vendor: KODAK Model: CDL-144 Rev: 1.41
Type: Medium Changer ANSI SCSI revision: 02
Attached scsi generic sg0 at scsi0, channel 0, id 0, lun 0, type 8
SCSI Media Changer driver v0.22 for Linux 2.6.8-rc3
scsi_to_iscsi:833:/usr/src/unh-iscsi/src/initiator/iscsi_initiator.c: ***ERROR*** request_bufflen 255 not zero when sc_data_direction 0 not READ or WRITE
ch0: type #1 (mt): 0x0+1 [medium transport]
ch0: type #2 (st): 0x1000+162 [storage]
ch0: type #3 (ie): 0x100+1 [import/export]
ch0: type #4 (dt): 0x200+1 [data transfer]
scsi_to_iscsi:833:/usr/src/unh-iscsi/src/initiator/iscsi_initiator.c: ***ERROR*** request_bufflen 256 not zero when sc_data_direction 0 not READ or WRITE
ch0: dt 0x200: not this SCSI bus
ch0: INITIALIZE ELEMENT STATUS, may take some time ...
do_scsi_response

/usr/src/unh-iscsi/src/initiator/initiator_utilities.c: ***ERROR*** rx-0-00 scsi response error from target, status 0x02
18 bytes of sense data
0: 70 00 05 00 00 00 00 0a 00 00 00 00 3e 01 00 00
16: 00 00
ch0: ... finished
Attached scsi changer ch0 at scsi0, channel 0, id 0, lun 0
when acessing the dvd-rom I get simliar results. Could this be a hardware problem ??
Thanks
Stefan