search

laurent-simon / storage-at-desk

Automatically exported from code.google.com/p/storage-at-desk
0 stars 0 forks source link

Format Hang #6

Closed GoogleCodeExporter closed 8 years ago

GoogleCodeExporter commented 8 years ago 
What steps will reproduce the problem?
1. Startup services
2. Connect to iSCSI
3. Format Drive

What is the expected output? What do you see instead?
The format should just work. Instead it hangs at 100% and the following is
seen over and over again

349925 16:26:21,850 [pool-1-thread-1] DEBUG storageserver.TargetThread  -
ISCSI op code 0x1 complete
349925 16:26:21,850 [pool-1-thread-1] DEBUG storageserver.TargetThread  -
Ready to read from initiator
349923 16:26:21,848 [Thread-1  ] DEBUG storageserver.VirtualDisk  - To get
the journal queue size 1
349925 16:26:21,850 [Thread-1  ] DEBUG storageserver.Journal  - To get the
queue
349925 16:26:21,850 [Thread-1  ] DEBUG storageserver.Journal  - get returns
349925 16:26:21,850 [Thread-1  ] DEBUG storageserver.VirtualDisk  - Writer
is running with size 1
349925 16:26:21,850 [Thread-1  ] DEBUG storageserver.VirtualDisk  - Read,
from the journal, entry of Version 252
349925 16:26:21,850 [Thread-1  ] DEBUG storageserver.VirtualDisk  - Writing
updates to disks
349925 16:26:21,850 [Thread-1  ] DEBUG storageserver.VirtualDisk  - Read
from aux file 43739136, 32768
349926 16:26:21,851 [Thread-1  ] DEBUG storageserver.VirtualDisk  - Read
from data file, bytes size 32768
349926 16:26:21,851 [Thread-1  ] DEBUG storageserver.VirtualDisk  - write
task begins
349926 16:26:21,851 [Thread-1  ] DEBUG storageserver.VirtualDisk  - Try
replica 0
349926 16:26:21,851 [Thread-1  ] DEBUG storageserver.VirtualDisk  - Write
for replica 0 virtual chunk 0 from machine (id
a49d8384-1a04-38ae-ac9e-5e6b5ce03a91) at 43739136
349926 16:26:21,851 [Thread-1  ] DEBUG storageserver.Version  - read from
file: 251
349928 16:26:21,853 [pool-1-thread-1] DEBUG storageserver.TargetThread  -
ISCSI op code 0x1
349928 16:26:21,853 [pool-1-thread-1] DEBUG storageserver.TargetThread  -
SCSI command
349928 16:26:21,853 [pool-1-thread-1] DEBUG storageserver.ScsiRequest  -
Decap scsi command request
349928 16:26:21,853 [pool-1-thread-1] DEBUG storageserver.ScsiRequest  -
decap finishes
349928 16:26:21,853 [pool-1-thread-1] DEBUG storageserver.TargetThread  -
SCSI Command (CmdSN 677, op 0x28)
349928 16:26:21,853 [pool-1-thread-1] DEBUG storageserver.TargetThread  -
No AHS to read
349928 16:26:21,853 [pool-1-thread-1] DEBUG common.Disk  - SCSI op 0x28 (LUN 0)
349928 16:26:21,853 [pool-1-thread-1] DEBUG common.Disk  - READ 10
349928 16:26:21,853 [pool-1-thread-1] DEBUG common.Disk  - Read 10 (lba
42714, len 32 blocks)
349928 16:26:21,853 [pool-1-thread-1] DEBUG storageserver.VirtualDisk  -
Read 32768 bytes at 43739136
349929 16:26:21,854 [pool-1-thread-1] DEBUG storageserver.VirtualDisk  -
Try replica 0
349929 16:26:21,854 [pool-1-thread-1] DEBUG storageserver.Version  - read
from file: 251
349929 16:26:21,854 [pool-1-thread-1] DEBUG storageserver.Version  - read
from file: 252
349929 16:26:21,854 [pool-1-thread-1] DEBUG storageserver.VirtualDisk  -
waiting for writes
349993 16:26:21,918 [Thread-1  ] DEBUG storageserver.VirtualDisk  - Write
32768 bytes from machine (id a49d8384-1a04-38ae-ac9e-5e6b5ce03a91) at 43739136
349993 16:26:21,918 [Thread-1  ] DEBUG storageserver.VirtualDisk  - Write ends
349994 16:26:21,919 [Thread-1  ] DEBUG storageserver.VirtualDisk  - Removed
Version 252
349994 16:26:21,919 [Thread-1  ] DEBUG storageserver.Journal  - Remove 252
from the journal queue
349994 16:26:21,919 [Thread-1  ] DEBUG storageserver.VirtualDisk  - Remove,
from the journal, entry of Version 252
349994 16:26:21,919 [Thread-1  ] DEBUG storageserver.VirtualDisk  - The
journal (size 0)

Original issue reported on code.google.com by tommy.mu...@gmail.com on 29 Mar 2009 at 8:29

GoogleCodeExporter commented 8 years ago 
Acronis Disk Director - ext3 - failed at 99%

Windows Disk Management Tool - NTFS regular format - failed at 100%
(the information in the above post was from this run)

Windows Disk Management Tool - FAT32 quick format - worked (took ~30 seconds for
~75MB to complete)

Original comment by tommy.mu...@gmail.com on 29 Mar 2009 at 8:46

GoogleCodeExporter commented 8 years ago 
The new VirtualFileNJ class seems to fix this issue.  It may have been caused 
by many 
issues - likely dealing with the overhead/extra timing required to do 
journaling and 
the fact that subsequent reads to something still in the journal are not read 
FROM 
the journal.

While the cause of the issue is not completely know, it is no longer 
reproducible.

Original comment by tommy.mu...@gmail.com on 23 Apr 2009 at 8:41