Re: 3.0-rc2 failed s2ram: Freezing of tasks failed after 20.00 seconds

[Rafael J. Wysocki]

On Tuesday, August 16, 2011, Carlos R. Mafra wrote:
> I started testing 3.0-rc2 yesterday and after a few successful suspend to ram
> it did not suspend anymore and I got this:
> 
>  PM: Syncing filesystems ... done.
>  PM: Preparing system for mem sleep
>  Freezing user space processes ... 
>  Freezing of tasks failed after 20.00 seconds (1 tasks refusing to freeze, wq_busy=0):
>  udisks-daemon   D ffff8800a641e5d0     0  5848   5845 0x00800004
>   ffff8800a6741928 0000000000000082 ffff880000000000 00000000000105c0
>   ffff8800a641e200 00000000000105c0 ffff8800a6741fd8 00000000000105c0
>   00000000000105c0 ffff8800a6740000 ffff8800a6741fd8 00000000000105c0
>  Call Trace:
>   [<ffffffff814cf355>] schedule_timeout+0x1c5/0x230
>   [<ffffffff814ce8c9>] ? schedule+0x399/0x8a0
>   [<ffffffff814ce3e0>] wait_for_common+0xc0/0x160
>   [<ffffffff8103ec60>] ? try_to_wake_up+0x290/0x290
>   [<ffffffff814d10ba>] ? _raw_spin_unlock_irq+0x2a/0x40
>   [<ffffffff814ce528>] wait_for_completion+0x18/0x20
>   [<ffffffff81059afb>] flush_work+0x2b/0x40
>   [<ffffffff81058fd0>] ? do_work_for_cpu+0x30/0x30
>   [<ffffffff8105a076>] flush_delayed_work+0x46/0x50
>   [<ffffffff8121e6b6>] disk_clear_events+0x76/0x110
>   [<ffffffff81106052>] check_disk_change+0x32/0x80
>   [<ffffffff812ee649>] sd_open+0xb9/0x190
>   [<ffffffff81106f81>] __blkdev_get+0x91/0x3d0
>   [<ffffffff81107600>] ? blkdev_get+0x340/0x340
>   [<ffffffff8110730e>] blkdev_get+0x4e/0x340
>   [<ffffffff810e1b17>] ? do_lookup+0xb7/0x380
>   [<ffffffff81107600>] ? blkdev_get+0x340/0x340
>   [<ffffffff8110765d>] blkdev_open+0x5d/0x80
>   [<ffffffff810d3d80>] __dentry_open+0x130/0x320
>   [<ffffffff810d5021>] nameidata_to_filp+0x71/0x80
>   [<ffffffff810e2ab1>] do_last+0xb1/0x800
>   [<ffffffff810e4603>] path_openat+0xd3/0x3f0
>   [<ffffffff81229077>] ? kobject_put+0x27/0x60
>   [<ffffffff812ced62>] ? put_device+0x12/0x20
>   [<ffffffff810e4984>] do_filp_open+0x44/0xa0
>   [<ffffffff810f0a44>] ? alloc_fd+0xf4/0x150
>   [<ffffffff810d512c>] do_sys_open+0xfc/0x1e0
>   [<ffffffff810d3a86>] ? filp_close+0x56/0x80
>   [<ffffffff810d522b>] sys_open+0x1b/0x20
>   [<ffffffff814d1c3b>] system_call_fastpath+0x16/0x1b
>  
>  Restarting tasks ... done.
>  video LNXVIDEO:01: Restoring backlight state
>  acpid: 1 client rule loaded
>  EXT4-fs (sda2): re-mounted. Opts: discard,commit=600
>  INFO: task udisks-daemon:5848 blocked for more than 120 seconds.
>  "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
>  udisks-daemon   D ffff8800a641e5d0     0  5848   5845 0x00000004
>   ffff8800a6741928 0000000000000082 ffff880000000000 00000000000105c0
>   ffff8800a641e200 00000000000105c0 ffff8800a6741fd8 00000000000105c0
>   00000000000105c0 ffff8800a6740000 ffff8800a6741fd8 00000000000105c0
>  Call Trace:
>   [<ffffffff814cf355>] schedule_timeout+0x1c5/0x230
>   [<ffffffff814ce8c9>] ? schedule+0x399/0x8a0
>   [<ffffffff814ce3e0>] wait_for_common+0xc0/0x160
>   [<ffffffff8103ec60>] ? try_to_wake_up+0x290/0x290
>   [<ffffffff814d10ba>] ? _raw_spin_unlock_irq+0x2a/0x40
>   [<ffffffff814ce528>] wait_for_completion+0x18/0x20
>   [<ffffffff81059afb>] flush_work+0x2b/0x40
>   [<ffffffff81058fd0>] ? do_work_for_cpu+0x30/0x30
>   [<ffffffff8105a076>] flush_delayed_work+0x46/0x50
>   [<ffffffff8121e6b6>] disk_clear_events+0x76/0x110
>   [<ffffffff81106052>] check_disk_change+0x32/0x80
>   [<ffffffff812ee649>] sd_open+0xb9/0x190
>   [<ffffffff81106f81>] __blkdev_get+0x91/0x3d0
>   [<ffffffff81107600>] ? blkdev_get+0x340/0x340
>   [<ffffffff8110730e>] blkdev_get+0x4e/0x340
>   [<ffffffff810e1b17>] ? do_lookup+0xb7/0x380
>   [<ffffffff81107600>] ? blkdev_get+0x340/0x340
>   [<ffffffff8110765d>] blkdev_open+0x5d/0x80
>   [<ffffffff810d3d80>] __dentry_open+0x130/0x320
>   [<ffffffff810d5021>] nameidata_to_filp+0x71/0x80
>   [<ffffffff810e2ab1>] do_last+0xb1/0x800
>   [<ffffffff810e4603>] path_openat+0xd3/0x3f0
>   [<ffffffff81229077>] ? kobject_put+0x27/0x60
>   [<ffffffff812ced62>] ? put_device+0x12/0x20
>   [<ffffffff810e4984>] do_filp_open+0x44/0xa0
>   [<ffffffff810f0a44>] ? alloc_fd+0xf4/0x150
>   [<ffffffff810d512c>] do_sys_open+0xfc/0x1e0
>   [<ffffffff810d3a86>] ? filp_close+0x56/0x80
>   [<ffffffff810d522b>] sys_open+0x1b/0x20
>   [<ffffffff814d1c3b>] system_call_fastpath+0x16/0x1b
>   
> 
> and there are some more of these traces in the logs, but they all look the 
> same.

It looks like udisks-daemon is waiting for a completion that's
never completed.

Do you use any removable storage devices?

Rafael
--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at  http://vger.kernel.org/majordomo-info.html
Please read the FAQ at  http://www.tux.org/lkml/