Log messages of sleep and wakeup

usbfail.log (text/plain), 22.24 KB, created by Till Crueger on 2012-03-24 19:32:43 UTC

Mar 23 23:27:54 chi NetworkManager[16396]: <info> sleep requested (sleeping: no  enabled: yes)
Mar 23 23:27:54 chi NetworkManager[16396]: <info> sleeping or disabling...
Mar 23 23:27:54 chi NetworkManager[16396]: <info> (wlan0): now unmanaged
Mar 23 23:27:54 chi NetworkManager[16396]: <info> (wlan0): device state change: 8 -> 1 (reason 37)
Mar 23 23:27:54 chi NetworkManager[16396]: <info> (wlan0): deactivating device (reason: 37).
Mar 23 23:27:54 chi dhcpcd[20206]: received SIGTERM, stopping
Mar 23 23:27:54 chi dhcpcd[20206]: wlan0: removing interface
Mar 23 23:27:54 chi NetworkManager[16396]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 20206
Mar 23 23:27:54 chi NetworkManager[16396]: <info> (wlan0): removing resolv.conf from /sbin/resolvconf
Mar 23 23:27:54 chi kernel: wlan0: deauthenticating from 00:1f:33:4b:d3:44 by local choice (reason=3)
Mar 23 23:27:54 chi kernel: cfg80211: Calling CRDA to update world regulatory domain
Mar 23 23:27:54 chi NetworkManager[16396]: <info> Clearing nscd hosts cache.
Mar 23 23:27:54 chi NetworkManager[16396]: <info> (wlan0): cleaning up...
Mar 23 23:27:54 chi NetworkManager[16396]: <info> (wlan0): taking down device.
Mar 23 23:27:55 chi NetworkManager[16396]: <info> (eth0): now unmanaged
Mar 23 23:27:55 chi dbus[16383]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
Mar 23 23:27:55 chi NetworkManager[16396]: <info> (eth0): device state change: 2 -> 1 (reason 37)
Mar 23 23:27:55 chi NetworkManager[16396]: <info> (eth0): cleaning up...
Mar 23 23:27:55 chi NetworkManager[16396]: <info> (eth0): taking down device.
Mar 23 23:27:55 chi dbus[16383]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Mar 23 23:27:55 chi NetworkManager[16396]: <warn> (pid 20206) unhandled DHCP event for interface wlan0
Mar 23 23:27:56 chi kernel: EXT4-fs (sda7): re-mounted. Opts: commit=0
Mar 23 23:27:56 chi kernel: EXT4-fs (sda5): re-mounted. Opts: commit=0
Mar 23 23:27:56 chi kernel: EXT4-fs (sda6): re-mounted. Opts: commit=0
Mar 23 23:27:56 chi kernel: [drm] Num pipes: 3
Mar 23 23:32:37 chi kernel: PM: Syncing filesystems ... done.
Mar 23 23:32:37 chi kernel: Freezing user space processes ... (elapsed 0.01 seconds) done.
Mar 23 23:32:37 chi kernel: Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Mar 23 23:32:37 chi kernel: Suspending console(s) (use no_console_suspend to debug)
Mar 23 23:32:37 chi kernel: sd 2:0:0:0: [sda] Synchronizing SCSI cache
Mar 23 23:32:37 chi kernel: parport_pc 00:02: disabled
Mar 23 23:32:37 chi kernel: ACPI handle has no context!
Mar 23 23:32:37 chi kernel: tifm_7xx1 0000:02:04.2: PCI INT A disabled
Mar 23 23:32:37 chi kernel: ACPI handle has no context!
Mar 23 23:32:37 chi kernel: b43-pci-bridge 0000:30:00.0: PCI INT A disabled
Mar 23 23:32:37 chi kernel: pci 0000:01:05.0: PCI INT A disabled
Mar 23 23:32:37 chi kernel: pata_atiixp 0000:00:14.1: PCI INT A disabled
Mar 23 23:32:37 chi kernel: ehci_hcd 0000:00:13.2: PCI INT A disabled
Mar 23 23:32:37 chi kernel: ohci_hcd 0000:00:13.1: PCI INT A disabled
Mar 23 23:32:37 chi kernel: sd 2:0:0:0: [sda] Stopping disk
Mar 23 23:32:37 chi kernel: ohci_hcd 0000:00:13.0: PCI INT A disabled
Mar 23 23:32:37 chi kernel: snd_hda_intel 0000:00:14.2: PCI INT A disabled
Mar 23 23:32:37 chi kernel: sata_sil 0000:00:12.0: PCI INT A disabled
Mar 23 23:32:37 chi kernel: PM: suspend of devices complete after 1095.223 msecs
Mar 23 23:32:37 chi kernel: ehci_hcd 0000:00:13.2: PME# enabled
Mar 23 23:32:37 chi kernel: ehci_hcd 0000:00:13.2: wake-up capability enabled by ACPI
Mar 23 23:32:37 chi kernel: ohci_hcd 0000:00:13.1: wake-up capability enabled by ACPI
Mar 23 23:32:37 chi kernel: ohci_hcd 0000:00:13.0: wake-up capability enabled by ACPI
Mar 23 23:32:37 chi kernel: PM: late suspend of devices complete after 14.674 msecs
Mar 23 23:32:37 chi kernel: ACPI: Preparing to enter system sleep state S3
Mar 23 23:32:37 chi kernel: PM: Saving platform NVS memory
Mar 23 23:32:37 chi kernel: Disabling non-boot CPUs ...
Mar 23 23:32:37 chi kernel: Extended CMOS year: 2000
Mar 23 23:32:37 chi kernel: ACPI: Low-level resume complete
Mar 23 23:32:37 chi kernel: PM: Restoring platform NVS memory
Mar 23 23:32:37 chi kernel: Extended CMOS year: 2000
Mar 23 23:32:37 chi kernel: ACPI: Waking up from system sleep state S3
Mar 23 23:32:37 chi kernel: pci 0000:00:01.0: restoring config space at offset 0x7 (was 0x2206161, writing 0x22206161)
Mar 23 23:32:37 chi kernel: pcieport 0000:00:04.0: restoring config space at offset 0x9 (was 0x10001, writing 0x1fff1)
Mar 23 23:32:37 chi kernel: pcieport 0000:00:04.0: restoring config space at offset 0x8 (was 0x0, writing 0xd3f0d000)
Mar 23 23:32:37 chi kernel: pcieport 0000:00:04.0: restoring config space at offset 0x7 (was 0x101, writing 0x5141)
Mar 23 23:32:37 chi kernel: pcieport 0000:00:04.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10010)
Mar 23 23:32:37 chi kernel: pcieport 0000:00:04.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
Mar 23 23:32:37 chi kernel: pcieport 0000:00:05.0: restoring config space at offset 0x9 (was 0x10001, writing 0x1fff1)
Mar 23 23:32:37 chi kernel: pcieport 0000:00:05.0: restoring config space at offset 0x8 (was 0x0, writing 0xcff0cc00)
Mar 23 23:32:37 chi kernel: pcieport 0000:00:05.0: restoring config space at offset 0x7 (was 0x101, writing 0x3121)
Mar 23 23:32:37 chi kernel: pcieport 0000:00:05.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10010)
Mar 23 23:32:37 chi kernel: pcieport 0000:00:05.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
Mar 23 23:32:37 chi kernel: pcieport 0000:00:06.0: restoring config space at offset 0x9 (was 0x10001, writing 0x1fff1)
Mar 23 23:32:37 chi kernel: pcieport 0000:00:06.0: restoring config space at offset 0x8 (was 0x0, writing 0xc800c800)
Mar 23 23:32:37 chi kernel: pcieport 0000:00:06.0: restoring config space at offset 0x7 (was 0x101, writing 0x1f1)
Mar 23 23:32:37 chi kernel: pcieport 0000:00:06.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10010)
Mar 23 23:32:37 chi kernel: pcieport 0000:00:06.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
Mar 23 23:32:37 chi kernel: sata_sil 0000:00:12.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
Mar 23 23:32:37 chi kernel: sata_sil 0000:00:12.0: restoring config space at offset 0x3 (was 0x0, writing 0x4010)
Mar 23 23:32:37 chi kernel: ohci_hcd 0000:00:13.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
Mar 23 23:32:37 chi kernel: ohci_hcd 0000:00:13.0: restoring config space at offset 0x4 (was 0x0, writing 0xd4401000)
Mar 23 23:32:37 chi kernel: ohci_hcd 0000:00:13.0: restoring config space at offset 0x3 (was 0x800000, writing 0x804010)
Mar 23 23:32:37 chi kernel: ohci_hcd 0000:00:13.0: restoring config space at offset 0x1 (was 0x2a00000, writing 0x2a00003)
Mar 23 23:32:37 chi kernel: ohci_hcd 0000:00:13.0: wake-up capability disabled by ACPI
Mar 23 23:32:37 chi kernel: ohci_hcd 0000:00:13.1: restoring config space at offset 0xf (was 0x100, writing 0x10b)
Mar 23 23:32:37 chi kernel: ohci_hcd 0000:00:13.1: restoring config space at offset 0x4 (was 0x0, writing 0xd4402000)
Mar 23 23:32:37 chi kernel: ohci_hcd 0000:00:13.1: restoring config space at offset 0x3 (was 0x10, writing 0x4010)
Mar 23 23:32:37 chi kernel: ohci_hcd 0000:00:13.1: restoring config space at offset 0x1 (was 0x2a00000, writing 0x2a00003)
Mar 23 23:32:37 chi kernel: ohci_hcd 0000:00:13.1: wake-up capability disabled by ACPI
Mar 23 23:32:37 chi kernel: ehci_hcd 0000:00:13.2: restoring config space at offset 0xf (was 0x100, writing 0x10b)
Mar 23 23:32:37 chi kernel: ehci_hcd 0000:00:13.2: restoring config space at offset 0x3 (was 0x10, writing 0x4010)
Mar 23 23:32:37 chi kernel: ehci_hcd 0000:00:13.2: wake-up capability disabled by ACPI
Mar 23 23:32:37 chi kernel: ehci_hcd 0000:00:13.2: PME# disabled
Mar 23 23:32:37 chi kernel: pata_atiixp 0000:00:14.1: restoring config space at offset 0xf (was 0x100, writing 0x10a)
Mar 23 23:32:37 chi kernel: pata_atiixp 0000:00:14.1: restoring config space at offset 0x8 (was 0x1, writing 0x7041)
Mar 23 23:32:37 chi kernel: pata_atiixp 0000:00:14.1: restoring config space at offset 0x3 (was 0x0, writing 0x4010)
Mar 23 23:32:37 chi kernel: pata_atiixp 0000:00:14.1: restoring config space at offset 0x1 (was 0x2300000, writing 0x2300005)
Mar 23 23:32:37 chi kernel: snd_hda_intel 0000:00:14.2: restoring config space at offset 0x1 (was 0x4100000, writing 0x4100002)
Mar 23 23:32:37 chi kernel: pci 0000:00:14.4: restoring config space at offset 0x3 (was 0x816000, writing 0x814000)
Mar 23 23:32:37 chi kernel: b43-pci-bridge 0000:30:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
Mar 23 23:32:37 chi kernel: b43-pci-bridge 0000:30:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xc8000000)
Mar 23 23:32:37 chi kernel: b43-pci-bridge 0000:30:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10)
Mar 23 23:32:37 chi kernel: b43-pci-bridge 0000:30:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
Mar 23 23:32:37 chi kernel: tg3 0000:02:01.0: restoring config space at offset 0xf (was 0x4001ff, writing 0x400105)
Mar 23 23:32:37 chi kernel: tg3 0000:02:01.0: restoring config space at offset 0xc (was 0x0, writing 0xefff0000)
Mar 23 23:32:37 chi kernel: tg3 0000:02:01.0: restoring config space at offset 0x4 (was 0xffff0000, writing 0xd4000000)
Mar 23 23:32:37 chi kernel: tg3 0000:02:01.0: restoring config space at offset 0x3 (was 0x0, writing 0x4010)
Mar 23 23:32:37 chi kernel: tg3 0000:02:01.0: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00006)
Mar 23 23:32:37 chi kernel: yenta_cardbus 0000:02:04.0: restoring config space at offset 0xf (was 0x34001ff, writing 0x5c0010a)
Mar 23 23:32:37 chi kernel: yenta_cardbus 0000:02:04.0: restoring config space at offset 0xe (was 0x0, writing 0x10fc)
Mar 23 23:32:37 chi kernel: yenta_cardbus 0000:02:04.0: restoring config space at offset 0xd (was 0x0, writing 0x1000)
Mar 23 23:32:37 chi kernel: yenta_cardbus 0000:02:04.0: restoring config space at offset 0xc (was 0x0, writing 0x14fc)
Mar 23 23:32:37 chi kernel: yenta_cardbus 0000:02:04.0: restoring config space at offset 0xb (was 0x0, writing 0x1400)
Mar 23 23:32:37 chi kernel: yenta_cardbus 0000:02:04.0: restoring config space at offset 0xa (was 0x0, writing 0xdffff000)
Mar 23 23:32:37 chi kernel: yenta_cardbus 0000:02:04.0: restoring config space at offset 0x9 (was 0x0, writing 0xdc000000)
Mar 23 23:32:37 chi kernel: yenta_cardbus 0000:02:04.0: restoring config space at offset 0x8 (was 0x0, writing 0xdbfff000)
Mar 23 23:32:37 chi kernel: yenta_cardbus 0000:02:04.0: restoring config space at offset 0x7 (was 0x0, writing 0xd8000000)
Mar 23 23:32:37 chi kernel: yenta_cardbus 0000:02:04.0: restoring config space at offset 0x6 (was 0x0, writing 0xb0060302)
Mar 23 23:32:37 chi kernel: yenta_cardbus 0000:02:04.0: restoring config space at offset 0x4 (was 0x0, writing 0xd4010000)
Mar 23 23:32:37 chi kernel: yenta_cardbus 0000:02:04.0: restoring config space at offset 0x3 (was 0x820000, writing 0x82a810)
Mar 23 23:32:37 chi kernel: yenta_cardbus 0000:02:04.0: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100007)
Mar 23 23:32:37 chi kernel: pci 0000:02:04.1: restoring config space at offset 0xf (was 0x4020100, writing 0x402010a)
Mar 23 23:32:37 chi kernel: pci 0000:02:04.1: restoring config space at offset 0x5 (was 0x0, writing 0xd4014000)
Mar 23 23:32:37 chi kernel: pci 0000:02:04.1: restoring config space at offset 0x4 (was 0x0, writing 0xd4011000)
Mar 23 23:32:37 chi kernel: pci 0000:02:04.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804010)
Mar 23 23:32:37 chi kernel: pci 0000:02:04.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006)
Mar 23 23:32:37 chi kernel: tifm_7xx1 0000:02:04.2: restoring config space at offset 0xf (was 0x40701ff, writing 0x407010a)
Mar 23 23:32:37 chi kernel: tifm_7xx1 0000:02:04.2: restoring config space at offset 0x4 (was 0x0, writing 0xd4018000)
Mar 23 23:32:37 chi kernel: tifm_7xx1 0000:02:04.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804010)
Mar 23 23:32:37 chi kernel: tifm_7xx1 0000:02:04.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006)
Mar 23 23:32:37 chi kernel: pci 0000:02:04.3: restoring config space at offset 0xf (was 0x40701ff, writing 0x407010a)
Mar 23 23:32:37 chi kernel: pci 0000:02:04.3: restoring config space at offset 0x4 (was 0x0, writing 0xd4019000)
Mar 23 23:32:37 chi kernel: pci 0000:02:04.3: restoring config space at offset 0x3 (was 0x800000, writing 0x804010)
Mar 23 23:32:37 chi kernel: pci 0000:02:04.3: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006)
Mar 23 23:32:37 chi kernel: PM: early resume of devices complete after 1.479 msecs
Mar 23 23:32:37 chi kernel: sata_sil 0000:00:12.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Mar 23 23:32:37 chi kernel: ohci_hcd 0000:00:13.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19
Mar 23 23:32:37 chi kernel: ohci_hcd 0000:00:13.1: PCI INT A -> GSI 19 (level, low) -> IRQ 19
Mar 23 23:32:37 chi kernel: ehci_hcd 0000:00:13.2: PCI INT A -> GSI 19 (level, low) -> IRQ 19
Mar 23 23:32:37 chi kernel: pata_atiixp 0000:00:14.1: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Mar 23 23:32:37 chi kernel: snd_hda_intel 0000:00:14.2: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Mar 23 23:32:37 chi kernel: pci 0000:01:05.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
Mar 23 23:32:37 chi kernel: b43-pci-bridge 0000:30:00.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
Mar 23 23:32:37 chi kernel: tifm_7xx1 0000:02:04.2: PCI INT A -> GSI 20 (level, low) -> IRQ 20
Mar 23 23:32:37 chi kernel: sd 2:0:0:0: [sda] Starting disk
Mar 23 23:32:37 chi kernel: usb usb2: root hub lost power or was reset
Mar 23 23:32:37 chi kernel: usb usb3: root hub lost power or was reset
Mar 23 23:32:37 chi kernel: usb usb1: root hub lost power or was reset
Mar 23 23:32:37 chi kernel: parport_pc 00:02: activated
Mar 23 23:32:37 chi kernel: ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out
Mar 23 23:32:37 chi kernel: ata1.00: ACPI cmd ef/03:22:00:00:00:a0 (SET FEATURES) filtered out
Mar 23 23:32:37 chi kernel: ata1.00: configured for MWDMA2
Mar 23 23:32:37 chi kernel: usb 3-1: reset full-speed USB device number 2 using ohci_hcd
Mar 23 23:32:37 chi kernel: ata4: SATA link down (SStatus 0 SControl 300)
Mar 23 23:32:37 chi kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 23 23:32:37 chi kernel: ata3.00: ACPI cmd ef/03:46:00:00:00:a0 (SET FEATURES) filtered out
Mar 23 23:32:37 chi kernel: ata3.00: configured for UDMA/100
Mar 23 23:32:37 chi kernel: PM: resume of devices complete after 3578.452 msecs
Mar 23 23:32:37 chi kernel: Restarting tasks ... 
Mar 23 23:32:37 chi kernel: usb 1-5: USB disconnect, device number 4
Mar 23 23:32:37 chi kernel: done.
Mar 23 23:32:37 chi kernel: video LNXVIDEO:00: Restoring backlight state
Mar 23 23:32:39 chi kernel: EXT4-fs (sda7): re-mounted. Opts: commit=0
Mar 23 23:32:40 chi kernel: [drm] Num pipes: 1
Mar 23 23:32:40 chi kernel: EXT4-fs (sda5): re-mounted. Opts: commit=0
Mar 23 23:32:40 chi kernel: EXT4-fs (sda6): re-mounted. Opts: commit=0
Mar 23 23:32:40 chi NetworkManager[16396]: <info> wake requested (sleeping: yes  enabled: yes)
Mar 23 23:32:40 chi NetworkManager[16396]: <info> waking up and re-enabling...
Mar 23 23:32:40 chi NetworkManager[16396]: <info> (wlan0): now managed
Mar 23 23:32:40 chi NetworkManager[16396]: <info> (wlan0): device state change: 1 -> 2 (reason 2)
Mar 23 23:32:40 chi NetworkManager[16396]: <info> (wlan0): bringing up device.
Mar 23 23:32:40 chi kernel: b43-phy0: Loading firmware version 478.104 (2008-07-01 00:50:23)
Mar 23 23:32:40 chi kernel: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Mar 23 23:32:40 chi NetworkManager[16396]: <info> (wlan0): preparing device.
Mar 23 23:32:40 chi NetworkManager[16396]: <info> (wlan0): deactivating device (reason: 2).
Mar 23 23:32:40 chi NetworkManager[16396]: <info> (eth0): now managed
Mar 23 23:32:40 chi NetworkManager[16396]: <info> (eth0): device state change: 1 -> 2 (reason 2)
Mar 23 23:32:40 chi NetworkManager[16396]: <info> (eth0): bringing up device.
Mar 23 23:32:41 chi kernel: ADDRCONF(NETDEV_UP): eth0: link is not ready
Mar 23 23:32:41 chi NetworkManager[16396]: <info> (eth0): preparing device.
Mar 23 23:32:41 chi NetworkManager[16396]: <info> (eth0): deactivating device (reason: 2).
Mar 23 23:32:41 chi NetworkManager[16396]: <info> (wlan0): supplicant interface state:  starting -> ready
Mar 23 23:32:41 chi NetworkManager[16396]: <info> (wlan0): device state change: 2 -> 3 (reason 42)
Mar 23 23:32:42 chi NetworkManager[16396]: <info> Activation (wlan0) starting connection 'CandyCave'
Mar 23 23:32:42 chi NetworkManager[16396]: <info> (wlan0): device state change: 3 -> 4 (reason 0)
Mar 23 23:32:42 chi NetworkManager[16396]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 23 23:32:42 chi NetworkManager[16396]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Mar 23 23:32:42 chi NetworkManager[16396]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Mar 23 23:32:42 chi NetworkManager[16396]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Mar 23 23:32:42 chi NetworkManager[16396]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Mar 23 23:32:42 chi NetworkManager[16396]: <info> (wlan0): device state change: 4 -> 5 (reason 0)
Mar 23 23:32:42 chi NetworkManager[16396]: <info> Activation (wlan0/wireless): connection 'CandyCave' has security, and secrets exist.  No new secrets needed.
Mar 23 23:32:42 chi NetworkManager[16396]: <info> Config: added 'ssid' value 'CandyCave'
Mar 23 23:32:42 chi NetworkManager[16396]: <info> Config: added 'scan_ssid' value '1'
Mar 23 23:32:42 chi NetworkManager[16396]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Mar 23 23:32:42 chi NetworkManager[16396]: <info> Config: added 'psk' value '<omitted>'
Mar 23 23:32:42 chi NetworkManager[16396]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Mar 23 23:32:42 chi NetworkManager[16396]: <info> Config: set interface ap_scan to 1
Mar 23 23:32:42 chi NetworkManager[16396]: <info> (wlan0): supplicant connection state:  inactive -> scanning
Mar 23 23:32:44 chi NetworkManager[16396]: <info> (wlan0): supplicant connection state:  scanning -> associating
Mar 23 23:32:44 chi kernel: wlan0: authenticate with 00:1f:33:4b:d3:44 (try 1)
Mar 23 23:32:44 chi kernel: wlan0: authenticated
Mar 23 23:32:44 chi kernel: wlan0: associate with 00:1f:33:4b:d3:44 (try 1)
Mar 23 23:32:44 chi kernel: wlan0: RX AssocResp from 00:1f:33:4b:d3:44 (capab=0x411 status=0 aid=1)
Mar 23 23:32:44 chi kernel: wlan0: associated
Mar 23 23:32:44 chi kernel: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Mar 23 23:32:44 chi NetworkManager[16396]: <info> (wlan0): supplicant connection state:  associating -> associated
Mar 23 23:32:44 chi NetworkManager[16396]: <info> (wlan0): supplicant connection state:  associated -> 4-way handshake
Mar 23 23:32:44 chi NetworkManager[16396]: <info> (wlan0): supplicant connection state:  4-way handshake -> group handshake
Mar 23 23:32:44 chi NetworkManager[16396]: <info> (wlan0): supplicant connection state:  group handshake -> completed
Mar 23 23:32:44 chi NetworkManager[16396]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'CandyCave'.
Mar 23 23:32:44 chi NetworkManager[16396]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Mar 23 23:32:44 chi NetworkManager[16396]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Mar 23 23:32:44 chi NetworkManager[16396]: <info> (wlan0): device state change: 5 -> 7 (reason 0)
Mar 23 23:32:44 chi NetworkManager[16396]: <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Mar 23 23:32:44 chi NetworkManager[16396]: <info> dhcpcd started with pid 20714
Mar 23 23:32:44 chi NetworkManager[16396]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Mar 23 23:32:44 chi dhcpcd[20714]: version 5.2.12 starting
Mar 23 23:32:44 chi NetworkManager[16396]: <info> (wlan0): DHCPv4 state changed nbi -> preinit
Mar 23 23:32:44 chi dhcpcd[20714]: wlan0: rebinding lease of 192.168.1.3
Mar 23 23:32:44 chi dhcpcd[20714]: wlan0: acknowledged 192.168.1.3 from 192.168.1.1
Mar 23 23:32:44 chi dhcpcd[20714]: wlan0: checking for 192.168.1.3
Mar 23 23:32:49 chi dhcpcd[20714]: wlan0: leased 192.168.1.3 for 86400 seconds
Mar 23 23:32:49 chi NetworkManager[16396]: <info> (wlan0): DHCPv4 state changed preinit -> bound
Mar 23 23:32:49 chi NetworkManager[16396]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled...
Mar 23 23:32:49 chi NetworkManager[16396]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started...
Mar 23 23:32:49 chi NetworkManager[16396]: <info>   address 192.168.1.3
Mar 23 23:32:49 chi NetworkManager[16396]: <info>   prefix 24 (255.255.255.0)
Mar 23 23:32:49 chi NetworkManager[16396]: <info>   gateway 192.168.1.1
Mar 23 23:32:49 chi NetworkManager[16396]: <info>   nameserver '192.168.1.1'
Mar 23 23:32:49 chi NetworkManager[16396]: <info> Scheduling stage 5
Mar 23 23:32:49 chi NetworkManager[16396]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled...
Mar 23 23:32:49 chi NetworkManager[16396]: <info> Done scheduling stage 5
Mar 23 23:32:49 chi NetworkManager[16396]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete.
Mar 23 23:32:49 chi NetworkManager[16396]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
Mar 23 23:32:50 chi NetworkManager[16396]: <info> (wlan0): writing resolv.conf to /sbin/resolvconf
Mar 23 23:32:50 chi NetworkManager[16396]: <info> Clearing nscd hosts cache.
Mar 23 23:32:50 chi NetworkManager[16396]: <info> (wlan0): device state change: 7 -> 8 (reason 0)
Mar 23 23:32:50 chi NetworkManager[16396]: <info> Policy set 'CandyCave' (wlan0) as default for IPv4 routing and DNS.
Mar 23 23:32:50 chi NetworkManager[16396]: <info> Activation (wlan0) successful, device activated.
Mar 23 23:32:50 chi NetworkManager[16396]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
Mar 23 23:32:50 chi dbus[16383]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
Mar 23 23:32:50 chi dbus[16383]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Mar 23 23:32:55 chi kernel: wlan0: no IPv6 routers present