Jun 1 09:10:13 gvs NetworkManager: Sleeping... Jun 1 09:10:13 gvs NetworkManager: (eth0): now unmanaged Jun 1 09:10:13 gvs NetworkManager: (eth0): device state change: 2 -> 1 (reason 37) Jun 1 09:10:13 gvs NetworkManager: (eth0): cleaning up... Jun 1 09:10:13 gvs NetworkManager: (eth0): taking down device. Jun 1 09:10:13 gvs NetworkManager: (wlan0): now unmanaged Jun 1 09:10:13 gvs NetworkManager: (wlan0): device state change: 8 -> 1 (reason 37) Jun 1 09:10:13 gvs NetworkManager: (wlan0): deactivating device (reason: 37). Jun 1 09:10:13 gvs kernel: [90353.616052] sky2 eth0: disabling interface Jun 1 09:10:13 gvs NetworkManager: (wlan0): canceled DHCP transaction, dhcp client pid 22326 Jun 1 09:10:13 gvs kernel: [90353.833347] wlan0: deauthenticating from 00:1e:58:e5:9c:b8 by local choice (reason=3) Jun 1 09:10:13 gvs NetworkManager: (wlan0): cleaning up... Jun 1 09:10:13 gvs NetworkManager: (wlan0): taking down device. Jun 1 09:10:13 gvs NetworkManager: (wmx0): now unmanaged Jun 1 09:10:13 gvs NetworkManager: (wmx0): device state change: 3 -> 1 (reason 37) Jun 1 09:10:13 gvs NetworkManager: (wmx0): cleaning up... Jun 1 09:10:13 gvs NetworkManager: (wmx0): taking down device. Jun 1 09:10:13 gvs NetworkManager: SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0) Jun 1 10:03:05 gvs kernel: [90354.908608] PM: Syncing filesystems ... done. Jun 1 10:03:05 gvs kernel: [90355.152035] PM: Preparing system for mem sleep Jun 1 10:03:05 gvs kernel: [90355.152040] Freezing user space processes ... (elapsed 0.00 seconds) done. Jun 1 10:03:05 gvs NetworkManager: bluez_manager_bdaddr_removed_cb: BT device 9C:18:74:B2:FC:F7 removed Jun 1 10:03:05 gvs kernel: [90355.152858] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. Jun 1 10:03:05 gvs kernel: [90355.152902] PM: Entering mem sleep Jun 1 10:03:05 gvs kernel: [90355.152919] Suspending console(s) (use no_console_suspend to debug) Jun 1 10:03:05 gvs kernel: [90355.174340] btusb_intr_complete: hci0 urb eba6c580 failed to resubmit (1) Jun 1 10:03:05 gvs kernel: [90355.175342] btusb_bulk_complete: hci0 urb ee6bf300 failed to resubmit (1) Jun 1 10:03:05 gvs kernel: [90355.176345] btusb_bulk_complete: hci0 urb eba6c880 failed to resubmit (1) Jun 1 10:03:05 gvs kernel: [90355.240374] sd 0:0:0:0: [sda] Synchronizing SCSI cache Jun 1 10:03:05 gvs kernel: [90355.240590] sd 0:0:0:0: [sda] Stopping disk Jun 1 10:03:05 gvs kernel: [90355.738307] PM: suspend of drv:sd dev:0:0:0:0 complete after 497.932 msecs Jun 1 10:03:05 gvs kernel: [90356.174477] PM: suspend of drv:psmouse dev:serio4 complete after 422.144 msecs Jun 1 10:03:05 gvs kernel: [90356.388217] PM: suspend of drv:atkbd dev:serio0 complete after 213.724 msecs Jun 1 10:03:05 gvs kernel: [90356.389803] ACPI handle has no context! Jun 1 10:03:05 gvs kernel: [90356.389917] ACPI handle has no context! Jun 1 10:03:05 gvs kernel: [90356.389925] sdhci-pci 0000:0a:01.2: PCI INT A disabled Jun 1 10:03:05 gvs kernel: [90356.389932] ACPI handle has no context! Jun 1 10:03:05 gvs kernel: [90356.406267] ACPI handle has no context! Jun 1 10:03:05 gvs kernel: [90356.532233] ehci_hcd 0000:00:1d.7: PCI INT A disabled Jun 1 10:03:05 gvs kernel: [90356.532246] uhci_hcd 0000:00:1d.2: PCI INT C disabled Jun 1 10:03:05 gvs kernel: [90356.532258] uhci_hcd 0000:00:1d.1: PCI INT B disabled Jun 1 10:03:05 gvs kernel: [90356.532269] uhci_hcd 0000:00:1d.0: PCI INT A disabled Jun 1 10:03:05 gvs kernel: [90356.636531] HDA Intel 0000:00:1b.0: PCI INT A disabled Jun 1 10:03:05 gvs kernel: [90356.652219] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.928 msecs Jun 1 10:03:05 gvs kernel: [90356.652231] ehci_hcd 0000:00:1a.7: PCI INT C disabled Jun 1 10:03:05 gvs kernel: [90356.652243] uhci_hcd 0000:00:1a.2: PCI INT C disabled Jun 1 10:03:05 gvs kernel: [90356.652255] uhci_hcd 0000:00:1a.1: PCI INT B disabled Jun 1 10:03:05 gvs kernel: [90356.652267] uhci_hcd 0000:00:1a.0: PCI INT A disabled Jun 1 10:03:05 gvs kernel: [90356.672423] PM: suspend of devices complete after 1519.167 msecs Jun 1 10:03:05 gvs kernel: [90356.672426] PM: suspend devices took 1.520 seconds Jun 1 10:03:05 gvs kernel: [90356.704222] PM: late suspend of devices complete after 31.792 msecs Jun 1 10:03:05 gvs kernel: [90356.704558] ACPI: Preparing to enter system sleep state S3 Jun 1 10:03:05 gvs kernel: [90356.740011] Disabling non-boot CPUs ... Jun 1 10:03:05 gvs kernel: [90356.740029] CPU0 attaching NULL sched-domain. Jun 1 10:03:05 gvs kernel: [90356.740032] CPU1 attaching NULL sched-domain. Jun 1 10:03:05 gvs kernel: [90356.804014] CPU0 attaching NULL sched-domain. Jun 1 10:03:05 gvs kernel: [90356.908019] CPU 1 is now offline Jun 1 10:03:05 gvs kernel: [90356.908021] SMP alternatives: switching to UP code Jun 1 10:03:05 gvs kernel: [90356.914463] Extended CMOS year: 2000 Jun 1 10:03:05 gvs kernel: [90356.914463] Back to C! Jun 1 10:03:05 gvs kernel: [90356.914463] CPU0: Thermal monitoring enabled (TM2) Jun 1 10:03:05 gvs kernel: [90356.914463] Extended CMOS year: 2000 Jun 1 10:03:05 gvs kernel: [90356.914463] Enabling non-boot CPUs ... Jun 1 10:03:05 gvs kernel: [90356.914463] SMP alternatives: switching to SMP code Jun 1 10:03:05 gvs kernel: [90356.920622] Booting processor 1 APIC 0x1 ip 0x6000 Jun 1 10:03:05 gvs kernel: [90356.914098] Initializing CPU#1 Jun 1 10:03:05 gvs kernel: [90356.914098] CPU: L1 I cache: 32K, L1 D cache: 32K Jun 1 10:03:05 gvs kernel: [90356.914098] CPU: L2 cache: 2048K Jun 1 10:03:05 gvs kernel: [90356.914098] CPU: Physical Processor ID: 0 Jun 1 10:03:05 gvs kernel: [90356.914098] CPU: Processor Core ID: 1 Jun 1 10:03:05 gvs kernel: [90356.914098] CPU1: Thermal monitoring enabled (TM2) Jun 1 10:03:05 gvs kernel: [90357.008091] CPU1: Intel(R) Core(TM)2 Duo CPU T6500 @ 2.10GHz stepping 0a Jun 1 10:03:05 gvs kernel: [90357.008155] CPU0 attaching NULL sched-domain. Jun 1 10:03:05 gvs kernel: [90357.036016] CPU0 attaching sched-domain: Jun 1 10:03:05 gvs kernel: [90357.036018] domain 0: span 0-1 level MC Jun 1 10:03:05 gvs kernel: [90357.036021] groups: 0 1 Jun 1 10:03:05 gvs kernel: [90357.036025] CPU1 attaching sched-domain: Jun 1 10:03:05 gvs kernel: [90357.036027] domain 0: span 0-1 level MC Jun 1 10:03:05 gvs kernel: [90357.036028] groups: 1 0 Jun 1 10:03:05 gvs kernel: [90357.036449] CPU1 is up Jun 1 10:03:05 gvs kernel: [90357.036929] ACPI: Waking up from system sleep state S3 Jun 1 10:03:05 gvs kernel: [90357.132212] i915 0000:00:02.0: restoring config space at offset 0xf (was 0x100, writing 0x105) Jun 1 10:03:05 gvs kernel: [90357.132225] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) Jun 1 10:03:05 gvs kernel: [90357.132254] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) Jun 1 10:03:05 gvs kernel: [90357.132292] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) Jun 1 10:03:05 gvs kernel: [90357.132334] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) Jun 1 10:03:05 gvs kernel: [90357.132376] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) Jun 1 10:03:05 gvs kernel: [90357.132427] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) Jun 1 10:03:05 gvs kernel: [90357.132463] HDA Intel 0000:00:1b.0: restoring config space at offset 0xf (was 0x100, writing 0x10a) Jun 1 10:03:05 gvs kernel: [90357.132483] HDA Intel 0000:00:1b.0: restoring config space at offset 0x4 (was 0x4, writing 0xf4800004) Jun 1 10:03:05 gvs kernel: [90357.132489] HDA Intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) Jun 1 10:03:05 gvs kernel: [90357.132495] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100002) Jun 1 10:03:05 gvs kernel: [90357.132530] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x40100, writing 0x4010b) Jun 1 10:03:05 gvs kernel: [90357.132542] pcieport 0000:00:1c.0: restoring config space at offset 0x9 (was 0x10001, writing 0x80318021) Jun 1 10:03:05 gvs kernel: [90357.132547] pcieport 0000:00:1c.0: restoring config space at offset 0x8 (was 0x0, writing 0x80108000) Jun 1 10:03:05 gvs kernel: [90357.132553] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0x20000000, writing 0x2020) Jun 1 10:03:05 gvs kernel: [90357.132558] pcieport 0000:00:1c.0: restoring config space at offset 0x6 (was 0x0, writing 0x30200) Jun 1 10:03:05 gvs kernel: [90357.132566] pcieport 0000:00:1c.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) Jun 1 10:03:05 gvs kernel: [90357.132573] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) Jun 1 10:03:05 gvs kernel: [90357.132624] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x40100, writing 0x4010b) Jun 1 10:03:05 gvs kernel: [90357.132637] pcieport 0000:00:1c.4: restoring config space at offset 0x9 (was 0x10001, writing 0x80718061) Jun 1 10:03:05 gvs kernel: [90357.132642] pcieport 0000:00:1c.4: restoring config space at offset 0x8 (was 0x0, writing 0x80508040) Jun 1 10:03:05 gvs kernel: [90357.132647] pcieport 0000:00:1c.4: restoring config space at offset 0x7 (was 0x20000000, writing 0x3030) Jun 1 10:03:05 gvs kernel: [90357.132657] pcieport 0000:00:1c.4: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) Jun 1 10:03:05 gvs kernel: [90357.132663] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) Jun 1 10:03:05 gvs kernel: [90357.132713] pcieport 0000:00:1c.5: restoring config space at offset 0xf (was 0x40200, writing 0x40205) Jun 1 10:03:05 gvs kernel: [90357.132726] pcieport 0000:00:1c.5: restoring config space at offset 0x9 (was 0x10001, writing 0x80b180a1) Jun 1 10:03:05 gvs kernel: [90357.132731] pcieport 0000:00:1c.5: restoring config space at offset 0x8 (was 0x0, writing 0x80908080) Jun 1 10:03:05 gvs kernel: [90357.132736] pcieport 0000:00:1c.5: restoring config space at offset 0x7 (was 0x20000000, writing 0x4040) Jun 1 10:03:05 gvs kernel: [90357.132746] pcieport 0000:00:1c.5: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) Jun 1 10:03:05 gvs kernel: [90357.132752] pcieport 0000:00:1c.5: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) Jun 1 10:03:05 gvs kernel: [90357.132817] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) Jun 1 10:03:05 gvs kernel: [90357.132859] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) Jun 1 10:03:05 gvs kernel: [90357.132900] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) Jun 1 10:03:05 gvs kernel: [90357.132950] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) Jun 1 10:03:05 gvs kernel: [90357.132978] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x40000, writing 0x400ff) Jun 1 10:03:05 gvs kernel: [90357.132991] pci 0000:00:1e.0: restoring config space at offset 0x9 (was 0x10001, writing 0x1fff1) Jun 1 10:03:05 gvs kernel: [90357.132996] pci 0000:00:1e.0: restoring config space at offset 0x8 (was 0x0, writing 0xf470f470) Jun 1 10:03:05 gvs kernel: [90357.133002] pci 0000:00:1e.0: restoring config space at offset 0x7 (was 0xa2800000, writing 0xa28000f0) Jun 1 10:03:05 gvs kernel: [90357.133016] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) Jun 1 10:03:05 gvs kernel: [90357.133096] ahci 0000:00:1f.2: restoring config space at offset 0xf (was 0x200, writing 0x20a) Jun 1 10:03:05 gvs kernel: [90357.133120] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) Jun 1 10:03:05 gvs kernel: [90357.133176] pci 0000:00:1f.3: restoring config space at offset 0x4 (was 0x4, writing 0x80c00004) Jun 1 10:03:05 gvs kernel: [90357.133231] sky2 0000:07:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) Jun 1 10:03:05 gvs kernel: [90357.133257] sky2 0000:07:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x3001) Jun 1 10:03:05 gvs kernel: [90357.133267] sky2 0000:07:00.0: restoring config space at offset 0x4 (was 0x4, writing 0x80400004) Jun 1 10:03:05 gvs kernel: [90357.133274] sky2 0000:07:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) Jun 1 10:03:05 gvs kernel: [90357.133283] sky2 0000:07:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) Jun 1 10:03:05 gvs kernel: [90357.133370] iwlagn 0000:08:00.0: restoring config space at offset 0xf (was 0x100, writing 0x105) Jun 1 10:03:05 gvs kernel: [90357.133406] iwlagn 0000:08:00.0: restoring config space at offset 0x4 (was 0x4, writing 0x80800004) Jun 1 10:03:05 gvs kernel: [90357.133414] iwlagn 0000:08:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) Jun 1 10:03:05 gvs kernel: [90357.133425] iwlagn 0000:08:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100406) Jun 1 10:03:05 gvs kernel: [90357.133513] ohci1394 0000:0a:01.0: restoring config space at offset 0x5 (was 0x0, writing 0xf4700000) Jun 1 10:03:05 gvs kernel: [90357.133520] ohci1394 0000:0a:01.0: restoring config space at offset 0x3 (was 0x800000, writing 0x802010) Jun 1 10:03:05 gvs kernel: [90357.133527] ohci1394 0000:0a:01.0: restoring config space at offset 0x1 (was 0x2100080, writing 0x2180097) Jun 1 10:03:05 gvs kernel: [90357.133573] sdhci-pci 0000:0a:01.2: restoring config space at offset 0x4 (was 0x0, writing 0xf4700800) Jun 1 10:03:05 gvs kernel: [90357.133579] sdhci-pci 0000:0a:01.2: restoring config space at offset 0x3 (was 0x0, writing 0x2010) Jun 1 10:03:05 gvs kernel: [90357.133587] sdhci-pci 0000:0a:01.2: restoring config space at offset 0x1 (was 0x4100000, writing 0x4100006) Jun 1 10:03:05 gvs kernel: [90357.133635] pci 0000:0a:01.3: restoring config space at offset 0x4 (was 0x0, writing 0xf4702000) Jun 1 10:03:05 gvs kernel: [90357.133641] pci 0000:0a:01.3: restoring config space at offset 0x3 (was 0x0, writing 0x2010) Jun 1 10:03:05 gvs kernel: [90357.133649] pci 0000:0a:01.3: restoring config space at offset 0x1 (was 0x4100000, writing 0x4100002) Jun 1 10:03:05 gvs kernel: [90357.134059] PM: early resume of devices complete after 1.955 msecs Jun 1 10:03:05 gvs kernel: [90357.496379] PM: resume of drv:battery dev:PNP0C0A:00 complete after 361.325 msecs Jun 1 10:03:05 gvs kernel: [90357.522402] i915 0000:00:02.0: setting latency timer to 64 Jun 1 10:03:05 gvs kernel: [90357.664365] PM: resume of drv:i915 dev:0000:00:02.0 complete after 141.967 msecs Jun 1 10:03:05 gvs kernel: [90357.664378] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 Jun 1 10:03:05 gvs kernel: [90357.664385] uhci_hcd 0000:00:1a.0: setting latency timer to 64 Jun 1 10:03:05 gvs kernel: [90357.664409] usb usb3: root hub lost power or was reset Jun 1 10:03:05 gvs kernel: [90357.664432] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 Jun 1 10:03:05 gvs kernel: [90357.664439] uhci_hcd 0000:00:1a.1: setting latency timer to 64 Jun 1 10:03:05 gvs kernel: [90357.664462] usb usb4: root hub lost power or was reset Jun 1 10:03:05 gvs kernel: [90357.664483] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 19 (level, low) -> IRQ 19 Jun 1 10:03:05 gvs kernel: [90357.664490] uhci_hcd 0000:00:1a.2: setting latency timer to 64 Jun 1 10:03:05 gvs kernel: [90357.664514] usb usb5: root hub lost power or was reset Jun 1 10:03:05 gvs kernel: [90357.664534] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 19 (level, low) -> IRQ 19 Jun 1 10:03:05 gvs kernel: [90357.664540] ehci_hcd 0000:00:1a.7: setting latency timer to 64 Jun 1 10:03:05 gvs kernel: [90357.664553] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 Jun 1 10:03:05 gvs kernel: [90357.664560] HDA Intel 0000:00:1b.0: setting latency timer to 64 Jun 1 10:03:05 gvs kernel: [90357.664594] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 Jun 1 10:03:05 gvs kernel: [90357.664600] uhci_hcd 0000:00:1d.0: setting latency timer to 64 Jun 1 10:03:05 gvs kernel: [90357.664624] usb usb6: root hub lost power or was reset Jun 1 10:03:05 gvs kernel: [90357.664657] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 Jun 1 10:03:05 gvs kernel: [90357.664663] uhci_hcd 0000:00:1d.1: setting latency timer to 64 Jun 1 10:03:05 gvs kernel: [90357.664686] usb usb7: root hub lost power or was reset Jun 1 10:03:05 gvs kernel: [90357.664719] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 Jun 1 10:03:05 gvs kernel: [90357.664725] uhci_hcd 0000:00:1d.2: setting latency timer to 64 Jun 1 10:03:05 gvs kernel: [90357.664748] usb usb8: root hub lost power or was reset Jun 1 10:03:05 gvs kernel: [90357.664769] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 Jun 1 10:03:05 gvs kernel: [90357.664775] ehci_hcd 0000:00:1d.7: setting latency timer to 64 Jun 1 10:03:05 gvs kernel: [90357.664801] pci 0000:00:1e.0: setting latency timer to 64 Jun 1 10:03:05 gvs kernel: [90357.664817] ahci 0000:00:1f.2: setting latency timer to 64 Jun 1 10:03:05 gvs kernel: [90357.721236] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[16] MMIO=[ff501000-ff5017ff] Max Packet=[2048] IR/IT contexts=[8/8] Jun 1 10:03:05 gvs kernel: [90357.724324] sdhci-pci 0000:0a:01.2: PCI INT A -> GSI 16 (level, low) -> IRQ 16 Jun 1 10:03:05 gvs kernel: [90357.992222] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Jun 1 10:03:05 gvs kernel: [90358.000237] ata6: SATA link down (SStatus 0 SControl 300) Jun 1 10:03:05 gvs kernel: [90358.000753] ata2.00: configured for UDMA/133 Jun 1 10:03:05 gvs kernel: [90358.004210] PM: resume of drv:usb dev:usb2 complete after 251.951 msecs Jun 1 10:03:05 gvs kernel: [90358.008227] ata5: SATA link down (SStatus 0 SControl 300) Jun 1 10:03:05 gvs kernel: [90358.252217] PM: resume of drv:usb dev:usb6 complete after 247.945 msecs Jun 1 10:03:05 gvs kernel: [90358.500216] PM: resume of drv:usb dev:usb7 complete after 247.981 msecs Jun 1 10:03:05 gvs kernel: [90358.612220] usb 2-4: reset high speed USB device using ehci_hcd and address 4 Jun 1 10:03:05 gvs kernel: [90358.755574] PM: resume of drv:usb dev:2-4 complete after 253.520 msecs Jun 1 10:03:05 gvs kernel: [90358.755595] sd 0:0:0:0: [sda] Starting disk Jun 1 10:03:05 gvs kernel: [90359.776221] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Jun 1 10:03:05 gvs kernel: [90359.777454] ata1.00: _GTF unexpected object type 0x1 Jun 1 10:03:05 gvs kernel: [90359.781764] ata1.00: _GTF unexpected object type 0x1 Jun 1 10:03:05 gvs kernel: [90359.782404] ata1.00: configured for UDMA/133 Jun 1 10:03:05 gvs kernel: [90359.801612] PM: resume of drv:sd dev:0:0:0:0 complete after 1046.017 msecs Jun 1 10:03:05 gvs kernel: [90359.912216] usb 7-1: reset full speed USB device using uhci_hcd and address 2 Jun 1 10:03:05 gvs kernel: [90360.067263] btusb 7-1:1.0: no reset_resume for driver btusb? Jun 1 10:03:05 gvs kernel: [90360.067266] btusb 7-1:1.1: no reset_resume for driver btusb? Jun 1 10:03:05 gvs kernel: [90360.316334] PM: resume of drv:usb dev:7-1 complete after 514.503 msecs Jun 1 10:03:05 gvs kernel: [90360.367457] i2400m_usb 2-6:1.0: 'RF Control' (0x4602) command failed: -84 - invalid state (3) Jun 1 10:03:05 gvs kernel: [90360.624216] usb 6-1: reset low speed USB device using uhci_hcd and address 5 Jun 1 10:03:05 gvs kernel: [90360.913262] usb 6-1: device firmware changed Jun 1 10:03:05 gvs kernel: [90360.913282] PM: resume of drv:usb dev:6-1 complete after 545.797 msecs Jun 1 10:03:05 gvs kernel: [90360.913311] PM: resume of devices complete after 3778.912 msecs Jun 1 10:03:05 gvs kernel: [90360.913608] PM: resume devices took 3.780 seconds Jun 1 10:03:05 gvs kernel: [90360.913639] PM: Finishing wakeup. Jun 1 10:03:05 gvs kernel: [90360.913641] Restarting tasks ... Jun 1 10:03:05 gvs kernel: [90360.913729] usb 6-1: USB disconnect, address 5 Jun 1 10:03:05 gvs kernel: [90360.958156] done. Jun 1 10:03:05 gvs kernel: [90361.049041] usb 6-1: new low speed USB device using uhci_hcd and address 6 Jun 1 10:03:05 gvs kernel: [90361.227357] usb 6-1: configuration #1 chosen from 1 choice Jun 1 10:03:05 gvs kernel: [90361.253550] input: Microsoft Microsoft Basic Optical Mouse as /devices/pci0000:00/0000:00:1d.0/usb6/6-1/6-1:1.0/input/input16 Jun 1 10:03:05 gvs kernel: [90361.253662] generic-usb 0003:045E:0084.0005: input,hidraw0: USB HID v1.11 Mouse [Microsoft Microsoft Basic Optical Mouse] on usb-0000:00:1d.0-1/input0 Jun 1 10:03:05 gvs NetworkManager: Waking up... Jun 1 10:03:05 gvs NetworkManager: (eth0): now managed Jun 1 10:03:05 gvs NetworkManager: (eth0): device state change: 1 -> 2 (reason 2) Jun 1 10:03:05 gvs NetworkManager: (eth0): bringing up device. Jun 1 10:03:05 gvs NetworkManager: (eth0): preparing device. Jun 1 10:03:05 gvs NetworkManager: (eth0): deactivating device (reason: 2). Jun 1 10:03:05 gvs NetworkManager: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Jun 1 10:03:05 gvs NetworkManager: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Jun 1 10:03:05 gvs NetworkManager: (wlan0): now managed Jun 1 10:03:05 gvs NetworkManager: (wlan0): device state change: 1 -> 2 (reason 2) Jun 1 10:03:05 gvs NetworkManager: (wlan0): bringing up device. Jun 1 10:03:05 gvs kernel: [90361.298681] sky2 eth0: enabling interface Jun 1 10:03:05 gvs kernel: [90361.299021] ADDRCONF(NETDEV_UP): eth0: link is not ready Jun 1 10:03:05 gvs kernel: [90361.328981] Registered led device: iwl-phy0::radio Jun 1 10:03:05 gvs kernel: [90361.329079] Registered led device: iwl-phy0::assoc Jun 1 10:03:05 gvs kernel: [90361.329428] Registered led device: iwl-phy0::RX Jun 1 10:03:05 gvs kernel: [90361.329532] Registered led device: iwl-phy0::TX Jun 1 10:03:05 gvs NetworkManager: (wlan0): preparing device. Jun 1 10:03:05 gvs NetworkManager: (wlan0): deactivating device (reason: 2). Jun 1 10:03:05 gvs NetworkManager: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Jun 1 10:03:05 gvs NetworkManager: (wmx0): now managed Jun 1 10:03:05 gvs NetworkManager: (wmx0): device state change: 1 -> 2 (reason 2) Jun 1 10:03:05 gvs NetworkManager: (wmx0): bringing up device. Jun 1 10:03:05 gvs NetworkManager: (wmx0): preparing device. Jun 1 10:03:05 gvs NetworkManager: (wmx0): deactivating device (reason: 2). Jun 1 10:03:05 gvs kernel: [90361.340762] ADDRCONF(NETDEV_UP): wlan0: link is not ready Jun 1 10:03:05 gvs kernel: [90361.342509] ADDRCONF(NETDEV_UP): wmx0: link is not ready Jun 1 10:03:05 gvs NetworkManager: (wmx0): device state change: 2 -> 3 (reason 0) Jun 1 10:03:05 gvs NetworkManager: Activation (wmx0) starting connection 'Auto wmx0' Jun 1 10:03:05 gvs NetworkManager: (wmx0): device state change: 3 -> 4 (reason 0) Jun 1 10:03:05 gvs NetworkManager: Activation (wmx0) Stage 1 of 5 (Device Prepare) scheduled... Jun 1 10:03:05 gvs NetworkManager: Activation (wmx0) Stage 1 of 5 (Device Prepare) started... Jun 1 10:03:05 gvs NetworkManager: Activation (wmx0) Stage 2 of 5 (Device Configure) scheduled... Jun 1 10:03:05 gvs NetworkManager: Activation (wmx0) Stage 1 of 5 (Device Prepare) complete. Jun 1 10:03:05 gvs NetworkManager: Activation (wmx0) Stage 2 of 5 (Device Configure) starting... Jun 1 10:03:05 gvs NetworkManager: (wmx0): device state change: 4 -> 5 (reason 0) Jun 1 10:03:05 gvs NetworkManager: Activation (wmx0) Stage 2 of 5 (Device Configure) successful. Jun 1 10:03:05 gvs NetworkManager: Activation (wmx0) Stage 3 of 5 (IP Configure Start) scheduled. Jun 1 10:03:05 gvs NetworkManager: Activation (wmx0) Stage 2 of 5 (Device Configure) complete. Jun 1 10:03:05 gvs NetworkManager: Activation (wmx0) Stage 3 of 5 (IP Configure Start) started... Jun 1 10:03:05 gvs NetworkManager: (wmx0): device state change: 5 -> 7 (reason 0) Jun 1 10:03:05 gvs NetworkManager: Activation (wmx0) Beginning DHCP transaction (timeout in 45 seconds) Jun 1 10:03:05 gvs NetworkManager: dhclient started with pid 23183 Jun 1 10:03:05 gvs NetworkManager: Activation (wmx0) Stage 4 of 5 (IP6 Configure Get) scheduled... Jun 1 10:03:05 gvs NetworkManager: Activation (wmx0) Stage 3 of 5 (IP Configure Start) complete. Jun 1 10:03:05 gvs NetworkManager: Activation (wmx0) Stage 4 of 5 (IP6 Configure Get) started... Jun 1 10:03:05 gvs NetworkManager: Activation (wmx0) Stage 4 of 5 (IP6 Configure Get) complete. Jun 1 10:03:05 gvs dhclient: Internet Systems Consortium DHCP Client V3.1.3 Jun 1 10:03:05 gvs dhclient: Copyright 2004-2009 Internet Systems Consortium. Jun 1 10:03:05 gvs dhclient: All rights reserved. Jun 1 10:03:05 gvs dhclient: For info, please visit https://www.isc.org/software/dhcp/ Jun 1 10:03:05 gvs dhclient: Jun 1 10:03:05 gvs NetworkManager: (wlan0): supplicant interface state: starting -> ready Jun 1 10:03:05 gvs NetworkManager: (wlan0): device state change: 2 -> 3 (reason 42) Jun 1 10:03:05 gvs NetworkManager: DHCP: device wmx0 state changed normal exit -> preinit Jun 1 10:03:05 gvs dhclient: Listening on LPF/wmx0/00:1d:e1:08:f9:d5 Jun 1 10:03:05 gvs dhclient: Sending on LPF/wmx0/00:1d:e1:08:f9:d5 Jun 1 10:03:05 gvs dhclient: Sending on Socket/fallback Jun 1 10:03:07 gvs NetworkManager: [1275372187.000564] ensure_killed(): waiting for vpn service pid 22727 to exit Jun 1 10:03:07 gvs NetworkManager: [1275372187.000701] ensure_killed(): vpn service pid 22727 cleaned up Jun 1 10:03:08 gvs NetworkManager: (eth0): carrier now ON (device state 2) Jun 1 10:03:08 gvs NetworkManager: (eth0): device state change: 2 -> 3 (reason 40) Jun 1 10:03:08 gvs NetworkManager: Activation (eth0) starting connection 'Auto eth0' Jun 1 10:03:08 gvs NetworkManager: (eth0): device state change: 3 -> 4 (reason 0) Jun 1 10:03:08 gvs NetworkManager: Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled... Jun 1 10:03:08 gvs kernel: [90364.050337] sky2 eth0: Link is up at 100 Mbps, full duplex, flow control both Jun 1 10:03:08 gvs kernel: [90364.050650] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Jun 1 10:03:08 gvs NetworkManager: Activation (eth0) Stage 1 of 5 (Device Prepare) started... Jun 1 10:03:08 gvs NetworkManager: Activation (eth0) Stage 2 of 5 (Device Configure) scheduled... Jun 1 10:03:08 gvs NetworkManager: Activation (eth0) Stage 1 of 5 (Device Prepare) complete. Jun 1 10:03:08 gvs NetworkManager: Activation (eth0) Stage 2 of 5 (Device Configure) starting... Jun 1 10:03:08 gvs NetworkManager: (eth0): device state change: 4 -> 5 (reason 0) Jun 1 10:03:08 gvs NetworkManager: Activation (eth0) Stage 2 of 5 (Device Configure) successful. Jun 1 10:03:08 gvs NetworkManager: Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled. Jun 1 10:03:08 gvs NetworkManager: Activation (eth0) Stage 2 of 5 (Device Configure) complete. Jun 1 10:03:08 gvs NetworkManager: Activation (eth0) Stage 3 of 5 (IP Configure Start) started... Jun 1 10:03:08 gvs NetworkManager: (eth0): device state change: 5 -> 7 (reason 0) Jun 1 10:03:08 gvs NetworkManager: Activation (eth0) Beginning DHCP transaction (timeout in 45 seconds) Jun 1 10:03:08 gvs NetworkManager: dhclient started with pid 23341 Jun 1 10:03:08 gvs NetworkManager: Activation (eth0) Stage 4 of 5 (IP6 Configure Get) scheduled... Jun 1 10:03:08 gvs NetworkManager: Activation (eth0) Stage 3 of 5 (IP Configure Start) complete. Jun 1 10:03:08 gvs NetworkManager: Activation (eth0) Stage 4 of 5 (IP6 Configure Get) started... Jun 1 10:03:08 gvs NetworkManager: Activation (eth0) Stage 4 of 5 (IP6 Configure Get) complete. Jun 1 10:03:08 gvs dhclient: Internet Systems Consortium DHCP Client V3.1.3 Jun 1 10:03:08 gvs dhclient: Copyright 2004-2009 Internet Systems Consortium. Jun 1 10:03:08 gvs dhclient: All rights reserved. Jun 1 10:03:08 gvs dhclient: For info, please visit https://www.isc.org/software/dhcp/ Jun 1 10:03:08 gvs dhclient: Jun 1 10:03:08 gvs NetworkManager: DHCP: device eth0 state changed normal exit -> preinit Jun 1 10:03:08 gvs dhclient: Listening on LPF/eth0/00:23:8b:fe:39:c5 Jun 1 10:03:08 gvs dhclient: Sending on LPF/eth0/00:23:8b:fe:39:c5 Jun 1 10:03:08 gvs dhclient: Sending on Socket/fallback Jun 1 10:03:09 gvs dhclient: DHCPDISCOVER on wmx0 to 255.255.255.255 port 67 interval 7 Jun 1 10:03:10 gvs dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 8 Jun 1 10:03:10 gvs NetworkManager: Activation (wlan0) starting connection 'Auto MRAP11' Jun 1 10:03:10 gvs NetworkManager: (wlan0): device state change: 3 -> 4 (reason 0) Jun 1 10:03:10 gvs NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Jun 1 10:03:10 gvs NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Jun 1 10:03:10 gvs NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Jun 1 10:03:10 gvs NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Jun 1 10:03:10 gvs NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Jun 1 10:03:10 gvs NetworkManager: (wlan0): device state change: 4 -> 5 (reason 0) Jun 1 10:03:10 gvs NetworkManager: Activation (wlan0/wireless): connection 'Auto MRAP11' has security, and secrets exist. No new secrets needed. Jun 1 10:03:10 gvs NetworkManager: Config: added 'ssid' value 'MRAP11' Jun 1 10:03:10 gvs NetworkManager: Config: added 'scan_ssid' value '1' Jun 1 10:03:10 gvs NetworkManager: Config: added 'key_mgmt' value 'WPA-PSK' Jun 1 10:03:10 gvs NetworkManager: Config: added 'psk' value '' Jun 1 10:03:10 gvs NetworkManager: nm_setting_802_1x_get_pkcs11_engine_path: assertion `NM_IS_SETTING_802_1X (setting)' failed Jun 1 10:03:10 gvs NetworkManager: nm_setting_802_1x_get_pkcs11_module_path: assertion `NM_IS_SETTING_802_1X (setting)' failed Jun 1 10:03:10 gvs NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Jun 1 10:03:10 gvs NetworkManager: Config: set interface ap_scan to 1 Jun 1 10:03:10 gvs NetworkManager: (wlan0): supplicant connection state: scanning -> disconnected Jun 1 10:03:10 gvs dhclient: DHCPOFFER of 195.218.191.182 from 195.218.191.242 Jun 1 10:03:10 gvs dhclient: DHCPREQUEST of 195.218.191.182 on eth0 to 255.255.255.255 port 67 Jun 1 10:03:10 gvs dhclient: DHCPACK of 195.218.191.182 from 195.218.191.242 Jun 1 10:03:10 gvs NetworkManager: DHCP: device eth0 state changed preinit -> bound Jun 1 10:03:10 gvs NetworkManager: Activation (eth0) Stage 4 of 5 (IP4 Configure Get) scheduled... Jun 1 10:03:10 gvs NetworkManager: Activation (eth0) Stage 4 of 5 (IP4 Configure Get) started... Jun 1 10:03:10 gvs NetworkManager: address 195.218.191.182 Jun 1 10:03:10 gvs NetworkManager: prefix 23 (255.255.254.0) Jun 1 10:03:10 gvs NetworkManager: gateway 195.218.190.1 Jun 1 10:03:10 gvs NetworkManager: nameserver '195.218.190.64' Jun 1 10:03:10 gvs NetworkManager: nameserver '195.218.191.171' Jun 1 10:03:10 gvs NetworkManager: nameserver '195.218.190.20' Jun 1 10:03:10 gvs NetworkManager: nameserver '195.218.191.188' Jun 1 10:03:10 gvs NetworkManager: domain name 'mail.msk' Jun 1 10:03:10 gvs NetworkManager: Activation (eth0) Stage 5 of 5 (IP Configure Commit) scheduled... Jun 1 10:03:10 gvs NetworkManager: Activation (eth0) Stage 4 of 5 (IP4 Configure Get) complete. Jun 1 10:03:10 gvs NetworkManager: Activation (eth0) Stage 5 of 5 (IP Configure Commit) started... Jun 1 10:03:10 gvs dhclient: bound to 195.218.191.182 -- renewal in 1739 seconds. Jun 1 10:03:11 gvs NetworkManager: (eth0): device state change: 7 -> 8 (reason 0) Jun 1 10:03:11 gvs NetworkManager: Policy set 'Auto eth0' (eth0) as default for routing and DNS. Jun 1 10:03:11 gvs NetworkManager: Activation (eth0) successful, device activated. Jun 1 10:03:11 gvs NetworkManager: Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete. Jun 1 10:03:13 gvs NetworkManager: (wlan0): supplicant connection state: disconnected -> scanning Jun 1 10:03:16 gvs dhclient: DHCPDISCOVER on wmx0 to 255.255.255.255 port 67 interval 12 Jun 1 10:03:17 gvs NetworkManager: (wlan0): supplicant connection state: scanning -> associating Jun 1 10:03:17 gvs kernel: [90373.610438] wlan0: deauthenticating from 00:20:a6:a7:35:90 by local choice (reason=3) Jun 1 10:03:17 gvs kernel: [90373.650114] wlan0: deauthenticating from 00:20:a6:a7:42:60 by local choice (reason=3) Jun 1 10:03:17 gvs kernel: [90373.650148] wlan0: deauthenticating from 00:20:a6:a7:42:60 by local choice (reason=3) Jun 1 10:03:17 gvs kernel: [90373.653933] wlan0: direct probe to AP 00:20:a6:a7:35:90 (try 1) Jun 1 10:03:17 gvs kernel: [90373.681557] wlan0: direct probe responded Jun 1 10:03:17 gvs kernel: [90373.681564] wlan0: authenticate with AP 00:20:a6:a7:35:90 (try 1) Jun 1 10:03:17 gvs kernel: [90373.683601] wlan0: authenticated Jun 1 10:03:17 gvs kernel: [90373.683639] wlan0: associate with AP 00:20:a6:a7:35:90 (try 1) Jun 1 10:03:17 gvs kernel: [90373.686083] wlan0: RX AssocResp from 00:20:a6:a7:35:90 (capab=0x431 status=0 aid=1) Jun 1 10:03:17 gvs kernel: [90373.686089] wlan0: associated Jun 1 10:03:17 gvs kernel: [90373.689052] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Jun 1 10:03:17 gvs NetworkManager: (wlan0): supplicant connection state: associating -> associated Jun 1 10:03:17 gvs NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake Jun 1 10:03:17 gvs NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake Jun 1 10:03:17 gvs NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Jun 1 10:03:17 gvs NetworkManager: Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'MRAP11'. Jun 1 10:03:17 gvs NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled. Jun 1 10:03:17 gvs NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) started... Jun 1 10:03:17 gvs NetworkManager: (wlan0): device state change: 5 -> 7 (reason 0) Jun 1 10:03:17 gvs NetworkManager: Activation (wlan0) Beginning DHCP transaction (timeout in 45 seconds) Jun 1 10:03:17 gvs NetworkManager: dhclient started with pid 23408 Jun 1 10:03:17 gvs NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) scheduled... Jun 1 10:03:17 gvs NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete. Jun 1 10:03:17 gvs NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) started... Jun 1 10:03:17 gvs NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) complete. Jun 1 10:03:17 gvs dhclient: Internet Systems Consortium DHCP Client V3.1.3 Jun 1 10:03:17 gvs dhclient: Copyright 2004-2009 Internet Systems Consortium. Jun 1 10:03:17 gvs dhclient: All rights reserved. Jun 1 10:03:17 gvs dhclient: For info, please visit https://www.isc.org/software/dhcp/ Jun 1 10:03:17 gvs dhclient: Jun 1 10:03:17 gvs NetworkManager: DHCP: device wlan0 state changed normal exit -> preinit Jun 1 10:03:17 gvs dhclient: Listening on LPF/wlan0/00:16:eb:0c:ef:68 Jun 1 10:03:17 gvs dhclient: Sending on LPF/wlan0/00:16:eb:0c:ef:68 Jun 1 10:03:17 gvs dhclient: Sending on Socket/fallback Jun 1 10:03:17 gvs kernel: [90374.056034] eth0: no IPv6 routers present Jun 1 10:03:20 gvs NetworkManager: (wlan0): device state change: 7 -> 3 (reason 39) Jun 1 10:03:20 gvs NetworkManager: (wlan0): deactivating device (reason: 39). Jun 1 10:03:20 gvs NetworkManager: (wlan0): canceled DHCP transaction, dhcp client pid 23408 Jun 1 10:03:20 gvs NetworkManager: Policy set 'Auto eth0' (eth0) as default for routing and DNS. Jun 1 10:03:20 gvs kernel: [90376.905174] wlan0: deauthenticating from 00:20:a6:a7:35:90 by local choice (reason=3) Jun 1 10:03:22 gvs NetworkManager: (wmx0): device state change: 7 -> 3 (reason 39) Jun 1 10:03:22 gvs NetworkManager: (wmx0): deactivating device (reason: 39). Jun 1 10:03:22 gvs NetworkManager: (wmx0): canceled DHCP transaction, dhcp client pid 23183 Jun 1 10:03:22 gvs NetworkManager: Policy set 'Auto eth0' (eth0) as default for routing and DNS. Jun 1 10:03:28 gvs kernel: [90384.528014] wlan0: no IPv6 routers present Jun 1 10:32:09 gvs dhclient: DHCPREQUEST of 195.218.191.182 on eth0 to 195.218.191.242 port 67 Jun 1 10:32:09 gvs dhclient: DHCPACK of 195.218.191.182 from 195.218.191.242 Jun 1 10:32:09 gvs dhclient: bound to 195.218.191.182 -- renewal in 40150 seconds. Jun 1 10:32:09 gvs NetworkManager: DHCP: device eth0 state changed bound -> renew Jun 1 10:32:09 gvs NetworkManager: address 195.218.191.182 Jun 1 10:32:09 gvs NetworkManager: prefix 23 (255.255.254.0) Jun 1 10:32:09 gvs NetworkManager: gateway 195.218.190.1 Jun 1 10:32:09 gvs NetworkManager: nameserver '195.218.190.64' Jun 1 10:32:09 gvs NetworkManager: nameserver '195.218.191.171' Jun 1 10:32:09 gvs NetworkManager: nameserver '195.218.190.20' Jun 1 10:32:09 gvs NetworkManager: nameserver '195.218.191.188' Jun 1 10:32:09 gvs NetworkManager: domain name 'mail.msk' Jun 1 18:55:33 gvs NetworkManager: Sleeping... Jun 1 18:55:33 gvs NetworkManager: (eth0): now unmanaged Jun 1 18:55:33 gvs NetworkManager: (eth0): device state change: 8 -> 1 (reason 37) Jun 1 18:55:33 gvs NetworkManager: (eth0): deactivating device (reason: 37). Jun 1 18:55:33 gvs NetworkManager: (eth0): canceled DHCP transaction, dhcp client pid 23341 Jun 1 18:55:33 gvs NetworkManager: check_one_route(): (eth0) error -34 returned from rtnl_route_del(): Sucess#012 Jun 1 18:55:33 gvs NetworkManager: (eth0): cleaning up... Jun 1 18:55:33 gvs NetworkManager: (eth0): taking down device. Jun 1 18:55:33 gvs NetworkManager: (wlan0): now unmanaged Jun 1 18:55:33 gvs NetworkManager: (wlan0): device state change: 3 -> 1 (reason 37) Jun 1 18:55:33 gvs NetworkManager: (wlan0): cleaning up... Jun 1 18:55:33 gvs kernel: [122310.256386] sky2 eth0: disabling interface Jun 1 18:55:33 gvs NetworkManager: (wlan0): taking down device. Jun 1 18:55:33 gvs NetworkManager: (wmx0): now unmanaged Jun 1 18:55:33 gvs NetworkManager: (wmx0): device state change: 3 -> 1 (reason 37) Jun 1 18:55:33 gvs NetworkManager: (wmx0): cleaning up... Jun 1 18:55:33 gvs NetworkManager: (wmx0): taking down device. Jun 1 18:55:33 gvs NetworkManager: (eth0): carrier now OFF (device state 1) Jun 1 20:35:32 gvs kernel: [122311.033445] PM: Syncing filesystems ... done. Jun 1 20:35:32 gvs kernel: [122311.141240] PM: Preparing system for mem sleep Jun 1 20:35:32 gvs kernel: [122311.141247] Freezing user space processes ... (elapsed 0.00 seconds) done. Jun 1 20:35:32 gvs NetworkManager: bluez_manager_bdaddr_removed_cb: BT device 9C:18:74:B2:FC:F7 removed Jun 1 20:35:32 gvs kernel: [122311.142104] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. Jun 1 20:35:32 gvs kernel: [122311.142143] PM: Entering mem sleep Jun 1 20:35:32 gvs kernel: [122311.142155] Suspending console(s) (use no_console_suspend to debug) Jun 1 20:35:32 gvs kernel: [122311.162311] btusb_intr_complete: hci0 urb eb8caa00 failed to resubmit (1) Jun 1 20:35:32 gvs kernel: [122311.163313] btusb_bulk_complete: hci0 urb eca20600 failed to resubmit (1) Jun 1 20:35:32 gvs kernel: [122311.164316] btusb_bulk_complete: hci0 urb eca20780 failed to resubmit (1) Jun 1 20:35:32 gvs kernel: [122311.216373] sd 0:0:0:0: [sda] Synchronizing SCSI cache Jun 1 20:35:32 gvs kernel: [122311.216588] sd 0:0:0:0: [sda] Stopping disk Jun 1 20:35:32 gvs kernel: [122311.716134] PM: suspend of drv:sd dev:0:0:0:0 complete after 499.761 msecs Jun 1 20:35:32 gvs kernel: [122312.156078] PM: suspend of drv:psmouse dev:serio4 complete after 423.744 msecs Jun 1 20:35:32 gvs kernel: [122312.368215] PM: suspend of drv:atkbd dev:serio0 complete after 212.121 msecs Jun 1 20:35:32 gvs kernel: [122312.369491] ACPI handle has no context! Jun 1 20:35:32 gvs kernel: [122312.369605] ACPI handle has no context! Jun 1 20:35:32 gvs kernel: [122312.369613] sdhci-pci 0000:0a:01.2: PCI INT A disabled Jun 1 20:35:32 gvs kernel: [122312.369620] ACPI handle has no context! Jun 1 20:35:32 gvs kernel: [122312.386269] ACPI handle has no context! Jun 1 20:35:32 gvs kernel: [122312.512235] ehci_hcd 0000:00:1d.7: PCI INT A disabled Jun 1 20:35:32 gvs kernel: [122312.512248] uhci_hcd 0000:00:1d.2: PCI INT C disabled Jun 1 20:35:32 gvs kernel: [122312.512260] uhci_hcd 0000:00:1d.1: PCI INT B disabled Jun 1 20:35:32 gvs kernel: [122312.512272] uhci_hcd 0000:00:1d.0: PCI INT A disabled Jun 1 20:35:32 gvs kernel: [122312.616523] HDA Intel 0000:00:1b.0: PCI INT A disabled Jun 1 20:35:32 gvs kernel: [122312.632217] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.924 msecs Jun 1 20:35:32 gvs kernel: [122312.632230] ehci_hcd 0000:00:1a.7: PCI INT C disabled Jun 1 20:35:32 gvs kernel: [122312.632241] uhci_hcd 0000:00:1a.2: PCI INT C disabled Jun 1 20:35:32 gvs kernel: [122312.632253] uhci_hcd 0000:00:1a.1: PCI INT B disabled Jun 1 20:35:32 gvs kernel: [122312.632265] uhci_hcd 0000:00:1a.0: PCI INT A disabled Jun 1 20:35:32 gvs kernel: [122312.652425] PM: suspend of devices complete after 1509.948 msecs Jun 1 20:35:32 gvs kernel: [122312.652428] PM: suspend devices took 1.512 seconds Jun 1 20:35:32 gvs kernel: [122312.684224] PM: late suspend of devices complete after 31.792 msecs Jun 1 20:35:32 gvs kernel: [122312.684560] ACPI: Preparing to enter system sleep state S3 Jun 1 20:35:32 gvs kernel: [122312.720011] Disabling non-boot CPUs ... Jun 1 20:35:32 gvs kernel: [122312.720029] CPU0 attaching NULL sched-domain. Jun 1 20:35:32 gvs kernel: [122312.720032] CPU1 attaching NULL sched-domain. Jun 1 20:35:32 gvs kernel: [122312.784014] CPU0 attaching NULL sched-domain. Jun 1 20:35:32 gvs kernel: [122312.888019] CPU 1 is now offline Jun 1 20:35:32 gvs kernel: [122312.888021] SMP alternatives: switching to UP code Jun 1 20:35:32 gvs kernel: [122312.894486] Extended CMOS year: 2000 Jun 1 20:35:32 gvs kernel: [122312.894486] Back to C! Jun 1 20:35:32 gvs kernel: [122312.894486] CPU0: Thermal monitoring enabled (TM2) Jun 1 20:35:32 gvs kernel: [122312.894486] Extended CMOS year: 2000 Jun 1 20:35:32 gvs kernel: [122312.894486] Enabling non-boot CPUs ... Jun 1 20:35:32 gvs kernel: [122312.894486] SMP alternatives: switching to SMP code Jun 1 20:35:32 gvs kernel: [122312.900643] Booting processor 1 APIC 0x1 ip 0x6000 Jun 1 20:35:32 gvs kernel: [122312.894114] Initializing CPU#1 Jun 1 20:35:32 gvs kernel: [122312.894114] CPU: L1 I cache: 32K, L1 D cache: 32K Jun 1 20:35:32 gvs kernel: [122312.894114] CPU: L2 cache: 2048K Jun 1 20:35:32 gvs kernel: [122312.894114] CPU: Physical Processor ID: 0 Jun 1 20:35:32 gvs kernel: [122312.894114] CPU: Processor Core ID: 1 Jun 1 20:35:32 gvs kernel: [122312.894114] CPU1: Thermal monitoring enabled (TM2) Jun 1 20:35:32 gvs kernel: [122312.988119] CPU1: Intel(R) Core(TM)2 Duo CPU T6500 @ 2.10GHz stepping 0a Jun 1 20:35:32 gvs kernel: [122312.988176] CPU0 attaching NULL sched-domain. Jun 1 20:35:32 gvs kernel: [122313.016015] CPU0 attaching sched-domain: Jun 1 20:35:32 gvs kernel: [122313.016018] domain 0: span 0-1 level MC Jun 1 20:35:32 gvs kernel: [122313.016020] groups: 0 1 Jun 1 20:35:32 gvs kernel: [122313.016024] CPU1 attaching sched-domain: Jun 1 20:35:32 gvs kernel: [122313.016026] domain 0: span 0-1 level MC Jun 1 20:35:32 gvs kernel: [122313.016028] groups: 1 0 Jun 1 20:35:32 gvs kernel: [122313.016446] CPU1 is up Jun 1 20:35:32 gvs kernel: [122313.016926] ACPI: Waking up from system sleep state S3 Jun 1 20:35:32 gvs kernel: [122313.120213] i915 0000:00:02.0: restoring config space at offset 0xf (was 0x100, writing 0x105) Jun 1 20:35:32 gvs kernel: [122313.120226] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) Jun 1 20:35:32 gvs kernel: [122313.120255] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) Jun 1 20:35:32 gvs kernel: [122313.120293] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) Jun 1 20:35:32 gvs kernel: [122313.120335] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) Jun 1 20:35:32 gvs kernel: [122313.120377] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) Jun 1 20:35:32 gvs kernel: [122313.120426] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) Jun 1 20:35:32 gvs kernel: [122313.120463] HDA Intel 0000:00:1b.0: restoring config space at offset 0xf (was 0x100, writing 0x10a) Jun 1 20:35:32 gvs kernel: [122313.120483] HDA Intel 0000:00:1b.0: restoring config space at offset 0x4 (was 0x4, writing 0xf4800004) Jun 1 20:35:32 gvs kernel: [122313.120488] HDA Intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) Jun 1 20:35:32 gvs kernel: [122313.120495] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100002) Jun 1 20:35:32 gvs kernel: [122313.120530] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x40100, writing 0x4010b) Jun 1 20:35:32 gvs kernel: [122313.120543] pcieport 0000:00:1c.0: restoring config space at offset 0x9 (was 0x10001, writing 0x80318021) Jun 1 20:35:32 gvs kernel: [122313.120548] pcieport 0000:00:1c.0: restoring config space at offset 0x8 (was 0x0, writing 0x80108000) Jun 1 20:35:32 gvs kernel: [122313.120553] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0x20000000, writing 0x20002020) Jun 1 20:35:32 gvs kernel: [122313.120558] pcieport 0000:00:1c.0: restoring config space at offset 0x6 (was 0x0, writing 0x30200) Jun 1 20:35:32 gvs kernel: [122313.120567] pcieport 0000:00:1c.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) Jun 1 20:35:32 gvs kernel: [122313.120573] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) Jun 1 20:35:32 gvs kernel: [122313.120624] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x40100, writing 0x4010b) Jun 1 20:35:32 gvs kernel: [122313.120637] pcieport 0000:00:1c.4: restoring config space at offset 0x9 (was 0x10001, writing 0x80718061) Jun 1 20:35:32 gvs kernel: [122313.120642] pcieport 0000:00:1c.4: restoring config space at offset 0x8 (was 0x0, writing 0x80508040) Jun 1 20:35:32 gvs kernel: [122313.120647] pcieport 0000:00:1c.4: restoring config space at offset 0x7 (was 0x20000000, writing 0x20003030) Jun 1 20:35:32 gvs kernel: [122313.120657] pcieport 0000:00:1c.4: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) Jun 1 20:35:32 gvs kernel: [122313.120663] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) Jun 1 20:35:32 gvs kernel: [122313.120714] pcieport 0000:00:1c.5: restoring config space at offset 0xf (was 0x40200, writing 0x40205) Jun 1 20:35:32 gvs kernel: [122313.120726] pcieport 0000:00:1c.5: restoring config space at offset 0x9 (was 0x10001, writing 0x80b180a1) Jun 1 20:35:32 gvs kernel: [122313.120732] pcieport 0000:00:1c.5: restoring config space at offset 0x8 (was 0x0, writing 0x80908080) Jun 1 20:35:32 gvs kernel: [122313.120737] pcieport 0000:00:1c.5: restoring config space at offset 0x7 (was 0x20000000, writing 0x20004040) Jun 1 20:35:32 gvs kernel: [122313.120747] pcieport 0000:00:1c.5: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) Jun 1 20:35:32 gvs kernel: [122313.120753] pcieport 0000:00:1c.5: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) Jun 1 20:35:32 gvs kernel: [122313.120818] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) Jun 1 20:35:32 gvs kernel: [122313.120859] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) Jun 1 20:35:32 gvs kernel: [122313.120901] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) Jun 1 20:35:32 gvs kernel: [122313.120951] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) Jun 1 20:35:32 gvs kernel: [122313.120979] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x40000, writing 0x400ff) Jun 1 20:35:32 gvs kernel: [122313.120992] pci 0000:00:1e.0: restoring config space at offset 0x9 (was 0x10001, writing 0x1fff1) Jun 1 20:35:32 gvs kernel: [122313.120997] pci 0000:00:1e.0: restoring config space at offset 0x8 (was 0x0, writing 0xf470f470) Jun 1 20:35:32 gvs kernel: [122313.121004] pci 0000:00:1e.0: restoring config space at offset 0x7 (was 0xa2800000, writing 0xa28000f0) Jun 1 20:35:32 gvs kernel: [122313.121018] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) Jun 1 20:35:32 gvs kernel: [122313.121099] ahci 0000:00:1f.2: restoring config space at offset 0xf (was 0x200, writing 0x20a) Jun 1 20:35:32 gvs kernel: [122313.121123] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) Jun 1 20:35:32 gvs kernel: [122313.121179] pci 0000:00:1f.3: restoring config space at offset 0x4 (was 0x4, writing 0x80c00004) Jun 1 20:35:32 gvs kernel: [122313.121234] sky2 0000:07:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) Jun 1 20:35:32 gvs kernel: [122313.121260] sky2 0000:07:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x3001) Jun 1 20:35:32 gvs kernel: [122313.121270] sky2 0000:07:00.0: restoring config space at offset 0x4 (was 0x4, writing 0x80400004) Jun 1 20:35:32 gvs kernel: [122313.121276] sky2 0000:07:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) Jun 1 20:35:32 gvs kernel: [122313.121286] sky2 0000:07:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) Jun 1 20:35:32 gvs kernel: [122313.121374] iwlagn 0000:08:00.0: restoring config space at offset 0xf (was 0x100, writing 0x105) Jun 1 20:35:32 gvs kernel: [122313.121411] iwlagn 0000:08:00.0: restoring config space at offset 0x4 (was 0x4, writing 0x80800004) Jun 1 20:35:32 gvs kernel: [122313.121419] iwlagn 0000:08:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) Jun 1 20:35:32 gvs kernel: [122313.121430] iwlagn 0000:08:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100406) Jun 1 20:35:32 gvs kernel: [122313.121518] ohci1394 0000:0a:01.0: restoring config space at offset 0x5 (was 0x0, writing 0xf4700000) Jun 1 20:35:32 gvs kernel: [122313.121524] ohci1394 0000:0a:01.0: restoring config space at offset 0x3 (was 0x800000, writing 0x802010) Jun 1 20:35:32 gvs kernel: [122313.121531] ohci1394 0000:0a:01.0: restoring config space at offset 0x1 (was 0x2100080, writing 0x2180097) Jun 1 20:35:32 gvs kernel: [122313.121578] sdhci-pci 0000:0a:01.2: restoring config space at offset 0x4 (was 0x0, writing 0xf4700800) Jun 1 20:35:32 gvs kernel: [122313.121584] sdhci-pci 0000:0a:01.2: restoring config space at offset 0x3 (was 0x0, writing 0x2010) Jun 1 20:35:32 gvs kernel: [122313.121591] sdhci-pci 0000:0a:01.2: restoring config space at offset 0x1 (was 0x4100000, writing 0x4100006) Jun 1 20:35:32 gvs kernel: [122313.121640] pci 0000:0a:01.3: restoring config space at offset 0x4 (was 0x0, writing 0xf4702000) Jun 1 20:35:32 gvs kernel: [122313.121646] pci 0000:0a:01.3: restoring config space at offset 0x3 (was 0x0, writing 0x2010) Jun 1 20:35:32 gvs kernel: [122313.121653] pci 0000:0a:01.3: restoring config space at offset 0x1 (was 0x4100000, writing 0x4100002) Jun 1 20:35:32 gvs kernel: [122313.122062] PM: early resume of devices complete after 1.958 msecs Jun 1 20:35:32 gvs kernel: [122313.484393] PM: resume of drv:battery dev:PNP0C0A:00 complete after 361.334 msecs Jun 1 20:35:32 gvs kernel: [122313.510449] i915 0000:00:02.0: setting latency timer to 64 Jun 1 20:35:32 gvs kernel: [122313.676364] PM: resume of drv:i915 dev:0000:00:02.0 complete after 165.919 msecs Jun 1 20:35:32 gvs kernel: [122313.676377] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 Jun 1 20:35:32 gvs kernel: [122313.676384] uhci_hcd 0000:00:1a.0: setting latency timer to 64 Jun 1 20:35:32 gvs kernel: [122313.676409] usb usb3: root hub lost power or was reset Jun 1 20:35:32 gvs kernel: [122313.676431] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 Jun 1 20:35:32 gvs kernel: [122313.676437] uhci_hcd 0000:00:1a.1: setting latency timer to 64 Jun 1 20:35:32 gvs kernel: [122313.676462] usb usb4: root hub lost power or was reset Jun 1 20:35:32 gvs kernel: [122313.676482] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 19 (level, low) -> IRQ 19 Jun 1 20:35:32 gvs kernel: [122313.676489] uhci_hcd 0000:00:1a.2: setting latency timer to 64 Jun 1 20:35:32 gvs kernel: [122313.676511] usb usb5: root hub lost power or was reset Jun 1 20:35:32 gvs kernel: [122313.676532] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 19 (level, low) -> IRQ 19 Jun 1 20:35:32 gvs kernel: [122313.676539] ehci_hcd 0000:00:1a.7: setting latency timer to 64 Jun 1 20:35:32 gvs kernel: [122313.676551] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 Jun 1 20:35:32 gvs kernel: [122313.676558] HDA Intel 0000:00:1b.0: setting latency timer to 64 Jun 1 20:35:32 gvs kernel: [122313.676592] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 Jun 1 20:35:32 gvs kernel: [122313.676599] uhci_hcd 0000:00:1d.0: setting latency timer to 64 Jun 1 20:35:32 gvs kernel: [122313.676622] usb usb6: root hub lost power or was reset Jun 1 20:35:32 gvs kernel: [122313.676655] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 Jun 1 20:35:32 gvs kernel: [122313.676661] uhci_hcd 0000:00:1d.1: setting latency timer to 64 Jun 1 20:35:32 gvs kernel: [122313.676685] usb usb7: root hub lost power or was reset Jun 1 20:35:32 gvs kernel: [122313.676717] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 Jun 1 20:35:32 gvs kernel: [122313.676724] uhci_hcd 0000:00:1d.2: setting latency timer to 64 Jun 1 20:35:32 gvs kernel: [122313.676747] usb usb8: root hub lost power or was reset Jun 1 20:35:32 gvs kernel: [122313.676767] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 Jun 1 20:35:32 gvs kernel: [122313.676774] ehci_hcd 0000:00:1d.7: setting latency timer to 64 Jun 1 20:35:32 gvs kernel: [122313.676800] pci 0000:00:1e.0: setting latency timer to 64 Jun 1 20:35:32 gvs kernel: [122313.676816] ahci 0000:00:1f.2: setting latency timer to 64 Jun 1 20:35:32 gvs kernel: [122313.733234] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[16] MMIO=[ff501000-ff5017ff] Max Packet=[2048] IR/IT contexts=[8/8] Jun 1 20:35:32 gvs kernel: [122313.736323] sdhci-pci 0000:0a:01.2: PCI INT A -> GSI 16 (level, low) -> IRQ 16 Jun 1 20:35:32 gvs kernel: [122314.004223] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Jun 1 20:35:32 gvs kernel: [122314.012219] ata6: SATA link down (SStatus 0 SControl 300) Jun 1 20:35:32 gvs kernel: [122314.016210] PM: resume of drv:usb dev:usb2 complete after 251.957 msecs Jun 1 20:35:32 gvs kernel: [122314.020227] ata5: SATA link down (SStatus 0 SControl 300) Jun 1 20:35:32 gvs kernel: [122314.052551] ata2.00: configured for UDMA/133 Jun 1 20:35:32 gvs kernel: [122314.264228] PM: resume of drv:usb dev:usb6 complete after 247.957 msecs Jun 1 20:35:32 gvs kernel: [122314.512234] PM: resume of drv:usb dev:usb7 complete after 247.988 msecs Jun 1 20:35:32 gvs kernel: [122314.624226] usb 2-4: reset high speed USB device using ehci_hcd and address 4 Jun 1 20:35:32 gvs kernel: [122314.767442] PM: resume of drv:usb dev:2-4 complete after 253.366 msecs Jun 1 20:35:32 gvs kernel: [122314.767464] sd 0:0:0:0: [sda] Starting disk Jun 1 20:35:32 gvs kernel: [122315.788221] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Jun 1 20:35:32 gvs kernel: [122315.789454] ata1.00: _GTF unexpected object type 0x1 Jun 1 20:35:32 gvs kernel: [122315.804275] ata1.00: _GTF unexpected object type 0x1 Jun 1 20:35:32 gvs kernel: [122315.804925] ata1.00: configured for UDMA/133 Jun 1 20:35:32 gvs kernel: [122315.824058] PM: resume of drv:sd dev:0:0:0:0 complete after 1056.594 msecs Jun 1 20:35:32 gvs kernel: [122315.936216] usb 7-1: reset full speed USB device using uhci_hcd and address 2 Jun 1 20:35:32 gvs kernel: [122316.087263] btusb 7-1:1.0: no reset_resume for driver btusb? Jun 1 20:35:32 gvs kernel: [122316.087267] btusb 7-1:1.1: no reset_resume for driver btusb? Jun 1 20:35:32 gvs kernel: [122316.336337] PM: resume of drv:usb dev:7-1 complete after 512.059 msecs Jun 1 20:35:32 gvs kernel: [122316.387452] i2400m_usb 2-6:1.0: 'RF Control' (0x4602) command failed: -84 - invalid state (3) Jun 1 20:35:32 gvs kernel: [122316.644216] usb 6-1: reset low speed USB device using uhci_hcd and address 6 Jun 1 20:35:32 gvs kernel: [122316.933263] usb 6-1: device firmware changed Jun 1 20:35:32 gvs kernel: [122316.933282] PM: resume of drv:usb dev:6-1 complete after 545.803 msecs Jun 1 20:35:32 gvs kernel: [122316.933312] PM: resume of devices complete after 3810.907 msecs Jun 1 20:35:32 gvs kernel: [122316.933605] PM: resume devices took 3.812 seconds Jun 1 20:35:32 gvs kernel: [122316.933636] PM: Finishing wakeup. Jun 1 20:35:32 gvs kernel: [122316.933638] Restarting tasks ... Jun 1 20:35:32 gvs kernel: [122316.933717] usb 6-1: USB disconnect, address 6 Jun 1 20:35:32 gvs kernel: [122316.969598] done. Jun 1 20:35:32 gvs kernel: [122317.073026] usb 6-1: new low speed USB device using uhci_hcd and address 7 Jun 1 20:35:33 gvs kernel: [122317.251365] usb 6-1: configuration #1 chosen from 1 choice Jun 1 20:35:33 gvs kernel: [122317.267523] input: Logitech Optical USB Mouse as /devices/pci0000:00/0000:00:1d.0/usb6/6-1/6-1:1.0/input/input17 Jun 1 20:35:33 gvs kernel: [122317.267636] generic-usb 0003:046D:C016.0006: input,hidraw0: USB HID v1.10 Mouse [Logitech Optical USB Mouse] on usb-0000:00:1d.0-1/input0 Jun 1 20:35:33 gvs NetworkManager: Waking up... Jun 1 20:35:33 gvs NetworkManager: (eth0): now managed Jun 1 20:35:33 gvs NetworkManager: (eth0): device state change: 1 -> 2 (reason 2) Jun 1 20:35:33 gvs NetworkManager: (eth0): bringing up device. Jun 1 20:35:33 gvs NetworkManager: (eth0): preparing device. Jun 1 20:35:33 gvs NetworkManager: (eth0): deactivating device (reason: 2). Jun 1 20:35:33 gvs NetworkManager: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Jun 1 20:35:33 gvs NetworkManager: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Jun 1 20:35:33 gvs NetworkManager: (wlan0): now managed Jun 1 20:35:33 gvs NetworkManager: (wlan0): device state change: 1 -> 2 (reason 2) Jun 1 20:35:33 gvs NetworkManager: (wlan0): bringing up device. Jun 1 20:35:33 gvs kernel: [122317.272301] sky2 eth0: enabling interface Jun 1 20:35:33 gvs kernel: [122317.272574] ADDRCONF(NETDEV_UP): eth0: link is not ready Jun 1 20:35:33 gvs kernel: [122317.304615] Registered led device: iwl-phy0::radio Jun 1 20:35:33 gvs kernel: [122317.304637] Registered led device: iwl-phy0::assoc Jun 1 20:35:33 gvs kernel: [122317.304655] Registered led device: iwl-phy0::RX Jun 1 20:35:33 gvs kernel: [122317.304672] Registered led device: iwl-phy0::TX Jun 1 20:35:33 gvs NetworkManager: (wlan0): preparing device. Jun 1 20:35:33 gvs kernel: [122317.316594] ADDRCONF(NETDEV_UP): wlan0: link is not ready Jun 1 20:35:33 gvs kernel: [122317.317622] ADDRCONF(NETDEV_UP): wmx0: link is not ready Jun 1 20:35:33 gvs NetworkManager: (wlan0): deactivating device (reason: 2). Jun 1 20:35:33 gvs NetworkManager: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Jun 1 20:35:33 gvs NetworkManager: (wmx0): now managed Jun 1 20:35:33 gvs NetworkManager: (wmx0): device state change: 1 -> 2 (reason 2) Jun 1 20:35:33 gvs NetworkManager: (wmx0): bringing up device. Jun 1 20:35:33 gvs NetworkManager: (wmx0): preparing device. Jun 1 20:35:33 gvs NetworkManager: (wmx0): deactivating device (reason: 2). Jun 1 20:35:33 gvs NetworkManager: (wmx0): device state change: 2 -> 3 (reason 0) Jun 1 20:35:33 gvs NetworkManager: Activation (wmx0) starting connection 'Auto wmx0' Jun 1 20:35:33 gvs NetworkManager: (wmx0): device state change: 3 -> 4 (reason 0) Jun 1 20:35:33 gvs NetworkManager: Activation (wmx0) Stage 1 of 5 (Device Prepare) scheduled... Jun 1 20:35:33 gvs NetworkManager: Activation (wmx0) Stage 1 of 5 (Device Prepare) started... Jun 1 20:35:33 gvs NetworkManager: Activation (wmx0) Stage 2 of 5 (Device Configure) scheduled... Jun 1 20:35:33 gvs NetworkManager: Activation (wmx0) Stage 1 of 5 (Device Prepare) complete. Jun 1 20:35:33 gvs NetworkManager: Activation (wmx0) Stage 2 of 5 (Device Configure) starting... Jun 1 20:35:33 gvs NetworkManager: (wmx0): device state change: 4 -> 5 (reason 0) Jun 1 20:35:33 gvs NetworkManager: Activation (wmx0) Stage 2 of 5 (Device Configure) successful. Jun 1 20:35:33 gvs NetworkManager: Activation (wmx0) Stage 3 of 5 (IP Configure Start) scheduled. Jun 1 20:35:33 gvs NetworkManager: Activation (wmx0) Stage 2 of 5 (Device Configure) complete. Jun 1 20:35:33 gvs NetworkManager: Activation (wmx0) Stage 3 of 5 (IP Configure Start) started... Jun 1 20:35:33 gvs NetworkManager: (wmx0): device state change: 5 -> 7 (reason 0) Jun 1 20:35:33 gvs NetworkManager: Activation (wmx0) Beginning DHCP transaction (timeout in 45 seconds) Jun 1 20:35:33 gvs NetworkManager: dhclient started with pid 29145 Jun 1 20:35:33 gvs NetworkManager: Activation (wmx0) Stage 4 of 5 (IP6 Configure Get) scheduled... Jun 1 20:35:33 gvs NetworkManager: Activation (wmx0) Stage 3 of 5 (IP Configure Start) complete. Jun 1 20:35:33 gvs NetworkManager: Activation (wmx0) Stage 4 of 5 (IP6 Configure Get) started... Jun 1 20:35:33 gvs NetworkManager: Activation (wmx0) Stage 4 of 5 (IP6 Configure Get) complete. Jun 1 20:35:33 gvs dhclient: Internet Systems Consortium DHCP Client V3.1.3 Jun 1 20:35:33 gvs dhclient: Copyright 2004-2009 Internet Systems Consortium. Jun 1 20:35:33 gvs dhclient: All rights reserved. Jun 1 20:35:33 gvs dhclient: For info, please visit https://www.isc.org/software/dhcp/ Jun 1 20:35:33 gvs dhclient: Jun 1 20:35:33 gvs NetworkManager: DHCP: device wmx0 state changed normal exit -> preinit Jun 1 20:35:33 gvs dhclient: Listening on LPF/wmx0/00:1d:e1:08:f9:d5 Jun 1 20:35:33 gvs dhclient: Sending on LPF/wmx0/00:1d:e1:08:f9:d5 Jun 1 20:35:33 gvs dhclient: Sending on Socket/fallback Jun 1 20:35:33 gvs NetworkManager: (wlan0): supplicant interface state: starting -> ready Jun 1 20:35:33 gvs NetworkManager: (wlan0): device state change: 2 -> 3 (reason 42) Jun 1 20:35:36 gvs dhclient: DHCPDISCOVER on wmx0 to 255.255.255.255 port 67 interval 7 Jun 1 20:35:43 gvs dhclient: DHCPDISCOVER on wmx0 to 255.255.255.255 port 67 interval 9 Jun 1 20:35:52 gvs dhclient: DHCPDISCOVER on wmx0 to 255.255.255.255 port 67 interval 10 Jun 1 20:35:56 gvs NetworkManager: Activation (wlan0) starting connection 'Auto gvs' Jun 1 20:35:56 gvs NetworkManager: (wlan0): device state change: 3 -> 4 (reason 0) Jun 1 20:35:56 gvs NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Jun 1 20:35:56 gvs NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Jun 1 20:35:56 gvs NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Jun 1 20:35:56 gvs NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Jun 1 20:35:56 gvs NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Jun 1 20:35:56 gvs NetworkManager: (wlan0): device state change: 4 -> 5 (reason 0) Jun 1 20:35:56 gvs NetworkManager: Activation (wlan0/wireless): connection 'Auto gvs' has security, and secrets exist. No new secrets needed. Jun 1 20:35:56 gvs NetworkManager: Config: added 'ssid' value 'gvs' Jun 1 20:35:56 gvs NetworkManager: Config: added 'scan_ssid' value '1' Jun 1 20:35:56 gvs NetworkManager: Config: added 'key_mgmt' value 'WPA-PSK' Jun 1 20:35:56 gvs NetworkManager: Config: added 'psk' value '' Jun 1 20:35:56 gvs NetworkManager: nm_setting_802_1x_get_pkcs11_engine_path: assertion `NM_IS_SETTING_802_1X (setting)' failed Jun 1 20:35:56 gvs NetworkManager: nm_setting_802_1x_get_pkcs11_module_path: assertion `NM_IS_SETTING_802_1X (setting)' failed Jun 1 20:35:56 gvs NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Jun 1 20:35:56 gvs NetworkManager: Config: set interface ap_scan to 1 Jun 1 20:35:56 gvs NetworkManager: (wlan0): supplicant connection state: scanning -> disconnected Jun 1 20:36:01 gvs NetworkManager: (wlan0): supplicant connection state: disconnected -> scanning Jun 1 20:36:02 gvs dhclient: DHCPDISCOVER on wmx0 to 255.255.255.255 port 67 interval 16 Jun 1 20:36:04 gvs NetworkManager: (wlan0): supplicant connection state: scanning -> associating Jun 1 20:36:04 gvs kernel: [122348.571777] wlan0: deauthenticating from 00:1e:58:e5:9c:b8 by local choice (reason=3) Jun 1 20:36:04 gvs kernel: [122348.611363] wlan0: direct probe to AP 00:1e:58:e5:9c:b8 (try 1) Jun 1 20:36:04 gvs kernel: [122348.614119] wlan0: direct probe responded Jun 1 20:36:04 gvs kernel: [122348.614126] wlan0: authenticate with AP 00:1e:58:e5:9c:b8 (try 1) Jun 1 20:36:04 gvs kernel: [122348.616153] wlan0: authenticated Jun 1 20:36:04 gvs kernel: [122348.616187] wlan0: associate with AP 00:1e:58:e5:9c:b8 (try 1) Jun 1 20:36:04 gvs kernel: [122348.618610] wlan0: RX AssocResp from 00:1e:58:e5:9c:b8 (capab=0x411 status=0 aid=1) Jun 1 20:36:04 gvs kernel: [122348.618615] wlan0: associated Jun 1 20:36:04 gvs kernel: [122348.620415] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Jun 1 20:36:04 gvs NetworkManager: (wlan0): supplicant connection state: associating -> associated Jun 1 20:36:04 gvs NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake Jun 1 20:36:04 gvs NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake Jun 1 20:36:04 gvs NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Jun 1 20:36:04 gvs NetworkManager: Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'gvs'. Jun 1 20:36:04 gvs NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled. Jun 1 20:36:04 gvs NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) started... Jun 1 20:36:04 gvs NetworkManager: (wlan0): device state change: 5 -> 7 (reason 0) Jun 1 20:36:04 gvs NetworkManager: Activation (wlan0) Beginning DHCP transaction (timeout in 45 seconds) Jun 1 20:36:04 gvs NetworkManager: dhclient started with pid 29318 Jun 1 20:36:04 gvs NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) scheduled... Jun 1 20:36:04 gvs NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete. Jun 1 20:36:04 gvs NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) started... Jun 1 20:36:04 gvs NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) complete. Jun 1 20:36:04 gvs dhclient: Internet Systems Consortium DHCP Client V3.1.3 Jun 1 20:36:04 gvs dhclient: Copyright 2004-2009 Internet Systems Consortium. Jun 1 20:36:04 gvs dhclient: All rights reserved. Jun 1 20:36:04 gvs dhclient: For info, please visit https://www.isc.org/software/dhcp/ Jun 1 20:36:04 gvs dhclient: Jun 1 20:36:04 gvs NetworkManager: DHCP: device wlan0 state changed normal exit -> preinit Jun 1 20:36:04 gvs dhclient: Listening on LPF/wlan0/00:16:eb:0c:ef:68 Jun 1 20:36:04 gvs dhclient: Sending on LPF/wlan0/00:16:eb:0c:ef:68 Jun 1 20:36:04 gvs dhclient: Sending on Socket/fallback Jun 1 20:36:05 gvs dhclient: DHCPREQUEST of 192.168.1.2 on wlan0 to 255.255.255.255 port 67 Jun 1 20:36:05 gvs dhclient: DHCPACK of 192.168.1.2 from 192.168.1.1 Jun 1 20:36:05 gvs NetworkManager: DHCP: device wlan0 state changed preinit -> reboot Jun 1 20:36:05 gvs NetworkManager: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled... Jun 1 20:36:05 gvs dhclient: bound to 192.168.1.2 -- renewal in 40297 seconds. Jun 1 20:36:05 gvs NetworkManager: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started... Jun 1 20:36:05 gvs NetworkManager: address 192.168.1.2 Jun 1 20:36:05 gvs NetworkManager: prefix 24 (255.255.255.0) Jun 1 20:36:05 gvs NetworkManager: gateway 192.168.1.1 Jun 1 20:36:05 gvs NetworkManager: nameserver '192.168.1.1' Jun 1 20:36:05 gvs NetworkManager: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled... Jun 1 20:36:05 gvs NetworkManager: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete. Jun 1 20:36:05 gvs NetworkManager: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started... Jun 1 20:36:06 gvs NetworkManager: (wlan0): device state change: 7 -> 8 (reason 0) Jun 1 20:36:06 gvs NetworkManager: Policy set 'Auto gvs' (wlan0) as default for routing and DNS. Jun 1 20:36:06 gvs NetworkManager: Activation (wlan0) successful, device activated. Jun 1 20:36:06 gvs NetworkManager: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete. Jun 1 20:36:14 gvs kernel: [122359.177016] wlan0: no IPv6 routers present Jun 1 20:36:18 gvs dhclient: DHCPDISCOVER on wmx0 to 255.255.255.255 port 67 interval 13 Jun 1 20:36:18 gvs NetworkManager: (wmx0): DHCP transaction took too long, stopping it. Jun 1 20:36:18 gvs NetworkManager: (wmx0): canceled DHCP transaction, dhcp client pid 29145 Jun 1 20:36:18 gvs NetworkManager: Activation (wmx0) Stage 4 of 5 (IP4 Configure Timeout) scheduled... Jun 1 20:36:18 gvs NetworkManager: Activation (wmx0) Stage 4 of 5 (IP4 Configure Timeout) started... Jun 1 20:36:18 gvs NetworkManager: (wmx0): device state change: 7 -> 9 (reason 5) Jun 1 20:36:18 gvs NetworkManager: Marking connection 'Auto wmx0' invalid. Jun 1 20:36:18 gvs NetworkManager: Activation (wmx0) failed. Jun 1 20:36:18 gvs NetworkManager: Activation (wmx0) Stage 4 of 5 (IP4 Configure Timeout) complete. Jun 1 20:36:18 gvs NetworkManager: (wmx0): device state change: 9 -> 3 (reason 0) Jun 1 20:36:18 gvs NetworkManager: (wmx0): deactivating device (reason: 0). Jun 1 20:36:18 gvs NetworkManager: Policy set 'Auto gvs' (wlan0) as default for routing and DNS.