Sep 14 13:24:55 XXX kernel: [58025.889594] keyboard: can't emulate rawmode for keycode 240
Sep 14 13:24:55 XXX kernel: [58025.889620] keyboard: can't emulate rawmode for keycode 240
Sep 14 13:24:55 XXX kernel: [58026.712348] usb 5-1: USB disconnect, device number 3
Sep 14 13:26:06 XXX NetworkManager[1077]: <info> (wlan0): device state change: activated -> disconnected (reason 'user-requested') [100 30 39]
Sep 14 13:26:06 XXX NetworkManager[1077]: <info> (wlan0): deactivating device (reason 'user-requested') [39]
Sep 14 13:26:06 XXX NetworkManager[1077]: <info> DNS: starting dnsmasq...
Sep 14 13:26:06 XXX NetworkManager[1077]: <info> (wlan0): writing resolv.conf to /sbin/resolvconf
Sep 14 13:26:06 XXX kernel: [58097.362396] wlan0: deauthenticating from 64:68:0c:80:62:c1 by local choice (reason=3)
Sep 14 13:26:06 XXX wpa_supplicant[1524]: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3
Sep 14 13:26:06 XXX kernel: [58097.408342] cfg80211: All devices are disconnected, going to restore regulatory settings
Sep 14 13:26:06 XXX kernel: [58097.408354] cfg80211: Restoring regulatory settings
Sep 14 13:26:06 XXX kernel: [58097.408363] cfg80211: Calling CRDA to update world regulatory domain
Sep 14 13:26:06 XXX NetworkManager[1077]: <info> Policy set 'Vodafone (Airtel) Default' (ppp0) as default for IPv4 routing and DNS.
Sep 14 13:26:06 XXX NetworkManager[1077]: <info> Policy set 'Vodafone (Airtel) Default' (ppp0) as default for IPv4 routing and DNS.
Sep 14 13:26:06 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: completed -> disconnected
Sep 14 13:26:06 XXX kernel: [58097.430499] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
Sep 14 13:26:06 XXX kernel: [58097.430503] cfg80211: World regulatory domain updated:
Sep 14 13:26:06 XXX kernel: [58097.430505] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Sep 14 13:26:06 XXX kernel: [58097.430507] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 14 13:26:06 XXX kernel: [58097.430510] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 14 13:26:06 XXX kernel: [58097.430513] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 14 13:26:06 XXX kernel: [58097.430515] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 14 13:26:06 XXX kernel: [58097.430518] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 14 14:26:41 XXX NetworkManager[1077]: <info> sleep requested (sleeping: no  enabled: yes)
Sep 14 14:26:41 XXX NetworkManager[1077]: <info> sleeping or disabling...
Sep 14 14:26:41 XXX NetworkManager[1077]: <info> (eth0): now unmanaged
Sep 14 14:26:41 XXX NetworkManager[1077]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Sep 14 14:26:41 XXX NetworkManager[1077]: <info> (eth0): cleaning up...
Sep 14 14:26:41 XXX NetworkManager[1077]: <info> (eth0): taking down device.
Sep 14 14:26:41 XXX NetworkManager[1077]: <info> (wlan0): now unmanaged
Sep 14 14:26:41 XXX NetworkManager[1077]: <info> (wlan0): device state change: disconnected -> unmanaged (reason 'sleeping') [30 10 37]
Sep 14 14:26:41 XXX NetworkManager[1077]: <info> (wlan0): cleaning up...
Sep 14 14:26:41 XXX NetworkManager[1077]: <info> (wlan0): taking down device.
Sep 14 14:26:41 XXX NetworkManager[1077]: <info> (ttyUSB0): now unmanaged
Sep 14 14:26:41 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
Sep 14 14:26:42 XXX NetworkManager[1077]: <info> (ttyUSB0): deactivating device (reason 'sleeping') [37]
Sep 14 14:26:42 XXX NetworkManager[1077]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Sep 14 14:26:42 XXX NetworkManager[1077]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Sep 14 14:26:42 XXX NetworkManager[1077]: <info> DNS: starting dnsmasq...
Sep 14 14:26:42 XXX NetworkManager[1077]: <info> (ttyUSB0): writing resolv.conf to /sbin/resolvconf
Sep 14 14:26:42 XXX NetworkManager[1077]: <info> (ttyUSB0): cleaning up...
Sep 14 14:26:42 XXX NetworkManager[1077]: <info> (ttyUSB0): taking down device.
Sep 14 14:26:42 XXX NetworkManager[1077]:    SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Sep 14 14:26:42 XXX modem-manager[4627]: <info>  Modem /org/freedesktop/ModemManager/Modems/1: state changed (connected -> disconnecting)
Sep 14 14:26:44 XXX modem-manager[4627]: <info>  Modem /org/freedesktop/ModemManager/Modems/1: state changed (disconnecting -> registered)
Sep 14 14:59:06 XXX kernel: [61737.557762] PM: Syncing filesystems ... done.
Sep 14 14:59:06 XXX kernel: [61737.588281] PM: Preparing system for mem sleep
Sep 14 14:59:06 XXX kernel: [61737.588297] Freezing user space processes ... (elapsed 0.01 seconds) done.
Sep 14 14:59:06 XXX kernel: [61737.604089] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Sep 14 14:59:06 XXX kernel: [61737.620086] PM: Entering mem sleep
Sep 14 14:59:06 XXX kernel: [61737.620124] Suspending console(s) (use no_console_suspend to debug)
Sep 14 14:59:06 XXX kernel: [61737.620746] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Sep 14 14:59:06 XXX kernel: [61737.620935] sd 0:0:0:0: [sda] Stopping disk
Sep 14 14:59:06 XXX kernel: [61737.621112] option: option_instat_callback: error -2
Sep 14 14:59:06 XXX kernel: [61737.621738] option: option_instat_callback: error -2
Sep 14 14:59:06 XXX kernel: [61737.673890] sdhci-pci 0000:09:01.1: PCI INT B disabled
Sep 14 14:59:06 XXX kernel: [61737.674086] uhci_hcd 0000:00:1d.2: PCI INT C disabled
Sep 14 14:59:06 XXX kernel: [61737.674133] pciehp 0000:00:1c.5:pcie04: pciehp_suspend ENTRY
Sep 14 14:59:06 XXX kernel: [61737.674145] pciehp 0000:00:1c.4:pcie04: pciehp_suspend ENTRY
Sep 14 14:59:06 XXX kernel: [61737.674247] uhci_hcd 0000:00:1a.1: PCI INT B disabled
Sep 14 14:59:06 XXX kernel: [61737.674515] ata_piix 0000:00:1f.1: PCI INT A disabled
Sep 14 14:59:06 XXX kernel: [61737.688058] ehci_hcd 0000:00:1d.7: PCI INT A disabled
Sep 14 14:59:06 XXX kernel: [61737.704048] pciehp 0000:00:01.0:pcie04: pciehp_suspend ENTRY
Sep 14 14:59:06 XXX kernel: [61737.708116] uhci_hcd 0000:00:1a.0: PCI INT A disabled
Sep 14 14:59:06 XXX kernel: [61737.708146] uhci_hcd 0000:00:1d.0: PCI INT A disabled
Sep 14 14:59:06 XXX kernel: [61737.708176] uhci_hcd 0000:00:1d.1: PCI INT B disabled
Sep 14 14:59:06 XXX kernel: [61737.736066] ehci_hcd 0000:00:1a.7: PCI INT C disabled
Sep 14 14:59:06 XXX kernel: [61737.967879] PM: suspend of drv:sd dev:0:0:0:0 complete after 347.134 msecs
Sep 14 14:59:06 XXX kernel: [61737.967916] PM: suspend of drv:scsi dev:target0:0:0 complete after 347.096 msecs
Sep 14 14:59:06 XXX kernel: [61737.967952] PM: suspend of drv:scsi dev:host0 complete after 296.496 msecs
Sep 14 14:59:06 XXX kernel: [61737.980188] snd_hda_intel 0000:00:1b.0: PCI INT A disabled
Sep 14 14:59:06 XXX kernel: [61737.984117] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 310.108 msecs
Sep 14 14:59:06 XXX kernel: [61737.996134] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 321.938 msecs
Sep 14 14:59:06 XXX kernel: [61737.996171] PM: suspend of drv: dev:pci0000:00 complete after 321.755 msecs
Sep 14 14:59:06 XXX kernel: [61737.996205] PM: suspend of devices complete after 375.809 msecs
Sep 14 14:59:06 XXX kernel: [61737.996210] PM: suspend devices took 0.376 seconds
Sep 14 14:59:06 XXX kernel: [61738.060292] ehci_hcd 0000:00:1d.7: PME# enabled
Sep 14 14:59:06 XXX kernel: [61738.060357] ehci_hcd 0000:00:1d.7: wake-up capability enabled by ACPI
Sep 14 14:59:06 XXX kernel: [61738.076225] uhci_hcd 0000:00:1d.2: wake-up capability enabled by ACPI
Sep 14 14:59:06 XXX kernel: [61738.076279] uhci_hcd 0000:00:1d.1: wake-up capability enabled by ACPI
Sep 14 14:59:06 XXX kernel: [61738.076317] uhci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI
Sep 14 14:59:06 XXX kernel: [61738.076523] ehci_hcd 0000:00:1a.7: PME# enabled
Sep 14 14:59:06 XXX kernel: [61738.076560] ehci_hcd 0000:00:1a.7: wake-up capability enabled by ACPI
Sep 14 14:59:06 XXX kernel: [61738.092221] uhci_hcd 0000:00:1a.1: wake-up capability enabled by ACPI
Sep 14 14:59:06 XXX kernel: [61738.092276] uhci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI
Sep 14 14:59:06 XXX kernel: [61738.092382] PM: late suspend of devices complete after 96.168 msecs
Sep 14 14:59:06 XXX kernel: [61738.092984] ACPI: Preparing to enter system sleep state S3
Sep 14 14:59:06 XXX kernel: [61738.118617] PM: Saving platform NVS memory
Sep 14 14:59:06 XXX kernel: [61738.118899] Disabling non-boot CPUs ...
Sep 14 14:59:06 XXX kernel: [61738.120334] CPU 1 is now offline
Sep 14 14:59:06 XXX kernel: [61738.120841] Extended CMOS year: 2000
Sep 14 14:59:06 XXX kernel: [61738.120841] ACPI: Low-level resume complete
Sep 14 14:59:06 XXX kernel: [61738.120841] PM: Restoring platform NVS memory
Sep 14 14:59:06 XXX kernel: [61738.120841] CPU0: Thermal monitoring handled by SMI
Sep 14 14:59:06 XXX kernel: [61738.120841] Extended CMOS year: 2000
Sep 14 14:59:06 XXX kernel: [61738.120841] Enabling non-boot CPUs ...
Sep 14 14:59:06 XXX kernel: [61738.120841] Booting Node 0 Processor 1 APIC 0x1
Sep 14 14:59:06 XXX kernel: [61738.120841] smpboot cpu 1: start_ip = 9b000
Sep 14 14:59:06 XXX kernel: [61738.120322] Initializing CPU#1
Sep 14 14:59:06 XXX kernel: [61738.120322] Calibrating delay loop (skipped) already calibrated this CPU
Sep 14 14:59:06 XXX kernel: [61738.120322] CPU1: Thermal monitoring handled by SMI
Sep 14 14:59:06 XXX kernel: [61738.132417] NMI watchdog enabled, takes one hw-pmu counter.
Sep 14 14:59:06 XXX kernel: [61738.136147] CPU1 is up
Sep 14 14:59:06 XXX kernel: [61738.139994] ACPI: Waking up from system sleep state S3
Sep 14 14:59:06 XXX kernel: [61738.317225] pcieport 0000:00:01.0: restoring config space at offset 0xa (was 0xf, writing 0x0)
Sep 14 14:59:06 XXX kernel: [61738.317233] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507)
Sep 14 14:59:06 XXX kernel: [61738.317267] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Sep 14 14:59:06 XXX kernel: [61738.317291] uhci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI
Sep 14 14:59:06 XXX kernel: [61738.317311] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Sep 14 14:59:06 XXX kernel: [61738.317334] uhci_hcd 0000:00:1a.1: wake-up capability disabled by ACPI
Sep 14 14:59:06 XXX kernel: [61738.317362] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102)
Sep 14 14:59:06 XXX kernel: [61738.317409] ehci_hcd 0000:00:1a.7: wake-up capability disabled by ACPI
Sep 14 14:59:06 XXX kernel: [61738.317414] ehci_hcd 0000:00:1a.7: PME# disabled
Sep 14 14:59:06 XXX kernel: [61738.317439] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
Sep 14 14:59:06 XXX kernel: [61738.317444] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102)
Sep 14 14:59:06 XXX kernel: [61738.317466] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x20100, writing 0x2010a)
Sep 14 14:59:06 XXX kernel: [61738.317480] pcieport 0000:00:1c.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Sep 14 14:59:06 XXX kernel: [61738.317485] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506)
Sep 14 14:59:06 XXX kernel: [61738.317518] pcieport 0000:00:1c.1: restoring config space at offset 0xf (was 0x20200, writing 0x20205)
Sep 14 14:59:06 XXX kernel: [61738.317532] pcieport 0000:00:1c.1: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Sep 14 14:59:06 XXX kernel: [61738.317537] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100106, writing 0x100506)
Sep 14 14:59:06 XXX kernel: [61738.317570] pcieport 0000:00:1c.2: restoring config space at offset 0xf (was 0x20300, writing 0x20306)
Sep 14 14:59:06 XXX kernel: [61738.317584] pcieport 0000:00:1c.2: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Sep 14 14:59:06 XXX kernel: [61738.317589] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100104, writing 0x100504)
Sep 14 14:59:06 XXX kernel: [61738.317622] pcieport 0000:00:1c.3: restoring config space at offset 0xf (was 0x20400, writing 0x20403)
Sep 14 14:59:06 XXX kernel: [61738.317635] pcieport 0000:00:1c.3: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Sep 14 14:59:06 XXX kernel: [61738.317640] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507)
Sep 14 14:59:06 XXX kernel: [61738.317673] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x20100, writing 0x2010a)
Sep 14 14:59:06 XXX kernel: [61738.317687] pcieport 0000:00:1c.4: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Sep 14 14:59:06 XXX kernel: [61738.317692] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507)
Sep 14 14:59:06 XXX kernel: [61738.317725] pcieport 0000:00:1c.5: restoring config space at offset 0xf (was 0x20200, writing 0x20205)
Sep 14 14:59:06 XXX kernel: [61738.317733] pcieport 0000:00:1c.5: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd031d021)
Sep 14 14:59:06 XXX kernel: [61738.317737] pcieport 0000:00:1c.5: restoring config space at offset 0x8 (was 0xfff0, writing 0xd010d000)
Sep 14 14:59:06 XXX kernel: [61738.317741] pcieport 0000:00:1c.5: restoring config space at offset 0x7 (was 0xf0, writing 0x1010)
Sep 14 14:59:06 XXX kernel: [61738.317748] pcieport 0000:00:1c.5: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Sep 14 14:59:06 XXX kernel: [61738.317753] pcieport 0000:00:1c.5: restoring config space at offset 0x1 (was 0x100104, writing 0x100507)
Sep 14 14:59:06 XXX kernel: [61738.317793] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Sep 14 14:59:06 XXX kernel: [61738.317816] uhci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI
Sep 14 14:59:06 XXX kernel: [61738.317836] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Sep 14 14:59:06 XXX kernel: [61738.317858] uhci_hcd 0000:00:1d.1: wake-up capability disabled by ACPI
Sep 14 14:59:06 XXX kernel: [61738.317878] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Sep 14 14:59:06 XXX kernel: [61738.317899] uhci_hcd 0000:00:1d.2: wake-up capability disabled by ACPI
Sep 14 14:59:06 XXX kernel: [61738.317927] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102)
Sep 14 14:59:06 XXX kernel: [61738.317971] ehci_hcd 0000:00:1d.7: wake-up capability disabled by ACPI
Sep 14 14:59:06 XXX kernel: [61738.317976] ehci_hcd 0000:00:1d.7: PME# disabled
Sep 14 14:59:06 XXX kernel: [61738.317985] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x20000, writing 0x200ff)
Sep 14 14:59:06 XXX kernel: [61738.318055] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005)
Sep 14 14:59:06 XXX kernel: [61738.318083] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407)
Sep 14 14:59:06 XXX kernel: [61738.318116] nvidia 0000:01:00.0: restoring config space at offset 0xc (was 0xfdee0000, writing 0x0)
Sep 14 14:59:06 XXX kernel: [61738.318122] nvidia 0000:01:00.0: restoring config space at offset 0x9 (was 0x1, writing 0xac01)
Sep 14 14:59:06 XXX kernel: [61738.318126] nvidia 0000:01:00.0: restoring config space at offset 0x7 (was 0x4, writing 0xfa000004)
Sep 14 14:59:06 XXX kernel: [61738.318131] nvidia 0000:01:00.0: restoring config space at offset 0x5 (was 0xc, writing 0xe000000c)
Sep 14 14:59:06 XXX kernel: [61738.318134] nvidia 0000:01:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xfc000000)
Sep 14 14:59:06 XXX kernel: [61738.318138] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x8, writing 0x0)
Sep 14 14:59:06 XXX kernel: [61738.318143] nvidia 0000:01:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100006)
Sep 14 14:59:06 XXX kernel: [61738.318180] atl1 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
Sep 14 14:59:06 XXX kernel: [61738.318188] atl1 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfdfa0000)
Sep 14 14:59:06 XXX kernel: [61738.318205] atl1 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
Sep 14 14:59:06 XXX kernel: [61738.318211] atl1 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100106)
Sep 14 14:59:06 XXX kernel: [61738.318260] iwl4965 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x105)
Sep 14 14:59:06 XXX kernel: [61738.318288] iwl4965 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe0fe004)
Sep 14 14:59:06 XXX kernel: [61738.318295] iwl4965 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
Sep 14 14:59:06 XXX kernel: [61738.318303] iwl4965 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100506)
Sep 14 14:59:06 XXX kernel: [61738.318372] pci 0000:05:00.0: restoring config space at offset 0xf (was 0x100, writing 0x103)
Sep 14 14:59:06 XXX kernel: [61738.318380] pci 0000:05:00.0: restoring config space at offset 0xc (was 0x1, writing 0xfe1e0000)
Sep 14 14:59:06 XXX kernel: [61738.318392] pci 0000:05:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xbc01)
Sep 14 14:59:06 XXX kernel: [61738.318398] pci 0000:05:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xfe1ffc00)
Sep 14 14:59:06 XXX kernel: [61738.318403] pci 0000:05:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
Sep 14 14:59:06 XXX kernel: [61738.318410] pci 0000:05:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107)
Sep 14 14:59:06 XXX kernel: [61738.318438] firewire_ohci 0000:09:01.0: restoring config space at offset 0xf (was 0x4020100, writing 0x402010a)
Sep 14 14:59:06 XXX kernel: [61738.318455] firewire_ohci 0000:09:01.0: restoring config space at offset 0x3 (was 0x800000, writing 0x802008)
Sep 14 14:59:06 XXX kernel: [61738.318460] firewire_ohci 0000:09:01.0: restoring config space at offset 0x1 (was 0x2100006, writing 0x2100106)
Sep 14 14:59:06 XXX kernel: [61738.318475] firewire_ohci 0000:09:01.0: proprietary Ricoh MMC controller disabled (via firewire function)
Sep 14 14:59:06 XXX kernel: [61738.318476] firewire_ohci 0000:09:01.0: MMC cards are now supported by standard SDHCI controller
Sep 14 14:59:06 XXX kernel: [61738.318487] sdhci-pci 0000:09:01.1: restoring config space at offset 0xf (was 0x200, writing 0x205)
Sep 14 14:59:06 XXX kernel: [61738.318502] sdhci-pci 0000:09:01.1: restoring config space at offset 0x4 (was 0x0, writing 0xfeaff400)
Sep 14 14:59:06 XXX kernel: [61738.318507] sdhci-pci 0000:09:01.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804008)
Sep 14 14:59:06 XXX kernel: [61738.318512] sdhci-pci 0000:09:01.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106)
Sep 14 14:59:06 XXX kernel: [61738.318530] r592 0000:09:01.2: restoring config space at offset 0xf (was 0x200, writing 0x205)
Sep 14 14:59:06 XXX kernel: [61738.318545] r592 0000:09:01.2: restoring config space at offset 0x4 (was 0x0, writing 0xfeafec00)
Sep 14 14:59:06 XXX kernel: [61738.318549] r592 0000:09:01.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804008)
Sep 14 14:59:06 XXX kernel: [61738.318555] r592 0000:09:01.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106)
Sep 14 14:59:06 XXX kernel: [61738.318572] r852 0000:09:01.3: restoring config space at offset 0xf (was 0x200, writing 0x205)
Sep 14 14:59:06 XXX kernel: [61738.318587] r852 0000:09:01.3: restoring config space at offset 0x4 (was 0x0, writing 0xfeafe800)
Sep 14 14:59:06 XXX kernel: [61738.318591] r852 0000:09:01.3: restoring config space at offset 0x3 (was 0x800000, writing 0x804008)
Sep 14 14:59:06 XXX kernel: [61738.318597] r852 0000:09:01.3: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106)
Sep 14 14:59:06 XXX kernel: [61738.319009] PM: early resume of devices complete after 1.894 msecs
Sep 14 14:59:06 XXX kernel: [61738.319165] pciehp 0000:00:01.0:pcie04: pciehp_resume ENTRY
Sep 14 14:59:06 XXX kernel: [61738.319176] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Sep 14 14:59:06 XXX kernel: [61738.319182] uhci_hcd 0000:00:1a.0: setting latency timer to 64
Sep 14 14:59:06 XXX kernel: [61738.319214] usb usb3: root hub lost power or was reset
Sep 14 14:59:06 XXX kernel: [61738.319226] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
Sep 14 14:59:06 XXX kernel: [61738.319231] uhci_hcd 0000:00:1a.1: setting latency timer to 64
Sep 14 14:59:06 XXX kernel: [61738.319251] usb usb4: root hub lost power or was reset
Sep 14 14:59:06 XXX kernel: [61738.319263] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18
Sep 14 14:59:06 XXX kernel: [61738.319268] ehci_hcd 0000:00:1a.7: setting latency timer to 64
Sep 14 14:59:06 XXX kernel: [61738.319281] snd_hda_intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
Sep 14 14:59:06 XXX kernel: [61738.319286] snd_hda_intel 0000:00:1b.0: setting latency timer to 64
Sep 14 14:59:06 XXX kernel: [61738.319337] pciehp 0000:00:1c.4:pcie04: pciehp_resume ENTRY
Sep 14 14:59:06 XXX kernel: [61738.319337] snd_hda_intel 0000:00:1b.0: irq 48 for MSI/MSI-X
Sep 14 14:59:06 XXX kernel: [61738.319348] pciehp 0000:00:1c.5:pcie04: pciehp_resume ENTRY
Sep 14 14:59:06 XXX kernel: [61738.319356] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23
Sep 14 14:59:06 XXX kernel: [61738.319361] uhci_hcd 0000:00:1d.0: setting latency timer to 64
Sep 14 14:59:06 XXX kernel: [61738.319377] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19
Sep 14 14:59:06 XXX kernel: [61738.319383] usb usb5: root hub lost power or was reset
Sep 14 14:59:06 XXX kernel: [61738.319383] uhci_hcd 0000:00:1d.1: setting latency timer to 64
Sep 14 14:59:06 XXX kernel: [61738.319396] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
Sep 14 14:59:06 XXX kernel: [61738.319402] uhci_hcd 0000:00:1d.2: setting latency timer to 64
Sep 14 14:59:06 XXX kernel: [61738.319408] usb usb6: root hub lost power or was reset
Sep 14 14:59:06 XXX kernel: [61738.319423] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23
Sep 14 14:59:06 XXX kernel: [61738.319430] usb usb7: root hub lost power or was reset
Sep 14 14:59:06 XXX kernel: [61738.319430] ehci_hcd 0000:00:1d.7: setting latency timer to 64
Sep 14 14:59:06 XXX kernel: [61738.319442] pci 0000:00:1e.0: setting latency timer to 64
Sep 14 14:59:06 XXX kernel: [61738.319452] ata_piix 0000:00:1f.1: PCI INT A -> GSI 18 (level, low) -> IRQ 18
Sep 14 14:59:06 XXX kernel: [61738.319458] ata_piix 0000:00:1f.1: setting latency timer to 64
Sep 14 14:59:06 XXX kernel: [61738.319461] ahci 0000:00:1f.2: setting latency timer to 64
Sep 14 14:59:06 XXX kernel: [61738.320557] ata5: port disabled--ignoring
Sep 14 14:59:06 XXX kernel: [61738.322240] sdhci-pci 0000:09:01.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Sep 14 14:59:06 XXX kernel: [61738.322244] sdhci-pci 0000:09:01.1: Will use DMA mode even though HW doesn't fully claim to support it.
Sep 14 14:59:06 XXX kernel: [61738.323692] sd 0:0:0:0: [sda] Starting disk
Sep 14 14:59:06 XXX kernel: [61738.350894] Extended CMOS year: 2000
Sep 14 14:59:06 XXX kernel: [61738.384129] firewire_core: skipped bus generations, destroying all nodes
Sep 14 14:59:06 XXX kernel: [61738.440137] PM: resume of drv:hub dev:3-0:1.0 complete after 117.426 msecs
Sep 14 14:59:06 XXX kernel: [61738.440144] PM: resume of drv: dev:ep_00 complete after 117.404 msecs
Sep 14 14:59:06 XXX kernel: [61738.440150] PM: resume of drv:hub dev:5-0:1.0 complete after 117.187 msecs
Sep 14 14:59:06 XXX kernel: [61738.440157] PM: resume of drv: dev:ep_00 complete after 116.941 msecs
Sep 14 14:59:06 XXX kernel: [61738.440161] PM: resume of drv:hub dev:6-0:1.0 complete after 116.753 msecs
Sep 14 14:59:06 XXX kernel: [61738.440167] PM: resume of drv: dev:ep_00 complete after 116.680 msecs
Sep 14 14:59:06 XXX kernel: [61738.440173] PM: resume of drv: dev:ep_81 complete after 117.442 msecs
Sep 14 14:59:06 XXX kernel: [61738.440177] PM: resume of drv: dev:ep_81 complete after 116.980 msecs
Sep 14 14:59:06 XXX kernel: [61738.440182] PM: resume of drv: dev:ep_81 complete after 116.734 msecs
Sep 14 14:59:06 XXX kernel: [61738.480151] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 160.677 msecs
Sep 14 14:59:06 XXX kernel: [61738.544122] PM: resume of drv:hub dev:7-0:1.0 complete after 220.550 msecs
Sep 14 14:59:06 XXX kernel: [61738.544135] PM: resume of drv: dev:ep_00 complete after 220.488 msecs
Sep 14 14:59:06 XXX kernel: [61738.544149] PM: resume of drv:hub dev:4-0:1.0 complete after 221.391 msecs
Sep 14 14:59:06 XXX kernel: [61738.544154] PM: resume of drv: dev:ep_81 complete after 220.558 msecs
Sep 14 14:59:06 XXX kernel: [61738.544162] PM: resume of drv: dev:ep_00 complete after 221.343 msecs
Sep 14 14:59:06 XXX kernel: [61738.544166] PM: resume of drv: dev:ep_81 complete after 221.401 msecs
Sep 14 14:59:06 XXX kernel: [61738.572134] PM: resume of drv:hub dev:1-0:1.0 complete after 249.575 msecs
Sep 14 14:59:06 XXX kernel: [61738.572144] PM: resume of drv: dev:ep_00 complete after 249.574 msecs
Sep 14 14:59:06 XXX kernel: [61738.572159] PM: resume of drv: dev:ep_81 complete after 249.598 msecs
Sep 14 14:59:06 XXX kernel: [61738.572257] PM: resume of drv: dev:ep_00 complete after 249.618 msecs
Sep 14 14:59:06 XXX kernel: [61738.572268] PM: resume of drv:hub dev:2-0:1.0 complete after 249.688 msecs
Sep 14 14:59:06 XXX kernel: [61738.572280] PM: resume of drv: dev:ep_81 complete after 249.691 msecs
Sep 14 14:59:06 XXX kernel: [61738.596421] ata4.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out
Sep 14 14:59:06 XXX kernel: [61738.596424] ata4.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out
Sep 14 14:59:06 XXX kernel: [61738.612310] ata4.00: configured for UDMA/33
Sep 14 14:59:06 XXX kernel: [61738.621473] PM: resume of drv:option dev:2-4:1.0 complete after 296.536 msecs
Sep 14 14:59:06 XXX kernel: [61738.621480] PM: resume of drv:option dev:2-4:1.1 complete after 296.324 msecs
Sep 14 14:59:06 XXX kernel: [61738.621495] PM: resume of drv:option1 dev:ttyUSB0 complete after 296.541 msecs
Sep 14 14:59:06 XXX kernel: [61738.621500] PM: resume of drv:option dev:2-4:1.2 complete after 296.118 msecs
Sep 14 14:59:06 XXX kernel: [61738.621506] PM: resume of drv: dev:ep_81 complete after 296.495 msecs
Sep 14 14:59:06 XXX kernel: [61738.621515] PM: resume of drv: dev:ep_82 complete after 296.460 msecs
Sep 14 14:59:06 XXX kernel: [61738.621520] PM: resume of drv:option dev:2-4:1.3 complete after 295.942 msecs
Sep 14 14:59:06 XXX kernel: [61738.621525] PM: resume of drv: dev:ep_01 complete after 296.427 msecs
Sep 14 14:59:06 XXX kernel: [61738.621556] PM: resume of drv:usb-storage dev:2-4:1.4 complete after 295.814 msecs
Sep 14 14:59:06 XXX kernel: [61738.621558] PM: resume of drv: dev:ep_04 complete after 295.833 msecs
Sep 14 14:59:06 XXX kernel: [61738.621569] PM: resume of drv: dev:ep_86 complete after 295.909 msecs
Sep 14 14:59:06 XXX kernel: [61738.621572] PM: resume of drv:usb-storage dev:2-4:1.5 complete after 295.572 msecs
Sep 14 14:59:06 XXX kernel: [61738.621574] PM: resume of drv:option1 dev:ttyUSB3 complete after 295.983 msecs
Sep 14 14:59:06 XXX kernel: [61738.621579] PM: resume of drv: dev:ep_03 complete after 296.060 msecs
Sep 14 14:59:06 XXX kernel: [61738.621582] PM: resume of drv: dev:ep_00 complete after 295.366 msecs
Sep 14 14:59:06 XXX kernel: [61738.621584] PM: resume of drv: dev:ep_85 complete after 296.133 msecs
Sep 14 14:59:06 XXX kernel: [61738.621587] PM: resume of drv:option1 dev:ttyUSB1 complete after 296.418 msecs
Sep 14 14:59:06 XXX kernel: [61738.621589] PM: resume of drv:option1 dev:ttyUSB2 complete after 296.156 msecs
Sep 14 14:59:06 XXX kernel: [61738.621592] PM: resume of drv: dev:ep_83 complete after 296.354 msecs
Sep 14 14:59:06 XXX kernel: [61738.621594] PM: resume of drv: dev:ep_02 complete after 296.283 msecs
Sep 14 14:59:06 XXX kernel: [61738.621597] PM: resume of drv: dev:ep_84 complete after 296.306 msecs
Sep 14 14:59:06 XXX kernel: [61738.621605] PM: resume of drv: dev:ep_88 complete after 295.444 msecs
Sep 14 14:59:06 XXX kernel: [61738.621607] PM: resume of drv:scsi dev:host31 complete after 295.802 msecs
Sep 14 14:59:06 XXX kernel: [61738.621611] PM: resume of drv: dev:ep_06 complete after 295.468 msecs
Sep 14 14:59:06 XXX kernel: [61738.621616] PM: resume of drv: dev:ep_87 complete after 295.714 msecs
Sep 14 14:59:06 XXX kernel: [61738.621618] PM: resume of drv:scsi dev:host32 complete after 295.574 msecs
Sep 14 14:59:06 XXX kernel: [61738.621621] PM: resume of drv: dev:ep_05 complete after 295.677 msecs
Sep 14 14:59:06 XXX kernel: [61738.621626] PM: resume of drv:scsi_host dev:host31 complete after 295.768 msecs
Sep 14 14:59:06 XXX kernel: [61738.621629] PM: resume of drv:scsi_host dev:host32 complete after 295.540 msecs
Sep 14 14:59:06 XXX kernel: [61738.621632] PM: resume of drv:scsi dev:target31:0:0 complete after 295.397 msecs
Sep 14 14:59:06 XXX kernel: [61738.621635] PM: resume of drv:scsi dev:target32:0:0 complete after 295.345 msecs
Sep 14 14:59:06 XXX kernel: [61738.621643] PM: resume of drv:sr dev:31:0:0:0 complete after 295.389 msecs
Sep 14 14:59:06 XXX kernel: [61738.621650] PM: resume of drv:sd dev:32:0:0:0 complete after 295.343 msecs
Sep 14 14:59:06 XXX kernel: [61738.621652] PM: resume of drv:scsi_device dev:31:0:0:0 complete after 295.379 msecs
Sep 14 14:59:06 XXX kernel: [61738.621659] PM: resume of drv:scsi_device dev:32:0:0:0 complete after 295.335 msecs
Sep 14 14:59:06 XXX kernel: [61738.644141] ata2: SATA link down (SStatus 0 SControl 300)
Sep 14 14:59:06 XXX kernel: [61738.652048] ata3: SATA link down (SStatus 0 SControl 300)
Sep 14 14:59:06 XXX kernel: [61738.684107] usb 4-2: reset full-speed USB device number 2 using uhci_hcd
Sep 14 14:59:06 XXX kernel: [61738.884065] firewire_core: rediscovered device fw0
Sep 14 14:59:06 XXX kernel: [61738.900782] btusb 4-2:1.0: no reset_resume for driver btusb?
Sep 14 14:59:06 XXX kernel: [61738.900786] btusb 4-2:1.1: no reset_resume for driver btusb?
Sep 14 14:59:06 XXX kernel: [61738.968055] usb 7-2: reset full-speed USB device number 2 using uhci_hcd
Sep 14 14:59:06 XXX kernel: [61739.100603] PM: resume of drv:uvcvideo dev:1-2:1.1 complete after 776.502 msecs
Sep 14 14:59:06 XXX kernel: [61739.100616] PM: resume of drv: dev:ep_00 complete after 776.464 msecs
Sep 14 14:59:06 XXX kernel: [61739.100622] PM: resume of drv:uvcvideo dev:1-2:1.0 complete after 776.730 msecs
Sep 14 14:59:06 XXX kernel: [61739.100627] PM: resume of drv: dev:ep_82 complete after 776.521 msecs
Sep 14 14:59:06 XXX kernel: [61739.100650] PM: resume of drv: dev:ep_81 complete after 776.709 msecs
Sep 14 14:59:06 XXX kernel: [61739.116633] PM: resume of drv: dev:ep_00 complete after 791.770 msecs
Sep 14 14:59:06 XXX kernel: [61739.116638] PM: resume of drv:usb dev:7-2:1.0 complete after 791.920 msecs
Sep 14 14:59:06 XXX kernel: [61739.116651] PM: resume of drv: dev:ep_02 complete after 791.803 msecs
Sep 14 14:59:06 XXX kernel: [61739.116673] PM: resume of drv: dev:ep_81 complete after 791.887 msecs
Sep 14 14:59:06 XXX kernel: [61739.152258] PM: resume of drv:usb dev:4-2:1.1 complete after 827.828 msecs
Sep 14 14:59:06 XXX kernel: [61739.152267] PM: resume of drv:usb dev:4-2:1.0 complete after 827.991 msecs
Sep 14 14:59:06 XXX kernel: [61739.152268] PM: resume of drv: dev:ep_03 complete after 827.770 msecs
Sep 14 14:59:06 XXX kernel: [61739.152276] PM: resume of drv:usb dev:4-2:1.2 complete after 827.700 msecs
Sep 14 14:59:06 XXX kernel: [61739.152273] PM: resume of drv: dev:ep_83 complete after 827.721 msecs
Sep 14 14:59:06 XXX kernel: [61739.152281] PM: resume of drv: dev:ep_00 complete after 827.637 msecs
Sep 14 14:59:06 XXX kernel: [61739.152282] PM: resume of drv: dev:ep_82 complete after 827.872 msecs
Sep 14 14:59:06 XXX kernel: [61739.152290] PM: resume of drv: dev:ep_81 complete after 827.996 msecs
Sep 14 14:59:06 XXX kernel: [61739.152287] PM: resume of drv: dev:ep_02 complete after 827.932 msecs
Sep 14 14:59:06 XXX kernel: [61741.660074] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Sep 14 14:59:06 XXX kernel: [61741.662780] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Sep 14 14:59:06 XXX kernel: [61741.663123] ata1.00: ACPI cmd ef/10:06:00:00:00:a0 (SET FEATURES) succeeded
Sep 14 14:59:06 XXX kernel: [61741.663130] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Sep 14 14:59:06 XXX kernel: [61741.674037] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Sep 14 14:59:06 XXX kernel: [61741.674166] ata1.00: ACPI cmd ef/10:06:00:00:00:a0 (SET FEATURES) succeeded
Sep 14 14:59:06 XXX kernel: [61741.674173] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Sep 14 14:59:06 XXX kernel: [61741.677274] ata1.00: configured for UDMA/133
Sep 14 14:59:06 XXX kernel: [61741.696273] PM: resume of drv:sd dev:0:0:0:0 complete after 3372.578 msecs
Sep 14 14:59:06 XXX kernel: [61741.696290] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 3345.350 msecs
Sep 14 14:59:06 XXX kernel: [61741.696310] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 3372.521 msecs
Sep 14 14:59:06 XXX kernel: [61741.696578] PM: resume of devices complete after 3377.508 msecs
Sep 14 14:59:06 XXX kernel: [61741.696968] PM: resume devices took 3.380 seconds
Sep 14 14:59:06 XXX kernel: [61741.697013] PM: Finishing wakeup.
Sep 14 14:59:06 XXX kernel: [61741.697015] Restarting tasks ... done.
Sep 14 14:59:06 XXX kernel: [61741.730108] video LNXVIDEO:00: Restoring backlight state
Sep 14 14:59:07 XXX kernel: [61742.488043] usb 5-1: new low-speed USB device number 4 using uhci_hcd
Sep 14 14:59:07 XXX kernel: [61742.689560] input: Genius Optical Mouse as /devices/pci0000:00/0000:00:1d.0/usb5/5-1/5-1:1.0/input/input12
Sep 14 14:59:07 XXX kernel: [61742.690022] generic-usb 0003:0458:003A.0003: input,hidraw0: USB HID v1.10 Mouse [Genius Optical Mouse] on usb-0000:00:1d.0-1/input0
Sep 14 14:59:07 XXX NetworkManager[1077]: <info> wake requested (sleeping: yes  enabled: yes)
Sep 14 14:59:07 XXX NetworkManager[1077]: <info> waking up and re-enabling...
Sep 14 14:59:07 XXX NetworkManager[1077]: <info> (eth0): now managed
Sep 14 14:59:07 XXX NetworkManager[1077]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Sep 14 14:59:07 XXX NetworkManager[1077]: <info> (eth0): bringing up device.
Sep 14 14:59:07 XXX NetworkManager[1077]: <info> (eth0): preparing device.
Sep 14 14:59:07 XXX NetworkManager[1077]: <info> (eth0): deactivating device (reason 'managed') [2]
Sep 14 14:59:07 XXX NetworkManager[1077]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Sep 14 14:59:07  NetworkManager[1077]: last message repeated 3 times
Sep 14 14:59:07 XXX NetworkManager[1077]: <info> (wlan0): now managed
Sep 14 14:59:07 XXX NetworkManager[1077]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Sep 14 14:59:07 XXX NetworkManager[1077]: <info> (wlan0): bringing up device.
Sep 14 14:59:07 XXX kernel: [61742.896961] atl1 0000:02:00.0: irq 50 for MSI/MSI-X
Sep 14 14:59:07 XXX kernel: [61742.897168] ADDRCONF(NETDEV_UP): eth0: link is not ready
Sep 14 14:59:08 XXX kernel: [61743.139737] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> (wlan0): preparing device.
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> (wlan0): deactivating device (reason 'managed') [2]
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> (ttyUSB0): now managed
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> (ttyUSB0): deactivating device (reason 'managed') [2]
Sep 14 14:59:08 XXX NetworkManager[1077]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Sep 14 14:59:08 XXX NetworkManager[1077]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: starting -> ready
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: ready -> inactive
Sep 14 14:59:08 XXX NetworkManager[1077]: <warn> Trying to remove a non-existant call id.
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> Auto-activating connection 'Vodafone (Airtel) Default'.
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) starting connection 'Vodafone (Airtel) Default'
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Sep 14 14:59:08 XXX modem-manager[4627]: <info>  Modem /org/freedesktop/ModemManager/Modems/1: state changed (registered -> connecting)
Sep 14 14:59:08 XXX modem-manager[4627]: <info>  Modem /org/freedesktop/ModemManager/Modems/1: state changed (connecting -> connected)
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled...
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting...
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: prepare -> config (reason 'none') [40 50 0]
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) successful.
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) scheduled.
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) started...
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: config -> ip-config (reason 'none') [50 70 0]
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> starting PPP connection
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> pppd started with pid 22905
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) complete.
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Sep 14 14:59:08 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Sep 14 14:59:08 XXX NetworkManager[1077]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Sep 14 14:59:08 XXX NetworkManager[1077]:    SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
Sep 14 14:59:12 XXX NetworkManager[1077]: <info> PPP manager(IP Config Get) reply received.
Sep 14 14:59:12 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Sep 14 14:59:12 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) started...
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> DNS: starting dnsmasq...
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> (ppp0): writing resolv.conf to /sbin/resolvconf
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Policy set 'Vodafone (Airtel) Default' (ppp0) as default for IPv4 routing and DNS.
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) successful, device activated.
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) complete.
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Auto-activating connection 'JAZZTEL_62C0'.
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (wlan0) starting connection 'JAZZTEL_62C0'
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (wlan0/wireless): access point 'JAZZTEL_62C0' has security, but secrets are required.
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (wlan0/wireless): connection 'JAZZTEL_62C0' has security, and secrets exist.  No new secrets needed.
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Config: added 'ssid' value 'JAZZTEL_62C0'
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Config: added 'scan_ssid' value '1'
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Config: added 'psk' value '<omitted>'
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> Config: set interface ap_scan to 1
Sep 14 14:59:13 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: inactive -> scanning
Sep 14 14:59:15 XXX wpa_supplicant[1524]: Trying to authenticate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2412 MHz)
Sep 14 14:59:15 XXX kernel: [61750.079554] wlan0: authenticate with 64:68:0c:80:62:c1 (try 1)
Sep 14 14:59:15 XXX wpa_supplicant[1524]: Trying to associate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2412 MHz)
Sep 14 14:59:15 XXX kernel: [61750.081635] wlan0: authenticated
Sep 14 14:59:15 XXX kernel: [61750.081969] wlan0: associate with 64:68:0c:80:62:c1 (try 1)
Sep 14 14:59:15 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: scanning -> associating
Sep 14 14:59:15 XXX kernel: [61750.094440] wlan0: RX AssocResp from 64:68:0c:80:62:c1 (capab=0x411 status=0 aid=5)
Sep 14 14:59:15 XXX kernel: [61750.094443] wlan0: associated
Sep 14 14:59:15 XXX wpa_supplicant[1524]: Associated with 64:68:0c:80:62:c1
Sep 14 14:59:15 XXX kernel: [61750.132750] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Sep 14 14:59:15 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: associating -> associated
Sep 14 14:59:15 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
Sep 14 14:59:15 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: 4-way handshake -> group handshake
Sep 14 14:59:15 XXX wpa_supplicant[1524]: WPA: Key negotiation completed with 64:68:0c:80:62:c1 [PTK=CCMP GTK=CCMP]
Sep 14 14:59:15 XXX wpa_supplicant[1524]: CTRL-EVENT-CONNECTED - Connection to 64:68:0c:80:62:c1 completed (auth) [id=0 id_str=]
Sep 14 14:59:15 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: group handshake -> completed
Sep 14 14:59:15 XXX NetworkManager[1077]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'JAZZTEL_62C0'.
Sep 14 14:59:15 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Sep 14 14:59:15 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Sep 14 14:59:15 XXX NetworkManager[1077]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
Sep 14 14:59:15 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Sep 14 14:59:15 XXX NetworkManager[1077]: <info> Activation (wlan0) Beginning IP6 addrconf.
Sep 14 14:59:15 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Sep 14 14:59:15 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started...
Sep 14 14:59:17 XXX NetworkManager[1077]: <info> DNS: starting dnsmasq...
Sep 14 14:59:17 XXX NetworkManager[1077]: <info> (wlan0): writing resolv.conf to /sbin/resolvconf
Sep 14 14:59:17 XXX NetworkManager[1077]: <info> Policy set 'Vodafone (Airtel) Default' (ppp0) as default for IPv4 routing and DNS.
Sep 14 14:59:17 XXX NetworkManager[1077]: <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Sep 14 14:59:17 XXX NetworkManager[1077]: <info> DNS: starting dnsmasq...
Sep 14 14:59:17 XXX NetworkManager[1077]: <info> (wlan0): writing resolv.conf to /sbin/resolvconf
Sep 14 14:59:17 XXX NetworkManager[1077]: <info> Policy set 'JAZZTEL_62C0' (wlan0) as default for IPv4 routing and DNS.
Sep 14 14:59:17 XXX NetworkManager[1077]: <info> Activation (wlan0) successful, device activated.
Sep 14 14:59:17 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete.
Sep 14 14:59:18 XXX kernel: [61753.560752] iwl4965 0000:03:00.0: iwl4965_tx_agg_start on ra = 64:68:0c:80:62:c1 tid = 0
Sep 14 14:59:26 XXX kernel: [61761.648035] wlan0: no IPv6 routers present
Sep 14 14:59:26 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: activated -> disconnected (reason 'user-requested') [100 30 39]
Sep 14 14:59:26 XXX NetworkManager[1077]: <info> (ttyUSB0): deactivating device (reason 'user-requested') [39]
Sep 14 14:59:26 XXX NetworkManager[1077]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Sep 14 14:59:26 XXX NetworkManager[1077]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Sep 14 14:59:26 XXX NetworkManager[1077]: <info> DNS: starting dnsmasq...
Sep 14 14:59:26 XXX NetworkManager[1077]: <info> (ttyUSB0): writing resolv.conf to /sbin/resolvconf
Sep 14 14:59:26 XXX NetworkManager[1077]: <info> Policy set 'JAZZTEL_62C0' (wlan0) as default for IPv4 routing and DNS.
Sep 14 14:59:26 XXX NetworkManager[1077]: <info> Policy set 'JAZZTEL_62C0' (wlan0) as default for IPv4 routing and DNS.
Sep 14 14:59:26 XXX modem-manager[4627]: <info>  Modem /org/freedesktop/ModemManager/Modems/1: state changed (connected -> disconnecting)
Sep 14 14:59:26 XXX NetworkManager[1077]:    SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Sep 14 14:59:27 XXX modem-manager[4627]: <info>  Modem /org/freedesktop/ModemManager/Modems/1: state changed (disconnecting -> registered)
Sep 14 14:59:36 XXX NetworkManager[1077]: <info> (wlan0): IP6 addrconf timed out or failed.
Sep 14 14:59:36 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Sep 14 14:59:36 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Sep 14 14:59:36 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Sep 14 15:21:11 XXX kernel: [63066.051108] iwl4965 0000:03:00.0: Aggregation not enabled for tid 6 because load = 0
Sep 14 15:42:34 XXX kernel: [64349.178973] iwl4965 0000:03:00.0: Aggregation not enabled for tid 6 because load = 1
Sep 14 16:01:57 XXX NetworkManager[1077]: <info> kernel firmware directory '/lib/firmware' changed
Sep 14 16:10:03 XXX kernel: [65998.222587] SGI XFS with ACLs, security attributes, realtime, large block/inode numbers, no debug enabled
Sep 14 16:10:03 XXX kernel: [65998.224788] SGI XFS Quota Management subsystem
Sep 14 16:10:03 XXX kernel: [65998.352699] JFS: nTxBlock = 8192, nTxLock = 65536
Sep 14 16:10:03 XXX kernel: [65998.415496] NTFS driver 2.1.30 [Flags: R/O MODULE].
Sep 14 16:10:03 XXX kernel: [65998.520873] QNX4 filesystem 0.2.3 registered.
Sep 14 16:10:28 XXX kernel: [66023.240066] type=1400 audit(1347631828.323:27): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince" pid=28541 comm="apparmor_parser"
Sep 14 16:10:28 XXX kernel: [66023.240867] type=1400 audit(1347631828.323:28): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince//launchpad_integration" pid=28541 comm="apparmor_parser"
Sep 14 16:10:28 XXX kernel: [66023.241184] type=1400 audit(1347631828.323:29): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince//sanitized_helper" pid=28541 comm="apparmor_parser"
Sep 14 16:10:28 XXX kernel: [66023.241918] type=1400 audit(1347631828.323:30): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince-previewer" pid=28541 comm="apparmor_parser"
Sep 14 16:10:28 XXX kernel: [66023.242489] type=1400 audit(1347631828.323:31): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince-previewer//launchpad_integration" pid=28541 comm="apparmor_parser"
Sep 14 16:10:28 XXX kernel: [66023.242806] type=1400 audit(1347631828.323:32): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince-previewer//sanitized_helper" pid=28541 comm="apparmor_parser"
Sep 14 16:10:28 XXX kernel: [66023.243310] type=1400 audit(1347631828.323:33): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince-thumbnailer" pid=28541 comm="apparmor_parser"
Sep 14 16:10:28 XXX kernel: [66023.243735] type=1400 audit(1347631828.323:34): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince-thumbnailer//sanitized_helper" pid=28541 comm="apparmor_parser"
Sep 14 16:32:32 XXX kernel: [67346.931215] keyboard: can't emulate rawmode for keycode 240
Sep 14 16:32:32 XXX kernel: [67346.931239] keyboard: can't emulate rawmode for keycode 240
Sep 14 16:32:33 XXX kernel: [67348.288178] usb 5-1: USB disconnect, device number 4
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) starting connection 'Vodafone (Airtel) Default'
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Sep 14 16:33:25 XXX modem-manager[4627]: <info>  Modem /org/freedesktop/ModemManager/Modems/1: state changed (registered -> connecting)
Sep 14 16:33:25 XXX modem-manager[4627]: <info>  Modem /org/freedesktop/ModemManager/Modems/1: state changed (connecting -> connected)
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled...
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting...
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: prepare -> config (reason 'none') [40 50 0]
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) successful.
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) scheduled.
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) started...
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: config -> ip-config (reason 'none') [50 70 0]
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> starting PPP connection
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> pppd started with pid 11592
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) complete.
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Sep 14 16:33:25 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Sep 14 16:33:25 XXX NetworkManager[1077]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Sep 14 16:33:25 XXX NetworkManager[1077]:    SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
Sep 14 16:33:27 XXX wpa_supplicant[1524]: CTRL-EVENT-DISCONNECTED bssid=64:68:0c:80:62:c1 reason=4
Sep 14 16:33:27 XXX kernel: [67402.182243] cfg80211: All devices are disconnected, going to restore regulatory settings
Sep 14 16:33:27 XXX kernel: [67402.182249] cfg80211: Restoring regulatory settings
Sep 14 16:33:27 XXX kernel: [67402.182254] cfg80211: Calling CRDA to update world regulatory domain
Sep 14 16:33:27 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: completed -> disconnected
Sep 14 16:33:27 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Sep 14 16:33:27 XXX kernel: [67402.359462] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
Sep 14 16:33:27 XXX kernel: [67402.359466] cfg80211: World regulatory domain updated:
Sep 14 16:33:27 XXX kernel: [67402.359468] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Sep 14 16:33:27 XXX kernel: [67402.359471] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 14 16:33:27 XXX kernel: [67402.359473] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 14 16:33:27 XXX kernel: [67402.359476] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 14 16:33:27 XXX kernel: [67402.359478] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 14 16:33:27 XXX kernel: [67402.359481] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 14 16:33:28 XXX NetworkManager[1077]: <info> (wlan0): device state change: activated -> disconnected (reason 'user-requested') [100 30 39]
Sep 14 16:33:28 XXX NetworkManager[1077]: <info> (wlan0): deactivating device (reason 'user-requested') [39]
Sep 14 16:33:28 XXX kernel: [67403.423641] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Sep 14 16:33:28 XXX NetworkManager[1077]: <info> DNS: starting dnsmasq...
Sep 14 16:33:28 XXX NetworkManager[1077]: <info> (wlan0): writing resolv.conf to /sbin/resolvconf
Sep 14 16:33:28 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: scanning -> disconnected
Sep 14 16:33:29 XXX NetworkManager[1077]: <info> PPP manager(IP Config Get) reply received.
Sep 14 16:33:29 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Sep 14 16:33:29 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) started...
Sep 14 16:33:30 XXX NetworkManager[1077]: <info> DNS: starting dnsmasq...
Sep 14 16:33:30 XXX NetworkManager[1077]: <info> (ppp0): writing resolv.conf to /sbin/resolvconf
Sep 14 16:33:30 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Sep 14 16:33:30 XXX NetworkManager[1077]: <info> Policy set 'Vodafone (Airtel) Default' (ppp0) as default for IPv4 routing and DNS.
Sep 14 16:33:30 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) successful, device activated.
Sep 14 16:33:30 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) complete.
Sep 14 17:03:40 XXX kernel: [69215.593544] keyboard: can't emulate rawmode for keycode 240
Sep 14 17:03:40 XXX kernel: [69215.593571] keyboard: can't emulate rawmode for keycode 240
Sep 14 17:03:42 XXX kernel: [69217.248102] usb 5-1: new low-speed USB device number 5 using uhci_hcd
Sep 14 17:03:42 XXX kernel: [69217.449611] input: Genius Optical Mouse as /devices/pci0000:00/0000:00:1d.0/usb5/5-1/5-1:1.0/input/input13
Sep 14 17:03:42 XXX kernel: [69217.449958] generic-usb 0003:0458:003A.0004: input,hidraw0: USB HID v1.10 Mouse [Genius Optical Mouse] on usb-0000:00:1d.0-1/input0
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Activation (wlan0) starting connection 'JAZZTEL_62C0'
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Activation (wlan0/wireless): access point 'JAZZTEL_62C0' has security, but secrets are required.
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Activation (wlan0/wireless): connection 'JAZZTEL_62C0' has security, and secrets exist.  No new secrets needed.
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Config: added 'ssid' value 'JAZZTEL_62C0'
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Config: added 'scan_ssid' value '1'
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Config: added 'psk' value '<omitted>'
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> Config: set interface ap_scan to 1
Sep 14 17:51:34 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Sep 14 17:51:36 XXX wpa_supplicant[1524]: Trying to authenticate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2412 MHz)
Sep 14 17:51:36 XXX wpa_supplicant[1524]: Trying to associate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2412 MHz)
Sep 14 17:51:36 XXX kernel: [72091.893363] wlan0: authenticate with 64:68:0c:80:62:c1 (try 1)
Sep 14 17:51:36 XXX kernel: [72091.895158] wlan0: authenticated
Sep 14 17:51:36 XXX kernel: [72091.895409] wlan0: associate with 64:68:0c:80:62:c1 (try 1)
Sep 14 17:51:36 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: scanning -> associating
Sep 14 17:51:36 XXX kernel: [72091.898715] wlan0: RX ReassocResp from 64:68:0c:80:62:c1 (capab=0x411 status=0 aid=6)
Sep 14 17:51:36 XXX kernel: [72091.898718] wlan0: associated
Sep 14 17:51:37 XXX wpa_supplicant[1524]: Associated with 64:68:0c:80:62:c1
Sep 14 17:51:37 XXX kernel: [72091.937045] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Sep 14 17:51:37 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: associating -> associated
Sep 14 17:51:37 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: activated -> disconnected (reason 'user-requested') [100 30 39]
Sep 14 17:51:37 XXX NetworkManager[1077]: <info> (ttyUSB0): deactivating device (reason 'user-requested') [39]
Sep 14 17:51:37 XXX NetworkManager[1077]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Sep 14 17:51:37 XXX NetworkManager[1077]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Sep 14 17:51:37 XXX NetworkManager[1077]: <info> DNS: starting dnsmasq...
Sep 14 17:51:37 XXX NetworkManager[1077]: <info> (ttyUSB0): writing resolv.conf to /sbin/resolvconf
Sep 14 17:51:37 XXX NetworkManager[1077]:    SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Sep 14 17:51:37 XXX modem-manager[4627]: <info>  Modem /org/freedesktop/ModemManager/Modems/1: state changed (connected -> disconnecting)
Sep 14 17:51:37 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
Sep 14 17:51:37 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: 4-way handshake -> group handshake
Sep 14 17:51:37 XXX wpa_supplicant[1524]: WPA: Key negotiation completed with 64:68:0c:80:62:c1 [PTK=CCMP GTK=CCMP]
Sep 14 17:51:37 XXX wpa_supplicant[1524]: CTRL-EVENT-CONNECTED - Connection to 64:68:0c:80:62:c1 completed (reauth) [id=0 id_str=]
Sep 14 17:51:37 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: group handshake -> completed
Sep 14 17:51:37 XXX NetworkManager[1077]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'JAZZTEL_62C0'.
Sep 14 17:51:37 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Sep 14 17:51:37 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Sep 14 17:51:37 XXX NetworkManager[1077]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
Sep 14 17:51:37 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Sep 14 17:51:37 XXX NetworkManager[1077]: <info> Activation (wlan0) Beginning IP6 addrconf.
Sep 14 17:51:37 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Sep 14 17:51:37 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started...
Sep 14 17:51:38 XXX modem-manager[4627]: <info>  Modem /org/freedesktop/ModemManager/Modems/1: state changed (disconnecting -> registered)
Sep 14 17:51:38 XXX NetworkManager[1077]: <info> DNS: starting dnsmasq...
Sep 14 17:51:38 XXX NetworkManager[1077]: <info> (wlan0): writing resolv.conf to /sbin/resolvconf
Sep 14 17:51:38 XXX NetworkManager[1077]: <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Sep 14 17:51:38 XXX NetworkManager[1077]: <info> Policy set 'JAZZTEL_62C0' (wlan0) as default for IPv4 routing and DNS.
Sep 14 17:51:38 XXX NetworkManager[1077]: <info> Activation (wlan0) successful, device activated.
Sep 14 17:51:38 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete.
Sep 14 17:51:40 XXX kernel: [72095.076198] iwl4965 0000:03:00.0: iwl4965_tx_agg_start on ra = 64:68:0c:80:62:c1 tid = 0
Sep 14 17:51:48 XXX kernel: [72102.944043] wlan0: no IPv6 routers present
Sep 14 17:51:58 XXX NetworkManager[1077]: <info> (wlan0): IP6 addrconf timed out or failed.
Sep 14 17:51:58 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Sep 14 17:51:58 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Sep 14 17:51:58 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Sep 14 20:28:54 XXX wpa_supplicant[1524]: CTRL-EVENT-DISCONNECTED bssid=64:68:0c:80:62:c1 reason=4
Sep 14 20:28:54 XXX kernel: [81529.679570] cfg80211: All devices are disconnected, going to restore regulatory settings
Sep 14 20:28:54 XXX kernel: [81529.679577] cfg80211: Restoring regulatory settings
Sep 14 20:28:54 XXX kernel: [81529.679581] cfg80211: Calling CRDA to update world regulatory domain
Sep 14 20:28:54 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: completed -> disconnected
Sep 14 20:28:54 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Sep 14 20:28:54 XXX kernel: [81529.809829] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
Sep 14 20:28:54 XXX kernel: [81529.809833] cfg80211: World regulatory domain updated:
Sep 14 20:28:54 XXX kernel: [81529.809835] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Sep 14 20:28:54 XXX kernel: [81529.809837] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 14 20:28:54 XXX kernel: [81529.809840] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 14 20:28:54 XXX kernel: [81529.809842] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 14 20:28:54 XXX kernel: [81529.809844] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 14 20:28:54 XXX kernel: [81529.809846] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 14 20:28:57 XXX wpa_supplicant[1524]: Trying to authenticate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2412 MHz)
Sep 14 20:28:57 XXX kernel: [81531.930665] wlan0: authenticate with 64:68:0c:80:62:c1 (try 1)
Sep 14 20:28:57 XXX wpa_supplicant[1524]: Trying to associate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2412 MHz)
Sep 14 20:28:57 XXX kernel: [81531.932708] wlan0: authenticated
Sep 14 20:28:57 XXX kernel: [81531.933066] wlan0: associate with 64:68:0c:80:62:c1 (try 1)
Sep 14 20:28:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: scanning -> associating
Sep 14 20:28:57 XXX kernel: [81531.936335] wlan0: RX ReassocResp from 64:68:0c:80:62:c1 (capab=0x411 status=0 aid=6)
Sep 14 20:28:57 XXX kernel: [81531.936338] wlan0: associated
Sep 14 20:28:57 XXX wpa_supplicant[1524]: Associated with 64:68:0c:80:62:c1
Sep 14 20:28:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: associating -> associated
Sep 14 20:28:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
Sep 14 20:28:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: 4-way handshake -> group handshake
Sep 14 20:28:57 XXX wpa_supplicant[1524]: WPA: Key negotiation completed with 64:68:0c:80:62:c1 [PTK=CCMP GTK=CCMP]
Sep 14 20:28:57 XXX wpa_supplicant[1524]: CTRL-EVENT-CONNECTED - Connection to 64:68:0c:80:62:c1 completed (reauth) [id=0 id_str=]
Sep 14 20:28:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: group handshake -> completed
Sep 14 20:32:05 XXX kernel: [81720.726521] iwl4965 0000:03:00.0: Aggregation not enabled for tid 0 because load = 0
Sep 14 20:32:10 XXX kernel: [81725.524024] iwl4965 0000:03:00.0: iwl4965_tx_agg_start on ra = 64:68:0c:80:62:c1 tid = 0
Sep 14 21:21:47 XXX kernel: [84702.432822] iwl4965 0000:03:00.0: Aggregation not enabled for tid 6 because load = 1
Sep 14 21:44:54 XXX wpa_supplicant[1524]: CTRL-EVENT-DISCONNECTED bssid=64:68:0c:80:62:c1 reason=4
Sep 14 21:44:54 XXX kernel: [86089.458316] cfg80211: All devices are disconnected, going to restore regulatory settings
Sep 14 21:44:54 XXX kernel: [86089.458328] cfg80211: Restoring regulatory settings
Sep 14 21:44:54 XXX kernel: [86089.458358] cfg80211: Calling CRDA to update world regulatory domain
Sep 14 21:44:54 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: completed -> disconnected
Sep 14 21:44:54 XXX kernel: [86089.468398] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
Sep 14 21:44:54 XXX kernel: [86089.468407] cfg80211: World regulatory domain updated:
Sep 14 21:44:54 XXX kernel: [86089.468412] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Sep 14 21:44:54 XXX kernel: [86089.468421] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 14 21:44:54 XXX kernel: [86089.468430] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 14 21:44:54 XXX kernel: [86089.468438] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 14 21:44:54 XXX kernel: [86089.468445] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 14 21:44:54 XXX kernel: [86089.468453] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 14 21:44:54 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Sep 14 21:44:56 XXX wpa_supplicant[1524]: Trying to authenticate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2412 MHz)
Sep 14 21:44:56 XXX kernel: [86091.753719] wlan0: authenticate with 64:68:0c:80:62:c1 (try 1)
Sep 14 21:44:56 XXX kernel: [86091.755525] wlan0: authenticated
Sep 14 21:44:56 XXX wpa_supplicant[1524]: Trying to associate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2412 MHz)
Sep 14 21:44:56 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Sep 14 21:44:56 XXX kernel: [86091.756278] wlan0: associate with 64:68:0c:80:62:c1 (try 1)
Sep 14 21:44:56 XXX kernel: [86091.764014] wlan0: RX ReassocResp from 64:68:0c:80:62:c1 (capab=0x411 status=0 aid=6)
Sep 14 21:44:56 XXX kernel: [86091.764017] wlan0: associated
Sep 14 21:44:56 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: authenticating -> associating
Sep 14 21:44:56 XXX wpa_supplicant[1524]: Associated with 64:68:0c:80:62:c1
Sep 14 21:44:56 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: associating -> associated
Sep 14 21:44:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
Sep 14 21:44:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: 4-way handshake -> group handshake
Sep 14 21:44:57 XXX wpa_supplicant[1524]: WPA: Key negotiation completed with 64:68:0c:80:62:c1 [PTK=CCMP GTK=CCMP]
Sep 14 21:44:57 XXX wpa_supplicant[1524]: CTRL-EVENT-CONNECTED - Connection to 64:68:0c:80:62:c1 completed (reauth) [id=0 id_str=]
Sep 14 21:44:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: group handshake -> completed
Sep 14 21:48:00 XXX kernel: [86275.837351] iwl4965 0000:03:00.0: Aggregation not enabled for tid 0 because load = 0
Sep 14 21:50:17 XXX kernel: [86412.648570] iwl4965 0000:03:00.0: Aggregation not enabled for tid 0 because load = 1
Sep 14 21:51:07 XXX kernel: [86462.857848] iwl4965 0000:03:00.0: Aggregation not enabled for tid 0 because load = 1
Sep 14 21:51:48 XXX kernel: [86503.610631] iwl4965 0000:03:00.0: iwl4965_tx_agg_start on ra = 64:68:0c:80:62:c1 tid = 0
Sep 14 22:12:07 XXX kernel: [87722.669726] warning: `proftpd' uses 32-bit capabilities (legacy support in use)
Sep 14 22:28:22 XXX kernel: [88696.978452] iwl4965 0000:03:00.0: Aggregation not enabled for tid 6 because load = 2
Sep 14 22:41:16 XXX kernel: [89471.460347] iwl4965 0000:03:00.0: Aggregation not enabled for tid 6 because load = 2
Sep 14 23:28:54 XXX wpa_supplicant[1524]: CTRL-EVENT-DISCONNECTED bssid=64:68:0c:80:62:c1 reason=4
Sep 14 23:28:55 XXX kernel: [92329.933162] cfg80211: All devices are disconnected, going to restore regulatory settings
Sep 14 23:28:55 XXX kernel: [92329.933167] cfg80211: Restoring regulatory settings
Sep 14 23:28:55 XXX kernel: [92329.933172] cfg80211: Calling CRDA to update world regulatory domain
Sep 14 23:28:55 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: completed -> disconnected
Sep 14 23:28:55 XXX kernel: [92329.989436] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
Sep 14 23:28:55 XXX kernel: [92329.989439] cfg80211: World regulatory domain updated:
Sep 14 23:28:55 XXX kernel: [92329.989441] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Sep 14 23:28:55 XXX kernel: [92329.989444] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 14 23:28:55 XXX kernel: [92329.989447] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 14 23:28:55 XXX kernel: [92329.989449] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 14 23:28:55 XXX kernel: [92329.989452] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 14 23:28:55 XXX kernel: [92329.989454] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 14 23:28:55 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Sep 14 23:28:57 XXX wpa_supplicant[1524]: Trying to authenticate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2412 MHz)
Sep 14 23:28:57 XXX kernel: [92332.275905] wlan0: authenticate with 64:68:0c:80:62:c1 (try 1)
Sep 14 23:28:57 XXX wpa_supplicant[1524]: Trying to associate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2412 MHz)
Sep 14 23:28:57 XXX kernel: [92332.277717] wlan0: authenticated
Sep 14 23:28:57 XXX kernel: [92332.277965] wlan0: associate with 64:68:0c:80:62:c1 (try 1)
Sep 14 23:28:57 XXX kernel: [92332.281712] wlan0: RX ReassocResp from 64:68:0c:80:62:c1 (capab=0x411 status=0 aid=6)
Sep 14 23:28:57 XXX kernel: [92332.281722] wlan0: associated
Sep 14 23:28:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: scanning -> associating
Sep 14 23:28:57 XXX wpa_supplicant[1524]: Associated with 64:68:0c:80:62:c1
Sep 14 23:28:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: associating -> associated
Sep 14 23:28:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
Sep 14 23:28:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: 4-way handshake -> group handshake
Sep 14 23:28:57 XXX wpa_supplicant[1524]: WPA: Key negotiation completed with 64:68:0c:80:62:c1 [PTK=CCMP GTK=CCMP]
Sep 14 23:28:57 XXX wpa_supplicant[1524]: CTRL-EVENT-CONNECTED - Connection to 64:68:0c:80:62:c1 completed (reauth) [id=0 id_str=]
Sep 14 23:28:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: group handshake -> completed
Sep 14 23:28:59 XXX kernel: [92334.588297] iwl4965 0000:03:00.0: iwl4965_tx_agg_start on ra = 64:68:0c:80:62:c1 tid = 0
Sep 15 00:08:54 XXX wpa_supplicant[1524]: CTRL-EVENT-DISCONNECTED bssid=64:68:0c:80:62:c1 reason=4
Sep 15 00:08:54 XXX kernel: [94729.898580] cfg80211: All devices are disconnected, going to restore regulatory settings
Sep 15 00:08:54 XXX kernel: [94729.898586] cfg80211: Restoring regulatory settings
Sep 15 00:08:54 XXX kernel: [94729.898590] cfg80211: Calling CRDA to update world regulatory domain
Sep 15 00:08:54 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: completed -> disconnected
Sep 15 00:08:54 XXX kernel: [94729.904742] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
Sep 15 00:08:54 XXX kernel: [94729.904745] cfg80211: World regulatory domain updated:
Sep 15 00:08:54 XXX kernel: [94729.904747] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Sep 15 00:08:54 XXX kernel: [94729.904750] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 15 00:08:54 XXX kernel: [94729.904752] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 15 00:08:54 XXX kernel: [94729.904755] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 15 00:08:54 XXX kernel: [94729.904757] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 15 00:08:54 XXX kernel: [94729.904760] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 15 00:08:55 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Sep 15 00:08:57 XXX wpa_supplicant[1524]: Trying to authenticate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2412 MHz)
Sep 15 00:08:57 XXX kernel: [94732.230943] wlan0: authenticate with 64:68:0c:80:62:c1 (try 1)
Sep 15 00:08:57 XXX wpa_supplicant[1524]: Trying to associate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2412 MHz)
Sep 15 00:08:57 XXX kernel: [94732.233313] wlan0: authenticated
Sep 15 00:08:57 XXX kernel: [94732.234174] wlan0: associate with 64:68:0c:80:62:c1 (try 1)
Sep 15 00:08:57 XXX kernel: [94732.238193] wlan0: RX ReassocResp from 64:68:0c:80:62:c1 (capab=0x411 status=0 aid=6)
Sep 15 00:08:57 XXX kernel: [94732.238196] wlan0: associated
Sep 15 00:08:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Sep 15 00:08:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: authenticating -> associating
Sep 15 00:08:57 XXX wpa_supplicant[1524]: Associated with 64:68:0c:80:62:c1
Sep 15 00:08:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: associating -> associated
Sep 15 00:08:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
Sep 15 00:08:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: 4-way handshake -> group handshake
Sep 15 00:08:57 XXX wpa_supplicant[1524]: WPA: Key negotiation completed with 64:68:0c:80:62:c1 [PTK=CCMP GTK=CCMP]
Sep 15 00:08:57 XXX wpa_supplicant[1524]: CTRL-EVENT-CONNECTED - Connection to 64:68:0c:80:62:c1 completed (reauth) [id=0 id_str=]
Sep 15 00:08:57 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: group handshake -> completed
Sep 15 00:10:13 XXX kernel: [94808.792604] iwl4965 0000:03:00.0: Aggregation not enabled for tid 0 because load = 1
Sep 15 00:11:19 XXX kernel: [94873.948468] iwl4965 0000:03:00.0: iwl4965_tx_agg_start on ra = 64:68:0c:80:62:c1 tid = 0
Sep 15 00:54:17 XXX kernel: [97452.773723] iwl4965 0000:03:00.0: Aggregation not enabled for tid 6 because load = 1
Sep 15 01:45:52 XXX kernel: [100547.795472] iwl4965 0000:03:00.0: Aggregation not enabled for tid 6 because load = 0
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> sleep requested (sleeping: no  enabled: yes)
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> sleeping or disabling...
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> (eth0): now unmanaged
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> (eth0): cleaning up...
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> (eth0): taking down device.
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> (wlan0): now unmanaged
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> (wlan0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> (wlan0): deactivating device (reason 'sleeping') [37]
Sep 15 01:58:01 XXX kernel: [101276.234574] wlan0: deauthenticating from 64:68:0c:80:62:c1 by local choice (reason=3)
Sep 15 01:58:01 XXX kernel: [101276.276213] cfg80211: All devices are disconnected, going to restore regulatory settings
Sep 15 01:58:01 XXX kernel: [101276.276219] cfg80211: Restoring regulatory settings
Sep 15 01:58:01 XXX kernel: [101276.276225] cfg80211: Calling CRDA to update world regulatory domain
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> DNS: starting dnsmasq...
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> (wlan0): writing resolv.conf to /sbin/resolvconf
Sep 15 01:58:01 XXX wpa_supplicant[1524]: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> (wlan0): cleaning up...
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> (wlan0): taking down device.
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> (ttyUSB0): now unmanaged
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: disconnected -> unmanaged (reason 'sleeping') [30 10 37]
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> (ttyUSB0): cleaning up...
Sep 15 01:58:01 XXX NetworkManager[1077]: <info> (ttyUSB0): taking down device.
Sep 15 01:58:01 XXX kernel: [101276.723309] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
Sep 15 01:58:01 XXX kernel: [101276.723313] cfg80211: World regulatory domain updated:
Sep 15 01:58:01 XXX kernel: [101276.723314] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Sep 15 01:58:01 XXX kernel: [101276.723317] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 15 01:58:01 XXX kernel: [101276.723319] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 15 01:58:01 XXX kernel: [101276.723322] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 15 01:58:01 XXX kernel: [101276.723324] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 15 01:58:01 XXX kernel: [101276.723326] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 15 01:58:04 XXX kernel: [101279.701781] init: anacron main process (7246) killed by TERM signal
Sep 15 10:56:57 XXX kernel: [101283.004331] PM: Syncing filesystems ... done.
Sep 15 10:56:57 XXX kernel: [101283.022495] PM: Preparing system for mem sleep
Sep 15 10:56:57 XXX kernel: [101283.022511] Freezing user space processes ... (elapsed 0.01 seconds) done.
Sep 15 10:56:57 XXX kernel: [101283.036088] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Sep 15 10:56:57 XXX kernel: [101283.052083] PM: Entering mem sleep
Sep 15 10:56:57 XXX kernel: [101283.052108] Suspending console(s) (use no_console_suspend to debug)
Sep 15 10:56:57 XXX kernel: [101283.052725] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Sep 15 10:56:57 XXX kernel: [101283.052918] sd 0:0:0:0: [sda] Stopping disk
Sep 15 10:56:57 XXX kernel: [101283.053092] option: option_instat_callback: error -2
Sep 15 10:56:57 XXX kernel: [101283.053588] option: option_instat_callback: error -2
Sep 15 10:56:57 XXX kernel: [101283.105997] sdhci-pci 0000:09:01.1: PCI INT B disabled
Sep 15 10:56:57 XXX kernel: [101283.112238] ehci_hcd 0000:00:1d.7: PCI INT A disabled
Sep 15 10:56:57 XXX kernel: [101283.112250] uhci_hcd 0000:00:1d.2: PCI INT C disabled
Sep 15 10:56:57 XXX kernel: [101283.112278] uhci_hcd 0000:00:1d.0: PCI INT A disabled
Sep 15 10:56:57 XXX kernel: [101283.112293] pciehp 0000:00:1c.5:pcie04: pciehp_suspend ENTRY
Sep 15 10:56:57 XXX kernel: [101283.112307] pciehp 0000:00:1c.4:pcie04: pciehp_suspend ENTRY
Sep 15 10:56:57 XXX kernel: [101283.112314] ata_piix 0000:00:1f.1: PCI INT A disabled
Sep 15 10:56:57 XXX kernel: [101283.112380] uhci_hcd 0000:00:1a.1: PCI INT B disabled
Sep 15 10:56:57 XXX kernel: [101283.135197] pciehp 0000:00:01.0:pcie04: pciehp_suspend ENTRY
Sep 15 10:56:57 XXX kernel: [101283.140084] uhci_hcd 0000:00:1a.0: PCI INT A disabled
Sep 15 10:56:57 XXX kernel: [101283.140107] uhci_hcd 0000:00:1d.1: PCI INT B disabled
Sep 15 10:56:57 XXX kernel: [101283.168047] ehci_hcd 0000:00:1a.7: PCI INT C disabled
Sep 15 10:56:57 XXX kernel: [101283.400697] PM: suspend of drv:sd dev:0:0:0:0 complete after 347.974 msecs
Sep 15 10:56:57 XXX kernel: [101283.400722] PM: suspend of drv:scsi dev:target0:0:0 complete after 347.956 msecs
Sep 15 10:56:57 XXX kernel: [101283.400746] PM: suspend of drv:scsi dev:host0 complete after 297.199 msecs
Sep 15 10:56:57 XXX kernel: [101283.416118] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 303.955 msecs
Sep 15 10:56:57 XXX kernel: [101283.420184] snd_hda_intel 0000:00:1b.0: PCI INT A disabled
Sep 15 10:56:57 XXX kernel: [101283.436120] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 323.785 msecs
Sep 15 10:56:57 XXX kernel: [101283.436157] PM: suspend of drv: dev:pci0000:00 complete after 323.720 msecs
Sep 15 10:56:57 XXX kernel: [101283.436191] PM: suspend of devices complete after 383.837 msecs
Sep 15 10:56:57 XXX kernel: [101283.436195] PM: suspend devices took 0.384 seconds
Sep 15 10:56:57 XXX kernel: [101283.500267] ehci_hcd 0000:00:1d.7: PME# enabled
Sep 15 10:56:57 XXX kernel: [101283.500329] ehci_hcd 0000:00:1d.7: wake-up capability enabled by ACPI
Sep 15 10:56:57 XXX kernel: [101283.516188] uhci_hcd 0000:00:1d.2: wake-up capability enabled by ACPI
Sep 15 10:56:57 XXX kernel: [101283.516254] uhci_hcd 0000:00:1d.1: wake-up capability enabled by ACPI
Sep 15 10:56:57 XXX kernel: [101283.516299] uhci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI
Sep 15 10:56:57 XXX kernel: [101283.516504] ehci_hcd 0000:00:1a.7: PME# enabled
Sep 15 10:56:57 XXX kernel: [101283.516541] ehci_hcd 0000:00:1a.7: wake-up capability enabled by ACPI
Sep 15 10:56:57 XXX kernel: [101283.532184] uhci_hcd 0000:00:1a.1: wake-up capability enabled by ACPI
Sep 15 10:56:57 XXX kernel: [101283.532249] uhci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI
Sep 15 10:56:57 XXX kernel: [101283.532364] PM: late suspend of devices complete after 96.164 msecs
Sep 15 10:56:57 XXX kernel: [101283.532966] ACPI: Preparing to enter system sleep state S3
Sep 15 10:56:57 XXX kernel: [101283.558730] PM: Saving platform NVS memory
Sep 15 10:56:57 XXX kernel: [101283.559016] Disabling non-boot CPUs ...
Sep 15 10:56:57 XXX kernel: [101283.560406] CPU 1 is now offline
Sep 15 10:56:57 XXX kernel: [101283.560919] Extended CMOS year: 2000
Sep 15 10:56:57 XXX kernel: [101283.560919] ACPI: Low-level resume complete
Sep 15 10:56:57 XXX kernel: [101283.560919] PM: Restoring platform NVS memory
Sep 15 10:56:57 XXX kernel: [101283.560919] CPU0: Thermal monitoring handled by SMI
Sep 15 10:56:57 XXX kernel: [101283.560919] Extended CMOS year: 2000
Sep 15 10:56:57 XXX kernel: [101283.560919] Enabling non-boot CPUs ...
Sep 15 10:56:57 XXX kernel: [101283.560919] Booting Node 0 Processor 1 APIC 0x1
Sep 15 10:56:57 XXX kernel: [101283.560919] smpboot cpu 1: start_ip = 9b000
Sep 15 10:56:57 XXX kernel: [101283.560398] Initializing CPU#1
Sep 15 10:56:57 XXX kernel: [101283.560398] Calibrating delay loop (skipped) already calibrated this CPU
Sep 15 10:56:57 XXX kernel: [101283.560398] CPU1: Thermal monitoring handled by SMI
Sep 15 10:56:57 XXX kernel: [101283.572503] NMI watchdog enabled, takes one hw-pmu counter.
Sep 15 10:56:57 XXX kernel: [101283.576223] CPU1 is up
Sep 15 10:56:57 XXX kernel: [101283.580113] ACPI: Waking up from system sleep state S3
Sep 15 10:56:57 XXX kernel: [101283.757225] pcieport 0000:00:01.0: restoring config space at offset 0xa (was 0xf, writing 0x0)
Sep 15 10:56:57 XXX kernel: [101283.757230] pcieport 0000:00:01.0: restoring config space at offset 0x7 (was 0xa0a0, writing 0x2000a0a0)
Sep 15 10:56:57 XXX kernel: [101283.757236] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507)
Sep 15 10:56:57 XXX kernel: [101283.757269] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Sep 15 10:56:57 XXX kernel: [101283.757294] uhci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI
Sep 15 10:56:57 XXX kernel: [101283.757314] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Sep 15 10:56:57 XXX kernel: [101283.757336] uhci_hcd 0000:00:1a.1: wake-up capability disabled by ACPI
Sep 15 10:56:57 XXX kernel: [101283.757364] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102)
Sep 15 10:56:57 XXX kernel: [101283.757411] ehci_hcd 0000:00:1a.7: wake-up capability disabled by ACPI
Sep 15 10:56:57 XXX kernel: [101283.757416] ehci_hcd 0000:00:1a.7: PME# disabled
Sep 15 10:56:57 XXX kernel: [101283.757442] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
Sep 15 10:56:57 XXX kernel: [101283.757447] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102)
Sep 15 10:56:57 XXX kernel: [101283.757468] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x20100, writing 0x2010a)
Sep 15 10:56:57 XXX kernel: [101283.757482] pcieport 0000:00:1c.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Sep 15 10:56:57 XXX kernel: [101283.757487] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506)
Sep 15 10:56:57 XXX kernel: [101283.757521] pcieport 0000:00:1c.1: restoring config space at offset 0xf (was 0x20200, writing 0x20205)
Sep 15 10:56:57 XXX kernel: [101283.757534] pcieport 0000:00:1c.1: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Sep 15 10:56:57 XXX kernel: [101283.757539] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100106, writing 0x100506)
Sep 15 10:56:57 XXX kernel: [101283.757573] pcieport 0000:00:1c.2: restoring config space at offset 0xf (was 0x20300, writing 0x20306)
Sep 15 10:56:57 XXX kernel: [101283.757586] pcieport 0000:00:1c.2: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Sep 15 10:56:57 XXX kernel: [101283.757591] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100104, writing 0x100504)
Sep 15 10:56:57 XXX kernel: [101283.757624] pcieport 0000:00:1c.3: restoring config space at offset 0xf (was 0x20400, writing 0x20403)
Sep 15 10:56:57 XXX kernel: [101283.757638] pcieport 0000:00:1c.3: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Sep 15 10:56:57 XXX kernel: [101283.757643] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507)
Sep 15 10:56:57 XXX kernel: [101283.757676] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x20100, writing 0x2010a)
Sep 15 10:56:57 XXX kernel: [101283.757690] pcieport 0000:00:1c.4: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Sep 15 10:56:57 XXX kernel: [101283.757695] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507)
Sep 15 10:56:57 XXX kernel: [101283.757728] pcieport 0000:00:1c.5: restoring config space at offset 0xf (was 0x20200, writing 0x20205)
Sep 15 10:56:57 XXX kernel: [101283.757736] pcieport 0000:00:1c.5: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd031d021)
Sep 15 10:56:57 XXX kernel: [101283.757741] pcieport 0000:00:1c.5: restoring config space at offset 0x8 (was 0xfff0, writing 0xd010d000)
Sep 15 10:56:57 XXX kernel: [101283.757745] pcieport 0000:00:1c.5: restoring config space at offset 0x7 (was 0xf0, writing 0x1010)
Sep 15 10:56:57 XXX kernel: [101283.757751] pcieport 0000:00:1c.5: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Sep 15 10:56:57 XXX kernel: [101283.757756] pcieport 0000:00:1c.5: restoring config space at offset 0x1 (was 0x100104, writing 0x100507)
Sep 15 10:56:57 XXX kernel: [101283.757796] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Sep 15 10:56:57 XXX kernel: [101283.757819] uhci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI
Sep 15 10:56:57 XXX kernel: [101283.757839] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Sep 15 10:56:57 XXX kernel: [101283.757861] uhci_hcd 0000:00:1d.1: wake-up capability disabled by ACPI
Sep 15 10:56:57 XXX kernel: [101283.757881] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Sep 15 10:56:57 XXX kernel: [101283.757903] uhci_hcd 0000:00:1d.2: wake-up capability disabled by ACPI
Sep 15 10:56:57 XXX kernel: [101283.757931] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102)
Sep 15 10:56:57 XXX kernel: [101283.757975] ehci_hcd 0000:00:1d.7: wake-up capability disabled by ACPI
Sep 15 10:56:57 XXX kernel: [101283.757980] ehci_hcd 0000:00:1d.7: PME# disabled
Sep 15 10:56:57 XXX kernel: [101283.757988] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x20000, writing 0x200ff)
Sep 15 10:56:57 XXX kernel: [101283.758059] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005)
Sep 15 10:56:57 XXX kernel: [101283.758087] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407)
Sep 15 10:56:57 XXX kernel: [101283.758120] nvidia 0000:01:00.0: restoring config space at offset 0xc (was 0xfdee0000, writing 0x0)
Sep 15 10:56:57 XXX kernel: [101283.758125] nvidia 0000:01:00.0: restoring config space at offset 0x9 (was 0x1, writing 0xac01)
Sep 15 10:56:57 XXX kernel: [101283.758130] nvidia 0000:01:00.0: restoring config space at offset 0x7 (was 0x4, writing 0xfa000004)
Sep 15 10:56:57 XXX kernel: [101283.758134] nvidia 0000:01:00.0: restoring config space at offset 0x5 (was 0xc, writing 0xe000000c)
Sep 15 10:56:57 XXX kernel: [101283.758138] nvidia 0000:01:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xfc000000)
Sep 15 10:56:57 XXX kernel: [101283.758142] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x8, writing 0x0)
Sep 15 10:56:57 XXX kernel: [101283.758147] nvidia 0000:01:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100006)
Sep 15 10:56:57 XXX kernel: [101283.758184] atl1 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
Sep 15 10:56:57 XXX kernel: [101283.758192] atl1 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfdfa0000)
Sep 15 10:56:57 XXX kernel: [101283.758208] atl1 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
Sep 15 10:56:57 XXX kernel: [101283.758215] atl1 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100106)
Sep 15 10:56:57 XXX kernel: [101283.758263] iwl4965 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x105)
Sep 15 10:56:57 XXX kernel: [101283.758292] iwl4965 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe0fe004)
Sep 15 10:56:57 XXX kernel: [101283.758298] iwl4965 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
Sep 15 10:56:57 XXX kernel: [101283.758307] iwl4965 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100506)
Sep 15 10:56:57 XXX kernel: [101283.758375] pci 0000:05:00.0: restoring config space at offset 0xf (was 0x100, writing 0x103)
Sep 15 10:56:57 XXX kernel: [101283.758383] pci 0000:05:00.0: restoring config space at offset 0xc (was 0x1, writing 0xfe1e0000)
Sep 15 10:56:57 XXX kernel: [101283.758395] pci 0000:05:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xbc01)
Sep 15 10:56:57 XXX kernel: [101283.758402] pci 0000:05:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xfe1ffc00)
Sep 15 10:56:57 XXX kernel: [101283.758407] pci 0000:05:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
Sep 15 10:56:57 XXX kernel: [101283.758413] pci 0000:05:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107)
Sep 15 10:56:57 XXX kernel: [101283.758441] firewire_ohci 0000:09:01.0: restoring config space at offset 0xf (was 0x4020100, writing 0x402010a)
Sep 15 10:56:57 XXX kernel: [101283.758458] firewire_ohci 0000:09:01.0: restoring config space at offset 0x3 (was 0x800000, writing 0x802008)
Sep 15 10:56:57 XXX kernel: [101283.758464] firewire_ohci 0000:09:01.0: restoring config space at offset 0x1 (was 0x2100006, writing 0x2100106)
Sep 15 10:56:57 XXX kernel: [101283.758479] firewire_ohci 0000:09:01.0: proprietary Ricoh MMC controller disabled (via firewire function)
Sep 15 10:56:57 XXX kernel: [101283.758480] firewire_ohci 0000:09:01.0: MMC cards are now supported by standard SDHCI controller
Sep 15 10:56:57 XXX kernel: [101283.758491] sdhci-pci 0000:09:01.1: restoring config space at offset 0xf (was 0x200, writing 0x205)
Sep 15 10:56:57 XXX kernel: [101283.758506] sdhci-pci 0000:09:01.1: restoring config space at offset 0x4 (was 0x0, writing 0xfeaff400)
Sep 15 10:56:57 XXX kernel: [101283.758511] sdhci-pci 0000:09:01.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804008)
Sep 15 10:56:57 XXX kernel: [101283.758516] sdhci-pci 0000:09:01.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106)
Sep 15 10:56:57 XXX kernel: [101283.758534] r592 0000:09:01.2: restoring config space at offset 0xf (was 0x200, writing 0x205)
Sep 15 10:56:57 XXX kernel: [101283.758549] r592 0000:09:01.2: restoring config space at offset 0x4 (was 0x0, writing 0xfeafec00)
Sep 15 10:56:57 XXX kernel: [101283.758553] r592 0000:09:01.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804008)
Sep 15 10:56:57 XXX kernel: [101283.758559] r592 0000:09:01.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106)
Sep 15 10:56:57 XXX kernel: [101283.758576] r852 0000:09:01.3: restoring config space at offset 0xf (was 0x200, writing 0x205)
Sep 15 10:56:57 XXX kernel: [101283.758591] r852 0000:09:01.3: restoring config space at offset 0x4 (was 0x0, writing 0xfeafe800)
Sep 15 10:56:57 XXX kernel: [101283.758596] r852 0000:09:01.3: restoring config space at offset 0x3 (was 0x800000, writing 0x804008)
Sep 15 10:56:57 XXX kernel: [101283.758601] r852 0000:09:01.3: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106)
Sep 15 10:56:57 XXX kernel: [101283.759021] PM: early resume of devices complete after 1.907 msecs
Sep 15 10:56:57 XXX kernel: [101283.759120] pciehp 0000:00:01.0:pcie04: pciehp_resume ENTRY
Sep 15 10:56:57 XXX kernel: [101283.759131] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Sep 15 10:56:57 XXX kernel: [101283.759137] uhci_hcd 0000:00:1a.0: setting latency timer to 64
Sep 15 10:56:57 XXX kernel: [101283.759158] usb usb3: root hub lost power or was reset
Sep 15 10:56:57 XXX kernel: [101283.759170] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
Sep 15 10:56:57 XXX kernel: [101283.759175] uhci_hcd 0000:00:1a.1: setting latency timer to 64
Sep 15 10:56:57 XXX kernel: [101283.759195] usb usb4: root hub lost power or was reset
Sep 15 10:56:57 XXX kernel: [101283.759209] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18
Sep 15 10:56:57 XXX kernel: [101283.759217] ehci_hcd 0000:00:1a.7: setting latency timer to 64
Sep 15 10:56:57 XXX kernel: [101283.759248] snd_hda_intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
Sep 15 10:56:57 XXX kernel: [101283.759255] snd_hda_intel 0000:00:1b.0: setting latency timer to 64
Sep 15 10:56:57 XXX kernel: [101283.759303] snd_hda_intel 0000:00:1b.0: irq 48 for MSI/MSI-X
Sep 15 10:56:57 XXX kernel: [101283.759369] pciehp 0000:00:1c.4:pcie04: pciehp_resume ENTRY
Sep 15 10:56:57 XXX kernel: [101283.759378] pciehp 0000:00:1c.5:pcie04: pciehp_resume ENTRY
Sep 15 10:56:57 XXX kernel: [101283.759384] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23
Sep 15 10:56:57 XXX kernel: [101283.759390] uhci_hcd 0000:00:1d.0: setting latency timer to 64
Sep 15 10:56:57 XXX kernel: [101283.759412] usb usb5: root hub lost power or was reset
Sep 15 10:56:57 XXX kernel: [101283.759423] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19
Sep 15 10:56:57 XXX kernel: [101283.759428] uhci_hcd 0000:00:1d.1: setting latency timer to 64
Sep 15 10:56:57 XXX kernel: [101283.759434] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
Sep 15 10:56:57 XXX kernel: [101283.759442] uhci_hcd 0000:00:1d.2: setting latency timer to 64
Sep 15 10:56:57 XXX kernel: [101283.759453] usb usb6: root hub lost power or was reset
Sep 15 10:56:57 XXX kernel: [101283.759468] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23
Sep 15 10:56:57 XXX kernel: [101283.759466] usb usb7: root hub lost power or was reset
Sep 15 10:56:57 XXX kernel: [101283.759474] ehci_hcd 0000:00:1d.7: setting latency timer to 64
Sep 15 10:56:57 XXX kernel: [101283.759496] pci 0000:00:1e.0: setting latency timer to 64
Sep 15 10:56:57 XXX kernel: [101283.759508] ata_piix 0000:00:1f.1: PCI INT A -> GSI 18 (level, low) -> IRQ 18
Sep 15 10:56:57 XXX kernel: [101283.759513] ata_piix 0000:00:1f.1: setting latency timer to 64
Sep 15 10:56:57 XXX kernel: [101283.759536] ahci 0000:00:1f.2: setting latency timer to 64
Sep 15 10:56:57 XXX kernel: [101283.759626] ata5: port disabled--ignoring
Sep 15 10:56:57 XXX kernel: [101283.761260] sdhci-pci 0000:09:01.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Sep 15 10:56:57 XXX kernel: [101283.761308] sdhci-pci 0000:09:01.1: Will use DMA mode even though HW doesn't fully claim to support it.
Sep 15 10:56:57 XXX kernel: [101283.762203] sd 0:0:0:0: [sda] Starting disk
Sep 15 10:56:57 XXX kernel: [101283.820097] firewire_core: skipped bus generations, destroying all nodes
Sep 15 10:56:57 XXX kernel: [101283.880137] PM: resume of drv:hub dev:3-0:1.0 complete after 118.444 msecs
Sep 15 10:56:57 XXX kernel: [101283.880145] PM: resume of drv: dev:ep_00 complete after 118.446 msecs
Sep 15 10:56:57 XXX kernel: [101283.880151] PM: resume of drv:hub dev:6-0:1.0 complete after 118.292 msecs
Sep 15 10:56:57 XXX kernel: [101283.880157] PM: resume of drv: dev:ep_00 complete after 118.266 msecs
Sep 15 10:56:57 XXX kernel: [101283.880163] PM: resume of drv: dev:ep_81 complete after 118.467 msecs
Sep 15 10:56:57 XXX kernel: [101283.880167] PM: resume of drv: dev:ep_81 complete after 118.291 msecs
Sep 15 10:56:57 XXX kernel: [101283.920369] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 160.839 msecs
Sep 15 10:56:57 XXX kernel: [101283.984126] PM: resume of drv:hub dev:7-0:1.0 complete after 222.149 msecs
Sep 15 10:56:57 XXX kernel: [101283.984140] PM: resume of drv:hub dev:5-0:1.0 complete after 222.344 msecs
Sep 15 10:56:57 XXX kernel: [101283.984146] PM: resume of drv: dev:ep_00 complete after 222.006 msecs
Sep 15 10:56:57 XXX kernel: [101283.984154] PM: resume of drv: dev:ep_00 complete after 222.329 msecs
Sep 15 10:56:57 XXX kernel: [101283.984159] PM: resume of drv: dev:ep_81 complete after 222.071 msecs
Sep 15 10:56:57 XXX kernel: [101283.984166] PM: resume of drv: dev:ep_81 complete after 222.357 msecs
Sep 15 10:56:57 XXX kernel: [101283.984203] PM: resume of drv: dev:ep_00 complete after 222.444 msecs
Sep 15 10:56:57 XXX kernel: [101283.984209] PM: resume of drv:hub dev:4-0:1.0 complete after 222.477 msecs
Sep 15 10:56:57 XXX kernel: [101283.984226] PM: resume of drv: dev:ep_81 complete after 222.481 msecs
Sep 15 10:56:57 XXX kernel: [101284.012118] PM: resume of drv:hub dev:1-0:1.0 complete after 250.515 msecs
Sep 15 10:56:57 XXX kernel: [101284.012130] PM: resume of drv: dev:ep_00 complete after 250.480 msecs
Sep 15 10:56:57 XXX kernel: [101284.012156] PM: resume of drv: dev:ep_81 complete after 250.550 msecs
Sep 15 10:56:57 XXX kernel: [101284.016131] PM: resume of drv:hub dev:2-0:1.0 complete after 254.469 msecs
Sep 15 10:56:57 XXX kernel: [101284.016142] PM: resume of drv: dev:ep_00 complete after 254.468 msecs
Sep 15 10:56:57 XXX kernel: [101284.016148] PM: resume of drv: dev:ep_81 complete after 254.475 msecs
Sep 15 10:56:57 XXX kernel: [101284.036410] ata4.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out
Sep 15 10:56:57 XXX kernel: [101284.036413] ata4.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out
Sep 15 10:56:57 XXX kernel: [101284.052288] ata4.00: configured for UDMA/33
Sep 15 10:56:57 XXX kernel: [101284.065710] PM: resume of drv:option dev:2-4:1.1 complete after 301.897 msecs
Sep 15 10:56:57 XXX kernel: [101284.065717] PM: resume of drv:option dev:2-4:1.0 complete after 302.085 msecs
Sep 15 10:56:57 XXX kernel: [101284.065722] PM: resume of drv:option dev:2-4:1.2 complete after 301.762 msecs
Sep 15 10:56:57 XXX kernel: [101284.065729] PM: resume of drv:option1 dev:ttyUSB0 complete after 302.058 msecs
Sep 15 10:56:57 XXX kernel: [101284.065732] PM: resume of drv:option dev:2-4:1.3 complete after 301.591 msecs
Sep 15 10:56:57 XXX kernel: [101284.065734] PM: resume of drv: dev:ep_81 complete after 302.022 msecs
Sep 15 10:56:57 XXX kernel: [101284.065739] PM: resume of drv: dev:ep_82 complete after 302.005 msecs
Sep 15 10:56:57 XXX kernel: [101284.065742] PM: resume of drv:usb-storage dev:2-4:1.4 complete after 301.454 msecs
Sep 15 10:56:57 XXX kernel: [101284.065745] PM: resume of drv: dev:ep_01 complete after 301.971 msecs
Sep 15 10:56:57 XXX kernel: [101284.065758] PM: resume of drv:usb-storage dev:2-4:1.5 complete after 301.259 msecs
Sep 15 10:56:57 XXX kernel: [101284.065761] PM: resume of drv: dev:ep_05 complete after 301.302 msecs
Sep 15 10:56:57 XXX kernel: [101284.065766] PM: resume of drv: dev:ep_87 complete after 301.349 msecs
Sep 15 10:56:57 XXX kernel: [101284.065768] PM: resume of drv: dev:ep_00 complete after 301.105 msecs
Sep 15 10:56:57 XXX kernel: [101284.065772] PM: resume of drv:scsi dev:host31 complete after 301.445 msecs
Sep 15 10:56:57 XXX kernel: [101284.065775] PM: resume of drv:option1 dev:ttyUSB1 complete after 301.909 msecs
Sep 15 10:56:57 XXX kernel: [101284.065780] PM: resume of drv: dev:ep_83 complete after 301.901 msecs
Sep 15 10:56:57 XXX kernel: [101284.065783] PM: resume of drv: dev:ep_04 complete after 301.533 msecs
Sep 15 10:56:57 XXX kernel: [101284.065786] PM: resume of drv: dev:ep_84 complete after 301.856 msecs
Sep 15 10:56:57 XXX kernel: [101284.065788] PM: resume of drv: dev:ep_86 complete after 301.555 msecs
Sep 15 10:56:57 XXX kernel: [101284.065790] PM: resume of drv: dev:ep_02 complete after 301.846 msecs
Sep 15 10:56:57 XXX kernel: [101284.065792] PM: resume of drv:option1 dev:ttyUSB3 complete after 301.612 msecs
Sep 15 10:56:57 XXX kernel: [101284.065795] PM: resume of drv:option1 dev:ttyUSB2 complete after 301.822 msecs
Sep 15 10:56:57 XXX kernel: [101284.065798] PM: resume of drv: dev:ep_03 complete after 301.698 msecs
Sep 15 10:56:57 XXX kernel: [101284.065801] PM: resume of drv:scsi dev:host32 complete after 301.279 msecs
Sep 15 10:56:57 XXX kernel: [101284.065803] PM: resume of drv: dev:ep_85 complete after 301.719 msecs
Sep 15 10:56:57 XXX kernel: [101284.065808] PM: resume of drv:scsi_host dev:host31 complete after 301.431 msecs
Sep 15 10:56:57 XXX kernel: [101284.065811] PM: resume of drv: dev:ep_06 complete after 301.211 msecs
Sep 15 10:56:57 XXX kernel: [101284.065814] PM: resume of drv:scsi dev:target31:0:0 complete after 301.113 msecs
Sep 15 10:56:57 XXX kernel: [101284.065817] PM: resume of drv: dev:ep_88 complete after 301.176 msecs
Sep 15 10:56:57 XXX kernel: [101284.065822] PM: resume of drv:scsi_host dev:host32 complete after 301.261 msecs
Sep 15 10:56:57 XXX kernel: [101284.065824] PM: resume of drv:sr dev:31:0:0:0 complete after 301.084 msecs
Sep 15 10:56:57 XXX kernel: [101284.065828] PM: resume of drv:scsi dev:target32:0:0 complete after 301.018 msecs
Sep 15 10:56:57 XXX kernel: [101284.065836] PM: resume of drv:scsi_device dev:31:0:0:0 complete after 301.040 msecs
Sep 15 10:56:57 XXX kernel: [101284.065848] PM: resume of drv:sd dev:32:0:0:0 complete after 300.997 msecs
Sep 15 10:56:57 XXX kernel: [101284.065870] PM: resume of drv:scsi_device dev:32:0:0:0 complete after 300.980 msecs
Sep 15 10:56:57 XXX kernel: [101284.088049] ata2: SATA link down (SStatus 0 SControl 300)
Sep 15 10:56:57 XXX kernel: [101284.096068] ata3: SATA link down (SStatus 0 SControl 300)
Sep 15 10:56:57 XXX kernel: [101284.124062] usb 4-2: reset full-speed USB device number 2 using uhci_hcd
Sep 15 10:56:57 XXX kernel: [101284.320061] firewire_core: rediscovered device fw0
Sep 15 10:56:57 XXX kernel: [101284.340744] btusb 4-2:1.0: no reset_resume for driver btusb?
Sep 15 10:56:57 XXX kernel: [101284.340746] btusb 4-2:1.1: no reset_resume for driver btusb?
Sep 15 10:56:57 XXX kernel: [101284.388039] PM: resume of drv:uvcvideo dev:1-2:1.1 complete after 625.494 msecs
Sep 15 10:56:57 XXX kernel: [101284.388046] PM: resume of drv: dev:ep_00 complete after 625.363 msecs
Sep 15 10:56:57 XXX kernel: [101284.388051] PM: resume of drv: dev:ep_82 complete after 625.383 msecs
Sep 15 10:56:57 XXX kernel: [101284.388054] PM: resume of drv:uvcvideo dev:1-2:1.0 complete after 625.651 msecs
Sep 15 10:56:57 XXX kernel: [101284.388062] PM: resume of drv: dev:ep_81 complete after 625.581 msecs
Sep 15 10:56:57 XXX kernel: [101284.556081] usb 5-1: reset low-speed USB device number 5 using uhci_hcd
Sep 15 10:56:57 XXX kernel: [101284.592202] PM: resume of drv:usb dev:4-2:1.1 complete after 829.107 msecs
Sep 15 10:56:57 XXX kernel: [101284.592205] PM: resume of drv:usb dev:4-2:1.0 complete after 829.327 msecs
Sep 15 10:56:57 XXX kernel: [101284.592211] PM: resume of drv: dev:ep_03 complete after 829.007 msecs
Sep 15 10:56:57 XXX kernel: [101284.592214] PM: resume of drv:usb dev:4-2:1.2 complete after 828.912 msecs
Sep 15 10:56:57 XXX kernel: [101284.592222] PM: resume of drv: dev:ep_83 complete after 828.929 msecs
Sep 15 10:56:57 XXX kernel: [101284.592225] PM: resume of drv: dev:ep_00 complete after 828.867 msecs
Sep 15 10:56:57 XXX kernel: [101284.592236] PM: resume of drv: dev:ep_82 complete after 829.159 msecs
Sep 15 10:56:57 XXX kernel: [101284.592238] PM: resume of drv: dev:ep_81 complete after 829.346 msecs
Sep 15 10:56:57 XXX kernel: [101284.592241] PM: resume of drv: dev:ep_02 complete after 829.242 msecs
Sep 15 10:56:57 XXX kernel: [101284.862820] PM: resume of drv: dev:ep_00 complete after 1097.784 msecs
Sep 15 10:56:57 XXX kernel: [101284.862836] PM: resume of drv:usbhid dev:5-1:1.0 complete after 1097.852 msecs
Sep 15 10:56:57 XXX kernel: [101284.862859] PM: resume of drv: dev:ep_81 complete after 1097.836 msecs
Sep 15 10:56:57 XXX kernel: [101284.956078] usb 7-2: reset full-speed USB device number 2 using uhci_hcd
Sep 15 10:56:57 XXX kernel: [101285.104070] PM: resume of drv: dev:ep_00 complete after 1340.534 msecs
Sep 15 10:56:57 XXX kernel: [101285.104087] PM: resume of drv:usb dev:7-2:1.0 complete after 1340.642 msecs
Sep 15 10:56:57 XXX kernel: [101285.104110] PM: resume of drv: dev:ep_02 complete after 1340.614 msecs
Sep 15 10:56:57 XXX kernel: [101285.104115] PM: resume of drv: dev:ep_81 complete after 1340.656 msecs
Sep 15 10:56:57 XXX kernel: [101287.104060] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Sep 15 10:56:57 XXX kernel: [101287.106672] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Sep 15 10:56:57 XXX kernel: [101287.106794] ata1.00: ACPI cmd ef/10:06:00:00:00:a0 (SET FEATURES) succeeded
Sep 15 10:56:57 XXX kernel: [101287.106798] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Sep 15 10:56:57 XXX kernel: [101287.119052] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Sep 15 10:56:57 XXX kernel: [101287.119156] ata1.00: ACPI cmd ef/10:06:00:00:00:a0 (SET FEATURES) succeeded
Sep 15 10:56:57 XXX kernel: [101287.119159] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Sep 15 10:56:57 XXX kernel: [101287.121754] ata1.00: configured for UDMA/133
Sep 15 10:56:57 XXX kernel: [101287.141396] PM: resume of drv:sd dev:0:0:0:0 complete after 3379.243 msecs
Sep 15 10:56:57 XXX kernel: [101287.141406] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 3352.348 msecs
Sep 15 10:56:57 XXX kernel: [101287.141422] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 3379.071 msecs
Sep 15 10:56:57 XXX kernel: [101287.141621] PM: resume of devices complete after 3382.545 msecs
Sep 15 10:56:57 XXX kernel: [101287.141984] PM: resume devices took 3.384 seconds
Sep 15 10:56:57 XXX kernel: [101287.142032] PM: Finishing wakeup.
Sep 15 10:56:57 XXX kernel: [101287.142034] Restarting tasks ... done.
Sep 15 10:56:57 XXX kernel: [101287.184635] video LNXVIDEO:00: Restoring backlight state
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> wake requested (sleeping: yes  enabled: yes)
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> waking up and re-enabling...
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (eth0): now managed
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (eth0): bringing up device.
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (eth0): preparing device.
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (eth0): deactivating device (reason 'managed') [2]
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Sep 15 10:56:59  NetworkManager[1077]: last message repeated 3 times
Sep 15 10:56:59 XXX kernel: [101288.512958] atl1 0000:02:00.0: irq 50 for MSI/MSI-X
Sep 15 10:56:59 XXX kernel: [101288.513176] ADDRCONF(NETDEV_UP): eth0: link is not ready
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (wlan0): now managed
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (wlan0): bringing up device.
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (wlan0): preparing device.
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (wlan0): deactivating device (reason 'managed') [2]
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (ttyUSB0): now managed
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Sep 15 10:56:59 XXX kernel: [101288.754961] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (ttyUSB0): deactivating device (reason 'managed') [2]
Sep 15 10:56:59 XXX NetworkManager[1077]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Sep 15 10:56:59 XXX NetworkManager[1077]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Auto-activating connection 'Vodafone (Airtel) Default'.
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) starting connection 'Vodafone (Airtel) Default'
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Sep 15 10:56:59 XXX modem-manager[4627]: <info>  Modem /org/freedesktop/ModemManager/Modems/1: state changed (registered -> connecting)
Sep 15 10:56:59 XXX modem-manager[4627]: <info>  Modem /org/freedesktop/ModemManager/Modems/1: state changed (connecting -> connected)
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled...
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting...
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: prepare -> config (reason 'none') [40 50 0]
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) successful.
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) scheduled.
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) started...
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: config -> ip-config (reason 'none') [50 70 0]
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> starting PPP connection
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> pppd started with pid 8119
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) complete.
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: starting -> ready
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Sep 15 10:56:59 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: ready -> inactive
Sep 15 10:56:59 XXX NetworkManager[1077]: <warn> Trying to remove a non-existant call id.
Sep 15 10:56:59 XXX NetworkManager[1077]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Sep 15 10:56:59 XXX NetworkManager[1077]:    SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
Sep 15 10:57:03 XXX NetworkManager[1077]: <info> Auto-activating connection 'JAZZTEL_62C0'.
Sep 15 10:57:03 XXX NetworkManager[1077]: <info> Activation (wlan0) starting connection 'JAZZTEL_62C0'
Sep 15 10:57:03 XXX NetworkManager[1077]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Sep 15 10:57:03 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Sep 15 10:57:03 XXX NetworkManager[1077]: <info> PPP manager(IP Config Get) reply received.
Sep 15 10:57:03 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Sep 15 10:57:03 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Sep 15 10:57:03 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Sep 15 10:57:03 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Sep 15 10:57:03 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) started...
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> DNS: starting dnsmasq...
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> (ppp0): writing resolv.conf to /sbin/resolvconf
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> (ttyUSB0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Policy set 'Vodafone (Airtel) Default' (ppp0) as default for IPv4 routing and DNS.
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) successful, device activated.
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) complete.
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Activation (wlan0/wireless): access point 'JAZZTEL_62C0' has security, but secrets are required.
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Activation (wlan0/wireless): connection 'JAZZTEL_62C0' has security, and secrets exist.  No new secrets needed.
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Config: added 'ssid' value 'JAZZTEL_62C0'
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Config: added 'scan_ssid' value '1'
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Config: added 'psk' value '<omitted>'
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> Config: set interface ap_scan to 1
Sep 15 10:57:04 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: inactive -> scanning
Sep 15 10:57:06 XXX wpa_supplicant[1524]: Trying to authenticate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2412 MHz)
Sep 15 10:57:06 XXX wpa_supplicant[1524]: Trying to associate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2412 MHz)
Sep 15 10:57:06 XXX kernel: [101296.316216] wlan0: authenticate with 64:68:0c:80:62:c1 (try 1)
Sep 15 10:57:06 XXX kernel: [101296.317982] wlan0: authenticated
Sep 15 10:57:06 XXX kernel: [101296.318096] wlan0: associate with 64:68:0c:80:62:c1 (try 1)
Sep 15 10:57:06 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: scanning -> associating
Sep 15 10:57:06 XXX kernel: [101296.323694] wlan0: RX AssocResp from 64:68:0c:80:62:c1 (capab=0x411 status=0 aid=4)
Sep 15 10:57:06 XXX kernel: [101296.323698] wlan0: associated
Sep 15 10:57:07 XXX wpa_supplicant[1524]: Associated with 64:68:0c:80:62:c1
Sep 15 10:57:07 XXX kernel: [101296.381736] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Sep 15 10:57:07 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: associating -> associated
Sep 15 10:57:07 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
Sep 15 10:57:07 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: 4-way handshake -> group handshake
Sep 15 10:57:07 XXX wpa_supplicant[1524]: WPA: Key negotiation completed with 64:68:0c:80:62:c1 [PTK=CCMP GTK=CCMP]
Sep 15 10:57:07 XXX wpa_supplicant[1524]: CTRL-EVENT-CONNECTED - Connection to 64:68:0c:80:62:c1 completed (auth) [id=0 id_str=]
Sep 15 10:57:07 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: group handshake -> completed
Sep 15 10:57:07 XXX NetworkManager[1077]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'JAZZTEL_62C0'.
Sep 15 10:57:07 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Sep 15 10:57:07 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Sep 15 10:57:07 XXX NetworkManager[1077]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
Sep 15 10:57:07 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Sep 15 10:57:07 XXX NetworkManager[1077]: <info> Activation (wlan0) Beginning IP6 addrconf.
Sep 15 10:57:07 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Sep 15 10:57:07 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started...
Sep 15 10:57:08 XXX NetworkManager[1077]: <info> DNS: starting dnsmasq...
Sep 15 10:57:08 XXX NetworkManager[1077]: <info> (wlan0): writing resolv.conf to /sbin/resolvconf
Sep 15 10:57:08 XXX NetworkManager[1077]: <info> Policy set 'Vodafone (Airtel) Default' (ppp0) as default for IPv4 routing and DNS.
Sep 15 10:57:08 XXX NetworkManager[1077]: <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Sep 15 10:57:08 XXX NetworkManager[1077]: <info> DNS: starting dnsmasq...
Sep 15 10:57:08 XXX NetworkManager[1077]: <info> (wlan0): writing resolv.conf to /sbin/resolvconf
Sep 15 10:57:08 XXX NetworkManager[1077]: <info> Policy set 'JAZZTEL_62C0' (wlan0) as default for IPv4 routing and DNS.
Sep 15 10:57:08 XXX NetworkManager[1077]: <info> Activation (wlan0) successful, device activated.
Sep 15 10:57:08 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete.
Sep 15 10:57:10 XXX kernel: [101299.956109] iwl4965 0000:03:00.0: iwl4965_tx_agg_start on ra = 64:68:0c:80:62:c1 tid = 0
Sep 15 10:57:17 XXX kernel: [101307.168013] wlan0: no IPv6 routers present
Sep 15 10:57:27 XXX NetworkManager[1077]: <info> (wlan0): IP6 addrconf timed out or failed.
Sep 15 10:57:27 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Sep 15 10:57:27 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Sep 15 10:57:27 XXX NetworkManager[1077]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Sep 15 10:58:23 XXX kernel: [101373.052078] iwl4965 0000:03:00.0: Aggregation not enabled for tid 6 because load = 2
Sep 15 11:01:51 XXX kernel: [101580.602620] iwl4965 0000:03:00.0: iwl4965_tx_agg_start on ra = 64:68:0c:80:62:c1 tid = 6
Sep 15 11:07:24 XXX kernel: [101913.784161] usb 5-1: USB disconnect, device number 5
Sep 15 11:07:24 XXX kernel: [101914.289038] keyboard: can't emulate rawmode for keycode 240
Sep 15 11:07:24 XXX kernel: [101914.289061] keyboard: can't emulate rawmode for keycode 240
Sep 15 11:07:25 XXX kernel: [101914.486666] init: anacron main process (7837) killed by TERM signal
Sep 15 11:08:06 XXX wpa_supplicant[1524]: CTRL-EVENT-DISCONNECTED bssid=64:68:0c:80:62:c1 reason=4
Sep 15 11:08:06 XXX kernel: [101955.722256] cfg80211: All devices are disconnected, going to restore regulatory settings
Sep 15 11:08:06 XXX kernel: [101955.722267] cfg80211: Restoring regulatory settings
Sep 15 11:08:06 XXX kernel: [101955.722293] cfg80211: Calling CRDA to update world regulatory domain
Sep 15 11:08:06 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: completed -> disconnected
Sep 15 11:08:06 XXX kernel: [101955.802970] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
Sep 15 11:08:06 XXX kernel: [101955.802974] cfg80211: World regulatory domain updated:
Sep 15 11:08:06 XXX kernel: [101955.802975] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Sep 15 11:08:06 XXX kernel: [101955.802978] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 15 11:08:06 XXX kernel: [101955.802980] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 15 11:08:06 XXX kernel: [101955.802983] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 15 11:08:06 XXX kernel: [101955.802985] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 15 11:08:06 XXX kernel: [101955.802987] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 15 11:08:06 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Sep 15 11:08:08 XXX wpa_supplicant[1524]: Trying to authenticate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2412 MHz)
Sep 15 11:08:08 XXX kernel: [101958.018079] wlan0: authenticate with 64:68:0c:80:62:c1 (try 1)
Sep 15 11:08:08 XXX wpa_supplicant[1524]: Trying to associate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2412 MHz)
Sep 15 11:08:08 XXX kernel: [101958.022645] wlan0: authenticated
Sep 15 11:08:08 XXX kernel: [101958.024065] wlan0: associate with 64:68:0c:80:62:c1 (try 1)
Sep 15 11:08:08 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Sep 15 11:08:08 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: authenticating -> associating
Sep 15 11:08:08 XXX kernel: [101958.029245] wlan0: RX ReassocResp from 64:68:0c:80:62:c1 (capab=0x411 status=0 aid=4)
Sep 15 11:08:08 XXX kernel: [101958.029251] wlan0: associated
Sep 15 11:08:08 XXX wpa_supplicant[1524]: Associated with 64:68:0c:80:62:c1
Sep 15 11:08:08 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: associating -> associated
Sep 15 11:08:08 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
Sep 15 11:08:08 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: 4-way handshake -> group handshake
Sep 15 11:08:08 XXX wpa_supplicant[1524]: WPA: Key negotiation completed with 64:68:0c:80:62:c1 [PTK=CCMP GTK=CCMP]
Sep 15 11:08:08 XXX wpa_supplicant[1524]: CTRL-EVENT-CONNECTED - Connection to 64:68:0c:80:62:c1 completed (reauth) [id=0 id_str=]
Sep 15 11:08:08 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: group handshake -> completed
Sep 15 11:09:44 XXX NetworkManager[1077]: <info> (wlan0): device state change: activated -> disconnected (reason 'user-requested') [100 30 39]
Sep 15 11:09:44 XXX NetworkManager[1077]: <info> (wlan0): deactivating device (reason 'user-requested') [39]
Sep 15 11:09:44 XXX kernel: [102054.130848] wlan0: deauthenticating from 64:68:0c:80:62:c1 by local choice (reason=3)
Sep 15 11:09:44 XXX kernel: [102054.144344] cfg80211: All devices are disconnected, going to restore regulatory settings
Sep 15 11:09:44 XXX kernel: [102054.144351] cfg80211: Restoring regulatory settings
Sep 15 11:09:44 XXX kernel: [102054.144358] cfg80211: Calling CRDA to update world regulatory domain
Sep 15 11:09:44 XXX NetworkManager[1077]: <info> DNS: starting dnsmasq...
Sep 15 11:09:44 XXX NetworkManager[1077]: <info> (wlan0): writing resolv.conf to /sbin/resolvconf
Sep 15 11:09:44 XXX wpa_supplicant[1524]: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3
Sep 15 11:09:44 XXX NetworkManager[1077]: <info> Policy set 'Vodafone (Airtel) Default' (ppp0) as default for IPv4 routing and DNS.
Sep 15 11:09:44 XXX NetworkManager[1077]: <info> Policy set 'Vodafone (Airtel) Default' (ppp0) as default for IPv4 routing and DNS.
Sep 15 11:09:44 XXX kernel: [102054.164220] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
Sep 15 11:09:44 XXX kernel: [102054.164224] cfg80211: World regulatory domain updated:
Sep 15 11:09:44 XXX kernel: [102054.164226] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Sep 15 11:09:44 XXX kernel: [102054.164229] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 15 11:09:44 XXX kernel: [102054.164231] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 15 11:09:44 XXX kernel: [102054.164234] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Sep 15 11:09:44 XXX kernel: [102054.164236] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 15 11:09:44 XXX kernel: [102054.164239] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Sep 15 11:09:44 XXX NetworkManager[1077]: <info> (wlan0): supplicant interface state: completed -> disconnected