From: Bhuvanchandra DV <bhuvanchandradv@gmail.com>
To: "linux-mtd@lists.infradead.org" <linux-mtd@lists.infradead.org>
Cc: richard@nod.at
Subject: Fwd: Re: UBIFS errors when file-system is full
Date: Thu, 16 Jul 2015 13:05:24 +0530 [thread overview]
Message-ID: <55A75EBC.7020109@gmail.com> (raw)
In-Reply-To: <55A74812.2020906@gmail.com>
-------- Forwarded Message --------
Subject:
Re: UBIFS errors when file-system is full
Date:
Thu, 16 Jul 2015 11:28:42 +0530
From:
Bhuvanchandra DV <bhuvanchandra.dv@gmail.com>
To:
Richard Weinberger <richard@nod.at>
On 07/14/2015 04:36 PM, Richard Weinberger wrote:
> Am 14.07.2015 um 12:08 schrieb Bhuvanchandra DV:
>> On 07/14/2015 02:12 PM, Richard Weinberger wrote:
>>
>>> Am 14.07.2015 um 10:29 schrieb Bhuvanchandra DV:
>>>> On 07/14/2015 12:02 PM, Richard Weinberger wrote:
>>>>
>>>>> Am 14.07.2015 um 08:30 schrieb Bhuvanchandra DV:
>>>>>> Reproduced the issue with a clean UBI image.
>>>>> On 4.1?
>>>>> How can you reproduce?
>>>> Flashed a clean UBI image with 4.1.
>>>> I can reproduce it by amost filling up the flash and doing a power resets for around ~200 cycles.
>>> And it really happens only if you're filling up the UBIFS?
>> The only thing observed so far during this tests is when the journactl log starts filling up the flash
>> memory and at some point of time fails to mount the ubifs.
> You mean filling up the filesystem? How and when does the power cut happen?
>
>> Flashed an Image which occupies less flash ~40-50%. Run the same tests for around 1200 power cycles
>> no issues observed during mounting but still there are few error messages:
>> ..
>> [ 6.879643] ubi0 error: __wl_get_peb: no free eraseblocks
> This message cannot come from a recent kernel.
>
>> So came to an assumption that filling the flash to max lead to this issue.
>>
>> Could there be any other possibility for this issue apart from filling up the flash ?
>>
>>> If you set the UBI module parameter "fm_debug", does the EBA selfcheck trigger?
>> Yes, EBA selfcheck got triggered. Atleast i could see the debug printks added
>> in 'self_check_eba' method after setting the fm_debug parameter.
>>
>>>> During some point U-Boot cannot mount the ubifs partition and fails with:
>>>> "Error reading superblock on volume 'ubi0:rootfs' errno=-22!"
>>> How exactly is U-Boot involved? Does U-Boot touch UBI in any way?
>> kernel, device tree blobs are available in rootfs /boot. U-Boot mounts the ubi partition
>> and loads the kernel and device tree blob.
>>
>> Also U-Boot is involved during flashing the UBI image to flash.
> In you last mail you wrote
> "During some point U-Boot cannot mount the ubifs partition and fails with"..
>
> So, please reproduce from scratch(!) with a recent kernel (4.1 or newer) and provide all needed infos.
> Otherwise I see no way howto help you.
>
> Thanks,
> //richard
>
Power cut test with 4.1.2 kernel:
Preparing UBI image using mkfs.ubifs utility:
$ mkfs.ubifs -c 8112 -e 124KiB -m 2KiB -o ubifs.img -r rootfs/ -v
Flashing UBI image from U-Boot:
# fatload ${interface} 0:1 ${loadaddr} ubifs.img
reading ubifs.img
108691456 bytes read in 7139 ms (14.5 MiB/s)
# ubi part ubi && ubi check rootfs || ubi create rootfs
UBI: default fastmap pool size: 50
UBI: default fastmap WL pool size: 25
UBI: attaching mtd1 to ubi0
UBI: attached by fastmap
UBI: fastmap pool size: 50
UBI: fastmap WL pool size: 25
UBI: attached mtd1 (name "mtd=3", size 126 MiB) to ubi0
UBI: PEB size: 131072 bytes (128 KiB), LEB size: 126976 bytes
UBI: min./max. I/O unit sizes: 2048/2048, sub-page size 2048
UBI: VID header offset: 2048 (aligned 2048), data offset: 4096
UBI: good PEBs: 1003, bad PEBs: 5, corrupted PEBs: 0
UBI: user volume: 1, internal volumes: 1, max. volumes count: 128
UBI: max/mean erase counter: 14/4, WL threshold: 4096, image sequence number: 0
UBI: available PEBs: 0, total reserved PEBs: 1003, PEBs reserved for bad PEB handling: 15
# ubi write ${loadaddr} rootfs ${filesize}
108691456 bytes written to volume rootfs
With 4.1.2 kernel after ~600 power cut cycles, no issues with mounting the ubifs,
but observed few stack traces.
Debug logs:
U-Boot 2015.04 (May 18 2015 - 15:37:34)
CPU: Freescale Vybrid VF500 at 396 MHz
Reset cause: POWER ON RESET
DRAM: 128 MiB
NAND: 128 MiB
MMC: FSL_SDHC: 0
*** Warning - bad CRC, using default environment
In: serial
Out: serial
Err: serial
Model: Toradex Colibri VF50 128MB V38.655341, Serial# 04833251
Net: FEC
Hit any key to stop autoboot: 1 \b\b\b 0
Booting from NAND...
UBI: default fastmap pool size: 50
UBI: default fastmap WL pool size: 25
UBI: attaching mtd1 to ubi0
UBI: attached by fastmap
UBI: fastmap pool size: 50
UBI: fastmap WL pool size: 25
UBI: attached mtd1 (name "mtd=3", size 126 MiB) to ubi0
UBI: PEB size: 131072 bytes (128 KiB), LEB size: 126976 bytes
UBI: min./max. I/O unit sizes: 2048/2048, sub-page size 2048
UBI: VID header offset: 2048 (aligned 2048), data offset: 4096
UBI: good PEBs: 1004, bad PEBs: 4, corrupted PEBs: 0
UBI: user volume: 1, internal volumes: 1, max. volumes count: 128
UBI: max/mean erase counter: 115/17, WL threshold: 4096, image sequence number: 0
UBI: available PEBs: 0, total reserved PEBs: 1004, PEBs reserved for bad PEB handling: 16
Loading file '/boot/zImage' to addr 0x82000000 with size 4433808 (0x0043a790)...
Done
Loading file '/boot/vf500-colibri-eval-v3.dtb' to addr 0x84000000 with size 21126 (0x00005286)...
Done
Kernel image @ 0x82000000 [ 0x000000 - 0x43a790 ]
## Flattened Device Tree blob at 84000000
Booting using the fdt blob at 0x84000000
Loading Device Tree to 876ff000, end 87707285 ... OK
Starting kernel ...
[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 4.1.2-00312-g796aee8 (dvb@dev) (gcc version 4.9.3 20141031 (prerelease) (Linaro GCC 2014.11) ) #4 Tue Jul 14 18:02:37 IST 2015
[ 0.000000] CPU: ARMv7 Processor [410fc051] revision 1 (ARMv7), cr=10c5387d
[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[ 0.000000] Machine model: Toradex Colibri VF50 on Colibri Evaluation Board
[ 0.000000] cma: Reserved 16 MiB at 0x86400000
[ 0.000000] Memory policy: Data cache writeback
[ 0.000000] CPU: All CPU(s) started in SVC mode.
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 32004
[ 0.000000] Kernel command line: ubi.mtd=ubi root=ubi0:rootfs rootfstype=ubifs ubi.fm_autoconvert=1 mtdparts=vf610_nfc:128k(vf-bcb)ro,1408k(u-boot)ro,512k(u-boot-env),-(ubi) console=tty1 console=ttyLP0,115200n8 consoleblank=0
[ 0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
[ 0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[ 0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[ 0.000000] Memory: 103168K/129024K available (5719K kernel code, 201K rwdata, 1844K rodata, 216K init, 171K bss, 9472K reserved, 16384K cma-reserved)
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
[ 0.000000] vmalloc : 0x88000000 - 0xff000000 (1904 MB)
[ 0.000000] lowmem : 0x80000000 - 0x87e00000 ( 126 MB)
[ 0.000000] modules : 0x7f000000 - 0x80000000 ( 16 MB)
[ 0.000000] .text : 0x80008000 - 0x8076b04c (7565 kB)
[ 0.000000] .init : 0x8076c000 - 0x807a2000 ( 216 kB)
[ 0.000000] .data : 0x807a2000 - 0x807d44e0 ( 202 kB)
[ 0.000000] .bss : 0x807d44e0 - 0x807ff1d0 ( 172 kB)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] NR_IRQS:16 nr_irqs:16 16
[ 0.000000] L2C: failed to init: -19
[ 0.000054] sched_clock: 64 bits at 132MHz, resolution 7ns, wraps every 2199023255551ns
[ 0.000121] clocksource arm_global_timer: mask: 0xffffffffffffffff max_cycles: 0x1e7178659b, max_idle_ns: 440795204301 ns
[ 0.000839] Console: colour dummy device 80x30
[ 0.002013] console [tty1] enabled
[ 0.002110] Calibrating delay loop... 262.96 BogoMIPS (lpj=1314816)
[ 0.070301] pid_max: default: 32768 minimum: 301
[ 0.070658] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.070770] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.072712] CPU: Testing write buffer coherency: ok
[ 0.073658] Setting up static identity map for 0x80008200 - 0x80008258
[ 0.077525] devtmpfs: initialized
[ 0.093135] VFP support v0.3: implementor 41 architecture 2 part 30 variant 5 rev 1
[ 0.094188] clocksource jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.097801] pinctrl core: initialized pinctrl subsystem
[ 0.101219] NET: Registered protocol family 16
[ 0.105104] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 0.108068] cpuidle: using governor menu
[ 0.137632] hw-breakpoint: found 2 (+1 reserved) breakpoint and 1 watchpoint registers.
[ 0.137788] hw-breakpoint: maximum watchpoint size is 4 bytes.
[ 0.138850] vf610-pinctrl 40048000.iomuxc: initialized IMX pinctrl driver
[ 0.174198] SCSI subsystem initialized
[ 0.175455] usbcore: registered new interface driver usbfs
[ 0.175794] usbcore: registered new interface driver hub
[ 0.176170] usbcore: registered new device driver usb
[ 0.178816] i2c i2c-0: IMX I2C adapter registered
[ 0.179100] i2c i2c-0: using dma0chan0 (tx) and dma0chan1 (rx) for DMA transfers
[ 0.179575] Linux video capture interface: v2.00
[ 0.180005] pps_core: LinuxPPS API ver. 1 registered
[ 0.180089] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 0.180439] PTP clock support registered
[ 0.181919] Advanced Linux Sound Architecture Driver Initialized.
[ 0.184905] Switched to clocksource arm_global_timer
[ 0.223577] NET: Registered protocol family 2
[ 0.226136] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.226299] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.226412] TCP: Hash tables configured (established 1024 bind 1024)
[ 0.226809] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 0.226943] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 0.227550] NET: Registered protocol family 1
[ 0.228636] RPC: Registered named UNIX socket transport module.
[ 0.228762] RPC: Registered udp transport module.
[ 0.228832] RPC: Registered tcp transport module.
[ 0.228895] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.234529] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 0.259291] NFS: Registering the id_resolver key type
[ 0.259517] Key type id_resolver registered
[ 0.259604] Key type id_legacy registered
[ 0.259755] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[ 0.261592] ntfs: driver 2.1.32 [Flags: R/W].
[ 0.263267] fuse init (API version 7.23)
[ 0.272288] io scheduler noop registered
[ 0.272427] io scheduler deadline registered
[ 0.272651] io scheduler cfq registered (default)
[ 0.284169] backlight supply power not found, using dummy regulator
[ 0.306254] Console: switching to colour frame buffer device 80x30
[ 0.318141] fb0: fb device registered successfully.
[ 0.323589] fb1: fb device registered successfully.
[ 0.328902] fb2: fb device registered successfully.
[ 0.333947] fb3: fb device registered successfully.
[ 0.338955] fb4: fb device registered successfully.
[ 0.343640] fb5: fb device registered successfully.
[ 0.349178] 40027000.serial: ttyLP0 at MMIO 0x40027000 (irq = 20, base_baud = 4125000) is a FSL_LPUART
[ 0.892838] console [ttyLP0] enabled
[ 0.901914] 40028000.serial: ttyLP1 at MMIO 0x40028000 (irq = 21, base_baud = 4125000) is a FSL_LPUART
[ 0.921023] 40029000.serial: ttyLP2 at MMIO 0x40029000 (irq = 22, base_baud = 4125000) is a FSL_LPUART
[ 0.959833] loop: module loaded
[ 0.971087] nand: device found, Manufacturer ID: 0xc2, Chip ID: 0xf1
[ 0.981940] nand: Macronix NAND 128MiB 3,3V 8-bit
[ 0.991078] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[ 1.007717] Bad block table found at page 65472, version 0x01
[ 1.018356] Bad block table found at page 65408, version 0x01
[ 1.029057] 4 cmdlinepart partitions found on MTD device vf610_nfc
[ 1.039930] Creating 4 MTD partitions on "vf610_nfc":
[ 1.049703] 0x000000000000-0x000000020000 : "vf-bcb"
[ 1.060727] 0x000000020000-0x000000180000 : "u-boot"
[ 1.071572] 0x000000180000-0x000000200000 : "u-boot-env"
[ 1.082758] 0x000000200000-0x000008000000 : "ubi"
[ 1.100289] 400d1000.ethernet supply phy not found, using dummy regulator
[ 1.122026] libphy: fec_enet_mii_bus: probed
[ 1.133190] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 1.146042] usbcore: registered new interface driver usb-storage
[ 1.158133] usbcore: registered new interface driver usbserial
[ 1.169262] usbcore: registered new interface driver usbserial_generic
[ 1.180859] usbserial: USB Serial support registered for generic
[ 1.192041] usbcore: registered new interface driver ftdi_sio
[ 1.202848] usbserial: USB Serial support registered for FTDI USB Serial Device
[ 1.219659] usbcore: registered new interface driver pl2303
[ 1.230175] usbserial: USB Serial support registered for pl2303
[ 1.250842] imx_usb 400b4000.usb: Can't register ci_hdrc platform device, err=-517
[ 1.269902] mousedev: PS/2 mouse device common for all mice
[ 1.282256] rtc-ds1307: probe of 0-0068 failed with error -5
[ 1.294713] snvs_rtc 400a7034.snvs-rtc-lp: rtc core: registered 400a7034.snvs-rtc-l as rtc0
[ 1.313066] i2c /dev entries driver
[ 1.325910] imx2-wdt 4003e000.wdog: timeout 60 sec (nowayout=0)
[ 1.337470] sdhci: Secure Digital Host Controller Interface driver
[ 1.348776] sdhci: Copyright(c) Pierre Ossman
[ 1.358357] sdhci-pltfm: SDHCI platform and OF driver helper
[ 1.370640] /soc/aips-bus@40080000/esdhc@400b2000: voltage-ranges unspecified
[ 1.383491] sdhci-esdhc-imx 400b2000.esdhc: Got CD GPIO
[ 1.394551] sdhci-esdhc-imx 400b2000.esdhc: No vmmc regulator found
[ 1.406232] sdhci-esdhc-imx 400b2000.esdhc: No vqmmc regulator found
[ 1.455030] mmc0: SDHCI controller on 400b2000.esdhc [400b2000.esdhc] using ADMA
[ 1.478433] usbcore: registered new interface driver usbhid
[ 1.489668] usbhid: USB HID core driver
[ 1.500167] 4003b000.adc supply vref not found, using dummy regulator
[ 1.517255] 400bb000.adc supply vref not found, using dummy regulator
[ 1.534193] Netfilter messages via NETLINK v0.30.
[ 1.545659] nfnl_acct: registering with nfnetlink.
[ 1.555779] nf_conntrack version 0.5.0 (1868 buckets, 7472 max)
[ 1.572189] nf_tables: (c) 2007-2009 Patrick McHardy <kaber@trash.net>
[ 1.586113] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 1.599463] NET: Registered protocol family 10
[ 1.613218] ip6_tables: (C) 2000-2006 Netfilter Core Team
[ 1.623982] sit: IPv6 over IPv4 tunneling driver
[ 1.636845] NET: Registered protocol family 17
[ 1.646637] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
[ 1.669574] Bridge firewalling registered
[ 1.680245] 8021q: 802.1Q VLAN Support v1.8
[ 1.691818] Key type dns_resolver registered
[ 1.701660] Registering SWP/SWPB emulation handler
[ 1.714822] registered taskstats version 1
[ 1.731301] usbh_vbus: supplied by 5v0
[ 1.744627] ci_hdrc ci_hdrc.1: EHCI Host Controller
[ 1.755851] ci_hdrc ci_hdrc.1: new USB bus registered, assigned bus number 1
[ 1.785043] ci_hdrc ci_hdrc.1: USB 2.0 started, EHCI 1.00
[ 1.798152] hub 1-0:1.0: USB hub found
[ 1.806943] hub 1-0:1.0: 1 port detected
[ 1.819450] input: colibri-vf50-ts as /devices/platform/vf50_touchctrl/input/input0
[ 1.839751] toradex,vf50_touchctrl vf50_touchctrl: Attached colibri-vf50-ts driver successfully
[ 1.859364] ubi0: default fastmap pool size: 50
[ 1.868992] ubi0: default fastmap WL pool size: 25
[ 1.878648] ubi0: attaching mtd3
[ 1.944886] ubi0: attached by fastmap
[ 1.953386] ubi0: fastmap pool size: 50
[ 1.961818] ubi0: fastmap WL pool size: 25
[ 1.981616] ubi0: attached mtd3 (name "ubi", size 126 MiB)
[ 1.991835] ubi0: PEB size: 131072 bytes (128 KiB), LEB size: 126976 bytes
[ 2.003327] ubi0: min./max. I/O unit sizes: 2048/2048, sub-page size 2048
[ 2.014674] ubi0: VID header offset: 2048 (aligned 2048), data offset: 4096
[ 2.026229] ubi0: good PEBs: 1004, bad PEBs: 4, corrupted PEBs: 0
[ 2.036951] ubi0: user volume: 1, internal volumes: 1, max. volumes count: 128
[ 2.053305] ubi0: max/mean erase counter: 115/11, WL threshold: 4096, image sequence number: 0
[ 2.071614] ubi0: available PEBs: 0, total reserved PEBs: 1004, PEBs reserved for bad PEB handling: 16
[ 2.091286] ubi0: background thread "ubi_bgt0d" started, PID 50
[ 2.103973] snvs_rtc 400a7034.snvs-rtc-lp: setting system clock to 1970-01-01 00:12:04 UTC (724)
[ 2.139189] ALSA device list:
[ 2.147659] No soundcards found.
[ 2.186556] UBIFS (ubi0:0): recovery needed
[ 2.215265] usb 1-1: new high-speed USB device number 2 using ci_hdrc
[ 2.378103] hub 1-1:1.0: USB hub found
[ 2.387452] hub 1-1:1.0: 4 ports detected
[ 2.552203] UBIFS (ubi0:0): recovery deferred
[ 2.561990] UBIFS (ubi0:0): UBIFS: mounted UBI device 0, volume 0, name "rootfs", R/O mode
[ 2.580664] UBIFS (ubi0:0): LEB size: 126976 bytes (124 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
[ 2.601698] UBIFS (ubi0:0): FS size: 123293696 bytes (117 MiB, 971 LEBs), journal size 9023488 bytes (8 MiB, 72 LEBs)
[ 2.623697] UBIFS (ubi0:0): reserved for root: 0 bytes (0 KiB)
[ 2.635373] UBIFS (ubi0:0): media format: w4/r0 (latest is w4/r0), UUID 8CD1C68E-FE03-420D-A522-9D1348A77201, small LPT model
[ 2.659795] VFS: Mounted root (ubifs filesystem) readonly on device 0:14.
[ 2.673917] devtmpfs: mounted
[ 2.684499] Freeing unused kernel memory: 216K (8076c000 - 807a2000)
[ 2.735477] usb 1-1.1: new high-speed USB device number 3 using ci_hdrc
[ 2.860342] usb-storage 1-1.1:1.0: USB Mass Storage device detected
[ 2.881163] scsi host0: usb-storage 1-1.1:1.0
[ 3.047468] random: systemd urandom read with 35 bits of entropy available
[ 3.091951] systemd[1]: systemd 218 running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT +GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD +IDN)
[ 3.130741] systemd[1]: Detected architecture 'arm'.
Welcome to The Ångström Distribution v2014.12!
[ 3.167766] systemd[1]: Set hostname to <colibri-vf>.
[ 3.689001] systemd[1]: Unit type .busname is not supported on this system.
[ 3.897008] scsi 0:0:0:0: Direct-Access Generic Flash-Disk 1.05 PQ: 0 ANSI: 2
[ 4.306965] systemd[1]: Cannot add dependency job for unit bootmisc.service, ignoring: Unit bootmisc.service is masked.
[ 4.330580] systemd[1]: Cannot add dependency job for unit banner.service, ignoring: Unit banner.service is masked.
[ 4.353712] systemd[1]: Cannot add dependency job for unit mountall.service, ignoring: Unit mountall.service is masked.
[ 4.378435] systemd[1]: Cannot add dependency job for unit populate-volatile.service, ignoring: Unit populate-volatile.service is masked.
[ 4.404195] systemd[1]: Cannot add dependency job for unit read-only-rootfs-hook.service, ignoring: Unit read-only-rootfs-hook.service is masked.
[ 4.430216] systemd[1]: Cannot add dependency job for unit sysfs.service, ignoring: Unit sysfs.service is masked.
[ 4.453515] systemd[1]: Cannot add dependency job for unit devpts.service, ignoring: Unit devpts.service is masked.
[ 4.477877] systemd[1]: Cannot add dependency job for unit checkroot.service, ignoring: Unit checkroot.service is masked.
[ 4.502421] systemd[1]: Cannot add dependency job for unit dmesg.service, ignoring: Unit dmesg.service is masked.
[ 4.526224] systemd[1]: Cannot add dependency job for unit hostname.service, ignoring: Unit hostname.service is masked.
[ 4.550765] systemd[1]: Cannot add dependency job for unit urandom.service, ignoring: Unit urandom.service is masked.
[ 4.576608] systemd[1]: Cannot add dependency job for unit rmnologin.service, ignoring: Unit rmnologin.service is masked.
[ 4.602803] systemd[1]: Cannot add dependency job for unit mountnfs.service, ignoring: Unit mountnfs.service is masked.
[ 4.628138] systemd[1]: Cannot add dependency job for unit mountnfs.service, ignoring: Unit mountnfs.service is masked.
[ 4.653227] systemd[1]: Cannot add dependency job for unit rmnologin.service, ignoring: Unit rmnologin.service is masked.
[ 4.694217] systemd[1]: Starting Forward Password Requests to Wall Directory Watch.
[ 4.718176] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[ 4.740225] systemd[1]: Expecting device dev-ttyLP0.device...
Expecting device dev-ttyLP0.device...
[ 4.775606] systemd[1]: Starting Root Slice.
[ OK ] Created slice Root Slice.
[ 4.805520] systemd[1]: Created slice Root Slice.
[ 4.817591] systemd[1]: Starting System Slice.
[ OK ] Created slice System Slice.
[ 4.845485] systemd[1]: Created slice System Slice.
[ 4.857452] systemd[1]: Starting system-getty.slice.
[ OK ] Created slice system-getty.slice.
[ 4.885514] systemd[1]: Created slice system-getty.slice.
[ 4.897628] systemd[1]: Starting User and Session Slice.
[ OK ] Created slice User and Session Slice.
[ 4.925523] systemd[1]: Created slice User and Session Slice.
[ 4.937782] systemd[1]: Starting /dev/initctl Compatibility Named Pipe.
[ OK ] Listening on /dev/initctl Compatibility Named Pipe.
[ 4.975502] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[ 4.988870] systemd[1]: Starting Delayed Shutdown Socket.
[ OK ] Listening on Delayed Shutdown Socket.
[ 5.025521] systemd[1]: Listening on Delayed Shutdown Socket.
[ 5.037429] systemd[1]: Starting system-serial\x2dgetty.slice.
[ OK ] Created slice system-serial\x2dgetty.slice.
[ 5.065506] systemd[1]: Created slice system-serial\x2dgetty.slice.
[ 5.077871] systemd[1]: Starting Dispatch Password Requests to Console Directory Watch.
[ 5.097329] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[ 5.117637] systemd[1]: Starting Journal Socket (/dev/log).
[ 5.129572] sd 0:0:0:0: [sda] 7761920 512-byte logical blocks: (3.97 GB/3.70 GiB)
[ OK ] Listening on J[ 5.150472] sd 0:0:0:0: [sda] Write Protect is off
ournal Socket (/dev/log).
[ 5.163099] sd 0:0:0:0: [sda] No Caching mode page found
[ 5.173454] sd 0:0:0:0: [sda] Assuming drive cache: write through
[ 5.185085] systemd[1]: Listening on Journal Socket (/dev/log).
[ 5.198892] systemd[1]: Expecting device dev-ttyGS0.device...
Expecting device dev-ttyGS0.device...
[ 5.217790] sda: sda1
[ 5.232267] sd 0:0:0:0: [sda] Attached SCSI removable disk
[ 5.243642] systemd[1]: Starting Journal Socket.
[ OK ] Listening on Journal Socket.
[ 5.275646] systemd[1]: Listening on Journal Socket.
[ 5.286378] systemd[1]: Starting (null)...
Starting ..
[ 5.343585] systemd[1]: Started Load Kernel Modules.
[ 5.358896] systemd[1]: Starting Apply Kernel Variab Startingriables.ernel Variables...
[ 5.396219] systemd[1]: Mounting FUSE Control File Sys Mounting FUSE Control File System...
[ 5.454009] systemd[1]: Mounting Configuration File System...
Mounting Configuration File System...
[ 5.546855] systemd[1]: Started Create list of required static device nodes for the current kernel.
[ 5.590853] systemd[1]: Starting udev Control Socket.
[ OK ] Listening on udev Control Socket.
[ 5.621530] systemd[1]: Listening on udev Control Socket.
[ 5.646880] systemd[1]: Mounted POSIX Message Queue File System.
[ 5.674078] systemd[1]: Mounted Huge Pages File System.
[ 5.847231] systemd[1]: Listening on Journal Audit Socket.
[ 5.860268] systemd[1]: Starting Journal Service...
Starting Journal Service...
[ 5.901190] systemd[1]: Starting udev Kernel Socket.
[ OK ] Listening on udev Kernel Socket.
[ 5.945608] systemd[1]: Listening on udev Kernel Socket.
[ 5.960923] systemd[1]: Starting udev Coldplug all Devices...
Starting udev Coldplug all Devices...
[ 6.000942] systemd[1]: Starting Paths.
[ OK ] Reached target Paths.
[ 6.065500] systemd[1]: Reached target Paths.
[ 6.086043] systemd[1]: Mounting Temporary Directory...
Mounting Temporary Directory...
[ 6.120796] systemd[1]: Starting Slices.
[ OK ] Reached target Slices.
[ 6.168419] systemd[1]: Reached target Slices.
[ 6.184476] systemd[1]: Starting File System Check on Root Device...
Starting File System Check on Root Device...
[ 6.231118] systemd[1]: Starting Swap.
[ OK ] Reached target Swap.
[ 6.278976] systemd[1]: Reached target Swap.
[ OK ] Mounted FUSE Control File System.
[ 6.338579] systemd[1]: Mounted FUSE Control File System.
[ OK ] Mounted Configuration File System.
[ 6.385515] systemd[1]: Mounted Configuration File System.
[ OK ] Mounted Temporary Directory.
[ 6.435501] systemd[1]: Mounted Temporary Directory.
[ OK ] Started (null).
[ 6.515669] systemd[1]: Started (null).
[ OK ] Started Apply Kernel Variables.
[ 6.555838] systemd[1]: Started Apply Kernel Variables.
[ OK ] Started File System Check on Root Device.
[ 6.665743] systemd[1]: Started File System Check on Root Device.
[ OK ] Started Journal Service.
[ 7.085522] systemd[1]: Started Journal Service.
[ OK ] Started udev Coldplug all Devices.
Starting Remount Root and Kernel File Systems...
[ 7.669256] UBIFS (ubi0:0): completing deferred recovery
[ 7.989718] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" started, PID 93
[ 8.201567] random: nonblocking pool is initialized
[ 8.440773] UBIFS error (ubi0:0 pid 89): ubifs_check_node: bad CRC: calculated 0x3e594a6c, read 0xfd9c1779
[ 8.458517] UBIFS error (ubi0:0 pid 89): ubifs_check_node: bad node at LEB 957:1920
[ 8.474556] magic 0x6101831
[ 8.482579] crc 0xfd9c1779
[ 8.490614] node_type 9 (indexing node)
[ 8.499268] group_type 0 (no node group)
[ 8.507927] sqnum 427384
[ 8.515589] len 188
[ 8.522902] child_cnt 8
[ 8.530025] level 0
[ 8.537043] Branches:
[ 8.543287] 0: LEB 902:4800 len 92 key (23325, data, 882)
[ 8.552944] 1: LEB 902:4896 len 92 key (23325, data, 883)
[ 8.562534] 2: LEB 902:4992 len 92 key (23325, data, 884)
[ 8.572039] 3: LEB 902:5088 len 92 key (23325, data, 885)
[ 8.581477] 4: LEB 902:5184 len 92 key (23325, data, 886)
[ 8.590856] 5: LEB 101718065:817737798 len 427374 key (0, inode)
[ 8.600949] 6: LEB 9:8 len 891 key (124792, inode)
[ 8.609820] CPU: 0 PID: 89 Comm: mount Not tainted 4.1.2-00312-g796aee8 #4
[ 8.620843] Hardware name: Freescale Vybrid VF5xx/VF6xx (Device Tree)
[ 8.631473] Backtrace:
[ 8.638159] [<80012ab8>] (dump_backtrace) from [<80012cd8>] (show_stack+0x18/0x1c)
[ 8.653694] r7:881d4780 r6:87b53000 r5:00000780 r4:ffffff8b
[ 8.664070] [<80012cc0>] (show_stack) from [<805982a8>] (dump_stack+0x24/0x28)
[ 8.679119] [<80598284>] (dump_stack) from [<8022cddc>] (ubifs_check_node+0x1b8/0x2e4)
[ 8.695402] [<8022cc24>] (ubifs_check_node) from [<80234340>] (ubifs_scan_a_node+0xcc/0x1e4)
[ 8.712845] r9:000003bd r8:00000000 r7:87b53000 r6:000003bd r5:87b53000 r4:881d4780
[ 8.730997] [<80234274>] (ubifs_scan_a_node) from [<80234838>] (ubifs_scan+0x60/0x39c)
[ 8.749411] r6:881d4780 r5:00000780 r4:0001e880
[ 8.760074] [<802347d8>] (ubifs_scan) from [<8023e0d0>] (ubifs_tnc_start_commit+0x6b8/0xab8)
[ 8.779783] r10:ffffffe4 r9:000000f5 r8:00000000 r7:87b53000 r6:0001f000 r5:00000001
[ 8.800496] r4:000003bd
[ 8.809485] [<8023da18>] (ubifs_tnc_start_commit) from [<80237ac4>] (do_commit+0x140/0x4dc)
[ 8.830491] r10:00008020 r9:00000020 r8:87b4ed44 r7:00000003 r6:87b53130 r5:00000000
[ 8.851929] r4:87b53000
[ 8.861020] [<80237984>] (do_commit) from [<80238060>] (ubifs_run_commit+0x74/0xb4)
[ 8.881320] r8:00000000 r7:87b537a8 r6:87b4ec00 r5:87b531a4 r4:87b53000
[ 8.895426] [<80237fec>] (ubifs_run_commit) from [<80246d88>] (ubifs_rcvry_gc_commit+0x7c/0x1ec)
[ 8.916989] r5:87b537ec r4:87b53000
[ 8.927248] [<80246d0c>] (ubifs_rcvry_gc_commit) from [<80228e28>] (ubifs_remount_fs+0x440/0x744)
[ 8.948618] r7:87b537a8 r6:00000000 r5:87b537ec r4:87b53000
[ 8.961318] [<802289e8>] (ubifs_remount_fs) from [<800c4cbc>] (do_remount_sb+0x6c/0x1c4)
[ 8.981687] r9:00000020 r8:00000000 r7:00000000 r6:00000000 r5:00000000 r4:87b47c00
[ 9.002500] [<800c4c50>] (do_remount_sb) from [<800df390>] (do_mount+0x5a8/0x9b4)
[ 9.022463] r8:87b47c00 r7:87b47c40 r6:00000000 r5:00000000 r4:87b086d0
[ 9.036416] [<800dede8>] (do_mount) from [<800dfad8>] (SyS_mount+0x7c/0xa8)
[ 9.049829] r10:00000000 r9:85cce000 r8:c0ed8020 r7:008ed1e8 r6:00000000 r5:85c5ee40
[ 9.070954] r4:85c5ef00
[ 9.079921] [<800dfa5c>] (SyS_mount) from [<8000f5c0>] (ret_fast_syscall+0x0/0x3c)
[ 9.099814] r8:8000f764 r7:00000015 r6:00000000 r5:008ed158 r4:00000000
[ 9.113569] UBIFS error (ubi0:0 pid 89): ubifs_scan: bad node
[ 9.125801] UBIFS error (ubi0:0 pid 89): ubifs_scanned_corruption: corruption at LEB 957:1920
[ 9.146679] UBIFS error (ubi0:0 pid 89): ubifs_scanned_corruption: first 8192 bytes from LEB 957:1920
[ 9.179238] UBIFS error (ubi0:0 pid 89): ubifs_scan: LEB 957 scanning failed
[ 9.192828] UBIFS error (ubi0:0 pid 89): do_commit: commit failed, error -117
[ 9.206460] UBIFS warning (ubi0:0 pid 89): ubifs_ro_mode: switched to read-only mode, error -117
[ 9.227664] CPU: 0 PID: 89 Comm: mount Not tainted 4.1.2-00312-g796aee8 #4
[ 9.241015] Hardware name: Freescale Vybrid VF5xx/VF6xx (Device Tree)
[ 9.253900] Backtrace:
[ 9.262769] [<80012ab8>] (dump_backtrace) from [<80012cd8>] (show_stack+0x18/0x1c)
[ 9.282622] r7:00000003 r6:87b53130 r5:00000010 r4:85d18980
[ 9.295256] [<80012cc0>] (show_stack) from [<805982a8>] (dump_stack+0x24/0x28)
[ 9.314631] [<80598284>] (dump_stack) from [<8022c50c>] (ubifs_ro_mode.part.9+0x7c/0x84)
[ 9.335027] [<8022c490>] (ubifs_ro_mode.part.9) from [<8022c624>] (ubifs_ro_mode+0x1c/0x20)
[ 9.355855] r5:ffffff8b r4:87b53000
[ 9.366062] [<8022c608>] (ubifs_ro_mode) from [<80237e50>] (do_commit+0x4cc/0x4dc)
[ 9.386052] [<80237984>] (do_commit) from [<80238060>] (ubifs_run_commit+0x74/0xb4)
[ 9.406342] r8:00000000 r7:87b537a8 r6:87b4ec00 r5:87b531a4 r4:87b53000
[ 9.420346] [<80237fec>] (ubifs_run_commit) from [<80246d88>] (ubifs_rcvry_gc_commit+0x7c/0x1ec)
[ 9.441865] r5:87b537ec r4:87b53000
[ 9.452097] [<80246d0c>] (ubifs_rcvry_gc_commit) from [<80228e28>] (ubifs_remount_fs+0x440/0x744)
[ 9.473678] r7:87b537a8 r6:00000000 r5:87b537ec r4:87b53000
[ 9.486526] [<802289e8>] (ubifs_remount_fs) from [<800c4cbc>] (do_remount_sb+0x6c/0x1c4)
[ 9.507262] r9:00000020 r8:00000000 r7:00000000 r6:00000000 r5:00000000 r4:87b47c00
[ 9.528476] [<800c4c50>] (do_remount_sb) from [<800df390>] (do_mount+0x5a8/0x9b4)
[ 9.548833] r8:87b47c00 r7:87b47c40 r6:00000000 r5:00000000 r4:87b086d0
[ 9.562946] [<800dede8>] (do_mount) from [<800dfad8>] (SyS_mount+0x7c/0xa8)
[ 9.576599] r10:00000000 r9:85cce000 r8:c0ed8020 r7:008ed1e8 r6:00000000 r5:85c5ee40
[ 9.597950] r4:85c5ef00
[ 9.606978] [<800dfa5c>] (SyS_mount) from [<8000f5c0>] (ret_fast_syscall+0x0/0x3c)
[ 9.627131] r8:8000f764 r7:00000015 r6:00000000 r5:008ed158 r4:00000000
[ 9.645229] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" stops
[FAILED] Failed to start Remount Root and Kernel File Systems.
...
Best regards,
Bhuvan
next prev parent reply other threads:[~2015-07-16 7:35 UTC|newest]
Thread overview: 35+ messages / expand[flat|nested] mbox.gz Atom feed top
2015-07-13 13:55 UBIFS errors when file-system is full Bhuvanchandra DV
2015-07-13 14:09 ` Richard Weinberger
2015-07-14 4:23 ` Bhuvanchandra DV
2015-07-14 6:13 ` Richard Weinberger
2015-07-14 6:30 ` Bhuvanchandra DV
2015-07-14 6:32 ` Richard Weinberger
2015-07-14 8:29 ` Bhuvanchandra DV
2015-07-14 8:42 ` Richard Weinberger
2015-07-14 10:08 ` Bhuvanchandra DV
2015-07-14 11:06 ` Richard Weinberger
[not found] ` <55A74812.2020906@gmail.com>
[not found] ` <55A74AA1.2000000@nod.at>
[not found] ` <55A7540C.3050900@nod.at>
[not found] ` <55A7592D.6010906@gmail.com>
[not found] ` <55A75A05.7040603@nod.at>
2015-07-16 7:33 ` Bhuvanchandra DV
2015-07-21 6:04 ` Bhuvanchandra DV
2015-07-21 6:14 ` Richard Weinberger
2015-07-22 7:10 ` Bhuvanchandra DV
2015-07-22 7:20 ` Richard Weinberger
2015-07-24 14:43 ` Bhuvanchandra DV
2015-07-24 16:51 ` Richard Weinberger
2015-07-31 16:47 ` Bhuvanchandra DV
2015-08-01 6:36 ` Richard Weinberger
2015-08-06 10:31 ` Bhuvanchandra DV
2015-08-07 12:37 ` Richard Weinberger
2015-08-12 7:01 ` Stefan Agner
2015-08-12 7:27 ` Richard Weinberger
2015-09-01 0:45 ` Stefan Agner
2015-09-01 1:43 ` Brian Norris
2015-09-01 2:20 ` Stefan Agner
2015-09-02 19:58 ` Stefan Agner
2015-09-02 20:13 ` Brian Norris
2015-09-02 20:41 ` Stefan Agner
2015-09-07 13:47 ` Bhuvanchandra
2015-09-11 4:03 ` Bhuvanchandra
2015-09-12 9:39 ` Richard Weinberger
2015-09-13 4:45 ` Bhuvanchandra
2015-07-16 7:35 ` Bhuvanchandra DV [this message]
[not found] <55A4F970.9030402@gmail.com>
2015-07-16 7:34 ` Fwd: " Bhuvanchandra DV
Reply instructions:
You may reply publicly to this message via plain-text email
using any one of the following methods:
* Save the following mbox file, import it into your mail client,
and reply-to-all from there: mbox
Avoid top-posting and favor interleaved quoting:
https://en.wikipedia.org/wiki/Posting_style#Interleaved_style
* Reply using the --to, --cc, and --in-reply-to
switches of git-send-email(1):
git send-email \
--in-reply-to=55A75EBC.7020109@gmail.com \
--to=bhuvanchandradv@gmail.com \
--cc=linux-mtd@lists.infradead.org \
--cc=richard@nod.at \
/path/to/YOUR_REPLY
https://kernel.org/pub/software/scm/git/docs/git-send-email.html
* If your mail client supports setting the In-Reply-To header
via mailto: links, try the mailto: link
Be sure your reply has a Subject: header at the top and a blank line
before the message body.
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.