From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1751951AbWG0S7T (ORCPT ); Thu, 27 Jul 2006 14:59:19 -0400 Received: (majordomo@vger.kernel.org) by vger.kernel.org id S1751953AbWG0S7S (ORCPT ); Thu, 27 Jul 2006 14:59:18 -0400 Received: from py-out-1112.google.com ([64.233.166.178]:14314 "EHLO py-out-1112.google.com") by vger.kernel.org with ESMTP id S1751951AbWG0S7S (ORCPT ); Thu, 27 Jul 2006 14:59:18 -0400 DomainKey-Signature: a=rsa-sha1; q=dns; c=nofws; s=beta; d=gmail.com; h=received:message-id:date:from:to:subject:cc:in-reply-to:mime-version:content-type:content-transfer-encoding:content-disposition:references; b=sij0o2ATobEojuh652q63uIf/hiokKFIjUOyR8SDXn79WRpPnEmh2EV+SivRv/coRGmjU6Akd2fAQwZ461fG0KnTWQeGlaHGHwmUFHcmTxzNXwxAX4+gPb3TzqmYYRuU7GX4aRI2WDOtluQE8cF/9rAMMPyoErUQiCfJhBJ2niY= Message-ID: <6bffcb0e0607271159y1ca4db0mb95854f626b1617a@mail.gmail.com> Date: Thu, 27 Jul 2006 20:59:17 +0200 From: "Michal Piotrowski" To: "Andrew Morton" Subject: Re: 2.6.18-rc2-mm1 Cc: linux-kernel@vger.kernel.org In-Reply-To: <6bffcb0e0607270632i2ae56e21k40fb12c712980de0@mail.gmail.com> MIME-Version: 1.0 Content-Type: text/plain; charset=ISO-8859-1; format=flowed Content-Transfer-Encoding: 7bit Content-Disposition: inline References: <20060727015639.9c89db57.akpm@osdl.org> <6bffcb0e0607270632i2ae56e21k40fb12c712980de0@mail.gmail.com> Sender: linux-kernel-owner@vger.kernel.org X-Mailing-List: linux-kernel@vger.kernel.org On 27/07/06, Michal Piotrowski wrote: > Hi Andrew, > > On 27/07/06, Andrew Morton wrote: > > > > ftp://ftp.kernel.org/pub/linux/kernel/people/akpm/patches/2.6/2.6.18-rc2/2.6.18-rc2-mm1/ > > > > It appears while /sbin/start_udev > > Jul 27 15:17:17 ltg01-fedora kernel: BUG: unable to handle kernel > paging request at virtual address 6b6b6c07 > Jul 27 15:17:17 ltg01-fedora kernel: printing eip: > Jul 27 15:17:17 ltg01-fedora kernel: c0138722 > Jul 27 15:17:17 ltg01-fedora kernel: *pde = 00000000 > Jul 27 15:17:17 ltg01-fedora kernel: Oops: 0002 [#1] > Jul 27 15:17:17 ltg01-fedora kernel: 4K_STACKS PREEMPT SMP > Jul 27 15:17:17 ltg01-fedora kernel: last sysfs file: > /devices/pci0000:00/0000:00:1d.7/uevent > Jul 27 15:17:17 ltg01-fedora kernel: Modules linked in: snd_timer snd > soundcore snd_page_alloc intel_agp agpgart ide_cd cdrom ipv6 w83627hf > hwmon_vid hwmon i2c_isa i2c_i801 skge af_packet > ip_conntrack_netbios_ns ipt_REJECT xt_state ip_conntrack nfnetlink > xt_tcpudp iptable_filter ip_tables x_tables cpufreq_userspace > p4_clockmod speedstep_lib binfmt_misc thermal processor fan container > rtc unix > Jul 27 15:17:17 ltg01-fedora kernel: CPU: 0 > Jul 27 15:17:17 ltg01-fedora kernel: EIP: 0060:[] Not > tainted VLI > Jul 27 15:17:17 ltg01-fedora kernel: EFLAGS: 00010046 (2.6.18-rc2-mm1 #78) > Jul 27 15:17:17 ltg01-fedora kernel: EIP is at __lock_acquire+0x362/0xaea > Jul 27 15:17:17 ltg01-fedora kernel: eax: 00000000 ebx: 6b6b6b6b > ecx: c0360358 edx: 00000000 > Jul 27 15:17:17 ltg01-fedora kernel: esi: 00000000 edi: 00000000 > ebp: f544ddf4 esp: f544ddc0 > Jul 27 15:17:17 ltg01-fedora kernel: ds: 007b es: 007b ss: 0068 > Jul 27 15:17:17 ltg01-fedora kernel: Process udevd (pid: 1353, > ti=f544d000 task=f6fce8f0 task.ti=f544d000) > Jul 27 15:17:17 ltg01-fedora kernel: Stack: 00000000 00000000 00000000 > c7749ea4 f6fce8f0 c0138e74 000001e8 00000000 > Jul 27 15:17:17 ltg01-fedora kernel: 00000000 f6653fa4 00000246 > 00000000 00000000 f544de1c c0139214 00000000 > Jul 27 15:17:17 ltg01-fedora kernel: 00000002 00000000 c014fe3a > c7749ea4 c7749e90 f6fce8f0 f5b19b04 f544de34 > Jul 27 15:17:17 ltg01-fedora kernel: Call Trace: > Jul 27 15:17:17 ltg01-fedora kernel: [] lock_acquire+0x71/0x91 > Jul 27 15:17:17 ltg01-fedora kernel: [] _spin_lock+0x23/0x32 > Jul 27 15:17:17 ltg01-fedora kernel: [] > __delayacct_blkio_ticks+0x16/0x67 > Jul 27 15:17:17 ltg01-fedora kernel: [] do_task_stat+0x3df/0x6c1 > Jul 27 15:17:17 ltg01-fedora kernel: [] proc_tgid_stat+0xd/0xf > Jul 27 15:17:17 ltg01-fedora kernel: [] proc_info_read+0x50/0xb3 > Jul 27 15:17:17 ltg01-fedora kernel: [] vfs_read+0xcb/0x177 > Jul 27 15:17:17 ltg01-fedora kernel: [] sys_read+0x3b/0x71 > Jul 27 15:17:17 ltg01-fedora kernel: [] sysenter_past_esp+0x56/0x8d > Jul 27 15:17:17 ltg01-fedora kernel: DWARF2 unwinder stuck at > sysenter_past_esp+0x56/0x8d > Jul 27 15:17:17 ltg01-fedora kernel: Leftover inexact backtrace: > Jul 27 15:17:17 ltg01-fedora kernel: [] show_stack_log_lvl+0x8c/0x97 > Jul 27 15:17:17 ltg01-fedora kernel: [] show_registers+0x15c/0x1ed > Jul 27 15:17:17 ltg01-fedora kernel: [] die+0x1b2/0x2b7 > Jul 27 15:17:17 ltg01-fedora kernel: [] do_page_fault+0x410/0x4f0 > Jul 27 15:17:17 ltg01-fedora kernel: [] error_code+0x39/0x40 > Jul 27 15:17:17 ltg01-fedora kernel: [] lock_acquire+0x71/0x91 > Jul 27 15:17:17 ltg01-fedora kernel: [] _spin_lock+0x23/0x32 > Jul 27 15:17:17 ltg01-fedora kernel: [] > __delayacct_blkio_ticks+0x16/0x67 > Jul 27 15:17:17 ltg01-fedora kernel: [] do_task_stat+0x3df/0x6c1 > Jul 27 15:17:17 ltg01-fedora kernel: [] proc_tgid_stat+0xd/0xf > Jul 27 15:17:17 ltg01-fedora kernel: [] proc_info_read+0x50/0xb3 > Jul 27 15:17:17 ltg01-fedora kernel: [] vfs_read+0xcb/0x177 > Jul 27 15:17:17 ltg01-fedora kernel: [] sys_read+0x3b/0x71 > Jul 27 15:17:17 ltg01-fedora kernel: [] sysenter_past_esp+0x56/0x8d > Jul 27 15:17:17 ltg01-fedora kernel: Code: 68 4b 75 2f c0 68 d5 04 00 > 00 68 b9 75 31 c0 68 e3 06 31 c0 e8 ce 7e fe ff e8 87 c2 fc ff 83 c4 > 10 eb 08 85 db 0f 84 6b 07 00 00 ff 83 9c 00 00 00 8b 55 dc 8b 92 > 5c 05 00 00 89 55 e4 83 fa > Jul 27 15:17:17 ltg01-fedora kernel: EIP: [] > __lock_acquire+0x362/0xaea SS:ESP 0068:f544ddc0 > Jul 27 15:17:17 ltg01-fedora kernel: <3>BUG: sleeping function called > from invalid context at /usr/src/linux-mm/kernel/rwsem.c:20 > Jul 27 15:17:17 ltg01-fedora kernel: in_atomic():1, irqs_disabled():1 > Jul 27 15:17:17 ltg01-fedora kernel: [] show_trace_log_lvl+0x58/0x152 > Jul 27 15:17:17 ltg01-fedora kernel: [] show_trace+0xd/0x10 > Jul 27 15:17:17 ltg01-fedora kernel: [] dump_stack+0x19/0x1b > Jul 27 15:17:17 ltg01-fedora kernel: [] __might_sleep+0x8d/0x95 > Jul 27 15:17:17 ltg01-fedora kernel: [] down_read+0x15/0x3b > Jul 27 15:17:17 ltg01-fedora kernel: [] > blocking_notifier_call_chain+0x11/0x2d > Jul 27 15:17:17 ltg01-fedora kernel: [] notify_watchers+0x17/0x53 > Jul 27 15:17:17 ltg01-fedora kernel: [] do_exit+0x26/0xa4f > Jul 27 15:17:18 ltg01-fedora kernel: [] die+0x291/0x2b7 > Jul 27 15:17:18 ltg01-fedora kernel: [] do_page_fault+0x410/0x4f0 > Jul 27 15:17:18 ltg01-fedora kernel: [] error_code+0x39/0x40 > Jul 27 15:17:18 ltg01-fedora kernel: DWARF2 unwinder stuck at > error_code+0x39/0x40 > Jul 27 15:17:18 ltg01-fedora kernel: Leftover inexact backtrace: > Jul 27 15:17:18 ltg01-fedora kernel: [] show_trace+0xd/0x10 > Jul 27 15:17:18 ltg01-fedora kernel: [] dump_stack+0x19/0x1b > Jul 27 15:17:18 ltg01-fedora kernel: [] __might_sleep+0x8d/0x95 > Jul 27 15:17:18 ltg01-fedora kernel: [] down_read+0x15/0x3b > Jul 27 15:17:18 ltg01-fedora kernel: [] > blocking_notifier_call_chain+0x11/0x2d > Jul 27 15:17:18 ltg01-fedora kernel: [] notify_watchers+0x17/0x53 > Jul 27 15:17:18 ltg01-fedora kernel: [] do_exit+0x26/0xa4f > Jul 27 15:17:18 ltg01-fedora kernel: [] die+0x291/0x2b7 > Jul 27 15:17:18 ltg01-fedora kernel: [] do_page_fault+0x410/0x4f0 > Jul 27 15:17:18 ltg01-fedora kernel: [] error_code+0x39/0x40 > Jul 27 15:17:18 ltg01-fedora kernel: [] lock_acquire+0x71/0x91 > Jul 27 15:17:18 ltg01-fedora kernel: [] _spin_lock+0x23/0x32 > Jul 27 15:17:18 ltg01-fedora kernel: [] > __delayacct_blkio_ticks+0x16/0x67 > Jul 27 15:17:18 ltg01-fedora kernel: [] do_task_stat+0x3df/0x6c1 > Jul 27 15:17:18 ltg01-fedora kernel: [] proc_tgid_stat+0xd/0xf > Jul 27 15:17:18 ltg01-fedora kernel: [] proc_info_read+0x50/0xb3 > Jul 27 15:17:18 ltg01-fedora kernel: [] vfs_read+0xcb/0x177 > Jul 27 15:17:18 ltg01-fedora kernel: [] sys_read+0x3b/0x71 > Jul 27 15:17:18 ltg01-fedora kernel: [] sysenter_past_esp+0x56/0x8d > Jul 27 15:17:18 ltg01-fedora kernel: note: udevd[1353] exited with > preempt_count 1 > Jul 27 15:17:18 ltg01-fedora kernel: BUG: scheduling while atomic: > udevd/0x00000001/1353 > Jul 27 15:17:18 ltg01-fedora kernel: [] show_trace_log_lvl+0x58/0x152 > Jul 27 15:17:18 ltg01-fedora kernel: [] show_trace+0xd/0x10 > Jul 27 15:17:18 ltg01-fedora kernel: [] dump_stack+0x19/0x1b > Jul 27 15:17:18 ltg01-fedora kernel: [] __sched_text_start+0x5f/0xc95 > Jul 27 15:17:18 ltg01-fedora kernel: [] __down+0xaf/0xc3 > Jul 27 15:17:18 ltg01-fedora kernel: [] __down_failed+0xa/0xe > Jul 27 15:17:18 ltg01-fedora kernel: DWARF2 unwinder stuck at > __down_failed+0xa/0xe > Jul 27 15:17:18 ltg01-fedora kernel: Leftover inexact backtrace: > Jul 27 15:17:18 ltg01-fedora kernel: [] show_trace+0xd/0x10 > Jul 27 15:17:18 ltg01-fedora kernel: [] dump_stack+0x19/0x1b > Jul 27 15:17:18 ltg01-fedora kernel: [] __sched_text_start+0x5f/0xc95 > Jul 27 15:17:18 ltg01-fedora kernel: [] __down+0xaf/0xc3 > Jul 27 15:17:18 ltg01-fedora kernel: [] __down_failed+0xa/0xe > Jul 27 15:17:18 ltg01-fedora kernel: [] > .text.lock.kernel_lock+0x1b/0x3d > Jul 27 15:17:18 ltg01-fedora kernel: [] disassociate_ctty+0xd/0x16e > Jul 27 15:17:18 ltg01-fedora kernel: [] do_exit+0x452/0xa4f > Jul 27 15:17:18 ltg01-fedora kernel: [] die+0x291/0x2b7 > Jul 27 15:17:18 ltg01-fedora kernel: [] do_page_fault+0x410/0x4f0 > Jul 27 15:17:18 ltg01-fedora kernel: [] error_code+0x39/0x40 > Jul 27 15:17:18 ltg01-fedora kernel: [] lock_acquire+0x71/0x91 > Jul 27 15:17:18 ltg01-fedora kernel: [] _spin_lock+0x23/0x32 > Jul 27 15:17:18 ltg01-fedora kernel: [] > __delayacct_blkio_ticks+0x16/0x67 > Jul 27 15:17:18 ltg01-fedora kernel: [] do_task_stat+0x3df/0x6c1 > Jul 27 15:17:18 ltg01-fedora kernel: [] proc_tgid_stat+0xd/0xf > Jul 27 15:17:18 ltg01-fedora kernel: [] proc_info_read+0x50/0xb3 > Jul 27 15:17:18 ltg01-fedora kernel: [] vfs_read+0xcb/0x177 > Jul 27 15:17:18 ltg01-fedora kernel: [] sys_read+0x3b/0x71 > Jul 27 15:17:18 ltg01-fedora kernel: [] sysenter_past_esp+0x56/0x8d > Jul 27 15:17:18 ltg01-fedora kernel: slab error in > verify_redzone_free(): cache `delayacct_cache': double free detected > Jul 27 15:17:18 ltg01-fedora kernel: [] show_trace_log_lvl+0x58/0x152 > Jul 27 15:17:18 ltg01-fedora kernel: [] show_trace+0xd/0x10 > Jul 27 15:17:18 ltg01-fedora kernel: [] dump_stack+0x19/0x1b > Jul 27 15:17:18 ltg01-fedora kernel: [] __slab_error+0x17/0x1c > Jul 27 15:17:18 ltg01-fedora kernel: [] > cache_free_debugcheck+0xcc/0x1c7 > Jul 27 15:17:18 ltg01-fedora kernel: [] kmem_cache_free+0xa0/0xff > Jul 27 15:17:18 ltg01-fedora kernel: [] > __delayacct_tsk_exit+0x38/0x3d > Jul 27 15:17:18 ltg01-fedora kernel: [] > delayacct_watch_task+0x5a/0x65 > Jul 27 15:17:18 ltg01-fedora kernel: [] notifier_call_chain+0x20/0x31 > Jul 27 15:17:18 ltg01-fedora kernel: [] > blocking_notifier_call_chain+0x1d/0x2d > Jul 27 15:17:18 ltg01-fedora kernel: [] notify_watchers+0x17/0x53 > Jul 27 15:17:18 ltg01-fedora kernel: [] do_exit+0x2b9/0xa4f > Jul 27 15:17:18 ltg01-fedora kernel: [] sys_exit_group+0x0/0x11 > Jul 27 15:17:18 ltg01-fedora kernel: [] show_trace+0xd/0x10 > Jul 27 15:17:18 ltg01-fedora kernel: [] dump_stack+0x19/0x1b > Jul 27 15:17:18 ltg01-fedora kernel: [] __slab_error+0x17/0x1c > Jul 27 15:17:18 ltg01-fedora kernel: [] > cache_free_debugcheck+0xcc/0x1c7 > Jul 27 15:17:18 ltg01-fedora kernel: [] kmem_cache_free+0xa0/0xff > Jul 27 15:17:18 ltg01-fedora kernel: [] > __delayacct_tsk_exit+0x38/0x3d > Jul 27 15:17:18 ltg01-fedora kernel: [] > delayacct_watch_task+0x5a/0x65 > Jul 27 15:17:18 ltg01-fedora kernel: [] notifier_call_chain+0x20/0x31 > Jul 27 15:17:18 ltg01-fedora kernel: [] > blocking_notifier_call_chain+0x1d/0x2d > Jul 27 15:17:18 ltg01-fedora kernel: [] notify_watchers+0x17/0x53 > Jul 27 15:17:18 ltg01-fedora kernel: [] do_exit+0x2b9/0xa4f > Jul 27 15:17:18 ltg01-fedora kernel: [] sys_exit_group+0x0/0x11 > Jul 27 15:17:18 ltg01-fedora kernel: [] sys_exit_group+0xf/0x11 > Jul 27 15:17:18 ltg01-fedora kernel: [] sysenter_past_esp+0x56/0x8d > > list *0xc0138722 > 0xc0138722 is in __lock_acquire (include2/asm/atomic.h:96). > 91 * > 92 * Atomically increments @v by 1. > 93 */ > 94 static __inline__ void atomic_inc(atomic_t *v) > 95 { > 96 __asm__ __volatile__( > 97 LOCK_PREFIX "incl %0" > 98 :"+m" (v->counter)); > 99 } > 100 > > http://www.stardust.webpages.pl/files/mm/2.6.18-rc2-mm1/mm-config > Bug is between reducing-local_bh_enable-disable-overhead-in-irqtrace.patch and reiserfs-use-generic_file_open-for-open-checks.patch (we could reject 5 reiserfs patches from suspects list) Between remove-incorrect-unlock_kernel-from-failure-path-in.patch and reiserfs-use-generic_file_open-for-open-checks.patch I have noticed a lot of this bugs Netfilter messages via NETLINK v0.30. ip_conntrack version 2.4 (8192 buckets, 65536 max) - 224 bytes per conntrack BUG: warning at /usr/src/linux-work1/kernel/cpu.c:51/unlock_cpu_hotplug() [] show_trace_log_lvl+0x58/0x152 [] show_trace+0xd/0x10 [] dump_stack+0x19/0x1b [] unlock_cpu_hotplug+0x2f/0x59 [] store_speed+0x8f/0x9b [cpufreq_userspace] [] store+0x37/0x48 [] sysfs_write_file+0xa6/0xcc [] vfs_write+0xab/0x157 [] sys_write+0x3b/0x60 [] sysenter_past_esp+0x56/0x8d DWARF2 unwinder stuck at sysenter_past_esp+0x56/0x8d Leftover inexact backtrace: [] show_trace+0xd/0x10 [] dump_stack+0x19/0x1b [] unlock_cpu_hotplug+0x2f/0x59 [] store_speed+0x8f/0x9b [cpufreq_userspace] [] store+0x37/0x48 [] sysfs_write_file+0xa6/0xcc [] vfs_write+0xab/0x157 [] sys_write+0x3b/0x60 [] sysenter_past_esp+0x56/0x8d I'll finish this bisection later. Regards, Michal -- Michal K. K. Piotrowski LTG - Linux Testers Group (http://www.stardust.webpages.pl/ltg/wiki/)