kern/151924: very slow boot from disk: 15m+

Thu Nov 4 17:30:13 UTC 2010

The following reply was made to PR kern/151924; it has been noted by GNATS.

From: Fulvio Ciriaco <oivulf at gmail.com>
To: Remko Lodder <remko at elvandar.org>
Cc: fulvio ciriaco <oivulf at gmail.com>, freebsd-gnats-submit at FreeBSD.org
Subject: Re: kern/151924: very slow boot from disk: 15m+
Date: Thu, 04 Nov 2010 17:51:23 +0100

 --Multipart_Thu_Nov__4_17:51:22_2010-1
 Content-Type: text/plain; charset=US-ASCII

 Hallo,
 first of all, all the phases of the boot are slow,
 to explain it better, at boot I have:
 FreeBSD/i386...
 (root@
 |/-\ 2min
 Load boot/defaults/loader.conf
 |/-\ 2min
 Warning: unable to open file /boot/loader.conf.local
 |/-\ 4min
 /boot/kernel/kernel text 0x.......
 if_iwi... |/-\ 2min
 zpool_cache ... * failed
 |/-\
 <chooser screen>
 \/|- 1min
 and the prompt.
 There is no problem once the system is up, not even exercising the
 disk intensively. 
 I attach the dmesg:

 --Multipart_Thu_Nov__4_17:51:22_2010-1
 Content-Type: text/plain; charset=US-ASCII
 Content-Disposition: inline; filename="dmesg.txt"

 Copyright (c) 1992-2010 The FreeBSD Project.
 Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
 	The Regents of the University of California. All rights reserved.
 FreeBSD is a registered trademark of The FreeBSD Foundation.
 FreeBSD 8.1-RELEASE #0: Mon Jul 19 02:55:53 UTC 2010
     root at almeida.cse.buffalo.edu:/usr/obj/usr/src/sys/GENERIC i386
 Timecounter "i8254" frequency 1193182 Hz quality 0
 CPU: Intel(R) Pentium(R) M processor 1.86GHz (1862.13-MHz 686-class CPU)
   Origin = "GenuineIntel"  Id = 0x6d8  Family = 6  Model = d  Stepping = 8
   Features=0xafe9fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,CLFLUSH,DTS,ACPI,MMX,FXSR,SSE,SSE2,SS,TM,PBE>
   Features2=0x180<EST,TM2>
   AMD Features=0x100000<NX>
 real memory  = 2147483648 (2048 MB)
 avail memory = 2090962944 (1994 MB)
 ACPI APIC Table: <DELL   CPi R  >
 ioapic0: Changing APIC ID to 1
 ioapic0 <Version 2.0> irqs 0-23 on motherboard
 kbd1 at kbdmux0
 acpi0: <DELL CPi R  > on motherboard
 acpi0: [ITHREAD]
 acpi0: reservation of 0, 9fc00 (3) failed
 acpi0: reservation of 100000, 7fed8000 (3) failed
 Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000
 acpi_timer0: <24-bit timer at 3.579545MHz> port 0x1008-0x100b on acpi0
 cpu0: <ACPI CPU> on acpi0
 acpi_acad0: <AC Adapter> on acpi0
 battery0: <ACPI Control Method Battery> on acpi0
 battery1: <ACPI Control Method Battery> on acpi0
 acpi_lid0: <Control Method Lid Switch> on acpi0
 acpi_button0: <Power Button> on acpi0
 acpi_button1: <Sleep Button> on acpi0
 pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
 pci0: <ACPI PCI bus> on pcib0
 pcib1: <ACPI PCI-PCI bridge> at device 1.0 on pci0
 pci1: <ACPI PCI bus> on pcib1
 vgapci0: <VGA-compatible display> port 0xde00-0xdeff mem 0xd0000000-0xd7ffffff,0xdfdf0000-0xdfdfffff irq 16 at device 0.0 on pci1
 pcib2: <ACPI PCI-PCI bridge> at device 28.0 on pci0
 pci2: <ACPI PCI bus> on pcib2
 bge0: <Broadcom NetXtreme Gigabit Ethernet Controller, ASIC rev. 0x004001> mem 0xdfcf0000-0xdfcfffff irq 16 at device 0.0 on pci2
 miibus0: <MII bus> on bge0
 brgphy0: <BCM5750 10/100/1000baseTX PHY> PHY 1 on miibus0
 brgphy0:  10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 1000baseT-FDX, auto
 bge0: Ethernet address: 00:14:22:e0:cc:40
 bge0: [ITHREAD]
 uhci0: <Intel 82801FB/FR/FW/FRW (ICH6) USB controller USB-A> port 0xbf80-0xbf9f irq 16 at device 29.0 on pci0
 uhci0: [ITHREAD]
 usbus0: <Intel 82801FB/FR/FW/FRW (ICH6) USB controller USB-A> on uhci0
 uhci1: <Intel 82801FB/FR/FW/FRW (ICH6) USB controller USB-B> port 0xbf60-0xbf7f irq 17 at device 29.1 on pci0
 uhci1: [ITHREAD]
 usbus1: <Intel 82801FB/FR/FW/FRW (ICH6) USB controller USB-B> on uhci1
 uhci2: <Intel 82801FB/FR/FW/FRW (ICH6) USB controller USB-C> port 0xbf40-0xbf5f irq 18 at device 29.2 on pci0
 uhci2: [ITHREAD]
 usbus2: <Intel 82801FB/FR/FW/FRW (ICH6) USB controller USB-C> on uhci2
 uhci3: <Intel 82801FB/FR/FW/FRW (ICH6) USB controller USB-D> port 0xbf20-0xbf3f irq 19 at device 29.3 on pci0
 uhci3: [ITHREAD]
 usbus3: <Intel 82801FB/FR/FW/FRW (ICH6) USB controller USB-D> on uhci3
 ehci0: <Intel 82801FB (ICH6) USB 2.0 controller> mem 0xffa80800-0xffa80bff irq 16 at device 29.7 on pci0
 ehci0: [ITHREAD]
 usbus4: EHCI version 1.0
 usbus4: <Intel 82801FB (ICH6) USB 2.0 controller> on ehci0
 pcib3: <ACPI PCI-PCI bridge> at device 30.0 on pci0
 pci3: <ACPI PCI bus> on pcib3
 cbb0: <PCI-CardBus Bridge> at device 1.0 on pci3
 cardbus0: <CardBus bus> on cbb0
 pccard0: <16-bit PCCard bus> on cbb0
 cbb0: [FILTER]
 pci3: <simple comms> at device 1.5 (no driver attached)
 iwi0: <Intel(R) PRO/Wireless 2200BG> mem 0xdfbff000-0xdfbfffff irq 17 at device 3.0 on pci3
 iwi0: [ITHREAD]
 pci0: <multimedia, audio> at device 30.2 (no driver attached)
 pci0: <simple comms, generic modem> at device 30.3 (no driver attached)
 isab0: <PCI-ISA bridge> at device 31.0 on pci0
 isa0: <ISA bus> on isab0
 atapci0: <Intel ICH6M SATA150 controller> port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0xbfa0-0xbfaf irq 17 at device 31.2 on pci0
 ata0: <ATA channel 0> on atapci0
 ata0: [ITHREAD]
 ata1: <ATA channel 1> on atapci0
 ata1: [ITHREAD]
 acpi_tz0: <Thermal Zone> on acpi0
 atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64,0x62,0x66 irq 1 on acpi0
 atkbd0: <AT Keyboard> irq 1 on atkbdc0
 kbd0 at atkbd0
 atkbd0: [GIANT-LOCKED]
 atkbd0: [ITHREAD]
 psm0: <PS/2 Mouse> irq 12 on atkbdc0
 psm0: [GIANT-LOCKED]
 psm0: [ITHREAD]
 psm0: model GlidePoint, device ID 0
 atrtc0: <AT realtime clock> port 0x70-0x71,0x72-0x77 irq 8 on acpi0
 uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
 uart0: [FILTER]
 pmtimer0 on isa0
 orm0: <ISA Option ROM> at iomem 0xc0000-0xcffff pnpid ORM0000 on isa0
 sc0: <System console> at flags 0x100 on isa0
 sc0: VGA <16 virtual consoles, flags=0x300>
 vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
 ppc0: parallel port not found.
 est0: <Enhanced SpeedStep Frequency Control> on cpu0
 p4tcc0: <CPU Frequency Thermal Control> on cpu0
 Timecounter "TSC" frequency 1862132075 Hz quality 800
 Timecounters tick every 1.000 msec
 usbus0: 12Mbps Full Speed USB v1.0
 usbus1: 12Mbps Full Speed USB v1.0
 usbus2: 12Mbps Full Speed USB v1.0
 usbus3: 12Mbps Full Speed USB v1.0
 usbus4: 480Mbps High Speed USB v2.0
 ugen0.1: <Intel> at usbus0
 uhub0: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus0
 ugen1.1: <Intel> at usbus1
 uhub1: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus1
 ugen2.1: <Intel> at usbus2
 uhub2: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus2
 ugen3.1: <Intel> at usbus3
 uhub3: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus3
 ugen4.1: <Intel> at usbus4
 uhub4: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus4
 ad0: 76319MB <SAMSUNG MP0804H UE200-16> at ata0-master UDMA100 SATA
 uhub0: 2 ports with 2 removable, self powered
 uhub1: 2 ports with 2 removable, self powered
 uhub2: 2 ports with 2 removable, self powered
 uhub3: 2 ports with 2 removable, self powered
 acd0: CDRW <TSSTcorpCD-RW/DVD-ROM TSL462C/DE01> at ata1-master UDMA33 SATA
 Root mount waiting for: usbus4
 Root mount waiting for: usbus4
 uhub4: 8 ports with 8 removable, self powered
 Root mount waiting for: usbus4
 Trying to mount root from ufs:/dev/ad0s1a
 ugen1.2: <vendor 0x413c> at usbus1
 wlan0: Ethernet address: 00:16:6f:36:1c:64
 iwi0: need multicast update callback
 wlan0: link state changed to UP
 drm0: <ATI Radeon Mobility X600 M24> on vgapci0
 info: [drm] MSI enabled 1 message(s)
 vgapci0: child drm0 requested pci_enable_busmaster
 info: [drm] Initialized radeon 1.31.0 20080613
 info: [drm] Setting GART location based on new memory map
 info: [drm] Loading R300 Microcode
 info: [drm] Num pipes: 1
 info: [drm] writeback test succeeded in 1 usecs
 drm0: [ITHREAD]
 wlan0: link state changed to DOWN
 wlan0: link state changed to UP

 Fulvio

 At Thu, 4 Nov 2010 11:23:31 +0100,
 Remko Lodder wrote:
 >
 > >
 >
 > Can you also send a verbose boot (dmesg) so that we can see how the hardware probing etc goes? To be honest;
 > the information you send in now, is not enough to see and understand why this takes long. It would also be great
 > if you can have a look whether or not there are error messages in the logfiles (/var/log/*) that might suggest that
 > there are timeouts or something (which in turn might indicate that the disk is having problems or something).
 >
 > Thanks,
 > Remko
 >
 > --
 > /"\   Best regards,                        | remko at FreeBSD.org
 > \ /   Remko Lodder                      |
 > X    http://www.evilcoder.org/    | Quis custodiet ipsos custodes
 > / \   ASCII Ribbon Campaign    | Against HTML Mail and News
 >
 >
 >
 >

 --Multipart_Thu_Nov__4_17:51:22_2010-1--