Attachment 499251 Details for Bug 772231

dmesg output

dmesg.txt (text/plain), 65.20 KB, created by Forgotten User l3FSILr33W on 2012-07-19 13:59:58 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Forgotten User l3FSILr33W

Created: 2012-07-19 13:59:58 UTC

Size: 65.20 KB

patch

obsolete

>[    1.870264] NET: Registered protocol family 10
>[    1.871607] lib80211: common routines for IEEE802.11 drivers
>[    1.871614] lib80211_crypt: registered algorithm 'NULL'
>[    1.871619] Registering the dns_resolver key type
>[    1.871669] libceph: loaded (mon/osd proto 15/24, osdmap 5/6 5/6)
>[    1.871695] Using IPI No-Shortcut mode
>[    1.872130] PM: Checking hibernation image partition /dev/disk/by-id/ata-Hitachi_HTS543216L9A300_090425FB2200VCF6KT6A-part1
>[    2.048056] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>[    2.048088] ata4: SATA link down (SStatus 0 SControl 300)
>[    2.048128] ata1: SATA link down (SStatus 0 SControl 300)
>[    2.048154] ata3: SATA link down (SStatus 0 SControl 300)
>[    2.048948] ata2.00: ATA-8: Hitachi HTS543216L9A300, FB2OC40C, max UDMA/133
>[    2.048957] ata2.00: 312581808 sectors, multi 16: LBA48 NCQ (depth 31/32)
>[    2.050037] ata2.00: configured for UDMA/133
>[    2.050336] scsi 1:0:0:0: Direct-Access     ATA      Hitachi HTS54321 FB2O PQ: 0 ANSI: 5
>[    2.050775] sd 1:0:0:0: [sda] 312581808 512-byte logical blocks: (160 GB/149 GiB)
>[    2.051114] sd 1:0:0:0: [sda] Write Protect is off
>[    2.051122] sd 1:0:0:0: [sda] Mode Sense: 00 3a 00 00
>[    2.051237] sd 1:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
>[    2.081369]  sda: sda1 sda2
>[    2.082336] sd 1:0:0:0: [sda] Attached SCSI disk
>[    2.082375] PM: Hibernation image not present or could not be loaded.
>[    2.082423] registered taskstats version 1
>[    2.098783]   Magic number: 4:113:75
>[    2.099044] rtc_cmos 00:06: setting system clock to 2012-07-19 11:04:30 UTC (1342695870)
>[    2.099408] Freeing unused kernel memory: 488k freed
>[    2.099821] Write protecting the kernel text: 5204k
>[    2.099930] Write protecting the kernel read-only data: 3536k
>[    2.099936] NX-protecting the kernel data: 5036k
>[    2.205102] usb 2-3: new high speed USB device number 3 using ehci_hcd
>[    2.238814] udevd[107]: starting version 173
>[    2.297573] ACPI: acpi_idle yielding to intel_idle
>[    2.328827] usb 2-3: New USB device found, idVendor=05ac, idProduct=1006
>[    2.328840] usb 2-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
>[    2.328849] usb 2-3: Product: Keyboard Hub
>[    2.328856] usb 2-3: Manufacturer: Apple, Inc.
>[    2.328863] usb 2-3: SerialNumber: 000000000000
>[    2.329561] hub 2-3:1.0: USB hub found
>[    2.329662] hub 2-3:1.0: 3 ports detected
>[    2.591071] usb 4-1: new low speed USB device number 2 using ohci_hcd
>[    2.879082] usb 4-1: New USB device found, idVendor=051d, idProduct=0002
>[    2.879092] usb 4-1: New USB device strings: Mfr=3, Product=1, SerialNumber=2
>[    2.879099] usb 4-1: Product: Back-UPS CS 650 FW:817.v4.I USB FW:v4
>[    2.879105] usb 4-1: Manufacturer: American Power Conversion
>[    2.879110] usb 4-1: SerialNumber: 3B0833X21870  
>[    4.419580] generic-usb 0003:051D:0002.0001: hiddev0,hidraw0: USB HID v1.10 Device [American Power Conversion Back-UPS CS 650 FW:817.v4.I USB FW:v4] on usb-0000:00:06.0-1/input0
>[    4.623643] PM: Marking nosave pages: 000000000009f000 - 0000000000100000
>[    4.623655] PM: Basic memory bitmaps created
>[    4.680101] usb 3-2: new full speed USB device number 2 using ohci_hcd
>[    4.701663] PM: Basic memory bitmaps freed
>[    4.771153] PM: Starting manual resume from disk
>[    4.771162] PM: Hibernation image partition 8:1 present
>[    4.771166] PM: Looking for hibernation image.
>[    4.771467] PM: Image not found (code -22)
>[    4.771472] PM: Hibernation image not present or could not be loaded.
>[    4.880103] usb 3-2: New USB device found, idVendor=07d1, idProduct=f101
>[    4.880113] usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
>[    4.880120] usb 3-2: Product: D-Link DBT-122
>[    4.880125] usb 3-2: Manufacturer: Broadcom Corp
>[    4.946819] usb 2-3.2: new low speed USB device number 4 using ehci_hcd
>[    5.042170] usb 2-3.2: New USB device found, idVendor=05ac, idProduct=0221
>[    5.042179] usb 2-3.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
>[    5.042186] usb 2-3.2: Product: Apple Keyboard
>[    5.042191] usb 2-3.2: Manufacturer: Apple, Inc
>[    5.047720] input: Apple, Inc Apple Keyboard as /devices/pci0000:00/0000:00:06.1/usb2/2-3/2-3.2/2-3.2:1.0/input/input0
>[    5.048199] apple 0003:05AC:0221.0002: input,hidraw1: USB HID v1.11 Keyboard [Apple, Inc Apple Keyboard] on usb-0000:00:06.1-3.2/input0
>[    5.052586] input: Apple, Inc Apple Keyboard as /devices/pci0000:00/0000:00:06.1/usb2/2-3/2-3.2/2-3.2:1.1/input/input1
>[    5.052980] apple 0003:05AC:0221.0003: input,hidraw2: USB HID v1.11 Device [Apple, Inc Apple Keyboard] on usb-0000:00:06.1-3.2/input1
>[   11.587078] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: acl,user_xattr
>[   11.788671] EXT4-fs (sda2): re-mounted. Opts: acl,user_xattr
>[   12.481577] systemd[1]: RTC configured in localtime, applying delta of 240 minutes to system time.
>[   12.681185] systemd[1]: systemd 37 running in system mode. (+PAM +LIBWRAP +AUDIT +SELINUX +SYSVINIT +LIBCRYPTSETUP; suse)
>[   13.204169] systemd[1]: Set hostname to <linux-mkzk.site>.
>[   15.897386] systemd-readahead-replay[343]: Bumped block_nr parameter of 8:0 to 16384. This is a temporary hack and should be removed one day.
>[   17.218304] boot.localnet[367]: Using boot-specified hostname 'linux-mkzk.site'
>[   17.480440] udevd[359]: starting version 173
>[   17.684599] boot.localnet[367]: Setting up hostname 'linux-mkzk'..done
>[   18.126215] boot.localnet[367]: Setting up loopback interface RTNETLINK answers: File exists
>[   18.129721] boot.localnet[367]: ..done
>[   18.961065] EXT4-fs (sda2): re-mounted. Opts: acl,user_xattr
>[   20.274828] BIOS EDD facility v0.16 2004-Jun-25, 1 devices found
>[   22.739846] type=1400 audit(1342681491.140:2): apparmor="STATUS" operation="profile_load" name="/bin/ping" pid=440 comm="apparmor_parser"
>[   23.410952] sd 1:0:0:0: Attached scsi generic sg0 type 0
>[   23.434126] input: Power Button as /devices/LNXSYSTM:00/device:00/PNP0C0C:00/input/input2
>[   23.434357] ACPI: Power Button [PWRB]
>[   23.434610] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
>[   23.434789] ACPI: Power Button [PWRF]
>[   23.457106] input: PC Speaker as /devices/platform/pcspkr/input/input4
>[   23.475919] type=1400 audit(1342681491.875:3): apparmor="STATUS" operation="profile_load" name="/home/rafis/Downloads/usr.bin.bin" pid=597 comm="apparmor_parser"
>[   23.593588] wmi: Mapper loaded
>[   23.756883] type=1400 audit(1342681492.156:4): apparmor="STATUS" operation="profile_load" name="/sbin/klogd" pid=640 comm="apparmor_parser"
>[   24.170602] r8169 Gigabit Ethernet driver 2.3LK-NAPI loaded
>[   24.171335] ACPI: PCI Interrupt Link [LN3A] enabled at IRQ 19
>[   24.171370] r8169 0000:04:00.0: PCI INT A -> Link[LN3A] -> GSI 19 (level, low) -> IRQ 19
>[   24.171420] r8169 0000:04:00.0: setting latency timer to 64
>[   24.171485] r8169 0000:04:00.0: irq 41 for MSI/MSI-X
>[   24.172659] r8169 0000:04:00.0: eth0: RTL8168d/8111d at 0xf7a1c000, 20:cf:30:c2:fe:45, XID 083000c0 IRQ 41
>[   24.364136] ACPI: resource nForce2_smbus [io  0x4d00-0x4d3f] conflicts with ACPI region SM00 [io 0x4d00-0x4d3f]
>[   24.364146] ACPI: If an ACPI driver is available for this device, you should use it instead of the native driver
>[   24.364298] i2c i2c-0: nForce2 SMBus adapter at 0x4e00
>[   24.379366] ifup[661]: Service network not started and mode 'auto' -> skipping
>[   24.851492] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
>[   24.853726] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
>[   24.880736] Bluetooth: Core ver 2.16
>[   24.880806] NET: Registered protocol family 31
>[   24.880811] Bluetooth: HCI device and connection manager initialized
>[   24.880818] Bluetooth: HCI socket layer initialized
>[   24.880824] Bluetooth: L2CAP socket layer initialized
>[   24.880839] Bluetooth: SCO socket layer initialized
>[   24.882164] Bluetooth: Generic Bluetooth USB driver ver 0.6
>[   25.121792] usbcore: registered new interface driver btusb
>[   25.245617] type=1400 audit(1342681493.645:5): apparmor="STATUS" operation="profile_load" name="/sbin/syslog-ng" pid=653 comm="apparmor_parser"
>[   25.713742] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[   25.713764] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[   25.714691] ACPI: PCI Interrupt Link [LAZA] enabled at IRQ 20
>[   25.714707] snd_hda_intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 20 (level, low) -> IRQ 20
>[   25.714717] hda_intel: Disabling MSI
>[   25.714781] snd_hda_intel 0000:00:08.0: setting latency timer to 64
>[   25.722211] ALSA hda_intel.c:1454 Enable delay in RIRB handling
>[   26.037378] type=1400 audit(1342681494.437:6): apparmor="STATUS" operation="profile_load" name="/sbin/syslogd" pid=679 comm="apparmor_parser"
>[   26.303045] hda_codec: ALC887: BIOS auto-probing.
>[   26.303059] ALSA patch_realtek.c:1228 SKU: Nid=0x1d sku_cfg=0x4004c601
>[   26.303066] ALSA patch_realtek.c:1230 SKU: port_connectivity=0x1
>[   26.303071] ALSA patch_realtek.c:1231 SKU: enable_pcbeep=0x0
>[   26.303077] ALSA patch_realtek.c:1232 SKU: check_sum=0x00000004
>[   26.303083] ALSA patch_realtek.c:1233 SKU: customization=0x000000c6
>[   26.303089] ALSA patch_realtek.c:1234 SKU: external_amp=0x0
>[   26.303095] ALSA patch_realtek.c:1235 SKU: platform_type=0x0
>[   26.303100] ALSA patch_realtek.c:1236 SKU: swap=0x0
>[   26.303106] ALSA patch_realtek.c:1237 SKU: override=0x1
>[   26.303119] ALSA hda_codec.c:4895 autoconfig: line_outs=1 (0x14/0x0/0x0/0x0/0x0) type:line
>[   26.303126] ALSA hda_codec.c:4899    speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
>[   26.303134] ALSA hda_codec.c:4903    hp_outs=1 (0x1b/0x0/0x0/0x0/0x0)
>[   26.303139] ALSA hda_codec.c:4904    mono: mono_out=0x0
>[   26.303145] ALSA hda_codec.c:4907    dig-out=0x1e/0x0
>[   26.303150] ALSA hda_codec.c:4908    inputs:
>[   26.303157] ALSA hda_codec.c:4912  Rear Mic=0x18
>[   26.303163] ALSA hda_codec.c:4912  Front Mic=0x19
>[   26.303169] ALSA hda_codec.c:4912  Line=0x1a
>[   26.303174] ALSA hda_codec.c:4914 
>[   26.450057] ALSA patch_realtek.c:1291 realtek: No valid SSID, checking pincfg 0x4004c601 for NID 0x1d
>[   26.450067] ALSA patch_realtek.c:1307 realtek: Enabling init ASM_ID=0xc601 CODEC_ID=10ec0887
>[   26.692246] input: HDA NVidia Headphone as /devices/pci0000:00/0000:00:08.0/sound/card0/input5
>[   26.711940] type=1400 audit(1342681495.111:7): apparmor="STATUS" operation="profile_load" name="/usr/lib/apache2/mpm-prefork/apache2" pid=689 comm="apparmor_parser"
>[   26.714003] type=1400 audit(1342681495.113:8): apparmor="STATUS" operation="profile_load" name="/usr/lib/apache2/mpm-prefork/apache2//DEFAULT_URI" pid=689 comm="apparmor_parser"
>[   26.715884] type=1400 audit(1342681495.115:9): apparmor="STATUS" operation="profile_load" name="/usr/lib/apache2/mpm-prefork/apache2//HANDLING_UNTRUSTED_INPUT" pid=689 comm="apparmor_parser"
>[   26.717518] type=1400 audit(1342681495.117:10): apparmor="STATUS" operation="profile_load" name="/usr/lib/apache2/mpm-prefork/apache2//phpsysinfo" pid=689 comm="apparmor_parser"
>[   27.210120] type=1400 audit(1342681495.609:11): apparmor="STATUS" operation="profile_load" name="/usr/lib/dovecot/deliver" pid=710 comm="apparmor_parser"
>[   27.951194] Adding 2103292k swap on /dev/sda1.  Priority:0 extents:1 across:2103292k 
>[   28.021058] nvidia: module license 'NVIDIA' taints kernel.
>[   28.021080] nvidia: module license 'NVIDIA' taints kernel.
>[   28.021096] Disabling lock debugging due to kernel taint
>[   28.514709] audit_printk_skb: 3 callbacks suppressed
>[   28.514719] type=1400 audit(1342681496.914:13): apparmor="STATUS" operation="profile_load" name="/usr/lib/dovecot/imap" pid=726 comm="apparmor_parser"
>[   28.785661] type=1400 audit(1342681497.185:14): apparmor="STATUS" operation="profile_load" name="/usr/lib/dovecot/imap-login" pid=730 comm="apparmor_parser"
>[   28.875428] type=1400 audit(1342681497.275:15): apparmor="STATUS" operation="profile_load" name="/usr/lib/dovecot/managesieve-login" pid=734 comm="apparmor_parser"
>[   29.065980] type=1400 audit(1342681497.465:16): apparmor="STATUS" operation="profile_load" name="/usr/lib/dovecot/pop3" pid=738 comm="apparmor_parser"
>[   29.151804] ACPI: PCI Interrupt Link [LPMU] enabled at IRQ 23
>[   29.151820] nvidia 0000:00:03.5: PCI INT B -> Link[LPMU] -> GSI 23 (level, low) -> IRQ 23
>[   29.152478] ACPI: PCI Interrupt Link [SGRU] enabled at IRQ 22
>[   29.152489] nvidia 0000:03:00.0: PCI INT A -> Link[SGRU] -> GSI 22 (level, low) -> IRQ 22
>[   29.152505] nvidia 0000:03:00.0: setting latency timer to 64
>[   29.152515] vgaarb: device changed decodes: PCI:0000:03:00.0,olddecodes=io+mem,decodes=none:owns=io+mem
>[   29.153883] NVRM: loading NVIDIA UNIX x86 Kernel Module  295.49  Mon Apr 30 23:30:07 PDT 2012
>[   29.280585] type=1400 audit(1342681497.681:17): apparmor="STATUS" operation="profile_load" name="/usr/lib/dovecot/pop3-login" pid=742 comm="apparmor_parser"
>[   29.404083] type=1400 audit(1342681497.804:18): apparmor="STATUS" operation="profile_load" name="/usr/sbin/avahi-daemon" pid=746 comm="apparmor_parser"
>[   29.602616] type=1400 audit(1342681498.001:19): apparmor="STATUS" operation="profile_load" name="/usr/sbin/dnsmasq" pid=750 comm="apparmor_parser"
>[   29.876698] type=1400 audit(1342681498.277:20): apparmor="STATUS" operation="profile_load" name="/usr/sbin/dovecot" pid=754 comm="apparmor_parser"
>[   30.038378] type=1400 audit(1342681498.438:21): apparmor="STATUS" operation="profile_load" name="/usr/sbin/identd" pid=758 comm="apparmor_parser"
>[   30.255463] type=1400 audit(1342681498.655:22): apparmor="STATUS" operation="profile_load" name="/usr/sbin/mdnsd" pid=762 comm="apparmor_parser"
>[   31.878345] boot.apparmor[417]: Starting AppArmor ..done
>[   32.034930] microcode: CPU0 sig=0x106c2, pf=0x8, revision=0x219
>[   32.244458] microcode: CPU1 sig=0x106c2, pf=0x8, revision=0x219
>[   32.249482] microcode: CPU2 sig=0x106c2, pf=0x8, revision=0x219
>[   32.254463] microcode: CPU3 sig=0x106c2, pf=0x8, revision=0x219
>[   32.259681] microcode: Microcode Update Driver: v2.00 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
>[   32.280169] powernow: This module only works with AMD K7 CPUs
>[   32.317526] rc.cpufreq[833]: CPU frequency scaling is not supported by your processor.
>[   32.320390] rc.cpufreq[834]: boot with 'CPUFREQ=no' in to avoid this warning.
>[   32.325497] cpufreq[800]: Loading CPUFreq modules - hardware support not available..skipped
>[   32.377177] microcode.ctl[790]: Checking/updating CPU microcode ..done
>[   33.015631] systemd[1]: NetworkManager.service: control process exited, code=exited status=1
>[   33.031000] systemd[1]: Job NetworkManager-wait-online.service/start failed with result 'dependency'.
>[   33.031140] systemd[1]: Unit NetworkManager.service entered failed state.
>[   33.122865] ip6_tables: (C) 2000-2006 Netfilter Core Team
>[   33.254749] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
>[   33.265000] ip_tables: (C) 2000-2006 Netfilter Core Team
>[   35.849195] r8169 0000:04:00.0: eth0: link down
>[   35.849210] r8169 0000:04:00.0: eth0: link down
>[   35.849674] ADDRCONF(NETDEV_UP): eth0: link is not ready
>[   36.606409] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
>[   36.606417] Bluetooth: BNEP filters: protocol multicast
>[   36.688567] Bluetooth: RFCOMM TTY layer initialized
>[   36.688600] Bluetooth: RFCOMM socket layer initialized
>[   36.688608] Bluetooth: RFCOMM ver 1.11
>[   37.756801] r8169 0000:04:00.0: eth0: link up
>[   37.757362] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
>[   40.611533] bootsplash: status on console 0 changed to on
>[   48.594027] eth0: no IPv6 routers present
>[   49.720359] EXT4-fs (sda2): re-mounted. Opts: acl,user_xattr,commit=0
>[   52.301075] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
>[  100.692734] process `skype' is using obsolete setsockopt SO_BSDCOMPAT
>[  114.950758] input: Apple Wireless Mouse as /devices/pci0000:00/0000:00:04.0/usb3/3-2/3-2:1.0/bluetooth/hci0/hci0:11/input6
>[  114.952733] magicmouse 0005:05AC:030D.0004: input,hidraw3: BLUETOOTH HID v0.84 Mouse [Apple Wireless Mouse] on 00:1E:58:F1:53:39
>[  117.172741] fuse init (API version 7.17)
>[ 1937.565105] PM: Marking nosave pages: 000000000009f000 - 0000000000100000
>[ 1937.565120] PM: Basic memory bitmaps created
>[ 1938.252660] Syncing filesystems ... done.
>[ 1938.266131] Freezing user space processes ... (elapsed 0.01 seconds) done.
>[ 1938.280206] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
>[ 1938.291560] PM: Preallocating image memory... done (allocated 415513 pages)
>[ 1940.632548] PM: Allocated 1662052 kbytes in 2.34 seconds (710.27 MB/s)
>[ 1940.632554] Suspending console(s) (use no_console_suspend to debug)
>[ 1940.634416] r8169 0000:04:00.0: eth0: link down
>[ 1940.914077] snd_hda_intel 0000:00:08.0: PCI INT A disabled
>[ 1940.925308] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D3
>[ 1941.035081] sd 1:0:0:0: [sda] Synchronizing SCSI cache
>[ 1941.044027] PM: freeze of devices complete after 411.461 msecs
>[ 1941.044664] PM: late freeze of devices complete after 0.626 msecs
>[ 1941.044710] ACPI: Preparing to enter system sleep state S4
>[ 1941.049457] PM: Saving platform NVS memory
>[ 1941.050588] Disabling non-boot CPUs ...
>[ 1941.053212] CPU 1 is now offline
>[ 1941.056440] CPU 2 is now offline
>[ 1941.059399] CPU 3 is now offline
>[ 1941.060621] Extended CMOS year: 2000
>[ 1941.060718] PM: Creating hibernation image:
>[ 1941.061024] PM: Need to copy 364289 pages
>[ 1941.061024] PM: Normal pages needed: 68916 + 1024, available pages: 156706
>[ 1941.061024] PM: Restoring platform NVS memory
>[ 1941.061024] Extended CMOS year: 2000
>[ 1941.066774] Enabling non-boot CPUs ...
>[ 1941.069308] Booting Node 0 Processor 1 APIC 0x2
>[ 1941.069314] smpboot cpu 1: start_ip = 9b000
>[ 1941.052934] Initializing CPU#1
>[ 1941.052934] Calibrating delay loop (skipped) already calibrated this CPU
>[ 1941.052934] Disabled fast string operations
>[ 1941.081027] Switched to NOHz mode on CPU #1
>[ 1941.081133] NMI watchdog enabled, takes one hw-pmu counter.
>[ 1941.083166] CPU1 is up
>[ 1941.083639] Booting Node 0 Processor 2 APIC 0x1
>[ 1941.083644] smpboot cpu 2: start_ip = 9b000
>[ 1941.056021] Initializing CPU#2
>[ 1941.056021] Calibrating delay loop (skipped) already calibrated this CPU
>[ 1941.056021] Disabled fast string operations
>[ 1941.095034] Switched to NOHz mode on CPU #2
>[ 1941.095891] NMI watchdog enabled, takes one hw-pmu counter.
>[ 1941.097159] CPU2 is up
>[ 1941.097699] Booting Node 0 Processor 3 APIC 0x3
>[ 1941.097704] smpboot cpu 3: start_ip = 9b000
>[ 1941.059223] Initializing CPU#3
>[ 1941.059223] Calibrating delay loop (skipped) already calibrated this CPU
>[ 1941.059223] Disabled fast string operations
>[ 1941.109038] Switched to NOHz mode on CPU #3
>[ 1941.110410] NMI watchdog enabled, takes one hw-pmu counter.
>[ 1941.112183] CPU3 is up
>[ 1941.114476] ACPI: Waking up from system sleep state S4
>[ 1941.118362] snd_hda_intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00006, writing 0xb00002)
>[ 1941.118429] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00403, writing 0xb00407)
>[ 1941.118845] nvidia 0000:03:00.0: restoring config space at offset 0xc (was 0xfbee0000, writing 0x0)
>[ 1941.118862] nvidia 0000:03:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0)
>[ 1941.118870] nvidia 0000:03:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100007)
>[ 1941.118937] r8169 0000:04:00.0: restoring config space at offset 0x1 (was 0x100007, writing 0x100407)
>[ 1941.119156] PM: early restore of devices complete after 1.343 msecs
>[ 1941.351787] ohci_hcd 0000:00:04.0: setting latency timer to 64
>[ 1941.351797] ehci_hcd 0000:00:04.1: setting latency timer to 64
>[ 1941.351827] ohci_hcd 0000:00:06.0: setting latency timer to 64
>[ 1941.351894] pci 0000:00:09.0: setting latency timer to 64
>[ 1941.351928] ahci 0000:00:0b.0: setting latency timer to 64
>[ 1941.351944] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 1941.351962] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 1941.351979] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 1941.351993] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 1941.352058] pci 0000:00:10.0: setting latency timer to 64
>[ 1941.352073] snd_hda_intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 20 (level, low) -> IRQ 20
>[ 1941.352087] snd_hda_intel 0000:00:08.0: setting latency timer to 64
>[ 1941.352779] Extended CMOS year: 2000
>[ 1941.354121] ehci_hcd 0000:00:06.1: setting latency timer to 64
>[ 1941.355366] sd 1:0:0:0: [sda] Starting disk
>[ 1941.404071] usb usb3: root hub lost power or was reset
>[ 1941.404125] usb usb4: root hub lost power or was reset
>[ 1941.404139] usb usb1: root hub lost power or was reset
>[ 1941.404157] ehci_hcd 0000:00:04.1: cache line size of 64 is not supported
>[ 1941.404227] usb usb2: root hub lost power or was reset
>[ 1941.404241] r8169 0000:04:00.0: eth0: link down
>[ 1941.404247] ehci_hcd 0000:00:06.1: cache line size of 64 is not supported
>[ 1941.657069] ata1: SATA link down (SStatus 0 SControl 300)
>[ 1941.657118] ata4: SATA link down (SStatus 0 SControl 300)
>[ 1941.657163] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>[ 1941.657199] ata3: SATA link down (SStatus 0 SControl 300)
>[ 1941.659132] ata2.00: configured for UDMA/133
>[ 1941.829083] usb 3-2: reset full speed USB device number 2 using ohci_hcd
>[ 1942.012170] btusb 3-2:1.0: no reset_resume for driver btusb?
>[ 1942.012178] btusb 3-2:1.1: no reset_resume for driver btusb?
>[ 1942.310059] usb 4-1: reset low speed USB device number 2 using ohci_hcd
>[ 1942.770086] usb 2-3: reset high speed USB device number 3 using ehci_hcd
>[ 1943.045976] r8169 0000:04:00.0: eth0: link up
>[ 1943.342621] usb 2-3.2: reset low speed USB device number 4 using ehci_hcd
>[ 1943.614677] PM: restore of devices complete after 2263.052 msecs
>[ 1943.672781] snapshot_deprecated_ioctl: 21 callbacks suppressed
>[ 1943.672797] snapshot_ioctl: ioctl '4004330c' is deprecated and will be removed soon, update your suspend-to-disk utilities
>[ 1943.672805] Restarting tasks ... done.
>[ 1943.758624] PM: Basic memory bitmaps freed
>[ 2335.538776] PM: Marking nosave pages: 000000000009f000 - 0000000000100000
>[ 2335.538790] PM: Basic memory bitmaps created
>[ 2335.699317] Syncing filesystems ... done.
>[ 2335.710505] Freezing user space processes ... (elapsed 0.01 seconds) done.
>[ 2335.725338] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
>[ 2335.736561] PM: Preallocating image memory... done (allocated 415577 pages)
>[ 2337.358615] PM: Allocated 1662308 kbytes in 1.62 seconds (1026.11 MB/s)
>[ 2337.358621] Suspending console(s) (use no_console_suspend to debug)
>[ 2337.359361] sd 1:0:0:0: [sda] Synchronizing SCSI cache
>[ 2337.360470] snd_hda_intel 0000:00:08.0: PCI INT A disabled
>[ 2337.360614] r8169 0000:04:00.0: eth0: link down
>[ 2337.371216] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D3
>[ 2337.410426] PM: freeze of devices complete after 51.791 msecs
>[ 2337.411306] PM: late freeze of devices complete after 0.869 msecs
>[ 2337.411354] ACPI: Preparing to enter system sleep state S4
>[ 2337.415938] PM: Saving platform NVS memory
>[ 2337.417002] Disabling non-boot CPUs ...
>[ 2337.419642] CPU 1 is now offline
>[ 2337.422745] CPU 2 is now offline
>[ 2337.425570] CPU 3 is now offline
>[ 2337.426702] Extended CMOS year: 2000
>[ 2337.426799] PM: Creating hibernation image:
>[ 2337.427015] PM: Need to copy 301127 pages
>[ 2337.427015] PM: Normal pages needed: 48819 + 1024, available pages: 176803
>[ 2337.427015] PM: Restoring platform NVS memory
>[ 2337.427015] Extended CMOS year: 2000
>[ 2337.432967] Enabling non-boot CPUs ...
>[ 2337.435501] Booting Node 0 Processor 1 APIC 0x2
>[ 2337.435506] smpboot cpu 1: start_ip = 9b000
>[ 2337.419381] Initializing CPU#1
>[ 2337.419381] Calibrating delay loop (skipped) already calibrated this CPU
>[ 2337.419381] Disabled fast string operations
>[ 2337.447037] Switched to NOHz mode on CPU #1
>[ 2337.447305] NMI watchdog enabled, takes one hw-pmu counter.
>[ 2337.449164] CPU1 is up
>[ 2337.449645] Booting Node 0 Processor 2 APIC 0x1
>[ 2337.449650] smpboot cpu 2: start_ip = 9b000
>[ 2337.422493] Initializing CPU#2
>[ 2337.422493] Calibrating delay loop (skipped) already calibrated this CPU
>[ 2337.422493] Disabled fast string operations
>[ 2337.461037] Switched to NOHz mode on CPU #2
>[ 2337.461901] NMI watchdog enabled, takes one hw-pmu counter.
>[ 2337.463165] CPU2 is up
>[ 2337.463703] Booting Node 0 Processor 3 APIC 0x3
>[ 2337.463708] smpboot cpu 3: start_ip = 9b000
>[ 2337.425413] Initializing CPU#3
>[ 2337.425413] Calibrating delay loop (skipped) already calibrated this CPU
>[ 2337.425413] Disabled fast string operations
>[ 2337.475035] Switched to NOHz mode on CPU #3
>[ 2337.476384] NMI watchdog enabled, takes one hw-pmu counter.
>[ 2337.478184] CPU3 is up
>[ 2337.480331] ACPI: Waking up from system sleep state S4
>[ 2337.484238] snd_hda_intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00006, writing 0xb00002)
>[ 2337.484304] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00403, writing 0xb00407)
>[ 2337.484720] nvidia 0000:03:00.0: restoring config space at offset 0xc (was 0xfbee0000, writing 0x0)
>[ 2337.484737] nvidia 0000:03:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0)
>[ 2337.484746] nvidia 0000:03:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100007)
>[ 2337.484812] r8169 0000:04:00.0: restoring config space at offset 0x1 (was 0x100007, writing 0x100407)
>[ 2337.484998] PM: early restore of devices complete after 1.311 msecs
>[ 2337.685388] ohci_hcd 0000:00:04.0: setting latency timer to 64
>[ 2337.685428] ehci_hcd 0000:00:04.1: setting latency timer to 64
>[ 2337.685439] ohci_hcd 0000:00:06.0: setting latency timer to 64
>[ 2337.685471] ehci_hcd 0000:00:06.1: setting latency timer to 64
>[ 2337.685630] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2337.685648] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2337.685657] pci 0000:00:09.0: setting latency timer to 64
>[ 2337.685664] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2337.685676] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2337.685698] snd_hda_intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 20 (level, low) -> IRQ 20
>[ 2337.685712] snd_hda_intel 0000:00:08.0: setting latency timer to 64
>[ 2337.685729] ahci 0000:00:0b.0: setting latency timer to 64
>[ 2337.685740] pci 0000:00:10.0: setting latency timer to 64
>[ 2337.687309] sd 1:0:0:0: [sda] Starting disk
>[ 2337.738119] usb usb4: root hub lost power or was reset
>[ 2337.738244] usb usb2: root hub lost power or was reset
>[ 2337.738270] ehci_hcd 0000:00:06.1: cache line size of 64 is not supported
>[ 2337.740132] usb usb3: root hub lost power or was reset
>[ 2337.740193] usb usb1: root hub lost power or was reset
>[ 2337.740200] r8169 0000:04:00.0: eth0: link down
>[ 2337.740213] ehci_hcd 0000:00:04.1: cache line size of 64 is not supported
>[ 2337.990069] ata1: SATA link down (SStatus 0 SControl 300)
>[ 2337.990112] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>[ 2337.991093] ata3: SATA link down (SStatus 0 SControl 300)
>[ 2337.991134] ata4: SATA link down (SStatus 0 SControl 300)
>[ 2337.992138] ata2.00: configured for UDMA/133
>[ 2338.165071] usb 3-2: reset full speed USB device number 2 using ohci_hcd
>[ 2338.351110] btusb 3-2:1.0: no reset_resume for driver btusb?
>[ 2338.351118] btusb 3-2:1.1: no reset_resume for driver btusb?
>[ 2338.443066] usb 2-3: reset high speed USB device number 3 using ehci_hcd
>[ 2338.864072] usb 4-1: reset low speed USB device number 2 using ohci_hcd
>[ 2339.473413] usb 2-3.2: reset low speed USB device number 4 using ehci_hcd
>[ 2339.620593] r8169 0000:04:00.0: eth0: link up
>[ 2339.745472] PM: restore of devices complete after 2060.254 msecs
>[ 2339.799371] snapshot_ioctl: ioctl '4004330c' is deprecated and will be removed soon, update your suspend-to-disk utilities
>[ 2339.799382] Restarting tasks ... done.
>[ 2339.888855] PM: Basic memory bitmaps freed
>[ 2347.313062] input: Apple Wireless Mouse as /devices/pci0000:00/0000:00:04.0/usb3/3-2/3-2:1.0/bluetooth/hci0/hci0:11/input7
>[ 2347.315112] magicmouse 0005:05AC:030D.0005: input,hidraw3: BLUETOOTH HID v0.84 Mouse [Apple Wireless Mouse] on 00:1E:58:F1:53:39
>[ 2468.766152] PM: Marking nosave pages: 000000000009f000 - 0000000000100000
>[ 2468.766168] PM: Basic memory bitmaps created
>[ 2468.955068] Syncing filesystems ... done.
>[ 2468.961518] Freezing user space processes ... (elapsed 0.01 seconds) done.
>[ 2468.976358] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
>[ 2468.987583] PM: Preallocating image memory... done (allocated 323151 pages)
>[ 2469.450912] PM: Allocated 1292604 kbytes in 0.46 seconds (2810.00 MB/s)
>[ 2469.450918] Suspending console(s) (use no_console_suspend to debug)
>[ 2469.451494] sd 1:0:0:0: [sda] Synchronizing SCSI cache
>[ 2469.452351] r8169 0000:04:00.0: eth0: link down
>[ 2469.604084] snd_hda_intel 0000:00:08.0: PCI INT A disabled
>[ 2469.615195] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D3
>[ 2469.615278] PM: freeze of devices complete after 164.348 msecs
>[ 2469.616160] PM: late freeze of devices complete after 0.871 msecs
>[ 2469.616204] ACPI: Preparing to enter system sleep state S4
>[ 2469.620818] PM: Saving platform NVS memory
>[ 2469.621873] Disabling non-boot CPUs ...
>[ 2469.624529] CPU 1 is now offline
>[ 2469.627743] CPU 2 is now offline
>[ 2469.630705] CPU 3 is now offline
>[ 2469.631875] Extended CMOS year: 2000
>[ 2469.631973] PM: Creating hibernation image:
>[ 2469.632013] PM: Need to copy 323671 pages
>[ 2469.632013] PM: Normal pages needed: 52419 + 1024, available pages: 173203
>[ 2469.632013] PM: Restoring platform NVS memory
>[ 2469.632013] Extended CMOS year: 2000
>[ 2469.638173] Enabling non-boot CPUs ...
>[ 2469.640751] Booting Node 0 Processor 1 APIC 0x2
>[ 2469.640756] smpboot cpu 1: start_ip = 9b000
>[ 2469.624247] Initializing CPU#1
>[ 2469.624247] Calibrating delay loop (skipped) already calibrated this CPU
>[ 2469.624247] Disabled fast string operations
>[ 2469.652037] Switched to NOHz mode on CPU #1
>[ 2469.652583] NMI watchdog enabled, takes one hw-pmu counter.
>[ 2469.654164] CPU1 is up
>[ 2469.654640] Booting Node 0 Processor 2 APIC 0x1
>[ 2469.654645] smpboot cpu 2: start_ip = 9b000
>[ 2469.627016] Initializing CPU#2
>[ 2469.627016] Calibrating delay loop (skipped) already calibrated this CPU
>[ 2469.627016] Disabled fast string operations
>[ 2469.666036] Switched to NOHz mode on CPU #2
>[ 2469.666918] NMI watchdog enabled, takes one hw-pmu counter.
>[ 2469.668160] CPU2 is up
>[ 2469.668705] Booting Node 0 Processor 3 APIC 0x3
>[ 2469.668710] smpboot cpu 3: start_ip = 9b000
>[ 2469.630547] Initializing CPU#3
>[ 2469.630547] Calibrating delay loop (skipped) already calibrated this CPU
>[ 2469.630547] Disabled fast string operations
>[ 2469.680035] Switched to NOHz mode on CPU #3
>[ 2469.681479] NMI watchdog enabled, takes one hw-pmu counter.
>[ 2469.683184] CPU3 is up
>[ 2469.685302] ACPI: Waking up from system sleep state S4
>[ 2469.689111] snd_hda_intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00006, writing 0xb00002)
>[ 2469.689176] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00403, writing 0xb00407)
>[ 2469.689593] nvidia 0000:03:00.0: restoring config space at offset 0xc (was 0xfbee0000, writing 0x0)
>[ 2469.689610] nvidia 0000:03:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0)
>[ 2469.689619] nvidia 0000:03:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100007)
>[ 2469.689684] r8169 0000:04:00.0: restoring config space at offset 0x1 (was 0x100007, writing 0x100407)
>[ 2469.689874] PM: early restore of devices complete after 1.311 msecs
>[ 2469.900822] ohci_hcd 0000:00:04.0: setting latency timer to 64
>[ 2469.900868] ehci_hcd 0000:00:04.1: setting latency timer to 64
>[ 2469.900950] ohci_hcd 0000:00:06.0: setting latency timer to 64
>[ 2469.901078] ehci_hcd 0000:00:06.1: setting latency timer to 64
>[ 2469.901118] pci 0000:00:09.0: setting latency timer to 64
>[ 2469.901426] pci 0000:00:10.0: setting latency timer to 64
>[ 2469.902143] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2469.902201] ahci 0000:00:0b.0: setting latency timer to 64
>[ 2469.902590] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2469.902622] sd 1:0:0:0: [sda] Starting disk
>[ 2469.902700] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2469.902818] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2469.902888] snd_hda_intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 20 (level, low) -> IRQ 20
>
>[ 2469.902902] snd_hda_intel 0000:00:08.0: setting latency timer to 64
>[ 2469.953113] usb usb4: root hub lost power or was reset
>[ 2469.953227] r8169 0000:04:00.0: eth0: link down
>[ 2469.953305] usb usb2: root hub lost power or was reset
>[ 2469.953325] ehci_hcd 0000:00:06.1: cache line size of 64 is not supported
>[ 2469.955103] usb usb3: root hub lost power or was reset
>[ 2469.955205] usb usb1: root hub lost power or was reset
>[ 2469.955226] ehci_hcd 0000:00:04.1: cache line size of 64 is not supported
>[ 2470.207066] ata4: SATA link down (SStatus 0 SControl 300)
>[ 2470.207117] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>[ 2470.207157] ata3: SATA link down (SStatus 0 SControl 300)
>[ 2470.207205] ata1: SATA link down (SStatus 0 SControl 300)
>[ 2470.209109] ata2.00: configured for UDMA/133
>[ 2470.380081] usb 3-2: reset full speed USB device number 2 using ohci_hcd
>[ 2470.564162] btusb 3-2:1.0: no reset_resume for driver btusb?
>[ 2470.564169] btusb 3-2:1.1: no reset_resume for driver btusb?
>[ 2470.861083] usb 4-1: reset low speed USB device number 2 using ohci_hcd
>[ 2471.321078] usb 2-3: reset high speed USB device number 3 using ehci_hcd
>[ 2471.857175] r8169 0000:04:00.0: eth0: link up
>[ 2471.893670] usb 2-3.2: reset low speed USB device number 4 using ehci_hcd
>[ 2472.165748] PM: restore of devices complete after 2265.070 msecs
>[ 2472.223985] snapshot_ioctl: ioctl '4004330c' is deprecated and will be removed soon, update your suspend-to-disk utilities
>[ 2472.223996] Restarting tasks ... done.
>[ 2472.379393] PM: Basic memory bitmaps freed
>[ 2512.136819] PM: Marking nosave pages: 000000000009f000 - 0000000000100000
>[ 2512.136835] PM: Basic memory bitmaps created
>[ 2512.305450] Syncing filesystems ... done.
>[ 2512.312503] Freezing user space processes ... (elapsed 0.01 seconds) done.
>[ 2512.327224] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
>[ 2512.338568] PM: Preallocating image memory... done (allocated 322458 pages)
>[ 2512.798072] PM: Allocated 1289832 kbytes in 0.45 seconds (2866.29 MB/s)
>[ 2512.798078] Suspending console(s) (use no_console_suspend to debug)
>[ 2512.798472] sd 1:0:0:0: [sda] Synchronizing SCSI cache
>[ 2512.799376] snd_hda_intel 0000:00:08.0: PCI INT A disabled
>[ 2512.799459] r8169 0000:04:00.0: eth0: link down
>[ 2512.810206] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D3
>[ 2512.855330] PM: freeze of devices complete after 57.234 msecs
>[ 2512.856209] PM: late freeze of devices complete after 0.867 msecs
>[ 2512.856254] ACPI: Preparing to enter system sleep state S4
>[ 2512.860868] PM: Saving platform NVS memory
>[ 2512.861932] Disabling non-boot CPUs ...
>[ 2512.864567] CPU 1 is now offline
>[ 2512.867772] CPU 2 is now offline
>[ 2512.870727] CPU 3 is now offline
>[ 2512.871889] Extended CMOS year: 2000
>[ 2512.871986] PM: Creating hibernation image:
>[ 2512.872013] PM: Need to copy 323135 pages
>[ 2512.872013] PM: Normal pages needed: 52190 + 1024, available pages: 173432
>[ 2512.872013] PM: Restoring platform NVS memory
>[ 2512.872013] Extended CMOS year: 2000
>[ 2512.878183] Enabling non-boot CPUs ...
>[ 2512.880745] Booting Node 0 Processor 1 APIC 0x2
>[ 2512.880751] smpboot cpu 1: start_ip = 9b000
>[ 2512.864301] Initializing CPU#1
>[ 2512.864301] Calibrating delay loop (skipped) already calibrated this CPU
>[ 2512.864301] Disabled fast string operations
>[ 2512.892037] Switched to NOHz mode on CPU #1
>[ 2512.892580] NMI watchdog enabled, takes one hw-pmu counter.
>[ 2512.894165] CPU1 is up
>[ 2512.894634] Booting Node 0 Processor 2 APIC 0x1
>[ 2512.894639] smpboot cpu 2: start_ip = 9b000
>[ 2512.867034] Initializing CPU#2
>[ 2512.867034] Calibrating delay loop (skipped) already calibrated this CPU
>[ 2512.867034] Disabled fast string operations
>[ 2512.906036] Switched to NOHz mode on CPU #2
>[ 2512.906918] NMI watchdog enabled, takes one hw-pmu counter.
>[ 2512.908152] CPU2 is up
>[ 2512.908691] Booting Node 0 Processor 3 APIC 0x3
>[ 2512.908696] smpboot cpu 3: start_ip = 9b000
>[ 2512.870571] Initializing CPU#3
>[ 2512.870571] Calibrating delay loop (skipped) already calibrated this CPU
>[ 2512.870571] Disabled fast string operations
>[ 2512.920036] Switched to NOHz mode on CPU #3
>[ 2512.921428] NMI watchdog enabled, takes one hw-pmu counter.
>[ 2512.923182] CPU3 is up
>[ 2512.925306] ACPI: Waking up from system sleep state S4
>[ 2512.929123] snd_hda_intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00006, writing 0xb00002)
>[ 2512.929189] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00403, writing 0xb00407)
>[ 2512.929605] nvidia 0000:03:00.0: restoring config space at offset 0xc (was 0xfbee0000, writing 0x0)
>[ 2512.929621] nvidia 0000:03:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0)
>[ 2512.929630] nvidia 0000:03:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100007)
>[ 2512.929696] r8169 0000:04:00.0: restoring config space at offset 0x1 (was 0x100007, writing 0x100407)
>[ 2512.929882] PM: early restore of devices complete after 1.307 msecs
>[ 2513.142218] ohci_hcd 0000:00:04.0: setting latency timer to 64
>[ 2513.142251] ehci_hcd 0000:00:04.1: setting latency timer to 64
>[ 2513.142266] ohci_hcd 0000:00:06.0: setting latency timer to 64
>[ 2513.142301] ehci_hcd 0000:00:06.1: setting latency timer to 64
>[ 2513.142470] pci 0000:00:09.0: setting latency timer to 64
>[ 2513.143204] pci 0000:00:10.0: setting latency timer to 64
>[ 2513.145108] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2513.145127] ahci 0000:00:0b.0: setting latency timer to 64
>[ 2513.145144] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2513.145162] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2513.145176] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2513.145217] snd_hda_intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 20 (level, low) -> IRQ 20
>[ 2513.145242] snd_hda_intel 0000:00:08.0: setting latency timer to 64
>[ 2513.145443] sd 1:0:0:0: [sda] Starting disk
>[ 2513.194195] r8169 0000:04:00.0: eth0: link down
>[ 2513.195123] usb usb3: root hub lost power or was reset
>[ 2513.195202] usb usb1: root hub lost power or was reset
>[ 2513.195219] ehci_hcd 0000:00:04.1: cache line size of 64 is not supported
>[ 2513.196252] usb usb4: root hub lost power or was reset
>[ 2513.196345] usb usb2: root hub lost power or was reset
>[ 2513.196361] ehci_hcd 0000:00:06.1: cache line size of 64 is not supported
>[ 2513.450071] ata4: SATA link down (SStatus 0 SControl 300)
>[ 2513.450118] ata3: SATA link down (SStatus 0 SControl 300)
>[ 2513.450163] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>[ 2513.450223] ata1: SATA link down (SStatus 0 SControl 300)
>[ 2513.452164] ata2.00: configured for UDMA/133
>[ 2513.620079] usb 3-2: reset full speed USB device number 2 using ohci_hcd
>[ 2513.803168] btusb 3-2:1.0: no reset_resume for driver btusb?
>[ 2513.803177] btusb 3-2:1.1: no reset_resume for driver btusb?
>[ 2514.101081] usb 4-1: reset low speed USB device number 2 using ohci_hcd
>[ 2514.561076] usb 2-3: reset high speed USB device number 3 using ehci_hcd
>[ 2514.846704] r8169 0000:04:00.0: eth0: link up
>[ 2515.133703] usb 2-3.2: reset low speed USB device number 4 using ehci_hcd
>[ 2515.406709] PM: restore of devices complete after 2264.634 msecs
>[ 2515.464829] snapshot_ioctl: ioctl '4004330c' is deprecated and will be removed soon, update your suspend-to-disk utilities
>[ 2515.464840] Restarting tasks ... done.
>[ 2515.569844] PM: Basic memory bitmaps freed
>[ 2518.889926] input: Apple Wireless Mouse as /devices/pci0000:00/0000:00:04.0/usb3/3-2/3-2:1.0/bluetooth/hci0/hci0:11/input8
>[ 2518.890756] magicmouse 0005:05AC:030D.0006: input,hidraw3: BLUETOOTH HID v0.84 Mouse [Apple Wireless Mouse] on 00:1E:58:F1:53:39
>[ 2534.096278] PM: Marking nosave pages: 000000000009f000 - 0000000000100000
>[ 2534.096292] PM: Basic memory bitmaps created
>[ 2534.795254] Syncing filesystems ... done.
>[ 2535.000259] Freezing user space processes ... (elapsed 0.01 seconds) done.
>[ 2535.014357] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
>[ 2535.025592] PM: Preallocating image memory... done (allocated 326412 pages)
>[ 2535.484480] PM: Allocated 1305648 kbytes in 0.45 seconds (2901.44 MB/s)
>[ 2535.484486] Suspending console(s) (use no_console_suspend to debug)
>[ 2535.484893] sd 1:0:0:0: [sda] Synchronizing SCSI cache
>[ 2535.485965] snd_hda_intel 0000:00:08.0: PCI INT A disabled
>[ 2535.486023] r8169 0000:04:00.0: eth0: link down
>[ 2535.497267] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D3
>[ 2535.537743] PM: freeze of devices complete after 53.240 msecs
>[ 2535.538661] PM: late freeze of devices complete after 0.905 msecs
>[ 2535.538705] ACPI: Preparing to enter system sleep state S4
>[ 2535.543338] PM: Saving platform NVS memory
>[ 2535.544390] Disabling non-boot CPUs ...
>[ 2535.547004] CPU 1 is now offline
>[ 2535.550213] CPU 2 is now offline
>[ 2535.553222] CPU 3 is now offline
>[ 2535.554503] Extended CMOS year: 2000
>[ 2535.554599] PM: Creating hibernation image:
>[ 2535.555016] PM: Need to copy 327085 pages
>[ 2535.555016] PM: Normal pages needed: 52627 + 1024, available pages: 172995
>[ 2535.555016] PM: Restoring platform NVS memory
>[ 2535.555016] Extended CMOS year: 2000
>[ 2535.560883] Enabling non-boot CPUs ...
>[ 2535.563404] Booting Node 0 Processor 1 APIC 0x2
>[ 2535.563410] smpboot cpu 1: start_ip = 9b000
>[ 2535.546756] Initializing CPU#1
>[ 2535.546756] Calibrating delay loop (skipped) already calibrated this CPU
>[ 2535.546756] Disabled fast string operations
>[ 2535.575029] Switched to NOHz mode on CPU #1
>[ 2535.575262] NMI watchdog enabled, takes one hw-pmu counter.
>[ 2535.577165] CPU1 is up
>[ 2535.577650] Booting Node 0 Processor 2 APIC 0x1
>[ 2535.577655] smpboot cpu 2: start_ip = 9b000
>[ 2535.549460] Initializing CPU#2
>[ 2535.549460] Calibrating delay loop (skipped) already calibrated this CPU
>[ 2535.549460] Disabled fast string operations
>[ 2535.589038] Switched to NOHz mode on CPU #2
>[ 2535.589984] NMI watchdog enabled, takes one hw-pmu counter.
>[ 2535.591162] CPU2 is up
>[ 2535.591701] Booting Node 0 Processor 3 APIC 0x3
>[ 2535.591706] smpboot cpu 3: start_ip = 9b000
>[ 2535.553071] Initializing CPU#3
>[ 2535.553071] Calibrating delay loop (skipped) already calibrated this CPU
>[ 2535.553071] Disabled fast string operations
>[ 2535.603036] Switched to NOHz mode on CPU #3
>[ 2535.604424] NMI watchdog enabled, takes one hw-pmu counter.
>[ 2535.606179] CPU3 is up
>[ 2535.608309] ACPI: Waking up from system sleep state S4
>[ 2535.612155] snd_hda_intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00006, writing 0xb00002)
>[ 2535.612221] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00403, writing 0xb00407)
>[ 2535.612637] nvidia 0000:03:00.0: restoring config space at offset 0xc (was 0xfbee0000, writing 0x0)
>[ 2535.612654] nvidia 0000:03:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0)
>[ 2535.612663] nvidia 0000:03:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100007)
>[ 2535.612729] r8169 0000:04:00.0: restoring config space at offset 0x1 (was 0x100007, writing 0x100407)
>[ 2535.612918] PM: early restore of devices complete after 1.312 msecs
>[ 2535.820156] ohci_hcd 0000:00:04.0: setting latency timer to 64
>[ 2535.820166] ehci_hcd 0000:00:04.1: setting latency timer to 64
>[ 2535.820223] ohci_hcd 0000:00:06.0: setting latency timer to 64
>[ 2535.820299] ehci_hcd 0000:00:06.1: setting latency timer to 64
>[ 2535.820360] pci 0000:00:09.0: setting latency timer to 64
>[ 2535.820391] ahci 0000:00:0b.0: setting latency timer to 64
>[ 2535.820405] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2535.820423] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2535.820431] pci 0000:00:10.0: setting latency timer to 64
>[ 2535.820440] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2535.820454] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2535.820477] snd_hda_intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 20 (level, low) -> IRQ 20
>[ 2535.820490] snd_hda_intel 0000:00:08.0: setting latency timer to 64
>[ 2535.821059] sd 1:0:0:0: [sda] Starting disk
>[ 2535.821334] Extended CMOS year: 2000
>[ 2535.872224] r8169 0000:04:00.0: eth0: link down
>[ 2535.873055] usb usb3: root hub lost power or was reset
>[ 2535.873125] usb usb1: root hub lost power or was reset
>[ 2535.873148] usb usb4: root hub lost power or was reset
>[ 2535.873170] ehci_hcd 0000:00:04.1: cache line size of 64 is not supported
>[ 2535.873278] usb usb2: root hub lost power or was reset
>[ 2535.873293] ehci_hcd 0000:00:06.1: cache line size of 64 is not supported
>[ 2536.125068] ata3: SATA link down (SStatus 0 SControl 300)
>[ 2536.125116] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>[ 2536.125156] ata1: SATA link down (SStatus 0 SControl 300)
>[ 2536.125192] ata4: SATA link down (SStatus 0 SControl 300)
>[ 2536.127136] ata2.00: configured for UDMA/133
>[ 2536.298070] usb 3-2: reset full speed USB device number 2 using ohci_hcd
>[ 2536.482151] btusb 3-2:1.0: no reset_resume for driver btusb?
>[ 2536.482158] btusb 3-2:1.1: no reset_resume for driver btusb?
>[ 2536.574083] usb 2-3: reset high speed USB device number 3 using ehci_hcd
>[ 2536.995073] usb 4-1: reset low speed USB device number 2 using ohci_hcd
>[ 2537.556216] r8169 0000:04:00.0: eth0: link up
>[ 2537.605619] usb 2-3.2: reset low speed USB device number 4 using ehci_hcd
>[ 2537.877608] PM: restore of devices complete after 2057.639 msecs
>[ 2537.936114] snapshot_ioctl: ioctl '4004330c' is deprecated and will be removed soon, update your suspend-to-disk utilities
>[ 2537.936125] Restarting tasks ... done.
>[ 2538.080145] PM: Basic memory bitmaps freed
>[ 2567.613675] PM: Marking nosave pages: 000000000009f000 - 0000000000100000
>[ 2567.613688] PM: Basic memory bitmaps created
>[ 2569.202546] Syncing filesystems ... done.
>[ 2569.498087] Freezing user space processes ... (elapsed 0.01 seconds) done.
>[ 2569.512321] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
>[ 2569.523568] PM: Preallocating image memory... done (allocated 336687 pages)
>[ 2569.990418] PM: Allocated 1346748 kbytes in 0.46 seconds (2927.71 MB/s)
>[ 2569.990424] Suspending console(s) (use no_console_suspend to debug)
>[ 2569.990827] sd 1:0:0:0: [sda] Synchronizing SCSI cache
>[ 2569.991734] r8169 0000:04:00.0: eth0: link down
>[ 2569.991912] snd_hda_intel 0000:00:08.0: PCI INT A disabled
>[ 2570.002290] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D3
>[ 2570.040861] PM: freeze of devices complete after 50.420 msecs
>[ 2570.041746] PM: late freeze of devices complete after 0.873 msecs
>[ 2570.041794] ACPI: Preparing to enter system sleep state S4
>[ 2570.046438] PM: Saving platform NVS memory
>[ 2570.047497] Disabling non-boot CPUs ...
>[ 2570.050074] CPU 1 is now offline
>[ 2570.053153] CPU 2 is now offline
>[ 2570.056147] CPU 3 is now offline
>[ 2570.057323] Extended CMOS year: 2000
>[ 2570.057418] PM: Creating hibernation image:
>[ 2570.058107] PM: Need to copy 337418 pages
>[ 2570.058107] PM: Normal pages needed: 52782 + 1024, available pages: 172840
>[ 2570.058107] PM: Restoring platform NVS memory
>[ 2570.058107] Extended CMOS year: 2000
>[ 2570.063593] Enabling non-boot CPUs ...
>[ 2570.066195] Booting Node 0 Processor 1 APIC 0x2
>[ 2570.066201] smpboot cpu 1: start_ip = 9b000
>[ 2570.049014] Initializing CPU#1
>[ 2570.049014] Calibrating delay loop (skipped) already calibrated this CPU
>[ 2570.049014] Disabled fast string operations
>[ 2570.078030] NMI watchdog enabled, takes one hw-pmu counter.
>[ 2570.078045] Switched to NOHz mode on CPU #1
>[ 2570.080167] CPU1 is up
>[ 2570.080648] Booting Node 0 Processor 2 APIC 0x1
>[ 2570.080653] smpboot cpu 2: start_ip = 9b000
>[ 2570.052405] Initializing CPU#2
>[ 2570.052405] Calibrating delay loop (skipped) already calibrated this CPU
>[ 2570.052405] Disabled fast string operations
>[ 2570.092035] Switched to NOHz mode on CPU #2
>[ 2570.092929] NMI watchdog enabled, takes one hw-pmu counter.
>[ 2570.094161] CPU2 is up
>[ 2570.094699] Booting Node 0 Processor 3 APIC 0x3
>[ 2570.094704] smpboot cpu 3: start_ip = 9b000
>[ 2570.055986] Initializing CPU#3
>[ 2570.055986] Calibrating delay loop (skipped) already calibrated this CPU
>[ 2570.055986] Disabled fast string operations
>[ 2570.106037] Switched to NOHz mode on CPU #3
>[ 2570.107428] NMI watchdog enabled, takes one hw-pmu counter.
>[ 2570.109179] CPU3 is up
>[ 2570.111315] ACPI: Waking up from system sleep state S4
>[ 2570.115219] snd_hda_intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00006, writing 0xb00002)
>[ 2570.115285] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00403, writing 0xb00407)
>[ 2570.115702] nvidia 0000:03:00.0: restoring config space at offset 0xc (was 0xfbee0000, writing 0x0)
>[ 2570.115718] nvidia 0000:03:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0)
>[ 2570.115728] nvidia 0000:03:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100007)
>[ 2570.115793] r8169 0000:04:00.0: restoring config space at offset 0x1 (was 0x100007, writing 0x100407)
>[ 2570.115979] PM: early restore of devices complete after 1.308 msecs
>[ 2570.336899] ohci_hcd 0000:00:04.0: setting latency timer to 64
>[ 2570.336944] ehci_hcd 0000:00:04.1: setting latency timer to 64
>[ 2570.336967] ohci_hcd 0000:00:06.0: setting latency timer to 64
>[ 2570.337040] ehci_hcd 0000:00:06.1: setting latency timer to 64
>[ 2570.337167] pci 0000:00:09.0: setting latency timer to 64
>[ 2570.337192] ahci 0000:00:0b.0: setting latency timer to 64
>[ 2570.337198] pci 0000:00:10.0: setting latency timer to 64
>[ 2570.337246] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2570.337265] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2570.337283] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2570.337296] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 2570.337319] snd_hda_intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 20 (level, low) -> IRQ 20
>[ 2570.337332] snd_hda_intel 0000:00:08.0: setting latency timer to 64
>[ 2570.337573] sd 1:0:0:0: [sda] Starting disk
>[ 2570.389115] usb usb4: root hub lost power or was reset
>[ 2570.389193] r8169 0000:04:00.0: eth0: link down
>[ 2570.389213] usb usb2: root hub lost power or was reset
>[ 2570.389231] ehci_hcd 0000:00:06.1: cache line size of 64 is not supported
>[ 2570.390129] usb usb3: root hub lost power or was reset
>[ 2570.390223] usb usb1: root hub lost power or was reset
>[ 2570.390238] ehci_hcd 0000:00:04.1: cache line size of 64 is not supported
>[ 2570.642077] ata1: SATA link down (SStatus 0 SControl 300)
>[ 2570.642126] ata4: SATA link down (SStatus 0 SControl 300)
>[ 2570.642164] ata3: SATA link down (SStatus 0 SControl 300)
>[ 2570.642202] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>[ 2570.644204] ata2.00: configured for UDMA/133
>[ 2570.815075] usb 3-2: reset full speed USB device number 2 using ohci_hcd
>[ 2570.999157] btusb 3-2:1.0: no reset_resume for driver btusb?
>[ 2570.999164] btusb 3-2:1.1: no reset_resume for driver btusb?
>[ 2571.296083] usb 4-1: reset low speed USB device number 2 using ohci_hcd
>[ 2571.757083] usb 2-3: reset high speed USB device number 3 using ehci_hcd
>[ 2572.303678] r8169 0000:04:00.0: eth0: link up
>[ 2572.328574] usb 2-3.2: reset low speed USB device number 4 using ehci_hcd
>[ 2572.600553] PM: restore of devices complete after 2263.808 msecs
>[ 2572.658605] snapshot_ioctl: ioctl '4004330c' is deprecated and will be removed soon, update your suspend-to-disk utilities
>[ 2572.658616] Restarting tasks ... done.
>[ 2572.775177] PM: Basic memory bitmaps freed
>[ 2582.281317] input: Apple Wireless Mouse as /devices/pci0000:00/0000:00:04.0/usb3/3-2/3-2:1.0/bluetooth/hci0/hci0:11/input9
>[ 2582.284395] magicmouse 0005:05AC:030D.0007: input,hidraw3: BLUETOOTH HID v0.84 Mouse [Apple Wireless Mouse] on 00:1E:58:F1:53:39
>[ 7979.701760] SFW2-INext-DROP-DEFLT IN=eth0 OUT= MAC=20:cf:30:c2:fe:45:48:5d:60:5e:7a:4a:08:00 SRC=192.168.46.143 DST=192.168.46.89 LEN=133 TOS=0x00 PREC=0x00 TTL=128 ID=177 PROTO=UDP SPT=14186 DPT=46860 LEN=113 
>[ 7981.743004] SFW2-INext-DROP-DEFLT IN=eth0 OUT= MAC=20:cf:30:c2:fe:45:48:5d:60:5e:7a:4a:08:00 SRC=192.168.46.143 DST=192.168.46.89 LEN=133 TOS=0x00 PREC=0x00 TTL=128 ID=216 PROTO=UDP SPT=14186 DPT=46860 LEN=113 
>[ 7985.790828] SFW2-INext-DROP-DEFLT IN=eth0 OUT= MAC=20:cf:30:c2:fe:45:48:5d:60:5e:7a:4a:08:00 SRC=192.168.46.143 DST=192.168.46.89 LEN=133 TOS=0x00 PREC=0x00 TTL=128 ID=259 PROTO=UDP SPT=14186 DPT=46860 LEN=113 
>[ 8380.692541] tracker-extract[8358]: segfault at 8cf5415 ip ae623dd1 sp bf8aca7c error 4 in libpoppler.so.18.0.0[ae528000+1b9000]
>[ 8382.061511] tracker-extract[8376]: segfault at 7fa9e11 ip ae723dd1 sp bfc3252c error 4 in libpoppler.so.18.0.0[ae628000+1b9000]
>[ 8386.481491] tracker-extract[8393]: segfault at 8cf5415 ip ac423dd1 sp bf87434c error 4 in libpoppler.so.18.0.0[ac328000+1b9000]
>[ 8387.357659] tracker-extract[8410]: segfault at 106a8a5c ip ae623dd1 sp bfcac3bc error 4 in libpoppler.so.18.0.0[ae528000+1b9000]
>[ 8389.218658] tracker-extract[8426]: segfault at b499540 ip ae723dd1 sp bf89f94c error 4 in libpoppler.so.18.0.0[ae628000+1b9000]
>[ 8390.659706] tracker-extract[8442]: segfault at b499540 ip ae623dd1 sp bf88e25c error 4 in libpoppler.so.18.0.0[ae528000+1b9000]
>[ 8392.357676] tracker-extract[8457]: segfault at 26e41d71 ip ae723dd1 sp bfcff50c error 4 in libpoppler.so.18.0.0[ae628000+1b9000]
>[ 8395.132112] tracker-extract[8472]: segfault at 9e5597d ip ac423dd1 sp bfae0e3c error 4 in libpoppler.so.18.0.0[ac328000+1b9000]
>[ 8396.774404] tracker-extract[8491]: segfault at 9e5597d ip ac423dd1 sp bfb01e3c error 4 in libpoppler.so.18.0.0[ac328000+1b9000]
>[ 8397.697115] tracker-extract[8509]: segfault at 26e41d71 ip ae723dd1 sp bfd8340c error 4 in libpoppler.so.18.0.0[ae628000+1b9000]
>[ 8766.438104] NET: Registered protocol family 17
>[ 8774.195357] st: Version 20101219, fixed bufsize 32768, s/g segs 256
>[ 8800.050884] ppdev: user-space parallel port driver
>[ 8800.069505] lp: driver loaded but no devices found
>[ 8800.183631] lp: driver loaded but no devices found
>[ 8804.551336] lp: driver loaded but no devices found
>[ 8897.985182] PM: Syncing filesystems ... done.
>[ 8898.395296] PM: Preparing system for mem sleep
>[ 8898.397448] Freezing user space processes ... (elapsed 0.01 seconds) done.
>[ 8898.411209] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
>[ 8898.422114] PM: Entering mem sleep
>[ 8898.422172] Suspending console(s) (use no_console_suspend to debug)
>[ 8898.423244] sd 1:0:0:0: [sda] Synchronizing SCSI cache
>[ 8898.424581] r8169 0000:04:00.0: eth0: link down
>[ 8898.440104] sd 1:0:0:0: [sda] Stopping disk
>[ 8898.576078] snd_hda_intel 0000:00:08.0: PCI INT A disabled
>[ 8898.587194] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D3
>[ 8899.000330] PM: suspend of devices complete after 577.910 msecs
>[ 8899.000553] r8169 0000:04:00.0: PME# enabled
>[ 8899.000586] pcieport 0000:00:15.0: wake-up capability enabled by ACPI
>[ 8899.055333] PM: late suspend of devices complete after 54.990 msecs
>[ 8899.055376] ACPI: Preparing to enter system sleep state S3
>[ 8899.060164] PM: Saving platform NVS memory
>[ 8899.061252] Disabling non-boot CPUs ...
>[ 8899.063902] CPU 1 is now offline
>[ 8899.067210] CPU 2 is now offline
>[ 8899.070195] CPU 3 is now offline
>[ 8899.071567] Extended CMOS year: 2000
>[ 8899.071567] ACPI: Low-level resume complete
>[ 8899.071567] PM: Restoring platform NVS memory
>[ 8899.071567] Extended CMOS year: 2000
>[ 8899.077873] Enabling non-boot CPUs ...
>[ 8899.080407] Booting Node 0 Processor 1 APIC 0x2
>[ 8899.080412] smpboot cpu 1: start_ip = 9b000
>[ 8899.063653] Initializing CPU#1
>[ 8899.063653] Calibrating delay loop (skipped) already calibrated this CPU
>[ 8899.063653] Disabled fast string operations
>[ 8899.092035] Switched to NOHz mode on CPU #1
>[ 8899.092282] NMI watchdog enabled, takes one hw-pmu counter.
>[ 8899.094164] CPU1 is up
>[ 8899.094651] Booting Node 0 Processor 2 APIC 0x1
>[ 8899.094656] smpboot cpu 2: start_ip = 9b000
>[ 8899.066948] Initializing CPU#2
>[ 8899.066948] Calibrating delay loop (skipped) already calibrated this CPU
>[ 8899.066948] Disabled fast string operations
>[ 8899.106036] Switched to NOHz mode on CPU #2
>[ 8899.106974] NMI watchdog enabled, takes one hw-pmu counter.
>[ 8899.108166] CPU2 is up
>[ 8899.108711] Booting Node 0 Processor 3 APIC 0x3
>[ 8899.108716] smpboot cpu 3: start_ip = 9b000
>[ 8899.070045] Initializing CPU#3
>[ 8899.070045] Calibrating delay loop (skipped) already calibrated this CPU
>[ 8899.070045] Disabled fast string operations
>[ 8899.120036] Switched to NOHz mode on CPU #3
>[ 8899.121496] NMI watchdog enabled, takes one hw-pmu counter.
>[ 8899.123178] CPU3 is up
>[ 8899.125340] ACPI: Waking up from system sleep state S3
>[ 8899.129162] snd_hda_intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00006, writing 0xb00002)
>[ 8899.129228] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00007, writing 0xb00407)
>[ 8899.129455] pci 0000:00:10.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10010)
>[ 8899.129663] nvidia 0000:03:00.0: restoring config space at offset 0xc (was 0xfbee0000, writing 0x0)
>[ 8899.129677] nvidia 0000:03:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0)
>[ 8899.129686] nvidia 0000:03:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100007)
>[ 8899.129729] r8169 0000:04:00.0: restoring config space at offset 0xf (was 0x1ff, writing 0x10a)
>[ 8899.129741] r8169 0000:04:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfbff0000)
>[ 8899.129754] r8169 0000:04:00.0: restoring config space at offset 0x8 (was 0xc, writing 0xf8ff800c)
>[ 8899.129764] r8169 0000:04:00.0: restoring config space at offset 0x6 (was 0xc, writing 0xf8fff00c)
>[ 8899.129775] r8169 0000:04:00.0: restoring config space at offset 0x4 (was 0x1, writing 0xe801)
>[ 8899.129784] r8169 0000:04:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10)
>[ 8899.129794] r8169 0000:04:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407)
>[ 8899.129984] PM: early resume of devices complete after 1.371 msecs
>[ 8899.130205] ohci_hcd 0000:00:04.0: setting latency timer to 64
>[ 8899.130214] ehci_hcd 0000:00:04.1: setting latency timer to 64
>[ 8899.130253] ohci_hcd 0000:00:06.0: setting latency timer to 64
>[ 8899.130274] ehci_hcd 0000:00:06.1: setting latency timer to 64
>[ 8899.130319] pci 0000:00:09.0: setting latency timer to 64
>[ 8899.130352] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 8899.130373] ahci 0000:00:0b.0: setting latency timer to 64
>[ 8899.130380] pci 0000:00:10.0: setting latency timer to 64
>[ 8899.130398] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 8899.130415] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 8899.130428] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 8899.130457] snd_hda_intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 20 (level, low) -> IRQ 20
>[ 8899.130483] snd_hda_intel 0000:00:08.0: setting latency timer to 64
>[ 8899.130490] pcieport 0000:00:15.0: wake-up capability disabled by ACPI
>[ 8899.130502] r8169 0000:04:00.0: PME# disabled
>[ 8899.131457] sd 1:0:0:0: [sda] Starting disk
>[ 8899.184201] r8169 0000:04:00.0: eth0: link down
>[ 8899.435064] ata3: SATA link down (SStatus 0 SControl 300)
>[ 8899.435112] ata4: SATA link down (SStatus 0 SControl 300)
>[ 8899.435153] ata1: SATA link down (SStatus 0 SControl 300)
>[ 8899.843059] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>[ 8899.848103] ata2.00: configured for UDMA/133
>[ 8899.868703] PM: resume of devices complete after 738.634 msecs
>[ 8899.868926] PM: Finishing wakeup.
>[ 8899.868931] Restarting tasks ... done.
>[ 8901.056159] r8169 0000:04:00.0: eth0: link up
>[ 9389.544930] PM: Marking nosave pages: 000000000009f000 - 0000000000100000
>[ 9389.544947] PM: Basic memory bitmaps created
>[ 9389.776354] Syncing filesystems ... done.
>[ 9389.817592] Freezing user space processes ... (elapsed 0.01 seconds) done.
>[ 9389.833209] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
>[ 9389.844566] PM: Preallocating image memory... done (allocated 415513 pages)
>[ 9392.031542] PM: Allocated 1662052 kbytes in 2.18 seconds (762.40 MB/s)
>[ 9392.031548] Suspending console(s) (use no_console_suspend to debug)
>[ 9392.033597] r8169 0000:04:00.0: eth0: link down
>[ 9392.185084] snd_hda_intel 0000:00:08.0: PCI INT A disabled
>[ 9392.196192] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D3
>[ 9392.434084] sd 1:0:0:0: [sda] Synchronizing SCSI cache
>[ 9392.475871] PM: freeze of devices complete after 444.305 msecs
>[ 9392.476767] PM: late freeze of devices complete after 0.884 msecs
>[ 9392.476820] ACPI: Preparing to enter system sleep state S4
>[ 9392.481513] PM: Saving platform NVS memory
>[ 9392.482631] Disabling non-boot CPUs ...
>[ 9392.485309] CPU 1 is now offline
>[ 9392.488724] CPU 2 is now offline
>[ 9392.491617] CPU 3 is now offline
>[ 9392.492967] Extended CMOS year: 2000
>[ 9392.493035] PM: Creating hibernation image:
>[ 9392.493035] PM: Need to copy 365721 pages
>[ 9392.493035] PM: Normal pages needed: 77284 + 1024, available pages: 148338
>[ 9392.493035] PM: Restoring platform NVS memory
>[ 9392.493035] Extended CMOS year: 2000
>[ 9392.499121] Enabling non-boot CPUs ...
>[ 9392.501626] Booting Node 0 Processor 1 APIC 0x2
>[ 9392.501631] smpboot cpu 1: start_ip = 9b000
>[ 9392.485000] Initializing CPU#1
>[ 9392.485000] Calibrating delay loop (skipped) already calibrated this CPU
>[ 9392.485000] Disabled fast string operations
>[ 9392.513038] Switched to NOHz mode on CPU #1
>[ 9392.513439] NMI watchdog enabled, takes one hw-pmu counter.
>[ 9392.515162] CPU1 is up
>[ 9392.515636] Booting Node 0 Processor 2 APIC 0x1
>[ 9392.515641] smpboot cpu 2: start_ip = 9b000
>[ 9392.488416] Initializing CPU#2
>[ 9392.488416] Calibrating delay loop (skipped) already calibrated this CPU
>[ 9392.488416] Disabled fast string operations
>[ 9392.527033] Switched to NOHz mode on CPU #2
>[ 9392.527909] NMI watchdog enabled, takes one hw-pmu counter.
>[ 9392.529156] CPU2 is up
>[ 9392.529701] Booting Node 0 Processor 3 APIC 0x3
>[ 9392.529706] smpboot cpu 3: start_ip = 9b000
>[ 9392.491438] Initializing CPU#3
>[ 9392.491438] Calibrating delay loop (skipped) already calibrated this CPU
>[ 9392.491438] Disabled fast string operations
>[ 9392.541039] Switched to NOHz mode on CPU #3
>[ 9392.542458] NMI watchdog enabled, takes one hw-pmu counter.
>[ 9392.544177] CPU3 is up
>[ 9392.546355] ACPI: Waking up from system sleep state S4
>[ 9392.550232] snd_hda_intel 0000:00:08.0: restoring config space at offset 0x1 (was 0xb00006, writing 0xb00002)
>[ 9392.550298] ahci 0000:00:0b.0: restoring config space at offset 0x1 (was 0xb00403, writing 0xb00407)
>[ 9392.550714] nvidia 0000:03:00.0: restoring config space at offset 0xc (was 0xfbee0000, writing 0x0)
>[ 9392.550731] nvidia 0000:03:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0)
>[ 9392.550740] nvidia 0000:03:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100007)
>[ 9392.550806] r8169 0000:04:00.0: restoring config space at offset 0x1 (was 0x100007, writing 0x100407)
>
>[ 9392.550998] PM: early restore of devices complete after 1.338 msecs
>[ 9392.783917] ohci_hcd 0000:00:04.0: setting latency timer to 64
>[ 9392.783954] ehci_hcd 0000:00:04.1: setting latency timer to 64
>[ 9392.783969] ohci_hcd 0000:00:06.0: setting latency timer to 64
>[ 9392.784057] ehci_hcd 0000:00:06.1: setting latency timer to 64
>[ 9392.784145] pci 0000:00:09.0: setting latency timer to 64
>[ 9392.784163] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 9392.784654] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 9392.784690] ahci 0000:00:0b.0: setting latency timer to 64
>[ 9392.784729] pci 0000:00:10.0: setting latency timer to 64
>[ 9392.784841] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 9392.784859] snd_hda_intel 0000:00:08.0: power state changed by ACPI to D0
>[ 9392.784884] snd_hda_intel 0000:00:08.0: PCI INT A -> Link[LAZA] -> GSI 20 (level, low) -> IRQ 20
>[ 9392.784898] snd_hda_intel 0000:00:08.0: setting latency timer to 64
>[ 9392.785368] Extended CMOS year: 2000
>[ 9392.787059] sd 1:0:0:0: [sda] Starting disk
>[ 9392.836065] usb usb3: root hub lost power or was reset
>[ 9392.836200] r8169 0000:04:00.0: eth0: link down
>[ 9392.836221] usb usb1: root hub lost power or was reset
>[ 9392.836242] ehci_hcd 0000:00:04.1: cache line size of 64 is not supported
>[ 9392.837151] usb usb4: root hub lost power or was reset
>[ 9392.837222] usb usb2: root hub lost power or was reset
>[ 9392.837239] ehci_hcd 0000:00:06.1: cache line size of 64 is not supported
>[ 9393.089070] ata3: SATA link down (SStatus 0 SControl 300)
>[ 9393.089108] ata1: SATA link down (SStatus 0 SControl 300)
>[ 9393.089138] ata4: SATA link down (SStatus 0 SControl 300)
>[ 9393.089170] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>[ 9393.091141] ata2.00: configured for UDMA/133
>[ 9393.261074] usb 3-2: reset full speed USB device number 2 using ohci_hcd
>[ 9393.444145] btusb 3-2:1.0: no reset_resume for driver btusb?
>[ 9393.444152] btusb 3-2:1.1: no reset_resume for driver btusb?
>[ 9393.742081] usb 4-1: reset low speed USB device number 2 using ohci_hcd
>[ 9394.205079] usb 2-3: reset high speed USB device number 3 using ehci_hcd
>[ 9394.498950] r8169 0000:04:00.0: eth0: link up
>[ 9394.776531] usb 2-3.2: reset low speed USB device number 4 using ehci_hcd
>[ 9395.048553] PM: restore of devices complete after 2264.783 msecs
>[ 9395.103568] snapshot_ioctl: ioctl '4004330c' is deprecated and will be removed soon, update your suspend-to-disk utilities
>[ 9395.103579] Restarting tasks ... done.
>[ 9395.190427] PM: Basic memory bitmaps freed
>linux-mkzk:~ # 
>linux-mkzk:~ # man dmesg
>linux-mkzk:~ # 
>

Actions: View

Attachments on bug 772231: 499250 | 499251