May 29 02:10:04 nash NetworkManager: (wlan0): supplicant connection state: completed -> group handshake May 29 02:10:04 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 02:13:15 nash dhclient: DHCPREQUEST of 10.33.16.201 on wlan0 to 171.64.7.89 port 67 May 29 02:13:15 nash dhclient: DHCPACK of 10.33.16.201 from 171.64.7.89 May 29 02:13:15 nash dhclient: bound to 10.33.16.201 -- renewal in 1007 seconds. May 29 02:13:15 nash NetworkManager: DHCP: device wlan0 state changed bound -> renew May 29 02:13:15 nash NetworkManager: address 10.33.16.201 May 29 02:13:15 nash NetworkManager: prefix 21 (255.255.248.0) May 29 02:13:15 nash NetworkManager: gateway 10.33.16.1 May 29 02:13:15 nash NetworkManager: nameserver '171.64.7.55' May 29 02:13:15 nash NetworkManager: nameserver '171.64.7.121' May 29 02:13:15 nash NetworkManager: nameserver '171.64.7.99' May 29 02:13:15 nash NetworkManager: domain name 'Stanford.EDU' May 29 02:13:15 nash NetworkManager: wins '171.64.7.155' May 29 02:13:15 nash NetworkManager: wins '171.64.7.177' May 29 02:13:15 nash NetworkManager: Policy set 'Auto GSB-WPA2' (wlan0) as default for routing and DNS. May 29 02:30:02 nash dhclient: DHCPREQUEST of 10.33.16.201 on wlan0 to 171.64.7.89 port 67 May 29 02:30:02 nash dhclient: DHCPACK of 10.33.16.201 from 171.64.7.89 May 29 02:30:02 nash dhclient: bound to 10.33.16.201 -- renewal in 1240 seconds. May 29 02:49:20 nash kernel: [16106.974820] __ratelimit: 6 callbacks suppressed May 29 02:49:20 nash kernel: [16106.974832] npviewer.bin.re[4862]: segfault at ff999ea8 ip 00000000ff999ea8 sp 00000000ffcb079c error 14 May 29 02:50:42 nash dhclient: DHCPREQUEST of 10.33.16.201 on wlan0 to 171.64.7.89 port 67 May 29 02:50:42 nash dhclient: DHCPACK of 10.33.16.201 from 171.64.7.89 May 29 02:50:42 nash dhclient: bound to 10.33.16.201 -- renewal in 1244 seconds. May 29 03:11:02 nash NetworkManager: (wlan0): supplicant connection state: completed -> group handshake May 29 03:11:02 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 03:11:26 nash dhclient: DHCPREQUEST of 10.33.16.201 on wlan0 to 171.64.7.89 port 67 May 29 03:11:26 nash dhclient: DHCPACK of 10.33.16.201 from 171.64.7.89 May 29 03:11:26 nash dhclient: bound to 10.33.16.201 -- renewal in 1061 seconds. May 29 03:29:07 nash dhclient: DHCPREQUEST of 10.33.16.201 on wlan0 to 171.64.7.89 port 67 May 29 03:29:07 nash dhclient: DHCPACK of 10.33.16.201 from 171.64.7.89 May 29 03:29:07 nash dhclient: bound to 10.33.16.201 -- renewal in 1143 seconds. May 29 03:48:10 nash dhclient: DHCPREQUEST of 10.33.16.201 on wlan0 to 171.64.7.89 port 67 May 29 03:48:10 nash dhclient: DHCPACK of 10.33.16.201 from 171.64.7.89 May 29 03:48:10 nash dhclient: bound to 10.33.16.201 -- renewal in 1249 seconds. May 29 04:08:59 nash dhclient: DHCPREQUEST of 10.33.16.201 on wlan0 to 171.64.7.89 port 67 May 29 04:08:59 nash dhclient: DHCPACK of 10.33.16.201 from 171.64.7.89 May 29 04:08:59 nash dhclient: bound to 10.33.16.201 -- renewal in 1095 seconds. May 29 04:12:02 nash NetworkManager: (wlan0): supplicant connection state: completed -> group handshake May 29 04:12:02 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 04:20:02 nash kernel: [21548.652653] CE: hpet increasing min_delta_ns to 50624 nsec May 29 04:27:14 nash dhclient: DHCPREQUEST of 10.33.16.201 on wlan0 to 171.64.7.89 port 67 May 29 04:27:14 nash dhclient: DHCPACK of 10.33.16.201 from 171.64.7.89 May 29 04:27:14 nash dhclient: bound to 10.33.16.201 -- renewal in 1261 seconds. May 29 04:30:38 nash kernel: [22185.040141] usb 3-1: USB disconnect, address 5 May 29 04:30:38 nash kernel: [22185.040211] btusb_intr_complete: hci0 urb ffff88009aeb2180 failed to resubmit (19) May 29 04:30:38 nash kernel: [22185.040233] btusb_bulk_complete: hci0 urb ffff8800b8613000 failed to resubmit (19) May 29 04:30:38 nash kernel: [22185.041214] btusb_bulk_complete: hci0 urb ffff8800b8613480 failed to resubmit (19) May 29 04:30:38 nash kernel: [22185.041457] btusb_send_frame: hci0 urb ffff88009fe546c0 submission failed May 29 04:30:38 nash NetworkManager: Sleeping... May 29 04:30:38 nash NetworkManager: (eth0): now unmanaged May 29 04:30:38 nash NetworkManager: (eth0): device state change: 2 -> 1 (reason 37) May 29 04:30:38 nash NetworkManager: (eth0): cleaning up... May 29 04:30:38 nash NetworkManager: (eth0): taking down device. May 29 04:30:38 nash NetworkManager: (wlan0): now unmanaged May 29 04:30:38 nash NetworkManager: (wlan0): device state change: 8 -> 1 (reason 37) May 29 04:30:38 nash NetworkManager: (wlan0): deactivating device (reason: 37). May 29 04:30:39 nash NetworkManager: (wlan0): canceled DHCP transaction, dhcp client pid 4285 May 29 04:30:39 nash NetworkManager: check_one_route(): (wlan0) error -34 returned from rtnl_route_del(): Sucess#012 May 29 04:30:39 nash kernel: [22185.652726] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 04:30:39 nash NetworkManager: (wlan0): cleaning up... May 29 04:30:39 nash NetworkManager: (wlan0): taking down device. May 29 04:32:35 nash kernel: [22186.612830] PM: Syncing filesystems ... done. May 29 04:32:35 nash kernel: [22186.702614] PM: Preparing system for mem sleep May 29 04:32:35 nash kernel: [22186.702619] Freezing user space processes ... (elapsed 0.00 seconds) done. May 29 04:32:35 nash kernel: [22186.703641] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. May 29 04:32:35 nash kernel: [22186.703686] PM: Entering mem sleep May 29 04:32:35 nash kernel: [22186.703700] Suspending console(s) (use no_console_suspend to debug) May 29 04:32:35 nash kernel: [22186.860080] PM: suspend of drv:ieee80211 dev:phy0 complete after 155.955 msecs May 29 04:32:35 nash kernel: [22186.900123] sd 2:0:0:0: [sda] Synchronizing SCSI cache May 29 04:32:35 nash kernel: [22186.900349] sd 2:0:0:0: [sda] Stopping disk May 29 04:32:35 nash kernel: [22187.484234] PM: suspend of drv:sd dev:2:0:0:0 complete after 584.112 msecs May 29 04:32:35 nash kernel: [22188.150082] PM: suspend of drv:atkbd dev:serio0 complete after 595.582 msecs May 29 04:32:35 nash kernel: [22188.153575] ACPI handle has no context! May 29 04:32:35 nash kernel: [22188.153587] sdhci-pci 0000:05:00.2: PCI INT C disabled May 29 04:32:35 nash kernel: [22188.153598] ACPI handle has no context! May 29 04:32:35 nash kernel: [22188.175177] ACPI handle has no context! May 29 04:32:35 nash kernel: [22188.270166] ata2: port disabled. ignoring. May 29 04:32:35 nash kernel: [22188.270241] ata_piix 0000:00:1f.1: PCI INT C disabled May 29 04:32:35 nash kernel: [22188.270263] ehci_hcd 0000:00:1d.7: PCI INT D disabled May 29 04:32:35 nash kernel: [22188.270281] uhci_hcd 0000:00:1d.1: PCI INT B disabled May 29 04:32:35 nash kernel: [22188.270296] uhci_hcd 0000:00:1d.0: PCI INT A disabled May 29 04:32:35 nash kernel: [22188.270304] pciehp 0000:00:1c.1:pcie04: pciehp_suspend ENTRY May 29 04:32:35 nash kernel: [22188.270311] pciehp 0000:00:1c.0:pcie04: pciehp_suspend ENTRY May 29 04:32:35 nash kernel: [22188.380338] HDA Intel 0000:00:1b.0: PCI INT B disabled May 29 04:32:35 nash kernel: [22188.400082] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 129.761 msecs May 29 04:32:35 nash kernel: [22188.400100] ehci_hcd 0000:00:1a.7: PCI INT C disabled May 29 04:32:35 nash kernel: [22188.400117] uhci_hcd 0000:00:1a.1: PCI INT B disabled May 29 04:32:35 nash kernel: [22188.400132] uhci_hcd 0000:00:1a.0: PCI INT A disabled May 29 04:32:35 nash kernel: [22188.401721] e1000e 0000:00:19.0: PCI INT A disabled May 29 04:32:35 nash kernel: [22188.401729] e1000e 0000:00:19.0: PME# enabled May 29 04:32:35 nash kernel: [22188.401735] e1000e 0000:00:19.0: wake-up capability enabled by ACPI May 29 04:32:35 nash kernel: [22188.460128] i915 0000:00:02.0: power state changed by ACPI to D3 May 29 04:32:35 nash kernel: [22188.460346] PM: suspend of devices complete after 1756.292 msecs May 29 04:32:35 nash kernel: [22188.460349] PM: suspend devices took 1.760 seconds May 29 04:32:35 nash kernel: [22188.500072] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 May 29 04:32:35 nash kernel: [22188.520067] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 May 29 04:32:35 nash kernel: [22188.560071] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 May 29 04:32:35 nash kernel: [22188.630063] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 May 29 04:32:35 nash kernel: [22188.630235] PM: late suspend of devices complete after 169.880 msecs May 29 04:32:35 nash kernel: [22188.730064] ACPI: Preparing to enter system sleep state S3 May 29 04:32:35 nash kernel: [22189.150015] Disabling non-boot CPUs ... May 29 04:32:35 nash kernel: [22189.150039] CPU0 attaching NULL sched-domain. May 29 04:32:35 nash kernel: [22189.150042] CPU1 attaching NULL sched-domain. May 29 04:32:35 nash kernel: [22189.310019] CPU0 attaching NULL sched-domain. May 29 04:32:35 nash kernel: [22189.420033] CPU 1 is now offline May 29 04:32:35 nash kernel: [22189.420036] SMP alternatives: switching to UP code May 29 04:32:35 nash kernel: [22189.427001] Extended CMOS year: 2000 May 29 04:32:35 nash kernel: [22189.427001] Back to C! May 29 04:32:35 nash kernel: [22189.427001] CPU0: Thermal monitoring enabled (TM2) May 29 04:32:35 nash kernel: [22189.427001] Extended CMOS year: 2000 May 29 04:32:35 nash kernel: [22189.427001] Enabling non-boot CPUs ... May 29 04:32:35 nash kernel: [22189.427001] SMP alternatives: switching to SMP code May 29 04:32:35 nash kernel: [22189.433122] Booting processor 1 APIC 0x1 ip 0x6000 May 29 04:32:35 nash kernel: [22189.426615] Initializing CPU#1 May 29 04:32:35 nash kernel: [22189.426615] CPU: L1 I cache: 32K, L1 D cache: 32K May 29 04:32:35 nash kernel: [22189.426615] CPU: L2 cache: 4096K May 29 04:32:35 nash kernel: [22189.426615] CPU 1/0x1 -> Node 0 May 29 04:32:35 nash kernel: [22189.426615] CPU: Physical Processor ID: 0 May 29 04:32:35 nash kernel: [22189.426615] CPU: Processor Core ID: 1 May 29 04:32:35 nash kernel: [22189.426615] CPU1: Thermal monitoring enabled (TM2) May 29 04:32:35 nash kernel: [22189.590073] CPU1: Intel(R) Core(TM)2 Duo CPU T7500 @ 2.20GHz stepping 0b May 29 04:32:35 nash kernel: [22189.590130] CPU0 attaching NULL sched-domain. May 29 04:32:35 nash kernel: [22189.660022] CPU0 attaching sched-domain: May 29 04:32:35 nash kernel: [22189.660024] domain 0: span 0-1 level MC May 29 04:32:35 nash kernel: [22189.660026] groups: 0 1 May 29 04:32:35 nash kernel: [22189.660030] CPU1 attaching sched-domain: May 29 04:32:35 nash kernel: [22189.660032] domain 0: span 0-1 level MC May 29 04:32:35 nash kernel: [22189.660034] groups: 1 0 May 29 04:32:35 nash kernel: [22189.660477] CPU1 is up May 29 04:32:35 nash kernel: [22189.661046] ACPI: Waking up from system sleep state S3 May 29 04:32:35 nash kernel: [22190.560219] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) May 29 04:32:35 nash kernel: [22190.560249] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) May 29 04:32:35 nash kernel: [22190.560382] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) May 29 04:32:35 nash kernel: [22190.670060] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 May 29 04:32:35 nash kernel: [22190.670109] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) May 29 04:32:35 nash kernel: [22190.670125] PM: early resume of drv:uhci_hcd dev:0000:00:1a.1 complete after 109.721 msecs May 29 04:32:35 nash kernel: [22190.670188] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) May 29 04:32:35 nash kernel: [22190.670275] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) May 29 04:32:35 nash kernel: [22190.670367] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) May 29 04:32:35 nash kernel: [22190.670466] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) May 29 04:32:35 nash kernel: [22190.690069] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 May 29 04:32:35 nash kernel: [22190.690114] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) May 29 04:32:35 nash kernel: [22190.690178] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) May 29 04:32:35 nash kernel: [22190.690249] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) May 29 04:32:35 nash kernel: [22190.690317] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) May 29 04:32:35 nash kernel: [22190.690506] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) May 29 04:32:35 nash kernel: [22190.690689] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) May 29 04:32:35 nash kernel: [22190.710089] ohci1394 0000:05:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x802000) May 29 04:32:35 nash kernel: [22190.710101] ohci1394 0000:05:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) May 29 04:32:35 nash kernel: [22190.730089] sdhci-pci 0000:05:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) May 29 04:32:35 nash kernel: [22190.730101] sdhci-pci 0000:05:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) May 29 04:32:35 nash kernel: [22190.730414] PM: early resume of devices complete after 170.306 msecs May 29 04:32:35 nash kernel: [22190.832973] i915 0000:00:02.0: setting latency timer to 64 May 29 04:32:35 nash kernel: [22191.193038] PM: resume of drv:i915 dev:0000:00:02.0 complete after 360.072 msecs May 29 04:32:35 nash kernel: [22191.193082] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 May 29 04:32:35 nash kernel: [22191.193088] e1000e 0000:00:19.0: setting latency timer to 64 May 29 04:32:35 nash kernel: [22191.193105] e1000e 0000:00:19.0: wake-up capability disabled by ACPI May 29 04:32:35 nash kernel: [22191.193111] e1000e 0000:00:19.0: PME# disabled May 29 04:32:35 nash kernel: [22191.193179] e1000e 0000:00:19.0: irq 27 for MSI/MSI-X May 29 04:32:35 nash kernel: [22191.522141] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 329.090 msecs May 29 04:32:35 nash kernel: [22191.522152] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 May 29 04:32:35 nash kernel: [22191.522163] uhci_hcd 0000:00:1a.0: setting latency timer to 64 May 29 04:32:35 nash kernel: [22191.522195] usb usb3: root hub lost power or was reset May 29 04:32:35 nash kernel: [22191.522237] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 May 29 04:32:35 nash kernel: [22191.522243] uhci_hcd 0000:00:1a.1: setting latency timer to 64 May 29 04:32:35 nash kernel: [22191.522267] usb usb4: root hub lost power or was reset May 29 04:32:35 nash kernel: [22191.522297] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 May 29 04:32:35 nash kernel: [22191.522309] ehci_hcd 0000:00:1a.7: setting latency timer to 64 May 29 04:32:35 nash kernel: [22191.522327] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 May 29 04:32:35 nash kernel: [22191.522340] HDA Intel 0000:00:1b.0: setting latency timer to 64 May 29 04:32:35 nash kernel: [22191.522365] pciehp 0000:00:1c.0:pcie04: pciehp_resume ENTRY May 29 04:32:35 nash kernel: [22191.522373] pciehp 0000:00:1c.1:pcie04: pciehp_resume ENTRY May 29 04:32:35 nash kernel: [22191.522388] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 May 29 04:32:35 nash kernel: [22191.522399] uhci_hcd 0000:00:1d.0: setting latency timer to 64 May 29 04:32:35 nash kernel: [22191.522430] usb usb5: root hub lost power or was reset May 29 04:32:35 nash kernel: [22191.522459] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 May 29 04:32:35 nash kernel: [22191.522472] uhci_hcd 0000:00:1d.1: setting latency timer to 64 May 29 04:32:35 nash kernel: [22191.522500] usb usb6: root hub lost power or was reset May 29 04:32:35 nash kernel: [22191.522527] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 May 29 04:32:35 nash kernel: [22191.522539] ehci_hcd 0000:00:1d.7: setting latency timer to 64 May 29 04:32:35 nash kernel: [22191.522569] pci 0000:00:1e.0: setting latency timer to 64 May 29 04:32:35 nash kernel: [22191.522582] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 May 29 04:32:35 nash kernel: [22191.522589] ata_piix 0000:00:1f.1: setting latency timer to 64 May 29 04:32:35 nash kernel: [22191.523079] ata2: port disabled. ignoring. May 29 04:32:35 nash kernel: [22191.523930] ahci 0000:00:1f.2: setting latency timer to 64 May 29 04:32:35 nash kernel: [22191.670079] PM: resume of drv:yenta_cardbus dev:0000:05:00.0 complete after 146.017 msecs May 29 04:32:35 nash kernel: [22191.732093] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[d7efe800-d7efefff] Max Packet=[2048] IR/IT contexts=[4/4] May 29 04:32:35 nash kernel: [22191.738190] sdhci-pci 0000:05:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 May 29 04:32:35 nash kernel: [22191.738193] sdhci-pci 0000:05:00.2: Will use DMA mode even though HW doesn't fully claim to support it. May 29 04:32:35 nash kernel: [22191.870102] ata5: SATA link down (SStatus 0 SControl 300) May 29 04:32:35 nash kernel: [22191.910097] PM: resume of drv:usb dev:usb1 complete after 170.298 msecs May 29 04:32:35 nash kernel: [22192.060072] PM: resume of drv:usb dev:usb2 complete after 149.955 msecs May 29 04:32:35 nash kernel: [22192.330072] PM: resume of drv:usb dev:usb3 complete after 269.982 msecs May 29 04:32:35 nash kernel: [22192.333265] sd 2:0:0:0: [sda] Starting disk May 29 04:32:35 nash kernel: [22192.410078] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) May 29 04:32:35 nash kernel: [22192.412791] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded May 29 04:32:35 nash kernel: [22192.412795] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out May 29 04:32:35 nash kernel: [22192.412797] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out May 29 04:32:35 nash kernel: [22192.430266] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded May 29 04:32:35 nash kernel: [22192.430269] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out May 29 04:32:35 nash kernel: [22192.430272] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out May 29 04:32:35 nash kernel: [22192.433235] ata3.00: configured for UDMA/133 May 29 04:32:35 nash kernel: [22192.456136] ata3.00: configured for UDMA/133 May 29 04:32:35 nash kernel: [22192.456138] ata3: EH complete May 29 04:32:35 nash kernel: [22192.462862] PM: resume of drv:sd dev:2:0:0:0 complete after 129.593 msecs May 29 04:32:35 nash kernel: [22192.580084] usb 2-1: reset high speed USB device using ehci_hcd and address 2 May 29 04:32:35 nash kernel: [22192.997971] snd-usb-audio 2-1:1.2: no reset_resume for driver snd-usb-audio? May 29 04:32:35 nash kernel: [22192.997974] snd-usb-audio 2-1:1.3: no reset_resume for driver snd-usb-audio? May 29 04:32:35 nash kernel: [22192.998252] PM: resume of drv:usb dev:2-1 complete after 535.326 msecs May 29 04:32:35 nash kernel: [22193.110086] usb 3-2: reset full speed USB device using uhci_hcd and address 3 May 29 04:32:35 nash kernel: [22193.267154] PM: resume of drv:usb dev:3-2 complete after 268.878 msecs May 29 04:32:35 nash kernel: [22193.269915] PM: resume of devices complete after 2539.406 msecs May 29 04:32:35 nash kernel: [22193.295653] PM: resume devices took 2.560 seconds May 29 04:32:35 nash kernel: [22193.295676] PM: Finishing wakeup. May 29 04:32:35 nash NetworkManager: SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/pan0, iface: pan0) May 29 04:32:35 nash kernel: [22193.295677] Restarting tasks ... done. May 29 04:32:36 nash NetworkManager: Waking up... May 29 04:32:36 nash NetworkManager: (eth0): now managed May 29 04:32:36 nash NetworkManager: (eth0): device state change: 1 -> 2 (reason 2) May 29 04:32:36 nash NetworkManager: (eth0): bringing up device. May 29 04:32:36 nash kernel: [22193.680331] e1000e 0000:00:19.0: irq 27 for MSI/MSI-X May 29 04:32:36 nash NetworkManager: (eth0): preparing device. May 29 04:32:36 nash NetworkManager: (eth0): deactivating device (reason: 2). May 29 04:32:36 nash NetworkManager: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) May 29 04:32:36 nash NetworkManager: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) May 29 04:32:36 nash NetworkManager: (wlan0): now managed May 29 04:32:36 nash NetworkManager: (wlan0): device state change: 1 -> 2 (reason 2) May 29 04:32:36 nash NetworkManager: (wlan0): bringing up device. May 29 04:32:36 nash kernel: [22193.740185] e1000e 0000:00:19.0: irq 27 for MSI/MSI-X May 29 04:32:36 nash kernel: [22193.740850] ADDRCONF(NETDEV_UP): eth0: link is not ready May 29 04:32:36 nash kernel: [22193.960146] Registered led device: iwl-phy0::radio May 29 04:32:36 nash kernel: [22193.960763] Registered led device: iwl-phy0::assoc May 29 04:32:36 nash kernel: [22193.961368] Registered led device: iwl-phy0::RX May 29 04:32:36 nash kernel: [22193.961943] Registered led device: iwl-phy0::TX May 29 04:32:36 nash NetworkManager: (wlan0): preparing device. May 29 04:32:36 nash NetworkManager: (wlan0): deactivating device (reason: 2). May 29 04:32:36 nash kernel: [22194.005537] ADDRCONF(NETDEV_UP): wlan0: link is not ready May 29 04:32:36 nash NetworkManager: (wlan0): supplicant interface state: starting -> ready May 29 04:32:36 nash NetworkManager: (wlan0): device state change: 2 -> 3 (reason 42) May 29 04:32:36 nash NetworkManager: Activation (wlan0) starting connection 'Auto GSB-WPA2' May 29 04:32:36 nash NetworkManager: (wlan0): device state change: 3 -> 4 (reason 0) May 29 04:32:36 nash NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... May 29 04:32:36 nash NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... May 29 04:32:36 nash NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... May 29 04:32:36 nash NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. May 29 04:32:36 nash NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... May 29 04:32:36 nash NetworkManager: (wlan0): device state change: 4 -> 5 (reason 0) May 29 04:32:36 nash NetworkManager: Activation (wlan0/wireless): connection 'Auto GSB-WPA2' has security, and secrets exist. No new secrets needed. May 29 04:32:36 nash NetworkManager: Config: added 'ssid' value 'GSB-WPA2' May 29 04:32:36 nash NetworkManager: Config: added 'scan_ssid' value '1' May 29 04:32:36 nash NetworkManager: Config: added 'key_mgmt' value 'WPA-PSK' May 29 04:32:36 nash NetworkManager: Config: added 'psk' value '' May 29 04:32:36 nash NetworkManager: nm_setting_802_1x_get_pkcs11_engine_path: assertion `NM_IS_SETTING_802_1X (setting)' failed May 29 04:32:36 nash NetworkManager: nm_setting_802_1x_get_pkcs11_module_path: assertion `NM_IS_SETTING_802_1X (setting)' failed May 29 04:32:36 nash NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. May 29 04:32:36 nash NetworkManager: Config: set interface ap_scan to 1 May 29 04:32:36 nash NetworkManager: (wlan0): supplicant connection state: scanning -> disconnected May 29 04:32:37 nash kernel: [22194.820107] usb 3-1: new full speed USB device using uhci_hcd and address 6 May 29 04:32:37 nash kernel: [22194.989453] usb 3-1: configuration #1 chosen from 1 choice May 29 04:32:37 nash NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/pan0, iface: pan0) May 29 04:32:37 nash NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/pan0, iface: pan0): no ifupdown configuration found. May 29 04:32:37 nash NetworkManager: device_creator(): /sys/devices/virtual/net/pan0: couldn't determine device driver; ignoring... May 29 04:32:41 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> scanning May 29 04:32:44 nash NetworkManager: (wlan0): supplicant connection state: scanning -> associating May 29 04:32:44 nash kernel: [22201.434045] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 04:32:44 nash kernel: [22201.474103] wlan0: direct probe to AP 00:11:93:2a:6f:21 (try 1) May 29 04:32:44 nash kernel: [22201.477183] wlan0: direct probe responded May 29 04:32:44 nash kernel: [22201.477187] wlan0: authenticate with AP 00:11:93:2a:6f:21 (try 1) May 29 04:32:44 nash kernel: [22201.483818] wlan0: authenticated May 29 04:32:44 nash kernel: [22201.483839] wlan0: associate with AP 00:11:93:2a:6f:21 (try 1) May 29 04:32:44 nash kernel: [22201.489067] wlan0: RX AssocResp from 00:11:93:2a:6f:21 (capab=0x431 status=0 aid=1) May 29 04:32:44 nash kernel: [22201.489071] wlan0: associated May 29 04:32:44 nash NetworkManager: (wlan0): supplicant connection state: associating -> associated May 29 04:32:44 nash kernel: [22201.510176] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready May 29 04:32:44 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 04:32:44 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 04:32:44 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 04:32:44 nash NetworkManager: Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'GSB-WPA2'. May 29 04:32:44 nash NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled. May 29 04:32:44 nash NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) started... May 29 04:32:44 nash NetworkManager: (wlan0): device state change: 5 -> 7 (reason 0) May 29 04:32:44 nash NetworkManager: Activation (wlan0) Beginning DHCP transaction (timeout in 45 seconds) May 29 04:32:44 nash NetworkManager: dhclient started with pid 6964 May 29 04:32:44 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) scheduled... May 29 04:32:44 nash NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete. May 29 04:32:44 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) started... May 29 04:32:44 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) complete. May 29 04:32:44 nash dhclient: Internet Systems Consortium DHCP Client V3.1.3 May 29 04:32:44 nash dhclient: Copyright 2004-2009 Internet Systems Consortium. May 29 04:32:44 nash dhclient: All rights reserved. May 29 04:32:44 nash dhclient: For info, please visit https://www.isc.org/software/dhcp/ May 29 04:32:44 nash dhclient: May 29 04:32:44 nash NetworkManager: DHCP: device wlan0 state changed normal exit -> preinit May 29 04:32:45 nash dhclient: Listening on LPF/wlan0/00:21:5c:31:f9:17 May 29 04:32:45 nash dhclient: Sending on LPF/wlan0/00:21:5c:31:f9:17 May 29 04:32:45 nash dhclient: Sending on Socket/fallback May 29 04:32:48 nash dhclient: DHCPREQUEST of 10.33.16.201 on wlan0 to 255.255.255.255 port 67 May 29 04:32:48 nash dhclient: DHCPACK of 10.33.16.201 from 10.33.16.2 May 29 04:32:48 nash dhclient: bound to 10.33.16.201 -- renewal in 1241 seconds. May 29 04:32:48 nash NetworkManager: DHCP: device wlan0 state changed preinit -> reboot May 29 04:32:48 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled... May 29 04:32:48 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started... May 29 04:32:48 nash NetworkManager: address 10.33.16.201 May 29 04:32:48 nash NetworkManager: prefix 21 (255.255.248.0) May 29 04:32:48 nash NetworkManager: gateway 10.33.16.1 May 29 04:32:48 nash NetworkManager: nameserver '171.64.7.99' May 29 04:32:48 nash NetworkManager: nameserver '171.64.7.77' May 29 04:32:48 nash NetworkManager: nameserver '171.64.7.55' May 29 04:32:48 nash NetworkManager: domain name 'Stanford.EDU' May 29 04:32:48 nash NetworkManager: wins '171.64.7.155' May 29 04:32:48 nash NetworkManager: wins '171.64.7.177' May 29 04:32:48 nash NetworkManager: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled... May 29 04:32:48 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete. May 29 04:32:48 nash NetworkManager: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started... May 29 04:32:49 nash NetworkManager: (wlan0): device state change: 7 -> 8 (reason 0) May 29 04:32:49 nash NetworkManager: Policy set 'Auto GSB-WPA2' (wlan0) as default for routing and DNS. May 29 04:32:49 nash NetworkManager: Activation (wlan0) successful, device activated. May 29 04:32:49 nash NetworkManager: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete. May 29 04:32:54 nash kernel: [22212.020085] wlan0: no IPv6 routers present May 29 04:53:29 nash dhclient: DHCPREQUEST of 10.33.16.201 on wlan0 to 171.64.7.89 port 67 May 29 04:53:29 nash dhclient: DHCPACK of 10.33.16.201 from 171.64.7.89 May 29 04:53:29 nash dhclient: bound to 10.33.16.201 -- renewal in 1086 seconds. May 29 04:53:29 nash NetworkManager: DHCP: device wlan0 state changed reboot -> renew May 29 04:53:29 nash NetworkManager: address 10.33.16.201 May 29 04:53:29 nash NetworkManager: prefix 21 (255.255.248.0) May 29 04:53:29 nash NetworkManager: gateway 10.33.16.1 May 29 04:53:29 nash NetworkManager: nameserver '171.64.7.99' May 29 04:53:29 nash NetworkManager: nameserver '171.64.7.77' May 29 04:53:29 nash NetworkManager: nameserver '171.64.7.55' May 29 04:53:29 nash NetworkManager: domain name 'Stanford.EDU' May 29 04:53:29 nash NetworkManager: wins '171.64.7.155' May 29 04:53:29 nash NetworkManager: wins '171.64.7.177' May 29 04:57:34 nash NetworkManager: Sleeping... May 29 04:57:34 nash NetworkManager: (eth0): now unmanaged May 29 04:57:34 nash NetworkManager: (eth0): device state change: 2 -> 1 (reason 37) May 29 04:57:34 nash NetworkManager: (eth0): cleaning up... May 29 04:57:34 nash NetworkManager: (eth0): taking down device. May 29 04:57:35 nash kernel: [23693.042692] usb 3-1: USB disconnect, address 6 May 29 04:57:35 nash kernel: [23693.042812] btusb_intr_complete: hci0 urb ffff88009dbe53c0 failed to resubmit (19) May 29 04:57:35 nash kernel: [23693.042835] btusb_bulk_complete: hci0 urb ffff88009dbe5300 failed to resubmit (19) May 29 04:57:35 nash kernel: [23693.043813] btusb_bulk_complete: hci0 urb ffff88009dbe5240 failed to resubmit (19) May 29 04:57:35 nash kernel: [23693.044047] btusb_send_frame: hci0 urb ffff8800abf2ae40 submission failed May 29 04:57:35 nash NetworkManager: (wlan0): now unmanaged May 29 04:57:35 nash NetworkManager: (wlan0): device state change: 8 -> 1 (reason 37) May 29 04:57:35 nash NetworkManager: (wlan0): deactivating device (reason: 37). May 29 04:57:35 nash NetworkManager: (wlan0): canceled DHCP transaction, dhcp client pid 6964 May 29 04:57:35 nash NetworkManager: check_one_route(): (wlan0) error -34 returned from rtnl_route_del(): Sucess#012 May 29 04:57:35 nash kernel: [23693.372728] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 04:57:35 nash NetworkManager: (wlan0): cleaning up... May 29 04:57:35 nash NetworkManager: (wlan0): taking down device. May 29 10:09:04 nash kernel: [23693.772821] PM: Syncing filesystems ... done. May 29 10:09:04 nash kernel: [23693.862611] PM: Preparing system for mem sleep May 29 10:09:04 nash kernel: [23693.862615] Freezing user space processes ... (elapsed 0.00 seconds) done. May 29 10:09:04 nash kernel: [23693.863638] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. May 29 10:09:04 nash kernel: [23693.863696] PM: Entering mem sleep May 29 10:09:04 nash kernel: [23693.863717] Suspending console(s) (use no_console_suspend to debug) May 29 10:09:04 nash kernel: [23694.020068] PM: suspend of drv:ieee80211 dev:phy0 complete after 155.936 msecs May 29 10:09:04 nash kernel: [23694.060119] sd 2:0:0:0: [sda] Synchronizing SCSI cache May 29 10:09:04 nash kernel: [23694.321942] sd 2:0:0:0: [sda] Stopping disk May 29 10:09:04 nash kernel: [23694.905674] PM: suspend of drv:sd dev:2:0:0:0 complete after 845.558 msecs May 29 10:09:04 nash kernel: [23695.580078] PM: suspend of drv:atkbd dev:serio0 complete after 599.814 msecs May 29 10:09:04 nash kernel: [23695.583999] ACPI handle has no context! May 29 10:09:04 nash kernel: [23695.584012] sdhci-pci 0000:05:00.2: PCI INT C disabled May 29 10:09:04 nash kernel: [23695.584020] ACPI handle has no context! May 29 10:09:04 nash kernel: [23695.605178] ACPI handle has no context! May 29 10:09:04 nash kernel: [23695.700167] ata2: port disabled. ignoring. May 29 10:09:04 nash kernel: [23695.700246] ata_piix 0000:00:1f.1: PCI INT C disabled May 29 10:09:04 nash kernel: [23695.700270] ehci_hcd 0000:00:1d.7: PCI INT D disabled May 29 10:09:04 nash kernel: [23695.700288] uhci_hcd 0000:00:1d.1: PCI INT B disabled May 29 10:09:04 nash kernel: [23695.700304] uhci_hcd 0000:00:1d.0: PCI INT A disabled May 29 10:09:04 nash kernel: [23695.700312] pciehp 0000:00:1c.1:pcie04: pciehp_suspend ENTRY May 29 10:09:04 nash kernel: [23695.700319] pciehp 0000:00:1c.0:pcie04: pciehp_suspend ENTRY May 29 10:09:04 nash kernel: [23695.810344] HDA Intel 0000:00:1b.0: PCI INT B disabled May 29 10:09:04 nash kernel: [23695.830080] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 129.750 msecs May 29 10:09:04 nash kernel: [23695.830097] ehci_hcd 0000:00:1a.7: PCI INT C disabled May 29 10:09:04 nash kernel: [23695.830115] uhci_hcd 0000:00:1a.1: PCI INT B disabled May 29 10:09:04 nash kernel: [23695.830130] uhci_hcd 0000:00:1a.0: PCI INT A disabled May 29 10:09:04 nash kernel: [23695.831733] e1000e 0000:00:19.0: PCI INT A disabled May 29 10:09:04 nash kernel: [23695.831741] e1000e 0000:00:19.0: PME# enabled May 29 10:09:04 nash kernel: [23695.831746] e1000e 0000:00:19.0: wake-up capability enabled by ACPI May 29 10:09:04 nash kernel: [23695.870124] i915 0000:00:02.0: power state changed by ACPI to D3 May 29 10:09:04 nash kernel: [23695.870353] PM: suspend of devices complete after 2006.293 msecs May 29 10:09:04 nash kernel: [23695.870356] PM: suspend devices took 2.010 seconds May 29 10:09:04 nash kernel: [23695.910074] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 May 29 10:09:04 nash kernel: [23695.930067] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 May 29 10:09:04 nash kernel: [23695.970073] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 May 29 10:09:04 nash kernel: [23696.040068] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 May 29 10:09:04 nash kernel: [23696.040226] PM: late suspend of devices complete after 169.864 msecs May 29 10:09:04 nash kernel: [23696.140067] ACPI: Preparing to enter system sleep state S3 May 29 10:09:04 nash kernel: [23696.560016] Disabling non-boot CPUs ... May 29 10:09:04 nash kernel: [23696.560039] CPU0 attaching NULL sched-domain. May 29 10:09:04 nash kernel: [23696.560042] CPU1 attaching NULL sched-domain. May 29 10:09:04 nash kernel: [23696.720019] CPU0 attaching NULL sched-domain. May 29 10:09:04 nash kernel: [23696.830031] CPU 1 is now offline May 29 10:09:04 nash kernel: [23696.830034] SMP alternatives: switching to UP code May 29 10:09:04 nash kernel: [23696.836962] Extended CMOS year: 2000 May 29 10:09:04 nash kernel: [23696.836962] Back to C! May 29 10:09:04 nash kernel: [23696.836962] CPU0: Thermal monitoring enabled (TM2) May 29 10:09:04 nash kernel: [23696.836962] Extended CMOS year: 2000 May 29 10:09:04 nash kernel: [23696.836962] Enabling non-boot CPUs ... May 29 10:09:04 nash kernel: [23696.836962] SMP alternatives: switching to SMP code May 29 10:09:04 nash kernel: [23696.843074] Booting processor 1 APIC 0x1 ip 0x6000 May 29 10:09:04 nash kernel: [23696.836589] Initializing CPU#1 May 29 10:09:04 nash kernel: [23696.836589] CPU: L1 I cache: 32K, L1 D cache: 32K May 29 10:09:04 nash kernel: [23696.836589] CPU: L2 cache: 4096K May 29 10:09:04 nash kernel: [23696.836589] CPU 1/0x1 -> Node 0 May 29 10:09:04 nash kernel: [23696.836589] CPU: Physical Processor ID: 0 May 29 10:09:04 nash kernel: [23696.836589] CPU: Processor Core ID: 1 May 29 10:09:04 nash kernel: [23696.836589] CPU1: Thermal monitoring enabled (TM2) May 29 10:09:04 nash kernel: [23697.000136] CPU1: Intel(R) Core(TM)2 Duo CPU T7500 @ 2.20GHz stepping 0b May 29 10:09:04 nash kernel: [23697.000193] CPU0 attaching NULL sched-domain. May 29 10:09:04 nash kernel: [23697.070022] CPU0 attaching sched-domain: May 29 10:09:04 nash kernel: [23697.070024] domain 0: span 0-1 level MC May 29 10:09:04 nash kernel: [23697.070027] groups: 0 1 May 29 10:09:04 nash kernel: [23697.070031] CPU1 attaching sched-domain: May 29 10:09:04 nash kernel: [23697.070032] domain 0: span 0-1 level MC May 29 10:09:04 nash kernel: [23697.070034] groups: 1 0 May 29 10:09:04 nash kernel: [23697.070473] CPU1 is up May 29 10:09:04 nash kernel: [23697.071043] ACPI: Waking up from system sleep state S3 May 29 10:09:04 nash kernel: [23697.970215] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) May 29 10:09:04 nash kernel: [23697.970245] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) May 29 10:09:04 nash kernel: [23697.970385] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) May 29 10:09:04 nash kernel: [23698.080058] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 May 29 10:09:04 nash kernel: [23698.080105] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) May 29 10:09:04 nash kernel: [23698.080121] PM: early resume of drv:uhci_hcd dev:0000:00:1a.1 complete after 109.716 msecs May 29 10:09:04 nash kernel: [23698.080180] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) May 29 10:09:04 nash kernel: [23698.080273] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) May 29 10:09:04 nash kernel: [23698.080362] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) May 29 10:09:04 nash kernel: [23698.080471] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) May 29 10:09:04 nash kernel: [23698.100072] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 May 29 10:09:04 nash kernel: [23698.100119] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) May 29 10:09:04 nash kernel: [23698.100178] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) May 29 10:09:04 nash kernel: [23698.100248] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) May 29 10:09:04 nash kernel: [23698.100315] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) May 29 10:09:04 nash kernel: [23698.100509] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) May 29 10:09:04 nash kernel: [23698.100696] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) May 29 10:09:04 nash kernel: [23698.120093] ohci1394 0000:05:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x802000) May 29 10:09:04 nash kernel: [23698.120105] ohci1394 0000:05:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) May 29 10:09:04 nash kernel: [23698.140087] sdhci-pci 0000:05:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) May 29 10:09:04 nash kernel: [23698.140099] sdhci-pci 0000:05:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) May 29 10:09:04 nash kernel: [23698.140410] PM: early resume of devices complete after 170.305 msecs May 29 10:09:04 nash kernel: [23698.242995] i915 0000:00:02.0: setting latency timer to 64 May 29 10:09:04 nash kernel: [23698.600359] PM: resume of drv:i915 dev:0000:00:02.0 complete after 357.369 msecs May 29 10:09:04 nash kernel: [23698.600399] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 May 29 10:09:04 nash kernel: [23698.600408] e1000e 0000:00:19.0: setting latency timer to 64 May 29 10:09:04 nash kernel: [23698.600424] e1000e 0000:00:19.0: wake-up capability disabled by ACPI May 29 10:09:04 nash kernel: [23698.600431] e1000e 0000:00:19.0: PME# disabled May 29 10:09:04 nash kernel: [23698.600504] e1000e 0000:00:19.0: irq 27 for MSI/MSI-X May 29 10:09:04 nash kernel: [23698.932146] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 331.776 msecs May 29 10:09:04 nash kernel: [23698.932157] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 May 29 10:09:04 nash kernel: [23698.932168] uhci_hcd 0000:00:1a.0: setting latency timer to 64 May 29 10:09:04 nash kernel: [23698.932199] usb usb3: root hub lost power or was reset May 29 10:09:04 nash kernel: [23698.932240] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 May 29 10:09:04 nash kernel: [23698.932246] uhci_hcd 0000:00:1a.1: setting latency timer to 64 May 29 10:09:04 nash kernel: [23698.932270] usb usb4: root hub lost power or was reset May 29 10:09:04 nash kernel: [23698.932295] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 May 29 10:09:04 nash kernel: [23698.932307] ehci_hcd 0000:00:1a.7: setting latency timer to 64 May 29 10:09:04 nash kernel: [23698.932325] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 May 29 10:09:04 nash kernel: [23698.932337] HDA Intel 0000:00:1b.0: setting latency timer to 64 May 29 10:09:04 nash kernel: [23698.932365] pciehp 0000:00:1c.0:pcie04: pciehp_resume ENTRY May 29 10:09:04 nash kernel: [23698.932372] pciehp 0000:00:1c.1:pcie04: pciehp_resume ENTRY May 29 10:09:04 nash kernel: [23698.932384] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 May 29 10:09:04 nash kernel: [23698.932392] uhci_hcd 0000:00:1d.0: setting latency timer to 64 May 29 10:09:04 nash kernel: [23698.932423] usb usb5: root hub lost power or was reset May 29 10:09:04 nash kernel: [23698.932451] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 May 29 10:09:04 nash kernel: [23698.932461] uhci_hcd 0000:00:1d.1: setting latency timer to 64 May 29 10:09:04 nash kernel: [23698.932492] usb usb6: root hub lost power or was reset May 29 10:09:04 nash kernel: [23698.932522] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 May 29 10:09:04 nash kernel: [23698.932534] ehci_hcd 0000:00:1d.7: setting latency timer to 64 May 29 10:09:04 nash kernel: [23698.932563] pci 0000:00:1e.0: setting latency timer to 64 May 29 10:09:04 nash kernel: [23698.932576] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 May 29 10:09:04 nash kernel: [23698.932583] ata_piix 0000:00:1f.1: setting latency timer to 64 May 29 10:09:04 nash kernel: [23698.933067] ata2: port disabled. ignoring. May 29 10:09:04 nash kernel: [23698.933916] ahci 0000:00:1f.2: setting latency timer to 64 May 29 10:09:04 nash kernel: [23699.080079] PM: resume of drv:yenta_cardbus dev:0000:05:00.0 complete after 146.031 msecs May 29 10:09:04 nash kernel: [23699.142095] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[d7efe800-d7efefff] Max Packet=[2048] IR/IT contexts=[4/4] May 29 10:09:04 nash kernel: [23699.148191] sdhci-pci 0000:05:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 May 29 10:09:04 nash kernel: [23699.148193] sdhci-pci 0000:05:00.2: Will use DMA mode even though HW doesn't fully claim to support it. May 29 10:09:04 nash kernel: [23699.280097] ata5: SATA link down (SStatus 0 SControl 300) May 29 10:09:04 nash kernel: [23699.320101] PM: resume of drv:usb dev:usb1 complete after 170.304 msecs May 29 10:09:04 nash kernel: [23699.470079] PM: resume of drv:usb dev:usb2 complete after 149.959 msecs May 29 10:09:04 nash kernel: [23699.740077] PM: resume of drv:usb dev:usb3 complete after 269.980 msecs May 29 10:09:04 nash kernel: [23699.742814] sd 2:0:0:0: [sda] Starting disk May 29 10:09:04 nash kernel: [23699.820081] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) May 29 10:09:04 nash kernel: [23699.822874] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded May 29 10:09:04 nash kernel: [23699.822878] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out May 29 10:09:04 nash kernel: [23699.822881] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out May 29 10:09:04 nash kernel: [23699.834739] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded May 29 10:09:04 nash kernel: [23699.834742] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out May 29 10:09:04 nash kernel: [23699.834745] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out May 29 10:09:04 nash kernel: [23699.838064] ata3.00: configured for UDMA/133 May 29 10:09:04 nash kernel: [23699.856001] ata3.00: configured for UDMA/133 May 29 10:09:04 nash kernel: [23699.856004] ata3: EH complete May 29 10:09:04 nash kernel: [23699.867378] PM: resume of drv:sd dev:2:0:0:0 complete after 124.563 msecs May 29 10:09:04 nash kernel: [23699.980083] usb 2-1: reset high speed USB device using ehci_hcd and address 2 May 29 10:09:04 nash kernel: [23700.397727] snd-usb-audio 2-1:1.2: no reset_resume for driver snd-usb-audio? May 29 10:09:04 nash kernel: [23700.397730] snd-usb-audio 2-1:1.3: no reset_resume for driver snd-usb-audio? May 29 10:09:04 nash kernel: [23700.398008] PM: resume of drv:usb dev:2-1 complete after 530.576 msecs May 29 10:09:04 nash kernel: [23700.510077] usb 3-2: reset full speed USB device using uhci_hcd and address 3 May 29 10:09:04 nash kernel: [23700.667159] PM: resume of drv:usb dev:3-2 complete after 269.127 msecs May 29 10:09:04 nash kernel: [23700.669913] PM: resume of devices complete after 2529.410 msecs May 29 10:09:04 nash kernel: [23700.694283] PM: resume devices took 2.550 seconds May 29 10:09:04 nash kernel: [23700.694306] PM: Finishing wakeup. May 29 10:09:04 nash NetworkManager: SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/pan0, iface: pan0) May 29 10:09:04 nash kernel: [23700.694307] Restarting tasks ... done. May 29 10:09:05 nash NetworkManager: Waking up... May 29 10:09:05 nash NetworkManager: (eth0): now managed May 29 10:09:05 nash NetworkManager: (eth0): device state change: 1 -> 2 (reason 2) May 29 10:09:05 nash NetworkManager: (eth0): bringing up device. May 29 10:09:05 nash kernel: [23701.433538] e1000e 0000:00:19.0: irq 27 for MSI/MSI-X May 29 10:09:05 nash NetworkManager: (eth0): preparing device. May 29 10:09:05 nash NetworkManager: (eth0): deactivating device (reason: 2). May 29 10:09:05 nash NetworkManager: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) May 29 10:09:05 nash NetworkManager: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) May 29 10:09:05 nash NetworkManager: (wlan0): now managed May 29 10:09:05 nash NetworkManager: (wlan0): device state change: 1 -> 2 (reason 2) May 29 10:09:05 nash NetworkManager: (wlan0): bringing up device. May 29 10:09:05 nash kernel: [23701.493356] e1000e 0000:00:19.0: irq 27 for MSI/MSI-X May 29 10:09:05 nash kernel: [23701.494657] ADDRCONF(NETDEV_UP): eth0: link is not ready May 29 10:09:05 nash kernel: [23701.716987] Registered led device: iwl-phy0::radio May 29 10:09:05 nash kernel: [23701.717036] Registered led device: iwl-phy0::assoc May 29 10:09:05 nash kernel: [23701.717078] Registered led device: iwl-phy0::RX May 29 10:09:05 nash kernel: [23701.717121] Registered led device: iwl-phy0::TX May 29 10:09:05 nash NetworkManager: (wlan0): preparing device. May 29 10:09:05 nash NetworkManager: (wlan0): deactivating device (reason: 2). May 29 10:09:05 nash kernel: [23701.768379] ADDRCONF(NETDEV_UP): wlan0: link is not ready May 29 10:09:05 nash NetworkManager: (wlan0): supplicant interface state: starting -> ready May 29 10:09:05 nash NetworkManager: (wlan0): device state change: 2 -> 3 (reason 42) May 29 10:09:05 nash NetworkManager: Activation (wlan0) starting connection 'Auto GSB-WPA2' May 29 10:09:05 nash NetworkManager: (wlan0): device state change: 3 -> 4 (reason 0) May 29 10:09:05 nash NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... May 29 10:09:05 nash NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... May 29 10:09:05 nash NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... May 29 10:09:05 nash NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. May 29 10:09:05 nash NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... May 29 10:09:05 nash NetworkManager: (wlan0): device state change: 4 -> 5 (reason 0) May 29 10:09:05 nash NetworkManager: Activation (wlan0/wireless): connection 'Auto GSB-WPA2' has security, and secrets exist. No new secrets needed. May 29 10:09:05 nash NetworkManager: Config: added 'ssid' value 'GSB-WPA2' May 29 10:09:05 nash NetworkManager: Config: added 'scan_ssid' value '1' May 29 10:09:05 nash NetworkManager: Config: added 'key_mgmt' value 'WPA-PSK' May 29 10:09:05 nash NetworkManager: Config: added 'psk' value '' May 29 10:09:05 nash NetworkManager: nm_setting_802_1x_get_pkcs11_engine_path: assertion `NM_IS_SETTING_802_1X (setting)' failed May 29 10:09:05 nash NetworkManager: nm_setting_802_1x_get_pkcs11_module_path: assertion `NM_IS_SETTING_802_1X (setting)' failed May 29 10:09:05 nash NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. May 29 10:09:05 nash NetworkManager: Config: set interface ap_scan to 1 May 29 10:09:05 nash NetworkManager: (wlan0): supplicant connection state: scanning -> disconnected May 29 10:09:06 nash kernel: [23702.570084] usb 3-1: new full speed USB device using uhci_hcd and address 7 May 29 10:09:06 nash kernel: [23702.751445] usb 3-1: configuration #1 chosen from 1 choice May 29 10:09:06 nash NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/pan0, iface: pan0) May 29 10:09:06 nash NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/pan0, iface: pan0): no ifupdown configuration found. May 29 10:09:06 nash NetworkManager: device_creator(): /sys/devices/virtual/net/pan0: couldn't determine device driver; ignoring... May 29 10:09:10 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> scanning May 29 10:09:13 nash NetworkManager: (wlan0): supplicant connection state: scanning -> associating May 29 10:09:13 nash kernel: [23709.171346] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 10:09:13 nash kernel: [23709.211644] wlan0: direct probe to AP 00:11:93:2a:6f:21 (try 1) May 29 10:09:13 nash kernel: [23709.411738] wlan0: direct probe to AP 00:11:93:2a:6f:21 (try 2) May 29 10:09:13 nash kernel: [23709.414795] wlan0: direct probe responded May 29 10:09:13 nash kernel: [23709.414802] wlan0: authenticate with AP 00:11:93:2a:6f:21 (try 1) May 29 10:09:13 nash kernel: [23709.418304] wlan0: authenticated May 29 10:09:13 nash kernel: [23709.418344] wlan0: associate with AP 00:11:93:2a:6f:21 (try 1) May 29 10:09:13 nash kernel: [23709.421812] wlan0: RX AssocResp from 00:11:93:2a:6f:21 (capab=0x431 status=0 aid=1) May 29 10:09:13 nash kernel: [23709.421819] wlan0: associated May 29 10:09:13 nash NetworkManager: (wlan0): supplicant connection state: associating -> 4-way handshake May 29 10:09:13 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> associated May 29 10:09:13 nash kernel: [23709.445968] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready May 29 10:09:14 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 10:09:14 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 10:09:14 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 10:09:14 nash NetworkManager: Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'GSB-WPA2'. May 29 10:09:14 nash NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled. May 29 10:09:14 nash NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) started... May 29 10:09:14 nash NetworkManager: (wlan0): device state change: 5 -> 7 (reason 0) May 29 10:09:14 nash NetworkManager: Activation (wlan0) Beginning DHCP transaction (timeout in 45 seconds) May 29 10:09:14 nash NetworkManager: dhclient started with pid 9052 May 29 10:09:14 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) scheduled... May 29 10:09:14 nash NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete. May 29 10:09:14 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) started... May 29 10:09:14 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) complete. May 29 10:09:14 nash dhclient: Internet Systems Consortium DHCP Client V3.1.3 May 29 10:09:14 nash dhclient: Copyright 2004-2009 Internet Systems Consortium. May 29 10:09:14 nash dhclient: All rights reserved. May 29 10:09:14 nash dhclient: For info, please visit https://www.isc.org/software/dhcp/ May 29 10:09:14 nash dhclient: May 29 10:09:14 nash NetworkManager: DHCP: device wlan0 state changed normal exit -> preinit May 29 10:09:14 nash dhclient: Listening on LPF/wlan0/00:21:5c:31:f9:17 May 29 10:09:14 nash dhclient: Sending on LPF/wlan0/00:21:5c:31:f9:17 May 29 10:09:14 nash dhclient: Sending on Socket/fallback May 29 10:09:17 nash dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 4 May 29 10:09:17 nash dhclient: DHCPOFFER of 10.33.16.201 from 10.33.16.2 May 29 10:09:17 nash dhclient: DHCPREQUEST of 10.33.16.201 on wlan0 to 255.255.255.255 port 67 May 29 10:09:17 nash dhclient: DHCPACK of 10.33.16.201 from 10.33.16.2 May 29 10:09:17 nash dhclient: bound to 10.33.16.201 -- renewal in 1075 seconds. May 29 10:09:17 nash NetworkManager: DHCP: device wlan0 state changed preinit -> bound May 29 10:09:17 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled... May 29 10:09:17 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started... May 29 10:09:17 nash NetworkManager: address 10.33.16.201 May 29 10:09:17 nash NetworkManager: prefix 21 (255.255.248.0) May 29 10:09:17 nash NetworkManager: gateway 10.33.16.1 May 29 10:09:17 nash NetworkManager: nameserver '171.64.7.99' May 29 10:09:17 nash NetworkManager: nameserver '171.64.7.121' May 29 10:09:17 nash NetworkManager: nameserver '171.64.7.55' May 29 10:09:17 nash NetworkManager: domain name 'Stanford.EDU' May 29 10:09:17 nash NetworkManager: wins '171.64.7.155' May 29 10:09:17 nash NetworkManager: wins '171.64.7.177' May 29 10:09:17 nash NetworkManager: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled... May 29 10:09:17 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete. May 29 10:09:17 nash NetworkManager: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started... May 29 10:09:18 nash NetworkManager: (wlan0): device state change: 7 -> 8 (reason 0) May 29 10:09:18 nash NetworkManager: [1275152958.737981] periodic_update(): Roamed from BSSID 00:11:93:1F:5F:31 (GSB-WPA2) to 00:11:93:2A:6F:21 (GSB-WPA2) May 29 10:09:18 nash NetworkManager: Policy set 'Auto GSB-WPA2' (wlan0) as default for routing and DNS. May 29 10:09:18 nash NetworkManager: Activation (wlan0) successful, device activated. May 29 10:09:18 nash NetworkManager: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete. May 29 10:09:23 nash kernel: [23719.760079] wlan0: no IPv6 routers present May 29 10:09:25 nash kernel: [23721.540149] usb 3-1: USB disconnect, address 7 May 29 10:09:25 nash kernel: [23721.540669] btusb_intr_complete: hci0 urb ffff88008a370e40 failed to resubmit (19) May 29 10:09:25 nash kernel: [23721.540691] btusb_bulk_complete: hci0 urb ffff8800a5eda0c0 failed to resubmit (19) May 29 10:09:25 nash kernel: [23721.541670] btusb_bulk_complete: hci0 urb ffff88008a3703c0 failed to resubmit (19) May 29 10:09:25 nash kernel: [23721.541903] btusb_send_frame: hci0 urb ffff8800a2af4240 submission failed May 29 10:09:25 nash NetworkManager: Sleeping... May 29 10:09:25 nash NetworkManager: (eth0): now unmanaged May 29 10:09:25 nash NetworkManager: (eth0): device state change: 2 -> 1 (reason 37) May 29 10:09:25 nash NetworkManager: (eth0): cleaning up... May 29 10:09:25 nash NetworkManager: (eth0): taking down device. May 29 10:09:25 nash NetworkManager: (wlan0): now unmanaged May 29 10:09:25 nash NetworkManager: (wlan0): device state change: 8 -> 1 (reason 37) May 29 10:09:25 nash NetworkManager: (wlan0): deactivating device (reason: 37). May 29 10:09:25 nash NetworkManager: (wlan0): canceled DHCP transaction, dhcp client pid 9052 May 29 10:09:25 nash NetworkManager: check_one_route(): (wlan0) error -34 returned from rtnl_route_del(): Sucess#012 May 29 10:09:25 nash kernel: [23722.082706] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 10:09:25 nash NetworkManager: (wlan0): cleaning up... May 29 10:09:25 nash NetworkManager: (wlan0): taking down device. May 29 12:32:45 nash kernel: [23724.062806] PM: Syncing filesystems ... done. May 29 12:32:45 nash kernel: [23724.152611] PM: Preparing system for mem sleep May 29 12:32:45 nash kernel: [23724.152616] Freezing user space processes ... (elapsed 0.00 seconds) done. May 29 12:32:45 nash kernel: [23724.153602] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. May 29 12:32:45 nash kernel: [23724.153656] PM: Entering mem sleep May 29 12:32:45 nash kernel: [23724.153683] Suspending console(s) (use no_console_suspend to debug) May 29 12:32:45 nash kernel: [23724.310068] PM: suspend of drv:ieee80211 dev:phy0 complete after 155.959 msecs May 29 12:32:45 nash kernel: [23724.350121] sd 2:0:0:0: [sda] Synchronizing SCSI cache May 29 12:32:45 nash kernel: [23724.350349] sd 2:0:0:0: [sda] Stopping disk May 29 12:32:45 nash kernel: [23724.935050] PM: suspend of drv:sd dev:2:0:0:0 complete after 584.930 msecs May 29 12:32:45 nash kernel: [23725.610081] PM: suspend of drv:atkbd dev:serio0 complete after 604.112 msecs May 29 12:32:45 nash kernel: [23725.613348] ACPI handle has no context! May 29 12:32:45 nash kernel: [23725.613360] sdhci-pci 0000:05:00.2: PCI INT C disabled May 29 12:32:45 nash kernel: [23725.613371] ACPI handle has no context! May 29 12:32:45 nash kernel: [23725.635181] ACPI handle has no context! May 29 12:32:45 nash kernel: [23725.730168] ata2: port disabled. ignoring. May 29 12:32:45 nash kernel: [23725.730244] ata_piix 0000:00:1f.1: PCI INT C disabled May 29 12:32:45 nash kernel: [23725.730266] ehci_hcd 0000:00:1d.7: PCI INT D disabled May 29 12:32:45 nash kernel: [23725.730284] uhci_hcd 0000:00:1d.1: PCI INT B disabled May 29 12:32:45 nash kernel: [23725.730300] uhci_hcd 0000:00:1d.0: PCI INT A disabled May 29 12:32:45 nash kernel: [23725.730308] pciehp 0000:00:1c.1:pcie04: pciehp_suspend ENTRY May 29 12:32:45 nash kernel: [23725.730315] pciehp 0000:00:1c.0:pcie04: pciehp_suspend ENTRY May 29 12:32:45 nash kernel: [23725.840336] HDA Intel 0000:00:1b.0: PCI INT B disabled May 29 12:32:45 nash kernel: [23725.860080] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 129.758 msecs May 29 12:32:45 nash kernel: [23725.860095] ehci_hcd 0000:00:1a.7: PCI INT C disabled May 29 12:32:45 nash kernel: [23725.860113] uhci_hcd 0000:00:1a.1: PCI INT B disabled May 29 12:32:45 nash kernel: [23725.860130] uhci_hcd 0000:00:1a.0: PCI INT A disabled May 29 12:32:45 nash kernel: [23725.861726] e1000e 0000:00:19.0: PCI INT A disabled May 29 12:32:45 nash kernel: [23725.861734] e1000e 0000:00:19.0: PME# enabled May 29 12:32:45 nash kernel: [23725.861740] e1000e 0000:00:19.0: wake-up capability enabled by ACPI May 29 12:32:45 nash kernel: [23725.900127] i915 0000:00:02.0: power state changed by ACPI to D3 May 29 12:32:45 nash kernel: [23725.900339] PM: suspend of devices complete after 1746.300 msecs May 29 12:32:45 nash kernel: [23725.900342] PM: suspend devices took 1.750 seconds May 29 12:32:45 nash kernel: [23725.940074] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 May 29 12:32:45 nash kernel: [23725.960067] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 May 29 12:32:45 nash kernel: [23726.000068] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 May 29 12:32:45 nash kernel: [23726.070054] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 May 29 12:32:45 nash kernel: [23726.070215] PM: late suspend of devices complete after 169.867 msecs May 29 12:32:45 nash kernel: [23726.170058] ACPI: Preparing to enter system sleep state S3 May 29 12:32:45 nash kernel: [23726.590014] Disabling non-boot CPUs ... May 29 12:32:45 nash kernel: [23726.590038] CPU0 attaching NULL sched-domain. May 29 12:32:45 nash kernel: [23726.590041] CPU1 attaching NULL sched-domain. May 29 12:32:45 nash kernel: [23726.750019] CPU0 attaching NULL sched-domain. May 29 12:32:45 nash kernel: [23726.860031] CPU 1 is now offline May 29 12:32:45 nash kernel: [23726.860034] SMP alternatives: switching to UP code May 29 12:32:45 nash kernel: [23726.866964] Extended CMOS year: 2000 May 29 12:32:45 nash kernel: [23726.866964] Back to C! May 29 12:32:45 nash kernel: [23726.866964] CPU0: Thermal monitoring enabled (TM2) May 29 12:32:45 nash kernel: [23726.866964] Extended CMOS year: 2000 May 29 12:32:45 nash kernel: [23726.866964] Enabling non-boot CPUs ... May 29 12:32:45 nash kernel: [23726.866964] SMP alternatives: switching to SMP code May 29 12:32:45 nash kernel: [23726.873077] Booting processor 1 APIC 0x1 ip 0x6000 May 29 12:32:45 nash kernel: [23726.866578] Initializing CPU#1 May 29 12:32:45 nash kernel: [23726.866578] CPU: L1 I cache: 32K, L1 D cache: 32K May 29 12:32:45 nash kernel: [23726.866578] CPU: L2 cache: 4096K May 29 12:32:45 nash kernel: [23726.866578] CPU 1/0x1 -> Node 0 May 29 12:32:45 nash kernel: [23726.866578] CPU: Physical Processor ID: 0 May 29 12:32:45 nash kernel: [23726.866578] CPU: Processor Core ID: 1 May 29 12:32:45 nash kernel: [23726.866578] CPU1: Thermal monitoring enabled (TM2) May 29 12:32:45 nash kernel: [23727.030129] CPU1: Intel(R) Core(TM)2 Duo CPU T7500 @ 2.20GHz stepping 0b May 29 12:32:45 nash kernel: [23727.030189] CPU0 attaching NULL sched-domain. May 29 12:32:45 nash kernel: [23727.100024] CPU0 attaching sched-domain: May 29 12:32:45 nash kernel: [23727.100027] domain 0: span 0-1 level MC May 29 12:32:45 nash kernel: [23727.100030] groups: 0 1 May 29 12:32:45 nash kernel: [23727.100034] CPU1 attaching sched-domain: May 29 12:32:45 nash kernel: [23727.100035] domain 0: span 0-1 level MC May 29 12:32:45 nash kernel: [23727.100037] groups: 1 0 May 29 12:32:45 nash kernel: [23727.100478] CPU1 is up May 29 12:32:45 nash kernel: [23727.101047] ACPI: Waking up from system sleep state S3 May 29 12:32:45 nash kernel: [23728.000219] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) May 29 12:32:45 nash kernel: [23728.000249] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) May 29 12:32:45 nash kernel: [23728.000384] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) May 29 12:32:45 nash kernel: [23728.110060] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 May 29 12:32:45 nash kernel: [23728.110107] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) May 29 12:32:45 nash kernel: [23728.110123] PM: early resume of drv:uhci_hcd dev:0000:00:1a.1 complete after 109.717 msecs May 29 12:32:45 nash kernel: [23728.110179] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) May 29 12:32:45 nash kernel: [23728.110268] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) May 29 12:32:45 nash kernel: [23728.110347] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) May 29 12:32:45 nash kernel: [23728.110457] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) May 29 12:32:45 nash kernel: [23728.130079] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 May 29 12:32:45 nash kernel: [23728.130126] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) May 29 12:32:45 nash kernel: [23728.130187] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) May 29 12:32:45 nash kernel: [23728.130263] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) May 29 12:32:45 nash kernel: [23728.130328] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) May 29 12:32:45 nash kernel: [23728.130514] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) May 29 12:32:45 nash kernel: [23728.130696] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) May 29 12:32:45 nash kernel: [23728.150094] ohci1394 0000:05:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x802000) May 29 12:32:45 nash kernel: [23728.150106] ohci1394 0000:05:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) May 29 12:32:45 nash kernel: [23728.170091] sdhci-pci 0000:05:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) May 29 12:32:45 nash kernel: [23728.170104] sdhci-pci 0000:05:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) May 29 12:32:45 nash kernel: [23728.170411] PM: early resume of devices complete after 170.300 msecs May 29 12:32:45 nash kernel: [23728.273368] i915 0000:00:02.0: setting latency timer to 64 May 29 12:32:45 nash kernel: [23728.629818] PM: resume of drv:i915 dev:0000:00:02.0 complete after 356.467 msecs May 29 12:32:45 nash kernel: [23728.629860] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 May 29 12:32:45 nash kernel: [23728.629867] e1000e 0000:00:19.0: setting latency timer to 64 May 29 12:32:45 nash kernel: [23728.629883] e1000e 0000:00:19.0: wake-up capability disabled by ACPI May 29 12:32:45 nash kernel: [23728.629890] e1000e 0000:00:19.0: PME# disabled May 29 12:32:45 nash kernel: [23728.629965] e1000e 0000:00:19.0: irq 27 for MSI/MSI-X May 29 12:32:45 nash kernel: [23728.962143] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 332.300 msecs May 29 12:32:45 nash kernel: [23728.962154] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 May 29 12:32:45 nash kernel: [23728.962165] uhci_hcd 0000:00:1a.0: setting latency timer to 64 May 29 12:32:45 nash kernel: [23728.962197] usb usb3: root hub lost power or was reset May 29 12:32:45 nash kernel: [23728.962236] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 May 29 12:32:45 nash kernel: [23728.962242] uhci_hcd 0000:00:1a.1: setting latency timer to 64 May 29 12:32:45 nash kernel: [23728.962266] usb usb4: root hub lost power or was reset May 29 12:32:45 nash kernel: [23728.962291] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 May 29 12:32:45 nash kernel: [23728.962303] ehci_hcd 0000:00:1a.7: setting latency timer to 64 May 29 12:32:45 nash kernel: [23728.962321] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 May 29 12:32:45 nash kernel: [23728.962331] HDA Intel 0000:00:1b.0: setting latency timer to 64 May 29 12:32:45 nash kernel: [23728.962362] pciehp 0000:00:1c.0:pcie04: pciehp_resume ENTRY May 29 12:32:45 nash kernel: [23728.962370] pciehp 0000:00:1c.1:pcie04: pciehp_resume ENTRY May 29 12:32:45 nash kernel: [23728.962385] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 May 29 12:32:45 nash kernel: [23728.962397] uhci_hcd 0000:00:1d.0: setting latency timer to 64 May 29 12:32:45 nash kernel: [23728.962428] usb usb5: root hub lost power or was reset May 29 12:32:45 nash kernel: [23728.962457] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 May 29 12:32:45 nash kernel: [23728.962468] uhci_hcd 0000:00:1d.1: setting latency timer to 64 May 29 12:32:45 nash kernel: [23728.962499] usb usb6: root hub lost power or was reset May 29 12:32:45 nash kernel: [23728.962524] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 May 29 12:32:45 nash kernel: [23728.962532] ehci_hcd 0000:00:1d.7: setting latency timer to 64 May 29 12:32:45 nash kernel: [23728.962562] pci 0000:00:1e.0: setting latency timer to 64 May 29 12:32:45 nash kernel: [23728.962575] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 May 29 12:32:45 nash kernel: [23728.962582] ata_piix 0000:00:1f.1: setting latency timer to 64 May 29 12:32:45 nash kernel: [23728.963045] ata2: port disabled. ignoring. May 29 12:32:45 nash kernel: [23728.963094] ahci 0000:00:1f.2: setting latency timer to 64 May 29 12:32:45 nash kernel: [23729.110086] PM: resume of drv:yenta_cardbus dev:0000:05:00.0 complete after 146.038 msecs May 29 12:32:45 nash kernel: [23729.172096] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[d7efe800-d7efefff] Max Packet=[2048] IR/IT contexts=[4/4] May 29 12:32:45 nash kernel: [23729.178191] sdhci-pci 0000:05:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 May 29 12:32:45 nash kernel: [23729.178194] sdhci-pci 0000:05:00.2: Will use DMA mode even though HW doesn't fully claim to support it. May 29 12:32:45 nash kernel: [23729.310100] ata5: SATA link down (SStatus 0 SControl 300) May 29 12:32:45 nash kernel: [23729.350103] PM: resume of drv:usb dev:usb1 complete after 170.275 msecs May 29 12:32:45 nash kernel: [23729.500078] PM: resume of drv:usb dev:usb2 complete after 149.957 msecs May 29 12:32:45 nash kernel: [23729.770077] PM: resume of drv:usb dev:usb3 complete after 269.977 msecs May 29 12:32:45 nash kernel: [23729.772704] sd 2:0:0:0: [sda] Starting disk May 29 12:32:45 nash kernel: [23729.910072] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) May 29 12:32:45 nash kernel: [23729.913272] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded May 29 12:32:45 nash kernel: [23729.913275] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out May 29 12:32:45 nash kernel: [23729.913278] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out May 29 12:32:45 nash kernel: [23729.929620] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded May 29 12:32:45 nash kernel: [23729.929623] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out May 29 12:32:45 nash kernel: [23729.929626] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out May 29 12:32:45 nash kernel: [23729.932502] ata3.00: configured for UDMA/133 May 29 12:32:45 nash kernel: [23729.956476] ata3.00: configured for UDMA/133 May 29 12:32:45 nash kernel: [23729.956478] ata3: EH complete May 29 12:32:45 nash kernel: [23729.973395] PM: resume of drv:sd dev:2:0:0:0 complete after 200.688 msecs May 29 12:32:45 nash kernel: [23730.090088] usb 2-1: reset high speed USB device using ehci_hcd and address 2 May 29 12:32:45 nash kernel: [23730.508354] snd-usb-audio 2-1:1.2: no reset_resume for driver snd-usb-audio? May 29 12:32:45 nash kernel: [23730.508357] snd-usb-audio 2-1:1.3: no reset_resume for driver snd-usb-audio? May 29 12:32:45 nash kernel: [23730.508631] PM: resume of drv:usb dev:2-1 complete after 535.177 msecs May 29 12:32:45 nash kernel: [23730.620079] usb 3-2: reset full speed USB device using uhci_hcd and address 3 May 29 12:32:45 nash kernel: [23730.777161] PM: resume of drv:usb dev:3-2 complete after 268.502 msecs May 29 12:32:45 nash kernel: [23730.779929] PM: resume of devices complete after 2609.425 msecs May 29 12:32:45 nash kernel: [23730.804913] PM: resume devices took 2.630 seconds May 29 12:32:45 nash kernel: [23730.804936] PM: Finishing wakeup. May 29 12:32:45 nash NetworkManager: SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/pan0, iface: pan0) May 29 12:32:45 nash kernel: [23730.804937] Restarting tasks ... done. May 29 12:32:45 nash NetworkManager: Waking up... May 29 12:32:45 nash NetworkManager: (eth0): now managed May 29 12:32:45 nash NetworkManager: (eth0): device state change: 1 -> 2 (reason 2) May 29 12:32:45 nash NetworkManager: (eth0): bringing up device. May 29 12:32:45 nash kernel: [23731.262891] e1000e 0000:00:19.0: irq 27 for MSI/MSI-X May 29 12:32:45 nash NetworkManager: (eth0): preparing device. May 29 12:32:45 nash NetworkManager: (eth0): deactivating device (reason: 2). May 29 12:32:45 nash NetworkManager: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) May 29 12:32:45 nash NetworkManager: Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) May 29 12:32:45 nash NetworkManager: (wlan0): now managed May 29 12:32:45 nash NetworkManager: (wlan0): device state change: 1 -> 2 (reason 2) May 29 12:32:45 nash NetworkManager: (wlan0): bringing up device. May 29 12:32:45 nash kernel: [23731.322709] e1000e 0000:00:19.0: irq 27 for MSI/MSI-X May 29 12:32:45 nash kernel: [23731.324202] ADDRCONF(NETDEV_UP): eth0: link is not ready May 29 12:32:46 nash kernel: [23731.546248] Registered led device: iwl-phy0::radio May 29 12:32:46 nash kernel: [23731.546307] Registered led device: iwl-phy0::assoc May 29 12:32:46 nash kernel: [23731.546567] Registered led device: iwl-phy0::RX May 29 12:32:46 nash kernel: [23731.546617] Registered led device: iwl-phy0::TX May 29 12:32:46 nash NetworkManager: (wlan0): preparing device. May 29 12:32:46 nash NetworkManager: (wlan0): deactivating device (reason: 2). May 29 12:32:46 nash kernel: [23731.597275] ADDRCONF(NETDEV_UP): wlan0: link is not ready May 29 12:32:46 nash NetworkManager: (wlan0): supplicant interface state: starting -> ready May 29 12:32:46 nash NetworkManager: (wlan0): device state change: 2 -> 3 (reason 42) May 29 12:32:46 nash NetworkManager: Activation (wlan0) starting connection 'Auto GSB-WPA2' May 29 12:32:46 nash NetworkManager: (wlan0): device state change: 3 -> 4 (reason 0) May 29 12:32:46 nash NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled... May 29 12:32:46 nash NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) started... May 29 12:32:46 nash NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled... May 29 12:32:46 nash NetworkManager: Activation (wlan0) Stage 1 of 5 (Device Prepare) complete. May 29 12:32:46 nash NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) starting... May 29 12:32:46 nash NetworkManager: (wlan0): device state change: 4 -> 5 (reason 0) May 29 12:32:46 nash NetworkManager: Activation (wlan0/wireless): connection 'Auto GSB-WPA2' has security, and secrets exist. No new secrets needed. May 29 12:32:46 nash NetworkManager: Config: added 'ssid' value 'GSB-WPA2' May 29 12:32:46 nash NetworkManager: Config: added 'scan_ssid' value '1' May 29 12:32:46 nash NetworkManager: Config: added 'key_mgmt' value 'WPA-PSK' May 29 12:32:46 nash NetworkManager: Config: added 'psk' value '' May 29 12:32:46 nash NetworkManager: nm_setting_802_1x_get_pkcs11_engine_path: assertion `NM_IS_SETTING_802_1X (setting)' failed May 29 12:32:46 nash NetworkManager: nm_setting_802_1x_get_pkcs11_module_path: assertion `NM_IS_SETTING_802_1X (setting)' failed May 29 12:32:46 nash NetworkManager: Activation (wlan0) Stage 2 of 5 (Device Configure) complete. May 29 12:32:46 nash NetworkManager: (wlan0): supplicant connection state: scanning -> disconnected May 29 12:32:46 nash NetworkManager: Config: set interface ap_scan to 1 May 29 12:32:46 nash kernel: [23732.320110] usb 3-1: new full speed USB device using uhci_hcd and address 8 May 29 12:32:47 nash kernel: [23732.489442] usb 3-1: configuration #1 chosen from 1 choice May 29 12:32:47 nash NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/pan0, iface: pan0) May 29 12:32:47 nash NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/pan0, iface: pan0): no ifupdown configuration found. May 29 12:32:47 nash NetworkManager: device_creator(): /sys/devices/virtual/net/pan0: couldn't determine device driver; ignoring... May 29 12:32:51 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> scanning May 29 12:32:53 nash NetworkManager: (wlan0): supplicant connection state: scanning -> associating May 29 12:32:53 nash kernel: [23738.983537] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 12:32:53 nash kernel: [23739.024190] wlan0: direct probe to AP 00:11:93:2a:6f:21 (try 1) May 29 12:32:53 nash kernel: [23739.027200] wlan0: direct probe responded May 29 12:32:53 nash kernel: [23739.027205] wlan0: authenticate with AP 00:11:93:2a:6f:21 (try 1) May 29 12:32:53 nash kernel: [23739.029969] wlan0: authenticated May 29 12:32:53 nash kernel: [23739.029990] wlan0: associate with AP 00:11:93:2a:6f:21 (try 1) May 29 12:32:53 nash kernel: [23739.033952] wlan0: RX AssocResp from 00:11:93:2a:6f:21 (capab=0x431 status=0 aid=1) May 29 12:32:53 nash kernel: [23739.033956] wlan0: associated May 29 12:32:53 nash NetworkManager: (wlan0): supplicant connection state: associating -> 4-way handshake May 29 12:32:53 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> associated May 29 12:32:53 nash kernel: [23739.053568] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready May 29 12:32:54 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 12:32:54 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 12:32:54 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 12:32:54 nash NetworkManager: Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'GSB-WPA2'. May 29 12:32:54 nash NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled. May 29 12:32:54 nash NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) started... May 29 12:32:54 nash NetworkManager: (wlan0): device state change: 5 -> 7 (reason 0) May 29 12:32:54 nash NetworkManager: Activation (wlan0) Beginning DHCP transaction (timeout in 45 seconds) May 29 12:32:54 nash NetworkManager: dhclient started with pid 9877 May 29 12:32:54 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) scheduled... May 29 12:32:54 nash NetworkManager: Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete. May 29 12:32:54 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) started... May 29 12:32:54 nash dhclient: Internet Systems Consortium DHCP Client V3.1.3 May 29 12:32:54 nash dhclient: Copyright 2004-2009 Internet Systems Consortium. May 29 12:32:54 nash dhclient: All rights reserved. May 29 12:32:54 nash dhclient: For info, please visit https://www.isc.org/software/dhcp/ May 29 12:32:54 nash dhclient: May 29 12:32:54 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) complete. May 29 12:32:54 nash NetworkManager: DHCP: device wlan0 state changed normal exit -> preinit May 29 12:32:54 nash dhclient: Listening on LPF/wlan0/00:21:5c:31:f9:17 May 29 12:32:54 nash dhclient: Sending on LPF/wlan0/00:21:5c:31:f9:17 May 29 12:32:54 nash dhclient: Sending on Socket/fallback May 29 12:32:57 nash dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 5 May 29 12:32:57 nash dhclient: DHCPOFFER of 10.33.17.100 from 10.33.16.2 May 29 12:32:57 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 255.255.255.255 port 67 May 29 12:32:57 nash dhclient: DHCPACK of 10.33.17.100 from 10.33.16.2 May 29 12:32:57 nash dhclient: bound to 10.33.17.100 -- renewal in 1112 seconds. May 29 12:32:57 nash NetworkManager: DHCP: device wlan0 state changed preinit -> bound May 29 12:32:57 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled... May 29 12:32:57 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started... May 29 12:32:57 nash NetworkManager: address 10.33.17.100 May 29 12:32:57 nash NetworkManager: prefix 21 (255.255.248.0) May 29 12:32:57 nash NetworkManager: gateway 10.33.16.1 May 29 12:32:57 nash NetworkManager: nameserver '171.64.7.55' May 29 12:32:57 nash NetworkManager: nameserver '171.64.7.121' May 29 12:32:57 nash NetworkManager: nameserver '171.64.7.99' May 29 12:32:57 nash NetworkManager: domain name 'Stanford.EDU' May 29 12:32:57 nash NetworkManager: wins '171.64.7.155' May 29 12:32:57 nash NetworkManager: wins '171.64.7.177' May 29 12:32:57 nash NetworkManager: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled... May 29 12:32:57 nash NetworkManager: Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete. May 29 12:32:57 nash NetworkManager: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started... May 29 12:32:58 nash NetworkManager: (wlan0): device state change: 7 -> 8 (reason 0) May 29 12:32:58 nash NetworkManager: [1275161578.086187] periodic_update(): Roamed from BSSID 00:11:93:17:AD:B1 (GSB-WPA2) to 00:11:93:2A:6F:21 (GSB-WPA2) May 29 12:32:58 nash NetworkManager: Policy set 'Auto GSB-WPA2' (wlan0) as default for routing and DNS. May 29 12:32:58 nash NetworkManager: Activation (wlan0) successful, device activated. May 29 12:32:58 nash NetworkManager: Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete. May 29 12:33:04 nash kernel: [23749.970023] wlan0: no IPv6 routers present May 29 12:51:29 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 12:51:29 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 12:51:29 nash dhclient: bound to 10.33.17.100 -- renewal in 964 seconds. May 29 12:51:29 nash NetworkManager: DHCP: device wlan0 state changed bound -> renew May 29 12:51:29 nash NetworkManager: address 10.33.17.100 May 29 12:51:29 nash NetworkManager: prefix 21 (255.255.248.0) May 29 12:51:29 nash NetworkManager: gateway 10.33.16.1 May 29 12:51:29 nash NetworkManager: nameserver '171.64.7.77' May 29 12:51:29 nash NetworkManager: nameserver '171.64.7.99' May 29 12:51:29 nash NetworkManager: nameserver '171.64.7.121' May 29 12:51:29 nash NetworkManager: domain name 'Stanford.EDU' May 29 12:51:29 nash NetworkManager: wins '171.64.7.155' May 29 12:51:29 nash NetworkManager: wins '171.64.7.177' May 29 12:51:29 nash NetworkManager: Policy set 'Auto GSB-WPA2' (wlan0) as default for routing and DNS. May 29 12:56:23 nash kernel: [25148.654446] npviewer.bin.re[10652]: segfault at ff999ea8 ip 00000000ff999ea8 sp 00000000ffa8fb9c error 14 May 29 13:07:33 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 13:07:33 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 13:07:33 nash dhclient: bound to 10.33.17.100 -- renewal in 1073 seconds. May 29 13:22:30 nash kernel: [26716.015775] npviewer.bin.re[11200]: segfault at 13 ip 00000000f662be87 sp 00000000ff9354a4 error 4 in libflashplayer.so[f5f4f000+994000] May 29 13:24:50 nash kernel: [26856.069279] npviewer.bin.re[11280]: segfault at ff999ea8 ip 00000000ff999ea8 sp 00000000fff28d2c error 14 May 29 13:25:26 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 13:25:26 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 13:25:26 nash dhclient: bound to 10.33.17.100 -- renewal in 1200 seconds. May 29 13:30:14 nash NetworkManager: (wlan0): supplicant connection state: completed -> group handshake May 29 13:30:14 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 13:32:53 nash kernel: [27339.140103] npviewer.bin.re[11489]: segfault at ff999ea8 ip 00000000ff999ea8 sp 00000000ffbb4c8c error 14 May 29 13:35:46 nash kernel: [27511.605565] process `skype' is using obsolete setsockopt SO_BSDCOMPAT May 29 13:45:26 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 13:45:26 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 13:45:26 nash dhclient: bound to 10.33.17.100 -- renewal in 1214 seconds. May 29 14:05:40 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 14:05:40 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 14:05:40 nash dhclient: bound to 10.33.17.100 -- renewal in 1202 seconds. May 29 14:25:42 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 14:25:42 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 14:25:42 nash dhclient: bound to 10.33.17.100 -- renewal in 1253 seconds. May 29 14:31:59 nash NetworkManager: (wlan0): supplicant connection state: completed -> group handshake May 29 14:31:59 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 14:41:29 nash kernel: [31454.510148] No probe response from AP 00:11:93:2a:6f:21 after 500ms, disconnecting. May 29 14:41:29 nash NetworkManager: (wlan0): supplicant connection state: completed -> disconnected May 29 14:41:29 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> scanning May 29 14:41:31 nash NetworkManager: (wlan0): supplicant connection state: scanning -> associating May 29 14:41:31 nash kernel: [31457.012174] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 14:41:31 nash kernel: [31457.020806] wlan0: direct probe to AP 00:11:93:1f:56:81 (try 1) May 29 14:41:31 nash kernel: [31457.025881] wlan0: direct probe responded May 29 14:41:31 nash kernel: [31457.025886] wlan0: authenticate with AP 00:11:93:1f:56:81 (try 1) May 29 14:41:31 nash kernel: [31457.029870] wlan0: authenticated May 29 14:41:31 nash kernel: [31457.029891] wlan0: associate with AP 00:11:93:1f:56:81 (try 1) May 29 14:41:31 nash kernel: [31457.033760] wlan0: RX AssocResp from 00:11:93:1f:56:81 (capab=0x431 status=0 aid=2) May 29 14:41:31 nash kernel: [31457.033764] wlan0: associated May 29 14:41:31 nash NetworkManager: (wlan0): supplicant connection state: associating -> associated May 29 14:41:31 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 14:41:31 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 14:41:31 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 14:41:34 nash NetworkManager: [1275169294.002320] periodic_update(): Roamed from BSSID 00:11:93:2A:6F:21 (GSB-WPA2) to 00:11:93:1F:56:81 (GSB-WPA2) May 29 14:46:35 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 14:46:35 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 14:46:35 nash dhclient: bound to 10.33.17.100 -- renewal in 1027 seconds. May 29 14:47:51 nash NetworkManager: (wlan0): supplicant connection state: completed -> associating May 29 14:47:51 nash kernel: [31836.632623] wlan0: deauthenticating from 00:11:93:1f:56:81 by local choice (reason=3) May 29 14:47:51 nash kernel: [31836.646901] wlan0: deauthenticating from 00:11:93:1f:56:81 by local choice (reason=3) May 29 14:47:51 nash NetworkManager: (wlan0): supplicant connection state: associating -> disconnected May 29 14:47:51 nash kernel: [31836.654617] wlan0: direct probe to AP 00:11:93:2a:6f:21 (try 1) May 29 14:47:51 nash kernel: [31836.657597] wlan0: direct probe responded May 29 14:47:51 nash kernel: [31836.657601] wlan0: authenticate with AP 00:11:93:2a:6f:21 (try 1) May 29 14:47:51 nash kernel: [31836.661219] wlan0: authenticated May 29 14:47:51 nash kernel: [31836.661241] wlan0: associate with AP 00:11:93:2a:6f:21 (try 1) May 29 14:47:51 nash kernel: [31836.666215] wlan0: RX AssocResp from 00:11:93:2a:6f:21 (capab=0x431 status=0 aid=1) May 29 14:47:51 nash kernel: [31836.666218] wlan0: associated May 29 14:47:51 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> associated May 29 14:47:51 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 14:47:51 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 14:47:51 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 14:47:52 nash NetworkManager: [1275169672.001417] periodic_update(): Roamed from BSSID 00:11:93:1F:56:81 (GSB-WPA2) to 00:11:93:2A:6F:21 (GSB-WPA2) May 29 14:51:35 nash kernel: [32060.510050] No probe response from AP 00:11:93:2a:6f:21 after 500ms, disconnecting. May 29 14:51:35 nash NetworkManager: (wlan0): supplicant connection state: completed -> disconnected May 29 14:51:35 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> scanning May 29 14:51:37 nash NetworkManager: (wlan0): supplicant connection state: scanning -> associating May 29 14:51:37 nash kernel: [32063.026009] wlan0: direct probe to AP 00:11:93:2a:6f:21 (try 1) May 29 14:51:37 nash kernel: [32063.029846] wlan0: direct probe responded May 29 14:51:37 nash kernel: [32063.029855] wlan0: authenticate with AP 00:11:93:2a:6f:21 (try 1) May 29 14:51:37 nash kernel: [32063.034260] wlan0: authenticated May 29 14:51:37 nash kernel: [32063.034282] wlan0: associate with AP 00:11:93:2a:6f:21 (try 1) May 29 14:51:37 nash kernel: [32063.039066] wlan0: RX AssocResp from 00:11:93:2a:6f:21 (capab=0x431 status=0 aid=1) May 29 14:51:37 nash kernel: [32063.039070] wlan0: associated May 29 14:51:37 nash NetworkManager: (wlan0): supplicant connection state: associating -> associated May 29 14:51:37 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 14:51:37 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 14:51:37 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 14:51:40 nash NetworkManager: [1275169900.004122] periodic_update(): Roamed from BSSID 00:11:93:2A:6F:21 (GSB-WPA2) to 00:11:93:2A:6F:21 (GSB-WPA2) May 29 15:03:42 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 15:03:42 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 15:03:42 nash dhclient: bound to 10.33.17.100 -- renewal in 1038 seconds. May 29 15:09:51 nash NetworkManager: (wlan0): supplicant connection state: completed -> associating May 29 15:09:51 nash kernel: [33156.682626] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 15:09:51 nash kernel: [33156.731233] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 15:09:51 nash kernel: [33156.739547] wlan0: direct probe to AP 00:11:93:1f:56:81 (try 1) May 29 15:09:51 nash kernel: [33156.742564] wlan0: direct probe responded May 29 15:09:51 nash kernel: [33156.742573] wlan0: authenticate with AP 00:11:93:1f:56:81 (try 1) May 29 15:09:51 nash kernel: [33156.746652] wlan0: authenticated May 29 15:09:51 nash kernel: [33156.752645] wlan0: associate with AP 00:11:93:1f:56:81 (try 1) May 29 15:09:51 nash NetworkManager: (wlan0): supplicant connection state: associating -> disconnected May 29 15:09:51 nash kernel: [33156.757083] wlan0: RX AssocResp from 00:11:93:1f:56:81 (capab=0x431 status=0 aid=1) May 29 15:09:51 nash kernel: [33156.757091] wlan0: associated May 29 15:09:51 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> associated May 29 15:09:51 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 15:09:51 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 15:09:51 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 15:09:52 nash NetworkManager: [1275170992.001143] periodic_update(): Roamed from BSSID 00:11:93:2A:6F:21 (GSB-WPA2) to 00:11:93:1F:56:81 (GSB-WPA2) May 29 15:11:51 nash NetworkManager: (wlan0): supplicant connection state: completed -> associating May 29 15:11:51 nash kernel: [33276.910363] wlan0: deauthenticating from 00:11:93:1f:56:81 by local choice (reason=3) May 29 15:11:51 nash kernel: [33276.922952] wlan0: direct probe to AP 00:11:93:1f:56:81 (try 1) May 29 15:11:51 nash kernel: [33276.922984] wlan0: deauthenticating from 00:11:93:1f:56:81 by local choice (reason=3) May 29 15:11:51 nash NetworkManager: (wlan0): supplicant connection state: associating -> disconnected May 29 15:11:51 nash kernel: [33276.931159] wlan0: direct probe to AP 00:11:93:2a:6f:21 (try 1) May 29 15:11:51 nash kernel: [33276.934120] wlan0: direct probe responded May 29 15:11:51 nash kernel: [33276.934129] wlan0: authenticate with AP 00:11:93:2a:6f:21 (try 1) May 29 15:11:51 nash kernel: [33276.938382] wlan0: authenticated May 29 15:11:51 nash kernel: [33276.991336] wlan0: associate with AP 00:11:93:2a:6f:21 (try 1) May 29 15:11:51 nash kernel: [33276.995639] wlan0: RX AssocResp from 00:11:93:2a:6f:21 (capab=0x431 status=0 aid=1) May 29 15:11:51 nash kernel: [33276.995647] wlan0: associated May 29 15:11:51 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> associated May 29 15:11:52 nash NetworkManager: [1275171112.001390] periodic_update(): Roamed from BSSID 00:11:93:1F:56:81 (GSB-WPA2) to 00:11:93:2A:6F:21 (GSB-WPA2) May 29 15:11:52 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 15:11:52 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 15:11:52 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 15:21:00 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 15:21:00 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 15:21:00 nash dhclient: bound to 10.33.17.100 -- renewal in 1208 seconds. May 29 15:32:56 nash NetworkManager: (wlan0): supplicant connection state: completed -> group handshake May 29 15:32:56 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 15:41:08 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 15:41:08 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 15:41:08 nash dhclient: bound to 10.33.17.100 -- renewal in 1194 seconds. May 29 16:01:02 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 16:01:02 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 16:01:02 nash dhclient: bound to 10.33.17.100 -- renewal in 1042 seconds. May 29 16:13:51 nash NetworkManager: (wlan0): supplicant connection state: completed -> associating May 29 16:13:51 nash kernel: [36996.722758] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 16:13:51 nash kernel: [36996.734937] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 16:13:51 nash kernel: [36996.743310] wlan0: direct probe to AP 00:11:93:1f:56:81 (try 1) May 29 16:13:51 nash kernel: [36996.747754] wlan0: direct probe responded May 29 16:13:51 nash kernel: [36996.747764] wlan0: authenticate with AP 00:11:93:1f:56:81 (try 1) May 29 16:13:51 nash NetworkManager: (wlan0): supplicant connection state: associating -> disconnected May 29 16:13:51 nash kernel: [36996.750309] wlan0: authenticated May 29 16:13:51 nash kernel: [36996.793111] wlan0: associate with AP 00:11:93:1f:56:81 (try 1) May 29 16:13:51 nash kernel: [36996.798502] wlan0: RX AssocResp from 00:11:93:1f:56:81 (capab=0x431 status=0 aid=1) May 29 16:13:51 nash kernel: [36996.798505] wlan0: associated May 29 16:13:51 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> associated May 29 16:13:51 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 16:13:51 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 16:13:51 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 16:13:52 nash NetworkManager: [1275174832.001339] periodic_update(): Roamed from BSSID 00:11:93:2A:6F:21 (GSB-WPA2) to 00:11:93:1F:56:81 (GSB-WPA2) May 29 16:15:51 nash NetworkManager: (wlan0): supplicant connection state: completed -> associating May 29 16:15:51 nash kernel: [37116.870247] wlan0: deauthenticating from 00:11:93:1f:56:81 by local choice (reason=3) May 29 16:15:51 nash kernel: [37116.884438] wlan0: deauthenticating from 00:11:93:1f:56:81 by local choice (reason=3) May 29 16:15:51 nash NetworkManager: (wlan0): supplicant connection state: associating -> disconnected May 29 16:15:51 nash kernel: [37116.892178] wlan0: direct probe to AP 00:11:93:2a:6f:21 (try 1) May 29 16:15:51 nash kernel: [37116.895339] wlan0: direct probe responded May 29 16:15:51 nash kernel: [37116.895348] wlan0: authenticate with AP 00:11:93:2a:6f:21 (try 1) May 29 16:15:51 nash kernel: [37116.897214] wlan0: authenticated May 29 16:15:51 nash kernel: [37116.951354] wlan0: associate with AP 00:11:93:2a:6f:21 (try 1) May 29 16:15:51 nash kernel: [37116.956925] wlan0: RX AssocResp from 00:11:93:2a:6f:21 (capab=0x431 status=0 aid=1) May 29 16:15:51 nash kernel: [37116.956934] wlan0: associated May 29 16:15:51 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> associated May 29 16:15:52 nash NetworkManager: [1275174952.001167] periodic_update(): Roamed from BSSID 00:11:93:1F:56:81 (GSB-WPA2) to 00:11:93:2A:6F:21 (GSB-WPA2) May 29 16:15:52 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 16:15:52 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 16:15:52 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 16:17:51 nash NetworkManager: (wlan0): supplicant connection state: completed -> associating May 29 16:17:51 nash kernel: [37236.860242] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 16:17:51 nash kernel: [37236.875099] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 16:17:51 nash NetworkManager: (wlan0): supplicant connection state: associating -> disconnected May 29 16:17:51 nash kernel: [37236.882697] wlan0: direct probe to AP 00:11:93:1f:56:81 (try 1) May 29 16:17:51 nash kernel: [37236.887069] wlan0: direct probe responded May 29 16:17:51 nash kernel: [37236.887078] wlan0: authenticate with AP 00:11:93:1f:56:81 (try 1) May 29 16:17:51 nash kernel: [37236.892982] wlan0: authenticated May 29 16:17:51 nash kernel: [37236.961350] wlan0: associate with AP 00:11:93:1f:56:81 (try 1) May 29 16:17:51 nash kernel: [37237.160149] wlan0: associate with AP 00:11:93:1f:56:81 (try 2) May 29 16:17:51 nash kernel: [37237.164892] wlan0: RX AssocResp from 00:11:93:1f:56:81 (capab=0x431 status=0 aid=1) May 29 16:17:51 nash kernel: [37237.164900] wlan0: associated May 29 16:17:51 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> associated May 29 16:17:52 nash NetworkManager: [1275175072.000766] periodic_update(): Roamed from BSSID 00:11:93:2A:6F:21 (GSB-WPA2) to 00:11:93:1F:56:81 (GSB-WPA2) May 29 16:17:52 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 16:17:52 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 16:17:52 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 16:18:24 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 16:18:24 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 16:18:24 nash dhclient: bound to 10.33.17.100 -- renewal in 1066 seconds. May 29 16:19:51 nash NetworkManager: (wlan0): supplicant connection state: completed -> associating May 29 16:19:51 nash kernel: [37357.020269] wlan0: deauthenticating from 00:11:93:1f:56:81 by local choice (reason=3) May 29 16:19:51 nash kernel: [37357.035399] wlan0: deauthenticating from 00:11:93:1f:56:81 by local choice (reason=3) May 29 16:19:51 nash NetworkManager: (wlan0): supplicant connection state: associating -> disconnected May 29 16:19:51 nash kernel: [37357.043102] wlan0: direct probe to AP 00:11:93:2a:6f:21 (try 1) May 29 16:19:51 nash kernel: [37357.046113] wlan0: direct probe responded May 29 16:19:51 nash kernel: [37357.046121] wlan0: authenticate with AP 00:11:93:2a:6f:21 (try 1) May 29 16:19:51 nash kernel: [37357.053143] wlan0: authenticated May 29 16:19:51 nash kernel: [37357.121372] wlan0: associate with AP 00:11:93:2a:6f:21 (try 1) May 29 16:19:51 nash kernel: [37357.125853] wlan0: RX AssocResp from 00:11:93:2a:6f:21 (capab=0x431 status=0 aid=1) May 29 16:19:51 nash kernel: [37357.125861] wlan0: associated May 29 16:19:51 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> associated May 29 16:19:51 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 16:19:51 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 16:19:51 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 16:19:52 nash NetworkManager: [1275175192.001348] periodic_update(): Roamed from BSSID 00:11:93:1F:56:81 (GSB-WPA2) to 00:11:93:2A:6F:21 (GSB-WPA2) May 29 16:35:34 nash NetworkManager: (wlan0): supplicant connection state: completed -> group handshake May 29 16:35:34 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 16:36:10 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 16:36:10 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 16:36:10 nash dhclient: bound to 10.33.17.100 -- renewal in 1126 seconds. May 29 16:54:56 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 16:54:56 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 16:54:56 nash dhclient: bound to 10.33.17.100 -- renewal in 1246 seconds. May 29 17:15:42 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 17:15:42 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 17:15:42 nash dhclient: bound to 10.33.17.100 -- renewal in 1256 seconds. May 29 17:35:42 nash NetworkManager: (wlan0): supplicant connection state: completed -> group handshake May 29 17:35:42 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 17:36:38 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 17:36:38 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 17:36:38 nash dhclient: bound to 10.33.17.100 -- renewal in 1037 seconds. May 29 17:53:55 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 17:53:55 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 17:53:55 nash dhclient: bound to 10.33.17.100 -- renewal in 1172 seconds. May 29 18:13:27 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 18:13:27 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 18:13:27 nash dhclient: bound to 10.33.17.100 -- renewal in 1261 seconds. May 29 18:21:51 nash NetworkManager: (wlan0): supplicant connection state: completed -> associating May 29 18:21:51 nash kernel: [44676.932626] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 18:21:51 nash kernel: [44677.022000] wlan0: direct probe to AP 00:11:93:2a:6f:21 (try 1) May 29 18:21:51 nash kernel: [44677.022040] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 18:21:51 nash kernel: [44677.029565] wlan0: direct probe to AP 00:11:93:1f:56:81 (try 1) May 29 18:21:51 nash kernel: [44677.032576] wlan0: direct probe responded May 29 18:21:51 nash kernel: [44677.032585] wlan0: authenticate with AP 00:11:93:1f:56:81 (try 1) May 29 18:21:51 nash kernel: [44677.037851] wlan0: authenticated May 29 18:21:51 nash kernel: [44677.037893] wlan0: associate with AP 00:11:93:1f:56:81 (try 1) May 29 18:21:51 nash kernel: [44677.042483] wlan0: RX AssocResp from 00:11:93:1f:56:81 (capab=0x431 status=0 aid=1) May 29 18:21:51 nash kernel: [44677.042490] wlan0: associated May 29 18:21:51 nash NetworkManager: (wlan0): supplicant connection state: associating -> disconnected May 29 18:21:51 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> associated May 29 18:21:51 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 18:21:51 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 18:21:51 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 18:21:52 nash NetworkManager: [1275182512.001694] periodic_update(): Roamed from BSSID 00:11:93:2A:6F:21 (GSB-WPA2) to 00:11:93:1F:56:81 (GSB-WPA2) May 29 18:23:51 nash NetworkManager: (wlan0): supplicant connection state: completed -> associating May 29 18:23:51 nash kernel: [44796.922629] wlan0: deauthenticating from 00:11:93:1f:56:81 by local choice (reason=3) May 29 18:23:51 nash kernel: [44796.971838] wlan0: deauthenticating from 00:11:93:1f:56:81 by local choice (reason=3) May 29 18:23:51 nash kernel: [44796.979435] wlan0: direct probe to AP 00:11:93:2a:6f:21 (try 1) May 29 18:23:51 nash kernel: [44796.982438] wlan0: direct probe responded May 29 18:23:51 nash kernel: [44796.982447] wlan0: authenticate with AP 00:11:93:2a:6f:21 (try 1) May 29 18:23:51 nash kernel: [44796.990809] wlan0: authenticated May 29 18:23:51 nash kernel: [44796.990847] wlan0: associate with AP 00:11:93:2a:6f:21 (try 1) May 29 18:23:51 nash kernel: [44796.995147] wlan0: RX AssocResp from 00:11:93:2a:6f:21 (capab=0x431 status=0 aid=1) May 29 18:23:51 nash kernel: [44796.995154] wlan0: associated May 29 18:23:51 nash NetworkManager: (wlan0): supplicant connection state: associating -> disconnected May 29 18:23:51 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> associated May 29 18:23:51 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 18:23:51 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 18:23:51 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 18:23:52 nash NetworkManager: [1275182632.001145] periodic_update(): Roamed from BSSID 00:11:93:1F:56:81 (GSB-WPA2) to 00:11:93:2A:6F:21 (GSB-WPA2) May 29 18:34:28 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 18:34:28 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 18:34:28 nash dhclient: bound to 10.33.17.100 -- renewal in 1079 seconds. May 29 18:36:15 nash NetworkManager: (wlan0): supplicant connection state: completed -> group handshake May 29 18:36:15 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 18:52:27 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 18:52:27 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 18:52:27 nash dhclient: bound to 10.33.17.100 -- renewal in 1195 seconds. May 29 19:12:22 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 19:12:22 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 19:12:22 nash dhclient: bound to 10.33.17.100 -- renewal in 1066 seconds. May 29 19:18:43 nash kernel: [48088.672144] npviewer.bin.re[16733]: segfault at ff999ea8 ip 00000000ff999ea8 sp 00000000ffcfd17c error 14 May 29 19:21:27 nash kernel: [48252.573882] npviewer.bin.re[16917]: segfault at ff999ea8 ip 00000000ff999ea8 sp 00000000ffd16b9c error 14 May 29 19:27:51 nash NetworkManager: (wlan0): supplicant connection state: completed -> associating May 29 19:27:51 nash kernel: [48636.910103] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 19:27:51 nash kernel: [48636.927759] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 19:27:51 nash kernel: [48636.935929] wlan0: direct probe to AP 00:11:93:1f:56:81 (try 1) May 29 19:27:51 nash kernel: [48636.939252] wlan0: direct probe responded May 29 19:27:51 nash kernel: [48636.939256] wlan0: authenticate with AP 00:11:93:1f:56:81 (try 1) May 29 19:27:51 nash kernel: [48636.942883] wlan0: authenticated May 29 19:27:51 nash NetworkManager: (wlan0): supplicant connection state: associating -> disconnected May 29 19:27:51 nash kernel: [48637.000572] wlan0: associate with AP 00:11:93:1f:56:81 (try 1) May 29 19:27:51 nash kernel: [48637.006137] wlan0: RX AssocResp from 00:11:93:1f:56:81 (capab=0x431 status=0 aid=4) May 29 19:27:51 nash kernel: [48637.006141] wlan0: associated May 29 19:27:51 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> associated May 29 19:27:51 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 19:27:51 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 19:27:51 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 19:27:52 nash NetworkManager: [1275186472.001267] periodic_update(): Roamed from BSSID 00:11:93:2A:6F:21 (GSB-WPA2) to 00:11:93:1F:56:81 (GSB-WPA2) May 29 19:29:51 nash NetworkManager: (wlan0): supplicant connection state: completed -> associating May 29 19:29:51 nash kernel: [48756.920095] wlan0: deauthenticating from 00:11:93:1f:56:81 by local choice (reason=3) May 29 19:29:51 nash kernel: [48756.934702] wlan0: deauthenticating from 00:11:93:1f:56:81 by local choice (reason=3) May 29 19:29:51 nash kernel: [48756.943977] wlan0: direct probe to AP 00:11:93:2a:6f:21 (try 1) May 29 19:29:51 nash kernel: [48756.947310] wlan0: direct probe responded May 29 19:29:51 nash kernel: [48756.947319] wlan0: authenticate with AP 00:11:93:2a:6f:21 (try 1) May 29 19:29:51 nash kernel: [48756.950114] wlan0: authenticated May 29 19:29:51 nash NetworkManager: (wlan0): supplicant connection state: associating -> disconnected May 29 19:29:51 nash kernel: [48757.020175] wlan0: associate with AP 00:11:93:2a:6f:21 (try 1) May 29 19:29:51 nash kernel: [48757.025056] wlan0: RX AssocResp from 00:11:93:2a:6f:21 (capab=0x431 status=0 aid=1) May 29 19:29:51 nash kernel: [48757.025064] wlan0: associated May 29 19:29:51 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> associated May 29 19:29:51 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 19:29:51 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 19:29:51 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 19:29:52 nash NetworkManager: [1275186592.000967] periodic_update(): Roamed from BSSID 00:11:93:1F:56:81 (GSB-WPA2) to 00:11:93:2A:6F:21 (GSB-WPA2) May 29 19:30:08 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 19:30:08 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 19:30:08 nash dhclient: bound to 10.33.17.100 -- renewal in 1053 seconds. May 29 19:36:22 nash NetworkManager: (wlan0): supplicant connection state: completed -> group handshake May 29 19:36:22 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 19:47:41 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 19:47:41 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 19:47:41 nash dhclient: bound to 10.33.17.100 -- renewal in 1200 seconds. May 29 20:07:41 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 20:07:41 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 20:07:41 nash dhclient: bound to 10.33.17.100 -- renewal in 1016 seconds. May 29 20:11:51 nash NetworkManager: (wlan0): supplicant connection state: completed -> associating May 29 20:11:51 nash kernel: [51276.940131] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 20:11:51 nash kernel: [51276.955846] wlan0: deauthenticating from 00:11:93:2a:6f:21 by local choice (reason=3) May 29 20:11:51 nash kernel: [51276.963721] wlan0: direct probe to AP 00:11:93:1f:56:81 (try 1) May 29 20:11:51 nash kernel: [51276.966691] wlan0: direct probe responded May 29 20:11:51 nash kernel: [51276.966699] wlan0: authenticate with AP 00:11:93:1f:56:81 (try 1) May 29 20:11:51 nash kernel: [51276.971814] wlan0: authenticated May 29 20:11:51 nash NetworkManager: (wlan0): supplicant connection state: associating -> disconnected May 29 20:11:51 nash kernel: [51277.030154] wlan0: associate with AP 00:11:93:1f:56:81 (try 1) May 29 20:11:51 nash kernel: [51277.035143] wlan0: RX AssocResp from 00:11:93:1f:56:81 (capab=0x431 status=0 aid=2) May 29 20:11:51 nash kernel: [51277.035151] wlan0: associated May 29 20:11:51 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> associated May 29 20:11:51 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 20:11:51 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 20:11:51 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 20:11:52 nash NetworkManager: [1275189112.001555] periodic_update(): Roamed from BSSID 00:11:93:2A:6F:21 (GSB-WPA2) to 00:11:93:1F:56:81 (GSB-WPA2) May 29 20:13:51 nash NetworkManager: (wlan0): supplicant connection state: completed -> associating May 29 20:13:51 nash kernel: [51396.710125] wlan0: deauthenticating from 00:11:93:1f:56:81 by local choice (reason=3) May 29 20:13:51 nash kernel: [51396.725278] wlan0: deauthenticating from 00:11:93:1f:56:81 by local choice (reason=3) May 29 20:13:51 nash kernel: [51396.733757] wlan0: direct probe to AP 00:11:93:2a:6f:21 (try 1) May 29 20:13:51 nash kernel: [51396.736757] wlan0: direct probe responded May 29 20:13:51 nash kernel: [51396.736766] wlan0: authenticate with AP 00:11:93:2a:6f:21 (try 1) May 29 20:13:51 nash kernel: [51396.739511] wlan0: authenticated May 29 20:13:51 nash NetworkManager: (wlan0): supplicant connection state: associating -> disconnected May 29 20:13:51 nash kernel: [51396.810181] wlan0: associate with AP 00:11:93:2a:6f:21 (try 1) May 29 20:13:51 nash kernel: [51396.814824] wlan0: RX AssocResp from 00:11:93:2a:6f:21 (capab=0x431 status=0 aid=1) May 29 20:13:51 nash kernel: [51396.814832] wlan0: associated May 29 20:13:51 nash NetworkManager: (wlan0): supplicant connection state: disconnected -> associated May 29 20:13:52 nash NetworkManager: [1275189232.001096] periodic_update(): Roamed from BSSID 00:11:93:1F:56:81 (GSB-WPA2) to 00:11:93:2A:6F:21 (GSB-WPA2) May 29 20:13:52 nash NetworkManager: (wlan0): supplicant connection state: associated -> 4-way handshake May 29 20:13:52 nash NetworkManager: (wlan0): supplicant connection state: 4-way handshake -> group handshake May 29 20:13:52 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 20:24:37 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 20:24:37 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 20:24:37 nash dhclient: bound to 10.33.17.100 -- renewal in 1137 seconds. May 29 20:37:31 nash NetworkManager: (wlan0): supplicant connection state: completed -> group handshake May 29 20:37:31 nash NetworkManager: (wlan0): supplicant connection state: group handshake -> completed May 29 20:43:34 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 20:43:34 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 20:43:34 nash dhclient: bound to 10.33.17.100 -- renewal in 1125 seconds. May 29 21:02:17 nash kernel: [54303.059050] npviewer.bin.re[21051]: segfault at ff999ea8 ip 00000000ff999ea8 sp 00000000ff916b6c error 14 May 29 21:02:19 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 21:02:19 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 21:02:19 nash dhclient: bound to 10.33.17.100 -- renewal in 1024 seconds. May 29 21:04:11 nash kernel: [54416.415111] npviewer.bin.re[21118]: segfault at ff999ea8 ip 00000000ff999ea8 sp 00000000ffb0aeec error 14 May 29 21:05:08 nash kernel: [54474.182075] npviewer.bin.re[21168]: segfault at 3c ip 00000000f60e32ea sp 00000000ffd7d750 error 4 in libflashplayer.so[f5e98000+994000] May 29 21:19:23 nash dhclient: DHCPREQUEST of 10.33.17.100 on wlan0 to 171.64.7.111 port 67 May 29 21:19:23 nash dhclient: DHCPACK of 10.33.17.100 from 171.64.7.111 May 29 21:19:23 nash dhclient: bound to 10.33.17.100 -- renewal in 985 seconds.