[ 87.569225] [nandsim] nandsim_parse_weakblocks: INFO: weakblocks: No weak blocks. [ 87.574636] [nandsim] nandsim_parse_weakpages: INFO: weakpages: No weak pages. [ 87.579273] [nandsim] nandsim_parse_gravepages: INFO: gravepages: No grave pages. [ 87.584348] [nandsim] nandsim_nand_read_byte: WARNING: Unexpected data output cycle. state=STATE_READY, outb=0x00 [ 87.591094] [nandsim] nandsim_nand_read_byte: WARNING: Unexpected data output cycle. state=STATE_READY, outb=0x00 [ 87.598077] [nandsim] nandsim_nand_read_byte: WARNING: Unexpected data output cycle. state=STATE_READY, outb=0x00 [ 87.604451] [nandsim] nandsim_nand_read_byte: WARNING: Unexpected data output cycle. state=STATE_READY, outb=0x00 [ 87.611120] [nandsim] nandsim_nand_read_byte: WARNING: Unexpected data output cycle. state=STATE_READY, outb=0x00 [ 87.617393] [nandsim] nandsim_nand_read_byte: WARNING: Unexpected data output cycle. state=STATE_READY, outb=0x00 [ 87.624250] nand: device found, Manufacturer ID: 0x98, Chip ID: 0x39 [ 87.629079] nand: Toshiba NAND 128MiB 1,8V 8-bit [ 87.632079] nand: 128MiB, SLC, page size: 512, OOB size: 16 [ 87.638432] [nandsim] nandsim_prof_dev_attr_boards_allocate: INFO: Allocate page read counters. page_reads_cntrs=0xffff880022c00000, size=0x100000 [ 87.646758] [nandsim] nandsim_prof_dev_attr_boards_allocate: INFO: Allocate page program counters. page_progs_cntrs=0xffff880023380000, size=0x40000 [ 87.655217] [nandsim] nandsim_prof_dev_attr_boards_allocate: INFO: Allocate page erase counters. page_erase_cntrs=0xffff8800233c0000, size=0x8000 [ 87.663273] Scanning device for bad blocks [ 87.839174] [nandsim] nandsim_badblocks_parse: INFO: badblocks: No bad blocks. [ 87.843878] [nandsim] nandsim_module_init: INFO: Register as MTD device. nbparts=1 [ 87.882570] Creating 1 MTD partitions on "NAND 128MiB 1,8V 8-bit": [ 87.886447] 0x000000000000-0x000008000000 : "NAND simulator partition 0" [ 87.891358] add_mtd_device: Register device. name=mtd0 [ 87.899184] add_mtd_device: Register device. name=mtd1 [ 87.909208] [nandsim] nandsim_module_init: INFO: Added whole NAND device node. name=mtd1 [ 88.020883] UBI: default fastmap pool size: 256 [ 88.024431] UBI: default fastmap WL pool size: 25 [ 88.027286] UBI: attaching mtd0 to ubi0 [ 88.260184] UBI: scanning is finished [ 88.262565] UBI: empty MTD device detected [ 88.558283] UBI: attached mtd0 (name "NAND simulator partition 0", size 128 MiB) to ubi0 [ 88.564000] UBI: PEB size: 16384 bytes (16 KiB), LEB size: 15872 bytes [ 88.568370] UBI: min./max. I/O unit sizes: 512/512, sub-page size 256 [ 88.572103] UBI: VID header offset: 256 (aligned 256), data offset: 512 [ 88.576152] UBI: good PEBs: 8192, bad PEBs: 0, corrupted PEBs: 0 [ 88.579851] UBI: user volume: 0, internal volumes: 1, max. volumes count: 92 [ 88.584169] UBI: max/mean erase counter: 0/0, WL threshold: 4096, image sequence number: 2622390617 [ 88.589520] UBI: available PEBs: 8014, total reserved PEBs: 178, PEBs reserved for bad PEB handling: 160 [ 88.596007] UBI: background thread "ubi_bgt0d" started, PID 2422 [ 89.071457] UBIFS: default file-system created [ 89.075763] UBIFS: background thread "ubifs_bgt0_0" started, PID 2437 [ 89.097531] UBIFS: mounted UBI device 0, volume 0, name "ubifsex" [ 89.101329] UBIFS: LEB size: 15872 bytes (15 KiB), min./max. I/O unit sizes: 512 bytes/512 bytes [ 89.107094] UBIFS: FS size: 126499840 bytes (120 MiB, 7970 LEBs), journal size 6348800 bytes (6 MiB, 400 LEBs) [ 89.113381] UBIFS: reserved for root: 4952683 bytes (4836 KiB) [ 89.117050] UBIFS: media format: w4/r0 (latest is w4/r0), UUID 2A000410-CB93-4743-9A4B-2DED82875576, big LPT model * Starting AppArmor profiles [ 145.164425] audit_printk_skb: 132 callbacks suppressed [ 145.167817] audit: type=1400 audit(1457100533.501:65): apparmor="STATUS" operation="profile_replace" name="/sbin/dhclient" pid=2502 comm="apparmor_parser" [ 145.176508] audit: type=1400 audit(1457100533.513:66): apparmor="STATUS" operation="profile_replace" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=2502 comm="apparmor_parser" [ 145.187005] audit: type=1400 audit(1457100533.524:67): apparmor="STATUS" operation="profile_replace" name="/usr/lib/connman/scripts/dhclient-script" pid=2502 comm="apparmor_parser" [ 145.198005] audit: type=1400 audit(1457100533.534:68): apparmor="STATUS" operation="profile_replace" name="/usr/lib/lightdm/lightdm-guest-session" pid=2501 comm="apparmor_parser" [ 145.221222] audit: type=1400 audit(1457100533.558:69): apparmor="STATUS" operation="profile_replace" name="chromium" pid=2501 comm="apparmor_parser" [ 145.227500] audit: type=1400 audit(1457100533.564:70): apparmor="STATUS" operation="profile_replace" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=2502 comm="apparmor_parser" [ 145.237745] audit: type=1400 audit(1457100533.575:71): apparmor="STATUS" operation="profile_replace" name="/usr/lib/connman/scripts/dhclient-script" pid=2502 comm="apparmor_parser" [ 145.248006] audit: type=1400 audit(1457100533.585:72): apparmor="STATUS" operation="profile_replace" name="/usr/lib/connman/scripts/dhclient-script" pid=2502 comm="apparmor_parser" [ 145.258317] audit: type=1400 audit(1457100533.595:73): apparmor="STATUS" operation="profile_replace" name="chromium" pid=2501 comm="apparmor_parser" Skipping profile in /etc/apparmor.d/disable: usr.bin.firefox [ 145.302649] audit: type=1400 audit(1457100533.639:74): apparmor="STATUS" operation="profile_replace" name="/usr/lib/telepathy/mission-control-5" pid=2505 comm="apparmor_parser" Skipping profile in /etc/apparmor.d/disable: usr.sbin.rsyslogd [ OK ] Loading the saved-state of the serial devices... /dev/ttyS0 at 0x03f8 (irq = 4) is a 16550A * Setting up X socket directories... [ OK ] * Starting DirMngr dirmngr [ OK ] * speech-dispatcher disabled; edit /etc/default/speech-dispatcher Starting the VirtualBox Guest Additions ...fail! (modprobe vboxguest failed) Starting VirtualBox Guest Addition service VirtualBox Additions module not loaded! saned disabled; edit /etc/default/saned * Restoring resolver state... [ OK ] [ 147.159973] init: plymouth-stop pre-start process (2713) terminated with status 1 [ 454.131725] UBI error: __wl_get_peb: no free eraseblocks [ 479.678022] UBI error: __wl_get_peb: no free eraseblocks [ 480.127639] UBI error: __wl_get_peb: no free eraseblocks [ 543.909836] UBI error: __wl_get_peb: no free eraseblocks [ 544.441822] UBI error: __wl_get_peb: no free eraseblocks [ 570.363683] UBI error: __wl_get_peb: no free eraseblocks [ 570.873712] UBI error: __wl_get_peb: no free eraseblocks [ 607.680898] UBI error: __wl_get_peb: no free eraseblocks [ 608.154458] UBI error: __wl_get_peb: no free eraseblocks [ 620.956412] hrtimer: interrupt took 1965201 ns [ 641.186272] UBI error: __wl_get_peb: no free eraseblocks [ 642.197006] UBI error: __wl_get_peb: no free eraseblocks [ 653.864272] UBI error: __wl_get_peb: no free eraseblocks [ 654.450351] UBI error: __wl_get_peb: no free eraseblocks [ 688.506229] UBI error: __wl_get_peb: no free eraseblocks [ 706.202702] UBI error: __wl_get_peb: no free eraseblocks [ 707.062650] BUG: unable to handle kernel NULL pointer dereference at 0000000000000020 [ 707.063510] IP: [] wl_tree_add+0x40/0xf0 [ubi] [ 707.063510] PGD 1dc52067 PUD 16212067 PMD 0 [ 707.063510] Oops: 0000 [#1] SMP [ 707.063510] Modules linked in: deflate ubifs ubi cmdlinepart nandsim_prof nand nand_ecc nand_bch bch nand_ids mtd rpcsec_gss_krb5 nfsv4 nfsd auth_rpcgss nfs_acl nfs lockd grace sunrpc joydev binfmt_misc fscache serio_raw i2c_piix4 hid_generic psmouse usbhid hid pcnet32 mii [ 707.063510] CPU: 0 PID: 2422 Comm: ubi_bgt0d Not tainted 3.18.25-ubifs.ex0+ #1 [ 707.063510] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 707.063510] task: ffff880025092360 ti: ffff880023374000 task.ti: ffff880023374000 [ 707.063510] RIP: 0010:[] [] wl_tree_add+0x40/0xf0 [ubi] [ 707.063510] RSP: 0018:ffff880023377cf8 EFLAGS: 00010202 [ 707.063510] RAX: ffff8800259a5730 RBX: 0000000000000008 RCX: ffff880022672878 [ 707.063510] RDX: 0000000000001b0d RSI: ffff8800259a5678 RDI: ffff8800226733b0 [ 707.063510] RBP: ffff880023377d28 R08: dead000000100100 R09: 0000000000000000 [ 707.063510] R10: 0000000000000000 R11: 0000000000000000 R12: ffff8800259a5740 [ 707.063510] R13: ffff8800226733b0 R14: ffff8800259a5678 R15: ffff880025092360 [ 707.063510] FS: 0000000000000000(0000) GS:ffff88002fc00000(0000) knlGS:0000000000000000 [ 707.063510] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b [ 707.063510] CR2: 0000000000000020 CR3: 000000002e2bf000 CR4: 00000000000006f0 [ 707.063510] Stack: [ 707.063510] ffff880023377d28 ffff8800226733b0 ffff8800259a5720 ffff8800259a4290 [ 707.063510] 0000000000000000 ffff8800259a5678 ffff880023377db8 ffffffffa0273d03 [ 707.063510] ffff880023377db8 0000000000000246 0000000000000000 ffff8800259a5760 [ 707.063510] Call Trace: [ 707.063510] [] erase_worker+0x453/0x8a0 [ubi] [ 707.063510] [] do_work+0xc5/0x150 [ubi] [ 707.063510] [] ubi_thread+0x160/0x240 [ubi] [ 707.063510] [] ? ubi_wl_get_peb+0xc0/0xc0 [ubi] [ 707.063510] [] kthread+0xee/0x110 [ 707.063510] [] ? _raw_spin_unlock_irq+0x30/0x50 [ 707.063510] [] ? __init_kthread_worker+0x70/0x70 [ 707.063510] [] ret_from_fork+0x58/0x90 [ 707.063510] [] ? __init_kthread_worker+0x70/0x70 [ 707.063510] Code: 90 31 c0 49 89 fd 49 89 f6 49 89 f4 65 4c 8b 3c 25 c0 b8 00 00 eb 0b 0f 1f 40 00 4c 8d 63 10 48 89 d8 49 8b 1c 24 48 85 db 74 40 <8b> 43 18 41 39 45 18 7c e7 7e 0d 4c 8d 63 08 eb e3 0f 1f 80 00 [ 707.063510] RIP [] wl_tree_add+0x40/0xf0 [ubi] [ 707.063510] RSP [ 707.063510] CR2: 0000000000000020 [ 707.063510] ---[ end trace f74004a66509482e ]--- [ 707.063510] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:41 [ 707.063510] in_atomic(): 1, irqs_disabled(): 1, pid: 2422, name: ubi_bgt0d [ 707.063510] INFO: lockdep is turned off. [ 707.063510] irq event stamp: 9610078 [ 707.063510] hardirqs last enabled at (9610077): [] _raw_spin_unlock_irqrestore+0x40/0x80 [ 707.063510] hardirqs last disabled at (9610078): [] error_sti+0x5/0x6 [ 707.063510] softirqs last enabled at (9610052): [] __do_softirq+0x223/0x3b0 [ 707.063510] softirqs last disabled at (9610045): [] irq_exit+0x145/0x150 [ 707.063510] CPU: 0 PID: 2422 Comm: ubi_bgt0d Tainted: G D 3.18.25-ubifs.ex0+ #1 [ 707.063510] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 707.063510] 0000000000000009 ffff8800233778d8 ffffffff8174af24 ffff8800233778d8 [ 707.063510] ffff880025092360 ffff8800233778f8 ffffffff8109ad53 0000000000000000 [ 707.063510] ffff880026e93eb8 ffff880023377928 ffffffff8174fef4 ffff880023377988 [ 707.063510] Call Trace: [ 707.063510] [] dump_stack+0x51/0x6d [ 707.063510] [] __might_sleep+0xf3/0x130 [ 707.063510] [] down_read+0x24/0x70 [ 707.063510] [] exit_signals+0x24/0x140 [ 707.063510] [] ? blocking_notifier_call_chain+0x16/0x20 [ 707.063510] [] do_exit+0xaf/0xb40 [ 707.063510] [] ? kmsg_dump+0x115/0x130 [ 707.063510] [] ? kmsg_dump+0x26/0x130 [ 707.063510] [] oops_end+0xb3/0x160 [ 707.063510] [] no_context+0x129/0x3a0 [ 707.063510] [] ? __module_text_address+0x12/0x60 [ 707.063510] [] ? __kernel_text_address+0x58/0x80 [ 707.063510] [] __bad_area_nosemaphore+0x13d/0x240 [ 707.063510] [] bad_area_nosemaphore+0x13/0x20 [ 707.063510] [] __do_page_fault+0x382/0x530 [ 707.063510] [] ? __slab_free+0xe1/0x540 [ 707.063510] [] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 707.063510] [] ? trace_hardirqs_on_caller+0x10d/0x1d0 [ 707.063510] [] do_page_fault+0x41/0x80 [ 707.063510] [] page_fault+0x28/0x30 [ 707.063510] [] ? wl_tree_add+0x40/0xf0 [ubi] [ 707.063510] [] erase_worker+0x453/0x8a0 [ubi] [ 707.063510] [] do_work+0xc5/0x150 [ubi] [ 707.063510] [] ubi_thread+0x160/0x240 [ubi] [ 707.063510] [] ? ubi_wl_get_peb+0xc0/0xc0 [ubi] [ 707.063510] [] kthread+0xee/0x110 [ 707.063510] [] ? _raw_spin_unlock_irq+0x30/0x50 [ 707.063510] [] ? __init_kthread_worker+0x70/0x70 [ 707.063510] [] ret_from_fork+0x58/0x90 [ 707.063510] [] ? __init_kthread_worker+0x70/0x70 [ 707.063510] note: ubi_bgt0d[2422] exited with preempt_count 1 [ 732.138040] NMI watchdog: BUG: soft lockup - CPU#1 stuck for 22s! [ddmtrand:13857] [ 732.138086] Modules linked in: deflate ubifs ubi cmdlinepart nandsim_prof nand nand_ecc nand_bch bch nand_ids mtd rpcsec_gss_krb5 nfsv4 nfsd auth_rpcgss nfs_acl nfs lockd grace sunrpc joydev binfmt_misc fscache serio_raw i2c_piix4 hid_generic psmouse usbhid hid pcnet32 mii [ 732.138086] irq event stamp: 67542 [ 732.138086] hardirqs last enabled at (67541): [] _raw_spin_unlock_irqrestore+0x40/0x80 [ 732.138086] hardirqs last disabled at (67542): [] __slab_alloc+0x54/0x5a0 [ 732.138086] softirqs last enabled at (67342): [] __do_softirq+0x223/0x3b0 [ 732.138086] softirqs last disabled at (67337): [] irq_exit+0x145/0x150 [ 732.138086] CPU: 1 PID: 13857 Comm: ddmtrand Tainted: G D 3.18.25-ubifs.ex0+ #1 [ 732.138086] Hardware name: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 [ 732.138086] task: ffff8800061a8000 ti: ffff88001dc68000 task.ti: ffff88001dc68000 [ 732.138086] RIP: 0010:[] [] native_read_tsc+0x6/0x20 [ 732.138086] RSP: 0018:ffff88001dc6b778 EFLAGS: 00000202 [ 732.138086] RAX: 000000004638ddf5 RBX: ffffffff817535b7 RCX: 000000000000006e [ 732.138086] RDX: 000000000000126e RSI: 0000000000000000 RDI: 0000000000000001 [ 732.138086] RBP: ffff88001dc6b778 R08: 0000000000000001 R09: 0000000000000000 [ 732.138086] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88001dc6b6e8 [ 732.138086] R13: ffff8800061a8000 R14: ffff88001dc68000 R15: ffff8800061a8000 [ 732.138086] FS: 00007f169a48e740(0000) GS:ffff88002fd00000(0000) knlGS:0000000000000000 [ 732.138086] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b [ 732.138086] CR2: 00007f169a4ad000 CR3: 00000000199d5000 CR4: 00000000000006e0 [ 732.138086] Stack: [ 732.138086] ffff88001dc6b7a8 ffffffff813dfbcf ffff8800259a5748 000000000048da42 [ 732.138086] 0000000092eeede0 ffff8800259a5780 ffff88001dc6b7b8 ffffffff813dfb5f [ 732.138086] ffff88001dc6b7e8 ffffffff810c7d9e ffff8800233ca520 ffff8800259a5748 [ 732.138086] Call Trace: [ 732.138086] [] delay_tsc+0x2f/0xb0 [ 732.138086] [] __delay+0xf/0x20 [ 732.138086] [] do_raw_spin_lock+0x8e/0x190 [ 732.138086] [] _raw_spin_lock+0x41/0x50 [ 732.138086] [] ? ubi_wl_put_peb+0xa7/0x3c0 [ubi] [ 732.138086] [] ubi_wl_put_peb+0xa7/0x3c0 [ubi] [ 732.138086] [] ubi_eba_unmap_leb+0xd4/0x130 [ubi] [ 732.138086] [] ubi_leb_unmap+0x9f/0xe0 [ubi] [ 732.138086] [] ubifs_leb_unmap+0x89/0x110 [ubifs] [ 732.138086] [] switch_gc_head+0x6b/0x130 [ubifs] [ 732.138086] [] ubifs_garbage_collect_leb+0x5ae/0x8d0 [ubifs] [ 732.138086] [] ? mutex_unlock+0xe/0x10 [ 732.138086] [] ubifs_garbage_collect+0x1b5/0x700 [ubifs] [ 732.138086] [] ubifs_budget_space+0x389/0x920 [ubifs] [ 732.138086] [] ubifs_write_begin+0x228/0x4f0 [ubifs] [ 732.138086] [] generic_perform_write+0xdd/0x1e0 [ 732.138086] [] ? security_inode_need_killpriv+0x16/0x20 [ 732.138086] [] ? generic_file_write_iter+0x45/0xd0 [ 732.138086] [] __generic_file_write_iter+0x18f/0x390 [ 732.138086] [] generic_file_write_iter+0x50/0xd0 [ 732.138086] [] ubifs_write_iter+0x104/0x1c0 [ubifs] [ 732.138086] [] ? vfs_write+0x15a/0x180 [ 732.138086] [] ? vfs_write+0x15a/0x180 [ 732.138086] [] ? up_read+0x23/0x40 [ 732.138086] [] new_sync_write+0x92/0xd0 [ 732.138086] [] ? vfs_write+0x15a/0x180 [ 732.138086] [] vfs_write+0xce/0x180 [ 732.138086] [] SyS_write+0x59/0xd0 [ 732.138086] [] system_call_fastpath+0x12/0x17 [ 732.138086] Code: ff 0c 25 d4 b8 00 00 c9 c3 66 66 66 2e 0f 1f 84 00 00 00 00 00 55 48 89 e5 e8 67 00 00 00 66 90 c9 c3 0f 1f 00 55 48 89 e5 0f 31 <89> c1 48 89 d0 48 c1 e0 20 89 c9 48 09 c8 c9 c3 66 2e 0f 1f 84