[    7.723365] iwlwifi 0000:03:00.0: Radio type=0x0-0x3-0x1
[    7.863499] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[    8.042641] Bridge firewalling registered
[    8.168728] ip_tables: (C) 2000-2006 Netfilter Core Team
[    8.175144] vboxdrv: Found 4 processor cores.
[    8.175856] vboxdrv: fAsync=0 offMin=0x217 offMax=0xf7f
[    8.176466] vboxdrv: TSC mode is 'synchronous', kernel timer mode is 'normal'.
[    8.176469] vboxdrv: Successfully loaded version 4.1.8_Ubuntu (interface 0x00190000).
[    8.191483] vboxpci: IOMMU not found (not registered)
[    8.207420] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
[    8.222825] ADDRCONF(NETDEV_UP): virbr0: link is not ready
[    8.475409] Ebtables v2.0 registered
[    8.483201] ip6_tables: (C) 2000-2006 Netfilter Core Team
[    9.515329] cfg80211: Found new beacon on frequency: 2472 MHz (Ch 13) on phy0
[   14.520234] IBM TrackPoint firmware: 0x0e, buttons: 3/3
[   14.813628] input: TPPS/2 IBM TrackPoint as /devices/platform/i8042/serio1/serio2/input/input8
[   15.732309] wlan0: authenticate with bc:05:43:7a:65:d5 (try 1)
[   15.736203] wlan0: authenticated
[   15.839456] wlan0: associate with bc:05:43:7a:65:d5 (try 1)
[   15.844037] wlan0: RX AssocResp from bc:05:43:7a:65:d5 (capab=0x431 status=0 aid=1)
[   15.844042] wlan0: associated
[   15.856526] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   23.411640] iwlwifi 0000:03:00.0: Tx aggregation enabled on ra = bc:05:43:7a:65:d5 tid = 0
[   25.884506] process `skype' is using obsolete setsockopt SO_BSDCOMPAT
[   25.906789] wlan0: no IPv6 routers present
[  129.605172] init: irqbalance main process (1786) killed by TERM signal
[  200.275257] audit_printk_skb: 94 callbacks suppressed
[  200.275259] type=1400 audit(1328358336.917:43): apparmor="STATUS" operation="profile_load" name="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" pid=7479 comm="apparmor_parser"
[  200.275681] type=1400 audit(1328358336.917:44): apparmor="STATUS" operation="profile_load" name="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}//browser_java" pid=7479 comm="apparmor_parser"
[  200.276010] type=1400 audit(1328358336.917:45): apparmor="STATUS" operation="profile_load" name="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}//browser_openjdk" pid=7479 comm="apparmor_parser"
[  200.276222] type=1400 audit(1328358336.917:46): apparmor="STATUS" operation="profile_load" name="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}//sanitized_helper" pid=7479 comm="apparmor_parser"
[  234.555447] type=1400 audit(1328358371.264:47): apparmor="DENIED" operation="open" parent=9552 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=9554 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[  236.032901] type=1400 audit(1328358372.743:48): apparmor="DENIED" operation="open" parent=9552 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=9589 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[  303.258662] type=1400 audit(1328358440.109:49): apparmor="DENIED" operation="open" parent=10464 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=10467 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[  510.642295] type=1400 audit(1328358647.934:50): apparmor="DENIED" operation="open" parent=12768 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=12771 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[  558.583368] type=1400 audit(1328358695.994:51): apparmor="DENIED" operation="open" parent=2536 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=9552 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[ 2031.139107] type=1400 audit(1328360172.238:52): apparmor="DENIED" operation="open" parent=29170 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=29172 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[ 3194.534498] type=1400 audit(1328361338.550:53): apparmor="DENIED" operation="open" parent=9745 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=9747 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[ 5267.311026] iwlwifi 0000:03:00.0: Tx aggregation enabled on ra = bc:05:43:7a:65:d5 tid = 6
[ 5514.153408] type=1400 audit(1328363663.982:54): apparmor="DENIED" operation="open" parent=5843 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=5845 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[10805.428839] usb 1-1.2: new low-speed USB device number 6 using ehci_hcd
[10805.614199] input: Logitech USB Optical Mouse as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/input/input9
[10805.614514] generic-usb 0003:046D:C019.0001: input,hidraw0: USB HID v1.11 Mouse [Logitech USB Optical Mouse] on usb-0000:00:1a.0-1.2/input0
[10805.614554] usbcore: registered new interface driver usbhid
[10805.614559] usbhid: USB HID core driver
[16388.211224] type=1400 audit(1328374565.294:55): apparmor="DENIED" operation="open" parent=27236 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=27238 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[16758.578701] wlan0: deauthenticating from bc:05:43:7a:65:d5 by local choice (reason=3)
[16758.688797] cfg80211: All devices are disconnected, going to restore regulatory settings
[16758.688805] cfg80211: Restoring regulatory settings
[16758.688817] cfg80211: Calling CRDA to update world regulatory domain
[16758.703513] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
[16758.703523] cfg80211: World regulatory domain updated:
[16758.703527] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[16758.703534] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[16758.703541] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[16758.703547] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[16758.703554] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[16758.703560] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[16762.244032] PM: Syncing filesystems ... done.
[16762.267972] PM: Preparing system for mem sleep
[16762.267989] Freezing user space processes ... (elapsed 0.01 seconds) done.
[16762.283376] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
[16762.299331] PM: Entering mem sleep
[16762.299466] Suspending console(s) (use no_console_suspend to debug)
[16762.487762] PM: suspend of drv:psmouse dev:serio2 complete after 188.545 msecs
[16762.488066] sd 1:0:0:0: [sdb] Synchronizing SCSI cache
[16762.488191] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[16762.488280] sd 1:0:0:0: [sdb] Stopping disk
[16762.488283] sd 0:0:0:0: [sda] Stopping disk
[16762.730261] PM: suspend of drv:tpm_tis dev:00:0b complete after 179.858 msecs
[16762.730738] ehci_hcd 0000:00:1d.0: PCI INT D disabled
[16762.731335] ehci_hcd 0000:00:1a.0: PCI INT D disabled
[16762.731651] sdhci-pci 0000:0d:00.0: PCI INT A disabled
[16762.738372] ACPI handle has no context!
[16762.738517] ACPI handle has no context!
[16762.797049] e1000e 0000:00:19.0: PCI INT A disabled
[16762.797060] e1000e 0000:00:19.0: PME# enabled
[16762.797069] e1000e 0000:00:19.0: wake-up capability enabled by ACPI
[16762.813971] PM: suspend of drv:sd dev:0:0:0:0 complete after 326.597 msecs
[16762.814044] PM: suspend of drv:scsi dev:target0:0:0 complete after 326.646 msecs
[16762.814064] PM: suspend of drv:scsi dev:host0 complete after 263.947 msecs
[16762.835042] snd_hda_intel 0000:00:1b.0: PCI INT B disabled
[16762.849950] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 119.466 msecs
[16763.062715] PM: suspend of drv:sd dev:1:0:0:0 complete after 576.094 msecs
[16763.062776] PM: suspend of drv:scsi dev:target1:0:0 complete after 576.082 msecs
[16763.062796] PM: suspend of drv:scsi dev:host1 complete after 513.312 msecs
[16763.077383] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 347.556 msecs
[16763.177129] snd_hda_intel 0000:01:00.1: PCI INT A disabled
[16763.177210] ACPI handle has no context!
[16763.193058] PM: suspend of drv:snd_hda_intel dev:0000:01:00.1 complete after 463.561 msecs
[16763.193121] PM: suspend of drv:pcieport dev:0000:00:01.0 complete after 455.678 msecs
[16763.193190] PM: suspend of drv: dev:pci0000:00 complete after 455.717 msecs
[16763.193244] PM: suspend of devices complete after 895.878 msecs
[16763.193248] PM: suspend devices took 0.896 seconds
[16763.209309] ehci_hcd 0000:00:1d.0: PME# enabled
[16763.209314] ehci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI
[16763.224985] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3
[16763.225044] ehci_hcd 0000:00:1a.0: PME# enabled
[16763.225051] ehci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI
[16763.240984] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3
[16763.256994] PM: late suspend of devices complete after 63.900 msecs
[16763.257182] ACPI: Preparing to enter system sleep state S3
[16763.456446] PM: Saving platform NVS memory
[16763.459822] Disabling non-boot CPUs ...
[16763.564171] CPU 1 is now offline
[16763.667870] CPU 2 is now offline
[16763.669114] Broke affinity for irq 19
[16763.669123] Broke affinity for irq 23
[16763.771621] CPU 3 is now offline
[16763.772094] Extended CMOS year: 2000
[16763.772337] ACPI: Low-level resume complete
[16763.772392] PM: Restoring platform NVS memory
[16763.773043] Extended CMOS year: 2000
[16763.773090] Enabling non-boot CPUs ...
[16763.773232] Booting Node 0 Processor 1 APIC 0x1
[16763.773233] smpboot cpu 1: start_ip = 99000
[16763.784237] Calibrating delay loop (skipped) already calibrated this CPU
[16763.804616] NMI watchdog enabled, takes one hw-pmu counter.
[16763.836494] CPU1 is up
[16763.836582] Booting Node 0 Processor 2 APIC 0x4
[16763.836583] smpboot cpu 2: start_ip = 99000
[16763.847586] Calibrating delay loop (skipped) already calibrated this CPU
[16763.868025] NMI watchdog enabled, takes one hw-pmu counter.
[16763.904277] CPU2 is up
[16763.904486] Booting Node 0 Processor 3 APIC 0x5
[16763.904488] smpboot cpu 3: start_ip = 99000
[16763.915492] Calibrating delay loop (skipped) already calibrated this CPU
[16763.936131] NMI watchdog enabled, takes one hw-pmu counter.
[16763.972341] CPU3 is up
[16763.975290] ACPI: Waking up from system sleep state S3
[16764.271471] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507)
[16764.271596] e1000e 0000:00:19.0: restoring config space at offset 0x1 (was 0x100103, writing 0x100107)
[16764.271626] ehci_hcd 0000:00:1a.0: restoring config space at offset 0xf (was 0x400, writing 0x40b)
[16764.271644] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2428000)
[16764.271650] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
[16764.271671] ehci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI
[16764.271676] ehci_hcd 0000:00:1a.0: PME# disabled
[16764.271679] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
[16764.271682] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
[16764.271810] pcieport 0000:00:1c.1: restoring config space at offset 0x7 (was 0xf0, writing 0x200000f0)
[16764.271905] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x100, writing 0x4010b)
[16764.271915] pcieport 0000:00:1c.4: restoring config space at offset 0x9 (was 0x10001, writing 0x1fff1)
[16764.271920] pcieport 0000:00:1c.4: restoring config space at offset 0x8 (was 0x0, writing 0xf210f210)
[16764.271924] pcieport 0000:00:1c.4: restoring config space at offset 0x7 (was 0x20000000, writing 0x200000f0)
[16764.271932] pcieport 0000:00:1c.4: restoring config space at offset 0x3 (was 0x810000, writing 0x810010)
[16764.271938] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100000, writing 0x100107)
[16764.271970] ehci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x400, writing 0x40b)
[16764.271987] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2428400)
[16764.271994] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102)
[16764.272011] ehci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI
[16764.272015] ehci_hcd 0000:00:1d.0: PME# disabled
[16764.272017] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[16764.272020] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[16764.272120] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407)
[16764.272184] intel ips 0000:00:1f.6: restoring config space at offset 0xf (was 0x400, writing 0x40b)
[16764.272206] intel ips 0000:00:1f.6: restoring config space at offset 0x1 (was 0x100000, writing 0x100002)
[16764.272250] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x800010, writing 0x800000)
[16764.272255] nvidia 0000:01:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100007)
[16764.272311] snd_hda_intel 0000:01:00.1: restoring config space at offset 0x1 (was 0x100106, writing 0x100102)
[16764.272847] PM: early resume of devices complete after 1.510 msecs
[16764.273000] e1000e 0000:00:19.0: wake-up capability disabled by ACPI
[16764.273006] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
[16764.273009] e1000e 0000:00:19.0: PME# disabled
[16764.273013] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
[16764.273029] ehci_hcd 0000:00:1a.0: PCI INT D -> GSI 23 (level, low) -> IRQ 23
[16764.273049] ehci_hcd 0000:00:1a.0: setting latency timer to 64
[16764.273088] mei 0000:00:16.0: irq 42 for MSI/MSI-X
[16764.273092] snd_hda_intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17
[16764.273107] snd_hda_intel 0000:00:1b.0: setting latency timer to 64
[16764.273139] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[16764.273145] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[16764.273151] pci 0000:00:1e.0: setting latency timer to 64
[16764.273157] ehci_hcd 0000:00:1d.0: PCI INT D -> GSI 19 (level, low) -> IRQ 19
[16764.273174] ehci_hcd 0000:00:1d.0: setting latency timer to 64
[16764.273195] ahci 0000:00:1f.2: setting latency timer to 64
[16764.273223] snd_hda_intel 0000:00:1b.0: irq 43 for MSI/MSI-X
[16764.273301] snd_hda_intel 0000:01:00.1: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[16764.273318] e1000e 0000:00:19.0: irq 44 for MSI/MSI-X
[16764.273320] snd_hda_intel 0000:01:00.1: setting latency timer to 64
[16764.273389] sdhci-pci 0000:0d:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[16764.273514] sd 0:0:0:0: [sda] Starting disk
[16764.273619] sd 1:0:0:0: [sdb] Starting disk
[16764.287879] watchdog: only one watchdog can use /dev/watchdog.
[16764.287881] watchdog: error registering /dev/watchdog (err=-16).
[16764.287882] mei: unable to register watchdog device.
[16764.327259] firewire_ohci 0000:0d:00.3: irq 45 for MSI/MSI-X
[16764.327397] firewire_core: skipped bus generations, destroying all nodes
[16764.450871] PM: resume of drv:hub dev:1-1:1.0 complete after 177.707 msecs
[16764.450874] PM: resume of drv:hub dev:2-1:1.0 complete after 177.565 msecs
[16764.450879] PM: resume of drv: dev:ep_00 complete after 177.710 msecs
[16764.450881] PM: resume of drv: dev:ep_00 complete after 177.522 msecs
[16764.450897] PM: resume of drv: dev:ep_81 complete after 177.563 msecs
[16764.450903] PM: resume of drv: dev:ep_81 complete after 177.736 msecs
[16764.522817] usb 2-1.5: reset full-speed USB device number 3 using ehci_hcd
[16764.590550] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[16764.591037] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
[16764.591040] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
[16764.591042] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
[16764.591888] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
[16764.591891] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
[16764.591893] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
[16764.592263] ata1.00: configured for UDMA/133
[16764.598542] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[16764.600800] ata2.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
[16764.600802] ata2.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
[16764.606535] ata6: SATA link down (SStatus 0 SControl 300)
[16764.606685] PM: resume of drv:sd dev:0:0:0:0 complete after 334.008 msecs
[16764.606695] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 290.522 msecs
[16764.606701] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 333.992 msecs
[16764.614487] ata5: SATA link down (SStatus 0 SControl 300)
[16764.618082] PM: resume of drv: dev:ep_00 complete after 344.726 msecs
[16764.618085] PM: resume of drv:usb dev:2-1.5:1.0 complete after 344.846 msecs
[16764.618099] PM: resume of drv: dev:ep_81 complete after 344.769 msecs
[16764.618105] PM: resume of drv: dev:ep_82 complete after 344.799 msecs
[16764.618109] PM: resume of drv: dev:ep_02 complete after 344.835 msecs
[16764.645657] ata2.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
[16764.645660] ata2.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
[16764.648879] ata2.00: configured for UDMA/133
[16764.668323] PM: resume of drv:sd dev:1:0:0:0 complete after 395.694 msecs
[16764.668342] PM: resume of drv:scsi_device dev:1:0:0:0 complete after 395.633 msecs
[16764.686401] usb 1-1.3: reset full-speed USB device number 3 using ehci_hcd
[16764.774044] sdhci-pci 0000:0d:00.0: Will use DMA mode even though HW doesn't fully claim to support it.
[16764.774147] sdhci-pci 0000:0d:00.0: setting latency timer to 64
[16764.774311] PM: resume of drv:sdhci-pci dev:0000:0d:00.0 complete after 502.230 msecs
[16764.778927] PM: resume of drv: dev:ep_00 complete after 506.243 msecs
[16764.778930] PM: resume of drv:usb dev:1-1.3:1.0 complete after 506.349 msecs
[16764.778943] PM: resume of drv: dev:ep_83 complete after 506.273 msecs
[16764.778949] PM: resume of drv: dev:ep_02 complete after 506.303 msecs
[16764.778953] PM: resume of drv: dev:ep_81 complete after 506.339 msecs
[16764.823112] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 551.284 msecs
[16764.825925] firewire_core: rediscovered device fw0
[16764.850015] usb 1-1.6: reset high-speed USB device number 4 using ehci_hcd
[16764.950870] PM: resume of drv:uvcvideo dev:1-1.6:1.0 complete after 678.572 msecs
[16764.950881] PM: resume of drv:uvcvideo dev:1-1.6:1.1 complete after 678.537 msecs
[16764.950898] PM: resume of drv: dev:ep_00 complete after 678.537 msecs
[16764.950914] PM: resume of drv:video4linux dev:video0 complete after 127.044 msecs
[16764.950920] PM: resume of drv: dev:ep_81 complete after 678.599 msecs
[16765.205140] usb 1-1.2: reset low-speed USB device number 6 using ehci_hcd
[16765.487580] PM: resume of drv:usbhid dev:1-1.2:1.0 complete after 1216.373 msecs
[16765.487592] PM: resume of drv: dev:ep_81 complete after 1216.367 msecs
[16765.487596] PM: resume of drv:generic-usb dev:0003:046D:C019.0001 complete after 537.930 msecs
[16765.487707] PM: resume of drv: dev:ep_00 complete after 1216.467 msecs
[16765.487798] PM: resume of devices complete after 1217.883 msecs
[16765.487899] PM: resume devices took 1.216 seconds
[16765.487929] PM: Finishing wakeup.
[16765.487930] Restarting tasks ... done.
[16765.509278] video LNXVIDEO:01: Restoring backlight state
[16766.125376] e1000e 0000:00:19.0: irq 44 for MSI/MSI-X
[16766.178560] e1000e 0000:00:19.0: irq 44 for MSI/MSI-X
[16766.179263] ADDRCONF(NETDEV_UP): eth0: link is not ready
[16766.180067] iwlwifi 0000:03:00.0: L1 Enabled; Disabling L0S
[16766.180257] iwlwifi 0000:03:00.0: Radio type=0x0-0x3-0x1
[16766.316696] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[16773.947843] wlan0: authenticate with bc:05:43:7a:65:d5 (try 1)
[16773.950637] wlan0: authenticated
[16773.951004] wlan0: associate with bc:05:43:7a:65:d5 (try 1)
[16773.953507] wlan0: RX AssocResp from bc:05:43:7a:65:d5 (capab=0x431 status=0 aid=1)
[16773.953512] wlan0: associated
[16773.962491] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[16776.295479] iwlwifi 0000:03:00.0: Tx aggregation enabled on ra = bc:05:43:7a:65:d5 tid = 0
[16784.560454] wlan0: no IPv6 routers present
[17467.714671] wlan0: deauthenticating from bc:05:43:7a:65:d5 by local choice (reason=3)
[17467.800821] cfg80211: All devices are disconnected, going to restore regulatory settings
[17467.800831] cfg80211: Restoring regulatory settings
[17467.800839] cfg80211: Calling CRDA to update world regulatory domain
[17467.812053] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
[17467.812063] cfg80211: World regulatory domain updated:
[17467.812067] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[17467.812074] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[17467.812079] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[17467.812085] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[17467.812091] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[17467.812097] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[17471.339236] wlan0: direct probe to 00:1b:2f:03:11:a0 (try 1/3)
[17471.535156] wlan0: direct probe to 00:1b:2f:03:11:a0 (try 2/3)
[17471.734610] wlan0: direct probe to 00:1b:2f:03:11:a0 (try 3/3)
[17471.934209] wlan0: direct probe to 00:1b:2f:03:11:a0 timed out
[17480.539704] wlan0: direct probe to 00:1b:2f:03:11:a0 (try 1/3)
[17480.736063] wlan0: direct probe to 00:1b:2f:03:11:a0 (try 2/3)
[17480.935619] wlan0: direct probe to 00:1b:2f:03:11:a0 (try 3/3)
[17481.135145] wlan0: direct probe to 00:1b:2f:03:11:a0 timed out
[17489.690844] wlan0: direct probe to 00:1b:2f:03:11:a0 (try 1/3)
[17489.889146] wlan0: direct probe to 00:1b:2f:03:11:a0 (try 2/3)
[17490.088676] wlan0: direct probe to 00:1b:2f:03:11:a0 (try 3/3)
[17490.288103] wlan0: direct probe to 00:1b:2f:03:11:a0 timed out
[17498.486694] wlan0: authenticate with 00:1b:2f:03:11:a0 (try 1)
[17498.683085] wlan0: authenticate with 00:1b:2f:03:11:a0 (try 2)
[17498.685678] wlan0: authenticated
[17498.781739] wlan0: associate with 00:1b:2f:03:11:a0 (try 1)
[17498.978361] wlan0: associate with 00:1b:2f:03:11:a0 (try 2)
[17498.981826] wlan0: RX AssocResp from 00:1b:2f:03:11:a0 (capab=0x431 status=0 aid=1)
[17498.981831] wlan0: associated
[18410.581017] usb 1-1.2: USB disconnect, device number 6
[18692.470725] type=1400 audit(1328383766.585:56): apparmor="DENIED" operation="open" parent=21256 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=21258 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[18740.160125] type=1400 audit(1328383814.393:57): apparmor="DENIED" operation="open" parent=21775 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=21777 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[18961.808058] type=1400 audit(1328384036.597:58): apparmor="DENIED" operation="open" parent=24180 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=24182 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[24437.921097] type=1400 audit(1328389526.433:59): apparmor="DENIED" operation="open" parent=19414 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=19416 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[25398.872577] type=1400 audit(1328390489.793:60): apparmor="DENIED" operation="open" parent=29786 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=29788 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[25420.889081] type=1400 audit(1328390511.865:61): apparmor="DENIED" operation="open" parent=30034 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=30036 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[25529.743301] type=1400 audit(1328390620.993:62): apparmor="DENIED" operation="open" parent=31226 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=31228 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[26262.229107] type=1400 audit(1328391355.313:63): apparmor="DENIED" operation="open" parent=7228 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=7231 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[26313.310374] type=1400 audit(1328391406.525:64): apparmor="DENIED" operation="open" parent=7788 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=7791 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0
[26481.959879] type=1400 audit(1328391575.597:65): apparmor="DENIED" operation="open" parent=9648 profile="/usr/lib/firefox-11.0/firefox{,*[^s][^h]}" name="/dev/nvidiactl" pid=9651 comm="firefox" requested_mask="rw" denied_mask="rw" fsuid=1000 ouid=0