* 2.6.39-rc1: btrfs "WARNING: at fs/btrfs/inode.c:2177"
@ 2011-04-07 9:41 Jeff Wu
2011-04-07 9:43 ` Wido den Hollander
2011-04-07 16:20 ` Josef Bacik
0 siblings, 2 replies; 5+ messages in thread
From: Jeff Wu @ 2011-04-07 9:41 UTC (permalink / raw)
To: ceph-devel; +Cc: linux-btrfs
Hi ,
I run iozone stress test on a ceph client for x86_64, ceph 0.26 +
linux-2.6.39-rc1 server,
printk "WARNING: at fs/btrfs/inode.c:2177"
1.log1 :
...........................................................
[ 1663.370008] CE: hpet2 increased min_delta_ns to 7500 nsec
[ 1663.375399] CE: hpet2 increased min_delta_ns to 11250 nsec
[ 4945.270388] ------------[ cut here ]------------
[ 4945.275011] WARNING: at fs/btrfs/inode.c:2177
btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
[ 4945.283326] Hardware name: OptiPlex 780
[ 4945.288629] Modules linked in: i915 fbcon tileblit font
snd_hda_codec_analog bitblit btrfs softcursor drm_kms_helper drm
snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd zlib_deflate
psmouse crc32c i2c_algo_bit soundcore snd_page_alloc libcrc32c ppdev
intel_agp parport_pc lp intel_gtt dell_wmi video parport serio_raw
sparse_keymap dcdbas r8169 mii
[ 4945.320862] Pid: 581, comm: btrfs-transacti Not tainted 2.6.39-rc1 #1
[ 4945.327282] Call Trace:
[ 4945.329722] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
[ 4945.335755] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
[ 4945.341602] [<ffffffffa01b2ae0>] btrfs_orphan_commit_root+0xb0/0xc0
[btrfs]
[ 4945.348636] [<ffffffffa01adfd1>] commit_fs_roots+0xa1/0x140 [btrfs]
[ 4945.355017] [<ffffffffa01aeef9>] btrfs_commit_transaction
+0x349/0x750 [btrfs]
[ 4945.362259] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
[ 4945.367649] [<ffffffffa01a8593>] transaction_kthread+0x283/0x290
[btrfs]
[ 4945.374460] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 4945.381269] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 4945.388080] [<ffffffff810812d6>] kthread+0x96/0xa0
[ 4945.392989] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
[ 4945.398895] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
[ 4945.405183] [<ffffffff815a6360>] ? gs_change+0x13/0x13
[ 4945.410409] ---[ end trace 87e42fb6fdfeba78 ]---
[ 5159.980447] ------------[ cut here ]------------
[ 5159.985072] WARNING: at fs/btrfs/inode.c:2177
btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
[ 5159.993353] Hardware name: OptiPlex 780
[ 5159.998648] Modules linked in: i915 fbcon tileblit font
snd_hda_codec_analog bitblit btrfs softcursor drm_kms_helper drm
snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd zlib_deflate
psmouse crc32c i2c_algo_bit soundcore snd_page_alloc libcrc32c ppdev
intel_agp parport_pc lp intel_gtt dell_wmi video parport serio_raw
sparse_keymap dcdbas r8169 mii
[ 5160.033593] Pid: 581, comm: btrfs-transacti Tainted: G W
2.6.39-rc1 #1
[ 5160.041904] Call Trace:
[ 5160.045326] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
[ 5160.052312] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
[ 5160.059102] [<ffffffffa01b2ae0>] btrfs_orphan_commit_root+0xb0/0xc0
[btrfs]
[ 5160.067143] [<ffffffffa01adfd1>] commit_fs_roots+0xa1/0x140 [btrfs]
[ 5160.074485] [<ffffffffa01aeef9>] btrfs_commit_transaction
+0x349/0x750 [btrfs]
[ 5160.082687] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
[ 5160.089034] [<ffffffffa01a8593>] transaction_kthread+0x283/0x290
[btrfs]
[ 5160.096811] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 5160.104568] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 5160.112294] [<ffffffff810812d6>] kthread+0x96/0xa0
[ 5160.118093] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
[ 5160.124964] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
[ 5160.132166] [<ffffffff815a6360>] ? gs_change+0x13/0x13
[ 5160.138292] ---[ end trace 87e42fb6fdfeba79 ]---
[ 5434.340486] ------------[ cut here ]------------
[ 5434.346091] WARNING: at fs/btrfs/inode.c:2177
btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
[ 5434.355303] Hardware name: OptiPlex 780
[ 5434.361640] Modules linked in: i915 fbcon tileblit font
snd_hda_codec_analog bitblit btrfs softcursor drm_kms_helper drm
snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd zlib_deflate
psmouse crc32c i2c_algo_bit soundcore snd_page_alloc libcrc32c ppdev
intel_agp parport_pc lp intel_gtt dell_wmi video parport serio_raw
sparse_keymap dcdbas r8169 mii
[ 5434.396772] Pid: 581, comm: btrfs-transacti Tainted: G W
2.6.39-rc1 #1
[ 5434.405087] Call Trace:
[ 5434.408546] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
[ 5434.415523] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
[ 5434.422300] [<ffffffffa01b2ae0>] btrfs_orphan_commit_root+0xb0/0xc0
[btrfs]
[ 5434.430290] [<ffffffffa01adfd1>] commit_fs_roots+0xa1/0x140 [btrfs]
[ 5434.437562] [<ffffffffa01aeef9>] btrfs_commit_transaction
+0x349/0x750 [btrfs]
[ 5434.445738] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
[ 5434.452087] [<ffffffffa01a8593>] transaction_kthread+0x283/0x290
[btrfs]
[ 5434.459792] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 5434.467542] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 5434.475291] [<ffffffff810812d6>] kthread+0x96/0xa0
[ 5434.481082] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
[ 5434.487949] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
[ 5434.495160] [<ffffffff815a6360>] ? gs_change+0x13/0x13
[ 5434.501314] ---[ end trace 87e42fb6fdfeba7a ]---
[ 5525.450514] ------------[ cut here ]------------
[ 5525.456066] WARNING: at fs/btrfs/inode.c:2177
btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
[ 5525.465286] Hardware name: OptiPlex 780
[ 5525.471543] Modules linked in: i915 fbcon tileblit font
snd_hda_codec_analog bitblit btrfs softcursor drm_kms_helper drm
snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd zlib_deflate
psmouse crc32c i2c_algo_bit soundcore snd_page_alloc libcrc32c ppdev
intel_agp parport_pc lp intel_gtt dell_wmi video parport serio_raw
sparse_keymap dcdbas r8169 mii
[ 5525.506568] Pid: 581, comm: btrfs-transacti Tainted: G W
2.6.39-rc1 #1
[ 5525.514879] Call Trace:
[ 5525.518281] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
[ 5525.525251] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
[ 5525.532037] [<ffffffffa01b2ae0>] btrfs_orphan_commit_root+0xb0/0xc0
[btrfs]
[ 5525.540022] [<ffffffffa01adfd1>] commit_fs_roots+0xa1/0x140 [btrfs]
[ 5525.547277] [<ffffffffa01aeef9>] btrfs_commit_transaction
+0x349/0x750 [btrfs]
[ 5525.555448] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
[ 5525.561794] [<ffffffffa01a8593>] transaction_kthread+0x283/0x290
[btrfs]
[ 5525.569502] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 5525.577240] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 5525.584937] [<ffffffff810812d6>] kthread+0x96/0xa0
[ 5525.590737] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
[ 5525.597541] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
[ 5525.604742] [<ffffffff815a6360>] ? gs_change+0x13/0x13
[ 5525.610890] ---[ end trace 87e42fb6fdfeba7b ]---
[ 5525.616529] ------------[ cut here ]------------
[ 5525.622090] WARNING: at fs/btrfs/inode.c:2177
btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
[ 5525.631338] Hardware name: OptiPlex 780
[ 5525.637564] Modules linked in: i915 fbcon tileblit font
snd_hda_codec_analog bitblit btrfs softcursor drm_kms_helper drm
snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd zlib_deflate
psmouse crc32c i2c_algo_bit soundcore snd_page_alloc libcrc32c ppdev
intel_agp parport_pc lp intel_gtt dell_wmi video parport serio_raw
sparse_keymap dcdbas r8169 mii
[ 5525.672639] Pid: 581, comm: btrfs-transacti Tainted: G W
2.6.39-rc1 #1
[ 5525.680962] Call Trace:
[ 5525.684372] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
[ 5525.691350] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
[ 5525.698109] [<ffffffffa01b2ae0>] btrfs_orphan_commit_root+0xb0/0xc0
[btrfs]
[ 5525.706118] [<ffffffffa01adfd1>] commit_fs_roots+0xa1/0x140 [btrfs]
[ 5525.713891] [<ffffffffa01aeef9>] btrfs_commit_transaction
+0x349/0x750 [btrfs]
[ 5525.722184] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
[ 5525.728576] [<ffffffffa01a8593>] transaction_kthread+0x283/0x290
[btrfs]
[ 5525.736388] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 5525.744113] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 5525.751814] [<ffffffff810812d6>] kthread+0x96/0xa0
[ 5525.757584] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
[ 5525.764431] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
[ 5525.771625] [<ffffffff815a6360>] ? gs_change+0x13/0x13
[ 5525.777748] ---[ end trace 87e42fb6fdfeba7c ]---
[ 5586.710386] ------------[ cut here ]------------
[ 5586.715937] WARNING: at fs/btrfs/inode.c:2177
btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
[ 5586.725153] Hardware name: OptiPlex 780
[ 5586.731407] Modules linked in: i915 fbcon tileblit font
snd_hda_codec_analog bitblit btrfs softcursor drm_kms_helper drm
snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd zlib_deflate
psmouse crc32c i2c_algo_bit soundcore snd_page_alloc libcrc32c ppdev
intel_agp parport_pc lp intel_gtt dell_wmi video parport serio_raw
sparse_keymap dcdbas r8169 mii
[ 5586.766448] Pid: 581, comm: btrfs-transacti Tainted: G W
2.6.39-rc1 #1
[ 5586.774812] Call Trace:
[ 5586.778228] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
[ 5586.785203] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
[ 5586.791974] [<ffffffffa01b2ae0>] btrfs_orphan_commit_root+0xb0/0xc0
[btrfs]
[ 5586.799921] [<ffffffffa01adfd1>] commit_fs_roots+0xa1/0x140 [btrfs]
[ 5586.807285] [<ffffffffa01aeef9>] btrfs_commit_transaction
+0x349/0x750 [btrfs]
[ 5586.815511] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
[ 5586.821875] [<ffffffffa01a8593>] transaction_kthread+0x283/0x290
[btrfs]
[ 5586.829578] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 5586.837318] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 5586.845025] [<ffffffff810812d6>] kthread+0x96/0xa0
[ 5586.850839] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
[ 5586.857641] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
[ 5586.864856] [<ffffffff815a6360>] ? gs_change+0x13/0x13
[ 5586.871013] ---[ end trace 87e42fb6fdfeba7d ]---
[ 5617.300421] ------------[ cut here ]------------
[ 5617.306012] WARNING: at fs/btrfs/inode.c:2177
btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
[ 5617.315227] Hardware name: OptiPlex 780
[ 5617.321585] Modules linked in: i915 fbcon tileblit font
snd_hda_codec_analog bitblit btrfs softcursor drm_kms_helper drm
snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd zlib_deflate
psmouse crc32c i2c_algo_bit soundcore snd_page_alloc libcrc32c ppdev
intel_agp parport_pc lp intel_gtt dell_wmi video parport serio_raw
sparse_keymap dcdbas r8169 mii
[ 5617.356848] Pid: 581, comm: btrfs-transacti Tainted: G W
2.6.39-rc1 #1
[ 5617.365158] Call Trace:
[ 5617.368567] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
[ 5617.375756] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
[ 5617.382535] [<ffffffffa01b2ae0>] btrfs_orphan_commit_root+0xb0/0xc0
[btrfs]
[ 5617.390519] [<ffffffffa01adfd1>] commit_fs_roots+0xa1/0x140 [btrfs]
[ 5617.397774] [<ffffffffa01aeef9>] btrfs_commit_transaction
+0x349/0x750 [btrfs]
[ 5617.405957] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
[ 5617.412291] [<ffffffffa01a8593>] transaction_kthread+0x283/0x290
[btrfs]
[ 5617.420156] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 5617.427930] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 5617.435720] [<ffffffff810812d6>] kthread+0x96/0xa0
[ 5617.441578] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
[ 5617.448432] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
[ 5617.455691] [<ffffffff815a6360>] ? gs_change+0x13/0x13
[ 5617.461847] ---[ end trace 87e42fb6fdfeba7e ]---
[ 5678.310579] ------------[ cut here ]------------
[ 5678.316119] WARNING: at fs/btrfs/inode.c:2177
btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
[ 5678.325325] Hardware name: OptiPlex 780
[ 5678.331580] Modules linked in: i915 fbcon tileblit font
snd_hda_codec_analog bitblit btrfs softcursor drm_kms_helper drm
snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd zlib_deflate
psmouse crc32c i2c_algo_bit soundcore snd_page_alloc libcrc32c ppdev
intel_agp parport_pc lp intel_gtt dell_wmi video parport serio_raw
sparse_keymap dcdbas r8169 mii
[ 5678.366629] Pid: 581, comm: btrfs-transacti Tainted: G W
2.6.39-rc1 #1
[ 5678.374945] Call Trace:
[ 5678.378350] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
[ 5678.385319] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
[ 5678.392107] [<ffffffffa01b2ae0>] btrfs_orphan_commit_root+0xb0/0xc0
[btrfs]
[ 5678.400095] [<ffffffffa01adfd1>] commit_fs_roots+0xa1/0x140 [btrfs]
[ 5678.407357] [<ffffffffa01aeef9>] btrfs_commit_transaction
+0x349/0x750 [btrfs]
[ 5678.415611] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
[ 5678.422001] [<ffffffffa01a8593>] transaction_kthread+0x283/0x290
[btrfs]
[ 5678.429753] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 5678.437559] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 5678.445286] [<ffffffff810812d6>] kthread+0x96/0xa0
[ 5678.451078] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
[ 5678.457889] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
[ 5678.465112] [<ffffffff815a6360>] ? gs_change+0x13/0x13
[ 5678.471264] ---[ end trace 87e42fb6fdfeba
2 log2
..........................................................
[ 60.920012] CE: hpet5 increased min_delta_ns to 11250 nsec
[ 60.920016] hrtimer: interrupt took 4156 ns
[ 883.170006] CE: hpet4 increased min_delta_ns to 7500 nsec
[ 883.170013] CE: hpet4 increased min_delta_ns to 11250 nsec
[ 2118.980008] CE: hpet3 increased min_delta_ns to 7500 nsec
[ 2118.980016] CE: hpet3 increased min_delta_ns to 11250 nsec
[ 6794.650771]
[ 6794.650774] =============================================
[ 6794.657667] [ INFO: possible recursive locking detected ]
[ 6794.660144] 2.6.39-rc1 #1
[ 6794.660144] ---------------------------------------------
[ 6794.660144] cosd/2100 is trying to acquire lock:
[ 6794.660144] (&(&eb->lock)->rlock){+.+...}, at: [<ffffffffa029f5c9>]
btrfs_try_spin_lock+0x59/0x100 [btrfs]
[ 6794.660144]
[ 6794.660144] but task is already holding lock:
[ 6794.660144] (&(&eb->lock)->rlock){+.+...}, at: [<ffffffffa029f692>]
btrfs_clear_lock_blocking+0x22/0x30 [btrfs]
[ 6794.660144]
[ 6794.660144] other info that might help us debug this:
[ 6794.660144] 2 locks held by cosd/2100:
[ 6794.660144] #0: (&sb->s_type->i_mutex_key#13){+.+.+.}, at:
[<ffffffffa027af82>] btrfs_file_aio_write+0xe2/0x4e0 [btrfs]
[ 6794.660144] #1: (&(&eb->lock)->rlock){+.+...}, at:
[<ffffffffa029f692>] btrfs_clear_lock_blocking+0x22/0x30 [btrfs]
[ 6794.660144]
[ 6794.660144] stack backtrace:
[ 6794.660144] Pid: 2100, comm: cosd Not tainted 2.6.39-rc1 #1
[ 6794.660144] Call Trace:
[ 6794.660144] [<ffffffff8109b224>] __lock_acquire+0x1154/0x14b0
[ 6794.660144] [<ffffffff8109b620>] lock_acquire+0xa0/0x150
[ 6794.660144] [<ffffffffa029f5c9>] ? btrfs_try_spin_lock+0x59/0x100
[btrfs]
[ 6794.660144] [<ffffffff815ac341>] _raw_spin_lock+0x31/0x40
[ 6794.660144] [<ffffffffa029f5c9>] ? btrfs_try_spin_lock+0x59/0x100
[btrfs]
[ 6794.660144] [<ffffffffa029f692>] ? btrfs_clear_lock_blocking
+0x22/0x30 [btrfs]
[ 6794.660144] [<ffffffffa029f5c9>] btrfs_try_spin_lock+0x59/0x100
[btrfs]
[ 6794.660144] [<ffffffffa0250e72>] btrfs_search_slot+0x7d2/0x850
[btrfs]
[ 6794.660144] [<ffffffffa02618bf>] btrfs_lookup_xattr+0x8f/0x120
[btrfs]
[ 6794.660144] [<ffffffff8114e525>] ? kmem_cache_alloc+0xe5/0x140
[ 6794.660144] [<ffffffffa0288d23>] __btrfs_getxattr+0x73/0x130 [btrfs]
[ 6794.660144] [<ffffffffa0288e5d>] btrfs_getxattr+0x7d/0x80 [btrfs]
[ 6794.660144] [<ffffffff812769ed>] cap_inode_need_killpriv+0x2d/0x40
[ 6794.660144] [<ffffffff81277e26>] security_inode_need_killpriv
+0x16/0x20
[ 6794.660144] [<ffffffff81103a2c>] file_remove_suid+0x2c/0x90
[ 6794.660144] [<ffffffff815ab1b3>] ? mutex_lock_nested+0x43/0x50
[ 6794.660144] [<ffffffffa027b038>] btrfs_file_aio_write+0x198/0x4e0
[btrfs]
[ 6794.660144] [<ffffffffa027aea0>] ? __btrfs_buffered_write
+0x310/0x310 [btrfs]
[ 6794.660144] [<ffffffff8115bc93>] do_sync_readv_writev+0xd3/0x110
[ 6794.660144] [<ffffffff812ad9d8>] ? apparmor_file_permission
+0x18/0x20
[ 6794.660144] [<ffffffff81279473>] ? security_file_permission
+0x23/0x90
[ 6794.660144] [<ffffffff8115cb04>] do_readv_writev+0xd4/0x1e0
[ 6794.660144] [<ffffffff8115d230>] ? fget_light+0x40/0x130
[ 6794.660144] [<ffffffff81099dfd>] ? trace_hardirqs_on+0xd/0x10
[ 6794.660144] [<ffffffff8115cc58>] vfs_writev+0x48/0x60
[ 6794.660144] [<ffffffff8115cd91>] sys_writev+0x51/0xc0
[ 6794.660144] [<ffffffff815b4d02>] system_call_fastpath+0x16/0x1b
[ 6823.410755] ------------[ cut here ]------------
[ 6823.416129] WARNING: at fs/btrfs/inode.c:2177
btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
[ 6823.425136] Hardware name: OptiPlex 780
[ 6823.431205] Modules linked in: btrfs i915 snd_hda_codec_analog fbcon
zlib_deflate tileblit crc32c font bitblit libcrc32c softcursor
drm_kms_helper drm snd_hda_intel snd_hda_codec snd_hwdep snd_pcm
snd_timer snd psmouse intel_agp i2c_algo_bit lp ppdev soundcore
parport_pc snd_page_alloc intel_gtt dell_wmi parport video serio_raw
sparse_keymap r8169 ata_piix mii
[ 6823.465884] Pid: 580, comm: btrfs-transacti Not tainted 2.6.39-rc1 #1
[ 6823.473170] Call Trace:
[ 6823.476439] [<ffffffff8105fe9f>] warn_slowpath_common+0x7f/0xc0
[ 6823.483268] [<ffffffff8105fefa>] warn_slowpath_null+0x1a/0x20
[ 6823.489917] [<ffffffffa026fc20>] btrfs_orphan_commit_root+0xb0/0xc0
[btrfs]
[ 6823.497790] [<ffffffffa026b0b9>] commit_fs_roots+0xa9/0x150 [btrfs]
[ 6823.504951] [<ffffffffa026c00b>] btrfs_commit_transaction
+0x34b/0x750 [btrfs]
[ 6823.512976] [<ffffffff810837e0>] ? wake_up_bit+0x40/0x40
[ 6823.519176] [<ffffffffa02655c3>] transaction_kthread+0x283/0x290
[btrfs]
[ 6823.526763] [<ffffffffa0265340>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 6823.534354] [<ffffffff81083276>] kthread+0xb6/0xc0
[ 6823.539996] [<ffffffff81099dad>] ? trace_hardirqs_on_caller
+0x13d/0x180
[ 6823.547482] [<ffffffff815b5ea4>] kernel_thread_helper+0x4/0x10
[ 6823.554183] [<ffffffff815acf14>] ? retint_restore_args+0x13/0x13
[ 6823.561046] [<ffffffff810831c0>] ? __init_kthread_worker+0x70/0x70
[ 6823.568085] [<ffffffff815b5ea0>] ? gs_change+0x13/0x13
[ 6823.574077] ---[ end trace 98f2cdf99cfce110 ]---
[16334.260703] ------------[ cut here ]------------
[16334.266098] WARNING: at fs/btrfs/inode.c:2177
btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
[16334.275143] Hardware name: OptiPlex 780
[16334.281245] Modules linked in: btrfs i915 snd_hda_codec_analog fbcon
zlib_deflate tileblit crc32c font bitblit libcrc32c softcursor
drm_kms_helper drm snd_hda_intel snd_hda_codec snd_hwdep snd_pcm
snd_timer snd psmouse intel_agp i2c_algo_bit lp ppdev soundcore
parport_pc snd_page_alloc intel_gtt dell_wmi parport video serio_raw
sparse_keymap r8169 ata_piix mii
[16334.316186] Pid: 580, comm: btrfs-transacti Tainted: G W
2.6.39-rc1 #1
[16334.324378] Call Trace:
[16334.327715] [<ffffffff8105fe9f>] warn_slowpath_common+0x7f/0xc0
[16334.334668] [<ffffffff8105fefa>] warn_slowpath_null+0x1a/0x20
[16334.341452] [<ffffffffa026fc20>] btrfs_orphan_commit_root+0xb0/0xc0
[btrfs]
[16334.349448] [<ffffffffa026b0b9>] commit_fs_roots+0xa9/0x150 [btrfs]
[16334.356754] [<ffffffffa026c00b>] btrfs_commit_transaction
+0x34b/0x750 [btrfs]
[16334.364934] [<ffffffff810837e0>] ? wake_up_bit+0x40/0x40
[16334.371300] [<ffffffffa02655c3>] transaction_kthread+0x283/0x290
[btrfs]
[16334.379046] [<ffffffffa0265340>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[16334.386796] [<ffffffff81083276>] kthread+0xb6/0xc0
[16334.392625] [<ffffffff81099dad>] ? trace_hardirqs_on_caller
+0x13d/0x180
[16334.400305] [<ffffffff815b5ea4>] kernel_thread_helper+0x4/0x10
[16334.407185] [<ffffffff815acf14>] ? retint_restore_args+0x13/0x13
[16334.414251] [<ffffffff810831c0>] ? __init_kthread_worker+0x70/0x70
[16334.421486] [<ffffffff815b5ea0>] ? gs_change+0x13/0x13
[16334.427681] ---[ end trace 98f2cdf99cfce111 ]---
[16895.139537] btrfs: unlinked 27 orphans
[20373.435695] btrfs: unlinked 1 orphans
[23764.970722] ------------[ cut here ]------------
[23764.976339] WARNING: at fs/btrfs/inode.c:2177
btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
[23764.985578] Hardware name: OptiPlex 780
[23764.991863] Modules linked in: btrfs i915 snd_hda_codec_analog fbcon
zlib_deflate tileblit crc32c font bitblit libcrc32c softcursor
drm_kms_helper drm snd_hda_intel snd_hda_codec snd_hwdep snd_pcm
snd_timer snd psmouse intel_agp i2c_algo_bit lp ppdev soundcore
parport_pc snd_page_alloc intel_gtt dell_wmi parport video serio_raw
sparse_keymap r8169 ata_piix mii
[23765.027372] Pid: 580, comm: btrfs-transacti Tainted: G W
2.6.39-rc1 #1
[23765.035693] Call Trace:
[23765.039130] [<ffffffff8105fe9f>] warn_slowpath_common+0x7f/0xc0
[23765.046197] [<ffffffff8105fefa>] warn_slowpath_null+0x1a/0x20
[23765.053063] [<ffffffffa026fc20>] btrfs_orphan_commit_root+0xb0/0xc0
[btrfs]
[23765.061110] [<ffffffffa026b0b9>] commit_fs_roots+0xa9/0x150 [btrfs]
[23765.068443] [<ffffffffa026c00b>] btrfs_commit_transaction
+0x34b/0x750 [btrfs]
[23765.076646] [<ffffffff810837e0>] ? wake_up_bit+0x40/0x40
[23765.083031] [<ffffffffa02655c3>] transaction_kthread+0x283/0x290
[btrfs]
[23765.090826] [<ffffffffa0265340>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[23765.098603] [<ffffffff81083276>] kthread+0xb6/0xc0
[23765.104474] [<ffffffff81099dad>] ? trace_hardirqs_on_caller
+0x13d/0x180
[23765.112148] [<ffffffff815b5ea4>] kernel_thread_helper+0x4/0x10
[23765.118995] [<ffffffff815acf14>] ? retint_restore_args+0x13/0x13
[23765.126012] [<ffffffff810831c0>] ? __init_kthread_worker+0x70/0x70
[23765.133209] [<ffffffff815b5ea0>] ? gs_change+0x13/0x13
[23765.139359] ---[ end trace 98f2cdf99cfce112 ]---
Jeff ,Wu
^ permalink raw reply [flat|nested] 5+ messages in thread
* Re: 2.6.39-rc1: btrfs "WARNING: at fs/btrfs/inode.c:2177"
2011-04-07 9:41 2.6.39-rc1: btrfs "WARNING: at fs/btrfs/inode.c:2177" Jeff Wu
@ 2011-04-07 9:43 ` Wido den Hollander
2011-04-07 16:20 ` Josef Bacik
1 sibling, 0 replies; 5+ messages in thread
From: Wido den Hollander @ 2011-04-07 9:43 UTC (permalink / raw)
To: cpwu; +Cc: ceph-devel, linux-btrfs
Hi,
On Thu, 2011-04-07 at 17:41 +0800, Jeff Wu wrote:
> Hi ,
> I run iozone stress test on a ceph client for x86_64, ceph 0.26 +
> linux-2.6.39-rc1 server,
> printk "WARNING: at fs/btrfs/inode.c:2177"
>
> 1.log1 :
This is a known issue, see: http://tracker.newdream.net/issues/563
It has been passed upstream to the btrfs developers and they are now
working on it.
>
> ...........................................................
> [ 1663.370008] CE: hpet2 increased min_delta_ns to 7500 nsec
> [ 1663.375399] CE: hpet2 increased min_delta_ns to 11250 nsec
> [ 4945.270388] ------------[ cut here ]------------
> [ 4945.275011] WARNING: at fs/btrfs/inode.c:2177
> btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
> [ 4945.283326] Hardware name: OptiPlex 780
> [ 4945.288629] Modules linked in: i915 fbcon tileblit font
> snd_hda_codec_analog bitblit btrfs softcursor drm_kms_helper drm
> snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd zlib_deflate
> psmouse crc32c i2c_algo_bit soundcore snd_page_alloc libcrc32c ppdev
> intel_agp parport_pc lp intel_gtt dell_wmi video parport serio_raw
> sparse_keymap dcdbas r8169 mii
> [ 4945.320862] Pid: 581, comm: btrfs-transacti Not tainted 2.6.39-rc1 #1
> [ 4945.327282] Call Trace:
> [ 4945.329722] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
> [ 4945.335755] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
> [ 4945.341602] [<ffffffffa01b2ae0>] btrfs_orphan_commit_root+0xb0/0xc0
> [btrfs]
> [ 4945.348636] [<ffffffffa01adfd1>] commit_fs_roots+0xa1/0x140 [btrfs]
> [ 4945.355017] [<ffffffffa01aeef9>] btrfs_commit_transaction
> +0x349/0x750 [btrfs]
> [ 4945.362259] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
> [ 4945.367649] [<ffffffffa01a8593>] transaction_kthread+0x283/0x290
> [btrfs]
> [ 4945.374460] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 4945.381269] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 4945.388080] [<ffffffff810812d6>] kthread+0x96/0xa0
> [ 4945.392989] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
> [ 4945.398895] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
> [ 4945.405183] [<ffffffff815a6360>] ? gs_change+0x13/0x13
> [ 4945.410409] ---[ end trace 87e42fb6fdfeba78 ]---
> [ 5159.980447] ------------[ cut here ]------------
> [ 5159.985072] WARNING: at fs/btrfs/inode.c:2177
> btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
> [ 5159.993353] Hardware name: OptiPlex 780
> [ 5159.998648] Modules linked in: i915 fbcon tileblit font
> snd_hda_codec_analog bitblit btrfs softcursor drm_kms_helper drm
> snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd zlib_deflate
> psmouse crc32c i2c_algo_bit soundcore snd_page_alloc libcrc32c ppdev
> intel_agp parport_pc lp intel_gtt dell_wmi video parport serio_raw
> sparse_keymap dcdbas r8169 mii
> [ 5160.033593] Pid: 581, comm: btrfs-transacti Tainted: G W
> 2.6.39-rc1 #1
> [ 5160.041904] Call Trace:
> [ 5160.045326] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
> [ 5160.052312] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
> [ 5160.059102] [<ffffffffa01b2ae0>] btrfs_orphan_commit_root+0xb0/0xc0
> [btrfs]
> [ 5160.067143] [<ffffffffa01adfd1>] commit_fs_roots+0xa1/0x140 [btrfs]
> [ 5160.074485] [<ffffffffa01aeef9>] btrfs_commit_transaction
> +0x349/0x750 [btrfs]
> [ 5160.082687] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
> [ 5160.089034] [<ffffffffa01a8593>] transaction_kthread+0x283/0x290
> [btrfs]
> [ 5160.096811] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 5160.104568] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 5160.112294] [<ffffffff810812d6>] kthread+0x96/0xa0
> [ 5160.118093] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
> [ 5160.124964] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
> [ 5160.132166] [<ffffffff815a6360>] ? gs_change+0x13/0x13
> [ 5160.138292] ---[ end trace 87e42fb6fdfeba79 ]---
> [ 5434.340486] ------------[ cut here ]------------
> [ 5434.346091] WARNING: at fs/btrfs/inode.c:2177
> btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
> [ 5434.355303] Hardware name: OptiPlex 780
> [ 5434.361640] Modules linked in: i915 fbcon tileblit font
> snd_hda_codec_analog bitblit btrfs softcursor drm_kms_helper drm
> snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd zlib_deflate
> psmouse crc32c i2c_algo_bit soundcore snd_page_alloc libcrc32c ppdev
> intel_agp parport_pc lp intel_gtt dell_wmi video parport serio_raw
> sparse_keymap dcdbas r8169 mii
> [ 5434.396772] Pid: 581, comm: btrfs-transacti Tainted: G W
> 2.6.39-rc1 #1
> [ 5434.405087] Call Trace:
> [ 5434.408546] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
> [ 5434.415523] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
> [ 5434.422300] [<ffffffffa01b2ae0>] btrfs_orphan_commit_root+0xb0/0xc0
> [btrfs]
> [ 5434.430290] [<ffffffffa01adfd1>] commit_fs_roots+0xa1/0x140 [btrfs]
> [ 5434.437562] [<ffffffffa01aeef9>] btrfs_commit_transaction
> +0x349/0x750 [btrfs]
> [ 5434.445738] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
> [ 5434.452087] [<ffffffffa01a8593>] transaction_kthread+0x283/0x290
> [btrfs]
> [ 5434.459792] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 5434.467542] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 5434.475291] [<ffffffff810812d6>] kthread+0x96/0xa0
> [ 5434.481082] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
> [ 5434.487949] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
> [ 5434.495160] [<ffffffff815a6360>] ? gs_change+0x13/0x13
> [ 5434.501314] ---[ end trace 87e42fb6fdfeba7a ]---
> [ 5525.450514] ------------[ cut here ]------------
> [ 5525.456066] WARNING: at fs/btrfs/inode.c:2177
> btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
> [ 5525.465286] Hardware name: OptiPlex 780
> [ 5525.471543] Modules linked in: i915 fbcon tileblit font
> snd_hda_codec_analog bitblit btrfs softcursor drm_kms_helper drm
> snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd zlib_deflate
> psmouse crc32c i2c_algo_bit soundcore snd_page_alloc libcrc32c ppdev
> intel_agp parport_pc lp intel_gtt dell_wmi video parport serio_raw
> sparse_keymap dcdbas r8169 mii
> [ 5525.506568] Pid: 581, comm: btrfs-transacti Tainted: G W
> 2.6.39-rc1 #1
> [ 5525.514879] Call Trace:
> [ 5525.518281] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
> [ 5525.525251] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
> [ 5525.532037] [<ffffffffa01b2ae0>] btrfs_orphan_commit_root+0xb0/0xc0
> [btrfs]
> [ 5525.540022] [<ffffffffa01adfd1>] commit_fs_roots+0xa1/0x140 [btrfs]
> [ 5525.547277] [<ffffffffa01aeef9>] btrfs_commit_transaction
> +0x349/0x750 [btrfs]
> [ 5525.555448] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
> [ 5525.561794] [<ffffffffa01a8593>] transaction_kthread+0x283/0x290
> [btrfs]
> [ 5525.569502] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 5525.577240] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 5525.584937] [<ffffffff810812d6>] kthread+0x96/0xa0
> [ 5525.590737] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
> [ 5525.597541] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
> [ 5525.604742] [<ffffffff815a6360>] ? gs_change+0x13/0x13
> [ 5525.610890] ---[ end trace 87e42fb6fdfeba7b ]---
> [ 5525.616529] ------------[ cut here ]------------
> [ 5525.622090] WARNING: at fs/btrfs/inode.c:2177
> btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
> [ 5525.631338] Hardware name: OptiPlex 780
> [ 5525.637564] Modules linked in: i915 fbcon tileblit font
> snd_hda_codec_analog bitblit btrfs softcursor drm_kms_helper drm
> snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd zlib_deflate
> psmouse crc32c i2c_algo_bit soundcore snd_page_alloc libcrc32c ppdev
> intel_agp parport_pc lp intel_gtt dell_wmi video parport serio_raw
> sparse_keymap dcdbas r8169 mii
> [ 5525.672639] Pid: 581, comm: btrfs-transacti Tainted: G W
> 2.6.39-rc1 #1
> [ 5525.680962] Call Trace:
> [ 5525.684372] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
> [ 5525.691350] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
> [ 5525.698109] [<ffffffffa01b2ae0>] btrfs_orphan_commit_root+0xb0/0xc0
> [btrfs]
> [ 5525.706118] [<ffffffffa01adfd1>] commit_fs_roots+0xa1/0x140 [btrfs]
> [ 5525.713891] [<ffffffffa01aeef9>] btrfs_commit_transaction
> +0x349/0x750 [btrfs]
> [ 5525.722184] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
> [ 5525.728576] [<ffffffffa01a8593>] transaction_kthread+0x283/0x290
> [btrfs]
> [ 5525.736388] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 5525.744113] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 5525.751814] [<ffffffff810812d6>] kthread+0x96/0xa0
> [ 5525.757584] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
> [ 5525.764431] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
> [ 5525.771625] [<ffffffff815a6360>] ? gs_change+0x13/0x13
> [ 5525.777748] ---[ end trace 87e42fb6fdfeba7c ]---
> [ 5586.710386] ------------[ cut here ]------------
> [ 5586.715937] WARNING: at fs/btrfs/inode.c:2177
> btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
> [ 5586.725153] Hardware name: OptiPlex 780
> [ 5586.731407] Modules linked in: i915 fbcon tileblit font
> snd_hda_codec_analog bitblit btrfs softcursor drm_kms_helper drm
> snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd zlib_deflate
> psmouse crc32c i2c_algo_bit soundcore snd_page_alloc libcrc32c ppdev
> intel_agp parport_pc lp intel_gtt dell_wmi video parport serio_raw
> sparse_keymap dcdbas r8169 mii
> [ 5586.766448] Pid: 581, comm: btrfs-transacti Tainted: G W
> 2.6.39-rc1 #1
> [ 5586.774812] Call Trace:
> [ 5586.778228] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
> [ 5586.785203] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
> [ 5586.791974] [<ffffffffa01b2ae0>] btrfs_orphan_commit_root+0xb0/0xc0
> [btrfs]
> [ 5586.799921] [<ffffffffa01adfd1>] commit_fs_roots+0xa1/0x140 [btrfs]
> [ 5586.807285] [<ffffffffa01aeef9>] btrfs_commit_transaction
> +0x349/0x750 [btrfs]
> [ 5586.815511] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
> [ 5586.821875] [<ffffffffa01a8593>] transaction_kthread+0x283/0x290
> [btrfs]
> [ 5586.829578] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 5586.837318] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 5586.845025] [<ffffffff810812d6>] kthread+0x96/0xa0
> [ 5586.850839] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
> [ 5586.857641] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
> [ 5586.864856] [<ffffffff815a6360>] ? gs_change+0x13/0x13
> [ 5586.871013] ---[ end trace 87e42fb6fdfeba7d ]---
> [ 5617.300421] ------------[ cut here ]------------
> [ 5617.306012] WARNING: at fs/btrfs/inode.c:2177
> btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
> [ 5617.315227] Hardware name: OptiPlex 780
> [ 5617.321585] Modules linked in: i915 fbcon tileblit font
> snd_hda_codec_analog bitblit btrfs softcursor drm_kms_helper drm
> snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd zlib_deflate
> psmouse crc32c i2c_algo_bit soundcore snd_page_alloc libcrc32c ppdev
> intel_agp parport_pc lp intel_gtt dell_wmi video parport serio_raw
> sparse_keymap dcdbas r8169 mii
> [ 5617.356848] Pid: 581, comm: btrfs-transacti Tainted: G W
> 2.6.39-rc1 #1
> [ 5617.365158] Call Trace:
> [ 5617.368567] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
> [ 5617.375756] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
> [ 5617.382535] [<ffffffffa01b2ae0>] btrfs_orphan_commit_root+0xb0/0xc0
> [btrfs]
> [ 5617.390519] [<ffffffffa01adfd1>] commit_fs_roots+0xa1/0x140 [btrfs]
> [ 5617.397774] [<ffffffffa01aeef9>] btrfs_commit_transaction
> +0x349/0x750 [btrfs]
> [ 5617.405957] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
> [ 5617.412291] [<ffffffffa01a8593>] transaction_kthread+0x283/0x290
> [btrfs]
> [ 5617.420156] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 5617.427930] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 5617.435720] [<ffffffff810812d6>] kthread+0x96/0xa0
> [ 5617.441578] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
> [ 5617.448432] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
> [ 5617.455691] [<ffffffff815a6360>] ? gs_change+0x13/0x13
> [ 5617.461847] ---[ end trace 87e42fb6fdfeba7e ]---
> [ 5678.310579] ------------[ cut here ]------------
> [ 5678.316119] WARNING: at fs/btrfs/inode.c:2177
> btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
> [ 5678.325325] Hardware name: OptiPlex 780
> [ 5678.331580] Modules linked in: i915 fbcon tileblit font
> snd_hda_codec_analog bitblit btrfs softcursor drm_kms_helper drm
> snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_timer snd zlib_deflate
> psmouse crc32c i2c_algo_bit soundcore snd_page_alloc libcrc32c ppdev
> intel_agp parport_pc lp intel_gtt dell_wmi video parport serio_raw
> sparse_keymap dcdbas r8169 mii
> [ 5678.366629] Pid: 581, comm: btrfs-transacti Tainted: G W
> 2.6.39-rc1 #1
> [ 5678.374945] Call Trace:
> [ 5678.378350] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
> [ 5678.385319] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
> [ 5678.392107] [<ffffffffa01b2ae0>] btrfs_orphan_commit_root+0xb0/0xc0
> [btrfs]
> [ 5678.400095] [<ffffffffa01adfd1>] commit_fs_roots+0xa1/0x140 [btrfs]
> [ 5678.407357] [<ffffffffa01aeef9>] btrfs_commit_transaction
> +0x349/0x750 [btrfs]
> [ 5678.415611] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
> [ 5678.422001] [<ffffffffa01a8593>] transaction_kthread+0x283/0x290
> [btrfs]
> [ 5678.429753] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 5678.437559] [<ffffffffa01a8310>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 5678.445286] [<ffffffff810812d6>] kthread+0x96/0xa0
> [ 5678.451078] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
> [ 5678.457889] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
> [ 5678.465112] [<ffffffff815a6360>] ? gs_change+0x13/0x13
> [ 5678.471264] ---[ end trace 87e42fb6fdfeba
>
> 2 log2
>
> ..........................................................
> [ 60.920012] CE: hpet5 increased min_delta_ns to 11250 nsec
> [ 60.920016] hrtimer: interrupt took 4156 ns
> [ 883.170006] CE: hpet4 increased min_delta_ns to 7500 nsec
> [ 883.170013] CE: hpet4 increased min_delta_ns to 11250 nsec
> [ 2118.980008] CE: hpet3 increased min_delta_ns to 7500 nsec
> [ 2118.980016] CE: hpet3 increased min_delta_ns to 11250 nsec
> [ 6794.650771]
> [ 6794.650774] =============================================
> [ 6794.657667] [ INFO: possible recursive locking detected ]
> [ 6794.660144] 2.6.39-rc1 #1
> [ 6794.660144] ---------------------------------------------
> [ 6794.660144] cosd/2100 is trying to acquire lock:
> [ 6794.660144] (&(&eb->lock)->rlock){+.+...}, at: [<ffffffffa029f5c9>]
> btrfs_try_spin_lock+0x59/0x100 [btrfs]
> [ 6794.660144]
> [ 6794.660144] but task is already holding lock:
> [ 6794.660144] (&(&eb->lock)->rlock){+.+...}, at: [<ffffffffa029f692>]
> btrfs_clear_lock_blocking+0x22/0x30 [btrfs]
> [ 6794.660144]
> [ 6794.660144] other info that might help us debug this:
> [ 6794.660144] 2 locks held by cosd/2100:
> [ 6794.660144] #0: (&sb->s_type->i_mutex_key#13){+.+.+.}, at:
> [<ffffffffa027af82>] btrfs_file_aio_write+0xe2/0x4e0 [btrfs]
> [ 6794.660144] #1: (&(&eb->lock)->rlock){+.+...}, at:
> [<ffffffffa029f692>] btrfs_clear_lock_blocking+0x22/0x30 [btrfs]
> [ 6794.660144]
> [ 6794.660144] stack backtrace:
> [ 6794.660144] Pid: 2100, comm: cosd Not tainted 2.6.39-rc1 #1
> [ 6794.660144] Call Trace:
> [ 6794.660144] [<ffffffff8109b224>] __lock_acquire+0x1154/0x14b0
> [ 6794.660144] [<ffffffff8109b620>] lock_acquire+0xa0/0x150
> [ 6794.660144] [<ffffffffa029f5c9>] ? btrfs_try_spin_lock+0x59/0x100
> [btrfs]
> [ 6794.660144] [<ffffffff815ac341>] _raw_spin_lock+0x31/0x40
> [ 6794.660144] [<ffffffffa029f5c9>] ? btrfs_try_spin_lock+0x59/0x100
> [btrfs]
> [ 6794.660144] [<ffffffffa029f692>] ? btrfs_clear_lock_blocking
> +0x22/0x30 [btrfs]
> [ 6794.660144] [<ffffffffa029f5c9>] btrfs_try_spin_lock+0x59/0x100
> [btrfs]
> [ 6794.660144] [<ffffffffa0250e72>] btrfs_search_slot+0x7d2/0x850
> [btrfs]
> [ 6794.660144] [<ffffffffa02618bf>] btrfs_lookup_xattr+0x8f/0x120
> [btrfs]
> [ 6794.660144] [<ffffffff8114e525>] ? kmem_cache_alloc+0xe5/0x140
> [ 6794.660144] [<ffffffffa0288d23>] __btrfs_getxattr+0x73/0x130 [btrfs]
> [ 6794.660144] [<ffffffffa0288e5d>] btrfs_getxattr+0x7d/0x80 [btrfs]
> [ 6794.660144] [<ffffffff812769ed>] cap_inode_need_killpriv+0x2d/0x40
> [ 6794.660144] [<ffffffff81277e26>] security_inode_need_killpriv
> +0x16/0x20
> [ 6794.660144] [<ffffffff81103a2c>] file_remove_suid+0x2c/0x90
> [ 6794.660144] [<ffffffff815ab1b3>] ? mutex_lock_nested+0x43/0x50
> [ 6794.660144] [<ffffffffa027b038>] btrfs_file_aio_write+0x198/0x4e0
> [btrfs]
> [ 6794.660144] [<ffffffffa027aea0>] ? __btrfs_buffered_write
> +0x310/0x310 [btrfs]
> [ 6794.660144] [<ffffffff8115bc93>] do_sync_readv_writev+0xd3/0x110
> [ 6794.660144] [<ffffffff812ad9d8>] ? apparmor_file_permission
> +0x18/0x20
> [ 6794.660144] [<ffffffff81279473>] ? security_file_permission
> +0x23/0x90
> [ 6794.660144] [<ffffffff8115cb04>] do_readv_writev+0xd4/0x1e0
> [ 6794.660144] [<ffffffff8115d230>] ? fget_light+0x40/0x130
> [ 6794.660144] [<ffffffff81099dfd>] ? trace_hardirqs_on+0xd/0x10
> [ 6794.660144] [<ffffffff8115cc58>] vfs_writev+0x48/0x60
> [ 6794.660144] [<ffffffff8115cd91>] sys_writev+0x51/0xc0
> [ 6794.660144] [<ffffffff815b4d02>] system_call_fastpath+0x16/0x1b
> [ 6823.410755] ------------[ cut here ]------------
> [ 6823.416129] WARNING: at fs/btrfs/inode.c:2177
> btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
> [ 6823.425136] Hardware name: OptiPlex 780
> [ 6823.431205] Modules linked in: btrfs i915 snd_hda_codec_analog fbcon
> zlib_deflate tileblit crc32c font bitblit libcrc32c softcursor
> drm_kms_helper drm snd_hda_intel snd_hda_codec snd_hwdep snd_pcm
> snd_timer snd psmouse intel_agp i2c_algo_bit lp ppdev soundcore
> parport_pc snd_page_alloc intel_gtt dell_wmi parport video serio_raw
> sparse_keymap r8169 ata_piix mii
> [ 6823.465884] Pid: 580, comm: btrfs-transacti Not tainted 2.6.39-rc1 #1
> [ 6823.473170] Call Trace:
> [ 6823.476439] [<ffffffff8105fe9f>] warn_slowpath_common+0x7f/0xc0
> [ 6823.483268] [<ffffffff8105fefa>] warn_slowpath_null+0x1a/0x20
> [ 6823.489917] [<ffffffffa026fc20>] btrfs_orphan_commit_root+0xb0/0xc0
> [btrfs]
> [ 6823.497790] [<ffffffffa026b0b9>] commit_fs_roots+0xa9/0x150 [btrfs]
> [ 6823.504951] [<ffffffffa026c00b>] btrfs_commit_transaction
> +0x34b/0x750 [btrfs]
> [ 6823.512976] [<ffffffff810837e0>] ? wake_up_bit+0x40/0x40
> [ 6823.519176] [<ffffffffa02655c3>] transaction_kthread+0x283/0x290
> [btrfs]
> [ 6823.526763] [<ffffffffa0265340>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [ 6823.534354] [<ffffffff81083276>] kthread+0xb6/0xc0
> [ 6823.539996] [<ffffffff81099dad>] ? trace_hardirqs_on_caller
> +0x13d/0x180
> [ 6823.547482] [<ffffffff815b5ea4>] kernel_thread_helper+0x4/0x10
> [ 6823.554183] [<ffffffff815acf14>] ? retint_restore_args+0x13/0x13
> [ 6823.561046] [<ffffffff810831c0>] ? __init_kthread_worker+0x70/0x70
> [ 6823.568085] [<ffffffff815b5ea0>] ? gs_change+0x13/0x13
> [ 6823.574077] ---[ end trace 98f2cdf99cfce110 ]---
> [16334.260703] ------------[ cut here ]------------
> [16334.266098] WARNING: at fs/btrfs/inode.c:2177
> btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
> [16334.275143] Hardware name: OptiPlex 780
> [16334.281245] Modules linked in: btrfs i915 snd_hda_codec_analog fbcon
> zlib_deflate tileblit crc32c font bitblit libcrc32c softcursor
> drm_kms_helper drm snd_hda_intel snd_hda_codec snd_hwdep snd_pcm
> snd_timer snd psmouse intel_agp i2c_algo_bit lp ppdev soundcore
> parport_pc snd_page_alloc intel_gtt dell_wmi parport video serio_raw
> sparse_keymap r8169 ata_piix mii
> [16334.316186] Pid: 580, comm: btrfs-transacti Tainted: G W
> 2.6.39-rc1 #1
> [16334.324378] Call Trace:
> [16334.327715] [<ffffffff8105fe9f>] warn_slowpath_common+0x7f/0xc0
> [16334.334668] [<ffffffff8105fefa>] warn_slowpath_null+0x1a/0x20
> [16334.341452] [<ffffffffa026fc20>] btrfs_orphan_commit_root+0xb0/0xc0
> [btrfs]
> [16334.349448] [<ffffffffa026b0b9>] commit_fs_roots+0xa9/0x150 [btrfs]
> [16334.356754] [<ffffffffa026c00b>] btrfs_commit_transaction
> +0x34b/0x750 [btrfs]
> [16334.364934] [<ffffffff810837e0>] ? wake_up_bit+0x40/0x40
> [16334.371300] [<ffffffffa02655c3>] transaction_kthread+0x283/0x290
> [btrfs]
> [16334.379046] [<ffffffffa0265340>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [16334.386796] [<ffffffff81083276>] kthread+0xb6/0xc0
> [16334.392625] [<ffffffff81099dad>] ? trace_hardirqs_on_caller
> +0x13d/0x180
> [16334.400305] [<ffffffff815b5ea4>] kernel_thread_helper+0x4/0x10
> [16334.407185] [<ffffffff815acf14>] ? retint_restore_args+0x13/0x13
> [16334.414251] [<ffffffff810831c0>] ? __init_kthread_worker+0x70/0x70
> [16334.421486] [<ffffffff815b5ea0>] ? gs_change+0x13/0x13
> [16334.427681] ---[ end trace 98f2cdf99cfce111 ]---
> [16895.139537] btrfs: unlinked 27 orphans
> [20373.435695] btrfs: unlinked 1 orphans
> [23764.970722] ------------[ cut here ]------------
> [23764.976339] WARNING: at fs/btrfs/inode.c:2177
> btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
> [23764.985578] Hardware name: OptiPlex 780
> [23764.991863] Modules linked in: btrfs i915 snd_hda_codec_analog fbcon
> zlib_deflate tileblit crc32c font bitblit libcrc32c softcursor
> drm_kms_helper drm snd_hda_intel snd_hda_codec snd_hwdep snd_pcm
> snd_timer snd psmouse intel_agp i2c_algo_bit lp ppdev soundcore
> parport_pc snd_page_alloc intel_gtt dell_wmi parport video serio_raw
> sparse_keymap r8169 ata_piix mii
> [23765.027372] Pid: 580, comm: btrfs-transacti Tainted: G W
> 2.6.39-rc1 #1
> [23765.035693] Call Trace:
> [23765.039130] [<ffffffff8105fe9f>] warn_slowpath_common+0x7f/0xc0
> [23765.046197] [<ffffffff8105fefa>] warn_slowpath_null+0x1a/0x20
> [23765.053063] [<ffffffffa026fc20>] btrfs_orphan_commit_root+0xb0/0xc0
> [btrfs]
> [23765.061110] [<ffffffffa026b0b9>] commit_fs_roots+0xa9/0x150 [btrfs]
> [23765.068443] [<ffffffffa026c00b>] btrfs_commit_transaction
> +0x34b/0x750 [btrfs]
> [23765.076646] [<ffffffff810837e0>] ? wake_up_bit+0x40/0x40
> [23765.083031] [<ffffffffa02655c3>] transaction_kthread+0x283/0x290
> [btrfs]
> [23765.090826] [<ffffffffa0265340>] ? btrfs_bio_wq_end_io+0x90/0x90
> [btrfs]
> [23765.098603] [<ffffffff81083276>] kthread+0xb6/0xc0
> [23765.104474] [<ffffffff81099dad>] ? trace_hardirqs_on_caller
> +0x13d/0x180
> [23765.112148] [<ffffffff815b5ea4>] kernel_thread_helper+0x4/0x10
> [23765.118995] [<ffffffff815acf14>] ? retint_restore_args+0x13/0x13
> [23765.126012] [<ffffffff810831c0>] ? __init_kthread_worker+0x70/0x70
> [23765.133209] [<ffffffff815b5ea0>] ? gs_change+0x13/0x13
> [23765.139359] ---[ end trace 98f2cdf99cfce112 ]---
>
> Jeff ,Wu
>
>
> --
> To unsubscribe from this list: send the line "unsubscribe ceph-devel" in
> the body of a message to majordomo@vger.kernel.org
> More majordomo info at http://vger.kernel.org/majordomo-info.html
^ permalink raw reply [flat|nested] 5+ messages in thread
* Re: 2.6.39-rc1: btrfs "WARNING: at fs/btrfs/inode.c:2177"
2011-04-07 9:41 2.6.39-rc1: btrfs "WARNING: at fs/btrfs/inode.c:2177" Jeff Wu
2011-04-07 9:43 ` Wido den Hollander
@ 2011-04-07 16:20 ` Josef Bacik
2011-04-08 5:53 ` Jeff Wu
1 sibling, 1 reply; 5+ messages in thread
From: Josef Bacik @ 2011-04-07 16:20 UTC (permalink / raw)
To: cpwu; +Cc: ceph-devel, linux-btrfs
[-- Attachment #1: Type: text/plain, Size: 338 bytes --]
On 04/07/2011 05:41 AM, Jeff Wu wrote:
> Hi ,
> I run iozone stress test on a ceph client for x86_64, ceph 0.26 +
> linux-2.6.39-rc1 server,
> printk "WARNING: at fs/btrfs/inode.c:2177"
>
Crap I was hoping I had fixed this, could you run with this debug patch
and get me the output so I can figure out what's going on? Thanks,
Josef
[-- Attachment #2: debug.patch --]
[-- Type: text/plain, Size: 330 bytes --]
diff --git a/fs/btrfs/extent-tree.c b/fs/btrfs/extent-tree.c
index f619c3c..79ec933 100644
--- a/fs/btrfs/extent-tree.c
+++ b/fs/btrfs/extent-tree.c
@@ -3696,6 +3696,7 @@ int btrfs_block_rsv_add(struct btrfs_trans_handle *trans,
{
int ret;
+ WARN_ON(block_rsv == root->orphan_block_rsv);
if (num_bytes == 0)
return 0;
^ permalink raw reply related [flat|nested] 5+ messages in thread
* Re: 2.6.39-rc1: btrfs "WARNING: at fs/btrfs/inode.c:2177"
2011-04-07 16:20 ` Josef Bacik
@ 2011-04-08 5:53 ` Jeff Wu
2011-04-08 14:38 ` Josef Bacik
0 siblings, 1 reply; 5+ messages in thread
From: Jeff Wu @ 2011-04-08 5:53 UTC (permalink / raw)
To: Josef Bacik; +Cc: ceph-devel, linux-btrfs@vger.kernel.org
Hi ,
I applied the patch to 2.6.39-rc1,took the following steps to compile
it:make && make modules_install && make install && mkinitramfs=20
but , it seam that it don't run to "WARN_ON(block_rsv =3D=3D
root->orphan_block_rsv);"
i attached the codes and logs at the below:
=2E...........................................
int btrfs_block_rsv_add(struct btrfs_trans_handle *trans,
struct btrfs_root *root,
struct btrfs_block_rsv *block_rsv,
u64 num_bytes)
{
int ret;
WARN_ON(block_rsv =3D=3D root->orphan_block_rsv);
if (num_bytes =3D=3D 0)
return 0;
ret =3D reserve_metadata_bytes(trans, root, block_rsv, num_byte=
s,
1);
if (!ret) {
block_rsv_add_bytes(block_rsv, num_bytes, 1);
return 0;
}
return ret;
}
=2E.................................................
1.log1
=2E.....................................................
[ 147.740003] CE: hpet5 increased min_delta_ns to 7500 nsec
[ 147.740012] CE: hpet5 increased min_delta_ns to 11250 nsec
[ 148.520005] CE: hpet4 increased min_delta_ns to 7500 nsec
[ 148.520012] CE: hpet4 increased min_delta_ns to 11250 nsec
[ 2561.740727] ------------[ cut here ]------------
[ 2561.740746] WARNING: at fs/btrfs/inode.c:2177
btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
[ 2561.740748] Hardware name: OptiPlex 780 =20
[ 2561.740750] Modules linked in: i915 btrfs fbcon tileblit font
snd_hda_codec_analog bitblit softcursor drm_kms_helper drm snd_hda_inte=
l
snd_hda_codec snd_hwdep snd_pcm zlib_deflate crc32c snd_timer libcrc32c
snd psmouse i2c_algo_bit ppdev intel_agp parport_pc soundcore lp
intel_gtt snd_page_alloc video dell_wmi parport serio_raw sparse_keymap
r8169 mii ata_piix
[ 2561.740781] Pid: 570, comm: btrfs-transacti Not tainted 2.6.39-rc1 #=
2
[ 2561.740783] Call Trace:
[ 2561.740789] [<ffffffff8105fe9f>] warn_slowpath_common+0x7f/0xc0
[ 2561.740793] [<ffffffff8105fefa>] warn_slowpath_null+0x1a/0x20
[ 2561.740803] [<ffffffffa01ddc50>] btrfs_orphan_commit_root+0xb0/0xc0
[btrfs]
[ 2561.740813] [<ffffffffa01d90e9>] commit_fs_roots+0xa9/0x150 [btrfs]
[ 2561.740824] [<ffffffffa01da03b>] btrfs_commit_transaction
+0x34b/0x750 [btrfs]
[ 2561.740828] [<ffffffff810837e0>] ? wake_up_bit+0x40/0x40
[ 2561.740838] [<ffffffffa01d35f3>] transaction_kthread+0x283/0x290
[btrfs]
[ 2561.740848] [<ffffffffa01d3370>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 2561.740851] [<ffffffff81083276>] kthread+0xb6/0xc0
[ 2561.740854] [<ffffffff81099dad>] ? trace_hardirqs_on_caller
+0x13d/0x180
[ 2561.740859] [<ffffffff815b5ea4>] kernel_thread_helper+0x4/0x10
[ 2561.740862] [<ffffffff815acf14>] ? retint_restore_args+0x13/0x13
[ 2561.740865] [<ffffffff810831c0>] ? __init_kthread_worker+0x70/0x70
[ 2561.740868] [<ffffffff815b5ea0>] ? gs_change+0x13/0x13
[ 2561.740870] ---[ end trace c68c126da4200e73 ]---
[ 2655.461017]=20
[ 2655.461019] =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=
=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=
=3D=3D
[ 2655.467908] [ INFO: possible recursive locking detected ]
[ 2655.470882] 2.6.39-rc1 #2
[ 2655.470882] ---------------------------------------------
[ 2655.470882] cosd/2420 is trying to acquire lock:
[ 2655.470882] (&(&eb->lock)->rlock){+.+...}, at: [<ffffffffa020d5f9>]
btrfs_try_spin_lock+0x59/0x100 [btrfs]
[ 2655.470882]=20
[ 2655.470882] but task is already holding lock:
[ 2655.470882] (&(&eb->lock)->rlock){+.+...}, at: [<ffffffffa020d6c2>]
btrfs_clear_lock_blocking+0x22/0x30 [btrfs]
[ 2655.470882]=20
[ 2655.470882] other info that might help us debug this:
[ 2655.470882] 2 locks held by cosd/2420:
[ 2655.470882] #0: (&sb->s_type->i_mutex_key#13){+.+.+.}, at:
[<ffffffff811694e5>] do_last+0x2f5/0x8a0
[ 2655.470882] #1: (&(&eb->lock)->rlock){+.+...}, at:
[<ffffffffa020d6c2>] btrfs_clear_lock_blocking+0x22/0x30 [btrfs]
[ 2655.470882]=20
[ 2655.470882] stack backtrace:
[ 2655.470882] Pid: 2420, comm: cosd Tainted: G W 2.6.39-rc1 #=
2
[ 2655.470882] Call Trace:
[ 2655.470882] [<ffffffff8109b224>] __lock_acquire+0x1154/0x14b0
[ 2655.470882] [<ffffffff8109b620>] lock_acquire+0xa0/0x150
[ 2655.470882] [<ffffffffa020d5f9>] ? btrfs_try_spin_lock+0x59/0x100
[btrfs]
[ 2655.470882] [<ffffffff815ac341>] _raw_spin_lock+0x31/0x40
[ 2655.470882] [<ffffffffa020d5f9>] ? btrfs_try_spin_lock+0x59/0x100
[btrfs]
[ 2655.470882] [<ffffffffa020d6c2>] ? btrfs_clear_lock_blocking
+0x22/0x30 [btrfs]
[ 2655.470882] [<ffffffffa020d5f9>] btrfs_try_spin_lock+0x59/0x100
[btrfs]
[ 2655.470882] [<ffffffffa01bee72>] btrfs_search_slot+0x7d2/0x850
[btrfs]
[ 2655.470882] [<ffffffffa01cfa02>] btrfs_lookup_dir_item+0x82/0x110
[btrfs]
[ 2655.470882] [<ffffffff8114e525>] ? kmem_cache_alloc+0xe5/0x140
[ 2655.470882] [<ffffffffa01e2b81>] btrfs_lookup_dentry+0xa1/0x4b0
[btrfs]
[ 2655.470882] [<ffffffff81171911>] ? d_alloc+0x141/0x1e0
[ 2655.470882] [<ffffffff81099dfd>] ? trace_hardirqs_on+0xd/0x10
[ 2655.470882] [<ffffffff812e63ee>] ? do_raw_spin_unlock+0x5e/0xb0
[ 2655.470882] [<ffffffffa01e2fa6>] btrfs_lookup+0x16/0x30 [btrfs]
[ 2655.470882] [<ffffffff81166ba5>] d_alloc_and_lookup+0x45/0x90
[ 2655.470882] [<ffffffff81172195>] ? d_lookup+0x35/0x60
[ 2655.470882] [<ffffffff81166cce>] __lookup_hash+0xde/0x180
[ 2655.470882] [<ffffffff811694f5>] do_last+0x305/0x8a0
[ 2655.470882] [<ffffffff8116a9fd>] path_openat+0xcd/0x3f0
[ 2655.470882] [<ffffffff8116ae6f>] do_filp_open+0x7f/0xa0
[ 2655.470882] [<ffffffff815acb9b>] ? _raw_spin_unlock+0x2b/0x40
[ 2655.470882] [<ffffffff81178e4a>] ? alloc_fd+0xfa/0x140
[ 2655.470882] [<ffffffff8115b684>] do_sys_open+0x104/0x1e0
[ 2655.470882] [<ffffffff8115b7a0>] sys_open+0x20/0x30
[ 2655.470882] [<ffffffff815b4d02>] system_call_fastpath+0x16/0x1b
2.log2
=2E..............................................................
[ 156.240012] CE: hpet2 increased min_delta_ns to 11250 nsec
[ 167.490003] CE: hpet4 increased min_delta_ns to 7500 nsec
[ 167.490010] CE: hpet4 increased min_delta_ns to 11250 nsec
[ 2601.060330] ------------[ cut here ]------------
[ 2601.060347] WARNING: at fs/btrfs/inode.c:2177
btrfs_orphan_commit_root+0xb0/0xc0 [btrfs]()
[ 2601.060349] Hardware name: OptiPlex 780 =20
[ 2601.060350] Modules linked in: btrfs zlib_deflate crc32c libcrc32c
i915 snd_hda_codec_analog fbcon tileblit font bitblit softcursor
drm_kms_helper drm snd_hda_intel snd_hda_codec snd_hwdep snd_pcm
snd_timer snd soundcore psmouse i2c_algo_bit snd_page_alloc lp intel_ag=
p
ppdev video intel_gtt parport_pc dell_wmi parport serio_raw
sparse_keymap dcdbas r8169 mii
[ 2601.060435] Pid: 586, comm: btrfs-transacti Not tainted 2.6.39-rc1 #=
2
[ 2601.060436] Call Trace:
[ 2601.060443] [<ffffffff8105f13f>] warn_slowpath_common+0x7f/0xc0
[ 2601.060446] [<ffffffff8105f19a>] warn_slowpath_null+0x1a/0x20
[ 2601.060456] [<ffffffffa024cb20>] btrfs_orphan_commit_root+0xb0/0xc0
[btrfs]
[ 2601.060466] [<ffffffffa0248011>] commit_fs_roots+0xa1/0x140 [btrfs]
[ 2601.060476] [<ffffffffa0248f39>] btrfs_commit_transaction
+0x349/0x750 [btrfs]
[ 2601.060480] [<ffffffff81081810>] ? wake_up_bit+0x40/0x40
[ 2601.060489] [<ffffffffa02425d3>] transaction_kthread+0x283/0x290
[btrfs]
[ 2601.060499] [<ffffffffa0242350>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 2601.060508] [<ffffffffa0242350>] ? btrfs_bio_wq_end_io+0x90/0x90
[btrfs]
[ 2601.060511] [<ffffffff810812d6>] kthread+0x96/0xa0
[ 2601.060514] [<ffffffff815a6364>] kernel_thread_helper+0x4/0x10
[ 2601.060517] [<ffffffff81081240>] ? __init_kthread_worker+0x40/0x40
[ 2601.060519] [<ffffffff815a6360>] ? gs_change+0x13/0x13
[ 2601.060521] ---[ end trace 4b0b0e47058a9a99 ]---
=E5=9C=A8 2011-04-08=E4=BA=94=E7=9A=84 00:20 +0800=EF=BC=8CJosef Bacik=E5=
=86=99=E9=81=93=EF=BC=9A
> On 04/07/2011 05:41 AM, Jeff Wu wrote:
> > Hi ,
> > I run iozone stress test on a ceph client for x86_64, ceph 0.26 +
> > linux-2.6.39-rc1 server,
> > printk "WARNING: at fs/btrfs/inode.c:2177"
> >
> Crap I was hoping I had fixed this, could you run with this debug pat=
ch=20
> and get me the output so I can figure out what's going on? Thanks,
>=20
> Josef
>=20
>=20
--
To unsubscribe from this list: send the line "unsubscribe ceph-devel" i=
n
the body of a message to majordomo@vger.kernel.org
More majordomo info at http://vger.kernel.org/majordomo-info.html
^ permalink raw reply [flat|nested] 5+ messages in thread
* Re: 2.6.39-rc1: btrfs "WARNING: at fs/btrfs/inode.c:2177"
2011-04-08 5:53 ` Jeff Wu
@ 2011-04-08 14:38 ` Josef Bacik
0 siblings, 0 replies; 5+ messages in thread
From: Josef Bacik @ 2011-04-08 14:38 UTC (permalink / raw)
To: cpwu; +Cc: ceph-devel, linux-btrfs@vger.kernel.org
[-- Attachment #1: Type: text/plain, Size: 724 bytes --]
On 04/08/2011 01:53 AM, Jeff Wu wrote:
>
> Hi ,
> I applied the patch to 2.6.39-rc1,took the following steps to compile
> it:make&& make modules_install&& make install&& mkinitramfs
> but , it seam that it don't run to "WARN_ON(block_rsv ==
> root->orphan_block_rsv);"
>
> i attached the codes and logs at the below:
>
Bummer ok so here's a much bigger debug patch, remove the previous one I
sent you and apply this one instead and run with it. As soon as you get
a warning stop iozone because this debug patch will create _a lot_ of
debug output, and I don't want to have to sift through all of it. Just
send me your logs after running this patch so I can try and piece
together what's going on. Thanks,
Josef
[-- Attachment #2: orphan-debug.patch --]
[-- Type: text/plain, Size: 5350 bytes --]
diff --git a/fs/btrfs/ctree.h b/fs/btrfs/ctree.h
index 0d00a07..c0d8c1d 100644
--- a/fs/btrfs/ctree.h
+++ b/fs/btrfs/ctree.h
@@ -766,6 +766,7 @@ struct btrfs_block_rsv {
unsigned int durable:1;
unsigned int refill_used:1;
unsigned int full:1;
+ unsigned int orphan:1;
};
/*
diff --git a/fs/btrfs/extent-tree.c b/fs/btrfs/extent-tree.c
index f619c3c..5ebcda8 100644
--- a/fs/btrfs/extent-tree.c
+++ b/fs/btrfs/extent-tree.c
@@ -3429,6 +3429,9 @@ static int reserve_metadata_bytes(struct btrfs_trans_handle *trans,
bool reserved = false;
bool committed = false;
+ if (block_rsv->orphan)
+ printk(KERN_ERR "resrving metadata bytes for orphan rsv %llu\n",
+ orig_bytes);
again:
ret = -ENOSPC;
if (reserved)
@@ -3556,6 +3559,9 @@ static struct btrfs_block_rsv *get_block_rsv(struct btrfs_trans_handle *trans,
if (!block_rsv)
block_rsv = &root->fs_info->empty_block_rsv;
+ if (block_rsv->orphan)
+ printk(KERN_ERR "got orphan block rsv\n");
+
return block_rsv;
}
@@ -3563,6 +3569,9 @@ static int block_rsv_use_bytes(struct btrfs_block_rsv *block_rsv,
u64 num_bytes)
{
int ret = -ENOSPC;
+ if (block_rsv->orphan)
+ printk(KERN_ERR "using %llu bytes from orphan\n",
+ num_bytes);
spin_lock(&block_rsv->lock);
if (block_rsv->reserved >= num_bytes) {
block_rsv->reserved -= num_bytes;
@@ -3577,6 +3586,9 @@ static int block_rsv_use_bytes(struct btrfs_block_rsv *block_rsv,
static void block_rsv_add_bytes(struct btrfs_block_rsv *block_rsv,
u64 num_bytes, int update_size)
{
+ if (block_rsv->orphan)
+ printk(KERN_ERR "adding %llu bytes, update_size=%d\n",
+ num_bytes, update_size);
spin_lock(&block_rsv->lock);
block_rsv->reserved += num_bytes;
if (update_size)
@@ -3592,6 +3604,10 @@ void block_rsv_release_bytes(struct btrfs_block_rsv *block_rsv,
struct btrfs_space_info *space_info = block_rsv->space_info;
spin_lock(&block_rsv->lock);
+ if (block_rsv->orphan)
+ printk(KERN_ERR "releasing %llu bytes from orhan, size=%llu, "
+ "reserved=%llu\n", num_bytes, block_rsv->size,
+ block_rsv->reserved);
if (num_bytes == (u64)-1)
num_bytes = block_rsv->size;
block_rsv->size -= num_bytes;
@@ -3668,6 +3684,9 @@ struct btrfs_block_rsv *btrfs_alloc_block_rsv(struct btrfs_root *root)
void btrfs_free_block_rsv(struct btrfs_root *root,
struct btrfs_block_rsv *rsv)
{
+ if (rsv->orphan)
+ printk(KERN_ERR "freeing orphan rsv\n");
+
if (rsv && atomic_dec_and_test(&rsv->usage)) {
btrfs_block_rsv_release(root, rsv, (u64)-1);
if (!rsv->durable)
@@ -3696,6 +3715,10 @@ int btrfs_block_rsv_add(struct btrfs_trans_handle *trans,
{
int ret;
+ if (block_rsv->orphan)
+ printk(KERN_ERR "adding %llu bytes to orphan\n",
+ num_bytes);
+
if (num_bytes == 0)
return 0;
@@ -3720,6 +3743,10 @@ int btrfs_block_rsv_check(struct btrfs_trans_handle *trans,
if (!block_rsv)
return 0;
+ if (block_rsv->orphan)
+ printk(KERN_ERR "checking orphan reserve for %llu bytes, "
+ "%d min factor\n", min_reserved, min_factor);
+
spin_lock(&block_rsv->lock);
if (min_factor > 0)
num_bytes = div_factor(block_rsv->size, min_factor);
@@ -3964,6 +3991,7 @@ int btrfs_orphan_reserve_metadata(struct btrfs_trans_handle *trans,
* transaction and use space it freed.
*/
u64 num_bytes = calc_trans_metadata_size(root, 4);
+ printk(KERN_ERR "reserving %llu bytes for orphan\n", num_bytes);
return block_rsv_migrate_bytes(src_rsv, dst_rsv, num_bytes);
}
@@ -3971,6 +3999,7 @@ void btrfs_orphan_release_metadata(struct inode *inode)
{
struct btrfs_root *root = BTRFS_I(inode)->root;
u64 num_bytes = calc_trans_metadata_size(root, 4);
+ printk(KERN_ERR "first release of %llu bytes from orphan\n", num_bytes);
btrfs_block_rsv_release(root, root->orphan_block_rsv, num_bytes);
}
@@ -5697,6 +5726,8 @@ use_block_rsv(struct btrfs_trans_handle *trans,
block_rsv = get_block_rsv(trans, root);
+ if (block_rsv->orphan)
+ printk(KERN_ERR "Using orphan block rsv for tree allocation\n");
if (block_rsv->size == 0) {
ret = reserve_metadata_bytes(trans, root, block_rsv,
blocksize, 0);
@@ -5739,6 +5770,8 @@ use_block_rsv(struct btrfs_trans_handle *trans,
static void unuse_block_rsv(struct btrfs_block_rsv *block_rsv, u32 blocksize)
{
+ if (block_rsv->orphan)
+ printk(KERN_ERR "unusing orphan block rsv\n");
block_rsv_add_bytes(block_rsv, blocksize, 0);
block_rsv_release_bytes(block_rsv, NULL, 0);
}
diff --git a/fs/btrfs/inode.c b/fs/btrfs/inode.c
index 032e8fb..58b1361 100644
--- a/fs/btrfs/inode.c
+++ b/fs/btrfs/inode.c
@@ -2176,7 +2176,11 @@ void btrfs_orphan_commit_root(struct btrfs_trans_handle *trans,
}
if (root->orphan_block_rsv) {
- WARN_ON(root->orphan_block_rsv->size > 0);
+ if (root->orphan_block_rsv->size > 0) {
+ printk(KERN_ERR "orphan rsv still has %llu bytes\n",
+ root->orphan_block_rsv->size);
+ WARN_ON(1);
+ }
btrfs_free_block_rsv(root, root->orphan_block_rsv);
root->orphan_block_rsv = NULL;
}
@@ -2205,6 +2209,7 @@ int btrfs_orphan_add(struct btrfs_trans_handle *trans, struct inode *inode)
spin_lock(&root->orphan_lock);
if (!root->orphan_block_rsv) {
root->orphan_block_rsv = block_rsv;
+ block_rsv->orphan = 1;
} else if (block_rsv) {
btrfs_free_block_rsv(root, block_rsv);
block_rsv = NULL;
^ permalink raw reply related [flat|nested] 5+ messages in thread
end of thread, other threads:[~2011-04-08 14:38 UTC | newest]
Thread overview: 5+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2011-04-07 9:41 2.6.39-rc1: btrfs "WARNING: at fs/btrfs/inode.c:2177" Jeff Wu
2011-04-07 9:43 ` Wido den Hollander
2011-04-07 16:20 ` Josef Bacik
2011-04-08 5:53 ` Jeff Wu
2011-04-08 14:38 ` Josef Bacik
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).