* 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).