Oct 27 05:52:39 XXX kernel: [112176.538843] iwl4965 0000:03:00.0: Aggregation not enabled for tid 6 because load = 2
Oct 27 06:03:27 XXX NetworkManager[1266]: <info> sleep requested (sleeping: no  enabled: yes)
Oct 27 06:03:27 XXX NetworkManager[1266]: <info> sleeping or disabling...
Oct 27 06:03:27 XXX NetworkManager[1266]: <info> (eth0): now unmanaged
Oct 27 06:03:27 XXX NetworkManager[1266]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Oct 27 06:03:27 XXX NetworkManager[1266]: <info> (eth0): cleaning up...
Oct 27 06:03:27 XXX NetworkManager[1266]: <info> (eth0): taking down device.
Oct 27 06:03:27 XXX NetworkManager[1266]: <info> (wlan0): now unmanaged
Oct 27 06:03:27 XXX NetworkManager[1266]: <info> (wlan0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
Oct 27 06:03:27 XXX NetworkManager[1266]: <info> (wlan0): deactivating device (reason 'sleeping') [37]
Oct 27 06:03:27 XXX NetworkManager[1266]: <info> DNS: starting dnsmasq...
Oct 27 06:03:27 XXX kernel: [112824.426804] wlan0: deauthenticating from 64:68:0c:80:62:c1 by local choice (reason=3)
Oct 27 06:03:27 XXX wpa_supplicant[1573]: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3
Oct 27 06:03:27 XXX kernel: [112824.460131] cfg80211: All devices are disconnected, going to restore regulatory settings
Oct 27 06:03:27 XXX kernel: [112824.460137] cfg80211: Restoring regulatory settings
Oct 27 06:03:27 XXX kernel: [112824.460140] cfg80211: Calling CRDA to update world regulatory domain
Oct 27 06:03:27 XXX NetworkManager[1266]: <info> (wlan0): writing resolv.conf to /sbin/resolvconf
Oct 27 06:03:28 XXX NetworkManager[1266]: <info> (wlan0): cleaning up...
Oct 27 06:03:28 XXX NetworkManager[1266]: <info> (wlan0): taking down device.
Oct 27 06:03:28 XXX NetworkManager[1266]: <info> (ttyUSB0): now unmanaged
Oct 27 06:03:28 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: disconnected -> unmanaged (reason 'sleeping') [30 10 37]
Oct 27 06:03:28 XXX NetworkManager[1266]: <info> (ttyUSB0): cleaning up...
Oct 27 06:03:28 XXX NetworkManager[1266]: <info> (ttyUSB0): taking down device.
Oct 27 06:03:29 XXX kernel: [112825.861151] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
Oct 27 06:03:29 XXX kernel: [112825.861154] cfg80211: World regulatory domain updated:
Oct 27 06:03:29 XXX kernel: [112825.861156] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Oct 27 06:03:29 XXX kernel: [112825.861159] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Oct 27 06:03:29 XXX kernel: [112825.861161] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Oct 27 06:03:29 XXX kernel: [112825.861163] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Oct 27 06:03:29 XXX kernel: [112825.861165] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Oct 27 06:03:29 XXX kernel: [112825.861168] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Oct 27 11:59:49 XXX kernel: [112831.295481] PM: Syncing filesystems ... done.
Oct 27 11:59:49 XXX kernel: [112831.332551] PM: Preparing system for mem sleep
Oct 27 11:59:49 XXX kernel: [112831.332566] Freezing user space processes ... (elapsed 0.01 seconds) done.
Oct 27 11:59:49 XXX kernel: [112831.348082] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Oct 27 11:59:49 XXX kernel: [112831.364062] PM: Entering mem sleep
Oct 27 11:59:49 XXX kernel: [112831.364087] Suspending console(s) (use no_console_suspend to debug)
Oct 27 11:59:49 XXX kernel: [112831.364744] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Oct 27 11:59:49 XXX kernel: [112831.365008] option: option_instat_callback: error -2
Oct 27 11:59:49 XXX kernel: [112831.365505] option: option_instat_callback: error -2
Oct 27 11:59:49 XXX kernel: [112831.414658] sd 0:0:0:0: [sda] Stopping disk
Oct 27 11:59:49 XXX kernel: [112831.418952] sdhci-pci 0000:09:01.1: PCI INT B disabled
Oct 27 11:59:49 XXX kernel: [112831.424185] uhci_hcd 0000:00:1d.2: PCI INT C disabled
Oct 27 11:59:49 XXX kernel: [112831.424211] uhci_hcd 0000:00:1d.0: PCI INT A disabled
Oct 27 11:59:49 XXX kernel: [112831.424223] pciehp 0000:00:1c.5:pcie04: pciehp_suspend ENTRY
Oct 27 11:59:49 XXX kernel: [112831.424232] pciehp 0000:00:1c.4:pcie04: pciehp_suspend ENTRY
Oct 27 11:59:49 XXX kernel: [112831.424315] uhci_hcd 0000:00:1a.1: PCI INT B disabled
Oct 27 11:59:49 XXX kernel: [112831.424464] ata_piix 0000:00:1f.1: PCI INT A disabled
Oct 27 11:59:49 XXX kernel: [112831.440079] ehci_hcd 0000:00:1d.7: PCI INT A disabled
Oct 27 11:59:49 XXX kernel: [112831.446585] pciehp 0000:00:01.0:pcie04: pciehp_suspend ENTRY
Oct 27 11:59:49 XXX kernel: [112831.456085] uhci_hcd 0000:00:1a.0: PCI INT A disabled
Oct 27 11:59:49 XXX kernel: [112831.456109] uhci_hcd 0000:00:1d.1: PCI INT B disabled
Oct 27 11:59:49 XXX kernel: [112831.484048] ehci_hcd 0000:00:1a.7: PCI INT C disabled
Oct 27 11:59:49 XXX kernel: [112831.732106] snd_hda_intel 0000:00:1b.0: PCI INT A disabled
Oct 27 11:59:49 XXX kernel: [112831.748051] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 323.785 msecs
Oct 27 11:59:49 XXX kernel: [112831.758314] PM: suspend of drv:sd dev:0:0:0:0 complete after 393.573 msecs
Oct 27 11:59:49 XXX kernel: [112831.758339] PM: suspend of drv:scsi dev:target0:0:0 complete after 393.507 msecs
Oct 27 11:59:49 XXX kernel: [112831.758364] PM: suspend of drv:scsi dev:host0 complete after 341.852 msecs
Oct 27 11:59:49 XXX kernel: [112831.772119] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 348.014 msecs
Oct 27 11:59:49 XXX kernel: [112831.772137] PM: suspend of drv: dev:pci0000:00 complete after 347.780 msecs
Oct 27 11:59:49 XXX kernel: [112831.772161] PM: suspend of devices complete after 407.825 msecs
Oct 27 11:59:49 XXX kernel: [112831.772165] PM: suspend devices took 0.408 seconds
Oct 27 11:59:49 XXX kernel: [112831.836267] ehci_hcd 0000:00:1d.7: PME# enabled
Oct 27 11:59:49 XXX kernel: [112831.836329] ehci_hcd 0000:00:1d.7: wake-up capability enabled by ACPI
Oct 27 11:59:49 XXX kernel: [112831.852187] uhci_hcd 0000:00:1d.2: wake-up capability enabled by ACPI
Oct 27 11:59:49 XXX kernel: [112831.852253] uhci_hcd 0000:00:1d.1: wake-up capability enabled by ACPI
Oct 27 11:59:49 XXX kernel: [112831.852297] uhci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI
Oct 27 11:59:49 XXX kernel: [112831.852504] ehci_hcd 0000:00:1a.7: PME# enabled
Oct 27 11:59:49 XXX kernel: [112831.852541] ehci_hcd 0000:00:1a.7: wake-up capability enabled by ACPI
Oct 27 11:59:49 XXX kernel: [112831.868188] uhci_hcd 0000:00:1a.1: wake-up capability enabled by ACPI
Oct 27 11:59:49 XXX kernel: [112831.868252] uhci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI
Oct 27 11:59:49 XXX kernel: [112831.868363] PM: late suspend of devices complete after 96.194 msecs
Oct 27 11:59:49 XXX kernel: [112831.868967] ACPI: Preparing to enter system sleep state S3
Oct 27 11:59:49 XXX kernel: [112831.890609] PM: Saving platform NVS memory
Oct 27 11:59:49 XXX kernel: [112831.890880] Disabling non-boot CPUs ...
Oct 27 11:59:49 XXX kernel: [112831.892250] CPU 1 is now offline
Oct 27 11:59:49 XXX kernel: [112831.892749] Extended CMOS year: 2000
Oct 27 11:59:49 XXX kernel: [112831.892749] ACPI: Low-level resume complete
Oct 27 11:59:49 XXX kernel: [112831.892749] PM: Restoring platform NVS memory
Oct 27 11:59:49 XXX kernel: [112831.892749] CPU0: Thermal monitoring handled by SMI
Oct 27 11:59:49 XXX kernel: [112831.892749] Extended CMOS year: 2000
Oct 27 11:59:49 XXX kernel: [112831.892749] Enabling non-boot CPUs ...
Oct 27 11:59:49 XXX kernel: [112831.892749] Booting Node 0 Processor 1 APIC 0x1
Oct 27 11:59:49 XXX kernel: [112831.892749] smpboot cpu 1: start_ip = 9b000
Oct 27 11:59:49 XXX kernel: [112831.892242] Initializing CPU#1
Oct 27 11:59:49 XXX kernel: [112831.892242] Calibrating delay loop (skipped) already calibrated this CPU
Oct 27 11:59:49 XXX kernel: [112831.892242] CPU1: Thermal monitoring handled by SMI
Oct 27 11:59:49 XXX kernel: [112831.904329] NMI watchdog enabled, takes one hw-pmu counter.
Oct 27 11:59:49 XXX kernel: [112831.908215] CPU1 is up
Oct 27 11:59:49 XXX kernel: [112831.912106] ACPI: Waking up from system sleep state S3
Oct 27 11:59:49 XXX kernel: [112832.089247] pcieport 0000:00:01.0: restoring config space at offset 0xa (was 0xf, writing 0x0)
Oct 27 11:59:49 XXX kernel: [112832.089251] pcieport 0000:00:01.0: restoring config space at offset 0x7 (was 0xa0a0, writing 0x2000a0a0)
Oct 27 11:59:49 XXX kernel: [112832.089257] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507)
Oct 27 11:59:49 XXX kernel: [112832.089290] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Oct 27 11:59:49 XXX kernel: [112832.089315] uhci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI
Oct 27 11:59:49 XXX kernel: [112832.089336] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Oct 27 11:59:49 XXX kernel: [112832.089357] uhci_hcd 0000:00:1a.1: wake-up capability disabled by ACPI
Oct 27 11:59:49 XXX kernel: [112832.089386] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102)
Oct 27 11:59:49 XXX kernel: [112832.089434] ehci_hcd 0000:00:1a.7: wake-up capability disabled by ACPI
Oct 27 11:59:49 XXX kernel: [112832.089439] ehci_hcd 0000:00:1a.7: PME# disabled
Oct 27 11:59:49 XXX kernel: [112832.089464] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
Oct 27 11:59:49 XXX kernel: [112832.089469] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102)
Oct 27 11:59:49 XXX kernel: [112832.089491] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x20100, writing 0x2010a)
Oct 27 11:59:49 XXX kernel: [112832.089505] pcieport 0000:00:1c.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Oct 27 11:59:49 XXX kernel: [112832.089510] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506)
Oct 27 11:59:49 XXX kernel: [112832.089543] pcieport 0000:00:1c.1: restoring config space at offset 0xf (was 0x20200, writing 0x20205)
Oct 27 11:59:49 XXX kernel: [112832.089557] pcieport 0000:00:1c.1: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Oct 27 11:59:49 XXX kernel: [112832.089562] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100106, writing 0x100506)
Oct 27 11:59:49 XXX kernel: [112832.089595] pcieport 0000:00:1c.2: restoring config space at offset 0xf (was 0x20300, writing 0x20306)
Oct 27 11:59:49 XXX kernel: [112832.089609] pcieport 0000:00:1c.2: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Oct 27 11:59:49 XXX kernel: [112832.089614] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100104, writing 0x100504)
Oct 27 11:59:49 XXX kernel: [112832.089647] pcieport 0000:00:1c.3: restoring config space at offset 0xf (was 0x20400, writing 0x20403)
Oct 27 11:59:49 XXX kernel: [112832.089661] pcieport 0000:00:1c.3: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Oct 27 11:59:49 XXX kernel: [112832.089666] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507)
Oct 27 11:59:49 XXX kernel: [112832.089699] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x20100, writing 0x2010a)
Oct 27 11:59:49 XXX kernel: [112832.089713] pcieport 0000:00:1c.4: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Oct 27 11:59:49 XXX kernel: [112832.089717] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507)
Oct 27 11:59:49 XXX kernel: [112832.089751] pcieport 0000:00:1c.5: restoring config space at offset 0xf (was 0x20200, writing 0x20205)
Oct 27 11:59:49 XXX kernel: [112832.089759] pcieport 0000:00:1c.5: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd031d021)
Oct 27 11:59:49 XXX kernel: [112832.089763] pcieport 0000:00:1c.5: restoring config space at offset 0x8 (was 0xfff0, writing 0xd010d000)
Oct 27 11:59:49 XXX kernel: [112832.089767] pcieport 0000:00:1c.5: restoring config space at offset 0x7 (was 0xf0, writing 0x1010)
Oct 27 11:59:49 XXX kernel: [112832.089774] pcieport 0000:00:1c.5: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Oct 27 11:59:49 XXX kernel: [112832.089778] pcieport 0000:00:1c.5: restoring config space at offset 0x1 (was 0x100104, writing 0x100507)
Oct 27 11:59:49 XXX kernel: [112832.089819] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Oct 27 11:59:49 XXX kernel: [112832.089842] uhci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI
Oct 27 11:59:49 XXX kernel: [112832.089862] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Oct 27 11:59:49 XXX kernel: [112832.089884] uhci_hcd 0000:00:1d.1: wake-up capability disabled by ACPI
Oct 27 11:59:49 XXX kernel: [112832.089904] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Oct 27 11:59:49 XXX kernel: [112832.089925] uhci_hcd 0000:00:1d.2: wake-up capability disabled by ACPI
Oct 27 11:59:49 XXX kernel: [112832.089953] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102)
Oct 27 11:59:49 XXX kernel: [112832.089998] ehci_hcd 0000:00:1d.7: wake-up capability disabled by ACPI
Oct 27 11:59:49 XXX kernel: [112832.090002] ehci_hcd 0000:00:1d.7: PME# disabled
Oct 27 11:59:49 XXX kernel: [112832.090011] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x20000, writing 0x200ff)
Oct 27 11:59:49 XXX kernel: [112832.090082] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005)
Oct 27 11:59:49 XXX kernel: [112832.090109] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407)
Oct 27 11:59:49 XXX kernel: [112832.090142] nvidia 0000:01:00.0: restoring config space at offset 0xc (was 0xfdee0000, writing 0x0)
Oct 27 11:59:49 XXX kernel: [112832.090148] nvidia 0000:01:00.0: restoring config space at offset 0x9 (was 0x1, writing 0xac01)
Oct 27 11:59:49 XXX kernel: [112832.090152] nvidia 0000:01:00.0: restoring config space at offset 0x7 (was 0x4, writing 0xfa000004)
Oct 27 11:59:49 XXX kernel: [112832.090157] nvidia 0000:01:00.0: restoring config space at offset 0x5 (was 0xc, writing 0xe000000c)
Oct 27 11:59:49 XXX kernel: [112832.090160] nvidia 0000:01:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xfc000000)
Oct 27 11:59:49 XXX kernel: [112832.090164] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x8, writing 0x0)
Oct 27 11:59:49 XXX kernel: [112832.090169] nvidia 0000:01:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100006)
Oct 27 11:59:49 XXX kernel: [112832.090206] atl1 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
Oct 27 11:59:49 XXX kernel: [112832.090214] atl1 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfdfa0000)
Oct 27 11:59:49 XXX kernel: [112832.090231] atl1 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
Oct 27 11:59:49 XXX kernel: [112832.090237] atl1 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100106)
Oct 27 11:59:49 XXX kernel: [112832.090285] iwl4965 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x105)
Oct 27 11:59:49 XXX kernel: [112832.090314] iwl4965 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe0fe004)
Oct 27 11:59:49 XXX kernel: [112832.090320] iwl4965 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
Oct 27 11:59:49 XXX kernel: [112832.090329] iwl4965 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100506)
Oct 27 11:59:49 XXX kernel: [112832.090397] pci 0000:05:00.0: restoring config space at offset 0xf (was 0x100, writing 0x103)
Oct 27 11:59:49 XXX kernel: [112832.090405] pci 0000:05:00.0: restoring config space at offset 0xc (was 0x1, writing 0xfe1e0000)
Oct 27 11:59:49 XXX kernel: [112832.090418] pci 0000:05:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xbc01)
Oct 27 11:59:49 XXX kernel: [112832.090424] pci 0000:05:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xfe1ffc00)
Oct 27 11:59:49 XXX kernel: [112832.090429] pci 0000:05:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
Oct 27 11:59:49 XXX kernel: [112832.090435] pci 0000:05:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107)
Oct 27 11:59:49 XXX kernel: [112832.090463] firewire_ohci 0000:09:01.0: restoring config space at offset 0xf (was 0x4020100, writing 0x402010a)
Oct 27 11:59:49 XXX kernel: [112832.090480] firewire_ohci 0000:09:01.0: restoring config space at offset 0x3 (was 0x800000, writing 0x802008)
Oct 27 11:59:49 XXX kernel: [112832.090486] firewire_ohci 0000:09:01.0: restoring config space at offset 0x1 (was 0x2100006, writing 0x2100106)
Oct 27 11:59:49 XXX kernel: [112832.090501] firewire_ohci 0000:09:01.0: proprietary Ricoh MMC controller disabled (via firewire function)
Oct 27 11:59:49 XXX kernel: [112832.090502] firewire_ohci 0000:09:01.0: MMC cards are now supported by standard SDHCI controller
Oct 27 11:59:49 XXX kernel: [112832.090513] sdhci-pci 0000:09:01.1: restoring config space at offset 0xf (was 0x200, writing 0x205)
Oct 27 11:59:49 XXX kernel: [112832.090528] sdhci-pci 0000:09:01.1: restoring config space at offset 0x4 (was 0x0, writing 0xfeaff400)
Oct 27 11:59:49 XXX kernel: [112832.090533] sdhci-pci 0000:09:01.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804008)
Oct 27 11:59:49 XXX kernel: [112832.090538] sdhci-pci 0000:09:01.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106)
Oct 27 11:59:49 XXX kernel: [112832.090556] r592 0000:09:01.2: restoring config space at offset 0xf (was 0x200, writing 0x205)
Oct 27 11:59:49 XXX kernel: [112832.090571] r592 0000:09:01.2: restoring config space at offset 0x4 (was 0x0, writing 0xfeafec00)
Oct 27 11:59:49 XXX kernel: [112832.090576] r592 0000:09:01.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804008)
Oct 27 11:59:49 XXX kernel: [112832.090581] r592 0000:09:01.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106)
Oct 27 11:59:49 XXX kernel: [112832.090598] r852 0000:09:01.3: restoring config space at offset 0xf (was 0x200, writing 0x205)
Oct 27 11:59:49 XXX kernel: [112832.090614] r852 0000:09:01.3: restoring config space at offset 0x4 (was 0x0, writing 0xfeafe800)
Oct 27 11:59:49 XXX kernel: [112832.090618] r852 0000:09:01.3: restoring config space at offset 0x3 (was 0x800000, writing 0x804008)
Oct 27 11:59:49 XXX kernel: [112832.090624] r852 0000:09:01.3: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106)
Oct 27 11:59:49 XXX kernel: [112832.091050] PM: early resume of devices complete after 1.915 msecs
Oct 27 11:59:49 XXX kernel: [112832.091128] pciehp 0000:00:01.0:pcie04: pciehp_resume ENTRY
Oct 27 11:59:49 XXX kernel: [112832.091139] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Oct 27 11:59:49 XXX kernel: [112832.091144] uhci_hcd 0000:00:1a.0: setting latency timer to 64
Oct 27 11:59:49 XXX kernel: [112832.091165] usb usb3: root hub lost power or was reset
Oct 27 11:59:49 XXX kernel: [112832.091177] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
Oct 27 11:59:49 XXX kernel: [112832.091183] uhci_hcd 0000:00:1a.1: setting latency timer to 64
Oct 27 11:59:49 XXX kernel: [112832.091205] usb usb4: root hub lost power or was reset
Oct 27 11:59:49 XXX kernel: [112832.091203] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18
Oct 27 11:59:49 XXX kernel: [112832.091211] ehci_hcd 0000:00:1a.7: setting latency timer to 64
Oct 27 11:59:49 XXX kernel: [112832.091221] snd_hda_intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
Oct 27 11:59:49 XXX kernel: [112832.091226] snd_hda_intel 0000:00:1b.0: setting latency timer to 64
Oct 27 11:59:49 XXX kernel: [112832.091271] snd_hda_intel 0000:00:1b.0: irq 49 for MSI/MSI-X
Oct 27 11:59:49 XXX kernel: [112832.091278] pciehp 0000:00:1c.4:pcie04: pciehp_resume ENTRY
Oct 27 11:59:49 XXX kernel: [112832.091287] pciehp 0000:00:1c.5:pcie04: pciehp_resume ENTRY
Oct 27 11:59:49 XXX kernel: [112832.091294] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23
Oct 27 11:59:49 XXX kernel: [112832.091301] uhci_hcd 0000:00:1d.0: setting latency timer to 64
Oct 27 11:59:49 XXX kernel: [112832.091311] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19
Oct 27 11:59:49 XXX kernel: [112832.091316] uhci_hcd 0000:00:1d.1: setting latency timer to 64
Oct 27 11:59:49 XXX kernel: [112832.091325] usb usb5: root hub lost power or was reset
Oct 27 11:59:49 XXX kernel: [112832.091343] usb usb6: root hub lost power or was reset
Oct 27 11:59:49 XXX kernel: [112832.091341] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
Oct 27 11:59:49 XXX kernel: [112832.091347] uhci_hcd 0000:00:1d.2: setting latency timer to 64
Oct 27 11:59:49 XXX kernel: [112832.091357] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23
Oct 27 11:59:49 XXX kernel: [112832.091362] ehci_hcd 0000:00:1d.7: setting latency timer to 64
Oct 27 11:59:49 XXX kernel: [112832.091369] usb usb7: root hub lost power or was reset
Oct 27 11:59:49 XXX kernel: [112832.091386] pci 0000:00:1e.0: setting latency timer to 64
Oct 27 11:59:49 XXX kernel: [112832.091384] ata_piix 0000:00:1f.1: PCI INT A -> GSI 18 (level, low) -> IRQ 18
Oct 27 11:59:49 XXX kernel: [112832.091388] ata_piix 0000:00:1f.1: setting latency timer to 64
Oct 27 11:59:49 XXX kernel: [112832.091399] ahci 0000:00:1f.2: setting latency timer to 64
Oct 27 11:59:49 XXX kernel: [112832.091501] sdhci-pci 0000:09:01.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Oct 27 11:59:49 XXX kernel: [112832.091504] sdhci-pci 0000:09:01.1: Will use DMA mode even though HW doesn't fully claim to support it.
Oct 27 11:59:49 XXX kernel: [112832.093061] sd 0:0:0:0: [sda] Starting disk
Oct 27 11:59:49 XXX kernel: [112832.094711] ata5: port disabled--ignoring
Oct 27 11:59:49 XXX kernel: [112832.124071] Extended CMOS year: 2000
Oct 27 11:59:49 XXX kernel: [112832.144117] firewire_core: skipped bus generations, destroying all nodes
Oct 27 11:59:49 XXX kernel: [112832.212138] PM: resume of drv:hub dev:3-0:1.0 complete after 119.361 msecs
Oct 27 11:59:49 XXX kernel: [112832.212146] PM: resume of drv: dev:ep_00 complete after 119.365 msecs
Oct 27 11:59:49 XXX kernel: [112832.212151] PM: resume of drv:hub dev:6-0:1.0 complete after 119.208 msecs
Oct 27 11:59:49 XXX kernel: [112832.212158] PM: resume of drv: dev:ep_00 complete after 119.185 msecs
Oct 27 11:59:49 XXX kernel: [112832.212163] PM: resume of drv: dev:ep_81 complete after 119.385 msecs
Oct 27 11:59:49 XXX kernel: [112832.212167] PM: resume of drv: dev:ep_81 complete after 119.209 msecs
Oct 27 11:59:49 XXX kernel: [112832.250697] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 159.289 msecs
Oct 27 11:59:49 XXX kernel: [112832.316074] PM: resume of drv:hub dev:7-0:1.0 complete after 223.060 msecs
Oct 27 11:59:49 XXX kernel: [112832.316088] PM: resume of drv:hub dev:4-0:1.0 complete after 223.287 msecs
Oct 27 11:59:49 XXX kernel: [112832.316095] PM: resume of drv: dev:ep_00 complete after 223.045 msecs
Oct 27 11:59:49 XXX kernel: [112832.316102] PM: resume of drv: dev:ep_00 complete after 223.255 msecs
Oct 27 11:59:49 XXX kernel: [112832.316107] PM: resume of drv: dev:ep_81 complete after 223.080 msecs
Oct 27 11:59:49 XXX kernel: [112832.316115] PM: resume of drv: dev:ep_81 complete after 223.313 msecs
Oct 27 11:59:49 XXX kernel: [112832.316131] PM: resume of drv:hub dev:5-0:1.0 complete after 223.254 msecs
Oct 27 11:59:49 XXX kernel: [112832.316142] PM: resume of drv: dev:ep_00 complete after 223.236 msecs
Oct 27 11:59:49 XXX kernel: [112832.316154] PM: resume of drv: dev:ep_81 complete after 223.263 msecs
Oct 27 11:59:49 XXX kernel: [112832.316445] ata4.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out
Oct 27 11:59:49 XXX kernel: [112832.316448] ata4.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out
Oct 27 11:59:49 XXX kernel: [112832.332254] ata4.00: configured for UDMA/33
Oct 27 11:59:49 XXX kernel: [112832.344066] PM: resume of drv:hub dev:1-0:1.0 complete after 251.337 msecs
Oct 27 11:59:49 XXX kernel: [112832.344078] PM: resume of drv: dev:ep_00 complete after 251.338 msecs
Oct 27 11:59:49 XXX kernel: [112832.344106] PM: resume of drv: dev:ep_81 complete after 251.366 msecs
Oct 27 11:59:49 XXX kernel: [112832.344113] PM: resume of drv:hub dev:2-0:1.0 complete after 251.358 msecs
Oct 27 11:59:49 XXX kernel: [112832.344125] PM: resume of drv: dev:ep_00 complete after 251.364 msecs
Oct 27 11:59:49 XXX kernel: [112832.344133] PM: resume of drv: dev:ep_81 complete after 251.379 msecs
Oct 27 11:59:49 XXX kernel: [112832.393341] PM: resume of drv:option dev:2-4:1.1 complete after 299.745 msecs
Oct 27 11:59:49 XXX kernel: [112832.393350] PM: resume of drv:option dev:2-4:1.0 complete after 299.828 msecs
Oct 27 11:59:49 XXX kernel: [112832.393352] PM: resume of drv:option dev:2-4:1.2 complete after 299.683 msecs
Oct 27 11:59:49 XXX kernel: [112832.393362] PM: resume of drv:option1 dev:ttyUSB0 complete after 299.826 msecs
Oct 27 11:59:49 XXX kernel: [112832.393365] PM: resume of drv:option dev:2-4:1.3 complete after 299.634 msecs
Oct 27 11:59:49 XXX kernel: [112832.393368] PM: resume of drv: dev:ep_81 complete after 299.818 msecs
Oct 27 11:59:49 XXX kernel: [112832.393373] PM: resume of drv: dev:ep_82 complete after 299.808 msecs
Oct 27 11:59:49 XXX kernel: [112832.393376] PM: resume of drv:usb-storage dev:2-4:1.4 complete after 299.586 msecs
Oct 27 11:59:49 XXX kernel: [112832.393379] PM: resume of drv: dev:ep_01 complete after 299.799 msecs
Oct 27 11:59:49 XXX kernel: [112832.393392] PM: resume of drv: dev:ep_05 complete after 299.543 msecs
Oct 27 11:59:49 XXX kernel: [112832.393395] PM: resume of drv:usb-storage dev:2-4:1.5 complete after 299.528 msecs
Oct 27 11:59:49 XXX kernel: [112832.393398] PM: resume of drv: dev:ep_87 complete after 299.563 msecs
Oct 27 11:59:49 XXX kernel: [112832.393404] PM: resume of drv:scsi dev:host135 complete after 299.602 msecs
Oct 27 11:59:49 XXX kernel: [112832.393407] PM: resume of drv: dev:ep_00 complete after 299.466 msecs
Oct 27 11:59:49 XXX kernel: [112832.393412] PM: resume of drv:option1 dev:ttyUSB1 complete after 299.802 msecs
Oct 27 11:59:49 XXX kernel: [112832.393415] PM: resume of drv: dev:ep_04 complete after 299.641 msecs
Oct 27 11:59:49 XXX kernel: [112832.393418] PM: resume of drv: dev:ep_83 complete after 299.793 msecs
Oct 27 11:59:49 XXX kernel: [112832.393420] PM: resume of drv: dev:ep_86 complete after 299.662 msecs
Oct 27 11:59:49 XXX kernel: [112832.393423] PM: resume of drv: dev:ep_84 complete after 299.784 msecs
Oct 27 11:59:49 XXX kernel: [112832.393426] PM: resume of drv:option1 dev:ttyUSB3 complete after 299.682 msecs
Oct 27 11:59:49 XXX kernel: [112832.393428] PM: resume of drv: dev:ep_02 complete after 299.774 msecs
Oct 27 11:59:49 XXX kernel: [112832.393431] PM: resume of drv: dev:ep_03 complete after 299.717 msecs
Oct 27 11:59:49 XXX kernel: [112832.393433] PM: resume of drv:option1 dev:ttyUSB2 complete after 299.749 msecs
Oct 27 11:59:49 XXX kernel: [112832.393436] PM: resume of drv: dev:ep_85 complete after 299.737 msecs
Oct 27 11:59:49 XXX kernel: [112832.393438] PM: resume of drv:scsi dev:host136 complete after 299.559 msecs
Oct 27 11:59:49 XXX kernel: [112832.393440] PM: resume of drv:scsi_host dev:host135 complete after 299.620 msecs
Oct 27 11:59:49 XXX kernel: [112832.393446] PM: resume of drv:scsi dev:target135:0:0 complete after 299.492 msecs
Oct 27 11:59:49 XXX kernel: [112832.393449] PM: resume of drv: dev:ep_06 complete after 299.538 msecs
Oct 27 11:59:49 XXX kernel: [112832.393454] PM: resume of drv: dev:ep_88 complete after 299.530 msecs
Oct 27 11:59:49 XXX kernel: [112832.393456] PM: resume of drv:sr dev:135:0:0:0 complete after 299.487 msecs
Oct 27 11:59:49 XXX kernel: [112832.393459] PM: resume of drv:scsi_host dev:host136 complete after 299.565 msecs
Oct 27 11:59:49 XXX kernel: [112832.393463] PM: resume of drv:scsi_device dev:135:0:0:0 complete after 299.480 msecs
Oct 27 11:59:49 XXX kernel: [112832.393466] PM: resume of drv:scsi dev:target136:0:0 complete after 299.466 msecs
Oct 27 11:59:49 XXX kernel: [112832.393481] PM: resume of drv:sd dev:136:0:0:0 complete after 299.468 msecs
Oct 27 11:59:49 XXX kernel: [112832.393504] PM: resume of drv:scsi_device dev:136:0:0:0 complete after 299.476 msecs
Oct 27 11:59:49 XXX kernel: [112832.416068] ata2: SATA link down (SStatus 0 SControl 300)
Oct 27 11:59:49 XXX kernel: [112832.424049] ata3: SATA link down (SStatus 0 SControl 300)
Oct 27 11:59:49 XXX kernel: [112832.456062] usb 4-2: reset full-speed USB device number 2 using uhci_hcd
Oct 27 11:59:49 XXX kernel: [112832.644061] firewire_core: rediscovered device fw0
Oct 27 11:59:49 XXX kernel: [112832.672827] btusb 4-2:1.0: no reset_resume for driver btusb?
Oct 27 11:59:49 XXX kernel: [112832.672829] btusb 4-2:1.1: no reset_resume for driver btusb?
Oct 27 11:59:49 XXX kernel: [112832.888070] usb 5-1: reset low-speed USB device number 5 using uhci_hcd
Oct 27 11:59:49 XXX kernel: [112832.924198] PM: resume of drv:usb dev:4-2:1.0 complete after 831.056 msecs
Oct 27 11:59:49 XXX kernel: [112832.924201] PM: resume of drv: dev:ep_00 complete after 830.938 msecs
Oct 27 11:59:49 XXX kernel: [112832.924212] PM: resume of drv:usb dev:4-2:1.2 complete after 830.967 msecs
Oct 27 11:59:49 XXX kernel: [112832.924215] PM: resume of drv:usb dev:4-2:1.1 complete after 831.012 msecs
Oct 27 11:59:49 XXX kernel: [112832.924227] PM: resume of drv: dev:ep_82 complete after 831.041 msecs
Oct 27 11:59:49 XXX kernel: [112832.924230] PM: resume of drv: dev:ep_81 complete after 831.074 msecs
Oct 27 11:59:49 XXX kernel: [112832.924233] PM: resume of drv: dev:ep_02 complete after 831.061 msecs
Oct 27 11:59:49 XXX kernel: [112832.924235] PM: resume of drv: dev:ep_03 complete after 831.020 msecs
Oct 27 11:59:49 XXX kernel: [112832.924242] PM: resume of drv: dev:ep_83 complete after 831.011 msecs
Oct 27 11:59:49 XXX kernel: [112832.946655] PM: resume of drv:uvcvideo dev:1-2:1.1 complete after 853.258 msecs
Oct 27 11:59:49 XXX kernel: [112832.946663] PM: resume of drv: dev:ep_00 complete after 853.230 msecs
Oct 27 11:59:49 XXX kernel: [112832.946667] PM: resume of drv: dev:ep_82 complete after 853.256 msecs
Oct 27 11:59:49 XXX kernel: [112832.946670] PM: resume of drv:uvcvideo dev:1-2:1.0 complete after 853.301 msecs
Oct 27 11:59:49 XXX kernel: [112832.946686] PM: resume of drv: dev:ep_81 complete after 853.304 msecs
Oct 27 11:59:49 XXX kernel: [112833.195901] PM: resume of drv: dev:ep_00 complete after 1102.409 msecs
Oct 27 11:59:49 XXX kernel: [112833.195917] PM: resume of drv:usbhid dev:5-1:1.0 complete after 1102.455 msecs
Oct 27 11:59:49 XXX kernel: [112833.195939] PM: resume of drv: dev:ep_81 complete after 1102.462 msecs
Oct 27 11:59:49 XXX kernel: [112833.288067] usb 7-2: reset full-speed USB device number 2 using uhci_hcd
Oct 27 11:59:49 XXX kernel: [112833.441036] PM: resume of drv: dev:ep_00 complete after 1347.701 msecs
Oct 27 11:59:49 XXX kernel: [112833.441052] PM: resume of drv:usb dev:7-2:1.0 complete after 1347.762 msecs
Oct 27 11:59:49 XXX kernel: [112833.441075] PM: resume of drv: dev:ep_02 complete after 1347.756 msecs
Oct 27 11:59:49 XXX kernel: [112833.441080] PM: resume of drv: dev:ep_81 complete after 1347.776 msecs
Oct 27 11:59:49 XXX kernel: [112835.432048] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Oct 27 11:59:49 XXX kernel: [112835.434621] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Oct 27 11:59:49 XXX kernel: [112835.434749] ata1.00: ACPI cmd ef/10:06:00:00:00:a0 (SET FEATURES) succeeded
Oct 27 11:59:49 XXX kernel: [112835.434752] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Oct 27 11:59:49 XXX kernel: [112835.448318] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Oct 27 11:59:49 XXX kernel: [112835.448443] ata1.00: ACPI cmd ef/10:06:00:00:00:a0 (SET FEATURES) succeeded
Oct 27 11:59:49 XXX kernel: [112835.448446] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Oct 27 11:59:49 XXX kernel: [112835.451265] ata1.00: configured for UDMA/133
Oct 27 11:59:49 XXX kernel: [112835.470648] PM: resume of drv:sd dev:0:0:0:0 complete after 3377.585 msecs
Oct 27 11:59:49 XXX kernel: [112835.470658] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 3346.556 msecs
Oct 27 11:59:49 XXX kernel: [112835.470674] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 3377.579 msecs
Oct 27 11:59:49 XXX kernel: [112835.470876] PM: resume of devices complete after 3379.781 msecs
Oct 27 11:59:49 XXX kernel: [112835.471236] PM: resume devices took 3.380 seconds
Oct 27 11:59:49 XXX kernel: [112835.471284] PM: Finishing wakeup.
Oct 27 11:59:49 XXX kernel: [112835.471286] Restarting tasks ... done.
Oct 27 11:59:49 XXX kernel: [112835.493167] video LNXVIDEO:00: Restoring backlight state
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> wake requested (sleeping: yes  enabled: yes)
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> waking up and re-enabling...
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> (eth0): now managed
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> (eth0): bringing up device.
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> (eth0): preparing device.
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> (eth0): deactivating device (reason 'managed') [2]
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Oct 27 11:59:50  NetworkManager[1266]: last message repeated 3 times
Oct 27 11:59:50 XXX kernel: [112836.769289] atl1 0000:02:00.0: irq 50 for MSI/MSI-X
Oct 27 11:59:50 XXX kernel: [112836.769493] ADDRCONF(NETDEV_UP): eth0: link is not ready
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> (wlan0): now managed
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> (wlan0): bringing up device.
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> (wlan0): preparing device.
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> (wlan0): deactivating device (reason 'managed') [2]
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> (ttyUSB0): now managed
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Oct 27 11:59:50 XXX kernel: [112837.007054] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Oct 27 11:59:50 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'managed') [2]
Oct 27 11:59:50 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 11:59:50 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 11:59:51 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: starting -> ready
Oct 27 11:59:51 XXX NetworkManager[1266]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Oct 27 11:59:51 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: ready -> inactive
Oct 27 11:59:51 XXX NetworkManager[1266]: <warn> Trying to remove a non-existant call id.
Oct 27 11:59:51 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Auto-activating connection 'JAZZTEL_62C0'.
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Activation (wlan0) starting connection 'JAZZTEL_62C0'
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Activation (wlan0/wireless): access point 'JAZZTEL_62C0' has security, but secrets are required.
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Activation (wlan0/wireless): connection 'JAZZTEL_62C0' has security, and secrets exist.  No new secrets needed.
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Config: added 'ssid' value 'JAZZTEL_62C0'
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Config: added 'scan_ssid' value '1'
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Config: added 'psk' value '<omitted>'
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> Config: set interface ap_scan to 1
Oct 27 11:59:54 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: inactive -> scanning
Oct 27 11:59:57 XXX wpa_supplicant[1573]: Trying to authenticate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2462 MHz)
Oct 27 11:59:57 XXX kernel: [112843.433765] wlan0: authenticate with 64:68:0c:80:62:c1 (try 1)
Oct 27 11:59:57 XXX wpa_supplicant[1573]: Trying to associate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2462 MHz)
Oct 27 11:59:57 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: scanning -> associating
Oct 27 11:59:57 XXX kernel: [112843.436132] wlan0: authenticated
Oct 27 11:59:57 XXX kernel: [112843.436246] wlan0: associate with 64:68:0c:80:62:c1 (try 1)
Oct 27 11:59:57 XXX kernel: [112843.442135] wlan0: RX AssocResp from 64:68:0c:80:62:c1 (capab=0x411 status=0 aid=6)
Oct 27 11:59:57 XXX kernel: [112843.442138] wlan0: associated
Oct 27 11:59:57 XXX wpa_supplicant[1573]: Associated with 64:68:0c:80:62:c1
Oct 27 11:59:57 XXX kernel: [112843.480701] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Oct 27 11:59:57 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: associating -> associated
Oct 27 11:59:57 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
Oct 27 11:59:57 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: 4-way handshake -> group handshake
Oct 27 11:59:57 XXX wpa_supplicant[1573]: WPA: Key negotiation completed with 64:68:0c:80:62:c1 [PTK=CCMP GTK=CCMP]
Oct 27 11:59:57 XXX wpa_supplicant[1573]: CTRL-EVENT-CONNECTED - Connection to 64:68:0c:80:62:c1 completed (auth) [id=0 id_str=]
Oct 27 11:59:57 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: group handshake -> completed
Oct 27 11:59:57 XXX NetworkManager[1266]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'JAZZTEL_62C0'.
Oct 27 11:59:57 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Oct 27 11:59:57 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Oct 27 11:59:57 XXX NetworkManager[1266]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
Oct 27 11:59:57 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Oct 27 11:59:57 XXX NetworkManager[1266]: <info> Activation (wlan0) Beginning IP6 addrconf.
Oct 27 11:59:57 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Oct 27 11:59:57 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started...
Oct 27 11:59:58 XXX NetworkManager[1266]: <info> DNS: starting dnsmasq...
Oct 27 11:59:58 XXX NetworkManager[1266]: <info> (wlan0): writing resolv.conf to /sbin/resolvconf
Oct 27 11:59:58 XXX NetworkManager[1266]: <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Oct 27 11:59:58 XXX NetworkManager[1266]: <info> Policy set 'JAZZTEL_62C0' (wlan0) as default for IPv4 routing and DNS.
Oct 27 11:59:58 XXX NetworkManager[1266]: <info> Activation (wlan0) successful, device activated.
Oct 27 11:59:58 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete.
Oct 27 11:59:59 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) starting DHCPv6 as requested by IPv6 router...
Oct 27 11:59:59 XXX NetworkManager[1266]: <info> Activation (wlan0) Beginning DHCPv6 transaction (timeout in 45 seconds)
Oct 27 11:59:59 XXX NetworkManager[1266]: <info> dhclient started with pid 28479
Oct 27 11:59:59 XXX dhclient: Internet Systems Consortium DHCP Client 4.1-ESV-R4
Oct 27 11:59:59 XXX dhclient: Copyright 2004-2011 Internet Systems Consortium.
Oct 27 11:59:59 XXX dhclient: All rights reserved.
Oct 27 11:59:59 XXX dhclient: For info, please visit https://www.isc.org/software/dhcp/
Oct 27 11:59:59 XXX dhclient: 
Oct 27 12:00:00 XXX dhclient: Bound to *:546
Oct 27 12:00:00 XXX dhclient: Listening on Socket/wlan0
Oct 27 12:00:00 XXX dhclient: Sending on   Socket/wlan0
Oct 27 12:00:00 XXX NetworkManager[1266]: <info> (wlan0): DHCPv6 state changed nbi -> preinit6
Oct 27 12:00:00 XXX dhclient: XMT: Solicit on wlan0, interval 1040ms.
Oct 27 12:00:00 XXX kernel: [112847.348246] iwl4965 0000:03:00.0: iwl4965_tx_agg_start on ra = 64:68:0c:80:62:c1 tid = 0
Oct 27 12:00:01 XXX dhclient: XMT: Solicit on wlan0, interval 2060ms.
Oct 27 12:00:03 XXX dhclient: XMT: Solicit on wlan0, interval 4230ms.
Oct 27 12:00:07 XXX dhclient: XMT: Solicit on wlan0, interval 8470ms.
Oct 27 12:00:16 XXX dhclient: XMT: Solicit on wlan0, interval 16170ms.
Oct 27 12:00:32 XXX dhclient: XMT: Solicit on wlan0, interval 32230ms.
Oct 27 12:00:45 XXX NetworkManager[1266]: <warn> (wlan0): DHCPv6 request timed out.
Oct 27 12:00:45 XXX NetworkManager[1266]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 28479
Oct 27 12:00:45 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Oct 27 12:00:45 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Oct 27 12:00:45 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Oct 27 12:02:31 XXX kernel: [112997.446618] type=1701 audit(1351332151.044:46): auid=4294967295 uid=1000 gid=1000 ses=4294967295 pid=28520 comm="chrome" reason="seccomp" sig=0 syscall=20 ip=0xe13416 code=0x50001
Oct 27 12:14:41 XXX kernel: [113728.259305] iwl4965 0000:03:00.0: Aggregation not enabled for tid 6 because load = 0
Oct 27 12:34:29 XXX kernel: [114915.709059] iwl4965 0000:03:00.0: Aggregation not enabled for tid 6 because load = 1
Oct 27 13:16:34 XXX kernel: [117441.198364] keyboard: can't emulate rawmode for keycode 240
Oct 27 13:16:34 XXX kernel: [117441.198384] keyboard: can't emulate rawmode for keycode 240
Oct 27 13:16:35 XXX kernel: [117441.808214] usb 5-1: USB disconnect, device number 5
Oct 27 13:39:31 XXX kernel: [118817.963071] wlan0: deauthenticated from 64:68:0c:80:62:c1 (Reason: 7)
Oct 27 13:39:31 XXX wpa_supplicant[1573]: CTRL-EVENT-DISCONNECTED bssid=64:68:0c:80:62:c1 reason=7
Oct 27 13:39:31 XXX kernel: [118818.026334] cfg80211: All devices are disconnected, going to restore regulatory settings
Oct 27 13:39:31 XXX kernel: [118818.026346] cfg80211: Restoring regulatory settings
Oct 27 13:39:31 XXX kernel: [118818.026355] cfg80211: Calling CRDA to update world regulatory domain
Oct 27 13:39:31 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: completed -> disconnected
Oct 27 13:39:31 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Oct 27 13:39:31 XXX kernel: [118818.313084] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
Oct 27 13:39:31 XXX kernel: [118818.313089] cfg80211: World regulatory domain updated:
Oct 27 13:39:31 XXX kernel: [118818.313091] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Oct 27 13:39:31 XXX kernel: [118818.313094] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Oct 27 13:39:31 XXX kernel: [118818.313096] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Oct 27 13:39:31 XXX kernel: [118818.313099] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Oct 27 13:39:31 XXX kernel: [118818.313101] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Oct 27 13:39:31 XXX kernel: [118818.313104] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Oct 27 13:39:33 XXX wpa_supplicant[1573]: Trying to authenticate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2462 MHz)
Oct 27 13:39:33 XXX kernel: [118820.211437] wlan0: authenticate with 64:68:0c:80:62:c1 (try 1)
Oct 27 13:39:33 XXX wpa_supplicant[1573]: Trying to associate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2462 MHz)
Oct 27 13:39:33 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Oct 27 13:39:33 XXX kernel: [118820.216956] wlan0: authenticated
Oct 27 13:39:33 XXX kernel: [118820.217725] wlan0: associate with 64:68:0c:80:62:c1 (try 1)
Oct 27 13:39:33 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: authenticating -> associating
Oct 27 13:39:33 XXX kernel: [118820.224092] wlan0: RX ReassocResp from 64:68:0c:80:62:c1 (capab=0x411 status=0 aid=5)
Oct 27 13:39:33 XXX kernel: [118820.224098] wlan0: associated
Oct 27 13:39:33 XXX wpa_supplicant[1573]: Associated with 64:68:0c:80:62:c1
Oct 27 13:39:33 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: associating -> associated
Oct 27 13:39:34 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
Oct 27 13:39:34 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: 4-way handshake -> group handshake
Oct 27 13:39:34 XXX wpa_supplicant[1573]: WPA: Key negotiation completed with 64:68:0c:80:62:c1 [PTK=CCMP GTK=CCMP]
Oct 27 13:39:34 XXX wpa_supplicant[1573]: CTRL-EVENT-CONNECTED - Connection to 64:68:0c:80:62:c1 completed (reauth) [id=0 id_str=]
Oct 27 13:39:34 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: group handshake -> completed
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) starting connection 'Pepephone Default 1'
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Oct 27 13:41:31 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (registered -> connecting)
Oct 27 13:41:31 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (connecting -> connected)
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled...
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting...
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: prepare -> config (reason 'none') [40 50 0]
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) successful.
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) scheduled.
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) started...
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: config -> ip-config (reason 'none') [50 70 0]
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> starting PPP connection
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> pppd started with pid 4355
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) complete.
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Oct 27 13:41:31 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Oct 27 13:41:32 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Oct 27 13:41:32 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
Oct 27 13:41:34 XXX NetworkManager[1266]: <info> PPP manager(IP Config Get) reply received.
Oct 27 13:41:34 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Oct 27 13:41:34 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) started...
Oct 27 13:41:35 XXX NetworkManager[1266]: <info> DNS: starting dnsmasq...
Oct 27 13:41:35 XXX NetworkManager[1266]: <info> (ppp0): writing resolv.conf to /sbin/resolvconf
Oct 27 13:41:35 XXX NetworkManager[1266]: <info> Policy set 'JAZZTEL_62C0' (wlan0) as default for IPv4 routing and DNS.
Oct 27 13:41:35 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Oct 27 13:41:35 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) successful, device activated.
Oct 27 13:41:35 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) complete.
Oct 27 13:41:36 XXX NetworkManager[1266]: <info> (wlan0): device state change: activated -> disconnected (reason 'user-requested') [100 30 39]
Oct 27 13:41:36 XXX NetworkManager[1266]: <info> (wlan0): deactivating device (reason 'user-requested') [39]
Oct 27 13:41:36 XXX NetworkManager[1266]: <info> DNS: starting dnsmasq...
Oct 27 13:41:36 XXX NetworkManager[1266]: <info> (wlan0): writing resolv.conf to /sbin/resolvconf
Oct 27 13:41:36 XXX kernel: [118942.607583] wlan0: deauthenticating from 64:68:0c:80:62:c1 by local choice (reason=3)
Oct 27 13:41:36 XXX wpa_supplicant[1573]: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3
Oct 27 13:41:36 XXX NetworkManager[1266]: <info> Policy set 'Pepephone Default 1' (ppp0) as default for IPv4 routing and DNS.
Oct 27 13:41:36 XXX NetworkManager[1266]: <info> Policy set 'Pepephone Default 1' (ppp0) as default for IPv4 routing and DNS.
Oct 27 13:41:36 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: completed -> disconnected
Oct 27 13:41:36 XXX kernel: [118942.672132] cfg80211: All devices are disconnected, going to restore regulatory settings
Oct 27 13:41:36 XXX kernel: [118942.672138] cfg80211: Restoring regulatory settings
Oct 27 13:41:36 XXX kernel: [118942.672142] cfg80211: Calling CRDA to update world regulatory domain
Oct 27 13:41:36 XXX kernel: [118942.683576] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
Oct 27 13:41:36 XXX kernel: [118942.683579] cfg80211: World regulatory domain updated:
Oct 27 13:41:36 XXX kernel: [118942.683581] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Oct 27 13:41:36 XXX kernel: [118942.683584] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Oct 27 13:41:36 XXX kernel: [118942.683586] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Oct 27 13:41:36 XXX kernel: [118942.683589] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Oct 27 13:41:36 XXX kernel: [118942.683591] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Oct 27 13:41:36 XXX kernel: [118942.683593] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Oct 27 14:10:26 XXX NetworkManager[1266]: <info> sleep requested (sleeping: no  enabled: yes)
Oct 27 14:10:26 XXX NetworkManager[1266]: <info> sleeping or disabling...
Oct 27 14:10:26 XXX NetworkManager[1266]: <info> (eth0): now unmanaged
Oct 27 14:10:26 XXX NetworkManager[1266]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Oct 27 14:10:26 XXX NetworkManager[1266]: <info> (eth0): cleaning up...
Oct 27 14:10:26 XXX NetworkManager[1266]: <info> (eth0): taking down device.
Oct 27 14:10:26 XXX NetworkManager[1266]: <info> (wlan0): now unmanaged
Oct 27 14:10:26 XXX NetworkManager[1266]: <info> (wlan0): device state change: disconnected -> unmanaged (reason 'sleeping') [30 10 37]
Oct 27 14:10:26 XXX NetworkManager[1266]: <info> (wlan0): cleaning up...
Oct 27 14:10:26 XXX NetworkManager[1266]: <info> (wlan0): taking down device.
Oct 27 14:10:26 XXX NetworkManager[1266]: <info> (ttyUSB0): now unmanaged
Oct 27 14:10:26 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
Oct 27 14:10:28 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'sleeping') [37]
Oct 27 14:10:28 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 14:10:28 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 14:10:30 XXX modem-manager[3539]: <info>  (ttyUSB0) closing serial port...
Oct 27 14:10:30 XXX modem-manager[3539]: <info>  (ttyUSB0) serial port closed
Oct 27 14:10:31 XXX NetworkManager[1266]: <info> DNS: starting dnsmasq...
Oct 27 14:10:32 XXX NetworkManager[1266]: <info> (ttyUSB0): writing resolv.conf to /sbin/resolvconf
Oct 27 14:10:32 XXX NetworkManager[1266]: <info> (ttyUSB0): cleaning up...
Oct 27 14:10:32 XXX NetworkManager[1266]: <info> (ttyUSB0): taking down device.
Oct 27 14:10:32 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (connected -> disconnecting)
Oct 27 14:10:32 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (disconnecting -> connected)
Oct 27 14:10:33 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Oct 27 14:10:33 XXX NetworkManager[1266]: <info> disconnect failed: (32) The serial port is not open.
Oct 27 14:14:23 XXX kernel: [120699.621107] PM: Syncing filesystems ... done.
Oct 27 14:14:23 XXX kernel: [120699.890510] PM: Preparing system for mem sleep
Oct 27 14:14:23 XXX kernel: [120699.890525] Freezing user space processes ... (elapsed 0.01 seconds) done.
Oct 27 14:14:23 XXX kernel: [120699.904076] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Oct 27 14:14:23 XXX kernel: [120699.920069] PM: Entering mem sleep
Oct 27 14:14:23 XXX kernel: [120699.920116] Suspending console(s) (use no_console_suspend to debug)
Oct 27 14:14:23 XXX kernel: [120699.920809] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Oct 27 14:14:23 XXX kernel: [120699.921078] option: option_instat_callback: error -2
Oct 27 14:14:23 XXX kernel: [120699.921156] option: option_instat_callback: error -2
Oct 27 14:14:23 XXX kernel: [120699.953817] sd 0:0:0:0: [sda] Stopping disk
Oct 27 14:14:23 XXX kernel: [120699.972839] sdhci-pci 0000:09:01.1: PCI INT B disabled
Oct 27 14:14:23 XXX kernel: [120699.980202] ehci_hcd 0000:00:1d.7: PCI INT A disabled
Oct 27 14:14:23 XXX kernel: [120699.980223] uhci_hcd 0000:00:1d.2: PCI INT C disabled
Oct 27 14:14:23 XXX kernel: [120699.980237] ata_piix 0000:00:1f.1: PCI INT A disabled
Oct 27 14:14:23 XXX kernel: [120699.980269] pciehp 0000:00:1c.5:pcie04: pciehp_suspend ENTRY
Oct 27 14:14:23 XXX kernel: [120699.980285] pciehp 0000:00:1c.4:pcie04: pciehp_suspend ENTRY
Oct 27 14:14:23 XXX kernel: [120699.980400] uhci_hcd 0000:00:1a.1: PCI INT B disabled
Oct 27 14:14:23 XXX kernel: [120700.001437] pciehp 0000:00:01.0:pcie04: pciehp_suspend ENTRY
Oct 27 14:14:23 XXX kernel: [120700.008115] uhci_hcd 0000:00:1a.0: PCI INT A disabled
Oct 27 14:14:23 XXX kernel: [120700.008135] uhci_hcd 0000:00:1d.0: PCI INT A disabled
Oct 27 14:14:23 XXX kernel: [120700.008191] uhci_hcd 0000:00:1d.1: PCI INT B disabled
Oct 27 14:14:23 XXX kernel: [120700.036045] ehci_hcd 0000:00:1a.7: PCI INT C disabled
Oct 27 14:14:23 XXX kernel: [120700.288131] snd_hda_intel 0000:00:1b.0: PCI INT A disabled
Oct 27 14:14:23 XXX kernel: [120700.303318] PM: suspend of drv:sd dev:0:0:0:0 complete after 382.509 msecs
Oct 27 14:14:23 XXX kernel: [120700.303355] PM: suspend of drv:scsi dev:target0:0:0 complete after 382.530 msecs
Oct 27 14:14:23 XXX kernel: [120700.303391] PM: suspend of drv:scsi dev:host0 complete after 331.983 msecs
Oct 27 14:14:23 XXX kernel: [120700.304047] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 323.713 msecs
Oct 27 14:14:23 XXX kernel: [120700.316099] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 343.111 msecs
Oct 27 14:14:23 XXX kernel: [120700.316136] PM: suspend of drv: dev:pci0000:00 complete after 335.679 msecs
Oct 27 14:14:23 XXX kernel: [120700.316171] PM: suspend of devices complete after 395.784 msecs
Oct 27 14:14:23 XXX kernel: [120700.316175] PM: suspend devices took 0.396 seconds
Oct 27 14:14:23 XXX kernel: [120700.380241] ehci_hcd 0000:00:1d.7: PME# enabled
Oct 27 14:14:23 XXX kernel: [120700.380314] ehci_hcd 0000:00:1d.7: wake-up capability enabled by ACPI
Oct 27 14:14:23 XXX kernel: [120700.396172] uhci_hcd 0000:00:1d.2: wake-up capability enabled by ACPI
Oct 27 14:14:23 XXX kernel: [120700.396237] uhci_hcd 0000:00:1d.1: wake-up capability enabled by ACPI
Oct 27 14:14:23 XXX kernel: [120700.396287] uhci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI
Oct 27 14:14:23 XXX kernel: [120700.396494] ehci_hcd 0000:00:1a.7: PME# enabled
Oct 27 14:14:23 XXX kernel: [120700.396530] ehci_hcd 0000:00:1a.7: wake-up capability enabled by ACPI
Oct 27 14:14:23 XXX kernel: [120700.412169] uhci_hcd 0000:00:1a.1: wake-up capability enabled by ACPI
Oct 27 14:14:23 XXX kernel: [120700.412233] uhci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI
Oct 27 14:14:23 XXX kernel: [120700.412353] PM: late suspend of devices complete after 96.174 msecs
Oct 27 14:14:23 XXX kernel: [120700.412957] ACPI: Preparing to enter system sleep state S3
Oct 27 14:14:23 XXX kernel: [120700.438734] PM: Saving platform NVS memory
Oct 27 14:14:23 XXX kernel: [120700.439015] Disabling non-boot CPUs ...
Oct 27 14:14:23 XXX kernel: [120700.440519] CPU 1 is now offline
Oct 27 14:14:23 XXX kernel: [120700.441032] Extended CMOS year: 2000
Oct 27 14:14:23 XXX kernel: [120700.441032] ACPI: Low-level resume complete
Oct 27 14:14:23 XXX kernel: [120700.441032] PM: Restoring platform NVS memory
Oct 27 14:14:23 XXX kernel: [120700.441032] CPU0: Thermal monitoring handled by SMI
Oct 27 14:14:23 XXX kernel: [120700.441032] Extended CMOS year: 2000
Oct 27 14:14:23 XXX kernel: [120700.441032] Enabling non-boot CPUs ...
Oct 27 14:14:23 XXX kernel: [120700.441032] Booting Node 0 Processor 1 APIC 0x1
Oct 27 14:14:23 XXX kernel: [120700.441032] smpboot cpu 1: start_ip = 9b000
Oct 27 14:14:23 XXX kernel: [120700.440511] Initializing CPU#1
Oct 27 14:14:23 XXX kernel: [120700.440511] Calibrating delay loop (skipped) already calibrated this CPU
Oct 27 14:14:23 XXX kernel: [120700.440511] CPU1: Thermal monitoring handled by SMI
Oct 27 14:14:23 XXX kernel: [120700.452594] NMI watchdog enabled, takes one hw-pmu counter.
Oct 27 14:14:23 XXX kernel: [120700.456144] CPU1 is up
Oct 27 14:14:23 XXX kernel: [120700.460046] ACPI: Waking up from system sleep state S3
Oct 27 14:14:23 XXX kernel: [120700.637110] pcieport 0000:00:01.0: restoring config space at offset 0xa (was 0xf, writing 0x0)
Oct 27 14:14:23 XXX kernel: [120700.637115] pcieport 0000:00:01.0: restoring config space at offset 0x7 (was 0xa0a0, writing 0x2000a0a0)
Oct 27 14:14:23 XXX kernel: [120700.637122] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507)
Oct 27 14:14:23 XXX kernel: [120700.637156] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Oct 27 14:14:23 XXX kernel: [120700.637182] uhci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI
Oct 27 14:14:23 XXX kernel: [120700.637203] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Oct 27 14:14:23 XXX kernel: [120700.637227] uhci_hcd 0000:00:1a.1: wake-up capability disabled by ACPI
Oct 27 14:14:23 XXX kernel: [120700.637256] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102)
Oct 27 14:14:23 XXX kernel: [120700.637305] ehci_hcd 0000:00:1a.7: wake-up capability disabled by ACPI
Oct 27 14:14:23 XXX kernel: [120700.637310] ehci_hcd 0000:00:1a.7: PME# disabled
Oct 27 14:14:23 XXX kernel: [120700.637336] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
Oct 27 14:14:23 XXX kernel: [120700.637341] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102)
Oct 27 14:14:23 XXX kernel: [120700.637364] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x20100, writing 0x2010a)
Oct 27 14:14:23 XXX kernel: [120700.637378] pcieport 0000:00:1c.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Oct 27 14:14:23 XXX kernel: [120700.637383] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506)
Oct 27 14:14:23 XXX kernel: [120700.637417] pcieport 0000:00:1c.1: restoring config space at offset 0xf (was 0x20200, writing 0x20205)
Oct 27 14:14:23 XXX kernel: [120700.637432] pcieport 0000:00:1c.1: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Oct 27 14:14:23 XXX kernel: [120700.637437] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100106, writing 0x100506)
Oct 27 14:14:23 XXX kernel: [120700.637471] pcieport 0000:00:1c.2: restoring config space at offset 0xf (was 0x20300, writing 0x20306)
Oct 27 14:14:23 XXX kernel: [120700.637485] pcieport 0000:00:1c.2: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Oct 27 14:14:23 XXX kernel: [120700.637490] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100104, writing 0x100504)
Oct 27 14:14:23 XXX kernel: [120700.637524] pcieport 0000:00:1c.3: restoring config space at offset 0xf (was 0x20400, writing 0x20403)
Oct 27 14:14:23 XXX kernel: [120700.637539] pcieport 0000:00:1c.3: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Oct 27 14:14:23 XXX kernel: [120700.637544] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507)
Oct 27 14:14:23 XXX kernel: [120700.637578] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x20100, writing 0x2010a)
Oct 27 14:14:23 XXX kernel: [120700.637592] pcieport 0000:00:1c.4: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Oct 27 14:14:23 XXX kernel: [120700.637597] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507)
Oct 27 14:14:23 XXX kernel: [120700.637631] pcieport 0000:00:1c.5: restoring config space at offset 0xf (was 0x20200, writing 0x20205)
Oct 27 14:14:23 XXX kernel: [120700.637640] pcieport 0000:00:1c.5: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd031d021)
Oct 27 14:14:23 XXX kernel: [120700.637644] pcieport 0000:00:1c.5: restoring config space at offset 0x8 (was 0xfff0, writing 0xd010d000)
Oct 27 14:14:23 XXX kernel: [120700.637649] pcieport 0000:00:1c.5: restoring config space at offset 0x7 (was 0xf0, writing 0x1010)
Oct 27 14:14:23 XXX kernel: [120700.637655] pcieport 0000:00:1c.5: restoring config space at offset 0x3 (was 0x810000, writing 0x810008)
Oct 27 14:14:23 XXX kernel: [120700.637660] pcieport 0000:00:1c.5: restoring config space at offset 0x1 (was 0x100104, writing 0x100507)
Oct 27 14:14:23 XXX kernel: [120700.637702] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Oct 27 14:14:23 XXX kernel: [120700.637726] uhci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI
Oct 27 14:14:23 XXX kernel: [120700.637747] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Oct 27 14:14:23 XXX kernel: [120700.637770] uhci_hcd 0000:00:1d.1: wake-up capability disabled by ACPI
Oct 27 14:14:23 XXX kernel: [120700.637791] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001)
Oct 27 14:14:23 XXX kernel: [120700.637814] uhci_hcd 0000:00:1d.2: wake-up capability disabled by ACPI
Oct 27 14:14:23 XXX kernel: [120700.637842] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102)
Oct 27 14:14:23 XXX kernel: [120700.637889] ehci_hcd 0000:00:1d.7: wake-up capability disabled by ACPI
Oct 27 14:14:23 XXX kernel: [120700.637894] ehci_hcd 0000:00:1d.7: PME# disabled
Oct 27 14:14:23 XXX kernel: [120700.637903] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x20000, writing 0x200ff)
Oct 27 14:14:23 XXX kernel: [120700.637976] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005)
Oct 27 14:14:23 XXX kernel: [120700.638004] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407)
Oct 27 14:14:23 XXX kernel: [120700.638038] nvidia 0000:01:00.0: restoring config space at offset 0xc (was 0xfdee0000, writing 0x0)
Oct 27 14:14:23 XXX kernel: [120700.638044] nvidia 0000:01:00.0: restoring config space at offset 0x9 (was 0x1, writing 0xac01)
Oct 27 14:14:23 XXX kernel: [120700.638049] nvidia 0000:01:00.0: restoring config space at offset 0x7 (was 0x4, writing 0xfa000004)
Oct 27 14:14:23 XXX kernel: [120700.638053] nvidia 0000:01:00.0: restoring config space at offset 0x5 (was 0xc, writing 0xe000000c)
Oct 27 14:14:23 XXX kernel: [120700.638057] nvidia 0000:01:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xfc000000)
Oct 27 14:14:23 XXX kernel: [120700.638061] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x8, writing 0x0)
Oct 27 14:14:23 XXX kernel: [120700.638066] nvidia 0000:01:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100006)
Oct 27 14:14:23 XXX kernel: [120700.638104] atl1 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
Oct 27 14:14:23 XXX kernel: [120700.638112] atl1 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfdfa0000)
Oct 27 14:14:23 XXX kernel: [120700.638129] atl1 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
Oct 27 14:14:23 XXX kernel: [120700.638136] atl1 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100106)
Oct 27 14:14:23 XXX kernel: [120700.638185] iwl4965 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x105)
Oct 27 14:14:23 XXX kernel: [120700.638214] iwl4965 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe0fe004)
Oct 27 14:14:23 XXX kernel: [120700.638220] iwl4965 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
Oct 27 14:14:23 XXX kernel: [120700.638229] iwl4965 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100506)
Oct 27 14:14:23 XXX kernel: [120700.638298] pci 0000:05:00.0: restoring config space at offset 0xf (was 0x100, writing 0x103)
Oct 27 14:14:23 XXX kernel: [120700.638306] pci 0000:05:00.0: restoring config space at offset 0xc (was 0x1, writing 0xfe1e0000)
Oct 27 14:14:23 XXX kernel: [120700.638319] pci 0000:05:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xbc01)
Oct 27 14:14:23 XXX kernel: [120700.638325] pci 0000:05:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xfe1ffc00)
Oct 27 14:14:23 XXX kernel: [120700.638330] pci 0000:05:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8)
Oct 27 14:14:23 XXX kernel: [120700.638337] pci 0000:05:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107)
Oct 27 14:14:23 XXX kernel: [120700.638366] firewire_ohci 0000:09:01.0: restoring config space at offset 0xf (was 0x4020100, writing 0x402010a)
Oct 27 14:14:23 XXX kernel: [120700.638383] firewire_ohci 0000:09:01.0: restoring config space at offset 0x3 (was 0x800000, writing 0x802008)
Oct 27 14:14:23 XXX kernel: [120700.638389] firewire_ohci 0000:09:01.0: restoring config space at offset 0x1 (was 0x2100006, writing 0x2100106)
Oct 27 14:14:23 XXX kernel: [120700.638404] firewire_ohci 0000:09:01.0: proprietary Ricoh MMC controller disabled (via firewire function)
Oct 27 14:14:23 XXX kernel: [120700.638406] firewire_ohci 0000:09:01.0: MMC cards are now supported by standard SDHCI controller
Oct 27 14:14:23 XXX kernel: [120700.638417] sdhci-pci 0000:09:01.1: restoring config space at offset 0xf (was 0x200, writing 0x205)
Oct 27 14:14:23 XXX kernel: [120700.638432] sdhci-pci 0000:09:01.1: restoring config space at offset 0x4 (was 0x0, writing 0xfeaff400)
Oct 27 14:14:23 XXX kernel: [120700.638437] sdhci-pci 0000:09:01.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804008)
Oct 27 14:14:23 XXX kernel: [120700.638443] sdhci-pci 0000:09:01.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106)
Oct 27 14:14:23 XXX kernel: [120700.638461] r592 0000:09:01.2: restoring config space at offset 0xf (was 0x200, writing 0x205)
Oct 27 14:14:23 XXX kernel: [120700.638476] r592 0000:09:01.2: restoring config space at offset 0x4 (was 0x0, writing 0xfeafec00)
Oct 27 14:14:23 XXX kernel: [120700.638481] r592 0000:09:01.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804008)
Oct 27 14:14:23 XXX kernel: [120700.638487] r592 0000:09:01.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106)
Oct 27 14:14:23 XXX kernel: [120700.638504] r852 0000:09:01.3: restoring config space at offset 0xf (was 0x200, writing 0x205)
Oct 27 14:14:23 XXX kernel: [120700.638520] r852 0000:09:01.3: restoring config space at offset 0x4 (was 0x0, writing 0xfeafe800)
Oct 27 14:14:23 XXX kernel: [120700.638525] r852 0000:09:01.3: restoring config space at offset 0x3 (was 0x800000, writing 0x804008)
Oct 27 14:14:23 XXX kernel: [120700.638530] r852 0000:09:01.3: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106)
Oct 27 14:14:23 XXX kernel: [120700.638951] PM: early resume of devices complete after 1.955 msecs
Oct 27 14:14:23 XXX kernel: [120700.639033] pciehp 0000:00:01.0:pcie04: pciehp_resume ENTRY
Oct 27 14:14:23 XXX kernel: [120700.639043] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Oct 27 14:14:23 XXX kernel: [120700.639049] uhci_hcd 0000:00:1a.0: setting latency timer to 64
Oct 27 14:14:23 XXX kernel: [120700.639070] usb usb3: root hub lost power or was reset
Oct 27 14:14:23 XXX kernel: [120700.639082] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
Oct 27 14:14:23 XXX kernel: [120700.639087] uhci_hcd 0000:00:1a.1: setting latency timer to 64
Oct 27 14:14:23 XXX kernel: [120700.639107] usb usb4: root hub lost power or was reset
Oct 27 14:14:23 XXX kernel: [120700.639119] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18
Oct 27 14:14:23 XXX kernel: [120700.639126] ehci_hcd 0000:00:1a.7: setting latency timer to 64
Oct 27 14:14:23 XXX kernel: [120700.639127] snd_hda_intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
Oct 27 14:14:23 XXX kernel: [120700.639132] snd_hda_intel 0000:00:1b.0: setting latency timer to 64
Oct 27 14:14:23 XXX kernel: [120700.639179] snd_hda_intel 0000:00:1b.0: irq 49 for MSI/MSI-X
Oct 27 14:14:23 XXX kernel: [120700.639194] pciehp 0000:00:1c.4:pcie04: pciehp_resume ENTRY
Oct 27 14:14:23 XXX kernel: [120700.639203] pciehp 0000:00:1c.5:pcie04: pciehp_resume ENTRY
Oct 27 14:14:23 XXX kernel: [120700.639209] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23
Oct 27 14:14:23 XXX kernel: [120700.639217] uhci_hcd 0000:00:1d.0: setting latency timer to 64
Oct 27 14:14:23 XXX kernel: [120700.639219] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19
Oct 27 14:14:23 XXX kernel: [120700.639224] uhci_hcd 0000:00:1d.1: setting latency timer to 64
Oct 27 14:14:23 XXX kernel: [120700.639240] usb usb5: root hub lost power or was reset
Oct 27 14:14:23 XXX kernel: [120700.639254] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
Oct 27 14:14:23 XXX kernel: [120700.639252] usb usb6: root hub lost power or was reset
Oct 27 14:14:23 XXX kernel: [120700.639261] uhci_hcd 0000:00:1d.2: setting latency timer to 64
Oct 27 14:14:23 XXX kernel: [120700.639266] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23
Oct 27 14:14:23 XXX kernel: [120700.639274] ehci_hcd 0000:00:1d.7: setting latency timer to 64
Oct 27 14:14:23 XXX kernel: [120700.639284] usb usb7: root hub lost power or was reset
Oct 27 14:14:23 XXX kernel: [120700.639296] pci 0000:00:1e.0: setting latency timer to 64
Oct 27 14:14:23 XXX kernel: [120700.639307] ata_piix 0000:00:1f.1: PCI INT A -> GSI 18 (level, low) -> IRQ 18
Oct 27 14:14:23 XXX kernel: [120700.639307] ahci 0000:00:1f.2: setting latency timer to 64
Oct 27 14:14:23 XXX kernel: [120700.639314] ata_piix 0000:00:1f.1: setting latency timer to 64
Oct 27 14:14:23 XXX kernel: [120700.641522] ata5: port disabled--ignoring
Oct 27 14:14:23 XXX kernel: [120700.641587] sdhci-pci 0000:09:01.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Oct 27 14:14:23 XXX kernel: [120700.641590] sdhci-pci 0000:09:01.1: Will use DMA mode even though HW doesn't fully claim to support it.
Oct 27 14:14:23 XXX kernel: [120700.643317] sd 0:0:0:0: [sda] Starting disk
Oct 27 14:14:23 XXX kernel: [120700.669577] Extended CMOS year: 2000
Oct 27 14:14:23 XXX kernel: [120700.696196] firewire_core: skipped bus generations, destroying all nodes
Oct 27 14:14:23 XXX kernel: [120700.760137] PM: resume of drv:hub dev:3-0:1.0 complete after 118.012 msecs
Oct 27 14:14:23 XXX kernel: [120700.760145] PM: resume of drv: dev:ep_00 complete after 117.916 msecs
Oct 27 14:14:23 XXX kernel: [120700.760150] PM: resume of drv:hub dev:5-0:1.0 complete after 117.654 msecs
Oct 27 14:14:23 XXX kernel: [120700.760156] PM: resume of drv: dev:ep_00 complete after 117.456 msecs
Oct 27 14:14:23 XXX kernel: [120700.760161] PM: resume of drv:hub dev:6-0:1.0 complete after 117.348 msecs
Oct 27 14:14:23 XXX kernel: [120700.760167] PM: resume of drv: dev:ep_00 complete after 117.156 msecs
Oct 27 14:14:23 XXX kernel: [120700.760172] PM: resume of drv: dev:ep_81 complete after 117.988 msecs
Oct 27 14:14:23 XXX kernel: [120700.760177] PM: resume of drv: dev:ep_81 complete after 117.566 msecs
Oct 27 14:14:23 XXX kernel: [120700.760181] PM: resume of drv: dev:ep_81 complete after 117.301 msecs
Oct 27 14:14:23 XXX kernel: [120700.799611] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 160.284 msecs
Oct 27 14:14:23 XXX kernel: [120700.860464] ata4.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out
Oct 27 14:14:23 XXX kernel: [120700.860467] ata4.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out
Oct 27 14:14:23 XXX kernel: [120700.864109] PM: resume of drv:hub dev:4-0:1.0 complete after 221.860 msecs
Oct 27 14:14:23 XXX kernel: [120700.864122] PM: resume of drv: dev:ep_00 complete after 221.857 msecs
Oct 27 14:14:23 XXX kernel: [120700.864135] PM: resume of drv:hub dev:7-0:1.0 complete after 220.961 msecs
Oct 27 14:14:23 XXX kernel: [120700.864141] PM: resume of drv: dev:ep_81 complete after 221.879 msecs
Oct 27 14:14:23 XXX kernel: [120700.864148] PM: resume of drv: dev:ep_00 complete after 220.906 msecs
Oct 27 14:14:23 XXX kernel: [120700.864153] PM: resume of drv: dev:ep_81 complete after 220.953 msecs
Oct 27 14:14:23 XXX kernel: [120700.876339] ata4.00: configured for UDMA/33
Oct 27 14:14:23 XXX kernel: [120700.892111] PM: resume of drv:hub dev:1-0:1.0 complete after 250.093 msecs
Oct 27 14:14:23 XXX kernel: [120700.892123] PM: resume of drv: dev:ep_00 complete after 250.098 msecs
Oct 27 14:14:23 XXX kernel: [120700.892145] PM: resume of drv: dev:ep_81 complete after 250.123 msecs
Oct 27 14:14:23 XXX kernel: [120700.892152] PM: resume of drv:hub dev:2-0:1.0 complete after 250.105 msecs
Oct 27 14:14:23 XXX kernel: [120700.892164] PM: resume of drv: dev:ep_00 complete after 250.109 msecs
Oct 27 14:14:23 XXX kernel: [120700.892179] PM: resume of drv: dev:ep_81 complete after 250.127 msecs
Oct 27 14:14:23 XXX kernel: [120700.941437] PM: resume of drv:option dev:2-4:1.1 complete after 296.660 msecs
Oct 27 14:14:23 XXX kernel: [120700.941444] PM: resume of drv:option dev:2-4:1.0 complete after 296.899 msecs
Oct 27 14:14:23 XXX kernel: [120700.941456] PM: resume of drv:option dev:2-4:1.2 complete after 296.438 msecs
Oct 27 14:14:23 XXX kernel: [120700.941465] PM: resume of drv:option1 dev:ttyUSB0 complete after 296.877 msecs
Oct 27 14:14:23 XXX kernel: [120700.941473] PM: resume of drv:option dev:2-4:1.3 complete after 296.264 msecs
Oct 27 14:14:23 XXX kernel: [120700.941478] PM: resume of drv: dev:ep_81 complete after 296.843 msecs
Oct 27 14:14:23 XXX kernel: [120700.941487] PM: resume of drv: dev:ep_82 complete after 296.800 msecs
Oct 27 14:14:23 XXX kernel: [120700.941492] PM: resume of drv:usb-storage dev:2-4:1.4 complete after 296.080 msecs
Oct 27 14:14:23 XXX kernel: [120700.941497] PM: resume of drv: dev:ep_01 complete after 296.765 msecs
Oct 27 14:14:23 XXX kernel: [120700.941530] PM: resume of drv: dev:ep_05 complete after 296.000 msecs
Oct 27 14:14:23 XXX kernel: [120700.941533] PM: resume of drv:usb-storage dev:2-4:1.5 complete after 295.943 msecs
Oct 27 14:14:23 XXX kernel: [120700.941536] PM: resume of drv: dev:ep_87 complete after 296.032 msecs
Oct 27 14:14:23 XXX kernel: [120700.941542] PM: resume of drv:scsi dev:host135 complete after 296.114 msecs
Oct 27 14:14:23 XXX kernel: [120700.941545] PM: resume of drv: dev:ep_00 complete after 295.693 msecs
Oct 27 14:14:23 XXX kernel: [120700.941550] PM: resume of drv:option1 dev:ttyUSB1 complete after 296.721 msecs
Oct 27 14:14:23 XXX kernel: [120700.941552] PM: resume of drv: dev:ep_04 complete after 296.199 msecs
Oct 27 14:14:23 XXX kernel: [120700.941555] PM: resume of drv: dev:ep_83 complete after 296.682 msecs
Oct 27 14:14:23 XXX kernel: [120700.941557] PM: resume of drv: dev:ep_86 complete after 296.274 msecs
Oct 27 14:14:23 XXX kernel: [120700.941560] PM: resume of drv: dev:ep_84 complete after 296.642 msecs
Oct 27 14:14:23 XXX kernel: [120700.941563] PM: resume of drv:option1 dev:ttyUSB3 complete after 296.299 msecs
Oct 27 14:14:23 XXX kernel: [120700.941565] PM: resume of drv: dev:ep_02 complete after 296.593 msecs
Oct 27 14:14:23 XXX kernel: [120700.941568] PM: resume of drv: dev:ep_03 complete after 296.405 msecs
Oct 27 14:14:23 XXX kernel: [120700.941570] PM: resume of drv:option1 dev:ttyUSB2 complete after 296.509 msecs
Oct 27 14:14:23 XXX kernel: [120700.941573] PM: resume of drv: dev:ep_85 complete after 296.455 msecs
Oct 27 14:14:23 XXX kernel: [120700.941576] PM: resume of drv:scsi dev:host136 complete after 295.950 msecs
Oct 27 14:14:23 XXX kernel: [120700.941578] PM: resume of drv:scsi_host dev:host135 complete after 296.093 msecs
Oct 27 14:14:23 XXX kernel: [120700.941583] PM: resume of drv:scsi dev:target135:0:0 complete after 295.714 msecs
Oct 27 14:14:23 XXX kernel: [120700.941586] PM: resume of drv: dev:ep_06 complete after 295.833 msecs
Oct 27 14:14:23 XXX kernel: [120700.941592] PM: resume of drv: dev:ep_88 complete after 295.796 msecs
Oct 27 14:14:23 XXX kernel: [120700.941594] PM: resume of drv:sr dev:135:0:0:0 complete after 295.654 msecs
Oct 27 14:14:23 XXX kernel: [120700.941596] PM: resume of drv:scsi_host dev:host136 complete after 295.891 msecs
Oct 27 14:14:23 XXX kernel: [120700.941601] PM: resume of drv:scsi_device dev:135:0:0:0 complete after 295.606 msecs
Oct 27 14:14:23 XXX kernel: [120700.941604] PM: resume of drv:scsi dev:target136:0:0 complete after 295.588 msecs
Oct 27 14:14:23 XXX kernel: [120700.941620] PM: resume of drv:sd dev:136:0:0:0 complete after 295.537 msecs
Oct 27 14:14:23 XXX kernel: [120700.941637] PM: resume of drv:scsi_device dev:136:0:0:0 complete after 295.499 msecs
Oct 27 14:14:23 XXX kernel: [120700.960113] ata3: SATA link down (SStatus 0 SControl 300)
Oct 27 14:14:23 XXX kernel: [120700.968048] ata2: SATA link down (SStatus 0 SControl 300)
Oct 27 14:14:23 XXX kernel: [120701.004043] usb 4-2: reset full-speed USB device number 2 using uhci_hcd
Oct 27 14:14:23 XXX kernel: [120701.196079] firewire_core: rediscovered device fw0
Oct 27 14:14:23 XXX kernel: [120701.220271] btusb 4-2:1.0: no reset_resume for driver btusb?
Oct 27 14:14:23 XXX kernel: [120701.220276] btusb 4-2:1.1: no reset_resume for driver btusb?
Oct 27 14:14:23 XXX kernel: [120701.344081] usb 7-2: reset full-speed USB device number 2 using uhci_hcd
Oct 27 14:14:23 XXX kernel: [120701.419859] PM: resume of drv:uvcvideo dev:1-2:1.1 complete after 775.513 msecs
Oct 27 14:14:23 XXX kernel: [120701.419872] PM: resume of drv: dev:ep_00 complete after 775.427 msecs
Oct 27 14:14:23 XXX kernel: [120701.419878] PM: resume of drv:uvcvideo dev:1-2:1.0 complete after 775.619 msecs
Oct 27 14:14:23 XXX kernel: [120701.419884] PM: resume of drv: dev:ep_82 complete after 775.481 msecs
Oct 27 14:14:23 XXX kernel: [120701.419909] PM: resume of drv: dev:ep_81 complete after 775.604 msecs
Oct 27 14:14:23 XXX kernel: [120701.472263] PM: resume of drv:usb dev:4-2:1.2 complete after 828.378 msecs
Oct 27 14:14:23 XXX kernel: [120701.472267] PM: resume of drv:usb dev:4-2:1.0 complete after 828.747 msecs
Oct 27 14:14:23 XXX kernel: [120701.472269] PM: resume of drv:usb dev:4-2:1.1 complete after 828.529 msecs
Oct 27 14:14:23 XXX kernel: [120701.472277] PM: resume of drv: dev:ep_00 complete after 828.348 msecs
Oct 27 14:14:23 XXX kernel: [120701.472280] PM: resume of drv: dev:ep_03 complete after 828.518 msecs
Oct 27 14:14:23 XXX kernel: [120701.472283] PM: resume of drv: dev:ep_81 complete after 828.721 msecs
Oct 27 14:14:23 XXX kernel: [120701.472285] PM: resume of drv: dev:ep_83 complete after 828.455 msecs
Oct 27 14:14:23 XXX kernel: [120701.472288] PM: resume of drv: dev:ep_02 complete after 828.671 msecs
Oct 27 14:14:23 XXX kernel: [120701.472294] PM: resume of drv: dev:ep_82 complete after 828.608 msecs
Oct 27 14:14:23 XXX kernel: [120701.497130] PM: resume of drv: dev:ep_00 complete after 852.952 msecs
Oct 27 14:14:23 XXX kernel: [120701.497148] PM: resume of drv:usb dev:7-2:1.0 complete after 853.054 msecs
Oct 27 14:14:23 XXX kernel: [120701.497180] PM: resume of drv: dev:ep_02 complete after 853.024 msecs
Oct 27 14:14:23 XXX kernel: [120701.497190] PM: resume of drv: dev:ep_81 complete after 853.090 msecs
Oct 27 14:14:23 XXX kernel: [120703.944049] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Oct 27 14:14:23 XXX kernel: [120703.946739] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Oct 27 14:14:23 XXX kernel: [120703.946852] ata1.00: ACPI cmd ef/10:06:00:00:00:a0 (SET FEATURES) succeeded
Oct 27 14:14:23 XXX kernel: [120703.946859] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Oct 27 14:14:23 XXX kernel: [120703.957841] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Oct 27 14:14:23 XXX kernel: [120703.957960] ata1.00: ACPI cmd ef/10:06:00:00:00:a0 (SET FEATURES) succeeded
Oct 27 14:14:23 XXX kernel: [120703.957966] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Oct 27 14:14:23 XXX kernel: [120703.960842] ata1.00: configured for UDMA/133
Oct 27 14:14:23 XXX kernel: [120703.980105] PM: resume of drv:sd dev:0:0:0:0 complete after 3336.781 msecs
Oct 27 14:14:23 XXX kernel: [120703.980122] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 3310.512 msecs
Oct 27 14:14:23 XXX kernel: [120703.980140] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 3336.731 msecs
Oct 27 14:14:23 XXX kernel: [120703.980353] PM: resume of devices complete after 3341.344 msecs
Oct 27 14:14:23 XXX kernel: [120703.980718] PM: resume devices took 3.344 seconds
Oct 27 14:14:23 XXX kernel: [120703.980766] PM: Finishing wakeup.
Oct 27 14:14:23 XXX kernel: [120703.980768] Restarting tasks ... done.
Oct 27 14:14:23 XXX kernel: [120703.998324] video LNXVIDEO:00: Restoring backlight state
Oct 27 14:14:23 XXX kernel: [120704.012096] uvcvideo: Failed to set UVC commit control : -71 (exp. 26).
Oct 27 14:14:23 XXX kernel: [120704.012103] uvcvideo 1-2:1.1: resume error -5
Oct 27 14:14:23 XXX kernel: [120704.189453] usb 1-2: USB disconnect, device number 4
Oct 27 14:14:23 XXX kernel: [120704.456037] usb 1-2: new high-speed USB device number 5 using ehci_hcd
Oct 27 14:14:23 XXX kernel: [120704.589518] uvcvideo: Found UVC 1.00 device USB2.0 1.3M UVC WebCam (04f2:b033)
Oct 27 14:14:24 XXX kernel: [120704.997403] input: USB2.0 1.3M UVC WebCam as /devices/pci0000:00/0000:00:1a.7/usb1/1-2/1-2:1.0/input/input15
Oct 27 14:14:24 XXX kernel: [120705.480077] usb 5-1: new low-speed USB device number 6 using uhci_hcd
Oct 27 14:14:24 XXX kernel: [120705.689541] input: Genius Optical Mouse as /devices/pci0000:00/0000:00:1d.0/usb5/5-1/5-1:1.0/input/input16
Oct 27 14:14:24 XXX kernel: [120705.689667] generic-usb 0003:0458:003A.0005: input,hidraw0: USB HID v1.10 Mouse [Genius Optical Mouse] on usb-0000:00:1d.0-1/input0
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> wake requested (sleeping: yes  enabled: yes)
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> waking up and re-enabling...
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (eth0): now managed
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (eth0): bringing up device.
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (eth0): preparing device.
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (eth0): deactivating device (reason 'managed') [2]
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Oct 27 14:14:25  NetworkManager[1266]: last message repeated 3 times
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (wlan0): now managed
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (wlan0): bringing up device.
Oct 27 14:14:25 XXX kernel: [120706.486381] atl1 0000:02:00.0: irq 50 for MSI/MSI-X
Oct 27 14:14:25 XXX kernel: [120706.486584] ADDRCONF(NETDEV_UP): eth0: link is not ready
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (wlan0): preparing device.
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (wlan0): deactivating device (reason 'managed') [2]
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (ttyUSB0): now managed
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Oct 27 14:14:25 XXX kernel: [120706.737889] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'managed') [2]
Oct 27 14:14:25 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 14:14:25 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: starting -> ready
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Oct 27 14:14:25 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: ready -> inactive
Oct 27 14:14:25 XXX NetworkManager[1266]: <warn> Trying to remove a non-existant call id.
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Auto-activating connection 'JAZZTEL_62C0'.
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Activation (wlan0) starting connection 'JAZZTEL_62C0'
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Activation (wlan0/wireless): access point 'JAZZTEL_62C0' has security, but secrets are required.
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Activation (wlan0/wireless): connection 'JAZZTEL_62C0' has security, and secrets exist.  No new secrets needed.
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Config: added 'ssid' value 'JAZZTEL_62C0'
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Config: added 'scan_ssid' value '1'
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Config: added 'psk' value '<omitted>'
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> Config: set interface ap_scan to 1
Oct 27 14:14:29 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: inactive -> scanning
Oct 27 14:14:31 XXX wpa_supplicant[1573]: Trying to authenticate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2462 MHz)
Oct 27 14:14:31 XXX kernel: [120712.766037] wlan0: authenticate with 64:68:0c:80:62:c1 (try 1)
Oct 27 14:14:31 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Oct 27 14:14:32 XXX kernel: [120712.964020] wlan0: authenticate with 64:68:0c:80:62:c1 (try 2)
Oct 27 14:14:32 XXX kernel: [120713.164019] wlan0: authenticate with 64:68:0c:80:62:c1 (try 3)
Oct 27 14:14:32 XXX kernel: [120713.364026] wlan0: authentication with 64:68:0c:80:62:c1 timed out
Oct 27 14:14:39 XXX wpa_supplicant[1573]: Trying to authenticate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2462 MHz)
Oct 27 14:14:39 XXX wpa_supplicant[1573]: Trying to associate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2462 MHz)
Oct 27 14:14:39 XXX kernel: [120720.549223] wlan0: authenticate with 64:68:0c:80:62:c1 (try 1)
Oct 27 14:14:39 XXX kernel: [120720.550994] wlan0: authenticated
Oct 27 14:14:39 XXX kernel: [120720.551858] wlan0: associate with 64:68:0c:80:62:c1 (try 1)
Oct 27 14:14:39 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: authenticating -> associating
Oct 27 14:14:39 XXX kernel: [120720.555099] wlan0: RX AssocResp from 64:68:0c:80:62:c1 (capab=0x411 status=0 aid=6)
Oct 27 14:14:39 XXX kernel: [120720.555102] wlan0: associated
Oct 27 14:14:39 XXX wpa_supplicant[1573]: Associated with 64:68:0c:80:62:c1
Oct 27 14:14:39 XXX kernel: [120720.593712] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Oct 27 14:14:39 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: associating -> associated
Oct 27 14:14:40 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
Oct 27 14:14:40 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: 4-way handshake -> group handshake
Oct 27 14:14:40 XXX wpa_supplicant[1573]: WPA: Key negotiation completed with 64:68:0c:80:62:c1 [PTK=CCMP GTK=CCMP]
Oct 27 14:14:40 XXX wpa_supplicant[1573]: CTRL-EVENT-CONNECTED - Connection to 64:68:0c:80:62:c1 completed (auth) [id=0 id_str=]
Oct 27 14:14:40 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: group handshake -> completed
Oct 27 14:14:40 XXX NetworkManager[1266]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'JAZZTEL_62C0'.
Oct 27 14:14:40 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Oct 27 14:14:40 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Oct 27 14:14:40 XXX NetworkManager[1266]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
Oct 27 14:14:40 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Oct 27 14:14:40 XXX NetworkManager[1266]: <info> Activation (wlan0) Beginning IP6 addrconf.
Oct 27 14:14:40 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Oct 27 14:14:40 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started...
Oct 27 14:14:41 XXX NetworkManager[1266]: <info> DNS: starting dnsmasq...
Oct 27 14:14:41 XXX NetworkManager[1266]: <info> (wlan0): writing resolv.conf to /sbin/resolvconf
Oct 27 14:14:41 XXX NetworkManager[1266]: <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Oct 27 14:14:41 XXX NetworkManager[1266]: <info> Policy set 'JAZZTEL_62C0' (wlan0) as default for IPv4 routing and DNS.
Oct 27 14:14:41 XXX NetworkManager[1266]: <info> Activation (wlan0) successful, device activated.
Oct 27 14:14:41 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete.
Oct 27 14:14:43 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) starting DHCPv6 as requested by IPv6 router...
Oct 27 14:14:43 XXX NetworkManager[1266]: <info> Activation (wlan0) Beginning DHCPv6 transaction (timeout in 45 seconds)
Oct 27 14:14:43 XXX NetworkManager[1266]: <info> dhclient started with pid 22223
Oct 27 14:14:43 XXX dhclient: Internet Systems Consortium DHCP Client 4.1-ESV-R4
Oct 27 14:14:43 XXX dhclient: Copyright 2004-2011 Internet Systems Consortium.
Oct 27 14:14:43 XXX dhclient: All rights reserved.
Oct 27 14:14:43 XXX dhclient: For info, please visit https://www.isc.org/software/dhcp/
Oct 27 14:14:43 XXX dhclient: 
Oct 27 14:14:43 XXX dhclient: Bound to *:546
Oct 27 14:14:43 XXX NetworkManager[1266]: <info> (wlan0): DHCPv6 state changed nbi -> preinit6
Oct 27 14:14:43 XXX dhclient: Listening on Socket/wlan0
Oct 27 14:14:43 XXX dhclient: Sending on   Socket/wlan0
Oct 27 14:14:44 XXX kernel: [120725.111326] type=1400 audit(1351340084.158:47): apparmor="STATUS" operation="profile_replace" name="/usr/share/gdm/guest-session/Xsession" pid=22292 comm="apparmor_parser"
Oct 27 14:14:44 XXX kernel: [120725.162458] type=1400 audit(1351340084.210:48): apparmor="STATUS" operation="profile_replace" name="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" pid=22293 comm="apparmor_parser"
Oct 27 14:14:44 XXX dhclient: XMT: Solicit on wlan0, interval 1070ms.
Oct 27 14:14:44 XXX kernel: [120725.529877] type=1400 audit(1351340084.578:49): apparmor="STATUS" operation="profile_replace" name="/sbin/dhclient" pid=22294 comm="apparmor_parser"
Oct 27 14:14:44 XXX kernel: [120725.530038] type=1400 audit(1351340084.578:50): apparmor="STATUS" operation="profile_replace" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=22294 comm="apparmor_parser"
Oct 27 14:14:44 XXX kernel: [120725.530133] type=1400 audit(1351340084.578:51): apparmor="STATUS" operation="profile_replace" name="/usr/lib/connman/scripts/dhclient-script" pid=22294 comm="apparmor_parser"
Oct 27 14:14:45 XXX dhclient: XMT: Solicit on wlan0, interval 2240ms.
Oct 27 14:14:45 XXX kernel: [120726.618875] type=1400 audit(1351340085.666:52): apparmor="STATUS" operation="profile_replace" name="/usr/lib/telepathy/mission-control-5" pid=22298 comm="apparmor_parser"
Oct 27 14:14:45 XXX kernel: [120726.619251] type=1400 audit(1351340085.666:53): apparmor="STATUS" operation="profile_replace" name="/usr/lib/telepathy/telepathy-*" pid=22298 comm="apparmor_parser"
Oct 27 14:14:46 XXX kernel: [120727.318050] type=1400 audit(1351340086.366:54): apparmor="STATUS" operation="profile_replace" name="/usr/lib/cups/backend/cups-pdf" pid=22299 comm="apparmor_parser"
Oct 27 14:14:46 XXX kernel: [120727.318401] type=1400 audit(1351340086.366:55): apparmor="STATUS" operation="profile_replace" name="/usr/sbin/cupsd" pid=22299 comm="apparmor_parser"
Oct 27 14:14:46 XXX kernel: [120727.678780] type=1400 audit(1351340086.726:56): apparmor="STATUS" operation="profile_replace" name="/usr/sbin/mysqld-akonadi" pid=22300 comm="apparmor_parser"
Oct 27 14:14:47 XXX dhclient: XMT: Solicit on wlan0, interval 4620ms.
Oct 27 14:14:52 XXX kernel: [120733.057054] audit_printk_skb: 6 callbacks suppressed
Oct 27 14:14:52 XXX kernel: [120733.057057] type=1400 audit(1351340092.106:59): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince" pid=22295 comm="apparmor_parser"
Oct 27 14:14:52 XXX kernel: [120733.060602] type=1400 audit(1351340092.110:60): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince//launchpad_integration" pid=22295 comm="apparmor_parser"
Oct 27 14:14:52 XXX kernel: [120733.063656] type=1400 audit(1351340092.110:61): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince//sanitized_helper" pid=22295 comm="apparmor_parser"
Oct 27 14:14:52 XXX kernel: [120733.064517] type=1400 audit(1351340092.114:62): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince-previewer" pid=22295 comm="apparmor_parser"
Oct 27 14:14:52 XXX kernel: [120733.065176] type=1400 audit(1351340092.114:63): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince-previewer//launchpad_integration" pid=22295 comm="apparmor_parser"
Oct 27 14:14:52 XXX kernel: [120733.065529] type=1400 audit(1351340092.114:64): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince-previewer//sanitized_helper" pid=22295 comm="apparmor_parser"
Oct 27 14:14:52 XXX kernel: [120733.066098] type=1400 audit(1351340092.114:65): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince-thumbnailer" pid=22295 comm="apparmor_parser"
Oct 27 14:14:52 XXX kernel: [120733.066564] type=1400 audit(1351340092.114:66): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince-thumbnailer//sanitized_helper" pid=22295 comm="apparmor_parser"
Oct 27 14:14:52 XXX dhclient: XMT: Solicit on wlan0, interval 9660ms.
Oct 27 14:14:52 XXX kernel: [120733.484869] type=1400 audit(1351340092.534:67): apparmor="STATUS" operation="profile_replace" name="/usr/share/gdm/guest-session/Xsession" pid=22378 comm="apparmor_parser"
Oct 27 14:14:52 XXX kernel: [120733.546430] type=1400 audit(1351340092.594:68): apparmor="STATUS" operation="profile_replace" name="/usr/lib/lightdm/lightdm/lightdm-guest-session-wrapper" pid=22379 comm="apparmor_parser"
Oct 27 14:14:54 XXX wpa_supplicant[1573]: CTRL-EVENT-DISCONNECTED bssid=64:68:0c:80:62:c1 reason=4
Oct 27 14:14:54 XXX kernel: [120735.072328] cfg80211: All devices are disconnected, going to restore regulatory settings
Oct 27 14:14:54 XXX kernel: [120735.072334] cfg80211: Restoring regulatory settings
Oct 27 14:14:54 XXX kernel: [120735.072337] cfg80211: Calling CRDA to update world regulatory domain
Oct 27 14:14:54 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: completed -> disconnected
Oct 27 14:14:54 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Oct 27 14:14:54 XXX kernel: [120735.167039] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
Oct 27 14:14:54 XXX kernel: [120735.167043] cfg80211: World regulatory domain updated:
Oct 27 14:14:54 XXX kernel: [120735.167045] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Oct 27 14:14:54 XXX kernel: [120735.167048] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Oct 27 14:14:54 XXX kernel: [120735.167051] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Oct 27 14:14:54 XXX kernel: [120735.167053] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Oct 27 14:14:54 XXX kernel: [120735.167056] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Oct 27 14:14:54 XXX kernel: [120735.167058] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Oct 27 14:14:56 XXX wpa_supplicant[1573]: Trying to authenticate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2462 MHz)
Oct 27 14:14:56 XXX kernel: [120737.450165] wlan0: authenticate with 64:68:0c:80:62:c1 (try 1)
Oct 27 14:14:56 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Oct 27 14:14:56 XXX wpa_supplicant[1573]: Trying to associate with 64:68:0c:80:62:c1 (SSID='JAZZTEL_62C0' freq=2462 MHz)
Oct 27 14:14:56 XXX kernel: [120737.459887] wlan0: authenticated
Oct 27 14:14:56 XXX kernel: [120737.460089] wlan0: associate with 64:68:0c:80:62:c1 (try 1)
Oct 27 14:14:56 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: authenticating -> associating
Oct 27 14:14:56 XXX kernel: [120737.485027] wlan0: RX ReassocResp from 64:68:0c:80:62:c1 (capab=0x411 status=0 aid=6)
Oct 27 14:14:56 XXX kernel: [120737.485031] wlan0: associated
Oct 27 14:14:56 XXX wpa_supplicant[1573]: Associated with 64:68:0c:80:62:c1
Oct 27 14:14:56 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: associating -> associated
Oct 27 14:14:56 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
Oct 27 14:14:56 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: 4-way handshake -> group handshake
Oct 27 14:14:56 XXX wpa_supplicant[1573]: WPA: Key negotiation completed with 64:68:0c:80:62:c1 [PTK=CCMP GTK=CCMP]
Oct 27 14:14:56 XXX wpa_supplicant[1573]: CTRL-EVENT-CONNECTED - Connection to 64:68:0c:80:62:c1 completed (reauth) [id=0 id_str=]
Oct 27 14:14:56 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: group handshake -> completed
Oct 27 14:15:00 XXX kernel: [120741.378407] audit_printk_skb: 30 callbacks suppressed
Oct 27 14:15:00 XXX kernel: [120741.378410] type=1400 audit(1351340100.426:79): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince" pid=22381 comm="apparmor_parser"
Oct 27 14:15:00 XXX kernel: [120741.379222] type=1400 audit(1351340100.426:80): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince//launchpad_integration" pid=22381 comm="apparmor_parser"
Oct 27 14:15:00 XXX kernel: [120741.379561] type=1400 audit(1351340100.426:81): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince//sanitized_helper" pid=22381 comm="apparmor_parser"
Oct 27 14:15:00 XXX kernel: [120741.380360] type=1400 audit(1351340100.430:82): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince-previewer" pid=22381 comm="apparmor_parser"
Oct 27 14:15:00 XXX kernel: [120741.380985] type=1400 audit(1351340100.430:83): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince-previewer//launchpad_integration" pid=22381 comm="apparmor_parser"
Oct 27 14:15:00 XXX kernel: [120741.381347] type=1400 audit(1351340100.430:84): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince-previewer//sanitized_helper" pid=22381 comm="apparmor_parser"
Oct 27 14:15:00 XXX kernel: [120741.381935] type=1400 audit(1351340100.430:85): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince-thumbnailer" pid=22381 comm="apparmor_parser"
Oct 27 14:15:00 XXX kernel: [120741.382397] type=1400 audit(1351340100.430:86): apparmor="STATUS" operation="profile_replace" name="/usr/bin/evince-thumbnailer//sanitized_helper" pid=22381 comm="apparmor_parser"
Oct 27 14:15:01 XXX dhclient: XMT: Solicit on wlan0, interval 19100ms.
Oct 27 14:15:21 XXX dhclient: XMT: Solicit on wlan0, interval 38310ms.
Oct 27 14:15:28 XXX NetworkManager[1266]: <warn> (wlan0): DHCPv6 request timed out.
Oct 27 14:15:28 XXX NetworkManager[1266]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 22223
Oct 27 14:15:28 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Oct 27 14:15:28 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Oct 27 14:15:28 XXX NetworkManager[1266]: <info> Activation (wlan0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Oct 27 14:16:17 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) starting connection 'Pepephone Default 1'
Oct 27 14:16:17 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Oct 27 14:16:17 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 27 14:16:17 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Oct 27 14:16:17 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Oct 27 14:16:17 XXX NetworkManager[1266]: <warn> GSM connection failed: (32) Sending command failed: device is not enabled
Oct 27 14:16:17 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: prepare -> failed (reason 'unknown') [40 120 1]
Oct 27 14:16:17 XXX NetworkManager[1266]: <warn> Activation (ttyUSB0) failed.
Oct 27 14:16:17 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Oct 27 14:16:17 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'none') [0]
Oct 27 14:16:17 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 14:16:17 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 14:16:17 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (connected -> disconnecting)
Oct 27 14:16:17 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (disconnecting -> connected)
Oct 27 14:16:17 XXX NetworkManager[1266]: <info> Policy set 'JAZZTEL_62C0' (wlan0) as default for IPv4 routing and DNS.
Oct 27 14:16:17 XXX NetworkManager[1266]: <info> Policy set 'JAZZTEL_62C0' (wlan0) as default for IPv4 routing and DNS.
Oct 27 14:16:17 XXX NetworkManager[1266]: <info> disconnect failed: (32) The serial port is not open.
Oct 27 14:16:20 XXX NetworkManager[1266]: <info> (wlan0): device state change: activated -> disconnected (reason 'user-requested') [100 30 39]
Oct 27 14:16:20 XXX NetworkManager[1266]: <info> (wlan0): deactivating device (reason 'user-requested') [39]
Oct 27 14:16:20 XXX NetworkManager[1266]: <info> DNS: starting dnsmasq...
Oct 27 14:16:20 XXX NetworkManager[1266]: <info> (wlan0): writing resolv.conf to /sbin/resolvconf
Oct 27 14:16:20 XXX kernel: [120820.708504] wlan0: deauthenticating from 64:68:0c:80:62:c1 by local choice (reason=3)
Oct 27 14:16:20 XXX kernel: [120820.760147] cfg80211: All devices are disconnected, going to restore regulatory settings
Oct 27 14:16:20 XXX kernel: [120820.760153] cfg80211: Restoring regulatory settings
Oct 27 14:16:20 XXX kernel: [120820.760157] cfg80211: Calling CRDA to update world regulatory domain
Oct 27 14:16:20 XXX wpa_supplicant[1573]: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3
Oct 27 14:16:20 XXX NetworkManager[1266]: <info> (wlan0): supplicant interface state: completed -> disconnected
Oct 27 14:16:20 XXX kernel: [120820.765017] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
Oct 27 14:16:20 XXX kernel: [120820.765020] cfg80211: World regulatory domain updated:
Oct 27 14:16:20 XXX kernel: [120820.765022] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Oct 27 14:16:20 XXX kernel: [120820.765025] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Oct 27 14:16:20 XXX kernel: [120820.765028] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Oct 27 14:16:20 XXX kernel: [120820.765030] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Oct 27 14:16:20 XXX kernel: [120820.765033] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Oct 27 14:16:20 XXX kernel: [120820.765035] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Oct 27 14:16:28 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) starting connection 'Pepephone Default 1'
Oct 27 14:16:28 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Oct 27 14:16:28 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 27 14:16:28 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Oct 27 14:16:28 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Oct 27 14:16:28 XXX NetworkManager[1266]: <warn> GSM connection failed: (32) Sending command failed: device is not enabled
Oct 27 14:16:28 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: prepare -> failed (reason 'unknown') [40 120 1]
Oct 27 14:16:28 XXX NetworkManager[1266]: <warn> Activation (ttyUSB0) failed.
Oct 27 14:16:28 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Oct 27 14:16:28 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'none') [0]
Oct 27 14:16:28 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 14:16:28 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 14:16:28 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (connected -> disconnecting)
Oct 27 14:16:28 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (disconnecting -> connected)
Oct 27 14:16:28 XXX NetworkManager[1266]: <info> disconnect failed: (32) The serial port is not open.
Oct 27 14:16:34 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) starting connection 'Pepephone Default 1'
Oct 27 14:16:34 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Oct 27 14:16:34 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 27 14:16:34 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Oct 27 14:16:34 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Oct 27 14:16:34 XXX NetworkManager[1266]: <warn> GSM connection failed: (32) Sending command failed: device is not enabled
Oct 27 14:16:34 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: prepare -> failed (reason 'unknown') [40 120 1]
Oct 27 14:16:34 XXX NetworkManager[1266]: <warn> Activation (ttyUSB0) failed.
Oct 27 14:16:34 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Oct 27 14:16:34 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'none') [0]
Oct 27 14:16:34 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 14:16:34 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 14:16:34 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (connected -> disconnecting)
Oct 27 14:16:34 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (disconnecting -> connected)
Oct 27 14:16:34 XXX NetworkManager[1266]: <info> disconnect failed: (32) The serial port is not open.
Oct 27 14:16:47 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) starting connection 'Pepephone Default 1'
Oct 27 14:16:47 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Oct 27 14:16:47 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 27 14:16:47 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Oct 27 14:16:47 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Oct 27 14:16:47 XXX NetworkManager[1266]: <warn> GSM connection failed: (32) Sending command failed: device is not enabled
Oct 27 14:16:47 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: prepare -> failed (reason 'unknown') [40 120 1]
Oct 27 14:16:47 XXX NetworkManager[1266]: <info> Marking connection 'Pepephone Default 1' invalid.
Oct 27 14:16:47 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (connected -> disconnecting)
Oct 27 14:16:47 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (disconnecting -> connected)
Oct 27 14:16:47 XXX NetworkManager[1266]: <warn> Activation (ttyUSB0) failed.
Oct 27 14:16:47 XXX NetworkManager[1266]: <info> disconnect failed: (32) The serial port is not open.
Oct 27 14:16:47 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Oct 27 14:16:47 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'none') [0]
Oct 27 14:16:47 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 14:16:47 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 14:17:01 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) starting connection 'Pepephone Default 1'
Oct 27 14:17:01 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Oct 27 14:17:01 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 27 14:17:01 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Oct 27 14:17:01 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Oct 27 14:17:01 XXX NetworkManager[1266]: <warn> GSM connection failed: (32) Sending command failed: device is not enabled
Oct 27 14:17:01 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: prepare -> failed (reason 'unknown') [40 120 1]
Oct 27 14:17:01 XXX NetworkManager[1266]: <info> Marking connection 'Pepephone Default 1' invalid.
Oct 27 14:17:01 XXX NetworkManager[1266]: <warn> Activation (ttyUSB0) failed.
Oct 27 14:17:01 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Oct 27 14:17:01 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'none') [0]
Oct 27 14:17:01 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 14:17:01 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 14:17:01 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (connected -> disconnecting)
Oct 27 14:17:01 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (disconnecting -> connected)
Oct 27 14:17:01 XXX NetworkManager[1266]: <info> disconnect failed: (32) The serial port is not open.
Oct 27 14:17:18 XXX kernel: [120879.092368] type=1701 audit(1351340238.654:87): auid=4294967295 uid=1000 gid=1000 ses=4294967295 pid=22993 comm="chrome" reason="seccomp" sig=0 syscall=20 ip=0x894416 code=0x50001
Oct 27 14:17:25 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) starting connection 'Pepephone Default 1'
Oct 27 14:17:25 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Oct 27 14:17:25 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 27 14:17:25 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Oct 27 14:17:25 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Oct 27 14:17:25 XXX NetworkManager[1266]: <warn> GSM connection failed: (32) Sending command failed: device is not enabled
Oct 27 14:17:25 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: prepare -> failed (reason 'unknown') [40 120 1]
Oct 27 14:17:25 XXX NetworkManager[1266]: <info> Marking connection 'Pepephone Default 1' invalid.
Oct 27 14:17:25 XXX NetworkManager[1266]: <warn> Activation (ttyUSB0) failed.
Oct 27 14:17:25 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Oct 27 14:17:25 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'none') [0]
Oct 27 14:17:25 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 14:17:25 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 14:17:25 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (connected -> disconnecting)
Oct 27 14:17:25 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (disconnecting -> connected)
Oct 27 14:17:25 XXX NetworkManager[1266]: <info> disconnect failed: (32) The serial port is not open.
Oct 27 14:17:30 XXX kernel: [120891.146365] usb 2-4: USB disconnect, device number 46
Oct 27 14:17:30 XXX kernel: [120891.146527] option: option_instat_callback: error -108
Oct 27 14:17:30 XXX kernel: [120891.146838] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
Oct 27 14:17:30 XXX kernel: [120891.146863] option 2-4:1.0: device disconnected
Oct 27 14:17:30 XXX kernel: [120891.146993] option: option_instat_callback: error -108
Oct 27 14:17:30 XXX modem-manager[3539]: <info>  (tty/ttyUSB0): released by modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4
Oct 27 14:17:30 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/17: state changed (connected -> disabled)
Oct 27 14:17:30 XXX modem-manager[3539]: <info>  (ttyUSB3) closing serial port...
Oct 27 14:17:30 XXX NetworkManager[1266]: <info> (ttyUSB0): now unmanaged
Oct 27 14:17:30 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: disconnected -> unmanaged (reason 'removed') [30 10 36]
Oct 27 14:17:30 XXX NetworkManager[1266]: <info> (ttyUSB0): cleaning up...
Oct 27 14:17:30 XXX NetworkManager[1266]: <info> (ttyUSB0): taking down device.
Oct 27 14:17:30 XXX kernel: [120891.148811] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
Oct 27 14:17:30 XXX kernel: [120891.148833] option 2-4:1.1: device disconnected
Oct 27 14:17:30 XXX kernel: [120891.149733] option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
Oct 27 14:17:30 XXX kernel: [120891.149754] option 2-4:1.2: device disconnected
Oct 27 14:17:30 XXX modem-manager[3539]: <info>  (ttyUSB3) serial port closed
Oct 27 14:17:30 XXX kernel: [120891.152281] option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3
Oct 27 14:17:30 XXX kernel: [120891.152300] option 2-4:1.3: device disconnected
Oct 27 14:17:30 XXX NetworkManager[1266]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Oct 27 14:17:30 XXX NetworkManager[1266]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Oct 27 14:17:37 XXX kernel: [120897.812078] usb 2-4: new high-speed USB device number 48 using ehci_hcd
Oct 27 14:17:37 XXX kernel: [120897.966865] scsi137 : usb-storage 2-4:1.0
Oct 27 14:17:37 XXX kernel: [120897.967813] usb 2-4: USB disconnect, device number 48
Oct 27 14:17:45 XXX kernel: [120905.720060] usb 2-4: new high-speed USB device number 49 using ehci_hcd
Oct 27 14:17:45 XXX kernel: [120905.872674] option 2-4:1.0: GSM modem (1-port) converter detected
Oct 27 14:17:45 XXX kernel: [120905.873003] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB0
Oct 27 14:17:45 XXX kernel: [120905.873508] option 2-4:1.1: GSM modem (1-port) converter detected
Oct 27 14:17:45 XXX kernel: [120905.873810] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB1
Oct 27 14:17:45 XXX kernel: [120905.874456] option 2-4:1.2: GSM modem (1-port) converter detected
Oct 27 14:17:45 XXX kernel: [120905.874740] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB2
Oct 27 14:17:45 XXX kernel: [120905.875192] option 2-4:1.3: GSM modem (1-port) converter detected
Oct 27 14:17:45 XXX kernel: [120905.875462] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB3
Oct 27 14:17:45 XXX kernel: [120905.876803] scsi142 : usb-storage 2-4:1.4
Oct 27 14:17:45 XXX kernel: [120905.878260] scsi143 : usb-storage 2-4:1.5
Oct 27 14:17:45 XXX modem-manager[3539]: <info>  (ttyUSB0) opening serial port...
Oct 27 14:17:46 XXX kernel: [120906.879378] scsi 142:0:0:0: CD-ROM            HUAWEI   Mass Storage     2.31 PQ: 0 ANSI: 2
Oct 27 14:17:46 XXX kernel: [120906.886482] scsi 143:0:0:0: Direct-Access     HUAWEI   MMC Storage      2.31 PQ: 0 ANSI: 2
Oct 27 14:17:46 XXX kernel: [120906.892210] sr1: scsi-1 drive
Oct 27 14:17:46 XXX kernel: [120906.902468] sr 142:0:0:0: Attached scsi CD-ROM sr1
Oct 27 14:17:46 XXX kernel: [120906.904484] sr 142:0:0:0: Attached scsi generic sg2 type 5
Oct 27 14:17:46 XXX kernel: [120906.907406] sd 143:0:0:0: Attached scsi generic sg3 type 0
Oct 27 14:17:46 XXX kernel: [120906.917585] sd 143:0:0:0: [sdb] Attached SCSI removable disk
Oct 27 14:17:47 XXX modem-manager[3539]: <info>  (ttyUSB0) closing serial port...
Oct 27 14:17:47 XXX modem-manager[3539]: <info>  (ttyUSB0) serial port closed
Oct 27 14:17:48 XXX modem-manager[3539]: <info>  (ttyUSB0) opening serial port...
Oct 27 14:17:48 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB0
Oct 27 14:17:48 XXX modem-manager[3539]: <info>  (ttyUSB3) opening serial port...
Oct 27 14:17:48 XXX modem-manager[3539]: <info>  (ttyUSB1) opening serial port...
Oct 27 14:17:48 XXX modem-manager[3539]: <info>  (ttyUSB2) opening serial port...
Oct 27 14:17:48 XXX modem-manager[3539]: <info>  (ttyUSB0) closing serial port...
Oct 27 14:17:48 XXX modem-manager[3539]: <info>  (ttyUSB0) serial port closed
Oct 27 14:17:51 XXX modem-manager[3539]: <info>  (ttyUSB3) closing serial port...
Oct 27 14:17:51 XXX modem-manager[3539]: <info>  (ttyUSB3) serial port closed
Oct 27 14:17:51 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB3
Oct 27 14:17:55 XXX modem-manager[3539]: <info>  (ttyUSB1) closing serial port...
Oct 27 14:17:55 XXX modem-manager[3539]: <info>  (ttyUSB1) serial port closed
Oct 27 14:17:55 XXX modem-manager[3539]: <info>  (ttyUSB2) closing serial port...
Oct 27 14:17:55 XXX modem-manager[3539]: <info>  (ttyUSB2) serial port closed
Oct 27 14:17:55 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB1
Oct 27 14:17:55 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB2
Oct 27 14:17:55 XXX NetworkManager[1266]: <warn> (ttyUSB0): failed to look up interface index
Oct 27 14:17:55 XXX NetworkManager[1266]: <info> WWAN now disabled by management service
Oct 27 14:17:55 XXX NetworkManager[1266]: <info> (ttyUSB0): new GSM/UMTS device (driver: 'option1' ifindex: 0)
Oct 27 14:17:55 XXX NetworkManager[1266]: <info> (ttyUSB0): exported as /org/freedesktop/NetworkManager/Devices/21
Oct 27 14:17:55 XXX NetworkManager[1266]: <info> (ttyUSB0): now managed
Oct 27 14:17:55 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Oct 27 14:17:55 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'managed') [2]
Oct 27 14:17:55 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 14:17:55 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 14:17:55 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Oct 27 14:18:00 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) starting connection 'Pepephone Default 1'
Oct 27 14:18:00 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Oct 27 14:18:00 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 27 14:18:00 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Oct 27 14:18:00 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Oct 27 14:18:00 XXX modem-manager[3539]: <info>  (ttyUSB0) opening serial port...
Oct 27 14:18:00 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/18: state changed (disabled -> enabling)
Oct 27 14:18:00 XXX modem-manager[3539]: <info>  (ttyUSB3) opening serial port...
Oct 27 14:18:00 XXX modem-manager[3539]: <info>  (ttyUSB0): using text mode for SMS
Oct 27 14:18:00 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/18: state changed (enabling -> enabled)
Oct 27 14:18:00 XXX NetworkManager[1266]: <info> WWAN now enabled by management service
Oct 27 14:18:01 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/18: state changed (enabled -> registered)
Oct 27 14:18:01 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/18: state changed (registered -> connecting)
Oct 27 14:18:01 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/18: state changed (connecting -> connected)
Oct 27 14:18:01 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled...
Oct 27 14:18:01 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting...
Oct 27 14:18:01 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: prepare -> config (reason 'none') [40 50 0]
Oct 27 14:18:01 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) successful.
Oct 27 14:18:01 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) scheduled.
Oct 27 14:18:01 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
Oct 27 14:18:01 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) started...
Oct 27 14:18:01 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: config -> ip-config (reason 'none') [50 70 0]
Oct 27 14:18:01 XXX NetworkManager[1266]: <info> starting PPP connection
Oct 27 14:18:01 XXX NetworkManager[1266]: <info> pppd started with pid 23161
Oct 27 14:18:01 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Oct 27 14:18:01 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) complete.
Oct 27 14:18:01 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Oct 27 14:18:01 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Oct 27 14:18:01 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Oct 27 14:18:01 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
Oct 27 14:18:03 XXX NetworkManager[1266]: <info> PPP manager(IP Config Get) reply received.
Oct 27 14:18:03 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Oct 27 14:18:03 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) started...
Oct 27 14:18:04 XXX NetworkManager[1266]: <info> DNS: starting dnsmasq...
Oct 27 14:18:04 XXX NetworkManager[1266]: <info> (ppp0): writing resolv.conf to /sbin/resolvconf
Oct 27 14:18:04 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Oct 27 14:18:04 XXX NetworkManager[1266]: <info> Policy set 'Pepephone Default 1' (ppp0) as default for IPv4 routing and DNS.
Oct 27 14:18:04 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) successful, device activated.
Oct 27 14:18:04 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) complete.
Oct 27 14:19:58 XXX kernel: [121039.195360] type=1701 audit(1351340398.751:88): auid=4294967295 uid=1000 gid=1000 ses=4294967295 pid=24022 comm="chrome" reason="seccomp" sig=0 syscall=20 ip=0x97f416 code=0x50001
Oct 27 14:31:15 XXX kernel: [121715.814146] type=1701 audit(1351341075.372:89): auid=4294967295 uid=1000 gid=1000 ses=4294967295 pid=26757 comm="chrome" reason="seccomp" sig=0 syscall=20 ip=0x2a4416 code=0x50001
Oct 27 14:31:45 XXX kernel: [121746.309307] type=1701 audit(1351341105.869:90): auid=4294967295 uid=1000 gid=1000 ses=4294967295 pid=26806 comm="chrome" reason="seccomp" sig=0 syscall=20 ip=0xb32416 code=0x50001
Oct 27 15:48:02 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: activated -> failed (reason 'ip-config-unavailable') [100 120 5]
Oct 27 15:48:02 XXX modem-manager[3539]: <info>  (ttyUSB0) closing serial port...
Oct 27 15:48:02 XXX modem-manager[3539]: <info>  (ttyUSB0) serial port closed
Oct 27 15:48:02 XXX NetworkManager[1266]: <warn> Activation (ttyUSB0) failed.
Oct 27 15:48:02 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/18: state changed (connected -> disconnecting)
Oct 27 15:48:02 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Oct 27 15:48:02 XXX NetworkManager[1266]: <info> (ttyUSB0): now unmanaged
Oct 27 15:48:02 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: failed -> unmanaged (reason 'removed') [120 10 36]
Oct 27 15:48:02 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'removed') [36]
Oct 27 15:48:02 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/18: state changed (disconnecting -> connected)
Oct 27 15:48:03 XXX NetworkManager[1266]: <warn> could not read ppp stats: No such device
Oct 27 15:48:03 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 15:48:03 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 15:48:03 XXX NetworkManager[1266]: <info> DNS: starting dnsmasq...
Oct 27 15:48:03 XXX NetworkManager[1266]: <info> (ttyUSB0): writing resolv.conf to /sbin/resolvconf
Oct 27 15:48:03 XXX NetworkManager[1266]: <info> (ttyUSB0): cleaning up...
Oct 27 15:48:03 XXX NetworkManager[1266]: <info> (ttyUSB0): taking down device.
Oct 27 15:48:03 XXX NetworkManager[1266]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Oct 27 15:48:03 XXX NetworkManager[1266]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Oct 27 15:48:03 XXX NetworkManager[1266]: <info> disconnect failed: (32) The serial port is not open.
Oct 27 15:48:51 XXX modem-manager[3539]: <info>  (tty/ttyUSB0): released by modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4
Oct 27 15:48:51 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/18: state changed (connected -> disabled)
Oct 27 15:48:51 XXX modem-manager[3539]: <info>  (ttyUSB3) closing serial port...
Oct 27 15:48:51 XXX kernel: [126372.420075] usb 2-4: USB disconnect, device number 49
Oct 27 15:48:51 XXX kernel: [126372.420562] option: option_instat_callback: error -108
Oct 27 15:48:51 XXX kernel: [126372.421016] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
Oct 27 15:48:51 XXX kernel: [126372.421035] option 2-4:1.0: device disconnected
Oct 27 15:48:51 XXX kernel: [126372.421160] option: option_instat_callback: error -108
Oct 27 15:48:51 XXX modem-manager[3539]: <info>  (ttyUSB3) serial port closed
Oct 27 15:48:51 XXX kernel: [126372.426185] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
Oct 27 15:48:51 XXX kernel: [126372.426205] option 2-4:1.1: device disconnected
Oct 27 15:48:51 XXX kernel: [126372.428058] option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
Oct 27 15:48:51 XXX kernel: [126372.428074] option 2-4:1.2: device disconnected
Oct 27 15:48:51 XXX kernel: [126372.428273] option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3
Oct 27 15:48:51 XXX kernel: [126372.428288] option 2-4:1.3: device disconnected
Oct 27 15:48:57 XXX kernel: [126378.420062] usb 2-4: new high-speed USB device number 50 using ehci_hcd
Oct 27 15:48:58 XXX kernel: [126378.568487] scsi144 : usb-storage 2-4:1.0
Oct 27 15:48:58 XXX kernel: [126378.569754] usb 2-4: USB disconnect, device number 50
Oct 27 15:49:05 XXX kernel: [126386.184067] usb 2-4: new high-speed USB device number 51 using ehci_hcd
Oct 27 15:49:05 XXX kernel: [126386.332406] option 2-4:1.0: GSM modem (1-port) converter detected
Oct 27 15:49:05 XXX kernel: [126386.332739] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB0
Oct 27 15:49:05 XXX kernel: [126386.333651] option 2-4:1.1: GSM modem (1-port) converter detected
Oct 27 15:49:05 XXX kernel: [126386.334227] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB1
Oct 27 15:49:05 XXX kernel: [126386.334627] option 2-4:1.2: GSM modem (1-port) converter detected
Oct 27 15:49:05 XXX kernel: [126386.335118] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB2
Oct 27 15:49:05 XXX kernel: [126386.335480] option 2-4:1.3: GSM modem (1-port) converter detected
Oct 27 15:49:05 XXX kernel: [126386.335990] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB3
Oct 27 15:49:05 XXX kernel: [126386.336856] scsi149 : usb-storage 2-4:1.4
Oct 27 15:49:05 XXX kernel: [126386.337861] scsi150 : usb-storage 2-4:1.5
Oct 27 15:49:06 XXX modem-manager[3539]: <info>  (ttyUSB0) opening serial port...
Oct 27 15:49:06 XXX kernel: [126387.342811] scsi 149:0:0:0: CD-ROM            HUAWEI   Mass Storage     2.31 PQ: 0 ANSI: 2
Oct 27 15:49:06 XXX kernel: [126387.344616] scsi 150:0:0:0: Direct-Access     HUAWEI   MMC Storage      2.31 PQ: 0 ANSI: 2
Oct 27 15:49:06 XXX kernel: [126387.356082] sr1: scsi-1 drive
Oct 27 15:49:06 XXX kernel: [126387.360411] sr 149:0:0:0: Attached scsi CD-ROM sr1
Oct 27 15:49:06 XXX kernel: [126387.361648] sr 149:0:0:0: Attached scsi generic sg2 type 5
Oct 27 15:49:06 XXX kernel: [126387.364265] sd 150:0:0:0: Attached scsi generic sg3 type 0
Oct 27 15:49:06 XXX kernel: [126387.372804] sd 150:0:0:0: [sdb] Attached SCSI removable disk
Oct 27 15:49:08 XXX modem-manager[3539]: <info>  (ttyUSB0) closing serial port...
Oct 27 15:49:08 XXX modem-manager[3539]: <info>  (ttyUSB0) serial port closed
Oct 27 15:49:08 XXX modem-manager[3539]: <info>  (ttyUSB0) opening serial port...
Oct 27 15:49:08 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB0
Oct 27 15:49:09 XXX modem-manager[3539]: <info>  (ttyUSB3) opening serial port...
Oct 27 15:49:09 XXX modem-manager[3539]: <info>  (ttyUSB1) opening serial port...
Oct 27 15:49:09 XXX modem-manager[3539]: <info>  (ttyUSB2) opening serial port...
Oct 27 15:49:09 XXX modem-manager[3539]: <info>  (ttyUSB0) closing serial port...
Oct 27 15:49:09 XXX modem-manager[3539]: <info>  (ttyUSB0) serial port closed
Oct 27 15:49:12 XXX modem-manager[3539]: <info>  (ttyUSB3) closing serial port...
Oct 27 15:49:12 XXX modem-manager[3539]: <info>  (ttyUSB3) serial port closed
Oct 27 15:49:12 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB3
Oct 27 15:49:16 XXX modem-manager[3539]: <info>  (ttyUSB1) closing serial port...
Oct 27 15:49:16 XXX modem-manager[3539]: <info>  (ttyUSB1) serial port closed
Oct 27 15:49:16 XXX modem-manager[3539]: <info>  (ttyUSB2) closing serial port...
Oct 27 15:49:16 XXX modem-manager[3539]: <info>  (ttyUSB2) serial port closed
Oct 27 15:49:16 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB1
Oct 27 15:49:16 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB2
Oct 27 15:49:16 XXX NetworkManager[1266]: <warn> (ttyUSB0): failed to look up interface index
Oct 27 15:49:16 XXX NetworkManager[1266]: <info> WWAN now disabled by management service
Oct 27 15:49:16 XXX NetworkManager[1266]: <info> (ttyUSB0): new GSM/UMTS device (driver: 'option1' ifindex: 0)
Oct 27 15:49:16 XXX NetworkManager[1266]: <info> (ttyUSB0): exported as /org/freedesktop/NetworkManager/Devices/22
Oct 27 15:49:16 XXX NetworkManager[1266]: <info> (ttyUSB0): now managed
Oct 27 15:49:16 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Oct 27 15:49:16 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'managed') [2]
Oct 27 15:49:16 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 15:49:16 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 15:49:16 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Oct 27 15:49:21 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) starting connection 'Pepephone Default 1'
Oct 27 15:49:21 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Oct 27 15:49:21 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 27 15:49:21 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Oct 27 15:49:21 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Oct 27 15:49:21 XXX modem-manager[3539]: <info>  (ttyUSB0) opening serial port...
Oct 27 15:49:21 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/19: state changed (disabled -> enabling)
Oct 27 15:49:21 XXX modem-manager[3539]: <info>  (ttyUSB3) opening serial port...
Oct 27 15:49:22 XXX modem-manager[3539]: <info>  (ttyUSB0): using text mode for SMS
Oct 27 15:49:22 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/19: state changed (enabling -> enabled)
Oct 27 15:49:22 XXX NetworkManager[1266]: <info> WWAN now enabled by management service
Oct 27 15:49:22 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/19: state changed (enabled -> registered)
Oct 27 15:49:22 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/19: state changed (registered -> connecting)
Oct 27 15:49:22 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/19: state changed (connecting -> connected)
Oct 27 15:49:22 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled...
Oct 27 15:49:22 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting...
Oct 27 15:49:22 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: prepare -> config (reason 'none') [40 50 0]
Oct 27 15:49:22 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) successful.
Oct 27 15:49:22 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) scheduled.
Oct 27 15:49:22 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
Oct 27 15:49:22 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) started...
Oct 27 15:49:22 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: config -> ip-config (reason 'none') [50 70 0]
Oct 27 15:49:22 XXX NetworkManager[1266]: <info> starting PPP connection
Oct 27 15:49:22 XXX NetworkManager[1266]: <info> pppd started with pid 32659
Oct 27 15:49:22 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Oct 27 15:49:22 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) complete.
Oct 27 15:49:22 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Oct 27 15:49:22 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Oct 27 15:49:22 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Oct 27 15:49:22 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
Oct 27 15:49:24 XXX NetworkManager[1266]: <info> PPP manager(IP Config Get) reply received.
Oct 27 15:49:24 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Oct 27 15:49:24 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) started...
Oct 27 15:49:25 XXX NetworkManager[1266]: <info> DNS: starting dnsmasq...
Oct 27 15:49:25 XXX NetworkManager[1266]: <info> (ppp0): writing resolv.conf to /sbin/resolvconf
Oct 27 15:49:25 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Oct 27 15:49:26 XXX NetworkManager[1266]: <info> Policy set 'Pepephone Default 1' (ppp0) as default for IPv4 routing and DNS.
Oct 27 15:49:26 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) successful, device activated.
Oct 27 15:49:26 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) complete.
Oct 27 15:53:08 XXX kernel: [126628.949316] type=1701 audit(1351345988.510:91): auid=4294967295 uid=1000 gid=1000 ses=4294967295 pid=335 comm="chrome" reason="seccomp" sig=0 syscall=20 ip=0x43e416 code=0x50001
Oct 27 15:53:10 XXX kernel: [126630.750333] type=1701 audit(1351345990.310:92): auid=4294967295 uid=1000 gid=1000 ses=4294967295 pid=371 comm="chrome" reason="seccomp" sig=0 syscall=20 ip=0x647416 code=0x50001
Oct 27 15:54:04 XXX kernel: [126684.868079] usb 2-2: new high-speed USB device number 52 using ehci_hcd
Oct 27 15:54:04 XXX kernel: [126685.396043] usb 2-2: device not accepting address 52, error -71
Oct 27 15:54:05 XXX kernel: [126685.508122] usb 2-2: new high-speed USB device number 53 using ehci_hcd
Oct 27 15:54:05 XXX kernel: [126685.641105] hub 2-2:1.0: USB hub found
Oct 27 15:54:05 XXX kernel: [126685.641226] hub 2-2:1.0: 4 ports detected
Oct 27 15:54:05 XXX kernel: [126685.928255] usb 2-2.1: new full-speed USB device number 54 using ehci_hcd
Oct 27 15:54:05 XXX kernel: [126686.082172] usblp0: USB Bidirectional printer dev 54 if 0 alt 0 proto 2 vid 0x03F0 pid 0x1604
Oct 27 15:54:05 XXX kernel: [126686.156852] usb 2-2.4: new high-speed USB device number 55 using ehci_hcd
Oct 27 15:54:05 XXX kernel: [126686.265101] hub 2-2.4:1.0: USB hub found
Oct 27 15:54:05 XXX kernel: [126686.265228] hub 2-2.4:1.0: 4 ports detected
Oct 27 15:54:07 XXX kernel: [126688.060678] usblp0: removed
Oct 27 15:54:07 XXX kernel: [126688.072869] usblp0: USB Bidirectional printer dev 54 if 0 alt 0 proto 2 vid 0x03F0 pid 0x1604
Oct 27 15:54:26 XXX kernel: [126706.843187] usblp0: removed
Oct 27 16:55:30 XXX kernel: [130371.143753] usb 2-2: USB disconnect, device number 53
Oct 27 16:55:30 XXX kernel: [130371.143762] usb 2-2.1: USB disconnect, device number 54
Oct 27 16:55:30 XXX kernel: [130371.144242] usb 2-2.4: USB disconnect, device number 55
Oct 27 16:55:30 XXX kernel: [130371.400131] usb 2-2: new high-speed USB device number 56 using ehci_hcd
Oct 27 16:55:31 XXX kernel: [130371.533356] hub 2-2:1.0: USB hub found
Oct 27 16:55:31 XXX kernel: [130371.533431] hub 2-2:1.0: 4 ports detected
Oct 27 16:55:31 XXX kernel: [130371.820204] usb 2-2.1: new full-speed USB device number 57 using ehci_hcd
Oct 27 16:55:31 XXX kernel: [130371.978157] usblp0: USB Bidirectional printer dev 57 if 0 alt 0 proto 2 vid 0x03F0 pid 0x1604
Oct 27 16:55:31 XXX kernel: [130372.068217] usb 2-2.4: new high-speed USB device number 58 using ehci_hcd
Oct 27 16:55:31 XXX kernel: [130372.176946] hub 2-2.4:1.0: USB hub found
Oct 27 16:55:31 XXX kernel: [130372.177073] hub 2-2.4:1.0: 4 ports detected
Oct 27 16:55:33 XXX kernel: [130373.778959] usblp0: removed
Oct 27 16:55:33 XXX kernel: [130373.787607] usblp0: USB Bidirectional printer dev 57 if 0 alt 0 proto 2 vid 0x03F0 pid 0x1604
Oct 27 17:19:24 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: activated -> failed (reason 'ip-config-unavailable') [100 120 5]
Oct 27 17:19:24 XXX modem-manager[3539]: <info>  (ttyUSB0) closing serial port...
Oct 27 17:19:24 XXX modem-manager[3539]: <info>  (ttyUSB0) serial port closed
Oct 27 17:19:24 XXX NetworkManager[1266]: <warn> Activation (ttyUSB0) failed.
Oct 27 17:19:24 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/19: state changed (connected -> disconnecting)
Oct 27 17:19:24 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/19: state changed (disconnecting -> connected)
Oct 27 17:19:24 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Oct 27 17:19:24 XXX NetworkManager[1266]: <info> (ttyUSB0): now unmanaged
Oct 27 17:19:24 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: failed -> unmanaged (reason 'removed') [120 10 36]
Oct 27 17:19:24 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'removed') [36]
Oct 27 17:19:24 XXX NetworkManager[1266]: <warn> could not read ppp stats: No such device
Oct 27 17:19:24 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 17:19:24 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 17:19:24 XXX NetworkManager[1266]: <info> DNS: starting dnsmasq...
Oct 27 17:19:24 XXX NetworkManager[1266]: <info> (ttyUSB0): writing resolv.conf to /sbin/resolvconf
Oct 27 17:19:24 XXX NetworkManager[1266]: <info> (ttyUSB0): cleaning up...
Oct 27 17:19:24 XXX NetworkManager[1266]: <info> (ttyUSB0): taking down device.
Oct 27 17:19:24 XXX NetworkManager[1266]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Oct 27 17:19:24 XXX NetworkManager[1266]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Oct 27 17:19:24 XXX NetworkManager[1266]: <info> disconnect failed: (32) The serial port is not open.
Oct 27 17:19:40 XXX kernel: [131820.798030] usb 2-4: USB disconnect, device number 51
Oct 27 17:19:40 XXX kernel: [131820.798169] option: option_instat_callback: error -108
Oct 27 17:19:40 XXX kernel: [131820.798471] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
Oct 27 17:19:40 XXX kernel: [131820.798490] option 2-4:1.0: device disconnected
Oct 27 17:19:40 XXX kernel: [131820.798618] option: option_instat_callback: error -108
Oct 27 17:19:40 XXX kernel: [131820.798780] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
Oct 27 17:19:40 XXX kernel: [131820.798798] option 2-4:1.1: device disconnected
Oct 27 17:19:40 XXX kernel: [131820.799012] option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
Oct 27 17:19:40 XXX kernel: [131820.799030] option 2-4:1.2: device disconnected
Oct 27 17:19:40 XXX modem-manager[3539]: <info>  (tty/ttyUSB0): released by modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4
Oct 27 17:19:40 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/19: state changed (connected -> disabled)
Oct 27 17:19:40 XXX modem-manager[3539]: <info>  (ttyUSB3) closing serial port...
Oct 27 17:19:40 XXX modem-manager[3539]: <info>  (ttyUSB3) serial port closed
Oct 27 17:19:40 XXX kernel: [131820.804212] option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3
Oct 27 17:19:40 XXX kernel: [131820.804231] option 2-4:1.3: device disconnected
Oct 27 17:19:46 XXX kernel: [131826.448065] usb 2-4: new high-speed USB device number 59 using ehci_hcd
Oct 27 17:19:46 XXX kernel: [131826.596400] scsi151 : usb-storage 2-4:1.0
Oct 27 17:19:46 XXX kernel: [131826.596973] usb 2-4: USB disconnect, device number 59
Oct 27 17:19:53 XXX kernel: [131834.284078] usb 2-4: new high-speed USB device number 60 using ehci_hcd
Oct 27 17:19:54 XXX kernel: [131834.444087] option 2-4:1.0: GSM modem (1-port) converter detected
Oct 27 17:19:54 XXX kernel: [131834.444784] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB0
Oct 27 17:19:54 XXX kernel: [131834.447801] option 2-4:1.1: GSM modem (1-port) converter detected
Oct 27 17:19:54 XXX kernel: [131834.448174] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB1
Oct 27 17:19:54 XXX kernel: [131834.448312] option 2-4:1.2: GSM modem (1-port) converter detected
Oct 27 17:19:54 XXX kernel: [131834.448476] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB2
Oct 27 17:19:54 XXX kernel: [131834.448596] option 2-4:1.3: GSM modem (1-port) converter detected
Oct 27 17:19:54 XXX kernel: [131834.448761] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB3
Oct 27 17:19:54 XXX kernel: [131834.449355] scsi156 : usb-storage 2-4:1.4
Oct 27 17:19:54 XXX kernel: [131834.450224] scsi157 : usb-storage 2-4:1.5
Oct 27 17:19:54 XXX modem-manager[3539]: <info>  (ttyUSB0) opening serial port...
Oct 27 17:19:55 XXX kernel: [131835.453502] scsi 156:0:0:0: CD-ROM            HUAWEI   Mass Storage     2.31 PQ: 0 ANSI: 2
Oct 27 17:19:55 XXX kernel: [131835.453709] scsi 157:0:0:0: Direct-Access     HUAWEI   MMC Storage      2.31 PQ: 0 ANSI: 2
Oct 27 17:19:55 XXX kernel: [131835.467968] sr1: scsi-1 drive
Oct 27 17:19:55 XXX kernel: [131835.468266] sr 156:0:0:0: Attached scsi CD-ROM sr1
Oct 27 17:19:55 XXX kernel: [131835.469287] sr 156:0:0:0: Attached scsi generic sg2 type 5
Oct 27 17:19:55 XXX kernel: [131835.470455] sd 157:0:0:0: Attached scsi generic sg3 type 0
Oct 27 17:19:55 XXX kernel: [131835.478721] sd 157:0:0:0: [sdb] Attached SCSI removable disk
Oct 27 17:19:56 XXX modem-manager[3539]: <info>  (ttyUSB0) closing serial port...
Oct 27 17:19:56 XXX modem-manager[3539]: <info>  (ttyUSB0) serial port closed
Oct 27 17:19:56 XXX modem-manager[3539]: <info>  (ttyUSB0) opening serial port...
Oct 27 17:19:56 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB0
Oct 27 17:19:57 XXX modem-manager[3539]: <info>  (ttyUSB1) opening serial port...
Oct 27 17:19:57 XXX modem-manager[3539]: <info>  (ttyUSB2) opening serial port...
Oct 27 17:19:57 XXX modem-manager[3539]: <info>  (ttyUSB3) opening serial port...
Oct 27 17:19:57 XXX modem-manager[3539]: <info>  (ttyUSB0) closing serial port...
Oct 27 17:19:57 XXX modem-manager[3539]: <info>  (ttyUSB0) serial port closed
Oct 27 17:20:00 XXX modem-manager[3539]: <info>  (ttyUSB3) closing serial port...
Oct 27 17:20:00 XXX modem-manager[3539]: <info>  (ttyUSB3) serial port closed
Oct 27 17:20:00 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB3
Oct 27 17:20:04 XXX modem-manager[3539]: <info>  (ttyUSB1) closing serial port...
Oct 27 17:20:04 XXX modem-manager[3539]: <info>  (ttyUSB1) serial port closed
Oct 27 17:20:04 XXX modem-manager[3539]: <info>  (ttyUSB2) closing serial port...
Oct 27 17:20:04 XXX modem-manager[3539]: <info>  (ttyUSB2) serial port closed
Oct 27 17:20:04 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB1
Oct 27 17:20:04 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB2
Oct 27 17:20:04 XXX NetworkManager[1266]: <warn> (ttyUSB0): failed to look up interface index
Oct 27 17:20:04 XXX NetworkManager[1266]: <info> WWAN now disabled by management service
Oct 27 17:20:04 XXX NetworkManager[1266]: <info> (ttyUSB0): new GSM/UMTS device (driver: 'option1' ifindex: 0)
Oct 27 17:20:04 XXX NetworkManager[1266]: <info> (ttyUSB0): exported as /org/freedesktop/NetworkManager/Devices/23
Oct 27 17:20:04 XXX NetworkManager[1266]: <info> (ttyUSB0): now managed
Oct 27 17:20:04 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Oct 27 17:20:04 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'managed') [2]
Oct 27 17:20:04 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 17:20:04 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 17:20:04 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Oct 27 17:20:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) starting connection 'Pepephone Default 1'
Oct 27 17:20:55 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Oct 27 17:20:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 27 17:20:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Oct 27 17:20:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Oct 27 17:20:55 XXX modem-manager[3539]: <info>  (ttyUSB0) opening serial port...
Oct 27 17:20:55 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/20: state changed (disabled -> enabling)
Oct 27 17:20:55 XXX modem-manager[3539]: <info>  (ttyUSB3) opening serial port...
Oct 27 17:20:55 XXX modem-manager[3539]: <info>  (ttyUSB0): using text mode for SMS
Oct 27 17:20:55 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/20: state changed (enabling -> enabled)
Oct 27 17:20:55 XXX NetworkManager[1266]: <info> WWAN now enabled by management service
Oct 27 17:20:55 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/20: state changed (enabled -> registered)
Oct 27 17:20:56 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/20: state changed (registered -> connecting)
Oct 27 17:20:56 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/20: state changed (connecting -> connected)
Oct 27 17:20:56 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled...
Oct 27 17:20:56 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting...
Oct 27 17:20:56 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: prepare -> config (reason 'none') [40 50 0]
Oct 27 17:20:56 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) successful.
Oct 27 17:20:56 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) scheduled.
Oct 27 17:20:56 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
Oct 27 17:20:56 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) started...
Oct 27 17:20:56 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: config -> ip-config (reason 'none') [50 70 0]
Oct 27 17:20:56 XXX NetworkManager[1266]: <info> starting PPP connection
Oct 27 17:20:56 XXX NetworkManager[1266]: <info> pppd started with pid 5434
Oct 27 17:20:56 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Oct 27 17:20:56 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) complete.
Oct 27 17:20:56 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Oct 27 17:20:56 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Oct 27 17:20:56 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Oct 27 17:20:56 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
Oct 27 17:20:58 XXX NetworkManager[1266]: <info> PPP manager(IP Config Get) reply received.
Oct 27 17:20:58 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Oct 27 17:20:58 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) started...
Oct 27 17:20:59 XXX NetworkManager[1266]: <info> DNS: starting dnsmasq...
Oct 27 17:20:59 XXX NetworkManager[1266]: <info> (ppp0): writing resolv.conf to /sbin/resolvconf
Oct 27 17:20:59 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Oct 27 17:20:59 XXX NetworkManager[1266]: <info> Policy set 'Pepephone Default 1' (ppp0) as default for IPv4 routing and DNS.
Oct 27 17:20:59 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) successful, device activated.
Oct 27 17:20:59 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) complete.
Oct 27 18:02:56 XXX kernel: [134417.270317] usb 2-2: USB disconnect, device number 56
Oct 27 18:02:56 XXX kernel: [134417.270327] usb 2-2.1: USB disconnect, device number 57
Oct 27 18:02:56 XXX kernel: [134417.271100] usblp0: removed
Oct 27 18:02:56 XXX kernel: [134417.271423] usb 2-2.4: USB disconnect, device number 58
Oct 27 18:02:57 XXX kernel: [134417.580116] usb 2-2: new high-speed USB device number 61 using ehci_hcd
Oct 27 18:02:57 XXX kernel: [134417.713139] hub 2-2:1.0: USB hub found
Oct 27 18:02:57 XXX kernel: [134417.713257] hub 2-2:1.0: 4 ports detected
Oct 27 18:02:57 XXX kernel: [134418.000270] usb 2-2.1: new full-speed USB device number 62 using ehci_hcd
Oct 27 18:02:57 XXX kernel: [134418.146697] usblp0: USB Bidirectional printer dev 62 if 0 alt 0 proto 2 vid 0x03F0 pid 0x1604
Oct 27 18:02:57 XXX kernel: [134418.232250] usb 2-2.4: new high-speed USB device number 63 using ehci_hcd
Oct 27 18:02:57 XXX kernel: [134418.340977] hub 2-2.4:1.0: USB hub found
Oct 27 18:02:57 XXX kernel: [134418.341093] hub 2-2.4:1.0: 4 ports detected
Oct 27 18:02:59 XXX kernel: [134420.019809] usblp0: removed
Oct 27 18:02:59 XXX kernel: [134420.035414] usblp0: USB Bidirectional printer dev 62 if 0 alt 0 proto 2 vid 0x03F0 pid 0x1604
Oct 27 18:50:57 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: activated -> failed (reason 'ip-config-unavailable') [100 120 5]
Oct 27 18:50:58 XXX modem-manager[3539]: <info>  (ttyUSB0) closing serial port...
Oct 27 18:50:58 XXX modem-manager[3539]: <info>  (ttyUSB0) serial port closed
Oct 27 18:50:58 XXX NetworkManager[1266]: <warn> Activation (ttyUSB0) failed.
Oct 27 18:50:58 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Oct 27 18:50:58 XXX NetworkManager[1266]: <info> (ttyUSB0): now unmanaged
Oct 27 18:50:58 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: failed -> unmanaged (reason 'removed') [120 10 36]
Oct 27 18:50:58 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'removed') [36]
Oct 27 18:50:58 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/20: state changed (connected -> disconnecting)
Oct 27 18:50:58 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/20: state changed (disconnecting -> connected)
Oct 27 18:50:58 XXX NetworkManager[1266]: <warn> could not read ppp stats: No such device
Oct 27 18:50:58 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 18:50:58 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 18:50:58 XXX NetworkManager[1266]: <info> DNS: starting dnsmasq...
Oct 27 18:50:58 XXX NetworkManager[1266]: <info> (ttyUSB0): writing resolv.conf to /sbin/resolvconf
Oct 27 18:50:58 XXX NetworkManager[1266]: <info> (ttyUSB0): cleaning up...
Oct 27 18:50:58 XXX NetworkManager[1266]: <info> (ttyUSB0): taking down device.
Oct 27 18:50:58 XXX NetworkManager[1266]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Oct 27 18:50:58 XXX NetworkManager[1266]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Oct 27 18:50:58 XXX NetworkManager[1266]: <info> disconnect failed: (32) The serial port is not open.
Oct 27 18:51:07 XXX kernel: [137307.726959] usb 2-4: USB disconnect, device number 60
Oct 27 18:51:07 XXX kernel: [137307.727097] option: option_instat_callback: error -108
Oct 27 18:51:07 XXX kernel: [137307.727418] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
Oct 27 18:51:07 XXX kernel: [137307.727438] option 2-4:1.0: device disconnected
Oct 27 18:51:07 XXX kernel: [137307.727567] option: option_instat_callback: error -108
Oct 27 18:51:07 XXX modem-manager[3539]: <info>  (tty/ttyUSB0): released by modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4
Oct 27 18:51:07 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/20: state changed (connected -> disabled)
Oct 27 18:51:07 XXX modem-manager[3539]: <info>  (ttyUSB3) closing serial port...
Oct 27 18:51:07 XXX kernel: [137307.730002] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
Oct 27 18:51:07 XXX kernel: [137307.730024] option 2-4:1.1: device disconnected
Oct 27 18:51:07 XXX kernel: [137307.730394] option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
Oct 27 18:51:07 XXX kernel: [137307.730413] option 2-4:1.2: device disconnected
Oct 27 18:51:07 XXX kernel: [137307.731183] option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3
Oct 27 18:51:07 XXX kernel: [137307.731193] option 2-4:1.3: device disconnected
Oct 27 18:51:07 XXX modem-manager[3539]: <info>  (ttyUSB3) serial port closed
Oct 27 18:51:13 XXX kernel: [137313.924063] usb 2-4: new high-speed USB device number 64 using ehci_hcd
Oct 27 18:51:13 XXX kernel: [137314.079051] scsi158 : usb-storage 2-4:1.0
Oct 27 18:51:13 XXX kernel: [137314.079666] usb 2-4: USB disconnect, device number 64
Oct 27 18:51:21 XXX kernel: [137321.752109] usb 2-4: new high-speed USB device number 65 using ehci_hcd
Oct 27 18:51:21 XXX kernel: [137321.904136] option 2-4:1.0: GSM modem (1-port) converter detected
Oct 27 18:51:21 XXX kernel: [137321.904408] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB0
Oct 27 18:51:21 XXX kernel: [137321.907094] option 2-4:1.1: GSM modem (1-port) converter detected
Oct 27 18:51:21 XXX kernel: [137321.907337] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB1
Oct 27 18:51:21 XXX kernel: [137321.907860] option 2-4:1.2: GSM modem (1-port) converter detected
Oct 27 18:51:21 XXX kernel: [137321.908089] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB2
Oct 27 18:51:21 XXX kernel: [137321.909067] option 2-4:1.3: GSM modem (1-port) converter detected
Oct 27 18:51:21 XXX kernel: [137321.909336] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB3
Oct 27 18:51:21 XXX kernel: [137321.916101] scsi163 : usb-storage 2-4:1.4
Oct 27 18:51:21 XXX kernel: [137321.920308] scsi164 : usb-storage 2-4:1.5
Oct 27 18:51:21 XXX modem-manager[3539]: <info>  (ttyUSB0) opening serial port...
Oct 27 18:51:22 XXX kernel: [137322.922553] scsi 163:0:0:0: CD-ROM            HUAWEI   Mass Storage     2.31 PQ: 0 ANSI: 2
Oct 27 18:51:22 XXX kernel: [137322.923925] scsi 164:0:0:0: Direct-Access     HUAWEI   MMC Storage      2.31 PQ: 0 ANSI: 2
Oct 27 18:51:22 XXX kernel: [137322.935406] sr1: scsi-1 drive
Oct 27 18:51:22 XXX kernel: [137322.935540] sr 163:0:0:0: Attached scsi CD-ROM sr1
Oct 27 18:51:22 XXX kernel: [137322.935622] sr 163:0:0:0: Attached scsi generic sg2 type 5
Oct 27 18:51:22 XXX kernel: [137322.937466] sd 164:0:0:0: Attached scsi generic sg3 type 0
Oct 27 18:51:22 XXX kernel: [137322.946534] sd 164:0:0:0: [sdb] Attached SCSI removable disk
Oct 27 18:51:24 XXX modem-manager[3539]: <info>  (ttyUSB0) closing serial port...
Oct 27 18:51:24 XXX modem-manager[3539]: <info>  (ttyUSB0) serial port closed
Oct 27 18:51:24 XXX modem-manager[3539]: <info>  (ttyUSB0) opening serial port...
Oct 27 18:51:24 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB0
Oct 27 18:51:24 XXX modem-manager[3539]: <info>  (ttyUSB1) opening serial port...
Oct 27 18:51:24 XXX modem-manager[3539]: <info>  (ttyUSB3) opening serial port...
Oct 27 18:51:24 XXX modem-manager[3539]: <info>  (ttyUSB2) opening serial port...
Oct 27 18:51:25 XXX modem-manager[3539]: <info>  (ttyUSB0) closing serial port...
Oct 27 18:51:25 XXX modem-manager[3539]: <info>  (ttyUSB0) serial port closed
Oct 27 18:51:27 XXX modem-manager[3539]: <info>  (ttyUSB3) closing serial port...
Oct 27 18:51:27 XXX modem-manager[3539]: <info>  (ttyUSB3) serial port closed
Oct 27 18:51:27 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB3
Oct 27 18:51:31 XXX modem-manager[3539]: <info>  (ttyUSB1) closing serial port...
Oct 27 18:51:31 XXX modem-manager[3539]: <info>  (ttyUSB1) serial port closed
Oct 27 18:51:31 XXX modem-manager[3539]: <info>  (ttyUSB2) closing serial port...
Oct 27 18:51:31 XXX modem-manager[3539]: <info>  (ttyUSB2) serial port closed
Oct 27 18:51:31 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB1
Oct 27 18:51:31 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB2
Oct 27 18:51:31 XXX NetworkManager[1266]: <warn> (ttyUSB0): failed to look up interface index
Oct 27 18:51:31 XXX NetworkManager[1266]: <info> WWAN now disabled by management service
Oct 27 18:51:31 XXX NetworkManager[1266]: <info> (ttyUSB0): new GSM/UMTS device (driver: 'option1' ifindex: 0)
Oct 27 18:51:31 XXX NetworkManager[1266]: <info> (ttyUSB0): exported as /org/freedesktop/NetworkManager/Devices/24
Oct 27 18:51:31 XXX NetworkManager[1266]: <info> (ttyUSB0): now managed
Oct 27 18:51:31 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Oct 27 18:51:31 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'managed') [2]
Oct 27 18:51:31 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 18:51:31 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 18:51:31 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Oct 27 18:51:35 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) starting connection 'Pepephone Default 1'
Oct 27 18:51:35 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Oct 27 18:51:35 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 27 18:51:35 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Oct 27 18:51:35 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Oct 27 18:51:35 XXX modem-manager[3539]: <info>  (ttyUSB0) opening serial port...
Oct 27 18:51:35 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/21: state changed (disabled -> enabling)
Oct 27 18:51:35 XXX modem-manager[3539]: <info>  (ttyUSB3) opening serial port...
Oct 27 18:51:36 XXX modem-manager[3539]: <info>  (ttyUSB0): using text mode for SMS
Oct 27 18:51:36 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/21: state changed (enabling -> enabled)
Oct 27 18:51:36 XXX NetworkManager[1266]: <info> WWAN now enabled by management service
Oct 27 18:51:36 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/21: state changed (enabled -> registered)
Oct 27 18:51:36 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/21: state changed (registered -> connecting)
Oct 27 18:51:36 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/21: state changed (connecting -> connected)
Oct 27 18:51:36 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled...
Oct 27 18:51:36 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting...
Oct 27 18:51:36 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: prepare -> config (reason 'none') [40 50 0]
Oct 27 18:51:36 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) successful.
Oct 27 18:51:36 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) scheduled.
Oct 27 18:51:36 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
Oct 27 18:51:36 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) started...
Oct 27 18:51:36 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: config -> ip-config (reason 'none') [50 70 0]
Oct 27 18:51:36 XXX NetworkManager[1266]: <info> starting PPP connection
Oct 27 18:51:36 XXX NetworkManager[1266]: <info> pppd started with pid 12771
Oct 27 18:51:36 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Oct 27 18:51:36 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) complete.
Oct 27 18:51:36 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Oct 27 18:51:36 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Oct 27 18:51:36 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Oct 27 18:51:36 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
Oct 27 18:51:38 XXX NetworkManager[1266]: <info> PPP manager(IP Config Get) reply received.
Oct 27 18:51:38 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Oct 27 18:51:38 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) started...
Oct 27 18:51:39 XXX NetworkManager[1266]: <info> DNS: starting dnsmasq...
Oct 27 18:51:39 XXX NetworkManager[1266]: <info> (ppp0): writing resolv.conf to /sbin/resolvconf
Oct 27 18:51:39 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Oct 27 18:51:39 XXX NetworkManager[1266]: <info> Policy set 'Pepephone Default 1' (ppp0) as default for IPv4 routing and DNS.
Oct 27 18:51:39 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) successful, device activated.
Oct 27 18:51:39 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) complete.
Oct 27 18:52:30 XXX kernel: [137390.531526] type=1701 audit(1351356750.092:93): auid=4294967295 uid=1000 gid=1000 ses=4294967295 pid=13051 comm="chrome" reason="seccomp" sig=0 syscall=20 ip=0x617416 code=0x50001
Oct 27 18:55:51 XXX kernel: [137591.919981] usb 2-2: USB disconnect, device number 61
Oct 27 18:55:51 XXX kernel: [137591.919987] usb 2-2.1: USB disconnect, device number 62
Oct 27 18:55:51 XXX kernel: [137591.920669] usblp0: removed
Oct 27 18:55:51 XXX kernel: [137591.920944] usb 2-2.4: USB disconnect, device number 63
Oct 27 18:55:51 XXX kernel: [137592.192157] usb 2-2: new high-speed USB device number 66 using ehci_hcd
Oct 27 18:55:51 XXX kernel: [137592.328181] hub 2-2:1.0: USB hub found
Oct 27 18:55:51 XXX kernel: [137592.328290] hub 2-2:1.0: 4 ports detected
Oct 27 18:55:52 XXX kernel: [137592.616212] usb 2-2.1: new full-speed USB device number 67 using ehci_hcd
Oct 27 18:55:52 XXX kernel: [137592.766370] usblp0: USB Bidirectional printer dev 67 if 0 alt 0 proto 2 vid 0x03F0 pid 0x1604
Oct 27 18:55:52 XXX kernel: [137592.856216] usb 2-2.4: new high-speed USB device number 68 using ehci_hcd
Oct 27 18:55:52 XXX kernel: [137592.965427] hub 2-2.4:1.0: USB hub found
Oct 27 18:55:52 XXX kernel: [137592.965564] hub 2-2.4:1.0: 4 ports detected
Oct 27 18:55:54 XXX kernel: [137594.606694] usblp0: removed
Oct 27 18:55:54 XXX kernel: [137594.616741] usblp0: USB Bidirectional printer dev 67 if 0 alt 0 proto 2 vid 0x03F0 pid 0x1604
Oct 27 19:39:12 XXX kernel: [140192.626497] usb 2-2: USB disconnect, device number 66
Oct 27 19:39:12 XXX kernel: [140192.626506] usb 2-2.1: USB disconnect, device number 67
Oct 27 19:39:12 XXX kernel: [140192.627251] usblp0: removed
Oct 27 19:39:12 XXX kernel: [140192.629795] usb 2-2.4: USB disconnect, device number 68
Oct 27 19:39:12 XXX kernel: [140192.880138] usb 2-2: new high-speed USB device number 69 using ehci_hcd
Oct 27 19:39:12 XXX kernel: [140193.013074] hub 2-2:1.0: USB hub found
Oct 27 19:39:12 XXX kernel: [140193.013199] hub 2-2:1.0: 4 ports detected
Oct 27 19:39:12 XXX kernel: [140193.112388] usb 2-2: USB disconnect, device number 69
Oct 27 19:39:12 XXX kernel: [140193.116385] hub 2-2:1.0: hub_port_status failed (err = -71)
Oct 27 19:39:12 XXX kernel: [140193.116398] hub 2-2:1.0: hub_port_status failed (err = -19)
Oct 27 19:39:12 XXX kernel: [140193.116406] hub 2-2:1.0: hub_port_status failed (err = -19)
Oct 27 19:39:13 XXX kernel: [140193.596119] usb 5-2: new full-speed USB device number 7 using uhci_hcd
Oct 27 19:39:13 XXX kernel: [140193.716072] usb 5-2: device descriptor read/64, error -71
Oct 27 19:39:13 XXX kernel: [140193.955771] usb 5-2: not running at top speed; connect to a high speed hub
Oct 27 19:39:13 XXX kernel: [140193.966800] hub 5-2:1.0: USB hub found
Oct 27 19:39:13 XXX kernel: [140193.968749] hub 5-2:1.0: 4 ports detected
Oct 27 19:39:13 XXX kernel: [140194.265851] usb 5-2.1: new full-speed USB device number 8 using uhci_hcd
Oct 27 19:39:14 XXX kernel: [140194.450962] usblp0: USB Bidirectional printer dev 8 if 0 alt 0 proto 2 vid 0x03F0 pid 0x1604
Oct 27 19:39:14 XXX kernel: [140194.541809] usb 5-2.4: new full-speed USB device number 9 using uhci_hcd
Oct 27 19:39:14 XXX kernel: [140194.658836] usb 5-2.4: not running at top speed; connect to a high speed hub
Oct 27 19:39:14 XXX kernel: [140194.669903] hub 5-2.4:1.0: USB hub found
Oct 27 19:39:14 XXX kernel: [140194.671770] hub 5-2.4:1.0: 4 ports detected
Oct 27 19:39:15 XXX kernel: [140195.788126] usb 2-2: new high-speed USB device number 71 using ehci_hcd
Oct 27 19:39:15 XXX kernel: [140195.856439] hub 2-0:1.0: unable to enumerate USB device on port 2
Oct 27 19:39:15 XXX kernel: [140195.880101] usb 5-2: USB disconnect, device number 7
Oct 27 19:39:15 XXX kernel: [140195.880111] usb 5-2.1: USB disconnect, device number 8
Oct 27 19:39:15 XXX kernel: [140195.880557] usblp0: removed
Oct 27 19:39:15 XXX kernel: [140195.880949] usb 5-2.4: USB disconnect, device number 9
Oct 27 19:39:15 XXX kernel: [140196.124088] usb 5-2: new full-speed USB device number 10 using uhci_hcd
Oct 27 19:39:15 XXX kernel: [140196.259787] usb 5-2: not running at top speed; connect to a high speed hub
Oct 27 19:39:15 XXX kernel: [140196.270890] hub 5-2:1.0: USB hub found
Oct 27 19:39:15 XXX kernel: [140196.272767] hub 5-2:1.0: 4 ports detected
Oct 27 19:39:16 XXX kernel: [140196.569857] usb 5-2.1: new full-speed USB device number 11 using uhci_hcd
Oct 27 19:39:16 XXX kernel: [140196.764032] usblp0: USB Bidirectional printer dev 11 if 0 alt 0 proto 2 vid 0x03F0 pid 0x1604
Oct 27 19:39:16 XXX kernel: [140196.853838] usb 5-2.4: new full-speed USB device number 12 using uhci_hcd
Oct 27 19:39:16 XXX kernel: [140196.971761] usb 5-2.4: not running at top speed; connect to a high speed hub
Oct 27 19:39:16 XXX kernel: [140196.982900] hub 5-2.4:1.0: USB hub found
Oct 27 19:39:16 XXX kernel: [140196.983770] hub 5-2.4:1.0: 4 ports detected
Oct 27 19:39:17 XXX kernel: [140198.029381] usblp0: removed
Oct 27 19:39:17 XXX kernel: [140198.045851] usblp0: USB Bidirectional printer dev 11 if 0 alt 0 proto 2 vid 0x03F0 pid 0x1604
Oct 27 20:21:38 XXX modem-manager[3539]: <info>  (ttyUSB0) closing serial port...
Oct 27 20:21:38 XXX modem-manager[3539]: <info>  (ttyUSB0) serial port closed
Oct 27 20:21:38 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Oct 27 20:21:38 XXX NetworkManager[1266]: <info> (ttyUSB0): now unmanaged
Oct 27 20:21:38 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: activated -> unmanaged (reason 'removed') [100 10 36]
Oct 27 20:21:38 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'removed') [36]
Oct 27 20:21:38 XXX NetworkManager[1266]: <warn> could not read ppp stats: No such device
Oct 27 20:21:38 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 20:21:38 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 20:21:38 XXX NetworkManager[1266]: <info> DNS: starting dnsmasq...
Oct 27 20:21:38 XXX NetworkManager[1266]: <info> (ttyUSB0): writing resolv.conf to /sbin/resolvconf
Oct 27 20:21:38 XXX NetworkManager[1266]: <info> (ttyUSB0): cleaning up...
Oct 27 20:21:38 XXX NetworkManager[1266]: <info> (ttyUSB0): taking down device.
Oct 27 20:21:38 XXX NetworkManager[1266]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Oct 27 20:21:38 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/21: state changed (connected -> disconnecting)
Oct 27 20:21:38 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/21: state changed (disconnecting -> connected)
Oct 27 20:21:38 XXX NetworkManager[1266]: <info> disconnect failed: (32) The serial port is not open.
Oct 27 20:47:25 XXX kernel: [144286.323490] usb 2-4: USB disconnect, device number 65
Oct 27 20:47:25 XXX kernel: [144286.323626] option: option_instat_callback: error -108
Oct 27 20:47:25 XXX kernel: [144286.323955] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
Oct 27 20:47:25 XXX kernel: [144286.323975] option 2-4:1.0: device disconnected
Oct 27 20:47:25 XXX kernel: [144286.324122] option: option_instat_callback: error -108
Oct 27 20:47:25 XXX modem-manager[3539]: <info>  (tty/ttyUSB0): released by modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4
Oct 27 20:47:25 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/21: state changed (connected -> disabled)
Oct 27 20:47:25 XXX modem-manager[3539]: <info>  (ttyUSB3) closing serial port...
Oct 27 20:47:25 XXX modem-manager[3539]: <info>  (ttyUSB3) serial port closed
Oct 27 20:47:25 XXX kernel: [144286.326315] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
Oct 27 20:47:25 XXX kernel: [144286.326332] option 2-4:1.1: device disconnected
Oct 27 20:47:25 XXX kernel: [144286.326476] option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
Oct 27 20:47:25 XXX kernel: [144286.326492] option 2-4:1.2: device disconnected
Oct 27 20:47:25 XXX kernel: [144286.329379] option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3
Oct 27 20:47:25 XXX kernel: [144286.329396] option 2-4:1.3: device disconnected
Oct 27 20:47:31 XXX kernel: [144292.404033] usb 2-4: new high-speed USB device number 72 using ehci_hcd
Oct 27 20:47:32 XXX kernel: [144292.551438] scsi165 : usb-storage 2-4:1.0
Oct 27 20:47:32 XXX kernel: [144292.552379] usb 2-4: USB disconnect, device number 72
Oct 27 20:47:39 XXX kernel: [144300.231186] usb 2-4: new high-speed USB device number 73 using ehci_hcd
Oct 27 20:47:39 XXX kernel: [144300.389687] option 2-4:1.0: GSM modem (1-port) converter detected
Oct 27 20:47:39 XXX kernel: [144300.390367] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB0
Oct 27 20:47:39 XXX kernel: [144300.391518] option 2-4:1.1: GSM modem (1-port) converter detected
Oct 27 20:47:39 XXX kernel: [144300.392093] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB1
Oct 27 20:47:39 XXX kernel: [144300.394138] option 2-4:1.2: GSM modem (1-port) converter detected
Oct 27 20:47:39 XXX kernel: [144300.394669] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB2
Oct 27 20:47:39 XXX kernel: [144300.397946] option 2-4:1.3: GSM modem (1-port) converter detected
Oct 27 20:47:39 XXX kernel: [144300.398206] usb 2-4: GSM modem (1-port) converter now attached to ttyUSB3
Oct 27 20:47:39 XXX kernel: [144300.402688] scsi170 : usb-storage 2-4:1.4
Oct 27 20:47:39 XXX kernel: [144300.404569] scsi171 : usb-storage 2-4:1.5
Oct 27 20:47:40 XXX modem-manager[3539]: <info>  (ttyUSB0) opening serial port...
Oct 27 20:47:40 XXX kernel: [144301.406180] scsi 170:0:0:0: CD-ROM            HUAWEI   Mass Storage     2.31 PQ: 0 ANSI: 2
Oct 27 20:47:40 XXX kernel: [144301.409202] scsi 171:0:0:0: Direct-Access     HUAWEI   MMC Storage      2.31 PQ: 0 ANSI: 2
Oct 27 20:47:40 XXX kernel: [144301.416915] sr1: scsi-1 drive
Oct 27 20:47:40 XXX kernel: [144301.417057] sr 170:0:0:0: Attached scsi CD-ROM sr1
Oct 27 20:47:40 XXX kernel: [144301.417139] sr 170:0:0:0: Attached scsi generic sg2 type 5
Oct 27 20:47:40 XXX kernel: [144301.422877] sd 171:0:0:0: Attached scsi generic sg3 type 0
Oct 27 20:47:40 XXX kernel: [144301.431917] sd 171:0:0:0: [sdb] Attached SCSI removable disk
Oct 27 20:47:42 XXX modem-manager[3539]: <info>  (ttyUSB0) closing serial port...
Oct 27 20:47:42 XXX modem-manager[3539]: <info>  (ttyUSB0) serial port closed
Oct 27 20:47:42 XXX modem-manager[3539]: <info>  (ttyUSB0) opening serial port...
Oct 27 20:47:42 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB0
Oct 27 20:47:43 XXX modem-manager[3539]: <info>  (ttyUSB2) opening serial port...
Oct 27 20:47:43 XXX modem-manager[3539]: <info>  (ttyUSB3) opening serial port...
Oct 27 20:47:43 XXX modem-manager[3539]: <info>  (ttyUSB1) opening serial port...
Oct 27 20:47:43 XXX modem-manager[3539]: <info>  (ttyUSB0) closing serial port...
Oct 27 20:47:43 XXX modem-manager[3539]: <info>  (ttyUSB0) serial port closed
Oct 27 20:47:46 XXX modem-manager[3539]: <info>  (ttyUSB3) closing serial port...
Oct 27 20:47:46 XXX modem-manager[3539]: <info>  (ttyUSB3) serial port closed
Oct 27 20:47:46 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB3
Oct 27 20:47:50 XXX modem-manager[3539]: <info>  (ttyUSB2) closing serial port...
Oct 27 20:47:50 XXX modem-manager[3539]: <info>  (ttyUSB2) serial port closed
Oct 27 20:47:50 XXX modem-manager[3539]: <info>  (ttyUSB1) closing serial port...
Oct 27 20:47:50 XXX modem-manager[3539]: <info>  (ttyUSB1) serial port closed
Oct 27 20:47:50 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB2
Oct 27 20:47:50 XXX modem-manager[3539]: <info>  (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-4 claimed port ttyUSB1
Oct 27 20:47:50 XXX NetworkManager[1266]: <warn> (ttyUSB0): failed to look up interface index
Oct 27 20:47:50 XXX NetworkManager[1266]: <info> WWAN now disabled by management service
Oct 27 20:47:50 XXX NetworkManager[1266]: <info> (ttyUSB0): new GSM/UMTS device (driver: 'option1' ifindex: 0)
Oct 27 20:47:50 XXX NetworkManager[1266]: <info> (ttyUSB0): exported as /org/freedesktop/NetworkManager/Devices/25
Oct 27 20:47:50 XXX NetworkManager[1266]: <info> (ttyUSB0): now managed
Oct 27 20:47:50 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Oct 27 20:47:50 XXX NetworkManager[1266]: <info> (ttyUSB0): deactivating device (reason 'managed') [2]
Oct 27 20:47:50 XXX NetworkManager[1266]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Oct 27 20:47:50 XXX NetworkManager[1266]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Oct 27 20:47:50 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) starting connection 'Pepephone Default 1'
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Oct 27 20:47:55 XXX modem-manager[3539]: <info>  (ttyUSB0) opening serial port...
Oct 27 20:47:55 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/22: state changed (disabled -> enabling)
Oct 27 20:47:55 XXX modem-manager[3539]: <info>  (ttyUSB3) opening serial port...
Oct 27 20:47:55 XXX modem-manager[3539]: <info>  (ttyUSB0): using text mode for SMS
Oct 27 20:47:55 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/22: state changed (enabling -> enabled)
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> WWAN now enabled by management service
Oct 27 20:47:55 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/22: state changed (enabled -> registered)
Oct 27 20:47:55 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/22: state changed (registered -> connecting)
Oct 27 20:47:55 XXX modem-manager[3539]: <info>  Modem /org/freedesktop/ModemManager/Modems/22: state changed (connecting -> connected)
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled...
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting...
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: prepare -> config (reason 'none') [40 50 0]
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) successful.
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) scheduled.
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) started...
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: config -> ip-config (reason 'none') [50 70 0]
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> starting PPP connection
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> pppd started with pid 15591
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) complete.
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Oct 27 20:47:55 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Oct 27 20:47:55 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Oct 27 20:47:55 XXX NetworkManager[1266]:    SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
Oct 27 20:47:58 XXX NetworkManager[1266]: <info> PPP manager(IP Config Get) reply received.
Oct 27 20:47:58 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Oct 27 20:47:58 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) started...
Oct 27 20:47:59 XXX NetworkManager[1266]: <info> DNS: starting dnsmasq...
Oct 27 20:47:59 XXX NetworkManager[1266]: <info> (ppp0): writing resolv.conf to /sbin/resolvconf
Oct 27 20:47:59 XXX NetworkManager[1266]: <info> (ttyUSB0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Oct 27 20:47:59 XXX NetworkManager[1266]: <info> Policy set 'Pepephone Default 1' (ppp0) as default for IPv4 routing and DNS.
Oct 27 20:47:59 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) successful, device activated.
Oct 27 20:47:59 XXX NetworkManager[1266]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) complete.
Oct 27 20:50:21 XXX kernel: [144461.824060] type=1701 audit(1351363821.385:94): auid=4294967295 uid=1000 gid=1000 ses=4294967295 pid=15693 comm="chrome" reason="seccomp" sig=0 syscall=20 ip=0xebf416 code=0x50001