task zmstat-mysql:19395 blocked for more than 120 seconds

[Eno]

Hi,

I'm seeing weird things in my Kernel logs, i'm not sure if it's related to Zimbra :

May 27 21:16:31 smtp kernel: [7273181.374158] INFO: task mysqld:15404 blocked for more than 120 seconds.
May 27 21:16:31 smtp kernel: [7273181.374183] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
May 27 21:16:31 smtp kernel: [7273181.374222] mysqld D ffff8101293aa8e8 0 15404 15189
May 27 21:16:31 smtp kernel: [7273181.374225] ffff8100af481da8 0000000000000082 ffff81012fa98000 ffffffff8023cfbd
May 27 21:16:31 smtp kernel: [7273181.374227] ffff81006b65d4b0 ffff8100229091e0 ffff81006b65d738 0000000100000001
May 27 21:16:31 smtp kernel: [7273181.374229] 0000000000000296 0000000000000003 ffff8100af481da8 ffffffff8022ae0b
May 27 21:16:31 smtp kernel: [7273181.374231] Call Trace:
May 27 21:16:31 smtp kernel: [7273181.374233] [<ffffffff8023cfbd>] lock_timer_base+0x26/0x4b
May 27 21:16:31 smtp kernel: [7273181.374236] [<ffffffff8022ae0b>] __wake_up+0x38/0x4f
May 27 21:16:31 smtp kernel: [7273181.374241] [<ffffffffa00d9614>] :jbd:log_wait_commit+0x9f/0xed
May 27 21:16:31 smtp kernel: [7273181.374243] [<ffffffff8024624d>] autoremove_wake_function+0x0/0x2e
May 27 21:16:31 smtp kernel: [7273181.374248] [<ffffffffa00d54f9>] :jbd:journal_stop+0x165/0x18d
May 27 21:16:31 smtp kernel: [7273181.374251] [<ffffffff802b6737>] __writeback_single_inode+0x17f/0x29d
May 27 21:16:31 smtp kernel: [7273181.374253] [<ffffffff8024624d>] autoremove_wake_function+0x0/0x2e
May 27 21:16:31 smtp kernel: [7273181.374256] [<ffffffff802b7063>] sync_inode+0x24/0x31
May 27 21:16:31 smtp kernel: [7273181.374265] [<ffffffffa00e53e2>] :ext3:ext3_sync_file+0x9e/0xb0
May 27 21:16:31 smtp kernel: [7273181.374268] [<ffffffff802b95da>] do_fsync+0x52/0xa4
May 27 21:16:31 smtp kernel: [7273181.374270] [<ffffffff802b964f>] __do_fsync+0x23/0x36
May 27 21:16:31 smtp kernel: [7273181.374272] [<ffffffff8020beca>] system_call_after_swapgs+0x8a/0x8f
May 27 21:16:31 smtp kernel: [7273181.374275]
May 27 21:16:31 smtp kernel: [7273181.374302] INFO: task qmgr:19098 blocked for more than 120 seconds.
May 27 21:16:31 smtp kernel: [7273181.374327] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
May 27 21:16:31 smtp kernel: [7273181.374367] qmgr D 0000000000000000 0 19098 19088
May 27 21:16:31 smtp kernel: [7273181.374369] ffff8100ca9d9ec0 0000000000000082 0000000000000000 0000000600000010
May 27 21:16:31 smtp kernel: [7273181.374372] ffff81009e4e8480 ffff81012fac3470 ffff81009e4e8708 000000012d7635c0
May 27 21:16:31 smtp kernel: [7273181.374374] ffff810120fc2970 0000000000000000 00000000ffffffff 0000000000000000
May 27 21:16:31 smtp kernel: [7273181.374376] Call Trace:
May 27 21:16:31 smtp kernel: [7273181.374380] [<ffffffff8042971d>] __mutex_lock_slowpath+0x64/0x9b
May 27 21:16:31 smtp kernel: [7273181.374383] [<ffffffff80429582>] mutex_lock+0xa/0xb
May 27 21:16:31 smtp kernel: [7273181.374385] [<ffffffff8029b7ed>] generic_file_llseek+0x2a/0x8b
May 27 21:16:31 smtp kernel: [7273181.374387] [<ffffffff8029b99c>] sys_lseek+0x40/0x60
May 27 21:16:31 smtp kernel: [7273181.374389] [<ffffffff802a986a>] sys_flock+0x12f/0x148
May 27 21:16:31 smtp kernel: [7273181.374392] [<ffffffff8020beca>] system_call_after_swapgs+0x8a/0x8f

10min after the begining of this, the server hangup and stop respond :/ I'm quiete sure something ( like MySQL ) used all memory / swap.

Thanks,

[phoenix]

Which operating system? Which kernel? Have you checked the HD? Which filesystem? Has it just started happening or has it been happening for a while?

[Eno]

It just happened once afaik, I'm using Zimbra ( Open Source ) zcs-6.0.4_GA on Debian Lenny 64bits :

Linux smtp 2.6.26-2-amd64 #1 SMP Tue Jan 12 22:12:20 UTC 2010 x86_64 GNU/Linux

The kernel is recent but it's not the very last version. FS is ext3 and it's a basic installation no fancy stuffs.