We have moved permanently! Join us @ http://forum.flexraid.com
We have moved permanently! Join us @ http://forum.flexraid.com
We have moved permanently! Join us @ http://forum.flexraid.com
[Logo] (Closed - visit http://forum.flexraid.com)
  [Search] Search   [Recent Topics] Recent Topics   [Hottest Topics] Hottest Topics   [Members]  Member Listing   [Groups] Back to home page 
[Register] Register / 
[Login] Login 
ArrayIndexOutOfBoundsException Error  XML
Forum Index » Issues/Bug Report
Author Message
jamestucker


Joined: 13/07/2008 12:47:09
Messages: 8
Offline

I've been playing around with Build 6 of RC3 and whenever I do a restore from the command line I get an "ArrayIndexOutOfBoundsException" error. My steps are:

1. Create a new array of the G:\ and F:\ drives using E:\Parity to store the parity information
2. Validate the array
3. Delete a file from the F:\ drive
4. Run Validate to see the missing file
5. Run Restore to restore the file

The file seems to restore properly but I get an exception after it is complete. Here's a snippet of the debug log:


[2008-07-18 09:05:55,656] DEBUG: Executing validate...
[2008-07-18 09:05:55,687] DEBUG: Valid: F:\Files\FlexRAID_1.0-RC3_build-006.exe
[2008-07-18 09:05:55,703] DEBUG: Valid: G:\More Files\FlexRAID_1.0-RC3_build-006.exe
[2008-07-18 09:05:55,796] DEBUG: Valid: E:\Parity\000001.flxr
[2008-07-18 09:05:55,796] DEBUG: Valid: E:\Parity\000002.flxr
[2008-07-18 09:05:55,812] DEBUG: Valid: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg8.exe
[2008-07-18 09:05:55,828] DEBUG: Valid: F:\Files\FlexRAID DOS Client_1.0-RC2_build-001.exe
[2008-07-18 09:05:55,828] DEBUG: Valid: G:\More Files\FlexRAID DOS Client_1.0-RC2_build-001.exe
[2008-07-18 09:05:55,843] DEBUG: Valid: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg7.exe
[2008-07-18 09:05:55,843] DEBUG: Valid: G:\More Files\cmd.rc3.zip
[2008-07-18 09:05:55,843] DEBUG: Valid: G:\More Files\Copy of cmd.rc3.zip
[2008-07-18 09:05:55,843] DEBUG: Valid: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg5.zip
[2008-07-18 09:05:55,843] DEBUG: Valid: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg6.zip
[2008-07-18 09:05:55,843] DEBUG: Valid: G:\More Files\New Text Document.txt
[2008-07-18 09:05:55,843] DEBUG: Valid: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg10.txt
[2008-07-18 09:05:55,843] DEBUG: Valid: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg9.txt
[2008-07-18 09:05:55,843] DEBUG: Valid: G:\More Files\New Bitmap Image.bmp
[2008-07-18 09:05:55,843] WARN : Deleted: F:\Files\Copy of FlexRAID_1.0-RC3_build-006.exe
[2008-07-18 09:06:07,812] DEBUG: Executing restore...
[2008-07-18 09:06:07,812] DEBUG: Skipping existing file: F:\Files\FlexRAID_1.0-RC3_build-006.exe...
[2008-07-18 09:06:07,812] DEBUG: Skipping existing file: G:\More Files\FlexRAID_1.0-RC3_build-006.exe...
[2008-07-18 09:06:07,812] DEBUG: Skipping existing file: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg8.exe...
[2008-07-18 09:06:07,812] DEBUG: Skipping existing file: F:\Files\FlexRAID DOS Client_1.0-RC2_build-001.exe...
[2008-07-18 09:06:07,812] DEBUG: Skipping existing file: G:\More Files\FlexRAID DOS Client_1.0-RC2_build-001.exe...
[2008-07-18 09:06:07,812] DEBUG: Skipping existing file: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg7.exe...
[2008-07-18 09:06:07,843] DEBUG: Skipping existing file: G:\More Files\cmd.rc3.zip...
[2008-07-18 09:06:07,843] DEBUG: Skipping existing file: G:\More Files\Copy of cmd.rc3.zip...
[2008-07-18 09:06:07,843] DEBUG: Skipping existing file: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg5.zip...
[2008-07-18 09:06:07,843] DEBUG: Skipping existing file: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg6.zip...
[2008-07-18 09:06:07,843] DEBUG: Skipping existing file: G:\More Files\New Text Document.txt...
[2008-07-18 09:06:07,843] DEBUG: Skipping existing file: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg10.txt...
[2008-07-18 09:06:07,843] DEBUG: Skipping existing file: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg9.txt...
[2008-07-18 09:06:07,843] DEBUG: Skipping existing file: G:\More Files\New Bitmap Image.bmp...
[2008-07-18 09:06:07,843] DEBUG: Opening E:\Parity\000001.flxr at 0
[2008-07-18 09:06:07,843] DEBUG: Restoring F:\Files\Copy of FlexRAID_1.0-RC3_build-006.exe to F:\Files\Copy of FlexRAID_1.0-RC3_build-006.exe...
[2008-07-18 09:06:07,875] DEBUG: Closing E:\Parity\000001.flxr at 34361078
[2008-07-18 09:06:07,875] DEBUG: Opening E:\Parity\000002.flxr at 0
[2008-07-18 09:06:08,031] DEBUG: Closing E:\Parity\000002.flxr at 6104958
[2008-07-18 09:06:10,421] ERROR:
java.lang.ArrayIndexOutOfBoundsException
at java.lang.Void.<unknown>(Unknown Source)
at java.lang.Void.<unknown>(Unknown Source)
at java.lang.Void.<unknown>(Unknown Source)
at java.lang.Void.<unknown>(Unknown Source)

I think tried to run Validate (which seemed to work) and another rsynch just to see what would happen and received another error. Here's the output:

[2008-07-18 09:14:44,265] DEBUG: Executing validate...
[2008-07-18 09:14:44,312] DEBUG: Valid: F:\Files\FlexRAID_1.0-RC3_build-006.exe
[2008-07-18 09:14:44,343] DEBUG: Valid: G:\More Files\FlexRAID_1.0-RC3_build-006.exe
[2008-07-18 09:14:44,468] DEBUG: Valid: E:\Parity\000001.flxr
[2008-07-18 09:14:44,484] DEBUG: Valid: E:\Parity\000002.flxr
[2008-07-18 09:14:44,484] DEBUG: Valid: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg8.exe
[2008-07-18 09:14:44,500] DEBUG: Valid: F:\Files\FlexRAID DOS Client_1.0-RC2_build-001.exe
[2008-07-18 09:14:44,515] DEBUG: Valid: G:\More Files\FlexRAID DOS Client_1.0-RC2_build-001.exe
[2008-07-18 09:14:44,515] DEBUG: Valid: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg7.exe
[2008-07-18 09:14:44,515] DEBUG: Valid: G:\More Files\cmd.rc3.zip
[2008-07-18 09:14:44,515] DEBUG: Valid: G:\More Files\Copy of cmd.rc3.zip
[2008-07-18 09:14:44,515] DEBUG: Valid: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg5.zip
[2008-07-18 09:14:44,515] DEBUG: Valid: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg6.zip
[2008-07-18 09:14:44,515] DEBUG: Valid: G:\More Files\New Text Document.txt
[2008-07-18 09:14:44,515] DEBUG: Valid: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg10.txt
[2008-07-18 09:14:44,515] DEBUG: Valid: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg9.txt
[2008-07-18 09:14:44,515] DEBUG: Valid: G:\More Files\New Bitmap Image.bmp
[2008-07-18 09:14:44,531] DEBUG: Valid: F:\Files\Copy of FlexRAID_1.0-RC3_build-006.exe
[2008-07-18 09:15:00,390] DEBUG: Executing rsynch...
[2008-07-18 09:15:01,546] ERROR: [rsynch] For input string: "_______"
java.lang.NumberFormatException: For input string: "_______"
at java.lang.Void.<unknown>(Unknown Source)
at java.lang.Void.<unknown>(Unknown Source)
at java.lang.Void.<unknown>(Unknown Source)
at java.lang.Void.<unknown>(Unknown Source)


Thanks,
James
jamestucker


Joined: 13/07/2008 12:47:09
Messages: 8
Offline

One more observation... If I restart the server I can successfully run Resynch without error.

Thanks,
James
Brahim


Joined: 09/04/2008 23:28:33
Messages: 2883
Offline

[2008-07-18 09:06:10,421] ERROR:
java.lang.ArrayIndexOutOfBoundsException


Can you replicate this in build 7?

[2008-07-18 09:15:01,546] ERROR: [rsynch] For input string: "_______"

This is a random but rare thing that occurs when Windows flakes on me a little whenever I am computing the available disk free space.
Usually, running the task again would complete without issues.

This message was edited 1 time. Last update was at 19/07/2008 10:21:12


Server (VMware ESXi): dual Quad 8356@2.4Ghz | ASUS KFN5-D SLI | 16GB (4x 4GB) DDR2 667Mhz ECC REG w/Parity [Chipkill] | Radeon X300 | Intel 160GB SSD (VM datastore) | 6+ TB storage
File Server VM (running FlexRAID): 512MB RAM | 2 vCPUs | 6TB storage | Parity on 2TB NAS
jamestucker


Joined: 13/07/2008 12:47:09
Messages: 8
Offline


2008-07-18 09:06:10,421] ERROR:
java.lang.ArrayIndexOutOfBoundsException



Can you replicate this in build 7?


I just tried Build 7 and I'm able to replicate the issue using the exact same steps.

Thanks,
James
Brahim


Joined: 09/04/2008 23:28:33
Messages: 2883
Offline

jamestucker wrote:
I just tried Build 7 and I'm able to replicate the issue using the exact same steps.

Thanks,
James


Cool, I will look into it.

Server (VMware ESXi): dual Quad 8356@2.4Ghz | ASUS KFN5-D SLI | 16GB (4x 4GB) DDR2 667Mhz ECC REG w/Parity [Chipkill] | Radeon X300 | Intel 160GB SSD (VM datastore) | 6+ TB storage
File Server VM (running FlexRAID): 512MB RAM | 2 vCPUs | 6TB storage | Parity on 2TB NAS
jamestucker


Joined: 13/07/2008 12:47:09
Messages: 8
Offline

Brahim,

One other thing I noticed... I tried the test again today with a larger data set and found that I had 7 files that didn't properly restore (out of 1200). The files were:

NOT FOUND ***** Files02\Desktop\SyncToy_b24c2387-fc08-436e-8fa9-0a54589a2af9.dat
NOT FOUND ***** Files02\Desktop\SyncToy_df25b269-168c-4de7-9118-7ce351d68969.dat
NOT FOUND ***** Files02\Temp\CiscoVPN5.0\.DS_Store
NOT FOUND ***** Files02\Temp\MozyPro Pilot Install\.DS_Store
NOT FOUND ***** Files02\Temp\Sametime\Emoticons\.DS_Store
NOT FOUND ***** Files02\Temp\Sametime\.DS_Store
NOT FOUND ***** Files02\Temp\_desktop.ini

I noticed that several of the files start with ".". Should this be a problem?

Thanks,
James
Brahim


Joined: 09/04/2008 23:28:33
Messages: 2883
Offline

Are those files hidden?

Hidden files are ignored as a feature.

I guess I should provide a way to turn off that feature.

Server (VMware ESXi): dual Quad 8356@2.4Ghz | ASUS KFN5-D SLI | 16GB (4x 4GB) DDR2 667Mhz ECC REG w/Parity [Chipkill] | Radeon X300 | Intel 160GB SSD (VM datastore) | 6+ TB storage
File Server VM (running FlexRAID): 512MB RAM | 2 vCPUs | 6TB storage | Parity on 2TB NAS
jamestucker


Joined: 13/07/2008 12:47:09
Messages: 8
Offline

Brahim,

You were correct - the files were hidden. I did more testing using several thousand files and all the non-hidden ones do restore properly. The only issue seems to be the error that occurs at the end of the restore process.

Thanks,
James
Brahim


Joined: 09/04/2008 23:28:33
Messages: 2883
Offline

jamestucker wrote:Brahim,

You were correct - the files were hidden. I did more testing using several thousand files and all the non-hidden ones do restore properly. The only issue seems to be the error that occurs at the end of the restore process.

Thanks,
James


Cool.
I will look into that error.

---------------
Note: I am only collecting information at this point.
I won't have time to work on the code for at least a week.

Server (VMware ESXi): dual Quad 8356@2.4Ghz | ASUS KFN5-D SLI | 16GB (4x 4GB) DDR2 667Mhz ECC REG w/Parity [Chipkill] | Radeon X300 | Intel 160GB SSD (VM datastore) | 6+ TB storage
File Server VM (running FlexRAID): 512MB RAM | 2 vCPUs | 6TB storage | Parity on 2TB NAS
Brahim


Joined: 09/04/2008 23:28:33
Messages: 2883
Offline

jamestucker wrote:
....
[2008-07-18 09:06:07,812] DEBUG: Executing restore...
[2008-07-18 09:06:07,812] DEBUG: Skipping existing file: F:\Files\FlexRAID_1.0-RC3_build-006.exe...
[2008-07-18 09:06:07,812] DEBUG: Skipping existing file: G:\More Files\FlexRAID_1.0-RC3_build-006.exe...
[2008-07-18 09:06:07,812] DEBUG: Skipping existing file: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg8.exe...
[2008-07-18 09:06:07,812] DEBUG: Skipping existing file: F:\Files\FlexRAID DOS Client_1.0-RC2_build-001.exe...
[2008-07-18 09:06:07,812] DEBUG: Skipping existing file: G:\More Files\FlexRAID DOS Client_1.0-RC2_build-001.exe...
[2008-07-18 09:06:07,812] DEBUG: Skipping existing file: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg7.exe...
[2008-07-18 09:06:07,843] DEBUG: Skipping existing file: G:\More Files\cmd.rc3.zip...
[2008-07-18 09:06:07,843] DEBUG: Skipping existing file: G:\More Files\Copy of cmd.rc3.zip...
[2008-07-18 09:06:07,843] DEBUG: Skipping existing file: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg5.zip...
[2008-07-18 09:06:07,843] DEBUG: Skipping existing file: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg6.zip...
[2008-07-18 09:06:07,843] DEBUG: Skipping existing file: G:\More Files\New Text Document.txt...
[2008-07-18 09:06:07,843] DEBUG: Skipping existing file: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg10.txt...
[2008-07-18 09:06:07,843] DEBUG: Skipping existing file: G:\RECYCLER\S-1-5-21-2215661921-3517641686-3797654922-500\Dg9.txt...
[2008-07-18 09:06:07,843] DEBUG: Skipping existing file: G:\More Files\New Bitmap Image.bmp...
[2008-07-18 09:06:07,843] DEBUG: Opening E:\Parity\000001.flxr at 0
[2008-07-18 09:06:07,843] DEBUG: Restoring F:\Files\Copy of FlexRAID_1.0-RC3_build-006.exe to F:\Files\Copy of FlexRAID_1.0-RC3_build-006.exe...
[2008-07-18 09:06:07,875] DEBUG: Closing E:\Parity\000001.flxr at 34361078
[2008-07-18 09:06:07,875] DEBUG: Opening E:\Parity\000002.flxr at 0
[2008-07-18 09:06:08,031] DEBUG: Closing E:\Parity\000002.flxr at 6104958
[2008-07-18 09:06:10,421] ERROR:
java.lang.ArrayIndexOutOfBoundsException
at java.lang.Void.<unknown>(Unknown Source)
at java.lang.Void.<unknown>(Unknown Source)
at java.lang.Void.<unknown>(Unknown Source)
at java.lang.Void.<unknown>(Unknown Source)

....

Thanks,
James


It looks like you have configured both drives to be under the same DRU.
This is not to say that that's the cause for the error message.
Nothing should fail regardless of how you configure your RAID.
I just want to make sure that you understand how to properly configure things.

Show me a screenshot of your RAID configuration.

So, far I have not been able to replicate this error.
Please send me the full log the next time you see such error again.

This message was edited 2 times. Last update was at 26/07/2008 09:19:57


Server (VMware ESXi): dual Quad 8356@2.4Ghz | ASUS KFN5-D SLI | 16GB (4x 4GB) DDR2 667Mhz ECC REG w/Parity [Chipkill] | Radeon X300 | Intel 160GB SSD (VM datastore) | 6+ TB storage
File Server VM (running FlexRAID): 512MB RAM | 2 vCPUs | 6TB storage | Parity on 2TB NAS
jamestucker


Joined: 13/07/2008 12:47:09
Messages: 8
Offline

Brahim,

You are correct that I had originally configured both drives under the same DRU. I went back and created two DRUs but I'm still getting the same issue. I'm attaching a copy of my create.txt and restore.txt to shwo you how I'm creating and restoring the raid.

Thanks,
James
 Filename create.txt [Disk] Download
 Description Create file
 Filesize 922 bytes
 Downloaded:  6 time(s)

 Filename restore02.txt [Disk] Download
 Description Restore file
 Filesize 632 bytes
 Downloaded:  4 time(s)

This message was edited 1 time. Last update was at 27/07/2008 12:51:18

Brahim


Joined: 09/04/2008 23:28:33
Messages: 2883
Offline

Your configuration looks fine.

Do you have the log for the second run?

Server (VMware ESXi): dual Quad 8356@2.4Ghz | ASUS KFN5-D SLI | 16GB (4x 4GB) DDR2 667Mhz ECC REG w/Parity [Chipkill] | Radeon X300 | Intel 160GB SSD (VM datastore) | 6+ TB storage
File Server VM (running FlexRAID): 512MB RAM | 2 vCPUs | 6TB storage | Parity on 2TB NAS
jamestucker


Joined: 13/07/2008 12:47:09
Messages: 8
Offline

Brahim,

I'm attaching the entire log. I did the following:

1) Ran Create.txt
2) Removed some files from DRU2
3) Ran Verify.txt
4) Ran Restore02.txt
5) Did a "Status" several times
6) Ran Verify.txt

I just realized that the "status" command after the restore is what I think causing the exception. Each time I executed the command I recieved the ArrayIndexOutOfBoundsException. Once I ran the "verify.txt" command I was able to do a "status" without getting an error.

Thanks,
James
 Filename log.log [Disk] Download
 Description Log file
 Filesize 14 Kbytes
 Downloaded:  7 time(s)

Brahim


Joined: 09/04/2008 23:28:33
Messages: 2883
Offline

Thanks for the additional info.

I have tried various tests and I just cannot replicate the error.
Running the status command during or after made no difference.

The bug is still open and I will look more into it later.

You might want to give the Web UI a trial in the meantime if that makes any difference.


Server (VMware ESXi): dual Quad 8356@2.4Ghz | ASUS KFN5-D SLI | 16GB (4x 4GB) DDR2 667Mhz ECC REG w/Parity [Chipkill] | Radeon X300 | Intel 160GB SSD (VM datastore) | 6+ TB storage
File Server VM (running FlexRAID): 512MB RAM | 2 vCPUs | 6TB storage | Parity on 2TB NAS
thrawn86


Joined: 18/05/2008 20:18:10
Messages: 29
Offline

I've been having the same error recently on 008. I'll have a video up for you later.
Brahim


Joined: 09/04/2008 23:28:33
Messages: 2883
Offline

thrawn86 wrote:I've been having the same error recently on 008. I'll have a video up for you later.


Looking forward to it.

Server (VMware ESXi): dual Quad 8356@2.4Ghz | ASUS KFN5-D SLI | 16GB (4x 4GB) DDR2 667Mhz ECC REG w/Parity [Chipkill] | Radeon X300 | Intel 160GB SSD (VM datastore) | 6+ TB storage
File Server VM (running FlexRAID): 512MB RAM | 2 vCPUs | 6TB storage | Parity on 2TB NAS
thrawn86


Joined: 18/05/2008 20:18:10
Messages: 29
Offline

http://dl.getdropbox.com/u/21159/FLEXRAID%20out%20of%20bounds.avi

note that the files were completely restored.
Brahim


Joined: 09/04/2008 23:28:33
Messages: 2883
Offline

thrawn86 wrote:http://dl.getdropbox.com/u/21159/FLEXRAID%20out%20of%20bounds.avi

note that the files were completely restored.


Cool.
I will work on it.

Server (VMware ESXi): dual Quad 8356@2.4Ghz | ASUS KFN5-D SLI | 16GB (4x 4GB) DDR2 667Mhz ECC REG w/Parity [Chipkill] | Radeon X300 | Intel 160GB SSD (VM datastore) | 6+ TB storage
File Server VM (running FlexRAID): 512MB RAM | 2 vCPUs | 6TB storage | Parity on 2TB NAS
Brahim


Joined: 09/04/2008 23:28:33
Messages: 2883
Offline

FYI, this bug has been resolved for build 009.

Server (VMware ESXi): dual Quad 8356@2.4Ghz | ASUS KFN5-D SLI | 16GB (4x 4GB) DDR2 667Mhz ECC REG w/Parity [Chipkill] | Radeon X300 | Intel 160GB SSD (VM datastore) | 6+ TB storage
File Server VM (running FlexRAID): 512MB RAM | 2 vCPUs | 6TB storage | Parity on 2TB NAS
johndoe74


Joined: 07/09/2008 14:30:07
Messages: 4
Offline

Brahim wrote:FYI, this bug has been resolved for build 009.


Is this the same issue as getting an 'NumberFormatException'? I tried to create a parity but always receives this exception:

[2008-11-15 10:52:01,745] ERROR: [create] For input string: "_______"
java.lang.NumberFormatException: For input string: "_______"
at java.lang.Void.<unknown>(Unknown Source)
at java.lang.Void.<unknown>(Unknown Source)
at java.lang.Void.<unknown>(Unknown Source)
at java.lang.Void.<unknown>(Unknown Source)

I am using Windows Server 2008.
 
Forum Index » Issues/Bug Report
Go to:   
Powered by JForum 2.1.8 © JForum Team



Locations of visitors to this page