[Linux,v4.2] workqueue: llvmlinux: acpid: BUG: sleeping function called from invalid context at kernel/workqueue.c:2680

Commit Message

Sedat Dilek Sept. 28, 2015, 7:17 a.m. UTC

On Mon, Sep 28, 2015 at 8:50 AM, Sedat Dilek <[e-mail]> wrote:
> [ CC only relevant people plus Paul as he took care in another thread ]
>
> First of all, sorry for flooding anybody or any mailing-list.
>
> Of course, using LLVM/Clang for the Linux-kernel is still WIP, but
> this does not mean using a different compiler does not find any
> bugs...
>
> Fascinated somehow of this problem I entered the "tracing" area.
> I played a bit with tracing and irq-flags (hardirqs).
>
> This is what I discovered now.
>
> [ TEST-1 ]
>
> --- a/drivers/hid/usbhid/hid-core.c
> +++ b/drivers/hid/usbhid/hid-core.c
> @@ -731,6 +731,7 @@ void usbhid_close(struct hid_device *hid)
>          */
>         spin_lock_irq(&usbhid->lock);
>         if (!--hid->open) {
> +               trace_hardirqs_off();
>                 spin_unlock_irq(&usbhid->lock);
>                 hid_cancel_delayed_stuff(usbhid);
>                 if (!(hid->quirks & HID_QUIRK_ALWAYS_POLL)) {
> @@ -1392,6 +1393,8 @@ static void usbhid_disconnect(struct usb_interface *intf)
>
>  static void hid_cancel_delayed_stuff(struct usbhid_device *usbhid)
>  {
> +       might_sleep();
> +
>         del_timer_sync(&usbhid->io_retry);
>         cancel_work_sync(&usbhid->reset_work);
>  }
>
> This makes the BUG line go away - only changes in the HID area.
>
> [ TEST-2 ]
>
> --- a/drivers/hid/usbhid/hid-core.c
> +++ b/drivers/hid/usbhid/hid-core.c
> @@ -732,6 +732,7 @@ void usbhid_close(struct hid_device *hid)
>         spin_lock_irq(&usbhid->lock);
>         if (!--hid->open) {
>                 spin_unlock_irq(&usbhid->lock);
> +               trace_hardirqs_off();
>                 hid_cancel_delayed_stuff(usbhid);
>                 if (!(hid->quirks & HID_QUIRK_ALWAYS_POLL)) {
>                         usb_kill_urb(usbhid->urbin);
> @@ -1392,6 +1393,8 @@ static void usbhid_disconnect(struct usb_interface *intf)
>
>  static void hid_cancel_delayed_stuff(struct usbhid_device *usbhid)
>  {
> +       might_sleep();
> +
>         del_timer_sync(&usbhid->io_retry);
>         cancel_work_sync(&usbhid->reset_work);
>  }
>
> This results in...
>
> [   23.236024] BUG: sleeping function called from invalid context at
> drivers/hid/usbhid/hid-core.c:1396
> [   23.236148] in_atomic(): 0, irqs_disabled(): 1, pid: 1419, name: acpid
> [   23.236233] 3 locks held by acpid/1419:
> [   23.236235]  #0:  (&evdev->mutex){+.+...}, at: [<ffffffff8174c98c>]
> evdev_release+0xbc/0xf0
> [   23.236250]  #1:  (&dev->mutex#2){+.+...}, at: [<ffffffff817440a7>]
> input_close_device+0x27/0x70
> [   23.236264]  #2:  (hid_open_mut){+.+...}, at: [<ffffffffa008e388>]
> usbhid_close+0x28/0xd0 [usbhid]
> [   23.236277] irq event stamp: 3406
> [   23.236280] hardirqs last  enabled at (3405): [<ffffffff8192ccd2>]
> _raw_spin_unlock_irq+0x32/0x60
> [   23.236286] hardirqs last disabled at (3406): [<ffffffffa008e3bd>]
> usbhid_close+0x5d/0xd0 [usbhid]
> [   23.236292] softirqs last  enabled at (2806): [<ffffffff818b3069>]
> local_bh_enable+0x9/0x20
> [   23.236298] softirqs last disabled at (2804): [<ffffffff818b3049>]
> local_bh_disable+0x9/0x20
> [   23.236306] CPU: 3 PID: 1419 Comm: acpid Not tainted
> 4.3.0-rc3-4-llvmlinux-amd64 #3
> [   23.236309] Hardware name: SAMSUNG ELECTRONICS CO., LTD.
> 530U3BI/530U4BI/530U4BH/530U3BI/530U4BI/530U4BH, BIOS 13XK 03/28/2013
> [   23.236313]  ffff8800c760b000 0000000000000096 0000000000000000
> ffff8800bce53c88
> [   23.236320]  ffffffff8149cabd ffff8800bce53cb8 ffffffff810cd5aa
> ffffffffa009780c
> [   23.236328]  ffff8800c5fd5740 0000000000000000 0000000000000574
> ffff8800bce53cf8
> [   23.236334] Call Trace:
> [   23.236342]  [<ffffffff8149cabd>] dump_stack+0x7d/0xa0
> [   23.236347]  [<ffffffff810cd5aa>] ___might_sleep+0x28a/0x2a0
> [   23.236351]  [<ffffffff810cd2af>] __might_sleep+0x4f/0xc0
> [   23.236357]  [<ffffffffa008e3d5>] usbhid_close+0x75/0xd0 [usbhid]
> [   23.236366]  [<ffffffffa00714d1>] hidinput_close+0x31/0x40 [hid]
> [   23.236373]  [<ffffffffa00714a0>] ? hidinput_open+0x40/0x40 [hid]
> [   23.236378]  [<ffffffff817440c8>] input_close_device+0x48/0x70
> [   23.236382]  [<ffffffff8174c9a6>] evdev_release+0xd6/0xf0
> [   23.236387]  [<ffffffff81274367>] __fput+0x107/0x240
> [   23.236392]  [<ffffffff812741f6>] ____fput+0x16/0x20
> [   23.236397]  [<ffffffff810b97bc>] task_work_run+0x6c/0xe0
> [   23.236403]  [<ffffffff81003b8a>] prepare_exit_to_usermode+0x13a/0x140
> [   23.236408]  [<ffffffff81003e11>] syscall_return_slowpath+0x281/0x2f0
> [   23.236414]  [<ffffffff81270a45>] ? filp_close+0x65/0x90
> [   23.236420]  [<ffffffff810f02d9>] ? trace_hardirqs_on_caller+0x19/0x290
> [   23.236425]  [<ffffffff81003017>] ? trace_hardirqs_on_thunk+0x17/0x19
> [   23.236431]  [<ffffffff8192d9e2>] int_ret_from_sys_call+0x25/0x9f
>
> I will not do a statement :-).
>
> Looking at TEST-1, the spin_unlock_irq() within the if-statement seems
> to do cause problems with tracing / irq-flags.
>
> I have added my kernel-config, llvmlinux patchset, diffs with
> corresponding dmesg-logs (look into the attached tarball).
>
> Hope this really helps you.
>

I looked over some commits which had problems with tracing and
irq-flags (hardirq)...

[ TEST-3 ]

I am not an locking expert, when I replace...

    spin_{un}lock_irq() with spin_{un}lock_bh()

...within usbhid_close() in drivers/hid/usbhid/hid-core.c...

This makes the BUG line go away for me.


Does this help?

- Sedat -
diff --git a/drivers/hid/usbhid/hid-core.c b/drivers/hid/usbhid/hid-core.c
index 36712e9f56c2..5531ef839a17 100644
--- a/drivers/hid/usbhid/hid-core.c
+++ b/drivers/hid/usbhid/hid-core.c
@@ -729,16 +729,16 @@ void usbhid_close(struct hid_device *hid)
 	 * data acquistion due to a resumption we no longer
 	 * care about
 	 */
-	spin_lock_irq(&usbhid->lock);
+	spin_lock_bh(&usbhid->lock);
 	if (!--hid->open) {
-		spin_unlock_irq(&usbhid->lock);
+		spin_unlock_bh(&usbhid->lock);
 		hid_cancel_delayed_stuff(usbhid);
 		if (!(hid->quirks & HID_QUIRK_ALWAYS_POLL)) {
 			usb_kill_urb(usbhid->urbin);
 			usbhid->intf->needs_remote_wakeup = 0;
 		}
 	} else {
-		spin_unlock_irq(&usbhid->lock);
+		spin_unlock_bh(&usbhid->lock);
 	}
 	mutex_unlock(&hid_open_mut);
 }
@@ -1392,6 +1392,8 @@ static void usbhid_disconnect(struct usb_interface *intf)
 
 static void hid_cancel_delayed_stuff(struct usbhid_device *usbhid)
 {
+	might_sleep();
+
 	del_timer_sync(&usbhid->io_retry);
 	cancel_work_sync(&usbhid->reset_work);
 }
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.3.0-rc3-4-llvmlinux-amd64 ([e-mail]@fambox) (clang version 3.7.0 (tags/RELEASE_370/final)) #3 SMP Sun Sep 27 19:32:52 CEST 2015
[    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-4.3.0-rc3-4-llvmlinux-amd64 root=UUID=001AADA61AAD9964 loop=/ubuntu/disks/root.disk ro
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000]   AMD AuthenticAMD
[    0.000000]   Centaur CentaurHauls
[    0.000000] Disabled fast string operations
[    0.000000] x86/fpu: xstate_offset[2]: 0240, xstate_sizes[2]: 0100
[    0.000000] x86/fpu: Supporting XSAVE feature 0x01: 'x87 floating point registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x02: 'SSE registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x04: 'AVX registers'
[    0.000000] x86/fpu: Enabled xstate features 0x7, context size is 0x340 bytes, using 'standard' format.
[    0.000000] x86/fpu: Using 'eager' FPU context switches.
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009d7ff] usable
[    0.000000] BIOS-e820: [mem 0x000000000009d800-0x000000000009ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000e0000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000001fffffff] usable
[    0.000000] BIOS-e820: [mem 0x0000000020000000-0x00000000201fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000020200000-0x000000003fffffff] usable
[    0.000000] BIOS-e820: [mem 0x0000000040000000-0x00000000401fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000040200000-0x00000000d9c9efff] usable
[    0.000000] BIOS-e820: [mem 0x00000000d9c9f000-0x00000000dae7efff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000dae7f000-0x00000000daf9efff] ACPI NVS
[    0.000000] BIOS-e820: [mem 0x00000000daf9f000-0x00000000daffefff] ACPI data
[    0.000000] BIOS-e820: [mem 0x00000000dafff000-0x00000000daffffff] usable
[    0.000000] BIOS-e820: [mem 0x00000000db000000-0x00000000df9fffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000f8000000-0x00000000fbffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fed08000-0x00000000fed08fff] 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 0x00000000ffd80000-0x00000000ffffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000011fdfffff] usable
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.6 present.
[    0.000000] DMI: SAMSUNG ELECTRONICS CO., LTD. 530U3BI/530U4BI/530U4BH/530U3BI/530U4BI/530U4BH, BIOS 13XK 03/28/2013
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x11fe00 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-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 000000000 mask F80000000 write-back
[    0.000000]   1 base 080000000 mask FC0000000 write-back
[    0.000000]   2 base 0C0000000 mask FE0000000 write-back
[    0.000000]   3 base 0DC000000 mask FFC000000 uncachable
[    0.000000]   4 base 0DB000000 mask FFF000000 uncachable
[    0.000000]   5 base 100000000 mask FE0000000 write-back
[    0.000000]   6 base 11FE00000 mask FFFE00000 uncachable
[    0.000000]   7 base 0FFC00000 mask FFFC00000 write-protect
[    0.000000]   8 disabled
[    0.000000]   9 disabled
[    0.000000] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- WT  
[    0.000000] e820: last_pfn = 0xdb000 max_arch_pfn = 0x400000000
[    0.000000] found SMP MP-table at [mem 0x000f00e0-0x000f00ef] mapped at [ffff8800000f00e0]
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] Base memory trampoline at [ffff880000096000] 96000 size 28672
[    0.000000] reserving inaccessible SNB gfx pages
[    0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff]
[    0.000000]  [mem 0x00000000-0x000fffff] page 4k
[    0.000000] BRK [0x03020000, 0x03020fff] PGTABLE
[    0.000000] BRK [0x03021000, 0x03021fff] PGTABLE
[    0.000000] BRK [0x03022000, 0x03022fff] PGTABLE
[    0.000000] init_memory_mapping: [mem 0x11fc00000-0x11fdfffff]
[    0.000000]  [mem 0x11fc00000-0x11fdfffff] page 2M
[    0.000000] BRK [0x03023000, 0x03023fff] PGTABLE
[    0.000000] init_memory_mapping: [mem 0x100000000-0x11fbfffff]
[    0.000000]  [mem 0x100000000-0x11fbfffff] page 2M
[    0.000000] init_memory_mapping: [mem 0xc0000000-0xd9c9efff]
[    0.000000]  [mem 0xc0000000-0xd9bfffff] page 2M
[    0.000000]  [mem 0xd9c00000-0xd9c9efff] page 4k
[    0.000000] BRK [0x03024000, 0x03024fff] PGTABLE
[    0.000000] BRK [0x03025000, 0x03025fff] PGTABLE
[    0.000000] init_memory_mapping: [mem 0xdafff000-0xdaffffff]
[    0.000000]  [mem 0xdafff000-0xdaffffff] page 4k
[    0.000000] init_memory_mapping: [mem 0x00100000-0x1fffffff]
[    0.000000]  [mem 0x00100000-0x001fffff] page 4k
[    0.000000]  [mem 0x00200000-0x1fffffff] page 2M
[    0.000000] init_memory_mapping: [mem 0x20200000-0x3fffffff]
[    0.000000]  [mem 0x20200000-0x3fffffff] page 2M
[    0.000000] init_memory_mapping: [mem 0x40200000-0xbfffffff]
[    0.000000]  [mem 0x40200000-0xbfffffff] page 2M
[    0.000000] RAMDISK: [mem 0x37888000-0x37c3bfff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F0100 000024 (v02 SECCSD)
[    0.000000] ACPI: XSDT 0x00000000DAFFE170 00008C (v01 SECCSD LH43STAR 00000002 PTEC 00000002)
[    0.000000] ACPI: FACP 0x00000000DAFEF000 00010C (v05 SECCSD LH43STAR 00000002 PTL  00000002)
[    0.000000] ACPI: DSDT 0x00000000DAFF2000 0083AC (v02 SECCSD SNB-CPT  00000000 INTL 20061109)
[    0.000000] ACPI: FACS 0x00000000DAF47000 000040
[    0.000000] ACPI: SLIC 0x00000000DAFFD000 000176 (v01 SECCSD LH43STAR 00000002 PTEC 00000001)
[    0.000000] ACPI: SSDT 0x00000000DAFFB000 001068 (v01 SECCSD PtidDevc 00001000 INTL 20061109)
[    0.000000] ACPI: ASF! 0x00000000DAFF1000 0000A5 (v32 SECCSD LH43STAR 00000002 PTL  00000002)
[    0.000000] ACPI: HPET 0x00000000DAFEE000 000038 (v01 SECCSD LH43STAR 00000002 PTL  00000002)
[    0.000000] ACPI: APIC 0x00000000DAFED000 000098 (v03 SECCSD LH43STAR 00000002 PTL  00000002)
[    0.000000] ACPI: MCFG 0x00000000DAFEC000 00003C (v01 SECCSD LH43STAR 00000002 PTL  00000002)
[    0.000000] ACPI: SSDT 0x00000000DAFEB000 000804 (v01 PmRef  Cpu0Ist  00003000 INTL 20061109)
[    0.000000] ACPI: SSDT 0x00000000DAFEA000 000996 (v01 PmRef  CpuPm    00003000 INTL 20061109)
[    0.000000] ACPI: UEFI 0x00000000DAFE9000 00003E (v01 SECCSD LH43STAR 00000002 PTL  00000002)
[    0.000000] ACPI: UEFI 0x00000000DAFE8000 000042 (v01 PTL    COMBUF   00000001 PTL  00000001)
[    0.000000] ACPI: UEFI 0x00000000DAFE7000 00026A (v01 SECCSD LH43STAR 00000002 PTL  00000002)
[    0.000000] ACPI: SSDT 0x00000000DAFE6000 0000D0 (v01 Iffs   IffsAsl  00003000 INTL 20061109)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000011fdfffff]
[    0.000000] NODE_DATA(0) allocated [mem 0x11fdf8000-0x11fdfcfff]
[    0.000000]  [ffffea0000000000-ffffea00047fffff] PMD -> [ffff88011b400000-ffff88011f3fffff] on node 0
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
[    0.000000]   Normal   [mem 0x0000000100000000-0x000000011fdfffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x000000000009cfff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x000000001fffffff]
[    0.000000]   node   0: [mem 0x0000000020200000-0x000000003fffffff]
[    0.000000]   node   0: [mem 0x0000000040200000-0x00000000d9c9efff]
[    0.000000]   node   0: [mem 0x00000000dafff000-0x00000000daffffff]
[    0.000000]   node   0: [mem 0x0000000100000000-0x000000011fdfffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000011fdfffff]
[    0.000000] On node 0 totalpages: 1021500
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 156 pages reserved
[    0.000000]   DMA zone: 3996 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 13859 pages used for memmap
[    0.000000]   DMA32 zone: 886944 pages, LIFO batch:31
[    0.000000]   Normal zone: 2040 pages used for memmap
[    0.000000]   Normal zone: 130560 pages, LIFO batch:31
[    0.000000] Reserving Intel graphics stolen memory at 0xdba00000-0xdf9fffff
[    0.000000] ACPI: PM-Timer IO Port: 0x408
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1])
[    0.000000] IOAPIC[0]: apic_id 14, version 32, address 0xfec00000, GSI 0-23
[    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: 0x8086a301 base: 0xfed00000
[    0.000000] smpboot: Allowing 8 CPUs, 4 hotplug CPUs
[    0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.000000] PM: Registered nosave memory: [mem 0x0009d000-0x0009dfff]
[    0.000000] PM: Registered nosave memory: [mem 0x0009e000-0x0009ffff]
[    0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000dffff]
[    0.000000] PM: Registered nosave memory: [mem 0x000e0000-0x000fffff]
[    0.000000] PM: Registered nosave memory: [mem 0x20000000-0x201fffff]
[    0.000000] PM: Registered nosave memory: [mem 0x40000000-0x401fffff]
[    0.000000] PM: Registered nosave memory: [mem 0xd9c9f000-0xdae7efff]
[    0.000000] PM: Registered nosave memory: [mem 0xdae7f000-0xdaf9efff]
[    0.000000] PM: Registered nosave memory: [mem 0xdaf9f000-0xdaffefff]
[    0.000000] PM: Registered nosave memory: [mem 0xdb000000-0xdf9fffff]
[    0.000000] PM: Registered nosave memory: [mem 0xdfa00000-0xf7ffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xf8000000-0xfbffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfc000000-0xfebfffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfec00000-0xfec00fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfec01000-0xfed07fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfed08000-0xfed08fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfed09000-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-0xffd7ffff]
[    0.000000] PM: Registered nosave memory: [mem 0xffd80000-0xffffffff]
[    0.000000] e820: [mem 0xdfa00000-0xf7ffffff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on bare hardware
[    0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
[    0.000000] setup_percpu: NR_CPUS:256 nr_cpumask_bits:256 nr_cpu_ids:8 nr_node_ids:1
[    0.000000] PERCPU: Embedded 34 pages/cpu @ffff88011fa00000 s99272 r8192 d31800 u262144
[    0.000000] pcpu-alloc: s99272 r8192 d31800 u262144 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 1 2 3 4 5 6 7 
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 1005381
[    0.000000] Policy zone: Normal
[    0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.3.0-rc3-4-llvmlinux-amd64 root=UUID=001AADA61AAD9964 loop=/ubuntu/disks/root.disk ro
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] Calgary: detecting Calgary via BIOS EBDA area
[    0.000000] Calgary: Unable to locate Rio Grande table in EBDA - bailing!
[    0.000000] Memory: 3915128K/4086000K available (9419K kernel code, 1182K rwdata, 3400K rodata, 2972K init, 14364K bss, 170872K reserved, 0K cma-reserved)
[    0.000000] Running RCU self tests
[    0.000000] Hierarchical RCU implementation.
[    0.000000] 	RCU lockdep checking is enabled.
[    0.000000] 	Build-time adjustment of leaf fanout to 64.
[    0.000000] 	RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=8.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=8
[    0.000000] NR_IRQS:16640 nr_irqs:488 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: 8159 kB
[    0.000000]  per task-struct memory footprint: 1920 bytes
[    0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 133484882848 ns
[    0.000000] hpet clockevent registered
[    0.000000] tsc: Fast TSC calibration using PIT
[    0.000000] tsc: Detected 1596.403 MHz processor
[    0.000004] Calibrating delay loop (skipped), value calculated using timer frequency.. 3192.80 BogoMIPS (lpj=6385612)
[    0.000020] pid_max: default: 32768 minimum: 301
[    0.000053] ACPI: Core revision 20150818
[    0.027253] ACPI: 5 ACPI AML tables successfully acquired and loaded
[    0.027421] Security Framework initialized
[    0.027430] Yama: becoming mindful.
[    0.027478] AppArmor: AppArmor initialized
[    0.028797] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.030333] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.030822] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.030856] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.032434] Initializing cgroup subsys io
[    0.032493] Initializing cgroup subsys memory
[    0.032525] Initializing cgroup subsys devices
[    0.032571] Initializing cgroup subsys freezer
[    0.032617] Initializing cgroup subsys perf_event
[    0.032633] Initializing cgroup subsys hugetlb
[    0.032730] Disabled fast string operations
[    0.032738] CPU: Physical Processor ID: 0
[    0.032745] CPU: Processor Core ID: 0
[    0.032756] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
[    0.032764] ENERGY_PERF_BIAS: View and update with x86_energy_perf_policy(8)
[    0.032774] mce: CPU supports 7 MCE banks
[    0.032822] CPU0: Thermal monitoring enabled (TM1)
[    0.032846] process: using mwait in idle threads
[    0.032856] Last level iTLB entries: 4KB 512, 2MB 8, 4MB 8
[    0.032864] Last level dTLB entries: 4KB 512, 2MB 32, 4MB 32, 1GB 0
[    0.033552] Freeing SMP alternatives memory: 28K (ffffffff82210000 - ffffffff82217000)
[    0.036397] ftrace: allocating 208498 entries in 815 pages
[    0.143949] x2apic: IRQ remapping doesn't support X2APIC mode
[    0.144644] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.184315] TSC deadline timer enabled
[    0.184321] smpboot: CPU0: Intel(R) Core(TM) i5-2467M CPU @ 1.60GHz (family: 0x6, model: 0x2a, stepping: 0x7)
[    0.184379] Performance Events: PEBS fmt1+, 16-deep LBR, SandyBridge events, full-width counters, Intel PMU driver.
[    0.184426] ... version:                3
[    0.184431] ... bit width:              48
[    0.184436] ... generic registers:      4
[    0.184441] ... value mask:             0000ffffffffffff
[    0.184447] ... max period:             0000ffffffffffff
[    0.184452] ... fixed-purpose events:   3
[    0.184457] ... event mask:             000000070000000f
[    0.186539] x86: Booting SMP configuration:
[    0.186547] .... node  #0, CPUs:      #1
[    0.186886] Disabled fast string operations
[    0.189468] NMI watchdog: enabled on all CPUs, permanently consumes one hw-PMU counter.
[    0.190027]  #2
[    0.190338] Disabled fast string operations
[    0.192943]  #3<6>[    0.193278] Disabled fast string operations
[    0.195423] x86: Booted up 1 node, 4 CPUs
[    0.195437] smpboot: Total of 4 processors activated (12771.22 BogoMIPS)
[    0.200723] devtmpfs: initialized
[    0.207293] evm: security.selinux
[    0.207300] evm: security.SMACK64
[    0.207304] evm: security.capability
[    0.207538] PM: Registering ACPI NVS region [mem 0xdae7f000-0xdaf9efff] (1179648 bytes)
[    0.207927] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.209580] NET: Registered protocol family 16
[    0.219623] cpuidle: using governor ladder
[    0.235580] cpuidle: using governor menu
[    0.235793] ACPI: bus type PCI registered
[    0.236124] PCI: MMCONFIG for domain 0000 [bus 00-3f] at [mem 0xf8000000-0xfbffffff] (base 0xf8000000)
[    0.236136] PCI: MMCONFIG at [mem 0xf8000000-0xfbffffff] reserved in E820
[    0.236152] PCI: Using configuration type 1 for base access
[    0.236875] perf_event_intel: PMU erratum BJ122, BV98, HSD29 worked around, HT is on
[    0.257271] ACPI: Added _OSI(Module Device)
[    0.257280] ACPI: Added _OSI(Processor Device)
[    0.257286] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.257292] ACPI: Added _OSI(Processor Aggregator Device)
[    0.271066] ACPI: Executed 1 blocks of module-level executable AML code
[    0.280721] [Firmware Bug]: ACPI: BIOS _OSI(Linux) query ignored
[    0.282729] ACPI: Dynamic OEM Table Load:
[    0.282759] ACPI: SSDT 0xFFFF88011B0A3800 000688 (v01 PmRef  Cpu0Cst  00003001 INTL 20061109)
[    0.285222] ACPI: Dynamic OEM Table Load:
[    0.285250] ACPI: SSDT 0xFFFF88011A2D9800 000303 (v01 PmRef  ApIst    00003000 INTL 20061109)
[    0.287499] ACPI: Dynamic OEM Table Load:
[    0.287526] ACPI: SSDT 0xFFFF88011A220200 000119 (v01 PmRef  ApCst    00003000 INTL 20061109)
[    0.291301] ACPI : EC: EC started
[    0.292733] ACPI: Interpreter enabled
[    0.292757] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20150818/hwxface-578)
[    0.292821] ACPI: (supports S0 S1 S3 S4 S5)
[    0.292827] ACPI: Using IOAPIC for interrupt routing
[    0.292915] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.316795] ACPI: Power Resource [FN00] (off)
[    0.317292] ACPI: Power Resource [FN01] (off)
[    0.317669] ACPI: Power Resource [FN02] (off)
[    0.318047] ACPI: Power Resource [FN03] (off)
[    0.318428] ACPI: Power Resource [FN04] (off)
[    0.321079] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-3e])
[    0.321094] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI]
[    0.321792] \_SB_.PCI0:_OSC invalid UUID
[    0.321794] _OSC request data:1 1f 0 
[    0.321800] acpi PNP0A08:00: _OSC failed (AE_ERROR); disabling ASPM
[    0.322790] PCI host bridge to bus 0000:00
[    0.322800] pci_bus 0000:00: root bus resource [bus 00-3e]
[    0.322808] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.322816] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.322824] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.322833] pci_bus 0000:00: root bus resource [mem 0xdfa00000-0xfeafffff window]
[    0.322842] pci_bus 0000:00: root bus resource [mem 0xfed40000-0xfed44fff window]
[    0.322942] pci 0000:00:00.0: [8086:0104] type 00 class 0x060000
[    0.323364] pci 0000:00:02.0: [8086:0116] type 00 class 0x030000
[    0.323394] pci 0000:00:02.0: reg 0x10: [mem 0xf0000000-0xf03fffff 64bit]
[    0.323406] pci 0000:00:02.0: reg 0x18: [mem 0xe0000000-0xefffffff 64bit pref]
[    0.323415] pci 0000:00:02.0: reg 0x20: [io  0x3000-0x303f]
[    0.323910] pci 0000:00:16.0: [8086:1c3a] type 00 class 0x078000
[    0.323973] pci 0000:00:16.0: reg 0x10: [mem 0xf0705000-0xf070500f 64bit]
[    0.324094] pci 0000:00:16.0: PME# supported from D0 D3hot D3cold
[    0.324474] pci 0000:00:1a.0: [8086:1c2d] type 00 class 0x0c0320
[    0.324530] pci 0000:00:1a.0: reg 0x10: [mem 0xf070a000-0xf070a3ff]
[    0.324668] pci 0000:00:1a.0: PME# supported from D0 D3hot D3cold
[    0.325022] pci 0000:00:1b.0: [8086:1c20] type 00 class 0x040300
[    0.325084] pci 0000:00:1b.0: reg 0x10: [mem 0xf0700000-0xf0703fff 64bit]
[    0.325225] pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold
[    0.325433] pci 0000:00:1b.0: System wakeup disabled by ACPI
[    0.325614] pci 0000:00:1c.0: [8086:1c10] type 01 class 0x060400
[    0.325769] pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold
[    0.325948] pci 0000:00:1c.0: System wakeup disabled by ACPI
[    0.326134] pci 0000:00:1c.3: [8086:1c16] type 01 class 0x060400
[    0.326288] pci 0000:00:1c.3: PME# supported from D0 D3hot D3cold
[    0.326466] pci 0000:00:1c.3: System wakeup disabled by ACPI
[    0.326645] pci 0000:00:1c.4: [8086:1c18] type 01 class 0x060400
[    0.326805] pci 0000:00:1c.4: PME# supported from D0 D3hot D3cold
[    0.327159] pci 0000:00:1d.0: [8086:1c26] type 00 class 0x0c0320
[    0.327215] pci 0000:00:1d.0: reg 0x10: [mem 0xf0709000-0xf07093ff]
[    0.327354] pci 0000:00:1d.0: PME# supported from D0 D3hot D3cold
[    0.327701] pci 0000:00:1f.0: [8086:1c49] type 00 class 0x060100
[    0.328183] pci 0000:00:1f.2: [8086:1c03] type 00 class 0x010601
[    0.328243] pci 0000:00:1f.2: reg 0x10: [io  0x3088-0x308f]
[    0.328260] pci 0000:00:1f.2: reg 0x14: [io  0x3094-0x3097]
[    0.328278] pci 0000:00:1f.2: reg 0x18: [io  0x3080-0x3087]
[    0.328295] pci 0000:00:1f.2: reg 0x1c: [io  0x3090-0x3093]
[    0.328312] pci 0000:00:1f.2: reg 0x20: [io  0x3060-0x307f]
[    0.328330] pci 0000:00:1f.2: reg 0x24: [mem 0xf0708000-0xf07087ff]
[    0.328399] pci 0000:00:1f.2: PME# supported from D3hot
[    0.328731] pci 0000:00:1f.3: [8086:1c22] type 00 class 0x0c0500
[    0.328769] pci 0000:00:1f.3: reg 0x10: [mem 0xf0704000-0xf07040ff 64bit]
[    0.328816] pci 0000:00:1f.3: reg 0x20: [io  0xefa0-0xefbf]
[    0.329535] pci 0000:01:00.0: [8086:0091] type 00 class 0x028000
[    0.330159] pci 0000:01:00.0: reg 0x10: [mem 0xf0600000-0xf0601fff 64bit]
[    0.331568] pci 0000:01:00.0: PME# supported from D0 D3hot D3cold
[    0.331877] pci 0000:01:00.0: System wakeup disabled by ACPI
[    0.339938] pci 0000:00:1c.0: PCI bridge to [bus 01]
[    0.339956] pci 0000:00:1c.0:   bridge window [mem 0xf0600000-0xf06fffff]
[    0.340173] pci 0000:02:00.0: [10ec:8168] type 00 class 0x020000
[    0.340373] pci 0000:02:00.0: reg 0x10: [io  0x2000-0x20ff]
[    0.340512] pci 0000:02:00.0: reg 0x18: [mem 0xf0404000-0xf0404fff 64bit pref]
[    0.340598] pci 0000:02:00.0: reg 0x20: [mem 0xf0400000-0xf0403fff 64bit pref]
[    0.340905] pci 0000:02:00.0: supports D1 D2
[    0.340907] pci 0000:02:00.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.341073] pci 0000:02:00.0: System wakeup disabled by ACPI
[    0.347859] pci 0000:00:1c.3: PCI bridge to [bus 02]
[    0.347870] pci 0000:00:1c.3:   bridge window [io  0x2000-0x2fff]
[    0.347886] pci 0000:00:1c.3:   bridge window [mem 0xf0400000-0xf04fffff 64bit pref]
[    0.348082] pci 0000:03:00.0: [1b21:1042] type 00 class 0x0c0330
[    0.348165] pci 0000:03:00.0: reg 0x10: [mem 0xf0500000-0xf0507fff 64bit]
[    0.348392] pci 0000:03:00.0: PME# supported from D3hot D3cold
[    0.348695] pci 0000:00:1c.4: PCI bridge to [bus 03]
[    0.348711] pci 0000:00:1c.4:   bridge window [mem 0xf0500000-0xf05fffff]
[    0.350876] ACPI: PCI Interrupt Link [LNKA] (IRQs 1 3 4 5 6 10 *11 12 14 15)
[    0.351082] ACPI: PCI Interrupt Link [LNKB] (IRQs 1 3 4 5 6 10 11 12 14 15) *0, disabled.
[    0.351288] ACPI: PCI Interrupt Link [LNKC] (IRQs 1 3 4 5 6 *10 11 12 14 15)
[    0.351490] ACPI: PCI Interrupt Link [LNKD] (IRQs 1 3 4 5 6 *10 11 12 14 15)
[    0.351689] ACPI: PCI Interrupt Link [LNKE] (IRQs 1 3 4 5 6 10 11 12 14 15) *9
[    0.351911] ACPI: PCI Interrupt Link [LNKF] (IRQs 1 3 4 5 6 10 11 12 14 15) *0, disabled.
[    0.352115] ACPI: PCI Interrupt Link [LNKG] (IRQs 1 3 4 5 6 10 *11 12 14 15)
[    0.352314] ACPI: PCI Interrupt Link [LNKH] (IRQs 1 3 4 5 6 10 11 12 14 15) *9
[    0.353768] ACPI: Enabled 4 GPEs in block 00 to 3F
[    0.353930] ACPI : EC: GPE = 0x17, I/O: command/status = 0x66, data = 0x62
[    0.354263] ACPI : EC: 0 stale EC events cleared
[    0.355107] vgaarb: setting as boot device: PCI:0000:00:02.0
[    0.355115] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    0.355132] vgaarb: loaded
[    0.355137] vgaarb: bridge control possible 0000:00:02.0
[    0.355960] SCSI subsystem initialized
[    0.356150] libata version 3.00 loaded.
[    0.356251] ACPI: bus type USB registered
[    0.356333] usbcore: registered new interface driver usbfs
[    0.356376] usbcore: registered new interface driver hub
[    0.356465] usbcore: registered new device driver usb
[    0.356962] PCI: Using ACPI for IRQ routing
[    0.360597] PCI: pci_cache_line_size set to 64 bytes
[    0.360732] e820: reserve RAM buffer [mem 0x0009d800-0x0009ffff]
[    0.360742] e820: reserve RAM buffer [mem 0xd9c9f000-0xdbffffff]
[    0.360745] e820: reserve RAM buffer [mem 0xdb000000-0xdbffffff]
[    0.360748] e820: reserve RAM buffer [mem 0x11fe00000-0x11fffffff]
[    0.361590] NetLabel: Initializing
[    0.361596] NetLabel:  domain hash size = 128
[    0.361601] NetLabel:  protocols = UNLABELED CIPSOv4
[    0.361665] NetLabel:  unlabeled traffic allowed by default
[    0.361915] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0
[    0.361931] hpet0: 8 comparators, 64-bit 14.318180 MHz counter
[    0.364036] clocksource: Switched to clocksource hpet
[    0.412332] AppArmor: AppArmor Filesystem Enabled
[    0.412622] pnp: PnP ACPI init
[    0.413351] system 00:00: [io  0x0680-0x069f] has been reserved
[    0.413362] system 00:00: [io  0x1000-0x100f] has been reserved
[    0.413370] system 00:00: [io  0x5000-0x5003] has been reserved
[    0.413379] system 00:00: [io  0xffff] has been reserved
[    0.413392] system 00:00: [io  0x0400-0x0453] could not be reserved
[    0.413400] system 00:00: [io  0x0458-0x047f] has been reserved
[    0.413408] system 00:00: [io  0x0500-0x057f] has been reserved
[    0.413417] system 00:00: [io  0x0a00-0x0a0f] has been reserved
[    0.413425] system 00:00: [io  0x164e-0x164f] has been reserved
[    0.413433] system 00:00: [io  0x5000-0x500f] could not be reserved
[    0.413478] system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.413582] pnp 00:01: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.413758] system 00:02: [io  0x0454-0x0457] has been reserved
[    0.413772] system 00:02: Plug and Play ACPI device, IDs INT3f0d PNP0c02 (active)
[    0.413881] pnp 00:03: Plug and Play ACPI device, IDs PNP0303 (active)
[    0.414020] pnp 00:04: Plug and Play ACPI device, IDs ETD0b00 SYN0002 PNP0f13 (active)
[    0.414505] system 00:05: [mem 0xfed1c000-0xfed1ffff] has been reserved
[    0.414515] system 00:05: [mem 0xfed10000-0xfed17fff] has been reserved
[    0.414524] system 00:05: [mem 0xfed18000-0xfed18fff] has been reserved
[    0.414532] system 00:05: [mem 0xfed19000-0xfed19fff] has been reserved
[    0.414541] system 00:05: [mem 0xf8000000-0xfbffffff] has been reserved
[    0.414550] system 00:05: [mem 0xfed20000-0xfed3ffff] has been reserved
[    0.414558] system 00:05: [mem 0xfed90000-0xfed93fff] has been reserved
[    0.414567] system 00:05: [mem 0xfed45000-0xfed8ffff] has been reserved
[    0.414576] system 00:05: [mem 0xff000000-0xffffffff] could not be reserved
[    0.414585] system 00:05: [mem 0xfee00000-0xfeefffff] could not be reserved
[    0.414598] system 00:05: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.415513] system 00:06: Plug and Play ACPI device, IDs PNP0c01 (active)
[    0.415575] pnp: PnP ACPI: found 7 devices
[    0.428549] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.428617] pci 0000:00:1c.0: PCI bridge to [bus 01]
[    0.428632] pci 0000:00:1c.0:   bridge window [mem 0xf0600000-0xf06fffff]
[    0.428654] pci 0000:00:1c.3: PCI bridge to [bus 02]
[    0.428662] pci 0000:00:1c.3:   bridge window [io  0x2000-0x2fff]
[    0.428681] pci 0000:00:1c.3:   bridge window [mem 0xf0400000-0xf04fffff 64bit pref]
[    0.428698] pci 0000:00:1c.4: PCI bridge to [bus 03]
[    0.428711] pci 0000:00:1c.4:   bridge window [mem 0xf0500000-0xf05fffff]
[    0.428733] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.428736] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.428738] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.428740] pci_bus 0000:00: resource 7 [mem 0xdfa00000-0xfeafffff window]
[    0.428742] pci_bus 0000:00: resource 8 [mem 0xfed40000-0xfed44fff window]
[    0.428745] pci_bus 0000:01: resource 1 [mem 0xf0600000-0xf06fffff]
[    0.428747] pci_bus 0000:02: resource 0 [io  0x2000-0x2fff]
[    0.428749] pci_bus 0000:02: resource 2 [mem 0xf0400000-0xf04fffff 64bit pref]
[    0.428752] pci_bus 0000:03: resource 1 [mem 0xf0500000-0xf05fffff]
[    0.428910] NET: Registered protocol family 2
[    0.429542] TCP established hash table entries: 32768 (order: 6, 262144 bytes)
[    0.430084] TCP bind hash table entries: 32768 (order: 9, 2097152 bytes)
[    0.432293] TCP: Hash tables configured (established 32768 bind 32768)
[    0.432464] UDP hash table entries: 2048 (order: 6, 327680 bytes)
[    0.432818] UDP-Lite hash table entries: 2048 (order: 6, 327680 bytes)
[    0.433475] NET: Registered protocol family 1
[    0.433512] pci 0000:00:02.0: Video device with shadowed ROM
[    0.433720] PCI: CLS 64 bytes, default 64
[    0.434044] Trying to unpack rootfs image as initramfs...
[    0.536147] Freeing initrd memory: 3792K (ffff880037888000 - ffff880037c3c000)
[    0.536266] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[    0.536275] software IO TLB [mem 0xd5c9f000-0xd9c9f000] (64MB) mapped at [ffff8800d5c9f000-ffff8800d9c9efff]
[    0.536457] RAPL PMU detected, API unit is 2^-32 Joules, 3 fixed counters 163840 ms ovfl timer
[    0.536467] hw unit of domain pp0-core 2^-16 Joules
[    0.536473] hw unit of domain package 2^-16 Joules
[    0.536478] hw unit of domain pp1-gpu 2^-16 Joules
[    0.536787] microcode: CPU0 sig=0x206a7, pf=0x10, revision=0x28
[    0.536815] microcode: CPU1 sig=0x206a7, pf=0x10, revision=0x28
[    0.536860] microcode: CPU2 sig=0x206a7, pf=0x10, revision=0x28
[    0.536886] microcode: CPU3 sig=0x206a7, pf=0x10, revision=0x28
[    0.537094] microcode: Microcode Update Driver: v2.00 <[e-mail]>, Peter Oruba
[    0.537185] Scanning for low memory corruption every 60 seconds
[    0.538616] futex hash table entries: 2048 (order: 6, 262144 bytes)
[    0.538825] audit: initializing netlink subsys (disabled)
[    0.538916] audit: type=2000 audit(1443431289.492:1): initialized
[    0.539986] Initialise system trusted keyring
[    0.540311] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.540862] VFS: Disk quotas dquot_6.6.0
[    0.540913] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.541850] fuse init (API version 7.23)
[    0.544421] Key type asymmetric registered
[    0.544450] Asymmetric key parser 'x509' registered
[    0.544525] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    0.544702] io scheduler noop registered
[    0.544712] io scheduler deadline registered (default)
[    0.544764] io scheduler cfq registered
[    0.544772] start plist test
[    0.546764] end plist test
[    0.548363] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    0.548393] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    0.548641] intel_idle: MWAIT substates: 0x21120
[    0.548643] intel_idle: v0.4 model 0x2A
[    0.548645] intel_idle: lapic_timer_reliable_states 0xffffffff
[    0.550252] ACPI: AC Adapter [ADP1] (on-line)
[    0.550540] input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0D:00/input/input0
[    0.550599] ACPI: Lid Switch [LID0]
[    0.550766] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input1
[    0.550824] ACPI: Power Button [PWRB]
[    0.550990] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
[    0.551003] ACPI: Power Button [PWRF]
[    0.555970] thermal LNXTHERM:00: registered as thermal_zone0
[    0.555980] ACPI: Thermal Zone [TZ00] (68 C)
[    0.556656] thermal LNXTHERM:01: registered as thermal_zone1
[    0.556663] ACPI: Thermal Zone [TZ01] (30 C)
[    0.556799] GHES: HEST is not enabled!
[    0.557194] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
[    0.566026] ACPI: Battery Slot [BAT1] (battery present)
[    0.566373] Linux agpgart interface v0.103
[    0.578172] brd: module loaded
[    0.584499] loop: module loaded
[    0.589979] ahci 0000:00:1f.2: version 3.0
[    0.590451] ahci 0000:00:1f.2: SSS flag set, parallel bus scan disabled
[    0.604050] ahci 0000:00:1f.2: AHCI 0001.0300 32 slots 6 ports 6 Gbps 0x1b impl SATA mode
[    0.604065] ahci 0000:00:1f.2: flags: 64bit ncq sntf ilck stag pm led clo pio slum part ems sxs apst 
[    0.630560] scsi host0: ahci
[    0.631223] scsi host1: ahci
[    0.631780] scsi host2: ahci
[    0.632296] scsi host3: ahci
[    0.632779] scsi host4: ahci
[    0.633204] scsi host5: ahci
[    0.633423] ata1: SATA max UDMA/133 abar m2048@0xf0708000 port 0xf0708100 irq 25
[    0.633434] ata2: SATA max UDMA/133 abar m2048@0xf0708000 port 0xf0708180 irq 25
[    0.633442] ata3: DUMMY
[    0.633448] ata4: SATA max UDMA/133 abar m2048@0xf0708000 port 0xf0708280 irq 25
[    0.633458] ata5: SATA max UDMA/133 abar m2048@0xf0708000 port 0xf0708300 irq 25
[    0.633466] ata6: DUMMY
[    0.635256] libphy: Fixed MDIO Bus: probed
[    0.635702] tun: Universal TUN/TAP device driver, 1.6
[    0.635708] tun: (C) 1999-2004 Max Krasnyansky <[e-mail]>
[    0.635939] PPP generic driver version 2.4.2
[    0.636376] xhci_hcd 0000:03:00.0: xHCI Host Controller
[    0.636599] xhci_hcd 0000:03:00.0: new USB bus registered, assigned bus number 1
[    0.646395] xhci_hcd 0000:03:00.0: hcc params 0x0200f180 hci version 0x96 quirks 0x00080000
[    0.647401] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    0.647410] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.647419] usb usb1: Product: xHCI Host Controller
[    0.647425] usb usb1: Manufacturer: Linux 4.3.0-rc3-4-llvmlinux-amd64 xhci-hcd
[    0.647434] usb usb1: SerialNumber: 0000:03:00.0
[    0.648464] hub 1-0:1.0: USB hub found
[    0.648548] hub 1-0:1.0: 2 ports detected
[    0.649573] xhci_hcd 0000:03:00.0: xHCI Host Controller
[    0.649592] xhci_hcd 0000:03:00.0: new USB bus registered, assigned bus number 2
[    0.649697] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[    0.649808] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003
[    0.649816] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.649824] usb usb2: Product: xHCI Host Controller
[    0.649830] usb usb2: Manufacturer: Linux 4.3.0-rc3-4-llvmlinux-amd64 xhci-hcd
[    0.649839] usb usb2: SerialNumber: 0000:03:00.0
[    0.650483] hub 2-0:1.0: USB hub found
[    0.650547] hub 2-0:1.0: 2 ports detected
[    0.651162] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.651174] ehci-pci: EHCI PCI platform driver
[    0.651578] ehci-pci 0000:00:1a.0: EHCI Host Controller
[    0.651609] ehci-pci 0000:00:1a.0: new USB bus registered, assigned bus number 3
[    0.651641] ehci-pci 0000:00:1a.0: debug port 2
[    0.655593] ehci-pci 0000:00:1a.0: cache line size of 64 is not supported
[    0.655634] ehci-pci 0000:00:1a.0: irq 16, io mem 0xf070a000
[    0.663873] ehci-pci 0000:00:1a.0: USB 2.0 started, EHCI 1.00
[    0.664014] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002
[    0.664022] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.664031] usb usb3: Product: EHCI Host Controller
[    0.664037] usb usb3: Manufacturer: Linux 4.3.0-rc3-4-llvmlinux-amd64 ehci_hcd
[    0.664045] usb usb3: SerialNumber: 0000:00:1a.0
[    0.664694] hub 3-0:1.0: USB hub found
[    0.664728] hub 3-0:1.0: 2 ports detected
[    0.665659] ehci-pci 0000:00:1d.0: EHCI Host Controller
[    0.665697] ehci-pci 0000:00:1d.0: new USB bus registered, assigned bus number 4
[    0.665727] ehci-pci 0000:00:1d.0: debug port 2
[    0.669653] ehci-pci 0000:00:1d.0: cache line size of 64 is not supported
[    0.669692] ehci-pci 0000:00:1d.0: irq 23, io mem 0xf0709000
[    0.679862] ehci-pci 0000:00:1d.0: USB 2.0 started, EHCI 1.00
[    0.680013] usb usb4: New USB device found, idVendor=1d6b, idProduct=0002
[    0.680021] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.680029] usb usb4: Product: EHCI Host Controller
[    0.680035] usb usb4: Manufacturer: Linux 4.3.0-rc3-4-llvmlinux-amd64 ehci_hcd
[    0.680042] usb usb4: SerialNumber: 0000:00:1d.0
[    0.680609] hub 4-0:1.0: USB hub found
[    0.680636] hub 4-0:1.0: 2 ports detected
[    0.681071] ehci-platform: EHCI generic platform driver
[    0.681109] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    0.681127] ohci-pci: OHCI PCI platform driver
[    0.681164] ohci-platform: OHCI generic platform driver
[    0.681194] uhci_hcd: USB Universal Host Controller Interface driver
[    0.681337] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:EPAD] at 0x60,0x64 irq 1,12
[    0.688618] serio: i8042 KBD port at 0x60,0x64 irq 1
[    0.688694] serio: i8042 AUX port at 0x60,0x64 irq 12
[    0.689645] mousedev: PS/2 mouse device common for all mice
[    0.691528] rtc_cmos 00:01: rtc core: registered rtc_cmos as rtc0
[    0.691578] rtc_cmos 00:01: alarms up to one month, y3k, 242 bytes nvram, hpet irqs
[    0.691667] device-mapper: uevent: version 1.0.3
[    0.692011] device-mapper: ioctl: 4.33.0-ioctl (2015-8-18) initialised: [e-mail]
[    0.692061] Intel P-state driver initializing.
[    0.693194] ledtrig-cpu: registered to indicate activity on CPUs
[    0.693961] NET: Registered protocol family 10
[    0.696345] NET: Registered protocol family 17
[    0.696430] Key type dns_resolver registered
[    0.700445] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3
[    0.700965] registered taskstats version 1
[    0.700987] Loading compiled-in X.509 certificates
[    0.702311] Loaded X.509 cert 'Build time autogenerated kernel key: d861534734a1854c045ffb7a95ace7fffd94d654'
[    0.708771] Key type trusted registered
[    0.718154] Key type encrypted registered
[    0.718180] AppArmor: AppArmor sha1 policy hashing enabled
[    0.718194] evm: HMAC attrs: 0x1
[    0.719252] rtc_cmos 00:01: setting system clock to 2015-09-28 09:08:09 UTC (1443431289)
[    0.719428] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
[    0.719436] EDD information not available.
[    0.951801] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[    0.953377] ata1.00: ATA-8: Hitachi HTS545050A7E380, GG2OA6C0, max UDMA/133
[    0.953387] ata1.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
[    0.955116] ata1.00: configured for UDMA/133
[    0.956368] scsi 0:0:0:0: Direct-Access     ATA      Hitachi HTS54505 A6C0 PQ: 0 ANSI: 5
[    0.957635] sd 0:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/465 GiB)
[    0.957648] sd 0:0:0:0: [sda] 4096-byte physical blocks
[    0.957908] sd 0:0:0:0: [sda] Write Protect is off
[    0.957921] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    0.958023] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    0.958154] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    0.974468]  sda: sda1 sda2 sda3
[    0.975663] usb 3-1: new high-speed USB device number 2 using ehci-pci
[    0.976233] sd 0:0:0:0: [sda] Attached SCSI disk
[    0.991761] usb 4-1: new high-speed USB device number 2 using ehci-pci
[    1.108364] usb 3-1: New USB device found, idVendor=8087, idProduct=0024
[    1.108384] usb 3-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    1.109304] hub 3-1:1.0: USB hub found
[    1.109446] hub 3-1:1.0: 6 ports detected
[    1.124344] usb 4-1: New USB device found, idVendor=8087, idProduct=0024
[    1.124355] usb 4-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    1.125151] hub 4-1:1.0: USB hub found
[    1.125380] hub 4-1:1.0: 6 ports detected
[    1.275570] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[    1.276178] ata2.00: ATA-8: SanDisk iSSD P4 16GB, SSD 9.14, max UDMA/133
[    1.276188] ata2.00: 31277232 sectors, multi 1: LBA48 
[    1.276925] ata2.00: configured for UDMA/133
[    1.277540] scsi 1:0:0:0: Direct-Access     ATA      SanDisk iSSD P4  9.14 PQ: 0 ANSI: 5
[    1.278430] sd 1:0:0:0: [sdb] 31277232 512-byte logical blocks: (16.0 GB/14.9 GiB)
[    1.278524] sd 1:0:0:0: Attached scsi generic sg1 type 0
[    1.278805] sd 1:0:0:0: [sdb] Write Protect is off
[    1.278815] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[    1.278933] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    1.280439]  sdb: sdb1 sdb2
[    1.281660] sd 1:0:0:0: [sdb] Attached SCSI disk
[    1.383484] usb 3-1.2: new high-speed USB device number 3 using ehci-pci
[    1.399449] usb 4-1.4: new low-speed USB device number 3 using ehci-pci
[    1.478985] usb 3-1.2: New USB device found, idVendor=12d1, idProduct=1436
[    1.478996] usb 3-1.2: New USB device strings: Mfr=4, Product=3, SerialNumber=0
[    1.479005] usb 3-1.2: Product: HUAWEI Mobile
[    1.479010] usb 3-1.2: Manufacturer: HUAWEI Technology
[    1.498440] usb 4-1.4: New USB device found, idVendor=046d, idProduct=c00e
[    1.498451] usb 4-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    1.498460] usb 4-1.4: Product: USB-PS/2 Optical Mouse
[    1.498465] usb 4-1.4: Manufacturer: Logitech
[    1.535478] tsc: Refined TSC clocksource calibration: 1596.373 MHz
[    1.535490] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1702c2a0637, max_idle_ns: 440795222505 ns
[    1.555360] usb 3-1.4: new high-speed USB device number 4 using ehci-pci
[    1.571364] usb 4-1.5: new full-speed USB device number 4 using ehci-pci
[    1.595325] ata4: SATA link down (SStatus 0 SControl 300)
[    1.668695] usb 4-1.5: New USB device found, idVendor=8086, idProduct=0189
[    1.668707] usb 4-1.5: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    1.808102] usb 3-1.4: New USB device found, idVendor=2232, idProduct=1018
[    1.808113] usb 3-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    1.808122] usb 3-1.4: Product: WebCam SC-13HDL11431N
[    1.808127] usb 3-1.4: Manufacturer: 123
[    1.915117] ata5: SATA link down (SStatus 0 SControl 300)
[    1.916491] Freeing unused kernel memory: 2972K (ffffffff81f29000 - ffffffff82210000)
[    1.916506] Write protecting the kernel read-only data: 14336k
[    1.917536] Freeing unused kernel memory: 808K (ffff880001936000 - ffff880001a00000)
[    1.918088] Freeing unused kernel memory: 696K (ffff880001d52000 - ffff880001e00000)
[    1.957383] udevd[160]: starting version 175
[    2.241639] usb-storage 3-1.2:1.0: USB Mass Storage device detected
[    2.252193] usb-storage 3-1.2:1.1: USB Mass Storage device detected
[    2.252579] usb-storage 3-1.2:1.2: USB Mass Storage device detected
[    2.253515] usb-storage 3-1.2:1.3: USB Mass Storage device detected
[    2.255528] usb-storage 3-1.2:1.4: USB Mass Storage device detected
[    2.263133] usb-storage 3-1.2:1.5: USB Mass Storage device detected
[    2.274369] scsi host11: usb-storage 3-1.2:1.5
[    2.276298] usb-storage 3-1.2:1.6: USB Mass Storage device detected
[    2.286740] scsi host12: usb-storage 3-1.2:1.6
[    2.288078] usbcore: registered new interface driver usb-storage
[    2.304171] r8169 Gigabit Ethernet driver 2.3LK-NAPI loaded
[    2.304193] r8169 0000:02:00.0: can't disable ASPM; OS doesn't have ASPM control
[    2.305610] r8169 0000:02:00.0 eth0: RTL8168evl/8111evl at 0xffffc90000990000, e8:03:9a:36:17:a9, XID 0c900800 IRQ 31
[    2.305624] r8169 0000:02:00.0 eth0: jumbo features [frames: 9200 bytes, tx checksumming: ko]
[    2.328054] usbcore: registered new interface driver usbhid
[    2.328071] usbhid: USB HID core driver
[    2.407039] input: Logitech USB-PS/2 Optical Mouse as /devices/pci0000:00/0000:00:1d.0/usb4/4-1/4-1.4/4-1.4:1.0/0003:046D:C00E.0001/input/input6
[    2.407665] hid-generic 0003:046D:C00E.0001: input: USB HID v1.10 Mouse [Logitech USB-PS/2 Optical Mouse] on usb-0000:00:1d.0-1.4/input0
[    2.534995] clocksource: Switched to clocksource tsc
[    2.982136] EXT4-fs (loop0): mounted filesystem with ordered data mode. Opts: (null)
[    3.276096] scsi 11:0:0:0: CD-ROM            HUAWEI   Mass Storage     2.31 PQ: 0 ANSI: 2
[    3.280345] sr 11:0:0:0: [sr0] scsi-1 drive
[    3.280355] cdrom: Uniform CD-ROM driver Revision: 3.20
[    3.281111] sr 11:0:0:0: Attached scsi CD-ROM sr0
[    3.281366] sr 11:0:0:0: Attached scsi generic sg2 type 5
[    3.287840] scsi 12:0:0:0: Direct-Access     HUAWEI   SD Storage       2.31 PQ: 0 ANSI: 2
[    3.288759] sd 12:0:0:0: Attached scsi generic sg3 type 0
[    3.290964] sd 12:0:0:0: [sdc] Attached SCSI removable disk
[    3.957749] random: nonblocking pool is initialized
[    5.294173] init: ureadahead main process (500) terminated with status 5
[    9.764168] Adding 262140k swap on /host/ubuntu/disks/swap.disk.  Priority:-1 extents:1 across:262140k FS
[    9.998777] EXT4-fs (loop0): re-mounted. Opts: errors=remount-ro
[   10.165538] udevd[736]: starting version 175
[   11.489233] lp: driver loaded but no devices found
[   13.552623] ACPI Warning: SystemIO range 0x0000000000000428-0x000000000000042F conflicts with OpRegion 0x0000000000000400-0x000000000000047F (\PMIO) (20150818/utaddress-245)
[   13.552639] ACPI: If an ACPI driver is available for this device, you should use it instead of the native driver
[   13.552649] ACPI Warning: SystemIO range 0x0000000000000540-0x000000000000054F conflicts with OpRegion 0x0000000000000500-0x0000000000000563 (\GPIO) (20150818/utaddress-245)
[   13.552658] ACPI: If an ACPI driver is available for this device, you should use it instead of the native driver
[   13.552663] ACPI Warning: SystemIO range 0x0000000000000530-0x000000000000053F conflicts with OpRegion 0x0000000000000500-0x0000000000000563 (\GPIO) (20150818/utaddress-245)
[   13.552671] ACPI: If an ACPI driver is available for this device, you should use it instead of the native driver
[   13.552675] ACPI Warning: SystemIO range 0x0000000000000500-0x000000000000052F conflicts with OpRegion 0x0000000000000500-0x0000000000000563 (\GPIO) (20150818/utaddress-245)
[   13.552684] ACPI: If an ACPI driver is available for this device, you should use it instead of the native driver
[   13.552687] lpc_ich: Resource conflict(s) found affecting gpio_ich
[   13.556463] wmi: Mapper loaded
[   13.992900] samsung_laptop: detected SABI interface: SwSmi@
[   14.598307] [drm] Initialized drm 1.1.0 20060810
[   15.179624] Bluetooth: Core ver 2.20
[   15.179670] NET: Registered protocol family 31
[   15.179672] Bluetooth: HCI device and connection manager initialized
[   15.179757] Bluetooth: HCI socket layer initialized
[   15.179763] Bluetooth: L2CAP socket layer initialized
[   15.179843] Bluetooth: SCO socket layer initialized
[   15.249053] psmouse serio1: elantech: assuming hardware version 3 (with firmware version 0x450f00)
[   15.264045] psmouse serio1: elantech: Synaptics capabilities query result 0x08, 0x17, 0x0c.
[   15.279119] psmouse serio1: elantech: Elan sample query result 03, 3f, 86
[   15.358153] input: ETPS/2 Elantech Touchpad as /devices/platform/i8042/serio1/input/input7
[   15.785670] usbcore: registered new interface driver btusb
[   15.865155] usbcore: registered new interface driver usbserial
[   16.453032] Linux video capture interface: v2.00
[   16.562320] cfg80211: World regulatory domain updated:
[   16.562326] cfg80211:  DFS Master region: unset
[   16.562328] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[   16.562332] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[   16.562334] cfg80211:   (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm), (N/A)
[   16.562336] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm), (N/A)
[   16.562339] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[   16.562341] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[   16.859589] audit: type=1400 audit(1443424105.645:2): apparmor="STATUS" operation="profile_load" name="/sbin/dhclient" pid=1080 comm="apparmor_parser"
[   16.859601] audit: type=1400 audit(1443424105.645:3): apparmor="STATUS" operation="profile_load" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=1080 comm="apparmor_parser"
[   16.859609] audit: type=1400 audit(1443424105.645:4): apparmor="STATUS" operation="profile_load" name="/usr/lib/connman/scripts/dhclient-script" pid=1080 comm="apparmor_parser"
[   16.859637] audit: type=1400 audit(1443424105.645:5): apparmor="STATUS" operation="profile_replace" name="/sbin/dhclient" pid=1087 comm="apparmor_parser"
[   16.859652] audit: type=1400 audit(1443424105.645:6): apparmor="STATUS" operation="profile_replace" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=1087 comm="apparmor_parser"
[   16.859665] audit: type=1400 audit(1443424105.645:7): apparmor="STATUS" operation="profile_replace" name="/usr/lib/connman/scripts/dhclient-script" pid=1087 comm="apparmor_parser"
[   16.860610] audit: type=1400 audit(1443424105.649:8): apparmor="STATUS" operation="profile_replace" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=1080 comm="apparmor_parser"
[   16.860627] audit: type=1400 audit(1443424105.649:9): apparmor="STATUS" operation="profile_replace" name="/usr/lib/connman/scripts/dhclient-script" pid=1080 comm="apparmor_parser"
[   16.860656] audit: type=1400 audit(1443424105.649:10): apparmor="STATUS" operation="profile_replace" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=1087 comm="apparmor_parser"
[   16.860669] audit: type=1400 audit(1443424105.649:11): apparmor="STATUS" operation="profile_replace" name="/usr/lib/connman/scripts/dhclient-script" pid=1087 comm="apparmor_parser"
[   17.033958] uvcvideo: Found UVC 1.00 device WebCam SC-13HDL11431N (2232:1018)
[   17.053816] input: WebCam SC-13HDL11431N as /devices/pci0000:00/0000:00:1a.0/usb3/3-1/3-1.4/3-1.4:1.0/input/input8
[   17.054490] usbcore: registered new interface driver uvcvideo
[   17.054495] USB Video Class driver (1.1.1)
[   17.445591] ppdev: user-space parallel port driver
[   17.672818] init: failsafe main process (1156) killed by TERM signal
[   17.754869] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   17.754875] Bluetooth: BNEP filters: protocol multicast
[   17.754888] Bluetooth: BNEP socket layer initialized
[   17.947702] cdc_ether 3-1.2:1.1 wwan0: register 'cdc_ether' at usb-0000:00:1a.0-1.2, Mobile Broadband Network Device, 02:50:f3:00:00:00
[   17.948060] usbcore: registered new interface driver cdc_ether
[   17.971753] Bluetooth: RFCOMM TTY layer initialized
[   17.971771] Bluetooth: RFCOMM socket layer initialized
[   17.971801] Bluetooth: RFCOMM ver 1.11
[   17.972279] usbcore: registered new interface driver option
[   17.974333] usbserial: USB Serial support registered for GSM modem (1-port)
[   17.974495] option 3-1.2:1.0: GSM modem (1-port) converter detected
[   17.977142] usb 3-1.2: GSM modem (1-port) converter now attached to ttyUSB0
[   17.977515] option 3-1.2:1.3: GSM modem (1-port) converter detected
[   17.978144] usb 3-1.2: GSM modem (1-port) converter now attached to ttyUSB1
[   17.978209] option 3-1.2:1.4: GSM modem (1-port) converter detected
[   17.978548] usb 3-1.2: GSM modem (1-port) converter now attached to ttyUSB2
[   18.665569] Intel(R) Wireless WiFi driver for Linux
[   18.665574] Copyright(c) 2003- 2015 Intel Corporation
[   18.665891] iwlwifi 0000:01:00.0: can't disable ASPM; OS doesn't have ASPM control
[   19.688505] iwlwifi 0000:01:00.0: loaded firmware version 18.168.6.1 op_mode iwldvm
[   20.153500] snd_hda_codec_realtek hdaudioC0D0: autoconfig for ALC269VC: line_outs=1 (0x14/0x0/0x0/0x0/0x0) type:speaker
[   20.153507] snd_hda_codec_realtek hdaudioC0D0:    speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
[   20.153510] snd_hda_codec_realtek hdaudioC0D0:    hp_outs=1 (0x15/0x0/0x0/0x0/0x0)
[   20.153512] snd_hda_codec_realtek hdaudioC0D0:    mono: mono_out=0x0
[   20.153514] snd_hda_codec_realtek hdaudioC0D0:    inputs:
[   20.153517] snd_hda_codec_realtek hdaudioC0D0:      Internal Mic=0x19
[   20.153521] snd_hda_codec_realtek hdaudioC0D0:      Mic=0x18
[   20.257449] input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1b.0/sound/card0/input9
[   20.258800] input: HDA Intel PCH Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card0/input10
[   20.259178] input: HDA Intel PCH HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:1b.0/sound/card0/input11
[   21.041949] [drm] Memory usable by graphics device = 2048M
[   21.042030] [drm] Replacing VGA console driver
[   21.052094] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[   21.052099] [drm] Driver supports precise vblank timestamp query.
[   21.052998] vgaarb: device changed decodes: PCI:0000:00:02.0,olddecodes=io+mem,decodes=io+mem:owns=io+mem
[   21.071180] ACPI: Video Device [GFX0] (multi-head: yes  rom: no  post: no)
[   21.072092] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input12
[   21.073295] [drm] Initialized i915 1.6.0 20150731 for 0000:00:02.0 on minor 0
[   21.086737] fbcon: inteldrmfb (fb0) is primary device
[   22.124270] Console: switching to colour frame buffer device 170x48
[   22.128838] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device
[   23.134672] iwlwifi 0000:01:00.0: CONFIG_IWLWIFI_DEBUG disabled
[   23.134677] iwlwifi 0000:01:00.0: CONFIG_IWLWIFI_DEBUGFS enabled
[   23.134679] iwlwifi 0000:01:00.0: CONFIG_IWLWIFI_DEVICE_TRACING enabled
[   23.134683] iwlwifi 0000:01:00.0: Detected Intel(R) Centrino(R) Advanced-N 6230 AGN, REV=0xB0
[   23.135219] iwlwifi 0000:01:00.0: L1 Enabled - LTR Disabled
[   23.250876] ieee80211 phy0: Selected rate control algorithm 'iwl-agn-rs'
[   25.380481] r8169 0000:02:00.0 eth0: link down
[   25.380772] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready

Patch

--- a/drivers/hid/usbhid/hid-core.c
+++ b/drivers/hid/usbhid/hid-core.c
@@ -729,16 +729,16 @@  void usbhid_close(struct hid_device *hid)
         * data acquistion due to a resumption we no longer
         * care about
         */
-       spin_lock_irq(&usbhid->lock);
+       spin_lock_bh(&usbhid->lock);
        if (!--hid->open) {
-               spin_unlock_irq(&usbhid->lock);
+               spin_unlock_bh(&usbhid->lock);
                hid_cancel_delayed_stuff(usbhid);
                if (!(hid->quirks & HID_QUIRK_ALWAYS_POLL)) {
                        usb_kill_urb(usbhid->urbin);
                        usbhid->intf->needs_remote_wakeup = 0;
                }
        } else {
-               spin_unlock_irq(&usbhid->lock);
+               spin_unlock_bh(&usbhid->lock);
        }
        mutex_unlock(&hid_open_mut);
 }
@@ -1392,6 +1392,8 @@  static void usbhid_disconnect(struct usb_interface *intf)

 static void hid_cancel_delayed_stuff(struct usbhid_device *usbhid)
 {
+       might_sleep();
+
        del_timer_sync(&usbhid->io_retry);
        cancel_work_sync(&usbhid->reset_work);
 }