You are not logged in.

#1 2016-03-03 13:06:34

macogala
Member
Registered: 2016-03-03
Posts: 4

Shutdown/Reboot takes long time

Sorry, when I posted this I got an error, but eventually it got posted. This is why it is now duplicated.


Hello everyone,
this is my first post here.

The problem is that when I reboot/shutdown the system, it takes long time to complete the operation. This is apparently a well-known issue, but I have surfed the internet all the morning and haven't found a solution yet.
The reboot/shutdown process is eventually completed though, but this is annoying.

As stated in the systemd wiki, the problem is most likely caused by systemd waiting for each service to exit before trying to kill it. The wiki then redirects this page where it describes the process of debugging this issue in order to find out what's causing the problem itself.
Following this guide, I have booted with the debug options:

systemd.log_level=debug systemd.log_target=kmsg log_buf_len=1M enforcing=0

I wasn't sure about where I was supposed to place that, but doing some search on the web told me to place it in /etc/default/grub, which now looks like:

GRUB_DEFAULT=0
GRUB_TIMEOUT=5
GRUB_DISTRIBUTOR="Arch"
GRUB_CMDLINE_LINUX_DEFAULT="quiet"
GRUB_CMDLINE_LINUX="systemd.log_level=debug systemd.log_target=kmsg log_buf_len=1M enforcing=0"
...
...

If this is not the right place to put it, please let me know smile

Also I have saved the following script as /usr/lib/systemd/system-shutdown/debug.sh and made it executable:

#!/bin/sh
mount -o remount,rw /
dmesg > /shutdown-log.txt
mount -o remount,ro /

and then rebooted.

The output of the shutdown-log.txt is the following:

[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.4.1-2-ARCH (builduser@foutrelis) (gcc version 5.3.0 (GCC) ) #1 SMP PREEMPT Wed Feb 3 13:12:33 UTC 2016
[    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-linux root=UUID=5b13aa8d-b666-43c4-a5c5-cef392f408f1 rw quiet
[    0.000000] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
[    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 832 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-0x0000000000091bff] usable
[    0.000000] BIOS-e820: [mem 0x0000000000091c00-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-0x0000000040003fff] usable
[    0.000000] BIOS-e820: [mem 0x0000000040004000-0x0000000040004fff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000040005000-0x00000000aaabefff] usable
[    0.000000] BIOS-e820: [mem 0x00000000aaabf000-0x00000000aaebefff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000aaebf000-0x00000000aafbefff] ACPI NVS
[    0.000000] BIOS-e820: [mem 0x00000000aafbf000-0x00000000aaffefff] ACPI data
[    0.000000] BIOS-e820: [mem 0x00000000aafff000-0x00000000aaffffff] usable
[    0.000000] BIOS-e820: [mem 0x00000000ab000000-0x00000000af9fffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] 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 0x00000000ffd80000-0x00000000ffffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000024f5fffff] usable
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.7 present.
[    0.000000] DMI: TOSHIBA SATELLITE U840/Type2 - Board Product Name1, BIOS 1.60 08/16/2012
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x24f600 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 000000000 mask F80000000 write-back
[    0.000000]   1 base 080000000 mask FC0000000 write-back
[    0.000000]   2 base 0AB000000 mask FFF000000 uncachable
[    0.000000]   3 base 0AC000000 mask FFC000000 uncachable
[    0.000000]   4 base 0B0000000 mask FF0000000 uncachable
[    0.000000]   5 base 0FFC00000 mask FFFC00000 write-protect
[    0.000000]   6 base 100000000 mask F00000000 write-back
[    0.000000]   7 base 200000000 mask FC0000000 write-back
[    0.000000]   8 base 240000000 mask FF0000000 write-back
[    0.000000]   9 base 24F600000 mask FFFE00000 uncachable
[    0.000000] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- WT  
[    0.000000] e820: last_pfn = 0xab000 max_arch_pfn = 0x400000000
[    0.000000] found SMP MP-table at [mem 0x000fe1b0-0x000fe1bf] mapped at [ffff8800000fe1b0]
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] Base memory trampoline at [ffff88000008b000] 8b000 size 24576
[    0.000000] BRK [0x01b3f000, 0x01b3ffff] PGTABLE
[    0.000000] BRK [0x01b40000, 0x01b40fff] PGTABLE
[    0.000000] BRK [0x01b41000, 0x01b41fff] PGTABLE
[    0.000000] BRK [0x01b42000, 0x01b42fff] PGTABLE
[    0.000000] BRK [0x01b43000, 0x01b43fff] PGTABLE
[    0.000000] BRK [0x01b44000, 0x01b44fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x37928000-0x37c8bfff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000FE020 000024 (v02 TOSQCI)
[    0.000000] ACPI: XSDT 0x00000000AAFFE210 0000A4 (v01 TOSQCI TOSQCI00 00000001      01000013)
[    0.000000] ACPI: FACP 0x00000000AAFFB000 00010C (v05 TOSQCI TOSQCI00 00000001 ACPI 00040000)
[    0.000000] ACPI: DSDT 0x00000000AAFEA000 00DA84 (v01 TOSQCI TOSQCI00 00000000 ACPI 00040000)
[    0.000000] ACPI: FACS 0x00000000AAFBA000 000040
[    0.000000] ACPI: UEFI 0x00000000AAFFD000 000236 (v01 TOSQCI TOSQCI00 00000001 ACPI 00040000)
[    0.000000] ACPI: ASF! 0x00000000AAFFC000 0000A5 (v32 TOSQCI TOSQCI00 00000001 ACPI 00040000)
[    0.000000] ACPI: HPET 0x00000000AAFFA000 000038 (v01 TOSQCI TOSQCI00 00000001 ACPI 00040000)
[    0.000000] ACPI: APIC 0x00000000AAFF9000 00008C (v03 TOSQCI TOSQCI00 00000001 ACPI 00040000)
[    0.000000] ACPI: MCFG 0x00000000AAFF8000 00003C (v01 TOSQCI TOSQCI00 00000001 ACPI 00040000)
[    0.000000] ACPI: SLIC 0x00000000AAFE9000 000176 (v01 TOSQCI TOSQCI00 00000001 ACPI 00040000)
[    0.000000] ACPI: WDAT 0x00000000AAFE8000 000224 (v01 TOSQCI TOSQCI00 00000001 ACPI 00040000)
[    0.000000] ACPI: SSDT 0x00000000AAFE6000 001068 (v01 INSYDE CR CRB   00001000 ACPI 00040000)
[    0.000000] ACPI: BOOT 0x00000000AAFE4000 000028 (v01 TOSQCI TOSQCI00 00000001 ACPI 00040000)
[    0.000000] ACPI: ASPT 0x00000000AAFDF000 000034 (v07 TOSQCI TOSQCI00 00000001 ACPI 00040000)
[    0.000000] ACPI: MSDM 0x00000000AAFDE000 000055 (v03 TOSQCI TOSQCI00 00000001 ACPI 00040000)
[    0.000000] ACPI: FPDT 0x00000000AAFDC000 000044 (v01 TOSQCI TOSQCI00 00000001 ACPI 00040000)
[    0.000000] ACPI: SSDT 0x00000000AAFDB000 0008CD (v01 INSYDE CR CRB   00003000 ACPI 00040000)
[    0.000000] ACPI: SSDT 0x00000000AAFDA000 000A92 (v01 INSYDE CR CRB   00003000 ACPI 00040000)
[    0.000000] ACPI: DMAR 0x00000000AAFD9000 0000B8 (v01 TOSQCI TOSQCI00 00000001 ACPI 00040000)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000024f5fffff]
[    0.000000] NODE_DATA(0) allocated [mem 0x24f5f4000-0x24f5f7fff]
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
[    0.000000]   Normal   [mem 0x0000000100000000-0x000000024f5fffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x0000000000090fff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x000000001fffffff]
[    0.000000]   node   0: [mem 0x0000000020200000-0x0000000040003fff]
[    0.000000]   node   0: [mem 0x0000000040005000-0x00000000aaabefff]
[    0.000000]   node   0: [mem 0x00000000aafff000-0x00000000aaffffff]
[    0.000000]   node   0: [mem 0x0000000100000000-0x000000024f5fffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000024f5fffff]
[    0.000000] On node 0 totalpages: 2072143
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 21 pages reserved
[    0.000000]   DMA zone: 3984 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 10851 pages used for memmap
[    0.000000]   DMA32 zone: 694463 pages, LIFO batch:31
[    0.000000]   Normal zone: 21464 pages used for memmap
[    0.000000]   Normal zone: 1373696 pages, LIFO batch:31
[    0.000000] Reserving Intel graphics stolen memory at 0xaba00000-0xaf9fffff
[    0.000000] ACPI: PM-Timer IO Port: 0x408
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] IOAPIC[0]: apic_id 0, 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: 0x8086a201 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 0x00091000-0x00091fff]
[    0.000000] PM: Registered nosave memory: [mem 0x00092000-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 0x40004000-0x40004fff]
[    0.000000] PM: Registered nosave memory: [mem 0xaaabf000-0xaaebefff]
[    0.000000] PM: Registered nosave memory: [mem 0xaaebf000-0xaafbefff]
[    0.000000] PM: Registered nosave memory: [mem 0xaafbf000-0xaaffefff]
[    0.000000] PM: Registered nosave memory: [mem 0xab000000-0xaf9fffff]
[    0.000000] PM: Registered nosave memory: [mem 0xafa00000-0xdfffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xe0000000-0xefffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xf0000000-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-0xffd7ffff]
[    0.000000] PM: Registered nosave memory: [mem 0xffd80000-0xffffffff]
[    0.000000] e820: [mem 0xafa00000-0xdfffffff] 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: 6370452778343963 ns
[    0.000000] setup_percpu: NR_CPUS:128 nr_cpumask_bits:128 nr_cpu_ids:8 nr_node_ids:1
[    0.000000] PERCPU: Embedded 32 pages/cpu @ffff88024f200000 s92696 r8192 d30184 u262144
[    0.000000] pcpu-alloc: s92696 r8192 d30184 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: 2039743
[    0.000000] Policy zone: Normal
[    0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-linux root=UUID=5b13aa8d-b666-43c4-a5c5-cef392f408f1 rw quiet
[    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: 8075028K/8288572K available (5721K kernel code, 945K rwdata, 1820K rodata, 1188K init, 1156K bss, 213544K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000]     Build-time adjustment of leaf fanout to 64.
[    0.000000]     RCU restricting CPUs from NR_CPUS=128 to nr_cpu_ids=8.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=8
[    0.000000] NR_IRQS:8448 nr_irqs:488 16
[    0.000000] Console: colour dummy device 80x25
[    0.000000] console [tty0] enabled
[    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 1692.984 MHz processor
[    0.000051] Calibrating delay loop (skipped), value calculated using timer frequency.. 3387.05 BogoMIPS (lpj=5643280)
[    0.000055] pid_max: default: 32768 minimum: 301
[    0.000062] ACPI: Core revision 20150930
[    0.012624] ACPI: 4 ACPI AML tables successfully acquired and loaded
[    0.012651] Security Framework initialized
[    0.012653] Yama: becoming mindful.
[    0.013338] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes)
[    0.016393] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.017761] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes)
[    0.017775] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes)
[    0.018057] Initializing cgroup subsys io
[    0.018062] Initializing cgroup subsys memory
[    0.018069] Initializing cgroup subsys devices
[    0.018072] Initializing cgroup subsys freezer
[    0.018074] Initializing cgroup subsys net_cls
[    0.018077] Initializing cgroup subsys pids
[    0.018106] CPU: Physical Processor ID: 0
[    0.018107] CPU: Processor Core ID: 0
[    0.018114] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
[    0.018115] ENERGY_PERF_BIAS: View and update with x86_energy_perf_policy(8)
[    0.018670] mce: CPU supports 7 MCE banks
[    0.018686] CPU0: Thermal monitoring enabled (TM1)
[    0.018694] process: using mwait in idle threads
[    0.018698] Last level iTLB entries: 4KB 512, 2MB 8, 4MB 8
[    0.018700] Last level dTLB entries: 4KB 512, 2MB 32, 4MB 32, 1GB 0
[    0.019300] Freeing SMP alternatives memory: 20K (ffffffff81a17000 - ffffffff81a1c000)
[    0.049188] ftrace: allocating 22852 entries in 90 pages
[    0.064398] DMAR: Host address width 36
[    0.064402] DMAR: DRHD base: 0x000000fed90000 flags: 0x0
[    0.064410] DMAR: dmar0: reg_base_addr fed90000 ver 1:0 cap c0000020e60262 ecap f0101a
[    0.064412] DMAR: DRHD base: 0x000000fed91000 flags: 0x1
[    0.064417] DMAR: dmar1: reg_base_addr fed91000 ver 1:0 cap c9008020660262 ecap f0105a
[    0.064418] DMAR: RMRR base: 0x000000aae8f000 end: 0x000000aaeaefff
[    0.064420] DMAR: RMRR base: 0x000000ab800000 end: 0x000000af9fffff
[    0.064423] DMAR-IR: IOAPIC id 0 under DRHD base  0xfed91000 IOMMU 1
[    0.064425] DMAR-IR: HPET id 0 under DRHD base 0xfed91000
[    0.064427] DMAR-IR: Queued invalidation will be enabled to support x2apic and Intr-remapping.
[    0.064826] DMAR-IR: Enabled IRQ remapping in x2apic mode
[    0.064830] x2apic enabled
[    0.064837] Switched APIC routing to cluster x2apic.
[    0.065299] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.098311] TSC deadline timer enabled
[    0.098316] smpboot: CPU0: Intel(R) Core(TM) i5-3317U CPU @ 1.70GHz (family: 0x6, model: 0x3a, stepping: 0x9)
[    0.098354] Performance Events: PEBS fmt1+, 16-deep LBR, IvyBridge events, full-width counters, Intel PMU driver.
[    0.098384] ... version:                3
[    0.098385] ... bit width:              48
[    0.098386] ... generic registers:      4
[    0.098387] ... value mask:             0000ffffffffffff
[    0.098389] ... max period:             0000ffffffffffff
[    0.098390] ... fixed-purpose events:   3
[    0.098392] ... event mask:             000000070000000f
[    0.128409] x86: Booting SMP configuration:
[    0.128412] .... node  #0, CPUs:      #1
[    0.131615] NMI watchdog: enabled on all CPUs, permanently consumes one hw-PMU counter.
[    0.138434]  #2 #3
[    0.151475] x86: Booted up 1 node, 4 CPUs
[    0.151480] smpboot: Total of 4 processors activated (13549.20 BogoMIPS)
[    0.156039] devtmpfs: initialized
[    0.160340] PM: Registering ACPI NVS region [mem 0xaaebf000-0xaafbefff] (1048576 bytes)
[    0.160475] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns
[    0.160592] pinctrl core: initialized pinctrl subsystem
[    0.160645] RTC time: 11:20:04, date: 03/03/16
[    0.160802] NET: Registered protocol family 16
[    0.172505] cpuidle: using governor ladder
[    0.185839] cpuidle: using governor menu
[    0.185885] Simple Boot Flag at 0x44 set to 0x1
[    0.185914] ACPI FADT declares the system doesn't support PCIe ASPM, so disable it
[    0.185916] ACPI: bus type PCI registered
[    0.185919] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.186021] PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000)
[    0.186024] PCI: MMCONFIG at [mem 0xe0000000-0xefffffff] reserved in E820
[    0.186041] PCI: Using configuration type 1 for base access
[    0.186365] perf_event_intel: PMU erratum BJ122, BV98, HSD29 worked around, HT is on
[    0.202938] ACPI: Added _OSI(Module Device)
[    0.202941] ACPI: Added _OSI(Processor Device)
[    0.202943] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.202945] ACPI: Added _OSI(Processor Aggregator Device)
[    0.207650] ACPI: Executed 1 blocks of module-level executable AML code
[    0.212178] ACPI: Dynamic OEM Table Load:
[    0.212190] ACPI: SSDT 0xFFFF880245787000 00083B (v01 PmRef  Cpu0Cst  00003001 INTL 20111123)
[    0.213052] ACPI: Dynamic OEM Table Load:
[    0.213061] ACPI: SSDT 0xFFFF880245072C00 000303 (v01 PmRef  ApIst    00003000 INTL 20111123)
[    0.213806] ACPI: Dynamic OEM Table Load:
[    0.213813] ACPI: SSDT 0xFFFF880245036A00 000119 (v01 PmRef  ApCst    00003000 INTL 20111123)
[    0.215116] ACPI : EC: EC started
[    0.215355] ACPI: Interpreter enabled
[    0.215365] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S1_] (20150930/hwxface-580)
[    0.215371] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20150930/hwxface-580)
[    0.215391] ACPI: (supports S0 S3 S4 S5)
[    0.215393] ACPI: Using IOAPIC for interrupt routing
[    0.215428] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.218536] acpi ABCD0000:00: ACPI dock station (docks/bays count: 1)
[    0.223111] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-fe])
[    0.223118] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI]
[    0.223280] \_SB_.PCI0:_OSC invalid UUID
[    0.223282] _OSC request data:1 1f 0 
[    0.223287] acpi PNP0A08:00: _OSC failed (AE_ERROR); disabling ASPM
[    0.223896] PCI host bridge to bus 0000:00
[    0.223901] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.223903] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.223906] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.223908] pci_bus 0000:00: root bus resource [mem 0xafa00000-0xfeafffff window]
[    0.223911] pci_bus 0000:00: root bus resource [bus 00-fe]
[    0.223921] pci 0000:00:00.0: [8086:0154] type 00 class 0x060000
[    0.224044] pci 0000:00:02.0: [8086:0166] type 00 class 0x030000
[    0.224063] pci 0000:00:02.0: reg 0x10: [mem 0xc0000000-0xc03fffff 64bit]
[    0.224071] pci 0000:00:02.0: reg 0x18: [mem 0xb0000000-0xbfffffff 64bit pref]
[    0.224077] pci 0000:00:02.0: reg 0x20: [io  0x3000-0x303f]
[    0.224243] pci 0000:00:14.0: [8086:1e31] type 00 class 0x0c0330
[    0.224289] pci 0000:00:14.0: reg 0x10: [mem 0xc0600000-0xc060ffff 64bit]
[    0.224374] pci 0000:00:14.0: PME# supported from D3hot D3cold
[    0.224431] pci 0000:00:14.0: System wakeup disabled by ACPI
[    0.224487] pci 0000:00:16.0: [8086:1e3a] type 00 class 0x078000
[    0.224530] pci 0000:00:16.0: reg 0x10: [mem 0xc0614000-0xc061400f 64bit]
[    0.224612] pci 0000:00:16.0: PME# supported from D0 D3hot D3cold
[    0.224730] pci 0000:00:1a.0: [8086:1e2d] type 00 class 0x0c0320
[    0.225038] pci 0000:00:1a.0: reg 0x10: [mem 0xc0619000-0xc06193ff]
[    0.226691] pci 0000:00:1a.0: PME# supported from D0 D3hot D3cold
[    0.226768] pci 0000:00:1a.0: System wakeup disabled by ACPI
[    0.226827] pci 0000:00:1b.0: [8086:1e20] type 00 class 0x040300
[    0.226865] pci 0000:00:1b.0: reg 0x10: [mem 0xc0610000-0xc0613fff 64bit]
[    0.226954] pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold
[    0.227014] pci 0000:00:1b.0: System wakeup disabled by ACPI
[    0.227071] pci 0000:00:1c.0: [8086:1e10] type 01 class 0x060400
[    0.227202] pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold
[    0.227267] pci 0000:00:1c.0: System wakeup disabled by ACPI
[    0.227324] pci 0000:00:1c.2: [8086:1e14] type 01 class 0x060400
[    0.227456] pci 0000:00:1c.2: PME# supported from D0 D3hot D3cold
[    0.227519] pci 0000:00:1c.2: System wakeup disabled by ACPI
[    0.227579] pci 0000:00:1c.6: [8086:1e1c] type 01 class 0x060400
[    0.227682] pci 0000:00:1c.6: PME# supported from D0 D3hot D3cold
[    0.227743] pci 0000:00:1c.6: System wakeup disabled by ACPI
[    0.227798] pci 0000:00:1d.0: [8086:1e26] type 00 class 0x0c0320
[    0.228100] pci 0000:00:1d.0: reg 0x10: [mem 0xc0618000-0xc06183ff]
[    0.229785] pci 0000:00:1d.0: PME# supported from D0 D3hot D3cold
[    0.229860] pci 0000:00:1d.0: System wakeup disabled by ACPI
[    0.229916] pci 0000:00:1f.0: [8086:1e57] type 00 class 0x060100
[    0.230139] pci 0000:00:1f.2: [8086:282a] type 00 class 0x010400
[    0.230173] pci 0000:00:1f.2: reg 0x10: [io  0x3088-0x308f]
[    0.230183] pci 0000:00:1f.2: reg 0x14: [io  0x3094-0x3097]
[    0.230194] pci 0000:00:1f.2: reg 0x18: [io  0x3080-0x3087]
[    0.230204] pci 0000:00:1f.2: reg 0x1c: [io  0x3090-0x3093]
[    0.230216] pci 0000:00:1f.2: reg 0x20: [io  0x3060-0x307f]
[    0.230228] pci 0000:00:1f.2: reg 0x24: [mem 0xc0617000-0xc06177ff]
[    0.230274] pci 0000:00:1f.2: PME# supported from D3hot
[    0.230374] pci 0000:00:1f.3: [8086:1e22] type 00 class 0x0c0500
[    0.230400] pci 0000:00:1f.3: reg 0x10: [mem 0xc0615000-0xc06150ff 64bit]
[    0.230433] pci 0000:00:1f.3: reg 0x20: [io  0x3040-0x305f]
[    0.230632] pci 0000:00:1c.0: PCI bridge to [bus 01]
[    0.230788] pci 0000:02:00.0: [8086:0887] type 00 class 0x028000
[    0.230891] pci 0000:02:00.0: reg 0x10: [mem 0xc0500000-0xc0501fff 64bit]
[    0.231147] pci 0000:02:00.0: PME# supported from D0 D3hot D3cold
[    0.231220] pci 0000:02:00.0: System wakeup disabled by ACPI
[    0.236087] pci 0000:00:1c.2: PCI bridge to [bus 02]
[    0.236096] pci 0000:00:1c.2:   bridge window [mem 0xc0500000-0xc05fffff]
[    0.236248] pci 0000:03:00.0: [1969:2062] type 00 class 0x020000
[    0.236447] pci 0000:03:00.0: reg 0x10: [mem 0xc0400000-0xc043ffff 64bit]
[    0.236499] pci 0000:03:00.0: reg 0x18: [io  0x2000-0x207f]
[    0.236918] pci 0000:03:00.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.237025] pci 0000:03:00.0: System wakeup disabled by ACPI
[    0.242760] pci 0000:00:1c.6: PCI bridge to [bus 03]
[    0.242766] pci 0000:00:1c.6:   bridge window [io  0x2000-0x2fff]
[    0.242771] pci 0000:00:1c.6:   bridge window [mem 0xc0400000-0xc04fffff]
[    0.243394] ACPI: PCI Interrupt Link [LNKA] (IRQs 1 3 4 5 6 10 11 12 14 15) *7
[    0.243464] ACPI: PCI Interrupt Link [LNKB] (IRQs 1 3 4 5 6 10 *11 12 14 15)
[    0.243531] ACPI: PCI Interrupt Link [LNKC] (IRQs 1 3 4 5 6 10 *11 12 14 15)
[    0.243597] ACPI: PCI Interrupt Link [LNKD] (IRQs 1 3 4 5 6 *10 11 12 14 15)
[    0.243663] ACPI: PCI Interrupt Link [LNKE] (IRQs 1 3 4 5 6 10 11 12 14 15) *0, disabled.
[    0.243729] ACPI: PCI Interrupt Link [LNKF] (IRQs 1 3 4 5 6 *10 11 12 14 15)
[    0.243794] ACPI: PCI Interrupt Link [LNKG] (IRQs 1 3 4 5 6 *10 11 12 14 15)
[    0.243858] ACPI: PCI Interrupt Link [LNKH] (IRQs 1 3 4 5 6 10 11 12 14 15) *7
[    0.244223] ACPI: Enabled 4 GPEs in block 00 to 3F
[    0.244272] ACPI : EC: GPE = 0x1e, I/O: command/status = 0x66, data = 0x62
[    0.244404] vgaarb: setting as boot device: PCI:0000:00:02.0
[    0.244407] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    0.244411] vgaarb: loaded
[    0.244412] vgaarb: bridge control possible 0000:00:02.0
[    0.244535] PCI: Using ACPI for IRQ routing
[    0.252188] PCI: pci_cache_line_size set to 64 bytes
[    0.252315] e820: reserve RAM buffer [mem 0x00091c00-0x0009ffff]
[    0.252318] e820: reserve RAM buffer [mem 0x40004000-0x43ffffff]
[    0.252319] e820: reserve RAM buffer [mem 0xaaabf000-0xabffffff]
[    0.252322] e820: reserve RAM buffer [mem 0xab000000-0xabffffff]
[    0.252323] e820: reserve RAM buffer [mem 0x24f600000-0x24fffffff]
[    0.252487] NetLabel: Initializing
[    0.252489] NetLabel:  domain hash size = 128
[    0.252490] NetLabel:  protocols = UNLABELED CIPSOv4
[    0.252506] NetLabel:  unlabeled traffic allowed by default
[    0.252544] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0
[    0.252551] hpet0: 8 comparators, 64-bit 14.318180 MHz counter
[    0.254591] clocksource: Switched to clocksource hpet
[    0.261629] pnp: PnP ACPI init
[    0.261803] system 00:00: [io  0x0680-0x069f] has been reserved
[    0.261807] system 00:00: [io  0x1100-0x110f] has been reserved
[    0.261810] system 00:00: [io  0xffff] has been reserved
[    0.261813] system 00:00: [io  0xffff] has been reserved
[    0.261816] system 00:00: [io  0x0400-0x0453] could not be reserved
[    0.261818] system 00:00: [io  0x0458-0x047f] has been reserved
[    0.261821] system 00:00: [io  0x0500-0x057f] has been reserved
[    0.261823] system 00:00: [io  0x164e-0x164f] has been reserved
[    0.261829] system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.261866] pnp 00:01: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.261931] system 00:02: [io  0x0454-0x0457] has been reserved
[    0.261936] system 00:02: Plug and Play ACPI device, IDs INT3f0d PNP0c02 (active)
[    0.261999] pnp 00:03: Plug and Play ACPI device, IDs TOS1102 PNP0303 (active)
[    0.262037] pnp 00:04: Plug and Play ACPI device, IDs TOS1100 PNP0f13 (active)
[    0.262201] system 00:05: [mem 0xfed1c000-0xfed1ffff] has been reserved
[    0.262204] system 00:05: [mem 0xfed10000-0xfed17fff] has been reserved
[    0.262206] system 00:05: [mem 0xfed18000-0xfed18fff] has been reserved
[    0.262209] system 00:05: [mem 0xfed19000-0xfed19fff] has been reserved
[    0.262212] system 00:05: [mem 0xe0000000-0xefffffff] has been reserved
[    0.262214] system 00:05: [mem 0xfed20000-0xfed3ffff] has been reserved
[    0.262217] system 00:05: [mem 0xfed90000-0xfed93fff] could not be reserved
[    0.262219] system 00:05: [mem 0xff000000-0xffffffff] could not be reserved
[    0.262222] system 00:05: [mem 0xfee00000-0xfeefffff] could not be reserved
[    0.262225] system 00:05: [mem 0xafa00000-0xafa00fff] has been reserved
[    0.262228] system 00:05: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.262553] system 00:06: [mem 0x20000000-0x201fffff] has been reserved
[    0.262556] system 00:06: [mem 0x40004000-0x40004fff] has been reserved
[    0.262560] system 00:06: Plug and Play ACPI device, IDs PNP0c01 (active)
[    0.262647] pnp: PnP ACPI: found 7 devices
[    0.269550] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.269601] pci 0000:00:1c.0: PCI bridge to [bus 01]
[    0.269622] pci 0000:00:1c.2: PCI bridge to [bus 02]
[    0.269630] pci 0000:00:1c.2:   bridge window [mem 0xc0500000-0xc05fffff]
[    0.269643] pci 0000:00:1c.6: PCI bridge to [bus 03]
[    0.269648] pci 0000:00:1c.6:   bridge window [io  0x2000-0x2fff]
[    0.269654] pci 0000:00:1c.6:   bridge window [mem 0xc0400000-0xc04fffff]
[    0.269669] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.269671] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.269674] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.269676] pci_bus 0000:00: resource 7 [mem 0xafa00000-0xfeafffff window]
[    0.269679] pci_bus 0000:02: resource 1 [mem 0xc0500000-0xc05fffff]
[    0.269682] pci_bus 0000:03: resource 0 [io  0x2000-0x2fff]
[    0.269684] pci_bus 0000:03: resource 1 [mem 0xc0400000-0xc04fffff]
[    0.269730] NET: Registered protocol family 2
[    0.269967] TCP established hash table entries: 65536 (order: 7, 524288 bytes)
[    0.270175] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
[    0.270325] TCP: Hash tables configured (established 65536 bind 65536)
[    0.270362] UDP hash table entries: 4096 (order: 5, 131072 bytes)
[    0.270402] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes)
[    0.270484] NET: Registered protocol family 1
[    0.270506] pci 0000:00:02.0: Video device with shadowed ROM
[    0.298008] PCI: CLS 64 bytes, default 64
[    0.298077] Unpacking initramfs...
[    0.383276] Freeing initrd memory: 3472K (ffff880037928000 - ffff880037c8c000)
[    0.383308] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[    0.383312] software IO TLB [mem 0xa6abf000-0xaaabf000] (64MB) mapped at [ffff8800a6abf000-ffff8800aaabefff]
[    0.383400] RAPL PMU detected, API unit is 2^-32 Joules, 3 fixed counters 163840 ms ovfl timer
[    0.383402] hw unit of domain pp0-core 2^-16 Joules
[    0.383403] hw unit of domain package 2^-16 Joules
[    0.383405] hw unit of domain pp1-gpu 2^-16 Joules
[    0.383596] Scanning for low memory corruption every 60 seconds
[    0.384000] futex hash table entries: 2048 (order: 5, 131072 bytes)
[    0.384506] Initialise system trusted keyring
[    0.384723] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.386674] zbud: loaded
[    0.386945] VFS: Disk quotas dquot_6.6.0
[    0.386991] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.387191] Key type big_key registered
[    0.387513] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[    0.387558] io scheduler noop registered
[    0.387563] io scheduler deadline registered
[    0.387604] io scheduler cfq registered (default)
[    0.388143] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    0.388151] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    0.388168] vesafb: mode is 1366x768x32, linelength=5504, pages=0
[    0.388169] vesafb: scrolling: redraw
[    0.388172] vesafb: Truecolor: size=8:8:8:8, shift=24:16:8:0
[    0.388181] pmd_set_huge: Cannot satisfy [mem 0xb0000000-0xb0200000] with a huge-page mapping due to MTRR override.
[    0.388195] vesafb: framebuffer at 0xb0000000, mapped to 0xffffc90001000000, using 4160k, total 4160k
[    0.489838] Console: switching to colour frame buffer device 170x48
[    0.591049] fb0: VESA VGA frame buffer device
[    0.591073] intel_idle: MWAIT substates: 0x21120
[    0.591075] intel_idle: v0.4 model 0x3A
[    0.591077] intel_idle: lapic_timer_reliable_states 0xffffffff
[    0.591352] GHES: HEST is not enabled!
[    0.591443] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[    0.591997] Linux agpgart interface v0.103
[    0.592439] rtc_cmos 00:01: RTC can wake from S4
[    0.592584] rtc_cmos 00:01: rtc core: registered rtc_cmos as rtc0
[    0.592629] rtc_cmos 00:01: alarms up to one month, 242 bytes nvram, hpet irqs
[    0.592646] Intel P-state driver initializing.
[    0.592833] ledtrig-cpu: registered to indicate activity on CPUs
[    0.593729] NET: Registered protocol family 10
[    0.594272] NET: Registered protocol family 17
[    0.594756] microcode: CPU0 sig=0x306a9, pf=0x10, revision=0x12
[    0.594778] microcode: CPU1 sig=0x306a9, pf=0x10, revision=0x12
[    0.594837] microcode: CPU2 sig=0x306a9, pf=0x10, revision=0x12
[    0.594864] microcode: CPU3 sig=0x306a9, pf=0x10, revision=0x12
[    0.595046] microcode: Microcode Update Driver: v2.01 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
[    0.595886] registered taskstats version 1
[    0.595933] Loading compiled-in X.509 certificates
[    0.596011] zswap: loaded using pool lzo/zbud
[    0.596853]   Magic number: 8:641:326
[    0.597025] rtc_cmos 00:01: setting system clock to 2016-03-03 11:20:04 UTC (1457004004)
[    0.597229] PM: Hibernation image not present or could not be loaded.
[    0.599943] Freeing unused kernel memory: 1188K (ffffffff818ee000 - ffffffff81a17000)
[    0.599947] Write protecting the kernel read-only data: 8192k
[    0.600536] Freeing unused kernel memory: 412K (ffff880001599000 - ffff880001600000)
[    0.602118] Freeing unused kernel memory: 228K (ffff8800017c7000 - ffff880001800000)
[    0.617456] random: systemd-tmpfile urandom read with 2 bits of entropy available
[    0.665084] i8042: PNP: PS/2 Controller [PNP0303:P2K2,PNP0f13:PS2M] at 0x60,0x64 irq 1,12
[    0.667107] i8042: Detected active multiplexing controller, rev 1.1
[    0.668814] serio: i8042 KBD port at 0x60,0x64 irq 1
[    0.668914] serio: i8042 AUX0 port at 0x60,0x64 irq 12
[    0.668989] serio: i8042 AUX1 port at 0x60,0x64 irq 12
[    0.669070] serio: i8042 AUX2 port at 0x60,0x64 irq 12
[    0.669143] serio: i8042 AUX3 port at 0x60,0x64 irq 12
[    0.676151] ACPI: bus type USB registered
[    0.676187] usbcore: registered new interface driver usbfs
[    0.676204] usbcore: registered new interface driver hub
[    0.676342] usbcore: registered new device driver usb
[    0.677526] SCSI subsystem initialized
[    0.677843] xhci_hcd 0000:00:14.0: xHCI Host Controller
[    0.677854] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 1
[    0.678971] xhci_hcd 0000:00:14.0: hcc params 0x20007181 hci version 0x100 quirks 0x0000b930
[    0.678981] xhci_hcd 0000:00:14.0: cache line size of 64 is not supported
[    0.679587] hub 1-0:1.0: USB hub found
[    0.679604] hub 1-0:1.0: 4 ports detected
[    0.680082] libata version 3.00 loaded.
[    0.680163] xhci_hcd 0000:00:14.0: xHCI Host Controller
[    0.680169] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 2
[    0.680484] hub 2-0:1.0: USB hub found
[    0.680500] hub 2-0:1.0: 4 ports detected
[    0.681414] ahci 0000:00:1f.2: version 3.0
[    0.681632] ahci 0000:00:1f.2: AHCI 0001.0300 32 slots 6 ports 6 Gbps 0x3 impl RAID mode
[    0.681637] ahci 0000:00:1f.2: flags: 64bit ncq pm led clo pio slum part ems apst 
[    0.681676] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.681869] ehci-pci: EHCI PCI platform driver
[    0.688766] scsi host0: ahci
[    0.688879] scsi host1: ahci
[    0.688975] scsi host2: ahci
[    0.689068] scsi host3: ahci
[    0.689160] scsi host4: ahci
[    0.689369] scsi host5: ahci
[    0.689455] ata1: SATA max UDMA/133 abar m2048@0xc0617000 port 0xc0617100 irq 27
[    0.689460] ata2: SATA max UDMA/133 abar m2048@0xc0617000 port 0xc0617180 irq 27
[    0.689463] ata3: DUMMY
[    0.689465] ata4: DUMMY
[    0.689466] ata5: DUMMY
[    0.689468] ata6: DUMMY
[    0.689668] ehci-pci 0000:00:1a.0: EHCI Host Controller
[    0.689677] ehci-pci 0000:00:1a.0: new USB bus registered, assigned bus number 3
[    0.689693] ehci-pci 0000:00:1a.0: debug port 2
[    0.693589] ehci-pci 0000:00:1a.0: cache line size of 64 is not supported
[    0.693615] ehci-pci 0000:00:1a.0: irq 16, io mem 0xc0619000
[    0.701304] ehci-pci 0000:00:1a.0: USB 2.0 started, EHCI 1.00
[    0.701581] hub 3-0:1.0: USB hub found
[    0.701594] hub 3-0:1.0: 2 ports detected
[    0.701927] ehci-pci 0000:00:1d.0: EHCI Host Controller
[    0.701933] ehci-pci 0000:00:1d.0: new USB bus registered, assigned bus number 4
[    0.701946] ehci-pci 0000:00:1d.0: debug port 2
[    0.705852] ehci-pci 0000:00:1d.0: cache line size of 64 is not supported
[    0.705871] ehci-pci 0000:00:1d.0: irq 23, io mem 0xc0618000
[    0.712555] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
[    0.714645] ehci-pci 0000:00:1d.0: USB 2.0 started, EHCI 1.00
[    0.715011] hub 4-0:1.0: USB hub found
[    0.715034] hub 4-0:1.0: 2 ports detected
[    1.007984] usb 3-1: new high-speed USB device number 2 using ehci-pci
[    1.007998] ata2: SATA link down (SStatus 0 SControl 300)
[    1.008046] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[    1.009803] ata1.00: supports DRM functions and may not be fully accessible
[    1.010076] ata1.00: READ LOG DMA EXT failed, trying unqueued
[    1.010178] ata1.00: failed to get NCQ Send/Recv Log Emask 0x1
[    1.010184] ata1.00: ATA-9: Samsung SSD 850 EVO 250GB, EMT01B6Q, max UDMA/133
[    1.010187] ata1.00: 488397168 sectors, multi 1: LBA48 NCQ (depth 31/32), AA
[    1.010725] ata1.00: supports DRM functions and may not be fully accessible
[    1.010952] ata1.00: failed to get NCQ Send/Recv Log Emask 0x1
[    1.011090] ata1.00: configured for UDMA/133
[    1.015155] scsi 0:0:0:0: Direct-Access     ATA      Samsung SSD 850  1B6Q PQ: 0 ANSI: 5
[    1.021228] usb 4-1: new high-speed USB device number 2 using ehci-pci
[    1.021285] sd 0:0:0:0: [sda] 488397168 512-byte logical blocks: (250 GB/232 GiB)
[    1.021526] sd 0:0:0:0: [sda] Write Protect is off
[    1.021534] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    1.021589] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    1.022438]  sda: sda1 sda2 sda3 sda4
[    1.023237] sd 0:0:0:0: [sda] Attached SCSI disk
[    1.083881] EXT4-fs (sda3): mounted filesystem with ordered data mode. Opts: (null)
[    1.128834] ip_tables: (C) 2000-2006 Netfilter Core Team
[    1.131609] systemd[1]: systemd 229 running in system mode. (+PAM -AUDIT -SELINUX -IMA -APPARMOR +SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[    1.131781] systemd[1]: Detected architecture x86-64.
[    1.131905] hub 3-1:1.0: USB hub found
[    1.131927] systemd[1]: Set hostname to <turtlein>.
[    1.131996] hub 3-1:1.0: 6 ports detected
[    1.145645] hub 4-1:1.0: USB hub found
[    1.145826] hub 4-1:1.0: 8 ports detected
[    1.189724] systemd[1]: Created slice User and Session Slice.
[    1.192310] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    1.192914] systemd[1]: Listening on Process Core Dump Socket.
[    1.192953] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    1.193066] systemd[1]: Created slice System Slice.
[    1.193085] systemd[1]: Reached target Remote File Systems.
[    1.193097] systemd[1]: Reached target Encrypted Volumes.
[    1.193125] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[    1.193139] systemd[1]: Reached target Slices.
[    1.193151] systemd[1]: Reached target Swap.
[    1.193183] systemd[1]: Listening on Journal Socket (/dev/log).
[    1.193210] systemd[1]: Listening on udev Kernel Socket.
[    1.193236] systemd[1]: Listening on LVM2 metadata daemon socket.
[    1.193266] systemd[1]: Listening on Journal Socket.
[    1.204716] systemd[1]: Mounting POSIX Message Queue File System...
[    1.205404] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[    1.205888] systemd[1]: Mounting Configuration File System...
[    1.206520] systemd[1]: Starting Journal Service...
[    1.207522] systemd[1]: Starting Apply Kernel Variables...
[    1.208467] systemd[1]: Mounting Temporary Directory...
[    1.209130] systemd[1]: Mounting Debug File System...
[    1.209740] systemd[1]: Starting Setup Virtual Console...
[    1.209825] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[    1.210444] systemd[1]: Mounting Huge Pages File System...
[    1.210648] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    1.212589] systemd[1]: Created slice system-getty.slice.
[    1.212618] systemd[1]: Reached target Paths.
[    1.213035] systemd[1]: Starting Remount Root and Kernel File Systems...
[    1.213191] systemd[1]: Listening on udev Control Socket.
[    1.214958] systemd[1]: Mounted Huge Pages File System.
[    1.215000] systemd[1]: Mounted Configuration File System.
[    1.215026] systemd[1]: Mounted Temporary Directory.
[    1.215595] systemd[1]: Mounted POSIX Message Queue File System.
[    1.216164] systemd[1]: Mounted Debug File System.
[    1.216304] EXT4-fs (sda3): re-mounted. Opts: data=ordered
[    1.216568] systemd[1]: Started Create list of required static device nodes for the current kernel.
[    1.218464] systemd[1]: Started Apply Kernel Variables.
[    1.218677] systemd[1]: Started Remount Root and Kernel File Systems.
[    1.246411] systemd[1]: Started Setup Virtual Console.
[    1.264658] systemd[1]: Starting Load/Save Random Seed...
[    1.265612] systemd[1]: Starting udev Coldplug all Devices...
[    1.266301] systemd[1]: Starting Create Static Device Nodes in /dev...
[    1.267722] systemd[1]: Started Load/Save Random Seed.
[    1.269520] systemd[1]: Started Journal Service.
[    1.298037] systemd-journald[163]: Received request to flush runtime journal from PID 1
[    1.337114] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input5
[    1.337121] ACPI: Power Button [PWRB]
[    1.337196] input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0D:00/input/input6
[    1.337252] ACPI: Lid Switch [LID]
[    1.337318] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input7
[    1.337321] ACPI: Power Button [PWRF]
[    1.343160] ACPI: AC Adapter [ACAD] (on-line)
[    1.343806] FUJITSU Extended Socket Network Device Driver - version 1.0 - Copyright (c) 2015 FUJITSU LIMITED
[    1.381189] tsc: Refined TSC clocksource calibration: 1692.851 MHz
[    1.381194] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1866c5c5e2d, max_idle_ns: 440795209329 ns
[    1.382029] ACPI Warning: SystemIO range 0x0000000000000428-0x000000000000042F conflicts with OpRegion 0x0000000000000400-0x000000000000047F (\PMIO) (20150930/utaddress-254)
[    1.382035] ACPI: If an ACPI driver is available for this device, you should use it instead of the native driver
[    1.382039] ACPI Warning: SystemIO range 0x0000000000000540-0x000000000000054F conflicts with OpRegion 0x0000000000000500-0x0000000000000563 (\GPIO) (20150930/utaddress-254)
[    1.382042] ACPI: If an ACPI driver is available for this device, you should use it instead of the native driver
[    1.382043] ACPI Warning: SystemIO range 0x0000000000000530-0x000000000000053F conflicts with OpRegion 0x0000000000000500-0x0000000000000563 (\GPIO) (20150930/utaddress-254)
[    1.382046] ACPI: If an ACPI driver is available for this device, you should use it instead of the native driver
[    1.382047] ACPI Warning: SystemIO range 0x0000000000000500-0x000000000000052F conflicts with OpRegion 0x0000000000000500-0x0000000000000563 (\GPIO) (20150930/utaddress-254)
[    1.382049] ACPI: If an ACPI driver is available for this device, you should use it instead of the native driver
[    1.382050] lpc_ich: Resource conflict(s) found affecting gpio_ich
[    1.394994] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[    1.397992] usb 3-1.2: new full-speed USB device number 3 using ehci-pci
[    1.401378] ACPI Warning: SystemIO range 0x0000000000003040-0x000000000000305F conflicts with OpRegion 0x0000000000003040-0x000000000000304F (\_SB_.PCI0.SBUS.SMBI) (20150930/utaddress-254)
[    1.401387] ACPI: If an ACPI driver is available for this device, you should use it instead of the native driver
[    1.442522] [drm] Initialized drm 1.1.0 20060810
[    1.460196] AVX version of gcm_enc/dec engaged.
[    1.460197] AES CTR mode by8 optimization enabled
[    1.484067] [drm] Memory usable by graphics device = 2048M
[    1.484070] checking generic (b0000000 410000) vs hw (b0000000 10000000)
[    1.484071] fb: switching to inteldrmfb from VESA VGA
[    1.491077] Intel(R) Wireless WiFi driver for Linux
[    1.491077] Copyright(c) 2003- 2015 Intel Corporation
[    1.491259] iwlwifi 0000:02:00.0: can't disable ASPM; OS doesn't have ASPM control
[    1.496206] iwlwifi 0000:02:00.0: loaded firmware version 18.168.6.1 op_mode iwldvm
[    1.503490] atl1c 0000:03:00.0: version 1.0.1.1-NAPI
[    1.521684] iwlwifi 0000:02:00.0: CONFIG_IWLWIFI_DEBUG disabled
[    1.521686] iwlwifi 0000:02:00.0: CONFIG_IWLWIFI_DEBUGFS disabled
[    1.521688] iwlwifi 0000:02:00.0: CONFIG_IWLWIFI_DEVICE_TRACING enabled
[    1.521690] iwlwifi 0000:02:00.0: Detected Intel(R) Centrino(R) Wireless-N 2230 BGN, REV=0xC8
[    1.521798] iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
[    1.557840] usb 3-1.4: new high-speed USB device number 4 using ehci-pci
[    1.571264] Console: switching to colour dummy device 80x25
[    1.573142] [drm] Replacing VGA console driver
[    1.580046] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    1.580049] [drm] Driver supports precise vblank timestamp query.
[    1.580156] vgaarb: device changed decodes: PCI:0000:00:02.0,olddecodes=io+mem,decodes=io+mem:owns=io+mem
[    1.589470] ieee80211 phy0: Selected rate control algorithm 'iwl-agn-rs'
[    1.594451] intel_rapl: Found RAPL domain package
[    1.594456] intel_rapl: Found RAPL domain core
[    1.594459] intel_rapl: Found RAPL domain uncore
[    1.594467] intel_rapl: RAPL package 0 domain package locked by BIOS
[    1.601366] ACPI: Video Device [GFX0] (multi-head: yes  rom: no  post: no)
[    1.601609] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:01/input/input10
[    1.601773] [drm] Initialized i915 1.6.0 20151010 for 0000:00:02.0 on minor 0
[    1.629589] iTCO_vendor_support: vendor-support=0
[    1.629904] snd_hda_codec_conexant hdaudioC0D0: CX20590: BIOS auto-probing.
[    1.630382] snd_hda_codec_conexant hdaudioC0D0: autoconfig for CX20590: line_outs=1 (0x1f/0x0/0x0/0x0/0x0) type:speaker
[    1.630386] snd_hda_codec_conexant hdaudioC0D0:    speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
[    1.630390] snd_hda_codec_conexant hdaudioC0D0:    hp_outs=1 (0x19/0x0/0x0/0x0/0x0)
[    1.630392] snd_hda_codec_conexant hdaudioC0D0:    mono: mono_out=0x0
[    1.630394] snd_hda_codec_conexant hdaudioC0D0:    inputs:
[    1.630399] snd_hda_codec_conexant hdaudioC0D0:      Internal Mic=0x23
[    1.630402] snd_hda_codec_conexant hdaudioC0D0:      Mic=0x1a
[    1.631298] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.11
[    1.631338] iTCO_wdt: Found a Panther Point TCO device (Version=2, TCOBASE=0x0460)
[    1.631459] iTCO_wdt: initialized. heartbeat=30 sec (nowayout=0)
[    1.631568] snd_hda_codec_conexant hdaudioC0D0: Enable sync_write for stable communication
[    1.639833] input: HDA Digital PCBeep as /devices/pci0000:00/0000:00:1b.0/sound/card0/input11
[    1.640164] input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1b.0/sound/card0/input12
[    1.640258] input: HDA Intel PCH Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card0/input13
[    1.640350] input: HDA Intel PCH HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:1b.0/sound/card0/input14
[    1.661183] [drm] GMBUS [i915 gmbus vga] timed out, falling back to bit banging on pin 2
[    1.731449] [Firmware Bug]: battery: (dis)charge rate invalid.
[    1.731461] ACPI: Battery Slot [BAT1] (battery present)
[    1.731876] wmi: Mapper loaded
[    1.731997] toshiba_bluetooth: Toshiba ACPI Bluetooth device driver
[    1.736499] toshiba_acpi: Toshiba Laptop ACPI Extras version 0.23
[    1.738740] input: Toshiba input device as /devices/virtual/input/input15
[    1.746922] toshiba_acpi: Supported laptop features: hotkeys keyboard-backlight touchpad usb-sleep-charge special-function-keys
[    1.781602] fbcon: inteldrmfb (fb0) is primary device
[    1.781840] Console: switching to colour frame buffer device 170x48
[    1.781857] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device
[    1.793137] psmouse serio2: synaptics: queried max coordinates: x [..5680], y [..4728]
[    1.821164] psmouse serio2: synaptics: queried min coordinates: x [1262..], y [1124..]
[    1.875003] psmouse serio2: synaptics: Touchpad model: 1, fw: 8.1, id: 0x1e2b1, caps: 0xd00123/0x840300/0x122c00/0x0, board id: 1744, fw id: 1122530
[    1.908679] input: SynPS/2 Synaptics TouchPad as /devices/platform/i8042/serio2/input/input9
[    1.910332] mousedev: PS/2 mouse device common for all mice
[    2.188551] media: Linux media interface: v0.10
[    2.191706] hidraw: raw HID events driver (C) Jiri Kosina
[    2.195800] Linux video capture interface: v2.00
[    2.198868] usbcore: registered new interface driver usbhid
[    2.198871] usbhid: USB HID core driver
[    2.200747] input: Logitech USB Receiver as /devices/pci0000:00/0000:00:1a.0/usb3/3-1/3-1.2/3-1.2:1.0/0003:046D:C52E.0001/input/input18
[    2.202620] uvcvideo: Found UVC 1.00 device TOSHIBA Web Camera - MP (04f2:b2e6)
[    2.251649] hid-generic 0003:046D:C52E.0001: input,hidraw0: USB HID v1.11 Keyboard [Logitech USB Receiver] on usb-0000:00:1a.0-1.2/input0
[    2.252085] input: Logitech USB Receiver as /devices/pci0000:00/0000:00:1a.0/usb3/3-1/3-1.2/3-1.2:1.1/0003:046D:C52E.0002/input/input19
[    2.268388] [drm:intel_set_pch_fifo_underrun_reporting [i915]] *ERROR* uncleared pch fifo underrun on pch transcoder A
[    2.268420] [drm:intel_pch_fifo_underrun_irq_handler [i915]] *ERROR* PCH transcoder A FIFO underrun
[    2.285135] input: TOSHIBA Web Camera - MP as /devices/pci0000:00/0000:00:1a.0/usb3/3-1/3-1.4/3-1.4:1.0/input/input20
[    2.305416] usbcore: registered new interface driver uvcvideo
[    2.305424] USB Video Class driver (1.1.1)
[    2.305575] hid-generic 0003:046D:C52E.0002: input,hiddev0,hidraw1: USB HID v1.11 Mouse [Logitech USB Receiver] on usb-0000:00:1a.0-1.2/input1
[    2.381376] clocksource: Switched to clocksource tsc
[    5.018693] random: nonblocking pool is initialized
[    9.698252] NET: Registered protocol family 3
[    9.719844] iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
[    9.727384] iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
[    9.727484] iwlwifi 0000:02:00.0: Radio type=0x2-0x0-0x0
[    9.978462] iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
[    9.986007] iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
[    9.986114] iwlwifi 0000:02:00.0: Radio type=0x2-0x0-0x0
[   10.045692] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   15.050409] atl1c 0000:03:00.0: atl1c: eth0 NIC Link is Up<100 Mbps Full Duplex>
[   17.111864] iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
[   17.119402] iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
[   17.119510] iwlwifi 0000:02:00.0: Radio type=0x2-0x0-0x0
[   17.362569] iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
[   17.370138] iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
[   17.370244] iwlwifi 0000:02:00.0: Radio type=0x2-0x0-0x0
[   17.424577] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   17.477831] atl1c 0000:03:00.0: atl1c: eth0 NIC Link is Up<100 Mbps Full Duplex>
[   17.517258] atl1c 0000:03:00.0: atl1c: eth0 NIC Link is Up<100 Mbps Full Duplex>
[  182.295913] watchdog watchdog0: watchdog did not stop!
[  182.298074] systemd-shutdown[1]: Sending SIGTERM to remaining processes...
[  182.312315] systemd-journald[163]: Received SIGTERM from PID 1 (systemd-shutdow).
[  182.331714] systemd-shutdown[1]: Sending SIGKILL to remaining processes...
[  182.335830] systemd-shutdown[1]: Hardware watchdog 'iTCO_wdt', version 0
[  182.336108] systemd-shutdown[1]: Unmounting file systems.
[  182.336375] systemd-shutdown[1]: Remounting '/' read-only with options 'data=ordered'.
[  182.360439] EXT4-fs (sda3): re-mounted. Opts: data=ordered
[  182.369908] systemd-shutdown[1]: Remounting '/' read-only with options 'data=ordered'.
[  182.369951] EXT4-fs (sda3): re-mounted. Opts: data=ordered
[  182.369958] systemd-shutdown[1]: All filesystems unmounted.
[  182.369962] systemd-shutdown[1]: Deactivating swaps.
[  182.370024] systemd-shutdown[1]: All swaps deactivated.
[  182.370028] systemd-shutdown[1]: Detaching loop devices.
[  182.370628] systemd-shutdown[1]: All loop devices detached.
[  182.370633] systemd-shutdown[1]: Detaching DM devices.
[  182.370703] systemd-shutdown[1]: All DM devices detached.
[  182.381056] EXT4-fs (sda3): re-mounted. Opts: data=ordered

Unfortunately, I am not able to understand this by myself, so any help to understand what is causing my system to hang on reboot/shutdown would appreciated.
I am using

Thank you very much,
MG

TL;DR: arch hangs a long time to shutdown, I have booted with debug options and saved the logs, but I can't understand them. Please help me smile

Last edited by macogala (2016-03-03 13:14:34)

Offline

#2 2016-03-03 18:58:16

mrunion
Member
From: Jonesborough, TN
Registered: 2007-01-26
Posts: 1,938
Website

Re: Shutdown/Reboot takes long time

Isn't this the same issue being discussed here? https://bbs.archlinux.org/viewtopic.php?id=209662


Matt

"It is very difficult to educate the educated."

Offline

#3 2016-03-04 08:41:39

macogala
Member
Registered: 2016-03-03
Posts: 4

Re: Shutdown/Reboot takes long time

Mmmm, I would say no..

Offline

Board footer

Powered by FluxBB