From mboxrd@z Thu Jan 1 00:00:00 1970 From: Sasha Levin Subject: Re: linux-next: Tree for Dec 7 Date: Wed, 07 Dec 2011 21:59:04 +0200 Message-ID: <1323287944.3904.4.camel@lappy> References: <20111207171218.e11db78531e7f20659e2ca65@canb.auug.org.au> <1323254393.4009.6.camel@lappy> Mime-Version: 1.0 Content-Type: text/plain; charset="us-ascii" Content-Transfer-Encoding: 7bit Return-path: Received: from mail-bw0-f46.google.com ([209.85.214.46]:62110 "EHLO mail-bw0-f46.google.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1756215Ab1LGT71 (ORCPT ); Wed, 7 Dec 2011 14:59:27 -0500 In-Reply-To: <1323254393.4009.6.camel@lappy> Sender: linux-next-owner@vger.kernel.org List-ID: To: Stephen Rothwell Cc: linux-next@vger.kernel.org, LKML , prasadjoshi.linux@gmail.com Bugs below were bisected to: commit 39da12ef4bbeded56cfc64bbea3ff877abbd596b Author: Prasad Joshi Date: Mon Oct 31 20:32:20 2011 +0530 logfs: take write mutex lock during fsync and sync Cc'ed Prasad. On Wed, 2011-12-07 at 12:39 +0200, Sasha Levin wrote: > Hi, > > I got a stream of warnings and bugs in today's linux-next tree when > trying to simply mount a (non logfs) filesystem. This has worked fine in > yesterday's linux-next. > > Here are the warnings: > > [ 46.120751] INFO: trying to register non-static key. > [ 46.121026] the code is fine but needs lockdep annotation. > [ 46.121026] turning off the locking correctness validator. > [ 46.121026] Pid: 2817, comm: mount Not tainted 3.2.0-rc4-next-20111207-sasha-00003-gd171823 #51 > [ 46.121026] Call Trace: > [ 46.121026] [] __lock_acquire+0x1574/0x1f00 > [ 46.121026] [] ? _raw_spin_unlock_irq+0x2b/0x70 > [ 46.121026] [] ? sub_preempt_count+0x9d/0xd0 > [ 46.121026] [] ? _raw_spin_unlock_irq+0x46/0x70 > [ 46.121026] [] ? wait_for_common+0x62/0x170 > [ 46.121026] [] ? try_to_wake_up+0x350/0x350 > [ 46.121026] [] lock_acquire+0xc5/0x100 > [ 46.121026] [] ? logfs_get_wblocks+0x3e/0x90 > [ 46.121026] [] ? writeback_inodes_sb_nr+0x151/0x190 > [ 46.121026] [] __mutex_lock_common+0x65/0x5b0 > [ 46.121026] [] ? logfs_get_wblocks+0x3e/0x90 > [ 46.121026] [] ? logfs_get_wblocks+0x3e/0x90 > [ 46.121026] [] mutex_lock_nested+0x40/0x50 > [ 46.121026] [] logfs_get_wblocks+0x3e/0x90 > [ 46.121026] [] logfs_sync_fs+0x18/0x40 > [ 46.121026] [] __sync_filesystem+0x5e/0x90 > [ 46.121026] [] sync_filesystem+0x32/0x60 > [ 46.121026] [] logfs_kill_sb+0x27/0xe0 > [ 46.121026] [] deactivate_locked_super+0x38/0x90 > [ 46.121026] [] logfs_mount+0x265/0x760 > [ 46.121026] [] mount_fs+0x42/0x1b0 > [ 46.121026] [] ? __alloc_percpu+0xb/0x10 > [ 46.121026] [] vfs_kern_mount+0x5e/0xd0 > [ 46.121026] [] ? _raw_read_unlock+0x30/0x60 > [ 46.121026] [] do_kern_mount+0x4f/0x100 > [ 46.121026] [] do_mount+0x50f/0x820 > [ 46.121026] [] ? strndup_user+0x53/0x70 > [ 46.121026] [] sys_mount+0x8b/0xe0 > [ 46.121026] [] system_call_fastpath+0x16/0x1b > [ 46.163790] BUG: unable to handle kernel NULL pointer dereference at (null) > [ 46.164433] IP: [] __list_add+0x26/0xa0 > [ 46.164433] PGD 10ed3067 PUD 10525067 PMD 0 > [ 46.164433] Oops: 0000 [#1] PREEMPT SMP > [ 46.164433] CPU 0 > [ 46.164433] Pid: 2817, comm: mount Not tainted 3.2.0-rc4-next-20111207-sasha-00003-gd171823 #51 > [ 46.164433] RIP: 0010:[] [] __list_add+0x26/0xa0 > [ 46.164433] RSP: 0018:ffff880011667b78 EFLAGS: 00010046 > [ 46.164433] RAX: ffff88001045ac20 RBX: ffff880011667c08 RCX: 0000000000000000 > [ 46.164433] RDX: ffff880010d5dec0 RSI: 0000000000000000 RDI: ffff880011667c08 > [ 46.164433] RBP: ffff880011667b98 R08: 0000000000000000 R09: 0000000000000000 > [ 46.164433] R10: 0000000000000002 R11: 0000000000000000 R12: ffff880010d5dec0 > [ 46.164433] R13: 0000000000000000 R14: 00000000ffffffff R15: ffffffffffffffea > [ 46.164433] FS: 00007f1686542740(0000) GS:ffff880013a00000(0000) knlGS:0000000000000000 > [ 46.164433] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b > [ 46.164433] CR2: 0000000000000000 CR3: 0000000010537000 CR4: 00000000000006f0 > [ 46.164433] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 > [ 46.164433] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400 > [ 46.164433] Process mount (pid: 2817, threadinfo ffff880011666000, task ffff88001045ac20) > [ 46.164433] Stack: > [ 46.164433] ffff880011667b98 ffff880010d5de70 0000000000000282 ffff880010d5de78 > [ 46.164433] ffff880011667c58 ffffffff823de157 ffffffff81432ece ffffffffffffffff > [ 46.164433] ffffffff83c1f06c 0000000000000000 0000000000000000 ffffffff82a1c6bb > [ 46.164433] Call Trace: > [ 46.164433] [] __mutex_lock_common+0x127/0x5b0 > [ 46.164433] [] ? logfs_get_wblocks+0x3e/0x90 > [ 46.164433] [] ? logfs_get_wblocks+0x3e/0x90 > [ 46.164433] [] mutex_lock_nested+0x40/0x50 > [ 46.164433] [] logfs_get_wblocks+0x3e/0x90 > [ 46.164433] [] logfs_sync_fs+0x18/0x40 > [ 46.164433] [] __sync_filesystem+0x5e/0x90 > [ 46.164433] [] sync_filesystem+0x32/0x60 > [ 46.164433] [] logfs_kill_sb+0x27/0xe0 > [ 46.164433] [] deactivate_locked_super+0x38/0x90 > [ 46.164433] [] logfs_mount+0x265/0x760 > [ 46.164433] [] mount_fs+0x42/0x1b0 > [ 46.164433] [] ? __alloc_percpu+0xb/0x10 > [ 46.164433] [] vfs_kern_mount+0x5e/0xd0 > [ 46.164433] [] ? _raw_read_unlock+0x30/0x60 > [ 46.164433] [] do_kern_mount+0x4f/0x100 > [ 46.164433] [] do_mount+0x50f/0x820 > [ 46.164433] [] ? strndup_user+0x53/0x70 > [ 46.164433] [] sys_mount+0x8b/0xe0 > [ 46.164433] [] system_call_fastpath+0x16/0x1b > [ 46.164433] Code: 0b 90 90 90 90 55 48 89 e5 48 83 ec 20 48 89 5d e8 4c 89 65 f0 4c 89 6d f8 48 89 fb 4c 8b 42 08 49 89 f5 49 89 d4 49 39 f0 75 27 <4d> 8b 45 00 4d 39 c4 75 40 49 89 5c 24 08 4c 89 23 4c 89 6b 08 > [ 46.164433] RIP [] __list_add+0x26/0xa0 > [ 46.164433] RSP > [ 46.164433] CR2: 0000000000000000 > [ 46.164433] ---[ end trace 0629a1357fe30cea ]--- > [ 46.164433] note: mount[2817] exited with preempt_count 1 > [ 46.164433] BUG: sleeping function called from invalid context at kernel/rwsem.c:21 > [ 46.164433] in_atomic(): 1, irqs_disabled(): 1, pid: 2817, name: mount > [ 46.164433] INFO: lockdep is turned off. > [ 46.164433] irq event stamp: 7047 > [ 46.164433] hardirqs last enabled at (7047): [] _raw_spin_unlock_irq+0x2b/0x70 > [ 46.164433] hardirqs last disabled at (7046): [] _raw_spin_lock_irq+0x24/0x90 > [ 46.164433] softirqs last enabled at (7034): [] bdi_queue_work+0x54/0x70 > [ 46.164433] softirqs last disabled at (7030): [] _raw_spin_lock_bh+0x13/0x80 > [ 46.164433] Pid: 2817, comm: mount Tainted: G D 3.2.0-rc4-next-20111207-sasha-00003-gd171823 #51 > [ 46.164433] Call Trace: > [ 46.164433] [] ? print_irqtrace_events+0xd0/0xe0 > [ 46.164433] [] __might_sleep+0x149/0x200 > [ 46.164433] [] down_read+0x25/0x90 > [ 46.164433] [] acct_collect+0x46/0x1a0 > [ 46.164433] [] do_exit+0x744/0x950 > [ 46.164433] [] ? kmsg_dump+0x75/0x1c0 > [ 46.164433] [] ? printk+0x3c/0x3e > [ 46.164433] [] oops_end+0xc2/0x110 > [ 46.164433] [] no_context+0x122/0x2e0 > [ 46.164433] [] __bad_area_nosemaphore+0x12d/0x230 > [ 46.164433] [] bad_area_nosemaphore+0xe/0x10 > [ 46.164433] [] do_page_fault+0x343/0x4e0 > [ 46.164433] [] ? show_trace+0x10/0x20 > [ 46.164433] [] ? dump_stack+0x72/0x7b > [ 46.164433] [] ? __lock_acquire+0x1574/0x1f00 > [ 46.164433] [] ? _raw_spin_unlock_irq+0x2b/0x70 > [ 46.164433] [] ? trace_hardirqs_on_thunk+0x3a/0x3f > [ 46.164433] [] do_async_page_fault+0x31/0x90 > [ 46.164433] [] async_page_fault+0x25/0x30 > [ 46.164433] [] ? __list_add+0x26/0xa0 > [ 46.164433] [] __mutex_lock_common+0x127/0x5b0 > [ 46.164433] [] ? logfs_get_wblocks+0x3e/0x90 > [ 46.164433] [] ? logfs_get_wblocks+0x3e/0x90 > [ 46.164433] [] mutex_lock_nested+0x40/0x50 > [ 46.164433] [] logfs_get_wblocks+0x3e/0x90 > [ 46.164433] [] logfs_sync_fs+0x18/0x40 > [ 46.164433] [] __sync_filesystem+0x5e/0x90 > [ 46.164433] [] sync_filesystem+0x32/0x60 > [ 46.164433] [] logfs_kill_sb+0x27/0xe0 > [ 46.164433] [] deactivate_locked_super+0x38/0x90 > [ 46.164433] [] logfs_mount+0x265/0x760 > [ 46.164433] [] mount_fs+0x42/0x1b0 > [ 46.164433] [] ? __alloc_percpu+0xb/0x10 > [ 46.164433] [] vfs_kern_mount+0x5e/0xd0 > [ 46.164433] [] ? _raw_read_unlock+0x30/0x60 > [ 46.164433] [] do_kern_mount+0x4f/0x100 > [ 46.164433] [] do_mount+0x50f/0x820 > [ 46.164433] [] ? strndup_user+0x53/0x70 > [ 46.164433] [] sys_mount+0x8b/0xe0 > [ 46.164433] [] system_call_fastpath+0x16/0x1b > [ 46.275955] BUG: scheduling while atomic: mount/2817/0x10000002 > [ 46.276972] INFO: lockdep is turned off. > [ 46.277649] Pid: 2817, comm: mount Tainted: G D 3.2.0-rc4-next-20111207-sasha-00003-gd171823 #51 > [ 46.279182] Call Trace: > [ 46.279574] [] __schedule_bug+0x85/0x90 > [ 46.280678] [] __schedule+0x887/0x930 > [ 46.281845] [] __cond_resched+0x13/0x20 > [ 46.282928] [] _cond_resched+0x2c/0x40 > [ 46.283783] [] unmap_vmas+0x5cd/0x900 > [ 46.284618] [] ? print_context_stack+0x75/0xd0 > [ 46.285570] [] exit_mmap+0x9c/0x140 > [ 46.286379] [] mmput+0x77/0x100 > [ 46.287126] [] exit_mm+0x108/0x130 > [ 46.288227] [] ? _raw_spin_unlock_irq+0x68/0x70 > [ 46.289560] [] do_exit+0x171/0x950 > [ 46.291224] [] ? kmsg_dump+0x75/0x1c0 > [ 46.293226] [] ? printk+0x3c/0x3e > [ 46.295163] [] oops_end+0xc2/0x110 > [ 46.296721] [] no_context+0x122/0x2e0 > [ 46.297932] [] __bad_area_nosemaphore+0x12d/0x230 > [ 46.299132] [] bad_area_nosemaphore+0xe/0x10 > [ 46.300056] [] do_page_fault+0x343/0x4e0 > [ 46.300898] [] ? show_trace+0x10/0x20 > [ 46.301735] [] ? dump_stack+0x72/0x7b > [ 46.302570] [] ? __lock_acquire+0x1574/0x1f00 > [ 46.303646] [] ? _raw_spin_unlock_irq+0x2b/0x70 > [ 46.305071] [] ? trace_hardirqs_on_thunk+0x3a/0x3f > [ 46.306479] [] do_async_page_fault+0x31/0x90 > [ 46.307789] [] async_page_fault+0x25/0x30 > [ 46.308761] [] ? __list_add+0x26/0xa0 > [ 46.309605] [] __mutex_lock_common+0x127/0x5b0 > [ 46.310551] [] ? logfs_get_wblocks+0x3e/0x90 > [ 46.311469] [] ? logfs_get_wblocks+0x3e/0x90 > [ 46.312467] [] mutex_lock_nested+0x40/0x50 > [ 46.313713] [] logfs_get_wblocks+0x3e/0x90 > [ 46.314963] [] logfs_sync_fs+0x18/0x40 > [ 46.316239] [] __sync_filesystem+0x5e/0x90 > [ 46.317421] [] sync_filesystem+0x32/0x60 > [ 46.318287] [] logfs_kill_sb+0x27/0xe0 > [ 46.319117] [] deactivate_locked_super+0x38/0x90 > [ 46.320089] [] logfs_mount+0x265/0x760 > [ 46.320904] [] mount_fs+0x42/0x1b0 > [ 46.321916] [] ? __alloc_percpu+0xb/0x10 > [ 46.323146] [] vfs_kern_mount+0x5e/0xd0 > [ 46.324342] [] ? _raw_read_unlock+0x30/0x60 > [ 46.325605] [] do_kern_mount+0x4f/0x100 > [ 46.326802] [] do_mount+0x50f/0x820 > [ 46.327661] [] ? strndup_user+0x53/0x70 > [ 46.328524] [] sys_mount+0x8b/0xe0 > [ 46.329320] [] system_call_fastpath+0x16/0x1b > -- Sasha.