Current mainline (3.6-rc3) UBIFS errors
David Jander
david.jander at protonic.nl
Thu Aug 23 03:50:48 EDT 2012
Hi,
I have tried latest mainline kernel as of today (3.6-rc3) on two different
platforms (ARMv5 i.MX28 and PowerPC MPC5121e) and see similar crashes when
accessing UBIFS volumes (i.e. booting from UBIFS):
Please excuse the lengthy e-mail, I have tried to strip down the error message
hemorrhage to a sensible size, but I don't want to leave out potentially
important information:
On the powerpc platform the errors start like this:
[ 0.729014] mpc5121_nfc 40000000.nfc: Configured for 8-bit NAND, page size 2048 with 64 spare
[ 0.738097] NAND device: Manufacturer ID: 0xec, Chip ID: 0xd3 (Samsung NAND 1GiB 3,3V 8-bit), page size: 2048, OOB siz
[ 0.749822] Bad block table found at page 524224, version
[ 0.755957] Bad block table found at page 524160, version
[ 0.762069] nand_read_bbt: bad block at 0x0000076a0000
[ 0.767284] nand_read_bbt: bad block at 0x00000a520000
[ 0.772361] nand_read_bbt: bad block at 0x00000a920000
[ 0.777524] nand_read_bbt: bad block at 0x000013120000
[ 0.782741] nand_read_bbt: bad block at 0x000018f80000
[ 0.787828] nand_read_bbt: bad block at 0x00001cde0000
[ 0.792947] nand_read_bbt: bad block at 0x00001dda0000
[ 0.798358] 1 ofpart partitions found on MTD device MPC5121 NAN
[ 0.804309] Creating 1 MTD partitions on "MPC5121 NAND"
[ 0.809709] 0x000000000000-0x000040000000 : "rootfs
[ 0.820303] UBI: attaching mtd9 to ubi0
[ 0.824400] UBI: physical eraseblock size: 131072 bytes (128 KiB)
[ 0.830791] UBI: logical eraseblock size: 129024 bytes
[ 0.836166] UBI: smallest flash I/O unit: 2048
[ 0.840851] UBI: sub-page size: 512
[ 0.845574] UBI: VID header offset: 512 (aligned
[ 0.851351] UBI: data offset: 2048
[ 2.937972] UBI: max. sequence number: 4335
[ 2.976054] UBI: attached mtd9 to u
[ 2.979839] UBI: MTD device name: "rootfs"
[ 2.984870] UBI: MTD device size: 1024 MiB
[ 2.990019] UBI: number of good PEBs: 8181
[ 2.994681] UBI: number of bad PEBs: 11
[ 2.999187] UBI: number of corrupted PEBs:
[ 3.003616] UBI: max. allowed volumes: 128
[ 3.008333] UBI: wear-leveling threshold: 4096
[ 3.013049] UBI: number of internal volumes:
[ 3.017441] UBI: number of user volumes:
[ 3.021973] UBI: available PEBs:
[ 3.026352] UBI: total number of reserved PEBs: 818
[ 3.031307] UBI: number of PEBs reserved for bad PEB handling: 16
[ 3.037480] UBI: max/mean erase counter:
[ 3.041844] UBI: image sequence number: 143079883
[ 3.046589] UBI: background thread "ubi_bgt0d" started, PID 637
...
[ 3.697900] UBIFS: recovery needed
[ 3.711531] UBIFS: recovery completed
[ 3.715164] UBIFS: mounted UBI device 0, volume 0, name "rootfs"
[ 3.721246] UBIFS: file system size: 1031933952 bytes (1007748 KiB, 984 MiB, 7998 LEBs)
[ 3.729371] UBIFS: journal size: 33546240 bytes (32760 KiB, 31 MiB, 260 LEB
[ 3.737125] UBIFS: media format: w4/r0 (latest is w4/r0)
[ 3.743288] UBIFS: default compressor: lzo
[ 3.747343] UBIFS: reserved for root: 4952683 bytes (4836 KiB)
[ 3.755392] VFS: Mounted root (ubifs filesystem) on device 0:10.
[ 3.762783] Freeing unused kernel memory: 184k free
[ 3.793628] usb 2-1: new high-speed USB device number 2 using fsl-ehc
[ 3.929736] hub 2-1:1.0: USB hub found
[ 3.935594] hub 2-1:1.0: 4 ports detecte
[ 4.019916] usb 1-1.1: new high-speed USB device number 3 using fsl-ehc
[ 4.066170] uncorrectable error :
[ 4.069409] UBI warning: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 5616:87112, read only 160 bytes
[ 4.081519] uncorrectable error :
[ 4.084704] UBI warning: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 5616:87112, read only 160 bytes
[ 4.096830] uncorrectable error :
[ 4.100024] UBI warning: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 5616:87112, read only 160 bytes
[ 4.112212] uncorrectable error :
[ 4.115558] UBI error: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 5616:87112, read 160
[ 4.126533] Call Trac
[ 4.128956] [df6efbb0] [c0007498] show_stack+0x4c/0x138 (unreliable)
[ 4.135272] [df6efbf0] [c0244810] ubi_io_read+0x220/0x2c0
[ 4.140685] [df6efc40] [c0242b98] ubi_eba_read_leb+0x254/0x334
[ 4.146728] [df6efc80] [c0241ef8] ubi_leb_read+0xe4/0x134
[ 4.152237] [df6efca0] [c01650ec] ubifs_leb_read+0x2c/0x80
[ 4.157702] [df6efcc0] [c0168710] fallible_read_node+0x64/0x1ac
[ 4.163828] [df6efd10] [c0169d1c] ubifs_tnc_locate+0x14c/0x1c4
[ 4.169694] [df6efd60] [c016177c] ubifs_iget+0x7c/0x7f0
[ 4.174900] [df6efd90] [c015ec50] ubifs_lookup+0x16c/0x1f0
[ 4.180607] [df6efdc0] [c009409c] lookup_real+0x40/0x60
[ 4.185829] [df6efdd0] [c0097f24] do_last.isra.50+0x498/0xa24
[ 4.191759] [df6efe30] [c0098550] path_openat+0xa0/0x3fc
[ 4.197121] [df6efe90] [c0098b88] do_filp_open+0x30/0x78
[ 4.202502] [df6eff10] [c0089bf4] do_sys_open+0x134/0x1c8
[ 4.208069] [df6eff40] [c000d670] ret_from_syscall+0x0/0x38
[ 4.213702] --- Exception: c01 at 0x20476a8
[ 4.213702] LR = 0x2
[ 4.221742] UBIFS error (pid 842): ubifs_leb_read: reading 160 bytes from LEB 3563:85064 failed, error -74
[ 4.231772] Call Trac
[ 4.234519] [df6efc60] [c0007498] show_stack+0x4c/0x138 (unreliable)
[ 4.241122] [df6efca0] [c0165134] ubifs_leb_read+0x74/0x80
[ 4.246692] [df6efcc0] [c0168710] fallible_read_node+0x64/0x1ac
[ 4.252690] [df6efd10] [c0169d1c] ubifs_tnc_locate+0x14c/0x1c4
[ 4.258716] [df6efd60] [c016177c] ubifs_iget+0x7c/0x7f0
[ 4.264181] [df6efd90] [c015ec50] ubifs_lookup+0x16c/0x1f0
[ 4.270043] [df6efdc0] [c009409c] lookup_real+0x40/0x60
[ 4.275370] [df6efdd0] [c0097f24] do_last.isra.50+0x498/0xa24
[ 4.281222] [df6efe30] [c0098550] path_openat+0xa0/0x3fc
[ 4.286468] [df6efe90] [c0098b88] do_filp_open+0x30/0x78
[ 4.291958] [df6eff10] [c0089bf4] do_sys_open+0x134/0x1c8
[ 4.297362] [df6eff40] [c000d670] ret_from_syscall+0x0/0x38
[ 4.302910] --- Exception: c01 at 0x20476a8
[ 4.302910] LR = 0x2
[ 4.310450] UBIFS error (pid 842): try_read_node: cannot read node type 0 from LEB 3563:85064, error -7
[ 4.319934] uncorrectable error :
[ 4.323244] UBI warning: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 5616:87112, read only 160 bytes
[ 4.335222] uncorrectable error :
[ 4.338550] UBI warning: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 5616:87112, read only 160 bytes
[ 4.350604] uncorrectable error : [ 4.353997] usb 1-1.1: reset high-speed USB device number 3 using fsl-e
[ 4.360890] UBI warning: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 5616:87112, read only 160 bytes
[ 4.373046] uncorrectable error :
[ 4.376370] UBI error: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 5616:87112, read 160
[ 4.387311] Call Trac
[ 4.389734] [df6efb80] [c0007498] show_stack+0x4c/0x138 (unreliable)
[ 4.396052] [df6efbc0] [c0244810] ubi_io_read+0x220/0x2c0
[ 4.401440] [df6efc10] [c0242b98] ubi_eba_read_leb+0x254/0x334
[ 4.407415] [df6efc50] [c0241ef8] ubi_leb_read+0xe4/0x134
[ 4.412788] [df6efc70] [c01650ec] ubifs_leb_read+0x2c/0x80
[ 4.418267] [df6efc90] [c0166ad8] ubifs_read_node+0x124/0x248
[ 4.424137] [df6efcc0] [c0181eac] ubifs_tnc_read_node+0x60/0x134
[ 4.430126] [df6efd10] [c0169c90] ubifs_tnc_locate+0xc0/0x1c4
[ 4.435925] [df6efd60] [c016177c] ubifs_iget+0x7c/0x7f0
[ 4.441134] [df6efd90] [c015ec50] ubifs_lookup+0x16c/0x1f0
[ 4.446634] [df6efdc0] [c009409c] lookup_real+0x40/0x60
[ 4.451881] [df6efdd0] [c0097f24] do_last.isra.50+0x498/0xa24
[ 4.457699] [df6efe30] [c0098550] path_openat+0xa0/0x3fc
[ 4.463070] [df6efe90] [c0098b88] do_filp_open+0x30/0x78
[ 4.468424] [df6eff10] [c0089bf4] do_sys_open+0x134/0x1c8
[ 4.473808] [df6eff40] [c000d670] ret_from_syscall+0x0/0x38
[ 4.479378] --- Exception: c01 at 0x20476a8
[ 4.479378] LR = 0x2
[ 4.487028] UBIFS error (pid 842): ubifs_check_node: bad magic 0x000000, expected 0x6101831
[ 4.495458] UBIFS error (pid 842): ubifs_check_node: bad node at LEB 3563:85064
[ 4.503120] Not a node, first 24 bytes:
[ 4.506864] 00000000: 00 00 00 00 00 00 00 00 20 fa 07 00 00 00 00 00 00 00 00 00 00 00 00 00 ........ ....
[ 4.519981] Call Trac
[ 4.522389] [df6efc30] [c0007498] show_stack+0x4c/0x138 (unreliable)
[ 4.528706] [df6efc70] [c01657ac] ubifs_check_node+0x24c/0x264
[ 4.534589] [df6efc90] [c0166b30] ubifs_read_node+0x17c/0x248
[ 4.540813] [df6efcc0] [c0181eac] ubifs_tnc_read_node+0x60/0x134
[ 4.548324] [df6efd10] [c0169c90] ubifs_tnc_locate+0xc0/0x1c4
[ 4.556135] [df6efd60] [c016177c] ubifs_iget+0x7c/0x7f0
[ 4.562873] [df6efd90] [c015ec50] ubifs_lookup+0x16c/0x1f0
[ 4.568652] [df6efdc0] [c009409c] lookup_real+0x40/0x60
[ 4.573940] [df6efdd0] [c0097f24] do_last.isra.50+0x498/0xa24
[ 4.579709] [df6efe30] [c0098550] path_openat+0xa0/0x3fc
[ 4.585082] [df6efe90] [c0098b88] do_filp_open+0x30/0x78
[ 4.590398] [df6eff10] [c0089bf4] do_sys_open+0x134/0x1c8
[ 4.595792] [df6eff40] [c000d670] ret_from_syscall+0x0/0x38
etc...
Eventually, after a continued storm of such error messages, the system gets to
the login prompt (wonder!). Upon fs accesses the following messages are
produced:
[ 632.095496] UBIFS error (pid 2044): make_reservation: cannot reserve 160 bytes in jhead 1, error -30
[ 632.106810] UBIFS error (pid 2044): ubifs_write_inode: can't write inode 4458, error -30
[ 632.117046] UBIFS error (pid 2044): make_reservation: cannot reserve 160 bytes in jhead 1, error -30
[ 632.128112] UBIFS error (pid 2044): ubifs_write_inode: can't write inode 4472, error -30
[ 791.850368] UBIFS error (pid 2277): make_reservation: cannot reserve 408 bytes in jhead 1, error -30
[ 791.861791] UBIFS error (pid 2277): ubifs_create: cannot create regular file, error -30
[ 791.872740] UBIFS error (pid 2277): make_reservation: cannot reserve 408 bytes in jhead 1, error -30
[ 791.884014] UBIFS error (pid 2277): ubifs_create: cannot create regular file, error -30
[ 791.894726] UBIFS error (pid 2277): make_reservation: cannot reserve 408 bytes in jhead 1, error -30
[ 791.905866] UBIFS error (pid 2277): ubifs_create: cannot create regular file, error -30
[ 791.990008] uncorrectable error :
[ 791.993271] uncorrectable error : [ 791.998354] uncorrectable error :
[ 792.004489] uncorrectable error : [ 792.009634] uncorrectable error :
[ 792.015271] uncorrectable error : [ 792.020251] uncorrectable error :
[ 792.026571] uncorrectable error : [ 792.031448] UBI error: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 5616:87432, read 160 bytes
[ 792.046615] UBIFS error (pid 1925): ubifs_leb_read: reading 160 bytes from LEB 3563:85384 failed, error -74
[ 792.072034] UBIFS error (pid 1925): try_read_node: cannot read node type 0 from LEB 3563:85384, error -74
[ 792.095807] uncorrectable error :
[ 792.099008] uncorrectable error : [ 792.114843] uncorrectable error :
[ 792.118112] uncorrectable error : [ 792.128933] uncorrectable error :
[ 792.135262] uncorrectable error : [ 792.145267] uncorrectable error :
[ 792.148473] uncorrectable error : [ 792.159756] UBI error: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 5616:87432, read 160 bytes
[ 792.186861] UBIFS error (pid 1925): ubifs_check_node: bad magic 0x000000, expected 0x6101831
[ 792.208112] UBIFS error (pid 1925): ubifs_check_node: bad node at LEB 3563:85384
[ 792.225532] Not a node, first 24 bytes:
[ 792.229169] 00000000: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ........................
[ 792.265468] UBIFS error (pid 1925): ubifs_read_node: expected node type 0
[ 792.282144] UBIFS error (pid 1925): ubifs_iget: failed to read inode 4173, error -117
[ 792.300927] UBIFS error (pid 1925): ubifs_lookup: dead directory entry 'dhclient.eth0.leases', error -117
On the ARM platform things look like this:
Booting kernel...
Image Name: Linux-3.6.0-rc2prt-00003-g315801
Created: 2012-08-22 7:31:05 UTC
Data Size: 3244456 Bytes = 3.1 MB
Load Address: 40008000
Entry Point: 40008000
booting Linux kernel with devicetree
Uncompressing Linux... done, booting the kernel.
[ 0.000000] Booting Linux on physical CPU 0
[ 0.000000] Linux version 3.6.0-rc2prt-00003-g315801e-dirty (robin at erd943) (gcc version 4.4.1 (Sourcery G++ Lite 2010q1-202) ) #561 PREEMPT Wed Aug 22 09:30:55 CEST 2012
[ 0.000000] CPU: ARM926EJ-S [41069265] revision 5 (ARMv5TEJ), cr=00053177
[ 0.000000] CPU: VIVT data cache, VIVT instruction cache
[ 0.000000] Machine: Freescale i.MX28 (Device Tree), model: Protonic I28 Board
[ 0.000000] Memory policy: ECC disabled, Data cache writeback
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 65024
[ 0.000000] Kernel command line: console=ttyAMA0,115200 rw consoleblank=0 ubi.mtd=0 root=ubi0:rootfs rootfstype=ubifs
[ 0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[ 0.000000] Memory: 256MB = 256MB total
[ 0.000000] Memory: 248212k/248212k available, 13932k reserved, 0K highmem
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xfff00000 - 0xfffe0000 ( 896 kB)
[ 0.000000] vmalloc : 0xd0800000 - 0xff000000 ( 744 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xd0000000 ( 256 MB)
[ 0.000000] modules : 0xbf000000 - 0xc0000000 ( 16 MB)
[ 0.000000] .text : 0xc0008000 - 0xc058abf0 (5643 kB)
[ 0.000000] .init : 0xc058b000 - 0xc05aca50 ( 135 kB)
[ 0.000000] .data : 0xc05ae000 - 0xc05f6138 ( 289 kB)
[ 0.000000] .bss : 0xc05f615c - 0xc0b483ec (5449 kB)
[ 0.000000] SLUB: Genslabs=13, HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] NR_IRQS:304
[ 0.000000] sched_clock: 32 bits at 100 Hz, resolution 10000000ns, wraps every 4294967286ms
[ 0.000000] Console: colour dummy device 80x30
[ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8
[ 0.000000] ... MAX_LOCK_DEPTH: 48
[ 0.000000] ... MAX_LOCKDEP_KEYS: 8191
[ 0.000000] ... CLASSHASH_SIZE: 4096
[ 0.000000] ... MAX_LOCKDEP_ENTRIES: 16384
[ 0.000000] ... MAX_LOCKDEP_CHAINS: 32768
[ 0.000000] ... CHAINHASH_SIZE: 16384
[ 0.000000] memory used by lock dependency info: 3695 kB
[ 0.000000] per task-struct memory footprint: 1152 bytes
[ 0.070000] Calibrating delay loop... 226.09 BogoMIPS (lpj=1130496)
[ 0.070000] pid_max: default: 32768 minimum: 301
[ 0.070000] Mount-cache hash table entries: 512
[ 0.100000] CPU: Testing write buffer coherency: ok
[ 0.110000] Setting up static identity map for 0x40412a98 - 0x40412af0
[ 0.120000] devtmpfs: initialized
[ 0.130000] pinctrl core: initialized pinctrl subsystem
[ 0.140000] dummy:
[ 0.140000] NET: Registered protocol family 16
[ 0.140000] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 0.190000] Serial: AMBA PL011 UART driver
[ 0.200000] 80074000.serial: ttyAMA0 at MMIO 0x80074000 (irq = 47) is a PL011 rev2
[ 0.380000] console [ttyAMA0] enabled
[ 0.420000] bio: create slab <bio-0> at 0
[ 0.430000] mxs-dma 80004000.dma-apbh: initialized
[ 0.440000] mxs-dma 80024000.dma-apbx: initialized
[ 0.450000] vddio: 3300 mV
[ 0.450000] vddio2: 3300 mV
[ 0.460000] usb0_vbus: 5000 mV
[ 0.460000] SCSI subsystem initialized
[ 0.470000] usbcore: registered new interface driver usbfs
[ 0.470000] usbcore: registered new interface driver hub
[ 0.480000] usbcore: registered new device driver usb
[ 0.500000] Advanced Linux Sound Architecture Driver Version 1.0.25.
[ 0.510000] Switching to clocksource mxs_timer
[ 0.720000] NET: Registered protocol family 2
[ 0.730000] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.740000] TCP bind hash table entries: 8192 (order: 6, 294912 bytes)
[ 0.750000] TCP: Hash tables configured (established 8192 bind 8192)
[ 0.760000] TCP: reno registered
[ 0.760000] UDP hash table entries: 256 (order: 2, 20480 bytes)
[ 0.770000] UDP-Lite hash table entries: 256 (order: 2, 20480 bytes)
[ 0.780000] NET: Registered protocol family 1
[ 0.790000] RPC: Registered named UNIX socket transport module.
[ 0.790000] RPC: Registered udp transport module.
[ 0.800000] RPC: Registered tcp transport module.
[ 0.800000] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.810000] NetWinder Floating Point Emulator V0.97 (double precision)
[ 0.890000] NFS: Registering the id_resolver key type
[ 0.900000] Key type id_resolver registered
[ 0.910000] Key type id_legacy registered
[ 0.910000] msgmni has been set to 484
[ 0.920000] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[ 0.930000] io scheduler noop registered (default)
[ 0.960000] Console: switching to colour frame buffer device 100x30
[ 0.980000] mxsfb 80030000.lcdif: initialized
[ 0.980000] Serial: AMBA driver
[ 0.990000] uart-pl011 80074000.serial: no DMA platform data
[ 0.990000] 80070000.serial: ttyAPP3 at MMIO 0x80070000 (irq = 115) is a 80070000.serial
[ 1.000000] mxs-auart 80070000.serial: Found APPUART 3.1.0
[ 1.020000] NAND device: Manufacturer ID: 0xec, Chip ID: 0xd3 (Samsung NAND 1GiB 3,3V 8-bit), page size: 2048, OOB size: 64
[ 1.030000] Scanning device for bad blocks
[ 1.130000] Bad eraseblock 183 at 0x0000016e0000
[ 1.370000] Bad eraseblock 677 at 0x0000054a0000
[ 1.520000] Bad eraseblock 963 at 0x000007860000
[ 3.170000] Bad eraseblock 4310 at 0x000021ac0000
[ 4.460000] Bad eraseblock 6907 at 0x000035f60000
[ 5.100000] GPMI NAND driver registered. (IMX)
[ 5.110000] UBI: attaching mtd0 to ubi0
[ 5.110000] UBI: physical eraseblock size: 131072 bytes (128 KiB)
[ 5.120000] UBI: logical eraseblock size: 126976 bytes
[ 5.120000] UBI: smallest flash I/O unit: 2048
[ 5.130000] UBI: VID header offset: 2048 (aligned 2048)
[ 5.140000] UBI: data offset: 4096
[ 23.330000] UBI: max. sequence number: 1116
[ 23.430000] UBI: attached mtd0 to ubi0
[ 23.430000] UBI: MTD device name: "gpmi-nand"
[ 23.440000] UBI: MTD device size: 1024 MiB
[ 23.440000] UBI: number of good PEBs: 8187
[ 23.450000] UBI: number of bad PEBs: 5
[ 23.450000] UBI: number of corrupted PEBs: 0
[ 23.460000] UBI: max. allowed volumes: 128
[ 23.460000] UBI: wear-leveling threshold: 4096
[ 23.470000] UBI: number of internal volumes: 1
[ 23.470000] UBI: number of user volumes: 1
[ 23.470000] UBI: available PEBs: 3792
[ 23.480000] UBI: total number of reserved PEBs: 4395
[ 23.480000] UBI: number of PEBs reserved for bad PEB handling: 162
[ 23.490000] UBI: max/mean erase counter: 10/5
[ 23.500000] UBI: image sequence number: 813592673
[ 23.500000] UBI: background thread "ubi_bgt0d" started, PID 38
[ 23.510000] vcan: Virtual CAN interface driver
[ 23.510000] CAN device driver interface
[ 23.520000] flexcan 80032000.can: device registered (reg_base=f5032000, irq=8)
[ 23.530000] flexcan 80034000.can: device registered (reg_base=f5034000, irq=9)
[ 23.660000] libphy: fec_enet_mii_bus: probed
[ 23.670000] Initializing USB Mass Storage driver...
[ 23.680000] usbcore: registered new interface driver usb-storage
[ 23.680000] USB Mass Storage support registered.
[ 23.690000] ci_hdrc ci_hdrc.0: doesn't support gadget
[ 23.700000] ci_hdrc ci_hdrc.0: ChipIdea HDRC EHCI
[ 23.700000] ci_hdrc ci_hdrc.0: new USB bus registered, assigned bus number 1
[ 23.760000] ci_hdrc ci_hdrc.0: USB 2.0 started, EHCI 1.00
[ 23.770000] hub 1-0:1.0: USB hub found
[ 23.780000] hub 1-0:1.0: 1 port detected
[ 23.790000] mousedev: PS/2 mouse device common for all mice
[ 23.810000] input: EP0700M06 as /devices/80000000.apb/80040000.apbx/80058000.i2c/i2c-0/0-0038/input/input0
[ 23.820000] rtc-pcf8563 0-0051: chip found, driver version 0.4.3
[ 23.830000] rtc-pcf8563 0-0051: low voltage detected, date/time is not reliable.
[ 23.840000] rtc-pcf8563 0-0051: retrieved date/time is not valid.
[ 23.850000] rtc-pcf8563 0-0051: rtc core: registered rtc-pcf8563 as rtc0
[ 23.860000] i2c /dev entries driver
[ 23.910000] mxs-mmc 80010000.ssp: initialized
[ 23.960000] mxs-mmc 80016000.ssp: initialized
[ 23.960000] sdhci: Secure Digital Host Controller Interface driver
[ 23.970000] mmc0: host does not support reading read-only switch. assuming write-enable.
[ 23.980000] sdhci: Copyright(c) Pierre Ossman
[ 23.980000] sdhci-pltfm: SDHCI platform and OF driver helper
[ 23.990000] usbcore: registered new interface driver usbhid
[ 23.990000] mmc0: new high speed SD card at address 1234
[ 24.000000] usbhid: USB HID core driver
[ 24.010000] mmcblk0: mmc0:1234 SA02G 1.83 GiB
[ 24.040000] mmcblk0: p1 p2 p3
[ 24.050000] sgtl5000 0-000a: Failed to get supply 'VDDD': -517
[ 24.050000] 0-000a: 1200 mV normal
[ 24.070000] sgtl5000 0-000a: Using internal LDO instead of VDDD
[ 24.080000] sgtl5000 0-000a: sgtl5000 revision 0x11
[ 24.110000] mxs-sgtl5000 sound.9: sgtl5000 <-> 80042000.saif mapping ok
[ 24.120000] mxs-sgtl5000 sound.9: sgtl5000 <-> 80046000.saif mapping ok
[ 24.140000] TCP: cubic registered
[ 24.150000] NET: Registered protocol family 17
[ 24.150000] can: controller area network core (rev 20120528 abi 9)
[ 24.160000] NET: Registered protocol family 29
[ 24.160000] can: raw protocol (rev 20120528)
[ 24.170000] can: broadcast manager protocol (rev 20120528 t)
[ 24.170000] Key type dns_resolver registered
[ 24.180000] registered taskstats version 1
[ 24.190000] rtc-pcf8563 0-0051: low voltage detected, date/time is not reliable.
[ 24.200000] rtc-pcf8563 0-0051: retrieved date/time is not valid.
[ 24.210000] rtc-pcf8563 0-0051: hctosys: invalid date/time
[ 24.210000] ALSA device list:
[ 24.220000] #0: mxs_sgtl5000
[ 24.380000] UBIFS: mounted UBI device 0, volume 0, name "rootfs"
[ 24.390000] UBIFS: file system size: 534949888 bytes (522412 KiB, 510 MiB, 4213 LEBs)
[ 24.390000] UBIFS: journal size: 26791936 bytes (26164 KiB, 25 MiB, 211 LEBs)
[ 24.400000] UBIFS: media format: w4/r0 (latest is w4/r0)
[ 24.410000] UBIFS: default compressor: lzo
[ 24.410000] UBIFS: reserved for root: 4952683 bytes (4836 KiB)
[ 24.430000] VFS: Mounted root (ubifs filesystem) on device 0:11.
[ 24.440000] Freeing init memory: 132K
mounting filesystems...done.
running rc.d services...
syslogd starting
klogd starting
crond starting
Starting system message bus: dbus.
hwclock: can't open '/dev/misc/rtc': No such file or directory
starting dropbear...failed
run-parts: /etc/rc.d/S16dropbear exited with code 1
Starting telnetd...
starting mdev
mounting tmpfs at /dev
kernel.hotplug = /sbin/mdev
creating static nodes
waiting for devices...done
restoring mixer state...no /etc/asound.state, aborting
Initializing bluetooth interface
starting network interfaces...
uim:@ main
uim:unable to open /sys/devices/platform/kim/install(No such file or directory)
[ 31.140000] eth0: Freescale FEC PHY driver [Micrel KS8041] (mii_bus:phy_addr=800f0000.etherne:00, irq=-1)
udhcpc (v1.18.5) started
Sending discover...
[ 33.140000] libphy: 800f0000.etherne:00 - Link is Up - 100/Full
Sending discover...
Sending select for 192.168.2.215...
Lease of 192.168.2.215 obtained, lease time 600
deleting routers
route: SIOCDELRT: No such process
adding dns 192.168.1.39
starting inetd...done
logrotate: rotating in /var/log
logrotate: rotation for messages ...
loading modules
Starting CAN interface...[ 35.730000] flexcan 80032000.can: can0: writing ctrl=0x05312005
[ 35.760000] flexcan 80034000.can: can1: writing ctrl=0x05312005
done
Initializing touch-screen...done
[ 35.960000] UBI warning: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 3248:67584, read only 160 bytes, retry
[ 35.990000] UBI warning: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 3248:67584, read only 160 bytes, retry
[ 36.010000] UBI warning: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 3248:67584, read only 160 bytes, retry
[ 36.030000] UBI error: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 3248:67584, read 160 bytes
[ 36.040000] [<c0014fd8>] (unwind_backtrace+0x0/0xf4) from [<c02b8508>] (ubi_io_read+0x1bc/0x37c)
[ 36.050000] [<c02b8508>] (ubi_io_read+0x1bc/0x37c) from [<c02b72e4>] (ubi_eba_read_leb+0xa4/0x3f4)
[ 36.060000] [<c02b72e4>] (ubi_eba_read_leb+0xa4/0x3f4) from [<c02b46fc>] (ubi_leb_read+0xdc/0x130)
[ 36.080000] [<c02b46fc>] (ubi_leb_read+0xdc/0x130) from [<c01b53dc>] (ubifs_leb_read+0x24/0x88)
[ 36.080000] [<c01b53dc>] (ubifs_leb_read+0x24/0x88) from [<c01b93a0>] (fallible_read_node+0x64/0x1c4)
[ 36.100000] [<c01b93a0>] (fallible_read_node+0x64/0x1c4) from [<c01b9f18>] (ubifs_tnc_locate+0x100/0x1a4)
[ 36.120000] [<c01b9f18>] (ubifs_tnc_locate+0x100/0x1a4) from [<c01af408>] (ubifs_iget+0x78/0x86c)
[ 36.120000] [<c01af408>] (ubifs_iget+0x78/0x86c) from [<c01acd90>] (ubifs_lookup+0x100/0x1f4)
[ 36.140000] [<c01acd90>] (ubifs_lookup+0x100/0x1f4) from [<c00e2ffc>] (lookup_real+0x20/0x50)
[ 36.150000] [<c00e2ffc>] (lookup_real+0x20/0x50) from [<c00e7984>] (do_last+0x7ec/0xbb4)
[ 36.160000] [<c00e7984>] (do_last+0x7ec/0xbb4) from [<c00e7df8>] (path_openat+0xac/0x450)
[ 36.170000] [<c00e7df8>] (path_openat+0xac/0x450) from [<c00e8284>] (do_filp_open+0x30/0x84)
[ 36.180000] [<c00e8284>] (do_filp_open+0x30/0x84) from [<c00e0088>] (open_exec+0x1c/0xe0)
[ 36.190000] [<c00e0088>] (open_exec+0x1c/0xe0) from [<c00e0b4c>] (do_execve+0x1c0/0x3ec)
[ 36.200000] [<c00e0b4c>] (do_execve+0x1c0/0x3ec) from [<c001257c>] (sys_execve+0x34/0x54)
[ 36.210000] [<c001257c>] (sys_execve+0x34/0x54) from [<c000f2a0>] (ret_fast_syscall+0x0/0x38)
[ 36.220000] UBIFS error (pid 173): ubifs_leb_read: reading 160 bytes from LEB 1077:63488 failed, error -74
[ 36.240000] [<c0014fd8>] (unwind_backtrace+0x0/0xf4) from [<c01b5434>] (ubifs_leb_read+0x7c/0x88)
[ 36.240000] [<c01b5434>] (ubifs_leb_read+0x7c/0x88) from [<c01b93a0>] (fallible_read_node+0x64/0x1c4)
[ 36.260000] [<c01b93a0>] (fallible_read_node+0x64/0x1c4) from [<c01b9f18>] (ubifs_tnc_locate+0x100/0x1a4)
[ 36.270000] [<c01b9f18>] (ubifs_tnc_locate+0x100/0x1a4) from [<c01af408>] (ubifs_iget+0x78/0x86c)
[ 36.280000] [<c01af408>] (ubifs_iget+0x78/0x86c) from [<c01acd90>] (ubifs_lookup+0x100/0x1f4)
[ 36.290000] [<c01acd90>] (ubifs_lookup+0x100/0x1f4) from [<c00e2ffc>] (lookup_real+0x20/0x50)
[ 36.300000] [<c00e2ffc>] (lookup_real+0x20/0x50) from [<c00e7984>] (do_last+0x7ec/0xbb4)
[ 36.320000] [<c00e7984>] (do_last+0x7ec/0xbb4) from [<c00e7df8>] (path_openat+0xac/0x450)
[ 36.320000] [<c00e7df8>] (path_openat+0xac/0x450) from [<c00e8284>] (do_filp_open+0x30/0x84)
[ 36.340000] [<c00e8284>] (do_filp_open+0x30/0x84) from [<c00e0088>] (open_exec+0x1c/0xe0)
[ 36.350000] [<c00e0088>] (open_exec+0x1c/0xe0) from [<c00e0b4c>] (do_execve+0x1c0/0x3ec)
[ 36.350000] [<c00e0b4c>] (do_execve+0x1c0/0x3ec) from [<c001257c>] (sys_execve+0x34/0x54)
[ 36.370000] [<c001257c>] (sys_execve+0x34/0x54) from [<c000f2a0>] (ret_fast_syscall+0x0/0x38)
[ 36.370000] UBIFS error (pid 173): try_read_node: cannot read node type 0 from LEB 1077:63488, error -74
[ 36.390000] UBI warning: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 3248:67584, read only 160 bytes, retry
[ 36.400000] UBI warning: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 3248:67584, read only 160 bytes, retry
[ 36.420000] UBI warning: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 3248:67584, read only 160 bytes, retry
[ 36.430000] UBI error: ubi_io_read: error -74 (ECC error) while reading 160 bytes from PEB 3248:67584, read 160 bytes
[ 36.440000] [<c0014fd8>] (unwind_backtrace+0x0/0xf4) from [<c02b8508>] (ubi_io_read+0x1bc/0x37c)
[ 36.450000] [<c02b8508>] (ubi_io_read+0x1bc/0x37c) from [<c02b72e4>] (ubi_eba_read_leb+0xa4/0x3f4)
[ 36.460000] [<c02b72e4>] (ubi_eba_read_leb+0xa4/0x3f4) from [<c02b46fc>] (ubi_leb_read+0xdc/0x130)
[ 36.480000] [<c02b46fc>] (ubi_leb_read+0xdc/0x130) from [<c01b53dc>] (ubifs_leb_read+0x24/0x88)
[ 36.480000] [<c01b53dc>] (ubifs_leb_read+0x24/0x88) from [<c01b54dc>] (ubifs_read_node+0x9c/0x2a4)
[ 36.490000] [<c01b54dc>] (ubifs_read_node+0x9c/0x2a4) from [<c01d3b08>] (ubifs_tnc_read_node+0x124/0x134)
[ 36.510000] [<c01d3b08>] (ubifs_tnc_read_node+0x124/0x134) from [<c01b9f9c>] (ubifs_tnc_locate+0x184/0x1a4)
[ 36.520000] [<c01b9f9c>] (ubifs_tnc_locate+0x184/0x1a4) from [<c01af408>] (ubifs_iget+0x78/0x86c)
[ 36.530000] [<c01af408>] (ubifs_iget+0x78/0x86c) from [<c01acd90>] (ubifs_lookup+0x100/0x1f4)
[ 36.540000] [<c01acd90>] (ubifs_lookup+0x100/0x1f4) from [<c00e2ffc>] (lookup_real+0x20/0x50)
[ 36.550000] [<c00e2ffc>] (lookup_real+0x20/0x50) from [<c00e7984>] (do_last+0x7ec/0xbb4)
[ 36.560000] [<c00e7984>] (do_last+0x7ec/0xbb4) from [<c00e7df8>] (path_openat+0xac/0x450)
[ 36.570000] [<c00e7df8>] (path_openat+0xac/0x450) from [<c00e8284>] (do_filp_open+0x30/0x84)
[ 36.580000] [<c00e8284>] (do_filp_open+0x30/0x84) from [<c00e0088>] (open_exec+0x1c/0xe0)
[ 36.590000] [<c00e0088>] (open_exec+0x1c/0xe0) from [<c00e0b4c>] (do_execve+0x1c0/0x3ec)
[ 36.600000] [<c00e0b4c>] (do_execve+0x1c0/0x3ec) from [<c001257c>] (sys_execve+0x34/0x54)
[ 36.600000] [<c001257c>] (sys_execve+0x34/0x54) from [<c000f2a0>] (ret_fast_syscall+0x0/0x38)
[ 36.620000] UBIFS error (pid 173): ubifs_check_node: bad node length 0
[ 36.620000] UBIFS error (pid 173): ubifs_check_node: bad node at LEB 1077:63488
[ 36.630000] magic 0x6101831
[ 36.640000] crc 0x6840206
[ 36.640000] node_type 0 (inode node)
[ 36.640000] group_type 0 (no node group)
[ 36.650000] sqnum 74179
[ 36.650000] len 0
[ 36.650000] key (3647, inode)
[ 36.660000] creat_sqnum 0
[ 36.660000] size 273
[ 36.660000] nlink 1
[ 36.670000] atime 1075978290.0
[ 36.670000] mtime 16.0
[ 36.680000] ctime 0.0
[ 36.680000] uid 0
[ 36.680000] gid 0
[ 36.680000] mode 12
[ 36.690000] flags 0x1
[ 36.690000] xattr_cnt 0
[ 36.690000] xattr_size 0
[ 36.700000] xattr_names 0
[ 36.700000] compr_type 0x0
[ 36.700000] data len 0
[ 36.710000] [<c0014fd8>] (unwind_backtrace+0x0/0xf4) from [<c01b3d8c>] (ubifs_check_node+0x1ac/0x2d0)
[ 36.720000] [<c01b3d8c>] (ubifs_check_node+0x1ac/0x2d0) from [<c01b551c>] (ubifs_read_node+0xdc/0x2a4)
[ 36.730000] [<c01b551c>] (ubifs_read_node+0xdc/0x2a4) from [<c01d3b08>] (ubifs_tnc_read_node+0x124/0x134)
[ 36.740000] [<c01d3b08>] (ubifs_tnc_read_node+0x124/0x134) from [<c01b9f9c>] (ubifs_tnc_locate+0x184/0x1a4)
[ 36.750000] [<c01b9f9c>] (ubifs_tnc_locate+0x184/0x1a4) from [<c01af408>] (ubifs_iget+0x78/0x86c)
[ 36.760000] [<c01af408>] (ubifs_iget+0x78/0x86c) from [<c01acd90>] (ubifs_lookup+0x100/0x1f4)
[ 36.770000] [<c01acd90>] (ubifs_lookup+0x100/0x1f4) from [<c00e2ffc>] (lookup_real+0x20/0x50)
[ 36.800000] [<c00e2ffc>] (lookup_real+0x20/0x50) from [<c00e7984>] (do_last+0x7ec/0xbb4)
[ 36.800000] [<c00e7984>] (do_last+0x7ec/0xbb4) from [<c00e7df8>] (path_openat+0xac/0x450)
[ 36.820000] [<c00e7df8>] (path_openat+0xac/0x450) from [<c00e8284>] (do_filp_open+0x30/0x84)
[ 36.820000] [<c00e8284>] (do_filp_open+0x30/0x84) from [<c00e0088>] (open_exec+0x1c/0xe0)
[ 36.840000] [<c00e0088>] (open_exec+0x1c/0xe0) from [<c00e0b4c>] (do_execve+0x1c0/0x3ec)
[ 36.850000] [<c00e0b4c>] (do_execve+0x1c0/0x3ec) from [<c001257c>] (sys_execve+0x34/0x54)
[ 36.860000] [<c001257c>] (sys_execve+0x34/0x54) from [<c000f2a0>] (ret_fast_syscall+0x0/0x38)
[ 36.870000] UBIFS error (pid 173): ubifs_read_node: expected node type 0
[ 36.880000] UBIFS error (pid 173): ubifs_iget: failed to read inode 3647, error -22
[ 36.890000] UBIFS error (pid 173): ubifs_lookup: dead directory entry 'application_startup', error -22
[ 36.900000] UBIFS warning (pid 173): ubifs_ro_mode: switched to read-only mode, error -22
[ 36.910000] [<c0014fd8>] (unwind_backtrace+0x0/0xf4) from [<c01ace68>] (ubifs_lookup+0x1d8/0x1f4)
[ 36.930000] [<c01ace68>] (ubifs_lookup+0x1d8/0x1f4) from [<c00e2ffc>] (lookup_real+0x20/0x50)
[ 36.930000] [<c00e2ffc>] (lookup_real+0x20/0x50) from [<c00e7984>] (do_last+0x7ec/0xbb4)
[ 36.940000] [<c00e7984>] (do_last+0x7ec/0xbb4) from [<c00e7df8>] (path_openat+0xac/0x450)
[ 36.960000] [<c00e7df8>] (path_openat+0xac/0x450) from [<c00e8284>] (do_filp_open+0x30/0x84)
[ 36.960000] [<c00e8284>] (do_filp_open+0x30/0x84) from [<c00e0088>] (open_exec+0x1c/0xe0)
[ 36.970000] [<c00e0088>] (open_exec+0x1c/0xe0) from [<c00e0b4c>] (do_execve+0x1c0/0x3ec)
[ 36.990000] [<c00e0b4c>] (do_execve+0x1c0/0x3ec) from [<c001257c>] (sys_execve+0x34/0x54)
[ 36.990000] [<c001257c>] (sys_execve+0x34/0x54) from [<c000f2a0>] (ret_fast_syscall+0x0/0x38)
Is this a known issue?
Best regards,
--
David Jander
Protonic Holland.
More information about the linux-mtd
mailing list