lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [<thread-prev] [day] [month] [year] [list]
Date:	Tue, 12 Jan 2010 20:44:14 +0100
From:	Jan Kara <jack@...e.cz>
To:	Marko Weber <weber@...kbummfertig.de>
Cc:	ext4 <linux-ext4@...r.kernel.org>
Subject: Re: ext4 crash? on rsyn action

  Hi,

  Thanks for the report.

> 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.
  It's not a crash but it might be a deadlock. But from the traces it's
not quite clear. There is just task jbd2/md0-8 waiting in
jbd2_journal_commit_transaction. Other tasks (spindownd and rsync) are
just waiting for commit to finish. If you can send your vmlinux file, we
can find out where exactly in jbd2_journal_commit_transaction we wait.
If we wait for IO, everything is fine and we should eventually resume.
If we wait for someone to drop handle to a transaction, it's probably a
deadlock...

								Honza

> 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
> 
> 
> --
> To unsubscribe from this list: send the line "unsubscribe linux-ext4" in
> the body of a message to majordomo@...r.kernel.org
> More majordomo info at  http://vger.kernel.org/majordomo-info.html
-- 
Jan Kara <jack@...e.cz>
SuSE CR Labs
--
To unsubscribe from this list: send the line "unsubscribe linux-ext4" in
the body of a message to majordomo@...r.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ