--- dmesg_3.8.0-rc4-next20130118-7-iniza-generic.txt	2013-01-19 17:10:53.235437107 +0100
+++ dmesg_3.8.0-rc4-next20130118-7-iniza-generic_freezer-pm-test.txt	2013-01-19 19:10:53.020629119 +0100
@@ -781,3 +781,277 @@
 [   25.509838] wlan0: associated
 [   25.509871] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
 [   59.295976] usb 2-1.5: USB disconnect, device number 3
+[  135.875539] wlan0: deauthenticating from 00:04:0e:e4:00:3d by local choice (reason=3)
+[  135.891374] cfg80211: Calling CRDA to update world regulatory domain
+[  135.901509] cfg80211: World regulatory domain updated:
+[  135.901522] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
+[  135.901531] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
+[  135.901537] cfg80211:   (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
+[  135.901543] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
+[  135.901549] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
+[  135.901554] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
+[  137.987040] PM: Syncing filesystems ... done.
+[  137.990760] PM: Preparing system for mem sleep
+[  140.840937] Freezing user space processes ... (elapsed 0.01 seconds) done.
+[  140.855940] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
+[  140.871968] PM: Entering mem sleep
+[  140.872042] Suspending console(s) (use no_console_suspend to debug)
+[  140.872268] sd 1:0:0:0: [sdb] Synchronizing SCSI cache
+[  140.872314] sd 0:0:0:0: [sda] Synchronizing SCSI cache
+[  140.872497] sd 0:0:0:0: [sda] Stopping disk
+[  140.874984] sd 1:0:0:0: [sdb] Stopping disk
+[  141.491801] PM: suspend of devices complete after 619.779 msecs
+[  141.491934] PM: late suspend of devices complete after 0.130 msecs
+[  141.507760] r8169 0000:02:00.0: System wakeup enabled by ACPI
+[  141.587686] PM: noirq suspend of devices complete after 95.770 msecs
+[  141.587951] ACPI: Preparing to enter system sleep state S3
+[  141.611764] PM: Saving platform NVS memory
+[  141.616485] Disabling non-boot CPUs ...
+[  141.719588] smpboot: CPU 1 is now offline
+[  141.823554] smpboot: CPU 2 is now offline
+[  141.927526] smpboot: CPU 3 is now offline
+[  141.927878] Extended CMOS year: 2000
+[  141.929116] ACPI: Low-level resume complete
+[  141.929160] PM: Restoring platform NVS memory
+[  141.930362] Extended CMOS year: 2000
+[  141.930430] Enabling non-boot CPUs ...
+[  141.930506] smpboot: Booting Node 0 Processor 1 APIC 0x1
+[  141.941595] Disabled fast string operations
+[  141.943904] CPU1 is up
+[  141.943975] smpboot: Booting Node 0 Processor 2 APIC 0x2
+[  141.955011] Disabled fast string operations
+[  141.957325] CPU2 is up
+[  141.957396] smpboot: Booting Node 0 Processor 3 APIC 0x3
+[  141.968432] Disabled fast string operations
+[  141.970834] CPU3 is up
+[  141.975562] ACPI: Waking up from system sleep state S3
+[  142.170158] PM: noirq resume of devices complete after 143.982 msecs
+[  142.170337] PM: early resume of devices complete after 0.114 msecs
+[  142.170374] i915 0000:00:02.0: setting latency timer to 64
+[  142.170394] ehci-pci 0000:00:1a.0: setting latency timer to 64
+[  142.170449] mei 0000:00:16.0: irq 47 for MSI/MSI-X
+[  142.170489] ehci-pci 0000:00:1d.0: setting latency timer to 64
+[  142.170502] snd_hda_intel 0000:00:1b.0: irq 50 for MSI/MSI-X
+[  142.170516] ahci 0000:00:1f.2: setting latency timer to 64
+[  142.170627] r8169 0000:02:00.0: System wakeup disabled by ACPI
+[  142.170680] usb usb3: root hub lost power or was reset
+[  142.170682] usb usb4: root hub lost power or was reset
+[  142.170739] iwlwifi 0000:01:00.0: RF_KILL bit toggled to enable radio.
+[  142.175383] xhci_hcd 0000:03:00.0: irq 41 for MSI/MSI-X
+[  142.175392] xhci_hcd 0000:03:00.0: irq 42 for MSI/MSI-X
+[  142.175400] xhci_hcd 0000:03:00.0: irq 43 for MSI/MSI-X
+[  142.175407] xhci_hcd 0000:03:00.0: irq 44 for MSI/MSI-X
+[  142.175414] xhci_hcd 0000:03:00.0: irq 45 for MSI/MSI-X
+[  142.398134] usb 1-1.4: reset high-speed USB device number 4 using ehci-pci
+[  142.493987] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
+[  142.498206] ata2.00: configured for UDMA/133
+[  142.501999] ata4: SATA link down (SStatus 0 SControl 300)
+[  142.510006] ata5: SATA link down (SStatus 0 SControl 300)
+[  142.514115] sd 1:0:0:0: [sdb] Starting disk
+[  142.750164] usb 1-1.2: reset low-speed USB device number 3 using ehci-pci
+[  143.737894] [drm] Enabling RC6 states: RC6 on, RC6p off, RC6pp off
+[  144.561561] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
+[  144.564031] ata1.00: configured for UDMA/133
+[  144.577725] sd 0:0:0:0: [sda] Starting disk
+[  144.607333] PM: resume of devices complete after 2437.494 msecs
+[  144.607515] PM: Finishing wakeup.
+[  144.607516] Restarting tasks ... done.
+[  144.613845] video LNXVIDEO:00: Restoring backlight state
+[  145.208760] iwlwifi 0000:01:00.0: L1 Enabled; Disabling L0S
+[  145.215526] iwlwifi 0000:01:00.0: Radio type=0x1-0x2-0x0
+[  145.437240] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
+[  151.703631] wlan0: authenticate with 00:04:0e:e4:00:3d
+[  151.712679] wlan0: send auth to 00:04:0e:e4:00:3d (try 1/3)
+[  151.714479] wlan0: authenticated
+[  151.714815] iwlwifi 0000:01:00.0 wlan0: disabling HT as WMM/QoS is not supported by the AP
+[  151.714827] iwlwifi 0000:01:00.0 wlan0: disabling VHT as WMM/QoS is not supported by the AP
+[  151.716083] wlan0: associate with 00:04:0e:e4:00:3d (try 1/3)
+[  151.720185] wlan0: RX AssocResp from 00:04:0e:e4:00:3d (capab=0x411 status=0 aid=1)
+[  151.725516] wlan0: associated
+[  151.725575] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
+[  224.771069] PM: Syncing filesystems ... done.
+[  224.941649] PM: Preparing system for mem sleep
+[  236.285147] Freezing user space processes ... (elapsed 0.01 seconds) done.
+[  236.301292] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
+[  236.317277] suspend debug: Waiting for 5 seconds.
+[  241.274211] PM: Finishing wakeup.
+[  241.274213] Restarting tasks ... done.
+[  241.280722] video LNXVIDEO:00: Restoring backlight state
+[  255.862087] PM: Syncing filesystems ... done.
+[  255.864652] PM: Preparing system for mem sleep
+[  263.773687] Freezing user space processes ... (elapsed 0.01 seconds) done.
+[  263.789852] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
+[  263.805877] suspend debug: Waiting for 5 seconds.
+[  268.762866] PM: Finishing wakeup.
+[  268.762868] Restarting tasks ... done.
+[  268.766806] video LNXVIDEO:00: Restoring backlight state
+[  275.176902] PM: Syncing filesystems ... done.
+[  275.289571] PM: Preparing system for mem sleep
+[  284.796252] Freezing user space processes ... (elapsed 0.01 seconds) done.
+[  284.811060] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
+[  284.826995] suspend debug: Waiting for 5 seconds.
+[  289.783938] PM: Finishing wakeup.
+[  289.783940] Restarting tasks ... done.
+[  289.788393] video LNXVIDEO:00: Restoring backlight state
+[  296.325454] PM: Syncing filesystems ... done.
+[  296.329011] PM: Preparing system for mem sleep
+[  303.849793] Freezing user space processes ... (elapsed 0.01 seconds) done.
+[  303.865565] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
+[  303.881524] suspend debug: Waiting for 5 seconds.
+[  308.838566] PM: Finishing wakeup.
+[  308.838568] Restarting tasks ... done.
+[  308.844147] video LNXVIDEO:00: Restoring backlight state
+[  312.770641] PM: Syncing filesystems ... done.
+[  312.896019] PM: Preparing system for mem sleep
+[  323.867461] Freezing user space processes ... (elapsed 0.01 seconds) done.
+[  323.883423] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
+[  323.899463] suspend debug: Waiting for 5 seconds.
+[  328.856369] PM: Finishing wakeup.
+[  328.856371] Restarting tasks ... done.
+[  328.861305] video LNXVIDEO:00: Restoring backlight state
+[  372.549821] PM: Syncing filesystems ... done.
+[  372.552100] PM: Preparing system for mem sleep
+[  379.947909] Freezing user space processes ... (elapsed 0.01 seconds) done.
+[  379.963787] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
+[  379.979814] suspend debug: Waiting for 5 seconds.
+[  384.936754] PM: Finishing wakeup.
+[  384.936755] Restarting tasks ... done.
+[  384.941584] video LNXVIDEO:00: Restoring backlight state
+[  411.318919] PM: Syncing filesystems ... done.
+[  411.910312] PM: Preparing system for mem sleep
+[  414.571172] Freezing user space processes ... (elapsed 0.01 seconds) done.
+[  414.587295] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
+[  414.603315] suspend debug: Waiting for 5 seconds.
+[  419.560201] PM: Finishing wakeup.
+[  419.560203] Restarting tasks ... done.
+[  419.565213] video LNXVIDEO:00: Restoring backlight state
+[  423.083779] PM: Syncing filesystems ... done.
+[  423.338216] PM: Preparing system for mem sleep
+[  427.157972] Freezing user space processes ... (elapsed 0.01 seconds) done.
+[  427.174337] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
+[  427.190451] suspend debug: Waiting for 5 seconds.
+[  432.147377] PM: Finishing wakeup.
+[  432.147379] Restarting tasks ... done.
+[  432.152279] video LNXVIDEO:00: Restoring backlight state
+[  896.490327] audit_printk_skb: 45 callbacks suppressed
+[  896.490340] type=1400 audit(1358612632.180:27): apparmor="DENIED" operation="capable" parent=1 profile="/usr/sbin/cupsd" pid=1032 comm="cupsd" pid=1032 comm="cupsd" capability=36  capname="block_suspend"
+[ 2549.145374] PM: Syncing filesystems ... done.
+[ 2549.147817] PM: Preparing system for mem sleep
+[ 2554.204012] Freezing user space processes ... (elapsed 0.03 seconds) done.
+[ 2554.235635] Freezing remaining freezable tasks ... 
+[ 2574.237482] Freezing of tasks failed after 20.01 seconds (1 tasks refusing to freeze, wq_busy=0):
+[ 2574.237606] jbd2/loop0-8    D ffffffff8180d780     0   299      2 0x00000000
+[ 2574.237616]  ffff88011822db68 0000000000000046 ffff880117c6dc80 0000000000000001
+[ 2574.237624]  ffff880117c6dc80 ffff88011822dfd8 ffff88011822dfd8 ffff88011822dfd8
+[ 2574.237631]  ffffffff81c15440 ffff880117c6dc80 ffff88011822db68 ffff88011fa14738
+[ 2574.237638] Call Trace:
+[ 2574.237657]  [<ffffffff811c6830>] ? __wait_on_buffer+0x30/0x30
+[ 2574.237665]  [<ffffffff816b7449>] schedule+0x29/0x70
+[ 2574.237671]  [<ffffffff816b751f>] io_schedule+0x8f/0xd0
+[ 2574.237678]  [<ffffffff811c683e>] sleep_on_buffer+0xe/0x20
+[ 2574.237688]  [<ffffffff816b5d1f>] __wait_on_bit+0x5f/0x90
+[ 2574.237695]  [<ffffffff811c5ef1>] ? submit_bh+0x121/0x1e0
+[ 2574.237702]  [<ffffffff811c6830>] ? __wait_on_buffer+0x30/0x30
+[ 2574.237710]  [<ffffffff816b5dcc>] out_of_line_wait_on_bit+0x7c/0x90
+[ 2574.237718]  [<ffffffff8107eb00>] ? autoremove_wake_function+0x40/0x40
+[ 2574.237725]  [<ffffffff811c682e>] __wait_on_buffer+0x2e/0x30
+[ 2574.237736]  [<ffffffff8128c02c>] jbd2_journal_commit_transaction+0x18cc/0x1d60
+[ 2574.237744]  [<ffffffff816b85de>] ? _raw_spin_lock_irqsave+0x2e/0x40
+[ 2574.237753]  [<ffffffff81069fbf>] ? try_to_del_timer_sync+0x4f/0x70
+[ 2574.237760]  [<ffffffff81290836>] kjournald2+0xd6/0x3e0
+[ 2574.237766]  [<ffffffff8107eac0>] ? add_wait_queue+0x60/0x60
+[ 2574.237771]  [<ffffffff81290760>] ? commit_timeout+0x10/0x10
+[ 2574.237777]  [<ffffffff8107ded0>] kthread+0xc0/0xd0
+[ 2574.237783]  [<ffffffff8107de10>] ? flush_kthread_worker+0xb0/0xb0
+[ 2574.237790]  [<ffffffff816c0dec>] ret_from_fork+0x7c/0xb0
+[ 2574.237795]  [<ffffffff8107de10>] ? flush_kthread_worker+0xb0/0xb0
+[ 2574.237864] 
+[ 2574.237867] Restarting kernel threads ... done.
+[ 2574.238033] Restarting tasks ... done.
+[ 2574.247957] video LNXVIDEO:00: Restoring backlight state
+[ 2966.420485] PM: Syncing filesystems ... done.
+[ 2966.424243] PM: Preparing system for mem sleep
+[ 2970.765087] Freezing user space processes ... (elapsed 0.01 seconds) done.
+[ 2970.781321] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
+[ 2970.797352] suspend debug: Waiting for 5 seconds.
+[ 2975.754271] PM: Finishing wakeup.
+[ 2975.754274] Restarting tasks ... done.
+[ 2975.759281] video LNXVIDEO:00: Restoring backlight state
+[ 3022.774958] PM: Syncing filesystems ... done.
+[ 3022.857508] PM: Preparing system for mem sleep
+[ 3028.328621] Freezing user space processes ... (elapsed 0.01 seconds) done.
+[ 3028.344589] Freezing remaining freezable tasks ... 
+[ 3048.346530] Freezing of tasks failed after 20.01 seconds (1 tasks refusing to freeze, wq_busy=0):
+[ 3048.346655] jbd2/loop0-8    D ffffffff8180d780     0   299      2 0x00000000
+[ 3048.346664]  ffff88011822db68 0000000000000046 ffff88011822db08 ffffffff81044c29
+[ 3048.346672]  ffff880117c6dc80 ffff88011822dfd8 ffff88011822dfd8 ffff88011822dfd8
+[ 3048.346679]  ffff880119b34560 ffff880117c6dc80 ffff88011822db68 ffff88011fad4738
+[ 3048.346686] Call Trace:
+[ 3048.346706]  [<ffffffff81044c29>] ? default_spin_lock_flags+0x9/0x10
+[ 3048.346717]  [<ffffffff811c6830>] ? __wait_on_buffer+0x30/0x30
+[ 3048.346725]  [<ffffffff816b7449>] schedule+0x29/0x70
+[ 3048.346731]  [<ffffffff816b751f>] io_schedule+0x8f/0xd0
+[ 3048.346737]  [<ffffffff811c683e>] sleep_on_buffer+0xe/0x20
+[ 3048.346748]  [<ffffffff816b5d1f>] __wait_on_bit+0x5f/0x90
+[ 3048.346754]  [<ffffffff811c5ef1>] ? submit_bh+0x121/0x1e0
+[ 3048.346761]  [<ffffffff811c6830>] ? __wait_on_buffer+0x30/0x30
+[ 3048.346769]  [<ffffffff816b5dcc>] out_of_line_wait_on_bit+0x7c/0x90
+[ 3048.346777]  [<ffffffff8107eb00>] ? autoremove_wake_function+0x40/0x40
+[ 3048.346784]  [<ffffffff811c682e>] __wait_on_buffer+0x2e/0x30
+[ 3048.346795]  [<ffffffff8128c02c>] jbd2_journal_commit_transaction+0x18cc/0x1d60
+[ 3048.346802]  [<ffffffff8107eac0>] ? add_wait_queue+0x60/0x60
+[ 3048.346811]  [<ffffffff81069fbf>] ? try_to_del_timer_sync+0x4f/0x70
+[ 3048.346818]  [<ffffffff81290836>] kjournald2+0xd6/0x3e0
+[ 3048.346823]  [<ffffffff8107eac0>] ? add_wait_queue+0x60/0x60
+[ 3048.346829]  [<ffffffff81290760>] ? commit_timeout+0x10/0x10
+[ 3048.346834]  [<ffffffff8107ded0>] kthread+0xc0/0xd0
+[ 3048.346840]  [<ffffffff8107de10>] ? flush_kthread_worker+0xb0/0xb0
+[ 3048.346848]  [<ffffffff816c0dec>] ret_from_fork+0x7c/0xb0
+[ 3048.346853]  [<ffffffff8107de10>] ? flush_kthread_worker+0xb0/0xb0
+[ 3048.346918] 
+[ 3048.346921] Restarting kernel threads ... done.
+[ 3048.347080] Restarting tasks ... done.
+[ 3048.359894] video LNXVIDEO:00: Restoring backlight state
+[ 3065.559184] PM: Syncing filesystems ... done.
+[ 3065.768458] PM: Preparing system for mem sleep
+[ 3072.733178] Freezing user space processes ... (elapsed 0.01 seconds) done.
+[ 3072.749284] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
+[ 3072.765274] suspend debug: Waiting for 5 seconds.
+[ 3077.722222] PM: Finishing wakeup.
+[ 3077.722224] Restarting tasks ... done.
+[ 3077.726719] video LNXVIDEO:00: Restoring backlight state
+[ 3081.596512] PM: Syncing filesystems ... done.
+[ 3081.750235] PM: Preparing system for mem sleep
+[ 3087.738204] Freezing user space processes ... (elapsed 0.04 seconds) done.
+[ 3087.786660] Freezing remaining freezable tasks ... 
+[ 3107.788540] Freezing of tasks failed after 20.01 seconds (1 tasks refusing to freeze, wq_busy=0):
+[ 3107.788664] jbd2/loop0-8    D ffffffff8180d780     0   299      2 0x00000000
+[ 3107.788673]  ffff88011822db68 0000000000000046 ffff88011822db08 ffffffff81044c29
+[ 3107.788682]  ffff880117c6dc80 ffff88011822dfd8 ffff88011822dfd8 ffff88011822dfd8
+[ 3107.788689]  ffffffff81c15440 ffff880117c6dc80 ffff88011822db68 ffff88011fa14738
+[ 3107.788696] Call Trace:
+[ 3107.788715]  [<ffffffff81044c29>] ? default_spin_lock_flags+0x9/0x10
+[ 3107.788726]  [<ffffffff811c6830>] ? __wait_on_buffer+0x30/0x30
+[ 3107.788735]  [<ffffffff816b7449>] schedule+0x29/0x70
+[ 3107.788741]  [<ffffffff816b751f>] io_schedule+0x8f/0xd0
+[ 3107.788748]  [<ffffffff811c683e>] sleep_on_buffer+0xe/0x20
+[ 3107.788758]  [<ffffffff816b5d1f>] __wait_on_bit+0x5f/0x90
+[ 3107.788765]  [<ffffffff811c5ef1>] ? submit_bh+0x121/0x1e0
+[ 3107.788771]  [<ffffffff811c6830>] ? __wait_on_buffer+0x30/0x30
+[ 3107.788779]  [<ffffffff816b5dcc>] out_of_line_wait_on_bit+0x7c/0x90
+[ 3107.788787]  [<ffffffff8107eb00>] ? autoremove_wake_function+0x40/0x40
+[ 3107.788794]  [<ffffffff811c682e>] __wait_on_buffer+0x2e/0x30
+[ 3107.788805]  [<ffffffff8128c02c>] jbd2_journal_commit_transaction+0x18cc/0x1d60
+[ 3107.788814]  [<ffffffff816b85de>] ? _raw_spin_lock_irqsave+0x2e/0x40
+[ 3107.788823]  [<ffffffff81069fbf>] ? try_to_del_timer_sync+0x4f/0x70
+[ 3107.788830]  [<ffffffff81290836>] kjournald2+0xd6/0x3e0
+[ 3107.788835]  [<ffffffff8107eac0>] ? add_wait_queue+0x60/0x60
+[ 3107.788841]  [<ffffffff81290760>] ? commit_timeout+0x10/0x10
+[ 3107.788846]  [<ffffffff8107ded0>] kthread+0xc0/0xd0
+[ 3107.788852]  [<ffffffff8107de10>] ? flush_kthread_worker+0xb0/0xb0
+[ 3107.788859]  [<ffffffff816c0dec>] ret_from_fork+0x7c/0xb0
+[ 3107.788865]  [<ffffffff8107de10>] ? flush_kthread_worker+0xb0/0xb0
+[ 3107.788929] 
+[ 3107.788932] Restarting kernel threads ... done.
+[ 3107.789086] Restarting tasks ... done.
+[ 3107.802117] video LNXVIDEO:00: Restoring backlight state
