From mboxrd@z Thu Jan 1 00:00:00 1970 From: Kevin Hilman Subject: Re: Hang on Suspend to RAM with 2.6.36-rc4 Date: Tue, 14 Sep 2010 16:02:49 -0700 Message-ID: <87r5gwhqsm.fsf@deeprootsystems.com> References: Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Return-path: Received: from mail-pz0-f46.google.com ([209.85.210.46]:38622 "EHLO mail-pz0-f46.google.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1755749Ab0INXCx (ORCPT ); Tue, 14 Sep 2010 19:02:53 -0400 Received: by pzk34 with SMTP id 34so2572390pzk.19 for ; Tue, 14 Sep 2010 16:02:53 -0700 (PDT) In-Reply-To: (Laine Walker-Avina's message of "Tue, 14 Sep 2010 14:39:26 -0700") Sender: linux-omap-owner@vger.kernel.org List-Id: linux-omap@vger.kernel.org To: Laine Walker-Avina Cc: linux-omap@vger.kernel.org, Linux Kernel ARM List Laine Walker-Avina writes: > I just pulled the latest changes today from the linux-omap git tree, > and something appears to have broken suspend to RAM on my OMAP3503 > board. Is this on the master branch? What defconfig? When was this last working for you? -rc3? Looks like you may have your rootfs on MMC. Do you have CONFIG_MMC_UNSAFE_RESUME=y in your .config? Kevin > Here's a log: > > ~$ echo mem > /sys/power/state > [ 23.102416] PM: Syncing filesystems ... done. > [ 29.493927] PM: Preparing system for mem sleep > [ 29.500396] PM: Adding info for No Bus:vcs63 > [ 29.506774] PM: Adding info for No Bus:vcsa63 > [ 29.547424] mmc0: card bed5 removed > [ 29.550964] PM: Removing info for mmc:mmc0:bed5 > [ 37.001373] INFO: task init:1 blocked for more than 5 seconds. > [ 37.007415] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" > disables this message. > [ 37.015472] init D c02a8cb8 0 1 0 0x00000000 > [ 37.021942] [] (schedule+0x320/0x368) from [] > (do_get_write_access+0x2bc/0x52c) > [ 37.031250] [] (do_get_write_access+0x2bc/0x52c) from > [] (journal_get_write_access+0x24/0x38) > [ 37.041809] [] (journal_get_write_access+0x24/0x38) from > [] (__ext3_journal_get_write_access+0x1c/0x48) > [ 37.053222] [] > (__ext3_journal_get_write_access+0x1c/0x48) from [] > (ext3_reserve_inode_write+0x3c/0x80) > [ 37.064636] [] (ext3_reserve_inode_write+0x3c/0x80) from > [] (ext3_mark_inode_dirty+0x30/0x54) > [ 37.075164] [] (ext3_mark_inode_dirty+0x30/0x54) from > [] (ext3_dirty_inode+0x68/0x80) > [ 37.084991] [] (ext3_dirty_inode+0x68/0x80) from > [] (__mark_inode_dirty+0x2c/0x188) > [ 37.094665] [] (__mark_inode_dirty+0x2c/0x188) from > [] (touch_atime+0x114/0x140) > [ 37.104064] [] (touch_atime+0x114/0x140) from > [] (generic_file_aio_read+0x6e8/0x76c) > [ 37.113830] [] (generic_file_aio_read+0x6e8/0x76c) from > [] (do_sync_read+0xa0/0xe8) > [ 37.123474] [] (do_sync_read+0xa0/0xe8) from [] > (vfs_read+0xa8/0x130) > [ 37.131896] [] (vfs_read+0xa8/0x130) from [] > (sys_read+0x3c/0x68) > [ 37.139984] [] (sys_read+0x3c/0x68) from [] > (ret_fast_syscall+0x0/0x3c) > [ 37.148559] 1 lock held by init/1: > [ 37.152008] #0: (jbd_handle){+.+...}, at: [] > start_this_handle+0x314/0x3c8 > [ 37.160247] INFO: task mmcqd:320 blocked for more than 5 seconds. > [ 37.166534] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" > disables this message. > [ 37.174560] mmcqd D c02a8cb8 0 320 2 0x00000000 > [ 37.181030] [] (schedule+0x320/0x368) from [] > (__mmc_claim_host+0xbc/0x158) > [ 37.190002] [] (__mmc_claim_host+0xbc/0x158) from > [] (mmc_blk_issue_rw_rq+0x38/0x504) > [ 37.199829] [] (mmc_blk_issue_rw_rq+0x38/0x504) from > [] (mmc_queue_thread+0xdc/0xe0) > [ 37.209564] [] (mmc_queue_thread+0xdc/0xe0) from > [] (kthread+0x80/0x88) > [ 37.218200] [] (kthread+0x80/0x88) from [] > (kernel_thread_exit+0x0/0x8) > [ 37.226776] no locks held by mmcqd/320. > [ 37.230682] INFO: task sh:363 blocked for more than 5 seconds. > [ 37.236694] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" > disables this message. > [ 37.244720] sh D c02a8cb8 0 363 1 0x00000000 > [ 37.251220] [] (schedule+0x320/0x368) from [] > (log_wait_commit+0xb8/0x110) > [ 37.260101] [] (log_wait_commit+0xb8/0x110) from > [] (ext3_sync_fs+0x3c/0x44) > [ 37.269134] [] (ext3_sync_fs+0x3c/0x44) from [] > (__sync_filesystem+0x80/0x9c) > [ 37.278289] [] (__sync_filesystem+0x80/0x9c) from > [] (fsync_bdev+0x18/0x38) > [ 37.287261] [] (fsync_bdev+0x18/0x38) from [] > (invalidate_partition+0x18/0x34) > [ 37.296478] [] (invalidate_partition+0x18/0x34) from > [] (del_gendisk+0x28/0xd0) > [ 37.305786] [] (del_gendisk+0x28/0xd0) from [] > (mmc_blk_remove+0x20/0x40) > [ 37.314575] [] (mmc_blk_remove+0x20/0x40) from > [] (mmc_bus_remove+0x18/0x20) > [ 37.323608] [] (mmc_bus_remove+0x18/0x20) from > [] (__device_release_driver+0x84/0xd0) > [ 37.333435] [] (__device_release_driver+0x84/0xd0) from > [] (device_release_driver+0x20/0x2c) > [ 37.343902] [] (device_release_driver+0x20/0x2c) from > [] (bus_remove_device+0xa4/0xb4) > [ 37.353820] [] (bus_remove_device+0xa4/0xb4) from > [] (device_del+0x114/0x194) > [ 37.362945] [] (device_del+0x114/0x194) from [] > (mmc_remove_card+0x5c/0x70) > [ 37.371917] [] (mmc_remove_card+0x5c/0x70) from > [] (mmc_sd_remove+0x30/0x40) > [ 37.380950] [] (mmc_sd_remove+0x30/0x40) from > [] (mmc_pm_notify+0x88/0xd8) > [ 37.389831] [] (mmc_pm_notify+0x88/0xd8) from > [] (notifier_call_chain+0x2c/0x70) > [ 37.399230] [] (notifier_call_chain+0x2c/0x70) from > [] (__blocking_notifier_call_chain+0x48/0x5c) > [ 37.410125] [] (__blocking_notifier_call_chain+0x48/0x5c) > from [] (blocking_notifier_call_chain+0x14/0x18) > [ 37.421813] [] (blocking_notifier_call_chain+0x14/0x18) > from [] (pm_notifier_call_chain+0x14/0x2c) > [ 37.432769] [] (pm_notifier_call_chain+0x14/0x2c) from > [] (enter_state+0x7c/0x120) > [ 37.442352] [] (enter_state+0x7c/0x120) from [] > (state_store+0xa0/0xbc) > [ 37.450958] [] (state_store+0xa0/0xbc) from [] > (kobj_attr_store+0x18/0x1c) > [ 37.459838] [] (kobj_attr_store+0x18/0x1c) from > [] (sysfs_write_file+0x10c/0x140) > [ 37.469329] [] (sysfs_write_file+0x10c/0x140) from > [] (vfs_write+0xac/0x134) > [ 37.478363] [] (vfs_write+0xac/0x134) from [] > (sys_write+0x3c/0x68) > [ 37.486602] [] (sys_write+0x3c/0x68) from [] > (ret_fast_syscall+0x0/0x3c) > [ 37.495269] 6 locks held by sh/363: > [ 37.498931] #0: (&buffer->mutex){+.+.+.}, at: [] > sysfs_write_file+0x28/0x140 > [ 37.507324] #1: (s_active#14){.+.+.+}, at: [] > sysfs_write_file+0xe8/0x140 > [ 37.515441] #2: (pm_mutex){+.+.+.}, at: [] enter_state+0x20/0x120 > [ 37.522888] #3: ((pm_chain_head).rwsem){.+.+..}, at: [] > __blocking_notifier_call_chain+0x2c/0x5c > [ 37.533020] #4: (&__lockdep_no_validate__){+.+.+.}, at: > [] device_release_driver+0x18/0x2c > [ 37.542633] #5: (&type->s_umount_key#22){.+.+..}, at: > [] get_super+0x60/0xbc > [ 42.568206] INFO: task init:1 blocked for more than 5 seconds. > [ 42.574096] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" > disables this message. > [ 42.582153] init D c02a8cb8 0 1 0 0x00000000 > [ 42.588623] [] (schedule+0x320/0x368) from [] > (do_get_write_access+0x2bc/0x52c) > [ 42.597961] [] (do_get_write_access+0x2bc/0x52c) from > [] (journal_get_write_access+0x24/0x38) > [ 42.608489] [] (journal_get_write_access+0x24/0x38) from > [] (__ext3_journal_get_write_access+0x1c/0x48) > [ 42.619903] [] > (__ext3_journal_get_write_access+0x1c/0x48) from [] > (ext3_reserve_inode_write+0x3c/0x80) > [ 42.631317] [] (ext3_reserve_inode_write+0x3c/0x80) from > [] (ext3_mark_inode_dirty+0x30/0x54) > [ 42.641845] [] (ext3_mark_inode_dirty+0x30/0x54) from > [] (ext3_dirty_inode+0x68/0x80) > [ 42.651702] [] (ext3_dirty_inode+0x68/0x80) from > [] (__mark_inode_dirty+0x2c/0x188) > [ 42.661376] [] (__mark_inode_dirty+0x2c/0x188) from > [] (touch_atime+0x114/0x140) > [ 42.670776] [] (touch_atime+0x114/0x140) from > [] (generic_file_aio_read+0x6e8/0x76c) > [ 42.680511] [] (generic_file_aio_read+0x6e8/0x76c) from > [] (do_sync_read+0xa0/0xe8) > [ 42.690155] [] (do_sync_read+0xa0/0xe8) from [] > (vfs_read+0xa8/0x130) > [ 42.698608] [] (vfs_read+0xa8/0x130) from [] > (sys_read+0x3c/0x68) > [ 42.706695] [] (sys_read+0x3c/0x68) from [] > (ret_fast_syscall+0x0/0x3c) > [ 42.715301] 1 lock held by init/1: > [ 42.718872] #0: (jbd_handle){+.+...}, at: [] > start_this_handle+0x314/0x3c8 > [ 42.727142] INFO: task mmcqd:320 blocked for more than 5 seconds. > [ 42.733428] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" > disables this message. > [ 42.741455] mmcqd D c02a8cb8 0 320 2 0x00000000 > [ 42.747924] [] (schedule+0x320/0x368) from [] > (__mmc_claim_host+0xbc/0x158) > [ 42.756866] [] (__mmc_claim_host+0xbc/0x158) from > [] (mmc_blk_issue_rw_rq+0x38/0x504) > [ 42.766693] [] (mmc_blk_issue_rw_rq+0x38/0x504) from > [] (mmc_queue_thread+0xdc/0xe0) > [ 42.776458] [] (mmc_queue_thread+0xdc/0xe0) from > [] (kthread+0x80/0x88) > [ 42.785064] [] (kthread+0x80/0x88) from [] > (kernel_thread_exit+0x0/0x8) > [ 42.793670] no locks held by mmcqd/320. > [ 42.797698] INFO: task kjournald:325 blocked for more than 5 seconds. > [ 42.804351] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" > disables this message. > [ 42.812377] kjournald D c02a8cb8 0 325 2 0x00000000 > [ 42.818847] [] (schedule+0x320/0x368) from [] > (io_schedule+0x44/0x70) > [ 42.827301] [] (io_schedule+0x44/0x70) from [] > (sync_buffer+0x44/0x4c) > [ 42.835845] [] (sync_buffer+0x44/0x4c) from [] > (__wait_on_bit+0x54/0x9c) > [ 42.844543] [] (__wait_on_bit+0x54/0x9c) from > [] (out_of_line_wait_on_bit+0x78/0x84) > [ 42.854309] [] (out_of_line_wait_on_bit+0x78/0x84) from > [] (journal_commit_transaction+0xcd0/0x1450) > [ 42.865447] [] (journal_commit_transaction+0xcd0/0x1450) > from [] (kjournald+0xbc/0x218) > [ 42.875488] [] (kjournald+0xbc/0x218) from [] > (kthread+0x80/0x88) > [ 42.883575] [] (kthread+0x80/0x88) from [] > (kernel_thread_exit+0x0/0x8) > [ 42.892181] no locks held by kjournald/325. > [ 42.896575] INFO: task sh:363 blocked for more than 5 seconds. > [ 42.902465] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" > disables this message. > [ 42.910491] sh D c02a8cb8 0 363 1 0x00000000 > [ 42.916961] [] (schedule+0x320/0x368) from [] > (log_wait_commit+0xb8/0x110) > [ 42.925842] [] (log_wait_commit+0xb8/0x110) from > [] (ext3_sync_fs+0x3c/0x44) > [ 42.934967] [] (ext3_sync_fs+0x3c/0x44) from [] > (__sync_filesystem+0x80/0x9c) > [ 42.944122] [] (__sync_filesystem+0x80/0x9c) from > [] (fsync_bdev+0x18/0x38) > [ 42.953094] [] (fsync_bdev+0x18/0x38) from [] > (invalidate_partition+0x18/0x34) > [ 42.962310] [] (invalidate_partition+0x18/0x34) from > [] (del_gendisk+0x28/0xd0) > [ 42.971618] [] (del_gendisk+0x28/0xd0) from [] > (mmc_blk_remove+0x20/0x40) > [ 42.980407] [] (mmc_blk_remove+0x20/0x40) from > [] (mmc_bus_remove+0x18/0x20) > [ 42.989471] [] (mmc_bus_remove+0x18/0x20) from > [] (__device_release_driver+0x84/0xd0) > [ 42.999298] [] (__device_release_driver+0x84/0xd0) from > [] (device_release_driver+0x20/0x2c) > [ 43.009735] [] (device_release_driver+0x20/0x2c) from > [] (bus_remove_device+0xa4/0xb4) > [ 43.019653] [] (bus_remove_device+0xa4/0xb4) from > [] (device_del+0x114/0x194) > [ 43.028808] [] (device_del+0x114/0x194) from [] > (mmc_remove_card+0x5c/0x70) > [ 43.037750] [] (mmc_remove_card+0x5c/0x70) from > [] (mmc_sd_remove+0x30/0x40) > [ 43.046813] [] (mmc_sd_remove+0x30/0x40) from > [] (mmc_pm_notify+0x88/0xd8) > [ 43.055694] [] (mmc_pm_notify+0x88/0xd8) from > [] (notifier_call_chain+0x2c/0x70) > [ 43.065093] [] (notifier_call_chain+0x2c/0x70) from > [] (__blocking_notifier_call_chain+0x48/0x5c) > [ 43.076019] [] (__blocking_notifier_call_chain+0x48/0x5c) > from [] (blocking_notifier_call_chain+0x14/0x18) > [ 43.087677] [] (blocking_notifier_call_chain+0x14/0x18) > from [] (pm_notifier_call_chain+0x14/0x2c) > [ 43.098663] [] (pm_notifier_call_chain+0x14/0x2c) from > [] (enter_state+0x7c/0x120) > [ 43.108245] [] (enter_state+0x7c/0x120) from [] > (state_store+0xa0/0xbc) > [ 43.116851] [] (state_store+0xa0/0xbc) from [] > (kobj_attr_store+0x18/0x1c) > [ 43.125732] [] (kobj_attr_store+0x18/0x1c) from > [] (sysfs_write_file+0x10c/0x140) > [ 43.135223] [] (sysfs_write_file+0x10c/0x140) from > [] (vfs_write+0xac/0x134) > [ 43.144256] [] (vfs_write+0xac/0x134) from [] > (sys_write+0x3c/0x68) > [ 43.152526] [] (sys_write+0x3c/0x68) from [] > (ret_fast_syscall+0x0/0x3c) > [ 43.161193] 6 locks held by sh/363: > [ 43.164855] #0: (&buffer->mutex){+.+.+.}, at: [] > sysfs_write_file+0x28/0x140 > [ 43.173278] #1: (s_active#14){.+.+.+}, at: [] > sysfs_write_file+0xe8/0x140 > [ 43.181427] #2: (pm_mutex){+.+.+.}, at: [] enter_state+0x20/0x120 > [ 43.188842] #3: ((pm_chain_head).rwsem){.+.+..}, at: [] > __blocking_notifier_call_chain+0x2c/0x5c > [ 43.199005] #4: (&__lockdep_no_validate__){+.+.+.}, at: > [] device_release_driver+0x18/0x2c > [ 43.208770] #5: (&type->s_umount_key#22){.+.+..}, at: > [] get_super+0x60/0xbc > [ 48.229949] INFO: task init:1 blocked for more than 5 seconds. > [ 48.235992] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" > disables this message. > [ 48.244049] init D c02a8cb8 0 1 0 0x00000000 > [ 48.250518] [] (schedule+0x320/0x368) from [] > (do_get_write_access+0x2bc/0x52c) > [ 48.259826] [] (do_get_write_access+0x2bc/0x52c) from > [] (journal_get_write_access+0x24/0x38) > [ 48.270385] [] (journal_get_write_access+0x24/0x38) from > [] (__ext3_journal_get_write_access+0x1c/0x48) > [ 48.281799] [] > (__ext3_journal_get_write_access+0x1c/0x48) from [] > (ext3_reserve_inode_write+0x3c/0x80) > [ 48.293212] [] (ext3_reserve_inode_write+0x3c/0x80) from > [] (ext3_mark_inode_dirty+0x30/0x54) > [ 48.303741] [] (ext3_mark_inode_dirty+0x30/0x54) from > [] (ext3_dirty_inode+0x68/0x80) > [ 48.313568] [] (ext3_dirty_inode+0x68/0x80) from > [] (__mark_inode_dirty+0x2c/0x188) > [ 48.323242] [] (__mark_inode_dirty+0x2c/0x188) from > [] (touch_atime+0x114/0x140) > [ 48.332672] [] (touch_atime+0x114/0x140) from > [] (generic_file_aio_read+0x6e8/0x76c) > [ 48.342437] [] (generic_file_aio_read+0x6e8/0x76c) from > [] (do_sync_read+0xa0/0xe8) > [ 48.352081] [] (do_sync_read+0xa0/0xe8) from [] > (vfs_read+0xa8/0x130) > [ 48.360504] [] (vfs_read+0xa8/0x130) from [] > (sys_read+0x3c/0x68) > [ 48.368591] [] (sys_read+0x3c/0x68) from [] > (ret_fast_syscall+0x0/0x3c) > [ 48.377166] 1 lock held by init/1: > [ 48.380767] #0: (jbd_handle){+.+...}, at: [] > start_this_handle+0x314/0x3c8 > [ 48.389007] INFO: task mmcqd:320 blocked for more than 5 seconds. > [ 48.395172] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" > disables this message. > [ 48.403198] mmcqd D c02a8cb8 0 320 2 0x00000000 > [ 48.409667] [] (schedule+0x320/0x368) from [] > (__mmc_claim_host+0xbc/0x158) > [ 48.418640] [] (__mmc_claim_host+0xbc/0x158) from > [] (mmc_blk_issue_rw_rq+0x38/0x504) > [ 48.428466] [] (mmc_blk_issue_rw_rq+0x38/0x504) from > [] (mmc_queue_thread+0xdc/0xe0) > [ 48.438232] [] (mmc_queue_thread+0xdc/0xe0) from > [] (kthread+0x80/0x88) > [ 48.446838] [] (kthread+0x80/0x88) from [] > (kernel_thread_exit+0x0/0x8) > [ 48.455444] no locks held by mmcqd/320. > [ 48.459472] INFO: task kjournald:325 blocked for more than 5 seconds. > [ 48.465972] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" > disables this message. > [ 48.473999] kjournald D c02a8cb8 0 325 2 0x00000000 > [ 48.480468] [] (schedule+0x320/0x368) from [] > (io_schedule+0x44/0x70) > [ 48.488922] [] (io_schedule+0x44/0x70) from [] > (sync_buffer+0x44/0x4c) > [ 48.497467] [] (sync_buffer+0x44/0x4c) from [] > (__wait_on_bit+0x54/0x9c) > [ 48.506164] [] (__wait_on_bit+0x54/0x9c) from > [] (out_of_line_wait_on_bit+0x78/0x84) > [ 48.515930] [] (out_of_line_wait_on_bit+0x78/0x84) from > [] (journal_commit_transaction+0xcd0/0x1450) > [ 48.527069] [] (journal_commit_transaction+0xcd0/0x1450) > from [] (kjournald+0xbc/0x218) > [ 48.537109] [] (kjournald+0xbc/0x218) from [] > (kthread+0x80/0x88) > [ 48.545196] [] (kthread+0x80/0x88) from [] > (kernel_thread_exit+0x0/0x8) > [ 48.553802] no locks held by kjournald/325.