On Thu 01-Jun-2017 at 23:58:43 +0200, Ben Hutchings <b...@decadent.org.uk> wrote: > On Thu, 2017-06-01 at 23:36 +0200, garj...@garjola.net wrote: >> On Thu 01-Jun-2017 at 00:15:29 +0200, Ben Hutchings <b...@decadent.org >> .uk> wrote: >> > Control: tag -1 moreinfo >> > >> > On Wed, 31 May 2017 21:34:59 +0200 Garjola Dindi <garj...@garjola.net> >> > wrote: >> > [...] >> > > For several weeks now I have been having issues after resume (both >> > >> > from RAM or from disk): my /home seems not to be accessible (at least >> > for writing). This does not happen every time, but more something like >> > once every 10 or 20 resume cycles. >> > [...] >> > >> > Please send the messages that appear in the kernel log when you resume. >> > (Run 'dmesg' as root to show the kernel log.) >> > >> > Ben. >> >> Hi, >> >> The problem appeared again. I ran dmesg and piped the output to a file >> (in one of the non encrypted partitions) just after the problematic >> resume, but after reboot (to be able to send this message), the file was >> gone. >> >> Below is the output of dmesg after a fresh reboot and one successful >> suspend/resume cycle. I dont't know if is is useful, since the problem >> has not happened since the reboot. > [...] > > I really need to see what happens in the failing case. > > Ben.
Hi, Here goes the output of dmesg after a failing resume. I have used a usb stick to save the output of dmesg so that I could send it to you. This is the sdc device which appears at the end of the log. Thanks. [ 4.490217] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.511012] 0000:00:16.3: ttyS0 at I/O 0x3080 (irq = 19, base_baud = 115200) is a 16550A [ 4.511185] Linux agpgart interface v0.103 [ 4.557309] tpm_tis 00:0b: 1.2 TPM (device-id 0x1B, rev-id 16) [ 4.941311] tpm tpm0: A TPM error (7) occurred attempting to read a pcr value [ 4.941368] tpm tpm0: TPM is disabled/deactivated (0x7) [ 4.941507] AMD IOMMUv2 driver by Joerg Roedel <jroe...@suse.de> [ 4.941508] AMD IOMMUv2 functionality not available on this system [ 4.941793] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at 0x60,0x64 irq 1,12 [ 4.943323] i8042: Detected active multiplexing controller, rev 1.1 [ 4.943926] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.943929] serio: i8042 AUX0 port at 0x60,0x64 irq 12 [ 4.943953] serio: i8042 AUX1 port at 0x60,0x64 irq 12 [ 4.943973] serio: i8042 AUX2 port at 0x60,0x64 irq 12 [ 4.943991] serio: i8042 AUX3 port at 0x60,0x64 irq 12 [ 4.944404] mousedev: PS/2 mouse device common for all mice [ 4.944448] rtc_cmos 00:03: RTC can wake from S4 [ 4.944999] rtc_cmos 00:03: rtc core: registered rtc_cmos as rtc0 [ 4.945080] rtc_cmos 00:03: alarms up to one month, y3k, 242 bytes nvram, hpet irqs [ 4.945086] intel_pstate: Intel P-state driver initializing [ 4.945356] intel_pstate: HWP enabled [ 4.945520] ledtrig-cpu: registered to indicate activity on CPUs [ 4.945776] NET: Registered protocol family 10 [ 4.945987] mip6: Mobile IPv6 [ 4.945988] NET: Registered protocol family 17 [ 4.945990] mpls_gso: MPLS GSO support [ 4.946163] microcode: sig=0x406e3, pf=0x80, revision=0x94 [ 4.946222] microcode: Microcode Update Driver: v2.01 <tig...@aivazian.fsnet.co.uk>, Peter Oruba [ 4.946333] registered taskstats version 1 [ 4.946346] zswap: loaded using pool lzo/zbud [ 4.970449] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 [ 4.993615] tpm tpm0: A TPM error (7) occurred attempting to read a pcr value [ 4.993708] ima: No TPM chip found, activating TPM-bypass! [ 4.996949] rtc_cmos 00:03: setting system clock to 2017-06-01 21:20:08 UTC (1496352008) [ 4.997131] PM: Checking hibernation image partition /dev/mapper/pc--117--162--vg-swap_1 [ 4.997139] PM: Hibernation image not present or could not be loaded. [ 4.999349] Freeing unused kernel memory: 1392K (ffffffffb711e000 - ffffffffb727a000) [ 4.999350] Write protecting the kernel read-only data: 12288k [ 4.999759] Freeing unused kernel memory: 1996K (ffff88494720d000 - ffff884947400000) [ 5.001549] Freeing unused kernel memory: 1244K (ffff8849476c9000 - ffff884947800000) [ 5.005189] x86/mm: Checked W+X mappings: passed, no W+X pages found. [ 5.057319] random: systemd-udevd: uninitialized urandom read (16 bytes read) [ 5.057384] random: systemd-udevd: uninitialized urandom read (16 bytes read) [ 5.057391] random: systemd-udevd: uninitialized urandom read (16 bytes read) [ 5.057400] random: systemd-udevd: uninitialized urandom read (16 bytes read) [ 5.058066] random: udevadm: uninitialized urandom read (16 bytes read) [ 5.058090] random: udevadm: uninitialized urandom read (16 bytes read) [ 5.058872] random: udevadm: uninitialized urandom read (16 bytes read) [ 5.058906] random: udevadm: uninitialized urandom read (16 bytes read) [ 5.058936] random: udevadm: uninitialized urandom read (16 bytes read) [ 5.058965] random: udevadm: uninitialized urandom read (16 bytes read) [ 5.092094] hidraw: raw HID events driver (C) Jiri Kosina [ 5.096782] ACPI: bus type USB registered [ 5.096806] usbcore: registered new interface driver usbfs [ 5.096814] usbcore: registered new interface driver hub [ 5.096851] usbcore: registered new device driver usb [ 5.097445] SCSI subsystem initialized [ 5.098214] xhci_hcd 0000:00:14.0: xHCI Host Controller [ 5.098219] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 1 [ 5.098518] rtsx_pci 0000:01:00.0: rtsx_pci_acquire_irq: pcr->msi_en = 1, pci->irq = 274 [ 5.099098] libata version 3.00 loaded. [ 5.099343] xhci_hcd 0000:00:14.0: hcc params 0x200077c1 hci version 0x100 quirks 0x00109810 [ 5.099355] xhci_hcd 0000:00:14.0: cache line size of 64 is not supported [ 5.099481] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 [ 5.099482] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 5.099484] usb usb1: Product: xHCI Host Controller [ 5.099485] usb usb1: Manufacturer: Linux 4.9.0-3-amd64 xhci-hcd [ 5.099486] usb usb1: SerialNumber: 0000:00:14.0 [ 5.099633] hub 1-0:1.0: USB hub found [ 5.099649] hub 1-0:1.0: 12 ports detected [ 5.100374] pps_core: LinuxPPS API ver. 1 registered [ 5.100375] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giome...@linux.it> [ 5.100591] PTP clock support registered [ 5.102149] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k [ 5.102150] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 5.107704] AVX2 version of gcm_enc/dec engaged. [ 5.107705] AES CTR mode by8 optimization enabled [ 5.112314] xhci_hcd 0000:00:14.0: xHCI Host Controller [ 5.112318] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 2 [ 5.112356] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003 [ 5.112357] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 5.112359] usb usb2: Product: xHCI Host Controller [ 5.112360] usb usb2: Manufacturer: Linux 4.9.0-3-amd64 xhci-hcd [ 5.112361] usb usb2: SerialNumber: 0000:00:14.0 [ 5.112493] hub 2-0:1.0: USB hub found [ 5.112506] hub 2-0:1.0: 6 ports detected [ 5.116471] usb: port power management may be unreliable [ 5.117824] thermal LNXTHERM:00: registered as thermal_zone0 [ 5.117825] ACPI: Thermal Zone [CPUZ] (32 C) [ 5.117948] ahci 0000:00:17.0: version 3.0 [ 5.118163] ahci 0000:00:17.0: SSS flag set, parallel bus scan disabled [ 5.118188] ahci 0000:00:17.0: AHCI 0001.0301 32 slots 2 ports 6 Gbps 0x5 impl SATA mode [ 5.118191] ahci 0000:00:17.0: flags: 64bit ncq stag pm led clo only pio slum part deso sadm sds apst [ 5.118856] scsi host0: ahci [ 5.118984] scsi host1: ahci [ 5.119119] scsi host2: ahci [ 5.119178] ata1: SATA max UDMA/133 abar m2048@0xe124e000 port 0xe124e100 irq 276 [ 5.119179] ata2: DUMMY [ 5.119181] ata3: SATA max UDMA/133 abar m2048@0xe124e000 port 0xe124e200 irq 276 [ 5.119379] e1000e 0000:00:1f.6: Interrupt Throttling Rate (ints/sec) set to dynamic conservative mode [ 5.148306] thermal LNXTHERM:01: registered as thermal_zone1 [ 5.148307] ACPI: Thermal Zone [GFXZ] (0 C) [ 5.171576] thermal LNXTHERM:02: registered as thermal_zone2 [ 5.171578] ACPI: Thermal Zone [EXTZ] (27 C) [ 5.193837] thermal LNXTHERM:03: registered as thermal_zone3 [ 5.193838] ACPI: Thermal Zone [LOCZ] (24 C) [ 5.203847] thermal LNXTHERM:04: registered as thermal_zone4 [ 5.203848] ACPI: Thermal Zone [BATZ] (24 C) [ 5.204309] thermal LNXTHERM:05: registered as thermal_zone5 [ 5.204311] ACPI: Thermal Zone [PCHZ] (127 C) [ 5.331926] e1000e 0000:00:1f.6 0000:00:1f.6 (uninitialized): registered PHC clock [ 5.417065] e1000e 0000:00:1f.6 eth0: (PCI Express:2.5GT/s:Width x1) 30:e1:71:89:f1:a4 [ 5.417073] e1000e 0000:00:1f.6 eth0: Intel(R) PRO/1000 Network Connection [ 5.417208] e1000e 0000:00:1f.6 eth0: MAC: 12, PHY: 12, PBA No: FFFFFF-0FF [ 5.418121] i801_smbus 0000:00:1f.4: SMBus using PCI interrupt [ 5.438853] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [ 5.440982] ata1.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [ 5.440989] ata1.00: ACPI cmd b1/c1:00:00:00:00:e0 (DEVICE CONFIGURATION OVERLAY) filtered out [ 5.441193] ata1.00: ATA-9: HGST HTS541010A7E630, SE0OA4G0, max UDMA/133 [ 5.441198] ata1.00: 1953525168 sectors, multi 16: LBA48 NCQ (depth 31/32), AA [ 5.441337] usb 1-7: new full-speed USB device number 2 using xhci_hcd [ 5.443517] ata1.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [ 5.443525] ata1.00: ACPI cmd b1/c1:00:00:00:00:e0 (DEVICE CONFIGURATION OVERLAY) filtered out [ 5.444070] ata1.00: configured for UDMA/133 [ 5.445140] scsi 0:0:0:0: Direct-Access ATA HGST HTS541010A7 A4G0 PQ: 0 ANSI: 5 [ 5.509671] tsc: Refined TSC clocksource calibration: 2807.989 MHz [ 5.509696] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2879bbf0a17, max_idle_ns: 440795299209 ns [ 5.583992] usb 1-7: New USB device found, idVendor=8087, idProduct=0a2b [ 5.583998] usb 1-7: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 5.705617] usb 1-8: new full-speed USB device number 3 using xhci_hcd [ 5.792542] ata3: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [ 5.793830] ata3.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [ 5.793942] ata3.00: ATA-9: SanDisk SD8SN8U-512G-1006, X4120006, max UDMA/133 [ 5.793947] ata3.00: 1000215216 sectors, multi 1: LBA48 NCQ (depth 31/32), AA [ 5.796444] ata3.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [ 5.797659] ata3.00: configured for UDMA/133 [ 5.798617] scsi 2:0:0:0: Direct-Access ATA SanDisk SD8SN8U- 0006 PQ: 0 ANSI: 5 [ 5.843143] sd 0:0:0:0: [sda] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB) [ 5.843145] sd 0:0:0:0: [sda] 4096-byte physical blocks [ 5.843169] sd 0:0:0:0: [sda] Write Protect is off [ 5.843172] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 [ 5.843182] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 5.843312] sd 2:0:0:0: [sdb] 1000215216 512-byte logical blocks: (512 GB/477 GiB) [ 5.843314] sd 2:0:0:0: [sdb] 4096-byte physical blocks [ 5.843513] sd 2:0:0:0: [sdb] Write Protect is off [ 5.843516] sd 2:0:0:0: [sdb] Mode Sense: 00 3a 00 00 [ 5.843562] sd 2:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 5.844305] sdb: sdb1 [ 5.844729] sd 2:0:0:0: [sdb] Attached SCSI disk [ 5.851351] usb 1-8: New USB device found, idVendor=138a, idProduct=003f [ 5.851352] usb 1-8: New USB device strings: Mfr=0, Product=0, SerialNumber=1 [ 5.851353] usb 1-8: SerialNumber: 00305e5f579d [ 5.891595] sda: sda1 sda2 < sda5 > [ 5.892731] sd 0:0:0:0: [sda] Attached SCSI disk [ 5.904051] random: fast init done [ 6.021787] input: PS/2 Generic Mouse as /devices/platform/i8042/serio2/input/input6 [ 6.029316] usb 1-9: new high-speed USB device number 4 using xhci_hcd [ 6.203804] usb 1-9: New USB device found, idVendor=04ca, idProduct=7054 [ 6.203813] usb 1-9: New USB device strings: Mfr=3, Product=1, SerialNumber=2 [ 6.203817] usb 1-9: Product: HP HD Camera [ 6.203821] usb 1-9: Manufacturer: DETNR01BI49DUK [ 6.203824] usb 1-9: SerialNumber: 200901010001 [ 6.222325] device-mapper: uevent: version 1.0.3 [ 6.222551] device-mapper: ioctl: 4.35.0-ioctl (2016-06-23) initialised: dm-de...@redhat.com [ 6.533816] clocksource: Switched to clocksource tsc [ 6.796708] psmouse serio3: synaptics: queried max coordinates: x [..5670], y [..4758] [ 6.830944] psmouse serio3: synaptics: queried min coordinates: x [1360..], y [1198..] [ 6.895635] psmouse serio3: synaptics: Touchpad model: 1, fw: 8.2, id: 0x1e2b1, caps: 0xf00123/0x840300/0x2e800/0x0, board id: 3139, fw id: 2000742 [ 6.937470] input: SynPS/2 Synaptics TouchPad as /devices/platform/i8042/serio3/input/input7 [ 10.354959] random: crng init done [ 14.096750] NET: Registered protocol family 38 [ 15.041022] PM: Starting manual resume from disk [ 15.041028] PM: Hibernation image partition 254:2 present [ 15.041029] PM: Looking for hibernation image. [ 15.041192] PM: Image not found (code -22) [ 15.041194] PM: Hibernation image not present or could not be loaded. [ 15.379584] EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts: (null) [ 15.495936] ip_tables: (C) 2000-2006 Netfilter Core Team [ 15.509923] systemd[1]: systemd 232 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN) [ 15.510225] systemd[1]: Detected architecture x86-64. [ 15.510454] systemd[1]: Set hostname to <pc-117-162>. [ 15.611739] systemd[1]: acpid.socket: Cannot add dependency job, ignoring: Unit acpid.socket is masked. [ 15.611749] systemd[1]: acpid.path: Cannot add dependency job, ignoring: Unit acpid.path is masked. [ 15.611766] systemd[1]: NetworkManager-wait-online.service: Cannot add dependency job, ignoring: Unit NetworkManager-wait-online.service is masked. [ 15.611874] systemd[1]: NetworkManager.service: Cannot add dependency job, ignoring: Unit NetworkManager.service is masked. [ 15.612524] systemd[1]: Listening on Device-mapper event daemon FIFOs. [ 15.612597] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ 15.612625] systemd[1]: Listening on LVM2 poll daemon socket. [ 15.629723] lp: driver loaded but no devices found [ 15.631022] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro [ 15.637403] ppdev: user-space parallel port driver [ 15.654108] RPC: Registered named UNIX socket transport module. [ 15.654109] RPC: Registered udp transport module. [ 15.654110] RPC: Registered tcp transport module. [ 15.654110] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 15.664644] systemd-journald[300]: Received request to flush runtime journal from PID 1 [ 15.754945] input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input9 [ 15.754950] ACPI: Sleep Button [SLPB] [ 15.755056] input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0D:00/input/input10 [ 15.755058] ACPI: Lid Switch [LID] [ 15.755123] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input11 [ 15.755126] ACPI: Power Button [PWRF] [ 15.756181] Initializing HPQ6001 module [ 15.757247] ACPI: AC Adapter [AC] (off-line) [ 15.757319] input: HP Wireless hotkeys as /devices/virtual/input/input12 [ 15.771332] ACPI: Battery Slot [BAT0] (battery present) [ 15.781623] hp_accel: hardware type HPB64xx found [ 15.795626] wmi: Mapper loaded [ 15.801076] Bluetooth: Core ver 2.22 [ 15.801088] NET: Registered protocol family 31 [ 15.801089] Bluetooth: HCI device and connection manager initialized [ 15.801092] Bluetooth: HCI socket layer initialized [ 15.801094] Bluetooth: L2CAP socket layer initialized [ 15.801100] Bluetooth: SCO socket layer initialized [ 15.803790] intel-lpss 0000:00:15.0: enabling device (0000 -> 0002) [ 15.806300] Bluetooth: HCI UART driver ver 2.3 [ 15.806301] Bluetooth: HCI UART protocol H4 registered [ 15.806302] Bluetooth: HCI UART protocol BCSP registered [ 15.806306] Bluetooth: HCI UART protocol LL registered [ 15.806308] Bluetooth: HCI UART protocol ATH3K registered [ 15.806310] Bluetooth: HCI UART protocol Three-wire (H5) registered [ 15.806854] sd 0:0:0:0: Attached scsi generic sg0 type 0 [ 15.806894] sd 2:0:0:0: Attached scsi generic sg1 type 0 [ 15.808142] Bluetooth: HCI UART protocol Intel registered [ 15.808159] Bluetooth: HCI UART protocol Broadcom registered [ 15.808160] Bluetooth: HCI UART protocol QCA registered [ 15.808160] Bluetooth: HCI UART protocol AG6XX registered [ 15.808161] Bluetooth: HCI UART protocol Marvell registered [ 15.813059] iTCO_vendor_support: vendor-support=0 [ 15.815330] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.11 [ 15.815471] iTCO_wdt: Found a Intel PCH TCO device (Version=4, TCOBASE=0x0400) [ 15.816411] iTCO_wdt: initialized. heartbeat=30 sec (nowayout=0) [ 15.816667] idma64 idma64.0: Found Intel integrated DMA 64-bit [ 15.816786] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 15.817376] mei_me 0000:00:16.0: enabling device (0000 -> 0002) [ 15.817759] [drm] Initialized [ 15.834789] input: PC Speaker as /devices/platform/pcspkr/input/input13 [ 15.848655] RAPL PMU: API unit is 2^-32 Joules, 5 fixed counters, 655360 ms ovfl timer [ 15.848657] RAPL PMU: hw unit of domain pp0-core 2^-14 Joules [ 15.848658] RAPL PMU: hw unit of domain package 2^-14 Joules [ 15.848658] RAPL PMU: hw unit of domain dram 2^-14 Joules [ 15.848659] RAPL PMU: hw unit of domain pp1-gpu 2^-14 Joules [ 15.848660] RAPL PMU: hw unit of domain psys 2^-14 Joules [ 15.852049] Intel(R) Wireless WiFi driver for Linux [ 15.852050] Copyright(c) 2003- 2015 Intel Corporation [ 15.857036] iwlwifi 0000:02:00.0: firmware: failed to load iwlwifi-8000C-26.ucode (-2) [ 15.857108] iwlwifi 0000:02:00.0: Direct firmware load for iwlwifi-8000C-26.ucode failed with error -2 [ 15.857122] iwlwifi 0000:02:00.0: firmware: failed to load iwlwifi-8000C-25.ucode (-2) [ 15.857190] iwlwifi 0000:02:00.0: Direct firmware load for iwlwifi-8000C-25.ucode failed with error -2 [ 15.857200] iwlwifi 0000:02:00.0: firmware: failed to load iwlwifi-8000C-24.ucode (-2) [ 15.857291] iwlwifi 0000:02:00.0: Direct firmware load for iwlwifi-8000C-24.ucode failed with error -2 [ 15.857301] iwlwifi 0000:02:00.0: firmware: failed to load iwlwifi-8000C-23.ucode (-2) [ 15.857361] iwlwifi 0000:02:00.0: Direct firmware load for iwlwifi-8000C-23.ucode failed with error -2 [ 15.874873] iwlwifi 0000:02:00.0: firmware: direct-loading firmware iwlwifi-8000C-22.ucode [ 15.875487] iwlwifi 0000:02:00.0: loaded firmware version 22.361476.0 op_mode iwlmvm [ 15.877547] [drm] Memory usable by graphics device = 4096M [ 15.877549] [drm] Replacing VGA console driver [ 15.879520] Console: switching to colour dummy device 80x25 [ 15.881176] kvm: disabled by bios [ 15.891740] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 15.891742] [drm] Driver supports precise vblank timestamp query. [ 15.892698] iwlwifi 0000:02:00.0: Detected Intel(R) Dual Band Wireless AC 8260, REV=0x208 [ 15.893008] intel_rapl: Found RAPL domain package [ 15.893010] intel_rapl: Found RAPL domain core [ 15.893012] intel_rapl: Found RAPL domain uncore [ 15.893340] i915 0000:00:02.0: firmware: failed to load i915/skl_dmc_ver1_26.bin (-2) [ 15.893348] i915 0000:00:02.0: Direct firmware load for i915/skl_dmc_ver1_26.bin failed with error -2 [ 15.893350] i915 0000:00:02.0: Failed to load DMC firmware [https://01.org/linuxgraphics/intel-linux-graphics-firmwares], disabling runtime power management. [ 15.894095] vgaarb: device changed decodes: PCI:0000:00:02.0,olddecodes=io+mem,decodes=io+mem:owns=io+mem [ 15.894838] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 15.895748] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 15.903542] wl: loading out-of-tree module taints kernel. [ 15.903546] wl: module license 'MIXED/Proprietary' taints kernel. [ 15.903546] Disabling lock debugging due to kernel taint [ 15.908042] lis3lv02d: 8 bits 3DC sensor found [ 15.917669] [drm] GuC firmware load skipped [ 15.983336] ACPI Error: Needed [Buffer/String/Package], found [Integer] ffff884a894fc798 (20160831/exresop-594) [ 15.983349] ACPI Exception: AE_AML_OPERAND_TYPE, While resolving operands for [OpcodeName unavailable] (20160831/dswexec-461) [ 15.983361] ACPI Error: Method parse/execution failed [\_SB.WMIV.WVPO] (Node ffff884a908a8988), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 15.983375] ACPI Error: Method parse/execution failed [\_SB.WMIV.WMPV] (Node ffff884a908a87a8), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 15.984377] ACPI Error: Needed [Buffer/String/Package], found [Integer] ffff884a894fc9d8 (20160831/exresop-594) [ 15.984388] ACPI Exception: AE_AML_OPERAND_TYPE, While resolving operands for [OpcodeName unavailable] (20160831/dswexec-461) [ 15.984400] ACPI Error: Method parse/execution failed [\_SB.WMIV.WVPO] (Node ffff884a908a8988), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 15.984414] ACPI Error: Method parse/execution failed [\_SB.WMIV.WMPV] (Node ffff884a908a87a8), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 15.994103] ACPI Error: Needed [Buffer/String/Package], found [Integer] ffff884a894fc240 (20160831/exresop-594) [ 15.994119] ACPI Exception: AE_AML_OPERAND_TYPE, While resolving operands for [OpcodeName unavailable] (20160831/dswexec-461) [ 15.994133] ACPI Error: Method parse/execution failed [\_SB.WMIV.WVPO] (Node ffff884a908a8988), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 15.994212] ACPI Error: Method parse/execution failed [\_SB.WMIV.WMPV] (Node ffff884a908a87a8), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 15.995116] input: HP WMI hotkeys as /devices/virtual/input/input14 [ 15.999240] ACPI Error: Attempt to CreateField of length zero (20160831/dsopcode-168) [ 15.999255] ACPI Error: Method parse/execution failed [\_SB.WMIV.WVPI] (Node ffff884a908a8190), AE_AML_OPERAND_VALUE (20160831/psparse-543) [ 15.999273] ACPI Error: Method parse/execution failed [\_SB.WMIV.WMPV] (Node ffff884a908a87a8), AE_AML_OPERAND_VALUE (20160831/psparse-543) [ 16.030485] ieee80211 phy0: Selected rate control algorithm 'iwl-mvm-rs' [ 16.030791] thermal thermal_zone8: failed to read out thermal zone (-5) [ 16.107555] iwlwifi 0000:02:00.0 wlp2s0: renamed from wlan0 [ 16.175261] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro,data=ordered,commit=600 [ 16.764719] Adding 39510012k swap on /dev/mapper/pc--117--162--vg-swap_1. Priority:-1 extents:1 across:39510012k FS [ 17.193344] input: ST LIS3LV02DL Accelerometer as /devices/platform/lis3lv02d/input/input15 [ 17.202876] ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no) [ 17.206084] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input16 [ 17.207176] snd_hda_intel 0000:00:1f.3: bound 0000:00:02.0 (ops i915_audio_component_bind_ops [i915]) [ 17.207184] [drm] Initialized i915 1.6.0 20160919 for 0000:00:02.0 on minor 0 [ 17.210452] fbcon: inteldrmfb (fb0) is primary device [ 17.227292] snd_hda_codec_conexant hdaudioC0D0: CX20724: BIOS auto-probing. [ 17.227902] snd_hda_codec_conexant hdaudioC0D0: autoconfig for CX20724: line_outs=1 (0x17/0x0/0x0/0x0/0x0) type:speaker [ 17.227903] snd_hda_codec_conexant hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 17.227904] snd_hda_codec_conexant hdaudioC0D0: hp_outs=1 (0x1d/0x0/0x0/0x0/0x0) [ 17.227904] snd_hda_codec_conexant hdaudioC0D0: mono: mono_out=0x0 [ 17.227905] snd_hda_codec_conexant hdaudioC0D0: inputs: [ 17.227906] snd_hda_codec_conexant hdaudioC0D0: Internal Mic=0x1a [ 17.227907] snd_hda_codec_conexant hdaudioC0D0: Mic=0x19 [ 17.228971] snd_hda_codec_conexant hdaudioC0D0: Enable sync_write for stable communication [ 17.237140] input: HDA Digital PCBeep as /devices/pci0000:00/0000:00:1f.3/sound/card0/input17 [ 17.239372] input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1f.3/sound/card0/input18 [ 17.239429] input: HDA Intel PCH Headphone as /devices/pci0000:00/0000:00:1f.3/sound/card0/input19 [ 17.239480] input: HDA Intel PCH HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input20 [ 17.239535] input: HDA Intel PCH HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input21 [ 17.829810] [drm] RC6 on [ 18.295879] Console: switching to colour frame buffer device 240x67 [ 18.316004] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro,data=ordered,commit=600 [ 18.316859] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device [ 19.668715] media: Linux media interface: v0.10 [ 19.672336] Linux video capture interface: v2.00 [ 19.738663] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro,data=ordered,commit=600 [ 19.797607] uvcvideo: Found UVC 1.00 device HP HD Camera (04ca:7054) [ 19.799799] uvcvideo 1-9:1.0: Entity type for entity Extension 4 was not initialized! [ 19.799803] uvcvideo 1-9:1.0: Entity type for entity Processing 2 was not initialized! [ 19.799804] uvcvideo 1-9:1.0: Entity type for entity Camera 1 was not initialized! [ 19.799897] input: HP HD Camera as /devices/pci0000:00/0000:00:14.0/usb1/1-9/1-9:1.0/input/input22 [ 19.799959] usbcore: registered new interface driver uvcvideo [ 19.799960] USB Video Class driver (1.1.1) [ 20.444641] EXT4-fs (sda1): mounting ext2 file system using the ext4 subsystem [ 20.515494] EXT4-fs (sda1): mounted filesystem without journal. Opts: (null) [ 20.878339] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro,data=ordered,commit=600 [ 20.926687] EXT4-fs (sda1): re-mounted. Opts: block_validity,barrier,user_xattr,acl,stripe=4 [ 22.097876] usbcore: registered new interface driver btusb [ 22.100333] Bluetooth: hci0: Bootloader revision 0.0 build 2 week 52 2014 [ 22.107348] Bluetooth: hci0: Device revision is 5 [ 22.107352] Bluetooth: hci0: Secure boot is enabled [ 22.107355] Bluetooth: hci0: OTP lock is enabled [ 22.107357] Bluetooth: hci0: API lock is enabled [ 22.107359] Bluetooth: hci0: Debug lock is disabled [ 22.107363] Bluetooth: hci0: Minimum firmware build 1 week 10 2014 [ 22.117589] bluetooth hci0: firmware: direct-loading firmware intel/ibt-11-5.sfi [ 22.117597] Bluetooth: hci0: Found device firmware: intel/ibt-11-5.sfi [ 22.212329] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro,data=ordered,commit=600 [ 22.294670] EXT4-fs (sda1): re-mounted. Opts: block_validity,barrier,user_xattr,acl,stripe=4 [ 23.370354] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro,data=ordered,commit=600 [ 23.461345] EXT4-fs (sda1): re-mounted. Opts: block_validity,barrier,user_xattr,acl,stripe=4 [ 23.479186] Bluetooth: hci0: Waiting for firmware download to complete [ 23.479275] Bluetooth: hci0: Firmware loaded in 1349093 usecs [ 23.479306] Bluetooth: hci0: Waiting for device to boot [ 23.490308] Bluetooth: hci0: Device booted in 10754 usecs [ 23.497324] bluetooth hci0: firmware: direct-loading firmware intel/ibt-11-5.ddc [ 23.497328] Bluetooth: hci0: Found Intel DDC parameters: intel/ibt-11-5.ddc [ 23.498308] Bluetooth: hci0: Failed to send Intel_Write_DDC (-22) [ 29.657288] EXT4-fs (dm-1): mounted filesystem with ordered data mode. Opts: (null) [ 29.892565] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 29.892566] Bluetooth: BNEP filters: protocol multicast [ 29.892570] Bluetooth: BNEP socket layer initialized [ 30.110849] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro,data=ordered,commit=600 [ 30.160739] EXT4-fs (sda1): re-mounted. Opts: block_validity,barrier,user_xattr,acl,stripe=4 [ 30.171903] EXT4-fs (dm-1): re-mounted. Opts: data=ordered,commit=600 [ 30.640021] NET: Registered protocol family 4 [ 30.642362] NET: Registered protocol family 3 [ 30.654011] NET: Registered protocol family 5 [ 30.681307] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 30.681700] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 30.816665] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 30.817060] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 30.900638] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [ 31.052388] Netfilter messages via NETLINK v0.30. [ 36.665562] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 39.752981] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 39.753753] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 39.882221] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 39.882621] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 39.982244] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [ 40.216050] e1000e: eth0 NIC Link is Down [ 40.441408] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 40.600022] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 40.600734] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 40.731235] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 40.731635] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 40.823716] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [ 46.419303] wlp2s0: authenticate with 40:65:a3:15:37:9e [ 46.432185] wlp2s0: send auth to 40:65:a3:15:37:9e (try 1/3) [ 46.440268] wlp2s0: authenticated [ 46.444705] wlp2s0: associate with 40:65:a3:15:37:9e (try 1/3) [ 46.448494] wlp2s0: RX AssocResp from 40:65:a3:15:37:9e (capab=0x1411 status=0 aid=3) [ 46.456029] wlp2s0: associated [ 46.456135] IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0: link becomes ready [ 69.755860] fuse init (API version 7.26) [ 736.630419] PM: Syncing filesystems ... done. [ 736.859880] PM: Preparing system for sleep (mem) [ 736.860616] (NULL device *): firmware: direct-loading firmware intel/ibt-11-5.ddc [ 736.861067] (NULL device *): firmware: direct-loading firmware intel/ibt-11-5.sfi [ 736.861609] (NULL device *): firmware: direct-loading firmware iwlwifi-8000C-22.ucode [ 736.861645] Freezing user space processes ... (elapsed 0.001 seconds) done. [ 736.863504] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done. [ 736.864764] PM: Suspending system (mem) [ 736.864833] Suspending console(s) (use no_console_suspend to debug) [ 736.974636] wlp2s0: deauthenticating from 40:65:a3:15:37:9e by local choice (Reason: 3=DEAUTH_LEAVING) [ 736.974900] sd 2:0:0:0: [sdb] Synchronizing SCSI cache [ 736.975194] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 736.982212] sd 2:0:0:0: [sdb] Stopping disk [ 737.102167] ACPI : EC: event blocked [ 737.187537] sd 0:0:0:0: [sda] Stopping disk [ 737.738942] PM: suspend of devices complete after 764.935 msecs [ 737.765913] PM: late suspend of devices complete after 26.964 msecs [ 737.766724] ACPI : EC: interrupt blocked [ 737.769262] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI [ 737.850329] PM: noirq suspend of devices complete after 84.412 msecs [ 737.851371] ACPI: Preparing to enter system sleep state S3 [ 737.853435] ACPI : EC: EC stopped [ 737.853436] PM: Saving platform NVS memory [ 737.853488] Disabling non-boot CPUs ... [ 737.855038] smpboot: CPU 1 is now offline [ 737.855578] Broke affinity for irq 275 [ 737.855580] Broke affinity for irq 280 [ 737.856907] smpboot: CPU 2 is now offline [ 737.857274] Broke affinity for irq 1 [ 737.857291] Broke affinity for irq 9 [ 737.857300] Broke affinity for irq 12 [ 737.857340] Broke affinity for irq 275 [ 737.857341] Broke affinity for irq 276 [ 737.857343] Broke affinity for irq 279 [ 737.857344] Broke affinity for irq 280 [ 737.858358] smpboot: CPU 3 is now offline [ 737.861033] ACPI: Low-level resume complete [ 737.861150] ACPI : EC: EC started [ 737.861151] PM: Restoring platform NVS memory [ 737.861881] Suspended for 6.361 seconds [ 737.861922] Enabling non-boot CPUs ... [ 737.861974] x86: Booting SMP configuration: [ 737.861975] smpboot: Booting Node 0 Processor 1 APIC 0x2 [ 737.864619] cache: parent cpu1 should not be sleeping [ 737.864821] CPU1 is up [ 737.864856] smpboot: Booting Node 0 Processor 2 APIC 0x1 [ 737.867497] cache: parent cpu2 should not be sleeping [ 737.867681] CPU2 is up [ 737.867716] smpboot: Booting Node 0 Processor 3 APIC 0x3 [ 737.870199] cache: parent cpu3 should not be sleeping [ 737.870396] CPU3 is up [ 737.874187] ACPI: Waking up from system sleep state S3 [ 737.884577] acpi LNXPOWER:17: Turning OFF [ 737.884606] acpi LNXPOWER:16: Turning OFF [ 737.884634] acpi LNXPOWER:15: Turning OFF [ 737.884662] acpi LNXPOWER:14: Turning OFF [ 737.884690] acpi LNXPOWER:13: Turning OFF [ 737.884726] acpi LNXPOWER:12: Turning OFF [ 737.884754] acpi LNXPOWER:11: Turning OFF [ 737.884782] acpi LNXPOWER:10: Turning OFF [ 737.884809] acpi LNXPOWER:0f: Turning OFF [ 737.884837] acpi LNXPOWER:0e: Turning OFF [ 737.884864] acpi LNXPOWER:0d: Turning OFF [ 737.884892] acpi LNXPOWER:0c: Turning OFF [ 737.884919] acpi LNXPOWER:0b: Turning OFF [ 737.884947] acpi LNXPOWER:0a: Turning OFF [ 737.884974] acpi LNXPOWER:09: Turning OFF [ 737.885002] acpi LNXPOWER:08: Turning OFF [ 737.885029] acpi LNXPOWER:07: Turning OFF [ 737.885057] acpi LNXPOWER:06: Turning OFF [ 737.885085] acpi LNXPOWER:05: Turning OFF [ 737.885234] acpi LNXPOWER:04: Turning OFF [ 737.948830] acpi LNXPOWER:03: Turning OFF [ 737.949209] ACPI : EC: interrupt unblocked [ 737.990828] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI [ 738.105583] PM: noirq resume of devices complete after 156.630 msecs [ 738.105655] pciehp 0000:00:1c.0:pcie004: Slot(1): Card present [ 738.105658] pciehp 0000:00:1c.0:pcie004: Slot(1): Link Up [ 738.106086] pciehp 0000:00:1c.0:pcie004: Slot(1): Link Up event ignored; already powering on [ 738.123678] PM: early resume of devices complete after 18.014 msecs [ 738.124048] usb usb1: root hub lost power or was reset [ 738.124052] usb usb2: root hub lost power or was reset [ 738.124593] sd 0:0:0:0: [sda] Starting disk [ 738.124613] sd 2:0:0:0: [sdb] Starting disk [ 738.126648] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 738.127457] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 738.185983] [drm] GuC firmware load skipped [ 738.212803] pciehp 0000:00:1c.0:pcie004: Device 0000:01:00.0 already exists at 0000:01:00, cannot hot-add [ 738.212806] pciehp 0000:00:1c.0:pcie004: Cannot add device at 0000:01:00 [ 738.255579] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 738.255966] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 738.320749] pciehp 0000:00:1c.0:pcie004: Device 0000:01:00.0 already exists at 0000:01:00, cannot hot-add [ 738.320753] pciehp 0000:00:1c.0:pcie004: Cannot add device at 0000:01:00 [ 738.439823] ata3: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [ 738.440504] ata3.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [ 738.443243] ata3.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [ 738.444497] ata3.00: configured for UDMA/133 [ 738.473157] usb 1-8: reset full-speed USB device number 3 using xhci_hcd [ 738.733036] usb 1-9: reset high-speed USB device number 4 using xhci_hcd [ 738.993207] usb 1-7: reset full-speed USB device number 2 using xhci_hcd [ 739.404809] ACPI : EC: event unblocked [ 739.406014] ACPI : button: The lid device is not compliant to SW_LID. [ 739.406084] rtc_cmos 00:03: System wakeup disabled by ACPI [ 739.516981] tpm tpm0: A TPM error (7) occurred attempting to read a pcr value [ 739.516984] tpm tpm0: TPM is disabled/deactivated (0x7) [ 739.521038] ACPI Error: Needed [Buffer/String/Package], found [Integer] ffff884a870a1af8 (20160831/exresop-594) [ 739.521044] ACPI Exception: AE_AML_OPERAND_TYPE, While resolving operands for [OpcodeName unavailable] (20160831/dswexec-461) [ 739.521056] ACPI Error: Method parse/execution failed [\_SB.WMIV.WVPO] (Node ffff884a908a8988), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 739.521067] ACPI Error: Method parse/execution failed [\_SB.WMIV.WMPV] (Node ffff884a908a87a8), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 739.522718] ACPI Error: Needed [Buffer/String/Package], found [Integer] ffff884a8622b1b0 (20160831/exresop-594) [ 739.522722] ACPI Exception: AE_AML_OPERAND_TYPE, While resolving operands for [OpcodeName unavailable] (20160831/dswexec-461) [ 739.522732] ACPI Error: Method parse/execution failed [\_SB.WMIV.WVPO] (Node ffff884a908a8988), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 739.522742] ACPI Error: Method parse/execution failed [\_SB.WMIV.WMPV] (Node ffff884a908a87a8), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 739.523204] PM: resume of devices complete after 1399.560 msecs [ 739.523423] usb 1-7:1.0: rebind failed: -517 [ 739.523429] usb 1-7:1.1: rebind failed: -517 [ 739.523978] PM: Finishing wakeup. [ 739.523981] Restarting tasks ... done. [ 739.527374] Bluetooth: hci0: Bootloader revision 0.0 build 2 week 52 2014 [ 739.534371] Bluetooth: hci0: Device revision is 5 [ 739.534372] Bluetooth: hci0: Secure boot is enabled [ 739.534373] Bluetooth: hci0: OTP lock is enabled [ 739.534373] Bluetooth: hci0: API lock is enabled [ 739.534374] Bluetooth: hci0: Debug lock is disabled [ 739.534375] Bluetooth: hci0: Minimum firmware build 1 week 10 2014 [ 739.534377] Bluetooth: hci0: Found device firmware: intel/ibt-11-5.sfi [ 739.586818] [drm] RC6 on [ 739.756684] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [ 739.758298] ata1.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [ 739.758303] ata1.00: ACPI cmd b1/c1:00:00:00:00:e0 (DEVICE CONFIGURATION OVERLAY) filtered out [ 739.760186] ata1.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [ 739.760191] ata1.00: ACPI cmd b1/c1:00:00:00:00:e0 (DEVICE CONFIGURATION OVERLAY) filtered out [ 739.760386] ata1.00: configured for UDMA/133 [ 740.723407] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 740.724236] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 740.807391] psmouse serio3: synaptics: queried max coordinates: x [..5670], y [..4758] [ 740.837249] psmouse serio3: synaptics: queried min coordinates: x [1360..], y [1198..] [ 740.852553] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 740.852914] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 740.943348] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [ 741.185540] e1000e: eth0 NIC Link is Down [ 741.256308] Bluetooth: hci0: Waiting for firmware download to complete [ 741.256378] Bluetooth: hci0: Firmware loaded in 1689614 usecs [ 741.256413] Bluetooth: hci0: Waiting for device to boot [ 741.267406] Bluetooth: hci0: Device booted in 10762 usecs [ 741.267418] Bluetooth: hci0: Found Intel DDC parameters: intel/ibt-11-5.ddc [ 741.268413] Bluetooth: hci0: Failed to send Intel_Write_DDC (-22) [ 741.405046] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 745.020624] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 745.021399] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 745.158637] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 745.159037] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 745.258394] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [ 748.987711] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 748.988508] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 749.119218] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 749.119718] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 749.221934] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [ 749.907404] e1000e: eth0 NIC Link is Down [ 750.129204] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 750.300500] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 750.301245] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 750.430632] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 750.431032] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 750.526811] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [ 752.851377] wlp2s0: authenticate with 40:65:a3:15:37:9e [ 752.862528] wlp2s0: send auth to 40:65:a3:15:37:9e (try 1/3) [ 752.870660] wlp2s0: authenticated [ 752.872354] wlp2s0: associate with 40:65:a3:15:37:9e (try 1/3) [ 752.876207] wlp2s0: RX AssocResp from 40:65:a3:15:37:9e (capab=0x1411 status=0 aid=3) [ 752.881844] wlp2s0: associated [ 752.881938] IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0: link becomes ready [ 1282.454844] PM: Syncing filesystems ... done. [ 1283.649804] PM: Preparing system for sleep (mem) [ 1283.651183] (NULL device *): firmware: direct-loading firmware iwlwifi-8000C-22.ucode [ 1283.651203] Freezing user space processes ... (elapsed 0.001 seconds) done. [ 1283.653153] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done. [ 1283.654380] PM: Suspending system (mem) [ 1283.654450] Suspending console(s) (use no_console_suspend to debug) [ 1283.761164] wlp2s0: deauthenticating from 40:65:a3:15:37:9e by local choice (Reason: 3=DEAUTH_LEAVING) [ 1283.761342] sd 2:0:0:0: [sdb] Synchronizing SCSI cache [ 1283.761384] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 1283.768605] sd 2:0:0:0: [sdb] Stopping disk [ 1283.805931] sd 0:0:0:0: [sda] Stopping disk [ 1283.888777] ACPI : EC: event blocked [ 1284.355417] PM: suspend of devices complete after 594.728 msecs [ 1284.380480] PM: late suspend of devices complete after 25.057 msecs [ 1284.381205] ACPI : EC: interrupt blocked [ 1284.383787] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI [ 1284.465228] PM: noirq suspend of devices complete after 84.745 msecs [ 1284.466270] ACPI: Preparing to enter system sleep state S3 [ 1284.468435] ACPI : EC: EC stopped [ 1284.468436] PM: Saving platform NVS memory [ 1284.468471] Disabling non-boot CPUs ... [ 1284.469005] Broke affinity for irq 276 [ 1284.469011] Broke affinity for irq 279 [ 1284.470055] smpboot: CPU 1 is now offline [ 1284.471252] Broke affinity for irq 272 [ 1284.471259] Broke affinity for irq 275 [ 1284.471261] Broke affinity for irq 276 [ 1284.471266] Broke affinity for irq 279 [ 1284.473351] smpboot: CPU 2 is now offline [ 1284.474164] Broke affinity for irq 1 [ 1284.474184] Broke affinity for irq 9 [ 1284.474193] Broke affinity for irq 12 [ 1284.474211] Broke affinity for irq 16 [ 1284.474248] Broke affinity for irq 272 [ 1284.474253] Broke affinity for irq 275 [ 1284.474255] Broke affinity for irq 276 [ 1284.474259] Broke affinity for irq 279 [ 1284.474261] Broke affinity for irq 280 [ 1284.475292] smpboot: CPU 3 is now offline [ 1284.478901] ACPI: Low-level resume complete [ 1284.479019] ACPI : EC: EC started [ 1284.479020] PM: Restoring platform NVS memory [ 1284.479750] Suspended for 24863.048 seconds [ 1284.479803] Enabling non-boot CPUs ... [ 1284.479854] x86: Booting SMP configuration: [ 1284.479855] smpboot: Booting Node 0 Processor 1 APIC 0x2 [ 1284.482495] cache: parent cpu1 should not be sleeping [ 1284.482678] CPU1 is up [ 1284.482716] smpboot: Booting Node 0 Processor 2 APIC 0x1 [ 1284.485395] cache: parent cpu2 should not be sleeping [ 1284.485593] CPU2 is up [ 1284.485630] smpboot: Booting Node 0 Processor 3 APIC 0x3 [ 1284.488257] cache: parent cpu3 should not be sleeping [ 1284.488482] CPU3 is up [ 1284.492067] ACPI: Waking up from system sleep state S3 [ 1284.513966] acpi LNXPOWER:17: Turning OFF [ 1284.513996] acpi LNXPOWER:16: Turning OFF [ 1284.514025] acpi LNXPOWER:15: Turning OFF [ 1284.514054] acpi LNXPOWER:14: Turning OFF [ 1284.514083] acpi LNXPOWER:13: Turning OFF [ 1284.514112] acpi LNXPOWER:12: Turning OFF [ 1284.514141] acpi LNXPOWER:11: Turning OFF [ 1284.514170] acpi LNXPOWER:10: Turning OFF [ 1284.514199] acpi LNXPOWER:0f: Turning OFF [ 1284.514228] acpi LNXPOWER:0e: Turning OFF [ 1284.514256] acpi LNXPOWER:0d: Turning OFF [ 1284.514285] acpi LNXPOWER:0c: Turning OFF [ 1284.514314] acpi LNXPOWER:0b: Turning OFF [ 1284.514343] acpi LNXPOWER:0a: Turning OFF [ 1284.514372] acpi LNXPOWER:09: Turning OFF [ 1284.514400] acpi LNXPOWER:08: Turning OFF [ 1284.514429] acpi LNXPOWER:07: Turning OFF [ 1284.514458] acpi LNXPOWER:06: Turning OFF [ 1284.514487] acpi LNXPOWER:05: Turning OFF [ 1284.514643] acpi LNXPOWER:04: Turning OFF [ 1284.576434] acpi LNXPOWER:03: Turning OFF [ 1284.576850] ACPI : EC: interrupt unblocked [ 1284.618374] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI [ 1284.733181] PM: noirq resume of devices complete after 156.625 msecs [ 1284.733271] pciehp 0000:00:1c.0:pcie004: Slot(1): Card present [ 1284.733274] pciehp 0000:00:1c.0:pcie004: Slot(1): Link Up [ 1284.736465] pciehp 0000:00:1c.0:pcie004: Slot(1): Link Up event ignored; already powering on [ 1284.746821] PM: early resume of devices complete after 13.520 msecs [ 1284.747189] usb usb1: root hub lost power or was reset [ 1284.747193] usb usb2: root hub lost power or was reset [ 1284.748009] sd 0:0:0:0: [sda] Starting disk [ 1284.748015] sd 2:0:0:0: [sdb] Starting disk [ 1284.749263] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1284.749991] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1284.805465] [drm] GuC firmware load skipped [ 1284.844386] pciehp 0000:00:1c.0:pcie004: Device 0000:01:00.0 already exists at 0000:01:00, cannot hot-add [ 1284.844389] pciehp 0000:00:1c.0:pcie004: Cannot add device at 0000:01:00 [ 1284.877743] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1284.878139] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1284.952349] pciehp 0000:00:1c.0:pcie004: Device 0000:01:00.0 already exists at 0000:01:00, cannot hot-add [ 1284.952353] pciehp 0000:00:1c.0:pcie004: Cannot add device at 0000:01:00 [ 1285.063084] ata3: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [ 1285.064184] ata3.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [ 1285.065811] ata3.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [ 1285.066599] ata3.00: configured for UDMA/133 [ 1285.092433] usb 1-7: reset full-speed USB device number 2 using xhci_hcd [ 1285.352683] usb 1-9: reset high-speed USB device number 4 using xhci_hcd [ 1285.612694] usb 1-8: reset full-speed USB device number 3 using xhci_hcd [ 1286.036382] ACPI : EC: event unblocked [ 1286.038783] rtc_cmos 00:03: System wakeup disabled by ACPI [ 1286.148436] tpm tpm0: A TPM error (7) occurred attempting to read a pcr value [ 1286.148440] tpm tpm0: TPM is disabled/deactivated (0x7) [ 1286.152800] ACPI Error: Needed [Buffer/String/Package], found [Integer] ffff884a7de845e8 (20160831/exresop-594) [ 1286.152805] ACPI Exception: AE_AML_OPERAND_TYPE, While resolving operands for [OpcodeName unavailable] (20160831/dswexec-461) [ 1286.152818] ACPI Error: Method parse/execution failed [\_SB.WMIV.WVPO] (Node ffff884a908a8988), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 1286.152829] ACPI Error: Method parse/execution failed [\_SB.WMIV.WMPV] (Node ffff884a908a87a8), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 1286.154908] ACPI Error: Needed [Buffer/String/Package], found [Integer] ffff884a8b29d9d8 (20160831/exresop-594) [ 1286.154913] ACPI Exception: AE_AML_OPERAND_TYPE, While resolving operands for [OpcodeName unavailable] (20160831/dswexec-461) [ 1286.154925] ACPI Error: Method parse/execution failed [\_SB.WMIV.WVPO] (Node ffff884a908a8988), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 1286.154937] ACPI Error: Method parse/execution failed [\_SB.WMIV.WMPV] (Node ffff884a908a87a8), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 1286.154995] PM: resume of devices complete after 1408.212 msecs [ 1286.155230] usb 1-7:1.0: rebind failed: -517 [ 1286.155235] usb 1-7:1.1: rebind failed: -517 [ 1286.155715] PM: Finishing wakeup. [ 1286.155717] Restarting tasks ... done. [ 1286.175049] Bluetooth: hci0: Bootloader revision 0.0 build 2 week 52 2014 [ 1286.181117] Bluetooth: hci0: Device revision is 5 [ 1286.181119] Bluetooth: hci0: Secure boot is enabled [ 1286.181120] Bluetooth: hci0: OTP lock is enabled [ 1286.181121] Bluetooth: hci0: API lock is enabled [ 1286.181122] Bluetooth: hci0: Debug lock is disabled [ 1286.181123] Bluetooth: hci0: Minimum firmware build 1 week 10 2014 [ 1286.181293] bluetooth hci0: firmware: direct-loading firmware intel/ibt-11-5.sfi [ 1286.181295] Bluetooth: hci0: Found device firmware: intel/ibt-11-5.sfi [ 1286.325635] [drm] RC6 on [ 1286.380280] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [ 1286.381860] ata1.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [ 1286.381862] ata1.00: ACPI cmd b1/c1:00:00:00:00:e0 (DEVICE CONFIGURATION OVERLAY) filtered out [ 1286.383761] ata1.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [ 1286.383763] ata1.00: ACPI cmd b1/c1:00:00:00:00:e0 (DEVICE CONFIGURATION OVERLAY) filtered out [ 1286.383943] ata1.00: configured for UDMA/133 [ 1287.402401] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1287.403119] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1287.435211] psmouse serio3: synaptics: queried max coordinates: x [..5670], y [..4758] [ 1287.466436] psmouse serio3: synaptics: queried min coordinates: x [1360..], y [1198..] [ 1287.531028] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1287.531428] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1287.619015] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [ 1287.784689] e1000e: eth0 NIC Link is Down [ 1287.788215] Bluetooth: hci0: Waiting for firmware download to complete [ 1287.789105] Bluetooth: hci0: Firmware loaded in 1578390 usecs [ 1287.789380] Bluetooth: hci0: Waiting for device to boot [ 1287.800142] Bluetooth: hci0: Device booted in 10698 usecs [ 1287.800188] bluetooth hci0: firmware: direct-loading firmware intel/ibt-11-5.ddc [ 1287.800193] Bluetooth: hci0: Found Intel DDC parameters: intel/ibt-11-5.ddc [ 1287.801287] Bluetooth: hci0: Failed to send Intel_Write_DDC (-22) [ 1288.000714] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 1291.007369] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1291.008291] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1291.138510] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1291.138910] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1291.234218] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [ 1294.910753] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1294.911465] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1295.041247] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1295.041646] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1295.138179] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [ 1295.707487] e1000e: eth0 NIC Link is Down [ 1295.924732] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 1296.063532] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1296.064283] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1296.195052] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1296.195544] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1296.295279] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [ 1298.592959] wlp2s0: authenticate with 40:65:a3:15:37:9e [ 1298.603769] wlp2s0: send auth to 40:65:a3:15:37:9e (try 1/3) [ 1298.611459] wlp2s0: authenticated [ 1298.611950] wlp2s0: associate with 40:65:a3:15:37:9e (try 1/3) [ 1298.615709] wlp2s0: RX AssocResp from 40:65:a3:15:37:9e (capab=0x1411 status=0 aid=3) [ 1298.618874] wlp2s0: associated [ 1298.618974] IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0: link becomes ready [ 1415.126613] PM: Syncing filesystems ... done. [ 1415.238888] PM: Preparing system for sleep (mem) [ 1415.239493] (NULL device *): firmware: direct-loading firmware intel/ibt-11-5.ddc [ 1415.239733] (NULL device *): firmware: direct-loading firmware intel/ibt-11-5.sfi [ 1415.240123] (NULL device *): firmware: direct-loading firmware iwlwifi-8000C-22.ucode [ 1415.240144] Freezing user space processes ... (elapsed 0.002 seconds) done. [ 1415.242277] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done. [ 1415.243620] PM: Suspending system (mem) [ 1415.243689] Suspending console(s) (use no_console_suspend to debug) [ 1415.353337] wlp2s0: deauthenticating from 40:65:a3:15:37:9e by local choice (Reason: 3=DEAUTH_LEAVING) [ 1415.353553] sd 2:0:0:0: [sdb] Synchronizing SCSI cache [ 1415.353572] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 1415.357091] sd 0:0:0:0: [sda] Stopping disk [ 1415.360751] sd 2:0:0:0: [sdb] Stopping disk [ 1415.476762] ACPI : EC: event blocked [ 1415.911948] PM: suspend of devices complete after 559.316 msecs [ 1415.936437] PM: late suspend of devices complete after 24.483 msecs [ 1415.937360] ACPI : EC: interrupt blocked [ 1415.939676] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI [ 1416.020998] PM: noirq suspend of devices complete after 84.559 msecs [ 1416.021918] ACPI: Preparing to enter system sleep state S3 [ 1416.023847] ACPI : EC: EC stopped [ 1416.023848] PM: Saving platform NVS memory [ 1416.023879] Disabling non-boot CPUs ... [ 1416.024390] Broke affinity for irq 276 [ 1416.025431] smpboot: CPU 1 is now offline [ 1416.026501] Broke affinity for irq 272 [ 1416.026507] Broke affinity for irq 275 [ 1416.026509] Broke affinity for irq 276 [ 1416.026512] Broke affinity for irq 277 [ 1416.026514] Broke affinity for irq 279 [ 1416.028513] smpboot: CPU 2 is now offline [ 1416.029291] Broke affinity for irq 1 [ 1416.029308] Broke affinity for irq 9 [ 1416.029318] Broke affinity for irq 12 [ 1416.029335] Broke affinity for irq 16 [ 1416.029367] Broke affinity for irq 272 [ 1416.029371] Broke affinity for irq 275 [ 1416.029373] Broke affinity for irq 276 [ 1416.029375] Broke affinity for irq 277 [ 1416.029377] Broke affinity for irq 279 [ 1416.029379] Broke affinity for irq 280 [ 1416.030404] smpboot: CPU 3 is now offline [ 1416.034535] ACPI: Low-level resume complete [ 1416.034671] ACPI : EC: EC started [ 1416.034672] PM: Restoring platform NVS memory [ 1416.035414] Suspended for 7463.444 seconds [ 1416.035484] Enabling non-boot CPUs ... [ 1416.035561] x86: Booting SMP configuration: [ 1416.035562] smpboot: Booting Node 0 Processor 1 APIC 0x2 [ 1416.038538] cache: parent cpu1 should not be sleeping [ 1416.038831] CPU1 is up [ 1416.038889] smpboot: Booting Node 0 Processor 2 APIC 0x1 [ 1416.041906] cache: parent cpu2 should not be sleeping [ 1416.042197] CPU2 is up [ 1416.042254] smpboot: Booting Node 0 Processor 3 APIC 0x3 [ 1416.045134] cache: parent cpu3 should not be sleeping [ 1416.045428] CPU3 is up [ 1416.051263] ACPI: Waking up from system sleep state S3 [ 1416.062828] acpi LNXPOWER:17: Turning OFF [ 1416.062876] acpi LNXPOWER:16: Turning OFF [ 1416.062923] acpi LNXPOWER:15: Turning OFF [ 1416.062970] acpi LNXPOWER:14: Turning OFF [ 1416.063017] acpi LNXPOWER:13: Turning OFF [ 1416.063064] acpi LNXPOWER:12: Turning OFF [ 1416.063111] acpi LNXPOWER:11: Turning OFF [ 1416.063158] acpi LNXPOWER:10: Turning OFF [ 1416.063204] acpi LNXPOWER:0f: Turning OFF [ 1416.063250] acpi LNXPOWER:0e: Turning OFF [ 1416.063296] acpi LNXPOWER:0d: Turning OFF [ 1416.063342] acpi LNXPOWER:0c: Turning OFF [ 1416.063388] acpi LNXPOWER:0b: Turning OFF [ 1416.063435] acpi LNXPOWER:0a: Turning OFF [ 1416.063481] acpi LNXPOWER:09: Turning OFF [ 1416.063528] acpi LNXPOWER:08: Turning OFF [ 1416.063574] acpi LNXPOWER:07: Turning OFF [ 1416.063620] acpi LNXPOWER:06: Turning OFF [ 1416.063667] acpi LNXPOWER:05: Turning OFF [ 1416.063899] acpi LNXPOWER:04: Turning OFF [ 1416.124812] acpi LNXPOWER:03: Turning OFF [ 1416.125192] ACPI : EC: interrupt unblocked [ 1416.166802] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI [ 1416.281544] PM: noirq resume of devices complete after 156.607 msecs [ 1416.281639] pciehp 0000:00:1c.0:pcie004: Slot(1): Card present [ 1416.281642] pciehp 0000:00:1c.0:pcie004: Slot(1): Link Up [ 1416.281940] pciehp 0000:00:1c.0:pcie004: Slot(1): Link Up event ignored; already powering on [ 1416.283629] ACPI Error: Needed [Buffer/String/Package], found [Integer] ffff884a88851bd0 (20160831/exresop-594) [ 1416.283633] ACPI Exception: AE_AML_OPERAND_TYPE, While resolving operands for [OpcodeName unavailable] (20160831/dswexec-461) [ 1416.283643] ACPI Error: Method parse/execution failed [\_SB.WMIV.WVPO] (Node ffff884a908a8988), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 1416.283652] ACPI Error: Method parse/execution failed [\_SB.WMIV.WMPV] (Node ffff884a908a87a8), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 1416.284976] ACPI Error: Needed [Buffer/String/Package], found [Integer] ffff884a88851168 (20160831/exresop-594) [ 1416.284979] ACPI Exception: AE_AML_OPERAND_TYPE, While resolving operands for [OpcodeName unavailable] (20160831/dswexec-461) [ 1416.284988] ACPI Error: Method parse/execution failed [\_SB.WMIV.WVPO] (Node ffff884a908a8988), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 1416.284997] ACPI Error: Method parse/execution failed [\_SB.WMIV.WMPV] (Node ffff884a908a87a8), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 1416.298302] PM: early resume of devices complete after 13.507 msecs [ 1416.298661] usb usb1: root hub lost power or was reset [ 1416.298665] usb usb2: root hub lost power or was reset [ 1416.300303] sd 0:0:0:0: [sda] Starting disk [ 1416.300304] sd 2:0:0:0: [sdb] Starting disk [ 1416.302134] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1416.302990] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1416.358039] [drm] GuC firmware load skipped [ 1416.388895] pciehp 0000:00:1c.0:pcie004: Device 0000:01:00.0 already exists at 0000:01:00, cannot hot-add [ 1416.388898] pciehp 0000:00:1c.0:pcie004: Cannot add device at 0000:01:00 [ 1416.431576] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1416.431963] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1416.496797] pciehp 0000:00:1c.0:pcie004: Device 0000:01:00.0 already exists at 0000:01:00, cannot hot-add [ 1416.496800] pciehp 0000:00:1c.0:pcie004: Cannot add device at 0000:01:00 [ 1416.615375] ata3: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [ 1416.616009] ata3.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [ 1416.617926] ata3.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [ 1416.619132] ata3.00: configured for UDMA/133 [ 1416.648898] usb 1-8: reset full-speed USB device number 3 using xhci_hcd [ 1416.908971] usb 1-7: reset full-speed USB device number 2 using xhci_hcd [ 1417.168857] usb 1-9: reset high-speed USB device number 4 using xhci_hcd [ 1417.572987] ACPI : EC: event unblocked [ 1417.573592] rtc_cmos 00:03: System wakeup disabled by ACPI [ 1417.684721] tpm tpm0: A TPM error (7) occurred attempting to read a pcr value [ 1417.684724] tpm tpm0: TPM is disabled/deactivated (0x7) [ 1417.688454] ACPI Error: Needed [Buffer/String/Package], found [Integer] ffff884a8b29d3f0 (20160831/exresop-594) [ 1417.688459] ACPI Exception: AE_AML_OPERAND_TYPE, While resolving operands for [OpcodeName unavailable] (20160831/dswexec-461) [ 1417.688470] ACPI Error: Method parse/execution failed [\_SB.WMIV.WVPO] (Node ffff884a908a8988), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 1417.688480] ACPI Error: Method parse/execution failed [\_SB.WMIV.WMPV] (Node ffff884a908a87a8), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 1417.689964] ACPI Error: Needed [Buffer/String/Package], found [Integer] ffff884a870a1828 (20160831/exresop-594) [ 1417.689967] ACPI Exception: AE_AML_OPERAND_TYPE, While resolving operands for [OpcodeName unavailable] (20160831/dswexec-461) [ 1417.689978] ACPI Error: Method parse/execution failed [\_SB.WMIV.WVPO] (Node ffff884a908a8988), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 1417.689988] ACPI Error: Method parse/execution failed [\_SB.WMIV.WMPV] (Node ffff884a908a87a8), AE_AML_OPERAND_TYPE (20160831/psparse-543) [ 1417.690449] PM: resume of devices complete after 1392.185 msecs [ 1417.690633] usb 1-7:1.0: rebind failed: -517 [ 1417.690640] usb 1-7:1.1: rebind failed: -517 [ 1417.690959] PM: Finishing wakeup. [ 1417.690960] Restarting tasks ... done. [ 1417.707952] Bluetooth: hci0: Bootloader revision 0.0 build 2 week 52 2014 [ 1417.714170] [drm] RC6 on [ 1417.714201] Bluetooth: hci0: Device revision is 5 [ 1417.714202] Bluetooth: hci0: Secure boot is enabled [ 1417.714203] Bluetooth: hci0: OTP lock is enabled [ 1417.714204] Bluetooth: hci0: API lock is enabled [ 1417.714204] Bluetooth: hci0: Debug lock is disabled [ 1417.714206] Bluetooth: hci0: Minimum firmware build 1 week 10 2014 [ 1417.714209] Bluetooth: hci0: Found device firmware: intel/ibt-11-5.sfi [ 1417.808709] usb 2-4: new SuperSpeed USB device number 2 using xhci_hcd [ 1417.829069] usb 2-4: New USB device found, idVendor=0424, idProduct=5534 [ 1417.829071] usb 2-4: New USB device strings: Mfr=2, Product=3, SerialNumber=0 [ 1417.829073] usb 2-4: Product: USB5534B [ 1417.829074] usb 2-4: Manufacturer: SMSC [ 1417.829745] hub 2-4:1.0: USB hub found [ 1417.829926] hub 2-4:1.0: 4 ports detected [ 1417.858993] NMI watchdog: enabled on all CPUs, permanently consumes one hw-PMU counter. [ 1417.948646] usb 1-4: new high-speed USB device number 7 using xhci_hcd [ 1417.949738] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [ 1417.950278] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro,data=ordered,commit=0 [ 1417.951244] ata1.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [ 1417.951246] ata1.00: ACPI cmd b1/c1:00:00:00:00:e0 (DEVICE CONFIGURATION OVERLAY) filtered out [ 1417.953104] ata1.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [ 1417.953107] ata1.00: ACPI cmd b1/c1:00:00:00:00:e0 (DEVICE CONFIGURATION OVERLAY) filtered out [ 1417.953312] ata1.00: configured for UDMA/133 [ 1418.088970] usb 1-4: New USB device found, idVendor=0424, idProduct=2134 [ 1418.088972] usb 1-4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 [ 1418.088973] usb 1-4: Product: USB2134B [ 1418.088975] usb 1-4: Manufacturer: SMSC [ 1418.089365] hub 1-4:1.0: USB hub found [ 1418.089436] hub 1-4:1.0: 4 ports detected [ 1418.512560] EXT4-fs (sda1): re-mounted. Opts: block_validity,barrier,user_xattr,acl,stripe=4 [ 1418.636491] EXT4-fs (dm-1): re-mounted. Opts: data=ordered,commit=0 [ 1418.878782] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1418.879502] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1418.912643] usb 1-4.3: new low-speed USB device number 9 using xhci_hcd [ 1419.006816] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1419.007221] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1419.014440] psmouse serio3: synaptics: queried max coordinates: x [..5670], y [..4758] [ 1419.016034] usb 1-4.3: New USB device found, idVendor=0461, idProduct=4d22 [ 1419.016038] usb 1-4.3: New USB device strings: Mfr=0, Product=2, SerialNumber=0 [ 1419.016041] usb 1-4.3: Product: USB Optical Mouse [ 1419.044570] psmouse serio3: synaptics: queried min coordinates: x [1360..], y [1198..] [ 1419.090778] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [ 1419.096622] usb 1-4.4: new low-speed USB device number 10 using xhci_hcd [ 1419.184200] Bluetooth: hci0: Waiting for firmware download to complete [ 1419.184947] Bluetooth: hci0: Firmware loaded in 1443476 usecs [ 1419.184974] Bluetooth: hci0: Waiting for device to boot [ 1419.195941] Bluetooth: hci0: Device booted in 10731 usecs [ 1419.195946] Bluetooth: hci0: Found Intel DDC parameters: intel/ibt-11-5.ddc [ 1419.196964] Bluetooth: hci0: Failed to send Intel_Write_DDC (-22) [ 1419.202270] usb 1-4.4: New USB device found, idVendor=413c, idProduct=2107 [ 1419.202273] usb 1-4.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 [ 1419.202275] usb 1-4.4: Product: Dell USB Entry Keyboard [ 1419.202276] usb 1-4.4: Manufacturer: DELL [ 1419.301997] e1000e 0000:00:1f.6: Failed to restore TIMINCA clock rate delta: -22 [ 1419.304585] e1000e: eth0 NIC Link is Down [ 1419.521228] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 1419.868796] usb 2-4.1: new SuperSpeed USB device number 3 using xhci_hcd [ 1419.896549] usb 2-4.1: New USB device found, idVendor=174c, idProduct=5106 [ 1419.896556] usb 2-4.1: New USB device strings: Mfr=2, Product=3, SerialNumber=1 [ 1419.896560] usb 2-4.1: Product: StoreJet Transcend [ 1419.896563] usb 2-4.1: Manufacturer: StoreJet Transcend [ 1419.896566] usb 2-4.1: SerialNumber: S344J9GFC07086 [ 1420.018578] usbcore: registered new interface driver usbhid [ 1420.018581] usbhid: USB HID core driver [ 1420.047505] input: USB Optical Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-4/1-4.3/1-4.3:1.0/0003:0461:4D22.0001/input/input35 [ 1420.048135] hid-generic 0003:0461:4D22.0001: input,hidraw0: USB HID v1.11 Mouse [USB Optical Mouse] on usb-0000:00:14.0-4.3/input0 [ 1420.048317] input: DELL Dell USB Entry Keyboard as /devices/pci0000:00/0000:00:14.0/usb1/1-4/1-4.4/1-4.4:1.0/0003:413C:2107.0002/input/input36 [ 1420.104800] hid-generic 0003:413C:2107.0002: input,hidraw1: USB HID v1.11 Keyboard [DELL Dell USB Entry Keyboard] on usb-0000:00:14.0-4.4/input0 [ 1420.253074] usb-storage 2-4.1:1.0: USB Mass Storage device detected [ 1420.253132] scsi host3: usb-storage 2-4.1:1.0 [ 1420.253204] usbcore: registered new interface driver usb-storage [ 1420.254217] usbcore: registered new interface driver uas [ 1421.072901] e1000e: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: None [ 1421.072983] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 1421.289345] scsi 3:0:0:0: Direct-Access StoreJet Transcend 0 PQ: 0 ANSI: 6 [ 1421.290828] sd 3:0:0:0: Attached scsi generic sg2 type 0 [ 1421.291008] sd 3:0:0:0: [sdc] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB) [ 1421.291281] sd 3:0:0:0: [sdc] Write Protect is off [ 1421.291293] sd 3:0:0:0: [sdc] Mode Sense: 43 00 00 00 [ 1421.291551] sd 3:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 1421.328608] sdc: sdc1 [ 1421.330412] sd 3:0:0:0: [sdc] Attached SCSI disk [ 1423.130149] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1423.130943] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1423.260900] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1423.261313] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1423.360917] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [ 1423.396660] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1423.397443] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1423.526406] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1423.526833] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [ 1423.617549] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [ 1423.873609] e1000e: eth0 NIC Link is Down [ 1424.109368] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 1424.317482] e1000e: eth0 NIC Link is Down [ 1424.605161] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 1426.129233] e1000e: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: None [ 1426.129365] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 1443.070398] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=1592 end=1593) time 166 us, min 1073, max 1079, scanline start 1069, end 1080 [ 1969.829087] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=31575 end=31576) time 146 us, min 1043, max 1049, scanline start 1041, end 1051 [ 1989.892857] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=34432 end=34433) time 142 us, min 1073, max 1079, scanline start 1070, end 1080 [ 2171.513669] UDF-fs: warning (device sdc1): udf_load_vrs: No VRS found [ 2171.513671] UDF-fs: warning (device sdc1): udf_fill_super: No partition found (2) [ 2171.515041] UDF-fs: warning (device sdc1): udf_load_vrs: No VRS found [ 2171.515042] UDF-fs: warning (device sdc1): udf_fill_super: No partition found (2) [ 2171.527923] ISOFS: Unable to identify CD-ROM format. [ 2171.539638] ISOFS: Unable to identify CD-ROM format. [ 2171.543723] FAT-fs (sdc1): bogus number of reserved sectors [ 2171.543727] FAT-fs (sdc1): Can't find a valid FAT filesystem [ 2171.545388] FAT-fs (sdc1): bogus number of reserved sectors [ 2171.545392] FAT-fs (sdc1): Can't find a valid FAT filesystem [ 2171.548867] hfsplus: unable to find HFS+ superblock [ 2171.550384] hfsplus: unable to find HFS+ superblock [ 2171.553543] hfs: can't find a HFS filesystem on dev sdc1 [ 2171.555028] hfs: can't find a HFS filesystem on dev sdc1 [ 2171.556293] EXT4-fs (sdc1): couldn't mount as ext3 due to feature incompatibilities [ 2171.557498] EXT4-fs (sdc1): couldn't mount as ext3 due to feature incompatibilities [ 2171.558679] EXT4-fs (sdc1): couldn't mount as ext2 due to feature incompatibilities [ 2171.559882] EXT4-fs (sdc1): couldn't mount as ext2 due to feature incompatibilities [ 2171.881837] EXT4-fs (sdc1): mounted filesystem with ordered data mode. Opts: errors=remount-ro [ 2431.007457] usb 1-2: new high-speed USB device number 11 using xhci_hcd [ 2431.149245] usb 1-2: New USB device found, idVendor=04e8, idProduct=6860 [ 2431.149252] usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 2431.149256] usb 1-2: Product: SAMSUNG_Android [ 2431.149260] usb 1-2: Manufacturer: SAMSUNG [ 2431.149264] usb 1-2: SerialNumber: 2cbc57d0 [ 2655.104892] usb 1-2: USB disconnect, device number 11 [ 2659.483576] usb 1-2: new high-speed USB device number 12 using xhci_hcd [ 2659.626802] usb 1-2: New USB device found, idVendor=04e8, idProduct=6860 [ 2659.626809] usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 2659.626814] usb 1-2: Product: SAMSUNG_Android [ 2659.626818] usb 1-2: Manufacturer: SAMSUNG [ 2659.626821] usb 1-2: SerialNumber: 2cbc57d0 [ 2773.709268] usb 1-2: USB disconnect, device number 12 [ 2776.335687] usb 1-2: new high-speed USB device number 13 using xhci_hcd [ 2776.477927] usb 1-2: New USB device found, idVendor=04e8, idProduct=6860 [ 2776.477934] usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 2776.477939] usb 1-2: Product: SAMSUNG_Android [ 2776.477943] usb 1-2: Manufacturer: SAMSUNG [ 2776.477946] usb 1-2: SerialNumber: 2cbc57d0 [ 2897.349704] usb 1-2: USB disconnect, device number 13 [ 3007.035872] usb 1-2: new high-speed USB device number 14 using xhci_hcd [ 3007.177479] usb 1-2: New USB device found, idVendor=04e8, idProduct=6860 [ 3007.177481] usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 3007.177482] usb 1-2: Product: SAMSUNG_Android [ 3007.177482] usb 1-2: Manufacturer: SAMSUNG [ 3007.177483] usb 1-2: SerialNumber: 2cbc57d0 [ 3141.326947] usb 1-2: USB disconnect, device number 14 [ 4309.825101] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=170341 end=170342) time 143 us, min 1073, max 1079, scanline start 1070, end 1080 [ 4838.482333] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=200152 end=200153) time 146 us, min 1043, max 1049, scanline start 1041, end 1050 [ 5427.515372] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=235467 end=235468) time 145 us, min 1043, max 1049, scanline start 1040, end 1050 [ 5820.181897] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=259009 end=259010) time 147 us, min 1043, max 1049, scanline start 1042, end 1052 [ 6409.231635] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=294325 end=294326) time 171 us, min 1043, max 1049, scanline start 1041, end 1053 [ 7895.326403] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=379983 end=379984) time 146 us, min 1043, max 1049, scanline start 1040, end 1050 [ 8091.676320] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=391755 end=391756) time 146 us, min 1043, max 1049, scanline start 1040, end 1050 [ 8751.109457] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=433613 end=433614) time 169 us, min 1073, max 1079, scanline start 1070, end 1082 [ 9083.416855] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=451214 end=451215) time 153 us, min 1043, max 1049, scanline start 1041, end 1051 [ 9153.849934] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=457800 end=457801) time 169 us, min 1073, max 1079, scanline start 1072, end 1084 [10331.816166] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=528544 end=528545) time 143 us, min 1073, max 1079, scanline start 1072, end 1082 [10528.199368] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=540338 end=540339) time 143 us, min 1073, max 1079, scanline start 1071, end 1081 [10961.399041] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=562900 end=562901) time 143 us, min 1073, max 1079, scanline start 1070, end 1080 [11354.132174] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=586486 end=586487) time 143 us, min 1073, max 1079, scanline start 1070, end 1080 [11686.506135] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=603840 end=603841) time 171 us, min 1043, max 1049, scanline start 1040, end 1052 [11746.865316] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=610072 end=610073) time 143 us, min 1073, max 1079, scanline start 1072, end 1082 [12079.205991] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=627384 end=627385) time 171 us, min 1043, max 1049, scanline start 1042, end 1053 [12275.572511] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=639157 end=639158) time 146 us, min 1043, max 1049, scanline start 1040, end 1050 [14456.281473] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=769341 end=769342) time 169 us, min 1073, max 1079, scanline start 1071, end 1082 [15377.688403] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=821704 end=821705) time 145 us, min 1043, max 1049, scanline start 1040, end 1050 [15438.047665] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=828302 end=828303) time 143 us, min 1073, max 1079, scanline start 1071, end 1081 [15966.738158] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=857020 end=857021) time 171 us, min 1043, max 1049, scanline start 1039, end 1050 [16742.080104] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=903505 end=903506) time 147 us, min 1043, max 1049, scanline start 1042, end 1052 [17144.854277] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=927653 end=927654) time 146 us, min 1043, max 1049, scanline start 1041, end 1050 [17537.570782] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=951198 end=951199) time 147 us, min 1043, max 1049, scanline start 1041, end 1051 [18186.963160] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=993391 end=993392) time 169 us, min 1073, max 1079, scanline start 1070, end 1082 [18519.320325] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=1010058 end=1010059) time 146 us, min 1043, max 1049, scanline start 1040, end 1050 [19108.336757] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=1045372 end=1045373) time 172 us, min 1043, max 1049, scanline start 1041, end 1053 [19304.703299] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=1057145 end=1057146) time 146 us, min 1043, max 1049, scanline start 1042, end 1051 [20160.502876] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=1111914 end=1111915) time 144 us, min 1073, max 1079, scanline start 1070, end 1080 [22850.144938] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=1266136 end=1266137) time 172 us, min 1043, max 1049, scanline start 1039, end 1051 [23509.627983] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=1309452 end=1309453) time 142 us, min 1073, max 1079, scanline start 1070, end 1080 [24617.327490] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=1372086 end=1372087) time 146 us, min 1043, max 1049, scanline start 1042, end 1052 [24884.110771] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=1391998 end=1391999) time 170 us, min 1073, max 1079, scanline start 1072, end 1084 [26188.126756] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=1466262 end=1466263) time 146 us, min 1043, max 1049, scanline start 1040, end 1050 [26454.876697] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=1486332 end=1486333) time 142 us, min 1073, max 1079, scanline start 1070, end 1080 [27191.119079] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=1522955 end=1522956) time 171 us, min 1043, max 1049, scanline start 1042, end 1054 [27976.485333] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=1570041 end=1570042) time 171 us, min 1043, max 1049, scanline start 1040, end 1052 [28036.844663] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=1577891 end=1577892) time 169 us, min 1073, max 1079, scanline start 1071, end 1083 [28565.551724] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=1605358 end=1605359) time 145 us, min 1043, max 1049, scanline start 1042, end 1052 [29154.568072] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=1640672 end=1640673) time 145 us, min 1043, max 1049, scanline start 1041, end 1051 [29421.367979] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=1661040 end=1661041) time 144 us, min 1073, max 1079, scanline start 1071, end 1081 [29607.660623] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=1672228 end=1672229) time 169 us, min 1073, max 1079, scanline start 1070, end 1082 [29743.667864] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=1675991 end=1675992) time 172 us, min 1043, max 1049, scanline start 1038, end 1050 [30126.276626] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=1698930 end=1698931) time 145 us, min 1043, max 1049, scanline start 1041, end 1051 [30196.693725] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=1707603 end=1707604) time 170 us, min 1073, max 1079, scanline start 1071, end 1082 [30795.784044] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=1743582 end=1743583) time 143 us, min 1073, max 1079, scanline start 1071, end 1081 [30921.717283] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=1746620 end=1746621) time 145 us, min 1043, max 1049, scanline start 1042, end 1052 [31188.483861] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=1767166 end=1767167) time 143 us, min 1073, max 1079, scanline start 1071, end 1081 [32100.285811] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=1813840 end=1813841) time 147 us, min 1043, max 1049, scanline start 1041, end 1051 [32356.994996] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=1833885 end=1833886) time 169 us, min 1073, max 1079, scanline start 1068, end 1080 [33474.701880] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe B (start=1896242 end=1896243) time 171 us, min 1043, max 1049, scanline start 1041, end 1053 [34495.658103] usb 1-4.2: new high-speed USB device number 15 using xhci_hcd [34495.759065] usb 1-4.2: New USB device found, idVendor=1908, idProduct=1320 [34495.759073] usb 1-4.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [34495.759077] usb 1-4.2: Product: Mass storage [34495.759081] usb 1-4.2: Manufacturer: Generic [34495.759085] usb 1-4.2: SerialNumber: aaaaaaaaaa7499211377 [34495.760132] usb-storage 1-4.2:1.0: USB Mass Storage device detected [34495.760379] scsi host4: usb-storage 1-4.2:1.0 [34496.784829] scsi 4:0:0:0: Direct-Access Generic Flash Disk 1.68 PQ: 0 ANSI: 2 [34496.786426] sd 4:0:0:0: [sdd] 2009088 512-byte logical blocks: (1.03 GB/981 MiB) [34496.786432] sd 4:0:0:0: Attached scsi generic sg3 type 0 [34496.787834] sd 4:0:0:0: [sdd] Write Protect is off [34496.787844] sd 4:0:0:0: [sdd] Mode Sense: 03 00 00 00 [34496.790093] sd 4:0:0:0: [sdd] No Caching mode page found [34496.790108] sd 4:0:0:0: [sdd] Assuming drive cache: write through [34496.797882] sdd: sdd1 [34496.801998] sd 4:0:0:0: [sdd] Attached SCSI removable disk [34507.053452] UDF-fs: warning (device sdd1): udf_load_vrs: No VRS found [34507.053458] UDF-fs: warning (device sdd1): udf_fill_super: No partition found (2) [34507.059141] UDF-fs: warning (device sdd1): udf_load_vrs: No VRS found [34507.059147] UDF-fs: warning (device sdd1): udf_fill_super: No partition found (2) [34507.100865] ISOFS: Unable to identify CD-ROM format. [34507.141504] ISOFS: Unable to identify CD-ROM format. [34507.154318] FAT-fs (sdd1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. [34621.133707] usb 1-4.2: USB disconnect, device number 15 [34966.485959] SGI XFS with ACLs, security attributes, realtime, no debug enabled [34966.489833] JFS: nTxBlock = 8192, nTxLock = 65536 [34966.496641] ntfs: driver 2.1.32 [Flags: R/O MODULE]. [34966.502788] QNX4 filesystem 0.2.3 registered. [34966.574192] raid6: sse2x1 gen() 10319 MB/s [34966.642180] raid6: sse2x1 xor() 8137 MB/s [34966.710173] raid6: sse2x2 gen() 14539 MB/s [34966.778199] raid6: sse2x2 xor() 10114 MB/s [34966.846177] raid6: sse2x4 gen() 15265 MB/s [34966.914175] raid6: sse2x4 xor() 11001 MB/s [34966.982167] raid6: avx2x1 gen() 23260 MB/s [34967.050165] raid6: avx2x2 gen() 26769 MB/s [34967.118163] raid6: avx2x4 gen() 27517 MB/s [34967.118165] raid6: using algorithm avx2x4 gen() 27517 MB/s [34967.118166] raid6: using avx2x2 recovery algorithm [34967.125992] xor: automatically using best checksumming function avx [34967.135469] Btrfs loaded, crc32c=crc32c-intel [35292.539517] PM: Syncing filesystems ... done. [35292.634936] PM: Preparing system for sleep (mem) [35292.637216] (NULL device *): firmware: direct-loading firmware iwlwifi-8000C-22.ucode [35292.637333] Freezing user space processes ... (elapsed 0.002 seconds) done. [35292.639995] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done. [35292.641525] PM: Suspending system (mem) [35292.641625] Suspending console(s) (use no_console_suspend to debug) [35292.642278] sd 3:0:0:0: [sdc] Synchronizing SCSI cache [35292.644115] sd 2:0:0:0: [sdb] Synchronizing SCSI cache [35292.644733] sd 0:0:0:0: [sda] Synchronizing SCSI cache [35292.645319] sd 2:0:0:0: [sdb] Stopping disk [35292.661892] sd 0:0:0:0: [sda] Stopping disk [35292.767742] e1000e: EEE TX LPI TIMER: 00000011 [35292.767974] ACPI : EC: event blocked [35293.219128] PM: suspend of devices complete after 576.922 msecs [35293.243440] PM: late suspend of devices complete after 24.299 msecs [35293.245021] ACPI : EC: interrupt blocked [35293.247923] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI [35293.332407] PM: noirq suspend of devices complete after 88.961 msecs [35293.333797] ACPI: Preparing to enter system sleep state S3 [35293.336640] ACPI : EC: EC stopped [35293.336641] PM: Saving platform NVS memory [35293.336684] Disabling non-boot CPUs ... [35293.337410] Broke affinity for irq 276 [35293.338478] smpboot: CPU 1 is now offline [35293.340041] Broke affinity for irq 272 [35293.340049] Broke affinity for irq 275 [35293.340053] Broke affinity for irq 276 [35293.342522] smpboot: CPU 2 is now offline [35293.343126] Broke affinity for irq 1 [35293.343144] Broke affinity for irq 9 [35293.343153] Broke affinity for irq 12 [35293.343170] Broke affinity for irq 16 [35293.343190] Broke affinity for irq 272 [35293.343192] Broke affinity for irq 275 [35293.343193] Broke affinity for irq 276 [35293.343194] Broke affinity for irq 279 [35293.343195] Broke affinity for irq 280 [35293.344204] smpboot: CPU 3 is now offline [35293.347461] ACPI: Low-level resume complete [35293.347597] ACPI : EC: EC started [35293.347598] PM: Restoring platform NVS memory [35293.348340] Suspended for 1912.557 seconds [35293.348401] Enabling non-boot CPUs ... [35293.348486] x86: Booting SMP configuration: [35293.348487] smpboot: Booting Node 0 Processor 1 APIC 0x2 [35293.351449] cache: parent cpu1 should not be sleeping [35293.351749] CPU1 is up [35293.351814] smpboot: Booting Node 0 Processor 2 APIC 0x1 [35293.354808] cache: parent cpu2 should not be sleeping [35293.355111] CPU2 is up [35293.355190] smpboot: Booting Node 0 Processor 3 APIC 0x3 [35293.357980] cache: parent cpu3 should not be sleeping [35293.358294] CPU3 is up [35293.364138] ACPI: Waking up from system sleep state S3 [35293.375365] acpi LNXPOWER:17: Turning OFF [35293.375412] acpi LNXPOWER:16: Turning OFF [35293.375459] acpi LNXPOWER:15: Turning OFF [35293.375506] acpi LNXPOWER:14: Turning OFF [35293.375553] acpi LNXPOWER:13: Turning OFF [35293.375600] acpi LNXPOWER:12: Turning OFF [35293.375647] acpi LNXPOWER:11: Turning OFF [35293.375693] acpi LNXPOWER:10: Turning OFF [35293.375739] acpi LNXPOWER:0f: Turning OFF [35293.375785] acpi LNXPOWER:0e: Turning OFF [35293.375832] acpi LNXPOWER:0d: Turning OFF [35293.375878] acpi LNXPOWER:0c: Turning OFF [35293.375924] acpi LNXPOWER:0b: Turning OFF [35293.375971] acpi LNXPOWER:0a: Turning OFF [35293.376017] acpi LNXPOWER:09: Turning OFF [35293.376064] acpi LNXPOWER:08: Turning OFF [35293.376110] acpi LNXPOWER:07: Turning OFF [35293.376157] acpi LNXPOWER:06: Turning OFF [35293.376204] acpi LNXPOWER:05: Turning OFF [35293.376435] acpi LNXPOWER:04: Turning OFF [35293.439240] acpi LNXPOWER:03: Turning OFF [35293.439661] ACPI : EC: interrupt unblocked [35293.481212] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI [35293.596019] PM: noirq resume of devices complete after 156.653 msecs [35293.596076] pciehp 0000:00:1c.0:pcie004: Slot(1): Card present [35293.596079] pciehp 0000:00:1c.0:pcie004: Slot(1): Link Up [35293.596449] pciehp 0000:00:1c.0:pcie004: Slot(1): Link Up event ignored; already powering on [35293.608668] PM: early resume of devices complete after 12.581 msecs [35293.608989] usb usb1: root hub lost power or was reset [35293.608992] usb usb2: root hub lost power or was reset [35293.609798] sd 0:0:0:0: [sda] Starting disk [35293.609800] sd 2:0:0:0: [sdb] Starting disk [35293.611840] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35293.612704] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35293.668774] [drm] GuC firmware load skipped [35293.703130] pciehp 0000:00:1c.0:pcie004: Device 0000:01:00.0 already exists at 0000:01:00, cannot hot-add [35293.703132] pciehp 0000:00:1c.0:pcie004: Cannot add device at 0000:01:00 [35293.747314] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35293.747697] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35293.811119] pciehp 0000:00:1c.0:pcie004: Device 0000:01:00.0 already exists at 0000:01:00, cannot hot-add [35293.811120] pciehp 0000:00:1c.0:pcie004: Cannot add device at 0000:01:00 [35293.921224] ata3: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [35293.921760] ata3.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [35293.923251] ata3.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [35293.924202] ata3.00: configured for UDMA/133 [35293.955137] usb 1-7: reset full-speed USB device number 2 using xhci_hcd [35294.215116] usb 1-9: reset high-speed USB device number 4 using xhci_hcd [35294.475268] usb 1-8: reset full-speed USB device number 3 using xhci_hcd [35294.923142] ACPI : EC: event unblocked [35294.923782] rtc_cmos 00:03: System wakeup disabled by ACPI [35295.035358] tpm tpm0: A TPM error (7) occurred attempting to read a pcr value [35295.035362] tpm tpm0: TPM is disabled/deactivated (0x7) [35295.039598] ACPI Error: Needed [Buffer/String/Package], found [Integer] ffff884a88be84c8 (20160831/exresop-594) [35295.039603] ACPI Exception: AE_AML_OPERAND_TYPE, While resolving operands for [OpcodeName unavailable] (20160831/dswexec-461) [35295.039616] ACPI Error: Method parse/execution failed [\_SB.WMIV.WVPO] (Node ffff884a908a8988), AE_AML_OPERAND_TYPE (20160831/psparse-543) [35295.039628] ACPI Error: Method parse/execution failed [\_SB.WMIV.WMPV] (Node ffff884a908a87a8), AE_AML_OPERAND_TYPE (20160831/psparse-543) [35295.041262] ACPI Error: Needed [Buffer/String/Package], found [Integer] ffff884a8b7f2168 (20160831/exresop-594) [35295.041266] ACPI Exception: AE_AML_OPERAND_TYPE, While resolving operands for [OpcodeName unavailable] (20160831/dswexec-461) [35295.041276] ACPI Error: Method parse/execution failed [\_SB.WMIV.WVPO] (Node ffff884a908a8988), AE_AML_OPERAND_TYPE (20160831/psparse-543) [35295.041286] ACPI Error: Method parse/execution failed [\_SB.WMIV.WMPV] (Node ffff884a908a87a8), AE_AML_OPERAND_TYPE (20160831/psparse-543) [35295.239313] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [35295.241462] ata1.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [35295.241468] ata1.00: ACPI cmd b1/c1:00:00:00:00:e0 (DEVICE CONFIGURATION OVERLAY) filtered out [35295.244283] ata1.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [35295.244288] ata1.00: ACPI cmd b1/c1:00:00:00:00:e0 (DEVICE CONFIGURATION OVERLAY) filtered out [35295.244754] ata1.00: configured for UDMA/133 [35296.428470] psmouse serio3: synaptics: queried max coordinates: x [..5670], y [..4758] [35296.462715] psmouse serio3: synaptics: queried min coordinates: x [1360..], y [1198..] [35296.516851] xhci_hcd 0000:00:14.0: Cannot set link state. [35296.516967] usb usb2-port4: cannot disable (err = -32) [35296.637218] PM: resume of devices complete after 3028.643 msecs [35296.637569] usb 1-7:1.0: rebind failed: -517 [35296.637576] usb 1-7:1.1: rebind failed: -517 [35296.638266] PM: Finishing wakeup. [35296.638268] Restarting tasks ... [35296.638539] usb 1-4: USB disconnect, device number 7 [35296.638560] usb 1-4.3: USB disconnect, device number 9 [35296.639134] usb 2-4: USB disconnect, device number 2 [35296.639146] usb 2-4.1: USB disconnect, device number 3 [35296.643709] sd 3:0:0:0: [sdc] Synchronizing SCSI cache [35296.643736] sd 3:0:0:0: [sdc] Synchronize Cache(10) failed: Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK [35296.644418] usb 1-4.4: USB disconnect, device number 10 [35296.663553] done. [35296.666942] Bluetooth: hci0: Bootloader revision 0.0 build 2 week 52 2014 [35296.667604] [drm] RC6 on [35296.674178] Bluetooth: hci0: Device revision is 5 [35296.674180] Bluetooth: hci0: Secure boot is enabled [35296.674180] Bluetooth: hci0: OTP lock is enabled [35296.674181] Bluetooth: hci0: API lock is enabled [35296.674181] Bluetooth: hci0: Debug lock is disabled [35296.674183] Bluetooth: hci0: Minimum firmware build 1 week 10 2014 [35296.674379] bluetooth hci0: firmware: direct-loading firmware intel/ibt-11-5.sfi [35296.674406] Bluetooth: hci0: Found device firmware: intel/ibt-11-5.sfi [35296.978699] e1000e: eth0 NIC Link is Down [35297.148281] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro,data=ordered,commit=600 [35297.220231] EXT4-fs (sda1): re-mounted. Opts: block_validity,barrier,user_xattr,acl,stripe=4 [35297.447190] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [35297.734953] EXT4-fs (dm-1): re-mounted. Opts: data=ordered,commit=600 [35297.835256] e1000e: eth0 NIC Link is Down [35298.051202] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [35298.061747] Bluetooth: hci0: Waiting for firmware download to complete [35298.062482] Bluetooth: hci0: Firmware loaded in 1370599 usecs [35298.062521] Bluetooth: hci0: Waiting for device to boot [35298.073503] Bluetooth: hci0: Device booted in 10737 usecs [35298.073912] bluetooth hci0: firmware: direct-loading firmware intel/ibt-11-5.ddc [35298.073915] Bluetooth: hci0: Found Intel DDC parameters: intel/ibt-11-5.ddc [35298.074485] Bluetooth: hci0: Failed to send Intel_Write_DDC (-22) [35301.766932] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35301.767740] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35301.905726] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35301.906128] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35302.002056] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [35302.253370] e1000e: eth0 NIC Link is Down [35302.503997] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [35302.658223] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35302.659001] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35302.787670] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35302.788070] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35302.884615] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [35303.660034] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35303.660808] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35303.790251] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35303.790651] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35303.888394] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [35304.096242] e1000e: eth0 NIC Link is Down [35304.315586] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [35305.207057] wlp2s0: authenticate with 40:65:a3:15:37:9e [35305.218146] wlp2s0: send auth to 40:65:a3:15:37:9e (try 1/3) [35305.225934] wlp2s0: authenticated [35305.226820] wlp2s0: associate with 40:65:a3:15:37:9e (try 1/3) [35305.230600] wlp2s0: RX AssocResp from 40:65:a3:15:37:9e (capab=0x1411 status=0 aid=2) [35305.234506] wlp2s0: associated [35305.234598] IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0: link becomes ready [35785.842843] PM: Syncing filesystems ... done. [35786.499609] PM: Preparing system for sleep (mem) [35786.500248] (NULL device *): firmware: direct-loading firmware intel/ibt-11-5.ddc [35786.500534] (NULL device *): firmware: direct-loading firmware intel/ibt-11-5.sfi [35786.501331] (NULL device *): firmware: direct-loading firmware iwlwifi-8000C-22.ucode [35786.501434] Freezing user space processes ... (elapsed 0.002 seconds) done. [35786.503528] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done. [35786.504876] PM: Suspending system (mem) [35786.504946] Suspending console(s) (use no_console_suspend to debug) [35786.614143] wlp2s0: deauthenticating from 40:65:a3:15:37:9e by local choice (Reason: 3=DEAUTH_LEAVING) [35786.614270] sd 2:0:0:0: [sdb] Synchronizing SCSI cache [35786.615078] sd 0:0:0:0: [sda] Synchronizing SCSI cache [35786.621443] sd 2:0:0:0: [sdb] Stopping disk [35786.648596] sd 0:0:0:0: [sda] Stopping disk [35786.741182] ACPI : EC: event blocked [35787.208553] PM: suspend of devices complete after 595.057 msecs [35787.233358] PM: late suspend of devices complete after 24.796 msecs [35787.234164] ACPI : EC: interrupt blocked [35787.236718] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI [35787.317503] PM: noirq suspend of devices complete after 84.138 msecs [35787.318430] ACPI: Preparing to enter system sleep state S3 [35787.320357] ACPI : EC: EC stopped [35787.320358] PM: Saving platform NVS memory [35787.320389] Disabling non-boot CPUs ... [35787.320890] Broke affinity for irq 276 [35787.321935] smpboot: CPU 1 is now offline [35787.323010] Broke affinity for irq 272 [35787.323017] Broke affinity for irq 275 [35787.323019] Broke affinity for irq 276 [35787.323022] Broke affinity for irq 279 [35787.323025] Broke affinity for irq 281 [35787.324049] smpboot: CPU 2 is now offline [35787.324812] Broke affinity for irq 1 [35787.324829] Broke affinity for irq 9 [35787.324837] Broke affinity for irq 12 [35787.324855] Broke affinity for irq 16 [35787.324889] Broke affinity for irq 272 [35787.324891] Broke affinity for irq 275 [35787.324892] Broke affinity for irq 276 [35787.324893] Broke affinity for irq 279 [35787.324894] Broke affinity for irq 280 [35787.324895] Broke affinity for irq 281 [35787.325901] smpboot: CPU 3 is now offline [35787.329158] ACPI: Low-level resume complete [35787.329293] ACPI : EC: EC started [35787.329294] PM: Restoring platform NVS memory [35787.330036] Suspended for 9.666 seconds [35787.330097] Enabling non-boot CPUs ... [35787.330183] x86: Booting SMP configuration: [35787.330184] smpboot: Booting Node 0 Processor 1 APIC 0x2 [35787.333150] cache: parent cpu1 should not be sleeping [35787.333447] CPU1 is up [35787.333511] smpboot: Booting Node 0 Processor 2 APIC 0x1 [35787.336520] cache: parent cpu2 should not be sleeping [35787.336825] CPU2 is up [35787.336891] smpboot: Booting Node 0 Processor 3 APIC 0x3 [35787.339666] cache: parent cpu3 should not be sleeping [35787.339973] CPU3 is up [35787.345820] ACPI: Waking up from system sleep state S3 [35787.357373] acpi LNXPOWER:17: Turning OFF [35787.357425] acpi LNXPOWER:16: Turning OFF [35787.357475] acpi LNXPOWER:15: Turning OFF [35787.357525] acpi LNXPOWER:14: Turning OFF [35787.357576] acpi LNXPOWER:13: Turning OFF [35787.357626] acpi LNXPOWER:12: Turning OFF [35787.357676] acpi LNXPOWER:11: Turning OFF [35787.357725] acpi LNXPOWER:10: Turning OFF [35787.357774] acpi LNXPOWER:0f: Turning OFF [35787.357824] acpi LNXPOWER:0e: Turning OFF [35787.357873] acpi LNXPOWER:0d: Turning OFF [35787.357922] acpi LNXPOWER:0c: Turning OFF [35787.357972] acpi LNXPOWER:0b: Turning OFF [35787.358021] acpi LNXPOWER:0a: Turning OFF [35787.358071] acpi LNXPOWER:09: Turning OFF [35787.358120] acpi LNXPOWER:08: Turning OFF [35787.358170] acpi LNXPOWER:07: Turning OFF [35787.358219] acpi LNXPOWER:06: Turning OFF [35787.358269] acpi LNXPOWER:05: Turning OFF [35787.358524] acpi LNXPOWER:04: Turning OFF [35787.420669] acpi LNXPOWER:03: Turning OFF [35787.421097] ACPI : EC: interrupt unblocked [35787.462456] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI [35787.577419] PM: noirq resume of devices complete after 156.618 msecs [35787.577510] pciehp 0000:00:1c.0:pcie004: Slot(1): Card present [35787.577513] pciehp 0000:00:1c.0:pcie004: Slot(1): Link Up [35787.580846] pciehp 0000:00:1c.0:pcie004: Slot(1): Link Up event ignored; already powering on [35787.590155] PM: early resume of devices complete after 12.613 msecs [35787.590466] usb usb1: root hub lost power or was reset [35787.590469] usb usb2: root hub lost power or was reset [35787.591376] sd 0:0:0:0: [sda] Starting disk [35787.591378] sd 2:0:0:0: [sdb] Starting disk [35787.593503] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35787.594229] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35787.649674] [drm] GuC firmware load skipped [35787.688588] pciehp 0000:00:1c.0:pcie004: Device 0000:01:00.0 already exists at 0000:01:00, cannot hot-add [35787.688593] pciehp 0000:00:1c.0:pcie004: Cannot add device at 0000:01:00 [35787.721960] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35787.722346] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35787.796627] pciehp 0000:00:1c.0:pcie004: Device 0000:01:00.0 already exists at 0000:01:00, cannot hot-add [35787.796630] pciehp 0000:00:1c.0:pcie004: Cannot add device at 0000:01:00 [35787.902922] ata3: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [35787.903663] ata3.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [35787.905349] ata3.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [35787.906263] ata3.00: configured for UDMA/133 [35787.937064] usb 1-7: reset full-speed USB device number 2 using xhci_hcd [35788.197113] usb 1-8: reset full-speed USB device number 3 using xhci_hcd [35788.456819] usb 1-9: reset high-speed USB device number 4 using xhci_hcd [35788.872954] ACPI : EC: event unblocked [35788.873506] rtc_cmos 00:03: System wakeup disabled by ACPI [35788.984914] tpm tpm0: A TPM error (7) occurred attempting to read a pcr value [35788.984917] tpm tpm0: TPM is disabled/deactivated (0x7) [35788.988352] ACPI Error: Needed [Buffer/String/Package], found [Integer] ffff884a88851bd0 (20160831/exresop-594) [35788.988358] ACPI Exception: AE_AML_OPERAND_TYPE, While resolving operands for [OpcodeName unavailable] (20160831/dswexec-461) [35788.988370] ACPI Error: Method parse/execution failed [\_SB.WMIV.WVPO] (Node ffff884a908a8988), AE_AML_OPERAND_TYPE (20160831/psparse-543) [35788.988382] ACPI Error: Method parse/execution failed [\_SB.WMIV.WMPV] (Node ffff884a908a87a8), AE_AML_OPERAND_TYPE (20160831/psparse-543) [35788.990081] ACPI Error: Needed [Buffer/String/Package], found [Integer] ffff884a8b7f2240 (20160831/exresop-594) [35788.990082] ACPI Exception: AE_AML_OPERAND_TYPE, While resolving operands for [OpcodeName unavailable] (20160831/dswexec-461) [35788.990086] ACPI Error: Method parse/execution failed [\_SB.WMIV.WVPO] (Node ffff884a908a8988), AE_AML_OPERAND_TYPE (20160831/psparse-543) [35788.990089] ACPI Error: Method parse/execution failed [\_SB.WMIV.WMPV] (Node ffff884a908a87a8), AE_AML_OPERAND_TYPE (20160831/psparse-543) [35788.990269] PM: resume of devices complete after 1400.098 msecs [35788.990437] usb 1-7:1.0: rebind failed: -517 [35788.990439] usb 1-7:1.1: rebind failed: -517 [35788.990688] PM: Finishing wakeup. [35788.990689] Restarting tasks ... done. [35789.007957] Bluetooth: hci0: Bootloader revision 0.0 build 2 week 52 2014 [35789.014226] Bluetooth: hci0: Device revision is 5 [35789.014227] Bluetooth: hci0: Secure boot is enabled [35789.014228] Bluetooth: hci0: OTP lock is enabled [35789.014229] Bluetooth: hci0: API lock is enabled [35789.014230] Bluetooth: hci0: Debug lock is disabled [35789.014231] Bluetooth: hci0: Minimum firmware build 1 week 10 2014 [35789.014234] Bluetooth: hci0: Found device firmware: intel/ibt-11-5.sfi [35789.018500] [drm] RC6 on [35789.114500] NMI watchdog: enabled on all CPUs, permanently consumes one hw-PMU counter. [35789.130549] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro,data=ordered,commit=0 [35789.228582] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [35789.230077] ata1.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [35789.230079] ata1.00: ACPI cmd b1/c1:00:00:00:00:e0 (DEVICE CONFIGURATION OVERLAY) filtered out [35789.231871] ata1.00: ACPI cmd f5/00:00:00:00:00:e0 (SECURITY FREEZE LOCK) filtered out [35789.231872] ata1.00: ACPI cmd b1/c1:00:00:00:00:e0 (DEVICE CONFIGURATION OVERLAY) filtered out [35789.232072] ata1.00: configured for UDMA/133 [35790.285558] psmouse serio3: synaptics: queried max coordinates: x [..5670], y [..4758] [35790.315687] psmouse serio3: synaptics: queried min coordinates: x [1360..], y [1198..] [35790.429835] Bluetooth: hci0: Waiting for firmware download to complete [35790.430253] Bluetooth: hci0: Firmware loaded in 1390769 usecs [35790.430291] Bluetooth: hci0: Waiting for device to boot [35790.441260] Bluetooth: hci0: Device booted in 10741 usecs [35790.441266] Bluetooth: hci0: Found Intel DDC parameters: intel/ibt-11-5.ddc [35790.442286] Bluetooth: hci0: Failed to send Intel_Write_DDC (-22) [35791.012752] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35791.013477] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35791.141596] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35791.141996] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35791.225891] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [35791.431531] e1000e: eth0 NIC Link is Down [35791.653317] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [35794.900788] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35794.901520] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35795.030232] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35795.030641] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35795.119078] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [35798.816868] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35798.817595] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35798.945872] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35798.946276] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35799.036479] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [35799.381162] e1000e: eth0 NIC Link is Down [35799.601416] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [35799.751296] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35799.752021] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35799.880398] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35799.880811] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled [35799.969593] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready [35802.311548] wlp2s0: authenticate with 40:65:a3:15:37:9e [35802.320175] wlp2s0: send auth to 40:65:a3:15:37:9e (try 1/3) [35802.327852] wlp2s0: authenticated [35802.328773] wlp2s0: associate with 40:65:a3:15:37:9e (try 1/3) [35802.334388] wlp2s0: RX AssocResp from 40:65:a3:15:37:9e (capab=0x1411 status=0 aid=2) [35802.336253] wlp2s0: associated [35802.336351] IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0: link becomes ready [35803.071297] PM: Hibernation mode set to 'platform' [35803.073307] (NULL device *): firmware: direct-loading firmware iwlwifi-8000C-22.ucode [35803.073322] PM: Syncing filesystems ... [35995.976169] usb 1-1: new high-speed USB device number 18 using xhci_hcd [35996.117136] usb 1-1: New USB device found, idVendor=8644, idProduct=800f [35996.117143] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [35996.117148] usb 1-1: Product: USB Flash Disk [35996.117151] usb 1-1: Manufacturer: General [35996.117155] usb 1-1: SerialNumber: 0340100000043239 [35996.118141] usb-storage 1-1:1.0: USB Mass Storage device detected [35996.118434] scsi host3: usb-storage 1-1:1.0 [35997.148869] scsi 3:0:0:0: Direct-Access General USB Flash Disk 1.0 PQ: 0 ANSI: 2 [35997.150323] sd 3:0:0:0: Attached scsi generic sg2 type 0 [35997.150668] sd 3:0:0:0: [sdc] 7831552 512-byte logical blocks: (4.01 GB/3.73 GiB) [35997.150945] sd 3:0:0:0: [sdc] Write Protect is off [35997.150951] sd 3:0:0:0: [sdc] Mode Sense: 03 00 00 00 [35997.151195] sd 3:0:0:0: [sdc] No Caching mode page found [35997.151206] sd 3:0:0:0: [sdc] Assuming drive cache: write through [35997.157652] sdc: sdc1 [35997.159049] sd 3:0:0:0: [sdc] Attached SCSI removable disk [36001.958742] UDF-fs: warning (device sdc1): udf_load_vrs: No VRS found [36001.958743] UDF-fs: warning (device sdc1): udf_fill_super: No partition found (2) [36001.960183] UDF-fs: warning (device sdc1): udf_load_vrs: No VRS found [36001.960184] UDF-fs: warning (device sdc1): udf_fill_super: No partition found (2) [36001.983762] ISOFS: Unable to identify CD-ROM format. [36002.012370] ISOFS: Unable to identify CD-ROM format. [36002.019980] FAT-fs (sdc1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. [36007.772226] INFO: task mount:9623 blocked for more than 120 seconds. [36007.772242] Tainted: P O 4.9.0-3-amd64 #1 [36007.772246] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [36007.772252] mount D 0 9623 9447 0x00000000 [36007.772265] ffff884910117000 ffff884a8c748000 ffff884a7c28d000 ffff884ab1598240 [36007.772275] ffff884a7fb29000 ffffa24f8c93bbc0 ffffffffb69ff483 00000000ffffff87 [36007.772284] 00000000fffffffb ffff884ab1598240 ffff884a87d02000 ffff884a7c28d000 [36007.772292] Call Trace: [36007.772309] [<ffffffffb69ff483>] ? __schedule+0x233/0x6d0 [36007.772317] [<ffffffffb6a000f0>] ? bit_wait+0x50/0x50 [36007.772322] [<ffffffffb69ff952>] ? schedule+0x32/0x80 [36007.772329] [<ffffffffb6a02d23>] ? schedule_timeout+0x243/0x310 [36007.772336] [<ffffffffb66f7b7f>] ? __blk_run_queue+0x2f/0x40 [36007.772344] [<ffffffffb64ec0dc>] ? ktime_get+0x3c/0xb0 [36007.772349] [<ffffffffb6a000f0>] ? bit_wait+0x50/0x50 [36007.772356] [<ffffffffb69ff1ed>] ? io_schedule_timeout+0x9d/0x100 [36007.772362] [<ffffffffb64b8977>] ? prepare_to_wait+0x57/0x80 [36007.772368] [<ffffffffb6a00107>] ? bit_wait_io+0x17/0x60 [36007.772373] [<ffffffffb69ffcd3>] ? __wait_on_bit+0x53/0x80 [36007.772379] [<ffffffffb6a000f0>] ? bit_wait+0x50/0x50 [36007.772385] [<ffffffffb69ffe2e>] ? out_of_line_wait_on_bit+0x7e/0xa0 [36007.772391] [<ffffffffb64b8de0>] ? wake_atomic_t_function+0x60/0x60 [36007.772399] [<ffffffffb663c896>] ? __sync_dirty_buffer+0xa6/0xf0 [36007.772451] [<ffffffffc077cb6d>] ? ext4_commit_super+0x20d/0x2b0 [ext4] [36007.772496] [<ffffffffc07800cf>] ? ext4_remount+0x59f/0x710 [ext4] [36007.772504] [<ffffffffb661ac9d>] ? shrink_dcache_sb+0xcd/0x110 [36007.772514] [<ffffffffb6605c97>] ? do_remount_sb+0x67/0x1f0 [36007.772522] [<ffffffffb6625cef>] ? do_mount+0x6ff/0xc80 [36007.772529] [<ffffffffb662659e>] ? SyS_mount+0x7e/0xd0 [36007.772538] [<ffffffffb6a0413b>] ? system_call_fast_compare_end+0xc/0x9b -- Dr. Dindi Dad, Philosopher, Hacker