Thank you Ryan for your response, I have included all the information you
requested in line to this document:
I will be testing SNV_86 again to see whether the problem persists, maybe it's
my hardware. I will confirm that soon enough.
On Thu, October 16, 2008 10:31 am, Ryan Arneson wrote:
> Tano wrote:
>> I'm not sure if this is a problem with the iscsitarget or zfs. I'd greatly
>> appreciate it if it gets moved to the proper list.
>>
>> Well I'm just about out of ideas on what might be wrong..
>>
>> Quick history:
>>
>> I installed OS 2008.05 when it was SNV_86 to try out ZFS with VMWare. Found
>> out that multilun's were being treated as multipaths so waited till SNV_94
>> came out to fix the issues with VMWARE and iscsitadm/zfs shareiscsi=on.
>>
>> I Installed OS2008.05 on a virtual machine as a test bed, pkg image-update
>> to SNV_94 a month ago, made some thin provisioned partitions, shared them
>> with iscsitadm and mounted on VMWare without any problems. Ran storage
>> VMotion and all went well.
>>
>> So with this success I purchased a Dell 1900 with a PERC 5/i controller 6 x
>> 15K SAS DRIVEs with ZFS RAIDZ1 configuration. I shared the zfs partitions
>> and mounted them on VMWare. Everything is great till I have to write to the
>> disks.
>>
>> It won't write!
>>
>
> What's the error exactly?
>From the VMWARE Infrastructure front end, everything looks like is in order.
I "Send Targets" to the iscsi IP, then rescan the HBA and it detects all the
LUNs and Targets.
> What step are you performing to get the error? Creating the vmfs3
> filesystem? Accessing the mountpoint?
The error occurs when attempting to write large data sets to the mount point.
Formatting the drive VMFS3 works, manually copying 5 megabytes of data to the
Target works. Running cp -a of the VM folder or cold VM migration will hang the
infrastructure client and the ESX host lags. No timeouts of any sort will
occur. I waited up to an hour.
>
>>
>> Steps I took creating the disks
>>
>> 1) Installed mega_sas drivers.
>> 2) zpool create tank raidz c5t0d0 c5t1d0 c5t2d0 c5t3d0 c5t4d0 c5t5d0
>> 3) zfs create -V 1TB tank/disk1
>> 4) zfs create -V 1TB tank/disk2
>> 5) iscsitadm create target -b /dev/zvol/rdsk/tank/disk1 LABEL1
>> 6) iscsitadm create target -b /dev/zvol/rdsk/tank/disk2 LABEL2
>>
>> Now both drives are lun 0 but with uniqu VMHBA device identifiers. SO they
>> are detected as seperate drives.
>>
>> I then redid (deleted) step 5 and 6 and changed it too
>>
>> 5) iscsitadm create target -u 0 -b /dev/zvol/rdsk/tank/disk1 LABEL1
>> 6) iscsitadm create target -u 1 -b /dev/zvol/rdsk/tank/disk2 LABEL1
>>
>> VMWARE discovers the seperate LUNs on the Device identifier, but still
>> unable to write to the iscsi luns.
>>
>> Why is it that the steps I've conducted in SNV_94 work but in SNV_97,98, or
>> 99 don't.
>>
>> Any ideas?? any log files I can check? I am still an ignorant linux user so
>> I only know to look in /var/log :)
>>
> The relevant errors from /var/log/vmkernel on the ESX server would be
> helpful.
>
So I weeded out the best that I could the logs from /var/log/vmkernel.
Basically everytime I initiated a command from vmware I captured the logs. I
have broken down what I was doing at what point in the logs.
Again the complete breakdown of both systems:
[b]VMware ESX 3.5 Update 2[/b]
[EMAIL PROTECTED] log]# uname -a
Linux vmware-860-1.ucr.edu 2.4.21-57.ELvmnix #1 Tue Aug 12 17:28:03 PDT 2008
i686 i686 i386 GNU/Linux
[EMAIL PROTECTED] log]# arch
i686
[b]Opensolaris:[/b]
Dell Poweredge 1900 PERC 5/i 6 disk 450GB each SAS 15kRPM
Broadcomm BNX driver: no conflicts. Quadcore 1600 Mhz 1066 FSB 8 GB RAM
[EMAIL PROTECTED]:~# uname -a
SunOS iscsi-sas 5.11 snv_99 i86pc i386 i86pc Solaris
[EMAIL PROTECTED]:~# isainfo -v
64-bit amd64 applications
ssse3 cx16 mon sse3 sse2 sse fxsr mmx cmov amd_sysc cx8 tsc fpu
32-bit i386 applications
ssse3 ahf cx16 mon sse3 sse2 sse fxsr mmx cmov sep cx8 tsc fpu
[EMAIL PROTECTED]:~# zpool status -v
pool: rpool
state: ONLINE
scrub: none requested
config:
NAME STATE READ WRITE CKSUM
rpool ONLINE 0 0 0
mirror ONLINE 0 0 0
c3t0d0s0 ONLINE 0 0 0
c3t1d0 ONLINE 0 0 0
errors: No known data errors
pool: vdrive
state: ONLINE
scrub: none requested
config:
NAME STATE READ WRITE CKSUM
vdrive ONLINE 0 0 0
raidz1 ONLINE 0 0 0
c5t0d0 ONLINE 0 0 0
c5t1d0 ONLINE 0 0 0
c5t2d0 ONLINE 0 0 0
c5t3d0 ONLINE 0 0 0
c5t4d0 ONLINE 0 0 0
c5t5d0 ONLINE 0 0 0
errors: No known data errors
[EMAIL PROTECTED]:~#
[EMAIL PROTECTED]:~# zfs create -V 750G vdrive/LUNA
[EMAIL PROTECTED]:~# zfs create -V 1250G vdrive/LUNB
[EMAIL PROTECTED]:~# zfs list
NAME USED AVAIL REFER MOUNTPOINT
rpool 5.12G 140G 56.5K /rpool
rpool/ROOT 5.11G 140G 18K /rpool/ROOT
rpool/ROOT/opensolaris 3.07G 140G 2.90G legacy
rpool/ROOT/opensolaris-1 2.04G 140G 3.22G /tmp/tmp4qmK4_
rpool/ROOT/opensolaris-1/opt 216K 140G 3.60M /tmp/tmp4qmK4_/opt
rpool/ROOT/opensolaris/opt 3.61M 140G 3.61M /opt
rpool/export 14.3M 140G 19K /export
rpool/export/home 14.3M 140G 14.2M /export/home
vdrive 1.95T 39.5G 29.9K /vdrive
vdrive/LUNA 750G 790G 26.6K -
vdrive/LUNB 1.22T 1.26T 26.6K -
[EMAIL PROTECTED]:~# iscsitadm create target -b /dev/zvol/rdsk/vdrive/LUNA LUNA
[EMAIL PROTECTED]:~# iscsitadm create target -b /dev/zvol/rdsk/vdrive/LUNB LUNB
[EMAIL PROTECTED]:~# iscsitadm list target -v
Target: luna
iSCSI Name: iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
Connections: 0
ACL list:
TPGT list:
LUN information:
LUN: 0
GUID: 0
VID: SUN
PID: SOLARIS
Type: disk
Size: 750G
Backing store: /dev/zvol/rdsk/vdrive/LUNA
Status: online
Target: lunb
iSCSI Name: iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
Connections: 0
ACL list:
TPGT list:
LUN information:
LUN: 0
GUID: 0
VID: SUN
PID: SOLARIS
Type: disk
Size: 1.2T
Backing store: /dev/zvol/rdsk/vdrive/LUNB
Status: online
[EMAIL PROTECTED]:~#
Logs before attempting to access the ISCSI target daemon from VMWARE
[EMAIL PROTECTED] log]# tail -f /var/log/vmkernel
Oct 16 04:37:36 vmware-860-1 vmkernel: 0:00:02:07.345 cpu1:1033)Config: 414:
"HostLocalSwapDirEnabled" = 0, Old Value: 0, (Status: 0x0)
Oct 16 04:47:18 vmware-860-1 vmkernel: 0:00:11:48.805 cpu1:1035)BC: 814: FileIO
failed with 0x0xbad0006(Limit exceeded)
[b]Once I connect VMWare to the iscsi target on Opensolaris:[/b]
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.951 cpu0:1035)iSCSI: bus 0
target 35 = iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.951 cpu0:1035)iSCSI: bus 0
target 35 portal 0 = address 138.23.117.32 port 3260 group 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.951 cpu0:1035)LinStubs: 2045:
fn=0x9bb1d8 arg=0x0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.951 cpu0:1035)World: vm 1068:
900: Starting world driver with flags 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.951 cpu1:1068)LinStubs: 2114:
fn=0x9bb1d8 arg=0x0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.951 cpu0:1035)LinStubs: 2045:
fn=0x9b186c arg=0x9a03f90
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.951 cpu0:1035)World: vm 1069:
900: Starting world driver with flags 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.951 cpu0:1035)LinStubs: 2045:
fn=0x9b7cd0 arg=0x9a03f90
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.951 cpu0:1035)World: vm 1070:
900: Starting world driver with flags 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.952 cpu0:1070)LinStubs: 2114:
fn=0x9b7cd0 arg=0x9a03f90
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.952 cpu0:1070)iSCSI: bus 0
target 35 trying to establish session 0x9a03f90 to portal 0, address
138.23.117.32 port 3260 group 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.952 cpu0:1069)LinStubs: 2114:
fn=0x9b186c arg=0x9a03f90
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.962 cpu0:1070)iSCSI: bus 0
target 35 established session 0x9a03f90 #1 to portal 0, address 138.23.117.32
port 3260 group 1, alias lunb
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.971 cpu0:1035)iSCSI: bus 0
target 36 = iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.971 cpu0:1035)iSCSI: bus 0
target 36 portal 0 = address 0.0.0.0 port 3260 group 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.971 cpu0:1035)LinStubs: 2045:
fn=0x9b186c arg=0x9a180a0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.971 cpu0:1035)World: vm 1071:
900: Starting world driver with flags 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.971 cpu0:1035)LinStubs: 2045:
fn=0x9b7cd0 arg=0x9a180a0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.971 cpu0:1035)World: vm 1072:
900: Starting world driver with flags 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.971 cpu1:1072)LinStubs: 2114:
fn=0x9b7cd0 arg=0x9a180a0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.971 cpu1:1072)iSCSI: bus 0
target 36 trying to establish session 0x9a180a0 to portal 0, address 0.0.0.0
port 3260 group 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.971 cpu1:1071)LinStubs: 2114:
fn=0x9b186c arg=0x9a180a0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.971 cpu1:1072)iSCSI: session
0x9a180a0 to iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
failed to connect, rc -5, I/O error
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.971 cpu1:1072)iSCSI: session
0x9a180a0 connect failed at 606098
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.971 cpu1:1072)iSCSI: session
0x9a180a0 to iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
waiting 1 seconds before next login attempt
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.991 cpu0:1035)iSCSI: bus 0
target 37 = iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.991 cpu0:1035)iSCSI: bus 0
target 37 portal 0 = address 0.0.0.0 port 3260 group 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.991 cpu0:1035)LinStubs: 2045:
fn=0x9b186c arg=0x9a2c1b0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.991 cpu0:1035)World: vm 1073:
900: Starting world driver with flags 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.991 cpu0:1035)LinStubs: 2045:
fn=0x9b7cd0 arg=0x9a2c1b0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.991 cpu0:1035)World: vm 1074:
900: Starting world driver with flags 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.992 cpu0:1074)LinStubs: 2114:
fn=0x9b7cd0 arg=0x9a2c1b0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.992 cpu0:1074)iSCSI: bus 0
target 37 trying to establish session 0x9a2c1b0 to portal 0, address 0.0.0.0
port 3260 group 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.992 cpu0:1073)LinStubs: 2114:
fn=0x9b186c arg=0x9a2c1b0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.992 cpu0:1074)iSCSI: session
0x9a2c1b0 to iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
failed to connect, rc -5, I/O error
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.992 cpu0:1074)iSCSI: session
0x9a2c1b0 connect failed at 606100
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:00.992 cpu0:1074)iSCSI: session
0x9a2c1b0 to iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
waiting 1 seconds before next login attempt
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.011 cpu0:1034)iSCSI: bus 0
target 38 = iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.011 cpu0:1034)iSCSI: bus 0
target 38 portal 0 = address 138.23.117.32 port 3260 group 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.011 cpu0:1034)LinStubs: 2045:
fn=0x9b186c arg=0x9a402c0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.011 cpu0:1034)World: vm 1075:
900: Starting world driver with flags 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.011 cpu1:1075)LinStubs: 2114:
fn=0x9b186c arg=0x9a402c0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.012 cpu0:1034)LinStubs: 2045:
fn=0x9b7cd0 arg=0x9a402c0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.012 cpu0:1034)World: vm 1076:
900: Starting world driver with flags 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.012 cpu1:1076)LinStubs: 2114:
fn=0x9b7cd0 arg=0x9a402c0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.012 cpu1:1076)iSCSI: bus 0
target 38 trying to establish session 0x9a402c0 to portal 0, address
138.23.117.32 port 3260 group 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.021 cpu1:1076)iSCSI: bus 0
target 38 established session 0x9a402c0 #1 to portal 0, address 138.23.117.32
port 3260 group 1, alias luna
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.031 cpu0:1034)iSCSI: bus 0
target 39 = iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.031 cpu0:1034)iSCSI: bus 0
target 39 portal 0 = address 0.0.0.0 port 3260 group 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.031 cpu0:1034)LinStubs: 2045:
fn=0x9b186c arg=0x9a543d0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.031 cpu0:1034)World: vm 1077:
900: Starting world driver with flags 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.032 cpu0:1034)LinStubs: 2045:
fn=0x9b7cd0 arg=0x9a543d0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.032 cpu0:1034)World: vm 1078:
900: Starting world driver with flags 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.032 cpu0:1078)LinStubs: 2114:
fn=0x9b7cd0 arg=0x9a543d0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.032 cpu0:1078)iSCSI: bus 0
target 39 trying to establish session 0x9a543d0 to portal 0, address 0.0.0.0
port 3260 group 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.032 cpu0:1077)LinStubs: 2114:
fn=0x9b186c arg=0x9a543d0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.032 cpu0:1078)iSCSI: session
0x9a543d0 to iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
failed to connect, rc -5, I/O error
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.032 cpu0:1078)iSCSI: session
0x9a543d0 connect failed at 606104
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.032 cpu0:1078)iSCSI: session
0x9a543d0 to iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
waiting 1 seconds before next login attempt
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.051 cpu0:1034)iSCSI: bus 0
target 40 = iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.051 cpu0:1034)iSCSI: bus 0
target 40 portal 0 = address 0.0.0.0 port 3260 group 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.051 cpu0:1034)LinStubs: 2045:
fn=0x9b186c arg=0x9a684e0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.051 cpu0:1034)World: vm 1079:
900: Starting world driver with flags 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.052 cpu1:1079)LinStubs: 2114:
fn=0x9b186c arg=0x9a684e0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.052 cpu0:1034)LinStubs: 2045:
fn=0x9b7cd0 arg=0x9a684e0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.052 cpu0:1034)World: vm 1080:
900: Starting world driver with flags 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.052 cpu1:1080)LinStubs: 2114:
fn=0x9b7cd0 arg=0x9a684e0
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.052 cpu1:1080)iSCSI: bus 0
target 40 trying to establish session 0x9a684e0 to portal 0, address 0.0.0.0
port 3260 group 1
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.052 cpu1:1080)iSCSI: session
0x9a684e0 to iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
failed to connect, rc -5, I/O error
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.052 cpu1:1080)iSCSI: session
0x9a684e0 connect failed at 606106
Oct 16 06:16:30 vmware-860-1 vmkernel: 0:01:41:01.052 cpu1:1080)iSCSI: session
0x9a684e0 to iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
waiting 1 seconds before next login attempt
Oct 16 06:16:31 vmware-860-1 vmkernel: 0:01:41:01.973 cpu1:1072)iSCSI: bus 0
target 36 trying to establish session 0x9a180a0 to portal 0, address 0.0.0.0
port 3260 group 1
Oct 16 06:16:31 vmware-860-1 vmkernel: 0:01:41:01.973 cpu1:1072)iSCSI: session
0x9a180a0 to iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
failed to connect, rc -5, I/O error
Oct 16 06:16:31 vmware-860-1 vmkernel: 0:01:41:01.973 cpu1:1072)iSCSI: session
0x9a180a0 connect failed at 606198
Oct 16 06:16:31 vmware-860-1 vmkernel: 0:01:41:01.973 cpu1:1072)<5>iSCSI:
session 0x9a180a0 iSCSI: session 0x9a180a0 retrying all the portals again,
since the portal list got exhausted
Oct 16 06:16:31 vmware-860-1 vmkernel: 0:01:41:01.973 cpu1:1072)iSCSI: session
0x9a180a0 to iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
waiting 1 seconds before next login attempt
Oct 16 06:16:31 vmware-860-1 vmkernel: 0:01:41:01.994 cpu0:1074)iSCSI: bus 0
target 37 trying to establish session 0x9a2c1b0 to portal 0, address 0.0.0.0
port 3260 group 1
Oct 16 06:16:31 vmware-860-1 vmkernel: 0:01:41:01.994 cpu0:1074)iSCSI: session
0x9a2c1b0 to iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
failed to connect, rc -5, I/O error
Oct 16 06:16:31 vmware-860-1 vmkernel: 0:01:41:01.994 cpu0:1074)iSCSI: session
0x9a2c1b0 connect failed at 606200
Oct 16 06:16:31 vmware-860-1 vmkernel: 0:01:41:01.994 cpu0:1074)<5>iSCSI:
session 0x9a2c1b0 iSCSI: session 0x9a2c1b0 retrying all the portals again,
since the portal list got exhausted
Oct 16 06:16:31 vmware-860-1 vmkernel: 0:01:41:01.994 cpu0:1074)iSCSI: session
0x9a2c1b0 to iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
waiting 1 seconds before next login attempt
Oct 16 06:16:31 vmware-860-1 vmkernel: 0:01:41:02.034 cpu0:1078)iSCSI: bus 0
target 39 trying to establish session 0x9a543d0 to portal 0, address 0.0.0.0
port 3260 group 1
Oct 16 06:16:31 vmware-860-1 vmkernel: 0:01:41:02.034 cpu0:1078)iSCSI: session
0x9a543d0 to iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
failed to connect, rc -5, I/O error
Oct 16 06:16:31 vmware-860-1 vmkernel: 0:01:41:02.034 cpu0:1078)iSCSI: session
0x9a543d0 connect failed at 606204
[b]Adding and Formatting LUNB with VMFS[/b]
Oct 16 06:34:46 vmware-860-1 vmkernel: 0:01:59:17.244 cpu1:1035)LVM: 6505:
LVMProbeDevice failed with status "Device does not contain a logical volume".
Oct 16 06:34:46 vmware-860-1 vmkernel: 0:01:59:17.586 cpu1:1035)LVM: 4866:
Initialized vmhba33:35:0:1, devID 48f742f6-af6382c0-581e-0019b9f7edde
Oct 16 06:34:46 vmware-860-1 vmkernel: 0:01:59:17.591 cpu1:1035)LVM: 5016: Zero
volumeSize specified: using available space (1342158623232).
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.284 cpu1:1035)FS3: 130:
<START rootDir>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.284 cpu1:1035)Lock [type
10c00001 offset 8486912 v 1, hb offset 0
Oct 16 06:34:54 vmware-860-1 vmkernel: gen 0, mode 0, owner
00000000-00000000-0000-000000000000 mtime 7165]
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.284 cpu1:1035)Addr <4, 0, 0>,
gen 1, links 2, type dir, flags 0x0, uid 0, gid 0, mode 1755
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.284 cpu1:1035)len 980, nb 1
tbz 0, zla 1, bs 8388608
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.284 cpu1:1035)FS3: 132: <END
rootDir>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.284 cpu1:1035)FS3: 157:
<START fbb>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.284 cpu1:1035)159968
resources, each of size 0
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.284 cpu1:1035)Organized as 25
CGs, 32 C/CG and 200 R/C
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.284 cpu1:1035)CGsize 32768.
0th CG at 65536
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.284 cpu1:1035)FS3: 159: <END
fbb>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.353 cpu1:1035)FS3: 130:
<START fbb>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.353 cpu1:1035)Lock [type
10c00001 offset 8488960 v 1, hb offset 0
Oct 16 06:34:54 vmware-860-1 vmkernel: gen 0, mode 0, owner
00000000-00000000-0000-000000000000 mtime 7165]
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.353 cpu1:1035)Addr <4, 0, 1>,
gen 1, links 1, type sys, flags 0x0, uid 0, gid 0, mode 400
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.353 cpu1:1035)len 884736, nb
1 tbz 0, zla 1, bs 8388608
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.353 cpu1:1035)FS3: 132: <END
fbb>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.353 cpu1:1035)FS3: 157:
<START fdc>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.353 cpu1:1035)30720
resources, each of size 2048
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.353 cpu1:1035)Organized as 5
CGs, 32 C/CG and 200 R/C
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.353 cpu1:1035)CGsize
13139968. 0th CG at 65536
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.353 cpu1:1035)FS3: 159: <END
fdc>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.406 cpu1:1035)FS3: 130:
<START fdc>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.406 cpu1:1035)Lock [type
10c00001 offset 8491008 v 1, hb offset 0
Oct 16 06:34:54 vmware-860-1 vmkernel: gen 0, mode 0, owner
00000000-00000000-0000-000000000000 mtime 7165]
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.406 cpu1:1035)Addr <4, 0, 2>,
gen 1, links 1, type sys, flags 0x0, uid 0, gid 0, mode 400
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.406 cpu1:1035)len 63143936,
nb 8 tbz 0, zla 1, bs 8388608
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.406 cpu1:1035)FS3: 132: <END
fdc>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.406 cpu1:1035)FS3: 157:
<START pbc>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.406 cpu1:1035)61440
resources, each of size 4096
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.406 cpu1:1035)Organized as
120 CGs, 32 C/CG and 16 R/C
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.406 cpu1:1035)CGsize 2129920.
0th CG at 65536
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.406 cpu1:1035)FS3: 159: <END
pbc>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.556 cpu1:1035)FS3: 130:
<START pbc>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.556 cpu1:1035)Lock [type
10c00001 offset 8493056 v 1, hb offset 0
Oct 16 06:34:54 vmware-860-1 vmkernel: gen 0, mode 0, owner
00000000-00000000-0000-000000000000 mtime 7165]
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.556 cpu1:1035)Addr <4, 0, 3>,
gen 1, links 1, type sys, flags 0x0, uid 0, gid 0, mode 400
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.556 cpu1:1035)len 255655936,
nb 31 tbz 0, zla 1, bs 8388608
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.556 cpu1:1035)FS3: 132: <END
pbc>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.556 cpu1:1035)FS3: 157:
<START sbc>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.556 cpu1:1035)3968 resources,
each of size 65536
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.556 cpu1:1035)Organized as 8
CGs, 32 C/CG and 16 R/C
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.556 cpu1:1035)CGsize
33587200. 0th CG at 65536
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.556 cpu1:1035)FS3: 159: <END
sbc>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.611 cpu1:1035)FS3: 130:
<START sbc>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.611 cpu1:1035)Lock [type
10c00001 offset 8495104 v 1, hb offset 0
Oct 16 06:34:54 vmware-860-1 vmkernel: gen 0, mode 0, owner
00000000-00000000-0000-000000000000 mtime 7165]
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.611 cpu1:1035)Addr <4, 0, 4>,
gen 1, links 1, type sys, flags 0x0, uid 0, gid 0, mode 400
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.611 cpu1:1035)len 260374528,
nb 32 tbz 0, zla 1, bs 8388608
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.611 cpu1:1035)FS3: 132: <END
sbc>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.611 cpu1:1035)FS3: 130:
<START vh>
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.611 cpu1:1035)Lock [type
10c00001 offset 8497152 v 1, hb offset 0
Oct 16 06:34:54 vmware-860-1 vmkernel: gen 0, mode 0, owner
00000000-00000000-0000-000000000000 mtime 7165]
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.611 cpu1:1035)Addr <4, 0, 5>,
gen 1, links 1, type sys, flags 0x0, uid 0, gid 0, mode 400
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.611 cpu1:1035)len 8388608, nb
1 tbz 0, zla 1, bs 8388608
Oct 16 06:34:54 vmware-860-1 vmkernel: 0:01:59:25.611 cpu1:1035)FS3: 132: <END
vh>
[b]Adding and Formatting LUNB[/b]
Oct 16 06:36:19 vmware-860-1 vmkernel: 0:02:00:50.176 cpu1:1034)LVM: 6505:
LVMProbeDevice failed with status "Device does not contain a logical volume".
Oct 16 06:36:19 vmware-860-1 vmkernel: 0:02:00:50.508 cpu1:1034)LVM: 4866:
Initialized vmhba33:38:0:1, devID 48f74353-97bf70f2-8731-0019b9f7edde
Oct 16 06:36:19 vmware-860-1 vmkernel: 0:02:00:50.513 cpu1:1034)LVM: 5016: Zero
volumeSize specified: using available space (805286372352).
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.442 cpu1:1034)FS3: 130:
<START rootDir>
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.442 cpu1:1034)Lock [type
10c00001 offset 4292608 v 1, hb offset 0
Oct 16 06:36:24 vmware-860-1 vmkernel: gen 0, mode 0, owner
00000000-00000000-0000-000000000000 mtime 7255]
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.442 cpu1:1034)Addr <4, 0, 0>,
gen 1, links 2, type dir, flags 0x0, uid 0, gid 0, mode 1755
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.442 cpu1:1034)len 980, nb 1
tbz 0, zla 1, bs 1048576
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.442 cpu1:1034)FS3: 132: <END
rootDir>
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.442 cpu1:1034)FS3: 157:
<START fbb>
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.442 cpu1:1034)767744
resources, each of size 0
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.442 cpu1:1034)Organized as
120 CGs, 32 C/CG and 200 R/C
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.442 cpu1:1034)CGsize 32768.
0th CG at 65536
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.442 cpu1:1034)FS3: 159: <END
fbb>
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.765 cpu1:1034)FS3: 130:
<START fbb>
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.765 cpu1:1034)Lock [type
10c00001 offset 4294656 v 1, hb offset 0
Oct 16 06:36:24 vmware-860-1 vmkernel: gen 0, mode 0, owner
00000000-00000000-0000-000000000000 mtime 7255]
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.765 cpu1:1034)Addr <4, 0, 1>,
gen 1, links 1, type sys, flags 0x0, uid 0, gid 0, mode 400
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.765 cpu1:1034)len 3997696, nb
4 tbz 0, zla 1, bs 1048576
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.765 cpu1:1034)FS3: 132: <END
fbb>
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.765 cpu1:1034)FS3: 157:
<START fdc>
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.765 cpu1:1034)30720
resources, each of size 2048
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.765 cpu1:1034)Organized as 5
CGs, 32 C/CG and 200 R/C
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.765 cpu1:1034)CGsize
13139968. 0th CG at 65536
Oct 16 06:36:24 vmware-860-1 vmkernel: 0:02:00:55.765 cpu1:1034)FS3: 159: <END
fdc>
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.820 cpu1:1034)FS3: 130:
<START fdc>
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.820 cpu1:1034)Lock [type
10c00001 offset 4296704 v 1, hb offset 0
Oct 16 06:36:25 vmware-860-1 vmkernel: gen 0, mode 0, owner
00000000-00000000-0000-000000000000 mtime 7255]
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.820 cpu1:1034)Addr <4, 0, 2>,
gen 1, links 1, type sys, flags 0x0, uid 0, gid 0, mode 400
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.820 cpu1:1034)len 63143936,
nb 61 tbz 0, zla 1, bs 1048576
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.820 cpu1:1034)FS3: 132: <END
fdc>
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.820 cpu1:1034)FS3: 157:
<START pbc>
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.820 cpu1:1034)61440
resources, each of size 4096
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.820 cpu1:1034)Organized as
120 CGs, 32 C/CG and 16 R/C
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.820 cpu1:1034)CGsize 2129920.
0th CG at 65536
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.820 cpu1:1034)FS3: 159: <END
pbc>
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.970 cpu1:1034)FS3: 130:
<START pbc>
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.970 cpu1:1034)Lock [type
10c00001 offset 4298752 v 1, hb offset 0
Oct 16 06:36:25 vmware-860-1 vmkernel: gen 0, mode 0, owner
00000000-00000000-0000-000000000000 mtime 7255]
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.970 cpu1:1034)Addr <4, 0, 3>,
gen 1, links 1, type sys, flags 0x0, uid 0, gid 0, mode 400
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.970 cpu1:1034)len 255655936,
nb 244 tbz 0, zla 1, bs 1048576
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.970 cpu1:1034)FS3: 132: <END
pbc>
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.970 cpu1:1034)FS3: 157:
<START sbc>
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.970 cpu1:1034)3968 resources,
each of size 65536
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.970 cpu1:1034)Organized as 8
CGs, 32 C/CG and 16 R/C
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.970 cpu1:1034)CGsize
33587200. 0th CG at 65536
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:55.970 cpu1:1034)FS3: 159: <END
sbc>
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:56.027 cpu1:1034)FS3: 130:
<START sbc>
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:56.027 cpu1:1034)Lock [type
10c00001 offset 4300800 v 1, hb offset 0
Oct 16 06:36:25 vmware-860-1 vmkernel: gen 0, mode 0, owner
00000000-00000000-0000-000000000000 mtime 7255]
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:56.027 cpu1:1034)Addr <4, 0, 4>,
gen 1, links 1, type sys, flags 0x0, uid 0, gid 0, mode 400
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:56.027 cpu1:1034)len 260374528,
nb 249 tbz 0, zla 1, bs 1048576
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:56.027 cpu1:1034)FS3: 132: <END
sbc>
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:56.027 cpu1:1034)FS3: 130:
<START vh>
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:56.027 cpu1:1034)Lock [type
10c00001 offset 4302848 v 1, hb offset 0
Oct 16 06:36:25 vmware-860-1 vmkernel: gen 0, mode 0, owner
00000000-00000000-0000-000000000000 mtime 7256]
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:56.027 cpu1:1034)Addr <4, 0, 5>,
gen 1, links 1, type sys, flags 0x0, uid 0, gid 0, mode 400
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:56.027 cpu1:1034)len 4194304, nb
4 tbz 0, zla 1, bs 1048576
Oct 16 06:36:25 vmware-860-1 vmkernel: 0:02:00:56.027 cpu1:1034)FS3: 132: <END
vh>
[b]When attempting to copy the VM from the local VMFS store to the ISCSI
target[/b]
Oct 16 06:38:00 vmware-860-1 vmkernel: 0:02:02:31.108 cpu0:1061)LinSCSI: 3201:
Abort failed for cmd with serial=1195, status=bad0001, retval=bad0001
Oct 16 06:38:00 vmware-860-1 last message repeated 3 times
Oct 16 06:38:00 vmware-860-1 vmkernel: 0:02:02:31.109 cpu0:1061)LinSCSI: 3201:
Abort failed for cmd with serial=1195, status=bad0001, retval=bad0001
Oct 16 06:38:28 vmware-860-1 vmkernel: 0:02:02:59.674 cpu1:1063)WARNING: FS3:
2649: Writing lock [type 10c00002 offset 9019392 v 3, hb offset 3460096
Oct 16 06:38:28 vmware-860-1 vmkernel: gen 25, mode 0, owner
00000000-00000000-0000-000000000000 mtime 7339] failed: Timeout (ok to retry)
Oct 16 06:38:28 vmware-860-1 vmkernel: 0:02:02:59.675 cpu1:1063)Res3: 585:
Failed to unlock cluster 2600 (typeID 1): Timeout (ok to retry)
Oct 16 06:38:28 vmware-860-1 vmkernel: 0:02:02:59.675 cpu1:1063)FS3: 5032:
Waiting for timed-out heartbeat [HB state abcdef02 offset 3460096 gen 25 stamp
7354900048 uuid 48f72706-f36533bb-10a9-0019b9f7eddd jrnl <FB 499802> drv 4.31]
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.092 cpu1:1072)iSCSI: bus 0
target 36 trying to establish session 0x9a180a0 to portal 0, address 0.0.0.0
port 3260 group 1
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.093 cpu1:1072)iSCSI: session
0x9a180a0 to iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
failed to connect, rc -5, I/O error
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.093 cpu1:1072)iSCSI: session
0x9a180a0 connect failed at 738010
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.093 cpu1:1072)<5>iSCSI:
session 0x9a180a0 iSCSI: session 0x9a180a0 retrying all the portals again,
since the portal list got exhausted
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.093 cpu1:1072)iSCSI: session
0x9a180a0 to iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
waiting 60 seconds before next login attempt
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.098 cpu1:1074)iSCSI: bus 0
target 37 trying to establish session 0x9a2c1b0 to portal 0, address 0.0.0.0
port 3260 group 1
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.098 cpu1:1074)iSCSI: session
0x9a2c1b0 to iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
failed to connect, rc -5, I/O error
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.099 cpu1:1074)iSCSI: session
0x9a2c1b0 connect failed at 738010
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.099 cpu1:1074)<5>iSCSI:
session 0x9a2c1b0 iSCSI: session 0x9a2c1b0 retrying all the portals again,
since the portal list got exhausted
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.099 cpu1:1074)iSCSI: session
0x9a2c1b0 to iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
waiting 60 seconds before next login attempt
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.150 cpu1:1078)iSCSI: bus 0
target 39 trying to establish session 0x9a543d0 to portal 0, address 0.0.0.0
port 3260 group 1
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.151 cpu1:1078)iSCSI: session
0x9a543d0 to iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
failed to connect, rc -5, I/O error
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.151 cpu1:1078)iSCSI: session
0x9a543d0 connect failed at 738016
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.151 cpu1:1078)<5>iSCSI:
session 0x9a543d0 iSCSI: session 0x9a543d0 retrying all the portals again,
since the portal list got exhausted
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.151 cpu1:1078)iSCSI: session
0x9a543d0 to iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
waiting 60 seconds before next login attempt
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.166 cpu1:1080)iSCSI: bus 0
target 40 trying to establish session 0x9a684e0 to portal 0, address 0.0.0.0
port 3260 group 1
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.167 cpu1:1080)iSCSI: session
0x9a684e0 to iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
failed to connect, rc -5, I/O error
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.167 cpu1:1080)iSCSI: session
0x9a684e0 connect failed at 738017
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.167 cpu1:1080)<5>iSCSI:
session 0x9a684e0 iSCSI: session 0x9a684e0 retrying all the portals again,
since the portal list got exhausted
Oct 16 06:38:29 vmware-860-1 vmkernel: 0:02:03:00.167 cpu1:1080)iSCSI: session
0x9a684e0 to iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
waiting 60 seconds before next login attempt
Oct 16 06:38:37 vmware-860-1 vmkernel: 0:02:03:08.108 cpu1:1068)iSCSI: 50
second timeout expired for session 0x9a402c0, rx 733811, ping 738310, now 738811
Oct 16 06:38:37 vmware-860-1 vmkernel: 0:02:03:08.109 cpu1:1075)iSCSI: session
0x9a402c0 xmit_data failed to send 131120 bytes, rc -512
Oct 16 06:38:37 vmware-860-1 vmkernel: 0:02:03:08.109 cpu1:1076)iSCSI: session
0x9a402c0 to luna dropped
Oct 16 06:38:37 vmware-860-1 vmkernel: 0:02:03:08.109 cpu1:1076)iSCSI: session
0x9a402c0 for (2 0 38 *) rx thread 1076handled timeout, notify tx
Oct 16 06:38:37 vmware-860-1 vmkernel: 0:02:03:08.109 cpu1:1076)iSCSI: bus 0
target 38 trying to establish session 0x9a402c0 to portal 0, address
138.23.117.32 port 3260 group 1
Oct 16 06:38:37 vmware-860-1 vmkernel: 0:02:03:08.115 cpu1:1076)iSCSI: bus 0
target 38 established session 0x9a402c0 #2 to portal 0, address 138.23.117.32
port 3260 group 1, alias luna
Oct 16 06:38:37 vmware-860-1 vmkernel: VMWARE SCSI Id: Supported VPD pages for
vmhba33:C0:T35:L0 : 0x0 0x80 0x83 0x86
Oct 16 06:38:37 vmware-860-1 vmkernel: VMWARE SCSI Id: Device id info for
vmhba33:C0:T35:L0: 0x51 0x95 0x0 0x4 0x0 0x0 0x0 0x1 0x51 0x94 0x0 0x4 0x0 0x0
0x0 0x1 0x51 0x3 0x0 0x10 0x60 0x1 0x44 0xf0 0x48 0xf7 0xa0 0xfa 0x0 0x0 0x1e
0xc9 0xd5 0x75 0xd2 0x0 0x53 0xa8 0x0 0x44 0x69 0x71 0x6e 0x2e 0x
Oct 16 06:38:37 vmware-860-1 vmkernel: 31 0x39 0x38 0x36 0x2d 0x30 0x33 0x2e
0x63 0x6f 0x6d 0x2e 0x73 0x75 0x6e 0x3a 0x30 0x32 0x3a 0x37 0x36 0x34 0x66 0x33
0x34 0x30 0x32 0x2d 0x38 0x62 0x65 0x33 0x2d 0x65 0x65 0x37 0x66 0x2d 0x65 0x65
0x30 0x34 0x2d 0x64 0x30 0x30 0x35 0x32 0x34 0x32 0x31 0x63
Oct 16 06:38:37 vmware-860-1 vmkernel: 0x34 0x34 0x61 0x2e 0x6c 0x75 0x6e 0x62
0x0 0x0 0x0 0x0 0x51 0xa3 0x0 0x10 0x60 0x1 0x44 0xf0 0x48 0xf7 0xa0 0xfa 0x0
0x0 0x1e 0xc9 0xd5 0x75 0xd2 0x0 0x51 0x93 0x0 0x10 0x60 0x1 0x44 0xf0 0x48
0xf7 0xa0 0xfa 0x0 0x0 0x1e 0xc9 0xd5 0x75 0xd2 0x0
Oct 16 06:38:37 vmware-860-1 vmkernel: VMWARE SCSI Id: Id for vmhba33:C0:T35:L0
0x60 0x01 0x44 0xf0 0x48 0xf7 0xa0 0xfa 0x00 0x00 0x1e 0xc9 0xd5 0x75 0xd2 0x00
0x53 0x4f 0x4c 0x41 0x52 0x49
Oct 16 06:38:40 vmware-860-1 vmkernel: 0:02:03:11.110 cpu0:1061)LinSCSI: 3201:
Abort failed for cmd with serial=1195, status=bad0001, retval=bad0001
Oct 16 06:38:40 vmware-860-1 last message repeated 4 times
Oct 16 06:38:44 vmware-860-1 vmkernel: 0:02:03:14.901 cpu1:1037)WARNING: FS3:
4785: Reservation error: Timeout
Oct 16 06:38:44 vmware-860-1 vmkernel: 0:02:03:14.901 cpu1:1037)WARNING: FS3:
4979: Reclaiming timed out heartbeat [HB state abcdef02 offset 3460096 gen 25
stamp 7354900048 uuid 48f72706-f36533bb-10a9-0019b9f7eddd jrnl <FB 499802> drv
4.31] failed: Timeout
Oct 16 06:38:44 vmware-860-1 vmkernel: 0:02:03:14.901 cpu1:1063)Res3: 585:
Failed to unlock cluster 2599 (typeID 1): Busy
Oct 16 06:38:44 vmware-860-1 vmkernel: 0:02:03:14.901 cpu1:1063)FS3: 5032:
Waiting for timed-out heartbeat [HB state abcdef02 offset 3460096 gen 25 stamp
7354900048 uuid 48f72706-f36533bb-10a9-0019b9f7eddd jrnl <FB 499802> drv 4.31]
Oct 16 06:39:17 vmware-860-1 vmkernel: 0:02:03:48.119 cpu1:1047)SCSI: 2285:
Could not verify that the disk id of path vmhba33:38:0 matches the id of the
target
Oct 16 06:39:20 vmware-860-1 vmkernel: 0:02:03:51.110 cpu0:1061)LinSCSI: 3201:
Abort failed for cmd with serial=1195, status=bad0001, retval=bad0001
Oct 16 06:39:20 vmware-860-1 last message repeated 4 times
Oct 16 06:39:24 vmware-860-1 vmkernel: 0:02:03:54.903 cpu1:1037)WARNING: FS3:
4785: Reservation error: Timeout
Oct 16 06:39:24 vmware-860-1 vmkernel: 0:02:03:54.903 cpu1:1037)WARNING: FS3:
4979: Reclaiming timed out heartbeat [HB state abcdef02 offset 3460096 gen 25
stamp 7354900048 uuid 48f72706-f36533bb-10a9-0019b9f7eddd jrnl <FB 499802> drv
4.31] failed: Timeout
Oct 16 06:39:24 vmware-860-1 vmkernel: 0:02:03:54.903 cpu1:1063)Res3: 585:
Failed to unlock cluster 2598 (typeID 1): Busy
Oct 16 06:39:24 vmware-860-1 vmkernel: 0:02:03:54.903 cpu1:1063)FS3: 5032:
Waiting for timed-out heartbeat [HB state abcdef02 offset 3460096 gen 25 stamp
7354900048 uuid 48f72706-f36533bb-10a9-0019b9f7eddd jrnl <FB 499802> drv 4.31]
Oct 16 06:39:28 vmware-860-1 vmkernel: 0:02:03:59.121 cpu1:1068)iSCSI: 50
second timeout expired for session 0x9a402c0, rx 738912, ping 743411, now 743912
Oct 16 06:39:28 vmware-860-1 vmkernel: 0:02:03:59.121 cpu1:1075)iSCSI: session
0x9a402c0 xmit_data failed to send 131120 bytes, rc -512
Oct 16 06:39:28 vmware-860-1 vmkernel: 0:02:03:59.121 cpu1:1076)iSCSI: session
0x9a402c0 to luna dropped
Oct 16 06:39:28 vmware-860-1 vmkernel: 0:02:03:59.121 cpu1:1076)iSCSI: session
0x9a402c0 for (2 0 38 *) rx thread 1076handled timeout, notify tx
Oct 16 06:39:28 vmware-860-1 vmkernel: 0:02:03:59.121 cpu1:1076)iSCSI: bus 0
target 38 trying to establish session 0x9a402c0 to portal 0, address
138.23.117.32 port 3260 group 1
Oct 16 06:39:28 vmware-860-1 vmkernel: 0:02:03:59.125 cpu1:1076)iSCSI: bus 0
target 38 established session 0x9a402c0 #3 to portal 0, address 138.23.117.32
port 3260 group 1, alias luna
Oct 16 06:39:28 vmware-860-1 vmkernel: VMWARE SCSI Id: Supported VPD pages for
vmhba33:C0:T35:L0 : 0x0 0x80 0x83 0x86
Oct 16 06:39:28 vmware-860-1 vmkernel: VMWARE SCSI Id: Device id info for
vmhba33:C0:T35:L0: 0x51 0x95 0x0 0x4 0x0 0x0 0x0 0x1 0x51 0x94 0x0 0x4 0x0 0x0
0x0 0x1 0x51 0x3 0x0 0x10 0x60 0x1 0x44 0xf0 0x48 0xf7 0xa0 0xfa 0x0 0x0 0x1e
0xc9 0xd5 0x75 0xd2 0x0 0x53 0xa8 0x0 0x44 0x69 0x71 0x6e 0x2e 0x
Oct 16 06:39:28 vmware-860-1 vmkernel: 31 0x39 0x38 0x36 0x2d 0x30 0x33 0x2e
0x63 0x6f 0x6d 0x2e 0x73 0x75 0x6e 0x3a 0x30 0x32 0x3a 0x37 0x36 0x34 0x66 0x33
0x34 0x30 0x32 0x2d 0x38 0x62 0x65 0x33 0x2d 0x65 0x65 0x37 0x66 0x2d 0x65 0x65
0x30 0x34 0x2d 0x64 0x30 0x30 0x35 0x32 0x34 0x32 0x31 0x63
Oct 16 06:39:28 vmware-860-1 vmkernel: 0x34 0x34 0x61 0x2e 0x6c 0x75 0x6e 0x62
0x0 0x0 0x0 0x0 0x51 0xa3 0x0 0x10 0x60 0x1 0x44 0xf0 0x48 0xf7 0xa0 0xfa 0x0
0x0 0x1e 0xc9 0xd5 0x75 0xd2 0x0 0x51 0x93 0x0 0x10 0x60 0x1 0x44 0xf0 0x48
0xf7 0xa0 0xfa 0x0 0x0 0x1e 0xc9 0xd5 0x75 0xd2 0x0
Oct 16 06:39:28 vmware-860-1 vmkernel: VMWARE SCSI Id: Id for vmhba33:C0:T35:L0
0x60 0x01 0x44 0xf0 0x48 0xf7 0xa0 0xfa 0x00 0x00 0x1e 0xc9 0xd5 0x75 0xd2 0x00
0x53 0x4f 0x4c 0x41 0x52 0x49
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.095 cpu1:1072)iSCSI: bus 0
target 36 trying to establish session 0x9a180a0 to portal 0, address 0.0.0.0
port 3260 group 1
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.095 cpu1:1072)iSCSI: session
0x9a180a0 to iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
failed to connect, rc -5, I/O error
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.095 cpu1:1072)iSCSI: session
0x9a180a0 connect failed at 744010
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.095 cpu1:1072)<5>iSCSI:
session 0x9a180a0 iSCSI: session 0x9a180a0 retrying all the portals again,
since the portal list got exhausted
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.095 cpu1:1072)iSCSI: session
0x9a180a0 to iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
waiting 60 seconds before next login attempt
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.101 cpu1:1074)iSCSI: bus 0
target 37 trying to establish session 0x9a2c1b0 to portal 0, address 0.0.0.0
port 3260 group 1
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.101 cpu1:1074)iSCSI: session
0x9a2c1b0 to iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
failed to connect, rc -5, I/O error
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.101 cpu1:1074)iSCSI: session
0x9a2c1b0 connect failed at 744011
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.101 cpu1:1074)<5>iSCSI:
session 0x9a2c1b0 iSCSI: session 0x9a2c1b0 retrying all the portals again,
since the portal list got exhausted
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.101 cpu1:1074)iSCSI: session
0x9a2c1b0 to iqn.1986-03.com.sun:02:764f3402-8be3-ee7f-ee04-d0052421c44a.lunb
waiting 60 seconds before next login attempt
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.151 cpu1:1078)iSCSI: bus 0
target 39 trying to establish session 0x9a543d0 to portal 0, address 0.0.0.0
port 3260 group 1
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.151 cpu1:1078)iSCSI: session
0x9a543d0 to iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
failed to connect, rc -5, I/O error
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.151 cpu1:1078)iSCSI: session
0x9a543d0 connect failed at 744016
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.151 cpu1:1078)<5>iSCSI:
session 0x9a543d0 iSCSI: session 0x9a543d0 retrying all the portals again,
since the portal list got exhausted
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.151 cpu1:1078)iSCSI: session
0x9a543d0 to iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
waiting 60 seconds before next login attempt
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.167 cpu1:1080)iSCSI: bus 0
target 40 trying to establish session 0x9a684e0 to portal 0, address 0.0.0.0
port 3260 group 1
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.167 cpu1:1080)iSCSI: session
0x9a684e0 to iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
failed to connect, rc -5, I/O error
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.167 cpu1:1080)iSCSI: session
0x9a684e0 connect failed at 744017
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.167 cpu1:1080)<5>iSCSI:
session 0x9a684e0 iSCSI: session 0x9a684e0 retrying all the portals again,
since the portal list got exhausted
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.167 cpu1:1080)iSCSI: session
0x9a684e0 to iqn.1986-03.com.sun:02:de84a16a-aa8e-c51f-ad5c-a198289b0bc6.luna
waiting 60 seconds before next login attempt
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.237 cpu1:1031)Fil3: 5004:
WRITE error 0xbad00e5
Oct 16 06:39:29 vmware-860-1 vmkernel: 0:02:04:00.237 cpu1:1037)FS3: 4829:
Reclaimed timed out heartbeat [HB state abcdef02 offset 3460096 gen 25 stamp
7336683462 uuid 48f72706-f36533bb-10a9-0019b9f7eddd jrnl <FB 499802> drv 4.31]
Oct 16 06:39:29 vmware-860-1 vmkernel: VMWARE SCSI Id: Supported VPD pages for
vmhba33:C0:T38:L0 : 0x0 0x80 0x83 0x86
Oct 16 06:39:29 vmware-860-1 vmkernel: VMWARE SCSI Id: Device id info for
vmhba33:C0:T38:L0: 0x51 0x95 0x0 0x4 0x0 0x0 0x0 0x1 0x51 0x94 0x0 0x4 0x0 0x0
0x0 0x1 0x51 0x3 0x0 0x10 0x60 0x1 0x44 0xf0 0x48 0xf7 0xa0 0xff 0x0 0x0 0x1e
0xc9 0xd5 0x75 0xd2 0x0 0x53 0xa8 0x0 0x44 0x69 0x71 0x6e 0x2e 0x
Oct 16 06:39:29 vmware-860-1 vmkernel: 31 0x39 0x38 0x36 0x2d 0x30 0x33 0x2e
0x63 0x6f 0x6d 0x2e 0x73 0x75 0x6e 0x3a 0x30 0x32 0x3a 0x64 0x65 0x38 0x34 0x61
0x31 0x36 0x61 0x2d 0x61 0x61 0x38 0x65 0x2d 0x63 0x35 0x31 0x66 0x2d 0x61 0x64
0x35 0x63 0x2d 0x61 0x31 0x39 0x38 0x32 0x38 0x39 0x62 0x30
Oct 16 06:39:29 vmware-860-1 vmkernel: 0x62 0x63 0x36 0x2e 0x6c 0x75 0x6e 0x61
0x0 0x0 0x0 0x0 0x51 0xa3 0x0 0x10 0x60 0x1 0x44 0xf0 0x48 0xf7 0xa0 0xff 0x0
0x0 0x1e 0xc9 0xd5 0x75 0xd2 0x0 0x51 0x93 0x0 0x10 0x60 0x1 0x44 0xf0 0x48
0xf7 0xa0 0xff 0x0 0x0 0x1e 0xc9 0xd5 0x75 0xd2 0x0
Oct 16 06:39:29 vmware-860-1 vmkernel: VMWARE SCSI Id: Id for vmhba33:C0:T38:L0
0x60 0x01 0x44 0xf0 0x48 0xf7 0xa0 0xff 0x00 0x00 0x1e 0xc9 0xd5 0x75
[EMAIL PROTECTED] log]#
Hope this helps!
Tano
> Also, iscsitadm list target -v
>
> Also, I blogged a bit on OpenSolaris iSCSI & VMware ESX ....I was using
> b98 on a X4500.
>
> http://blogs.sun.com/rarneson/entry/zfs_clones_iscsi_and_vmware
>
>> --
>> This message posted from opensolaris.org
>> _______________________________________________
>> zfs-discuss mailing list
>> zfs-discuss@opensolaris.org
>> http://mail.opensolaris.org/mailman/listinfo/zfs-discuss
>>
>
>
> --
> Ryan Arneson
> Sun Microsystems, Inc.
> 303-223-6264
> [EMAIL PROTECTED]
> http://blogs.sun.com/rarneson
>
>
--
This message posted from opensolaris.org
_______________________________________________
zfs-discuss mailing list
zfs-discuss@opensolaris.org
http://mail.opensolaris.org/mailman/listinfo/zfs-discuss
