[    0.072003] ... value mask:             000000ffffffffff
[    0.072003] ... max period:             000000007fffffff
[    0.072003] ... fixed-purpose events:   3
[    0.072003] ... event mask:             0000000700000003
[    0.072003] CPU 1 irqstacks, hard=f74aa000 soft=f74ac000
[    0.072003] Booting Node   0, Processors  #1 Ok.
[    0.008000] Initializing CPU#1
[   23.254682] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   23.271059] ADDRCONF(NETDEV_UP): eth0: link is not ready
[   33.304048] sis190 0000:00:04.0: eth0: auto-negotiating...
[   34.477239] hda-intel: IRQ timing workaround is activated for card #0. Suggest a bigger bdl_pos_adj.
[   35.895644] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0
[   36.617126] EXT4-fs (sda5): re-mounted. Opts: commit=0
[   36.663841] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0
[   54.081358] wlan0: authenticate with 00:26:5a:63:b3:2a (try 1)
[   54.083557] wlan0: authenticated
[   54.221322] wlan0: associate with 00:26:5a:63:b3:2a (try 1)
[   54.223589] wlan0: RX AssocResp from 00:26:5a:63:b3:2a (capab=0x431 status=0 aid=1)
[   54.223595] wlan0: associated
[   54.229327] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   64.608031] wlan0: no IPv6 routers present
[  626.731602] exe (12237): /proc/12237/oom_adj is deprecated, please use /proc/12237/oom_score_adj instead.
[16021.019925] wlan0: deauthenticating from 00:26:5a:63:b3:2a by local choice (reason=3)
[16021.105362] cfg80211: All devices are disconnected, going to restore regulatory settings
[16021.105374] cfg80211: Restoring regulatory settings
[16021.105388] cfg80211: Calling CRDA to update world regulatory domain
[16021.113581] cfg80211: Updating information on frequency 2412 MHz for a 20 MHz width channel with regulatory rule:
[16021.113589] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16021.113593] cfg80211: Updating information on frequency 2417 MHz for a 20 MHz width channel with regulatory rule:
[16021.113598] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16021.113603] cfg80211: Updating information on frequency 2422 MHz for a 20 MHz width channel with regulatory rule:
[16021.113608] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16021.113613] cfg80211: Updating information on frequency 2427 MHz for a 20 MHz width channel with regulatory rule:
[16021.113617] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16021.113622] cfg80211: Updating information on frequency 2432 MHz for a 20 MHz width channel with regulatory rule:
[16021.113627] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16021.113631] cfg80211: Updating information on frequency 2437 MHz for a 20 MHz width channel with regulatory rule:
[16021.113636] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16021.113640] cfg80211: Updating information on frequency 2442 MHz for a 20 MHz width channel with regulatory rule:
[16021.113645] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16021.113650] cfg80211: Updating information on frequency 2447 MHz for a 20 MHz width channel with regulatory rule:
[16021.113655] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16021.113659] cfg80211: Updating information on frequency 2452 MHz for a 20 MHz width channel with regulatory rule:
[16021.113664] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16021.113668] cfg80211: Updating information on frequency 2457 MHz for a 20 MHz width channel with regulatory rule:
[16021.113673] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16021.113677] cfg80211: Updating information on frequency 2462 MHz for a 20 MHz width channel with regulatory rule:
[16021.113682] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16021.113687] cfg80211: Updating information on frequency 2467 MHz for a 20 MHz width channel with regulatory rule:
[16021.113692] cfg80211: 2457000 KHz - 2482000 KHz @  KHz), (300 mBi, 2000 mBm)
[16021.113696] cfg80211: Updating information on frequency 2472 MHz for a 20 MHz width channel with regulatory rule:
[16021.113701] cfg80211: 2457000 KHz - 2482000 KHz @  KHz), (300 mBi, 2000 mBm)
[16021.113705] cfg80211: Updating information on frequency 2484 MHz for a 20 MHz width channel with regulatory rule:
[16021.113710] cfg80211: 2474000 KHz - 2494000 KHz @  KHz), (300 mBi, 2000 mBm)
[16021.113715] cfg80211: World regulatory domain updated:
[16021.113719] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[16021.113724] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[16021.113729] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[16021.113733] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[16021.113738] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[16021.113743] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[16022.469612] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0
[16022.614753] EXT4-fs (sda5): re-mounted. Opts: commit=0
[16022.923177] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0
[16029.114832] PM: Syncing filesystems ... done.
[16029.532606] PM: Preparing system for mem sleep
[16029.532633] Freezing user space processes ... (elapsed 0.01 seconds) done.
[16029.548139] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
[16029.564114] PM: Entering mem sleep
[16029.564136] Suspending console(s) (use no_console_suspend to debug)
[16029.564879] sd 2:0:0:0: [sda] Synchronizing SCSI cache
[16029.565028] sd 2:0:0:0: [sda] Stopping disk
[16029.608536] ACPI handle has no context!
[16029.608552] jmb38x_ms 0000:03:00.3: PCI INT A disabled
[16029.608563] ACPI handle has no context!
[16029.608702] ACPI handle has no context!
[16029.608712] sdhci-pci 0000:03:00.0: PCI INT A disabled
[16029.608722] ACPI handle has no context!
[16029.609351] sis190 0000:00:04.0: PCI INT A disabled
[16029.609393] ohci_hcd 0000:00:03.1: PCI INT B disabled
[16029.609433] ohci_hcd 0000:00:03.0: PCI INT A disabled
[16029.609556] pata_sis 0000:00:02.5: PCI INT A disabled
[16029.692025] usb 1-2: reset high speed USB device using ehci_hcd and address 2
[16029.816086] HDA Intel 0000:00:0f.0: PCI INT A disabled
[16029.816122] ACPI handle has no context!
[16029.832021] PM: suspend of drv:HDA Intel dev:0000:00:0f.0 complete after 223.202 msecs
[16029.840025] PM: suspend of drv:usb dev:1-2 complete after 274.749 msecs
[16029.840439] PM: suspend of drv:usb dev:usb1 complete after 232.519 msecs
[16029.856031] ehci_hcd 0000:00:03.3: PCI INT C disabled
[16029.856036] PM: suspend of drv:ehci_hcd dev:0000:00:03.3 complete after 246.674 msecs
[16030.575670] PM: suspend of drv:sd dev:2:0:0:0 complete after 1010.798 msecs
[16030.575686] PM: suspend of drv:scsi dev:target2:0:0 complete after 1010.591 msecs
[16030.575709] PM: suspend of drv:scsi dev:host2 complete after 1010.229 msecs
[16030.575729] sata_sis 0000:00:05.0: PCI INT A disabled
[16030.575734] PM: suspend of drv:sata_sis dev:0000:00:05.0 complete after 966.424 msecs
[16030.575748] PM: suspend of drv: dev:pci0000:00 complete after 966.248 msecs
[16030.575761] PM: suspend of devices complete after 1011.115 msecs
[16030.575765] PM: suspend devices took 1.008 seconds
[16030.592205] PM: late suspend of devices complete after 16.433 msecs
[16030.592449] ACPI: Preparing to enter system sleep state S3
[16030.633856] PM: Saving platform NVS memory
[16030.633890] Disabling non-boot CPUs ...
[16030.736023] CPU 1 is now offline
[16030.736606] Extended CMOS year: 2000
[16030.736606] Back to C!
[16030.736606] PM: Restoring platform NVS memory
[16030.736606] Extended CMOS year: 2000
[16030.736606] Enabling non-boot CPUs ...
[16030.736606] Booting Node 0 Processor 1 APIC 0x1
[16030.635273] Initializing CPU#1
[16030.824902] CPU1 is up
[16030.825544] ACPI: Waking up from system sleep state S3
[16030.828026] Switched to NOHz mode on CPU #1
[16031.080184] pci 0000:00:01.0: restoring config space at offset 0x7 (was 0x209090, writing 0x20209090)
[16031.080229] pata_sis 0000:00:02.5: restoring config space at offset 0xf (was 0x1ff, writing 0x0)
[16031.080242] pata_sis 0000:00:02.5: restoring config space at offset 0x7 (was 0x375, writing 0x1)
[16031.080246] pata_sis 0000:00:02.5: restoring config space at offset 0x6 (was 0x171, writing 0x1)
[16031.080250] pata_sis 0000:00:02.5: restoring config space at offset 0x5 (was 0x3f5, writing 0x1)
[16031.080255] pata_sis 0000:00:02.5: restoring config space at offset 0x4 (was 0x1f1, writing 0x1)
[16031.080260] pata_sis 0000:00:02.5: restoring config space at offset 0x2 (was 0x1018a01, writing 0x1018001)
[16031.080291] ohci_hcd 0000:00:03.0: restoring config space at offset 0x1 (was 0x2800017, writing 0x2800013)
[16031.080317] ohci_hcd 0000:00:03.1: restoring config space at offset 0x1 (was 0x2800017, writing 0x2800013)
[16031.096020] ehci_hcd 0000:00:03.3: BAR 0: set to [mem 0xd8206000-0xd8206fff] (PCI address [0xd8206000-0xd8206fff])
[16031.096045] ehci_hcd 0000:00:03.3: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002)
[16031.096078] sis190 0000:00:04.0: restoring config space at offset 0x1 (was 0x2100007, writing 0x2100003)
[16031.096111] sata_sis 0000:00:05.0: restoring config space at offset 0x1 (was 0x2100005, writing 0x2100001)
[16031.096145] pcieport 0000:00:06.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd861d851)
[16031.096155] pcieport 0000:00:06.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004)
[16031.096160] pcieport 0000:00:06.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[16031.096203] pcieport 0000:00:07.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd881d871)
[16031.096208] pcieport 0000:00:07.0: restoring config space at offset 0x7 (was 0x200001f1, writing 0x2121)
[16031.096216] pcieport 0000:00:07.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004)
[16031.096221] pcieport 0000:00:07.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[16031.096263] HDA Intel 0000:00:0f.0: restoring config space at offset 0x1 (was 0x2100006, writing 0x2100002)
[16031.096287] pcieport 0000:00:1f.0: restoring config space at offset 0xf (was 0x0, writing 0x400ff)
[16031.096297] pcieport 0000:00:1f.0: restoring config space at offset 0x9 (was 0x1ff01, writing 0x1fff1)
[16031.096303] pcieport 0000:00:1f.0: restoring config space at offset 0x7 (was 0x101, writing 0x200001f1)
[16031.096308] pcieport 0000:00:1f.0: restoring config space at offset 0x6 (was 0x0, writing 0x40400)
[16031.096315] pcieport 0000:00:1f.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004)
[16031.096321] pcieport 0000:00:1f.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[16031.096349] pci 0000:01:00.0: restoring config space at offset 0xf (was 0x100, writing 0x109)
[16031.096359] pci 0000:01:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x9001)
[16031.096363] pci 0000:01:00.0: restoring config space at offset 0x5 (was 0x0, writing 0xd8000000)
[16031.096367] pci 0000:01:00.0: restoring config space at offset 0x4 (was 0x8, writing 0xc0000008)
[16031.096372] pci 0000:01:00.0: restoring config space at offset 0x1 (was 0x2300000, writing 0x2300003)
[16031.096431] sdhci-pci 0000:03:00.0: restoring config space at offset 0x3 (was 0x800000, writing 0x800004)
[16031.096508] pci 0000:03:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x800004)
[16031.096516] pci 0000:03:00.2: restoring config space at offset 0x1 (was 0x100007, writing 0x100003)
[16031.096589] jmb38x_ms 0000:03:00.3: restoring config space at offset 0x3 (was 0x800000, writing 0x800004)
[16031.097024] PM: early resume of devices complete after 16.960 msecs
[16031.097108] pci 0000:00:01.0: setting latency timer to 64
[16031.097127] pata_sis 0000:00:02.5: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[16031.097153] ohci_hcd 0000:00:03.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
[16031.097195] ohci_hcd 0000:00:03.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
[16031.097387] ata2: port disabled. ignoring.
[16031.097403] ehci_hcd 0000:00:03.3: PCI INT C -> GSI 22 (level, low) -> IRQ 22
[16031.097418] sis190 0000:00:04.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19
[16031.097423] sis190 0000:00:04.0: setting latency timer to 64
[16031.097435] sata_sis 0000:00:05.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
[16031.097442] HDA Intel 0000:00:0f.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
[16031.097448] HDA Intel 0000:00:0f.0: setting latency timer to 64
[16031.097458] sdhci-pci 0000:03:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[16031.097475] sdhci-pci 0000:03:00.0: setting latency timer to 64
[16031.097509] jmb38x_ms 0000:03:00.3: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[16031.097516] jmb38x_ms 0000:03:00.3: setting latency timer to 64
[16031.098020] sd 2:0:0:0: [sda] Starting disk
[16031.200049] PM: resume of drv:usb dev:usb3 complete after 102.497 msecs
[16031.200070] PM: resume of drv:hub dev:3-0:1.0 complete after 102.514 msecs
[16031.200092] PM: resume of drv: dev:ep_00 complete after 102.496 msecs
[16031.200097] PM: resume of drv: dev:ep_81 complete after 102.538 msecs
[16031.208048] PM: resume of drv:usb dev:usb2 complete after 110.510 msecs
[16031.208085] PM: resume of drv:hub dev:2-0:1.0 complete after 110.543 msecs
[16031.208089] PM: resume of drv: dev:ep_00 complete after 110.539 msecs
[16031.208096] PM: resume of drv: dev:ep_81 complete after 110.555 msecs
[16031.260038] PM: resume of drv:usb dev:usb1 complete after 162.529 msecs
[16031.260056] PM: resume of drv:hub dev:1-0:1.0 complete after 162.531 msecs
[16031.260080] PM: resume of drv: dev:ep_00 complete after 162.547 msecs
[16031.260085] PM: resume of drv: dev:ep_81 complete after 162.556 msecs
[16031.310627] PM: resume of drv:usb dev:1-7 complete after 190.584 msecs
[16031.310645] PM: resume of drv:uvcvideo dev:1-7:1.0 complete after 180.581 msecs
[16031.310664] PM: resume of drv: dev:ep_00 complete after 180.559 msecs
[16031.310669] PM: resume of drv:uvcvideo dev:1-7:1.1 complete after 180.575 msecs
[16031.310673] PM: resume of drv: dev:ep_82 complete after 180.602 msecs
[16031.372035] usb 1-2: reset high speed USB device using ehci_hcd and address 2
[16031.506884] PM: resume of drv:usb dev:1-2 complete after 409.231 msecs
[16031.506916] PM: resume of drv: dev:ep_00 complete after 408.933 msecs
[16031.506921] PM: resume of drv:usb dev:1-2:1.0 complete after 409.261 msecs
[16031.506947] PM: resume of drv: dev:ep_83 complete after 409.263 msecs
[16031.506951] PM: resume of drv: dev:ep_89 complete after 409.112 msecs
[16031.506955] PM: resume of drv: dev:ep_04 complete after 409.243 msecs
[16031.506959] PM: resume of drv: dev:ep_0a complete after 409.074 msecs
[16031.506964] PM: resume of drv: dev:ep_05 complete after 409.222 msecs
[16031.506968] PM: resume of drv: dev:ep_0b complete after 409.050 msecs
[16031.506972] PM: resume of drv: dev:ep_06 complete after 409.199 msecs
[16031.506976] PM: resume of drv: dev:ep_0c complete after 409.028 msecs
[16031.506980] PM: resume of drv: dev:ep_07 complete after 409.175 msecs
[16062.048041] ata3: lost interrupt (Status 0x50)
[16062.048059] ata3.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[16062.048064] ata3.00: failed command: READ VERIFY SECTOR(S)
[16062.048074] ata3.00: cmd 40/00:01:00:00:00/00:00:00:00:00/e0 tag 0
[16062.048076]          res 40/00:ff:00:00:00/00:00:00:00:00/40 Emask 0x4 (timeout)
[16062.048080] ata3.00: status: { DRDY }
[16062.048107] ata3: soft resetting link
[16062.252361] ata3.00: configured for UDMA/133
[16062.252367] ata3.00: device reported invalid CHS sector 0
[16062.252385] ata3: EH complete
[16062.252393] sd 2:0:0:0: [sda] START_STOP FAILED
[16062.252397] sd 2:0:0:0: [sda]  Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
[16062.252403] sd 2:0:0:0: [sda]  Sense Key : Aborted Command [current] [descriptor]
[16062.252410] sd 2:0:0:0: [sda]  Add. Sense: No additional sense information
[16062.252497] pm_op(): scsi_bus_resume_common+0x0/0x50 returns 134217730
[16062.252510] PM: Device 2:0:0:0 failed to resume async: error 134217730
[16062.252516] PM: resume of drv:scsi_device dev:2:0:0:0 complete after 31154.420 msecs
[16062.252549] PM: resume of drv:scsi_disk dev:2:0:0:0 complete after 31112.993 msecs
[16062.252670] PM: resume of devices complete after 31155.602 msecs
[16062.528222] cfg80211: Updating information on frequency 2412 MHz for a 20 MHz width channel with regulatory rule:
[16062.528227] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16062.528231] cfg80211: Updating information on frequency 2417 MHz for a 20 MHz width channel with regulatory rule:
[16062.528236] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16062.528240] cfg80211: Updating information on frequency 2422 MHz for a 20 MHz width channel with regulatory rule:
[16062.528244] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16062.528248] cfg80211: Updating information on frequency 2427 MHz for a 20 MHz width channel with regulatory rule:
[16062.528252] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16062.528256] cfg80211: Updating information on frequency 2432 MHz for a 20 MHz width channel with regulatory rule:
[16062.528260] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16062.528263] cfg80211: Updating information on frequency 2437 MHz for a 20 MHz width channel with regulatory rule:
[16062.528268] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16062.528272] cfg80211: Updating information on frequency 2442 MHz for a 20 MHz width channel with regulatory rule:
[16062.528276] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16062.528280] cfg80211: Updating information on frequency 2447 MHz for a 20 MHz width channel with regulatory rule:
[16062.528285] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16062.528289] cfg80211: Updating information on frequency 2452 MHz for a 20 MHz width channel with regulatory rule:
[16062.528293] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16062.528297] cfg80211: Updating information on frequency 2457 MHz for a 20 MHz width channel with regulatory rule:
[16062.528301] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16062.528305] cfg80211: Updating information on frequency 2462 MHz for a 20 MHz width channel with regulatory rule:
[16062.528309] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[16062.528313] cfg80211: Updating information on frequency 2467 MHz for a 20 MHz width channel with regulatory rule:
[16062.528317] cfg80211: 2457000 KHz - 2482000 KHz @  KHz), (300 mBi, 2000 mBm)
[16062.528321] cfg80211: Updating information on frequency 2472 MHz for a 20 MHz width channel with regulatory rule:
[16062.528326] cfg80211: 2457000 KHz - 2482000 KHz @  KHz), (300 mBi, 2000 mBm)
[16062.528330] cfg80211: Updating information on frequency 2484 MHz for a 20 MHz width channel with regulatory rule:
[16062.528334] cfg80211: 2474000 KHz - 2494000 KHz @  KHz), (300 mBi, 2000 mBm)
[16062.528490] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
[16062.529159] ieee80211 phy1: hwaddr 1c:4b:d6:26:6d:9b, RTL8187BvE V0 + rtl8225z2, rfkill mask 2
[16062.550647] rtl8187: Customer ID is 0x00
[16062.550711] Registered led device: rtl8187-phy1::radio
[16062.550761] Registered led device: rtl8187-phy1::tx
[16062.550811] Registered led device: rtl8187-phy1::rx
[16062.551510] rtl8187: wireless switch is on
[16062.551845] PM: resume devices took 31.452 seconds
[16062.551847] ------------[ cut here ]------------
[16062.551854] WARNING: at /build/buildd/linux-2.6.38/kernel/power/suspend_test.c:53 suspend_test_finish+0x89/0x90()
[16062.551856] Hardware name: POSITIVO MOBILE                 
[16062.551858] Component: resume devices, time: 31452
[16062.551859] Modules linked in: binfmt_misc parport_pc ppdev dm_crypt vesafb snd_hda_codec_si3054 snd_hda_codec_realtek joydev snd_hda_intel snd_hda_codec arc4 lp uvcvideo videodev snd_hwdep snd_pcm snd_seq_midi psmouse snd_rawmidi jmb38x_ms rtl8187 snd_seq_midi_event mac80211 snd_seq snd_timer snd_seq_device cfg80211 memstick eeprom_93cx6 shpchp serio_raw snd parport soundcore snd_page_alloc video sis190 sdhci_pci sata_sis sis_agp sdhci
[16062.551890] Pid: 20150, comm: pm-suspend Not tainted 2.6.38-8-generic-pae #42-Ubuntu
[16062.551892] Call Trace:
[16062.551899]  [<c1058ce2>] ? warn_slowpath_common+0x72/0xa0
[16062.551902]  [<c1094429>] ? suspend_test_finish+0x89/0x90
[16062.551905]  [<c1094429>] ? suspend_test_finish+0x89/0x90
[16062.551908]  [<c1058db3>] ? warn_slowpath_fmt+0x33/0x40
[16062.551910]  [<c1094429>] ? suspend_test_finish+0x89/0x90
[16062.551913]  [<c109417e>] ? suspend_devices_and_enter+0x9e/0x150
[16062.551916]  [<c1094331>] ? enter_state+0x101/0x120
[16062.551919]  [<c10938a6>] ? state_store+0x86/0xc0
[16062.551922]  [<c1093820>] ? state_store+0x0/0xc0
[16062.551926]  [<c127e990>] ? kobj_attr_store+0x20/0x30
[16062.551931]  [<c118e39e>] ? sysfs_write_file+0x9e/0x100
[16062.551935]  [<c11328a2>] ? vfs_write+0xa2/0x170
[16062.551937]  [<c118e300>] ? sysfs_write_file+0x0/0x100
[16062.551940]  [<c1132b82>] ? sys_write+0x42/0x70
[16062.551944]  [<c100ab5f>] ? sysenter_do_call+0x12/0x28
[16062.551946] ---[ end trace b37694dd17db0706 ]---
[16062.551990] PM: Finishing wakeup.
[16062.551992] Restarting tasks ... done.
[16062.593176] video LNXVIDEO:00: Restoring backlight state
[16065.890328] ADDRCONF(NETDEV_UP): eth0: link is not ready
[16069.710591] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[16070.427142] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0
[16070.958134] EXT4-fs (sda5): re-mounted. Opts: commit=0
[16071.181319] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0
[16074.505278] wlan0: authenticate with 00:26:5a:63:b3:2a (try 1)
[16074.507393] wlan0: authenticated
[16074.645283] wlan0: associate with 00:26:5a:63:b3:2a (try 1)
[16074.648913] wlan0: RX AssocResp from 00:26:5a:63:b3:2a (capab=0x431 status=0 aid=1)
[16074.648916] wlan0: associated
[16074.654600] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[16085.360017] wlan0: no IPv6 routers present
[21450.060520] type=1400 audit(1302958739.018:25): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24160 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21450.460101] type=1400 audit(1302958739.418:26): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24161 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21450.623420] type=1400 audit(1302958739.578:27): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24163 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21450.815412] type=1400 audit(1302958739.770:28): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24164 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21451.036570] type=1400 audit(1302958739.994:29): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24165 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21451.260642] type=1400 audit(1302958740.218:30): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24166 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21451.589424] type=1400 audit(1302958740.546:31): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24167 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21451.802832] type=1400 audit(1302958740.758:32): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24168 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21452.107910] type=1400 audit(1302958741.062:33): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24169 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21452.740782] type=1400 audit(1302958741.698:34): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24172 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21455.584141] audit_printk_skb: 15 callbacks suppressed
[21455.584146] type=1400 audit(1302958744.542:40): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24185 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21456.340368] type=1400 audit(1302958745.298:41): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24186 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21458.985398] type=1400 audit(1302958747.942:42): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24187 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21459.197619] type=1400 audit(1302958748.154:43): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24188 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21459.386772] type=1400 audit(1302958748.342:44): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24189 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21459.625411] type=1400 audit(1302958748.582:45): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24190 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21460.608459] type=1400 audit(1302958749.566:46): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24194 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21461.690877] type=1400 audit(1302958750.646:47): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24197 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21462.097079] type=1400 audit(1302958751.054:48): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24198 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21462.882920] type=1400 audit(1302958751.838:49): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24199 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21463.170525] type=1400 audit(1302958752.126:50): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24200 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21463.417631] type=1400 audit(1302958752.374:51): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24201 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21463.968268] type=1400 audit(1302958752.926:52): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24202 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21464.626282] type=1400 audit(1302958753.582:53): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24203 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21464.885155] type=1400 audit(1302958753.842:54): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24204 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21465.253013] type=1400 audit(1302958754.210:55): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24205 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21467.088918] type=1400 audit(1302958756.046:56): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24206 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21467.681359] type=1400 audit(1302958756.638:57): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24209 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[21467.957540] type=1400 audit(1302958756.914:58): apparmor="DENIED" operation="open" parent=24159 profile="/usr/bin/evince-thumbnailer" name="/dev/.udev/data/b8:6" pid=24210 comm="evince-thumbnai" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
[25205.192711] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=600
[25205.537949] EXT4-fs (sda5): re-mounted. Opts: commit=600
[25205.759471] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=600
[25221.531269] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0
[25221.909925] EXT4-fs (sda5): re-mounted. Opts: commit=0
[25222.049888] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0
[26276.977086] wlan0: deauthenticating from 00:26:5a:63:b3:2a by local choice (reason=3)
[26277.101335] cfg80211: All devices are disconnected, going to restore regulatory settings
[26277.101342] cfg80211: Restoring regulatory settings
[26277.101349] cfg80211: Calling CRDA to update world regulatory domain
[26277.107007] cfg80211: Updating information on frequency 2412 MHz for a 20 MHz width channel with regulatory rule:
[26277.107012] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26277.107014] cfg80211: Updating information on frequency 2417 MHz for a 20 MHz width channel with regulatory rule:
[26277.107017] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26277.107020] cfg80211: Updating information on frequency 2422 MHz for a 20 MHz width channel with regulatory rule:
[26277.107023] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26277.107025] cfg80211: Updating information on frequency 2427 MHz for a 20 MHz width channel with regulatory rule:
[26277.107028] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26277.107030] cfg80211: Updating information on frequency 2432 MHz for a 20 MHz width channel with regulatory rule:
[26277.107033] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26277.107035] cfg80211: Updating information on frequency 2437 MHz for a 20 MHz width channel with regulatory rule:
[26277.107038] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26277.107040] cfg80211: Updating information on frequency 2442 MHz for a 20 MHz width channel with regulatory rule:
[26277.107043] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26277.107045] cfg80211: Updating information on frequency 2447 MHz for a 20 MHz width channel with regulatory rule:
[26277.107048] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26277.107050] cfg80211: Updating information on frequency 2452 MHz for a 20 MHz width channel with regulatory rule:
[26277.107053] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26277.107055] cfg80211: Updating information on frequency 2457 MHz for a 20 MHz width channel with regulatory rule:
[26277.107058] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26277.107060] cfg80211: Updating information on frequency 2462 MHz for a 20 MHz width channel with regulatory rule:
[26277.107063] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26277.107065] cfg80211: Updating information on frequency 2467 MHz for a 20 MHz width channel with regulatory rule:
[26277.107068] cfg80211: 2457000 KHz - 2482000 KHz @  KHz), (300 mBi, 2000 mBm)
[26277.107070] cfg80211: Updating information on frequency 2472 MHz for a 20 MHz width channel with regulatory rule:
[26277.107073] cfg80211: 2457000 KHz - 2482000 KHz @  KHz), (300 mBi, 2000 mBm)
[26277.107076] cfg80211: Updating information on frequency 2484 MHz for a 20 MHz width channel with regulatory rule:
[26277.107078] cfg80211: 2474000 KHz - 2494000 KHz @  KHz), (300 mBi, 2000 mBm)
[26277.107081] cfg80211: World regulatory domain updated:
[26277.107083] cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[26277.107086] cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[26277.107089] cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[26277.107091] cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[26277.107094] cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[26277.107096] cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[26278.333579] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0
[26278.578689] EXT4-fs (sda5): re-mounted. Opts: commit=0
[26278.958556] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0
[26285.603222] PM: Syncing filesystems ... done.
[26285.631443] PM: Preparing system for mem sleep
[26285.631464] Freezing user space processes ... (elapsed 0.01 seconds) done.
[26285.648125] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
[26285.664101] PM: Entering mem sleep
[26285.664123] Suspending console(s) (use no_console_suspend to debug)
[26285.664696] sd 2:0:0:0: [sda] Synchronizing SCSI cache
[26285.671977] sd 2:0:0:0: [sda] Stopping disk
[26285.706629] ACPI handle has no context!
[26285.706642] sdhci-pci 0000:03:00.0: PCI INT A disabled
[26285.706651] ACPI handle has no context!
[26285.706738] ACPI handle has no context!
[26285.706746] jmb38x_ms 0000:03:00.3: PCI INT A disabled
[26285.706755] ACPI handle has no context!
[26285.706873] sis190 0000:00:04.0: PCI INT A disabled
[26285.706919] ohci_hcd 0000:00:03.1: PCI INT B disabled
[26285.706930] ohci_hcd 0000:00:03.0: PCI INT A disabled
[26285.707081] pata_sis 0000:00:02.5: PCI INT A disabled
[26285.792016] usb 1-2: reset high speed USB device using ehci_hcd and address 2
[26285.912069] HDA Intel 0000:00:0f.0: PCI INT A disabled
[26285.912096] ACPI handle has no context!
[26285.928014] PM: suspend of drv:HDA Intel dev:0000:00:0f.0 complete after 221.238 msecs
[26285.940013] PM: suspend of drv:usb dev:1-2 complete after 275.125 msecs
[26285.940475] PM: suspend of drv:usb dev:usb1 complete after 234.364 msecs
[26285.956023] ehci_hcd 0000:00:03.3: PCI INT C disabled
[26285.956026] PM: suspend of drv:ehci_hcd dev:0000:00:03.3 complete after 249.147 msecs
[26286.707472] PM: suspend of drv:sd dev:2:0:0:0 complete after 1042.783 msecs
[26286.707483] PM: suspend of drv:scsi dev:target2:0:0 complete after 1042.688 msecs
[26286.707501] PM: suspend of drv:scsi dev:host2 complete after 1042.467 msecs
[26286.707511] sata_sis 0000:00:05.0: PCI INT A disabled
[26286.707514] PM: suspend of drv:sata_sis dev:0000:00:05.0 complete after 1000.679 msecs
[26286.707522] PM: suspend of drv: dev:pci0000:00 complete after 1000.520 msecs
[26286.707530] PM: suspend of devices complete after 1043.011 msecs
[26286.707533] PM: suspend devices took 1.040 seconds
[26286.724156] PM: late suspend of devices complete after 16.618 msecs
[26286.724353] ACPI: Preparing to enter system sleep state S3
[26286.765328] PM: Saving platform NVS memory
[26286.765355] Disabling non-boot CPUs ...
[26286.868030] CPU 1 is now offline
[26286.868485] Extended CMOS year: 2000
[26286.868485] Back to C!
[26286.868485] PM: Restoring platform NVS memory
[26286.868485] Extended CMOS year: 2000
[26286.868485] Enabling non-boot CPUs ...
[26286.868485] Booting Node 0 Processor 1 APIC 0x1
[26286.766643] Initializing CPU#1
[26286.956623] CPU1 is up
[26286.957063] ACPI: Waking up from system sleep state S3
[26286.960022] Switched to NOHz mode on CPU #1
[26287.212182] pci 0000:00:01.0: restoring config space at offset 0x7 (was 0x209090, writing 0x20209090)
[26287.212227] pata_sis 0000:00:02.5: restoring config space at offset 0xf (was 0x1ff, writing 0x0)
[26287.212239] pata_sis 0000:00:02.5: restoring config space at offset 0x7 (was 0x375, writing 0x1)
[26287.212244] pata_sis 0000:00:02.5: restoring config space at offset 0x6 (was 0x171, writing 0x1)
[26287.212248] pata_sis 0000:00:02.5: restoring config space at offset 0x5 (was 0x3f5, writing 0x1)
[26287.212253] pata_sis 0000:00:02.5: restoring config space at offset 0x4 (was 0x1f1, writing 0x1)
[26287.212258] pata_sis 0000:00:02.5: restoring config space at offset 0x2 (was 0x1018a01, writing 0x1018001)
[26287.212289] ohci_hcd 0000:00:03.0: restoring config space at offset 0x1 (was 0x2800017, writing 0x2800013)
[26287.212315] ohci_hcd 0000:00:03.1: restoring config space at offset 0x1 (was 0x2800017, writing 0x2800013)
[26287.228019] ehci_hcd 0000:00:03.3: BAR 0: set to [mem 0xd8206000-0xd8206fff] (PCI address [0xd8206000-0xd8206fff])
[26287.228044] ehci_hcd 0000:00:03.3: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002)
[26287.228078] sis190 0000:00:04.0: restoring config space at offset 0x1 (was 0x2100007, writing 0x2100003)
[26287.228111] sata_sis 0000:00:05.0: restoring config space at offset 0x1 (was 0x2100005, writing 0x2100001)
[26287.228145] pcieport 0000:00:06.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd861d851)
[26287.228155] pcieport 0000:00:06.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004)
[26287.228160] pcieport 0000:00:06.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[26287.228203] pcieport 0000:00:07.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd881d871)
[26287.228208] pcieport 0000:00:07.0: restoring config space at offset 0x7 (was 0x200001f1, writing 0x20002121)
[26287.228216] pcieport 0000:00:07.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004)
[26287.228222] pcieport 0000:00:07.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[26287.228264] HDA Intel 0000:00:0f.0: restoring config space at offset 0x1 (was 0x2100006, writing 0x2100002)
[26287.228287] pcieport 0000:00:1f.0: restoring config space at offset 0xf (was 0x0, writing 0x400ff)
[26287.228298] pcieport 0000:00:1f.0: restoring config space at offset 0x9 (was 0x1ff01, writing 0x1fff1)
[26287.228304] pcieport 0000:00:1f.0: restoring config space at offset 0x7 (was 0x101, writing 0x1f1)
[26287.228308] pcieport 0000:00:1f.0: restoring config space at offset 0x6 (was 0x0, writing 0x40400)
[26287.228315] pcieport 0000:00:1f.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004)
[26287.228321] pcieport 0000:00:1f.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[26287.228349] pci 0000:01:00.0: restoring config space at offset 0xf (was 0x100, writing 0x109)
[26287.228360] pci 0000:01:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x9001)
[26287.228363] pci 0000:01:00.0: restoring config space at offset 0x5 (was 0x0, writing 0xd8000000)
[26287.228367] pci 0000:01:00.0: restoring config space at offset 0x4 (was 0x8, writing 0xc0000008)
[26287.228372] pci 0000:01:00.0: restoring config space at offset 0x1 (was 0x2300000, writing 0x2300003)
[26287.228432] sdhci-pci 0000:03:00.0: restoring config space at offset 0x3 (was 0x800000, writing 0x800004)
[26287.228509] pci 0000:03:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x800004)
[26287.228517] pci 0000:03:00.2: restoring config space at offset 0x1 (was 0x100007, writing 0x100003)
[26287.228590] jmb38x_ms 0000:03:00.3: restoring config space at offset 0x3 (was 0x800000, writing 0x800004)
[26287.229026] PM: early resume of devices complete after 16.965 msecs
[26287.229108] pci 0000:00:01.0: setting latency timer to 64
[26287.229127] pata_sis 0000:00:02.5: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[26287.229154] ohci_hcd 0000:00:03.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
[26287.229215] ohci_hcd 0000:00:03.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
[26287.229233] ehci_hcd 0000:00:03.3: PCI INT C -> GSI 22 (level, low) -> IRQ 22
[26287.229266] sis190 0000:00:04.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19
[26287.229272] sis190 0000:00:04.0: setting latency timer to 64
[26287.229280] sata_sis 0000:00:05.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
[26287.229298] HDA Intel 0000:00:0f.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
[26287.229303] HDA Intel 0000:00:0f.0: setting latency timer to 64
[26287.229357] sdhci-pci 0000:03:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[26287.229372] sdhci-pci 0000:03:00.0: setting latency timer to 64
[26287.229396] jmb38x_ms 0000:03:00.3: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[26287.229403] jmb38x_ms 0000:03:00.3: setting latency timer to 64
[26287.229434] ata2: port disabled. ignoring.
[26287.229994] sd 2:0:0:0: [sda] Starting disk
[26287.340053] PM: resume of drv:usb dev:usb2 complete after 110.592 msecs
[26287.340074] PM: resume of drv:hub dev:2-0:1.0 complete after 110.607 msecs
[26287.340083] PM: resume of drv:usb dev:usb3 complete after 110.601 msecs
[26287.340088] PM: resume of drv: dev:ep_00 complete after 110.611 msecs
[26287.340096] PM: resume of drv:hub dev:3-0:1.0 complete after 110.609 msecs
[26287.340100] PM: resume of drv: dev:ep_81 complete after 110.627 msecs
[26287.340108] PM: resume of drv: dev:ep_81 complete after 110.554 msecs
[26287.340112] PM: resume of drv: dev:ep_00 complete after 110.525 msecs
[26287.394399] PM: resume of drv:usb dev:usb1 complete after 164.961 msecs
[26287.394411] PM: resume of drv:hub dev:1-0:1.0 complete after 164.969 msecs
[26287.394428] PM: resume of drv: dev:ep_00 complete after 164.976 msecs
[26287.394441] PM: resume of drv: dev:ep_81 complete after 164.994 msecs
[26287.442500] PM: resume of drv:usb dev:1-7 complete after 180.462 msecs
[26287.442517] PM: resume of drv:uvcvideo dev:1-7:1.0 complete after 180.458 msecs
[26287.442539] PM: resume of drv: dev:ep_00 complete after 180.461 msecs
[26287.442543] PM: resume of drv:uvcvideo dev:1-7:1.1 complete after 180.468 msecs
[26287.442557] PM: resume of drv: dev:ep_82 complete after 180.491 msecs
[26287.504037] usb 1-2: reset high speed USB device using ehci_hcd and address 2
[26287.638880] PM: resume of drv:usb dev:1-2 complete after 409.245 msecs
[26287.638911] PM: resume of drv:usb dev:1-2:1.0 complete after 409.268 msecs
[26287.638916] PM: resume of drv: dev:ep_00 complete after 408.953 msecs
[26287.638941] PM: resume of drv: dev:ep_83 complete after 409.268 msecs
[26287.638945] PM: resume of drv: dev:ep_04 complete after 409.240 msecs
[26287.638949] PM: resume of drv: dev:ep_05 complete after 409.214 msecs
[26287.638953] PM: resume of drv: dev:ep_07 complete after 409.154 msecs
[26287.638957] PM: resume of drv: dev:ep_06 complete after 409.189 msecs
[26287.638961] PM: resume of drv: dev:ep_0a complete after 409.097 msecs
[26287.638966] PM: resume of drv: dev:ep_89 complete after 409.133 msecs
[26287.638970] PM: resume of drv: dev:ep_0c complete after 409.044 msecs
[26287.638973] PM: resume of drv: dev:ep_0b complete after 409.079 msecs
[26318.048043] ata3: lost interrupt (Status 0x50)
[26318.048061] ata3.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[26318.048066] ata3.00: failed command: READ VERIFY SECTOR(S)
[26318.048075] ata3.00: cmd 40/00:01:00:00:00/00:00:00:00:00/e0 tag 0
[26318.048077]          res 40/00:ff:00:00:00/00:00:00:00:00/40 Emask 0x4 (timeout)
[26318.048081] ata3.00: status: { DRDY }
[26318.048107] ata3: soft resetting link
[26318.252361] ata3.00: configured for UDMA/133
[26318.252367] ata3.00: device reported invalid CHS sector 0
[26318.252387] ata3: EH complete
[26318.252394] sd 2:0:0:0: [sda] START_STOP FAILED
[26318.252398] sd 2:0:0:0: [sda]  Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
[26318.252404] sd 2:0:0:0: [sda]  Sense Key : Aborted Command [current] [descriptor]
[26318.252411] sd 2:0:0:0: [sda]  Add. Sense: No additional sense information
[26318.252431] pm_op(): scsi_bus_resume_common+0x0/0x50 returns 134217730
[26318.252445] PM: Device 2:0:0:0 failed to resume async: error 134217730
[26318.252450] PM: resume of drv:scsi_disk dev:2:0:0:0 complete after 30953.869 msecs
[26318.252455] PM: resume of drv:scsi_device dev:2:0:0:0 complete after 31022.435 msecs
[26318.252576] PM: resume of devices complete after 31023.506 msecs
[26318.528207] cfg80211: Updating information on frequency 2412 MHz for a 20 MHz width channel with regulatory rule:
[26318.528212] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26318.528216] cfg80211: Updating information on frequency 2417 MHz for a 20 MHz width channel with regulatory rule:
[26318.528221] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26318.528225] cfg80211: Updating information on frequency 2422 MHz for a 20 MHz width channel with regulatory rule:
[26318.528229] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26318.528232] cfg80211: Updating information on frequency 2427 MHz for a 20 MHz width channel with regulatory rule:
[26318.528237] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26318.528240] cfg80211: Updating information on frequency 2432 MHz for a 20 MHz width channel with regulatory rule:
[26318.528245] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26318.528248] cfg80211: Updating information on frequency 2437 MHz for a 20 MHz width channel with regulatory rule:
[26318.528253] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26318.528257] cfg80211: Updating information on frequency 2442 MHz for a 20 MHz width channel with regulatory rule:
[26318.528261] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26318.528265] cfg80211: Updating information on frequency 2447 MHz for a 20 MHz width channel with regulatory rule:
[26318.528269] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26318.528273] cfg80211: Updating information on frequency 2452 MHz for a 20 MHz width channel with regulatory rule:
[26318.528277] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26318.528281] cfg80211: Updating information on frequency 2457 MHz for a 20 MHz width channel with regulatory rule:
[26318.528285] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26318.528289] cfg80211: Updating information on frequency 2462 MHz for a 20 MHz width channel with regulatory rule:
[26318.528293] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26318.528297] cfg80211: Updating information on frequency 2467 MHz for a 20 MHz width channel with regulatory rule:
[26318.528301] cfg80211: 2457000 KHz - 2482000 KHz @  KHz), (300 mBi, 2000 mBm)
[26318.528305] cfg80211: Updating information on frequency 2472 MHz for a 20 MHz width channel with regulatory rule:
[26318.528309] cfg80211: 2457000 KHz - 2482000 KHz @  KHz), (300 mBi, 2000 mBm)
[26318.528313] cfg80211: Updating information on frequency 2484 MHz for a 20 MHz width channel with regulatory rule:
[26318.528317] cfg80211: 2474000 KHz - 2494000 KHz @  KHz), (300 mBi, 2000 mBm)
[26318.528476] ieee80211 phy2: Selected rate control algorithm 'minstrel_ht'
[26318.529129] ieee80211 phy2: hwaddr 1c:4b:d6:26:6d:9b, RTL8187BvE V0 + rtl8225z2, rfkill mask 2
[26318.550639] rtl8187: Customer ID is 0x00
[26318.550696] Registered led device: rtl8187-phy2::radio
[26318.550737] Registered led device: rtl8187-phy2::tx
[26318.550783] Registered led device: rtl8187-phy2::rx
[26318.551503] rtl8187: wireless switch is on
[26318.551833] PM: resume devices took 31.320 seconds
[26318.551835] ------------[ cut here ]------------
[26318.551843] WARNING: at /build/buildd/linux-2.6.38/kernel/power/suspend_test.c:53 suspend_test_finish+0x89/0x90()
[26318.551845] Hardware name: POSITIVO MOBILE                 
[26318.551847] Component: resume devices, time: 31320
[26318.551848] Modules linked in: binfmt_misc parport_pc ppdev dm_crypt vesafb snd_hda_codec_si3054 snd_hda_codec_realtek joydev snd_hda_intel snd_hda_codec arc4 lp uvcvideo videodev snd_hwdep snd_pcm snd_seq_midi psmouse snd_rawmidi jmb38x_ms rtl8187 snd_seq_midi_event mac80211 snd_seq snd_timer snd_seq_device cfg80211 memstick eeprom_93cx6 shpchp serio_raw snd parport soundcore snd_page_alloc video sis190 sdhci_pci sata_sis sis_agp sdhci
[26318.551879] Pid: 26117, comm: pm-suspend Tainted: G        W   2.6.38-8-generic-pae #42-Ubuntu
[26318.551882] Call Trace:
[26318.551888]  [<c1058ce2>] ? warn_slowpath_common+0x72/0xa0
[26318.551892]  [<c1094429>] ? suspend_test_finish+0x89/0x90
[26318.551894]  [<c1094429>] ? suspend_test_finish+0x89/0x90
[26318.551898]  [<c1058db3>] ? warn_slowpath_fmt+0x33/0x40
[26318.551900]  [<c1094429>] ? suspend_test_finish+0x89/0x90
[26318.551904]  [<c109417e>] ? suspend_devices_and_enter+0x9e/0x150
[26318.551906]  [<c1094331>] ? enter_state+0x101/0x120
[26318.551909]  [<c10938a6>] ? state_store+0x86/0xc0
[26318.551912]  [<c1093820>] ? state_store+0x0/0xc0
[26318.551916]  [<c127e990>] ? kobj_attr_store+0x20/0x30
[26318.551921]  [<c118e39e>] ? sysfs_write_file+0x9e/0x100
[26318.551925]  [<c11328a2>] ? vfs_write+0xa2/0x170
[26318.551927]  [<c118e300>] ? sysfs_write_file+0x0/0x100
[26318.551930]  [<c1132b82>] ? sys_write+0x42/0x70
[26318.551934]  [<c100ab5f>] ? sysenter_do_call+0x12/0x28
[26318.551936] ---[ end trace b37694dd17db0707 ]---
[26318.551980] PM: Finishing wakeup.
[26318.551981] Restarting tasks ... done.
[26318.606495] video LNXVIDEO:00: Restoring backlight state
[26322.204899] ADDRCONF(NETDEV_UP): eth0: link is not ready
[26326.054499] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[26327.303265] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0
[26327.469913] EXT4-fs (sda5): re-mounted. Opts: commit=0
[26327.755328] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0
[26330.181350] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0
[26330.287380] EXT4-fs (sda5): re-mounted. Opts: commit=0
[26330.435528] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0
[26336.997181] PM: Syncing filesystems ... done.
[26337.171042] PM: Preparing system for mem sleep
[26337.171061] Freezing user space processes ... (elapsed 0.01 seconds) done.
[26337.188135] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
[26337.204101] PM: Entering mem sleep
[26337.204125] Suspending console(s) (use no_console_suspend to debug)
[26337.204692] sd 2:0:0:0: [sda] Synchronizing SCSI cache
[26337.204919] sd 2:0:0:0: [sda] Stopping disk
[26337.247255] ACPI handle has no context!
[26337.247270] jmb38x_ms 0000:03:00.3: PCI INT A disabled
[26337.247282] ACPI handle has no context!
[26337.247283] ACPI handle has no context!
[26337.247294] sdhci-pci 0000:03:00.0: PCI INT A disabled
[26337.247302] ACPI handle has no context!
[26337.247345] sis190 0000:00:04.0: PCI INT A disabled
[26337.247775] ohci_hcd 0000:00:03.1: PCI INT B disabled
[26337.247776] ohci_hcd 0000:00:03.0: PCI INT A disabled
[26337.247856] pata_sis 0000:00:02.5: PCI INT A disabled
[26337.332017] usb 1-2: reset high speed USB device using ehci_hcd and address 2
[26337.452075] HDA Intel 0000:00:0f.0: PCI INT A disabled
[26337.452102] ACPI handle has no context!
[26337.468013] PM: suspend of drv:HDA Intel dev:0000:00:0f.0 complete after 220.684 msecs
[26337.480013] PM: suspend of drv:usb dev:1-2 complete after 275.198 msecs
[26337.480602] PM: suspend of drv:usb dev:usb1 complete after 233.886 msecs
[26337.496022] ehci_hcd 0000:00:03.3: PCI INT C disabled
[26337.496026] PM: suspend of drv:ehci_hcd dev:0000:00:03.3 complete after 248.676 msecs
[26338.201871] PM: suspend of drv:sd dev:2:0:0:0 complete after 997.185 msecs
[26338.201882] PM: suspend of drv:scsi dev:target2:0:0 complete after 997.117 msecs
[26338.201906] PM: suspend of drv:scsi dev:host2 complete after 996.903 msecs
[26338.201918] sata_sis 0000:00:05.0: PCI INT A disabled
[26338.201921] PM: suspend of drv:sata_sis dev:0000:00:05.0 complete after 954.585 msecs
[26338.201930] PM: suspend of drv: dev:pci0000:00 complete after 954.096 msecs
[26338.201938] PM: suspend of devices complete after 997.415 msecs
[26338.201940] PM: suspend devices took 0.996 seconds
[26338.216155] PM: late suspend of devices complete after 14.211 msecs
[26338.216344] ACPI: Preparing to enter system sleep state S3
[26338.257320] PM: Saving platform NVS memory
[26338.257348] Disabling non-boot CPUs ...
[26338.360031] CPU 1 is now offline
[26338.360453] Extended CMOS year: 2000
[26338.360453] Back to C!
[26338.360453] PM: Restoring platform NVS memory
[26338.360453] Extended CMOS year: 2000
[26338.360453] Enabling non-boot CPUs ...
[26338.360453] Booting Node 0 Processor 1 APIC 0x1
[26338.258637] Initializing CPU#1
[26338.448550] CPU1 is up
[26338.448992] ACPI: Waking up from system sleep state S3
[26338.452022] Switched to NOHz mode on CPU #1
[26338.704183] pci 0000:00:01.0: restoring config space at offset 0x7 (was 0x209090, writing 0x20209090)
[26338.704228] pata_sis 0000:00:02.5: restoring config space at offset 0xf (was 0x1ff, writing 0x0)
[26338.704241] pata_sis 0000:00:02.5: restoring config space at offset 0x7 (was 0x375, writing 0x1)
[26338.704245] pata_sis 0000:00:02.5: restoring config space at offset 0x6 (was 0x171, writing 0x1)
[26338.704250] pata_sis 0000:00:02.5: restoring config space at offset 0x5 (was 0x3f5, writing 0x1)
[26338.704254] pata_sis 0000:00:02.5: restoring config space at offset 0x4 (was 0x1f1, writing 0x1)
[26338.704260] pata_sis 0000:00:02.5: restoring config space at offset 0x2 (was 0x1018a01, writing 0x1018001)
[26338.704291] ohci_hcd 0000:00:03.0: restoring config space at offset 0x1 (was 0x2800017, writing 0x2800013)
[26338.704317] ohci_hcd 0000:00:03.1: restoring config space at offset 0x1 (was 0x2800017, writing 0x2800013)
[26338.720019] ehci_hcd 0000:00:03.3: BAR 0: set to [mem 0xd8206000-0xd8206fff] (PCI address [0xd8206000-0xd8206fff])
[26338.720044] ehci_hcd 0000:00:03.3: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002)
[26338.720078] sis190 0000:00:04.0: restoring config space at offset 0x1 (was 0x2100007, writing 0x2100003)
[26338.720111] sata_sis 0000:00:05.0: restoring config space at offset 0x1 (was 0x2100005, writing 0x2100001)
[26338.720145] pcieport 0000:00:06.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd861d851)
[26338.720155] pcieport 0000:00:06.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004)
[26338.720161] pcieport 0000:00:06.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[26338.720203] pcieport 0000:00:07.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd881d871)
[26338.720209] pcieport 0000:00:07.0: restoring config space at offset 0x7 (was 0x200001f1, writing 0x2121)
[26338.720216] pcieport 0000:00:07.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004)
[26338.720222] pcieport 0000:00:07.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[26338.720264] HDA Intel 0000:00:0f.0: restoring config space at offset 0x1 (was 0x2100006, writing 0x2100002)
[26338.720287] pcieport 0000:00:1f.0: restoring config space at offset 0xf (was 0x0, writing 0x400ff)
[26338.720298] pcieport 0000:00:1f.0: restoring config space at offset 0x9 (was 0x1ff01, writing 0x1fff1)
[26338.720304] pcieport 0000:00:1f.0: restoring config space at offset 0x7 (was 0x101, writing 0x1f1)
[26338.720309] pcieport 0000:00:1f.0: restoring config space at offset 0x6 (was 0x0, writing 0x40400)
[26338.720316] pcieport 0000:00:1f.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004)
[26338.720322] pcieport 0000:00:1f.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[26338.720350] pci 0000:01:00.0: restoring config space at offset 0xf (was 0x100, writing 0x109)
[26338.720360] pci 0000:01:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x9001)
[26338.720363] pci 0000:01:00.0: restoring config space at offset 0x5 (was 0x0, writing 0xd8000000)
[26338.720367] pci 0000:01:00.0: restoring config space at offset 0x4 (was 0x8, writing 0xc0000008)
[26338.720373] pci 0000:01:00.0: restoring config space at offset 0x1 (was 0x2300000, writing 0x2300003)
[26338.720433] sdhci-pci 0000:03:00.0: restoring config space at offset 0x3 (was 0x800000, writing 0x800004)
[26338.720509] pci 0000:03:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x800004)
[26338.720518] pci 0000:03:00.2: restoring config space at offset 0x1 (was 0x100007, writing 0x100003)
[26338.720591] jmb38x_ms 0000:03:00.3: restoring config space at offset 0x3 (was 0x800000, writing 0x800004)
[26338.721028] PM: early resume of devices complete after 16.967 msecs
[26338.721116] pci 0000:00:01.0: setting latency timer to 64
[26338.721135] pata_sis 0000:00:02.5: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[26338.721161] ohci_hcd 0000:00:03.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
[26338.721209] ohci_hcd 0000:00:03.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
[26338.721244] ehci_hcd 0000:00:03.3: PCI INT C -> GSI 22 (level, low) -> IRQ 22
[26338.721271] sis190 0000:00:04.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19
[26338.721276] sis190 0000:00:04.0: setting latency timer to 64
[26338.721285] sata_sis 0000:00:05.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
[26338.721307] HDA Intel 0000:00:0f.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
[26338.721315] HDA Intel 0000:00:0f.0: setting latency timer to 64
[26338.721352] sdhci-pci 0000:03:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[26338.721367] sdhci-pci 0000:03:00.0: setting latency timer to 64
[26338.721390] jmb38x_ms 0000:03:00.3: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[26338.721397] jmb38x_ms 0000:03:00.3: setting latency timer to 64
[26338.721426] ata2: port disabled. ignoring.
[26338.721586] sd 2:0:0:0: [sda] Starting disk
[26338.824049] PM: resume of drv:usb dev:usb3 complete after 102.557 msecs
[26338.824083] PM: resume of drv:hub dev:3-0:1.0 complete after 102.587 msecs
[26338.824088] PM: resume of drv: dev:ep_00 complete after 102.577 msecs
[26338.824097] PM: resume of drv: dev:ep_81 complete after 102.598 msecs
[26338.832046] PM: resume of drv:usb dev:usb2 complete after 110.579 msecs
[26338.832075] PM: resume of drv:hub dev:2-0:1.0 complete after 110.600 msecs
[26338.832080] PM: resume of drv: dev:ep_00 complete after 110.590 msecs
[26338.832088] PM: resume of drv: dev:ep_81 complete after 110.610 msecs
[26338.884043] PM: resume of drv:usb dev:usb1 complete after 162.607 msecs
[26338.884063] PM: resume of drv:hub dev:1-0:1.0 complete after 162.611 msecs
[26338.884077] PM: resume of drv: dev:ep_00 complete after 162.615 msecs
[26338.884083] PM: resume of drv: dev:ep_81 complete after 162.627 msecs
[26338.934683] PM: resume of drv:usb dev:1-7 complete after 211.788 msecs
[26338.934701] PM: resume of drv:uvcvideo dev:1-7:1.0 complete after 211.801 msecs
[26338.934720] PM: resume of drv: dev:ep_00 complete after 211.740 msecs
[26338.934725] PM: resume of drv:uvcvideo dev:1-7:1.1 complete after 211.773 msecs
[26338.934729] PM: resume of drv: dev:ep_82 complete after 211.818 msecs
[26338.996041] usb 1-2: reset high speed USB device using ehci_hcd and address 2
[26338.997719] PM: resume of drv:thermal dev:LNXTHERM:00 complete after 252.256 msecs
[26339.130940] PM: resume of drv:usb dev:1-2 complete after 409.409 msecs
[26339.130958] PM: resume of drv:usb dev:1-2:1.0 complete after 409.426 msecs
[26339.130979] PM: resume of drv: dev:ep_00 complete after 409.403 msecs
[26339.130999] PM: resume of drv: dev:ep_83 complete after 409.464 msecs
[26339.131003] PM: resume of drv: dev:ep_04 complete after 409.462 msecs
[26339.131007] PM: resume of drv: dev:ep_05 complete after 409.466 msecs
[26339.131011] PM: resume of drv: dev:ep_06 complete after 409.466 msecs
[26339.131015] PM: resume of drv: dev:ep_89 complete after 409.460 msecs
[26339.131019] PM: resume of drv: dev:ep_07 complete after 409.469 msecs
[26339.131023] PM: resume of drv: dev:ep_0b complete after 409.458 msecs
[26339.131028] PM: resume of drv: dev:ep_0a complete after 409.467 msecs
[26339.131045] PM: resume of drv: dev:ep_0c complete after 409.475 msecs
[26369.000040] ata3: lost interrupt (Status 0x50)
[26369.000061] ata3.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[26369.000066] ata3.00: failed command: READ VERIFY SECTOR(S)
[26369.000076] ata3.00: cmd 40/00:01:00:00:00/00:00:00:00:00/e0 tag 0
[26369.000077]          res 40/00:ff:00:00:00/00:00:00:00:00/40 Emask 0x4 (timeout)
[26369.000081] ata3.00: status: { DRDY }
[26369.000110] ata3: soft resetting link
[26369.204363] ata3.00: configured for UDMA/133
[26369.204369] ata3.00: device reported invalid CHS sector 0
[26369.204388] ata3: EH complete
[26369.204396] sd 2:0:0:0: [sda] START_STOP FAILED
[26369.204400] sd 2:0:0:0: [sda]  Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
[26369.204406] sd 2:0:0:0: [sda]  Sense Key : Aborted Command [current] [descriptor]
[26369.204413] sd 2:0:0:0: [sda]  Add. Sense: No additional sense information
[26369.204434] pm_op(): scsi_bus_resume_common+0x0/0x50 returns 134217730
[26369.204449] PM: Device 2:0:0:0 failed to resume async: error 134217730
[26369.204454] PM: resume of drv:scsi_device dev:2:0:0:0 complete after 30482.867 msecs
[26369.204459] PM: resume of drv:scsi_disk dev:2:0:0:0 complete after 30199.425 msecs
[26369.204588] PM: resume of devices complete after 30483.511 msecs
[26369.480249] cfg80211: Updating information on frequency 2412 MHz for a 20 MHz width channel with regulatory rule:
[26369.480254] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26369.480258] cfg80211: Updating information on frequency 2417 MHz for a 20 MHz width channel with regulatory rule:
[26369.480262] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26369.480266] cfg80211: Updating information on frequency 2422 MHz for a 20 MHz width channel with regulatory rule:
[26369.480271] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26369.480275] cfg80211: Updating information on frequency 2427 MHz for a 20 MHz width channel with regulatory rule:
[26369.480279] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26369.480283] cfg80211: Updating information on frequency 2432 MHz for a 20 MHz width channel with regulatory rule:
[26369.480288] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26369.480292] cfg80211: Updating information on frequency 2437 MHz for a 20 MHz width channel with regulatory rule:
[26369.480296] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26369.480300] cfg80211: Updating information on frequency 2442 MHz for a 20 MHz width channel with regulatory rule:
[26369.480304] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26369.480308] cfg80211: Updating information on frequency 2447 MHz for a 20 MHz width channel with regulatory rule:
[26369.480312] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26369.480316] cfg80211: Updating information on frequency 2452 MHz for a 20 MHz width channel with regulatory rule:
[26369.480320] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26369.480324] cfg80211: Updating information on frequency 2457 MHz for a 20 MHz width channel with regulatory rule:
[26369.480328] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26369.480332] cfg80211: Updating information on frequency 2462 MHz for a 20 MHz width channel with regulatory rule:
[26369.480336] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26369.480340] cfg80211: Updating information on frequency 2467 MHz for a 20 MHz width channel with regulatory rule:
[26369.480344] cfg80211: 2457000 KHz - 2482000 KHz @  KHz), (300 mBi, 2000 mBm)
[26369.480348] cfg80211: Updating information on frequency 2472 MHz for a 20 MHz width channel with regulatory rule:
[26369.480352] cfg80211: 2457000 KHz - 2482000 KHz @  KHz), (300 mBi, 2000 mBm)
[26369.480356] cfg80211: Updating information on frequency 2484 MHz for a 20 MHz width channel with regulatory rule:
[26369.480361] cfg80211: 2474000 KHz - 2494000 KHz @  KHz), (300 mBi, 2000 mBm)
[26369.480518] ieee80211 phy3: Selected rate control algorithm 'minstrel_ht'
[26369.481169] ieee80211 phy3: hwaddr 1c:4b:d6:26:6d:9b, RTL8187BvE V0 + rtl8225z2, rfkill mask 2
[26369.502679] rtl8187: Customer ID is 0x00
[26369.502738] Registered led device: rtl8187-phy3::radio
[26369.502780] Registered led device: rtl8187-phy3::tx
[26369.502822] Registered led device: rtl8187-phy3::rx
[26369.503543] rtl8187: wireless switch is on
[26369.503879] PM: resume devices took 30.780 seconds
[26369.503881] ------------[ cut here ]------------
[26369.503888] WARNING: at /build/buildd/linux-2.6.38/kernel/power/suspend_test.c:53 suspend_test_finish+0x89/0x90()
[26369.503891] Hardware name: POSITIVO MOBILE                 
[26369.503892] Component: resume devices, time: 30780
[26369.503894] Modules linked in: binfmt_misc parport_pc ppdev dm_crypt vesafb snd_hda_codec_si3054 snd_hda_codec_realtek joydev snd_hda_intel snd_hda_codec arc4 lp uvcvideo videodev snd_hwdep snd_pcm snd_seq_midi psmouse snd_rawmidi jmb38x_ms rtl8187 snd_seq_midi_event mac80211 snd_seq snd_timer snd_seq_device cfg80211 memstick eeprom_93cx6 shpchp serio_raw snd parport soundcore snd_page_alloc video sis190 sdhci_pci sata_sis sis_agp sdhci
[26369.503926] Pid: 26909, comm: pm-suspend Tainted: G        W   2.6.38-8-generic-pae #42-Ubuntu
[26369.503928] Call Trace:
[26369.503934]  [<c1058ce2>] ? warn_slowpath_common+0x72/0xa0
[26369.503937]  [<c1094429>] ? suspend_test_finish+0x89/0x90
[26369.503940]  [<c1094429>] ? suspend_test_finish+0x89/0x90
[26369.503943]  [<c1058db3>] ? warn_slowpath_fmt+0x33/0x40
[26369.503946]  [<c1094429>] ? suspend_test_finish+0x89/0x90
[26369.503949]  [<c109417e>] ? suspend_devices_and_enter+0x9e/0x150
[26369.503952]  [<c1094331>] ? enter_state+0x101/0x120
[26369.503954]  [<c10938a6>] ? state_store+0x86/0xc0
[26369.503957]  [<c1093820>] ? state_store+0x0/0xc0
[26369.503961]  [<c127e990>] ? kobj_attr_store+0x20/0x30
[26369.503966]  [<c118e39e>] ? sysfs_write_file+0x9e/0x100
[26369.503970]  [<c11328a2>] ? vfs_write+0xa2/0x170
[26369.503973]  [<c118e300>] ? sysfs_write_file+0x0/0x100
[26369.503976]  [<c1132b82>] ? sys_write+0x42/0x70
[26369.503979]  [<c100ab5f>] ? sysenter_do_call+0x12/0x28
[26369.503982] ---[ end trace b37694dd17db0708 ]---
[26369.504055] PM: Finishing wakeup.
[26369.504057] Restarting tasks ... done.
[26369.546742] video LNXVIDEO:00: Restoring backlight state
[26372.977358] ADDRCONF(NETDEV_UP): eth0: link is not ready
[26376.854564] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[26377.742072] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0
[26378.061634] EXT4-fs (sda5): re-mounted. Opts: commit=0
[26378.405825] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0
[26380.755319] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0
[26380.848425] EXT4-fs (sda5): re-mounted. Opts: commit=0
[26381.119778] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0
[26387.500194] PM: Syncing filesystems ... done.
[26387.528917] PM: Preparing system for mem sleep
[26387.528937] Freezing user space processes ... (elapsed 0.01 seconds) done.
[26387.544129] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
[26387.560100] PM: Entering mem sleep
[26387.560123] Suspending console(s) (use no_console_suspend to debug)
[26387.560685] sd 2:0:0:0: [sda] Synchronizing SCSI cache
[26387.623945] ACPI handle has no context!
[26387.623960] jmb38x_ms 0000:03:00.3: PCI INT A disabled
[26387.623964] ACPI handle has no context!
[26387.623971] ACPI handle has no context!
[26387.623977] sdhci-pci 0000:03:00.0: PCI INT A disabled
[26387.623986] ACPI handle has no context!
[26387.624462] sis190 0000:00:04.0: PCI INT A disabled
[26387.624478] ohci_hcd 0000:00:03.1: PCI INT B disabled
[26387.624481] ohci_hcd 0000:00:03.0: PCI INT A disabled
[26387.624556] pata_sis 0000:00:02.5: PCI INT A disabled
[26387.628382] sd 2:0:0:0: [sda] Stopping disk
[26387.712015] usb 1-2: reset high speed USB device using ehci_hcd and address 2
[26387.832065] HDA Intel 0000:00:0f.0: PCI INT A disabled
[26387.832093] ACPI handle has no context!
[26387.848014] PM: suspend of drv:HDA Intel dev:0000:00:0f.0 complete after 223.997 msecs
[26387.860012] PM: suspend of drv:usb dev:1-2 complete after 299.224 msecs
[26387.861122] PM: suspend of drv:usb dev:usb1 complete after 237.720 msecs
[26387.876023] ehci_hcd 0000:00:03.3: PCI INT C disabled
[26387.876026] PM: suspend of drv:ehci_hcd dev:0000:00:03.3 complete after 251.559 msecs
[26388.654495] PM: suspend of drv:sd dev:2:0:0:0 complete after 1093.815 msecs
[26388.654506] PM: suspend of drv:scsi dev:target2:0:0 complete after 1093.748 msecs
[26388.654521] PM: suspend of drv:scsi dev:host2 complete after 1093.599 msecs
[26388.654535] sata_sis 0000:00:05.0: PCI INT A disabled
[26388.654538] PM: suspend of drv:sata_sis dev:0000:00:05.0 complete after 1030.078 msecs
[26388.654546] PM: suspend of drv: dev:pci0000:00 complete after 1030.018 msecs
[26388.654554] PM: suspend of devices complete after 1094.039 msecs
[26388.654557] PM: suspend devices took 1.092 seconds
[26388.668158] PM: late suspend of devices complete after 13.596 msecs
[26388.668340] ACPI: Preparing to enter system sleep state S3
[26388.709319] PM: Saving platform NVS memory
[26388.709346] Disabling non-boot CPUs ...
[26388.812017] CPU 1 is now offline
[26388.812431] Extended CMOS year: 2000
[26388.812431] Back to C!
[26388.812431] PM: Restoring platform NVS memory
[26388.812431] Extended CMOS year: 2000
[26388.812431] Enabling non-boot CPUs ...
[26388.812431] Booting Node 0 Processor 1 APIC 0x1
[26388.710633] Initializing CPU#1
[26388.900586] CPU1 is up
[26388.901035] ACPI: Waking up from system sleep state S3
[26388.904022] Switched to NOHz mode on CPU #1
[26389.156183] pci 0000:00:01.0: restoring config space at offset 0x7 (was 0x209090, writing 0x20209090)
[26389.156228] pata_sis 0000:00:02.5: restoring config space at offset 0xf (was 0x1ff, writing 0x0)
[26389.156241] pata_sis 0000:00:02.5: restoring config space at offset 0x7 (was 0x375, writing 0x1)
[26389.156245] pata_sis 0000:00:02.5: restoring config space at offset 0x6 (was 0x171, writing 0x1)
[26389.156250] pata_sis 0000:00:02.5: restoring config space at offset 0x5 (was 0x3f5, writing 0x1)
[26389.156254] pata_sis 0000:00:02.5: restoring config space at offset 0x4 (was 0x1f1, writing 0x1)
[26389.156260] pata_sis 0000:00:02.5: restoring config space at offset 0x2 (was 0x1018a01, writing 0x1018001)
[26389.156291] ohci_hcd 0000:00:03.0: restoring config space at offset 0x1 (was 0x2800017, writing 0x2800013)
[26389.156317] ohci_hcd 0000:00:03.1: restoring config space at offset 0x1 (was 0x2800017, writing 0x2800013)
[26389.172019] ehci_hcd 0000:00:03.3: BAR 0: set to [mem 0xd8206000-0xd8206fff] (PCI address [0xd8206000-0xd8206fff])
[26389.172044] ehci_hcd 0000:00:03.3: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002)
[26389.172078] sis190 0000:00:04.0: restoring config space at offset 0x1 (was 0x2100007, writing 0x2100003)
[26389.172111] sata_sis 0000:00:05.0: restoring config space at offset 0x1 (was 0x2100005, writing 0x2100001)
[26389.172145] pcieport 0000:00:06.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd861d851)
[26389.172155] pcieport 0000:00:06.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004)
[26389.172160] pcieport 0000:00:06.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[26389.172203] pcieport 0000:00:07.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xd881d871)
[26389.172208] pcieport 0000:00:07.0: restoring config space at offset 0x7 (was 0x200001f1, writing 0x20002121)
[26389.172216] pcieport 0000:00:07.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004)
[26389.172222] pcieport 0000:00:07.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[26389.172264] HDA Intel 0000:00:0f.0: restoring config space at offset 0x1 (was 0x2100006, writing 0x2100002)
[26389.172287] pcieport 0000:00:1f.0: restoring config space at offset 0xf (was 0x0, writing 0x400ff)
[26389.172298] pcieport 0000:00:1f.0: restoring config space at offset 0x9 (was 0x1ff01, writing 0x1fff1)
[26389.172304] pcieport 0000:00:1f.0: restoring config space at offset 0x7 (was 0x101, writing 0x1f1)
[26389.172309] pcieport 0000:00:1f.0: restoring config space at offset 0x6 (was 0x0, writing 0x40400)
[26389.172316] pcieport 0000:00:1f.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10004)
[26389.172322] pcieport 0000:00:1f.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
[26389.172350] pci 0000:01:00.0: restoring config space at offset 0xf (was 0x100, writing 0x109)
[26389.172360] pci 0000:01:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x9001)
[26389.172364] pci 0000:01:00.0: restoring config space at offset 0x5 (was 0x0, writing 0xd8000000)
[26389.172367] pci 0000:01:00.0: restoring config space at offset 0x4 (was 0x8, writing 0xc0000008)
[26389.172373] pci 0000:01:00.0: restoring config space at offset 0x1 (was 0x2300000, writing 0x2300003)
[26389.172433] sdhci-pci 0000:03:00.0: restoring config space at offset 0x3 (was 0x800000, writing 0x800004)
[26389.172510] pci 0000:03:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x800004)
[26389.172518] pci 0000:03:00.2: restoring config space at offset 0x1 (was 0x100007, writing 0x100003)
[26389.172591] jmb38x_ms 0000:03:00.3: restoring config space at offset 0x3 (was 0x800000, writing 0x800004)
[26389.173029] PM: early resume of devices complete after 16.967 msecs
[26389.173111] pci 0000:00:01.0: setting latency timer to 64
[26389.173130] pata_sis 0000:00:02.5: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[26389.173157] ohci_hcd 0000:00:03.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
[26389.173202] ohci_hcd 0000:00:03.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
[26389.173219] ehci_hcd 0000:00:03.3: PCI INT C -> GSI 22 (level, low) -> IRQ 22
[26389.173238] sis190 0000:00:04.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19
[26389.173243] sis190 0000:00:04.0: setting latency timer to 64
[26389.173252] sata_sis 0000:00:05.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
[26389.173270] HDA Intel 0000:00:0f.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
[26389.173276] HDA Intel 0000:00:0f.0: setting latency timer to 64
[26389.173314] sdhci-pci 0000:03:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[26389.173330] sdhci-pci 0000:03:00.0: setting latency timer to 64
[26389.173354] jmb38x_ms 0000:03:00.3: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[26389.173361] jmb38x_ms 0000:03:00.3: setting latency timer to 64
[26389.173413] ata2: port disabled. ignoring.
[26389.173565] sd 2:0:0:0: [sda] Starting disk
[26389.284047] PM: resume of drv:usb dev:usb3 complete after 110.593 msecs
[26389.284054] PM: resume of drv:usb dev:usb2 complete after 110.621 msecs
[26389.284064] PM: resume of drv: dev:ep_00 complete after 110.597 msecs
[26389.284069] PM: resume of drv:hub dev:3-0:1.0 complete after 110.613 msecs
[26389.284094] PM: resume of drv: dev:ep_00 complete after 110.648 msecs
[26389.284098] PM: resume of drv:hub dev:2-0:1.0 complete after 110.663 msecs
[26389.284102] PM: resume of drv: dev:ep_81 complete after 110.641 msecs
[26389.284110] PM: resume of drv: dev:ep_81 complete after 110.671 msecs
[26389.336039] PM: resume of drv:usb dev:usb1 complete after 162.646 msecs
[26389.336057] PM: resume of drv:hub dev:1-0:1.0 complete after 162.659 msecs
[26389.336078] PM: resume of drv: dev:ep_00 complete after 162.654 msecs
[26389.336095] PM: resume of drv: dev:ep_81 complete after 162.692 msecs
[26389.386670] PM: resume of drv:usb dev:1-7 complete after 213.090 msecs
[26389.386687] PM: resume of drv:uvcvideo dev:1-7:1.0 complete after 181.105 msecs
[26389.386703] PM: resume of drv: dev:ep_00 complete after 181.105 msecs
[26389.386707] PM: resume of drv:uvcvideo dev:1-7:1.1 complete after 181.112 msecs
[26389.386722] PM: resume of drv: dev:ep_82 complete after 181.131 msecs
[26389.448204] usb 1-2: reset high speed USB device using ehci_hcd and address 2
[26389.582926] PM: resume of drv:usb dev:1-2 complete after 409.433 msecs
[26389.582957] PM: resume of drv:usb dev:1-2:1.0 complete after 409.462 msecs
[26389.582961] PM: resume of drv: dev:ep_00 complete after 409.402 msecs
[26389.582984] PM: resume of drv: dev:ep_04 complete after 409.470 msecs
[26389.582989] PM: resume of drv: dev:ep_06 complete after 409.464 msecs
[26389.582993] PM: resume of drv: dev:ep_05 complete after 409.474 msecs
[26389.582997] PM: resume of drv: dev:ep_0b complete after 409.453 msecs
[26389.583001] PM: resume of drv: dev:ep_07 complete after 409.473 msecs
[26389.583005] PM: resume of drv: dev:ep_89 complete after 409.471 msecs
[26389.583010] PM: resume of drv: dev:ep_0a complete after 409.471 msecs
[26389.583014] PM: resume of drv: dev:ep_83 complete after 409.499 msecs
[26389.583018] PM: resume of drv: dev:ep_0c complete after 409.469 msecs
[26420.004044] ata3: lost interrupt (Status 0x50)
[26420.004062] ata3.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[26420.004067] ata3.00: failed command: READ VERIFY SECTOR(S)
[26420.004077] ata3.00: cmd 40/00:01:00:00:00/00:00:00:00:00/e0 tag 0
[26420.004079]          res 40/00:ff:00:00:00/00:00:00:00:00/40 Emask 0x4 (timeout)
[26420.004083] ata3.00: status: { DRDY }
[26420.004110] ata3: soft resetting link
[26420.208363] ata3.00: configured for UDMA/133
[26420.208369] ata3.00: device reported invalid CHS sector 0
[26420.208386] ata3: EH complete
[26420.208395] sd 2:0:0:0: [sda] START_STOP FAILED
[26420.208399] sd 2:0:0:0: [sda]  Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
[26420.208405] sd 2:0:0:0: [sda]  Sense Key : Aborted Command [current] [descriptor]
[26420.208412] sd 2:0:0:0: [sda]  Add. Sense: No additional sense information
[26420.208431] pm_op(): scsi_bus_resume_common+0x0/0x50 returns 134217730
[26420.208444] PM: Device 2:0:0:0 failed to resume async: error 134217730
[26420.208449] PM: resume of drv:scsi_disk dev:2:0:0:0 complete after 30964.720 msecs
[26420.208454] PM: resume of drv:scsi_device dev:2:0:0:0 complete after 31034.889 msecs
[26420.208574] PM: resume of devices complete after 31035.500 msecs
[26420.484252] cfg80211: Updating information on frequency 2412 MHz for a 20 MHz width channel with regulatory rule:
[26420.484257] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26420.484261] cfg80211: Updating information on frequency 2417 MHz for a 20 MHz width channel with regulatory rule:
[26420.484265] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26420.484269] cfg80211: Updating information on frequency 2422 MHz for a 20 MHz width channel with regulatory rule:
[26420.484274] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26420.484278] cfg80211: Updating information on frequency 2427 MHz for a 20 MHz width channel with regulatory rule:
[26420.484282] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26420.484286] cfg80211: Updating information on frequency 2432 MHz for a 20 MHz width channel with regulatory rule:
[26420.484290] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26420.484294] cfg80211: Updating information on frequency 2437 MHz for a 20 MHz width channel with regulatory rule:
[26420.484298] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26420.484302] cfg80211: Updating information on frequency 2442 MHz for a 20 MHz width channel with regulatory rule:
[26420.484306] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26420.484310] cfg80211: Updating information on frequency 2447 MHz for a 20 MHz width channel with regulatory rule:
[26420.484315] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26420.484319] cfg80211: Updating information on frequency 2452 MHz for a 20 MHz width channel with regulatory rule:
[26420.484323] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26420.484327] cfg80211: Updating information on frequency 2457 MHz for a 20 MHz width channel with regulatory rule:
[26420.484331] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26420.484335] cfg80211: Updating information on frequency 2462 MHz for a 20 MHz width channel with regulatory rule:
[26420.484339] cfg80211: 2402000 KHz - 2472000 KHz @  KHz), (300 mBi, 2000 mBm)
[26420.484343] cfg80211: Updating information on frequency 2467 MHz for a 20 MHz width channel with regulatory rule:
[26420.484348] cfg80211: 2457000 KHz - 2482000 KHz @  KHz), (300 mBi, 2000 mBm)
[26420.484351] cfg80211: Updating information on frequency 2472 MHz for a 20 MHz width channel with regulatory rule:
[26420.484356] cfg80211: 2457000 KHz - 2482000 KHz @  KHz), (300 mBi, 2000 mBm)
[26420.484360] cfg80211: Updating information on frequency 2484 MHz for a 20 MHz width channel with regulatory rule:
[26420.484364] cfg80211: 2474000 KHz - 2494000 KHz @  KHz), (300 mBi, 2000 mBm)
[26420.484517] ieee80211 phy4: Selected rate control algorithm 'minstrel_ht'
[26420.485165] ieee80211 phy4: hwaddr 1c:4b:d6:26:6d:9b, RTL8187BvE V0 + rtl8225z2, rfkill mask 2
[26420.506684] rtl8187: Customer ID is 0x00
[26420.506742] Registered led device: rtl8187-phy4::radio
[26420.506786] Registered led device: rtl8187-phy4::tx
[26420.506830] Registered led device: rtl8187-phy4::rx
[26420.507548] rtl8187: wireless switch is on
[26420.507879] PM: resume devices took 31.332 seconds
[26420.507880] ------------[ cut here ]------------
[26420.507888] WARNING: at /build/buildd/linux-2.6.38/kernel/power/suspend_test.c:53 suspend_test_finish+0x89/0x90()
[26420.507890] Hardware name: POSITIVO MOBILE                 
[26420.507892] Component: resume devices, time: 31332
[26420.507893] Modules linked in: binfmt_misc parport_pc ppdev dm_crypt vesafb snd_hda_codec_si3054 snd_hda_codec_realtek joydev snd_hda_intel snd_hda_codec arc4 lp uvcvideo videodev snd_hwdep snd_pcm snd_seq_midi psmouse snd_rawmidi jmb38x_ms rtl8187 snd_seq_midi_event mac80211 snd_seq snd_timer snd_seq_device cfg80211 memstick eeprom_93cx6 shpchp serio_raw snd parport soundcore snd_page_alloc video sis190 sdhci_pci sata_sis sis_agp sdhci
[26420.507924] Pid: 27680, comm: pm-suspend Tainted: G        W   2.6.38-8-generic-pae #42-Ubuntu
[26420.507926] Call Trace:
[26420.507933]  [<c1058ce2>] ? warn_slowpath_common+0x72/0xa0
[26420.507936]  [<c1094429>] ? suspend_test_finish+0x89/0x90
[26420.507939]  [<c1094429>] ? suspend_test_finish+0x89/0x90
[26420.507942]  [<c1058db3>] ? warn_slowpath_fmt+0x33/0x40
[26420.507944]  [<c1094429>] ? suspend_test_finish+0x89/0x90
[26420.507947]  [<c109417e>] ? suspend_devices_and_enter+0x9e/0x150
[26420.507950]  [<c1094331>] ? enter_state+0x101/0x120
[26420.507953]  [<c10938a6>] ? state_store+0x86/0xc0
[26420.507955]  [<c1093820>] ? state_store+0x0/0xc0
[26420.507960]  [<c127e990>] ? kobj_attr_store+0x20/0x30
[26420.507964]  [<c118e39e>] ? sysfs_write_file+0x9e/0x100
[26420.507968]  [<c11328a2>] ? vfs_write+0xa2/0x170
[26420.507971]  [<c118e300>] ? sysfs_write_file+0x0/0x100
[26420.507974]  [<c1132b82>] ? sys_write+0x42/0x70
[26420.507977]  [<c100ab5f>] ? sysenter_do_call+0x12/0x28
[26420.507980] ---[ end trace b37694dd17db0709 ]---
[26420.508054] PM: Finishing wakeup.
[26420.508056] Restarting tasks ... done.
[26420.547336] video LNXVIDEO:00: Restoring backlight state
[26424.692486] ADDRCONF(NETDEV_UP): eth0: link is not ready
[26428.550579] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[26429.732385] EXT4-fs (sda6): re-mounted. Opts: errors=remount-ro,commit=0
[26429.931750] EXT4-fs (sda5): re-mounted. Opts: commit=0
[26430.108662] EXT4-fs (sda7): re-mounted. Opts: user_xattr,commit=0
[26433.393347] wlan0: authenticate with 00:26:5a:63:b3:2a (try 1)
[26433.395462] wlan0: authenticated
[26433.533225] wlan0: associate with 00:26:5a:63:b3:2a (try 1)
[26433.535486] wlan0: RX AssocResp from 00:26:5a:63:b3:2a (capab=0x431 status=0 aid=1)
[26433.535490] wlan0: associated
[26433.541164] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[26443.592025] wlan0: no IPv6 routers present