upgrade to intrepid broke hibernate/suspend ("2 tasks refusing to freeze") (nfs related?)

[András Korn]

Binary package hint: linux-image-2.6.27-7-generic

Hi,

I just upgraded a desktop box to intrepid.

Suspending used to work flawlessly before (at least with 2.6.24-12-generic). Indeed it still works if I just boot the same system, but the old kernel.

When attempting to suspend 2.6.27-7-generic via Gnome, the screen goes to text mode for 20 seconds, then a kernel message appears complaining "2 tasks refusing to freeze" and after a while the Gnome desktop comes back. The 2 tasks are gnome-panel and gvfsd-trash. (On some occasions, it's only 1 task: gnome-panel.)

The lines from the kernel log that appear relevant read as follows:

Nov 2 17:50:58 seraph kernel: [ 400.478367] forcedeth 0000:00:08.0: PCI INT A disabled
Nov 2 17:50:59 seraph kernel: [ 401.578875] tuner-simple 2-0061: destroying instance
Nov 2 17:50:59 seraph kernel: [ 401.579116] ivtv 0000:01:07.0: PCI INT A disabled
Nov 2 17:50:59 seraph kernel: [ 401.579125] ivtv0: Removed Hauppauge WinTV PVR-250, card #0
Nov 2 17:51:01 seraph kernel: [ 403.763935] PM: Syncing filesystems ... done.
Nov 2 17:51:01 seraph kernel: [ 403.769763] PM: Preparing system for mem sleep
[...]
Nov 2 17:51:21 seraph kernel: [ 423.787150] gnome-panel D 00000000 0 6336 6213
Nov 2 17:51:21 seraph kernel: [ 423.787153] f32ddbf8 00000086 f32ddbe4 00000000 00000000 00004040 f4afd004 000000a4
Nov 2 17:51:21 seraph kernel: [ 423.787160] 000000a4 00000000 f3f6b240 f32ddbe0 fa1aead2 f3a85274 00000001 00000000
Nov 2 17:51:21 seraph kernel: [ 423.787166] c050f080 001ba324 f32dc000 f3f6b4b8 c221cd00 00000001 00006326 c221cd00
Nov 2 17:51:21 seraph kernel: [ 423.787172] Call Trace:
Nov 2 17:51:21 seraph kernel: [ 423.787175] [<fa1aead2>] ? xs_sendpages+0x72/0x210 [sunrpc]
Nov 2 17:51:21 seraph kernel: [ 423.787191] [<c037e866>] ? _spin_unlock_bh+0x16/0x20
Nov 2 17:51:21 seraph kernel: [ 423.787195] [<fa1b1c5d>] rpc_wait_bit_killable+0x1d/0x50 [sunrpc]
Nov 2 17:51:21 seraph kernel: [ 423.787209] [<c037d2ef>] __wait_on_bit+0x4f/0x70
Nov 2 17:51:21 seraph kernel: [ 423.787213] [<fa1b1c40>] ? rpc_wait_bit_killable+0x0/0x50 [sunrpc]
Nov 2 17:51:21 seraph kernel: [ 423.787228] [<c037d3bd>] out_of_line_wait_on_bit+0xad/0xc0
Nov 2 17:51:21 seraph kernel: [ 423.787231] [<fa1b1c40>] ? rpc_wait_bit_killable+0x0/0x50 [sunrpc]
Nov 2 17:51:21 seraph kernel: [ 423.787246] [<c0147500>] ? wake_bit_function+0x0/0x60
Nov 2 17:51:21 seraph kernel: [ 423.787250] [<fa1b26ae>] __rpc_execute+0xae/0x250 [sunrpc]
Nov 2 17:51:21 seraph kernel: [ 423.787265] [<fa1b28c9>] rpc_execute+0x79/0x90 [sunrpc]
Nov 2 17:51:21 seraph kernel: [ 423.787279] [<fa1ab743>] rpc_run_task+0x33/0x70 [sunrpc]
Nov 2 17:51:21 seraph kernel: [ 423.787293] [<fa1ab8a0>] rpc_call_sync+0x40/0x70 [sunrpc]
Nov 2 17:51:21 seraph kernel: [ 423.787316] [<fa249f13>] nfs3_rpc_wrapper+0x23/0x60 [nfs]
Nov 2 17:51:21 seraph kernel: [ 423.787334] [<fa24a3b3>] nfs3_proc_access+0xb3/0x160 [nfs]
Nov 2 17:51:21 seraph kernel: [ 423.787351] [<f8a88461>] ? xfs_bmap_search_multi_extents+0x81/0x100 [xfs]
Nov 2 17:51:21 seraph kernel: [ 423.787375] [<f8a88545>] ? xfs_bmap_search_extents+0x65/0x140 [xfs]
Nov 2 17:51:21 seraph kernel: [ 423.787393] [<c012b6ab>] ? default_wake_function+0xb/0x10
Nov 2 17:51:21 seraph kernel: [ 423.787397] [<c0121670>] ? __wake_up_common+0x40/0x70
Nov 2 17:51:21 seraph kernel: [ 423.787406] [<f8a8dfee>] ? xfs_bmapi+0x2fe/0x1520 [xfs]
Nov 2 17:51:21 seraph kernel: [ 423.787424] [<c037e62d>] ? _spin_lock+0xd/0x10
Nov 2 17:51:21 seraph kernel: [ 423.787433] [<fa237a94>] nfs_do_access+0xb4/0x1b0 [nfs]
Nov 2 17:51:21 seraph kernel: [ 423.787447] [<fa237c16>] nfs_permission+0x86/0x160 [nfs]
Nov 2 17:51:21 seraph kernel: [ 423.787460] [<f8ad4e70>] ? xfs_vn_put_link+0x0/0x30 [xfs]
Nov 2 17:51:21 seraph kernel: [ 423.787482] [<c01b9cf9>] __inode_permission+0x69/0xc0
Nov 2 17:51:21 seraph kernel: [ 423.787486] [<c01b9d71>] path_permission+0x21/0x40
Nov 2 17:51:21 seraph kernel: [ 423.787489] [<c01bbaef>] __link_path_walk+0x7f/0xb40
Nov 2 17:51:21 seraph kernel: [ 423.787493] [<c01bca14>] path_walk+0x54/0xb0
Nov 2 17:51:21 seraph kernel: [ 423.787496] [<c01bcbc6>] do_path_lookup+0xb6/0x1a0
Nov 2 17:51:21 seraph kernel: [ 423.787500] [<c01bd7da>] user_path_at+0x4a/0x80
Nov 2 17:51:21 seraph kernel: [ 423.787504] [<c01e207c>] ? inotify_inode_queue_event+0xbc/0xe0
Nov 2 17:51:21 seraph kernel: [ 423.787511] [<f8acb4c8>] ? xfs_release+0x108/0x1f0 [xfs]
Nov 2 17:51:21 seraph kernel: [ 423.787532] [<c01e1fce>] ? inotify_inode_queue_event+0xe/0xe0
Nov 2 17:51:21 seraph kernel: [ 423.787537] [<c01b5d03>] vfs_lstat_fd+0x23/0x50
Nov 2 17:51:21 seraph kernel: [ 423.787540] [<c01b5dc6>] vfs_lstat+0x16/0x20
Nov 2 17:51:21 seraph kernel: [ 423.787544] [<c01b5de9>] sys_lstat64+0x19/0x30
Nov 2 17:51:21 seraph kernel: [ 423.787548] [<c01b358f>] ? fput+0x1f/0x30
Nov 2 17:51:21 seraph kernel: [ 423.787551] [<c01afe8e>] ? filp_close+0x4e/0x80
Nov 2 17:51:21 seraph kernel: [ 423.787555] [<c01aff3f>] ? sys_close+0x7f/0xd0
Nov 2 17:51:21 seraph kernel: [ 423.787558] [<c0103f7b>] sysenter_do_call+0x12/0x2f
[...]
Nov 2 17:51:21 seraph kernel: [ 423.789719] gvfsd-trash D f7677b90 0 7159 1
Nov 2 17:51:21 seraph kernel: [ 423.789722] f7677bf8 00000086 f7677b90 f7677b90 00000246 f4ab3204 c054e500 c054e500
Nov 2 17:51:21 seraph kernel: [ 423.789728] f7677bb0 c013c91c f63e0000 001778bd 00000000 f4ab3234 00000000 f2ea16c0
Nov 2 17:51:21 seraph kernel: [ 423.789735] c050f080 f2f54000 f7676000 f63e0278 c2211d00 00000000 c0137548 c2211d00
Nov 2 17:51:21 seraph kernel: [ 423.789741] Call Trace:
Nov 2 17:51:21 seraph kernel: [ 423.789743] [<c013c91c>] ? __mod_timer+0xac/0xf0
Nov 2 17:51:21 seraph kernel: [ 423.789747] [<c0137548>] ? local_bh_disable+0x8/0x20
Nov 2 17:51:21 seraph kernel: [ 423.789752] [<fa1b1c5d>] rpc_wait_bit_killable+0x1d/0x50 [sunrpc]
Nov 2 17:51:21 seraph kernel: [ 423.789770] [<c037d2ef>] __wait_on_bit+0x4f/0x70
Nov 2 17:51:21 seraph kernel: [ 423.789774] [<fa1b1c40>] ? rpc_wait_bit_killable+0x0/0x50 [sunrpc]
Nov 2 17:51:21 seraph kernel: [ 423.789788] [<c037d3bd>] out_of_line_wait_on_bit+0xad/0xc0
Nov 2 17:51:21 seraph kernel: [ 423.789792] [<fa1b1c40>] ? rpc_wait_bit_killable+0x0/0x50 [sunrpc]
Nov 2 17:51:21 seraph kernel: [ 423.789806] [<c0147500>] ? wake_bit_function+0x0/0x60
Nov 2 17:51:21 seraph kernel: [ 423.789810] [<fa1b26ae>] __rpc_execute+0xae/0x250 [sunrpc]
Nov 2 17:51:21 seraph kernel: [ 423.789825] [<fa1b28c9>] rpc_execute+0x79/0x90 [sunrpc]
Nov 2 17:51:21 seraph kernel: [ 423.789839] [<fa1ab743>] rpc_run_task+0x33/0x70 [sunrpc]
Nov 2 17:51:21 seraph kernel: [ 423.789853] [<fa1ab8a0>] rpc_call_sync+0x40/0x70 [sunrpc]
Nov 2 17:51:21 seraph kernel: [ 423.789874] [<fa249f13>] nfs3_rpc_wrapper+0x23/0x60 [nfs]
Nov 2 17:51:21 seraph kernel: [ 423.789892] [<fa24a3b3>] nfs3_proc_access+0xb3/0x160 [nfs]
Nov 2 17:51:21 seraph kernel: [ 423.789906] [<c0189e8a>] ? buffered_rmqueue+0x1aa/0x2b0
Nov 2 17:51:21 seraph kernel: [ 423.789911] [<c01843e3>] ? find_get_page+0x23/0xb0
Nov 2 17:51:21 seraph kernel: [ 423.789916] [<c018487e>] ? find_lock_page+0x2e/0x70
Nov 2 17:51:21 seraph kernel: [ 423.789920] [<c018de89>] ? mark_page_accessed+0x9/0x60
Nov 2 17:51:21 seraph kernel: [ 423.789925] [<c0185d78>] ? filemap_fault+0x1c8/0x3f0
Nov 2 17:51:21 seraph kernel: [ 423.789928] [<c037e62d>] ? _spin_lock+0xd/0x10
Nov 2 17:51:21 seraph kernel: [ 423.789938] [<fa237a94>] nfs_do_access+0xb4/0x1b0 [nfs]
Nov 2 17:51:21 seraph kernel: [ 423.789950] [<fa237c16>] nfs_permission+0x86/0x160 [nfs]
Nov 2 17:51:21 seraph kernel: [ 423.789962] [<c01c626f>] ? dput+0x8f/0x140
Nov 2 17:51:21 seraph kernel: [ 423.789966] [<c01b9cf9>] __inode_permission+0x69/0xc0
Nov 2 17:51:21 seraph kernel: [ 423.789970] [<c01b9d71>] path_permission+0x21/0x40
Nov 2 17:51:21 seraph kernel: [ 423.789973] [<c01bbaef>] __link_path_walk+0x7f/0xb40
Nov 2 17:51:21 seraph kernel: [ 423.789977] [<c014ba18>] ? up_read+0x8/0x20
Nov 2 17:51:21 seraph kernel: [ 423.789981] [<c01bca14>] path_walk+0x54/0xb0
Nov 2 17:51:21 seraph kernel: [ 423.789984] [<c01bcbc6>] do_path_lookup+0xb6/0x1a0
Nov 2 17:51:21 seraph kernel: [ 423.789988] [<c01bd7da>] user_path_at+0x4a/0x80
Nov 2 17:51:21 seraph kernel: [ 423.789991] [<c019911e>] ? remove_vma+0x4e/0x70
Nov 2 17:51:21 seraph kernel: [ 423.789995] [<c037e5f8>] ? _write_lock_irq+0x8/0x30
Nov 2 17:51:21 seraph kernel: [ 423.789999] [<c01b5d03>] vfs_lstat_fd+0x23/0x50
Nov 2 17:51:21 seraph kernel: [ 423.790003] [<c01b5dc6>] vfs_lstat+0x16/0x20
Nov 2 17:51:21 seraph kernel: [ 423.790006] [<c01b5de9>] sys_lstat64+0x19/0x30
Nov 2 17:51:21 seraph kernel: [ 423.790010] [<c03808a0>] ? do_page_fault+0x0/0x720
Nov 2 17:51:21 seraph kernel: [ 423.790014] [<c037e9e2>] ? error_code+0x72/0x80
Nov 2 17:51:21 seraph kernel: [ 423.790018] [<c02540b1>] ? __put_user_4+0x11/0x20
Nov 2 17:51:21 seraph kernel: [ 423.790023] [<c0103f7b>] sysenter_do_call+0x12/0x2f

(There are several gvfsd-trash processes, but this is the one I believe is failing to freeze.)

Nov 2 17:51:21 seraph kernel: [ 423.790031] Sched Debug Version: v0.07, 2.6.27-7-generic #1
Nov 2 17:51:21 seraph kernel: [ 423.790033] now at 423790.031729 msecs
Nov 2 17:51:21 seraph kernel: [ 423.790036] .sysctl_sched_latency : 40.000000
Nov 2 17:51:21 seraph kernel: [ 423.790038] .sysctl_sched_min_granularity : 8.000000
Nov 2 17:51:21 seraph kernel: [ 423.790041] .sysctl_sched_wakeup_granularity : 10.000000
Nov 2 17:51:21 seraph kernel: [ 423.790043] .sysctl_sched_child_runs_first : 0.000001
Nov 2 17:51:21 seraph kernel: [ 423.790045] .sysctl_sched_features : 7935
Nov 2 17:51:21 seraph kernel: [ 423.790048]
Nov 2 17:51:21 seraph kernel: [ 423.790049] cpu#0, 2009.222 MHz
Nov 2 17:51:21 seraph kernel: [ 423.790051] .nr_running : 0
Nov 2 17:51:21 seraph kernel: [ 423.790053] .load : 0
Nov 2 17:51:21 seraph kernel: [ 423.790055] .nr_switches : 149885
Nov 2 17:51:21 seraph kernel: [ 423.790057] .nr_load_updates : 25346
Nov 2 17:51:21 seraph kernel: [ 423.790059] .nr_uninterruptible : 503
Nov 2 17:51:21 seraph kernel: [ 423.790061] .jiffies : 30947
Nov 2 17:51:21 seraph kernel: [ 423.790063] .next_balance : 0.030948
Nov 2 17:51:21 seraph kernel: [ 423.790065] .curr->pid : 0
Nov 2 17:51:21 seraph kernel: [ 423.790068] .clock : 423788.006334
Nov 2 17:51:21 seraph kernel: [ 423.790070] .cpu_load[0] : 0
Nov 2 17:51:21 seraph kernel: [ 423.790071] .cpu_load[1] : 0
Nov 2 17:51:21 seraph kernel: [ 423.790073] .cpu_load[2] : 0
Nov 2 17:51:21 seraph kernel: [ 423.790075] .cpu_load[3] : 0
Nov 2 17:51:21 seraph kernel: [ 423.790077] .cpu_load[4] : 0
Nov 2 17:51:21 seraph kernel: [ 423.790080]
Nov 2 17:51:21 seraph kernel: [ 423.790080] cfs_rq[0]:/
Nov 2 17:51:21 seraph kernel: [ 423.790082] .exec_clock : 15478.967994
Nov 2 17:51:21 seraph kernel: [ 423.790085] .MIN_vruntime : 0.000001
Nov 2 17:51:21 seraph kernel: [ 423.790087] .min_vruntime : 168613.947469
Nov 2 17:51:21 seraph kernel: [ 423.790089] .max_vruntime : 0.000001
Nov 2 17:51:21 seraph kernel: [ 423.790091] .spread : 0.000000
Nov 2 17:51:21 seraph kernel: [ 423.790093] .spread0 : 0.000000
Nov 2 17:51:21 seraph kernel: [ 423.790095] .nr_running : 0
Nov 2 17:51:21 seraph kernel: [ 423.790097] .load : 0
Nov 2 17:51:21 seraph kernel: [ 423.790099] .yld_exp_empty : 0
Nov 2 17:51:21 seraph kernel: [ 423.790101] .yld_act_empty : 0
Nov 2 17:51:21 seraph kernel: [ 423.790103] .yld_both_empty : 0
Nov 2 17:51:21 seraph kernel: [ 423.790105] .yld_count : 33
Nov 2 17:51:21 seraph kernel: [ 423.790107] .sched_switch : 0
Nov 2 17:51:21 seraph kernel: [ 423.790109] .sched_count : 154243
Nov 2 17:51:21 seraph kernel: [ 423.790111] .sched_goidle : 25896
Nov 2 17:51:21 seraph kernel: [ 423.790112] .ttwu_count : 73730
Nov 2 17:51:21 seraph kernel: [ 423.790115] .ttwu_local : 68128
Nov 2 17:51:21 seraph kernel: [ 423.790117] .bkl_count : 641
Nov 2 17:51:21 seraph kernel: [ 423.790119] .nr_spread_over : 6855
Nov 2 17:51:21 seraph kernel: [ 423.790120] .shares : 0
Nov 2 17:51:21 seraph kernel: [ 423.790123]
Nov 2 17:51:21 seraph kernel: [ 423.790123] rt_rq[0]:/
Nov 2 17:51:21 seraph kernel: [ 423.790125] .rt_nr_running : 0
Nov 2 17:51:21 seraph kernel: [ 423.790127] .rt_throttled : 0
Nov 2 17:51:21 seraph kernel: [ 423.790129] .rt_time : 0.000000
Nov 2 17:51:21 seraph kernel: [ 423.790131] .rt_runtime : 950.000000
Nov 2 17:51:21 seraph kernel: [ 423.790134]
Nov 2 17:51:21 seraph kernel: [ 423.790135] runnable tasks:
Nov 2 17:51:21 seraph kernel: [ 423.790136] task PID tree-key switches prio exec-runtime sum-exec sum-sleep
Nov 2 17:51:21 seraph kernel: [ 423.790137] ----------------------------------------------------------------------------------------------------------
Nov 2 17:51:21 seraph kernel: [ 423.790167]
Nov 2 17:51:21 seraph kernel: [ 423.790168] cpu#1, 2009.222 MHz
Nov 2 17:51:21 seraph kernel: [ 423.790170] .nr_running : 1
Nov 2 17:51:21 seraph kernel: [ 423.790172] .load : 1024
Nov 2 17:51:21 seraph kernel: [ 423.790174] .nr_switches : 242032
Nov 2 17:51:21 seraph kernel: [ 423.790176] .nr_load_updates : 23699
Nov 2 17:51:21 seraph kernel: [ 423.790178] .nr_uninterruptible : 4294966953
Nov 2 17:51:21 seraph kernel: [ 423.790180] .jiffies : 30947
Nov 2 17:51:21 seraph kernel: [ 423.790182] .next_balance : 0.030992
Nov 2 17:51:21 seraph kernel: [ 423.790184] .curr->pid : 7080
Nov 2 17:51:21 seraph kernel: [ 423.790186] .clock : 423788.505019
Nov 2 17:51:21 seraph kernel: [ 423.790189] .cpu_load[0] : 1024
Nov 2 17:51:21 seraph kernel: [ 423.790190] .cpu_load[1] : 1024
Nov 2 17:51:21 seraph kernel: [ 423.790192] .cpu_load[2] : 1024
Nov 2 17:51:21 seraph kernel: [ 423.790195] .cpu_load[3] : 1024
Nov 2 17:51:21 seraph kernel: [ 423.790197] .cpu_load[4] : 1024
Nov 2 17:51:21 seraph kernel: [ 423.790199]
Nov 2 17:51:21 seraph kernel: [ 423.790199] cfs_rq[1]:/
Nov 2 17:51:21 seraph kernel: [ 423.790201] .exec_clock : 69594.454835
Nov 2 17:51:21 seraph kernel: [ 423.790203] .MIN_vruntime : 0.000001
Nov 2 17:51:21 seraph kernel: [ 423.790206] .min_vruntime : 167022.385064
Nov 2 17:51:21 seraph kernel: [ 423.790208] .max_vruntime : 0.000001
Nov 2 17:51:21 seraph kernel: [ 423.790210] .spread : 0.000000
Nov 2 17:51:21 seraph kernel: [ 423.790212] .spread0 : -1591.562405
Nov 2 17:51:21 seraph kernel: [ 423.790215] .nr_running : 1
Nov 2 17:51:21 seraph kernel: [ 423.790216] .load : 1024
Nov 2 17:51:21 seraph kernel: [ 423.790218] .yld_exp_empty : 0
Nov 2 17:51:21 seraph kernel: [ 423.790220] .yld_act_empty : 0
Nov 2 17:51:21 seraph kernel: [ 423.790222] .yld_both_empty : 0
Nov 2 17:51:21 seraph kernel: [ 423.790224] .yld_count : 3138471
Nov 2 17:51:21 seraph kernel: [ 423.790226] .sched_switch : 0
Nov 2 17:51:21 seraph kernel: [ 423.790228] .sched_count : 3383430
Nov 2 17:51:21 seraph kernel: [ 423.790230] .sched_goidle : 37402
Nov 2 17:51:21 seraph kernel: [ 423.790232] .ttwu_count : 124503
Nov 2 17:51:21 seraph kernel: [ 423.790234] .ttwu_local : 120515
Nov 2 17:51:21 seraph kernel: [ 423.790236] .bkl_count : 2755
Nov 2 17:51:21 seraph kernel: [ 423.790238] .nr_spread_over : 6757
Nov 2 17:51:21 seraph kernel: [ 423.790240] .shares : 0
Nov 2 17:51:21 seraph kernel: [ 423.790241]
Nov 2 17:51:21 seraph kernel: [ 423.790242] rt_rq[1]:/
Nov 2 17:51:21 seraph kernel: [ 423.790244] .rt_nr_running : 0
Nov 2 17:51:21 seraph kernel: [ 423.790246] .rt_throttled : 0
Nov 2 17:51:21 seraph kernel: [ 423.790247] .rt_time : 0.000000
Nov 2 17:51:21 seraph kernel: [ 423.790250] .rt_runtime : 950.000000
Nov 2 17:51:21 seraph kernel: [ 423.790253]
Nov 2 17:51:21 seraph kernel: [ 423.790254] runnable tasks:
Nov 2 17:51:21 seraph kernel: [ 423.790254] task PID tree-key switches prio exec-runtime sum-exec sum-sleep
Nov 2 17:51:21 seraph kernel: [ 423.790256] ----------------------------------------------------------------------------------------------------------
Nov 2 17:51:21 seraph kernel: [ 423.790278] R pm-suspend 7080 167038.889732 100 120 167038.889732 20039.079292 3493.900072 /
Nov 2 17:51:21 seraph kernel: [ 423.790285]
Nov 2 17:51:21 seraph kernel: [ 423.790324] gnome-panel
Nov 2 17:51:21 seraph kernel: [ 423.790370] gvfsd-trash
Nov 2 17:51:21 seraph kernel: [ 423.790407]
Nov 2 17:51:21 seraph kernel: [ 423.790409] Restarting tasks ... done.

The issue may have to do with me having a filesystem mounted via nfs (not /home, but the desktop wallpaper for example resides on the nfs mount).

# lspci
00:00.0 RAM memory: nVidia Corporation MCP55 Memory Controller (rev a2)
00:01.0 ISA bridge: nVidia Corporation MCP55 LPC Bridge (rev a3)
00:01.1 SMBus: nVidia Corporation MCP55 SMBus (rev a3)
00:02.0 USB Controller: nVidia Corporation MCP55 USB Controller (rev a1)
00:02.1 USB Controller: nVidia Corporation MCP55 USB Controller (rev a2)
00:04.0 IDE interface: nVidia Corporation MCP55 IDE (rev a1)
00:05.0 IDE interface: nVidia Corporation MCP55 SATA Controller (rev a3)
00:05.1 IDE interface: nVidia Corporation MCP55 SATA Controller (rev a3)
00:05.2 IDE interface: nVidia Corporation MCP55 SATA Controller (rev a3)
00:06.0 PCI bridge: nVidia Corporation MCP55 PCI bridge (rev a2)
00:06.1 Audio device: nVidia Corporation MCP55 High Definition Audio (rev a2)
00:08.0 Bridge: nVidia Corporation MCP55 Ethernet (rev a3)
00:0f.0 PCI bridge: nVidia Corporation MCP55 PCI Express bridge (rev a3)
00:18.0 Host bridge: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron] HyperTransport Technology Configuration
00:18.1 Host bridge: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron] Address Map
00:18.2 Host bridge: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron] DRAM Controller
00:18.3 Host bridge: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron] Miscellaneous Control
01:07.0 Multimedia video controller: Internext Compression Inc iTVC16 (CX23416) MPEG-2 Encoder (rev 01)
02:00.0 VGA compatible controller: nVidia Corporation G70 [GeForce 7600 GT] (rev a1)

I'm running a 32bit installation on amd64 (X2 3600+ in case it matters).

I don't think this is quite the same suspend issue(s) others have reported that was/were related to the nvidia binary drivers (although I also use them). All the nfs functions in the call stacks of the non-freezing processes seems to suggest, at least to me, that this problem is related to nfs. Those functions didn't appear in the call stacks of any other processes.

[Jeff Workman (jeff-workman)]

I'm having the same problem with suspend and tasks not freezing. The processes have been gvfsd-trash, mount.nfs, and landscape-sysinfo on different occasions, and all have NFS-related functions in the call stack.
Here's output from dmesg on two different occasions, the first with mount.nfs not freezing, the second with gvfsd-trash and landscape-sysinfo not freezing.
This is on Intrepid.

[ 759.921827] mount.nfs D 6f000002 0 8215 8212
[ 759.921830] eb135c3c 00000082 00000010 6f000002 d000a8c0 00000000 00000000 00000000
[ 759.921835] 00000000 00000000 f745d7f0 000010c6 00000000 00000000 00000000 00000000
[ 759.921841] c050f080 eb056000 eb134000 f745da68 c2b05d00 00000000 00000000 c2b05d00
[ 759.921847] Call Trace:
[ 759.921849] [<f90f8c5d>] rpc_wait_bit_killable+0x1d/0x50 [sunrpc]
[ 759.921861] [<c037d2ef>] __wait_on_bit+0x4f/0x70
[ 759.921864] [<f90f8c40>] ? rpc_wait_bit_killable+0x0/0x50 [sunrpc]
[ 759.921876] [<c037d3bd>] out_of_line_wait_on_bit+0xad/0xc0
[ 759.921879] [<f90f8c40>] ? rpc_wait_bit_killable+0x0/0x50 [sunrpc]
[ 759.921891] [<c0147500>] ? wake_bit_function+0x0/0x60
[ 759.921895] [<f90f96ae>] __rpc_execute+0xae/0x250 [sunrpc]
[ 759.921907] [<f90f98c9>] rpc_execute+0x79/0x90 [sunrpc]
[ 759.921918] [<f90f2743>] rpc_run_task+0x33/0x70 [sunrpc]
[ 759.921930] [<f90f28a0>] rpc_call_sync+0x40/0x70 [sunrpc]
[ 759.921949] [<f90f2918>] rpc_ping+0x48/0x60 [sunrpc]
[ 759.921969] [<f90f3302>] rpc_create+0x1b2/0x210 [sunrpc]
[ 759.921981] [<c018a053>] ? get_page_from_freelist+0xc3/0x160
[ 759.921985] [<c018a1ae>] ? __alloc_pages_internal+0xbe/0x490
[ 759.921988] [<c018916e>] ? get_pageblock_flags_group+0xe/0x80
[ 759.921992] [<f9190b88>] nfs_mount+0x88/0x180 [nfs]
[ 759.922005] [<f91850e7>] ? nfs_parse_ip_address+0xc7/0x120 [nfs]
[ 759.922025] [<f9185e7f>] nfs_try_mount+0x9f/0x120 [nfs]
[ 759.922036] [<f918639b>] nfs_validate_mount_data+0x49b/0x550 [nfs]
[ 759.922046] [<f91881d4>] nfs_get_sb+0x84/0x300 [nfs]
[ 759.922063] [<c01cbc7d>] ? alloc_vfsmnt+0x8d/0x120
[ 759.922069] [<c01b43ee>] vfs_kern_mount+0x5e/0x130
[ 759.922074] [<c01b451e>] do_kern_mount+0x3e/0xe0
[ 759.922077] [<c01cc9ef>] do_new_mount+0x6f/0x90
[ 759.922080] [<c01ccf32>] do_mount+0x1d2/0x1f0
[ 759.922083] [<c01ca67d>] ? exact_copy_from_user+0x4d/0xa0
[ 759.922086] [<c01cac8e>] ? copy_mount_options+0x6e/0xd0
[ 759.922090] [<c01ccfe1>] sys_mount+0x91/0xc0
[ 759.922093] [<c0103f7b>] sysenter_do_call+0x12/0x2f

[83268.835117] landscape-sys D 00000000 0 25091 25089
[83268.835120] ddd89ccc 00200086 ddd89cb8 00000000 00000000 00004040 ee25e004 00000064
[83268.835126] 00000064 00000000 ee1c4b60 0002ce83 00000000 ee4f0000 00000001 ecc8b880
[83268.835132] c050f080 ddcf6000 ddd88000 ee1c4dd8 c2b10d00 00000001 ddd89ca4 c2b10d00
[83268.835138] Call Trace:
[83268.835140] [<f90f8c5d>] rpc_wait_bit_killable+0x1d/0x50 [sunrpc]
[83268.835152] [<c037d2ef>] __wait_on_bit+0x4f/0x70
[83268.835155] [<f90f8c40>] ? rpc_wait_bit_killable+0x0/0x50 [sunrpc]
[83268.835168] [<c037d3bd>] out_of_line_wait_on_bit+0xad/0xc0
[83268.835171] [<f90f8c40>] ? rpc_wait_bit_killable+0x0/0...

[dilbert (dilbert-side)]

Yes I confirm the reported behaviour.
kernel 2.6.27-11-generic latest
Gnome 2.24.1

every thing started when I put my nfs server in place, and here in indonesia many times a day I lose my connection to my server (because of blackout) and when I want to hibernate on my laptop without network connection, gnome gets crazy with many handles spawned by gvfsd-trash (as many as 10 for one nfs mount)

I have to reboot to stop the mess, no user interaction, ctrl+alt+backspace let me relogin but no gnome interface, it freezes in middle of the the desktop environment load.

well I changed my line in my /etc/fstab
FROM
serv64ub:/srv/nfs4/shared /home/shared nfs rw,hard,intr,rsize=8192,wsize=8192,timeo=14,_netdev,users 0 0
TO
serv64ub:/srv/nfs4/shared /home/shared nfs rw,hard,intr,rsize=8192,wsize=8192,timeo=14,_netdev,users,noauto 0 0

so it doesn't timeout when no network at boot and only 1 gvfsd-trash process runs

when no nfs are mounted, no problem at all gnome interface plays its role as expected.

but at any nfs server connection(or mounted) loss , the whole gnome user interaction is a pain as there unsustainable delay (more than 10 sec) before accessing systems functions, like hibernate, halt, etc

Of course it is impossible to kill the incriminated processes and cannot unmount the nfs mount even with root credentials. the only solution is a dirty reboot as I cannot unmount cleanly my truecrypts volumes and close some applications.

I cannot understand the library which is part of gvfsd-trash keep handle on temporary network mount. we should have a flag somewhere to indicate to gnome not to care about them as for usbdisk, I guess ?

any ideas for a fix ?

[Leann Ogasawara (leannogasawara)]

Hi Andras,

Care to test and confirm if this remains in the latest Jaunty 9.04 Alpha 4 release? It contains a 2.6.28 based kernel. You should be able to test suspend (not hibernate) via a LiveCD. Definitely let us know your results. Thanks.

http://cdimage.ubuntu.com/releases/jaunty/

[András Korn (kornandras)]

Hi,

yes, I will test with the new version and let you know. Could be a few days though.

[András Korn (kornandras)]

Unfortunately, the problem is still present in 2.6.28.8-generic.

I'm attaching the kernel log.

[mehturt (mehturt)]

I am having similar problem, although it appears rarely.. perhaps one time in 50 suspends.
There is no NFS on my laptop.
The task refusing to freeze is landscape-sysinfo, stack:

Feb 26 18:51:04 kernel: [ 8267.277070] landscape-sys D ffffffff802340a9 0 20841 20837
Feb 26 18:51:04 kernel: [ 8267.277073] ffff88004c881c98 0000000000000086 ffff88004c881bf8 ffffffff8024760d
Feb 26 18:51:04 kernel: [ 8267.277077] ffffffff807a6400 ffffffff807a6400 ffffffff807a6400 ffffffff807a6400
Feb 26 18:51:04 kernel: [ 8267.277081] ffffffff807a6400 ffffffff807a6400 ffffffff807a6400 ffffffff807a6400
Feb 26 18:51:04 kernel: [ 8267.277085] Call Trace:
Feb 26 18:51:04 kernel: [ 8267.277087] [<ffffffff8024760d>] ? default_wake_function+0xd/0x10
Feb 26 18:51:04 kernel: [ 8267.277097] [<ffffffffa0000de5>] request_wait_answer+0x85/0x250 [fuse]
Feb 26 18:51:04 kernel: [ 8267.277100] [<ffffffff802670a0>] ? autoremove_wake_function+0x0/0x40
Feb 26 18:51:04 kernel: [ 8267.277107] [<ffffffffa000103a>] request_send+0x8a/0x90 [fuse]
Feb 26 18:51:04 kernel: [ 8267.277113] [<ffffffffa00030b3>] fuse_do_getattr+0x143/0x2d0 [fuse]
Feb 26 18:51:04 kernel: [ 8267.277116] [<ffffffff802f3956>] ? putname+0x36/0x50
Feb 26 18:51:04 kernel: [ 8267.277123] [<ffffffffa00049f5>] fuse_update_attributes+0x75/0x80 [fuse]
Feb 26 18:51:04 kernel: [ 8267.277130] [<ffffffffa0004a51>] fuse_getattr+0x51/0x60 [fuse]
Feb 26 18:51:04 kernel: [ 8267.277132] [<ffffffff802edf24>] vfs_getattr+0x54/0x80
Feb 26 18:51:04 kernel: [ 8267.277135] [<ffffffff802ee193>] vfs_stat_fd+0x43/0x60
Feb 26 18:51:04 kernel: [ 8267.277138] [<ffffffff802ee25c>] sys_newstat+0x2c/0x50
Feb 26 18:51:04 kernel: [ 8267.277141] [<ffffffff8026b55e>] ? up_write+0xe/0x10
Feb 26 18:51:04 kernel: [ 8267.277144] [<ffffffff8021285a>] system_call_fastpath+0x16/0x1b

I am on 8.10 x86_64, 2.6.27-12-generic.

[András Korn (kornandras)]

FWIW, it appears that suspend works on the problem box with 2.6.32-9-generic; resuming does not (no video after resume), but that's a different issue.

[Ralf Barth (ubuntu-haggy)]

Just wanted to add i experience the same issues while having an nfs(4) share mounted. Suspend/Resume works only once while in Gnome - then subsequently fails with attached error messages in dmesg. The task refusing to suspend is gnome-settings-daemon - backtrace clearly shows nfs related calls. If i quit Gnome to gdm before suspend or unmount the share manually before suspend, it works flawlessly.

[MountainX (dave-mountain)]

I'm running 10.10 and it fails to suspend to RAM if I have used any NFS shares. This happens 100% of the time and is easily reproducible. (I'm using NFS 3.) Even if no files are currently open, if some files on a share were previously opened during the current session, Ubuntu will fail to suspend.

If I log out of the desktop and log back in, I can suspend if I do so before beginning any work that involves a share. This also produces the stated outcome 100% of the time.

I tried the solution in https://bugs.launchpad.net/ubuntu/+source/pm-utils/+bug/506116. It didn't resolve the issue for me.

Here is a typical log. Clearly, this is NFS related.

Jan 6 02:11:19 MyComputer kernel: [157643.665593] Freezing user space processes ...
Jan 6 02:11:19 MyComputer kernel: [157663.667670] gnome-panel D 0000000000000082 0 8620 8542 0x00800004
Jan 6 02:11:19 MyComputer kernel: [157663.667673] ffff8803ec591b68 0000000000000082 ffff8803ec591ae8 0000000000015980
Jan 6 02:11:19 MyComputer kernel: [157663.667675] ffff8803ec591fd8 0000000000015980 ffff8803ec591fd8 ffff88040d42c4a0
Jan 6 02:11:19 MyComputer kernel: [157663.667678] 0000000000015980 0000000000015980 ffff8803ec591fd8 0000000000015980
Jan 6 02:11:19 MyComputer kernel: [157663.667680] Call Trace:
Jan 6 02:11:19 MyComputer kernel: [157663.667691] [<ffffffffa0bbbbe0>] ? rpc_wait_bit_killable+0x0/0x40 [sunrpc]
Jan 6 02:11:19 MyComputer kernel: [157663.667697] [<ffffffffa0bbbc04>] rpc_wait_bit_killable+0x24/0x40 [sunrpc]
Jan 6 02:11:19 MyComputer kernel: [157663.667701] [<ffffffff815884ff>] __wait_on_bit+0x5f/0x90
Jan 6 02:11:19 MyComputer kernel: [157663.667706] [<ffffffffa0bbbbe0>] ? rpc_wait_bit_killable+0x0/0x40 [sunrpc]
Jan 6 02:11:19 MyComputer kernel: [157663.667708] [<ffffffff815885a8>] out_of_line_wait_on_bit+0x78/0x90
Jan 6 02:11:19 MyComputer kernel: [157663.667711] [<ffffffff8107f770>] ? wake_bit_function+0x0/0x40
Jan 6 02:11:19 MyComputer kernel: [157663.667717] [<ffffffffa0bbc4ed>] __rpc_execute+0xed/0x290 [sunrpc]
Jan 6 02:11:19 MyComputer kernel: [157663.667722] [<ffffffffa0bbc713>] rpc_execute+0x83/0xa0 [sunrpc]
Jan 6 02:11:19 MyComputer kernel: [157663.667727] [<ffffffffa0bb4c99>] rpc_run_task+0x29/0x40 [sunrpc]
Jan 6 02:11:19 MyComputer kernel: [157663.667731] [<ffffffffa0bb4db2>] rpc_call_sync+0x42/0x70 [sunrpc]
Jan 6 02:11:19 MyComputer kernel: [157663.667734] [<ffffffff8116ec90>] ? mntput_no_expire+0x30/0x110
Jan 6 02:11:19 MyComputer kernel: [157663.667742] [<ffffffffa0cf6645>] T.1034+0x35/0x80 [nfs]
Jan 6 02:11:19 MyComputer kernel: [157663.667749] [<ffffffffa0cf7367>] nfs3_proc_getattr+0x47/0x90 [nfs]
Jan 6 02:11:19 MyComputer kernel: [157663.667754] [<ffffffffa0ce6a30>] __nfs_revalidate_inode+0xd0/0x210 [nfs]
Jan 6 02:11:19 MyComputer kernel: [157663.667759] [<ffffffffa0ce6ce6>] nfs_revalidate_inode+0x36/0x60 [nfs]
Jan 6 02:11:19 MyComputer kernel: [157663.667764] [<ffffffffa0ce6dc6>] nfs_getattr+0x66/0x120 [nfs]
Jan 6 02:11:19 MyComputer kernel: [157663.667767] [<ffffffff81157531>] vfs_getattr+0x51/0x80
Jan 6 02:11:19 MyComputer kernel: [157663.667769] [<ffffffff811575c0>] vfs_fstatat+0x60/0x80
Jan 6 02:11:19 MyComputer ke...

[Brad Figg (brad-figg)]

This bug was filed against a series that is no longer supported and so is being marked as Won't Fix. If this issue still exists in a supported series, please file a new bug.

This change has been made by an automated script, maintained by the Ubuntu Kernel Team.