From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1945995AbWGOEBX (ORCPT ); Sat, 15 Jul 2006 00:01:23 -0400 Received: (majordomo@vger.kernel.org) by vger.kernel.org id S1945998AbWGOEBW (ORCPT ); Sat, 15 Jul 2006 00:01:22 -0400 Received: from mx1.redhat.com ([66.187.233.31]:27037 "EHLO mx1.redhat.com") by vger.kernel.org with ESMTP id S1945995AbWGOEBV (ORCPT ); Sat, 15 Jul 2006 00:01:21 -0400 Date: Sat, 15 Jul 2006 00:01:19 -0400 From: Dave Jones To: Linux Kernel Subject: lockdep warnings during shutdown Message-ID: <20060715040119.GA3772@redhat.com> Mail-Followup-To: Dave Jones , Linux Kernel Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline User-Agent: Mutt/1.4.2.1i Sender: linux-kernel-owner@vger.kernel.org X-Mailing-List: linux-kernel@vger.kernel.org This monster trace happened when I went to power off a box. (rc1-git5) Dave BUG: warning at kernel/lockdep.c:565/print_infinite_recursion_bug() (Not tainted) Call Trace: [] show_trace+0xaa/0x23d [] dump_stack+0x15/0x17 [] print_infinite_recursion_bug+0x45/0x49 [] check_noncircular+0x37/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] check_noncircular+0x87/0xae [] __lock_acquire+0x899/0xaab [] lock_acquire+0x7b/0xa1 [] down_read+0x3e/0x4a [] m_start+0x1d/0x4d [] seq_read+0xea/0x29c [] vfs_read+0xcc/0x172 [] sys_read+0x47/0x6f [] tracesys+0xd1/0xdb -> #20 (&rq->rq_lock_key#4){++..}: [] lock_acquire+0x7a/0xa1 [] _spin_lock+0x24/0x31 [] double_rq_lock+0x2d/0x33 [] rebalance_tick+0x1cb/0x35a [] scheduler_tick+0x344/0x362 [] update_process_times+0x67/0x79 [] smp_local_timer_interrupt+0x2a/0x50 [] smp_apic_timer_interrupt+0x57/0x62 [] apic_timer_interrupt+0x69/0x70 -> #19 (&rq->rq_lock_key#3){++..}: [] lock_acquire+0x7a/0xa1 [] _spin_lock+0x24/0x31 [] double_rq_lock+0x2d/0x33 [] rebalance_tick+0x1cb/0x35a [] scheduler_tick+0x344/0x362 [] update_process_times+0x67/0x79 [] smp_local_timer_interrupt+0x2a/0x50 [] smp_apic_timer_interrupt+0x57/0x62 [] apic_timer_interrupt+0x69/0x70 -> #18 (&rq->rq_lock_key#2){++..}: [] lock_acquire+0x7a/0xa1 [] _spin_lock+0x24/0x31 [] double_rq_lock+0x2d/0x33 [] __migrate_task+0x63/0xeb [] migration_thread+0x1e1/0x240 [] kthread+0xff/0x136 [] child_rip+0x7/0x12 -> #17 (&rq->rq_lock_key){++..}: [] lock_acquire+0x7a/0xa1 [] _spin_lock+0x24/0x31 [] task_rq_lock+0x41/0x74 [] try_to_wake_up+0x26/0x418 [] wake_up_process+0xf/0x12 [] __up_read+0xcc/0xf4 [] up_read+0x25/0x2a [] futex_wake+0xd2/0xe5 [] do_futex+0x2d2/0x904 [] sys_futex+0x100/0x122 [] system_call+0x7d/0x83 -> #16 (&sem->wait_lock){....}: [] lock_acquire+0x7a/0xa1 [] _spin_lock_irqsave+0x2b/0x3c [] __down_read_trylock+0x15/0x46 [] down_read_trylock+0xe/0x3e [] prune_dcache+0x16d/0x1c9 [] shrink_dcache_parent+0x29/0x164 [] proc_flush_task+0x62/0x200 [] release_task+0x2d4/0x306 [] do_wait+0x7cc/0xb63 [] sys_wait4+0x27/0x2a [] tracesys+0xd0/0xdb -> #15 (&dentry->d_lock){--..}: [] lock_acquire+0x7a/0xa1 [] _spin_lock+0x24/0x31 [] d_move+0x70/0x408 [] vfs_rename+0x315/0x46d [] sys_renameat+0x19d/0x21c [] sys_rename+0x15/0x18 [] system_call+0x7d/0x83 -> #14 (rename_lock){--..}: [] lock_acquire+0x7a/0xa1 [] _spin_lock+0x24/0x31 [] d_move+0x43/0x408 [] vfs_rename+0x315/0x46d [] sys_renameat+0x19d/0x21c [] sys_rename+0x15/0x18 [] system_call+0x7d/0x83 -> #13 (dcache_lock){--..}: [] lock_acquire+0x7a/0xa1 [] _spin_lock+0x24/0x31 [] set_dentry_child_flags+0x22/0x173 [] inotify_add_watch+0xe0/0x1cd [] sys_inotify_add_watch+0x15e/0x1a9 [] system_call+0x7d/0x83 -> #12 (&ih->mutex){--..}: [] lock_acquire+0x7a/0xa1 [] __mutex_lock_slowpath+0xeb/0x29f [] mutex_lock+0x29/0x2e [] inotify_find_update_watch+0x4f/0x9d [] sys_inotify_add_watch+0xe1/0x1a9 [] system_call+0x7d/0x83 -> #11 (&inode->inotify_mutex){--..}: [] lock_acquire+0x7a/0xa1 [] __mutex_lock_slowpath+0xeb/0x29f [] mutex_lock+0x29/0x2e [] inotify_unmount_inodes+0xbc/0x180 [] invalidate_inodes+0x49/0x155 [] __invalidate_device+0x2f/0x50 [] invalidate_partition+0x31/0x45 [] del_gendisk+0x20/0x145 [] sd_remove+0x2e/0x6e [sd_mod] [] __device_release_driver+0x82/0x9f [] device_release_driver+0x3c/0x5e [] bus_remove_device+0xa5/0xbc [] device_del+0x149/0x17d [] __scsi_remove_device+0x3a/0x75 [scsi_mod] [] scsi_forget_host+0x41/0x6a [scsi_mod] [] scsi_remove_host+0x8d/0x126 [scsi_mod] [] quiesce_and_remove_host+0x96/0x9b [usb_storage] [] storage_disconnect+0x17/0x24 [usb_storage] [] usb_unbind_interface+0x49/0x89 [] __device_release_driver+0x82/0x9f [] device_release_driver+0x3c/0x5e [] bus_remove_device+0xa5/0xbc [] device_del+0x149/0x17d [] usb_disable_device+0x81/0xfb [] usb_disconnect+0xb5/0x135 [] hub_pre_reset+0x37/0x6c [] usb_reset_composite_device+0xc9/0x184 [] hub_thread+0x14f/0xb7e [] kthread+0xff/0x136 [] child_rip+0x7/0x12 -> #10 (iprune_mutex){--..}: [] lock_acquire+0x7a/0xa1 [] __mutex_lock_slowpath+0xeb/0x29f [] mutex_lock+0x29/0x2e [] invalidate_inodes+0x35/0x155 [] generic_shutdown_super+0x77/0x19d [] kill_block_super+0x25/0x3b [] deactivate_super+0x6e/0x84 [] mntput_no_expire+0x55/0x9a [] path_release_on_umount+0x1c/0x21 [] sys_umount+0x24d/0x294 [] tracesys+0xd0/0xdb -> #9 (&type->s_lock_key#9){--..}: [] lock_acquire+0x7a/0xa1 [] __mutex_lock_slowpath+0xeb/0x29f [] mutex_lock+0x29/0x2e [] ext3_orphan_add+0x43/0x25b [ext3] [] ext3_setattr+0x179/0x21e [ext3] [] notify_change+0x154/0x2f7 [] do_truncate+0x52/0x72 [] may_open+0x1d5/0x231 [] open_namei+0x290/0x6b4 [] do_filp_open+0x27/0x46 [] do_sys_open+0x4e/0xcd [] sys_open+0x1a/0x1d [] system_call+0x7d/0x83 -> #8 (&inode->i_alloc_sem){--..}: [] lock_acquire+0x7a/0xa1 [] down_write+0x3a/0x47 [] notify_change+0x104/0x2f7 [] do_truncate+0x52/0x72 [] may_open+0x1d5/0x231 [] open_namei+0x290/0x6b4 [] do_filp_open+0x27/0x46 [] do_sys_open+0x4e/0xcd [] sys_open+0x1a/0x1d [] system_call+0x7d/0x83 -> #7 (&sysfs_inode_imutex_key){--..}: [] lock_acquire+0x7a/0xa1 [] __mutex_lock_slowpath+0xeb/0x29f [] mutex_lock+0x29/0x2e [] sysfs_hash_and_remove+0x3f/0x11c [] sysfs_remove_file+0xf/0x12 [] sysdev_remove_file+0xc/0xf [] mce_cpu_callback+0x4b/0xbd [] notifier_call_chain+0x28/0x3e [] blocking_notifier_call_chain+0x29/0x41 [] cpu_down+0x18c/0x267 [] disable_nonboot_cpus+0x41/0xbb [] enter_state+0xae/0x20e [] state_store+0x67/0x86 [] subsys_attr_store+0x23/0x26 [] sysfs_write_file+0xd0/0x103 [] vfs_write+0xce/0x175 [] sys_write+0x46/0x70 [] tracesys+0xd0/0xdb -> #6 ((cpu_chain).rwsem){----}: [] lock_acquire+0x7a/0xa1 [] down_read+0x3d/0x4a [] blocking_notifier_call_chain+0x1a/0x41 [] cpu_up+0x57/0xd9 [] init+0xb7/0x3cd [] child_rip+0x7/0x12 -> #5 (cpucontrol){--..}: [] lock_acquire+0x7a/0xa1 [] __mutex_lock_slowpath+0xeb/0x29f [] mutex_lock+0x29/0x2e [] __lock_cpu_hotplug+0x3c/0x5f [] lock_cpu_hotplug+0xa/0xd [] kmem_cache_create+0x86/0x72b [] fib_hash_init+0x31/0xce [] __fib_new_table+0xf/0x2a [] fib_magic+0x6d/0x120 [] fib_add_ifaddr+0x76/0x135 [] fib_inetaddr_event+0x2d/0x1d0 [] notifier_call_chain+0x28/0x3e [] blocking_notifier_call_chain+0x29/0x41 [] inet_insert_ifa+0x112/0x11a [] devinet_ioctl+0x45c/0x5ee [] inet_ioctl+0x70/0x8f [] sock_ioctl+0x1e7/0x20a [] do_ioctl+0x29/0x77 [] vfs_ioctl+0x259/0x277 [] sys_ioctl+0x5e/0x82 [] system_call+0x7d/0x83 -> #4 ((inetaddr_chain).rwsem){..--}: [] lock_acquire+0x7a/0xa1 [] down_read+0x3d/0x4a [] blocking_notifier_call_chain+0x1a/0x41 [] inet_insert_ifa+0x112/0x11a [] devinet_ioctl+0x45c/0x5ee [] inet_ioctl+0x70/0x8f [] sock_ioctl+0x1e7/0x20a [] do_ioctl+0x29/0x77 [] vfs_ioctl+0x259/0x277 [] sys_ioctl+0x5e/0x82 [] system_call+0x7d/0x83 -> #3 (rtnl_mutex){--..}: [] lock_acquire+0x7a/0xa1 [] __mutex_lock_slowpath+0xeb/0x29f [] mutex_lock+0x29/0x2e [] rtnl_lock+0xf/0x12 [] ip_mc_leave_group+0x1e/0xae [] do_ip_setsockopt+0x6ad/0x9b2 [] ip_setsockopt+0x2a/0x84 [] udp_setsockopt+0xd/0x1c [] sock_common_setsockopt+0xe/0x11 [] sys_setsockopt+0x8e/0xb4 [] tracesys+0xd0/0xdb -> #2 (sk_lock-AF_INET){--..}: [] lock_acquire+0x7a/0xa1 [] lock_sock+0xd4/0xe7 [] tcp_sendmsg+0x1e/0xb1a [] inet_sendmsg+0x45/0x53 [] sock_sendmsg+0x110/0x130 [] kernel_sendmsg+0x3c/0x52 [] xs_tcp_send_request+0x117/0x320 [sunrpc] [] xprt_transmit+0x105/0x21e [sunrpc] [] call_transmit+0x1f4/0x239 [sunrpc] [] __rpc_execute+0x9b/0x1e6 [sunrpc] [] rpc_execute+0x1a/0x1d [sunrpc] [] rpc_call_sync+0x87/0xb9 [sunrpc] [] nfs3_rpc_wrapper+0x2e/0x74 [nfs] [] nfs3_proc_lookup+0xe0/0x163 [nfs] [] nfs_lookup+0xef/0x1d6 [nfs] [] do_lookup+0xd0/0x1c9 [] __link_path_walk+0xa29/0xf7d [] link_path_walk+0x69/0x101 [] do_path_lookup+0x27b/0x2e7 [] __user_walk_fd+0x40/0x5c [] vfs_lstat_fd+0x23/0x5a [] sys_newlstat+0x21/0x3c [] tracesys+0xd0/0xdb -> #1 (&inode->i_mutex){--..}: [] lock_acquire+0x7a/0xa1 [] __mutex_lock_slowpath+0xeb/0x29f [] mutex_lock+0x29/0x2e [] graft_tree+0x75/0x110 [] do_add_mount+0xb1/0x171 [] do_mount+0x6bc/0x711 [] sys_mount+0x88/0xcb [] system_call+0x7d/0x83 -> #0 (&namespace_sem){----}: [] lock_acquire+0x7a/0xa1 [] down_read+0x3d/0x4a [] m_start+0x1c/0x4d [] seq_read+0xe9/0x29c [] vfs_read+0xcb/0x172 [] sys_read+0x46/0x6f [] tracesys+0xd0/0xdb other info that might help us debug this: 1 lock held by awk/29576: #0: (&p->lock){--..}, at: [] mutex_lock+0x2a/0x2e stack backtrace: Call Trace: [] show_trace+0xaa/0x23d [] dump_stack+0x15/0x17 [] print_circular_bug_tail+0x6c/0x77 [] __lock_acquire+0x8a2/0xaab [] lock_acquire+0x7b/0xa1 [] down_read+0x3e/0x4a [] m_start+0x1d/0x4d [] seq_read+0xea/0x29c [] vfs_read+0xcc/0x172 [] sys_read+0x47/0x6f [] tracesys+0xd1/0xdb BUG: sleeping function called from invalid context at kernel/sched.c:4438 in_atomic():0, irqs_disabled():1 Call Trace: [] show_trace+0xaa/0x23d [] dump_stack+0x15/0x17 [] __might_sleep+0xb2/0xb4 [] __cond_resched+0x15/0x55 [] cond_resched+0x3b/0x42 [] console_conditional_schedule+0x12/0x14 [] fbcon_redraw+0xf6/0x160 [] fbcon_scroll+0x5d9/0xb52 [] scrup+0x6b/0xd6 [] lf+0x24/0x44 [] vt_console_print+0x166/0x23d [] __call_console_drivers+0x65/0x76 [] _call_console_drivers+0x5e/0x62 [] release_console_sem+0x14b/0x232 [] fb_flashcursor+0x279/0x2a6 [] run_workqueue+0xa8/0xfb [] worker_thread+0xef/0x122 [] kthread+0x100/0x136 [] child_rip+0x8/0x12 -- http://www.codemonkey.org.uk