All of lore.kernel.org
 help / color / mirror / Atom feed
* UBIFS Corruption
@ 2011-07-08 13:12 Reginald Perrin
  2011-07-18 11:33 ` Artem Bityutskiy
  2011-07-18 11:38 ` Artem Bityutskiy
  0 siblings, 2 replies; 16+ messages in thread
From: Reginald Perrin @ 2011-07-08 13:12 UTC (permalink / raw)
  To: MTD Mailing List

Hi folks,

We're using ubifs in an embedded uclinux system (based on ADI Blackfin's BF524). 
 Been working great for us for a while.  Kernel is 2.6.34.7 (uclinux).

However, we just saw 2 corruptions within the past 48h that we can't explain. 
 We've been doing the same basic operation (in terms of flashing/reading/writing 
images) for quite some time, and have reflashed our units many times (over 
thousands of different hardware units).

Device #1 failure:
* Device was running out of a partition mounted to /home (a 93MB partition from 
a 128MB NAND device)
* Our app was running normally and locked up (not sure why).  Our code may have 
been updating a sqlite database located in that partition
* When we power cycled, the partition had the corruption issue noted.

Device #1 boot log:
UBI device number 1, total 750 LEBs (96768000 bytes, 92.3 MiB), available 0 LEBs 
(0 bytes), LEB size 129024 bytes (126.0 KiB) 
[ 5.228000] UBIFS: recovery needed 
[ 5.320000] UBIFS error (pid 363): ubifs_scanned_corruption: corruption at LEB 
172:45056 
[ 5.348000] UBIFS error (pid 363): ubifs_recover_leb: LEB 172 scanning failed 
mount: mounting ubi1:home on /home failed: Structure needs cleaning 

Device #2 failure:
* Device was running normally.
* We upgraded our application (which involved updating executables on that 
partition)
* After the successful upgrade, we powered the unit down and stored 
* Days later, powered up the device and the above invalid CRC as noted

Device #2 boot log:
UBI device number 1, total 750 LEBs (96768000 bytes, 92.3 MiB), available 0 LEBs 
(0 bytes), LEB size 129024 bytes (126.0 KiB) 
[ 5.488000] UBIFS: recovery needed 
[ 5.492000] UBIFS error (pid 365): check_lpt_crc: invalid crc in LPT node: crc 
a0 calc 9013
mount: mounting ubi1:home on /home failed: Invalid argument


So, what is concerning is the sheer randomness of these failures.  In neither 
case were we doing anything new (vs. standard operations we have been performing 
for over a year on many devices per day).  Additionally, there's no additional 
logging available, because this *never* happens.  We have never needed (after we 
got UBIFS working) to have the debug output enabled in the driver.  To make 
matters worse, if you ask me to reproduce this, I don't know any way of doing 
it.  We have automated tests that run continually, and they never see these 
issues.

One corruption could be written off as a fluke, but 2 happening within 48h is 
very unusual.  

Can anybody give me any insight into this?  

TIA
RP

^ permalink raw reply	[flat|nested] 16+ messages in thread

* Re: UBIFS Corruption
  2011-07-08 13:12 UBIFS Corruption Reginald Perrin
@ 2011-07-18 11:33 ` Artem Bityutskiy
  2011-07-19 20:47   ` Reginald Perrin
  2011-07-26 19:17   ` Reginald Perrin
  2011-07-18 11:38 ` Artem Bityutskiy
  1 sibling, 2 replies; 16+ messages in thread
From: Artem Bityutskiy @ 2011-07-18 11:33 UTC (permalink / raw)
  To: Reginald Perrin; +Cc: MTD Mailing List

Hi,

On Fri, 2011-07-08 at 06:12 -0700, Reginald Perrin wrote:
> Hi folks,
> 
> We're using ubifs in an embedded uclinux system (based on ADI Blackfin's BF524). 
>  Been working great for us for a while.  Kernel is 2.6.34.7 (uclinux).
> 
> However, we just saw 2 corruptions within the past 48h that we can't explain. 
>  We've been doing the same basic operation (in terms of flashing/reading/writing 
> images) for quite some time, and have reflashed our units many times (over 
> thousands of different hardware units).

OK, did more happen meanwhile?

> Device #1 failure:
> * Device was running out of a partition mounted to /home (a 93MB partition from 
> a 128MB NAND device)
> * Our app was running normally and locked up (not sure why).  Our code may have 
> been updating a sqlite database located in that partition
> * When we power cycled, the partition had the corruption issue noted.

Do you still have these devices? You need to enable UBIFS recovery
debugging messages and send them to me, may be then I can help.

> 
> Device #1 boot log:
> UBI device number 1, total 750 LEBs (96768000 bytes, 92.3 MiB), available 0 LEBs 
> (0 bytes), LEB size 129024 bytes (126.0 KiB) 
> [ 5.228000] UBIFS: recovery needed 
> [ 5.320000] UBIFS error (pid 363): ubifs_scanned_corruption: corruption at LEB 
> 172:45056 
> [ 5.348000] UBIFS error (pid 363): ubifs_recover_leb: LEB 172 scanning failed 
> mount: mounting ubi1:home on /home failed: Structure needs cleaning 

You need to enable UBIFS debugging at least, better the recovery
messages as well, and try to mount the UBI volume again, then send the
UBIFS output. Remember to make sure that you send all of them, not only
those which you see on your console, see here for more details:

http://www.linux-mtd.infradead.org/doc/ubifs.html#L_how_send_bugreport

> 
> Device #2 failure:
> * Device was running normally.
> * We upgraded our application (which involved updating executables on that 
> partition)
> * After the successful upgrade, we powered the unit down and stored 
> * Days later, powered up the device and the above invalid CRC as noted
> 
> Device #2 boot log:
> UBI device number 1, total 750 LEBs (96768000 bytes, 92.3 MiB), available 0 LEBs 
> (0 bytes), LEB size 129024 bytes (126.0 KiB) 
> [ 5.488000] UBIFS: recovery needed 
> [ 5.492000] UBIFS error (pid 365): check_lpt_crc: invalid crc in LPT node: crc 
> a0 calc 9013
> mount: mounting ubi1:home on /home failed: Invalid argument
> 
> 
> So, what is concerning is the sheer randomness of these failures.  In neither 
> case were we doing anything new (vs. standard operations we have been performing 
> for over a year on many devices per day).  Additionally, there's no additional 
> logging available, because this *never* happens.  We have never needed (after we 
> got UBIFS working) to have the debug output enabled in the driver.  To make 
> matters worse, if you ask me to reproduce this, I don't know any way of doing 
> it.  We have automated tests that run continually, and they never see these 
> issues.

Well, if UBIFS is unable to mount, then you should not re-flash the
device, then you at least may re-compile the kernel and enable debugging
and try to figure out what makes UBIFS reject the flash.

> One corruption could be written off as a fluke, but 2 happening within 48h is 
> very unusual.  
> 
> Can anybody give me any insight into this?  

Yeah, 2 is enough to start worrying. Note, we probably also fixed some
recovery failure since 2.6.34, you might check the UBIFS back-port
trees.

-- 
Best Regards,
Artem Bityutskiy

^ permalink raw reply	[flat|nested] 16+ messages in thread

* Re: UBIFS Corruption
  2011-07-08 13:12 UBIFS Corruption Reginald Perrin
  2011-07-18 11:33 ` Artem Bityutskiy
@ 2011-07-18 11:38 ` Artem Bityutskiy
  1 sibling, 0 replies; 16+ messages in thread
From: Artem Bityutskiy @ 2011-07-18 11:38 UTC (permalink / raw)
  To: Reginald Perrin; +Cc: MTD Mailing List

On Fri, 2011-07-08 at 06:12 -0700, Reginald Perrin wrote:
> Hi folks,
> 
> We're using ubifs in an embedded uclinux system (based on ADI Blackfin's BF524). 
>  Been working great for us for a while.  Kernel is 2.6.34.7 (uclinux).
> 
> However, we just saw 2 corruptions within the past 48h that we can't explain. 
>  We've been doing the same basic operation (in terms of flashing/reading/writing 
> images) for quite some time, and have reflashed our units many times (over 
> thousands of different hardware units).
> 
> Device #1 failure:
> * Device was running out of a partition mounted to /home (a 93MB partition from 
> a 128MB NAND device)
> * Our app was running normally and locked up (not sure why).  Our code may have 
> been updating a sqlite database located in that partition
> * When we power cycled, the partition had the corruption issue noted.
> 
> Device #1 boot log:
> UBI device number 1, total 750 LEBs (96768000 bytes, 92.3 MiB), available 0 LEBs 
> (0 bytes), LEB size 129024 bytes (126.0 KiB) 
> [ 5.228000] UBIFS: recovery needed 
> [ 5.320000] UBIFS error (pid 363): ubifs_scanned_corruption: corruption at LEB 
> 172:45056 
> [ 5.348000] UBIFS error (pid 363): ubifs_recover_leb: LEB 172 scanning failed 
> mount: mounting ubi1:home on /home failed: Structure needs cleaning 

While this is very typical symptom which might mean many things,

> Device #2 failure:
> * Device was running normally.
> * We upgraded our application (which involved updating executables on that 
> partition)
> * After the successful upgrade, we powered the unit down and stored 
> * Days later, powered up the device and the above invalid CRC as noted
> 
> Device #2 boot log:
> UBI device number 1, total 750 LEBs (96768000 bytes, 92.3 MiB), available 0 LEBs 
> (0 bytes), LEB size 129024 bytes (126.0 KiB) 
> [ 5.488000] UBIFS: recovery needed 
> [ 5.492000] UBIFS error (pid 365): check_lpt_crc: invalid crc in LPT node: crc 
> a0 calc 9013
> mount: mounting ubi1:home on /home failed: Invalid argument

I do not remember this one. This may be an UBIFS bug of course, but
quite possible this is just flash corruption or memory corruption (e.g.,
some driver corrupts memory and makes UBIFS write incorrect information
to the flash).

-- 
Best Regards,
Artem Bityutskiy

^ permalink raw reply	[flat|nested] 16+ messages in thread

* Re: UBIFS Corruption
  2011-07-18 11:33 ` Artem Bityutskiy
@ 2011-07-19 20:47   ` Reginald Perrin
  2011-07-26 19:17   ` Reginald Perrin
  1 sibling, 0 replies; 16+ messages in thread
From: Reginald Perrin @ 2011-07-19 20:47 UTC (permalink / raw)
  To: dedekind1; +Cc: MTD Mailing List

> > 
> > However, we just saw  2 corruptions within the past 48h that we can't 
>explain. 
>
> >  We've  been doing the same basic operation (in terms of 
>flashing/reading/writing 
>
> > images) for quite some time, and have reflashed our units many times  (over 
> > thousands of different hardware units).
> 
> OK, did more  happen meanwhile?


No, just these two so far

> 
> > Device #1 failure:
> > * Device was running  out of a partition mounted to /home (a 93MB partition 
>from 
>
> > a 128MB NAND  device)
> > * Our app was running normally and locked up (not sure  why).  Our code may 
>have 
>
> > been updating a sqlite database located  in that partition
> > * When we power cycled, the partition had the  corruption issue noted.
> 
> Do you still have these devices? You need to  enable UBIFS recovery
> debugging messages and send them to me, may be then I  can help.
> 

Excellent ideal.  Let me try enabling that in the kernel and updating just the 
kernel to see what we can find.

FWIW, I have downloaded a complete image of that corrupt partition.  Not sure if 
that helps me yet, but worth mentioning.

RP

^ permalink raw reply	[flat|nested] 16+ messages in thread

* Re: UBIFS Corruption
  2011-07-18 11:33 ` Artem Bityutskiy
  2011-07-19 20:47   ` Reginald Perrin
@ 2011-07-26 19:17   ` Reginald Perrin
  2011-08-02 16:14     ` Reginald Perrin
  2011-08-15 12:29     ` Artem Bityutskiy
  1 sibling, 2 replies; 16+ messages in thread
From: Reginald Perrin @ 2011-07-26 19:17 UTC (permalink / raw)
  To: dedekind1, MTD Mailing List

> 
> Do you still have these devices? You need to  enable UBIFS recovery
> debugging messages and send them to me, may be then I  can help.
> 


Artem,

Sorry this took so long.  Here is the boot output as the device boots and tries 
to load that partition:

[    1.832000] 0x000000000000-0x000000140000 : "bootloader(nand)"
[    1.840000] 0x000000140000-0x000000440000 : "kernel1"
[    1.848000] 0x000000440000-0x000000e40000 : "rfs1"
[    1.856000] 0x000000e40000-0x000001140000 : "kernel2"
[    1.864000] 0x000001140000-0x000001b40000 : "rfs2"
[    1.876000] 0x000001b40000-0x000007900000 : "home"
[    1.920000] UBI: attaching mtd2 to ubi0
[    1.924000] UBI: physical eraseblock size:   131072 bytes (128 KiB)
[    1.928000] UBI: logical eraseblock size:    129024 bytes
[    1.932000] UBI: smallest flash I/O unit:    2048
[    1.936000] UBI: sub-page size:              512
[    1.940000] UBI: VID header offset:          512 (aligned 512)
[    1.944000] UBI: data offset:                2048
[    2.004000] UBI: volume 0 ("rfs") re-sized from 74 to 74 LEBs
[    2.008000] UBI: attached mtd2 to ubi0
[    2.012000] UBI: MTD device name:            "rfs1"
[    2.016000] UBI: MTD device size:            10 MiB
[    2.020000] UBI: number of good PEBs:        80
[    2.024000] UBI: number of bad PEBs:         0
[    2.028000] UBI: max. allowed volumes:       128
[    2.032000] UBI: wear-leveling threshold:    4096
[    2.036000] UBI: number of internal volumes: 1
[    2.040000] UBI: number of user volumes:     1
[    2.044000] UBI: available PEBs:             0
[    2.048000] UBI: total number of reserved PEBs: 80
[    2.052000] UBI: number of PEBs reserved for bad PEB handling: 2
[    2.056000] UBI: max/mean erase counter: 1/0
[    2.060000] UBI: image sequence number: 1281595386
[    2.076000] UBI: background thread "ubi_bgt0d" started, PID 294

(snip)

[    5.612000] UBIFS: mounted UBI device 0, volume 0, name "rfs"
[    5.616000] UBIFS: file system size:   8257536 bytes (8064 KiB, 7 MiB, 64 
LEBs)
[    5.620000] UBIFS: journal size:       1677312 bytes (1638 KiB, 1 MiB, 13 
LEBs)
[    5.624000] UBIFS: media format:       w4/r0 (latest is w4/r0)
[    5.628000] UBIFS: default compressor: lzo
[    5.632000] UBIFS: reserved for root:  0 bytes (0 KiB)
[    5.644000] VFS: Mounted root (ubifs filesystem) on device 0:10.

(snip)

[    7.736000] UBI: attaching mtd5 to ubi1
[    7.740000] UBI: physical eraseblock size:   131072 bytes (128 KiB)
[    7.744000] UBI: logical eraseblock size:    129024 bytes
[    7.748000] UBI: smallest flash I/O unit:    2048
[    7.752000] UBI: sub-page size:              512
[    7.756000] UBI: VID header offset:          512 (aligned 512)
[    7.760000] UBI: data offset:                2048
[    8.228000] UBI: attached mtd5 to ubi1
[    8.228000] UBI: MTD device name:            "home"
[    8.232000] UBI: MTD device size:            93 MiB
[    8.236000] UBI: number of good PEBs:        750
[    8.240000] UBI: number of bad PEBs:         0
[    8.244000] UBI: max. allowed volumes:       128
[    8.248000] UBI: wear-leveling threshold:    4096
[    8.252000] UBI: number of internal volumes: 1
[    8.256000] UBI: number of user volumes:     1
[    8.260000] UBI: available PEBs:             0
[    8.264000] UBI: total number of reserved PEBs: 750
[    8.268000] UBI: number of PEBs reserved for bad PEB handling: 7
[    8.272000] UBI: max/mean erase counter: 3/1
[    8.276000] UBI: image sequence number: 458433007
[    8.292000] UBI: background thread "ubi_bgt1d" started, PID 364
UBI device number 1, total 750 LEBs (96768000 bytes, 92.3 MiB), available 0 LEBs 
(0 bytes), LEB size 129024 bytes (126.0 KiB)
[    8.428000] UBIFS: recovery needed
[    9.056000] UBIFS error (pid 366): ubifs_recover_leb: garbage
[    9.060000] UBIFS error (pid 366): ubifs_scanned_corruption: corruption at 
LEB 172:45056
[    9.064000] UBIFS error (pid 366): ubifs_scanned_corruption: first 8192 bytes 
from LEB 172:45056
[    9.096000] UBIFS error (pid 366): ubifs_recover_leb: LEB 172 scanning failed
mount: mounting ubi1:home on /home failed: Structure needs cleaning

BTW, is there a command to clean the structure manually?  Can I possibly detect 
this and have a command which will allow me to recover?


Here is the dmesg output:

root:/> dmesg 

[   14.120000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:50184, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:21504, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:21696, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:21888, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:22080, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:22272, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:22464, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:22656, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:22848, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:50376, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:23040, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:23232, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:23424, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:23616, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:23808, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:24000, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:24192, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:24384, indexing 
node, length 188
[   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:53832, indexing 
node, length 188
[   14.136000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:50568, indexing 
node, length 188
[   14.136000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:24576, indexing 
node, length 188
[   14.136000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:24768, indexing 
node, length 188
[   14.136000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:24960, indexing 
node, length 188
[   14.136000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:25152, indexing 
node, length 188
[   14.136000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:25344, indexing 
node, length 188
[   14.136000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:25536, indexing 
node, length 188
[   14.136000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:25728, indexing 
node, length 188
[   14.136000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:25920, indexing 
node, length 188
[   14.136000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:50760, indexing 
node, length 188
[   14.136000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:26112, indexing 
node, length 188
[   14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:26304, indexing 
node, length 188
[   14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:26496, indexing 
node, length 188
[   14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:26688, indexing 
node, length 188
[   14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:26880, indexing 
node, length 188
[   14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:27072, indexing 
node, length 188
[   14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:27264, indexing 
node, length 188
[   14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:27456, indexing 
node, length 188
[   14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:50952, indexing 
node, length 188
[   14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:27648, indexing 
node, length 188
[   14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:27840, indexing 
node, length 188
[   14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:28032, indexing 
node, length 188
[   14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:28224, indexing 
node, length 188
[   14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:28416, indexing 
node, length 188
[   14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:28608, indexing 
node, length 188
[   14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:28800, indexing 
node, length 188
[   14.164000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:28992, indexing 
node, length 188
[   14.164000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:51144, indexing 
node, length 188
[   14.164000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:29184, indexing 
node, length 188
[   14.164000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:29376, indexing 
node, length 188
[   14.164000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:29568, indexing 
node, length 188
[   14.164000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:29760, indexing 
node, length 188
[   14.164000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:29952, indexing 
node, length 188
[   14.164000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:30144, indexing 
node, length 188
[   14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:30336, indexing 
node, length 188
[   14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:30528, indexing 
node, length 188
[   14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:51336, indexing 
node, length 188
[   14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:30720, indexing 
node, length 188
[   14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:30912, indexing 
node, length 188
[   14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:31104, indexing 
node, length 188
[   14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:31296, indexing 
node, length 188
[   14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:31488, indexing 
node, length 188
[   14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:31680, indexing 
node, length 188
[   14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:31872, indexing 
node, length 188
[   14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:32064, indexing 
node, length 188
[   14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:51528, indexing 
node, length 188
[   14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:32256, indexing 
node, length 188
[   14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:32448, indexing 
node, length 188
[   14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:32640, indexing 
node, length 188
[   14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:32832, indexing 
node, length 188
[   14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:33024, indexing 
node, length 188
[   14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:33216, indexing 
node, length 188
[   14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:33408, indexing 
node, length 188
[   14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:33600, indexing 
node, length 188
[   14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:51720, indexing 
node, length 188
[   14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:33792, indexing 
node, length 188
[   14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:33984, indexing 
node, length 188
[   14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:34176, indexing 
node, length 188
[   14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:34368, indexing 
node, length 188
[   14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:34560, indexing 
node, length 188
[   14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:34752, indexing 
node, length 188
[   14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:34944, indexing 
node, length 188
[   14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:35136, indexing 
node, length 188
[   14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:51912, indexing 
node, length 188
[   14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:35328, indexing 
node, length 188
[   14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:35520, indexing 
node, length 188
[   14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:35712, indexing 
node, length 188
[   14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:35904, indexing 
node, length 188
[   14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:36096, indexing 
node, length 188
[   14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:36288, indexing 
node, length 188
[   14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:36480, indexing 
node, length 188
[   14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:36672, indexing 
node, length 188
[   14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:54024, indexing 
node, length 168
[   14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:52104, indexing 
node, length 188
[   14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:36864, indexing 
node, length 188
[   14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:37056, indexing 
node, length 188
[   14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:37248, indexing 
node, length 188
[   14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:37440, indexing 
node, length 188
[   14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:37632, indexing 
node, length 188
[   14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:37824, indexing 
node, length 188
[   14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:38016, indexing 
node, length 188
[   14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:38208, indexing 
node, length 188
[   14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:52296, indexing 
node, length 188
[   14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:38400, indexing 
node, length 188
[   14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:38592, indexing 
node, length 188
[   14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:38784, indexing 
node, length 188
[   14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:38976, indexing 
node, length 188
[   14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:39168, indexing 
node, length 188
[   14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:39360, indexing 
node, length 188
[   14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:39552, indexing 
node, length 188
[   14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:39744, indexing 
node, length 188
[   14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:52488, indexing 
node, length 188
[   14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:39936, indexing 
node, length 188
[   14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:40128, indexing 
node, length 188
[   14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:40320, indexing 
node, length 188
[   14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:40512, indexing 
node, length 188
[   14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:40704, indexing 
node, length 188
[   14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:40896, indexing 
node, length 188
[   14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:41088, indexing 
node, length 188
[   14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:41280, indexing 
node, length 188
[   14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:52680, indexing 
node, length 188
[   14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:41472, indexing 
node, length 188
[   14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:41664, indexing 
node, length 188
[   14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:41856, indexing 
node, length 188
[   14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:42048, indexing 
node, length 188
[   14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:42240, indexing 
node, length 188
[   14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:42432, indexing 
node, length 188
[   14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:42624, indexing 
node, length 188
[   14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:42816, indexing 
node, length 188
[   14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:52872, indexing 
node, length 188
[   14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:43008, indexing 
node, length 188
[   14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:43200, indexing 
node, length 188
[   14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:43392, indexing 
node, length 188
[   14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:43584, indexing 
node, length 188
[   14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:43776, indexing 
node, length 188
[   14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:43968, indexing 
node, length 188
[   14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:44160, indexing 
node, length 188
[   14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:44352, indexing 
node, length 188
[   14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:53064, indexing 
node, length 188
[   14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:44544, indexing 
node, length 188
[   14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:44736, indexing 
node, length 188
[   14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:44928, indexing 
node, length 188
[   14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:45120, indexing 
node, length 188
[   14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:45312, indexing 
node, length 188
[   14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:45504, indexing 
node, length 188
[   14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:45696, indexing 
node, length 188
[   14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:45888, indexing 
node, length 188
[   14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:53256, indexing 
node, length 188
[   14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:46080, indexing 
node, length 188
[   14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:46272, indexing 
node, length 188
[   14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:46464, indexing 
node, length 188
[   14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:46656, indexing 
node, length 188
[   14.224000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:46848, indexing 
node, length 188
[   14.224000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:47040, indexing 
node, length 188
[   14.224000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:47232, indexing 
node, length 188
[   14.224000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:47424, indexing 
node, length 68
[   14.224000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:56784, indexing 
node, length 128
[   14.224000] UBIFS DBG (pid 330): ubifs_write_node: LEB 1:4096, master node, 
length 512 (aligned 2048)
[   14.236000] UBIFS DBG (pid 330): ubifs_write_node: LEB 2:4096, master node, 
length 512 (aligned 2048)
[   14.236000] UBIFS DBG (pid 330): ubifs_log_post_commit: unmap log LEB 3
[   14.236000] UBIFS DBG (pid 330): do_commit: commit end
[   40.972000] UBIFS DBG (pid 420): ubifs_lookup: 'dmesg' in dir ino 252
[   40.972000] UBIFS DBG (pid 420): ubifs_lookup_level0: search key (252, 
direntry, 0x11163d8b)
[   40.972000] UBIFS DBG (pid 420): ubifs_lookup_level0: found 1, lvl 0, n 1
[   40.972000] UBIFS DBG (pid 420): ubifs_read_node: LEB 28:123544, direntry 
node, length 62
[   40.972000] UBIFS DBG (pid 420): ubifs_iget: inode 331
[   40.972000] UBIFS DBG (pid 420): ubifs_lookup_level0: search key (331, inode)
[   40.972000] UBIFS DBG (pid 420): ubifs_lookup_level0: found 1, lvl 0, n 2
[   40.972000] UBIFS DBG (pid 420): fallible_read_node: LEB 28:123376, key (331, 
inode)
[   40.972000] UBIFS DBG (pid 420): try_read_node: LEB 28:123376, inode node, 
length 167
[   40.972000] UBIFS DBG (pid 420): ubifs_getxattr: xattr 'security.capability', 
ino 360 ('busybox'), buf size 20
[   40.972000] UBIFS DBG (pid 420): ubifs_lookup_level0: search key (360, 
xentry, 0x10888ae6)
[   40.976000] UBIFS DBG (pid 420): ubifs_lookup_level0: found 0, lvl 0, n 2


RP

^ permalink raw reply	[flat|nested] 16+ messages in thread

* Re: UBIFS Corruption
  2011-07-26 19:17   ` Reginald Perrin
@ 2011-08-02 16:14     ` Reginald Perrin
  2011-08-15 12:30       ` Artem Bityutskiy
  2011-08-15 12:29     ` Artem Bityutskiy
  1 sibling, 1 reply; 16+ messages in thread
From: Reginald Perrin @ 2011-08-02 16:14 UTC (permalink / raw)
  To: dedekind1, MTD Mailing List

Artem or others, any assistance on this issue, please?


----- Original Message ----
> From: Reginald Perrin <reggyperrin@yahoo.com>
> To: dedekind1@gmail.com; MTD Mailing  List <linux-mtd@lists.infradead.org>
> Sent: Tue, July 26, 2011 3:17:31 PM
> Subject: Re: UBIFS Corruption
> 
> > 
> > Do you still have these devices? You need to  enable UBIFS  recovery
> > debugging messages and send them to me, may be then I  can  help.
> > 
> 
> 
> Artem,
> 
> Sorry this took so long.  Here is  the boot output as the device boots and 
>tries 
>
> to load that  partition:
> 
> [    1.832000] 0x000000000000-0x000000140000 :  "bootloader(nand)"
> [    1.840000] 0x000000140000-0x000000440000 :  "kernel1"
> [    1.848000] 0x000000440000-0x000000e40000 :  "rfs1"
> [    1.856000] 0x000000e40000-0x000001140000 :  "kernel2"
> [    1.864000] 0x000001140000-0x000001b40000 :  "rfs2"
> [    1.876000] 0x000001b40000-0x000007900000 :  "home"
> [    1.920000] UBI: attaching mtd2 to ubi0
> [     1.924000] UBI: physical eraseblock size:   131072 bytes (128  KiB)
> [    1.928000] UBI: logical eraseblock size:     129024 bytes
> [    1.932000] UBI: smallest flash I/O unit:     2048
> [    1.936000] UBI: sub-page size:               512
> [    1.940000] UBI: VID header  offset:          512 (aligned 512)
> [     1.944000] UBI: data offset:                 2048
> [    2.004000] UBI: volume 0 ("rfs") re-sized from 74  to 74 LEBs
> [    2.008000] UBI: attached mtd2 to ubi0
> [     2.012000] UBI: MTD device name:             "rfs1"
> [    2.016000] UBI: MTD device size:             10 MiB
> [    2.020000] UBI: number of good  PEBs:        80
> [    2.024000] UBI: number of  bad PEBs:         0
> [    2.028000] UBI: max.  allowed volumes:       128
> [    2.032000] UBI:  wear-leveling threshold:    4096
> [    2.036000] UBI:  number of internal volumes: 1
> [    2.040000] UBI: number of user  volumes:     1
> [    2.044000] UBI: available PEBs:              0
> [    2.048000] UBI: total  number of reserved PEBs: 80
> [    2.052000] UBI: number of PEBs  reserved for bad PEB handling: 2
> [    2.056000] UBI: max/mean erase  counter: 1/0
> [    2.060000] UBI: image sequence number:  1281595386
> [    2.076000] UBI: background thread "ubi_bgt0d"  started, PID 294
> 
> (snip)
> 
> [    5.612000] UBIFS: mounted  UBI device 0, volume 0, name "rfs"
> [    5.616000] UBIFS: file  system size:   8257536 bytes (8064 KiB, 7 MiB, 64 
> LEBs)
> [     5.620000] UBIFS: journal size:       1677312 bytes (1638  KiB, 1 MiB, 13 

> LEBs)
> [    5.624000] UBIFS: media format:        w4/r0 (latest is w4/r0)
> [    5.628000] UBIFS:  default compressor: lzo
> [    5.632000] UBIFS: reserved for  root:  0 bytes (0 KiB)
> [    5.644000] VFS: Mounted root (ubifs  filesystem) on device 0:10.
> 
> (snip)
> 
> [    7.736000] UBI:  attaching mtd5 to ubi1
> [    7.740000] UBI: physical eraseblock  size:   131072 bytes (128 KiB)
> [    7.744000] UBI: logical  eraseblock size:    129024 bytes
> [    7.748000] UBI:  smallest flash I/O unit:    2048
> [    7.752000] UBI:  sub-page size:              512
> [     7.756000] UBI: VID header offset:          512  (aligned 512)
> [    7.760000] UBI: data offset:                 2048
> [    8.228000] UBI:  attached mtd5 to ubi1
> [    8.228000] UBI: MTD device name:             "home"
> [    8.232000] UBI: MTD  device size:            93 MiB
> [     8.236000] UBI: number of good PEBs:        750
> [     8.240000] UBI: number of bad PEBs:          0
> [    8.244000] UBI: max. allowed volumes:        128
> [    8.248000] UBI: wear-leveling threshold:     4096
> [    8.252000] UBI: number of internal volumes: 1
> [     8.256000] UBI: number of user volumes:     1
> [     8.260000] UBI: available PEBs:              0
> [    8.264000] UBI: total number of reserved PEBs: 750
> [     8.268000] UBI: number of PEBs reserved for bad PEB handling: 7
> [     8.272000] UBI: max/mean erase counter: 3/1
> [    8.276000]  UBI: image sequence number: 458433007
> [    8.292000] UBI:  background thread "ubi_bgt1d" started, PID 364
> UBI device number 1, total 750  LEBs (96768000 bytes, 92.3 MiB), available 0 
>LEBs 
>
> (0 bytes), LEB size 129024  bytes (126.0 KiB)
> [    8.428000] UBIFS: recovery needed
> [     9.056000] UBIFS error (pid 366): ubifs_recover_leb: garbage
> [     9.060000] UBIFS error (pid 366): ubifs_scanned_corruption: corruption at 

> LEB 172:45056
> [    9.064000] UBIFS error (pid 366):  ubifs_scanned_corruption: first 8192 
>bytes 
>
> from LEB 172:45056
> [     9.096000] UBIFS error (pid 366): ubifs_recover_leb: LEB 172 scanning  
>failed
> mount: mounting ubi1:home on /home failed: Structure needs  cleaning
> 
> BTW, is there a command to clean the structure manually?   Can I possibly 
>detect 
>
> this and have a command which will allow me to  recover?
> 
> 
> Here is the dmesg output:
> 
> root:/> dmesg 
> 
> [   14.120000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:50184,  indexing 
> node, length 188
> [   14.128000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:21504, indexing 
> node, length 188
> [    14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:21696, indexing 
> node, length 188
> [   14.128000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:21888, indexing 
> node, length 188
> [    14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:22080, indexing 
> node, length 188
> [   14.128000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:22272, indexing 
> node, length 188
> [    14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:22464, indexing 
> node, length 188
> [   14.128000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:22656, indexing 
> node, length 188
> [    14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:22848, indexing 
> node, length 188
> [   14.128000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:50376, indexing 
> node, length 188
> [    14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:23040, indexing 
> node, length 188
> [   14.128000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:23232, indexing 
> node, length 188
> [    14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:23424, indexing 
> node, length 188
> [   14.128000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:23616, indexing 
> node, length 188
> [    14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:23808, indexing 
> node, length 188
> [   14.128000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:24000, indexing 
> node, length 188
> [    14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:24192, indexing 
> node, length 188
> [   14.128000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:24384, indexing 
> node, length 188
> [    14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:53832, indexing 
> node, length 188
> [   14.136000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:50568, indexing 
> node, length 188
> [    14.136000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:24576, indexing 
> node, length 188
> [   14.136000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:24768, indexing 
> node, length 188
> [    14.136000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:24960, indexing 
> node, length 188
> [   14.136000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:25152, indexing 
> node, length 188
> [    14.136000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:25344, indexing 
> node, length 188
> [   14.136000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:25536, indexing 
> node, length 188
> [    14.136000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:25728, indexing 
> node, length 188
> [   14.136000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:25920, indexing 
> node, length 188
> [    14.136000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:50760, indexing 
> node, length 188
> [   14.136000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:26112, indexing 
> node, length 188
> [    14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:26304, indexing 
> node, length 188
> [   14.140000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:26496, indexing 
> node, length 188
> [    14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:26688, indexing 
> node, length 188
> [   14.140000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:26880, indexing 
> node, length 188
> [    14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:27072, indexing 
> node, length 188
> [   14.140000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:27264, indexing 
> node, length 188
> [    14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:27456, indexing 
> node, length 188
> [   14.140000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:50952, indexing 
> node, length 188
> [    14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:27648, indexing 
> node, length 188
> [   14.140000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:27840, indexing 
> node, length 188
> [    14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:28032, indexing 
> node, length 188
> [   14.140000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:28224, indexing 
> node, length 188
> [    14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:28416, indexing 
> node, length 188
> [   14.140000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:28608, indexing 
> node, length 188
> [    14.140000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:28800, indexing 
> node, length 188
> [   14.164000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:28992, indexing 
> node, length 188
> [    14.164000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:51144, indexing 
> node, length 188
> [   14.164000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:29184, indexing 
> node, length 188
> [    14.164000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:29376, indexing 
> node, length 188
> [   14.164000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:29568, indexing 
> node, length 188
> [    14.164000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:29760, indexing 
> node, length 188
> [   14.164000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:29952, indexing 
> node, length 188
> [    14.164000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:30144, indexing 
> node, length 188
> [   14.176000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:30336, indexing 
> node, length 188
> [    14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:30528, indexing 
> node, length 188
> [   14.176000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:51336, indexing 
> node, length 188
> [    14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:30720, indexing 
> node, length 188
> [   14.176000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:30912, indexing 
> node, length 188
> [    14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:31104, indexing 
> node, length 188
> [   14.176000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:31296, indexing 
> node, length 188
> [    14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:31488, indexing 
> node, length 188
> [   14.176000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:31680, indexing 
> node, length 188
> [    14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:31872, indexing 
> node, length 188
> [   14.176000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:32064, indexing 
> node, length 188
> [    14.176000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:51528, indexing 
> node, length 188
> [   14.180000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:32256, indexing 
> node, length 188
> [    14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:32448, indexing 
> node, length 188
> [   14.180000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:32640, indexing 
> node, length 188
> [    14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:32832, indexing 
> node, length 188
> [   14.180000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:33024, indexing 
> node, length 188
> [    14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:33216, indexing 
> node, length 188
> [   14.180000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:33408, indexing 
> node, length 188
> [    14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:33600, indexing 
> node, length 188
> [   14.180000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:51720, indexing 
> node, length 188
> [    14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:33792, indexing 
> node, length 188
> [   14.180000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:33984, indexing 
> node, length 188
> [    14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:34176, indexing 
> node, length 188
> [   14.180000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:34368, indexing 
> node, length 188
> [    14.180000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:34560, indexing 
> node, length 188
> [   14.188000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:34752, indexing 
> node, length 188
> [    14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:34944, indexing 
> node, length 188
> [   14.188000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:35136, indexing 
> node, length 188
> [    14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:51912, indexing 
> node, length 188
> [   14.188000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:35328, indexing 
> node, length 188
> [    14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:35520, indexing 
> node, length 188
> [   14.188000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:35712, indexing 
> node, length 188
> [    14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:35904, indexing 
> node, length 188
> [   14.188000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:36096, indexing 
> node, length 188
> [    14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:36288, indexing 
> node, length 188
> [   14.188000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:36480, indexing 
> node, length 188
> [    14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:36672, indexing 
> node, length 188
> [   14.188000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:54024, indexing 
> node, length 168
> [    14.188000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:52104, indexing 
> node, length 188
> [   14.192000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:36864, indexing 
> node, length 188
> [    14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:37056, indexing 
> node, length 188
> [   14.192000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:37248, indexing 
> node, length 188
> [    14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:37440, indexing 
> node, length 188
> [   14.192000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:37632, indexing 
> node, length 188
> [    14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:37824, indexing 
> node, length 188
> [   14.192000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:38016, indexing 
> node, length 188
> [    14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:38208, indexing 
> node, length 188
> [   14.192000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:52296, indexing 
> node, length 188
> [    14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:38400, indexing 
> node, length 188
> [   14.192000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:38592, indexing 
> node, length 188
> [    14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:38784, indexing 
> node, length 188
> [   14.192000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:38976, indexing 
> node, length 188
> [    14.192000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:39168, indexing 
> node, length 188
> [   14.192000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:39360, indexing 
> node, length 188
> [    14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:39552, indexing 
> node, length 188
> [   14.200000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:39744, indexing 
> node, length 188
> [    14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:52488, indexing 
> node, length 188
> [   14.200000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:39936, indexing 
> node, length 188
> [    14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:40128, indexing 
> node, length 188
> [   14.200000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:40320, indexing 
> node, length 188
> [    14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:40512, indexing 
> node, length 188
> [   14.200000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:40704, indexing 
> node, length 188
> [    14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:40896, indexing 
> node, length 188
> [   14.200000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:41088, indexing 
> node, length 188
> [    14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:41280, indexing 
> node, length 188
> [   14.200000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:52680, indexing 
> node, length 188
> [    14.200000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:41472, indexing 
> node, length 188
> [   14.200000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:41664, indexing 
> node, length 188
> [    14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:41856, indexing 
> node, length 188
> [   14.212000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:42048, indexing 
> node, length 188
> [    14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:42240, indexing 
> node, length 188
> [   14.212000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:42432, indexing 
> node, length 188
> [    14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:42624, indexing 
> node, length 188
> [   14.212000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:42816, indexing 
> node, length 188
> [    14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:52872, indexing 
> node, length 188
> [   14.212000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:43008, indexing 
> node, length 188
> [    14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:43200, indexing 
> node, length 188
> [   14.212000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:43392, indexing 
> node, length 188
> [    14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:43584, indexing 
> node, length 188
> [   14.212000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:43776, indexing 
> node, length 188
> [    14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:43968, indexing 
> node, length 188
> [   14.212000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:44160, indexing 
> node, length 188
> [    14.212000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:44352, indexing 
> node, length 188
> [   14.212000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:53064, indexing 
> node, length 188
> [    14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:44544, indexing 
> node, length 188
> [   14.216000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:44736, indexing 
> node, length 188
> [    14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:44928, indexing 
> node, length 188
> [   14.216000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:45120, indexing 
> node, length 188
> [    14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:45312, indexing 
> node, length 188
> [   14.216000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:45504, indexing 
> node, length 188
> [    14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:45696, indexing 
> node, length 188
> [   14.216000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:45888, indexing 
> node, length 188
> [    14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:53256, indexing 
> node, length 188
> [   14.216000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:46080, indexing 
> node, length 188
> [    14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:46272, indexing 
> node, length 188
> [   14.216000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:46464, indexing 
> node, length 188
> [    14.216000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:46656, indexing 
> node, length 188
> [   14.224000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:46848, indexing 
> node, length 188
> [    14.224000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:47040, indexing 
> node, length 188
> [   14.224000] UBIFS DBG (pid 330):  ubifs_read_node: LEB 34:47232, indexing 
> node, length 188
> [    14.224000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:47424, indexing 
> node, length 68
> [   14.224000] UBIFS DBG (pid 330): ubifs_read_node:  LEB 34:56784, indexing 
> node, length 128
> [   14.224000] UBIFS DBG  (pid 330): ubifs_write_node: LEB 1:4096, master node, 
>
> length 512 (aligned  2048)
> [   14.236000] UBIFS DBG (pid 330): ubifs_write_node: LEB 2:4096,  master node, 
>
> length 512 (aligned 2048)
> [   14.236000] UBIFS DBG (pid  330): ubifs_log_post_commit: unmap log LEB 3
> [   14.236000] UBIFS DBG  (pid 330): do_commit: commit end
> [   40.972000] UBIFS DBG (pid 420):  ubifs_lookup: 'dmesg' in dir ino 252
> [   40.972000] UBIFS DBG (pid 420):  ubifs_lookup_level0: search key (252, 
> direntry, 0x11163d8b)
> [    40.972000] UBIFS DBG (pid 420): ubifs_lookup_level0: found 1, lvl 0, n  1
> [   40.972000] UBIFS DBG (pid 420): ubifs_read_node: LEB 28:123544,  direntry 
> node, length 62
> [   40.972000] UBIFS DBG (pid 420):  ubifs_iget: inode 331
> [   40.972000] UBIFS DBG (pid 420):  ubifs_lookup_level0: search key (331, 
>inode)
> [   40.972000] UBIFS DBG  (pid 420): ubifs_lookup_level0: found 1, lvl 0, n 2
> [   40.972000] UBIFS  DBG (pid 420): fallible_read_node: LEB 28:123376, key 
>(331, 
>
> inode)
> [   40.972000] UBIFS DBG (pid 420): try_read_node: LEB  28:123376, inode node, 

> length 167
> [   40.972000] UBIFS DBG (pid  420): ubifs_getxattr: xattr 
>'security.capability', 
>
> ino 360 ('busybox'), buf  size 20
> [   40.972000] UBIFS DBG (pid 420): ubifs_lookup_level0: search  key (360, 
> xentry, 0x10888ae6)
> [   40.976000] UBIFS DBG (pid 420):  ubifs_lookup_level0: found 0, lvl 0, n 2
> 
> 
> RP
> 

^ permalink raw reply	[flat|nested] 16+ messages in thread

* Re: UBIFS Corruption
  2011-07-26 19:17   ` Reginald Perrin
  2011-08-02 16:14     ` Reginald Perrin
@ 2011-08-15 12:29     ` Artem Bityutskiy
  2011-11-28 20:04       ` Reginald Perrin
  1 sibling, 1 reply; 16+ messages in thread
From: Artem Bityutskiy @ 2011-08-15 12:29 UTC (permalink / raw)
  To: Reginald Perrin; +Cc: MTD Mailing List

Hi,

On Tue, 2011-07-26 at 12:17 -0700, Reginald Perrin wrote:
> Artem,
> 
> Sorry this took so long.  Here is the boot output as the device boots and tries 
> to load that partition:
> 
> [    1.832000] 0x000000000000-0x000000140000 : "bootloader(nand)"
> [    1.840000] 0x000000140000-0x000000440000 : "kernel1"
> [    1.848000] 0x000000440000-0x000000e40000 : "rfs1"
> [    1.856000] 0x000000e40000-0x000001140000 : "kernel2"
> [    1.864000] 0x000001140000-0x000001b40000 : "rfs2"
> [    1.876000] 0x000001b40000-0x000007900000 : "home"
> [    1.920000] UBI: attaching mtd2 to ubi0
> [    1.924000] UBI: physical eraseblock size:   131072 bytes (128 KiB)
> [    1.928000] UBI: logical eraseblock size:    129024 bytes
> [    1.932000] UBI: smallest flash I/O unit:    2048
> [    1.936000] UBI: sub-page size:              512
> [    1.940000] UBI: VID header offset:          512 (aligned 512)
> [    1.944000] UBI: data offset:                2048
> [    2.004000] UBI: volume 0 ("rfs") re-sized from 74 to 74 LEBs
> [    2.008000] UBI: attached mtd2 to ubi0
> [    2.012000] UBI: MTD device name:            "rfs1"
> [    2.016000] UBI: MTD device size:            10 MiB
> [    2.020000] UBI: number of good PEBs:        80
> [    2.024000] UBI: number of bad PEBs:         0
> [    2.028000] UBI: max. allowed volumes:       128
> [    2.032000] UBI: wear-leveling threshold:    4096
> [    2.036000] UBI: number of internal volumes: 1
> [    2.040000] UBI: number of user volumes:     1
> [    2.044000] UBI: available PEBs:             0
> [    2.048000] UBI: total number of reserved PEBs: 80
> [    2.052000] UBI: number of PEBs reserved for bad PEB handling: 2
> [    2.056000] UBI: max/mean erase counter: 1/0
> [    2.060000] UBI: image sequence number: 1281595386
> [    2.076000] UBI: background thread "ubi_bgt0d" started, PID 294
> 
> (snip)
> 
> [    5.612000] UBIFS: mounted UBI device 0, volume 0, name "rfs"
> [    5.616000] UBIFS: file system size:   8257536 bytes (8064 KiB, 7 MiB, 64 
> LEBs)
> [    5.620000] UBIFS: journal size:       1677312 bytes (1638 KiB, 1 MiB, 13 
> LEBs)
> [    5.624000] UBIFS: media format:       w4/r0 (latest is w4/r0)
> [    5.628000] UBIFS: default compressor: lzo
> [    5.632000] UBIFS: reserved for root:  0 bytes (0 KiB)
> [    5.644000] VFS: Mounted root (ubifs filesystem) on device 0:10.
> 
> (snip)
> 
> [    7.736000] UBI: attaching mtd5 to ubi1
> [    7.740000] UBI: physical eraseblock size:   131072 bytes (128 KiB)
> [    7.744000] UBI: logical eraseblock size:    129024 bytes
> [    7.748000] UBI: smallest flash I/O unit:    2048
> [    7.752000] UBI: sub-page size:              512
> [    7.756000] UBI: VID header offset:          512 (aligned 512)
> [    7.760000] UBI: data offset:                2048
> [    8.228000] UBI: attached mtd5 to ubi1
> [    8.228000] UBI: MTD device name:            "home"
> [    8.232000] UBI: MTD device size:            93 MiB
> [    8.236000] UBI: number of good PEBs:        750
> [    8.240000] UBI: number of bad PEBs:         0
> [    8.244000] UBI: max. allowed volumes:       128
> [    8.248000] UBI: wear-leveling threshold:    4096
> [    8.252000] UBI: number of internal volumes: 1
> [    8.256000] UBI: number of user volumes:     1
> [    8.260000] UBI: available PEBs:             0
> [    8.264000] UBI: total number of reserved PEBs: 750
> [    8.268000] UBI: number of PEBs reserved for bad PEB handling: 7
> [    8.272000] UBI: max/mean erase counter: 3/1
> [    8.276000] UBI: image sequence number: 458433007
> [    8.292000] UBI: background thread "ubi_bgt1d" started, PID 364
> UBI device number 1, total 750 LEBs (96768000 bytes, 92.3 MiB), available 0 LEBs 
> (0 bytes), LEB size 129024 bytes (126.0 KiB)
> [    8.428000] UBIFS: recovery needed
> [    9.056000] UBIFS error (pid 366): ubifs_recover_leb: garbage
> [    9.060000] UBIFS error (pid 366): ubifs_scanned_corruption: corruption at 
> LEB 172:45056
> [    9.064000] UBIFS error (pid 366): ubifs_scanned_corruption: first 8192 bytes 
> from LEB 172:45056
> [    9.096000] UBIFS error (pid 366): ubifs_recover_leb: LEB 172 scanning failed
> mount: mounting ubi1:home on /home failed: Structure needs cleaning
> 
> BTW, is there a command to clean the structure manually?  Can I possibly detect 
> this and have a command which will allow me to recover?

Unfortunately, this does not include all the UBIFS diagnostic messages.
I should definitely make them to be always present and more verbose by
default in the next kernel release. But for now you should enable UBIFS
debugging. Do not enable any other extra messages. Reproduce the issue
and type dmesg.

> root:/> dmesg 
> 
> [   14.120000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:50184, indexing 
> node, length 188
> [   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:21504, indexing 
> node, length 188
> [   14.128000] UBIFS DBG (pid 330): ubifs_read_node: LEB 34:21696, indexing 
> node, length 188

There are no interesting messages:

1. You enabled all UBIFS debugging messages which are too verbose and
not needed. Do not enable them because they "push out" the interesting
diagnostic messages from the kernel printk ring buffer.

2. And it is strange that the last messages are about the commit and not
about mount failure - are you sure that you typed dmesg right after the
UBIFS mount failure without rebooting ?

-- 
Best Regards,
Artem Bityutskiy

^ permalink raw reply	[flat|nested] 16+ messages in thread

* Re: UBIFS Corruption
  2011-08-02 16:14     ` Reginald Perrin
@ 2011-08-15 12:30       ` Artem Bityutskiy
  0 siblings, 0 replies; 16+ messages in thread
From: Artem Bityutskiy @ 2011-08-15 12:30 UTC (permalink / raw)
  To: Reginald Perrin; +Cc: MTD Mailing List

On Tue, 2011-08-02 at 09:14 -0700, Reginald Perrin wrote:
> Artem or others, any assistance on this issue, please?

Hi,

I was away (vacation) so could not answer. See my other reply - you
still did not provide the needed UBIFS messages.

-- 
Best Regards,
Artem Bityutskiy

^ permalink raw reply	[flat|nested] 16+ messages in thread

* Re: UBIFS Corruption
  2011-08-15 12:29     ` Artem Bityutskiy
@ 2011-11-28 20:04       ` Reginald Perrin
  2011-11-29 22:30         ` Artem Bityutskiy
  2011-12-07 17:19         ` Reginald Perrin
  0 siblings, 2 replies; 16+ messages in thread
From: Reginald Perrin @ 2011-11-28 20:04 UTC (permalink / raw)
  To: dedekind1; +Cc: MTD Mailing List


> There are no interesting messages:
> 
> 1. You enabled all UBIFS debugging messages which are too verbose and
> not needed. Do not enable them because they "push out" the interesting
> diagnostic messages from the kernel printk ring buffer.
> 
> 2. And it is strange that the last messages are about the commit and not
> about mount failure - are you sure that you typed dmesg right after the
> UBIFS mount failure without rebooting ?
> 
> -- 
> Best Regards,
> Artem Bityutskiy
> 
> 


Hi Artem,

Whatever is causing this, it seems to be increasing in frequency (or we are getting better at inadvertently causing it).

I now have several units in this state, and have tried to change the log level to gather more information.  Since the ubifs volume I need to mount isn't the root-file-system, I have the luxury of being able to create test kernels, flash them, and boot them into the system, at which point they try and mount the corrupted partition.

Below, you can see where I tried to mount this partition after booting (I discarded all old messages, so this is just the output of attempting to mount this volume).  

NOTE:  My #1 priority is figuring out how to recover from this -- when this happens, we're actually bricked, and can't recover (since all the key recovery programs are actually on this volume that can't mount).  

What else can I do to get more information to you to assist?

TIA
RP

root:/> rm /var/log/messages 
root:/> mount -t ubifs ubi1:home /home
mount: mounting ubi1:home on /home failed: Structure needs cleaning
root:/> cat /var/log/messages 
Dec 31 16:01:51 kernel: [  111.400000] UBIFS DBG (pid 524): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
Dec 31 16:01:51 kernel: [  111.404000] UBIFS DBG (pid 424): do_readpage: ino 69, pg 0, i_size 9, flags 0x1
Dec 31 16:01:51 kernel: [  111.444000] UBIFS DBG (pid 524): do_readpage: ino 319, pg 0, i_size 318888, flags 0x1
Dec 31 16:01:51 kernel: [  111.448000] UBIFS DBG (pid 524): do_readpage: ino 133, pg 0, i_size 20396, flags 0x1
Dec 31 16:01:51 kernel: [  111.452000] UBIFS DBG (pid 524): do_readpage: ino 319, pg 74, i_size 318888, flags 0x1
Dec 31 16:01:51 kernel: [  111.456000] UBIFS DBG (pid 524): do_readpage: ino 319, pg 75, i_size 318888, flags 0x1
Dec 31 16:01:51 kernel: [  111.460000] UBIFS DBG (pid 524): populate_page: ino 319, pg 76, i_size 318888, flags 0x1
Dec 31 16:01:51 kernel: [  111.460000] UBIFS DBG (pid 524): populate_page: ino 319, pg 77, i_size 318888, flags 0x1
Dec 31 16:01:51 kernel: [  111.460000] UBIFS DBG (pid 524): do_readpage: ino 133, pg 4, i_size 20396, flags 0x1
Dec 31 16:01:51 kernel: [  111.464000] UBIFS DBG (pid 524): do_readpage: ino 107, pg 0, i_size 2083, flags 0x1
Dec 31 16:01:51 kernel: [  111.468000] UBIFS DBG (pid 524): do_readpage: ino 134, pg 0, i_size 240184, flags 0x1
Dec 31 16:01:51 kernel: [  111.472000] UBIFS DBG (pid 524): do_readpage: ino 134, pg 56, i_size 240184, flags 0x1
Dec 31 16:01:51 kernel: [  111.472000] UBIFS DBG (pid 524): do_readpage: ino 134, pg 57, i_size 240184, flags 0x1
Dec 31 16:01:51 kernel: [  111.476000] UBIFS DBG (pid 524): populate_page: ino 134, pg 58, i_size 240184, flags 0x1
Dec 31 16:01:51 kernel: [  111.988000] UBIFS DBG (pid 405): ubifs_readdir: dir ino 1, f_pos 0x0
Dec 31 16:01:51 kernel: [  111.988000] UBIFS DBG (pid 405): ubifs_readdir: feed 'bin', ino 208, new f_pos 0x23605a
Dec 31 16:01:51 kernel: [  111.988000] UBIFS DBG (pid 405): ubifs_readdir: feed 'dev', ino 384, new f_pos 0x23ee05
Dec 31 16:01:51 kernel: [  111.992000] UBIFS DBG (pid 405): ubifs_readdir: feed 'etc', ino 65, new f_pos 0x24a603
Dec 31 16:01:52 kernel: [  112.012000] UBIFS DBG (pid 405): ubifs_readdir: feed 'lib', ino 124, new f_pos 0x2697fa
Dec 31 16:01:52 kernel: [  112.012000] UBIFS DBG (pid 405): ubifs_readdir: feed 'opt', ino 391, new f_pos 0x27d35e
Dec 31 16:01:52 kernel: [  112.012000] UBIFS DBG (pid 405): ubifs_readdir: feed 'tmp', ino 122, new f_pos 0x295e98
Dec 31 16:01:52 kernel: [  112.012000] UBIFS DBG (pid 405): ubifs_readdir: feed 'sys', ino 379, new f_pos 0x2968b1
Dec 31 16:01:52 kernel: [  112.012000] UBIFS DBG (pid 405): ubifs_readdir: feed 'var', ino 380, new f_pos 0x29ab98
Dec 31 16:01:52 kernel: [  112.020000] UBIFS DBG (pid 405): ubifs_readdir: feed 'usr', ino 390, new f_pos 0x29e101
Dec 31 16:01:52 kernel: [  112.020000] UBIFS DBG (pid 405): ubifs_readdir: feed 'logo.565', ino 378, new f_pos 0x1428f67
Dec 31 16:01:52 kernel: [  112.020000] UBIFS DBG (pid 405): ubifs_readdir: feed 'home', ino 389, new f_pos 0x19cc780
Dec 31 16:01:52 kernel: [  112.020000] UBIFS DBG (pid 405): ubifs_readdir: feed 'proc', ino 123, new f_pos 0x1baa5b4
Dec 31 16:01:52 kernel: [  112.020000] UBIFS DBG (pid 405): ubifs_readdir: feed 'sbin', ino 207, new f_pos 0x1c000e1
Dec 31 16:01:52 kernel: [  112.028000] UBIFS DBG (pid 405): ubifs_readdir: feed 'root', ino 373, new f_pos 0x1c0d8cc
Dec 31 16:01:52 kernel: [  112.028000] UBIFS DBG (pid 405): ubifs_readdir: dir ino 1, f_pos 0x2
Dec 31 16:01:52 kernel: [  112.028000] UBIFS DBG (pid 525): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
Dec 31 16:01:53 kernel: [  113.088000] UBIFS DBG (pid 526): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
Dec 31 16:01:53 kernel: [  113.120000] UBIFS DBG (pid 526): ubifs_get_sb: name ubi1:home, flags 0x8000
Dec 31 16:01:53 kernel: [  113.120000] UBIFS DBG (pid 526): ubifs_get_sb: opened ubi1_0
Dec 31 16:01:53 kernel: [  113.120000] PM: Adding info for No Bus:ubifs_1_0
Dec 31 16:01:53 kernel: [  113.120000] UBIFS DBG (pid 527): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
Dec 31 16:01:53 kernel: [  113.132000] UBIFS DBG (pid 528): ubifs_bg_thread: background thread "ubifs_bgt1_0" started, PID 528
Dec 31 16:01:53 kernel: [  113.192000] UBIFS DBG (pid 527): do_readpage: ino 68, pg 0, i_size 268, flags 0x1
Dec 31 16:01:53 kernel: [  113.352000] UBIFS: recovery needed
Dec 31 16:01:54 kernel: ffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 000010e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001100: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001120: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001140: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001160: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001180: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 000011a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 000011c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 000011e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001200: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001220: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001240: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001260: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001280: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 000012a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 000012c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 000012e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001300: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001320: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001340: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001360: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001380: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 000013a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 000013c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 000013e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001400: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001420: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001440: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001460: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001480: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 000014a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 000014c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 000014e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.048000] 00001500: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001520: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001540: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001560: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001580: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 000015a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 000015c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 000015e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001600: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001620: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001640: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001660: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001680: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 000016a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 000016c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 000016e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001700: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001720: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001740: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001760: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001780: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 000017a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 000017c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 000017e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001800: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001820: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001840: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001860: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001880: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 000018a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 000018c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 000018e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001900: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001920: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001940: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001960: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 00001980: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 000019a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 000019c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.052000] 000019e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001a00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001a20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001a40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001a60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001a80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001aa0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001ac0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001ae0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001b00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001b20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001b40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001b60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001b80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001ba0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001bc0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001be0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001c00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001c20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001c40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001c60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001c80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001ca0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001cc0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001ce0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001d00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001d20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001d40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001d60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001d80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001da0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001dc0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001de0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001e00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001e20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001e40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001e60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001e80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001ea0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001ec0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.056000] 00001ee0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.060000] 00001f00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.060000] 00001f20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.060000] 00001f40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.060000] 00001f60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.060000] 00001f80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.060000] 00001fa0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.060000] 00001fc0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.060000] 00001fe0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Dec 31 16:01:54 kernel: [  114.060000] UBIFS error (pid 526): ubifs_recover_leb: LEB 584 scanning failed
Dec 31 16:01:54 kernel: [  114.060000] UBIFS DBG (pid 424): do_readpage: ino 69, pg 0, i_size 9, flags 0x1
Dec 31 16:01:54 kernel: [  114.116000] UBIFS DBG (pid 528): ubifs_bg_thread: background thread "ubifs_bgt1_0" stops
Dec 31 16:01:54 kernel: [  114.116000] PM: Removing info for No Bus:ubifs_1_0
Dec 31 16:01:54 kernel: [  114.116000] UBIFS DBG (pid 529): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
Dec 31 16:01:54 kernel: [  114.116000] UBIFS DBG (pid 529): do_readpage: ino 319, pg 0, i_size 318888, flags 0x1
Dec 31 16:01:54 kernel: [  114.124000] UBIFS DBG (pid 529): do_readpage: ino 133, pg 0, i_size 20396, flags 0x1
Dec 31 16:01:54 kernel: [  114.128000] UBIFS DBG (pid 421): do_readpage: ino 110, pg 0, i_size 87, flags 0x1
Dec 31 16:01:54 kernel: [  114.128000] UBIFS DBG (pid 529): do_readpage: ino 319, pg 74, i_size 318888, flags 0x1
Dec 31 16:01:54 kernel: [  114.132000] UBIFS DBG (pid 529): do_readpage: ino 319, pg 75, i_size 318888, flags 0x1
Dec 31 16:01:54 kernel: [  114.136000] UBIFS DBG (pid 529): populate_page: ino 319, pg 76, i_size 318888, flags 0x21
Dec 31 16:01:54 kernel: [  114.136000] UBIFS DBG (pid 529): populate_page: ino 319, pg 77, i_size 318888, flags 0x1
Dec 31 16:01:54 kernel: [  114.136000] UBIFS DBG (pid 529): do_readpage: ino 133, pg 4, i_size 20396, flags 0x1
Dec 31 16:01:54 kernel: [  114.140000] UBIFS DBG (pid 530): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
Dec 31 16:01:54 kernel: [  114.144000] UBIFS DBG (pid 529): do_readpage: ino 107, pg 0, i_size 2083, flags 0x1
Dec 31 16:01:54 kernel: [  114.148000] UBIFS DBG (pid 530): do_readpage: ino 319, pg 0, i_size 318888, flags 0x1
Dec 31 16:01:54 kernel: [  114.152000] UBIFS DBG (pid 529): do_readpage: ino 134, pg 0, i_size 240184, flags 0x1
Dec 31 16:01:54 kernel: [  114.168000] UBIFS DBG (pid 530): do_readpage: ino 133, pg 0, i_size 20396, flags 0x1
Dec 31 16:01:54 kernel: [  114.168000] UBIFS DBG (pid 529): do_readpage: ino 134, pg 56, i_size 240184, flags 0x1
Dec 31 16:01:54 kernel: [  114.172000] UBIFS DBG (pid 530): do_readpage: ino 319, pg 74, i_size 318888, flags 0x1
Dec 31 16:01:54 kernel: [  114.176000] UBIFS DBG (pid 530): do_readpage: ino 319, pg 75, i_size 318888, flags 0x1
Dec 31 16:01:54 kernel: [  114.184000] UBIFS DBG (pid 529): do_readpage: ino 134, pg 57, i_size 240184, flags 0x1
Dec 31 16:01:54 kernel: [  114.184000] UBIFS DBG (pid 529): populate_page: ino 134, pg 58, i_size 240184, flags 0x1
Dec 31 16:01:54 kernel: [  114.188000] UBIFS DBG (pid 530): populate_page: ino 319, pg 76, i_size 318888, flags 0x1
Dec 31 16:01:54 kernel: [  114.228000] UBIFS DBG (pid 529): do_readpage: ino 68, pg 0, i_size 268, flags 0x1
Dec 31 16:01:54 kernel: [  114.708000] UBIFS DBG (pid 405): ubifs_readdir: dir ino 1, f_pos 0x0
Dec 31 16:01:54 kernel: [  114.708000] UBIFS DBG (pid 405): ubifs_readdir: feed 'bin', ino 208, new f_pos 0x23605a
Dec 31 16:01:54 kernel: [  114.708000] UBIFS DBG (pid 405): ubifs_readdir: feed 'dev', ino 384, new f_pos 0x23ee05
Dec 31 16:01:54 kernel: [  114.712000] UBIFS DBG (pid 405): ubifs_readdir: feed 'etc', ino 65, new f_pos 0x24a603
Dec 31 16:01:54 kernel: [  114.716000] UBIFS DBG (pid 405): ubifs_readdir: feed 'lib', ino 124, new f_pos 0x2697fa
Dec 31 16:01:54 kernel: [  114.716000] UBIFS DBG (pid 405): ubifs_readdir: feed 'opt', ino 391, new f_pos 0x27d35e
Dec 31 16:01:54 kernel: [  114.716000] UBIFS DBG (pid 405): ubifs_readdir: feed 'tmp', ino 122, new f_pos 0x295e98
Dec 31 16:01:54 kernel: [  114.716000] UBIFS DBG (pid 405): ubifs_readdir: feed 'sys', ino 379, new f_pos 0x2968b1
Dec 31 16:01:54 kernel: [  114.716000] UBIFS DBG (pid 405): ubifs_readdir: feed 'var', ino 380, new f_pos 0x29ab98
Dec 31 16:01:54 kernel: [  114.724000] UBIFS DBG (pid 405): ubifs_readdir: feed 'usr', ino 390, new f_pos 0x29e101
Dec 31 16:01:54 kernel: [  114.724000] UBIFS DBG (pid 405): ubifs_readdir: feed 'logo.565', ino 378, new f_pos 0x1428f67
Dec 31 16:01:54 kernel: [  114.724000] UBIFS DBG (pid 405): ubifs_readdir: feed 'home', ino 389, new f_pos 0x19cc780
Dec 31 16:01:54 kernel: [  114.724000] UBIFS DBG (pid 405): ubifs_readdir: feed 'proc', ino 123, new f_pos 0x1baa5b4
Dec 31 16:01:54 kernel: [  114.728000] UBIFS DBG (pid 405): ubifs_readdir: feed 'sbin', ino 207, new f_pos 0x1c000e1
Dec 31 16:01:54 kernel: [  114.732000] UBIFS DBG (pid 405): ubifs_readdir: feed 'root', ino 373, new f_pos 0x1c0d8cc
Dec 31 16:01:54 kernel: [  114.732000] UBIFS DBG (pid 405): ubifs_readdir: dir ino 1, f_pos 0x2
Dec 31 16:01:54 kernel: [  114.732000] UBIFS DBG (pid 531): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
Dec 31 16:01:56 kernel: [  116.228000] UBIFS DBG (pid 532): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
Dec 31 16:01:56 kernel: [  116.232000] UBIFS DBG (pid 424): do_readpage: ino 69, pg 0, i_size 9, flags 0x1
Dec 31 16:01:56 kernel: [  116.256000] UBIFS DBG (pid 532): do_readpage: ino 319, pg 0, i_size 318888, flags 0x1
Dec 31 16:01:56 kernel: [  116.256000] UBIFS DBG (pid 532): do_readpage: ino 133, pg 0, i_size 20396, flags 0x1
Dec 31 16:01:56 kernel: [  116.292000] UBIFS DBG (pid 532): do_readpage: ino 319, pg 74, i_size 318888, flags 0x1
Dec 31 16:01:56 kernel: [  116.292000] UBIFS DBG (pid 532): do_readpage: ino 319, pg 75, i_size 318888, flags 0x1
Dec 31 16:01:56 kernel: [  116.296000] UBIFS DBG (pid 532): populate_page: ino 319, pg 76, i_size 318888, flags 0x1
Dec 31 16:01:56 kernel: [  116.296000] UBIFS DBG (pid 532): populate_page: ino 319, pg 77, i_size 318888, flags 0x1
Dec 31 16:01:56 kernel: [  116.316000] UBIFS DBG (pid 532): do_readpage: ino 133, pg 4, i_size 20396, flags 0x1
Dec 31 16:01:56 kernel: [  116.316000] UBIFS DBG (pid 532): do_readpage: ino 107, pg 0, i_size 2083, flags 0x1
Dec 31 16:01:56 kernel: [  116.320000] UBIFS DBG (pid 532): do_readpage: ino 134, pg 0, i_size 240184, flags 0x1
Dec 31 16:01:56 kernel: [  116.320000] UBIFS DBG (pid 532): do_readpage: ino 134, pg 56, i_size 240184, flags 0x1
Dec 31 16:01:56 kernel: [  116.324000] UBIFS DBG (pid 532): do_readpage: ino 134, pg 57, i_size 240184, flags 0x1
Dec 31 16:01:56 kernel: [  116.328000] UBIFS DBG (pid 532): populate_page: ino 134, pg 58, i_size 240184, flags 0x21
Dec 31 16:01:56 kernel: [  116.836000] UBIFS DBG (pid 405): ubifs_readdir: dir ino 1, f_pos 0x0
Dec 31 16:01:56 kernel: [  116.836000] UBIFS DBG (pid 405): ubifs_readdir: feed 'bin', ino 208, new f_pos 0x23605a
Dec 31 16:01:56 kernel: [  116.836000] UBIFS DBG (pid 405): ubifs_readdir: feed 'dev', ino 384, new f_pos 0x23ee05
Dec 31 16:01:56 kernel: [  116.836000] UBIFS DBG (pid 405): ubifs_readdir: feed 'etc', ino 65, new f_pos 0x24a603
Dec 31 16:01:56 kernel: [  116.844000] UBIFS DBG (pid 405): ubifs_readdir: feed 'lib', ino 124, new f_pos 0x2697fa
Dec 31 16:01:56 kernel: [  116.844000] UBIFS DBG (pid 405): ubifs_readdir: feed 'opt', ino 391, new f_pos 0x27d35e
Dec 31 16:01:56 kernel: [  116.844000] UBIFS DBG (pid 405): ubifs_readdir: feed 'tmp', ino 122, new f_pos 0x295e98
Dec 31 16:01:56 kernel: [  116.844000] UBIFS DBG (pid 405): ubifs_readdir: feed 'sys', ino 379, new f_pos 0x2968b1
Dec 31 16:01:56 kernel: [  116.844000] UBIFS DBG (pid 405): ubifs_readdir: feed 'var', ino 380, new f_pos 0x29ab98
Dec 31 16:01:56 kernel: [  116.844000] UBIFS DBG (pid 405): ubifs_readdir: feed 'usr', ino 390, new f_pos 0x29e101
Dec 31 16:01:56 kernel: [  116.852000] UBIFS DBG (pid 405): ubifs_readdir: feed 'logo.565', ino 378, new f_pos 0x1428f67
Dec 31 16:01:56 kernel: [  116.852000] UBIFS DBG (pid 405): ubifs_readdir: feed 'home', ino 389, new f_pos 0x19cc780
Dec 31 16:01:56 kernel: [  116.852000] UBIFS DBG (pid 405): ubifs_readdir: feed 'proc', ino 123, new f_pos 0x1baa5b4
Dec 31 16:01:56 kernel: [  116.852000] UBIFS DBG (pid 405): ubifs_readdir: feed 'sbin', ino 207, new f_pos 0x1c000e1
Dec 31 16:01:56 kernel: [  116.860000] UBIFS DBG (pid 405): ubifs_readdir: feed 'root', ino 373, new f_pos 0x1c0d8cc
Dec 31 16:01:56 kernel: [  116.860000] UBIFS DBG (pid 405): ubifs_readdir: dir ino 1, f_pos 0x2
Dec 31 16:01:56 kernel: [  116.860000] UBIFS DBG (pid 533): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
Dec 31 16:01:57 kernel: [  117.968000] UBIFS DBG (pid 534): ubifs_lookup: 'cat' in dir ino 208
Dec 31 16:01:57 kernel: [  117.976000] UBIFS DBG (pid 534): ubifs_iget: inode 217
Dec 31 16:01:57 kernel: [  117.976000] UBIFS DBG (pid 534): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
Dec 31 16:01:58 kernel: [  118.360000] UBIFS DBG (pid 535): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
Dec 31 16:01:58 kernel: [  118.364000] UBIFS DBG (pid 424): do_readpage: ino 69, pg 0, i_size 9, flags 0x1
Dec 31 16:01:58 kernel: [  118.388000] UBIFS DBG (pid 535): do_readpage: ino 319, pg 0, i_size 318888, flags 0x1
Dec 31 16:01:58 kernel: [  118.392000] UBIFS DBG (pid 535): do_readpage: ino 133, pg 0, i_size 20396, flags 0x1
Dec 31 16:01:58 kernel: [  118.440000] UBIFS DBG (pid 535): do_readpage: ino 319, pg 74, i_size 318888, flags 0x1
Dec 31 16:01:58 kernel: [  118.440000] UBIFS DBG (pid 535): do_readpage: ino 319, pg 75, i_size 318888, flags 0x1
Dec 31 16:01:58 kernel: [  118.444000] UBIFS DBG (pid 535): populate_page: ino 319, pg 76, i_size 318888, flags 0x1
Dec 31 16:01:58 kernel: [  118.444000] UBIFS DBG (pid 535): populate_page: ino 319, pg 77, i_size 318888, flags 0x1
Dec 31 16:01:58 kernel: [  118.444000] UBIFS DBG (pid 535): do_readpage: ino 133, pg 4, i_size 20396, flags 0x1
Dec 31 16:01:58 kernel: [  118.452000] UBIFS DBG (pid 535): do_readpage: ino 107, pg 0, i_size 2083, flags 0x1
Dec 31 16:01:58 kernel: [  118.452000] UBIFS DBG (pid 535): do_readpage: ino 134, pg 0, i_size 240184, flags 0x1
Dec 31 16:01:58 kernel: [  118.456000] UBIFS DBG (pid 535): do_readpage: ino 134, pg 56, i_size 240184, flags 0x1
Dec 31 16:01:58 kernel: [  118.460000] UBIFS DBG (pid 535): do_readpage: ino 134, pg 57, i_size 240184, flags 0x1
Dec 31 16:01:58 kernel: [  118.460000] UBIFS DBG (pid 535): populate_page: ino 134, pg 58, i_size 240184, flags 0x21
Dec 31 16:01:58 kernel: [  118.972000] UBIFS DBG (pid 405): ubifs_readdir: dir ino 1, f_pos 0x0
Dec 31 16:01:58 kernel: [  118.972000] UBIFS DBG (pid 405): ubifs_readdir: feed 'bin', ino 208, new f_pos 0x23605a
Dec 31 16:01:58 kernel: [  118.972000] UBIFS DBG (pid 405): ubifs_readdir: feed 'dev', ino 384, new f_pos 0x23ee05
Dec 31 16:01:58 kernel: [  118.976000] UBIFS DBG (pid 405): ubifs_readdir: feed 'etc', ino 65, new f_pos 0x24a603
Dec 31 16:01:59 kernel: [  118.996000] UBIFS DBG (pid 405): ubifs_readdir: feed 'lib', ino 124, new f_pos 0x2697fa
Dec 31 16:01:59 kernel: [  119.000000] UBIFS DBG (pid 405): ubifs_readdir: feed 'opt', ino 391, new f_pos 0x27d35e
Dec 31 16:01:59 kernel: [  119.000000] UBIFS DBG (pid 405): ubifs_readdir: feed 'tmp', ino 122, new f_pos 0x295e98
Dec 31 16:01:59 kernel: [  119.000000] UBIFS DBG (pid 405): ubifs_readdir: feed 'sys', ino 379, new f_pos 0x2968b1
Dec 31 16:01:59 kernel: [  119.000000] UBIFS DBG (pid 405): ubifs_readdir: feed 'var', ino 380, new f_pos 0x29ab98
Dec 31 16:01:59 kernel: [  119.000000] UBIFS DBG (pid 405): ubifs_readdir: feed 'usr', ino 390, new f_pos 0x29e101
Dec 31 16:01:59 kernel: [  119.008000] UBIFS DBG (pid 405): ubifs_readdir: feed 'logo.565', ino 378, new f_pos 0x1428f67
Dec 31 16:01:59 kernel: [  119.008000] UBIFS DBG (pid 405): ubifs_readdir: feed 'home', ino 389, new f_pos 0x19cc780
Dec 31 16:01:59 kernel: [  119.008000] UBIFS DBG (pid 405): ubifs_readdir: feed 'proc', ino 123, new f_pos 0x1baa5b4
Dec 31 16:01:59 kernel: [  119.008000] UBIFS DBG (pid 405): ubifs_readdir: feed 'sbin', ino 207, new f_pos 0x1c000e1
Dec 31 16:01:59 kernel: [  119.012000] UBIFS DBG (pid 405): ubifs_readdir: feed 'root', ino 373, new f_pos 0x1c0d8cc
Dec 31 16:01:59 kernel: [  119.012000] UBIFS DBG (pid 405): ubifs_readdir: dir ino 1, f_pos 0x2
Dec 31 16:01:59 kernel: [  119.012000] UBIFS DBG (pid 536): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
Dec 31 16:02:00 kernel: [  120.568000] UBIFS DBG (pid 537): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
Dec 31 16:02:00 kernel: [  120.572000] UBIFS DBG (pid 424): do_readpage: ino 69, pg 0, i_size 9, flags 0x1
Dec 31 16:02:00 kernel: [  120.596000] UBIFS DBG (pid 537): do_readpage: ino 319, pg 0, i_size 318888, flags 0x1
Dec 31 16:02:00 kernel: [  120.600000] UBIFS DBG (pid 537): do_readpage: ino 133, pg 0, i_size 20396, flags 0x1
Dec 31 16:02:00 kernel: [  120.604000] UBIFS DBG (pid 537): do_readpage: ino 319, pg 74, i_size 318888, flags 0x1
Dec 31 16:02:00 kernel: [  120.624000] UBIFS DBG (pid 537): do_readpage: ino 319, pg 75, i_size 318888, flags 0x1
Dec 31 16:02:00 kernel: [  120.628000] UBIFS DBG (pid 537): populate_page: ino 319, pg 76, i_size 318888, flags 0x1
Dec 31 16:02:00 kernel: [  120.628000] UBIFS DBG (pid 537): populate_page: ino 319, pg 77, i_size 318888, flags 0x1
Dec 31 16:02:00 kernel: [  120.628000] UBIFS DBG (pid 537): do_readpage: ino 133, pg 4, i_size 20396, flags 0x1
Dec 31 16:02:00 kernel: [  120.632000] UBIFS DBG (pid 537): do_readpage: ino 107, pg 0, i_size 2083, flags 0x1
Dec 31 16:02:00 kernel: [  120.636000] UBIFS DBG (pid 537): do_readpage: ino 134, pg 0, i_size 240184, flags 0x1
Dec 31 16:02:00 kernel: [  120.640000] UBIFS DBG (pid 537): do_readpage: ino 134, pg 56, i_size 240184, flags 0x1
Dec 31 16:02:00 kernel: [  120.640000] UBIFS DBG (pid 537): do_readpage: ino 134, pg 57, i_size 240184, flags 0x1
Dec 31 16:02:00 kernel: [  120.644000] UBIFS DBG (pid 537): populate_page: ino 134, pg 58, i_size 240184, flags 0x21
root:/> 


^ permalink raw reply	[flat|nested] 16+ messages in thread

* Re: UBIFS Corruption
  2011-11-28 20:04       ` Reginald Perrin
@ 2011-11-29 22:30         ` Artem Bityutskiy
  2011-12-07 17:19         ` Reginald Perrin
  1 sibling, 0 replies; 16+ messages in thread
From: Artem Bityutskiy @ 2011-11-29 22:30 UTC (permalink / raw)
  To: Reginald Perrin; +Cc: MTD Mailing List

On Mon, 2011-11-28 at 12:04 -0800, Reginald Perrin wrote:
> > 
> 
> > There are no interesting messages:
> > 
> > 1. You enabled all UBIFS debugging messages which are too verbose and
> > not needed. Do not enable them because they "push out" the interesting
> > diagnostic messages from the kernel printk ring buffer.
> > 
> > 2. And it is strange that the last messages are about the commit and not
> > about mount failure - are you sure that you typed dmesg right after the
> > UBIFS mount failure without rebooting ?
> > 
> > -- 
> > Best Regards,
> > Artem Bityutskiy
> > 
> > 
> 
> 
> Hi Artem,
> 
> Whatever is causing this, it seems to be increasing in frequency (or
> we are getting better at inadvertently causing it).

I do not remember - did I ask you to validate your flash with mtd tests?
Please do - it is useful.

> I now have several units in this state, and have tried to change the
> log level to gather more information.  Since the ubifs volume I need
> to mount isn't the root-file-system, I have the luxury of being able
> to create test kernels, flash them, and boot them into the system, at
> which point they try and mount the corrupted partition.
> 
> Below, you can see where I tried to mount this partition after booting
> (I discarded all old messages, so this is just the output of
> attempting to mount this volume).  
> 
> NOTE:  My #1 priority is figuring out how to recover from this -- when
> this happens, we're actually bricked, and can't recover (since all the
> key recovery programs are actually on this volume that can't mount).  
> 
> What else can I do to get more information to you to assist?
> 
> TIA
> RP
> 
> root:/> rm /var/log/messages 
> root:/> mount -t ubifs ubi1:home /home
> mount: mounting ubi1:home on /home failed: Structure needs cleaning
> root:/> cat /var/log/messages 

> Dec 31 16:01:51 kernel: [  111.400000] UBIFS DBG (pid 524): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
> Dec 31 16:01:51 kernel: [  111.404000] UBIFS DBG (pid 424): do_readpage: ino 69, pg 0, i_size 9, flags 0x1
> Dec 31 16:01:51 kernel: [  111.444000] UBIFS DBG (pid 524): do_readpage: ino 319, pg 0, i_size 318888, flags 0x1
> Dec 31 16:01:51 kernel: [  111.448000] UBIFS DBG (pid 524): do_readpage: ino 133, pg 0, i_size 20396, flags 0x1
> Dec 31 16:01:51 kernel: [  111.452000] UBIFS DBG (pid 524): do_readpage: ino 319, pg 74, i_size 318888, flags 0x1
> Dec 31 16:01:51 kernel: [  111.456000] UBIFS DBG (pid 524): do_readpage: ino 319, pg 75, i_size 318888, flags 0x1
> Dec 31 16:01:51 kernel: [  111.460000] UBIFS DBG (pid 524): populate_page: ino 319, pg 76, i_size 318888, flags 0x1
> Dec 31 16:01:51 kernel: [  111.460000] UBIFS DBG (pid 524): populate_page: ino 319, pg 77, i_size 318888, flags 0x1
> Dec 31 16:01:51 kernel: [  111.460000] UBIFS DBG (pid 524): do_readpage: ino 133, pg 4, i_size 20396, flags 0x1
> Dec 31 16:01:51 kernel: [  111.464000] UBIFS DBG (pid 524): do_readpage: ino 107, pg 0, i_size 2083, flags 0x1
> Dec 31 16:01:51 kernel: [  111.468000] UBIFS DBG (pid 524): do_readpage: ino 134, pg 0, i_size 240184, flags 0x1
> Dec 31 16:01:51 kernel: [  111.472000] UBIFS DBG (pid 524): do_readpage: ino 134, pg 56, i_size 240184, flags 0x1
> Dec 31 16:01:51 kernel: [  111.472000] UBIFS DBG (pid 524): do_readpage: ino 134, pg 57, i_size 240184, flags 0x1
> Dec 31 16:01:51 kernel: [  111.476000] UBIFS DBG (pid 524): populate_page: ino 134, pg 58, i_size 240184, flags 0x1
> Dec 31 16:01:51 kernel: [  111.988000] UBIFS DBG (pid 405): ubifs_readdir: dir ino 1, f_pos 0x0
> Dec 31 16:01:51 kernel: [  111.988000] UBIFS DBG (pid 405): ubifs_readdir: feed 'bin', ino 208, new f_pos 0x23605a
> Dec 31 16:01:51 kernel: [  111.988000] UBIFS DBG (pid 405): ubifs_readdir: feed 'dev', ino 384, new f_pos 0x23ee05
> Dec 31 16:01:51 kernel: [  111.992000] UBIFS DBG (pid 405): ubifs_readdir: feed 'etc', ino 65, new f_pos 0x24a603
> Dec 31 16:01:52 kernel: [  112.012000] UBIFS DBG (pid 405): ubifs_readdir: feed 'lib', ino 124, new f_pos 0x2697fa
> Dec 31 16:01:52 kernel: [  112.012000] UBIFS DBG (pid 405): ubifs_readdir: feed 'opt', ino 391, new f_pos 0x27d35e
> Dec 31 16:01:52 kernel: [  112.012000] UBIFS DBG (pid 405): ubifs_readdir: feed 'tmp', ino 122, new f_pos 0x295e98
> Dec 31 16:01:52 kernel: [  112.012000] UBIFS DBG (pid 405): ubifs_readdir: feed 'sys', ino 379, new f_pos 0x2968b1
> Dec 31 16:01:52 kernel: [  112.012000] UBIFS DBG (pid 405): ubifs_readdir: feed 'var', ino 380, new f_pos 0x29ab98
> Dec 31 16:01:52 kernel: [  112.020000] UBIFS DBG (pid 405): ubifs_readdir: feed 'usr', ino 390, new f_pos 0x29e101
> Dec 31 16:01:52 kernel: [  112.020000] UBIFS DBG (pid 405): ubifs_readdir: feed 'logo.565', ino 378, new f_pos 0x1428f67
> Dec 31 16:01:52 kernel: [  112.020000] UBIFS DBG (pid 405): ubifs_readdir: feed 'home', ino 389, new f_pos 0x19cc780
> Dec 31 16:01:52 kernel: [  112.020000] UBIFS DBG (pid 405): ubifs_readdir: feed 'proc', ino 123, new f_pos 0x1baa5b4
> Dec 31 16:01:52 kernel: [  112.020000] UBIFS DBG (pid 405): ubifs_readdir: feed 'sbin', ino 207, new f_pos 0x1c000e1
> Dec 31 16:01:52 kernel: [  112.028000] UBIFS DBG (pid 405): ubifs_readdir: feed 'root', ino 373, new f_pos 0x1c0d8cc
> Dec 31 16:01:52 kernel: [  112.028000] UBIFS DBG (pid 405): ubifs_readdir: dir ino 1, f_pos 0x2
> Dec 31 16:01:52 kernel: [  112.028000] UBIFS DBG (pid 525): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
> Dec 31 16:01:53 kernel: [  113.088000] UBIFS DBG (pid 526): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
> Dec 31 16:01:53 kernel: [  113.120000] UBIFS DBG (pid 526): ubifs_get_sb: name ubi1:home, flags 0x8000
> Dec 31 16:01:53 kernel: [  113.120000] UBIFS DBG (pid 526): ubifs_get_sb: opened ubi1_0

You enabled all the debugging - there is too much of it so it does not
fit your ring buffer. Did you increase it? Try making it larger. I do
not know how much RAM you have, but to make it 64MiB add this to your
kernel boot parameters:

log_buf_len=64M

Also add

ignore_loglevel 

to see all messages in the console.

I suggested you to disable debugging messages altogether, though, like
it says here in item 3:

http://linux-mtd.infradead.org/faq/ubifs.html#L_how_send_bugreport

> Dec 31 16:01:53 kernel: [  113.120000] PM: Adding info for No Bus:ubifs_1_0
> Dec 31 16:01:53 kernel: [  113.120000] UBIFS DBG (pid 527): ubifs_getxattr: xattr 'security.capability', ino 319 ('busybox'), buf size 20
> Dec 31 16:01:53 kernel: [  113.132000] UBIFS DBG (pid 528): ubifs_bg_thread: background thread "ubifs_bgt1_0" started, PID 528
> Dec 31 16:01:53 kernel: [  113.192000] UBIFS DBG (pid 527): do_readpage: ino 68, pg 0, i_size 268, flags 0x1


> Dec 31 16:01:53 kernel: [  113.352000] UBIFS: recovery needed
> Dec 31 16:01:54 kernel: ffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................

This line seem to be corrupted - no time-stamp. There is missing
information before "Recovery needed and the dump, I think.

^ permalink raw reply	[flat|nested] 16+ messages in thread

* Re: UBIFS Corruption
  2011-11-28 20:04       ` Reginald Perrin
  2011-11-29 22:30         ` Artem Bityutskiy
@ 2011-12-07 17:19         ` Reginald Perrin
  2011-12-08 21:42           ` Artem Bityutskiy
  1 sibling, 1 reply; 16+ messages in thread
From: Reginald Perrin @ 2011-12-07 17:19 UTC (permalink / raw)
  Cc: MTD Mailing List



>>  1. You enabled all UBIFS debugging messages which are too verbose and
>>  not needed. Do not enable them because they "push out" the 
> interesting
>>  diagnostic messages from the kernel printk ring buffer.
>> 


Artem,

I'm trying to get a good log, but seem to have issues getting the right configuration.  Currently, I have these config settings:
CONFIG_MTD_UBI=y
CONFIG_MTD_UBI_WL_THRESHOLD=4096
CONFIG_MTD_UBI_BEB_RESERVE=1
CONFIG_UBIFS_FS=y
CONFIG_UBIFS_FS_XATTR=y
CONFIG_UBIFS_FS_LZO=y
CONFIG_UBIFS_FS_ZLIB=y
CONFIG_UBIFS_FS_DEBUG=y
CONFIG_UBIFS_FS_DEBUG_MSG_LVL=0



Booting shows:
[    4.860000] UBI: background thread "ubi_bgt1d" started, PID 355
UBI device number 1, total 749 LEBs (96638976 bytes, 92.2 MiB), available 0 LEBs (0 bytes), LEB size 129024 bytes (126.0 KiB)
[    5.004000] UBIFS: recovery needed
[    5.580000] UBIFS error (pid 357): ubifs_recover_leb: garbage
[    5.584000] UBIFS error (pid 357): ubifs_scanned_corruption: corruption at LEB 498:81920
[    5.588000] UBIFS error (pid 357): ubifs_scanned_corruption: first 8192 bytes from LEB 498:81920
[    5.616000] UBIFS error (pid 357): ubifs_recover_leb: LEB 498 scanning failed



When I do a dmesg, I get:
root:/> dmesg
fffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[    5.608000] 00001460: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
(snip)
[    5.616000] 00001fe0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[    5.616000] UBIFS error (pid 357): ubifs_recover_leb: LEB 498 scanning failed
[    5.620000] UBIFS DBG (pid 359): ubifs_bg_thread: background thread "ubifs_bgt1_0" stops



This is pretty much the start of the log:

root:/> head -n 20 /var/log/messages 
Jan  1 12:38:28 syslogd started: BusyBox v1.16.2
Jan  1 12:38:28 kernel: [    5.608000] 00001480: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................



I can actually pull the image off the flash (using JTAG), it's about 20MB.  When I've tried to mount this in the past on my PC, I get a mount error and can't get more information.  We would be willing to share (under NDA), and contract assistance, if required.  

Is this any better?


^ permalink raw reply	[flat|nested] 16+ messages in thread

* Re: UBIFS Corruption
  2011-12-07 17:19         ` Reginald Perrin
@ 2011-12-08 21:42           ` Artem Bityutskiy
  2011-12-12 14:29             ` Reginald Perrin
  0 siblings, 1 reply; 16+ messages in thread
From: Artem Bityutskiy @ 2011-12-08 21:42 UTC (permalink / raw)
  To: Reginald Perrin; +Cc: MTD Mailing List

Hi,

On Wed, 2011-12-07 at 09:19 -0800, Reginald Perrin wrote:
> Booting shows:
> [    4.860000] UBI: background thread "ubi_bgt1d" started, PID 355
> UBI device number 1, total 749 LEBs (96638976 bytes, 92.2 MiB), available 0 LEBs (0 bytes), LEB size 129024 bytes (126.0 KiB)
> [    5.004000] UBIFS: recovery needed
> [    5.580000] UBIFS error (pid 357): ubifs_recover_leb: garbage
> [    5.584000] UBIFS error (pid 357): ubifs_scanned_corruption: corruption at LEB 498:81920
> [    5.588000] UBIFS error (pid 357): ubifs_scanned_corruption: first 8192 bytes from LEB 498:81920
> [    5.616000] UBIFS error (pid 357): ubifs_recover_leb: LEB 498 scanning failed

Sorry, but I believe I sent you the solution - make the logbuffer larger
and add the ignore debuglevel option. Citing myself:

"log_buf_len=64M

Also add

ignore_loglevel"

IOW, add "log_buf_len=64M ignore_loglevel" options to you kernel boot
options.

Artem.

What is your flash exactly?

Artem.

^ permalink raw reply	[flat|nested] 16+ messages in thread

* Re: UBIFS Corruption
  2011-12-08 21:42           ` Artem Bityutskiy
@ 2011-12-12 14:29             ` Reginald Perrin
  2011-12-12 16:34               ` Artem Bityutskiy
  0 siblings, 1 reply; 16+ messages in thread
From: Reginald Perrin @ 2011-12-12 14:29 UTC (permalink / raw)
  To: MTD Mailing List

> IOW, add "log_buf_len=64M ignore_loglevel" options to you kernel boot

> options.
> 
> Artem.
> 
> What is your flash exactly?
> 
> Artem.
> 


Hi Artem,

We're using ST/Nymonyx 1Gb NAND:  NAND device: Manufacturer ID: 0x20, Chip ID: 0xf1 (ST Micro NAND 128MiB 3,3V 8-bit)


Apologies for missing the kernel boot instructions -- I kept rebuilding the kernel trying to get the parameters right, so I never got the right results.  Once I used the command line parameters, I think I have it now.  Our system has 64M of RAM, so I had to switch to 32M.  Below is the info that I think you want (it's from the boot console output).  When I switch to 32M buffer, dmesg fails due to an out-of-memory error.

Jan  1 19:33:33 kernel: [    3.772000] Freeing unused kernel memory: 124k freed
Jan  1 19:33:33 kernel: [    5.432000] UBI: attaching mtd5 to ubi1
Jan  1 19:33:33 kernel: [    5.436000] UBI: physical eraseblock size:   131072 bytes (128 KiB)
Jan  1 19:33:33 kernel: [    5.440000] UBI: logical eraseblock size:    129024 bytes
Jan  1 19:33:33 kernel: [    5.444000] UBI: smallest flash I/O unit:    2048
Jan  1 19:33:33 kernel: [    5.448000] UBI: sub-page size:              512
Jan  1 19:33:33 kernel: [    5.452000] UBI: VID header offset:          512 (aligned 512)
Jan  1 19:33:33 kernel: [    5.456000] UBI: data offset:                2048
Jan  1 19:33:33 kernel: [    5.808000] PM: Adding info for No Bus:ubi1
Jan  1 19:33:33 kernel: [    5.820000] PM: Adding info for No Bus:ubi1_0
Jan  1 19:33:33 kernel: [    5.828000] UBI: attached mtd5 to ubi1
Jan  1 19:33:33 kernel: [    5.832000] UBI: MTD device name:            "home"
Jan  1 19:33:33 kernel: [    5.836000] UBI: MTD device size:            93 MiB
Jan  1 19:33:33 kernel: [    5.840000] UBI: number of good PEBs:        749
Jan  1 19:33:33 kernel: [    5.844000] UBI: number of bad PEBs:         1
Jan  1 19:33:33 kernel: [    5.852000] UBI: max. allowed volumes:       128
Jan  1 19:33:33 kernel: [    5.856000] UBI: wear-leveling threshold:    4096
Jan  1 19:33:33 kernel: [    5.860000] UBI: number of internal volumes: 1
Jan  1 19:33:33 kernel: [    5.864000] UBI: number of user volumes:     1
Jan  1 19:33:33 kernel: [    5.868000] UBI: available PEBs:             0
Jan  1 19:33:33 kernel: [    5.872000] UBI: total number of reserved PEBs: 749
Jan  1 19:33:33 kernel: [    5.876000] UBI: number of PEBs reserved for bad PEB handling: 7
Jan  1 19:33:33 kernel: [    5.880000] UBI: max/mean erase counter: 3/1
Jan  1 19:33:33 kernel: [    5.884000] UBI: image sequence number: 1651720779
Jan  1 19:33:33 kernel: [    5.904000] UBI: background thread "ubi_bgt1d" started, PID 355
Jan  1 19:33:33 kernel: [    5.964000] PM: Adding info for No Bus:ubifs_1_0
Jan  1 19:33:33 kernel: [    5.976000] UBIFS DBG (pid 359): ubifs_bg_thread: background thread "ubifs_bgt1_0" started, PID 359
Jan  1 19:33:33 kernel: [    6.048000] UBIFS: recovery needed
Jan  1 19:33:33 kernel: [    6.616000] UBIFS error (pid 357): ubifs_recover_leb: garbage
Jan  1 19:33:33 kernel: [    6.620000] UBIFS error (pid 357): ubifs_scanned_corruption: corruption at LEB 498:81920
Jan  1 19:33:33 kernel: [    6.624000] UBIFS error (pid 357): ubifs_scanned_corruption: first 8192 bytes from LEB 498:81920
Jan  1 19:33:33 kernel: [    6.628000] 00000000: 17100610 b01ee889 00000000 02e40000 00010000 04ae0000 00930000 00002000  ............................. ..
Jan  1 19:33:33 kernel: [    6.632000] 00000020: 00000000 10000000 00010000 0b000000 632e6364 7ef2bf00 00030079 3a9a88e3  ................dc.c...~y......:
Jan  1 19:33:33 kernel: [    6.636000] 00000040: 00bb8306 0000195d 00000004 01004d09 1d0000cc 6f6d6552 57206574 75656b61  ....]........M......Remote Wakeu
Jan  1 19:33:33 kernel: [    6.640000] 00000060: 65722070 73657571 20646574 656a6572 64657463 7473202c 3d657461 52206425  p requested rejected, state=%d R
Jan  1 19:33:33 kernel: [    6.644000] 00000080: bd504957 00584500 73750008 2e636462 8b680000 7fb30c93 923a209b 9387fd01  WIP..EX...usbdc...h...... :.....
Jan  1 19:33:33 kernel: [    6.648000] 000000a0: 9c7b340c 01923a20 0c93e451 209d7204 7f01923a d50c9376 3a209e68 b42f0192  .4{. :..Q....r. :...v...h. :../.
Jan  1 19:33:33 kernel: [    6.652000] 000000c0: b28e0c93 923a20b0 90e88001 1eeb010c 4b744a35 0000b207 00000200 ac07d000  ..... :.........5JtK............
Jan  1 19:33:33 kernel: [    6.656000] 000000e0: 00014800 44410003 48542043 204d5245 28776172 20296425 0078566d 63700009  .H....ADC THERM raw(%d) mVx...pc
Jan  1 19:33:33 kernel: [    6.660000] 00000100: 706d6574 8400632e 1b0892db b108b149 1c0108d0 6c00000d 0208f000 54414256  temp.c......I..........l....VBAT
Jan  1 19:33:33 kernel: [    6.664000] 00000120: 01103054 6c6f7606 00632e74 08921db6 08b1734c 705cdc26 bd00ec07 5cdc2d5c  T0...volt.c.....Ls..&.\p....\-.\
Jan  1 19:33:33 kernel: [    6.668000] 00000140: 696e6903 5c616974 0b16275c 088ba44b 00006ecc aa352019 9341aa0c 376a4c0c  .initia\\'..K....n... 5...A..Lj7
Jan  1 19:33:33 kernel: [    6.672000] 00000160: 01923a20 0c93fb60 203865cd 0e01913a 614d2ea7 923a2039 87a7c401 3a581e19   :..`....e8 :.....Ma9 :.......X:
Jan  1 19:33:33 kernel: [    6.676000] 00000180: 01923a20 0c93f851 203b539e 3a01923a 6f0c939b 3a203c4a a16e0192 45ef0c93   :..Q....S; :..:...oJ< :..n....E
Jan  1 19:33:33 kernel: [    6.680000] 000001a0: 923a203d 939d4901 3e3cc00c 01923a20 0c931cfb 203f3840 3101923a 120c93a6  = :..I....<> :......@8? :..1....
Jan  1 19:33:33 kernel: [    6.684000] 000001c0: 3a20441d 380a0192 b7010c90 2059d5b7 df148e19 e70893a8 0890d621 148e1520  .D :...8......Y ........!... ...
Jan  1 19:33:33 kernel: [    6.688000] 000001e0: 08928e7b 08b04767 148d1320 974ca316 109cd747 148e3520 149faea5 00004318  {...gG.. .....L.G... 5.......C..
Jan  1 19:33:33 kernel: [    6.692000] 00000200: 7d01933a 0c979e5d 20d93e98 0a01963a 190c93ce 3a20da3a 440f0192 35990c93  :..}]....>. :.......:. :...D...5
Jan  1 19:33:33 kernel: [    6.696000] 00000220: 923a20db 93782801 dc311a0c 01923a20 0c9393b3 20dd2c9a 1301923a 1b0c93f5  . :..(x...1. :.......,. :.......
Jan  1 19:33:33 kernel: [    6.700000] 00000240: 3a20de28 7f160192 1eeb0c93 923a20df 93e7e301 e015bc0c 01923a20 0c93d829  (. :......... :......... :..)...
Jan  1 19:33:33 kernel: [    6.704000] 00000260: 20ef6843 b601923a 010c9090 69769082 14921920 0892f41c 08b0bab2 14921520  Ch. :.........vi ........... ...
Jan  1 19:33:33 kernel: [    6.712000] 00000280: 0893d285 847724e2 92132011 00002e14 78206308 35200888 12461492 1be30c93  .....$w.. .......c x.. 5..F.....
Jan  1 19:33:33 kernel: [    6.716000] 000002a0: 923a2079 93ee4e01 7a17640c 01923a20 0c9392f7 207b12e4 ee01923a 650c933f  y :..N...d.z :........{ :...?..e
Jan  1 19:33:33 kernel: [    6.720000] 000002c0: 3a207c0e dfdf0192 e5010c90 205d7d09 6001963a 010c945d 5e7d0566 1101943b  .| :.........}] :..`]...f.}^;...
Jan  1 19:33:33 kernel: [    6.724000] 000002e0: 20310000 18312e30 582b0610 b01fe643 00000000 02dd0000 00010000 04ae0000  ..1 0.1...+XC...................
Jan  1 19:33:33 kernel: [    6.728000] 00000300: 00940000 00002000 00000000 10000000 00010000 19000000 57206574 75656b61  ..... ..................te Wakeu
Jan  1 19:33:33 kernel: [    6.732000] 00000320: 65722070 73657571 20646574 656a6572 64657463 7473202c 3d657461 52206425  p requested rejected, state=%d R
Jan  1 19:33:33 kernel: [    6.736000] 00000340: bd504957 00584500 75001000 00006273 ee00632e 00797ea9 bc360003 06695e7d  WIP..EX....usb...c...~y...6.}^i.
Jan  1 19:33:33 kernel: [    6.740000] 00000360: 5d00bb83 04000019 09000000 cc01004d 65520500 65746f6d 19205720 ddfb0196  ...]........M.....Remote W .....
Jan  1 19:33:33 kernel: [    6.744000] 00000380: b7b60c97 923a207f 93be9001 80ae870c 01923a20 0c93e973 20902e4e 3901933a  ..... :......... :..s...N.. :..9
Jan  1 19:33:33 kernel: [    6.748000] 000003a0: 0c945e7d 17694d01 07327079 000000b2 00000002 00ac07d0 03000148 43444100  }^...Mi.yp2.............H....ADC
Jan  1 19:33:33 kernel: [    6.752000] 000003c0: 45485420 72204d52 25287761 6d202964 09007856 74637000 2e706d65 6f210063   THERM raw(%d) mVx...pctemp.c.!o
Jan  1 19:33:33 kernel: [    6.756000] 000003e0: 5d7d0893 b108b593 1c0108d4 6c00000d 0208f400 54414256 01143054 00007606  ..}]...........l....VBATT0...v..
Jan  1 19:33:33 kernel: [    6.760000] 00000400: 00632e74 08960158 08b1bdae 7043dc26 bd00ec07 43dc2d43 696e6903 54616974  t.c.X.......&.Cp....C-.C.initiaT
Jan  1 19:33:33 kernel: [    6.764000] 00000420: 098e2750 088b9a78 9418b92e 8a352019 932b5309 19b4af0c 01920000 0c93dcd3  P'..x........ 5..S+.............
Jan  1 19:33:33 kernel: [    6.768000] 00000440: 201ab02f d701923a af0c93b2 3a201bab a49d0192 a2800c93 923a201c 93c75401  /.. :......... :......... :..T..
Jan  1 19:33:33 kernel: [    6.772000] 00000460: 1d99500c 01923a20 0c93c85a 201e9021 4301913a 86f1a4bb 923a201f 84ba0801  .P.. :..Z...!.. :..C..... :.....
Jan  1 19:33:33 kernel: [    6.776000] 00000480: 7dc20119 3a20205d 5d3c0196 74920c97 923a2021 936d1601 2c39b70c 01923a20  ...}]  :..<]...t! :...m...9, :..
Jan  1 19:33:33 kernel: [    6.780000] 000004a0: 0c90caaf b846c901 96192088 9202d314 b070f908 92152008 9214cf14 b0967908  ......F.. ........p.. .......y..
Jan  1 19:33:33 kernel: [    6.784000] 000004c0: 92132008 8bd90c14 b996a908 35201990 27da1492 922a0c93 913a20ba 3fafcc01  . ............ 5...'..*.. :....?
Jan  1 19:33:33 kernel: [    6.788000] 000004e0: 20bb8daa ca01923a 2b198724 3a20bc89 0f420192 00000c93 923a20bd 931cc301  ... :...$..+.. :..B...... :.....
Jan  1 19:33:33 kernel: [    6.792000] 00000500: be802c0c 01923a20 0c93dee2 20bf76fc 4501923a 010c904a c0725d7d 01963a20  .,.. :.......v. :..EJ...}]r. :..
Jan  1 19:33:33 kernel: [    6.796000] 00000520: 0c97d097 20c16dfd 9101923a 010c9099 c2695e7d 01963a20 0c97d387 20cd4163  .....m. :.......}^i. :......cA. 
Jan  1 19:33:33 kernel: [    6.800000] 00000540: c501923a 010c9097 98591ae4 14961920 08921374 08b04514 14961520 08927095  :.........Y. ...t....E.. ....p..
Jan  1 19:33:33 kernel: [    6.804000] 00000560: 08b06f44 14961320 088b7cbe 905a6ac5 96352019 93cb2714 5b66450c 01923a20  Do.. ....|...jZ.. 5..'...Ef[ :..
Jan  1 19:33:33 kernel: [    6.808000] 00000580: 0c93cf75 205c61c6 9b01913a 58968baf 923a205d 87b3d201 5e541719 01923a20  u....a\ :......X] :.......T^ :..
Jan  1 19:33:33 kernel: [    6.812000] 000005a0: 0c93874f 205f4f97 0501923a 180c9391 3a20604b abf60000 6141e869 01902520  O....O_ :.......K` :....i.Aa %..
Jan  1 19:33:33 kernel: [    6.816000] 000005c0: 40000011 18310218 c8ae0610 b0209636 00000000 01b90000 00010000 04ae0000  ...@..1.....6. .................
Jan  1 19:33:33 kernel: [    6.820000] 000005e0: 00950000 00002000 00000000 066b0000 00010000 1b000000 253d5049 00002064  ..... ........k.........IP=%d ..
Jan  1 19:33:33 kernel: [    6.824000] 00000600: 64253d45 62737500 632e6364 7ef5b400 00030079 986238b8 00bb8306 0000195d  E=%d.usbdc.c...~y....8b.....]...
Jan  1 19:33:33 kernel: [    6.828000] 00000620: 00000004 01004d09 150000cc 6f6d6552 57206574 75656b61 65722070 73657571  .....M......Remote Wakeup reques
Jan  1 19:33:33 kernel: [    6.832000] 00000640: 20646574 656a6572 64657463 7473202c 54657461 5220020a bc504957 01922b00  ted rejected, stateT.. RWIP..+..
Jan  1 19:33:33 kernel: [    6.836000] 00000660: 0c9352a6 20632f89 da01923a 010c939f 3a207395 21340192 af010c90 78a7f9b3  .R.../c :........s :..4!.......x
Jan  1 19:33:33 kernel: [    6.840000] 00000680: 00b20000 00020000 07d00000 014800ac 41000300 54204344 4d524548 77617220  ..............H....ADC THERM raw
Jan  1 19:33:33 kernel: [    6.844000] 000006a0: 29642528 78566d20 70000900 6d657463 00632e70 08938dc2 91fa2290 08d0b108  (%d) mVx...pctemp.c......"......
Jan  1 19:33:33 kernel: [    6.848000] 000006c0: 000d1c01 f0006c00 42560208 30545441 76060110 2e746c6f e0010063 48100892  .....l....VBATT0...volt.c......H
Jan  1 19:33:33 kernel: [    6.852000] 000006e0: c02608b1 ec077037 2d37a100 690337c0 6974696e 27374061 46f207ee 4cf0088b  ..&.7p....7-.7.initia@7'...F...L
Jan  1 19:33:33 kernel: [    6.856000] 00000700: 20109cfb e407ee35 710c93b0 3a20fc48 9b6c0192 3f410c93 923a20fd 9384c601  ... 5......qH. :..l...A?. :.....
Jan  1 19:33:33 kernel: [    6.860000] 00000720: fe36120c 01923a20 0c934430 20ff3192 8f01923a 010c90c6 a8002d12 00005874  ..6. :..0D...1. :........-..tX..
Jan  1 19:33:33 kernel: [    6.864000] 00000740: 792707e2 23e30c93 923a2001 93242801 021f630c 01923a20 0c936bd7 20031634  ..'y...#. :..($..c.. :...k..4.. 
Jan  1 19:33:33 kernel: [    6.868000] 00000760: d801923a b40c93f7 3a200411 00240192 7f690c93 933a200e 7e6fea01 00000011  :......... :..$...i.. :...o~....
Jan  1 19:33:33 kernel: [    6.872000] 00000780: 00000000 18310000 7a710610 00003003 00000000 001c0000 00050000 005c0000  ......1...qz.0................\.
Jan  1 19:33:33 kernel: [    6.876000] 000007a0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    6.880000] 000007c0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    6.884000] 000007e0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    6.888000] 00000800: 9185bf39 b0220000 00000000 00000000 00000000 04a80000 20000093 00000000  9.....".................... ....
Jan  1 19:33:33 kernel: [    6.892000] 00000820: 00000000 00001000 00000001 20763200 30203033 33353a38 2033303a 6e72656b  .............2v 30 08:53:03 kern
Jan  1 19:33:33 kernel: [    6.896000] 00000840: 203a6c65 3520205b 382e3038 30303830 73205d30 74726f70 61747320 20737574  el: [  580.808000] sport status 
Jan  1 19:33:33 kernel: [    6.900000] 00000860: 6f727265 54203a72 0a465655 20766f4e fe383033 20343600 0200fc1a 2e310000  error: TUVF.Nov 308..64 ......1.
Jan  1 19:33:33 kernel: [    6.904000] 00000880: fd1d2032 1d203700 203600fd 3501fd0d 0104fc2c 33322e33 01fc0c20 3300ff2f  2 ...7 ...6 ...5,...3.23 .../..3
Jan  1 19:33:33 kernel: [    6.908000] 000008a0: 0b203631 fd2f03fc 1e203600 279801fc 07fd0920 03ff2c36 20302e34 2f01fc0d  16 .../..6 ....' ...6,..4.0 .../
Jan  1 19:33:33 kernel: [    6.912000] 000008c0: 323100fe 08fc0c20 3400fd2f 01fc0f20 5b337a07 5d313036 c822203a 36250062  ..12 .../..4 ....z3[601]: ".b.%6
Jan  1 19:33:33 kernel: [    6.916000] 000008e0: 3835373a 2d313120 322d3033 20313130 55424544 725f2047 73696765 74617274  :758 11-30-2011 DEBUG _registrat
Jan  1 19:33:33 kernel: [    6.920000] 00000900: 2e6e6f69 3a707063 33343220 565a2022 3a70696f 0054763a 65520100 65764567  ion.cpp: 243" ZVoip::vT...RegEve
Jan  1 19:33:33 kernel: [    6.924000] 00000920: 2928746e 65735520 30202072 01684420 206c6c69 070007ac 64657265 0000202e  nt() User  0 Dh.ill ....ered. ..
Jan  1 19:33:33 kernel: [    6.928000] 00000940: 20704b20 20656874 74696166 2e2e2e68 0d522d20 52322036 20303702 20025304   Kp the faith... -R.6 2R.70 .S. 
Jan  1 19:33:33 kernel: [    6.932000] 00000960: 50273139 74530402 20747261 04178052 6e697265 9d752067 06203112 182b01c4  91'P..Start R...ering u..1 ...+.
Jan  1 19:33:33 kernel: [    6.936000] 00000980: 4e490204 33204f46 2f300419 69020418 72657473 07041827 3722202c 20223231  ..INFO 3..0/...ister'..., "712" 
Jan  1 19:33:33 kernel: [    6.940000] 000009a0: 2f272031 21646502 020e0420 05203138 372003d4 2e370629 33380b7e 0e7c0c20  1 '/.ed! ...81 ... 7).7.~.83 .|.
Jan  1 19:33:33 kernel: [    6.948000] 000009c0: 3900fd2f 09205b78 fe2d0d7c 982e3500 fe1a2063 20343300 2f177c0c 179801fc  /..9x[ .|.-..5..c ...34 .|./....
Jan  1 19:33:33 kernel: [    6.952000] 000009e0: 01fc0b20 5b337a03 29383438 37020d7c 3430393a 200b2930 00600000 00000000   ....z3[848)|..7:9040). ..`.....
Jan  1 19:33:33 kernel: [    6.956000] 00000a00: bc616964 076b0000 00003820 00002220 00004d72 28200000 6f666e49 3a290000  dia...k. 8.. "..rM.... (Info..):
Jan  1 19:33:33 kernel: [    6.960000] 00000a20: 203a3834 4c746547 516b6e69 696c6175 29287974 35343620 20736d32 027a202d  48: GetLinkQuality() 6452ms - z.
Jan  1 19:33:33 kernel: [    6.964000] 00000a40: d874315b 2f530a00 352d5b4e 392d2f37 35332033 6d626420 6552205d 65697274  [1t...S/N[-57/-93 35 dbm] Retrie
Jan  1 19:33:33 kernel: [    6.968000] 00000a60: 73545b73 2e300900 46205d30 6d676172 73746e65 2039315b 5d392e32 73694d20  s[Ts..0.0] Fragments[19 2.9] Mis
Jan  1 19:33:33 kernel: [    6.972000] 00000a80: 00782763 736d3401 0aa82e22 542f0000 550920de 542c3804 2e350109 0c203537  c'x..4ms"...../T. .U.8,T..5.75 .
Jan  1 19:33:33 kernel: [    6.976000] 00000aa0: fc2d18d4 2e360100 327c3430 1ed40820 3100fd2f 19201880 203400fd 2f09540d  ..-...6.04|2 .../..1.. ...4 .T./
Jan  1 19:33:33 kernel: [    6.980000] 00000ac0: 983501fd fd1920ce 62983601 00fe0720 fc2c3031 2e380105 0b203630 fd300c54  ..5.. ...6.b ...10,...8.06 .T.0.
Jan  1 19:33:33 kernel: [    6.984000] 00000ae0: 0c203200 fd3024d4 0c203400 fd3003fc 0c203600 fc3120d4 fd0d2000 fe2e3101  .2 ..$0..4 ...0..6 .. 1.. ...1..
Jan  1 19:33:33 kernel: [    6.988000] 00000b00: 20373804 3114550b 3900ff2d 0d20302e fd2f06fc 1e203200 0d2001fc fe3020d4  .87 .U.1-..9.0 .../..2 ... .. 0.
Jan  1 19:33:33 kernel: [    6.992000] 00000b20: 20383402 2f08fc0c 4f9802fc 10fe0820 ff2c3231 382e3905 17207f78 390100fc  .48 .../...O ...12,..9.8x. ....9
Jan  1 19:33:33 kernel: [    6.996000] 00000b40: 80302e30 fc082008 2c0c0000 d2543039 03fc0c20 2000fc2f 2f04fc0e 353600fe  0.0.. .....,90T. .../.. .../..65
Jan  1 19:33:33 kernel: [    7.000000] 00000b60: 09fc0c20 5e33d42c 2039381f 3115fd0b 3100ff2d 0c20302e fe3014fc 20393200   ...,.3^.89 ...1-..1.0 ...0..29 
Jan  1 19:33:33 kernel: [    7.004000] 00000b80: 3102ff1b 0d20342e fe2f0bfc 20323502 2f06fc0c 0d2000fc 343134d6 2b2b8022  ...1.4 .../..52 .../.. ..414".++
Jan  1 19:33:33 kernel: [    7.008000] 00000ba0: 31032054 30323a34 57342031 34383220 36205533 36205524 0320543e 34203932  T .14:201 4W 2843U 6$U 6>T .29 4
Jan  1 19:33:33 kernel: [    7.012000] 00000bc0: 0120362e 2c312055 3901362c 20312e33 31392c0c 0d2000fc fc2f0954 392c3501  .6 .U 1,,6.93.1 .,91.. .T./..5,9
Jan  1 19:33:33 kernel: [    7.016000] 00000be0: b5000011 06101831 7f2edd2d 00000000 00000000 0000001c 00000005 00000000  ....1...-.......................
Jan  1 19:33:33 kernel: [    7.020000] 00000c00: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.024000] 00000c20: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.028000] 00000c40: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.032000] 00000c60: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.036000] 00000c80: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.040000] 00000ca0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.044000] 00000cc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.048000] 00000ce0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.052000] 00000d00: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.056000] 00000d20: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.060000] 00000d40: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.068000] 00000d80: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.072000] 00000da0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.076000] 00000dc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.080000] 00000de0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.084000] 00000e00: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.088000] 00000e20: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.092000] 00000e40: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.096000] 00000e60: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.100000] 00000e80: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.104000] 00000ea0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.108000] 00000ec0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.112000] 00000ee0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.116000] 00000f00: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.120000] 00000f20: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.124000] 00000f40: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.128000] 00000f60: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.132000] 00000f80: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.136000] 00000fa0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.140000] 00000fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.144000] 00000fe0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000  ................................
Jan  1 19:33:33 kernel: [    7.148000] 00001000: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.152000] 00001020: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.156000] 00001040: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.160000] 00001060: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.164000] 00001080: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.168000] 000010a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.172000] 000010c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.176000] 000010e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.184000] 00001100: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.188000] 00001120: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.192000] 00001140: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.196000] 00001160: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.200000] 00001180: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.204000] 000011a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.208000] 000011c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.212000] 000011e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.216000] 00001200: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.220000] 00001220: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.224000] 00001240: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.228000] 00001260: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.232000] 00001280: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.236000] 000012a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.240000] 000012c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.244000] 000012e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.248000] 00001300: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.252000] 00001320: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.256000] 00001340: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.260000] 00001360: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.264000] 00001380: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.268000] 000013a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.272000] 000013c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.276000] 000013e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.280000] 00001400: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.284000] 00001420: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.288000] 00001440: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.292000] 00001460: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.296000] 00001480: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.300000] 000014a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.308000] 000014e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.312000] 00001500: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.316000] 00001520: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.320000] 00001540: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.324000] 00001560: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.328000] 00001580: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.332000] 000015a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.336000] 000015c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.340000] 000015e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.344000] 00001600: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.348000] 00001620: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.352000] 00001640: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.356000] 00001660: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.360000] 00001680: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.364000] 000016a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.368000] 000016c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.372000] 000016e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.376000] 00001700: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.380000] 00001720: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.384000] 00001740: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.388000] 00001760: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.392000] 00001780: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.396000] 000017a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.400000] 000017c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.404000] 000017e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.408000] 00001800: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.412000] 00001820: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.420000] 00001840: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.424000] 00001860: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.428000] 00001880: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.432000] 000018a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.436000] 000018c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.440000] 000018e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.444000] 00001900: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.448000] 00001920: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.452000] 00001940: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.456000] 00001960: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.460000] 00001980: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.464000] 000019a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.468000] 000019c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.472000] 000019e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.476000] 00001a00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.480000] 00001a20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.484000] 00001a40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.488000] 00001a60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.492000] 00001a80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.496000] 00001aa0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.500000] 00001ac0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.504000] 00001ae0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.508000] 00001b00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.512000] 00001b20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.516000] 00001b40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.520000] 00001b60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.524000] 00001b80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.528000] 00001ba0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.532000] 00001bc0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.536000] 00001be0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.540000] 00001c00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.544000] 00001c20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.548000] 00001c40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.552000] 00001c60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.556000] 00001c80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.560000] 00001ca0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.564000] 00001cc0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.568000] 00001ce0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.572000] 00001d00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.576000] 00001d20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.580000] 00001d40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.584000] 00001d60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.588000] 00001d80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.592000] 00001da0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.596000] 00001dc0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.600000] 00001de0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.604000] 00001e00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.608000] 00001e20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.612000] 00001e40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.616000] 00001e60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.620000] 00001e80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.624000] 00001ea0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.628000] 00001ec0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.632000] 00001ee0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.636000] 00001f00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.640000] 00001f20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.644000] 00001f40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.648000] 00001f60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.656000] 00001f80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.660000] 00001fa0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.664000] 00001fc0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.668000] 00001fe0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
Jan  1 19:33:33 kernel: [    7.672000] UBIFS error (pid 357): ubifs_recover_leb: LEB 498 scanning failed
Jan  1 19:33:34 kernel: [    7.692000] UBIFS DBG (pid 359): ubifs_bg_thread: background thread "ubifs_bgt1_0" stops
Jan  1 19:33:34 kernel: [    7.696000] PM: Removing info for No Bus:ubifs_1_0


^ permalink raw reply	[flat|nested] 16+ messages in thread

* Re: UBIFS Corruption
  2011-12-12 14:29             ` Reginald Perrin
@ 2011-12-12 16:34               ` Artem Bityutskiy
       [not found]                 ` <1323709988.3066.YahooMailNeo@web114618.mail.gq1.yahoo.com>
  0 siblings, 1 reply; 16+ messages in thread
From: Artem Bityutskiy @ 2011-12-12 16:34 UTC (permalink / raw)
  To: Reginald Perrin; +Cc: MTD Mailing List

[-- Attachment #1: Type: text/plain, Size: 1011 bytes --]

On Mon, 2011-12-12 at 06:29 -0800, Reginald Perrin wrote:
> Jan  1 19:33:33 kernel: [    5.976000] UBIFS DBG (pid 359): ubifs_bg_thread: background thread "ubifs_bgt1_0" started, PID 359
> Jan  1 19:33:33 kernel: [    6.048000] UBIFS: recovery needed
> Jan  1 19:33:33 kernel: [    6.616000] UBIFS error (pid 357): ubifs_recover_leb: garbage
> Jan  1 19:33:33 kernel: [    6.620000] UBIFS error (pid 357): ubifs_scanned_corruption: corruption at LEB 498:81920
> Jan  1 19:33:33 kernel: [    6.624000] UBIFS error (pid 357): ubifs_scanned_corruption: first 8192 bytes from LEB 498:81920

OK, would you please enable mount and scanning debugging messages and
send the results again (or provide a pastebin link). Please, do not
enable other debugging message - they will only introduce noise.

But even better would be - first add only mount messages, capture the
output, then enable both mount and scan and capture the output. Then
provide both logs. Thanks!

-- 
Best Regards,
Artem Bityutskiy

[-- Attachment #2: This is a digitally signed message part --]
[-- Type: application/pgp-signature, Size: 836 bytes --]

^ permalink raw reply	[flat|nested] 16+ messages in thread

* Re: UBIFS Corruption
       [not found]                 ` <1323709988.3066.YahooMailNeo@web114618.mail.gq1.yahoo.com>
@ 2011-12-13 16:25                   ` Reginald Perrin
  2011-12-13 20:19                     ` Artem Bityutskiy
  0 siblings, 1 reply; 16+ messages in thread
From: Reginald Perrin @ 2011-12-13 16:25 UTC (permalink / raw)
  To: MTD Mailing List



> echo 4 > /sys/module/ubifs/parameters/debug_tsts
> 
> Mount: http://pastebin.com/rh5vz4xB
> 
> echo 2052 > /sys/module/ubifs/parameters/debug_tsts
> 
> Mount and scan:  http://pastebin.com/C87dYK1g
> 
> However, I don't see a huge difference.  Did I do that right?
> 
> Thanks, RP
> 

Artem,

We're the pastebin's helpful?  Not sure I saw a response on this.

Thanks, RP

^ permalink raw reply	[flat|nested] 16+ messages in thread

* Re: UBIFS Corruption
  2011-12-13 16:25                   ` Reginald Perrin
@ 2011-12-13 20:19                     ` Artem Bityutskiy
  0 siblings, 0 replies; 16+ messages in thread
From: Artem Bityutskiy @ 2011-12-13 20:19 UTC (permalink / raw)
  To: Reginald Perrin; +Cc: MTD Mailing List

On Tue, 2011-12-13 at 08:25 -0800, Reginald Perrin wrote:
> 
> > echo 4 > /sys/module/ubifs/parameters/debug_tsts
> > 
> > Mount: http://pastebin.com/rh5vz4xB
> > 
> > echo 2052 > /sys/module/ubifs/parameters/debug_tsts
> > 
> > Mount and scan:  http://pastebin.com/C87dYK1g
> > 
> > However, I don't see a huge difference.  Did I do that right?
> > 
> > Thanks, RP
> > 
> 
> Artem,
> 
> We're the pastebin's helpful?  Not sure I saw a response on this.

I did respond - I wrote "dmesg" - I meant that you have to run 'dmesg'
to produce full output. These pastebins do not contain all theuseful
information because it was not printed to your console. So:

echo >
mount
dmesg
->> to pastebin.

Artem.

^ permalink raw reply	[flat|nested] 16+ messages in thread

end of thread, other threads:[~2011-12-13 20:19 UTC | newest]

Thread overview: 16+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2011-07-08 13:12 UBIFS Corruption Reginald Perrin
2011-07-18 11:33 ` Artem Bityutskiy
2011-07-19 20:47   ` Reginald Perrin
2011-07-26 19:17   ` Reginald Perrin
2011-08-02 16:14     ` Reginald Perrin
2011-08-15 12:30       ` Artem Bityutskiy
2011-08-15 12:29     ` Artem Bityutskiy
2011-11-28 20:04       ` Reginald Perrin
2011-11-29 22:30         ` Artem Bityutskiy
2011-12-07 17:19         ` Reginald Perrin
2011-12-08 21:42           ` Artem Bityutskiy
2011-12-12 14:29             ` Reginald Perrin
2011-12-12 16:34               ` Artem Bityutskiy
     [not found]                 ` <1323709988.3066.YahooMailNeo@web114618.mail.gq1.yahoo.com>
2011-12-13 16:25                   ` Reginald Perrin
2011-12-13 20:19                     ` Artem Bityutskiy
2011-07-18 11:38 ` Artem Bityutskiy

This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.