boottime increased again

[MadMan2k]

the only error that is displayed is that /selinux is not found.
But I added my full dmesg log as attachment.

My system is only a athlon XP2800+ with 768MB CL2.5 166Mhz Ram and
a Maxtor 80GB 7200rpm hdd. (+200GB Maxtor 7200rpm data partition)

M2k

Phillip Susi wrote:
> WOW!  1:25 bootchart?  Mine's 0:24.  It looks like you're spending an 
> insane amount of time just doing hardware detection in the initrd ( ~44 
> seconds before the real init runs ).  Are you getting any kind of odd 
> dmesg errors during this time?
> 
> Then once the main system starts coming up, I see several fscks in there 
> and evms stuff.  I assume you are using evms?  Seems like that is doing 
> a lot of work it shouldn't need to.
> 
> For reference I'm running an athlon 64 3200+ ( 2 years old now, socket 
> 774 I think it was, before 939 ), 1 gig of corsair low latency pc3200 
> sdram, and booting from a hardware fakeraid raid0 of 2 x 10,000 rpm 36 
> gig WD raptor drives partitioned as:
> 
> 1 - 40 gigs NTFS winder's partition ( never use anymore )
> 2 - (extended partition)
> 3 - 10 gig experimental partition ( alternate OSes and such )
> 5 - 50 MB /boot  ext2
> 6 - 1 gig swap   swap
> 7 - 22 gig /     reiserfs
> 
> MadMan2k wrote:
>> I'm sorry I forgot, who was responsible for the boot-time 
>> optimizations, so I am sending it to the devel.
>>
>> after the first work on that, my bootime used to be at ~70s and 
>> increased now again.
>> I am not sure with which update it started, but I think it was 
>> sometime after linux went final.
>>
>> here is my bootchart
>> http://www.madman2k.net/files/dapper-20060125-1.png
>>
>> M2k
>>
> 
> 
-------------- next part --------------
00] ACPI: PCI Interrupt Link [LFIR] (IRQs 3 4 5 6 7 9 10 11 12 14 15) *0, disabled.
[4294669.541000] ACPI: PCI Interrupt Link [L3CM] (IRQs 3 4 5 6 7 9 10 11 12 14 15) *0, disabled.
[4294669.541000] ACPI: PCI Interrupt Link [LIDE] (IRQs 3 4 5 6 7 9 10 11 12 14 15) *0, disabled.
[4294669.542000] ACPI: PCI Interrupt Link [LSID] (IRQs 3 4 5 6 7 9 10 *11 12 14 15)
[4294669.542000] ACPI: PCI Interrupt Link [APC1] (IRQs *16), disabled.
[4294669.543000] ACPI: PCI Interrupt Link [APC2] (IRQs *17), disabled.
[4294669.543000] ACPI: PCI Interrupt Link [APC3] (IRQs *18), disabled.
[4294669.543000] ACPI: PCI Interrupt Link [APC4] (IRQs *19), disabled.
[4294669.544000] ACPI: PCI Interrupt Link [APC5] (IRQs *16), disabled.
[4294669.544000] ACPI: PCI Interrupt Link [APCF] (IRQs 20 21) *0, disabled.
[4294669.544000] ACPI: PCI Interrupt Link [APCG] (IRQs 20 21) *0, disabled.
[4294669.545000] ACPI: PCI Interrupt Link [APCH] (IRQs 20 21) *0, disabled.
[4294669.545000] ACPI: PCI Interrupt Link [APCI] (IRQs 20 21) *0, disabled.
[4294669.546000] ACPI: PCI Interrupt Link [APCJ] (IRQs 20 21) *0, disabled.
[4294669.546000] ACPI: PCI Interrupt Link [APCK] (IRQs 20 21) *0, disabled.
[4294669.547000] ACPI: PCI Interrupt Link [APCS] (IRQs *23), disabled.
[4294669.547000] ACPI: PCI Interrupt Link [APCL] (IRQs 20 21) *0, disabled.
[4294669.547000] ACPI: PCI Interrupt Link [APCM] (IRQs 20 21) *0, disabled.
[4294669.548000] ACPI: PCI Interrupt Link [AP3C] (IRQs 20 21) *0, disabled.
[4294669.548000] ACPI: PCI Interrupt Link [APCZ] (IRQs 20 21) *0, disabled.
[4294669.549000] ACPI: PCI Interrupt Link [APSI] (IRQs 22) *0, disabled.
[4294669.552000] Linux Plug and Play Support v0.97 (c) Adam Belay
[4294669.552000] pnp: PnP ACPI init
[4294669.558000] pnp: PnP ACPI: found 12 devices
[4294669.558000] PnPBIOS: Disabled by ACPI PNP
[4294669.558000] PCI: Using ACPI for IRQ routing
[4294669.558000] PCI: If a device doesn't work, try "pci=routeirq".  If it helps, post a report
[4294669.605000] pnp: 00:00: ioport range 0x4000-0x407f could not be reserved
[4294669.605000] pnp: 00:00: ioport range 0x4080-0x40ff has been reserved
[4294669.605000] pnp: 00:00: ioport range 0x4400-0x447f has been reserved
[4294669.606000] pnp: 00:00: ioport range 0x4480-0x44ff could not be reserved
[4294669.606000] pnp: 00:00: ioport range 0x4200-0x427f has been reserved
[4294669.606000] pnp: 00:00: ioport range 0x4280-0x42ff has been reserved
[4294669.606000] pnp: 00:01: ioport range 0x5000-0x503f has been reserved
[4294669.606000] pnp: 00:01: ioport range 0x5100-0x513f has been reserved
[4294669.606000] PCI: Bridge: 0000:00:08.0
[4294669.606000]   IO window: a000-afff
[4294669.606000]   MEM window: e4000000-e4ffffff
[4294669.606000]   PREFETCH window: disabled.
[4294669.606000] PCI: Bridge: 0000:00:1e.0
[4294669.606000]   IO window: 9000-9fff
[4294669.606000]   MEM window: e5000000-e6ffffff
[4294669.606000]   PREFETCH window: d0000000-dfffffff
[4294669.606000] PCI: Setting latency timer of device 0000:00:08.0 to 64
[4294669.606000] audit: initializing netlink socket (disabled)
[4294669.606000] audit(1138363517.604:1): initialized
[4294669.606000] VFS: Disk quotas dquot_6.5.1
[4294669.606000] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[4294669.606000] Initializing Cryptographic API
[4294669.606000] io scheduler noop registered
[4294669.607000] io scheduler anticipatory registered
[4294669.607000] io scheduler deadline registered
[4294669.607000] io scheduler cfq registered
[4294669.629000] isapnp: Scanning for PnP cards...
[4294669.986000] isapnp: No Plug & Play device found
[4294670.001000] PNP: PS/2 Controller [PNP0303:PS2K] at 0x60,0x64 irq 1
[4294670.001000] PNP: PS/2 controller doesn't have AUX irq; using default 12
[4294670.002000] serio: i8042 AUX port at 0x60,0x64 irq 12
[4294670.002000] serio: i8042 KBD port at 0x60,0x64 irq 1
[4294670.002000] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
[4294670.002000] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[4294670.003000] 00:09: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[4294670.003000] RAMDISK driver initialized: 16 RAM disks of 65536K size 1024 blocksize
[4294670.003000] Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
[4294670.003000] ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
[4294670.003000] ACPI: bus type ide registered
[4294670.003000] mice: PS/2 mouse device common for all mice
[4294670.004000] EISA: Probing bus 0 at eisa.0
[4294670.004000] Cannot allocate resource for EISA slot 4
[4294670.004000] Cannot allocate resource for EISA slot 5
[4294670.004000] EISA: Detected 0 cards.
[4294670.004000] NET: Registered protocol family 2
[4294670.013000] IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
[4294670.013000] TCP established hash table entries: 131072 (order: 8, 1572864 bytes)
[4294670.015000] TCP bind hash table entries: 65536 (order: 7, 786432 bytes)
[4294670.016000] TCP: Hash tables configured (established 131072 bind 65536)
[4294670.016000] TCP reno registered
[4294670.016000] TCP bic registered
[4294670.016000] NET: Registered protocol family 1
[4294670.016000] NET: Registered protocol family 8
[4294670.016000] NET: Registered protocol family 20
[4294670.016000] Using IPI No-Shortcut mode
[4294670.016000] ACPI wakeup devices: 
[4294670.016000] HUB0 HUB1 USB0 USB1 USB2 F139 MMAC MMCI UAR1 
[4294670.016000] ACPI: (supports S0 S1 S4 S5)
[4294670.016000] Freeing unused kernel memory: 300k freed
[4294670.093000] vesafb: cannot reserve video memory at 0xd0000000
[4294670.093000] vesafb: framebuffer at 0xd0000000, mapped to 0xf0880000, using 7680k, total 262144k
[4294670.093000] vesafb: mode is 1280x1024x24, linelength=3840, pages=67
[4294670.093000] vesafb: protected mode interface info at c000:57ff
[4294670.093000] vesafb: scrolling: redraw
[4294670.093000] vesafb: Truecolor: size=0:8:8:8, shift=0:16:8:0
[4294670.093000] vesafb: Mode is VGA compatible
[4294670.224000] input: AT Translated Set 2 keyboard as /class/input/input0
[4294670.290000] Console: switching to colour frame buffer device 160x64
[4294670.290000] fb0: VESA VGA frame buffer device
[4294671.417000] Capability LSM initialized
[4294671.820000] usbcore: registered new driver usbfs
[4294671.820000] usbcore: registered new driver hub
[4294671.821000] ohci_hcd: 2005 April 22 USB 1.1 'Open' Host Controller (OHCI) Driver (PCI)
[4294671.822000] ACPI: PCI Interrupt Link [APCF] enabled at IRQ 21
[4294671.822000] ACPI: PCI Interrupt 0000:00:02.0[A] -> Link [APCF] -> GSI 21 (level, high) -> IRQ 177
[4294671.822000] PCI: Setting latency timer of device 0000:00:02.0 to 64
[4294671.822000] ohci_hcd 0000:00:02.0: OHCI Host Controller
[4294671.822000] ohci_hcd 0000:00:02.0: new USB bus registered, assigned bus number 1
[4294671.822000] ohci_hcd 0000:00:02.0: irq 177, io mem 0xe7000000
[4294671.875000] hub 1-0:1.0: USB hub found
[4294671.875000] hub 1-0:1.0: 4 ports detected
[4294671.976000] ACPI: PCI Interrupt Link [APCG] enabled at IRQ 20
[4294671.976000] ACPI: PCI Interrupt 0000:00:02.1[B] -> Link [APCG] -> GSI 20 (level, high) -> IRQ 185
[4294671.976000] PCI: Setting latency timer of device 0000:00:02.1 to 64
[4294671.976000] ohci_hcd 0000:00:02.1: OHCI Host Controller
[4294671.977000] ohci_hcd 0000:00:02.1: new USB bus registered, assigned bus number 2
[4294671.977000] ohci_hcd 0000:00:02.1: irq 185, io mem 0xe7001000
[4294672.030000] hub 2-0:1.0: USB hub found
[4294672.030000] hub 2-0:1.0: 4 ports detected
[4294672.182000] ACPI: PCI Interrupt Link [APCL] enabled at IRQ 21
[4294672.182000] ACPI: PCI Interrupt 0000:00:02.2[C] -> Link [APCL] -> GSI 21 (level, high) -> IRQ 177
[4294672.182000] PCI: Setting latency timer of device 0000:00:02.2 to 64
[4294672.182000] ehci_hcd 0000:00:02.2: EHCI Host Controller
[4294672.182000] ehci_hcd 0000:00:02.2: debug port 1
[4294672.182000] PCI: cache line size of 64 is not supported by device 0000:00:02.2
[4294672.183000] ehci_hcd 0000:00:02.2: new USB bus registered, assigned bus number 3
[4294672.183000] ehci_hcd 0000:00:02.2: irq 177, io mem 0xe7002000
[4294672.183000] ehci_hcd 0000:00:02.2: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
[4294672.183000] hub 3-0:1.0: USB hub found
[4294672.183000] hub 3-0:1.0: 8 ports detected
[4294672.193000] usb 1-2: new low speed USB device using ohci_hcd and address 2
[4294672.313000] forcedeth.c: Reverse Engineered nForce ethernet driver. Version 0.48.
[4294672.314000] ACPI: PCI Interrupt Link [APCH] enabled at IRQ 20
[4294672.314000] ACPI: PCI Interrupt 0000:00:04.0[A] -> Link [APCH] -> GSI 20 (level, high) -> IRQ 185
[4294672.314000] PCI: Setting latency timer of device 0000:00:04.0 to 64
[4294672.827000] eth0: forcedeth.c: subsystem: 01462:570c bound to 0000:00:04.0
[4294672.903000] NFORCE2-U400R: IDE controller at PCI slot 0000:00:09.0
[4294672.903000] NFORCE2-U400R: chipset revision 163
[4294672.903000] NFORCE2-U400R: not 100% native mode: will probe irqs later
[4294672.903000] NFORCE2-U400R: BIOS didn't set cable bits correctly. Enabling workaround.
[4294672.903000] NFORCE2-U400R: 0000:00:09.0 (rev a3) UDMA133 controller
[4294672.903000]     ide0: BM-DMA at 0xf000-0xf007, BIOS settings: hda:DMA, hdb:DMA
[4294672.903000]     ide1: BM-DMA at 0xf008-0xf00f, BIOS settings: hdc:DMA, hdd:DMA
[4294672.903000] Probing IDE interface ide0...
[4294672.924000] usb 3-3: new high speed USB device using ehci_hcd and address 3
[4294673.170000] hda: Maxtor 6Y080P0, ATA DISK drive
[4294673.412000] usb 1-2: new low speed USB device using ohci_hcd and address 3
[4294673.425000] hdb: Maxtor 6Y200P0, ATA DISK drive
[4294673.476000] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
[4294673.476000] Probing IDE interface ide1...
[4294673.569000] ohci_hcd 0000:00:02.1: wakeup
[4294673.846000] usb 2-2: new low speed USB device using ohci_hcd and address 2
[4294674.369000] usbcore: registered new driver hiddev
[4294674.391000] input: Logitech Logitech RumblePad 2 USB as /class/input/input1
[4294674.391000] input: USB HID v1.10 Joystick [Logitech Logitech RumblePad 2 USB] on usb-0000:00:02.0-2
[4294674.404000] input: Logitech USB-PS/2 Optical Mouse as /class/input/input2
[4294674.404000] input: USB HID v1.10 Mouse [Logitech USB-PS/2 Optical Mouse] on usb-0000:00:02.1-2
[4294674.404000] usbcore: registered new driver usbhid
[4294674.404000] drivers/usb/input/hid-core.c: v2.6:USB HID core driver
[4294687.894000] hdc: BENQ DVD DD DW1640, ATAPI CD/DVD-ROM drive
[4294688.610000] hdd: TSSTcorpDVD-ROM TS-H352A, ATAPI CD/DVD-ROM drive
[4294688.663000] ide1 at 0x170-0x177,0x376 on irq 15
[4294688.667000] SCSI subsystem initialized
[4294688.668000] libata version 1.20 loaded.
[4294688.670000] sata_nv 0000:00:0b.0: version 0.8
[4294688.671000] ACPI: PCI Interrupt Link [APSI] enabled at IRQ 22
[4294688.671000] ACPI: PCI Interrupt 0000:00:0b.0[A] -> Link [APSI] -> GSI 22 (level, high) -> IRQ 193
[4294688.671000] PCI: Setting latency timer of device 0000:00:0b.0 to 64
[4294688.671000] ata1: SATA max UDMA/133 cmd 0x9F0 ctl 0xBF2 bmdma 0xBC00 irq 193
[4294688.671000] ata2: SATA max UDMA/133 cmd 0x970 ctl 0xB72 bmdma 0xBC08 irq 193
[4294688.872000] ata1: no device found (phy stat 00000000)
[4294688.873000] scsi0 : sata_nv
[4294689.075000] ata2: no device found (phy stat 00000000)
[4294689.075000] scsi1 : sata_nv
[4294689.081000] hda: max request size: 128KiB
[4294689.095000] hda: 160086528 sectors (81964 MB) w/7936KiB Cache, CHS=65535/16/63, UDMA(133)
[4294689.095000] hda: cache flushes supported
[4294689.095000]  hda: hda1 hda2 < hda5 hda6 hda7 >
[4294689.150000] hdb: max request size: 1024KiB
[4294689.150000] hdb: 398297088 sectors (203928 MB) w/7936KiB Cache, CHS=24792/255/63, UDMA(133)
[4294689.150000] hdb: cache flushes supported
[4294689.151000]  hdb: hdb1 hdb2 < hdb5 >
[4294689.181000] hdc: ATAPI 48X DVD-ROM DVD-R CD-R/RW drive, 2048kB Cache, UDMA(33)
[4294689.181000] Uniform CD-ROM driver Revision: 3.20
[4294689.194000] hdd: ATAPI 48X DVD-ROM drive, 512kB Cache, UDMA(33)
[4294689.695000] ReiserFS: hda6: found reiserfs format "3.6" with standard journal
[4294711.638000] ReiserFS: hda6: using ordered data mode
[4294711.651000] ReiserFS: hda6: journal params: device hda6, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30
[4294711.652000] ReiserFS: hda6: checking transaction log (hda6)
[4294711.727000] ReiserFS: hda6: Using r5 hash to sort names
[4294715.693000] NET: Registered protocol family 17
[4294716.506000] Linux agpgart interface v0.101 (c) Dave Jones
[4294716.510000] agpgart: Detected NVIDIA nForce2 chipset
[4294716.515000] agpgart: AGP aperture is 64M @ 0xe0000000
[4294716.831000] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[4294716.839000] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[4294716.923000] parport: PnPBIOS parport detected.
[4294716.923000] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
[4294716.947000] parport0: faking semi-colon
[4294716.947000] parport0: Printer, HEWLETT-PACKARD DESKJET 720C
[4294716.950000] Floppy drive(s): fd0 is 1.44M
[4294716.957000] i2c_adapter i2c-0: nForce2 SMBus adapter at 0x5000
[4294716.957000] i2c_adapter i2c-1: nForce2 SMBus adapter at 0x5100
[4294717.017000] FDC 0 is a post-1991 82077
[4294717.052000] Real Time Clock Driver v1.12
[4294717.233000] gameport: EMU10K1 is pci0000:02:0a.1/gameport0, io 0xa400, speed 890kHz
[4294717.492000] ieee1394: Initialized config rom entry `ip1394'
[4294717.494000] ohci1394: $Rev: 1313 $ Ben Collins <bcollins at debian.org>
[4294717.496000] ACPI: PCI Interrupt Link [APC4] enabled at IRQ 19
[4294717.496000] ACPI: PCI Interrupt 0000:02:0a.2[B] -> Link [APC4] -> GSI 19 (level, low) -> IRQ 201
[4294717.549000] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[201]  MMIO=[e4004000-e40047ff]  Max Packet=[2048]
[4294717.550000] input: PC Speaker as /class/input/input3
[4294717.765000] ACPI: PCI Interrupt Link [APC3] enabled at IRQ 18
[4294717.765000] ACPI: PCI Interrupt 0000:02:0a.0[A] -> Link [APC3] -> GSI 18 (level, low) -> IRQ 209
[4294717.770000] Installing spdif_bug patch: Audigy 2 ZS [SB0350]
[4294717.798000] NET: Registered protocol family 10
[4294717.798000] lo: Disabled Privacy Extensions
[4294717.799000] IPv6 over IPv4 tunneling driver
[4294718.784000] lp0: using parport0 (interrupt-driven).
[4294718.807000] ieee1394: Host added: ID:BUS[0-00:1023]  GUID[00023c015113ec90]
[4294718.865000] sbp2: $Rev: 1306 $ Ben Collins <bcollins at debian.org>
[4294718.865000] ieee1394: sbp2: Driver forced to serialize I/O (serialize_io=1)
[4294718.865000] ieee1394: sbp2: Try serialize_io=0 for better performance
[4294726.858000] md: md driver 0.90.3 MAX_MD_DEVS=256, MD_SB_DISKS=27
[4294726.858000] md: bitmap version 4.39
[4294727.530000] device-mapper: 4.4.0-ioctl (2005-01-12) initialised: dm-devel at redhat.com
[4294728.213000] eth0: no IPv6 routers present
[4294728.319000] cdrom: open failed.
[4294729.568000] cdrom: open failed.
[4294729.569000] cdrom: open failed.
[4294733.707000] ReiserFS: hda7: found reiserfs format "3.6" with standard journal
[4294734.659000] ReiserFS: hda7: using ordered data mode
[4294734.704000] ReiserFS: hda7: journal params: device hda7, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30
[4294734.705000] ReiserFS: hda7: checking transaction log (hda7)
[4294734.831000] ReiserFS: hda7: Using r5 hash to sort names
[4294734.911000] kjournald starting.  Commit interval 5 seconds
[4294734.911000] EXT3 FS on hdb1, internal journal
[4294734.911000] EXT3-fs: mounted filesystem with ordered data mode.
[4294735.987000] Adding 524280k swap on /swapfile.  Priority:-1 extents:64 across:533836k