Apr 27 01:02:17 virtu kernel: [43170.001569] ata3: exception Emask 0x10 SAct 0x1 SErr 0x50000 action 0x1e frozen Apr 27 01:02:17 virtu kernel: [43170.001579] ata3: irq_stat 0x00400008, PHY RDY changed Apr 27 01:02:17 virtu kernel: [43170.001588] ata3: SError: { PHYRdyChg CommWake } Apr 27 01:02:17 virtu kernel: [43170.001601] ata3: hard resetting link Apr 27 01:02:17 virtu kernel: [43170.724093] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300) Apr 27 01:02:17 virtu kernel: [43170.744670] ata3.00: configured for UDMA/133 Apr 27 01:02:17 virtu kernel: [43170.760309] ata3: EH complete Apr 27 01:02:19 virtu kernel: [43172.628305] usb 5-1: new low speed USB device using uhci_hcd and address 7 Apr 27 01:02:19 virtu kernel: [43172.801697] usb 5-1: configuration #1 chosen from 1 choice Apr 27 01:02:19 virtu kernel: [43172.819061] input: Logitech Optical USB Mouse as /devices/pci0000:00/0000:00:1d.0/usb5/5-1/5-1:1.0/input/input22 Apr 27 01:02:19 virtu kernel: [43172.819591] generic-usb 0003:046D:C016.0008: input,hidraw0: USB HID v1.10 Mouse [Logitech Optical USB Mouse] on usb-0000:00:1d.0-1/input0 Apr 27 01:02:38 virtu NetworkManager: Sleeping... Apr 27 01:02:38 virtu NetworkManager: (wlan0): now unmanaged Apr 27 01:02:38 virtu NetworkManager: (wlan0): device state change: 8 -> 1 (reason 37) Apr 27 01:02:38 virtu NetworkManager: (wlan0): deactivating device (reason: 37). Apr 27 01:02:38 virtu NetworkManager: (wlan0): canceled DHCP transaction, dhcp client pid 7380 Apr 27 01:02:38 virtu kernel: [43191.609275] wlan0: deauthenticating from 00:0c:f6:61:4a:bc by local choice (reason=3) Apr 27 01:02:38 virtu NetworkManager: check_one_route(): (wlan0) error -34 returned from rtnl_route_del(): Sucess#012 Apr 27 01:02:38 virtu NetworkManager: (wlan0): cleaning up... Apr 27 01:02:38 virtu NetworkManager: (wlan0): taking down device. Apr 27 01:02:38 virtu NetworkManager: (eth0): now unmanaged Apr 27 01:02:38 virtu NetworkManager: (eth0): device state change: 2 -> 1 (reason 37) Apr 27 01:02:38 virtu NetworkManager: (eth0): cleaning up... Apr 27 01:02:38 virtu NetworkManager: (eth0): taking down device. Apr 27 01:02:50 virtu kernel: [43192.769332] PM: Syncing filesystems ... done. Apr 27 01:02:50 virtu kernel: [43192.997072] PM: Preparing system for mem sleep Apr 27 01:02:50 virtu kernel: [43192.997079] Freezing user space processes ... (elapsed 0.00 seconds) done. Apr 27 01:02:50 virtu kernel: [43192.998136] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. Apr 27 01:02:50 virtu kernel: [43192.998192] PM: Entering mem sleep Apr 27 01:02:50 virtu kernel: [43192.998203] Suspending console(s) (use no_console_suspend to debug) Apr 27 01:02:50 virtu kernel: [43193.106094] btusb_intr_complete: hci0 urb e64a2700 failed to resubmit (1) Apr 27 01:02:50 virtu kernel: [43193.107096] btusb_bulk_complete: hci0 urb e64a2580 failed to resubmit (1) Apr 27 01:02:50 virtu kernel: [43193.108098] btusb_bulk_complete: hci0 urb e64a2080 failed to resubmit (1) Apr 27 01:02:50 virtu kernel: [43193.144113] sd 2:0:0:0: [sda] Synchronizing SCSI cache Apr 27 01:02:50 virtu kernel: [43193.144340] sd 2:0:0:0: [sda] Stopping disk Apr 27 01:02:50 virtu kernel: [43193.470648] PM: suspend of drv:sd dev:2:0:0:0 complete after 326.538 msecs Apr 27 01:02:50 virtu kernel: [43193.907991] PM: suspend of drv:psmouse dev:serio1 complete after 423.798 msecs Apr 27 01:02:50 virtu kernel: [43194.010123] ACPI handle has no context! Apr 27 01:02:50 virtu kernel: [43194.010131] sdhci-pci 0000:03:01.1: PCI INT B disabled Apr 27 01:02:50 virtu kernel: [43194.010137] ACPI handle has no context! Apr 27 01:02:50 virtu kernel: [43194.108346] ata2: port disabled. ignoring. Apr 27 01:02:50 virtu kernel: [43194.108439] ata_piix 0000:00:1f.1: PCI INT A disabled Apr 27 01:02:50 virtu kernel: [43194.108456] ehci_hcd 0000:00:1d.7: PCI INT A disabled Apr 27 01:02:50 virtu kernel: [43194.108467] uhci_hcd 0000:00:1d.2: PCI INT C disabled Apr 27 01:02:50 virtu kernel: [43194.108477] uhci_hcd 0000:00:1d.1: PCI INT B disabled Apr 27 01:02:50 virtu kernel: [43194.108487] uhci_hcd 0000:00:1d.0: PCI INT A disabled Apr 27 01:02:50 virtu kernel: [43194.500258] HDA Intel 0000:00:1b.0: PCI INT A disabled Apr 27 01:02:50 virtu kernel: [43194.516231] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 407.721 msecs Apr 27 01:02:50 virtu kernel: [43194.516243] ehci_hcd 0000:00:1a.7: PCI INT C disabled Apr 27 01:02:50 virtu kernel: [43194.516256] uhci_hcd 0000:00:1a.1: PCI INT B disabled Apr 27 01:02:50 virtu kernel: [43194.516269] uhci_hcd 0000:00:1a.0: PCI INT A disabled Apr 27 01:02:50 virtu kernel: [43194.564562] PM: suspend of devices complete after 1565.969 msecs Apr 27 01:02:50 virtu kernel: [43194.564565] PM: suspend devices took 1.568 seconds Apr 27 01:02:50 virtu kernel: [43194.565144] ricoh-mmc: Suspending. Apr 27 01:02:50 virtu kernel: [43194.565154] ricoh-mmc: Controller is now re-enabled. Apr 27 01:02:50 virtu kernel: [43194.596244] PM: late suspend of devices complete after 31.673 msecs Apr 27 01:02:50 virtu kernel: [43194.600457] ACPI: Preparing to enter system sleep state S3 Apr 27 01:02:50 virtu kernel: [43194.616013] Disabling non-boot CPUs ... Apr 27 01:02:50 virtu kernel: [43194.616152] CPU0 attaching NULL sched-domain. Apr 27 01:02:50 virtu kernel: [43194.616155] CPU1 attaching NULL sched-domain. Apr 27 01:02:50 virtu kernel: [43194.680016] CPU0 attaching NULL sched-domain. Apr 27 01:02:50 virtu kernel: [43194.681140] Breaking affinity for irq 9 Apr 27 01:02:50 virtu kernel: [43194.681172] Breaking affinity for irq 20 Apr 27 01:02:50 virtu kernel: [43194.784022] CPU 1 is now offline Apr 27 01:02:50 virtu kernel: [43194.784025] SMP alternatives: switching to UP code Apr 27 01:02:50 virtu kernel: [43194.790780] Extended CMOS year: 2000 Apr 27 01:02:50 virtu kernel: [43194.790780] Back to C! Apr 27 01:02:50 virtu kernel: [43194.790780] CPU0: Thermal monitoring enabled (TM2) Apr 27 01:02:50 virtu kernel: [43194.790780] Extended CMOS year: 2000 Apr 27 01:02:50 virtu kernel: [43194.790780] Enabling non-boot CPUs ... Apr 27 01:02:50 virtu kernel: [43194.790780] SMP alternatives: switching to SMP code Apr 27 01:02:50 virtu kernel: [43194.796604] Booting processor 1 APIC 0x1 ip 0x6000 Apr 27 01:02:50 virtu kernel: [43194.790375] Initializing CPU#1 Apr 27 01:02:50 virtu kernel: [43194.790375] CPU: L1 I cache: 32K, L1 D cache: 32K Apr 27 01:02:50 virtu kernel: [43194.790375] CPU: L2 cache: 2048K Apr 27 01:02:50 virtu kernel: [43194.790375] CPU: Physical Processor ID: 0 Apr 27 01:02:50 virtu kernel: [43194.790375] CPU: Processor Core ID: 1 Apr 27 01:02:50 virtu kernel: [43194.790375] CPU1: Thermal monitoring enabled (TM2) Apr 27 01:02:50 virtu kernel: [43194.884115] CPU1: Intel(R) Core(TM)2 Duo CPU T7250 @ 2.00GHz stepping 0d Apr 27 01:02:50 virtu kernel: [43194.884192] CPU0 attaching NULL sched-domain. Apr 27 01:02:50 virtu kernel: [43194.912017] CPU0 attaching sched-domain: Apr 27 01:02:50 virtu kernel: [43194.912020] domain 0: span 0-1 level MC Apr 27 01:02:50 virtu kernel: [43194.912022] groups: 0 1 Apr 27 01:02:50 virtu kernel: [43194.912027] CPU1 attaching sched-domain: Apr 27 01:02:50 virtu kernel: [43194.912029] domain 0: span 0-1 level MC Apr 27 01:02:50 virtu kernel: [43194.912031] groups: 1 0 Apr 27 01:02:50 virtu kernel: [43194.912500] CPU1 is up Apr 27 01:02:50 virtu kernel: [43194.912919] ACPI: Waking up from system sleep state S3 Apr 27 01:02:50 virtu kernel: [43194.922378] i915 0000:00:02.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) Apr 27 01:02:50 virtu kernel: [43194.922386] i915 0000:00:02.0: restoring config space at offset 0x8 (was 0x1, writing 0xeff9) Apr 27 01:02:50 virtu kernel: [43194.922391] i915 0000:00:02.0: restoring config space at offset 0x6 (was 0xc, writing 0xe000000c) Apr 27 01:02:50 virtu kernel: [43194.922395] i915 0000:00:02.0: restoring config space at offset 0x4 (was 0x4, writing 0xfea00004) Apr 27 01:02:50 virtu kernel: [43194.922401] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900000, writing 0x900407) Apr 27 01:02:50 virtu kernel: [43194.922430] pci 0000:00:02.1: restoring config space at offset 0x4 (was 0x4, writing 0xfeb00004) Apr 27 01:02:50 virtu kernel: [43194.922435] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) Apr 27 01:02:50 virtu kernel: [43194.922448] uhci_hcd 0000:00:1a.0: restoring config space at offset 0xf (was 0x100, writing 0x10a) Apr 27 01:02:50 virtu kernel: [43194.922463] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x8 (was 0x1, writing 0x6f21) Apr 27 01:02:50 virtu kernel: [43194.922478] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) Apr 27 01:02:50 virtu kernel: [43194.922492] uhci_hcd 0000:00:1a.1: restoring config space at offset 0xf (was 0x200, writing 0x209) Apr 27 01:02:50 virtu kernel: [43194.922507] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x8 (was 0x1, writing 0x6f01) Apr 27 01:02:50 virtu kernel: [43194.922522] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) Apr 27 01:02:50 virtu kernel: [43194.922545] ehci_hcd 0000:00:1a.7: restoring config space at offset 0xf (was 0x300, writing 0x307) Apr 27 01:02:50 virtu kernel: [43194.922566] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x4 (was 0x0, writing 0xfed1c400) Apr 27 01:02:50 virtu kernel: [43194.922575] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) Apr 27 01:02:50 virtu kernel: [43194.922611] HDA Intel 0000:00:1b.0: restoring config space at offset 0xf (was 0x100, writing 0x109) Apr 27 01:02:50 virtu kernel: [43194.922632] HDA Intel 0000:00:1b.0: restoring config space at offset 0x4 (was 0xfebfc004, writing 0xfe9fc004) Apr 27 01:02:50 virtu kernel: [43194.922638] HDA Intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) Apr 27 01:02:50 virtu kernel: [43194.922645] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100102) Apr 27 01:02:50 virtu kernel: [43194.922682] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x100, writing 0x20100) Apr 27 01:02:50 virtu kernel: [43194.922695] pcieport 0000:00:1c.0: restoring config space at offset 0x9 (was 0x10001, writing 0xc031c021) Apr 27 01:02:50 virtu kernel: [43194.922701] pcieport 0000:00:1c.0: restoring config space at offset 0x8 (was 0x0, writing 0xc010c000) Apr 27 01:02:50 virtu kernel: [43194.922707] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0x0, writing 0x2020) Apr 27 01:02:50 virtu kernel: [43194.922712] pcieport 0000:00:1c.0: restoring config space at offset 0x6 (was 0x0, writing 0xb0b00) Apr 27 01:02:51 virtu kernel: [43194.922721] pcieport 0000:00:1c.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) Apr 27 01:02:51 virtu kernel: [43194.922728] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) Apr 27 01:02:51 virtu kernel: [43194.922782] pcieport 0000:00:1c.1: restoring config space at offset 0xf (was 0x200, writing 0x20200) Apr 27 01:02:51 virtu kernel: [43194.922795] pcieport 0000:00:1c.1: restoring config space at offset 0x9 (was 0x10001, writing 0xc051c041) Apr 27 01:02:51 virtu kernel: [43194.922801] pcieport 0000:00:1c.1: restoring config space at offset 0x8 (was 0x0, writing 0xfe80fe80) Apr 27 01:02:51 virtu kernel: [43194.922807] pcieport 0000:00:1c.1: restoring config space at offset 0x7 (was 0x20000000, writing 0x3030) Apr 27 01:02:51 virtu kernel: [43194.922812] pcieport 0000:00:1c.1: restoring config space at offset 0x6 (was 0x0, writing 0xc0c00) Apr 27 01:02:51 virtu kernel: [43194.922821] pcieport 0000:00:1c.1: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) Apr 27 01:02:51 virtu kernel: [43194.922828] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100507) Apr 27 01:02:51 virtu kernel: [43194.922882] pcieport 0000:00:1c.3: restoring config space at offset 0xf (was 0x400, writing 0x20400) Apr 27 01:02:51 virtu kernel: [43194.922895] pcieport 0000:00:1c.3: restoring config space at offset 0x9 (was 0x10001, writing 0xf011f001) Apr 27 01:02:51 virtu kernel: [43194.922901] pcieport 0000:00:1c.3: restoring config space at offset 0x8 (was 0x0, writing 0xfe70fe60) Apr 27 01:02:51 virtu kernel: [43194.922907] pcieport 0000:00:1c.3: restoring config space at offset 0x7 (was 0x0, writing 0xd0d0) Apr 27 01:02:51 virtu kernel: [43194.922912] pcieport 0000:00:1c.3: restoring config space at offset 0x6 (was 0x0, writing 0xe0d00) Apr 27 01:02:51 virtu kernel: [43194.922921] pcieport 0000:00:1c.3: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) Apr 27 01:02:51 virtu kernel: [43194.922928] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) Apr 27 01:02:51 virtu kernel: [43194.922982] pcieport 0000:00:1c.5: restoring config space at offset 0xf (was 0x200, writing 0x20200) Apr 27 01:02:51 virtu kernel: [43194.922995] pcieport 0000:00:1c.5: restoring config space at offset 0x9 (was 0x10001, writing 0xc071c061) Apr 27 01:02:51 virtu kernel: [43194.923000] pcieport 0000:00:1c.5: restoring config space at offset 0x8 (was 0x0, writing 0xfe50fe50) Apr 27 01:02:51 virtu kernel: [43194.923006] pcieport 0000:00:1c.5: restoring config space at offset 0x7 (was 0x0, writing 0x4040) Apr 27 01:02:51 virtu kernel: [43194.923012] pcieport 0000:00:1c.5: restoring config space at offset 0x6 (was 0x0, writing 0x90900) Apr 27 01:02:51 virtu kernel: [43194.923020] pcieport 0000:00:1c.5: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) Apr 27 01:02:51 virtu kernel: [43194.923028] pcieport 0000:00:1c.5: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) Apr 27 01:02:51 virtu kernel: [43194.923090] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) Apr 27 01:02:51 virtu kernel: [43194.923104] uhci_hcd 0000:00:1d.1: restoring config space at offset 0xf (was 0x200, writing 0x209) Apr 27 01:02:51 virtu kernel: [43194.923119] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x8 (was 0x1, writing 0x6f61) Apr 27 01:02:51 virtu kernel: [43194.923134] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) Apr 27 01:02:51 virtu kernel: [43194.923147] uhci_hcd 0000:00:1d.2: restoring config space at offset 0xf (was 0x300, writing 0x307) Apr 27 01:02:51 virtu kernel: [43194.923162] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x8 (was 0x1, writing 0x6f41) Apr 27 01:02:51 virtu kernel: [43194.923176] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) Apr 27 01:02:51 virtu kernel: [43194.923221] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) Apr 27 01:02:51 virtu kernel: [43194.923256] pci 0000:00:1e.0: restoring config space at offset 0x9 (was 0x100f1, writing 0x1fff1) Apr 27 01:02:51 virtu kernel: [43194.923262] pci 0000:00:1e.0: restoring config space at offset 0x8 (was 0x40, writing 0xfe40fe40) Apr 27 01:02:51 virtu kernel: [43194.923268] pci 0000:00:1e.0: restoring config space at offset 0x7 (was 0x2280e0f0, writing 0x228000f0) Apr 27 01:02:51 virtu kernel: [43194.923281] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100007, writing 0x100107) Apr 27 01:02:51 virtu kernel: [43194.923335] pci 0000:00:1f.0: restoring config space at offset 0x1 (was 0x2100007, writing 0x2100107) Apr 27 01:02:51 virtu kernel: [43194.923356] ata_piix 0000:00:1f.1: restoring config space at offset 0xf (was 0x100, writing 0x10b) Apr 27 01:02:51 virtu kernel: [43194.923382] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) Apr 27 01:02:51 virtu kernel: [43194.923407] ahci 0000:00:1f.2: restoring config space at offset 0xf (was 0x200, writing 0x20a) Apr 27 01:02:51 virtu kernel: [43194.923420] ahci 0000:00:1f.2: restoring config space at offset 0x9 (was 0x0, writing 0xfe9fb800) Apr 27 01:02:51 virtu kernel: [43194.923437] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00407) Apr 27 01:02:51 virtu kernel: [43194.923475] pci 0000:00:1f.3: restoring config space at offset 0xf (was 0x200, writing 0x20a) Apr 27 01:02:51 virtu kernel: [43194.923496] pci 0000:00:1f.3: restoring config space at offset 0x4 (was 0x0, writing 0xfe9fb700) Apr 27 01:02:51 virtu kernel: [43194.923505] pci 0000:00:1f.3: restoring config space at offset 0x1 (was 0x2800001, writing 0x2800103) Apr 27 01:02:51 virtu kernel: [43194.923582] iwl3945 0000:0c:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a) Apr 27 01:02:51 virtu kernel: [43194.923650] iwl3945 0000:0c:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xfe8ff000) Apr 27 01:02:51 virtu kernel: [43194.923664] iwl3945 0000:0c:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) Apr 27 01:02:51 virtu kernel: [43194.923683] iwl3945 0000:0c:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100506) Apr 27 01:02:51 virtu kernel: [43194.923893] tg3 0000:09:00.0: restoring config space at offset 0xc (was 0x0, writing 0xffff0000) Apr 27 01:02:51 virtu kernel: [43194.923945] tg3 0000:09:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) Apr 27 01:02:51 virtu kernel: [43194.923963] tg3 0000:09:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100106) Apr 27 01:02:51 virtu kernel: [43194.924063] ohci1394 0000:03:01.0: restoring config space at offset 0xf (was 0x4020100, writing 0x4020105) Apr 27 01:02:51 virtu kernel: [43194.924087] ohci1394 0000:03:01.0: restoring config space at offset 0x3 (was 0x800000, writing 0x804010) Apr 27 01:02:51 virtu kernel: [43194.924095] ohci1394 0000:03:01.0: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) Apr 27 01:02:51 virtu kernel: [43194.924122] sdhci-pci 0000:03:01.1: restoring config space at offset 0xf (was 0x200, writing 0x204) Apr 27 01:02:51 virtu kernel: [43194.924144] sdhci-pci 0000:03:01.1: restoring config space at offset 0x4 (was 0x0, writing 0xfe4ff400) Apr 27 01:02:51 virtu kernel: [43194.924151] sdhci-pci 0000:03:01.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804010) Apr 27 01:02:51 virtu kernel: [43194.924158] sdhci-pci 0000:03:01.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) Apr 27 01:02:51 virtu kernel: [43194.924184] ricoh-mmc 0000:03:01.2: restoring config space at offset 0xf (was 0x200, writing 0x204) Apr 27 01:02:51 virtu kernel: [43194.924207] ricoh-mmc 0000:03:01.2: restoring config space at offset 0x4 (was 0x0, writing 0xfe4ff500) Apr 27 01:02:51 virtu kernel: [43194.924213] ricoh-mmc 0000:03:01.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804010) Apr 27 01:02:51 virtu kernel: [43194.924221] ricoh-mmc 0000:03:01.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) Apr 27 01:02:51 virtu kernel: [43194.924233] ricoh-mmc: Resuming. Apr 27 01:02:51 virtu kernel: [43194.924243] ricoh-mmc: Controller is now disabled. Apr 27 01:02:51 virtu kernel: [43194.924257] pci 0000:03:01.3: restoring config space at offset 0xf (was 0x200, writing 0x204) Apr 27 01:02:51 virtu kernel: [43194.924280] pci 0000:03:01.3: restoring config space at offset 0x4 (was 0x0, writing 0xfe4ff700) Apr 27 01:02:51 virtu kernel: [43194.924286] pci 0000:03:01.3: restoring config space at offset 0x3 (was 0x800000, writing 0x804010) Apr 27 01:02:51 virtu kernel: [43194.924294] pci 0000:03:01.3: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) Apr 27 01:02:51 virtu kernel: [43194.940021] pci 0000:03:01.4: Refused to change power state, currently in D3 Apr 27 01:02:51 virtu kernel: [43194.940518] PM: early resume of devices complete after 18.289 msecs Apr 27 01:02:51 virtu kernel: [43194.993506] i915 0000:00:02.0: setting latency timer to 64 Apr 27 01:02:51 virtu kernel: [43195.136371] PM: resume of drv:i915 dev:0000:00:02.0 complete after 142.872 msecs Apr 27 01:02:51 virtu kernel: [43195.136390] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 Apr 27 01:02:51 virtu kernel: [43195.136406] uhci_hcd 0000:00:1a.0: setting latency timer to 64 Apr 27 01:02:51 virtu kernel: [43195.136433] usb usb3: root hub lost power or was reset Apr 27 01:02:51 virtu kernel: [43195.136469] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 Apr 27 01:02:51 virtu kernel: [43195.136476] uhci_hcd 0000:00:1a.1: setting latency timer to 64 Apr 27 01:02:51 virtu kernel: [43195.136502] usb usb4: root hub lost power or was reset Apr 27 01:02:51 virtu kernel: [43195.136528] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 Apr 27 01:02:51 virtu kernel: [43195.136534] ehci_hcd 0000:00:1a.7: setting latency timer to 64 Apr 27 01:02:51 virtu kernel: [43195.136570] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 21 (level, low) -> IRQ 21 Apr 27 01:02:51 virtu kernel: [43195.136577] HDA Intel 0000:00:1b.0: setting latency timer to 64 Apr 27 01:02:51 virtu kernel: [43195.136612] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 Apr 27 01:02:51 virtu kernel: [43195.136619] uhci_hcd 0000:00:1d.0: setting latency timer to 64 Apr 27 01:02:51 virtu kernel: [43195.136645] usb usb5: root hub lost power or was reset Apr 27 01:02:51 virtu kernel: [43195.136677] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 Apr 27 01:02:51 virtu kernel: [43195.136684] uhci_hcd 0000:00:1d.1: setting latency timer to 64 Apr 27 01:02:51 virtu kernel: [43195.136709] usb usb6: root hub lost power or was reset Apr 27 01:02:51 virtu kernel: [43195.136730] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 22 (level, low) -> IRQ 22 Apr 27 01:02:51 virtu kernel: [43195.136736] uhci_hcd 0000:00:1d.2: setting latency timer to 64 Apr 27 01:02:51 virtu kernel: [43195.136762] usb usb7: root hub lost power or was reset Apr 27 01:02:51 virtu kernel: [43195.136783] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 20 (level, low) -> IRQ 20 Apr 27 01:02:51 virtu kernel: [43195.136790] ehci_hcd 0000:00:1d.7: setting latency timer to 64 Apr 27 01:02:51 virtu kernel: [43195.136819] pci 0000:00:1e.0: setting latency timer to 64 Apr 27 01:02:51 virtu kernel: [43195.136832] ata_piix 0000:00:1f.1: PCI INT A -> GSI 16 (level, low) -> IRQ 16 Apr 27 01:02:51 virtu kernel: [43195.136837] ata_piix 0000:00:1f.1: setting latency timer to 64 Apr 27 01:02:51 virtu kernel: [43195.136866] ata1.00: _GTF evaluation failed (AE 0x1001) Apr 27 01:02:51 virtu kernel: [43195.136929] ata2: port disabled. ignoring. Apr 27 01:02:51 virtu kernel: [43195.136944] ahci 0000:00:1f.2: setting latency timer to 64 Apr 27 01:02:51 virtu kernel: [43195.194241] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[19] MMIO=[fe4ff800-fe4fffff] Max Packet=[2048] IR/IT contexts=[4/4] Apr 27 01:02:51 virtu kernel: [43195.200258] sdhci-pci 0000:03:01.1: PCI INT B -> GSI 18 (level, low) -> IRQ 18 Apr 27 01:02:51 virtu kernel: [43195.444506] ata1.00: configured for UDMA/33 Apr 27 01:02:51 virtu kernel: [43195.452221] PM: resume of drv:usb dev:usb1 complete after 250.441 msecs Apr 27 01:02:51 virtu kernel: [43195.464236] ata5: SATA link down (SStatus 0 SControl 300) Apr 27 01:02:51 virtu kernel: [43195.704224] PM: resume of drv:usb dev:usb2 complete after 251.980 msecs Apr 27 01:02:51 virtu kernel: [43195.952226] PM: resume of drv:usb dev:usb3 complete after 247.983 msecs Apr 27 01:02:51 virtu kernel: [43196.200225] PM: resume of drv:usb dev:usb5 complete after 247.964 msecs Apr 27 01:02:51 virtu kernel: [43196.323878] PM: resume of drv:i8042 dev:i8042 complete after 123.609 msecs Apr 27 01:02:51 virtu kernel: [43196.436220] usb 2-6: reset high speed USB device using ehci_hcd and address 3 Apr 27 01:02:51 virtu kernel: [43196.628608] PM: resume of drv:usb dev:2-6 complete after 304.565 msecs Apr 27 01:02:51 virtu kernel: [43196.628633] sd 2:0:0:0: [sda] Starting disk Apr 27 01:02:51 virtu kernel: [43198.168227] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300) Apr 27 01:02:51 virtu kernel: [43198.181569] ata3.00: configured for UDMA/133 Apr 27 01:02:51 virtu kernel: [43198.202953] PM: resume of drv:sd dev:2:0:0:0 complete after 1574.320 msecs Apr 27 01:02:51 virtu kernel: [43198.464226] usb 5-1: reset low speed USB device using uhci_hcd and address 7 Apr 27 01:02:51 virtu kernel: [43198.771371] PM: resume of drv:usb dev:5-1 complete after 565.131 msecs Apr 27 01:02:51 virtu kernel: [43198.771402] PM: resume of devices complete after 3828.948 msecs Apr 27 01:02:51 virtu kernel: [43198.771706] PM: resume devices took 3.828 seconds Apr 27 01:02:51 virtu kernel: [43198.771738] PM: Finishing wakeup. Apr 27 01:02:51 virtu kernel: [43198.771740] Restarting tasks ... done. Apr 27 01:02:51 virtu NetworkManager: Waking up... Apr 27 01:02:51 virtu NetworkManager: (wlan0): now managed Apr 27 01:02:51 virtu NetworkManager: (wlan0): device state change: 1 -> 2 (reason 2) Apr 27 01:02:51 virtu NetworkManager: (wlan0): bringing up device. Apr 27 01:02:51 virtu kernel: [43199.016284] usb 3-2: USB disconnect, address 2 Apr 27 01:02:51 virtu kernel: [43199.016293] usb 3-2.1: USB disconnect, address 3 Apr 27 01:02:51 virtu kernel: [43199.085664] Registered led device: iwl-phy0::radio Apr 27 01:02:51 virtu kernel: [43199.085686] Registered led device: iwl-phy0::assoc Apr 27 01:02:51 virtu kernel: [43199.085704] Registered led device: iwl-phy0::RX Apr 27 01:02:51 virtu kernel: [43199.085723] Registered led device: iwl-phy0::TX Apr 27 01:02:51 virtu kernel: [43199.102837] ADDRCONF(NETDEV_UP): wlan0: link is not ready Apr 27 01:02:51 virtu kernel: [43199.105626] tg3 0000:09:00.0: irq 31 for MSI/MSI-X Apr 27 01:02:51 virtu NetworkManager: (wlan0): preparing device. Apr 27 01:02:51 virtu NetworkManager: (wlan0): deactivating device (reason: 2). Apr 27 01:02:51 virtu NetworkManager: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Apr 27 01:02:51 virtu NetworkManager: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Apr 27 01:02:51 virtu NetworkManager: (eth0): now managed Apr 27 01:02:51 virtu NetworkManager: (eth0): device state change: 1 -> 2 (reason 2) Apr 27 01:02:51 virtu NetworkManager: (eth0): bringing up device. Apr 27 01:02:51 virtu NetworkManager: (eth0): preparing device. Apr 27 01:02:51 virtu NetworkManager: (eth0): deactivating device (reason: 2). Apr 27 01:02:51 virtu kernel: [43199.138826] ADDRCONF(NETDEV_UP): eth0: link is not ready Apr 27 01:02:51 virtu NetworkManager: (wlan0): supplicant interface state: starting -> ready Apr 27 01:02:51 virtu NetworkManager: (wlan0): device state change: 2 -> 3 (reason 42) Apr 27 01:02:51 virtu NetworkManager: Activation (wlan0) starting connection 'piopio' Apr 27 01:02:51 virtu NetworkManager: (wlan0): device state change: 3 -> 4 (reason 0) Apr 27 01:02:51 virtu NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... Apr 27 01:02:51 virtu NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... Apr 27 01:02:51 virtu NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... Apr 27 01:02:51 virtu NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. Apr 27 01:02:51 virtu NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... Apr 27 01:02:51 virtu NetworkManager: (wlan0): device state change: 4 -> 5 (reason 0) Apr 27 01:02:51 virtu NetworkManager: Activation (wlan0/wireless): connection 'piopio' has security, and secrets exist. No new secrets needed. Apr 27 01:02:51 virtu NetworkManager: Config: added 'ssid' value 'piopio' Apr 27 01:02:51 virtu NetworkManager: Config: added 'scan_ssid' value '1' Apr 27 01:02:51 virtu NetworkManager: Config: added 'key_mgmt' value 'WPA-PSK' Apr 27 01:02:51 virtu NetworkManager: Config: added 'psk' value '' Apr 27 01:02:51 virtu NetworkManager: nm_setting_802_1x_get_pkcs11_engine_path: assertion `NM_IS_SETTING_802_1X (setting)' failed Apr 27 01:02:51 virtu NetworkManager: nm_setting_802_1x_get_pkcs11_module_path: assertion `NM_IS_SETTING_802_1X (setting)' failed Apr 27 01:02:51 virtu NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. Apr 27 01:02:51 virtu NetworkManager: Config: set interface ap_scan to 1 Apr 27 01:02:51 virtu NetworkManager: (wlan0): supplicant connection state: scanning -> disconnected Apr 27 01:02:51 virtu kernel: [43199.268390] usb 3-2.2: USB disconnect, address 4 Apr 27 01:02:51 virtu NetworkManager: bluez_manager_bdaddr_removed_cb: BT device 34:7E:39:42:AF:B9 removed Apr 27 01:02:51 virtu kernel: [43199.316342] usb 3-2.3: USB disconnect, address 5 Apr 27 01:02:51 virtu kernel: [43199.584051] usb 3-2: new full speed USB device using uhci_hcd and address 6 Apr 27 01:02:51 virtu kernel: [43199.585495] input: PS/2 Mouse as /devices/platform/i8042/serio1/input/input23 Apr 27 01:02:51 virtu kernel: [43199.600965] input: AlpsPS/2 ALPS GlidePoint as /devices/platform/i8042/serio1/input/input24 Apr 27 01:02:51 virtu kernel: [43199.761770] usb 3-2: configuration #1 chosen from 1 choice Apr 27 01:02:51 virtu kernel: [43199.763531] hub 3-2:1.0: USB hub found Apr 27 01:02:51 virtu kernel: [43199.768172] hub 3-2:1.0: 3 ports detected Apr 27 01:02:52 virtu kernel: [43200.053423] usb 3-2.1: new full speed USB device using uhci_hcd and address 7 Apr 27 01:02:52 virtu kernel: [43200.184517] usb 3-2.1: configuration #1 chosen from 1 choice Apr 27 01:02:52 virtu kernel: [43200.264424] usb 3-2.2: new full speed USB device using uhci_hcd and address 8 Apr 27 01:02:52 virtu kernel: [43200.398671] usb 3-2.2: configuration #1 chosen from 1 choice Apr 27 01:02:52 virtu kernel: [43200.408568] input: Broadcom Corp as /devices/pci0000:00/0000:00:1a.0/usb3/3-2/3-2.2/3-2.2:1.0/input/input25 Apr 27 01:02:52 virtu kernel: [43200.408963] generic-usb 0003:0A5C:4502.0009: input,hidraw1: USB HID v1.11 Keyboard [Broadcom Corp] on usb-0000:00:1a.0-2.2/input0 Apr 27 01:02:52 virtu kernel: [43200.480559] usb 3-2.3: new full speed USB device using uhci_hcd and address 9 Apr 27 01:02:52 virtu kernel: [43200.607960] usb 3-2.3: configuration #1 chosen from 1 choice Apr 27 01:02:52 virtu kernel: [43200.620623] input: Broadcom Corp as /devices/pci0000:00/0000:00:1a.0/usb3/3-2/3-2.3/3-2.3:1.0/input/input26 Apr 27 01:02:52 virtu kernel: [43200.620902] generic-usb 0003:0A5C:4503.000A: input,hidraw2: USB HID v1.11 Mouse [Broadcom Corp] on usb-0000:00:1a.0-2.3/input0 Apr 27 01:02:56 virtu NetworkManager: (wlan0): supplicant connection state: disconnected -> scanning Apr 27 01:02:58 virtu NetworkManager: (wlan0): supplicant connection state: scanning -> associating Apr 27 01:02:58 virtu kernel: [43206.047057] wlan0: deauthenticating from 00:0c:f6:61:4a:bc by local choice (reason=3) Apr 27 01:02:58 virtu kernel: [43206.083724] wlan0: direct probe to AP 00:0c:f6:61:4a:bc (try 1) Apr 27 01:02:58 virtu kernel: [43206.106130] wlan0: direct probe responded Apr 27 01:02:58 virtu kernel: [43206.106135] wlan0: authenticate with AP 00:0c:f6:61:4a:bc (try 1) Apr 27 01:02:58 virtu kernel: [43206.118171] wlan0: authenticated Apr 27 01:02:58 virtu kernel: [43206.118193] wlan0: associate with AP 00:0c:f6:61:4a:bc (try 1) Apr 27 01:02:58 virtu kernel: [43206.131111] wlan0: RX AssocResp from 00:0c:f6:61:4a:bc (capab=0x411 status=0 aid=1) Apr 27 01:02:58 virtu kernel: [43206.131115] wlan0: associated Apr 27 01:02:58 virtu kernel: [43206.139927] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Apr 27 01:02:58 virtu NetworkManager: (wlan0): supplicant connection state: associating -> associated Apr 27 01:02:58 virtu NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake Apr 27 01:02:58 virtu NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake Apr 27 01:02:58 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 01:02:58 virtu NetworkManager: Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'piopio'. Apr 27 01:02:58 virtu NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled. Apr 27 01:02:58 virtu NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) started... Apr 27 01:02:58 virtu NetworkManager: (wlan0): device state change: 5 -> 7 (reason 0) Apr 27 01:02:58 virtu NetworkManager: Activation (wlan0) Beginning DHCP transaction (timeout in 45 seconds) Apr 27 01:02:58 virtu NetworkManager: dhclient started with pid 9457 Apr 27 01:02:58 virtu NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) scheduled... Apr 27 01:02:58 virtu NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete. Apr 27 01:02:58 virtu NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) started... Apr 27 01:02:58 virtu NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) complete. Apr 27 01:02:58 virtu dhclient: Internet Systems Consortium DHCP Client V3.1.3 Apr 27 01:02:58 virtu dhclient: Copyright 2004-2009 Internet Systems Consortium. Apr 27 01:02:58 virtu dhclient: All rights reserved. Apr 27 01:02:58 virtu dhclient: For info, please visit https://www.isc.org/software/dhcp/ Apr 27 01:02:58 virtu dhclient: Apr 27 01:02:58 virtu NetworkManager: DHCP: device wlan0 state changed normal exit -> preinit Apr 27 01:02:58 virtu dhclient: Listening on LPF/wlan0/00:1f:3c:a9:fe:1a Apr 27 01:02:58 virtu dhclient: Sending on LPF/wlan0/00:1f:3c:a9:fe:1a Apr 27 01:02:58 virtu dhclient: Sending on Socket/fallback Apr 27 01:03:00 virtu dhclient: DHCPREQUEST of 192.168.15.91 on wlan0 to 255.255.255.255 port 67 Apr 27 01:03:00 virtu dhclient: DHCPACK of 192.168.15.91 from 192.168.15.254 Apr 27 01:03:00 virtu dhclient: bound to 192.168.15.91 -- renewal in 285510 seconds. Apr 27 01:03:00 virtu NetworkManager: DHCP: device wlan0 state changed preinit -> reboot Apr 27 01:03:00 virtu NetworkManager: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled... Apr 27 01:03:00 virtu NetworkManager: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started... Apr 27 01:03:00 virtu NetworkManager: address 192.168.15.91 Apr 27 01:03:00 virtu NetworkManager: prefix 24 (255.255.255.0) Apr 27 01:03:00 virtu NetworkManager: gateway 192.168.15.254 Apr 27 01:03:00 virtu NetworkManager: nameserver '192.168.15.254' Apr 27 01:03:00 virtu NetworkManager: domain name 'local' Apr 27 01:03:00 virtu NetworkManager: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled... Apr 27 01:03:00 virtu NetworkManager: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete. Apr 27 01:03:00 virtu NetworkManager: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started... Apr 27 01:03:01 virtu NetworkManager: (wlan0): device state change: 7 -> 8 (reason 0) Apr 27 01:03:01 virtu NetworkManager: Policy set 'piopio' (wlan0) as default for routing and DNS. Apr 27 01:03:01 virtu NetworkManager: Activation (wlan0) successful, device activated. Apr 27 01:03:01 virtu NetworkManager: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete. Apr 27 01:03:08 virtu kernel: [43216.188035] wlan0: no IPv6 routers present Apr 27 01:05:13 virtu NetworkManager: (wlan0): supplicant connection state: completed -> group handshake Apr 27 01:05:13 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 02:05:42 virtu NetworkManager: (wlan0): supplicant connection state: completed -> group handshake Apr 27 02:05:42 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 03:06:08 virtu NetworkManager: (wlan0): supplicant connection state: completed -> group handshake Apr 27 03:06:08 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 04:06:34 virtu NetworkManager: (wlan0): supplicant connection state: completed -> group handshake Apr 27 04:06:34 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 05:07:07 virtu NetworkManager: (wlan0): supplicant connection state: completed -> group handshake Apr 27 05:07:07 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 06:07:43 virtu NetworkManager: (wlan0): supplicant connection state: completed -> group handshake Apr 27 06:07:43 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 06:54:35 virtu kernel: [64302.470106] wlan0: deauthenticated from 00:0c:f6:61:4a:bc (Reason: 6) Apr 27 06:54:35 virtu NetworkManager: (wlan0): supplicant connection state: completed -> disconnected Apr 27 06:54:35 virtu NetworkManager: (wlan0): supplicant connection state: disconnected -> scanning Apr 27 06:54:37 virtu NetworkManager: (wlan0): supplicant connection state: scanning -> associating Apr 27 06:54:37 virtu kernel: [64304.331750] wlan0: direct probe to AP 00:0c:f6:61:4a:bc (try 1) Apr 27 06:54:37 virtu kernel: [64304.335807] wlan0: direct probe responded Apr 27 06:54:37 virtu kernel: [64304.335818] wlan0: authenticate with AP 00:0c:f6:61:4a:bc (try 1) Apr 27 06:54:37 virtu kernel: [64304.337987] wlan0: authenticated Apr 27 06:54:37 virtu kernel: [64304.338030] wlan0: associate with AP 00:0c:f6:61:4a:bc (try 1) Apr 27 06:54:37 virtu kernel: [64304.346155] wlan0: RX AssocResp from 00:0c:f6:61:4a:bc (capab=0x411 status=0 aid=1) Apr 27 06:54:37 virtu kernel: [64304.346164] wlan0: associated Apr 27 06:54:37 virtu NetworkManager: (wlan0): supplicant connection state: associating -> associated Apr 27 06:54:37 virtu NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake Apr 27 06:54:37 virtu NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake Apr 27 06:54:37 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 07:08:11 virtu NetworkManager: (wlan0): supplicant connection state: completed -> group handshake Apr 27 07:08:11 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 08:08:41 virtu NetworkManager: (wlan0): supplicant connection state: completed -> group handshake Apr 27 08:08:41 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 09:09:12 virtu NetworkManager: (wlan0): supplicant connection state: completed -> group handshake Apr 27 09:09:12 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 10:09:43 virtu NetworkManager: (wlan0): supplicant connection state: completed -> group handshake Apr 27 10:09:43 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 11:10:10 virtu NetworkManager: (wlan0): supplicant connection state: completed -> group handshake Apr 27 11:10:10 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 12:10:40 virtu NetworkManager: (wlan0): supplicant connection state: completed -> group handshake Apr 27 12:10:40 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 12:26:58 virtu kernel: [84245.694091] wlan0: deauthenticated from 00:0c:f6:61:4a:bc (Reason: 3) Apr 27 12:26:58 virtu NetworkManager: (wlan0): supplicant connection state: completed -> disconnected Apr 27 12:26:58 virtu NetworkManager: (wlan0): supplicant connection state: disconnected -> scanning Apr 27 12:27:00 virtu kernel: [84247.560151] wlan0: direct probe to AP 00:0c:f6:61:4a:bc (try 1) Apr 27 12:27:00 virtu NetworkManager: (wlan0): supplicant connection state: scanning -> associating Apr 27 12:27:00 virtu kernel: [84247.564275] wlan0: direct probe responded Apr 27 12:27:00 virtu kernel: [84247.564284] wlan0: authenticate with AP 00:0c:f6:61:4a:bc (try 1) Apr 27 12:27:00 virtu kernel: [84247.566372] wlan0: authenticated Apr 27 12:27:00 virtu kernel: [84247.566415] wlan0: associate with AP 00:0c:f6:61:4a:bc (try 1) Apr 27 12:27:00 virtu kernel: [84247.576265] wlan0: RX AssocResp from 00:0c:f6:61:4a:bc (capab=0x411 status=0 aid=1) Apr 27 12:27:00 virtu kernel: [84247.576269] wlan0: associated Apr 27 12:27:00 virtu NetworkManager: (wlan0): supplicant connection state: associating -> associated Apr 27 12:27:00 virtu NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake Apr 27 12:27:00 virtu NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake Apr 27 12:27:00 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 12:42:24 virtu kernel: [85171.512189] usb 2-3: new high speed USB device using ehci_hcd and address 8 Apr 27 12:42:24 virtu kernel: [85171.646392] usb 2-3: configuration #1 chosen from 2 choices Apr 27 12:42:24 virtu kernel: [85171.856078] Initializing USB Mass Storage driver... Apr 27 12:42:24 virtu kernel: [85171.856448] scsi5 : SCSI emulation for USB Mass Storage devices Apr 27 12:42:24 virtu kernel: [85171.856667] usbcore: registered new interface driver usb-storage Apr 27 12:42:24 virtu kernel: [85171.856675] USB Mass Storage support registered. Apr 27 12:42:24 virtu kernel: [85171.857426] usb-storage: device found at 8 Apr 27 12:42:24 virtu kernel: [85171.857431] usb-storage: waiting for device to settle before scanning Apr 27 12:42:29 virtu kernel: [85176.856458] usb-storage: device scan complete Apr 27 12:42:29 virtu kernel: [85176.857063] scsi 5:0:0:0: Direct-Access Nokia N900 031 PQ: 0 ANSI: 2 Apr 27 12:42:29 virtu kernel: [85176.857614] scsi 5:0:0:1: Direct-Access Nokia N900 031 PQ: 0 ANSI: 2 Apr 27 12:42:29 virtu kernel: [85176.858678] sd 5:0:0:0: Attached scsi generic sg2 type 0 Apr 27 12:42:29 virtu kernel: [85176.863596] sd 5:0:0:1: Attached scsi generic sg3 type 0 Apr 27 12:42:29 virtu kernel: [85176.870235] sd 5:0:0:0: [sdb] Attached SCSI removable disk Apr 27 12:42:29 virtu kernel: [85176.871064] sd 5:0:0:1: [sdc] Attached SCSI removable disk Apr 27 12:42:37 virtu kernel: [85184.457383] usb 2-3: USB disconnect, address 8 Apr 27 12:42:39 virtu kernel: [85186.205078] usb 2-3: new high speed USB device using ehci_hcd and address 9 Apr 27 12:42:39 virtu kernel: [85186.339474] usb 2-3: configuration #1 chosen from 2 choices Apr 27 12:42:39 virtu kernel: [85186.455463] cdc_subset: probe of 2-3:1.0 failed with error -22 Apr 27 12:42:39 virtu kernel: [85186.458731] usb0: register 'cdc_subset' at usb-0000:00:1d.7-3, Linux Device, f6:c0:73:71:72:69 Apr 27 12:42:39 virtu kernel: [85186.458789] usbcore: registered new interface driver cdc_subset Apr 27 12:42:39 virtu kernel: [85186.478438] cdc_ether: probe of 2-3:1.0 failed with error -16 Apr 27 12:42:39 virtu kernel: [85186.478485] usbcore: registered new interface driver cdc_ether Apr 27 12:42:39 virtu NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-3/2-3:1.1/net/usb0, iface: usb0) Apr 27 12:42:39 virtu NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-3/2-3:1.1/net/usb0, iface: usb0): no ifupdown configuration found. Apr 27 12:42:39 virtu NetworkManager: (usb0): carrier is OFF Apr 27 12:42:39 virtu NetworkManager: (usb0): new Ethernet device (driver: 'cdc_subset') Apr 27 12:42:39 virtu NetworkManager: (usb0): exported as /org/freedesktop/NetworkManager/Devices/2 Apr 27 12:42:39 virtu NetworkManager: (usb0): now managed Apr 27 12:42:39 virtu NetworkManager: (usb0): device state change: 1 -> 2 (reason 2) Apr 27 12:42:39 virtu NetworkManager: (usb0): bringing up device. Apr 27 12:42:39 virtu NetworkManager: (usb0): preparing device. Apr 27 12:42:39 virtu NetworkManager: (usb0): deactivating device (reason: 2). Apr 27 12:42:39 virtu NetworkManager: (usb0): carrier now ON (device state 2) Apr 27 12:42:39 virtu NetworkManager: (usb0): device state change: 2 -> 3 (reason 40) Apr 27 12:42:39 virtu NetworkManager: Activation (usb0) starting connection 'dhcp' Apr 27 12:42:39 virtu NetworkManager: (usb0): device state change: 3 -> 4 (reason 0) Apr 27 12:42:39 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) scheduled... Apr 27 12:42:39 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) started... Apr 27 12:42:39 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) scheduled... Apr 27 12:42:39 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) complete. Apr 27 12:42:39 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) starting... Apr 27 12:42:39 virtu NetworkManager: (usb0): device state change: 4 -> 5 (reason 0) Apr 27 12:42:39 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) successful. Apr 27 12:42:39 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) scheduled. Apr 27 12:42:39 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) complete. Apr 27 12:42:39 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) started... Apr 27 12:42:39 virtu NetworkManager: (usb0): device state change: 5 -> 7 (reason 0) Apr 27 12:42:39 virtu NetworkManager: Activation (usb0) Beginning DHCP transaction (timeout in 45 seconds) Apr 27 12:42:39 virtu NetworkManager: dhclient started with pid 10501 Apr 27 12:42:39 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) scheduled... Apr 27 12:42:39 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) complete. Apr 27 12:42:39 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) started... Apr 27 12:42:39 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) complete. Apr 27 12:42:39 virtu dhclient: Internet Systems Consortium DHCP Client V3.1.3 Apr 27 12:42:39 virtu dhclient: Copyright 2004-2009 Internet Systems Consortium. Apr 27 12:42:39 virtu dhclient: All rights reserved. Apr 27 12:42:39 virtu dhclient: For info, please visit https://www.isc.org/software/dhcp/ Apr 27 12:42:39 virtu dhclient: Apr 27 12:42:39 virtu NetworkManager: DHCP: device usb0 state changed (null) -> preinit Apr 27 12:42:39 virtu dhclient: Listening on LPF/usb0/f6:c0:73:71:72:69 Apr 27 12:42:39 virtu dhclient: Sending on LPF/usb0/f6:c0:73:71:72:69 Apr 27 12:42:39 virtu dhclient: Sending on Socket/fallback Apr 27 12:42:42 virtu dhclient: DHCPDISCOVER on usb0 to 255.255.255.255 port 67 interval 3 Apr 27 12:42:45 virtu dhclient: DHCPDISCOVER on usb0 to 255.255.255.255 port 67 interval 8 Apr 27 12:42:49 virtu kernel: [85196.912058] usb0: no IPv6 routers present Apr 27 12:42:50 virtu NetworkManager: (usb0): device state change: 7 -> 3 (reason 0) Apr 27 12:42:50 virtu NetworkManager: (usb0): deactivating device (reason: 0). Apr 27 12:42:50 virtu NetworkManager: (usb0): canceled DHCP transaction, dhcp client pid 10501 Apr 27 12:42:50 virtu NetworkManager: Policy set 'piopio' (wlan0) as default for routing and DNS. Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) starting connection 'maemo' Apr 27 12:42:50 virtu NetworkManager: (usb0): device state change: 3 -> 4 (reason 0) Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) scheduled... Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) started... Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) scheduled... Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) complete. Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) starting... Apr 27 12:42:50 virtu NetworkManager: (usb0): device state change: 4 -> 5 (reason 0) Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) successful. Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) scheduled. Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) complete. Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) started... Apr 27 12:42:50 virtu NetworkManager: (usb0): device state change: 5 -> 7 (reason 0) Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP4 Configure Get) scheduled... Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) scheduled... Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) complete. Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP4 Configure Get) started... Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP4 Configure Get) complete. Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) started... Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 5 of 5 (IP Configure Commit) scheduled... Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) complete. Apr 27 12:42:50 virtu NetworkManager: Activation (usb0) Stage 5 of 5 (IP Configure Commit) started... Apr 27 12:42:51 virtu NetworkManager: Policy set 'piopio' (wlan0) as default for routing and DNS. Apr 27 12:42:51 virtu NetworkManager: (usb0): device state change: 7 -> 8 (reason 0) Apr 27 12:42:51 virtu NetworkManager: Activation (usb0) successful, device activated. Apr 27 12:42:51 virtu NetworkManager: Activation (usb0) Stage 5 of 5 (IP Configure Commit) complete. Apr 27 12:45:02 virtu kernel: [85329.541430] usb 2-3: USB disconnect, address 9 Apr 27 12:45:02 virtu kernel: [85329.541924] usb0: unregister 'cdc_subset' usb-0000:00:1d.7-3, Linux Device Apr 27 12:45:02 virtu NetworkManager: (usb0): carrier now OFF (device state 8, deferring action for 4 seconds) Apr 27 12:45:03 virtu NetworkManager: SCPlugin-Ifupdown: devices removed (path: /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-3/2-3:1.1/net/usb0, iface: usb0) Apr 27 12:45:03 virtu NetworkManager: (usb0): now unmanaged Apr 27 12:45:03 virtu NetworkManager: (usb0): device state change: 8 -> 1 (reason 36) Apr 27 12:45:03 virtu NetworkManager: (usb0): deactivating device (reason: 36). Apr 27 12:45:03 virtu NetworkManager: Policy set 'piopio' (wlan0) as default for routing and DNS. Apr 27 12:45:03 virtu NetworkManager: (usb0): cleaning up... Apr 27 12:45:03 virtu kernel: [85330.164123] usb 2-3: new high speed USB device using ehci_hcd and address 10 Apr 27 12:45:03 virtu kernel: [85330.298643] usb 2-3: configuration #1 chosen from 2 choices Apr 27 12:45:03 virtu kernel: [85330.304565] usb0: register 'cdc_ether' at usb-0000:00:1d.7-3, CDC Ethernet Device, 9e:ed:68:0f:9d:2a Apr 27 12:45:03 virtu NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-3/2-3:1.8/net/usb0, iface: usb0) Apr 27 12:45:03 virtu NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-3/2-3:1.8/net/usb0, iface: usb0): no ifupdown configuration found. Apr 27 12:45:03 virtu NetworkManager: (usb0): carrier is OFF Apr 27 12:45:03 virtu NetworkManager: (usb0): new Ethernet device (driver: 'cdc_ether') Apr 27 12:45:03 virtu NetworkManager: (usb0): exported as /org/freedesktop/NetworkManager/Devices/3 Apr 27 12:45:03 virtu NetworkManager: (usb0): now managed Apr 27 12:45:03 virtu NetworkManager: (usb0): device state change: 1 -> 2 (reason 2) Apr 27 12:45:03 virtu NetworkManager: (usb0): bringing up device. Apr 27 12:45:03 virtu NetworkManager: (usb0): preparing device. Apr 27 12:45:03 virtu NetworkManager: (usb0): deactivating device (reason: 2). Apr 27 12:45:03 virtu NetworkManager: (usb0): carrier now ON (device state 2) Apr 27 12:45:03 virtu NetworkManager: (usb0): device state change: 2 -> 3 (reason 40) Apr 27 12:45:03 virtu NetworkManager: Activation (usb0) starting connection 'dhcp' Apr 27 12:45:03 virtu NetworkManager: (usb0): device state change: 3 -> 4 (reason 0) Apr 27 12:45:03 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) scheduled... Apr 27 12:45:03 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) started... Apr 27 12:45:03 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) scheduled... Apr 27 12:45:03 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) complete. Apr 27 12:45:03 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) starting... Apr 27 12:45:03 virtu NetworkManager: (usb0): device state change: 4 -> 5 (reason 0) Apr 27 12:45:03 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) successful. Apr 27 12:45:03 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) scheduled. Apr 27 12:45:03 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) complete. Apr 27 12:45:03 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) started... Apr 27 12:45:03 virtu NetworkManager: (usb0): device state change: 5 -> 7 (reason 0) Apr 27 12:45:03 virtu NetworkManager: Activation (usb0) Beginning DHCP transaction (timeout in 45 seconds) Apr 27 12:45:03 virtu NetworkManager: dhclient started with pid 10668 Apr 27 12:45:03 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) scheduled... Apr 27 12:45:03 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) complete. Apr 27 12:45:03 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) started... Apr 27 12:45:03 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) complete. Apr 27 12:45:03 virtu dhclient: Internet Systems Consortium DHCP Client V3.1.3 Apr 27 12:45:03 virtu dhclient: Copyright 2004-2009 Internet Systems Consortium. Apr 27 12:45:03 virtu dhclient: All rights reserved. Apr 27 12:45:03 virtu dhclient: For info, please visit https://www.isc.org/software/dhcp/ Apr 27 12:45:03 virtu dhclient: Apr 27 12:45:03 virtu NetworkManager: DHCP: device usb0 state changed normal exit -> preinit Apr 27 12:45:03 virtu dhclient: Listening on LPF/usb0/9e:ed:68:0f:9d:2a Apr 27 12:45:03 virtu dhclient: Sending on LPF/usb0/9e:ed:68:0f:9d:2a Apr 27 12:45:03 virtu dhclient: Sending on Socket/fallback Apr 27 12:45:03 virtu dhclient: DHCPDISCOVER on usb0 to 255.255.255.255 port 67 interval 7 Apr 27 12:45:03 virtu kernel: [85330.369878] cdc_acm 2-3:1.6: This device cannot do calls on its own. It is not a modem. Apr 27 12:45:03 virtu kernel: [85330.370130] cdc_acm 2-3:1.6: ttyACM0: USB ACM device Apr 27 12:45:03 virtu kernel: [85330.371094] usbcore: registered new interface driver cdc_acm Apr 27 12:45:03 virtu kernel: [85330.371183] cdc_acm: v0.26:USB Abstract Control Model driver for USB modems and ISDN adapters Apr 27 12:45:03 virtu NetworkManager: (usb0): carrier now OFF (device state 7, deferring action for 4 seconds) Apr 27 12:45:03 virtu modem-manager: (ttyACM0) opening serial device... Apr 27 12:45:03 virtu modem-manager: (ttyACM0): probe requested by plugin 'Nokia' Apr 27 12:45:03 virtu kernel: [85330.415216] NET: Registered protocol family 35 Apr 27 12:45:03 virtu kernel: [85330.432944] usbcore: registered new interface driver cdc_phonet Apr 27 12:45:03 virtu NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-3/2-3:1.0/net/usbpn0, iface: usbpn0) Apr 27 12:45:03 virtu NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-3/2-3:1.0/net/usbpn0, iface: usbpn0): no ifupdown configuration found. Apr 27 12:45:08 virtu NetworkManager: (usb0): device state change: 7 -> 2 (reason 40) Apr 27 12:45:08 virtu NetworkManager: (usb0): deactivating device (reason: 40). Apr 27 12:45:08 virtu NetworkManager: (usb0): canceled DHCP transaction, dhcp client pid 10668 Apr 27 12:45:08 virtu NetworkManager: Policy set 'piopio' (wlan0) as default for routing and DNS. Apr 27 12:45:08 virtu modem-manager: (ttyACM0) closing serial device... Apr 27 12:45:08 virtu modem-manager: (Nokia): GSM modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-3 claimed port ttyACM0 Apr 27 12:45:08 virtu modem-manager: Added modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-3 Apr 27 12:45:08 virtu modem-manager: Exported modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-3 as /org/freedesktop/ModemManager/Modems/0 Apr 27 12:45:08 virtu NetworkManager: (ttyACM0): new GSM device (driver: 'cdc_acm') Apr 27 12:45:08 virtu NetworkManager: (ttyACM0): exported as /org/freedesktop/NetworkManager/Devices/4 Apr 27 12:45:08 virtu NetworkManager: (ttyACM0): now managed Apr 27 12:45:08 virtu NetworkManager: (ttyACM0): device state change: 1 -> 2 (reason 2) Apr 27 12:45:08 virtu NetworkManager: (ttyACM0): deactivating device (reason: 2). Apr 27 12:45:08 virtu NetworkManager: (ttyACM0): device state change: 2 -> 3 (reason 0) Apr 27 12:45:13 virtu kernel: [85340.445131] usb0: no IPv6 routers present Apr 27 12:45:21 virtu NetworkManager: (usb0): carrier now ON (device state 2) Apr 27 12:45:21 virtu NetworkManager: (usb0): device state change: 2 -> 3 (reason 40) Apr 27 12:45:21 virtu NetworkManager: Activation (usb0) starting connection 'dhcp' Apr 27 12:45:21 virtu NetworkManager: (usb0): device state change: 3 -> 4 (reason 0) Apr 27 12:45:21 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) scheduled... Apr 27 12:45:21 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) started... Apr 27 12:45:21 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) scheduled... Apr 27 12:45:21 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) complete. Apr 27 12:45:21 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) starting... Apr 27 12:45:21 virtu NetworkManager: (usb0): device state change: 4 -> 5 (reason 0) Apr 27 12:45:21 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) successful. Apr 27 12:45:21 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) scheduled. Apr 27 12:45:21 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) complete. Apr 27 12:45:21 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) started... Apr 27 12:45:21 virtu NetworkManager: (usb0): device state change: 5 -> 7 (reason 0) Apr 27 12:45:21 virtu NetworkManager: Activation (usb0) Beginning DHCP transaction (timeout in 45 seconds) Apr 27 12:45:21 virtu NetworkManager: dhclient started with pid 10684 Apr 27 12:45:21 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) scheduled... Apr 27 12:45:21 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) complete. Apr 27 12:45:21 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) started... Apr 27 12:45:21 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) complete. Apr 27 12:45:21 virtu dhclient: Internet Systems Consortium DHCP Client V3.1.3 Apr 27 12:45:21 virtu dhclient: Copyright 2004-2009 Internet Systems Consortium. Apr 27 12:45:21 virtu dhclient: All rights reserved. Apr 27 12:45:21 virtu dhclient: For info, please visit https://www.isc.org/software/dhcp/ Apr 27 12:45:21 virtu dhclient: Apr 27 12:45:21 virtu NetworkManager: DHCP: device usb0 state changed normal exit -> preinit Apr 27 12:45:21 virtu dhclient: Listening on LPF/usb0/9e:ed:68:0f:9d:2a Apr 27 12:45:21 virtu dhclient: Sending on LPF/usb0/9e:ed:68:0f:9d:2a Apr 27 12:45:21 virtu dhclient: Sending on Socket/fallback Apr 27 12:45:22 virtu dhclient: DHCPDISCOVER on usb0 to 255.255.255.255 port 67 interval 8 Apr 27 12:45:26 virtu NetworkManager: (usb0): device state change: 7 -> 3 (reason 0) Apr 27 12:45:26 virtu NetworkManager: (usb0): deactivating device (reason: 0). Apr 27 12:45:26 virtu NetworkManager: (usb0): canceled DHCP transaction, dhcp client pid 10684 Apr 27 12:45:26 virtu NetworkManager: Policy set 'piopio' (wlan0) as default for routing and DNS. Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) starting connection 'maemo' Apr 27 12:45:26 virtu NetworkManager: (usb0): device state change: 3 -> 4 (reason 0) Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) scheduled... Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) started... Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) scheduled... Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) complete. Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) starting... Apr 27 12:45:26 virtu NetworkManager: (usb0): device state change: 4 -> 5 (reason 0) Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) successful. Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) scheduled. Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) complete. Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) started... Apr 27 12:45:26 virtu NetworkManager: (usb0): device state change: 5 -> 7 (reason 0) Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP4 Configure Get) scheduled... Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) scheduled... Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) complete. Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP4 Configure Get) started... Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP4 Configure Get) complete. Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) started... Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 5 of 5 (IP Configure Commit) scheduled... Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) complete. Apr 27 12:45:26 virtu NetworkManager: Activation (usb0) Stage 5 of 5 (IP Configure Commit) started... Apr 27 12:45:27 virtu NetworkManager: Policy set 'piopio' (wlan0) as default for routing and DNS. Apr 27 12:45:27 virtu NetworkManager: (usb0): device state change: 7 -> 8 (reason 0) Apr 27 12:45:27 virtu NetworkManager: Activation (usb0) successful, device activated. Apr 27 12:45:27 virtu NetworkManager: Activation (usb0) Stage 5 of 5 (IP Configure Commit) complete. Apr 27 12:58:13 virtu kernel: [86120.399180] usb 2-3: USB disconnect, address 10 Apr 27 12:58:13 virtu NetworkManager: SCPlugin-Ifupdown: devices removed (path: /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-3/2-3:1.0/net/usbpn0, iface: usbpn0) Apr 27 12:58:13 virtu kernel: [86120.422186] usb0: unregister 'cdc_ether' usb-0000:00:1d.7-3, CDC Ethernet Device Apr 27 12:58:13 virtu modem-manager: Removed modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-3 Apr 27 12:58:13 virtu NetworkManager: (ttyACM0): now unmanaged Apr 27 12:58:13 virtu NetworkManager: (ttyACM0): device state change: 3 -> 1 (reason 36) Apr 27 12:58:13 virtu NetworkManager: (ttyACM0): cleaning up... Apr 27 12:58:13 virtu NetworkManager: (ttyACM0): taking down device. Apr 27 12:58:13 virtu NetworkManager: (usb0): carrier now OFF (device state 8, deferring action for 4 seconds) Apr 27 12:58:13 virtu NetworkManager: SCPlugin-Ifupdown: devices removed (path: /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-3/2-3:1.8/net/usb0, iface: usb0) Apr 27 12:58:13 virtu NetworkManager: (usb0): now unmanaged Apr 27 12:58:13 virtu NetworkManager: (usb0): device state change: 8 -> 1 (reason 36) Apr 27 12:58:13 virtu NetworkManager: (usb0): deactivating device (reason: 36). Apr 27 12:58:13 virtu NetworkManager: Policy set 'piopio' (wlan0) as default for routing and DNS. Apr 27 12:58:13 virtu NetworkManager: (usb0): cleaning up... Apr 27 13:11:02 virtu NetworkManager: (wlan0): supplicant connection state: completed -> group handshake Apr 27 13:11:02 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 14:11:34 virtu NetworkManager: (wlan0): supplicant connection state: completed -> group handshake Apr 27 14:11:34 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 14:21:34 virtu kernel: [91120.785643] wlan0: deauthenticated from 00:0c:f6:61:4a:bc (Reason: 3) Apr 27 14:21:34 virtu NetworkManager: (wlan0): supplicant connection state: completed -> disconnected Apr 27 14:21:34 virtu NetworkManager: (wlan0): supplicant connection state: disconnected -> scanning Apr 27 14:21:36 virtu NetworkManager: (wlan0): supplicant connection state: scanning -> associating Apr 27 14:21:36 virtu kernel: [91122.569344] wlan0: direct probe to AP 00:0c:f6:61:4a:bc (try 1) Apr 27 14:21:36 virtu kernel: [91122.768193] wlan0: direct probe to AP 00:0c:f6:61:4a:bc (try 2) Apr 27 14:21:36 virtu kernel: [91122.772449] wlan0: direct probe responded Apr 27 14:21:36 virtu kernel: [91122.772459] wlan0: authenticate with AP 00:0c:f6:61:4a:bc (try 1) Apr 27 14:21:36 virtu kernel: [91122.774557] wlan0: authenticated Apr 27 14:21:36 virtu kernel: [91122.774602] wlan0: associate with AP 00:0c:f6:61:4a:bc (try 1) Apr 27 14:21:36 virtu kernel: [91122.783256] wlan0: RX AssocResp from 00:0c:f6:61:4a:bc (capab=0x411 status=0 aid=1) Apr 27 14:21:36 virtu kernel: [91122.783265] wlan0: associated Apr 27 14:21:36 virtu NetworkManager: (wlan0): supplicant connection state: associating -> associated Apr 27 14:21:36 virtu NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake Apr 27 14:21:36 virtu NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake Apr 27 14:21:36 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 15:11:54 virtu NetworkManager: (wlan0): supplicant connection state: completed -> group handshake Apr 27 15:11:54 virtu NetworkManager: (wlan0): supplicant connection state: group handshake -> completed Apr 27 15:36:18 virtu kernel: [95604.972131] usb 2-3: new high speed USB device using ehci_hcd and address 11 Apr 27 15:36:18 virtu kernel: [95605.105552] usb 2-3: configuration #1 chosen from 2 choices Apr 27 15:36:18 virtu kernel: [95605.107227] scsi6 : SCSI emulation for USB Mass Storage devices Apr 27 15:36:18 virtu kernel: [95605.107545] usb-storage: device found at 11 Apr 27 15:36:18 virtu kernel: [95605.107551] usb-storage: waiting for device to settle before scanning Apr 27 15:36:23 virtu kernel: [95610.105382] usb-storage: device scan complete Apr 27 15:36:23 virtu kernel: [95610.106009] scsi 6:0:0:0: Direct-Access Nokia N900 031 PQ: 0 ANSI: 2 Apr 27 15:36:23 virtu kernel: [95610.106473] scsi 6:0:0:1: Direct-Access Nokia N900 031 PQ: 0 ANSI: 2 Apr 27 15:36:23 virtu kernel: [95610.107499] sd 6:0:0:0: Attached scsi generic sg2 type 0 Apr 27 15:36:23 virtu kernel: [95610.107849] sd 6:0:0:1: Attached scsi generic sg3 type 0 Apr 27 15:36:23 virtu kernel: [95610.114562] sd 6:0:0:0: [sdb] Attached SCSI removable disk Apr 27 15:36:23 virtu kernel: [95610.119737] sd 6:0:0:1: [sdc] Attached SCSI removable disk Apr 27 15:36:45 virtu kernel: [95632.377606] usb 2-3: USB disconnect, address 11 Apr 27 15:36:47 virtu kernel: [95633.988107] usb 2-3: new high speed USB device using ehci_hcd and address 12 Apr 27 15:36:47 virtu kernel: [95634.122194] usb 2-3: configuration #1 chosen from 2 choices Apr 27 15:36:47 virtu kernel: [95634.123647] cdc_subset: probe of 2-3:1.0 failed with error -22 Apr 27 15:36:47 virtu kernel: [95634.126929] usb0: register 'cdc_ether' at usb-0000:00:1d.7-3, CDC Ethernet Device, a2:28:07:bb:69:f4 Apr 27 15:36:47 virtu NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-3/2-3:1.0/net/usb0, iface: usb0) Apr 27 15:36:47 virtu NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-3/2-3:1.0/net/usb0, iface: usb0): no ifupdown configuration found. Apr 27 15:36:47 virtu NetworkManager: (usb0): carrier is OFF Apr 27 15:36:47 virtu NetworkManager: (usb0): new Ethernet device (driver: 'cdc_ether') Apr 27 15:36:47 virtu NetworkManager: (usb0): exported as /org/freedesktop/NetworkManager/Devices/5 Apr 27 15:36:47 virtu NetworkManager: (usb0): now managed Apr 27 15:36:47 virtu NetworkManager: (usb0): device state change: 1 -> 2 (reason 2) Apr 27 15:36:47 virtu NetworkManager: (usb0): bringing up device. Apr 27 15:36:47 virtu NetworkManager: (usb0): preparing device. Apr 27 15:36:47 virtu NetworkManager: (usb0): deactivating device (reason: 2). Apr 27 15:36:47 virtu NetworkManager: (usb0): carrier now ON (device state 2) Apr 27 15:36:47 virtu NetworkManager: (usb0): device state change: 2 -> 3 (reason 40) Apr 27 15:36:47 virtu NetworkManager: Activation (usb0) starting connection 'dhcp' Apr 27 15:36:47 virtu NetworkManager: (usb0): device state change: 3 -> 4 (reason 0) Apr 27 15:36:47 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) scheduled... Apr 27 15:36:47 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) started... Apr 27 15:36:47 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) scheduled... Apr 27 15:36:47 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) complete. Apr 27 15:36:47 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) starting... Apr 27 15:36:47 virtu NetworkManager: (usb0): device state change: 4 -> 5 (reason 0) Apr 27 15:36:47 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) successful. Apr 27 15:36:47 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) scheduled. Apr 27 15:36:47 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) complete. Apr 27 15:36:47 virtu NetworkManager: (usb0): carrier now OFF (device state 5, deferring action for 4 seconds) Apr 27 15:36:47 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) started... Apr 27 15:36:47 virtu NetworkManager: (usb0): device state change: 5 -> 7 (reason 0) Apr 27 15:36:47 virtu NetworkManager: Activation (usb0) Beginning DHCP transaction (timeout in 45 seconds) Apr 27 15:36:47 virtu NetworkManager: dhclient started with pid 11626 Apr 27 15:36:47 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) scheduled... Apr 27 15:36:47 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) complete. Apr 27 15:36:47 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) started... Apr 27 15:36:47 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) complete. Apr 27 15:36:47 virtu dhclient: Internet Systems Consortium DHCP Client V3.1.3 Apr 27 15:36:47 virtu dhclient: Copyright 2004-2009 Internet Systems Consortium. Apr 27 15:36:47 virtu dhclient: All rights reserved. Apr 27 15:36:47 virtu dhclient: For info, please visit https://www.isc.org/software/dhcp/ Apr 27 15:36:47 virtu dhclient: Apr 27 15:36:47 virtu NetworkManager: DHCP: device usb0 state changed normal exit -> preinit Apr 27 15:36:47 virtu dhclient: Listening on LPF/usb0/a2:28:07:bb:69:f4 Apr 27 15:36:47 virtu dhclient: Sending on LPF/usb0/a2:28:07:bb:69:f4 Apr 27 15:36:47 virtu dhclient: Sending on Socket/fallback Apr 27 15:36:48 virtu dhclient: DHCPDISCOVER on usb0 to 255.255.255.255 port 67 interval 8 Apr 27 15:36:52 virtu NetworkManager: (usb0): device state change: 7 -> 2 (reason 40) Apr 27 15:36:52 virtu NetworkManager: (usb0): deactivating device (reason: 40). Apr 27 15:36:52 virtu NetworkManager: (usb0): canceled DHCP transaction, dhcp client pid 11626 Apr 27 15:36:52 virtu NetworkManager: Policy set 'piopio' (wlan0) as default for routing and DNS. Apr 27 15:36:53 virtu NetworkManager: (usb0): carrier now ON (device state 2) Apr 27 15:36:53 virtu NetworkManager: (usb0): device state change: 2 -> 3 (reason 40) Apr 27 15:36:53 virtu NetworkManager: Activation (usb0) starting connection 'dhcp' Apr 27 15:36:53 virtu NetworkManager: (usb0): device state change: 3 -> 4 (reason 0) Apr 27 15:36:53 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) scheduled... Apr 27 15:36:53 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) started... Apr 27 15:36:53 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) scheduled... Apr 27 15:36:53 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) complete. Apr 27 15:36:53 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) starting... Apr 27 15:36:53 virtu NetworkManager: (usb0): device state change: 4 -> 5 (reason 0) Apr 27 15:36:53 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) successful. Apr 27 15:36:53 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) scheduled. Apr 27 15:36:53 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) complete. Apr 27 15:36:53 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) started... Apr 27 15:36:53 virtu NetworkManager: (usb0): device state change: 5 -> 7 (reason 0) Apr 27 15:36:53 virtu NetworkManager: Activation (usb0) Beginning DHCP transaction (timeout in 45 seconds) Apr 27 15:36:53 virtu NetworkManager: dhclient started with pid 11629 Apr 27 15:36:53 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) scheduled... Apr 27 15:36:53 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) complete. Apr 27 15:36:53 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) started... Apr 27 15:36:53 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) complete. Apr 27 15:36:53 virtu dhclient: Internet Systems Consortium DHCP Client V3.1.3 Apr 27 15:36:53 virtu dhclient: Copyright 2004-2009 Internet Systems Consortium. Apr 27 15:36:53 virtu dhclient: All rights reserved. Apr 27 15:36:53 virtu dhclient: For info, please visit https://www.isc.org/software/dhcp/ Apr 27 15:36:53 virtu dhclient: Apr 27 15:36:53 virtu NetworkManager: DHCP: device usb0 state changed normal exit -> preinit Apr 27 15:36:53 virtu dhclient: Listening on LPF/usb0/a2:28:07:bb:69:f4 Apr 27 15:36:53 virtu dhclient: Sending on LPF/usb0/a2:28:07:bb:69:f4 Apr 27 15:36:53 virtu dhclient: Sending on Socket/fallback Apr 27 15:36:54 virtu dhclient: DHCPDISCOVER on usb0 to 255.255.255.255 port 67 interval 5 Apr 27 15:36:57 virtu NetworkManager: (usb0): device state change: 7 -> 3 (reason 0) Apr 27 15:36:57 virtu NetworkManager: (usb0): deactivating device (reason: 0). Apr 27 15:36:57 virtu NetworkManager: (usb0): canceled DHCP transaction, dhcp client pid 11629 Apr 27 15:36:57 virtu NetworkManager: Policy set 'piopio' (wlan0) as default for routing and DNS. Apr 27 15:36:57 virtu NetworkManager: Activation (usb0) starting connection 'linklocal' Apr 27 15:36:57 virtu NetworkManager: (usb0): device state change: 3 -> 4 (reason 0) Apr 27 15:36:57 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) scheduled... Apr 27 15:36:57 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) started... Apr 27 15:36:57 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) scheduled... Apr 27 15:36:57 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) complete. Apr 27 15:36:57 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) starting... Apr 27 15:36:57 virtu NetworkManager: (usb0): device state change: 4 -> 5 (reason 0) Apr 27 15:36:57 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) successful. Apr 27 15:36:57 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) scheduled. Apr 27 15:36:57 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) complete. Apr 27 15:36:57 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) started... Apr 27 15:36:57 virtu NetworkManager: (usb0): device state change: 5 -> 7 (reason 0) Apr 27 15:36:57 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) started avahi-autoipd... Apr 27 15:36:57 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) scheduled... Apr 27 15:36:57 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) complete. Apr 27 15:36:57 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) started... Apr 27 15:36:57 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) complete. Apr 27 15:36:57 virtu kernel: [95644.204131] usb0: no IPv6 routers present Apr 27 15:36:59 virtu NetworkManager: (usb0): device state change: 7 -> 3 (reason 0) Apr 27 15:36:59 virtu NetworkManager: (usb0): deactivating device (reason: 0). Apr 27 15:36:59 virtu NetworkManager: [1272375419.550451] aipd_cleanup(): waiting for ppp pid 11631 to exit Apr 27 15:36:59 virtu NetworkManager: [1272375419.566056] aipd_cleanup(): ppp pid 11631 cleaned up Apr 27 15:36:59 virtu NetworkManager: Policy set 'piopio' (wlan0) as default for routing and DNS. Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) starting connection 'maemo' Apr 27 15:36:59 virtu NetworkManager: (usb0): device state change: 3 -> 4 (reason 0) Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) scheduled... Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) started... Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) scheduled... Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) Stage 1 of 5 (Device Prepare) complete. Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) starting... Apr 27 15:36:59 virtu NetworkManager: (usb0): device state change: 4 -> 5 (reason 0) Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) successful. Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) scheduled. Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) Stage 2 of 5 (Device Configure) complete. Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) started... Apr 27 15:36:59 virtu NetworkManager: (usb0): device state change: 5 -> 7 (reason 0) Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP4 Configure Get) scheduled... Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) scheduled... Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) Stage 3 of 5 (IP Configure Start) complete. Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP4 Configure Get) started... Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP4 Configure Get) complete. Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) Stage 4 of 5 (IP6 Configure Get) started... Apr 27 15:36:59 virtu NetworkManager: Activation (usb0) Stage 5 of 5 (IP Configure Commit) scheduled... Apr 27 15:36:59 virtu NetworkManager: