From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: from plane.gmane.org ([80.91.229.3]:43477 "EHLO plane.gmane.org" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1756063Ab3FEOk5 (ORCPT ); Wed, 5 Jun 2013 10:40:57 -0400 Received: from list by plane.gmane.org with local (Exim 4.69) (envelope-from ) id 1UkEtS-0000bI-PA for linux-btrfs@vger.kernel.org; Wed, 05 Jun 2013 16:40:54 +0200 Received: from cpc21-stap10-2-0-cust974.12-2.cable.virginmedia.com ([86.0.163.207]) by main.gmane.org with esmtp (Gmexim 0.1 (Debian)) id 1AlnuQ-0007hv-00 for ; Wed, 05 Jun 2013 16:40:54 +0200 Received: from m_btrfs by cpc21-stap10-2-0-cust974.12-2.cable.virginmedia.com with local (Gmexim 0.1 (Debian)) id 1AlnuQ-0007hv-00 for ; Wed, 05 Jun 2013 16:40:54 +0200 To: linux-btrfs@vger.kernel.org From: Martin Subject: btrfs raid1 on 16TB: "INFO: task rsync:11022 blocked for more than 180 seconds" Date: Wed, 05 Jun 2013 15:40:44 +0100 Message-ID: Mime-Version: 1.0 Content-Type: text/plain; charset=ISO-8859-1 Sender: linux-btrfs-owner@vger.kernel.org List-ID: Dear Devs, I have x4 4TB HDDs formatted with: mkfs.btrfs -L bu-16TB_0 -d raid1 -m raid1 /dev/sd[cdef] /etc/fstab mounts with the options: noatime,noauto,space_cache,inode_cache All on kernel 3.8.13. Upon using rsync to copy some heavily hardlinked backups from ReiserFS, I've so far had various: "INFO: task rsync:11022 blocked for more than 180 seconds" and one: "INFO: task btrfs-endio-wri:10816 blocked for more than 180 seconds" Further detail listed below. What's the fix or any debug worthwhile? Regards, Martin x1 of these: kernel: INFO: task rsync:11022 blocked for more than 180 seconds. kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kernel: rsync D 0000000000000000 0 11022 11021 0x00000000 kernel: ffff88012b0ae360 0000000000000082 ffffffff815f1400 00000000000120c0 kernel: 0000000000004000 ffff880108a67fd8 0000000000000000 ffffffff810312ac kernel: ffff8801115ae748 ffffffff810e3bad ffff8801115ae748 0000000000000081 kernel: Call Trace: kernel: [] ? ns_capable+0x33/0x46 kernel: [] ? generic_permission+0x19e/0x1fe kernel: [] ? __inode_permission+0x2f/0x6d kernel: [] ? lookup_fast+0x39/0x23c kernel: [] ? wait_current_trans.isra.29+0xa9/0xd8 kernel: [] ? abort_exclusive_wait+0x79/0x79 kernel: [] ? start_transaction+0x3de/0x408 kernel: [] ? setattr_copy+0x8c/0xcb kernel: [] ? btrfs_dirty_inode+0x24/0xa4 kernel: [] ? notify_change+0x1f0/0x2b8 kernel: [] ? utimes_common+0x10c/0x135 kernel: [] ? cp_new_stat+0x10d/0x11f kernel: [] ? do_utimes+0xf1/0x129 kernel: [] ? sys_newlstat+0x23/0x2b kernel: [] ? sys_utimensat+0x64/0x6b kernel: [] ? system_call_fastpath+0x16/0x1b x2 of these: kernel: INFO: task rsync:11022 blocked for more than 180 seconds. kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kernel: rsync D 0000000000000000 0 11022 11021 0x00000000 kernel: ffff88012b0ae360 0000000000000082 ffffffff815f1400 00000000000120c0 kernel: 0000000000004000 ffff880108a67fd8 0000000000000000 ffffffff810e3d63 kernel: ffff88012b0ae360 ffffffff810e3b65 ffff88001e959ef8 0000000000000081 kernel: Call Trace: kernel: [] ? lookup_fast+0x39/0x23c kernel: [] ? generic_permission+0x156/0x1fe kernel: [] ? __inode_permission+0x2f/0x6d kernel: [] ? lookup_fast+0x39/0x23c kernel: [] ? wait_current_trans.isra.29+0xa9/0xd8 kernel: [] ? abort_exclusive_wait+0x79/0x79 kernel: [] ? start_transaction+0x3de/0x408 kernel: [] ? setattr_copy+0x8c/0xcb kernel: [] ? btrfs_dirty_inode+0x24/0xa4 kernel: [] ? notify_change+0x1f0/0x2b8 kernel: [] ? utimes_common+0x10c/0x135 kernel: [] ? cp_new_stat+0x10d/0x11f kernel: [] ? do_utimes+0xf1/0x129 kernel: [] ? sys_newlstat+0x23/0x2b kernel: [] ? sys_utimensat+0x64/0x6b kernel: [] ? system_call_fastpath+0x16/0x1b x7 of these: kernel: INFO: task rsync:11022 blocked for more than 180 seconds. kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kernel: rsync D 0000000000000000 0 11022 11021 0x00000000 kernel: ffff88012b0ae360 0000000000000082 ffffffff815f1400 00000000000120c0 kernel: 0000000000004000 ffff880108a67fd8 ffff88010d9270c9 ffffffff810e520b kernel: 00007fffd5adb458 ffffffff811e2fba ffff880108a67d88 ffffffff810e3411 kernel: Call Trace: kernel: [] ? path_init+0x1da/0x32c kernel: [] ? reserve_metadata_bytes.isra.59+0x7b/0x741 kernel: [] ? complete_walk+0x85/0xd6 kernel: [] ? __d_lookup+0x60/0x122 kernel: [] ? wait_current_trans.isra.29+0xa9/0xd8 kernel: [] ? abort_exclusive_wait+0x79/0x79 kernel: [] ? start_transaction+0x3de/0x408 kernel: [] ? kern_path_create+0x78/0x110 kernel: [] ? btrfs_link+0x75/0x185 kernel: [] ? vfs_link+0x102/0x184 kernel: [] ? sys_linkat+0x16d/0x1c7 kernel: [] ? system_call_fastpath+0x16/0x1b x1 of these: kernel: INFO: task btrfs-endio-wri:10816 blocked for more than 180 seconds. kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kernel: btrfs-endio-wri D 0000000000000000 0 10816 2 0x00000000 kernel: ffff880129bf4f80 0000000000000046 ffffffff815f1400 00000000000120c0 kernel: 0000000000004000 ffff88010c635fd8 ffff8801294404ea ffff880129440000 Mkernel: 0000000000000000 0000000000000050 0000000000000000 ffff880129e85240 kernel: Call Trace: kernel: [] ? kmem_cache_alloc+0x3e/0xde kernel: [] ? btrfs_put_ordered_extent+0x77/0xb3 kernel: [] ? btrfs_put_ordered_extent+0x77/0xb3 kernel: [] ? kmem_cache_free+0x53/0xf9 kernel: [] ? wait_current_trans.isra.29+0xa9/0xd8 kernel: [] ? abort_exclusive_wait+0x79/0x79 kernel: [] ? start_transaction+0x3de/0x408 kernel: [] ? btrfs_finish_ordered_io+0x13f/0x2ee kernel: [] ? usleep_range+0x3e/0x3e kernel: [] ? worker_loop+0x15f/0x435 kernel: [] ? btrfs_queue_worker+0x258/0x258 kernel: [] ? kthread+0xad/0xb5 kernel: [] ? __kthread_parkme+0x5e/0x5e kernel: [] ? ret_from_fork+0x7c/0xb0 kernel: [] ? __kthread_parkme+0x5e/0x5e