UBIFS does not mount after powerfail

UBIFS does not mount after powerfail

[Manfred Spraul]

Hello together,

in a power fail stress test with ubifs, I ran into 5 images that do not 
mount:

3x ubifs_check_node() fails, called by ubifs_garbage_collect_leb()
2x UBIFS assert failed in ubifs_rcvry_gc_commit at 1219 (pid 9570)
(and ~550 images with just asserts, the mount was successful)

The test was done using kernel 4.14.0-rc5+ #105 SMP PREEMPT.
The test involves create/rename/remove, using ecryptfs with xattr.

Are these known issues?
Are there any patches that I should apply?
What would you need to analyze them?

If you need the image (~32 MB), I'll try to find a suitable server.
Below is the dmesg output from mounting the first bad image.

For me, it appears as if ubifs_check_node() tries to access a node that 
is only partially written:

(from image hexdump):

> 00D61780   6C 00 00 00  09 00 00 00  04 00 00 00  E8 00 00 00  20 7B 
> 00 00  A0 00 00 00  67 9A 00 00  00 00 00 00  l............... 
> {......g.......
> 00D617A0   0C 00 00 00  10 AB 01 00  46 00 00 00  67 9A 00 00 0E 8D D5 
> 7B  E8 00 00 00  C0 7B 00 00  A0 00 00 00 ........F...g......{.....{......
> 00D617C0   68 9A 00 00  00 00 00 00  0C 00 00 00  00 B0 01 00 46 00 00 
> 00  68 9A 00 00  0E 8D D5 7B  00 00 00 00 h...............F...h......{....
> 00D617E0   31 18 10 06  FB 80 A3 3C  2A 1F 0C 00  00 00 00 00 6C 00 00 
> 00  09 00 00 00  04 00 01 00  55 00 00 00 1......<*.......l...........U...
> 00D61800   FF FF FF FF  FF FF FF FF  FF FF FF FF  FF FF FF FF FF FF FF 
> FF  FF FF FF FF  FF FF FF FF  FF FF FF FF ................................
> 00D61820   FF FF FF FF  FF FF FF FF  FF FF FF FF  FF FF FF FF FF FF FF 
> FF  FF FF FF FF  FF FF FF FF  FF FF FF FF ................................
The next write operation would have written this to 0xD16800:

> 00D617E0   31 18 10 06  FB 80 A3 3C  2A 1F 0C 00  00 00 00 00  6C 00 
> 00 00  09 00 00 00  04 00 01 00  55 00 00 00  
> 1......<*.......l...........U...
> 00D61800   20 06 00 00  6C 00 00 00  5F 9A 00 00  00 00 00 00 55 00 00 
> 00  90 06 00 00  6C 00 00 00  61 9A 00 00 ...l..._.......U.......l...a...
> 00D61820   00 00 00 00  55 00 00 00  00 07 00 00  6C 00 00 00 64 9A 00 
> 00  00 00 00 00  55 00 00 00  70 07 00 00 ....U.......l...d.......U...p...
> 00D61840   6C 00 00 00  67 9A 00 00  00 00 00 00  90 99 00 00 31 18 10 
> 06  A2 02 94 35  2B 1F 0C 00  00 00 00 00 l...g...........1......5+.......
> 00D61860   6C 00 00 00  09 00 00 00  04 00 02 00  55 00 00 00 50 01 00 
> 00  6C 00 00 00  3F 9A 00 00  00 00 00 00 l...........U...P...l...?.......
> 00D61880   55 00 00 00  80 03 00 00  6C 00 00 00  49 9A 00 00 00 00 00 
> 00  55 00 00 00  B0 05 00 00  6C 00 00 00 U.......l...I.......U.......l...
> 00D618A0   54 9A 00 00  00 00 00 00  55 00 00 00  E0 07 00 00 6C 00 00 
> 00  5F 9A 00 00  00 00 00 00  01 01 00 00 T.......U.......l..._...........
> 00D618C0   31 18 10 06  2C 7B AB 32  2C 1F 0C 00  00 00 00 00 6C 00 00 
> 00  09 00 00 00  04 00 00 00  0C 00 00 00 1...,{.2,.......l...............

Note:

The test was done with a modified mtdram device that creates a log of 
all write and erase operations, so the issues are not caused by HW issues.
The log contains checksum for everything, so the tool can't be the 
reason for the mount failure.
(and after 5 images, mount is again successful.)

168166: FUNC_WRITE_CHK(addr=0xd60800, len=0x800, chk=0x3335956be2). <<< 
last ok image
168167: FUNC_WRITE_CHK(addr=0xd61000, len=0x800, chk=0x34dad52f0e). <<< 
garbage_collect_leb
168168: FUNC_WRITE_CHK(addr=0xd61800, len=0x800, chk=0x350a12238d). <<< -"-
168169: FUNC_WRITE_CHK(addr=0xd62000, len=0x800, chk=0x3573a0893b). <<< -"-
168170: FUNC_WRITE_CHK(addr=0xd62800, len=0x800, chk=0x356a0366c2). <<< 
assert line 1219
168171: FUNC_WRITE_CHK(addr=0x1b34000, len=0x800, chk=0x3ca1e19062). <<< 
-"-
168172: FUNC_WRITE_CHK(addr=0xc35800, len=0x800, chk=0x334dc94b1f). <<< 
first ok image.

--

     Manfred

<<<<<<<<<<

[61821.708507] ubi0: attaching mtd0
[61821.710142] ubi0: scanning is finished
[61821.712212] ubi0: attached mtd0 (name "mtdram test device", size 32 MiB)
[61821.713285] ubi0: PEB size: 131072 bytes (128 KiB), LEB size: 126976 
bytes
[61821.714336] ubi0: min./max. I/O unit sizes: 2048/2048, sub-page size 2048
[61821.715394] ubi0: VID header offset: 2048 (aligned 2048), data 
offset: 4096
[61821.716425] ubi0: good PEBs: 256, bad PEBs: 0, corrupted PEBs: 0
[61821.717447] ubi0: user volume: 1, internal volumes: 1, max. volumes 
count: 128
[61821.718481] ubi0: max/mean erase counter: 85/32, WL threshold: 4096, 
image sequence number: 18852820
[61821.719566] ubi0: available PEBs: 4, total reserved PEBs: 252, PEBs 
reserved for bad PEB handling: 0
[61821.720694] ubi0: background thread "ubi_bgt0d" started, PID 9421
[61821.725737] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" started, 
PID 9428
[61821.725913] UBIFS (ubi0:0): recovery needed
[61821.743777] UBIFS error (ubi0:0 pid 9426): ubifs_check_node: bad CRC: 
calculated 0xbe6fe258, read 0x3ca380fb
[61821.744917] UBIFS error (ubi0:0 pid 9426): ubifs_check_node: bad node 
at LEB 85:2016
[61821.746174]  magic          0x6101831
[61821.747274]  crc            0x3ca380fb
[61821.748334]  node_type      9 (indexing node)
[61821.749379]  group_type     0 (no node group)
[61821.750404]  sqnum          794410
[61821.751401]  len            108
[61821.752379]  child_cnt      4
[61821.753341]  level          1
[61821.754288]  Branches:
[61821.755212]  0: LEB 85:-1 len -1 key (bad key type: 0xffffffff, 
0xffffffff)
[61821.756166]  1: LEB -1:-1 len -1 key (bad key type: 0xffffffff, 
0xffffffff)
[61821.757091]  2: LEB -1:-1 len -1 key (bad key type: 0xffffffff, 
0xffffffff)
[61821.758027]  3: LEB -1:-1 len -1 key (bad key type: 0xffffffff, 
0xffffffff)
[61821.758939] CPU: 1 PID: 9426 Comm: mount Not tainted 4.14.0-rc5+ #105
[61821.759848] Hardware name: Hewlett-Packard HP 350 G2/803A, BIOS F.13 
06/10/2015
[61821.760777] Call Trace:
[61821.761690]  ? dump_stack+0x67/0x8e
[61821.762610]  ? ubifs_check_node+0x1b1/0x1be
[61821.763539]  ? ubifs_scan_a_node+0x153/0x223
[61821.764466]  ? ubifs_scan+0xab/0x23a
[61821.765387]  ? ubifs_garbage_collect_leb+0x1dd/0x735
[61821.766323]  ? ubifs_rcvry_gc_commit+0x1bc/0x27b
[61821.767254]  ? ubifs_mount+0x1050/0x1ad4
[61821.768177]  ? sb_set+0x13/0x13
[61821.769086]  ? mount_fs+0x6a/0x109
[61821.769975]  ? vfs_kern_mount+0x6b/0x151
[61821.770851]  ? do_mount+0x914/0xb60
[61821.771701]  ? memdup_user+0x3e/0x5a
[61821.772523]  ? SyS_mount+0x72/0x98
[61821.773317]  ? entry_SYSCALL_64_fastpath+0x1c/0xb1
[61821.774138] UBIFS error (ubi0:0 pid 9426): ubifs_scan: bad node
[61821.774974] UBIFS error (ubi0:0 pid 9426): ubifs_scanned_corruption: 
corruption at LEB 85:2016
[61821.775820] UBIFS error (ubi0:0 pid 9426): ubifs_scanned_corruption: 
first 8192 bytes from LEB 85:2016
[61821.776679] 00000000: 06101831 3ca380fb 000c1f2a 00000000 0000006c 
00000009 00010004 00000055 1......<*.......l...........U...
[61821.776683] 00000020: ffffffff ffffffff ffffffff ffffffff ffffffff 
ffffffff ffffffff ffffffff ................................
[61821.776687] 00000040: ffffffff ffffffff ffffffff ffffffff ffffffff 
ffffffff ffffffff ffffffff ................................
[... all 0xff 0xff]
[61821.777645] UBIFS error (ubi0:0 pid 9426): ubifs_scan: LEB 85 
scanning failed
[61821.778501] UBIFS error (ubi0:0 pid 9426): ubifs_rcvry_gc_commit: GC 
failed, error -117
[61821.779948] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" stops
<<<<<<<<<<

Re: UBIFS does not mount after powerfail

[Richard Weinberger]

Manfred,

Am Sonntag, 12. November 2017, 20:26:38 CET schrieb Manfred Spraul:
> Hello together,
> 
> in a power fail stress test with ubifs, I ran into 5 images that do not
> mount:
> 
> 3x ubifs_check_node() fails, called by ubifs_garbage_collect_leb()
> 2x UBIFS assert failed in ubifs_rcvry_gc_commit at 1219 (pid 9570)
> (and ~550 images with just asserts, the mount was successful)
> 
> The test was done using kernel 4.14.0-rc5+ #105 SMP PREEMPT.
> The test involves create/rename/remove, using ecryptfs with xattr.
> 
> Are these known issues?

Nope.
This should not happen at all. ;-(

> Are there any patches that I should apply?

No, 4.14 is recent.

> What would you need to analyze them?

Can you retry without encryptfs and xattr?
xattrs had already some issues, maybe there are more.
Just as a start to know where to start digging.

> If you need the image (~32 MB), I'll try to find a suitable server.
> Below is the dmesg output from mounting the first bad image.

An image would be good but I need one with the 1st generation error.
Can you please make sure the the UBIFS debugfs knob "chk_fs" is set?
If set, UBIFS will check the whole filesystem while mounting.

Thanks,
//richard

Re: UBIFS does not mount after powerfail

[Manfred Spraul]

On 11/12/2017 08:54 PM, Richard Weinberger wrote:
> Manfred,
>
> Am Sonntag, 12. November 2017, 20:26:38 CET schrieb Manfred Spraul:
>> Hello together,
>>
>> in a power fail stress test with ubifs, I ran into 5 images that do not
>> mount:
>>
>> 3x ubifs_check_node() fails, called by ubifs_garbage_collect_leb()
>> 2x UBIFS assert failed in ubifs_rcvry_gc_commit at 1219 (pid 9570)
>> (and ~550 images with just asserts, the mount was successful)
>>
>> The test was done using kernel 4.14.0-rc5+ #105 SMP PREEMPT.
>> The test involves create/rename/remove, using ecryptfs with xattr.
>>
>> Are these known issues?
> Nope.
> This should not happen at all. ;-(
>
>> Are there any patches that I should apply?
> No, 4.14 is recent.
>
>> What would you need to analyze them?
> Can you retry without encryptfs and xattr?
> xattrs had already some issues, maybe there are more.
> Just as a start to know where to start digging.
I'll try to do that, but it will take a few days (the tests run at ~800 
power fails per hour)

>> If you need the image (~32 MB), I'll try to find a suitable server.
>> Below is the dmesg output from mounting the first bad image.
> An image would be good but I need one with the 1st generation error.
What do you mean with 1st generation error?
The image prior to the first mount after power fail? I have that.

And I found a server:

https://sourceforge.net/projects/calculix-rpm/files/ubifs/

image-168167.bin is the last image that mounts.
then the next 5 images do not mount
then the next image mounts again.
The images are for mtdram, with the min write size set to 2048.

> Can you please make sure the the UBIFS debugfs knob "chk_fs" is set?
> If set, UBIFS will check the whole filesystem while mounting.
Ok, I'll try that first - but it will take a few days as well.
> Thanks,
> //richard

Re: UBIFS does not mount after powerfail

[Richard Weinberger]

Manfred,

Am Montag, 13. November 2017, 21:59:18 CET schrieb Manfred Spraul:
> On 11/12/2017 08:54 PM, Richard Weinberger wrote:
> > Manfred,
> > 
> > Am Sonntag, 12. November 2017, 20:26:38 CET schrieb Manfred Spraul:
> >> Hello together,
> >> 
> >> in a power fail stress test with ubifs, I ran into 5 images that do not
> >> mount:
> >> 
> >> 3x ubifs_check_node() fails, called by ubifs_garbage_collect_leb()
> >> 2x UBIFS assert failed in ubifs_rcvry_gc_commit at 1219 (pid 9570)
> >> (and ~550 images with just asserts, the mount was successful)
> >> 
> >> The test was done using kernel 4.14.0-rc5+ #105 SMP PREEMPT.
> >> The test involves create/rename/remove, using ecryptfs with xattr.
> >> 
> >> Are these known issues?
> > 
> > Nope.
> > This should not happen at all. ;-(
> > 
> >> Are there any patches that I should apply?
> > 
> > No, 4.14 is recent.
> > 
> >> What would you need to analyze them?
> > 
> > Can you retry without encryptfs and xattr?
> > xattrs had already some issues, maybe there are more.
> > Just as a start to know where to start digging.
> 
> I'll try to do that, but it will take a few days (the tests run at ~800
> power fails per hour)

Pretty high rate. :-)

> >> If you need the image (~32 MB), I'll try to find a suitable server.
> >> Below is the dmesg output from mounting the first bad image.
> > 
> > An image would be good but I need one with the 1st generation error.
> 
> What do you mean with 1st generation error?
> The image prior to the first mount after power fail? I have that.

No, the image that contains the first logical error.
UBIFS can mount even if there are many problems with the structure.
Therefore I need you to enable chk_fs. 

> And I found a server:
> 
> https://sourceforge.net/projects/calculix-rpm/files/ubifs/
> 
> image-168167.bin is the last image that mounts.
> then the next 5 images do not mount
> then the next image mounts again.
> The images are for mtdram, with the min write size set to 2048.
> 
> > Can you please make sure the the UBIFS debugfs knob "chk_fs" is set?
> > If set, UBIFS will check the whole filesystem while mounting.
> 
> Ok, I'll try that first - but it will take a few days as well.

Okay.

Thanks,
//richard

Re: UBIFS does not mount after powerfail

[Manfred Spraul]

Hi Richard,

On 11/13/2017 10:06 PM, Richard Weinberger wrote:
>
> No, the image that contains the first logical error.
> UBIFS can mount even if there are many problems with the structure.
> Therefore I need you to enable chk_fs.
Done - and I even found a 2nd instance where the image got bad:
https://sourceforge.net/projects/calculix-rpm/files/ubifs/chk_fs/

Note that the image file 17052.bin contains the operation 0...17051.
And, also probably obvious: I didn't check all images.

0: FUNC_WRITE_CHK(addr=0x8a3000, len=0x800, chk=0x3340ce3e21).
1: FUNC_WRITE_CHK(addr=0x8c3000, len=0x800, chk=0x333fa195e8).
2: FUNC_WRITE_CHK(addr=0x902000, len=0x800, chk=0x34341e02e8).
3: FUNC_WRITE_CHK(addr=0x902800, len=0x800, chk=0x342d493f65).
[...]
17049: FUNC_WRITE_CHK(addr=0xef6000, len=0x800, chk=0x3994208eb4).
17050: FUNC_WRITE_CHK(addr=0x13ec800, len=0x800, chk=0x341c792851).
17051: FUNC_WRITE_CHK(addr=0xef6800, len=0x800, chk=0x34f4bd0ddb).
<<< image-17052.bin: last good.

17052: FUNC_ERASE_CHK(addr=0xa80000, len=0x20000, chk=0x189a1de0000).
<<< image-17053.bin: first bad

[44220.724139] UBIFS (ubi0:0): recovery needed
[44220.734390] UBIFS (ubi0:0): recovery completed
[44220.755927] UBIFS error (ubi0:0 pid 28505): ubifs_read_node: bad node 
type (255 but expected 0)
[44220.757129] UBIFS error (ubi0:0 pid 28505): ubifs_read_node: bad node 
at LEB 142:82472, LEB mapping status 0
[44220.758325] Not a node, first 24 bytes:
[44220.758329] 00000000: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 
ff ff ff ff ff ff ff ff ........................
[44220.760717] CPU: 3 PID: 28505 Comm: mount Not tainted 4.14.0-rc5+ #105
[44220.761923] Hardware name: Hewlett-Packard HP 350 G2/803A, BIOS F.13 
06/10/2015
[44220.763097] Call Trace:
[44220.764231]  ? dump_stack+0x67/0x8e
[44220.765362]  ? ubifs_read_node+0x240/0x250
[44220.766492]  ? ubifs_tnc_read_node+0x53/0x181
[44220.767623]  ? rcu_read_lock_sched_held+0x5d/0x63
[44220.768764]  ? __kmalloc+0x224/0x267
[44220.769902]  ? check_leaf+0x5a/0x322
[44220.771027]  ? check_leaf+0x74/0x322
[44220.772150]  ? dbg_check_key_order+0x2f1/0x2f1
[44220.773254]  ? dbg_walk_index+0x149/0x26a
[44220.774344]  ? dbg_check_filesystem+0x52/0x2bc
[44220.775430]  ? ubifs_mount+0x1195/0x1ad4


17053: FUNC_WRITE_CHK(addr=0x1440800, len=0x800, chk=0x3337f61989).
[...]
17074: FUNC_WRITE_CHK(addr=0x120b800, len=0x800, chk=0x350157ab97).
17075: FUNC_WRITE_CHK(addr=0x192d800, len=0x800, chk=0x3e964ae864).
<<< last bad: image-17076.bin
17076: FUNC_WRITE_CHK(addr=0x14ef000, len=0x800, chk=0x33520a41bd).
<<< first good: image-17077.bin
17077: FUNC_WRITE_CHK(addr=0x164f000, len=0x800, chk=0x3351c63d66).
[...]
168075: FUNC_WRITE_CHK(addr=0x1253800, len=0x800, chk=0x35d695d6fd).
168076: FUNC_WRITE_CHK(addr=0x1254000, len=0x800, chk=0x3545e92899).
<<< last good: image-168077.bin

168077: FUNC_ERASE_CHK(addr=0x1d00000, len=0x20000, chk=0x43f2cae0000).
<<< first bad: image-168078.bin
[44846.903651] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" started, 
PID 5106
[44846.903939] UBIFS (ubi0:0): recovery needed
[44846.919364] UBIFS (ubi0:0): recovery completed
[44847.097635] UBIFS error (ubi0:0 pid 5104): dbg_check_filesystem: 
inode 39012 nlink is 1, but calculated nlink is 0
[44847.098877] UBIFS (ubi0:0): dump of the inode 39012 sitting in LEB 
155:63104
[44847.100112]  magic          0x6101831
[44847.101304]  crc            0xbdf8574f
[44847.102470]  node_type      0 (inode node)
[44847.103616]  group_type     2 (last of node group)
[44847.104745]  sqnum          792243
[44847.105840]  len            257
[44847.106924]  key            (39012, inode)
[44847.108000]  creat_sqnum    783767
[44847.109077]  size           97
[44847.110147]  nlink          1
[44847.111215]  atime          1510332948.0
[44847.112277]  mtime          1510332948.0
[44847.113326]  ctime          1510332948.0
[44847.114349]  uid            0
[44847.115350]  gid            0
[44847.116345]  mode           33279
[44847.117310]  flags          0x20
[44847.118247]  xattr_cnt      0
[44847.119159]  xattr_size     0
[44847.120042]  xattr_names    0
[44847.120906]  compr_type     0x1
[44847.121746]  data len       97
[44847.122612] UBIFS error (ubi0:0 pid 5104): dbg_check_filesystem: 
file-system check failed with error -22


Anything else where I can support?

--
     Manfred

Re: UBIFS does not mount after powerfail

[Manfred Spraul]

Hi,
On 11/14/2017 07:49 PM, Manfred Spraul wrote:
> Hi Richard,
>
> On 11/13/2017 10:06 PM, Richard Weinberger wrote:
>>
>> No, the image that contains the first logical error.
>> UBIFS can mount even if there are many problems with the structure.
>> Therefore I need you to enable chk_fs.
> Done - and I even found a 2nd instance where the image got bad:
> https://sourceforge.net/projects/calculix-rpm/files/ubifs/chk_fs/
>
Sorry for the self-reply, I did another test:
I applied "ubifs: replay: Detect and kill orphaned xattrs", i.e. 
https://patchwork.ozlabs.org/patch/780667/.

Finding 1: It does not help
Finding 2: I get for some images lots of "UBIFS error (ubi0:0 pid 
13670): ubifs_replay_journal: Expected ino node, got: 1/2/3"

The last good image is e.g. 164908, the first image with such messages 
is 164909:
https://sourceforge.net/projects/calculix-rpm/files/ubifs/kill_xattr/

And, perhaps it helps to understand the issues:
The stress test is parallel, i.e. 4 parallel bash scripts that do 
something like "touch a b c ;rm c; mv a b;".

--
     Manfred

Re: UBIFS does not mount after powerfail

[Richard Weinberger]

Manfred,

Am Mittwoch, 15. November 2017, 21:04:14 CET schrieb Manfred Spraul:
> Hi,
> 
> On 11/14/2017 07:49 PM, Manfred Spraul wrote:
> > Hi Richard,
> > 
> > On 11/13/2017 10:06 PM, Richard Weinberger wrote:
> >> No, the image that contains the first logical error.
> >> UBIFS can mount even if there are many problems with the structure.
> >> Therefore I need you to enable chk_fs.
> > 
> > Done - and I even found a 2nd instance where the image got bad:
> > https://sourceforge.net/projects/calculix-rpm/files/ubifs/chk_fs/
> 
> Sorry for the self-reply, I did another test:
> I applied "ubifs: replay: Detect and kill orphaned xattrs", i.e.
> https://patchwork.ozlabs.org/patch/780667/.

I would be astonished if that patch would fix your issues, it is supposed
to fix a -ENOSPC problem.
 
> Finding 1: It does not help
> Finding 2: I get for some images lots of "UBIFS error (ubi0:0 pid
> 13670): ubifs_replay_journal: Expected ino node, got: 1/2/3"

1/2/3?

Is always "got 0xFF but expected something different"?
If so, it can be a problem with garbage collect.

> The last good image is e.g. 164908, the first image with such messages
> is 164909:
> https://sourceforge.net/projects/calculix-rpm/files/ubifs/kill_xattr/
> 
> And, perhaps it helps to understand the issues:
> The stress test is parallel, i.e. 4 parallel bash scripts that do
> something like "touch a b c ;rm c; mv a b;".

Hmm, nothing fancy. No O_TMPFILE, no xattr, no encryption. :-(
I hope I'll find some time during the weekend to inspect your images.

Thanks,
//richard

Re: UBIFS does not mount after powerfail

[Manfred Spraul]

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

Hi Richard,

(2nd mail, forced as non-html)

On 11/15/2017 09:29 PM, Richard Weinberger wrote:
> Manfred,
>
> Am Mittwoch, 15. November 2017, 21:04:14 CET schrieb Manfred Spraul:
>> Hi,
>>
>> On 11/14/2017 07:49 PM, Manfred Spraul wrote:
>>> Hi Richard,
>>>
>>> On 11/13/2017 10:06 PM, Richard Weinberger wrote:
>>>> No, the image that contains the first logical error.
>>>> UBIFS can mount even if there are many problems with the structure.
>>>> Therefore I need you to enable chk_fs.
>>> Done - and I even found a 2nd instance where the image got bad:
>>> https://sourceforge.net/projects/calculix-rpm/files/ubifs/chk_fs/
>> Sorry for the self-reply, I did another test:
>> I applied "ubifs: replay: Detect and kill orphaned xattrs", i.e.
>> https://patchwork.ozlabs.org/patch/780667/.
> I would be astonished if that patch would fix your issues, it is supposed
> to fix a -ENOSPC problem.
After enabling chk_fs, the result was
> UBIFS error (ubi0:0 pid 5104): dbg_check_filesystem: inode 39012 nlink is 1, but calculated nlink is 0
and link count 1 instead of 0 sounded a bit like a leak.

dmesg:
https://sourceforge.net/projects/calculix-rpm/files/ubifs/chk_fs/result-168078.txt/download
image:
https://sourceforge.net/projects/calculix-rpm/files/ubifs/chk_fs/image-168078.bin/download

Thus I decided that it might make sense to try a patch that should 
improve the behavior with leaks.
And it did not take much time.

>   
>> Finding 1: It does not help
>> Finding 2: I get for some images lots of "UBIFS error (ubi0:0 pid
>> 13670): ubifs_replay_journal: Expected ino node, got: 1/2/3"
> 1/2/3?
Sorry, that was too short:

https://sourceforge.net/projects/calculix-rpm/files/ubifs/kill_xattr/result-168168.txt/download

> [ 1281.127396] ubi0: background thread "ubi_bgt0d" started, PID 9653
> [ 1281.132641] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" 
> started, PID 9660
> [ 1281.132813] UBIFS (ubi0:0): recovery needed
> [ 1281.137769] UBIFS error (ubi0:0 pid 9658): ubifs_replay_journal: 
> Expected ino node, got: 2
> [ 1281.138860] UBIFS error (ubi0:0 pid 9658): ubifs_replay_journal: 
> Expected ino node, got: 3
> [ 1281.139898] UBIFS error (ubi0:0 pid 9658): ubifs_replay_journal: 
> Expected ino node, got: 2
> [ 1281.140922] UBIFS error (ubi0:0 pid 9658): ubifs_replay_journal: 
> Expected ino node, got: 3
> [ 1281.142023] UBIFS error (ubi0:0 pid 9658): ubifs_replay_journal: 
> Expected ino node, got: 1
> [...]

Image:
https://sourceforge.net/projects/calculix-rpm/files/ubifs/kill_xattr/image-168168.bin/download  


> Is always "got 0xFF but expected something different"?
> If so, it can be a problem with garbage collect.
>> The last good image is e.g. 164908, the first image with such messages
>> is 164909:
>> https://sourceforge.net/projects/calculix-rpm/files/ubifs/kill_xattr/
>>
>> And, perhaps it helps to understand the issues:
>> The stress test is parallel, i.e. 4 parallel bash scripts that do
>> something like "touch a b c ;rm c; mv a b;".
> Hmm, nothing fancy. No O_TMPFILE, no xattr, no encryption. :-(
> I hope I'll find some time during the weekend to inspect your images.
I have attached the generator:
With xattr, due to ecryptfs with ecryptfs_xattr_metadata.

Regarding the "expected ino node":
Is it possible that it is caused by parallel operation?

There are 8 threads that do parallel operations, on a 2-core (4 thread) 
Core-I3 system.
What prevents that another thread creates a journal entry between 
initial UBIFS_XENT_KEY and the two UBIFS_INO_KEY?

If you need something, just send me a mail

--
     Manfred

[-- Attachment #2: gen.sh --]
[-- Type: application/x-shellscript, Size: 1237 bytes --]

Re: UBIFS does not mount after powerfail

[Richard Weinberger]

Manfred,

Am Donnerstag, 16. November 2017, 17:51:26 CET schrieb Manfred Spraul:
> Hi Richard,
> 
> (2nd mail, forced as non-html)
> 
> On 11/15/2017 09:29 PM, Richard Weinberger wrote:
> > Manfred,
> > 
> > Am Mittwoch, 15. November 2017, 21:04:14 CET schrieb Manfred Spraul:
> >> Hi,
> >> 
> >> On 11/14/2017 07:49 PM, Manfred Spraul wrote:
> >>> Hi Richard,
> >>> 
> >>> On 11/13/2017 10:06 PM, Richard Weinberger wrote:
> >>>> No, the image that contains the first logical error.
> >>>> UBIFS can mount even if there are many problems with the structure.
> >>>> Therefore I need you to enable chk_fs.
> >>> 
> >>> Done - and I even found a 2nd instance where the image got bad:
> >>> https://sourceforge.net/projects/calculix-rpm/files/ubifs/chk_fs/
> >> 
> >> Sorry for the self-reply, I did another test:
> >> I applied "ubifs: replay: Detect and kill orphaned xattrs", i.e.
> >> https://patchwork.ozlabs.org/patch/780667/.
> > 
> > I would be astonished if that patch would fix your issues, it is supposed
> > to fix a -ENOSPC problem.
> 
> After enabling chk_fs, the result was
> 
> > UBIFS error (ubi0:0 pid 5104): dbg_check_filesystem: inode 39012 nlink is
> > 1, but calculated nlink is 0
> and link count 1 instead of 0 sounded a bit like a leak.
> 
> dmesg:
> https://sourceforge.net/projects/calculix-rpm/files/ubifs/chk_fs/result-1680
> 78.txt/download image:
> https://sourceforge.net/projects/calculix-rpm/files/ubifs/chk_fs/image-16807
> 8.bin/download
> 
> Thus I decided that it might make sense to try a patch that should
> improve the behavior with leaks.
> And it did not take much time.

Hmmm, maybe we confuse UBIFS that much that it fails differently in your case.

Anyway, let's reconsider.
If you're facing the problem which should be solved by the said patch, we can 
do a check.

1. Do you face the issue without ecryptfs?
2. Does it also happen without xattrs being used at all? Hence also without 
ecryptfs?
 
I'm afraid that the patch has still problems (it is not merged for reasons).
So, please keep on a stock kernel for now.

Thanks,
//richard

Re: UBIFS does not mount after powerfail

[Manfred Spraul]

Hi Richard,

I have now three datasets:
- no xattr, no FASTMAP:
The log consists of ~189.000 WRITE or ERASE commands.
-- with chk_fs: 30.000 images tested, all ok.

-- with chk_fs, when splitting large writes at PAGE_SIZE: 814 images 
tested, all ok.

--> no issues at all when not using xattr.

- ecryptfs with ecryptfs_xattr_metadata:
The log consists of ~188.000 WRITE or ERASE commands.

-- without chk_fs: 23.000 images tested, 5 not mountable images, all 5 
within garbage_collect_leb():

If I see it right, the root cause is always a node that crosses a page 
boundary:
the first half of the node is written, the 2nd half is not written, it 
is still 0xff.
These nodes cause CRC failures during scanning.
(perhaps: output of layout_in_empty_space(), writing to a erased LEB 
instead of changing a LEB not properly handled?)

-- with chk_fs: 795 images tested, 62 not mountable.
Obviously including the 5 above: chk_fs runs after recovery_completed, 
garbage_collect_leb() is run during recovery.

-- kill-orphaned-xattr, with chk_fs: 215 images tested, 156 not mountable.
Note: This is not worse than without the patch. There are long streams 
of images that fail during chk_fs, 200 images is not enough for good 
statistics.
And: I have not tested the same images as without the patch.

- ecryptfs with ecryptfs_xattr_metadata and with FASTMAP
The log consists of ~197.000 WRITE or ERASE commands.

21.000 images tested, 178 do not mount. all fail in chk_fs.
The failure is always something like this:
> [34802.217857] UBIFS error (ubi0:0 pid 25706): ubifs_read_node: bad 
> node at LEB 243:74672, LEB mapping status 0
> [34802.218965] Not a node, first 24 bytes:
> [34802.218969] 00000000: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 
> ff ff ff ff
I have not tested with chk_fastmap.
And: Unlike above, where I tested the last images, I have here tested 
the first 20k images, thus a more or less empty media.
The lower failure rate could be caused by that.

Did you have the time to look at the images?
If you need more images, or if I should test a patch, just ask.

I have uploaded the most interesting images to sourceforge.
https://sourceforge.net/projects/calculix-rpm/files/ubifs/

--
     Manfred

Re: UBIFS does not mount after powerfail

[Richard Weinberger]

Manfred,


Am Donnerstag, 23. November 2017, 23:03:28 CET schrieb Manfred Spraul:
> Hi Richard,
> 
> I have now three datasets:
> - no xattr, no FASTMAP:
> The log consists of ~189.000 WRITE or ERASE commands.
> -- with chk_fs: 30.000 images tested, all ok.
> 
> -- with chk_fs, when splitting large writes at PAGE_SIZE: 814 images
> tested, all ok.
> 
> --> no issues at all when not using xattr.
> 
> - ecryptfs with ecryptfs_xattr_metadata:
> The log consists of ~188.000 WRITE or ERASE commands.
> 
> -- without chk_fs: 23.000 images tested, 5 not mountable images, all 5
> within garbage_collect_leb():
> 
> If I see it right, the root cause is always a node that crosses a page
> boundary:
> the first half of the node is written, the 2nd half is not written, it
> is still 0xff.
> These nodes cause CRC failures during scanning.
> (perhaps: output of layout_in_empty_space(), writing to a erased LEB
> instead of changing a LEB not properly handled?)
> 
> -- with chk_fs: 795 images tested, 62 not mountable.
> Obviously including the 5 above: chk_fs runs after recovery_completed,
> garbage_collect_leb() is run during recovery.
> 
> -- kill-orphaned-xattr, with chk_fs: 215 images tested, 156 not mountable.
> Note: This is not worse than without the patch. There are long streams
> of images that fail during chk_fs, 200 images is not enough for good
> statistics.
> And: I have not tested the same images as without the patch.
> 
> - ecryptfs with ecryptfs_xattr_metadata and with FASTMAP
> The log consists of ~197.000 WRITE or ERASE commands.
> 
> 21.000 images tested, 178 do not mount. all fail in chk_fs.
> 
> The failure is always something like this:
> > [34802.217857] UBIFS error (ubi0:0 pid 25706): ubifs_read_node: bad
> > node at LEB 243:74672, LEB mapping status 0
> > [34802.218965] Not a node, first 24 bytes:
> > [34802.218969] 00000000: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
> > ff ff ff ff
> 
> I have not tested with chk_fastmap.
> And: Unlike above, where I tested the last images, I have here tested
> the first 20k images, thus a more or less empty media.
> The lower failure rate could be caused by that.
> 
> Did you have the time to look at the images?
> If you need more images, or if I should test a patch, just ask.

I tied, but TBH I'm completely lost in all the data you throwing on me.

Let's recap, you trigger a corruption that happens only(!) when xattrs are 
used?
How is Fastmap involved in the game? If so, I want to know whether you can 
trigger without Fastmap being enabled.

Which one is the image that failed first with chk_fs enabled?
On a vanilla kernel...

How did you save that image? I'd like to use it in my simulator too
Make sure to not store OOB data.

Thanks,
//richard

Re: UBIFS does not mount after powerfail

[Manfred Spraul]

Hi Richard,

On 11/28/2017 10:00 PM, Richard Weinberger wrote:
> Manfred,
>
>
> Am Donnerstag, 23. November 2017, 23:03:28 CET schrieb Manfred Spraul:
>
> I tied, but TBH I'm completely lost in all the data you throwing on me.
Understood, I generated way too much data.
I have also confused all my coworkers :-(

Let's keep it simple and concentrate on the 
garbage_collect_leb()/ubifs_scan issue.

> Let's recap, you trigger a corruption that happens only(!) when xattrs are
> used?
Correct, I only see the corruption when xattr is used.
> How is Fastmap involved in the game? If so, I want to know whether you can
> trigger without Fastmap being enabled.
Correct, I have seen the corruption without FASTMAP.
> Which one is the image that failed first with chk_fs enabled?
> On a vanilla kernel...
I have used a modified MTDRAM: I have patched it so that it writes every 
ERASE and every WRITE into a log.
Both the addresses and the data.
First, I have created an initial image 
(ubiformat/ubiattach/ubimkvol/ubidetach).
Then I did a dump of it (nanddump /dev/mtd0 > dump-before.bin)
Then I enabled logging, and run the test load (ubiattach/mount/mount 
ecryptfs/<many touch/write/rm/mv commands>/umount/ubidetach).
Then I created a dump of the final image. (nanddump /dev/mtd0 > 
dump-after.bin)

Then the obvious cross checks:
Initial image+apply all commands from the log is identical to the final 
image.

Now I can use the log to create virtual power fail events:

What if a power failure happens after 168168 commands?
Image:
https://sourceforge.net/projects/calculix-rpm/files/ubifs/image-168168.bin/download

Logfile of a mount without chk_fs:
https://sourceforge.net/projects/calculix-rpm/files/ubifs/r-168168.txt/download

What was the last write command?
FUNC_WRITE_CHK(addr=0xd61000, len=0x800)

What would have been the next write command:
FUNC_WRITE_CHK(addr=0xd61800, len=0x800)

Image after that command:
https://sourceforge.net/projects/calculix-rpm/files/ubifs/image-168169.bin/download


Just to clarify:
I have simulated power fails.
A bit like ubi_dbg_power_cut, but instead of stopping further 
writes/erase commands, I created a log file.
And now I can replay the log to an arbitrary point, and then mount the 
result.
I have a total of 5 logs, and from these 5 logs, I did around 100k 
replays and mounted the results.

--
     Manfred

P.S.: I'm doing final polishing of the modifications to mtdram. As soon 
as I have the internal release, I will send it to linux-mtd.
P.P.S.: To avoid any brown paperbag bugs: mtd->_erase and mtd->_write 
are allowed to sleep, correct? At least, st_spi_fsm contains mutex_lock().

Re: UBIFS does not mount after powerfail

[Richard Weinberger]

Manfred,

Am Donnerstag, 30. November 2017, 18:41:52 CET schrieb Manfred Spraul:
> Hi Richard,
> 
> On 11/28/2017 10:00 PM, Richard Weinberger wrote:
> > Manfred,
> > 
> > 
> > Am Donnerstag, 23. November 2017, 23:03:28 CET schrieb Manfred Spraul:
> > 
> > I tied, but TBH I'm completely lost in all the data you throwing on me.
> 
> Understood, I generated way too much data.
> I have also confused all my coworkers :-(

No problem.
We will sort this out. :-)
 
> Let's keep it simple and concentrate on the
> garbage_collect_leb()/ubifs_scan issue.
> 
> > Let's recap, you trigger a corruption that happens only(!) when xattrs are
> > used?
> 
> Correct, I only see the corruption when xattr is used.

Good.

> > How is Fastmap involved in the game? If so, I want to know whether you can
> > trigger without Fastmap being enabled.
> 
> Correct, I have seen the corruption without FASTMAP.

Also Good.

> > Which one is the image that failed first with chk_fs enabled?
> > On a vanilla kernel...
> 
> I have used a modified MTDRAM: I have patched it so that it writes every
> ERASE and every WRITE into a log.
> Both the addresses and the data.
> First, I have created an initial image
> (ubiformat/ubiattach/ubimkvol/ubidetach).
> Then I did a dump of it (nanddump /dev/mtd0 > dump-before.bin)
> Then I enabled logging, and run the test load (ubiattach/mount/mount
> ecryptfs/<many touch/write/rm/mv commands>/umount/ubidetach).
> Then I created a dump of the final image. (nanddump /dev/mtd0 >
> dump-after.bin)

Okay.

> Then the obvious cross checks:
> Initial image+apply all commands from the log is identical to the final
> image.
> 
> Now I can use the log to create virtual power fail events:
> 
> What if a power failure happens after 168168 commands?
> Image:
> https://sourceforge.net/projects/calculix-rpm/files/ubifs/image-168168.bin/d
> ownload
> 
> Logfile of a mount without chk_fs:
> https://sourceforge.net/projects/calculix-rpm/files/ubifs/r-168168.txt/downl
> oad
> 
> What was the last write command?
> FUNC_WRITE_CHK(addr=0xd61000, len=0x800)
> 
> What would have been the next write command:
> FUNC_WRITE_CHK(addr=0xd61800, len=0x800)
> 
> Image after that command:
> https://sourceforge.net/projects/calculix-rpm/files/ubifs/image-168169.bin/d
> ownload

Which file contains the MTD with chk_fs being enabled?

What did you set for CONFIG_MTDRAM_TOTAL_SIZE and CONFIG_MTDRAM_ERASE_SIZE?

> 
> Just to clarify:
> I have simulated power fails.
> A bit like ubi_dbg_power_cut, but instead of stopping further
> writes/erase commands, I created a log file.
> And now I can replay the log to an arbitrary point, and then mount the
> result.
> I have a total of 5 logs, and from these 5 logs, I did around 100k
> replays and mounted the results.
> 
> --
>      Manfred
> 
> P.S.: I'm doing final polishing of the modifications to mtdram. As soon
> as I have the internal release, I will send it to linux-mtd.

Wonderful. Your tool seems to be a good stresstest.
I'd love to see it upstream.

> P.P.S.: To avoid any brown paperbag bugs: mtd->_erase and mtd->_write
> are allowed to sleep, correct? At least, st_spi_fsm contains mutex_lock().

I always assumed that they can sleep and everything else would surprise me.

Thanks,
//richard

Re: UBIFS does not mount after powerfail

[Manfred Spraul]

Hi Richard,

On 11/30/2017 09:03 PM, Richard Weinberger wrote:
> Manfred,
>
> Am Donnerstag, 30. November 2017, 18:41:52 CET schrieb Manfred Spraul:
>
>> Let's keep it simple and concentrate on the
>> garbage_collect_leb()/ubifs_scan issue.
>>
>>> Let's recap, you trigger a corruption that happens only(!) when xattrs are
>>> used?
>> Correct, I only see the corruption when xattr is used.
> Good.
>
>>> How is Fastmap involved in the game? If so, I want to know whether you can
>>> trigger without Fastmap being enabled.
>> Correct, I have seen the corruption without FASTMAP.
> Also Good.
>
>>> Which one is the image that failed first with chk_fs enabled?
>>> On a vanilla kernel...
>> I have used a modified MTDRAM: I have patched it so that it writes every
>> ERASE and every WRITE into a log.
>> Both the addresses and the data.
>> First, I have created an initial image
>> (ubiformat/ubiattach/ubimkvol/ubidetach).
>> Then I did a dump of it (nanddump /dev/mtd0 > dump-before.bin)
>> Then I enabled logging, and run the test load (ubiattach/mount/mount
>> ecryptfs/<many touch/write/rm/mv commands>/umount/ubidetach).
>> Then I created a dump of the final image. (nanddump /dev/mtd0 >
>> dump-after.bin)
> Okay.
>
>> Then the obvious cross checks:
>> Initial image+apply all commands from the log is identical to the final
>> image.
>>
>> Now I can use the log to create virtual power fail events:
>>
>> What if a power failure happens after 168168 commands?
>> Image:
>> https://sourceforge.net/projects/calculix-rpm/files/ubifs/image-168168.bin/d
>> ownload
>>
>> Logfile of a mount without chk_fs:
>> https://sourceforge.net/projects/calculix-rpm/files/ubifs/r-168168.txt/downl
>> oad
>>
>> What was the last write command?
>> FUNC_WRITE_CHK(addr=0xd61000, len=0x800)
>>
>> What would have been the next write command:
>> FUNC_WRITE_CHK(addr=0xd61800, len=0x800)
>>
>> Image after that command:
>> https://sourceforge.net/projects/calculix-rpm/files/ubifs/image-168169.bin/d
>> ownload
> Which file contains the MTD with chk_fs being enabled?
I'm not sure if this help, it only adds more data that will confuse:
The image.bin is obviously identical, I have only enabled chk_fs for the 
replay.

And: The mount was done using 4.14, the image was created using 4.14-rc5.
If you want: Here is the data (dmesg output + images)
https://sourceforge.net/projects/calculix-rpm/files/ubifs/chkfs_4.14/

> What did you set for CONFIG_MTDRAM_TOTAL_SIZE and CONFIG_MTDRAM_ERASE_SIZE?
CONFIG_MTD_MTDRAM=y
CONFIG_MTDRAM_TOTAL_SIZE=32768
CONFIG_MTDRAM_ERASE_SIZE=128
CONFIG_MTDRAM_WRITE_SIZE=2048
CONFIG_MTDRAM_WRITEBUF_SIZE=2048
CONFIG_MTDRAM_FLIGHTRECORDER=y
CONFIG_MTDRAM_FLIGHTRECORDER_BUFFER_SIZE=131072
CONFIG_MTDRAM_FLIGHTRECORDER_ENABLED=y

With regards to WRITEBUF_SIZE, it may have been at 64.

--
     Manfred

Re: UBIFS does not mount after powerfail

[Richard Weinberger]

Manfred,

Am Freitag, 1. Dezember 2017, 08:41:28 CET schrieb Manfred Spraul:
> > What did you set for CONFIG_MTDRAM_TOTAL_SIZE and
> > CONFIG_MTDRAM_ERASE_SIZE?
> 
> CONFIG_MTD_MTDRAM=y
> CONFIG_MTDRAM_TOTAL_SIZE=32768
> CONFIG_MTDRAM_ERASE_SIZE=128

> CONFIG_MTDRAM_WRITE_SIZE=2048
> CONFIG_MTDRAM_WRITEBUF_SIZE=2048
> CONFIG_MTDRAM_FLIGHTRECORDER=y
> CONFIG_MTDRAM_FLIGHTRECORDER_BUFFER_SIZE=131072
> CONFIG_MTDRAM_FLIGHTRECORDER_ENABLED=y

These options are not available on my kernel?
Should the image actually work on a stock MTDRAM?

Thanks,
//richard

Re: UBIFS does not mount after powerfail

[Manfred Spraul]

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

Hi Richard,
On 12/01/2017 11:01 AM, Richard Weinberger wrote:
> Manfred,
>
> Am Freitag, 1. Dezember 2017, 08:41:28 CET schrieb Manfred Spraul:
>>> What did you set for CONFIG_MTDRAM_TOTAL_SIZE and
>>> CONFIG_MTDRAM_ERASE_SIZE?
>> CONFIG_MTD_MTDRAM=y
>> CONFIG_MTDRAM_TOTAL_SIZE=32768
>> CONFIG_MTDRAM_ERASE_SIZE=128
>> CONFIG_MTDRAM_WRITE_SIZE=2048
>> CONFIG_MTDRAM_WRITEBUF_SIZE=2048
>> CONFIG_MTDRAM_FLIGHTRECORDER=y
>> CONFIG_MTDRAM_FLIGHTRECORDER_BUFFER_SIZE=131072
>> CONFIG_MTDRAM_FLIGHTRECORDER_ENABLED=y
> These options are not available on my kernel?
> Should the image actually work on a stock MTDRAM?
Sorry.
I have retest it, you need the attached patch to set the writesize and 
writebufsize to 2048.

and then as .config:
CONFIG_MTD_MTDRAM=y
CONFIG_MTDRAM_TOTAL_SIZE=32768
CONFIG_MTDRAM_ERASE_SIZE=128


--
     Manfred
> Thanks,
> //richard

[-- Attachment #2: mtdram-preliminary.patch --]
[-- Type: text/x-patch, Size: 817 bytes --]

diff --git a/drivers/mtd/devices/mtdram.c b/drivers/mtd/devices/mtdram.c
index 0bf4aeaf0cb8..d63ed375dc49 100644
--- a/drivers/mtd/devices/mtdram.c
+++ b/drivers/mtd/devices/mtdram.c
@@ -20,7 +20,7 @@
 
 static unsigned long total_size = CONFIG_MTDRAM_TOTAL_SIZE;
 static unsigned long erase_size = CONFIG_MTDRAM_ERASE_SIZE;
-static unsigned long writebuf_size = 64;
+static unsigned long writebuf_size = 2048;
 #define MTDRAM_TOTAL_SIZE (total_size * 1024)
 #define MTDRAM_ERASE_SIZE (erase_size * 1024)
 
@@ -134,7 +134,7 @@ int mtdram_init_device(struct mtd_info *mtd, void *mapped_address,
 	mtd->type = MTD_RAM;
 	mtd->flags = MTD_CAP_RAM;
 	mtd->size = size;
-	mtd->writesize = 1;
+	mtd->writesize = 2048;
 	mtd->writebufsize = writebuf_size;
 	mtd->erasesize = MTDRAM_ERASE_SIZE;
 	mtd->priv = mapped_address;

[-- Attachment #3: final-test.txt --]
[-- Type: text/plain, Size: 77560 bytes --]

[    0.000000] Linux version 4.15.0-rc1+ (manfred@localhost.localdomain) (gcc version 7.2.1 20170915 (Red Hat 7.2.1-2) (GCC)) #112 SMP PREEMPT Fri Dec 1 15:51:49 CET 2017
[    0.000000] Command line: BOOT_IMAGE=/bzImage
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000]   AMD AuthenticAMD
[    0.000000]   Centaur CentaurHauls
[    0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[    0.000000] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
[    0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format.
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x0000000000057fff] usable
[    0.000000] BIOS-e820: [mem 0x0000000000058000-0x0000000000058fff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000059000-0x0000000000087fff] usable
[    0.000000] BIOS-e820: [mem 0x0000000000088000-0x00000000000bffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000009bb7efff] usable
[    0.000000] BIOS-e820: [mem 0x000000009bb7f000-0x000000009cb7efff] reserved
[    0.000000] BIOS-e820: [mem 0x000000009cb7f000-0x000000009cf7efff] ACPI NVS
[    0.000000] BIOS-e820: [mem 0x000000009cf7f000-0x000000009cffefff] ACPI data
[    0.000000] BIOS-e820: [mem 0x000000009cfff000-0x000000009cffffff] usable
[    0.000000] BIOS-e820: [mem 0x000000009d000000-0x000000009fffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fe101000-0x00000000fe112fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feb00000-0x00000000feb03fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fed10000-0x00000000fed19fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000ffa00000-0x00000000ffffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000015dffffff] usable
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] e820: update [mem 0x81aea018-0x81afa057] usable ==> usable
[    0.000000] e820: update [mem 0x81aea018-0x81afa057] usable ==> usable
[    0.000000] extended physical RAM map:
[    0.000000] reserve setup_data: [mem 0x0000000000000000-0x0000000000057fff] usable
[    0.000000] reserve setup_data: [mem 0x0000000000058000-0x0000000000058fff] reserved
[    0.000000] reserve setup_data: [mem 0x0000000000059000-0x0000000000087fff] usable
[    0.000000] reserve setup_data: [mem 0x0000000000088000-0x00000000000bffff] reserved
[    0.000000] reserve setup_data: [mem 0x0000000000100000-0x0000000081aea017] usable
[    0.000000] reserve setup_data: [mem 0x0000000081aea018-0x0000000081afa057] usable
[    0.000000] reserve setup_data: [mem 0x0000000081afa058-0x000000009bb7efff] usable
[    0.000000] reserve setup_data: [mem 0x000000009bb7f000-0x000000009cb7efff] reserved
[    0.000000] reserve setup_data: [mem 0x000000009cb7f000-0x000000009cf7efff] ACPI NVS
[    0.000000] reserve setup_data: [mem 0x000000009cf7f000-0x000000009cffefff] ACPI data
[    0.000000] reserve setup_data: [mem 0x000000009cfff000-0x000000009cffffff] usable
[    0.000000] reserve setup_data: [mem 0x000000009d000000-0x000000009fffffff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000e0000000-0x00000000efffffff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000fe101000-0x00000000fe112fff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000feb00000-0x00000000feb03fff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000fec00000-0x00000000fec00fff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000fed10000-0x00000000fed19fff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000fee00000-0x00000000fee00fff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000ffa00000-0x00000000ffffffff] reserved
[    0.000000] reserve setup_data: [mem 0x0000000100000000-0x000000015dffffff] usable
[    0.000000] efi: EFI v2.40 by INSYDE Corp.
[    0.000000] efi:  SMBIOS=0x9c0ee000  ESRT=0x9c172518  ACPI 2.0=0x9cffe014 
[    0.000000] random: fast init done
[    0.000000] SMBIOS 2.7 present.
[    0.000000] DMI: Hewlett-Packard HP 350 G2/803A, BIOS F.13 06/10/2015
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x15e000 max_arch_pfn = 0x400000000
[    0.000000] MTRR default type: uncachable
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-BFFFF uncachable
[    0.000000]   C0000-E7FFF write-protect
[    0.000000]   E8000-EFFFF write-combining
[    0.000000]   F0000-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 0000000000 mask 7F80000000 write-back
[    0.000000]   1 base 0080000000 mask 7FE0000000 write-back
[    0.000000]   2 base 009D000000 mask 7FFF000000 uncachable
[    0.000000]   3 base 009E000000 mask 7FFE000000 uncachable
[    0.000000]   4 base 00FFA00000 mask 7FFFE00000 write-protect
[    0.000000]   5 base 00FFC00000 mask 7FFFC00000 write-protect
[    0.000000]   6 base 0100000000 mask 7F80000000 write-back
[    0.000000]   7 base 015E000000 mask 7FFE000000 uncachable
[    0.000000]   8 base 0160000000 mask 7FE0000000 uncachable
[    0.000000]   9 disabled
[    0.000000] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
[    0.000000] e820: last_pfn = 0x9d000 max_arch_pfn = 0x400000000
[    0.000000] esrt: Reserving ESRT space from 0x000000009c172518 to 0x000000009c172550.
[    0.000000] Base memory trampoline at [        (ptrval)] 32000 size 24576
[    0.000000] Using GB pages for direct mapping
[    0.000000] BRK [0x02c80000, 0x02c80fff] PGTABLE
[    0.000000] BRK [0x02c81000, 0x02c81fff] PGTABLE
[    0.000000] BRK [0x02c82000, 0x02c82fff] PGTABLE
[    0.000000] BRK [0x02c83000, 0x02c83fff] PGTABLE
[    0.000000] BRK [0x02c84000, 0x02c84fff] PGTABLE
[    0.000000] Secure boot disabled
[    0.000000] RAMDISK: [mem 0x3cf91000-0x3e358fff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x000000009CFFE014 000024 (v02 HPQOEM)
[    0.000000] ACPI: XSDT 0x000000009CFFE120 0000C4 (v01 HPQOEM SLIC-MPC 00000001      01000013)
[    0.000000] ACPI: FACP 0x000000009CFF0000 00010C (v05 HPQOEM SLIC-MPC 00000001 HP   00040000)
[    0.000000] ACPI: DSDT 0x000000009CFDC000 00F633 (v02 HPQOEM SLIC-MPC 00000000 ACPI 00040000)
[    0.000000] ACPI: FACS 0x000000009CF78000 000040
[    0.000000] ACPI: UEFI 0x000000009CFFD000 000236 (v01 HPQOEM INSYDE   00000001 HP   00040000)
[    0.000000] ACPI: UEFI 0x000000009CFFC000 000042 (v01 HPQOEM INSYDE   00000000 HP   00040000)
[    0.000000] ACPI: SSDT 0x000000009CFF5000 005F53 (v01 HPQOEM INSYDE   00001000 ACPI 00040000)
[    0.000000] ACPI: ASF! 0x000000009CFF4000 0000A5 (v32 HPQOEM INSYDE   00000001 HP   00040000)
[    0.000000] ACPI: ASPT 0x000000009CFF3000 000034 (v07 HPQOEM INSYDE   00000001 HP   00040000)
[    0.000000] ACPI: BOOT 0x000000009CFF2000 000028 (v01 HPQOEM INSYDE   00000001 HP   00040000)
[    0.000000] ACPI: DBGP 0x000000009CFF1000 000034 (v01 HPQOEM SLIC-MPC 00000001 HP   00040000)
[    0.000000] ACPI: HPET 0x000000009CFEF000 000038 (v01 HPQOEM INSYDE   00000001 HP   00040000)
[    0.000000] ACPI: LPIT 0x000000009CFEE000 000094 (v01 HPQOEM INSYDE   00000000 HP   00040000)
[    0.000000] ACPI: APIC 0x000000009CFED000 00008C (v03 HPQOEM SLIC-MPC 00000001 HP   00040000)
[    0.000000] ACPI: MCFG 0x000000009CFEC000 00003C (v01 HPQOEM INSYDE   00000001 HP   00040000)
[    0.000000] ACPI: SSDT 0x000000009CFD8000 00342D (v02 HPQOEM INSYDE   00001000 ACPI 00040000)
[    0.000000] ACPI: SSDT 0x000000009CFD7000 00064B (v02 HPQOEM INSYDE   00001000 ACPI 00040000)
[    0.000000] ACPI: SSDT 0x000000009CFD6000 000539 (v02 HPQOEM INSYDE   00003000 ACPI 00040000)
[    0.000000] ACPI: SSDT 0x000000009CFD5000 000B74 (v02 HPQOEM INSYDE   00003000 ACPI 00040000)
[    0.000000] ACPI: SSDT 0x000000009CFCF000 005B22 (v02 HPQOEM INSYDE   00003000 ACPI 00040000)
[    0.000000] ACPI: DMAR 0x000000009CFCE000 0000B0 (v01 HPQOEM INSYDE   00000001 HP   00040000)
[    0.000000] ACPI: FPDT 0x000000009CFCD000 000044 (v01 HPQOEM SLIC-MPC 00000002 HP   00040000)
[    0.000000] ACPI: BGRT 0x000000009CFCC000 000038 (v01 HPQOEM INSYDE   00000001 HP   00040000)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] tsc: Fast TSC calibration using PIT
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
[    0.000000]   Normal   [mem 0x0000000100000000-0x000000015dffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x0000000000057fff]
[    0.000000]   node   0: [mem 0x0000000000059000-0x0000000000087fff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x000000009bb7efff]
[    0.000000]   node   0: [mem 0x000000009cfff000-0x000000009cffffff]
[    0.000000]   node   0: [mem 0x0000000100000000-0x000000015dffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000015dffffff]
[    0.000000] On node 0 totalpages: 1022726
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 95 pages reserved
[    0.000000]   DMA zone: 3974 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 9902 pages used for memmap
[    0.000000]   DMA32 zone: 633728 pages, LIFO batch:31
[    0.000000]   Normal zone: 6016 pages used for memmap
[    0.000000]   Normal zone: 385024 pages, LIFO batch:31
[    0.000000] Reserved but unavailable: 100 pages
[    0.000000] Reserving Intel graphics memory at 0x000000009e000000-0x000000009fffffff
[    0.000000] ACPI: PM-Timer IO Port: 0x1808
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-39
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] [Firmware Bug]: TSC_DEADLINE disabled due to Errata; please update microcode to version: 0x25 (or later)
[    0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs
[    0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.000000] PM: Registered nosave memory: [mem 0x00058000-0x00058fff]
[    0.000000] PM: Registered nosave memory: [mem 0x00088000-0x000bffff]
[    0.000000] PM: Registered nosave memory: [mem 0x000c0000-0x000fffff]
[    0.000000] PM: Registered nosave memory: [mem 0x81aea000-0x81aeafff]
[    0.000000] PM: Registered nosave memory: [mem 0x81afa000-0x81afafff]
[    0.000000] PM: Registered nosave memory: [mem 0x9bb7f000-0x9cb7efff]
[    0.000000] PM: Registered nosave memory: [mem 0x9cb7f000-0x9cf7efff]
[    0.000000] PM: Registered nosave memory: [mem 0x9cf7f000-0x9cffefff]
[    0.000000] PM: Registered nosave memory: [mem 0x9d000000-0x9fffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xa0000000-0xdfffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xe0000000-0xefffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xf0000000-0xfe100fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfe101000-0xfe112fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfe113000-0xfeafffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfeb00000-0xfeb03fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfeb04000-0xfebfffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfec00000-0xfec00fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfec01000-0xfed0ffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfed10000-0xfed19fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfed1a000-0xfed1bfff]
[    0.000000] PM: Registered nosave memory: [mem 0xfed1c000-0xfed1ffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfed20000-0xfedfffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfee00000-0xfee00fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfee01000-0xff9fffff]
[    0.000000] PM: Registered nosave memory: [mem 0xffa00000-0xffffffff]
[    0.000000] e820: [mem 0xa0000000-0xdfffffff] available for PCI devices
[    0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:4 nr_node_ids:1
[    0.000000] percpu: Embedded 38 pages/cpu @        (ptrval) s115536 r8192 d31920 u524288
[    0.000000] pcpu-alloc: s115536 r8192 d31920 u524288 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 1 2 3 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1006649
[    0.000000] Kernel command line: BOOT_IMAGE=/bzImage
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.000000] Memory: 3717164K/4090904K available (6674K kernel code, 1134K rwdata, 2980K rodata, 1248K init, 12464K bss, 373740K reserved, 0K cma-reserved)
[    0.000000] ftrace: allocating 30082 entries in 118 pages
[    0.000000] Running RCU self tests
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] 	RCU event tracing is enabled.
[    0.000000] 	RCU lockdep checking is enabled.
[    0.000000] 	RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4.
[    0.000000] 	Tasks RCU enabled.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 4352, nr_irqs: 728, preallocated irqs: 16
[    0.000000] Console: colour dummy device 80x25
[    0.000000] console [tty0] enabled
[    0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[    0.000000] ... MAX_LOCKDEP_SUBCLASSES:  8
[    0.000000] ... MAX_LOCK_DEPTH:          48
[    0.000000] ... MAX_LOCKDEP_KEYS:        8191
[    0.000000] ... CLASSHASH_SIZE:          4096
[    0.000000] ... MAX_LOCKDEP_ENTRIES:     32768
[    0.000000] ... MAX_LOCKDEP_CHAINS:      65536
[    0.000000] ... CHAINHASH_SIZE:          32768
[    0.000000]  memory used by lock dependency info: 7391 kB
[    0.000000]  per task-struct memory footprint: 2304 bytes
[    0.000000] ACPI: Core revision 20170831
[    0.000000] ACPI: 7 ACPI AML tables successfully acquired and loaded
[    0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 133484882848 ns
[    0.000000] hpet clockevent registered
[    0.000000] APIC: Switch to symmetric I/O mode setup
[    0.000000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.005000] tsc: Fast TSC calibration using PIT
[    0.006000] tsc: Detected 2095.086 MHz processor
[    0.006000] Calibrating delay loop (skipped), value calculated using timer frequency.. 4190.17 BogoMIPS (lpj=2095086)
[    0.006000] pid_max: default: 32768 minimum: 301
[    0.008262] Security Framework initialized
[    0.008295] SELinux:  Initializing.
[    0.008435] SELinux:  Starting in permissive mode
[    0.008567] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.008612] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.010651] CPU: Physical Processor ID: 0
[    0.010677] CPU: Processor Core ID: 0
[    0.010703] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
[    0.010730] ENERGY_PERF_BIAS: View and update with x86_energy_perf_policy(8)
[    0.010765] mce: CPU supports 7 MCE banks
[    0.010816] process: using mwait in idle threads
[    0.010845] Last level iTLB entries: 4KB 64, 2MB 8, 4MB 8
[    0.010875] Last level dTLB entries: 4KB 64, 2MB 0, 4MB 0, 1GB 4
[    0.011204] Freeing SMP alternatives memory: 24K
[    0.029000] smpboot: CPU0: Intel(R) Core(TM) i3-5010U CPU @ 2.10GHz (family: 0x6, model: 0x3d, stepping: 0x4)
[    0.032090] Performance Events: PEBS fmt2+, Broadwell events, 16-deep LBR, full-width counters, Intel PMU driver.
[    0.032247] ... version:                3
[    0.032273] ... bit width:              48
[    0.032300] ... generic registers:      4
[    0.032326] ... value mask:             0000ffffffffffff
[    0.032355] ... max period:             00007fffffffffff
[    0.032385] ... fixed-purpose events:   3
[    0.032411] ... event mask:             000000070000000f
[    0.034032] Hierarchical SRCU implementation.
[    0.040034] smp: Bringing up secondary CPUs ...
[    0.047787] x86: Booting SMP configuration:
[    0.047824] .... node  #0, CPUs:      #1 #2 #3
[    0.064264] smp: Brought up 1 node, 4 CPUs
[    0.064264] smpboot: Max logical packages: 1
[    0.064264] smpboot: Total of 4 processors activated (16760.68 BogoMIPS)
[    0.066356] devtmpfs: initialized
[    0.068775] PM: Registering ACPI NVS region [mem 0x9cb7f000-0x9cf7efff] (4194304 bytes)
[    0.073633] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    0.073691] futex hash table entries: 1024 (order: 5, 131072 bytes)
[    0.075585] NET: Registered protocol family 16
[    0.076880] audit: initializing netlink subsys (disabled)
[    0.077146] audit: type=2000 audit(1512149086.077:1): state=initialized audit_enabled=0 res=1
[    0.080264] cpuidle: using governor ladder
[    0.080381] cpuidle: using governor menu
[    0.080629] Simple Boot Flag at 0x44 set to 0x1
[    0.080739] ACPI FADT declares the system doesn't support PCIe ASPM, so disable it
[    0.080792] ACPI: bus type PCI registered
[    0.081692] PCI: Using configuration type 1 for base access
[    0.133502] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.137126] ACPI: Added _OSI(Module Device)
[    0.137163] ACPI: Added _OSI(Processor Device)
[    0.137198] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.137233] ACPI: Added _OSI(Processor Aggregator Device)
[    0.143679] ACPI: Executed 13 blocks of module-level executable AML code
[    0.378252] ACPI: Dynamic OEM Table Load:
[    0.378389] ACPI: SSDT 0xFFFF880158A6B800 0003D3 (v02 PmRef  Cpu0Cst  00003001 INTL 20130117)
[    0.382101] ACPI: Dynamic OEM Table Load:
[    0.382237] ACPI: SSDT 0xFFFF880159122800 0005AA (v02 PmRef  ApIst    00003000 INTL 20130117)
[    0.386731] ACPI: Dynamic OEM Table Load:
[    0.386880] ACPI: SSDT 0xFFFF880158AC9400 000119 (v02 PmRef  ApCst    00003000 INTL 20130117)
[    0.400284] ACPI: EC: EC started
[    0.400318] ACPI: EC: interrupt blocked
[    0.447013] ACPI: \_SB_.PCI0.LPCB.H_EC: Used as first EC
[    0.447050] ACPI: \_SB_.PCI0.LPCB.H_EC: GPE=0x7, EC_CMD/EC_SC=0x66, EC_DATA=0x62
[    0.447091] ACPI: \_SB_.PCI0.LPCB.H_EC: Used as boot DSDT EC to handle transactions
[    0.447127] ACPI: Interpreter enabled
[    0.447352] ACPI: (supports S0 S3 S4 S5)
[    0.447383] ACPI: Using IOAPIC for interrupt routing
[    0.447816] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.454210] ACPI: Enabled 8 GPEs in block 00 to 7F
[    0.671240] ACPI: Power Resource [FN00] (on)
[    0.695816] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-fe])
[    0.695864] acpi PNP0A08:00: _OSC: OS supports [Segments MSI]
[    0.697425] acpi PNP0A08:00: _OSC failed (AE_ERROR); disabling ASPM
[    0.701549] PCI host bridge to bus 0000:00
[    0.701581] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.701617] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.701652] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.701689] pci_bus 0000:00: root bus resource [mem 0xa0000000-0xfeafffff window]
[    0.701729] pci_bus 0000:00: root bus resource [bus 00-fe]
[    0.701838] pci 0000:00:00.0: [8086:1604] type 00 class 0x060000
[    0.704672] pci 0000:00:02.0: [8086:1616] type 00 class 0x030000
[    0.704736] pci 0000:00:02.0: reg 0x10: [mem 0xc1000000-0xc1ffffff 64bit]
[    0.704780] pci 0000:00:02.0: reg 0x18: [mem 0xb0000000-0xbfffffff 64bit pref]
[    0.704813] pci 0000:00:02.0: reg 0x20: [io  0x6000-0x603f]
[    0.704894] pci 0000:00:02.0: BAR 2: assigned to efifb
[    0.707720] pci 0000:00:03.0: [8086:160c] type 00 class 0x040300
[    0.707780] pci 0000:00:03.0: reg 0x10: [mem 0xc3210000-0xc3213fff 64bit]
[    0.710721] pci 0000:00:14.0: [8086:9cb1] type 00 class 0x0c0330
[    0.710802] pci 0000:00:14.0: reg 0x10: [mem 0xc3200000-0xc320ffff 64bit]
[    0.711045] pci 0000:00:14.0: PME# supported from D3hot D3cold
[    0.713803] pci 0000:00:16.0: [8086:9cba] type 00 class 0x078000
[    0.713875] pci 0000:00:16.0: reg 0x10: [mem 0xc321b000-0xc321b01f 64bit]
[    0.714119] pci 0000:00:16.0: PME# supported from D0 D3hot D3cold
[    0.716759] pci 0000:00:1b.0: [8086:9ca0] type 00 class 0x040300
[    0.716840] pci 0000:00:1b.0: reg 0x10: [mem 0xc3214000-0xc3217fff 64bit]
[    0.717078] pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold
[    0.719773] pci 0000:00:1c.0: [8086:9c90] type 01 class 0x060400
[    0.720037] pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold
[    0.723357] pci 0000:00:1c.1: [8086:9c92] type 01 class 0x060400
[    0.723599] pci 0000:00:1c.1: PME# supported from D0 D3hot D3cold
[    0.726920] pci 0000:00:1c.3: [8086:9c96] type 01 class 0x060400
[    0.727184] pci 0000:00:1c.3: PME# supported from D0 D3hot D3cold
[    0.730527] pci 0000:00:1d.0: [8086:9ca6] type 00 class 0x0c0320
[    0.731021] pci 0000:00:1d.0: reg 0x10: [mem 0xc3219000-0xc32193ff]
[    0.732487] pci 0000:00:1d.0: PME# supported from D0 D3hot D3cold
[    0.735316] pci 0000:00:1f.0: [8086:9cc3] type 00 class 0x060100
[    0.738241] pci 0000:00:1f.2: [8086:9c83] type 00 class 0x010601
[    0.738301] pci 0000:00:1f.2: reg 0x10: [io  0x6088-0x608f]
[    0.738330] pci 0000:00:1f.2: reg 0x14: [io  0x609c-0x609f]
[    0.738359] pci 0000:00:1f.2: reg 0x18: [io  0x6080-0x6087]
[    0.738387] pci 0000:00:1f.2: reg 0x1c: [io  0x6098-0x609b]
[    0.738415] pci 0000:00:1f.2: reg 0x20: [io  0x6060-0x607f]
[    0.738444] pci 0000:00:1f.2: reg 0x24: [mem 0xc3218000-0xc32187ff]
[    0.738598] pci 0000:00:1f.2: PME# supported from D3hot
[    0.741330] pci 0000:00:1f.3: [8086:9ca2] type 00 class 0x0c0500
[    0.741391] pci 0000:00:1f.3: reg 0x10: [mem 0xc321a000-0xc321a0ff 64bit]
[    0.741456] pci 0000:00:1f.3: reg 0x20: [io  0x6040-0x605f]
[    0.744603] pci 0000:01:00.0: [10ec:8168] type 00 class 0x020000
[    0.744659] pci 0000:01:00.0: reg 0x10: [io  0x5000-0x50ff]
[    0.744728] pci 0000:01:00.0: reg 0x18: [mem 0xc3104000-0xc3104fff 64bit]
[    0.744780] pci 0000:01:00.0: reg 0x20: [mem 0xc3100000-0xc3103fff 64bit]
[    0.745047] pci 0000:01:00.0: supports D1 D2
[    0.745058] pci 0000:01:00.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.745790] pci 0000:00:1c.0: PCI bridge to [bus 01]
[    0.745827] pci 0000:00:1c.0:   bridge window [io  0x5000-0x5fff]
[    0.745843] pci 0000:00:1c.0:   bridge window [mem 0xc3100000-0xc31fffff]
[    0.747369] pci 0000:02:00.0: [10ec:b723] type 00 class 0x028000
[    0.747598] pci 0000:02:00.0: reg 0x10: [io  0x4000-0x40ff]
[    0.748394] pci 0000:02:00.0: reg 0x18: [mem 0xc3000000-0xc3003fff 64bit]
[    0.750363] pci 0000:02:00.0: supports D1 D2
[    0.750375] pci 0000:02:00.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.752091] pci 0000:00:1c.1: PCI bridge to [bus 02]
[    0.752127] pci 0000:00:1c.1:   bridge window [io  0x4000-0x4fff]
[    0.752143] pci 0000:00:1c.1:   bridge window [mem 0xc3000000-0xc30fffff]
[    0.752596] pci 0000:03:00.0: [10ec:5227] type 00 class 0xff0000
[    0.752651] pci 0000:03:00.0: reg 0x10: [mem 0xc2000000-0xc2000fff]
[    0.752992] pci 0000:03:00.0: supports D1 D2
[    0.753026] pci 0000:03:00.0: PME# supported from D1 D2 D3hot D3cold
[    0.753727] pci 0000:00:1c.3: PCI bridge to [bus 03-08]
[    0.753765] pci 0000:00:1c.3:   bridge window [io  0x3000-0x3fff]
[    0.753791] pci 0000:00:1c.3:   bridge window [mem 0xc2000000-0xc2ffffff]
[    0.753812] pci 0000:00:1c.3:   bridge window [mem 0xc0000000-0xc0ffffff 64bit pref]
[    0.753871] pci_bus 0000:00: on NUMA node 0
[    0.765535] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 10 11 12 14 15) *7
[    0.766394] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 *10 11 12 14 15)
[    0.767239] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 10 11 12 14 15) *7
[    0.768080] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 *10 11 12 14 15)
[    0.768894] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 10 11 12 14 15) *0, disabled.
[    0.769736] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 10 11 12 14 15) *0, disabled.
[    0.770588] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 10 *11 12 14 15)
[    0.771427] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 6 10 *11 12 14 15)
[    0.776588] ACPI: EC: interrupt unblocked
[    0.776631] ACPI: EC: event unblocked
[    0.776796] ACPI: \_SB_.PCI0.LPCB.H_EC: GPE=0x7, EC_CMD/EC_SC=0x66, EC_DATA=0x62
[    0.776842] ACPI: \_SB_.PCI0.LPCB.H_EC: Used as boot DSDT EC to handle transactions and events
[    0.779305] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[    0.779343] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    0.779445] pci 0000:00:02.0: vgaarb: bridge control possible
[    0.779475] vgaarb: loaded
[    0.782164] SCSI subsystem initialized
[    0.782532] libata version 3.00 loaded.
[    0.783615] ACPI: bus type USB registered
[    0.784171] usbcore: registered new interface driver usbfs
[    0.784462] usbcore: registered new interface driver hub
[    0.785013] usbcore: registered new device driver usb
[    0.785473] pps_core: LinuxPPS API ver. 1 registered
[    0.785504] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.785693] PTP clock support registered
[    0.785974] Registered efivars operations
[    0.813175] PCI: Using ACPI for IRQ routing
[    0.813208] PCI: pci_cache_line_size set to 64 bytes
[    0.814065] e820: reserve RAM buffer [mem 0x00058000-0x0005ffff]
[    0.814096] e820: reserve RAM buffer [mem 0x00088000-0x0008ffff]
[    0.814117] e820: reserve RAM buffer [mem 0x81aea018-0x83ffffff]
[    0.814138] e820: reserve RAM buffer [mem 0x9bb7f000-0x9bffffff]
[    0.814159] e820: reserve RAM buffer [mem 0x9d000000-0x9fffffff]
[    0.814180] e820: reserve RAM buffer [mem 0x15e000000-0x15fffffff]
[    0.816438] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0
[    0.816563] hpet0: 8 comparators, 64-bit 14.318180 MHz counter
[    0.818229] clocksource: Switched to clocksource hpet
[    0.935533] pnp: PnP ACPI init
[    0.937797] system 00:00: [io  0x0680-0x069f] has been reserved
[    0.937847] system 00:00: [io  0xffff] has been reserved
[    0.937885] system 00:00: [io  0xffff] has been reserved
[    0.937921] system 00:00: [io  0xffff] has been reserved
[    0.937958] system 00:00: [io  0x0500-0x0501] has been reserved
[    0.937996] system 00:00: [io  0x1800-0x18fe] has been reserved
[    0.938070] system 00:00: [io  0x164e-0x164f] has been reserved
[    0.938327] system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.939068] pnp 00:01: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.939980] system 00:02: [io  0x1854-0x1857] has been reserved
[    0.940294] system 00:02: Plug and Play ACPI device, IDs INT3f0d PNP0c02 (active)
[    0.940817] pnp 00:03: Plug and Play ACPI device, IDs HPQ8001 PNP0303 (active)
[    0.941393] pnp 00:04: Plug and Play ACPI device, IDs SYN1eac SYN1e00 SYN0002 PNP0f13 PNP0f03 (active)
[    0.945962] system 00:05: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.950520] system 00:06: [mem 0xfed1c000-0xfed1ffff] has been reserved
[    0.950566] system 00:06: [mem 0xfed10000-0xfed17fff] has been reserved
[    0.950607] system 00:06: [mem 0xfed18000-0xfed18fff] has been reserved
[    0.950648] system 00:06: [mem 0xfed19000-0xfed19fff] has been reserved
[    0.950688] system 00:06: [mem 0xe0000000-0xefffffff] has been reserved
[    0.950729] system 00:06: [mem 0xfed20000-0xfed3ffff] has been reserved
[    0.950769] system 00:06: [mem 0xfed90000-0xfed93fff] has been reserved
[    0.950809] system 00:06: [mem 0xfed45000-0xfed8ffff] has been reserved
[    0.950850] system 00:06: [mem 0xff000000-0xffffffff] could not be reserved
[    0.950892] system 00:06: [mem 0xfee00000-0xfeefffff] could not be reserved
[    0.950934] system 00:06: [mem 0xa0010000-0xa001ffff] has been reserved
[    0.950974] system 00:06: [mem 0xa0000000-0xa000ffff] has been reserved
[    0.951234] system 00:06: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.954780] pnp: PnP ACPI: found 7 devices
[    0.993973] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.994137] pci 0000:00:1c.0: PCI bridge to [bus 01]
[    0.994169] pci 0000:00:1c.0:   bridge window [io  0x5000-0x5fff]
[    0.994208] pci 0000:00:1c.0:   bridge window [mem 0xc3100000-0xc31fffff]
[    0.994257] pci 0000:00:1c.1: PCI bridge to [bus 02]
[    0.994286] pci 0000:00:1c.1:   bridge window [io  0x4000-0x4fff]
[    0.994324] pci 0000:00:1c.1:   bridge window [mem 0xc3000000-0xc30fffff]
[    0.994373] pci 0000:00:1c.3: PCI bridge to [bus 03-08]
[    0.994402] pci 0000:00:1c.3:   bridge window [io  0x3000-0x3fff]
[    0.994440] pci 0000:00:1c.3:   bridge window [mem 0xc2000000-0xc2ffffff]
[    0.994476] pci 0000:00:1c.3:   bridge window [mem 0xc0000000-0xc0ffffff 64bit pref]
[    0.994523] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.994534] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.994545] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.994556] pci_bus 0000:00: resource 7 [mem 0xa0000000-0xfeafffff window]
[    0.994566] pci_bus 0000:01: resource 0 [io  0x5000-0x5fff]
[    0.994577] pci_bus 0000:01: resource 1 [mem 0xc3100000-0xc31fffff]
[    0.994587] pci_bus 0000:02: resource 0 [io  0x4000-0x4fff]
[    0.994598] pci_bus 0000:02: resource 1 [mem 0xc3000000-0xc30fffff]
[    0.994608] pci_bus 0000:03: resource 0 [io  0x3000-0x3fff]
[    0.994619] pci_bus 0000:03: resource 1 [mem 0xc2000000-0xc2ffffff]
[    0.994629] pci_bus 0000:03: resource 2 [mem 0xc0000000-0xc0ffffff 64bit pref]
[    0.996628] NET: Registered protocol family 2
[    0.997779] TCP established hash table entries: 32768 (order: 6, 262144 bytes)
[    0.998075] TCP bind hash table entries: 32768 (order: 9, 2359296 bytes)
[    1.000228] TCP: Hash tables configured (established 32768 bind 32768)
[    1.000522] UDP hash table entries: 2048 (order: 6, 327680 bytes)
[    1.000855] UDP-Lite hash table entries: 2048 (order: 6, 327680 bytes)
[    1.001795] NET: Registered protocol family 1
[    1.001894] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    1.002435] PCI: CLS 64 bytes, default 64
[    1.003143] Trying to unpack rootfs image as initramfs...
[    1.695890] Freeing initrd memory: 20256K
[    1.695928] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[    1.695963] software IO TLB [mem 0x8f0c6000-0x930c6000] (64MB) mapped at [00000000c7f731c5-00000000addc819a]
[    1.696356] RAPL PMU: API unit is 2^-32 Joules, 4 fixed counters, 655360 ms ovfl timer
[    1.696396] RAPL PMU: hw unit of domain pp0-core 2^-14 Joules
[    1.696426] RAPL PMU: hw unit of domain package 2^-14 Joules
[    1.696457] RAPL PMU: hw unit of domain dram 2^-14 Joules
[    1.696486] RAPL PMU: hw unit of domain pp1-gpu 2^-14 Joules
[    1.698116] Machine check injector initialized
[    1.714256] Initialise system trusted keyrings
[    1.714636] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[    1.716473] jffs2: version 2.2. (NAND) \xc2\xa9 2001-2006 Red Hat, Inc.
[    1.717328] SELinux:  Registering netfilter hooks
[    1.724789] Key type asymmetric registered
[    1.724839] Asymmetric key parser 'x509' registered
[    1.724995] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    1.725380] io scheduler noop registered
[    1.725430] io scheduler deadline registered
[    1.725569] io scheduler cfq registered (default)
[    1.733381] efifb: probing for efifb
[    1.733493] efifb: framebuffer at 0xb0000000, using 4128k, total 4128k
[    1.733556] efifb: mode is 1366x768x32, linelength=5504, pages=1
[    1.733599] efifb: scrolling: redraw
[    1.733624] efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0
[    1.738200] Console: switching to colour frame buffer device 170x48
[    1.741443] fb0: EFI VGA frame buffer device
[    1.951677] ACPI: AC Adapter [AC] (off-line)
[    1.952656] input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0D:00/input/input0
[    1.952827] ACPI: Lid Switch [LID0]
[    1.953336] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input1
[    1.953546] ACPI: Power Button [PWRB]
[    1.954183] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
[    1.954528] ACPI: Power Button [PWRF]
[    1.961063] Monitor-Mwait will be used to enter C-1 state
[    1.961173] Monitor-Mwait will be used to enter C-2 state
[    1.961223] Monitor-Mwait will be used to enter C-3 state
[    1.993203] thermal LNXTHERM:00: registered as thermal_zone0
[    1.993269] ACPI: Thermal Zone [TZ00] (39 C)
[    1.998673] thermal LNXTHERM:01: registered as thermal_zone1
[    1.998739] ACPI: Thermal Zone [TZ01] (0 C)
[    2.000812] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    2.019720] Non-volatile memory driver v1.3
[    2.020288] Linux agpgart interface v0.103
[    2.021925] intelfb: Framebuffer driver for Intel(R) 830M/845G/852GM/855GM/865G/915G/915GM/945G/945GM/945GME/965G/965GM chipsets
[    2.022143] intelfb: Version 0.9.6
[    2.051378] ACPI: Battery Slot [BAT0] (battery present)
[    2.051550] brd: module loaded
[    2.063463] loop: module loaded
[    2.064530] ahci 0000:00:1f.2: version 3.0
[    2.066638] ahci 0000:00:1f.2: SSS flag set, parallel bus scan disabled
[    2.076875] ahci 0000:00:1f.2: AHCI 0001.0300 32 slots 4 ports 6 Gbps 0x3 impl SATA mode
[    2.076962] ahci 0000:00:1f.2: flags: 64bit ncq ilck stag pm led clo pio slum part deso sadm sds apst 
[    2.086360] scsi host0: ahci
[    2.089773] scsi host1: ahci
[    2.091971] scsi host2: ahci
[    2.095316] scsi host3: ahci
[    2.097119] ata1: SATA max UDMA/133 abar m2048@0xc3218000 port 0xc3218100 irq 40
[    2.098322] ata2: SATA max UDMA/133 abar m2048@0xc3218000 port 0xc3218180 irq 40
[    2.099380] ata3: DUMMY
[    2.100419] ata4: DUMMY
[    2.132295] e100: Intel(R) PRO/100 Network Driver, 3.5.24-k2-NAPI
[    2.133517] e100: Copyright(c) 1999-2006 Intel Corporation
[    2.134761] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[    2.135958] e1000: Copyright (c) 1999-2006 Intel Corporation.
[    2.137258] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k
[    2.138446] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[    2.139683] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    2.140897] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    2.142074] ohci-platform: OHCI generic platform driver
[    2.143395] usbcore: registered new interface driver usb-storage
[    2.144912] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12
[    2.155705] serio: i8042 KBD port at 0x60,0x64 irq 1
[    2.157373] serio: i8042 AUX port at 0x60,0x64 irq 12
[    2.159660] mousedev: PS/2 mouse device common for all mice
[    2.162768] EFI Variables Facility v0.08 2004-May-17
[    2.174776] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3
[    2.214752] usbcore: registered new interface driver usbhid
[    2.216247] usbhid: USB HID core driver
[    2.217399] oprofile: using NMI interrupt.
[    2.218596] pktgen: Packet Generator for packet performance testing. Version: 2.75
[    2.221966] Netfilter messages via NETLINK v0.30.
[    2.223302] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
[    2.224544] ctnetlink v0.93: registering with nfnetlink.
[    2.226117] Initializing XFRM netlink socket
[    2.227220] NET: Registered protocol family 17
[    2.228273] 8021q: 802.1Q VLAN Support v1.8
[    2.229423] sctp: Hash tables configured (bind 32/56)
[    2.230633] start plist test
[    2.232396] end plist test
[    2.235156] microcode: sig=0x306d4, pf=0x40, revision=0x1d
[    2.236777] microcode: Microcode Update Driver: v2.2.
[    2.236954] sched_clock: Marking stable (2236705282, 0)->(2346971650, -110266368)
[    2.241119] registered taskstats version 1
[    2.242184] Loading compiled-in X.509 certificates
[    2.414243] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[    2.416839] ata1.00: ATA-9: HGST HTS545050A7E680, GR2OA350, max UDMA/133
[    2.417866] ata1.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
[    2.420573] ata1.00: configured for UDMA/133
[    2.423088] scsi 0:0:0:0: Direct-Access     ATA      HGST HTS545050A7 A350 PQ: 0 ANSI: 5
[    2.427315] sd 0:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/466 GiB)
[    2.427500] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    2.430151] sd 0:0:0:0: [sda] 4096-byte physical blocks
[    2.431286] sd 0:0:0:0: [sda] Write Protect is off
[    2.432306] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    2.432411] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    2.505519]  sda: sda1 sda2 sda3 sda4 sda5
[    2.510684] sd 0:0:0:0: [sda] Attached SCSI disk
[    2.719225] tsc: Refined TSC clocksource calibration: 2095.149 MHz
[    2.720316] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1e3349f83e9, max_idle_ns: 440795233330 ns
[    2.743109] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[    2.747135] ata2.00: ATAPI: hp       DVDRW  GUB0N, UB00, max UDMA/133
[    2.752523] ata2.00: configured for UDMA/133
[    2.758295] scsi 1:0:0:0: CD-ROM            hp       DVDRW  GUB0N     UB00 PQ: 0 ANSI: 5
[    2.773971] scsi 1:0:0:0: Attached scsi generic sg1 type 5
[    2.787858] Freeing unused kernel memory: 1248K
[    2.789043] Write protecting the kernel read-only data: 12288k
[    2.795181] Freeing unused kernel memory: 1504K
[    2.801515] Freeing unused kernel memory: 1116K
[    3.096940] psmouse serio1: synaptics: queried max coordinates: x [..5716], y [..4886]
[    3.147098] psmouse serio1: synaptics: queried min coordinates: x [1220..], y [986..]
[    3.242957] psmouse serio1: synaptics: Touchpad model: 1, fw: 7.5, id: 0x1e0b1, caps: 0xf00173/0x640000/0xa2400/0x0, board id: 2654, fw id: 1323623
[    3.303440] input: SynPS/2 Synaptics TouchPad as /devices/platform/i8042/serio1/input/input5
[    3.727619] clocksource: Switched to clocksource tsc
[    4.449337] random: crng init done
[    7.883678] EXT4-fs (sda4): couldn't mount as ext3 due to feature incompatibilities
[    7.885701] EXT4-fs (sda4): couldn't mount as ext2 due to feature incompatibilities
[    7.888264] EXT4-fs (sda4): INFO: recovery required on readonly filesystem
[    7.889375] EXT4-fs (sda4): write access will be enabled during recovery
[    9.276783] EXT4-fs (sda4): recovery complete
[    9.294857] EXT4-fs (sda4): mounted filesystem with ordered data mode. Opts: (null)
[    9.389628] EXT4-fs (sda5): couldn't mount as ext3 due to feature incompatibilities
[    9.391425] EXT4-fs (sda5): couldn't mount as ext2 due to feature incompatibilities
[    9.495150] EXT4-fs (sda5): mounted filesystem with ordered data mode. Opts: (null)
[    9.524513] EXT4-fs (sda2): couldn't mount as ext3 due to feature incompatibilities
[    9.526285] EXT4-fs (sda2): couldn't mount as ext2 due to feature incompatibilities
[    9.636938] EXT4-fs (sda2): recovery complete
[    9.657523] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null)
[   84.701707] ubi0: default fastmap pool size: 10
[   84.702415] ubi0: default fastmap WL pool size: 5
[   84.703200] ubi0: attaching mtd0
[   84.704953] ubi0: scanning is finished
[   84.708489] ubi0: attached mtd0 (name "mtdram test device", size 32 MiB)
[   84.709279] ubi0: PEB size: 131072 bytes (128 KiB), LEB size: 126976 bytes
[   84.710018] ubi0: min./max. I/O unit sizes: 2048/2048, sub-page size 2048
[   84.710832] ubi0: VID header offset: 2048 (aligned 2048), data offset: 4096
[   84.711634] ubi0: good PEBs: 256, bad PEBs: 0, corrupted PEBs: 0
[   84.712419] ubi0: user volume: 1, internal volumes: 1, max. volumes count: 128
[   84.713195] ubi0: max/mean erase counter: 85/32, WL threshold: 4096, image sequence number: 18852820
[   84.713940] ubi0: available PEBs: 2, total reserved PEBs: 254, PEBs reserved for bad PEB handling: 0
[   84.714764] ubi0: background thread "ubi_bgt0d" started, PID 1060
[   98.436808] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" started, PID 1067
[   98.437013] UBIFS (ubi0:0): recovery needed
[   98.481822] UBIFS error (ubi0:0 pid 1065): ubifs_check_node: bad CRC: calculated 0xbe6fe258, read 0x3ca380fb
[   98.482784] UBIFS error (ubi0:0 pid 1065): ubifs_check_node: bad node at LEB 85:2016
[   98.483746] 	magic          0x6101831
[   98.484678] 	crc            0x3ca380fb
[   98.485600] 	node_type      9 (indexing node)
[   98.486513] 	group_type     0 (no node group)
[   98.487417] 	sqnum          794410
[   98.488311] 	len            108
[   98.489199] 	child_cnt      4
[   98.490077] 	level          1
[   98.490925] 	Branches:
[   98.491796] 	0: LEB 85:-1 len -1 key (bad key type: 0xffffffff, 0xffffffff)
[   98.492718] 	1: LEB -1:-1 len -1 key (bad key type: 0xffffffff, 0xffffffff)
[   98.493635] 	2: LEB -1:-1 len -1 key (bad key type: 0xffffffff, 0xffffffff)
[   98.494543] 	3: LEB -1:-1 len -1 key (bad key type: 0xffffffff, 0xffffffff)
[   98.495453] CPU: 2 PID: 1065 Comm: mount Not tainted 4.15.0-rc1+ #112
[   98.496353] Hardware name: Hewlett-Packard HP 350 G2/803A, BIOS F.13 06/10/2015
[   98.497294] Call Trace:
[   98.498200]  dump_stack+0x67/0x8e
[   98.499098]  ubifs_check_node+0x1b1/0x1be
[   98.499995]  ubifs_scan_a_node+0x153/0x223
[   98.500888]  ubifs_scan+0xab/0x23a
[   98.501773]  ubifs_garbage_collect_leb+0x1dd/0x735
[   98.502669]  ubifs_rcvry_gc_commit+0x1bc/0x27b
[   98.503560]  ubifs_mount+0x104a/0x1ace
[   98.504441]  ? sb_set+0x13/0x13
[   98.505325]  mount_fs+0x6a/0x109
[   98.506208]  vfs_kern_mount+0x6b/0x151
[   98.507094]  do_mount+0x917/0xb63
[   98.507965]  ? memdup_user+0x3e/0x5a
[   98.508828]  SyS_mount+0x72/0x98
[   98.509682]  entry_SYSCALL_64_fastpath+0x1c/0x89
[   98.510547] RIP: 0033:0x7fa9d48c37da
[   98.511407] RSP: 002b:00007ffd6f618b68 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
[   98.512322] RAX: ffffffffffffffda RBX: 00007fa9d4b88c20 RCX: 00007fa9d48c37da
[   98.513226] RDX: 0000563957947b50 RSI: 0000563957949890 RDI: 0000563957947b70
[   98.514144] RBP: 00007fa9d4b88c78 R08: 0000000000000000 R09: 00005639579498d0
[   98.515038] R10: 00000000c0ed0000 R11: 0000000000000246 R12: 0000000000001010
[   98.515925] R13: 0000000000001000 R14: 00007fa9d4b88c78 R15: 0000000000000000
[   98.516838] UBIFS error (ubi0:0 pid 1065): ubifs_scan: bad node
[   98.517737] UBIFS error (ubi0:0 pid 1065): ubifs_scanned_corruption: corruption at LEB 85:2016
[   98.518638] UBIFS error (ubi0:0 pid 1065): ubifs_scanned_corruption: first 8192 bytes from LEB 85:2016
[   98.519528] 00000000: 06101831 3ca380fb 000c1f2a 00000000 0000006c 00000009 00010004 00000055  1......<*.......l...........U...
[   98.519540] 00000020: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519552] 00000040: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519564] 00000060: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519576] 00000080: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519587] 000000a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519599] 000000c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519611] 000000e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519623] 00000100: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519635] 00000120: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519646] 00000140: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519658] 00000160: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519670] 00000180: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519682] 000001a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519694] 000001c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519705] 000001e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519717] 00000200: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519729] 00000220: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519741] 00000240: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519752] 00000260: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519764] 00000280: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519776] 000002a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519788] 000002c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519800] 000002e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519811] 00000300: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519823] 00000320: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519835] 00000340: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519847] 00000360: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519858] 00000380: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519870] 000003a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519882] 000003c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519894] 000003e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519905] 00000400: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519917] 00000420: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519929] 00000440: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519941] 00000460: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519953] 00000480: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519964] 000004a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519976] 000004c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.519988] 000004e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520000] 00000500: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520011] 00000520: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520046] 00000540: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520058] 00000560: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520070] 00000580: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520082] 000005a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520094] 000005c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520105] 000005e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520117] 00000600: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520129] 00000620: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520141] 00000640: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520153] 00000660: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520164] 00000680: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520176] 000006a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520188] 000006c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520200] 000006e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520211] 00000700: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520223] 00000720: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520235] 00000740: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520247] 00000760: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520258] 00000780: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520270] 000007a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520282] 000007c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520294] 000007e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520305] 00000800: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520317] 00000820: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520329] 00000840: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520341] 00000860: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520352] 00000880: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520364] 000008a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520376] 000008c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520388] 000008e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520399] 00000900: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520411] 00000920: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520423] 00000940: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520435] 00000960: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520446] 00000980: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520458] 000009a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520470] 000009c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520482] 000009e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520494] 00000a00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520505] 00000a20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520517] 00000a40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520529] 00000a60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520541] 00000a80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520552] 00000aa0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520564] 00000ac0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520576] 00000ae0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520588] 00000b00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520599] 00000b20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520611] 00000b40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520623] 00000b60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520635] 00000b80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520646] 00000ba0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520658] 00000bc0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520670] 00000be0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520682] 00000c00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520694] 00000c20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520705] 00000c40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520717] 00000c60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520729] 00000c80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520741] 00000ca0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520752] 00000cc0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520764] 00000ce0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520776] 00000d00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520788] 00000d20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520800] 00000d40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520811] 00000d60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520823] 00000d80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520835] 00000da0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520847] 00000dc0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520859] 00000de0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520870] 00000e00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520882] 00000e20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520894] 00000e40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520906] 00000e60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520917] 00000e80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520929] 00000ea0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520941] 00000ec0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520953] 00000ee0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520965] 00000f00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520976] 00000f20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.520988] 00000f40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521000] 00000f60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521012] 00000f80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521046] 00000fa0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521058] 00000fc0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521070] 00000fe0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521082] 00001000: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521093] 00001020: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521105] 00001040: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521117] 00001060: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521129] 00001080: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521141] 000010a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521152] 000010c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521164] 000010e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521176] 00001100: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521188] 00001120: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521199] 00001140: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521211] 00001160: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521223] 00001180: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521235] 000011a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521247] 000011c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521258] 000011e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521270] 00001200: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521282] 00001220: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521294] 00001240: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521305] 00001260: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521317] 00001280: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521329] 000012a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521341] 000012c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521352] 000012e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521364] 00001300: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521376] 00001320: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521388] 00001340: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521400] 00001360: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521411] 00001380: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521423] 000013a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521435] 000013c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521447] 000013e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521458] 00001400: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521470] 00001420: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521482] 00001440: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521494] 00001460: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521506] 00001480: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521517] 000014a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521529] 000014c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521541] 000014e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521553] 00001500: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521564] 00001520: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521576] 00001540: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521588] 00001560: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521600] 00001580: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521612] 000015a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521623] 000015c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521635] 000015e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521647] 00001600: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521659] 00001620: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521671] 00001640: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521682] 00001660: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521694] 00001680: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521706] 000016a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521718] 000016c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521730] 000016e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521741] 00001700: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521753] 00001720: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521765] 00001740: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521777] 00001760: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521789] 00001780: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521800] 000017a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521812] 000017c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521824] 000017e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521836] 00001800: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521848] 00001820: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521859] 00001840: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521871] 00001860: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521883] 00001880: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521895] 000018a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521906] 000018c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521918] 000018e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521930] 00001900: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521942] 00001920: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521954] 00001940: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521965] 00001960: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521977] 00001980: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.521989] 000019a0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522001] 000019c0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522013] 000019e0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522047] 00001a00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522059] 00001a20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522070] 00001a40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522082] 00001a60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522094] 00001a80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522106] 00001aa0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522118] 00001ac0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522129] 00001ae0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522141] 00001b00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522153] 00001b20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522165] 00001b40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522177] 00001b60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522188] 00001b80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522200] 00001ba0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522212] 00001bc0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522224] 00001be0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522236] 00001c00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522247] 00001c20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522259] 00001c40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522271] 00001c60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522283] 00001c80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522295] 00001ca0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522306] 00001cc0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522318] 00001ce0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522330] 00001d00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522342] 00001d20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522354] 00001d40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522366] 00001d60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522377] 00001d80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522389] 00001da0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522401] 00001dc0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522413] 00001de0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522424] 00001e00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522436] 00001e20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522448] 00001e40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522460] 00001e60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522472] 00001e80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522483] 00001ea0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522495] 00001ec0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522507] 00001ee0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522519] 00001f00: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522531] 00001f20: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522542] 00001f40: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522554] 00001f60: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522566] 00001f80: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522578] 00001fa0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522589] 00001fc0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522601] 00001fe0: ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff ffffffff  ................................
[   98.522614] UBIFS error (ubi0:0 pid 1065): ubifs_scan: LEB 85 scanning failed
[   98.523483] UBIFS error (ubi0:0 pid 1065): ubifs_rcvry_gc_commit: GC failed, error -117
[   98.524989] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" stops

Re: UBIFS does not mount after powerfail

[Richard Weinberger]

Manfred,

Am Freitag, 1. Dezember 2017, 08:41:28 CET schrieb Manfred Spraul:
> >> Image after that command:
> >> https://sourceforge.net/projects/calculix-rpm/files/ubifs/image-168169.bi
> >> n/d ownload
> > 
> > Which file contains the MTD with chk_fs being enabled?
> 
> I'm not sure if this help, it only adds more data that will confuse:
> The image.bin is obviously identical, I have only enabled chk_fs for the
> replay.
> 
> And: The mount was done using 4.14, the image was created using 4.14-rc5.
> If you want: Here is the data (dmesg output + images)
> https://sourceforge.net/projects/calculix-rpm/files/ubifs/chkfs_4.14/

I still don't get which Image is the right one.
I gave image-168167.bin a try, but it contains many errors, I'm not sure
if it contains the first error.

So, which file do I need to download to get the image that contains the first 
error as detected by chk_fs?

The mode of operation of your tool should be:
1. Mount UBIFS
2. Do various filesystem operations
3. Random power-cut (in software)
4. Dump the image to a file X
5. Set chk_fs to 1
6. Mount it again
7. If mount succeeds, drop X, umount and goto 1
   Else if, chk_fs finds a problem. Store file X.

I want file X. :-)

Thanks,
//richard

Re: UBIFS does not mount after powerfail

[Manfred Spraul]

On 12/05/2017 02:27 PM, Richard Weinberger wrote:
> Manfred,
>
> Am Freitag, 1. Dezember 2017, 08:41:28 CET schrieb Manfred Spraul:
>>>> Image after that command:
>>>> https://sourceforge.net/projects/calculix-rpm/files/ubifs/image-168169.bi
>>>> n/d ownload
>>> Which file contains the MTD with chk_fs being enabled?
>> I'm not sure if this help, it only adds more data that will confuse:
>> The image.bin is obviously identical, I have only enabled chk_fs for the
>> replay.
>>
>> And: The mount was done using 4.14, the image was created using 4.14-rc5.
>> If you want: Here is the data (dmesg output + images)
>> https://sourceforge.net/projects/calculix-rpm/files/ubifs/chkfs_4.14/
> I still don't get which Image is the right one.
> I gave image-168167.bin a try, but it contains many errors, I'm not sure
> if it contains the first error.
>
> So, which file do I need to download to get the image that contains the first
> error as detected by chk_fs?
>
> The mode of operation of your tool should be:
> 1. Mount UBIFS
> 2. Do various filesystem operations
> 3. Random power-cut (in software)
> 4. Dump the image to a file X
> 5. Set chk_fs to 1
> 6. Mount it again
> 7. If mount succeeds, drop X, umount and goto 1
>     Else if, chk_fs finds a problem. Store file X.
>
> I want file X. :-)
The tool is in release process, thus let's just wait a bit.
There is no "goto 1" in my flow.

Part 1: (done 5x, i.e. with xattr, without xattr, ...)

0. create UBI image, create empty UBIFS image.
1. Mount UBIFS
2. Do various filesystem operations.
    In the background, record nand operations and store it in a trace.

Part 2: (done ~100.000 times)
1. Create an image file X by replaying from the trace.
2. Set chk_fs to 1
3. Mount the image
7. If mount succeeds: ignore the file X
    If mount fails: remember file X

Thus every image is a first error. No image has seen multiple unclean 
unmounts.

--
     Manfred

Re: UBIFS does not mount after powerfail

[Richard Weinberger]

Manfred,

Am Dienstag, 5. Dezember 2017, 20:11:20 CET schrieb Manfred Spraul:
> > So, which file do I need to download to get the image that contains the
> > first error as detected by chk_fs?
> > 
> > The mode of operation of your tool should be:
> > 1. Mount UBIFS
> > 2. Do various filesystem operations
> > 3. Random power-cut (in software)
> > 4. Dump the image to a file X
> > 5. Set chk_fs to 1
> > 6. Mount it again
> > 7. If mount succeeds, drop X, umount and goto 1
> > 
> >     Else if, chk_fs finds a problem. Store file X.
> > 
> > I want file X. :-)
> 
> The tool is in release process, thus let's just wait a bit.
> There is no "goto 1" in my flow.
> 
> Part 1: (done 5x, i.e. with xattr, without xattr, ...)
> 
> 0. create UBI image, create empty UBIFS image.
> 1. Mount UBIFS
> 2. Do various filesystem operations.
>     In the background, record nand operations and store it in a trace.
> 
> Part 2: (done ~100.000 times)
> 1. Create an image file X by replaying from the trace.
> 2. Set chk_fs to 1
> 3. Mount the image
> 7. If mount succeeds: ignore the file X
>     If mount fails: remember file X
> 
> Thus every image is a first error. No image has seen multiple unclean
> unmounts.

Hmm, when does the power-cut occur in your setup?
i.e image-168167.bin and image-168168.bin show different errors and none of 
them fails the chf_fs test. They fail because of other reasons.

Thanks,
//richard

Re: UBIFS does not mount after powerfail

[Richard Weinberger]

Manfred,

Am Dienstag, 5. Dezember 2017, 21:06:28 CET schrieb Richard Weinberger:
> Manfred,
> 
> Am Dienstag, 5. Dezember 2017, 20:11:20 CET schrieb Manfred Spraul:
> > > So, which file do I need to download to get the image that contains the
> > > first error as detected by chk_fs?
> > > 
> > > The mode of operation of your tool should be:
> > > 1. Mount UBIFS
> > > 2. Do various filesystem operations
> > > 3. Random power-cut (in software)
> > > 4. Dump the image to a file X
> > > 5. Set chk_fs to 1
> > > 6. Mount it again
> > > 7. If mount succeeds, drop X, umount and goto 1
> > > 
> > >     Else if, chk_fs finds a problem. Store file X.
> > > 
> > > I want file X. :-)
> > 
> > The tool is in release process, thus let's just wait a bit.
> > There is no "goto 1" in my flow.
> > 
> > Part 1: (done 5x, i.e. with xattr, without xattr, ...)
> > 
> > 0. create UBI image, create empty UBIFS image.
> > 1. Mount UBIFS
> > 2. Do various filesystem operations.
> > 
> >     In the background, record nand operations and store it in a trace.
> > 
> > Part 2: (done ~100.000 times)
> > 1. Create an image file X by replaying from the trace.
> > 2. Set chk_fs to 1
> > 3. Mount the image
> > 7. If mount succeeds: ignore the file X
> > 
> >     If mount fails: remember file X
> > 
> > Thus every image is a first error. No image has seen multiple unclean
> > unmounts.
> 
> Hmm, when does the power-cut occur in your setup?
> i.e image-168167.bin and image-168168.bin show different errors and none of
> them fails the chf_fs test. They fail because of other reasons.

Short update, the issue in image-168168.bin seems to be a garbage collector 
bug which is not at all related to xattrs.
Are you 100% sure that all your tests run fine when xattrs are not used?

Thanks,
//richard

Re: UBIFS does not mount after powerfail

[Richard Weinberger]

Manfred,

Am Dienstag, 5. Dezember 2017, 23:19:17 CET schrieb Richard Weinberger:
> Manfred,
> 
> Am Dienstag, 5. Dezember 2017, 21:06:28 CET schrieb Richard Weinberger:
> > Manfred,
> > 
> > Am Dienstag, 5. Dezember 2017, 20:11:20 CET schrieb Manfred Spraul:
> > > > So, which file do I need to download to get the image that contains
> > > > the
> > > > first error as detected by chk_fs?
> > > > 
> > > > The mode of operation of your tool should be:
> > > > 1. Mount UBIFS
> > > > 2. Do various filesystem operations
> > > > 3. Random power-cut (in software)
> > > > 4. Dump the image to a file X
> > > > 5. Set chk_fs to 1
> > > > 6. Mount it again
> > > > 7. If mount succeeds, drop X, umount and goto 1
> > > > 
> > > >     Else if, chk_fs finds a problem. Store file X.
> > > > 
> > > > I want file X. :-)
> > > 
> > > The tool is in release process, thus let's just wait a bit.
> > > There is no "goto 1" in my flow.
> > > 
> > > Part 1: (done 5x, i.e. with xattr, without xattr, ...)
> > > 
> > > 0. create UBI image, create empty UBIFS image.
> > > 1. Mount UBIFS
> > > 2. Do various filesystem operations.
> > > 
> > >     In the background, record nand operations and store it in a trace.
> > > 
> > > Part 2: (done ~100.000 times)
> > > 1. Create an image file X by replaying from the trace.
> > > 2. Set chk_fs to 1
> > > 3. Mount the image
> > > 7. If mount succeeds: ignore the file X
> > > 
> > >     If mount fails: remember file X
> > > 
> > > Thus every image is a first error. No image has seen multiple unclean
> > > unmounts.
> > 
> > Hmm, when does the power-cut occur in your setup?
> > i.e image-168167.bin and image-168168.bin show different errors and none
> > of
> > them fails the chf_fs test. They fail because of other reasons.
> 
> Short update, the issue in image-168168.bin seems to be a garbage collector
> bug which is not at all related to xattrs.
> Are you 100% sure that all your tests run fine when xattrs are not used?

Another question. Do you happen to have the full flight record file of that 
run? Such that I can replay on my own.

As it looks like we have a problem with the LEB property tree.
But no idea so far what the root cause is.

Thanks,
//richard