Hello and thanks for you reply,
I will run your script and monitor it's output. I have provided some additional
information below. I also phoned our local support here in Paris to see what
they can come up with. Before what we did was to run the same tests on an old
E450 running identical OS, Patch levels etc but with 100Mbit interfaces. The
same problem is there. We changed switches from Giga SW to 100Mbit SW. Nothing
seems to help.
Today after my post I changed IQN on the Sun box, re-created a small partition
~128mb which took me ~25min ( see below complete messages file output during
newfs) to make a new filesystem on. It comes through in the end but then if i
try to make something on the filesystem it's slow once again. None of this was
happening using Cisco's drivers on the old Sol 8 box.
[EMAIL PROTECTED] tmp]# [b]iscsiadm list target -v[/b]
Target: idevice.name.00e08134fe16.017
Alias: -
TPGT: 1
ISID: 4000002a0000
Connections: 1
CID: 0
IP address (Local): 163.187.174.108:32845
IP address (Peer): 163.187.174.100:3260
Discovery Method: SendTargets
Login Parameters (Negotiated):
Data Sequence In Order: yes
Data PDU In Order: yes
Default Time To Retain: 0
Default Time To Wait: 0
Error Recovery Level: 0
First Burst Length: 65536
Immediate Data: yes
Initial Ready To Transfer (R2T): yes
Max Burst Length: 262144
Max Outstanding R2T: 1
Max Receive Data Segment Length: 65536
Max Connections: 1
Header Digest: NONE
Data Digest: NONE
[b]This is what my iSCSI log says:[/b]
Feb 19 15:08:17.092 24753 ERROR:Bad "Opcode": Got 0 expected 5.
Feb 19 15:08:17.092 24753 ERROR:iscsi_write_data_decap() failed
Feb 19 15:08:17.092 24753 ERROR:ReadDataPDU(2) failed
Feb 19 15:08:17.092 24753 ERROR:Unable to read request from host, terminate
session. function (bufferScsiRequest)
... SCSI op 0x2a (lun 0):, Length: 1048576, Direction: WRITE
Feb 19 15:08:17.092 24753 ERROR:iscsiDevice() failed
Feb 19 15:08:17.092 24753 ERROR:scsi_command_t() failed
Feb 19 15:08:17.092 24753 ERROR:execute_t() failed
Feb 19 15:08:17.092 24753 INFO:iSCSI Logout, Host IP: 163.187.174.108, target:
'idevice.name.00e08134fe16.017', initiator: 'iqn.1986-03.com.epsu82.174.108'
Feb 19 15:08:17.093 1672 INFO:connection accepted on port 3260 (local IP
163.187.174.100, remote IP 163.187.174.108)
Feb 19 15:08:17.100 24901 INFO:login_command_t: looking for iSCSI init
iqn.1986-03.com.epsu82.174.108
Feb 19 15:08:17.100 24901 INFO:login_command: Adding new iscsi initiator
Feb 19 15:08:17.528 24901 INFO:add_is_init: initiator already exists
Feb 19 15:08:17.528 24901 INFO:Successful Normal iSCSI login from Host IP:
163.187.174.108 initiator: 'iqn.1986-03.com.epsu82.174.108' to target:
'idevice.name.00e08134fe16.017'
Feb 19 15:08:32.039 24901 ERROR:Bad "Opcode": Got 0 expected 5.
Feb 19 15:08:32.039 24901 ERROR:iscsi_write_data_decap() failed
Feb 19 15:08:32.039 24901 ERROR:ReadDataPDU(2) failed
Feb 19 15:08:32.039 24901 ERROR:Unable to read request from host, terminate
session. function (bufferScsiRequest)
... SCSI op 0x2a (lun 0):, Length: 1048576, Direction: WRITE
Feb 19 15:08:32.039 24901 ERROR:iscsiDevice() failed
Feb 19 15:08:32.039 24901 ERROR:scsi_command_t() failed
Feb 19 15:08:32.039 24901 ERROR:execute_t() failed
Feb 19 15:08:32.039 24901 INFO:iSCSI Logout, Host IP: 163.187.174.108, target:
'idevice.name.00e08134fe16.017', initiator: 'iqn.1986-03.com.epsu82.174.108'
Feb 19 15:08:32.040 1672 INFO:connection accepted on port 3260 (local IP
163.187.174.100, remote IP 163.187.174.108)
Feb 19 15:08:32.047 25019 INFO:login_command_t: looking for iSCSI init
iqn.1986-03.com.epsu82.174.108
Feb 19 15:08:32.047 25019 INFO:login_command: Adding new iscsi initiator
Feb 19 15:08:32.472 25019 INFO:add_is_init: initiator already exists
Feb 19 15:08:32.472 25019 INFO:Successful Normal iSCSI login from Host IP:
163.187.174.108 initiator: 'iqn.1986-03.com.epsu82.174.108' to target:
'idevice.name.00e08134fe16.017'
Feb 19 15:08:50.954 25019 ERROR:Bad "Opcode": Got 0 expected 5.
Feb 19 15:08:50.954 25019 ERROR:iscsi_write_data_decap() failed
Feb 19 15:08:50.954 25019 ERROR:ReadDataPDU(2) failed
Feb 19 15:08:50.954 25019 ERROR:Unable to read request from host, terminate
session. function (bufferScsiRequest)
... SCSI op 0x2a (lun 0):, Length: 1048576, Direction: WRITE
Feb 19 15:08:50.955 25019 ERROR:iscsiDevice() failed
Feb 19 15:08:50.955 25019 ERROR:scsi_command_t() failed
Feb 19 15:08:50.955 25019 ERROR:execute_t() failed
Feb 19 15:08:50.955 25019 INFO:iSCSI Logout, Host IP: 163.187.174.108, target:
'idevice.name.00e08134fe16.017', initiator: 'iqn.1986-03.com.epsu82.174.108'
Feb 19 15:08:50.963 1672 INFO:connection accepted on port 3260 (local IP
163.187.174.100, remote IP 163.187.174.108)
Feb 19 15:08:50.967 25167 INFO:login_command_t: looking for iSCSI init
iqn.1986-03.com.epsu82.174.108
Feb 19 15:08:50.967 25167 INFO:login_command: Adding new iscsi initiator
Feb 19 15:08:51.402 25167 INFO:add_is_init: initiator already exists
Feb 19 15:08:51.402 25167 INFO:Successful Normal iSCSI login from Host IP:
163.187.174.108 initiator: 'iqn.1986-03.com.epsu82.174.108' to target:
'idevice.name.00e08134fe16.017'
****************************** End Log Buffer ******************************
[b]/var/adm/messsages:[/b]
Feb 19 15:52:01 epsu82 iscsi: [ID 240218 kern.notice] NOTICE: iscsi session(10)
idevice.name.00e08134fe16.017 online
Feb 19 15:52:01 epsu82 scsi: [ID 799468 kern.info] sd1 at iscsi0: name
0000idevice.name.00e08134fe16.0170001,0, bus address
0000idevice.name.00e08134fe16.0170001,0
Feb 19 15:52:01 epsu82 genunix: [ID 936769 kern.info] sd1 is /iscsi/[EMAIL
PROTECTED],0
Feb 19 15:52:01 epsu82 genunix: [ID 408114 kern.info] /iscsi/[EMAIL
PROTECTED],0 (sd1) online
Feb 19 15:52:02 epsu82 pseudo: [ID 129642 kern.info] pseudo-device: devinfo0
Feb 19 15:52:02 epsu82 genunix: [ID 936769 kern.info] devinfo0 is
/pseudo/[EMAIL PROTECTED]
Feb 19 15:52:49 epsu82 scsi: [ID 193665 kern.info] sd19 at glm3: target 0 lun 0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] sd19 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED],1/[EMAIL PROTECTED],0
Feb 19 15:52:49 epsu82 hme: [ID 517527 kern.info] SUNW,hme0 : PCI IO 2.0 (Rev
Id = c1) Found
Feb 19 15:52:49 epsu82 pcipsy: [ID 370704 kern.info] PCI-device: [EMAIL
PROTECTED],1, hme0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] hme0 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED],1
Feb 19 15:52:49 epsu82 ebus: [ID 521012 kern.info] ecpp0 at ebus0: offset
14,3043bc
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] ecpp0 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED]/[EMAIL PROTECTED],3043bc
Feb 19 15:52:49 epsu82 pseudo: [ID 129642 kern.info] pseudo-device: fssnap0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] fssnap0 is /pseudo/[EMAIL
PROTECTED]
Feb 19 15:52:49 epsu82 scsi: [ID 193665 kern.info] sd35 at glm4: target 0 lun 0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] sd35 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED]/[EMAIL PROTECTED],0
Feb 19 15:52:49 epsu82 scsi: [ID 193665 kern.info] sd16 at glm2: target 1 lun 0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] sd16 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED]/[EMAIL PROTECTED],0
Feb 19 15:52:49 epsu82 scsi: [ID 193665 kern.info] sd52 at glm5: target 0 lun 0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] sd52 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED],1/[EMAIL PROTECTED],0
Feb 19 15:52:49 epsu82 fbt: [ID 741329 kern.notice] NOTICE: cannot instrument
return of fd_intr at 7bb90278: non-canonical return instruction
Feb 19 15:52:49 epsu82 scsi: [ID 193665 kern.info] sd78 at glm1: target 2 lun 0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] sd78 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED]/[EMAIL PROTECTED],0
Feb 19 15:52:49 epsu82 fbt: [ID 741329 kern.notice] NOTICE: cannot instrument
return of fd_intr at 7bb90278: non-canonical return instruction
Feb 19 15:52:49 epsu82 pseudo: [ID 129642 kern.info] pseudo-device: ramdisk1024
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] ramdisk1024 is
/pseudo/[EMAIL PROTECTED]
Feb 19 15:52:49 epsu82 pseudo: [ID 129642 kern.info] pseudo-device: winlock0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] winlock0 is
/pseudo/[EMAIL PROTECTED]
Feb 19 15:52:49 epsu82 scsi: [ID 193665 kern.info] sd17 at glm0: target 1 lun 0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] sd17 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED]/[EMAIL PROTECTED],0
Feb 19 15:52:49 epsu82 pseudo: [ID 129642 kern.info] pseudo-device: vol0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] vol0 is /pseudo/[EMAIL
PROTECTED]
Feb 19 15:52:49 epsu82 scsi: [ID 193665 kern.info] sd20 at glm3: target 1 lun 0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] sd20 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED],1/[EMAIL PROTECTED],0
Feb 19 15:52:49 epsu82 scsi: [ID 193665 kern.info] sd37 at glm4: target 1 lun 0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] sd37 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED]/[EMAIL PROTECTED],0
Feb 19 15:52:49 epsu82 scsi: [ID 193665 kern.info] sd18 at glm0: target 2 lun 0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] sd18 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED]/[EMAIL PROTECTED],0
Feb 19 15:52:49 epsu82 scsi: [ID 193665 kern.info] sd53 at glm5: target 1 lun 0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] sd53 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED],1/[EMAIL PROTECTED],0
Feb 19 15:52:49 epsu82 scsi: [ID 193665 kern.info] sd79 at glm1: target 3 lun 0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] sd79 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED]/[EMAIL PROTECTED],0
Feb 19 15:52:49 epsu82 pseudo: [ID 129642 kern.info] pseudo-device: fcp0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] fcp0 is /pseudo/[EMAIL
PROTECTED]
Feb 19 15:52:49 epsu82 pseudo: [ID 129642 kern.info] pseudo-device: fcsm0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] fcsm0 is /pseudo/[EMAIL
PROTECTED]
Feb 19 15:52:49 epsu82 pseudo: [ID 129642 kern.info] pseudo-device: llc10
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] llc10 is /pseudo/[EMAIL
PROTECTED]
Feb 19 15:52:49 epsu82 pseudo: [ID 129642 kern.info] pseudo-device: tod0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] tod0 is /pseudo/[EMAIL
PROTECTED]
Feb 19 15:52:49 epsu82 scsi: [ID 193665 kern.info] sd80 at glm1: target 4 lun 0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] sd80 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED]/[EMAIL PROTECTED],0
Feb 19 15:52:49 epsu82 scsi: [ID 193665 kern.info] sd5 at glm1: target 6 lun 0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] sd5 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED]/[EMAIL PROTECTED],0
Feb 19 15:52:49 epsu82 scsi: [ID 193665 kern.info] sd84 at glm1: target a lun 0
Feb 19 15:52:49 epsu82 genunix: [ID 936769 kern.info] sd84 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED]/[EMAIL PROTECTED],0
Feb 19 15:52:50 epsu82 scsi: [ID 193665 kern.info] sd85 at glm1: target b lun 0
Feb 19 15:52:50 epsu82 genunix: [ID 936769 kern.info] sd85 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED]/[EMAIL PROTECTED],0
Feb 19 15:52:51 epsu82 ebus: [ID 521012 kern.info] fd0 at ebus0: offset
14,3023f0
Feb 19 15:52:51 epsu82 genunix: [ID 936769 kern.info] fd0 is /[EMAIL
PROTECTED],4000/[EMAIL PROTECTED]/[EMAIL PROTECTED],3023f0
Feb 19 15:52:58 epsu82 pseudo: [ID 129642 kern.info] pseudo-device: lofi0
Feb 19 15:52:58 epsu82 genunix: [ID 936769 kern.info] lofi0 is /pseudo/[EMAIL
PROTECTED]
Feb 19 15:52:58 epsu82 pseudo: [ID 129642 kern.info] pseudo-device: rsm0
Feb 19 15:52:58 epsu82 genunix: [ID 936769 kern.info] rsm0 is /pseudo/[EMAIL
PROTECTED]
Feb 19 15:52:58 epsu82 pseudo: [ID 129642 kern.info] pseudo-device: trapstat0
Feb 19 15:52:58 epsu82 genunix: [ID 936769 kern.info] trapstat0 is
/pseudo/[EMAIL PROTECTED]
Feb 19 15:52:58 epsu82 pseudo: [ID 129642 kern.info] pseudo-device: zfs0
Feb 19 15:52:58 epsu82 genunix: [ID 936769 kern.info] zfs0 is /pseudo/[EMAIL
PROTECTED]
Feb 19 16:08:54 epsu82 scsi: [ID 107833 kern.warning] WARNING: /iscsi/[EMAIL
PROTECTED],0 (sd1):
Feb 19 16:08:54 epsu82 SCSI transport failed: reason 'tran_err': retrying
command
Feb 19 16:11:04 epsu82 scsi: [ID 107833 kern.warning] WARNING: /iscsi/[EMAIL
PROTECTED],0 (sd1):
Feb 19 16:11:04 epsu82 SCSI transport failed: reason 'tran_err': giving up
Feb 19 16:12:42 epsu82 scsi: [ID 107833 kern.warning] WARNING: /iscsi/[EMAIL
PROTECTED],0 (sd1):
Feb 19 16:12:42 epsu82 SCSI transport failed: reason 'tran_err': giving up
Feb 19 16:15:15 epsu82 scsi: [ID 107833 kern.warning] WARNING: /iscsi/[EMAIL
PROTECTED],0 (sd1):
Feb 19 16:15:15 epsu82 SCSI transport failed: reason 'tran_err': giving up
Feb 19 16:21:16 epsu82 scsi: [ID 107833 kern.warning] WARNING: /iscsi/[EMAIL
PROTECTED],0 (sd1):
Feb 19 16:21:16 epsu82 SCSI transport failed: reason 'tran_err': giving up
And now i have my disk with slow perf.
I will continue to make tests and post my snoop output if needed. I did not see
so much before when i was running snoop. But maybe someone here is more custom
to view it's output than me.
Regards,
Pierre
This message posted from opensolaris.org
_______________________________________________
storage-discuss mailing list
[email protected]
http://mail.opensolaris.org/mailman/listinfo/storage-discuss
