From: Marko Weber <weber@zackbummfertig.de>
Subject: ext4 crash? on rsyn action
Date: Mon, 11 Jan 2010 21:31:52 +0100
Message-ID: <4B4B8AB8.5040907@zackbummfertig.de>
Mime-Version: 1.0
Content-Type: text/plain; charset=ISO-8859-15; format=flowed
Content-Transfer-Encoding: 7bit
To: ext4 <linux-ext4@vger.kernel.org>
Return-path: <linux-ext4-owner@vger.kernel.org>
Received: from weberweb.zackbummfertig.de ([78.46.42.243]:43279 "EHLO
	weberweb.zackbummfertig.de" rhost-flags-OK-OK-OK-OK)
	by vger.kernel.org with ESMTP id S1753953Ab0AKUb7 (ORCPT
	<rfc822;linux-ext4@vger.kernel.org>);
	Mon, 11 Jan 2010 15:31:59 -0500
Received: from localhost (localhost [127.0.0.1])
	by weberweb.zackbummfertig.de (Postfix) with ESMTP id F3ACFECC065
	for <linux-ext4@vger.kernel.org>; Mon, 11 Jan 2010 21:32:15 +0100 (CET)
Received: from weberweb.zackbummfertig.de ([127.0.0.1])
	by localhost (weberweb.zackbummfertig.de [127.0.0.1]) (amavisd-new, port 10024)
	with ESMTP id 7Y+cvOrpb7Nn for <linux-ext4@vger.kernel.org>;
	Mon, 11 Jan 2010 21:32:11 +0100 (CET)
Received: from [192.168.2.3] (188-193-22-46-dynip.superkabel.de [188.193.22.46])
	(Authenticated sender: weber)
	by weberweb.zackbummfertig.de (Postfix) with ESMTPA id 698BFECC064
	for <linux-ext4@vger.kernel.org>; Mon, 11 Jan 2010 21:32:11 +0100 (CET)
Sender: linux-ext4-owner@vger.kernel.org
List-ID: <linux-ext4.vger.kernel.org>

Hi all,

i had an crash on moving data with rsync on my system.
from system hdd (xfs) i moved with rsync data to my raid5 array (ext4).
nearly at end of rsync the opration hang.
i dont know if this is a crash.

used kernel on the system 2.6.32.3

in /var/log/messages i found this =

Jan 11 19:38:51 zagreb kernel: INFO: task jbd2/md0-8:5435 blocked for 
more than 120 seconds.
Jan 11 19:38:51 zagreb kernel: "echo 0 > 
/proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jan 11 19:38:51 zagreb kernel: jbd2/md0-8    D ffff88022e3f7d80     0  
5435      2 0x00000000
Jan 11 19:38:51 zagreb kernel: ffff88022f87a870 0000000000000046 
0000000000000000 ffff88022bc43e24
Jan 11 19:38:51 zagreb kernel: 000000022bc43cb0 ffff88022bc43e10 
000000000000e2e0 ffff88022bc43fd8
Jan 11 19:38:51 zagreb kernel: 0000000000012000 ffff88022bc43fd8 
0000000000012000 ffff88022fad4140
Jan 11 19:38:51 zagreb kernel: Call Trace:
Jan 11 19:38:51 zagreb kernel: [<ffffffff81026fd0>] ? __switch_to+0xd0/0x297
Jan 11 19:38:51 zagreb kernel: [<ffffffff8114d0fe>] ? 
jbd2_journal_commit_transaction+0x17d/0xf9f
Jan 11 19:38:51 zagreb kernel: [<ffffffff8105676f>] ? 
finish_task_switch+0x3a/0xb4
Jan 11 19:38:51 zagreb kernel: [<ffffffff8106d5fd>] ? 
autoremove_wake_function+0x0/0x2e
Jan 11 19:38:51 zagreb kernel: [<ffffffff810641e5>] ? 
lock_timer_base+0x26/0x4b
Jan 11 19:38:51 zagreb kernel: [<ffffffff81064274>] ? 
try_to_del_timer_sync+0x6a/0x73
Jan 11 19:38:51 zagreb kernel: [<ffffffff81150ea2>] ? kjournald2+0xbd/0x1dd
Jan 11 19:38:51 zagreb kernel: [<ffffffff8106d5fd>] ? 
autoremove_wake_function+0x0/0x2e
Jan 11 19:38:51 zagreb kernel: [<ffffffff81150de5>] ? kjournald2+0x0/0x1dd
Jan 11 19:38:51 zagreb kernel: [<ffffffff8106d38f>] ? kthread+0x79/0x81
Jan 11 19:38:51 zagreb kernel: [<ffffffff8102942a>] ? child_rip+0xa/0x20
Jan 11 19:38:51 zagreb kernel: [<ffffffff8106d316>] ? kthread+0x0/0x81
Jan 11 19:38:51 zagreb kernel: [<ffffffff81029420>] ? child_rip+0x0/0x20
Jan 11 19:38:51 zagreb kernel: INFO: task spindownd:9924 blocked for 
more than 120 seconds.
Jan 11 19:38:51 zagreb kernel: "echo 0 > 
/proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jan 11 19:38:51 zagreb kernel: spindownd     D ffff880224e01e80     0  
9924      1 0x00000000
Jan 11 19:38:51 zagreb kernel: ffffffff816828a0 0000000000000082 
0000000000000000 ffff88022e6fd000
Jan 11 19:38:51 zagreb kernel: 0000000000000292 ffff88022bc52f28 
000000000000e2e0 ffff880224e01fd8
Jan 11 19:38:51 zagreb kernel: 0000000000012000 ffff880224e01fd8 
0000000000012000 ffff88022e2c9830
Jan 11 19:38:51 zagreb kernel: Call Trace:
Jan 11 19:38:51 zagreb kernel: [<ffffffff811508ae>] ? 
jbd2_log_wait_commit+0xbe/0x110
Jan 11 19:38:51 zagreb kernel: [<ffffffff8106d5fd>] ? 
autoremove_wake_function+0x0/0x2e
Jan 11 19:38:51 zagreb kernel: [<ffffffff8112f32e>] ? ext4_sync_fs+0x44/0x4d
Jan 11 19:38:51 zagreb kernel: [<ffffffff810fcaa1>] ? 
sync_quota_sb+0x45/0xf6
Jan 11 19:38:51 zagreb kernel: [<ffffffff810d952a>] ? 
__sync_filesystem+0x43/0x70
Jan 11 19:38:51 zagreb kernel: [<ffffffff810d95f0>] ? 
sync_filesystems+0x99/0xe1
Jan 11 19:38:51 zagreb kernel: [<ffffffff810d967d>] ? sys_sync+0x1c/0x2e
Jan 11 19:38:51 zagreb kernel: [<ffffffff81028442>] ? 
system_call_fastpath+0x16/0x1b
Jan 11 19:38:51 zagreb kernel: INFO: task rsync:23457 blocked for more 
than 120 seconds.
Jan 11 19:38:51 zagreb kernel: "echo 0 > 
/proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jan 11 19:38:51 zagreb kernel: rsync         D ffff88022faa3824     0 
23457      1 0x00000004
Jan 11 19:38:51 zagreb kernel: ffff88022f8a67b0 0000000000000082 
0000000000000000 0000000000000001
Jan 11 19:38:51 zagreb kernel: 0000000063400074 0000000000000001 
000000000000e2e0 ffff880207cb5fd8
Jan 11 19:38:51 zagreb kernel: 0000000000012000 ffff880207cb5fd8 
0000000000012000 ffff8801de07d100
Jan 11 19:38:51 zagreb kernel: Call Trace:
Jan 11 19:38:51 zagreb kernel: [<ffffffff81139da9>] ? 
__ext4_handle_dirty_metadata+0xc9/0xd4
Jan 11 19:38:51 zagreb kernel: [<ffffffff8114cb5c>] ? 
start_this_handle+0x2d9/0x3a1
Jan 11 19:38:51 zagreb kernel: [<ffffffff8106d5fd>] ? 
autoremove_wake_function+0x0/0x2e
Jan 11 19:38:51 zagreb kernel: [<ffffffff8114cd82>] ? 
jbd2_journal_start+0x96/0xc6
Jan 11 19:38:51 zagreb kernel: [<ffffffff81129c48>] ? 
ext4_da_write_begin+0xe2/0x1ed
Jan 11 19:38:51 zagreb kernel: [<ffffffff81095953>] ? 
generic_file_buffered_write+0x118/0x278
Jan 11 19:38:51 zagreb kernel: [<ffffffff81095f45>] ? 
__generic_file_aio_write+0x340/0x374
Jan 11 19:38:51 zagreb kernel: [<ffffffff813eec37>] ? 
sock_aio_read+0x109/0x11d
Jan 11 19:38:51 zagreb kernel: [<ffffffff81095fd2>] ? 
generic_file_aio_write+0x59/0x9f
Jan 11 19:38:51 zagreb kernel: [<ffffffff810bc2f6>] ? 
do_sync_write+0xce/0x113
Jan 11 19:38:51 zagreb kernel: [<ffffffff810270ad>] ? 
__switch_to+0x1ad/0x297
Jan 11 19:38:51 zagreb kernel: [<ffffffff8106d5fd>] ? 
autoremove_wake_function+0x0/0x2e
Jan 11 19:38:51 zagreb kernel: [<ffffffff810732a2>] ? ktime_get_ts+0x68/0xb2
Jan 11 19:38:51 zagreb kernel: [<ffffffff810c931a>] ? 
poll_select_copy_remaining+0xd0/0xf3
Jan 11 19:38:51 zagreb kernel: [<ffffffff810bcc05>] ? vfs_write+0xad/0x149
Jan 11 19:38:51 zagreb kernel: [<ffffffff810bcd5d>] ? sys_write+0x45/0x6e
Jan 11 19:38:51 zagreb kernel: [<ffffffff81028442>] ? 
system_call_fastpath+0x16/0x1b
Jan 11 19:39:01 zagreb cron[23909]: (root) CMD 
(/usr/local/mrourmon/bin/omupdate.sh)
Jan 11 19:39:47 zagreb su[24028]: Successful su for root by weber
Jan 11 19:39:47 zagreb su[24028]: + pts/4 weber:root
Jan 11 19:39:47 zagreb su[24028]: pam_unix(su:session): session opened 
for user root by weber(uid=1000)
Jan 11 19:40:01 zagreb cron[24042]: (root) CMD 
(/usr/local/mrourmon/bin/omupdate.sh)
Jan 11 19:40:01 zagreb cron[24043]: (root) CMD (test -x 
/usr/sbin/run-crons && /usr/sbin/run-crons )
Jan 11 19:40:48 zagreb su[22526]: pam_unix(su:session): session closed 
for user root
Jan 11 19:40:51 zagreb kernel: INFO: task shutdown:23846 blocked for 
more than 120 seconds.
Jan 11 19:40:51 zagreb kernel: "echo 0 > 
/proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jan 11 19:40:51 zagreb kernel: shutdown      D ffffffffffffffff     0 
23846  23580 0x00000000
Jan 11 19:40:51 zagreb kernel: ffff880224d0d040 0000000000000086 
0000000000012000 ffff8801f2e67a80
Jan 11 19:40:51 zagreb kernel: 0000000000000000 ffff8801f2e67a80 
000000000000e2e0 ffff88010000bfd8
Jan 11 19:40:51 zagreb kernel: 0000000000012000 ffff88010000bfd8 
0000000000012000 ffff8802291a40c0
Jan 11 19:40:51 zagreb kernel: Call Trace:
Jan 11 19:40:51 zagreb kernel: [<ffffffff8104edfc>] ? 
check_preempt_wakeup+0x186/0x215
Jan 11 19:40:51 zagreb kernel: [<ffffffff814aa198>] ? 
__mutex_lock_slowpath+0xd6/0x121
Jan 11 19:40:51 zagreb kernel: [<ffffffff814aa041>] ? mutex_lock+0x12/0x28
Jan 11 19:40:51 zagreb kernel: [<ffffffff810d956a>] ? 
sync_filesystems+0x13/0xe1
Jan 11 19:40:51 zagreb kernel: [<ffffffff810d9673>] ? sys_sync+0x12/0x2e
Jan 11 19:40:51 zagreb kernel: [<ffffffff81028442>] ? 
system_call_fastpath+0x16/0x1b