From mboxrd@z Thu Jan 1 00:00:00 1970 From: Pat LaVarre Subject: Re: zeroes read back more often than appended Date: 09 Oct 2003 18:52:31 -0600 Sender: linux-fsdevel-owner@vger.kernel.org Message-ID: <1065747151.2314.12.camel@patehci2> References: <1065553341.8172.45.camel@patehci2> <20031007204951.A25423@falcon.csc.calpoly.edu> <1065732882.5176.14.camel@patehci2> Mime-Version: 1.0 Content-Type: text/plain Content-Transfer-Encoding: 7bit Return-path: Received: from email-out1.iomega.com ([147.178.1.82]:21242 "EHLO email.iomega.com") by vger.kernel.org with ESMTP id S262692AbTJJAwr (ORCPT ); Thu, 9 Oct 2003 20:52:47 -0400 Received: from royntex01.iomegacorp.com (unknown [147.178.90.120]) by email.iomega.com (Postfix) with ESMTP id 0157A1CFD for ; Thu, 9 Oct 2003 18:52:45 -0600 (MDT) To: linux-fsdevel@vger.kernel.org In-Reply-To: <1065732882.5176.14.camel@patehci2> List-Id: linux-fsdevel.vger.kernel.org Ouch I see I forgot to mention, when I do see zeroes read back more often then appended, ... Often I also see dmesg like those discussed in the recently dead linux_udf thread "balloc free bit already set and byte minus one". For example we see: UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3791 already set UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte=ffffff80 ... UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3796 already set UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte=1f The complete tty log follows. I see trouble in single writes without these messages, so I guess these messages are related but not the only cause. Alternatively, these messages may be loudly discussing normal activity, rather than complaining of real trouble, I'm too new to know. Pat LaVarre $ $ uname -msr Linux 2.6.0-test7 i686 $ $ dmesg >1 $ $ udfwh mkudffs 0xFFFF 0xC00 + rm dd.bin + dd if=/dev/zero of=dd.bin bs=1M seek=1023 count=1 1+0 records in 1+0 records out + ls -l dd.bin -rw-rw-r-- 1 pat pat 1073741824 Oct 9 18:42 dd.bin + sudo losetup /dev/loop0 dd.bin + sudo mkudffs + head -1 mkudffs 1.0.0b2 for UDF FS 1.0.0-cvs, 2002/02/09 + sudo mkudffs /dev/loop0 start=0, blocks=16, type=RESERVED start=16, blocks=3, type=VRS start=19, blocks=237, type=USPACE start=256, blocks=1, type=ANCHOR start=257, blocks=16, type=PVDS start=273, blocks=1, type=LVID start=274, blocks=523757, type=PSPACE start=524031, blocks=1, type=ANCHOR start=524032, blocks=239, type=USPACE start=524271, blocks=16, type=RVDS start=524287, blocks=1, type=ANCHOR + sudo mount /dev/loop0 /mnt/loop0 ++ id -u ++ id -g + sudo chown 500:500 /mnt/loop0/. + cd /mnt/loop0 + wh 0xFFFF 0xC00 0 hexdump -C wh.bin | head -3 00000000 aa aa aa aa aa aa aa aa aa aa aa aa aa aa aa aa |................| * 04372000 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................| real 0m10.480s user 0m2.787s sys 0m1.894s + cd - + sudo umount /mnt/loop0 + sudo losetup -d /dev/loop0 $ $ dmesg >2 $ $ diff 1 2 | grep '^>' > he hash table of 4096 buckets, 32Kbytes > UDF-fs DEBUG fs/udf/lowlevel.c:65:udf_get_last_session: CDROMMULTISESSION not supported: rc=-22 > UDF-fs DEBUG fs/udf/super.c:1544:udf_fill_super: Multi-session=0 > UDF-fs DEBUG fs/udf/super.c:532:udf_vrs: Starting at sector 16 (2048 byte sectors) > UDF-fs DEBUG fs/udf/super.c:875:udf_load_pvoldesc: recording time 1065746572/73570, 2003/10/09 18:42 (1e98) > UDF-fs DEBUG fs/udf/super.c:886:udf_load_pvoldesc: volIdent[] = 'LinuxUDF' > UDF-fs DEBUG fs/udf/super.c:893:udf_load_pvoldesc: volSetIdent[] = '3f86008cLinuxUDF' > UDF-fs DEBUG fs/udf/super.c:1085:udf_load_logicalvol: Partition (0:0) type 1 on volume 1 > UDF-fs DEBUG fs/udf/super.c:1095:udf_load_logicalvol: FileSet found in LogicalVolDesc at block=32, partition=0 > UDF-fs DEBUG fs/udf/super.c:923:udf_load_partdesc: Searching map: (0 == 0) > UDF-fs DEBUG fs/udf/super.c:964:udf_load_partdesc: unallocSpaceBitmap (part 0) @ 0 > UDF-fs DEBUG fs/udf/super.c:1005:udf_load_partdesc: Partition (0:0 type 1511) starts at physical 274, block length 523757 > UDF-fs DEBUG fs/udf/super.c:1338:udf_load_partition: Using anchor in block 256 > UDF-fs DEBUG fs/udf/super.c:1571:udf_fill_super: Lastblock=0 > UDF-fs DEBUG fs/udf/super.c:847:udf_find_fileset: Fileset at block=32, partition=0 > UDF-fs DEBUG fs/udf/super.c:909:udf_load_fileset: Rootdir at block=34, partition=0 > UDF-fs INFO UDF 0.9.7 (2002/11/15) Mounting volume 'LinuxUDF', timestamp 2003/10/09 18:42 (1e98) > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3791 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte=ffffff80 > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3791 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte=ffffff80 > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3792 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte= 1 > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3791 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte=ffffff80 > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3792 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte= 3 > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3793 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte= 3 > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3791 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte=ffffff80 > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3792 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte= 7 > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3793 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte= 7 > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3794 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte= 7 > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3791 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte=ffffff80 > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3792 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte= f > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3793 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte= f > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3794 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte= f > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3795 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte= f > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3791 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte=ffffff80 > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3792 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte=1f > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3793 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte=1f > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3794 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte=1f > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3795 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte=1f > UDF-fs DEBUG fs/udf/balloc.c:192:udf_bitmap_free_blocks: bit 3796 already set > UDF-fs DEBUG fs/udf/balloc.c:193:udf_bitmap_free_blocks: byte=1f $