* Writing to UBIFS leads to all threads accessing files are stuck in nand_get_device
@ 2018-07-13 11:04 Sebastian Lassacher
2018-07-13 20:39 ` Richard Weinberger
0 siblings, 1 reply; 2+ messages in thread
From: Sebastian Lassacher @ 2018-07-13 11:04 UTC (permalink / raw)
To: linux-mtd
Hi there,
I ran into troubles when writing files on an UBIFS volume.
This happens on three different systems with similar conditions.
Since they where installed on customer sides I'm not sure what caused
the problems in the first place.
My theory is that all three suffered from a power cut.
As reported by the command df there should be enough space:
Filesystem Size Used Available Use% Mounted on
none 26.5M 0 26.5M 0% /dev
ubi0:root 441.6M 101.4M 340.2M 23% /mnt/images
But when I write to to the UBIFS, some processes get stuck, for instance:
From ash inside an initramfs those commands working fine and no errors
are reported:
$ mount -t ubifs -o noatime,nodiratime,chk_data_crc ubi0:root /mnt/images
$ ls -l /mnt/images/test
$ dd if=/dev/urandom of=/mnt/images/test/urandom_1k bs=1k count=1
After a few minutes I ran to be sure the the background sync has already
started:
$ ls -l /mnt/images/test
and it did not return which later on got me this:
[ 480.130000] INFO: task ls:149 blocked for more than 120 seconds.
[ 480.130000] Not tainted 4.4.66-SEM #15
[ 480.140000] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[ 480.140000] ls D c044cc28 0 149 1 0x00000000
[ 480.150000] Backtrace:
[ 480.150000] [<c044ca14>] (__schedule) from [<c044cf9c>]
(schedule+0xa0/0xb8)
[ 480.160000] r10:c2312000 r9:c25b9aa8 r8:c22b4a98 r7:00000015
r6:ffffe000 r5:c20fa818
[ 480.170000] r4:c25b8000
[ 480.170000] [<c044cefc>] (schedule) from [<c02e5ec8>]
(nand_get_device+0xf8/0x110)
[ 480.180000] r5:c20fa818 r4:c22b4a90
[ 480.180000] [<c02e5dd0>] (nand_get_device) from [<c02ea410>]
(nand_read+0x28/0x84)
[ 480.190000] r9:00000000 r8:01400000 r7:00000000 r6:08c06000
r5:c25b9af4 r4:c20fab70
[ 480.200000] [<c02ea3e8>] (nand_read) from [<c02df6fc>]
(part_read+0x50/0x88)
[ 480.200000] r7:000001e0 r6:00000000 r5:00000000 r4:c2312000
[ 480.210000] [<c02df6ac>] (part_read) from [<c02dcca0>]
(mtd_read+0x6c/0xa4)
[ 480.220000] r9:00000000 r8:07806000 r6:00000043 r5:00000000 r4:173fa000
[ 480.220000] [<c02dcc34>] (mtd_read) from [<c02dcd28>]
(mtd_read_slc_mode+0x50/0xe0)
[ 480.230000] r10:c25b9c24 r9:00000000 r8:07806000 r7:000001e0
r6:00000043 r5:00000043
[ 480.240000] r4:c2312000
[ 480.240000] [<c02dccd8>] (mtd_read_slc_mode) from [<c02fe8fc>]
(ubi_io_read+0x168/0x338)
[ 480.250000] r10:c2528980 r9:00000004 r8:00006000 r7:000001e0
r6:00000043 r5:c302d000
[ 480.260000] r4:c302d000
[ 480.260000] [<c02fe794>] (ubi_io_read) from [<c02fc484>]
(ubi_eba_read_leb+0x344/0x458)
[ 480.270000] r10:c23ca400 r9:000001e0 r8:00000000 r7:00000043
r6:0000039e r5:00000000
[ 480.280000] r4:c302d000
[ 480.280000] [<c02fc140>] (ubi_eba_read_leb) from [<c02fafe0>]
(ubi_leb_read+0xc4/0x114)
[ 480.290000] r10:00000000 r9:c302d000 r8:00005000 r7:0000039e
r6:00000043 r5:00000000
[ 480.290000] r4:c23ca400
[ 480.300000] [<c02faf1c>] (ubi_leb_read) from [<c01ae394>]
(ubifs_leb_read+0x34/0x98)
[ 480.310000] r10:00000002 r9:c2528980 r8:00005000 r7:0000039e
r6:00000043 r5:c302e000
[ 480.310000] r4:c302e000
[ 480.320000] [<c01ae360>] (ubifs_leb_read) from [<c01b0324>]
(ubifs_read_node+0x18c/0x340)
[ 480.320000] r8:00000043 r7:c092d2d0 r6:0000039e r5:00005000 r4:c302e000
[ 480.330000] [<c01b0198>] (ubifs_read_node) from [<c01b0684>]
(ubifs_read_node_wbuf+0x1ac/0x378)
[ 480.340000] r10:00000043 r9:00000002 r8:c2528980 r7:0000039e
r6:c24496a0 r5:00005000
[ 480.350000] r4:c302e000
[ 480.350000] [<c01b04d8>] (ubifs_read_node_wbuf) from [<c01cefb4>]
(ubifs_tnc_read_node+0x54/0x1ec)
[ 480.360000] r10:c2528980 r9:40000000 r8:c302e314 r7:00000002
r6:c2528980 r5:c302e000
[ 480.370000] r4:c2524e78
[ 480.370000] [<c01cef60>] (ubifs_tnc_read_node) from [<c01b1700>]
(tnc_read_node_nm+0x1c0/0x1f4)
[ 480.380000] r8:c302e314 r7:c302e000 r6:c2528980 r5:c302e000 r4:c2524e78
[ 480.390000] [<c01b1540>] (tnc_read_node_nm) from [<c01b5404>]
(ubifs_tnc_next_ent+0x228/0x274)
[ 480.390000] r7:c25b9ee8 r6:00000048 r5:c302e000 r4:c2524e00
[ 480.400000] [<c01b51dc>] (ubifs_tnc_next_ent) from [<c01a7118>]
(ubifs_readdir+0x20c/0x4c4)
[ 480.410000] r10:c092cd48 r9:00000000 r8:c28c81c8 r7:c302e000
r6:c234e600 r5:c25b9f68
[ 480.420000] r4:c0102e8c
[ 480.420000] [<c01a6f0c>] (ubifs_readdir) from [<c0102cc0>]
(iterate_dir+0x74/0xf8)
[ 480.430000] r10:00000000 r9:c25b8000 r8:c28c8238 r7:c234e608
r6:00000000 r5:c25b9f68
[ 480.430000] r4:c234e600
[ 480.440000] [<c0102c4c>] (iterate_dir) from [<c0103158>]
(SyS_getdents64+0x84/0xe4)
[ 480.440000] r10:00000000 r9:c25b8000 r8:c000f8c4 r7:c234e600
r6:c234e600 r5:00008000
[ 480.450000] r4:000a80b0
[ 480.450000] [<c01030d4>] (SyS_getdents64) from [<c000f720>]
(ret_fast_syscall+0x0/0x34)
[ 480.460000] r7:000000d9 r6:b6f95570 r5:000a8094 r4:000a80b0
In a similar test when I create a directory, call sync, then write to a
file the background thread is also effected together with the ls command.
This output is from another test perfomed on the same device (also one
backtrace for ls is present):
[ 960.130000] INFO: task ubifs_bgt0_0:132 blocked for more than 120
seconds.
[ 960.130000] Not tainted 4.4.66-SEM-MK11 #12
[ 960.140000] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[ 960.140000] ubifs_bgt0_0 D c044cc28 0 132 2 0x00000000
[ 960.150000] Backtrace:
[ 960.150000] [<c044ca14>] (__schedule) from [<c044cf9c>]
(schedule+0xa0/0xb8)
[ 960.160000] r10:c251b000 r9:c249fc50 r8:c22c3a98 r7:00000007
r6:ffffe000 r5:c20fa818
[ 960.170000] r4:c249e000
[ 960.170000] [<c044cefc>] (schedule) from [<c02e5ec8>]
(nand_get_device+0xf8/0x110)
[ 960.180000] r5:c20fa818 r4:c22c3a90
[ 960.180000] [<c02e5dd0>] (nand_get_device) from [<c02e780c>]
(nand_write+0x28/0x84)
[ 960.190000] r9:00000000 r8:06cbf000 r7:00000000 r6:080bf000
r5:c249fc9c r4:c20fab70
[ 960.200000] [<c02e77e4>] (nand_write) from [<c02df0f0>]
(part_write+0x48/0x50)
[ 960.210000] r7:00001000 r6:0003f000 r5:00000000 r4:01400000
[ 960.210000] [<c02df0a8>] (part_write) from [<c02dbcf0>]
(mtd_write+0x84/0x9c)
[ 960.220000] r5:00000000 r4:c02df0a8
[ 960.220000] [<c02dbc6c>] (mtd_write) from [<c02dbd4c>]
(mtd_write_slc_mode+0x44/0xcc)
[ 960.230000] r9:00000000 r8:06cbf000 r7:00001000 r6:0003f000
r5:00001000 r4:c2310000
[ 960.240000] [<c02dbd08>] (mtd_write_slc_mode) from [<c02ff8ec>]
(ubi_io_write+0x37c/0x60c)
[ 960.250000] r10:00000000 r9:c251b000 r8:000001b2 r7:00001000
r6:0003f000 r5:000001b2
[ 960.250000] r4:c302d000
[ 960.260000] [<c02ff570>] (ubi_io_write) from [<c02fc7b0>]
(ubi_eba_write_leb+0xf4/0x77c)
[ 960.260000] r10:c248f280 r9:00000457 r8:000001b2 r7:00000457
r6:00000000 r5:00000000
[ 960.270000] r4:c302d000
[ 960.270000] [<c02fc6bc>] (ubi_eba_write_leb) from [<c02fb254>]
(ubi_leb_write+0x110/0x138)
[ 960.280000] r10:c248f280 r9:00000000 r8:c302d000 r7:00000457
r6:0003e000 r5:c23c8400
[ 960.290000] r4:00001000
[ 960.290000] [<c02fb144>] (ubi_leb_write) from [<c01ae498>]
(ubifs_leb_write+0xa0/0x130)
[ 960.300000] r10:0003e000 r9:00000084 r8:00001000 r7:0003e000
r6:00000457 r5:c251b000
[ 960.310000] r4:c251a000
[ 960.310000] [<c01ae3f8>] (ubifs_leb_write) from [<c01af0d4>]
(ubifs_wbuf_sync_nolock+0x294/0x350)
[ 960.320000] r8:00000b70 r7:00000490 r6:00001000 r5:c251a000 r4:c2449740
[ 960.330000] [<c01aee40>] (ubifs_wbuf_sync_nolock) from [<c01af4a8>]
(ubifs_bg_wbufs_sync+0x104/0x1b0)
[ 960.340000] r10:00000000 r9:00000000 r8:000000a0 r7:c2449764
r6:00000002 r5:c2449740
[ 960.350000] r4:c251a000
[ 960.350000] [<c01af3a4>] (ubifs_bg_wbufs_sync) from [<c01bab58>]
(ubifs_bg_thread+0xb8/0x1e8)
[ 960.360000] r9:00000000 r8:ffffe000 r7:c095e770 r6:c251a924
r5:00000000 r4:c251a000
[ 960.360000] [<c01baaa0>] (ubifs_bg_thread) from [<c003c6b8>]
(kthread+0xfc/0x10c)
[ 960.370000] r8:c01baaa0 r7:c251a000 r6:00000000 r5:c248f540 r4:c249e000
[ 960.380000] [<c003c5bc>] (kthread) from [<c000f7c8>]
(ret_from_fork+0x14/0x2c)
[ 960.390000] r8:00000000 r7:00000000 r6:00000000 r5:c003c5bc r4:c248f540
Printing the stacktraces only happens when UBIFS is mounted async. In
sync mode there are none.
But the behavior is the same: writing some data besides creating
directories or touching files leads to all access to files on the UBIFS
get stuck.
As stated before, multiple systems are effected and all of them share
one common debug output close to or at the end:
[ 742.010000] ubifs_wbuf_write_nolock: UBIFS DBG io (pid 59): 4144
bytes (data node) to jhead 0 (GC) wbuf at LEB 644:208896
[ 742.020000] ubifs_wbuf_write_nolock: UBIFS DBG io (pid 59): write
4096 bytes to LEB 644:208896
Depending on which system I perform the write test I get a different LEB
of course.
I assume that all threads are waiting in
mtd/nand/nand_base.c:nand_get_device() for the right nand device state,
but it does get right anyhow.
Since read access is no problem before writing something I wonder if the
nand device is left in the wrong state when writing data to it and
something unexpected happens.
I currently do not know how I can investigate the issue any more.
Maybe you can see something in the full log output and/or can point me
in the right direction.
Thanks,
Sebastian
PS: Here is the full output of the first example:
/ # mount -t ubifs -o noatime,nodiratime,chk_data_crc ubi0:root /mnt/images
[ 16.480000] ubifs_mount: UBIFS DBG gen (pid 81): name ubi0:root,
flags 0x0
[ 16.480000] ubifs_mount: UBIFS DBG gen (pid 81): opened ubi0_0
[ 16.490000] ubifs_read_node: UBIFS DBG io (pid 81): LEB 0:0,
superblock node, length 4096
[ 16.500000] ubifs_start_scan: UBIFS DBG scan (pid 81): scan LEB 1:0
[ 16.510000] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" started,
PID 82
[ 16.570000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:0
(258048 bytes left)
[ 16.570000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:0
[ 16.580000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:512
(257536 bytes left)
[ 16.590000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:512
[ 16.600000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:512, offset now 4096
[ 16.610000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:4096
(253952 bytes left)
[ 16.620000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:4096
[ 16.620000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:4608
(253440 bytes left)
[ 16.630000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:4608
[ 16.640000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:4608, offset now 8192
[ 16.650000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:8192
(249856 bytes left)
[ 16.660000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:8192
[ 16.670000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:8704
(249344 bytes left)
[ 16.670000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:8704
[ 16.680000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:8704, offset now 12288
[ 16.690000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:12288
(245760 bytes left)
[ 16.700000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:12288
[ 16.710000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:12800
(245248 bytes left)
[ 16.720000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:12800
[ 16.730000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:12800, offset now 16384
[ 16.740000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:16384
(241664 bytes left)
[ 16.740000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:16384
[ 16.750000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:16896
(241152 bytes left)
[ 16.760000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:16896
[ 16.770000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:16896, offset now 20480
[ 16.780000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:20480
(237568 bytes left)
[ 16.790000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:20480
[ 16.790000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:20992
(237056 bytes left)
[ 16.800000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:20992
[ 16.810000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:20992, offset now 24576
[ 16.820000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:24576
(233472 bytes left)
[ 16.830000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:24576
[ 16.840000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:25088
(232960 bytes left)
[ 16.850000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:25088
[ 16.850000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:25088, offset now 28672
[ 16.860000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:28672
(229376 bytes left)
[ 16.870000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:28672
[ 16.880000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:29184
(228864 bytes left)
[ 16.890000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:29184
[ 16.900000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:29184, offset now 32768
[ 16.910000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:32768
(225280 bytes left)
[ 16.920000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:32768
[ 16.920000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:33280
(224768 bytes left)
[ 16.930000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:33280
[ 16.940000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:33280, offset now 36864
[ 16.950000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:36864
(221184 bytes left)
[ 16.960000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:36864
[ 16.970000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:37376
(220672 bytes left)
[ 16.970000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:37376
[ 16.980000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:37376, offset now 40960
[ 16.990000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:40960
(217088 bytes left)
[ 17.000000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:40960
[ 17.010000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:41472
(216576 bytes left)
[ 17.020000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:41472
[ 17.030000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:41472, offset now 45056
[ 17.040000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:45056
(212992 bytes left)
[ 17.040000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:45056
[ 17.050000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:45568
(212480 bytes left)
[ 17.060000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:45568
[ 17.070000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:45568, offset now 49152
[ 17.080000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:49152
(208896 bytes left)
[ 17.090000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:49152
[ 17.090000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:49664
(208384 bytes left)
[ 17.100000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:49664
[ 17.110000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:49664, offset now 53248
[ 17.120000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:53248
(204800 bytes left)
[ 17.130000] watchdog watchdog0: watchdog did not stop!
[ 17.140000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:53248
[ 17.150000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:53760
(204288 bytes left)
[ 17.160000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:53760
[ 17.160000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:53760, offset now 57344
[ 17.170000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:57344
(200704 bytes left)
[ 17.180000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:57344
[ 17.190000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:57856
(200192 bytes left)
[ 17.200000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:57856
[ 17.210000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:57856, offset now 61440
[ 17.220000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:61440
(196608 bytes left)
[ 17.230000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:61440
[ 17.230000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:61952
(196096 bytes left)
[ 17.240000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:61952
[ 17.250000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:61952, offset now 65536
[ 17.260000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:65536
(192512 bytes left)
[ 17.270000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:65536
[ 17.280000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:66048
(192000 bytes left)
[ 17.280000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:66048
[ 17.290000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:66048, offset now 69632
[ 17.300000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:69632
(188416 bytes left)
[ 17.310000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:69632
[ 17.320000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:70144
(187904 bytes left)
[ 17.330000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:70144
[ 17.340000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:70144, offset now 73728
[ 17.350000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:73728
(184320 bytes left)
[ 17.350000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:73728
[ 17.360000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:74240
(183808 bytes left)
[ 17.370000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:74240
[ 17.380000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:74240, offset now 77824
[ 17.390000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:77824
(180224 bytes left)
[ 17.400000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:77824
[ 17.400000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:78336
(179712 bytes left)
[ 17.410000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:78336
[ 17.420000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:78336, offset now 81920
[ 17.430000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:81920
(176128 bytes left)
[ 17.440000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:81920
[ 17.450000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:82432
(175616 bytes left)
[ 17.460000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:82432
[ 17.460000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:82432, offset now 86016
[ 17.470000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:86016
(172032 bytes left)
[ 17.480000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:86016
[ 17.490000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:86528
(171520 bytes left)
[ 17.500000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:86528
[ 17.510000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:86528, offset now 90112
[ 17.520000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:90112
(167936 bytes left)
[ 17.520000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:90112
[ 17.530000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:90624
(167424 bytes left)
[ 17.540000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:90624
[ 17.550000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:90624, offset now 94208
[ 17.560000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:94208
(163840 bytes left)
[ 17.570000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:94208
[ 17.580000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:94720
(163328 bytes left)
[ 17.580000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:94720
[ 17.590000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:94720, offset now 98304
[ 17.600000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:98304
(159744 bytes left)
[ 17.610000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:98304
[ 17.620000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:98816
(159232 bytes left)
[ 17.630000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:98816
[ 17.640000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:98816, offset now 102400
[ 17.650000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:102400
(155648 bytes left)
[ 17.650000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:102400
[ 17.660000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:102912
(155136 bytes left)
[ 17.670000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:102912
[ 17.680000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:102912, offset now 106496
[ 17.690000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:106496
(151552 bytes left)
[ 17.700000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:106496
[ 17.710000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:107008
(151040 bytes left)
[ 17.710000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:107008
[ 17.720000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:107008, offset now 110592
[ 17.730000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:110592
(147456 bytes left)
[ 17.740000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:110592
[ 17.750000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:111104
(146944 bytes left)
[ 17.760000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:111104
[ 17.770000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:111104, offset now 114688
[ 17.780000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:114688
(143360 bytes left)
[ 17.780000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:114688
[ 17.790000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:115200
(142848 bytes left)
[ 17.800000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:115200
[ 17.810000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:115200, offset now 118784
[ 17.820000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:118784
(139264 bytes left)
[ 17.830000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 1:118784
[ 17.840000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:119296
(138752 bytes left)
[ 17.840000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 1:119296
[ 17.850000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 1:119296, offset now 122880
[ 17.860000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 1:122880
(135168 bytes left)
[ 17.870000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): hit empty
space at LEB 1:122880
[ 17.880000] ubifs_end_scan: UBIFS DBG scan (pid 81): stop scanning
LEB 1 at offset 122880
[ 17.890000] ubifs_start_scan: UBIFS DBG scan (pid 81): scan LEB 2:0
[ 17.950000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:0
(258048 bytes left)
[ 17.950000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:0
[ 17.960000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:512
(257536 bytes left)
[ 17.970000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:512
[ 17.980000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:512, offset now 4096
[ 17.990000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:4096
(253952 bytes left)
[ 18.000000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:4096
[ 18.000000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:4608
(253440 bytes left)
[ 18.010000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:4608
[ 18.020000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:4608, offset now 8192
[ 18.030000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:8192
(249856 bytes left)
[ 18.040000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:8192
[ 18.050000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:8704
(249344 bytes left)
[ 18.050000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:8704
[ 18.060000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:8704, offset now 12288
[ 18.070000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:12288
(245760 bytes left)
[ 18.080000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:12288
[ 18.090000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:12800
(245248 bytes left)
[ 18.100000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:12800
[ 18.110000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:12800, offset now 16384
[ 18.120000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:16384
(241664 bytes left)
[ 18.120000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:16384
[ 18.130000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:16896
(241152 bytes left)
[ 18.140000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:16896
[ 18.150000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:16896, offset now 20480
[ 18.160000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:20480
(237568 bytes left)
[ 18.170000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:20480
[ 18.180000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:20992
(237056 bytes left)
[ 18.180000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:20992
[ 18.190000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:20992, offset now 24576
[ 18.200000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:24576
(233472 bytes left)
[ 18.210000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:24576
[ 18.220000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:25088
(232960 bytes left)
[ 18.230000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:25088
[ 18.230000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:25088, offset now 28672
[ 18.240000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:28672
(229376 bytes left)
[ 18.250000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:28672
[ 18.260000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:29184
(228864 bytes left)
[ 18.270000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:29184
[ 18.280000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:29184, offset now 32768
[ 18.290000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:32768
(225280 bytes left)
[ 18.300000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:32768
[ 18.300000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:33280
(224768 bytes left)
[ 18.310000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:33280
[ 18.320000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:33280, offset now 36864
[ 18.330000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:36864
(221184 bytes left)
[ 18.340000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:36864
[ 18.350000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:37376
(220672 bytes left)
[ 18.350000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:37376
[ 18.360000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:37376, offset now 40960
[ 18.370000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:40960
(217088 bytes left)
[ 18.380000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:40960
[ 18.390000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:41472
(216576 bytes left)
[ 18.400000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:41472
[ 18.410000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:41472, offset now 45056
[ 18.420000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:45056
(212992 bytes left)
[ 18.420000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:45056
[ 18.430000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:45568
(212480 bytes left)
[ 18.440000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:45568
[ 18.450000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:45568, offset now 49152
[ 18.460000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:49152
(208896 bytes left)
[ 18.470000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:49152
[ 18.470000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:49664
(208384 bytes left)
[ 18.480000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:49664
[ 18.490000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:49664, offset now 53248
[ 18.500000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:53248
(204800 bytes left)
[ 18.510000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:53248
[ 18.520000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:53760
(204288 bytes left)
[ 18.530000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:53760
[ 18.530000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:53760, offset now 57344
[ 18.540000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:57344
(200704 bytes left)
[ 18.550000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:57344
[ 18.560000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:57856
(200192 bytes left)
[ 18.570000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:57856
[ 18.580000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:57856, offset now 61440
[ 18.590000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:61440
(196608 bytes left)
[ 18.590000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:61440
[ 18.600000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:61952
(196096 bytes left)
[ 18.610000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:61952
[ 18.620000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:61952, offset now 65536
[ 18.630000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:65536
(192512 bytes left)
[ 18.640000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:65536
[ 18.650000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:66048
(192000 bytes left)
[ 18.650000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:66048
[ 18.660000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:66048, offset now 69632
[ 18.670000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:69632
(188416 bytes left)
[ 18.680000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:69632
[ 18.690000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:70144
(187904 bytes left)
[ 18.700000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:70144
[ 18.710000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:70144, offset now 73728
[ 18.720000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:73728
(184320 bytes left)
[ 18.720000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:73728
[ 18.730000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:74240
(183808 bytes left)
[ 18.740000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:74240
[ 18.750000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:74240, offset now 77824
[ 18.760000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:77824
(180224 bytes left)
[ 18.770000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:77824
[ 18.770000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:78336
(179712 bytes left)
[ 18.780000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:78336
[ 18.790000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:78336, offset now 81920
[ 18.800000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:81920
(176128 bytes left)
[ 18.810000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:81920
[ 18.820000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:82432
(175616 bytes left)
[ 18.830000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:82432
[ 18.830000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:82432, offset now 86016
[ 18.840000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:86016
(172032 bytes left)
[ 18.850000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:86016
[ 18.860000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:86528
(171520 bytes left)
[ 18.870000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:86528
[ 18.880000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:86528, offset now 90112
[ 18.890000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:90112
(167936 bytes left)
[ 18.890000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:90112
[ 18.900000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:90624
(167424 bytes left)
[ 18.910000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:90624
[ 18.920000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:90624, offset now 94208
[ 18.930000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:94208
(163840 bytes left)
[ 18.940000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:94208
[ 18.950000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:94720
(163328 bytes left)
[ 18.950000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:94720
[ 18.960000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:94720, offset now 98304
[ 18.970000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:98304
(159744 bytes left)
[ 18.980000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:98304
[ 18.990000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:98816
(159232 bytes left)
[ 19.000000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:98816
[ 19.010000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:98816, offset now 102400
[ 19.020000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:102400
(155648 bytes left)
[ 19.020000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:102400
[ 19.030000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:102912
(155136 bytes left)
[ 19.040000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:102912
[ 19.050000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:102912, offset now 106496
[ 19.060000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:106496
(151552 bytes left)
[ 19.070000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:106496
[ 19.080000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:107008
(151040 bytes left)
[ 19.080000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:107008
[ 19.090000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:107008, offset now 110592
[ 19.100000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:110592
(147456 bytes left)
[ 19.110000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:110592
[ 19.120000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:111104
(146944 bytes left)
[ 19.130000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:111104
[ 19.140000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:111104, offset now 114688
[ 19.150000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:114688
(143360 bytes left)
[ 19.150000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:114688
[ 19.160000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:115200
(142848 bytes left)
[ 19.170000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:115200
[ 19.180000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:115200, offset now 118784
[ 19.190000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:118784
(139264 bytes left)
[ 19.200000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
master node at LEB 2:118784
[ 19.210000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:119296
(138752 bytes left)
[ 19.210000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 2:119296
[ 19.220000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3556 bytes
padded at LEB 2:119296, offset now 122880
[ 19.230000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 2:122880
(135168 bytes left)
[ 19.240000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): hit empty
space at LEB 2:122880
[ 19.250000] ubifs_end_scan: UBIFS DBG scan (pid 81): stop scanning
LEB 2 at offset 122880
[ 19.260000] ubifs_read_node: UBIFS DBG io (pid 81): LEB 747:1792,
indexing node, length 68
[ 19.270000] lpt_init_rd: UBIFS DBG lp (pid 81): space_bits 15
[ 19.270000] lpt_init_rd: UBIFS DBG lp (pid 81): lpt_lnum_bits 2
[ 19.280000] lpt_init_rd: UBIFS DBG lp (pid 81): lpt_offs_bits 18
[ 19.280000] lpt_init_rd: UBIFS DBG lp (pid 81): lpt_spc_bits 18
[ 19.290000] lpt_init_rd: UBIFS DBG lp (pid 81): pcnt_bits 9
[ 19.300000] lpt_init_rd: UBIFS DBG lp (pid 81): lnum_bits 11
[ 19.300000] lpt_init_rd: UBIFS DBG lp (pid 81): pnode_sz 18
[ 19.310000] lpt_init_rd: UBIFS DBG lp (pid 81): nnode_sz 13
[ 19.310000] lpt_init_rd: UBIFS DBG lp (pid 81): ltab_sz 12
[ 19.320000] lpt_init_rd: UBIFS DBG lp (pid 81): lsave_sz 355
[ 19.320000] lpt_init_rd: UBIFS DBG lp (pid 81): lsave_cnt 256
[ 19.330000] lpt_init_rd: UBIFS DBG lp (pid 81): lpt_hght 5
[ 19.340000] lpt_init_rd: UBIFS DBG lp (pid 81): big_lpt 0
[ 19.340000] lpt_init_rd: UBIFS DBG lp (pid 81): LPT root is at 7:12497
[ 19.350000] lpt_init_rd: UBIFS DBG lp (pid 81): LPT head is at 7:16384
[ 19.350000] lpt_init_rd: UBIFS DBG lp (pid 81): LPT ltab is at 7:12288
[ 19.360000] ubifs_write_node: UBIFS DBG io (pid 81): LEB 1:122880,
master node, length 512 (aligned 4096)
[ 19.370000] ubifs_write_node: UBIFS DBG io (pid 81): LEB 2:122880,
master node, length 512 (aligned 4096)
[ 19.380000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 81): LEB 7 add 13
to 16017
[ 19.390000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 81): LEB 7 add 12
to 16030
[ 19.400000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 81): LEB 7 add 13
to 16042
[ 19.400000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 81): LEB 7 add 13
to 16055
[ 19.410000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 81): LEB 7 add 13
to 16068
[ 19.420000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 81): LEB 7 add 13
to 16081
[ 19.430000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 81): LEB 7 add 18
to 16094
[ 19.430000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 81): LEB 1720,
free 0, dirty 250872, flags 34
[ 19.440000] ubifs_change_lp: UBIFS DBG lp (pid 81): LEB 1720, free
-2147483647, dirty -2147483647, flags 50
[ 19.450000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 81): LEB 1720,
free 0, dirty 250872, flags 34
[ 19.460000] ubifs_replay_journal: UBIFS DBG mnt (pid 81): start
replaying the journal
[ 19.470000] replay_log_leb: UBIFS DBG mnt (pid 81): replay log LEB 3:0
[ 19.480000] ubifs_start_scan: UBIFS DBG scan (pid 81): scan LEB 3:0
[ 19.540000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 3:0
(258048 bytes left)
[ 19.540000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
commit start node at LEB 3:0
[ 19.550000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 3:32
(258016 bytes left)
[ 19.560000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
reference node at LEB 3:32
[ 19.570000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 3:96
(257952 bytes left)
[ 19.580000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
reference node at LEB 3:96
[ 19.580000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 3:160
(257888 bytes left)
[ 19.590000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): scanning
padding node at LEB 3:160
[ 19.600000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): 3908 bytes
padded at LEB 3:160, offset now 4096
[ 19.610000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 3:4096
(253952 bytes left)
[ 19.620000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): hit empty
space at LEB 3:4096
[ 19.630000] ubifs_end_scan: UBIFS DBG scan (pid 81): stop scanning
LEB 3 at offset 4096
[ 19.630000] replay_log_leb: UBIFS DBG mnt (pid 81): commit start
sqnum 48183645
[ 19.640000] add_replay_bud: UBIFS DBG mnt (pid 81): add replay bud
LEB 644:208896, head 0
[ 19.650000] ubifs_add_bud: UBIFS DBG log (pid 81): LEB 644:208896,
jhead 0 (GC), bud_bytes 49152
[ 19.660000] add_replay_bud: UBIFS DBG mnt (pid 81): add replay bud
LEB 926:36864, head 1
[ 19.670000] ubifs_add_bud: UBIFS DBG log (pid 81): LEB 926:36864,
jhead 1 (base), bud_bytes 270336
[ 19.680000] replay_log_leb: UBIFS DBG mnt (pid 81): replay log LEB 4:0
[ 19.680000] ubifs_start_scan: UBIFS DBG scan (pid 81): scan LEB 4:0
[ 19.690000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB 4:0
(258048 bytes left)
[ 19.700000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): hit empty
space at LEB 4:0
[ 19.700000] ubifs_end_scan: UBIFS DBG scan (pid 81): stop scanning
LEB 4 at offset 0
[ 19.710000] replay_bud: UBIFS DBG mnt (pid 81): replay bud LEB 644,
head 0, offs 208896, is_last 1
[ 19.720000] ubifs_start_scan: UBIFS DBG scan (pid 81): scan LEB
644:208896
[ 19.740000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB
644:208896 (49152 bytes left)
[ 19.750000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): hit empty
space at LEB 644:208896
[ 19.760000] ubifs_end_scan: UBIFS DBG scan (pid 81): stop scanning
LEB 644 at offset 208896
[ 19.760000] replay_bud: UBIFS DBG mnt (pid 81): bud LEB 644 replied:
dirty 0, free 49152
[ 19.770000] replay_bud: UBIFS DBG mnt (pid 81): replay bud LEB 926,
head 1, offs 36864, is_last 1
[ 19.780000] ubifs_start_scan: UBIFS DBG scan (pid 81): scan LEB 926:36864
[ 19.830000] ubifs_scan: UBIFS DBG scan (pid 81): look at LEB
926:36864 (221184 bytes left)
[ 19.840000] ubifs_scan_a_node: UBIFS DBG scan (pid 81): hit empty
space at LEB 926:36864
[ 19.850000] ubifs_end_scan: UBIFS DBG scan (pid 81): stop scanning
LEB 926 at offset 36864
[ 19.860000] replay_bud: UBIFS DBG mnt (pid 81): bud LEB 926 replied:
dirty 0, free 221184
[ 19.870000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 81): LEB 7 add 13
to 16112
[ 19.880000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 81): LEB 7 add 13
to 16125
[ 19.880000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 81): LEB 7 add 13
to 16138
[ 19.890000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 81): LEB 7 add 13
to 16151
[ 19.900000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 81): LEB 7 add 18
to 16164
[ 19.910000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 81): LEB 644,
free 49152, dirty 136656, flags 1
[ 19.910000] ubifs_change_lp: UBIFS DBG lp (pid 81): LEB 644, free
49152, dirty 136656, flags 17
[ 19.920000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 81): LEB 644,
free 49152, dirty 136656, flags 1
[ 19.930000] ubifs_wbuf_seek_nolock: UBIFS DBG io (pid 81): LEB
644:208896, jhead 0 (GC)
[ 19.940000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 81): LEB 7 add 13
to 16182
[ 19.950000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 81): LEB 7 add 13
to 16195
[ 19.960000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 81): LEB 7 add 13
to 16208
[ 19.960000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 81): LEB 7 add 18
to 16221
[ 19.970000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 81): LEB 926,
free 221184, dirty 35560, flags 3
[ 19.980000] ubifs_change_lp: UBIFS DBG lp (pid 81): LEB 926, free
221184, dirty 35560, flags 19
[ 19.990000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 81): LEB 926,
free 221184, dirty 35560, flags 3
[ 20.000000] ubifs_wbuf_seek_nolock: UBIFS DBG io (pid 81): LEB
926:36864, jhead 1 (base)
[ 20.010000] ubifs_replay_journal: UBIFS DBG mnt (pid 81): finished,
log head LEB 3:4096, max_sqnum 48183652, highest_inum 252
[ 20.020000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 81): LEB 643,
free 258048, dirty 0, flags 4
[ 20.030000] ubifs_change_lp: UBIFS DBG lp (pid 81): LEB 643, free
258048, dirty 0, flags 20
[ 20.030000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 81): LEB 643,
free 258048, dirty 0, flags 4
[ 20.040000] UBIFS (ubi0:0): UBIFS: mounted UBI device 0, volume 0,
name "root"
[ 20.050000] UBIFS (ubi0:0): LEB size: 258048 bytes (252 KiB),
min./max. I/O unit sizes: 4096 bytes/4096 bytes
[ 20.060000] UBIFS (ubi0:0): FS size: 493903872 bytes (471 MiB, 1914
LEBs), journal size 9420800 bytes (8 MiB, 37 LEBs)
[ 20.070000] UBIFS (ubi0:0): reserved for root: 0 bytes (0 KiB)
[ 20.080000] UBIFS (ubi0:0): media format: w4/r0 (latest is w4/r0),
UUID 9B699AB7-D22F-4714-9879-DD0E1E577BF6, small LPT model
[ 20.090000] mount_ubifs: UBIFS DBG gen (pid 81): default compressor: lzo
[ 20.100000] mount_ubifs: UBIFS DBG gen (pid 81): data journal heads: 1
[ 20.100000] mount_ubifs: UBIFS DBG gen (pid 81): log LEBs:
4 (3 - 6)
[ 20.110000] mount_ubifs: UBIFS DBG gen (pid 81): LPT area LEBs:
2 (7 - 8)
[ 20.120000] mount_ubifs: UBIFS DBG gen (pid 81): orphan area LEBs:
1 (9 - 9)
[ 20.120000] mount_ubifs: UBIFS DBG gen (pid 81): main area LEBs:
1914 (10 - 1923)
[ 20.130000] mount_ubifs: UBIFS DBG gen (pid 81): index LEBs: 59
[ 20.140000] mount_ubifs: UBIFS DBG gen (pid 81): total index bytes:
1240624 (1211 KiB, 1 MiB)
[ 20.150000] mount_ubifs: UBIFS DBG gen (pid 81): key hash type: 0
[ 20.150000] mount_ubifs: UBIFS DBG gen (pid 81): tree fanout: 8
[ 20.160000] mount_ubifs: UBIFS DBG gen (pid 81): reserved GC LEB: 643
[ 20.170000] mount_ubifs: UBIFS DBG gen (pid 81): max. znode size 240
[ 20.170000] mount_ubifs: UBIFS DBG gen (pid 81): max. index node size 192
[ 20.180000] mount_ubifs: UBIFS DBG gen (pid 81): node sizes:
data 48, inode 160, dentry 56
[ 20.190000] mount_ubifs: UBIFS DBG gen (pid 81): node sizes:
trun 56, sb 4096, master 512
[ 20.200000] mount_ubifs: UBIFS DBG gen (pid 81): node sizes:
ref 64, cmt. start 32, orph 32
[ 20.210000] mount_ubifs: UBIFS DBG gen (pid 81): max. node sizes:
data 4144, inode 4256 dentry 312, idx 188
[ 20.220000] mount_ubifs: UBIFS DBG gen (pid 81): dead watermark:
4096
[ 20.230000] mount_ubifs: UBIFS DBG gen (pid 81): dark watermark:
8192
[ 20.230000] mount_ubifs: UBIFS DBG gen (pid 81): LEB overhead:
1120
[ 20.240000] mount_ubifs: UBIFS DBG gen (pid 81): max. dark space:
15679488 (15312 KiB, 14 MiB)
[ 20.250000] mount_ubifs: UBIFS DBG gen (pid 81): maximum bud bytes:
8388608 (8192 KiB, 8 MiB)
[ 20.260000] mount_ubifs: UBIFS DBG gen (pid 81): BG commit bud bytes:
6815744 (6656 KiB, 6 MiB)
[ 20.270000] mount_ubifs: UBIFS DBG gen (pid 81): current bud bytes
270336 (264 KiB, 0 MiB)
[ 20.270000] mount_ubifs: UBIFS DBG gen (pid 81): max. seq. number:
48183652
[ 20.280000] mount_ubifs: UBIFS DBG gen (pid 81): commit number:
29060
[ 20.290000] ubifs_iget: UBIFS DBG gen (pid 81): inode 1
[ 20.290000] ubifs_lookup_level0: UBIFS DBG tnc (pid 81): search key
(1, inode)
[ 20.300000] ubifs_read_node: UBIFS DBG io (pid 81): LEB 747:1792,
indexing node, length 68
[ 20.310000] read_znode: UBIFS DBG tnc (pid 81): LEB 747:1792, level
5, 2 branch
[ 20.320000] ubifs_read_node: UBIFS DBG io (pid 81): LEB 624:656,
indexing node, length 188
[ 20.330000] read_znode: UBIFS DBG tnc (pid 81): LEB 624:656, level 4,
8 branch
[ 20.330000] ubifs_read_node: UBIFS DBG io (pid 81): LEB 624:464,
indexing node, length 188
[ 20.340000] read_znode: UBIFS DBG tnc (pid 81): LEB 624:464, level 3,
8 branch
[ 20.350000] ubifs_read_node: UBIFS DBG io (pid 81): LEB 624:352,
indexing node, length 108
[ 20.360000] read_znode: UBIFS DBG tnc (pid 81): LEB 624:352, level 2,
4 branch
[ 20.370000] ubifs_read_node: UBIFS DBG io (pid 81): LEB 624:224,
indexing node, length 128
[ 20.370000] read_znode: UBIFS DBG tnc (pid 81): LEB 624:224, level 1,
5 branch
[ 20.380000] ubifs_read_node: UBIFS DBG io (pid 81): LEB 624:112,
indexing node, length 108
[ 20.390000] read_znode: UBIFS DBG tnc (pid 81): LEB 624:112, level 0,
4 branch
[ 20.400000] ubifs_lookup_level0: UBIFS DBG tnc (pid 81): found 1, lvl
0, n 0
[ 20.400000] ubifs_read_node_wbuf: UBIFS DBG io (pid 81): LEB
926:8416, inode node, length 160, jhead 1 (base)
[ 20.410000] ubifs_read_node: UBIFS DBG io (pid 81): LEB 926:8416,
inode node, length 160
/ # [ 22.140000] watchdog watchdog0: watchdog did not stop!
[ 27.150000] watchdog watchdog0: watchdog did not stop!
[ 32.160000] watchdog watchdog0: watchdog did not stop!
/ # dmesg | grep UBI | grep -v DBG
[ 16.510000] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" started,
PID 82
[ 20.040000] UBIFS (ubi0:0): UBIFS: mounted UBI device 0, volume 0,
name "root"
[ 20.050000] UBIFS (ubi0:0): LEB size: 258048 bytes (252 KiB),
min./max. I/O unit sizes: 4096 bytes/4096 bytes
[ 20.060000] UBIFS (ubi0:0): FS size: 493903872 bytes (471 MiB, 1914
LEBs), journal size 9420800 bytes (8 MiB, 37 LEBs)
[ 20.070000] UBIFS (ubi0:0): reserved for root: 0 bytes (0 KiB)
[ 20.080000] UBIFS (ubi0:0): media format: w4/r0 (latest is w4/r0),
UUID 9B699AB7-D22F-4714-9879-DD0E1E577BF6, small LPT model
/ # [ 37.170000] watchdog watchdog0: watchdog did not stop!
[ 42.180000] watchdog watchdog0: watchdog did not stop!
[ 47.190000] watchdog watchdog0: watchdog did not stop!
[ 52.200000] watchdog watchdog0: watchdog did not stop!
[snip] only 5s apart watchdog messages
[ 132.350000] watchdog watchdog0: watchdog did not stop!
/ #
/ # dd if=/dev/urandom of=/mnt/images/test/urandom_1k bs=1k count=1
[ 135.830000] ubifs_lookup: UBIFS DBG gen (pid 110): 'test' in dir ino 1
[ 135.840000] ubifs_lookup_level0: UBIFS DBG tnc (pid 110): search key
(1, direntry, 0x1c4ddc5)
[ 135.850000] ubifs_lookup_level0: UBIFS DBG tnc (pid 110): found 1,
lvl 0, n 3
[ 135.850000] ubifs_read_node_wbuf: UBIFS DBG io (pid 110): LEB
926:8192, direntry node, length 61, jhead 1 (base)
[ 135.860000] ubifs_read_node: UBIFS DBG io (pid 110): LEB 926:8192,
direntry node, length 61
[ 135.870000] ubifs_iget: UBIFS DBG gen (pid 110): inode 249
[ 135.880000] ubifs_lookup_level0: UBIFS DBG tnc (pid 110): search key
(249, inode)
[ 135.890000] ubifs_read_node: UBIFS DBG io (pid 110): LEB 747:1640,
indexing node, length 148
[ 135.890000] read_znode: UBIFS DBG tnc (pid 110): LEB 747:1640, level
4, 6 branch
[ 135.900000] ubifs_read_node: UBIFS DBG io (pid 110): LEB 747:1512,
indexing node, length 128
[ 135.910000] read_znode: UBIFS DBG tnc (pid 110): LEB 747:1512, level
3, 5 branch
[ 135.920000] ubifs_read_node: UBIFS DBG io (pid 110): LEB 747:1344,
indexing node, length 168
[ 135.930000] read_znode: UBIFS DBG tnc (pid 110): LEB 747:1344, level
2, 7 branch
[ 135.930000] ubifs_read_node: UBIFS DBG io (pid 110): LEB 747:464,
indexing node, length 128
[ 135.940000] read_znode: UBIFS DBG tnc (pid 110): LEB 747:464, level
1, 5 branch
[ 135.950000] ubifs_read_node: UBIFS DBG io (pid 110): LEB 747:112,
indexing node, length 108
[ 135.960000] read_znode: UBIFS DBG tnc (pid 110): LEB 747:112, level
0, 4 branch
[ 135.970000] ubifs_lookup_level0: UBIFS DBG tnc (pid 110): found 1,
lvl 0, n 2
[ 135.970000] ubifs_read_node_wbuf: UBIFS DBG io (pid 110): LEB
926:28904, inode node, length 160, jhead 1 (base)
[ 135.980000] ubifs_read_node: UBIFS DBG io (pid 110): LEB 926:28904,
inode node, length 160
[ 135.990000] ubifs_lookup: UBIFS DBG gen (pid 110): 'urandom_1k' in
dir ino 249
[ 136.000000] ubifs_lookup_level0: UBIFS DBG tnc (pid 110): search key
(249, direntry, 0x101b358e)
[ 136.010000] ubifs_read_node: UBIFS DBG io (pid 110): LEB 747:224,
indexing node, length 108
[ 136.020000] read_znode: UBIFS DBG tnc (pid 110): LEB 747:224, level
0, 4 branch
[ 136.020000] ubifs_lookup_level0: UBIFS DBG tnc (pid 110): found 1,
lvl 0, n 0
[ 136.030000] ubifs_read_node_wbuf: UBIFS DBG io (pid 110): LEB
926:12288, direntry node, length 67, jhead 1 (base)
[ 136.040000] ubifs_read_node: UBIFS DBG io (pid 110): LEB 926:12288,
direntry node, length 67
[ 136.050000] ubifs_iget: UBIFS DBG gen (pid 110): inode 250
[ 136.060000] ubifs_lookup_level0: UBIFS DBG tnc (pid 110): search key
(250, inode)
[ 136.060000] ubifs_lookup_level0: UBIFS DBG tnc (pid 110): found 1,
lvl 0, n 2
[ 136.070000] ubifs_read_node_wbuf: UBIFS DBG io (pid 110): LEB
926:16384, inode node, length 160, jhead 1 (base)
[ 136.080000] ubifs_read_node: UBIFS DBG io (pid 110): LEB 926:16384,
inode node, length 160
[ 136.090000] ubifs_getxattr: UBIFS DBG gen (pid 110): xattr
'security.capability', ino 250 ('urandom_1k'), buf size 0
[ 136.100000] ubifs_lookup_level0: UBIFS DBG tnc (pid 110): search key
(250, xentry, 0x10888ae6)
[ 136.110000] ubifs_lookup_level0: UBIFS DBG tnc (pid 110): found 0,
lvl 0, n 3
[ 136.120000] ubifs_setattr: UBIFS DBG gen (pid 110): ino 250, mode
0x81a4, ia_valid 0xa068
[ 136.120000] do_truncation: UBIFS DBG gen (pid 110): ino 250, size
1024 -> 0
[ 136.130000] ubifs_jnl_truncate: UBIFS DBG jnl (pid 110): ino 250,
size 1024 -> 0
[ 136.140000] write_head: UBIFS DBG jnl (pid 110): jhead 1 (base), LEB
926:36864, len 216
[ 136.150000] ubifs_wbuf_write_nolock: UBIFS DBG io (pid 110): 216
bytes (inode node) to jhead 1 (base) wbuf at LEB 926:36864
[ 136.160000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 926,
free 221184, dirty 35560, flags 16
[ 136.170000] ubifs_change_lp: UBIFS DBG lp (pid 110): LEB 926, free
220968, dirty 35560, flags 16
[ 136.180000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 926,
free 221184, dirty 35560, flags 16
[ 136.190000] new_wbuf_timer_nolock: UBIFS DBG io (pid 110): set timer
for jhead 1 (base), 3000-5000 millisecs
[ 136.200000] ubifs_tnc_add: UBIFS DBG tnc (pid 110): 926:36864, len
160, key (250, inode)
[ 136.200000] lookup_level0_dirty: UBIFS DBG tnc (pid 110): search and
dirty key (250, inode)
[ 136.210000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 110): LEB 7 add 13
to 16239
[ 136.220000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 110): LEB 7 add 13
to 16252
[ 136.230000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 110): LEB 7 add 18
to 16265
[ 136.240000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 747,
free 45056, dirty 205576, flags 34
[ 136.240000] ubifs_change_lp: UBIFS DBG lp (pid 110): LEB 747, free
-2147483647, dirty 205644, flags 34
[ 136.250000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 747,
free 45056, dirty 205576, flags 34
[ 136.260000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 747,
free 45056, dirty 205648, flags 34
[ 136.270000] ubifs_change_lp: UBIFS DBG lp (pid 110): LEB 747, free
-2147483647, dirty 205796, flags 34
[ 136.280000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 747,
free 45056, dirty 205648, flags 34
[ 136.290000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 747,
free 45056, dirty 205800, flags 34
[ 136.300000] ubifs_change_lp: UBIFS DBG lp (pid 110): LEB 747, free
-2147483647, dirty 205928, flags 34
[ 136.310000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 747,
free 45056, dirty 205800, flags 34
[ 136.320000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 747,
free 45056, dirty 205928, flags 34
[ 136.330000] ubifs_change_lp: UBIFS DBG lp (pid 110): LEB 747, free
-2147483647, dirty 206096, flags 34
[ 136.340000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 747,
free 45056, dirty 205928, flags 34
[ 136.350000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 747,
free 45056, dirty 206096, flags 34
[ 136.360000] ubifs_change_lp: UBIFS DBG lp (pid 110): LEB 747, free
-2147483647, dirty 206224, flags 34
[ 136.370000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 747,
free 45056, dirty 206096, flags 34
[ 136.380000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 747,
free 45056, dirty 206224, flags 34
[ 136.390000] ubifs_change_lp: UBIFS DBG lp (pid 110): LEB 747, free
-2147483647, dirty 206332, flags 34
[ 136.400000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 747,
free 45056, dirty 206224, flags 34
[ 136.400000] lookup_level0_dirty: UBIFS DBG tnc (pid 110): found 1,
lvl 0, n 2
[ 136.410000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 926,
free 220968, dirty 35560, flags 16
[ 136.420000] ubifs_change_lp: UBIFS DBG lp (pid 110): LEB 926, free
-2147483647, dirty 35720, flags 16
[ 136.430000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 926,
free 220968, dirty 35560, flags 16
[ 136.440000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 926,
free 220968, dirty 35720, flags 16
[ 136.450000] ubifs_change_lp: UBIFS DBG lp (pid 110): LEB 926, free
-2147483647, dirty 35776, flags 16
[ 136.460000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 926,
free 220968, dirty 35720, flags 16
[ 136.470000] ubifs_lookup_level0: UBIFS DBG tnc (pid 110): search key
(250, data, 0)
[ 136.480000] ubifs_lookup_level0: UBIFS DBG tnc (pid 110): found 1,
lvl 0, n 3
[ 136.480000] tnc_delete: UBIFS DBG tnc (pid 110): deleting key (250,
data, 0)
[ 136.490000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 110): LEB 7 add 13
to 16283
[ 136.500000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 110): LEB 7 add 13
to 16296
[ 136.510000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 110): LEB 7 add 13
to 16309
[ 136.510000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 110): LEB 7 add 18
to 16322
[ 136.520000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 1111,
free 0, dirty 8280, flags 1
[ 136.530000] ubifs_change_lp: UBIFS DBG lp (pid 110): LEB 1111, free
-2147483647, dirty 9352, flags 1
[ 136.540000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 110): LEB 1111,
free 0, dirty 8280, flags 1
[ 136.550000] ubifs_lookup_level0: UBIFS DBG tnc (pid 110): search key
(250, data, 0)
[ 136.550000] ubifs_lookup_level0: UBIFS DBG tnc (pid 110): found 0,
lvl 0, n 2
[ 136.560000] ubifs_read_node: UBIFS DBG io (pid 110): LEB 747:336,
indexing node, length 128
[ 136.570000] read_znode: UBIFS DBG tnc (pid 110): LEB 747:336, level
0, 5 branch
[ 136.580000] ubifs_getxattr: UBIFS DBG gen (pid 110): xattr
'security.capability', ino 250 ('urandom_1k'), buf size 0
[ 136.590000] ubifs_lookup_level0: UBIFS DBG tnc (pid 110): search key
(250, xentry, 0x10888ae6)
[ 136.600000] ubifs_lookup_level0: UBIFS DBG tnc (pid 110): found 0,
lvl 0, n 2
[ 136.600000] do_readpage: UBIFS DBG gen (pid 110): ino 250, pg 0,
i_size 0, flags 0x5
[ 136.610000] ubifs_write_end: UBIFS DBG gen (pid 110): ino 250, pos 0,
pg 0, len 1024, copied 1024, i_size 0
[ 136.620000] ubifs_dirty_inode: UBIFS DBG gen (pid 110): inode 250
1+0 records in
1+0 records out
/ # [ 137.360000] watchdog watchdog0: watchdog did not stop!
[ 141.040000] wbuf_timer_callback_nolock: UBIFS DBG io (pid 0): jhead 1
(base)
[ 141.040000] ubifs_bg_wbufs_sync: UBIFS DBG io (pid 82): synchronize
[ 141.050000] ubifs_wbuf_sync_nolock: UBIFS DBG io (pid 82): LEB
926:36864, 216 bytes, jhead 1 (base)
[ 141.060000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 82): LEB 926,
free 220968, dirty 35776, flags 16
[ 141.070000] ubifs_change_lp: UBIFS DBG lp (pid 82): LEB 926, free
217088, dirty 39656, flags 16
[ 141.080000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 82): LEB 926,
free 220968, dirty 35776, flags 16
[ 142.370000] watchdog watchdog0: watchdog did not stop!
[ 147.380000] watchdog watchdog0: watchdog did not stop!
[ 152.390000] watchdog watchdog0: watchdog did not stop!
[ 157.390000] watchdog watchdog0: watchdog did not stop!
[ 162.400000] watchdog watchdog0: watchdog did not stop!
[ 166.680000] ubifs_writepage: UBIFS DBG gen (pid 59): ino 250, pg 0,
pg flags 0x92d
[ 166.680000] ubifs_write_inode: UBIFS DBG gen (pid 59): inode 250,
mode 0x81a4, nlink 1
[ 166.690000] ubifs_jnl_write_inode: UBIFS DBG jnl (pid 59): ino 250,
nlink 1
[ 166.700000] write_head: UBIFS DBG jnl (pid 59): jhead 1 (base), LEB
926:40960, len 160
[ 166.710000] ubifs_wbuf_write_nolock: UBIFS DBG io (pid 59): 160 bytes
(inode node) to jhead 1 (base) wbuf at LEB 926:40960
[ 166.720000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 59): LEB 926,
free 217088, dirty 39656, flags 16
[ 166.730000] ubifs_change_lp: UBIFS DBG lp (pid 59): LEB 926, free
216928, dirty 39656, flags 16
[ 166.730000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 59): LEB 926,
free 217088, dirty 39656, flags 16
[ 166.740000] new_wbuf_timer_nolock: UBIFS DBG io (pid 59): set timer
for jhead 1 (base), 3000-5000 millisecs
[ 166.750000] ubifs_tnc_add: UBIFS DBG tnc (pid 59): 926:40960, len
160, key (250, inode)
[ 166.760000] lookup_level0_dirty: UBIFS DBG tnc (pid 59): search and
dirty key (250, inode)
[ 166.770000] lookup_level0_dirty: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 2
[ 166.780000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 59): LEB 926,
free 216928, dirty 39656, flags 16
[ 166.790000] ubifs_change_lp: UBIFS DBG lp (pid 59): LEB 926, free
-2147483647, dirty 39816, flags 16
[ 166.800000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 59): LEB 926,
free 216928, dirty 39656, flags 16
[ 166.810000] ubifs_jnl_write_data: UBIFS DBG jnl (pid 59): ino 250,
blk 0, len 1024, key (250, data, 0)
[ 166.810000] ubifs_find_free_space: UBIFS DBG find (pid 59): min_space
1072
[ 167.170000] reserve_space: UBIFS DBG jnl (pid 59): no free space in
jhead 2 (data), run GC
[ 167.180000] ubifs_gc_should_commit: UBIFS DBG cmt (pid 59): commit
not requested
[ 167.180000] ubifs_gc_should_commit: UBIFS DBG cmt (pid 59): commit
not requested
[ 167.190000] ubifs_find_dirty_leb: UBIFS DBG find (pid 59): found LEB
1682, free 0, dirty 253904, flags 0x1
[ 167.200000] ubifs_change_lp: UBIFS DBG lp (pid 59): LEB 1682, free
-2147483647, dirty -2147483647, flags 17
[ 167.210000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 59): LEB 8 add 13
to 247688
[ 167.220000] ubifs_add_lpt_dirt: UBIFS DBG lp (pid 59): LEB 8 add 18
to 247701
[ 167.220000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 59): LEB 1682,
free 0, dirty 253904, flags 1
[ 167.230000] ubifs_garbage_collect: UBIFS DBG gc (pid 59): found LEB
1682: free 0, dirty 253904, sum 253904 (min. space 4096)
[ 167.240000] ubifs_start_scan: UBIFS DBG scan (pid 59): scan LEB 1682:0
[ 167.300000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB 1682:0
(258048 bytes left)
[ 167.310000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:0
[ 167.320000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB 1682:424
(257624 bytes left)
[ 167.330000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:424
[ 167.340000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3644 bytes
padded at LEB 1682:424, offset now 4096
[ 167.350000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:4096 (253952 bytes left)
[ 167.360000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:4096
[ 167.360000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:5080 (252968 bytes left)
[ 167.370000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:5080
[ 167.380000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3084 bytes
padded at LEB 1682:5080, offset now 8192
[ 167.390000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:8192 (249856 bytes left)
[ 167.400000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:8192
[ 167.410000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:9704 (248344 bytes left)
[ 167.420000] watchdog watchdog0: watchdog did not stop!
[ 167.430000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:9704
[ 167.430000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 2556 bytes
padded at LEB 1682:9704, offset now 12288
[ 167.440000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:12288 (245760 bytes left)
[ 167.450000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:12288
[ 167.460000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:12856 (245192 bytes left)
[ 167.470000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:12856
[ 167.480000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3500 bytes
padded at LEB 1682:12856, offset now 16384
[ 167.490000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:16384 (241664 bytes left)
[ 167.500000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:16384
[ 167.510000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:16928 (241120 bytes left)
[ 167.510000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:16928
[ 167.520000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3524 bytes
padded at LEB 1682:16928, offset now 20480
[ 167.530000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:20480 (237568 bytes left)
[ 167.540000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:20480
[ 167.550000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:21096 (236952 bytes left)
[ 167.560000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:21096
[ 167.570000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3452 bytes
padded at LEB 1682:21096, offset now 24576
[ 167.580000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:24576 (233472 bytes left)
[ 167.580000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:24576
[ 167.590000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:24912 (233136 bytes left)
[ 167.600000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:24912
[ 167.610000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3732 bytes
padded at LEB 1682:24912, offset now 28672
[ 167.620000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:28672 (229376 bytes left)
[ 167.630000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:28672
[ 167.640000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:29952 (228096 bytes left)
[ 167.650000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:29952
[ 167.650000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 2788 bytes
padded at LEB 1682:29952, offset now 32768
[ 167.660000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:32768 (225280 bytes left)
[ 167.670000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:32768
[ 167.680000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:34560 (223488 bytes left)
[ 167.690000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:34560
[ 167.700000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 2276 bytes
padded at LEB 1682:34560, offset now 36864
[ 167.710000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:36864 (221184 bytes left)
[ 167.720000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:36864
[ 167.730000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:38152 (219896 bytes left)
[ 167.730000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:38152
[ 167.740000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 2780 bytes
padded at LEB 1682:38152, offset now 40960
[ 167.750000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:40960 (217088 bytes left)
[ 167.760000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:40960
[ 167.770000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:41632 (216416 bytes left)
[ 167.780000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:41632
[ 167.790000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3396 bytes
padded at LEB 1682:41632, offset now 45056
[ 167.800000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:45056 (212992 bytes left)
[ 167.800000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:45056
[ 167.810000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:45680 (212368 bytes left)
[ 167.820000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:45680
[ 167.830000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3444 bytes
padded at LEB 1682:45680, offset now 49152
[ 167.840000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:49152 (208896 bytes left)
[ 167.850000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:49152
[ 167.860000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:49744 (208304 bytes left)
[ 167.870000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:49744
[ 167.870000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3476 bytes
padded at LEB 1682:49744, offset now 53248
[ 167.880000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:53248 (204800 bytes left)
[ 167.890000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:53248
[ 167.900000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:53808 (204240 bytes left)
[ 167.910000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:53808
[ 167.920000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3508 bytes
padded at LEB 1682:53808, offset now 57344
[ 167.930000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:57344 (200704 bytes left)
[ 167.940000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:57344
[ 167.940000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:57720 (200328 bytes left)
[ 167.950000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:57720
[ 167.960000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3692 bytes
padded at LEB 1682:57720, offset now 61440
[ 167.970000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:61440 (196608 bytes left)
[ 167.980000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:61440
[ 167.990000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:62008 (196040 bytes left)
[ 168.000000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:62008
[ 168.010000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3500 bytes
padded at LEB 1682:62008, offset now 65536
[ 168.020000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:65536 (192512 bytes left)
[ 168.020000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:65536
[ 168.030000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:66112 (191936 bytes left)
[ 168.040000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:66112
[ 168.050000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3492 bytes
padded at LEB 1682:66112, offset now 69632
[ 168.060000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:69632 (188416 bytes left)
[ 168.070000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:69632
[ 168.080000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:70216 (187832 bytes left)
[ 168.090000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:70216
[ 168.090000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3484 bytes
padded at LEB 1682:70216, offset now 73728
[ 168.100000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:73728 (184320 bytes left)
[ 168.110000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:73728
[ 168.120000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:73920 (184128 bytes left)
[ 168.130000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:73920
[ 168.140000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3876 bytes
padded at LEB 1682:73920, offset now 77824
[ 168.150000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:77824 (180224 bytes left)
[ 168.160000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:77824
[ 168.160000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:78072 (179976 bytes left)
[ 168.170000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:78072
[ 168.180000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3820 bytes
padded at LEB 1682:78072, offset now 81920
[ 168.190000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:81920 (176128 bytes left)
[ 168.200000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:81920
[ 168.210000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:82216 (175832 bytes left)
[ 168.220000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:82216
[ 168.230000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3772 bytes
padded at LEB 1682:82216, offset now 86016
[ 168.240000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:86016 (172032 bytes left)
[ 168.240000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:86016
[ 168.250000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:86288 (171760 bytes left)
[ 168.260000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:86288
[ 168.270000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3796 bytes
padded at LEB 1682:86288, offset now 90112
[ 168.280000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:90112 (167936 bytes left)
[ 168.290000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:90112
[ 168.300000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:90360 (167688 bytes left)
[ 168.310000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:90360
[ 168.310000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3820 bytes
padded at LEB 1682:90360, offset now 94208
[ 168.320000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:94208 (163840 bytes left)
[ 168.330000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:94208
[ 168.340000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:96184 (161864 bytes left)
[ 168.350000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:96184
[ 168.360000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 2092 bytes
padded at LEB 1682:96184, offset now 98304
[ 168.370000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:98304 (159744 bytes left)
[ 168.380000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:98304
[ 168.380000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:100040 (158008 bytes left)
[ 168.390000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:100040
[ 168.400000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 2332 bytes
padded at LEB 1682:100040, offset now 102400
[ 168.410000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:102400 (155648 bytes left)
[ 168.420000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:102400
[ 168.430000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:103656 (154392 bytes left)
[ 168.440000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:103656
[ 168.450000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 2812 bytes
padded at LEB 1682:103656, offset now 106496
[ 168.460000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:106496 (151552 bytes left)
[ 168.470000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:106496
[ 168.470000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:107808 (150240 bytes left)
[ 168.480000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:107808
[ 168.490000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 2756 bytes
padded at LEB 1682:107808, offset now 110592
[ 168.500000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:110592 (147456 bytes left)
[ 168.510000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:110592
[ 168.520000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:111600 (146448 bytes left)
[ 168.530000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:111600
[ 168.540000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3060 bytes
padded at LEB 1682:111600, offset now 114688
[ 168.550000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:114688 (143360 bytes left)
[ 168.550000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:114688
[ 168.560000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:115200 (142848 bytes left)
[ 168.570000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:115200
[ 168.580000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3556 bytes
padded at LEB 1682:115200, offset now 118784
[ 168.590000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:118784 (139264 bytes left)
[ 168.600000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:118784
[ 168.610000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:118920 (139128 bytes left)
[ 168.620000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:118920
[ 168.620000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3932 bytes
padded at LEB 1682:118920, offset now 122880
[ 168.630000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:122880 (135168 bytes left)
[ 168.640000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:122880
[ 168.650000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:122984 (135064 bytes left)
[ 168.660000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:122984
[ 168.670000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3964 bytes
padded at LEB 1682:122984, offset now 126976
[ 168.680000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:126976 (131072 bytes left)
[ 168.690000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:126976
[ 168.700000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:127576 (130472 bytes left)
[ 168.700000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:127576
[ 168.710000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3468 bytes
padded at LEB 1682:127576, offset now 131072
[ 168.720000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:131072 (126976 bytes left)
[ 168.730000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:131072
[ 168.740000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:131912 (126136 bytes left)
[ 168.750000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:131912
[ 168.760000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:134552 (123496 bytes left)
[ 168.770000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:134552
[ 168.770000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 588 bytes
padded at LEB 1682:134552, offset now 135168
[ 168.780000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:135168 (122880 bytes left)
[ 168.790000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:135168
[ 168.800000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:139176 (118872 bytes left)
[ 168.810000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:139176
[ 168.820000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:143320 (114728 bytes left)
[ 168.830000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:143320
[ 168.840000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 12 bytes
padded at LEB 1682:143320, offset now 143360
[ 168.850000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:143360 (114688 bytes left)
[ 168.850000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:143360
[ 168.860000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:145392 (112656 bytes left)
[ 168.870000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:145392
[ 168.880000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:147888 (110160 bytes left)
[ 168.890000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:147888
[ 168.900000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3636 bytes
padded at LEB 1682:147888, offset now 151552
[ 168.910000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:151552 (106496 bytes left)
[ 168.920000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:151552
[ 168.920000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:153120 (104928 bytes left)
[ 168.930000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:153120
[ 168.940000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:154408 (103640 bytes left)
[ 168.950000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:154408
[ 168.960000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 1212 bytes
padded at LEB 1682:154408, offset now 155648
[ 168.970000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:155648 (102400 bytes left)
[ 168.980000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:155648
[ 168.990000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:156944 (101104 bytes left)
[ 168.990000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:156944
[ 169.000000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:158312 (99736 bytes left)
[ 169.010000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:158312
[ 169.020000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 1404 bytes
padded at LEB 1682:158312, offset now 159744
[ 169.030000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:159744 (98304 bytes left)
[ 169.040000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:159744
[ 169.050000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:161120 (96928 bytes left)
[ 169.060000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:161120
[ 169.060000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:163032 (95016 bytes left)
[ 169.070000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:163032
[ 169.080000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 780 bytes
padded at LEB 1682:163032, offset now 163840
[ 169.090000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:163840 (94208 bytes left)
[ 169.100000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:163840
[ 169.110000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:165760 (92288 bytes left)
[ 169.120000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:165760
[ 169.130000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:167744 (90304 bytes left)
[ 169.130000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:167744
[ 169.140000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 164 bytes
padded at LEB 1682:167744, offset now 167936
[ 169.150000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:167936 (90112 bytes left)
[ 169.160000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:167936
[ 169.170000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:169936 (88112 bytes left)
[ 169.180000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:169936
[ 169.190000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:170856 (87192 bytes left)
[ 169.200000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:170856
[ 169.200000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 1148 bytes
padded at LEB 1682:170856, offset now 172032
[ 169.210000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:172032 (86016 bytes left)
[ 169.220000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:172032
[ 169.230000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:173408 (84640 bytes left)
[ 169.240000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:173408
[ 169.250000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:175248 (82800 bytes left)
[ 169.260000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:175248
[ 169.270000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 852 bytes
padded at LEB 1682:175248, offset now 176128
[ 169.280000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:176128 (81920 bytes left)
[ 169.280000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:176128
[ 169.290000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:177984 (80064 bytes left)
[ 169.300000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:177984
[ 169.310000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:178800 (79248 bytes left)
[ 169.320000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:178800
[ 169.330000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 1396 bytes
padded at LEB 1682:178800, offset now 180224
[ 169.340000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:180224 (77824 bytes left)
[ 169.350000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:180224
[ 169.350000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:181208 (76840 bytes left)
[ 169.360000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:181208
[ 169.370000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:182632 (75416 bytes left)
[ 169.380000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:182632
[ 169.390000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 1660 bytes
padded at LEB 1682:182632, offset now 184320
[ 169.400000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:184320 (73728 bytes left)
[ 169.410000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:184320
[ 169.420000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:185760 (72288 bytes left)
[ 169.420000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:185760
[ 169.430000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:186608 (71440 bytes left)
[ 169.440000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:186608
[ 169.450000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 1780 bytes
padded at LEB 1682:186608, offset now 188416
[ 169.460000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:188416 (69632 bytes left)
[ 169.470000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:188416
[ 169.480000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:188928 (69120 bytes left)
[ 169.490000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:188928
[ 169.490000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:189600 (68448 bytes left)
[ 169.500000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:189600
[ 169.510000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 2884 bytes
padded at LEB 1682:189600, offset now 192512
[ 169.520000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:192512 (65536 bytes left)
[ 169.530000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:192512
[ 169.540000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:193184 (64864 bytes left)
[ 169.550000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:193184
[ 169.550000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:193520 (64528 bytes left)
[ 169.560000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:193520
[ 169.570000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3060 bytes
padded at LEB 1682:193520, offset now 196608
[ 169.580000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:196608 (61440 bytes left)
[ 169.590000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:196608
[ 169.600000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:196944 (61104 bytes left)
[ 169.610000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:196944
[ 169.620000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:197120 (60928 bytes left)
[ 169.620000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:197120
[ 169.630000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3556 bytes
padded at LEB 1682:197120, offset now 200704
[ 169.640000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:200704 (57344 bytes left)
[ 169.650000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:200704
[ 169.660000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:200888 (57160 bytes left)
[ 169.670000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:200888
[ 169.680000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:201016 (57032 bytes left)
[ 169.690000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:201016
[ 169.690000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3756 bytes
padded at LEB 1682:201016, offset now 204800
[ 169.700000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:204800 (53248 bytes left)
[ 169.710000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:204800
[ 169.720000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:205056 (52992 bytes left)
[ 169.730000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:205056
[ 169.740000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3812 bytes
padded at LEB 1682:205056, offset now 208896
[ 169.750000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:208896 (49152 bytes left)
[ 169.760000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:208896
[ 169.770000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:209160 (48888 bytes left)
[ 169.770000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:209160
[ 169.780000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3804 bytes
padded at LEB 1682:209160, offset now 212992
[ 169.790000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:212992 (45056 bytes left)
[ 169.800000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:212992
[ 169.810000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:213560 (44488 bytes left)
[ 169.820000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:213560
[ 169.830000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3500 bytes
padded at LEB 1682:213560, offset now 217088
[ 169.840000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:217088 (40960 bytes left)
[ 169.850000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:217088
[ 169.850000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:217864 (40184 bytes left)
[ 169.860000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:217864
[ 169.870000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3292 bytes
padded at LEB 1682:217864, offset now 221184
[ 169.880000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:221184 (36864 bytes left)
[ 169.890000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:221184
[ 169.900000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:221616 (36432 bytes left)
[ 169.910000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:221616
[ 169.920000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3636 bytes
padded at LEB 1682:221616, offset now 225280
[ 169.930000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:225280 (32768 bytes left)
[ 169.930000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:225280
[ 169.940000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:225696 (32352 bytes left)
[ 169.950000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:225696
[ 169.960000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3652 bytes
padded at LEB 1682:225696, offset now 229376
[ 169.970000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:229376 (28672 bytes left)
[ 169.980000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:229376
[ 169.990000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:229816 (28232 bytes left)
[ 170.000000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:229816
[ 170.000000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3628 bytes
padded at LEB 1682:229816, offset now 233472
[ 170.010000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:233472 (24576 bytes left)
[ 170.020000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:233472
[ 170.030000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:234048 (24000 bytes left)
[ 170.040000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:234048
[ 170.050000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3492 bytes
padded at LEB 1682:234048, offset now 237568
[ 170.060000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:237568 (20480 bytes left)
[ 170.070000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:237568
[ 170.080000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:238112 (19936 bytes left)
[ 170.080000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:238112
[ 170.090000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3524 bytes
padded at LEB 1682:238112, offset now 241664
[ 170.100000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:241664 (16384 bytes left)
[ 170.110000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:241664
[ 170.120000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:242152 (15896 bytes left)
[ 170.130000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:242152
[ 170.140000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3580 bytes
padded at LEB 1682:242152, offset now 245760
[ 170.150000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:245760 (12288 bytes left)
[ 170.160000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:245760
[ 170.160000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:246296 (11752 bytes left)
[ 170.170000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:246296
[ 170.180000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3532 bytes
padded at LEB 1682:246296, offset now 249856
[ 170.190000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:249856 (8192 bytes left)
[ 170.200000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:249856
[ 170.210000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:250280 (7768 bytes left)
[ 170.220000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:250280
[ 170.230000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3644 bytes
padded at LEB 1682:250280, offset now 253952
[ 170.240000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:253952 (4096 bytes left)
[ 170.240000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning data
node at LEB 1682:253952
[ 170.250000] ubifs_scan: UBIFS DBG scan (pid 59): look at LEB
1682:254392 (3656 bytes left)
[ 170.260000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): scanning
padding node at LEB 1682:254392
[ 170.270000] ubifs_scan_a_node: UBIFS DBG scan (pid 59): 3628 bytes
padded at LEB 1682:254392, offset now 258048
[ 170.280000] ubifs_end_scan: UBIFS DBG scan (pid 59): stop scanning
LEB 1682 at offset 258048
[ 170.290000] ubifs_garbage_collect_leb: UBIFS DBG gc (pid 59): data
LEB 1682 (free 0, dirty 253904)
[ 170.300000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12731)
[ 170.310000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 1215:9992,
indexing node, length 188
[ 170.310000] read_znode: UBIFS DBG tnc (pid 59): LEB 1215:9992, level
3, 8 branch
[ 170.320000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 485:12872,
indexing node, length 188
[ 170.330000] read_znode: UBIFS DBG tnc (pid 59): LEB 485:12872, level
2, 8 branch
[ 170.340000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 1775:16104,
indexing node, length 188
[ 170.350000] read_znode: UBIFS DBG tnc (pid 59): LEB 1775:16104, level
1, 8 branch
[ 170.360000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 1775:15912,
indexing node, length 188
[ 170.370000] read_znode: UBIFS DBG tnc (pid 59): LEB 1775:15912, level
0, 8 branch
[ 170.370000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 1
[ 170.380000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12731)
[ 170.390000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 1
[ 170.390000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12731)
[ 170.400000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 1
[ 170.410000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12732)
[ 170.420000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 2
[ 170.420000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12732)
[ 170.430000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 2
[ 170.440000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12732)
[ 170.450000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 2
[ 170.450000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12732)
[ 170.460000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 2
[ 170.470000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12733)
[ 170.480000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 3
[ 170.480000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12733)
[ 170.490000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 3
[ 170.500000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12733)
[ 170.510000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 3
[ 170.510000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12733)
[ 170.520000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 3
[ 170.530000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12734)
[ 170.540000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 4
[ 170.540000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12734)
[ 170.550000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 4
[ 170.560000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12734)
[ 170.570000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 4
[ 170.570000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12734)
[ 170.580000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 4
[ 170.590000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12735)
[ 170.600000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 5
[ 170.600000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12735)
[ 170.610000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 5
[ 170.620000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12735)
[ 170.630000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 5
[ 170.630000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12735)
[ 170.640000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 5
[ 170.650000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12736)
[ 170.660000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 6
[ 170.660000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12736)
[ 170.670000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 6
[ 170.680000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12736)
[ 170.690000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 6
[ 170.690000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12736)
[ 170.700000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 6
[ 170.710000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12737)
[ 170.720000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 7
[ 170.720000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12737)
[ 170.730000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 7
[ 170.740000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12737)
[ 170.750000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 7
[ 170.750000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12737)
[ 170.760000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 7
[ 170.770000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12738)
[ 170.780000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 485:10952,
indexing node, length 188
[ 170.790000] read_znode: UBIFS DBG tnc (pid 59): LEB 485:10952, level
1, 8 branch
[ 170.790000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 1775:16296,
indexing node, length 188
[ 170.800000] read_znode: UBIFS DBG tnc (pid 59): LEB 1775:16296, level
0, 8 branch
[ 170.810000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 0
[ 170.820000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12738)
[ 170.830000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 0
[ 170.830000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12738)
[ 170.840000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 0
[ 170.850000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12738)
[ 170.850000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 0
[ 170.860000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12739)
[ 170.870000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 1
[ 170.880000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 2180)
[ 170.880000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 1215:4616,
indexing node, length 188
[ 170.890000] read_znode: UBIFS DBG tnc (pid 59): LEB 1215:4616, level
3, 8 branch
[ 170.900000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 1215:4424,
indexing node, length 188
[ 170.910000] read_znode: UBIFS DBG tnc (pid 59): LEB 1215:4424, level
2, 8 branch
[ 170.920000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 1215:4232,
indexing node, length 188
[ 170.930000] read_znode: UBIFS DBG tnc (pid 59): LEB 1215:4232, level
1, 8 branch
[ 170.930000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 1215:3848,
indexing node, length 188
[ 170.940000] read_znode: UBIFS DBG tnc (pid 59): LEB 1215:3848, level
0, 8 branch
[ 170.950000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 2
[ 170.960000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 2181)
[ 170.970000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 3
[ 170.970000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 2247)
[ 170.980000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 1775:3048,
indexing node, length 188
[ 170.990000] read_znode: UBIFS DBG tnc (pid 59): LEB 1775:3048, level
1, 8 branch
[ 171.000000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 1775:2856,
indexing node, length 188
[ 171.010000] read_znode: UBIFS DBG tnc (pid 59): LEB 1775:2856, level
0, 8 branch
[ 171.010000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 5
[ 171.020000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 2248)
[ 171.030000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 6
[ 171.040000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19026)
[ 171.040000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 1215:10952,
indexing node, length 188
[ 171.050000] read_znode: UBIFS DBG tnc (pid 59): LEB 1215:10952, level
3, 8 branch
[ 171.060000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 1215:10760,
indexing node, length 188
[ 171.070000] read_znode: UBIFS DBG tnc (pid 59): LEB 1215:10760, level
2, 8 branch
[ 171.080000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 1215:10568,
indexing node, length 188
[ 171.090000] read_znode: UBIFS DBG tnc (pid 59): LEB 1215:10568, level
1, 8 branch
[ 171.090000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 1775:20904,
indexing node, length 188
[ 171.100000] read_znode: UBIFS DBG tnc (pid 59): LEB 1775:20904, level
0, 8 branch
[ 171.110000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 0
[ 171.120000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19027)
[ 171.130000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 1
[ 171.130000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19029)
[ 171.140000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 3
[ 171.150000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19030)
[ 171.150000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 4
[ 171.160000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19030)
[ 171.170000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 4
[ 171.180000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19031)
[ 171.180000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 5
[ 171.190000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19031)
[ 171.200000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 5
[ 171.210000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19032)
[ 171.210000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 6
[ 171.220000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19032)
[ 171.230000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 6
[ 171.240000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19033)
[ 171.240000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 7
[ 171.250000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19033)
[ 171.260000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 7
[ 171.270000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19034)
[ 171.270000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 1431:22248,
indexing node, length 188
[ 171.280000] read_znode: UBIFS DBG tnc (pid 59): LEB 1431:22248, level
0, 8 branch
[ 171.290000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 0
[ 171.300000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19035)
[ 171.310000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 1
[ 171.310000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19036)
[ 171.320000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 2
[ 171.330000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19036)
[ 171.340000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 2
[ 171.340000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19037)
[ 171.350000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 3
[ 171.360000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19038)
[ 171.370000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 4
[ 171.370000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19039)
[ 171.380000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 5
[ 171.390000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19039)
[ 171.400000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 5
[ 171.400000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19040)
[ 171.410000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 6
[ 171.420000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19042)
[ 171.430000] ubifs_read_node: UBIFS DBG io (pid 59): LEB 1215:10184,
indexing node, length 188
[ 171.430000] read_znode: UBIFS DBG tnc (pid 59): LEB 1215:10184, level
0, 8 branch
[ 171.440000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 0
[ 171.450000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19043)
[ 171.460000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 1
[ 171.460000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19043)
[ 171.470000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 1
[ 171.480000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19044)
[ 171.490000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 2
[ 171.490000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19044)
[ 171.500000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 2
[ 171.510000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19045)
[ 171.520000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 3
[ 171.520000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19045)
[ 171.530000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 3
[ 171.540000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 19046)
[ 171.550000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 4
[ 171.550000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12739)
[ 171.560000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 1
[ 171.570000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12739)
[ 171.580000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 1
[ 171.580000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12739)
[ 171.590000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 1
[ 171.600000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12740)
[ 171.610000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 2
[ 171.610000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12740)
[ 171.620000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 2
[ 171.630000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12740)
[ 171.640000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 2
[ 171.640000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12740)
[ 171.650000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 2
[ 171.660000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12741)
[ 171.670000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 3
[ 171.670000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12741)
[ 171.680000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 3
[ 171.690000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12741)
[ 171.700000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 3
[ 171.700000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12741)
[ 171.710000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 3
[ 171.720000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12742)
[ 171.730000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 4
[ 171.730000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): search key
(76, data, 12742)
[ 171.740000] ubifs_lookup_level0: UBIFS DBG tnc (pid 59): found 1, lvl
0, n 4
[ 171.750000] wbuf_timer_callback_nolock: UBIFS DBG io (pid 59): jhead
1 (base)
[ 171.760000] ubifs_bg_wbufs_sync: UBIFS DBG io (pid 82): synchronize
[ 171.760000] ubifs_wbuf_sync_nolock: UBIFS DBG io (pid 82): LEB
926:40960, 160 bytes, jhead 1 (base)
[ 171.770000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 82): LEB 926,
free 216928, dirty 39816, flags 16
[ 171.780000] ubifs_change_lp: UBIFS DBG lp (pid 82): LEB 926, free
212992, dirty 43752, flags 16
[ 171.790000] ubifs_lpt_lookup_dirty: UBIFS DBG lp (pid 82): LEB 926,
free 216928, dirty 39816, flags 16
[ 171.800000] ubifs_wbuf_write_nolock: UBIFS DBG io (pid 59): 4144
bytes (data node) to jhead 0 (GC) wbuf at LEB 644:208896
[ 171.810000] ubifs_wbuf_write_nolock: UBIFS DBG io (pid 59): write
4096 bytes to LEB 644:208896
[ 172.450000] watchdog watchdog0: watchdog did not stop!
[ 177.600000] watchdog watchdog0: watchdog did not stop!
[ 183.160000] watchdog watchdog0: watchdog did not stop!
[ 188.350000] watchdog watchdog0: watchdog did not stop!
[snip] only 5s apart watchdog messages
[ 312.050000] watchdog watchdog0: watchdog did not stop!
[ 317.190000] watchdog watchdog0: watchdog did not stop!
[ 322.470000] watchdog watchdog0: watchdog did not stop!
/ # [ 328.220000] watchdog watchdog0: watchdog did not stop!
/ # ls -l /mnt/images/test
[ 332.810000] ubifs_readdir: UBIFS DBG gen (pid 149): dir ino 249,
f_pos 0x0
[ 332.820000] ubifs_tnc_next_ent: UBIFS DBG tnc (pid 149): (lowest)
(249, direntry, 0x000000)
[ 332.820000] ubifs_lookup_level0: UBIFS DBG tnc (pid 149): search key
(249, direntry, 0x000000)
[ 332.830000] ubifs_lookup_level0: UBIFS DBG tnc (pid 149): found 0,
lvl 0, n 2
[ 332.840000] ubifs_read_node_wbuf: UBIFS DBG io (pid 149): LEB
926:20480, direntry node, length 67, jhead 1 (base)
[ 332.850000] ubifs_read_node: UBIFS DBG io (pid 149): LEB 926:20480,
direntry node, length 67
[ 333.270000] watchdog watchdog0: watchdog did not stop!
[ 338.300000] watchdog watchdog0: watchdog did not stop!
[snip] only 5s apart watchdog messages
[ 480.130000] INFO: task ls:149 blocked for more than 120 seconds.
[ 480.130000] Not tainted 4.4.66-SEM-MK10 #15
[ 480.140000] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this message.
[ 480.140000] ls D c044cc28 0 149 1 0x00000000
[ 480.150000] Backtrace:
[ 480.150000] [<c044ca14>] (__schedule) from [<c044cf9c>]
(schedule+0xa0/0xb8)
[ 480.160000] r10:c2312000 r9:c25b9aa8 r8:c22b4a98 r7:00000015
r6:ffffe000 r5:c20fa818
[ 480.170000] r4:c25b8000
[ 480.170000] [<c044cefc>] (schedule) from [<c02e5ec8>]
(nand_get_device+0xf8/0x110)
[ 480.180000] r5:c20fa818 r4:c22b4a90
[ 480.180000] [<c02e5dd0>] (nand_get_device) from [<c02ea410>]
(nand_read+0x28/0x84)
[ 480.190000] r9:00000000 r8:01400000 r7:00000000 r6:08c06000
r5:c25b9af4 r4:c20fab70
[ 480.200000] [<c02ea3e8>] (nand_read) from [<c02df6fc>]
(part_read+0x50/0x88)
[ 480.200000] r7:000001e0 r6:00000000 r5:00000000 r4:c2312000
[ 480.210000] [<c02df6ac>] (part_read) from [<c02dcca0>]
(mtd_read+0x6c/0xa4)
[ 480.220000] r9:00000000 r8:07806000 r6:00000043 r5:00000000 r4:173fa000
[ 480.220000] [<c02dcc34>] (mtd_read) from [<c02dcd28>]
(mtd_read_slc_mode+0x50/0xe0)
[ 480.230000] r10:c25b9c24 r9:00000000 r8:07806000 r7:000001e0
r6:00000043 r5:00000043
[ 480.240000] r4:c2312000
[ 480.240000] [<c02dccd8>] (mtd_read_slc_mode) from [<c02fe8fc>]
(ubi_io_read+0x168/0x338)
[ 480.250000] r10:c2528980 r9:00000004 r8:00006000 r7:000001e0
r6:00000043 r5:c302d000
[ 480.260000] r4:c302d000
[ 480.260000] [<c02fe794>] (ubi_io_read) from [<c02fc484>]
(ubi_eba_read_leb+0x344/0x458)
[ 480.270000] r10:c23ca400 r9:000001e0 r8:00000000 r7:00000043
r6:0000039e r5:00000000
[ 480.280000] r4:c302d000
[ 480.280000] [<c02fc140>] (ubi_eba_read_leb) from [<c02fafe0>]
(ubi_leb_read+0xc4/0x114)
[ 480.290000] r10:00000000 r9:c302d000 r8:00005000 r7:0000039e
r6:00000043 r5:00000000
[ 480.290000] r4:c23ca400
[ 480.300000] [<c02faf1c>] (ubi_leb_read) from [<c01ae394>]
(ubifs_leb_read+0x34/0x98)
[ 480.310000] r10:00000002 r9:c2528980 r8:00005000 r7:0000039e
r6:00000043 r5:c302e000
[ 480.310000] r4:c302e000
[ 480.320000] [<c01ae360>] (ubifs_leb_read) from [<c01b0324>]
(ubifs_read_node+0x18c/0x340)
[ 480.320000] r8:00000043 r7:c092d2d0 r6:0000039e r5:00005000 r4:c302e000
[ 480.330000] [<c01b0198>] (ubifs_read_node) from [<c01b0684>]
(ubifs_read_node_wbuf+0x1ac/0x378)
[ 480.340000] r10:00000043 r9:00000002 r8:c2528980 r7:0000039e
r6:c24496a0 r5:00005000
[ 480.350000] r4:c302e000
[ 480.350000] [<c01b04d8>] (ubifs_read_node_wbuf) from [<c01cefb4>]
(ubifs_tnc_read_node+0x54/0x1ec)
[ 480.360000] r10:c2528980 r9:40000000 r8:c302e314 r7:00000002
r6:c2528980 r5:c302e000
[ 480.370000] r4:c2524e78
[ 480.370000] [<c01cef60>] (ubifs_tnc_read_node) from [<c01b1700>]
(tnc_read_node_nm+0x1c0/0x1f4)
[ 480.380000] r8:c302e314 r7:c302e000 r6:c2528980 r5:c302e000 r4:c2524e78
[ 480.390000] [<c01b1540>] (tnc_read_node_nm) from [<c01b5404>]
(ubifs_tnc_next_ent+0x228/0x274)
[ 480.390000] r7:c25b9ee8 r6:00000048 r5:c302e000 r4:c2524e00
[ 480.400000] [<c01b51dc>] (ubifs_tnc_next_ent) from [<c01a7118>]
(ubifs_readdir+0x20c/0x4c4)
[ 480.410000] r10:c092cd48 r9:00000000 r8:c28c81c8 r7:c302e000
r6:c234e600 r5:c25b9f68
[ 480.420000] r4:c0102e8c
[ 480.420000] [<c01a6f0c>] (ubifs_readdir) from [<c0102cc0>]
(iterate_dir+0x74/0xf8)
[ 480.430000] r10:00000000 r9:c25b8000 r8:c28c8238 r7:c234e608
r6:00000000 r5:c25b9f68
[ 480.430000] r4:c234e600
[ 480.440000] [<c0102c4c>] (iterate_dir) from [<c0103158>]
(SyS_getdents64+0x84/0xe4)
[ 480.440000] r10:00000000 r9:c25b8000 r8:c000f8c4 r7:c234e600
r6:c234e600 r5:00008000
[ 480.450000] r4:000a80b0
[ 480.450000] [<c01030d4>] (SyS_getdents64) from [<c000f720>]
(ret_fast_syscall+0x0/0x34)
[ 480.460000] r7:000000d9 r6:b6f95570 r5:000a8094 r4:000a80b0
[ 483.050000] watchdog watchdog0: watchdog did not stop!
^ permalink raw reply [flat|nested] 2+ messages in thread
* Re: Writing to UBIFS leads to all threads accessing files are stuck in nand_get_device
2018-07-13 11:04 Writing to UBIFS leads to all threads accessing files are stuck in nand_get_device Sebastian Lassacher
@ 2018-07-13 20:39 ` Richard Weinberger
0 siblings, 0 replies; 2+ messages in thread
From: Richard Weinberger @ 2018-07-13 20:39 UTC (permalink / raw)
To: Sebastian Lassacher; +Cc: linux-mtd @ lists . infradead . org
Sebastian,
On Fri, Jul 13, 2018 at 1:04 PM, Sebastian Lassacher
<sebastian@lassacher.co.at> wrote:
> Hi there,
>
> I ran into troubles when writing files on an UBIFS volume.
> This happens on three different systems with similar conditions.
> Since they where installed on customer sides I'm not sure what caused
> the problems in the first place.
> My theory is that all three suffered from a power cut.
>
> As reported by the command df there should be enough space:
> Filesystem Size Used Available Use% Mounted on
> none 26.5M 0 26.5M 0% /dev
> ubi0:root 441.6M 101.4M 340.2M 23% /mnt/images
>
> But when I write to to the UBIFS, some processes get stuck, for instance:
> From ash inside an initramfs those commands working fine and no errors
> are reported:
> $ mount -t ubifs -o noatime,nodiratime,chk_data_crc ubi0:root /mnt/images
> $ ls -l /mnt/images/test
> $ dd if=/dev/urandom of=/mnt/images/test/urandom_1k bs=1k count=1
>
> After a few minutes I ran to be sure the the background sync has already
> started:
> $ ls -l /mnt/images/test
> and it did not return which later on got me this:
> [ 480.130000] INFO: task ls:149 blocked for more than 120 seconds.
> [ 480.130000] Not tainted 4.4.66-SEM #15
> [ 480.140000] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [ 480.140000] ls D c044cc28 0 149 1 0x00000000
> [ 480.150000] Backtrace:
> [ 480.150000] [<c044ca14>] (__schedule) from [<c044cf9c>]
> (schedule+0xa0/0xb8)
> [ 480.160000] r10:c2312000 r9:c25b9aa8 r8:c22b4a98 r7:00000015
> r6:ffffe000 r5:c20fa818
> [ 480.170000] r4:c25b8000
> [ 480.170000] [<c044cefc>] (schedule) from [<c02e5ec8>]
> (nand_get_device+0xf8/0x110)
> [ 480.180000] r5:c20fa818 r4:c22b4a90
> [ 480.180000] [<c02e5dd0>] (nand_get_device) from [<c02ea410>]
> (nand_read+0x28/0x84)
> [ 480.190000] r9:00000000 r8:01400000 r7:00000000 r6:08c06000
> r5:c25b9af4 r4:c20fab70
> [ 480.200000] [<c02ea3e8>] (nand_read) from [<c02df6fc>]
> (part_read+0x50/0x88)
> [ 480.200000] r7:000001e0 r6:00000000 r5:00000000 r4:c2312000
> [ 480.210000] [<c02df6ac>] (part_read) from [<c02dcca0>]
> (mtd_read+0x6c/0xa4)
> [ 480.220000] r9:00000000 r8:07806000 r6:00000043 r5:00000000 r4:173fa000
> [ 480.220000] [<c02dcc34>] (mtd_read) from [<c02dcd28>]
> (mtd_read_slc_mode+0x50/0xe0)
> [ 480.230000] r10:c25b9c24 r9:00000000 r8:07806000 r7:000001e0
> r6:00000043 r5:00000043
> [ 480.240000] r4:c2312000
> [ 480.240000] [<c02dccd8>] (mtd_read_slc_mode) from [<c02fe8fc>]
Huh?
This function is not part of mainline.
Does the problem also arise on a pristine kernel?
--
Thanks,
//richard
^ permalink raw reply [flat|nested] 2+ messages in thread
end of thread, other threads:[~2018-07-13 20:40 UTC | newest]
Thread overview: 2+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2018-07-13 11:04 Writing to UBIFS leads to all threads accessing files are stuck in nand_get_device Sebastian Lassacher
2018-07-13 20:39 ` Richard Weinberger
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.