Re: 2.6.25-rc5-mm1: "consolechars" hangs on boot

Laurent Riffard
2008-03-14T21:06:54Z

[-- Attachment #1: Type: text/plain, Size: 4238 bytes --]

Le 14.03.2008 06:26, Oleg Nesterov a écrit :
> On 03/13, Andrew Morton wrote:
>> On Thu, 13 Mar 2008 23:07:30 +0100
>> Laurent Riffard <laurent.riffard@free.fr> wrote:
>>
>>> Le 11.03.2008 09:14, Andrew Morton a __crit :
>>>> ftp://ftp.kernel.org/pub/linux/kernel/people/akpm/patches/2.6/2.6.25-rc5/2.6.25-rc5-mm1/
>>>>
>>> With 2.6.25-rc5-mm1, my system (Ubuntu 7.10/Gutsy) reliably hangs on
>>> boot. Sysrq-T shows 12 "consolechars" processes stuck in do_exit call.
>>>
>>> The bisection said "Sucker is
>>> patches/signals-send_signal-factor-out-signal_group_exit-checks.patch"
>>>
>>> Actually, it's 2.6.25-rc5-mm1 + add-warn_on_secs-macro-fix-fix.patch,
>>> I guess this patch is innocent.
>
> Laurent, thanks a lot!
>
> What was the state of consolechars? Where exactly did it hang? do_exit+??

(hand-copied data)
=================
consolechars ? de8925bc 3432 2795 1
.
.
.
Call Trace:
do_exit+0x5dd/0x5e1
do_group_exit+0x5e/0x86
sys_exit_group+0xf/0x11
sysenter_past_esp+0x5f/0xa5
=================

On first line, last number is always "1" for each of the 12 consolechars.
The call trace is always the same for each of the 12 consolechars.

>> Actually I later dropped
>> signals-send_signal-factor-out-signal_group_exit-checks.patch at Oleg's
>> request.
>>
>> But I don't think we did that because it was known to be buggy, so perhaps
>> the same bug crept back in in another form..
>
> Yes, currently I suspect we have another bug.
>
> And. While doing this patch I forgot we should fix the bugs with init first!
> (will try to make the patch soon).
>
> Laurent, any chance you can try 2.6.25-rc5-mm1 + the patch below?
> Unlikely it can help, but would be great to be sure.

Yes it does help ! Thanks.

Despite a big ERR in dmesg, the system now runs fine.

[ 26.536458] ReiserFS: sda7: Using r5 hash to sort names
[ 26.780261] ERR!! init is killed by 10
[ 26.781486] ------------[ cut here ]------------
[ 26.781492] WARNING: at kernel/signal.c:724 complete_signal+0x163/0x1eb()
[ 26.781497] Modules linked in: nls_iso8859_1 nls_cp850 vfat fat reiserfs eeprom w83781d hwmon_vid ipv6 snd_ens1371 firewire_ohci firewire_core gameport crc_itu_t snd_ac97_codec 8250_pnp ac97_bus snd_pcm_oss snd_mixer_oss 8250 serial_core snd_pcm snd_seq_oss floppy snd_seq_midi snd_rawmidi rtc snd_seq_midi_event snd_seq snd_timer snd_seq_device pcspkr snd uhci_hcd sr_mod cdrom soundcore snd_page_alloc ohci1394 sg via686a ne2k_pci 8390 ieee1394 i2c_viapro usbcore ata_generic parport_pc parport via_agp agpgart evdev dm_snapshot reiser4 lzo_decompress lzo_compress sd_mod pata_via libata scsi_mod dm_mirror dm_log dm_mod
[ 26.781609] Pid: 2590, comm: sh Not tainted 2.6.25-rc5-mm1 #18
[ 26.781619] [<c01188bd>] warn_on_slowpath+0x41/0x6d
[ 26.781640] [<c0119200>] ? vprintk+0x289/0x3b6
[ 26.781650] [<c01cc3a8>] ? number+0x10d/0x1cd
[ 26.781671] [<c0158db9>] ? cache_free_debugcheck+0x1e1/0x1ec
[ 26.781699] [<c0119342>] ? printk+0x15/0x17
[ 26.781709] [<c0120fa9>] complete_signal+0x163/0x1eb
[ 26.781719] [<c01211d4>] send_signal+0x1a3/0x1cf
[ 26.781729] [<c0121216>] __group_send_sig_info+0xa/0xc
[ 26.781737] [<c01217cc>] group_send_sig_info+0x44/0x62
[ 26.781747] [<c0121de4>] kill_pid_info+0x33/0x47
[ 26.781757] [<c0122443>] sys_kill+0x73/0x145
[ 26.781767] [<c014c655>] ? handle_mm_fault+0x21d/0x4f6
[ 26.781791] [<c012af3c>] ? up_read+0x16/0x2a
[ 26.781803] [<c011214c>] ? do_page_fault+0x25a/0x4da
[ 26.781815] [<c0103906>] sysenter_past_esp+0x5f/0xa5
[ 26.781834] =======================
[ 26.781838] ---[ end trace c053f6e3c5b0fb23 ]---
[ 26.827206] Adding 1048568k swap on /dev/mapper/vglinux1-lvswap. Priority:-1 extents:1 across:1048568k

(full dmesg attached)

> Oleg.
>
> --- MM/kernel/signal.c~ 2008-03-14 08:08:07.000000000 +0300
> +++ MM/kernel/signal.c 2008-03-14 08:08:17.000000000 +0300
> @@ -719,6 +719,10 @@ static void complete_signal(int sig, str
> /*
> * This signal will be fatal to the whole group.
> */
> +if (is_global_init(p)) {
> + printk(KERN_CRIT "ERR!! init is killed by %d\n", sig);
> + WARN_ON_ONCE(1);
> +} else
> if (!sig_kernel_coredump(sig)) {
> /*
> * Start a group exit and wake everybody up.
>

[-- Attachment #2: dmesg-2.6.25-rc5-mm1 --]
[-- Type: text/plain, Size: 32180 bytes --]

[ 0.000000] Linux version 2.6.25-rc5-mm1 (laurent@calimero) (gcc version 4.1.3 20070929 (prerelease) (Ubuntu 4.1.2-16ubuntu2)) #18 PREEMPT Fri Mar 14 21:41:35 CET 2008
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: 0000000000000000 - 000000000009fc00 (usable)
[ 0.000000] BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved)
[ 0.000000] BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
[ 0.000000] BIOS-e820: 0000000000100000 - 000000001ffec000 (usable)
[ 0.000000] BIOS-e820: 000000001ffec000 - 000000001ffef000 (ACPI data)
[ 0.000000] BIOS-e820: 000000001ffef000 - 000000001ffff000 (reserved)
[ 0.000000] BIOS-e820: 000000001ffff000 - 0000000020000000 (ACPI NVS)
[ 0.000000] BIOS-e820: 00000000ffff0000 - 0000000100000000 (reserved)
[ 0.000000] 511MB LOWMEM available.
[ 0.000000] Scan SMP from c0000000 for 1024 bytes.
[ 0.000000] Scan SMP from c009fc00 for 1024 bytes.
[ 0.000000] Scan SMP from c00f0000 for 65536 bytes.
[ 0.000000] Scan SMP from c009fc00 for 1024 bytes.
[ 0.000000] Entering add_active_range(0, 0, 131052) 0 entries of 256 used
[ 0.000000] sizeof(struct page) = 32
[ 0.000000] Zone PFN ranges:
[ 0.000000] DMA 0 -> 4096
[ 0.000000] Normal 4096 -> 131052
[ 0.000000] Movable zone start PFN for each node
[ 0.000000] early_node_map[1] active PFN ranges
[ 0.000000] 0: 0 -> 131052
[ 0.000000] On node 0 totalpages: 131052
[ 0.000000] Node 0 memmap at 0xc1000000 size 4194304 first pfn 0xc1000000
[ 0.000000] DMA zone: 32 pages used for memmap
[ 0.000000] DMA zone: 0 pages reserved
[ 0.000000] DMA zone: 4064 pages, LIFO batch:0
[ 0.000000] Normal zone: 991 pages used for memmap
[ 0.000000] Normal zone: 125965 pages, LIFO batch:31
[ 0.000000] Movable zone: 0 pages used for memmap
[ 0.000000] DMI 2.3 present.
[ 0.000000] ACPI: RSDP 000F6A80, 0014 (r0 ASUS )
[ 0.000000] ACPI: RSDT 1FFEC000, 002C (r1 ASUS A7V133-C 30303031 MSFT 31313031)
[ 0.000000] ACPI: FACP 1FFEC080, 0074 (r1 ASUS A7V133-C 30303031 MSFT 31313031)
[ 0.000000] ACPI: DSDT 1FFEC100, 2CE1 (r1 ASUS A7V133-C 1000 MSFT 100000B)
[ 0.000000] ACPI: FACS 1FFFF000, 0040
[ 0.000000] ACPI: BOOT 1FFEC040, 0028 (r1 ASUS A7V133-C 30303031 MSFT 31313031)
[ 0.000000] ACPI: PM-Timer IO Port: 0xe408
[ 0.000000] Allocating PCI resources starting at 30000000 (gap: 20000000:dfff0000)
[ 0.000000] PM: Registered nosave memory: 000000000009f000 - 00000000000a0000
[ 0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000f0000
[ 0.000000] PM: Registered nosave memory: 00000000000f0000 - 0000000000100000
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 130029
[ 0.000000] Kernel command line: root=/dev/mapper/vglinux1-lv_ubuntu2 ro locale=fr_FR video=radeonfb:1280x1024@60 resume=/dev/mapper/vglinux1-lvswap
[ 0.000000] Local APIC disabled by BIOS -- you can enable it with "lapic"
[ 0.000000] mapped APIC to ffffb000 (01406000)
[ 0.000000] Enabling fast FPU save and restore... done.
[ 0.000000] Enabling unmasked SIMD FPU exception support... done.
[ 0.000000] Initializing CPU#0
[ 0.000000] Preemptible RCU implementation.
[ 0.000000] CPU 0 irqstacks, hard=c03c4000 soft=c03c3000
[ 0.000000] PID hash table entries: 2048 (order: 11, 8192 bytes)
[ 0.000000] Detected 1410.227 MHz processor.
[ 0.004000] Console: colour VGA+ 80x25
[ 0.004000] console [tty0] enabled
[ 0.004000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[ 0.004000] ... MAX_LOCKDEP_SUBCLASSES: 8
[ 0.004000] ... MAX_LOCK_DEPTH: 48
[ 0.004000] ... MAX_LOCKDEP_KEYS: 2048
[ 0.004000] ... CLASSHASH_SIZE: 1024
[ 0.004000] ... MAX_LOCKDEP_ENTRIES: 8192
[ 0.004000] ... MAX_LOCKDEP_CHAINS: 16384
[ 0.004000] ... CHAINHASH_SIZE: 8192
[ 0.004000] memory used by lock dependency info: 992 kB
[ 0.004000] per task-struct memory footprint: 1920 bytes
[ 0.004000] ------------------------
[ 0.004000] | Locking API testsuite:
[ 0.004000] ----------------------------------------------------------------------------
[ 0.004000] | spin |wlock |rlock |mutex | wsem | rsem |
[ 0.004000] --------------------------------------------------------------------------
[ 0.004000] A-A deadlock: ok | ok | ok | ok | ok | ok |
[ 0.004000] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok |
[ 0.004000] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok |
[ 0.004000] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok |
[ 0.004000] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok |
[ 0.004000] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok |
[ 0.004000] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok |
[ 0.004000] double unlock: ok | ok | ok | ok | ok | ok |
[ 0.004000] initialize held: ok | ok | ok | ok | ok | ok |
[ 0.004000] bad unlock order: ok | ok | ok | ok | ok | ok |
[ 0.004000] --------------------------------------------------------------------------
[ 0.004000] recursive read-lock: | ok | | ok |
[ 0.004000] recursive read-lock #2: | ok | | ok |
[ 0.004000] mixed read-write-lock: | ok | | ok |
[ 0.004000] mixed write-read-lock: | ok | | ok |
[ 0.004000] --------------------------------------------------------------------------
[ 0.004000] hard-irqs-on + irq-safe-A/12: ok | ok | ok |
[ 0.004000] soft-irqs-on + irq-safe-A/12: ok | ok | ok |
[ 0.004000] hard-irqs-on + irq-safe-A/21: ok | ok | ok |
[ 0.004000] soft-irqs-on + irq-safe-A/21: ok | ok | ok |
[ 0.004000] sirq-safe-A => hirqs-on/12: ok | ok | ok |
[ 0.004000] sirq-safe-A => hirqs-on/21: ok | ok | ok |
[ 0.004000] hard-safe-A + irqs-on/12: ok | ok | ok |
[ 0.004000] soft-safe-A + irqs-on/12: ok | ok | ok |
[ 0.004000] hard-safe-A + irqs-on/21: ok | ok | ok |
[ 0.004000] soft-safe-A + irqs-on/21: ok | ok | ok |
[ 0.004000] hard-safe-A + unsafe-B #1/123: ok | ok | ok |
[ 0.004000] soft-safe-A + unsafe-B #1/123: ok | ok | ok |
[ 0.004000] hard-safe-A + unsafe-B #1/132: ok | ok | ok |
[ 0.004000] soft-safe-A + unsafe-B #1/132: ok | ok | ok |
[ 0.004000] hard-safe-A + unsafe-B #1/213: ok | ok | ok |
[ 0.004000] soft-safe-A + unsafe-B #1/213: ok | ok | ok |
[ 0.004000] hard-safe-A + unsafe-B #1/231: ok | ok | ok |
[ 0.004000] soft-safe-A + unsafe-B #1/231: ok | ok | ok |
[ 0.004000] hard-safe-A + unsafe-B #1/312: ok | ok | ok |
[ 0.004000] soft-safe-A + unsafe-B #1/312: ok | ok | ok |
[ 0.004000] hard-safe-A + unsafe-B #1/321: ok | ok | ok |
[ 0.004000] soft-safe-A + unsafe-B #1/321: ok | ok | ok |
[ 0.004000] hard-safe-A + unsafe-B #2/123: ok | ok | ok |
[ 0.004000] soft-safe-A + unsafe-B #2/123: ok | ok | ok |
[ 0.004000] hard-safe-A + unsafe-B #2/132: ok | ok | ok |
[ 0.004000] soft-safe-A + unsafe-B #2/132: ok | ok | ok |
[ 0.004000] hard-safe-A + unsafe-B #2/213: ok | ok | ok |
[ 0.004000] soft-safe-A + unsafe-B #2/213: ok | ok | ok |
[ 0.004000] hard-safe-A + unsafe-B #2/231: ok | ok | ok |
[ 0.004000] soft-safe-A + unsafe-B #2/231: ok | ok | ok |
[ 0.004000] hard-safe-A + unsafe-B #2/312: ok | ok | ok |
[ 0.004000] soft-safe-A + unsafe-B #2/312: ok | ok | ok |
[ 0.004000] hard-safe-A + unsafe-B #2/321: ok | ok | ok |
[ 0.004000] soft-safe-A + unsafe-B #2/321: ok | ok | ok |
[ 0.004000] hard-irq lock-inversion/123: ok | ok | ok |
[ 0.004000] soft-irq lock-inversion/123: ok | ok | ok |
[ 0.004000] hard-irq lock-inversion/132: ok | ok | ok |
[ 0.004000] soft-irq lock-inversion/132: ok | ok | ok |
[ 0.004000] hard-irq lock-inversion/213: ok | ok | ok |
[ 0.004000] soft-irq lock-inversion/213: ok | ok | ok |
[ 0.004000] hard-irq lock-inversion/231: ok | ok | ok |
[ 0.004000] soft-irq lock-inversion/231: ok | ok | ok |
[ 0.004000] hard-irq lock-inversion/312: ok | ok | ok |
[ 0.004000] soft-irq lock-inversion/312: ok | ok | ok |
[ 0.004000] hard-irq lock-inversion/321: ok | ok | ok |
[ 0.004000] soft-irq lock-inversion/321: ok | ok | ok |
[ 0.004000] hard-irq read-recursion/123: ok |
[ 0.004000] soft-irq read-recursion/123: ok |
[ 0.004000] hard-irq read-recursion/132: ok |
[ 0.004000] soft-irq read-recursion/132: ok |
[ 0.004000] hard-irq read-recursion/213: ok |
[ 0.004000] soft-irq read-recursion/213: ok |
[ 0.004000] hard-irq read-recursion/231: ok |
[ 0.004000] soft-irq read-recursion/231: ok |
[ 0.004000] hard-irq read-recursion/312: ok |
[ 0.004000] soft-irq read-recursion/312: ok |
[ 0.004000] hard-irq read-recursion/321: ok |
[ 0.004000] soft-irq read-recursion/321: ok |
[ 0.004000] -------------------------------------------------------
[ 0.004000] Good, all 218 testcases passed! |
[ 0.004000] ---------------------------------
[ 0.004000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.004000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.004000] Memory: 510720k/524208k available (1723k kernel code, 12920k reserved, 895k data, 192k init, 0k highmem)
[ 0.004000] virtual kernel memory layout:
[ 0.004000] fixmap : 0xfffb5000 - 0xfffff000 ( 296 kB)
[ 0.004000] vmalloc : 0xe0800000 - 0xfffb3000 ( 503 MB)
[ 0.004000] lowmem : 0xc0000000 - 0xdffec000 ( 511 MB)
[ 0.004000] .init : 0xc0390000 - 0xc03c0000 ( 192 kB)
[ 0.004000] .data : 0xc02aee60 - 0xc038eca0 ( 895 kB)
[ 0.004000] .text : 0xc0100000 - 0xc02aee60 (1723 kB)
[ 0.004000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[ 0.004000] CPA: page pool initialized 1 of 1 pages preallocated
[ 0.084010] Calibrating delay using timer specific routine.. 2824.51 BogoMIPS (lpj=5649035)
[ 0.084406] Mount-cache hash table entries: 512
[ 0.085740] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line)
[ 0.085808] CPU: L2 Cache: 256K (64 bytes/line)
[ 0.085867] Intel machine check architecture supported.
[ 0.085927] Intel machine check reporting enabled on CPU#0.
[ 0.085993] Compat vDSO mapped to ffffe000.
[ 0.086056] CPU: AMD Athlon(TM) XP 1600+ stepping 02
[ 0.086189] Checking 'hlt' instruction... OK.
[ 0.100255] Freeing SMP alternatives: 0k freed
[ 0.100313] ACPI: Core revision 20070126
[ 0.100435] INFO: trying to register non-static key.
[ 0.100494] the code is fine but needs lockdep annotation.
[ 0.100554] turning off the locking correctness validator.
[ 0.100616] Pid: 0, comm: swapper Not tainted 2.6.25-rc5-mm1 #18
[ 0.100680] [<c0132021>] __lock_acquire+0x13d/0xac0
[ 0.100783] [<c01d9c47>] ? __spin_lock_init+0x27/0x51
[ 0.100920] [<c0158b5c>] ? cache_alloc_debugcheck_after+0xe9/0x165
[ 0.101061] [<c0159e1b>] ? kmem_cache_alloc+0x81/0xcf
[ 0.101537] [<c0132d04>] lock_acquire+0x4c/0x67
[ 0.101631] [<c012b378>] ? down_trylock+0xc/0x27
[ 0.101764] [<c02adc7f>] _spin_lock_irqsave+0x2c/0x3c
[ 0.101866] [<c012b378>] ? down_trylock+0xc/0x27
[ 0.101995] [<c012b378>] down_trylock+0xc/0x27
[ 0.102089] [<c01fdd69>] acpi_os_wait_semaphore+0x70/0x14b
[ 0.102190] [<c0218b0f>] acpi_ut_acquire_mutex+0x64/0xd1
[ 0.102292] [<c020e7f9>] acpi_ns_root_initialize+0x1a/0x279
[ 0.102393] [<c0218aa4>] ? acpi_ut_mutex_initialize+0x75/0x7c
[ 0.102527] [<c03a3dea>] acpi_initialize_subsystem+0x47/0x6a
[ 0.102625] [<c03a4079>] acpi_early_init+0x57/0xf8
[ 0.102719] [<c03909a4>] start_kernel+0x254/0x25e
[ 0.102816] [<c039029d>] i386_start_kernel+0x8/0xa
[ 0.102916] =======================
[ 0.112671] Parsing all Control Methods:
[ 0.112976] Table [DSDT](id 0001) - 356 Objects with 38 Devices 115 Methods 24 Regions
[ 0.113092] tbxface-0598 [00] tb_load_namespace : ACPI Tables successfully acquired
[ 0.113243] ACPI: setting ELCR to 0200 (from 0820)
[ 0.113399] evxfevnt-0091 [00] enable : Transition to ACPI mode successful
[ 0.114291] khelper used greatest stack depth: 3252 bytes left
[ 0.114739] net_namespace: 300 bytes
[ 0.115389] NET: Registered protocol family 16
[ 0.116689] ACPI: bus type pci registered
[ 0.116912] khelper used greatest stack depth: 3156 bytes left
[ 0.119498] PCI: PCI BIOS revision 2.10 entry at 0xf1180, last bus=1
[ 0.119562] PCI: Using configuration type 1
[ 0.119618] Setting up standard PCI resources
[ 0.129006] khelper used greatest stack depth: 3124 bytes left
[ 0.133951] evgpeblk-0956 [00] ev_create_gpe_block : GPE 00 to 0F [_GPE] 2 regs on int 0x9
[ 0.136703] evgpeblk-1052 [00] ev_initialize_gpe_bloc: Found 4 Wake, Enabled 0 Runtime GPEs in this block
[ 0.136863] ACPI: EC: Look up EC in DSDT
[ 0.141645] Completing Region/Field/Buffer/Package initialization:................................................
[ 0.149429] Initialized 16/24 Regions 2/2 Fields 19/19 Buffers 11/14 Packages (365 nodes)
[ 0.149545] Initializing Device/Processor/Thermal objects by executing _INI methods:
[ 0.149647] Executed 0 _INI methods requiring 0 _STA executions (examined 41 objects)
[ 0.149840] ACPI: Interpreter enabled
[ 0.149895] ACPI: (supports S0 S1 S3 S4 S5)
[ 0.150200] ACPI: Using PIC for interrupt routing
[ 0.176842] ACPI: PCI Root Bridge [PCI0] (0000:00)
[ 0.177611] pci 0000:00:04.4: quirk: region e200-e27f claimed by vt82c686 HW-mon
[ 0.177688] pci 0000:00:04.4: quirk: region e800-e80f claimed by vt82c686 SMB
[ 0.178189] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[ 0.322052] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 9 10 *11 12 14 15)
[ 0.322837] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 7 9 10 11 12 14 15) *0, disabled.
[ 0.323679] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 7 9 10 11 12 14 15) *0, disabled.
[ 0.324579] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 *5 6 7 9 10 11 12 14 15)
[ 0.325666] Linux Plug and Play Support v0.97 (c) Adam Belay
[ 0.325911] pnp: PnP ACPI init
[ 0.326004] ACPI: bus type pnp registered
[ 0.335951] pnp: PnP ACPI: found 14 devices
[ 0.336031] ACPI: ACPI bus type pnp unregistered
[ 0.337705] PCI: Using ACPI for IRQ routing
[ 0.356375] Time: tsc clocksource has been installed.
[ 0.380461] system 00:00: iomem range 0x0-0x9ffff could not be reserved
[ 0.380461] system 00:00: iomem range 0xf0000-0xfffff could not be reserved
[ 0.380461] system 00:00: iomem range 0x100000-0x1fffffff could not be reserved
[ 0.380461] system 00:00: iomem range 0xfffe0000-0xffffffff could not be reserved
[ 0.380461] system 00:02: ioport range 0x3f0-0x3f1 has been reserved
[ 0.380461] system 00:02: ioport range 0x4d0-0x4d1 has been reserved
[ 0.380461] system 00:03: ioport range 0xe400-0xe47f has been reserved
[ 0.380461] system 00:03: ioport range 0xe800-0xe80f has been reserved
[ 0.409540] PCI: Bridge: 0000:00:01.0
[ 0.409600] IO window: d000-dfff
[ 0.409658] MEM window: 0xb7000000-0xb7ffffff
[ 0.409718] PREFETCH window: 0x00000000b8000000-0x00000000cfffffff
[ 0.409806] PCI: Setting latency timer of device 0000:00:01.0 to 64
[ 0.409950] NET: Registered protocol family 2
[ 0.484570] IP route cache hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.484570] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
[ 0.484570] TCP bind hash table entries: 16384 (order: 7, 524288 bytes)
[ 0.484570] TCP: Hash tables configured (established 16384 bind 16384)
[ 0.484570] TCP reno registered
[ 0.508580] checking if image is initramfs... it is
[ 0.912640] Switched to NOHz mode on CPU #0
[ 1.010956] Freeing initrd memory: 3283k freed
[ 1.011609] Simple Boot Flag at 0x3a set to 0x1
[ 1.014941] msgmni has been set to 1004 for ipc namespace c0379000
[ 1.015151] io scheduler noop registered
[ 1.015208] io scheduler anticipatory registered
[ 1.015266] io scheduler deadline registered
[ 1.015343] io scheduler cfq registered (default)
[ 1.015421] pci 0000:00:00.0: Applying VIA southbridge workaround
[ 1.015488] PCI: VIA PCI bridge detected. Disabling DAC.
[ 1.015552] pci 0000:00:04.0: Disabling VIA external APIC routing
[ 1.015666] pci 0000:01:00.0: Boot video device
[ 1.017331] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 11
[ 1.017396] PCI: setting IRQ 11 as level-triggered
[ 1.017458] ACPI: PCI Interrupt 0000:01:00.0[A] -> Link [LNKA] -> GSI 11 (level, low) -> IRQ 11
[ 1.018346] radeonfb: Found Intel x86 BIOS ROM Image
[ 1.018410] radeonfb: Retrieved PLL infos from BIOS
[ 1.018469] radeonfb: Reference=27.00 MHz (RefDiv=12) Memory=250.00 Mhz, System=166.00 MHz
[ 1.018544] radeonfb: PLL min 20000 max 40000
[ 1.177376] i2c-adapter i2c-1: unable to read EDID block.
[ 1.394439] i2c-adapter i2c-1: unable to read EDID block.
[ 1.634350] i2c-adapter i2c-1: unable to read EDID block.
[ 1.874262] i2c-adapter i2c-3: unable to read EDID block.
[ 2.102195] i2c-adapter i2c-3: unable to read EDID block.
[ 2.342091] i2c-adapter i2c-3: unable to read EDID block.
[ 2.684899] radeonfb: Monitor 1 type CRT found
[ 2.684910] radeonfb: EDID probed
[ 2.684963] radeonfb: Monitor 2 type no found
[ 2.747641] Console: switching to colour frame buffer device 160x64
[ 2.786206] radeonfb (0000:01:00.0): ATI Radeon 5961 "Ya"
[ 2.787714] input: Power Button (FF) as /class/input/input0
[ 2.788009] ACPI: Power Button (FF) [PWRF]
[ 2.788579] input: Power Button (CM) as /class/input/input1
[ 2.788876] ACPI: Power Button (CM) [PWRB]
[ 2.789965] ACPI: CPU0 (power states: C1[C1] C2[C2])
[ 2.790514] ACPI: ACPI0007:00 is registered as cooling_device0
[ 2.790825] ACPI: Processor [CPU0] (supports 16 throttling states)
[ 2.799218] khelper used greatest stack depth: 3116 bytes left
[ 2.847070] Marking TSC unstable due to: possible TSC halt in C2.
[ 2.851055] Time: acpi_pm clocksource has been installed.
[ 2.859787] brd: module loaded
[ 2.860437] PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at 0x60,0x64 irq 1,12
[ 2.861580] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 2.861852] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 2.862813] mice: PS/2 mouse device common for all mice
[ 2.884324] input: AT Translated Set 2 keyboard as /class/input/input2
[ 2.912025] TCP cubic registered
[ 2.912237] NET: Registered protocol family 1
[ 2.912507] Using IPI Shortcut mode
[ 2.913688] BIOS EDD facility v0.16 2004-Jun-25, 6 devices found
[ 2.915592] Freeing unused kernel memory: 192k freed
[ 2.915896] Write protecting the kernel text: 1724k
[ 2.916164] Write protecting the kernel read-only data: 736k
[ 2.918840] busybox used greatest stack depth: 2908 bytes left
[ 2.933385] exe used greatest stack depth: 2896 bytes left
[ 3.103442] input: ImExPS/2 Generic Explorer Mouse as /class/input/input3
[ 3.120152] consolechars used greatest stack depth: 2460 bytes left
[ 3.324518] device-mapper: uevent: version 1.0.3
[ 3.325101] device-mapper: ioctl: 4.13.0-ioctl (2007-10-18) initialised: dm-devel@redhat.com
[ 3.348866] SCSI subsystem initialized
[ 3.361212] libata version 3.00 loaded.
[ 3.364405] pata_via 0000:00:04.1: version 0.3.3
[ 3.365150] scsi0 : pata_via
[ 3.365667] scsi1 : pata_via
[ 3.368214] ata1: PATA max UDMA/100 cmd 0x1f0 ctl 0x3f6 bmdma 0xb800 irq 14
[ 3.368576] ata2: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0xb808 irq 15
[ 3.551419] ata1.00: ATA-5: ST340016A, 3.75, max UDMA/100
[ 3.552279] ata1.00: 78165360 sectors, multi 16: LBA
[ 3.553326] ata1.01: ATA-7: Maxtor 6Y080L0, YAR41BW0, max UDMA/133
[ 3.554241] ata1.01: 160086528 sectors, multi 16: LBA
[ 3.579144] ata1.00: configured for UDMA/100
[ 3.602850] ata1.01: configured for UDMA/100
[ 4.109645] ata2.00: ATAPI: HL-DT-ST DVDRAM GSA-4165B, DL05, max UDMA/33
[ 4.110649] ata2.01: ATAPI: CD-950E/AKU, A4Q, max MWDMA2, CDB intr
[ 4.289087] ata2.00: configured for UDMA/33
[ 4.464807] ata2.01: configured for MWDMA2
[ 4.484559] scsi 0:0:0:0: Direct-Access ATA ST340016A 3.75 PQ: 0 ANSI: 5
[ 4.486403] scsi 0:0:1:0: Direct-Access ATA Maxtor 6Y080L0 YAR4 PQ: 0 ANSI: 5
[ 4.491762] scsi 1:0:0:0: CD-ROM HL-DT-ST DVDRAM GSA-4165B DL05 PQ: 0 ANSI: 5
[ 4.493905] scsi 1:0:1:0: CD-ROM E-IDE CD-950E/AKU A4Q PQ: 0 ANSI: 5
[ 4.496087] modprobe used greatest stack depth: 2128 bytes left
[ 4.509243] Driver 'sd' needs updating - please use bus_type methods
[ 4.510791] sd 0:0:0:0: [sda] 78165360 512-byte hardware sectors (40021 MB)
[ 4.511976] sd 0:0:0:0: [sda] Write Protect is off
[ 4.513020] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 4.514222] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 4.515673] sd 0:0:0:0: [sda] 78165360 512-byte hardware sectors (40021 MB)
[ 4.516894] sd 0:0:0:0: [sda] Write Protect is off
[ 4.518104] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 4.519273] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 4.520600] sda: sda1 sda2 sda3 < sda5 sda6 sda7 > sda4
[ 4.575675] sd 0:0:0:0: [sda] Attached SCSI disk
[ 4.577205] sd 0:0:1:0: [sdb] 160086528 512-byte hardware sectors (81964 MB)
[ 4.578522] sd 0:0:1:0: [sdb] Write Protect is off
[ 4.579655] sd 0:0:1:0: [sdb] Mode Sense: 00 3a 00 00
[ 4.580852] sd 0:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 4.582644] sd 0:0:1:0: [sdb] 160086528 512-byte hardware sectors (81964 MB)
[ 4.583938] sd 0:0:1:0: [sdb] Write Protect is off
[ 4.585096] sd 0:0:1:0: [sdb] Mode Sense: 00 3a 00 00
[ 4.586318] sd 0:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 4.587735] sdb: sdb1 sdb2 < sdb5 sdb6 sdb7 >
[ 4.633294] sd 0:0:1:0: [sdb] Attached SCSI disk
[ 4.634908] modprobe used greatest stack depth: 1824 bytes left
[ 4.653883] Loading Reiser4. See www.namesys.com for a description of Reiser4.
[ 7.483006] PM: Starting manual resume from disk
[ 7.678628] kjournald starting. Commit interval 5 seconds
[ 7.680220] EXT3-fs: mounted filesystem with ordered data mode.
[ 14.869482] Linux agpgart interface v0.103
[ 14.887631] agpgart: Detected VIA Twister-K/KT133x/KM133 chipset
[ 14.985698] agpgart: AGP aperture is 256M @ 0xd0000000
[ 15.294566] parport_pc: VIA 686A/8231 detected
[ 15.295704] parport_pc: probing current configuration
[ 15.296840] parport_pc: Current parallel port base: 0x378
[ 15.298104] parport0: PC-style at 0x378 (0x778), irq 7 [PCSPP,TRISTATE]
[ 15.362723] <3>ACPI: I/O resource vt596_smbus [0xe800-0xe807] conflicts with ACPI region SM00 [0xe800-0xe806]
[ 15.364105] ACPI: Device needs an ACPI driver
[ 15.365204] vt596_smbus: probe of 0000:00:04.4 failed with error -16
[ 15.386243] usbcore: registered new interface driver usbfs
[ 15.387761] usbcore: registered new interface driver hub
[ 15.391634] parport_pc: VIA parallel port: io=0x378, irq=7
[ 15.416757] ne2k-pci.c:v1.03 9/22/2003 D. Becker/P. Gortmaker
[ 15.418002] ne2k-pci 0000:00:0b.0: enabling device (0000 -> 0001)
[ 15.420362] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 11
[ 15.421501] ACPI: PCI Interrupt 0000:00:0b.0[A] -> Link [LNKB] -> GSI 11 (level, low) -> IRQ 11
[ 15.424021] usbcore: registered new device driver usb
[ 15.453345] eth0: RealTek RTL-8029 found at 0x9400, IRQ 11, 00:40:95:46:6e:2d.
[ 15.454664] via686a 0000:00:04.4: Forcing ISA address 0xe200
[ 15.455850] via686a 0000:00:04.4: Enabling sensors
[ 15.474374] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 15.475643] sd 0:0:1:0: Attached scsi generic sg1 type 0
[ 15.477033] scsi 1:0:0:0: Attached scsi generic sg2 type 5
[ 15.478253] scsi 1:0:1:0: Attached scsi generic sg3 type 5
[ 15.491339] ohci1394 0000:00:09.0: enabling device (0014 -> 0016)
[ 15.493413] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 5
[ 15.494510] PCI: setting IRQ 5 as level-triggered
[ 15.495553] ACPI: PCI Interrupt 0000:00:09.0[A] -> Link [LNKD] -> GSI 5 (level, low) -> IRQ 5
[ 15.548299] USB Universal Host Controller Interface driver v3.0
[ 15.557542] Driver 'sr' needs updating - please use bus_type methods
[ 15.578224] sr0: scsi3-mmc drive: 48x/48x writer dvd-ram cd/rw xa/form2 cdda tray
[ 15.579460] Uniform CD-ROM driver Revision: 3.20
[ 15.581018] sr 1:0:0:0: Attached scsi CD-ROM sr0
[ 15.589475] sr1: scsi3-mmc drive: 0x/48x cd/rw xa/form2 cdda tray
[ 15.600304] sr 1:0:1:0: Attached scsi CD-ROM sr1
[ 15.664086] input: PC Speaker as /class/input/input4
[ 15.880138] Real Time Clock Driver v1.12ac
[ 15.900335] ohci1394: fw-host0: OHCI-1394 1.0 (PCI): IRQ=[5] MMIO=[b6800000-b68007ff] Max Packet=[2048] IR/IT contexts=[8/8]
[ 15.972889] Floppy drive(s): fd0 is 1.44M
[ 16.008178] FDC 0 is a post-1991 82077
[ 16.023321] ACPI: PCI Interrupt 0000:00:04.2[D] -> Link [LNKD] -> GSI 5 (level, low) -> IRQ 5
[ 16.024698] uhci_hcd 0000:00:04.2: UHCI Host Controller
[ 16.026809] uhci_hcd 0000:00:04.2: new USB bus registered, assigned bus number 1
[ 16.028116] uhci_hcd 0000:00:04.2: irq 5, io base 0x0000b400
[ 16.029874] usb usb1: configuration #1 chosen from 1 choice
[ 16.031290] hub 1-0:1.0: USB hub found
[ 16.032388] hub 1-0:1.0: 2 ports detected
[ 16.098646] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing disabled
[ 16.116179] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[ 16.124625] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
[ 16.136700] ACPI: PCI Interrupt 0000:00:04.3[D] -> Link [LNKD] -> GSI 5 (level, low) -> IRQ 5
[ 16.138122] uhci_hcd 0000:00:04.3: UHCI Host Controller
[ 16.139414] uhci_hcd 0000:00:04.3: new USB bus registered, assigned bus number 2
[ 16.140902] uhci_hcd 0000:00:04.3: irq 5, io base 0x0000b000
[ 16.142623] usb usb2: configuration #1 chosen from 1 choice
[ 16.143959] hub 2-0:1.0: USB hub found
[ 16.145183] hub 2-0:1.0: 2 ports detected
[ 16.377409] usb 1-1: new full speed USB device using uhci_hcd and address 2
[ 16.475002] 00:0a: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[ 16.476602] 00:0b: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
[ 16.544039] usb 1-1: configuration #1 chosen from 1 choice
[ 16.548978] hub 1-1:1.0: USB hub found
[ 16.551753] hub 1-1:1.0: 4 ports detected
[ 16.911471] ENS1371 0000:00:0d.0: enabling device (0004 -> 0005)
[ 16.912808] ACPI: PCI Interrupt 0000:00:0d.0[A] -> Link [LNKD] -> GSI 5 (level, low) -> IRQ 5
[ 17.299209] ieee1394: Host added: ID:BUS[0-00:1023] GUID[00308d0120e085ca]
[ 17.591475] NET: Registered protocol family 10
[ 24.933074] EXT3 FS on dm-10, internal journal
[ 25.809923] ReiserFS: dm-0: found reiserfs format "3.6" with standard journal
[ 25.809956] ReiserFS: dm-0: using ordered data mode
[ 25.814985] ReiserFS: dm-0: journal params: device dm-0, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30
[ 25.818453] ReiserFS: dm-0: checking transaction log (dm-0)
[ 25.884446] ReiserFS: dm-0: Using r5 hash to sort names
[ 25.961670] reiser4: dm-5: found disk format 4.0.0.
[ 26.203312] reiser4: dm-4: found disk format 4.0.0.
[ 26.396289] kjournald starting. Commit interval 5 seconds
[ 26.396317] EXT3-fs warning: maximal mount count reached, running e2fsck is recommended
[ 26.396638] EXT3 FS on sda5, internal journal
[ 26.396652] EXT3-fs: mounted filesystem with ordered data mode.
[ 26.500551] ReiserFS: sda7: found reiserfs format "3.6" with standard journal
[ 26.500592] ReiserFS: sda7: using ordered data mode
[ 26.506362] ReiserFS: sda7: journal params: device sda7, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30
[ 26.510054] ReiserFS: sda7: checking transaction log (sda7)
[ 26.536458] ReiserFS: sda7: Using r5 hash to sort names
[ 26.780261] ERR!! init is killed by 10
[ 26.781486] ------------[ cut here ]------------
[ 26.781492] WARNING: at kernel/signal.c:724 complete_signal+0x163/0x1eb()
[ 26.781497] Modules linked in: nls_iso8859_1 nls_cp850 vfat fat reiserfs eeprom w83781d hwmon_vid ipv6 snd_ens1371 firewire_ohci firewire_core gameport crc_itu_t snd_ac97_codec 8250_pnp ac97_bus snd_pcm_oss snd_mixer_oss 8250 serial_core snd_pcm snd_seq_oss floppy snd_seq_midi snd_rawmidi rtc snd_seq_midi_event snd_seq snd_timer snd_seq_device pcspkr snd uhci_hcd sr_mod cdrom soundcore snd_page_alloc ohci1394 sg via686a ne2k_pci 8390 ieee1394 i2c_viapro usbcore ata_generic parport_pc parport via_agp agpgart evdev dm_snapshot reiser4 lzo_decompress lzo_compress sd_mod pata_via libata scsi_mod dm_mirror dm_log dm_mod
[ 26.781609] Pid: 2590, comm: sh Not tainted 2.6.25-rc5-mm1 #18
[ 26.781619] [<c01188bd>] warn_on_slowpath+0x41/0x6d
[ 26.781640] [<c0119200>] ? vprintk+0x289/0x3b6
[ 26.781650] [<c01cc3a8>] ? number+0x10d/0x1cd
[ 26.781671] [<c0158db9>] ? cache_free_debugcheck+0x1e1/0x1ec
[ 26.781699] [<c0119342>] ? printk+0x15/0x17
[ 26.781709] [<c0120fa9>] complete_signal+0x163/0x1eb
[ 26.781719] [<c01211d4>] send_signal+0x1a3/0x1cf
[ 26.781729] [<c0121216>] __group_send_sig_info+0xa/0xc
[ 26.781737] [<c01217cc>] group_send_sig_info+0x44/0x62
[ 26.781747] [<c0121de4>] kill_pid_info+0x33/0x47
[ 26.781757] [<c0122443>] sys_kill+0x73/0x145
[ 26.781767] [<c014c655>] ? handle_mm_fault+0x21d/0x4f6
[ 26.781791] [<c012af3c>] ? up_read+0x16/0x2a
[ 26.781803] [<c011214c>] ? do_page_fault+0x25a/0x4da
[ 26.781815] [<c0103906>] sysenter_past_esp+0x5f/0xa5
[ 26.781834] =======================
[ 26.781838] ---[ end trace c053f6e3c5b0fb23 ]---
[ 26.827206] Adding 1048568k swap on /dev/mapper/vglinux1-lvswap. Priority:-1 extents:1 across:1048568k
[ 27.762345] eth0: no IPv6 routers present
[ 32.522353] lp0: using parport0 (interrupt-driven).
[ 32.840715] [drm] Initialized drm 1.1.0 20060810
[ 32.892406] [drm] Initialized radeon 1.28.0 20060524 on minor 0
[ 34.604435] agpgart: Found an AGP 2.0 compliant device at 0000:00:00.0.
[ 34.604475] agpgart: Putting AGP V2 device at 0000:00:00.0 into 4x mode
[ 34.604604] agpgart: Putting AGP V2 device at 0000:01:00.0 into 4x mode
[ 36.796991] [drm] Setting GART location based on new memory map
[ 36.797011] [drm] Loading R200 Microcode
[ 36.797055] [drm] writeback test succeeded in 1 usecs
[ 37.919570] warning: `avahi-daemon' uses 32-bit capabilities (legacy support in use)
[ 53.671327] gpg-agent used greatest stack depth: 1692 bytes left
[ 65.797323] ed used greatest stack depth: 1600 bytes left
[ 237.574866] reiser4[pdflush(139)]: disable_write_barrier (fs/reiser4/wander.c:234)[zam-1055]:
[ 237.574872] NOTICE: dm-4 does not support write barriers, using synchronous write instead.