Date: Fri, 24 Nov 2000 10:32:19 +0100 (MET) From: Mikael Hybsch <mhybsch@rsasecurity.com> To: <freebsd-hackers@freebsd.org> Subject: 4.2-STABLE takes 35 seconds to probe ata1-master. Message-ID: <Pine.GSO.4.30.0011241006130.15577-100000@spirit.dynas.se>
next in thread | raw e-mail | index | archive | help
Hi.
I cvsuped RELENG_4 last night on my home machine an now I have a 35 second
delay while probing my DVD on ata1-master. Before the upgrade I was
running RELENG_4 from around June and it didn't show this behaviour.
This is a SMP machine, but the dmesg output below is from a GENERIC kernel
I compiled to make sure that I didn't add anything strange.
There is a comment in the dmesg output to show where I get the delay.
Regards,
Mikael.
Copyright (c) 1992-2000 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 4.2-STABLE #0: Thu Nov 23 23:07:53 CET 2000
micke@kaka.home.dynas.se:/d/src/sys/compile/GENERIC
Calibrating clock(s) ... TSC clock: 400886114 Hz, i8254 clock: 1193114 Hz
CLK_USE_I8254_CALIBRATION not specified - using default frequency
Timecounter "i8254" frequency 1193182 Hz
CLK_USE_TSC_CALIBRATION not specified - using old calibration method
CPU: Pentium II/Pentium II Xeon/Celeron (400.91-MHz 686-class CPU)
Origin = "GenuineIntel" Id = 0x651 Stepping = 1
Features=0x183fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR>
real memory = 134205440 (131060K bytes)
Physical memory chunk(s):
0x00001000 - 0x0009efff, 647168 bytes (158 pages)
0x0044f000 - 0x07ff4fff, 129654784 bytes (31654 pages)
avail memory = 126377984 (123416K bytes)
bios32: Found BIOS32 Service Directory header at 0xc00f9d80
bios32: Entry = 0xf0530 (c00f0530) Rev = 0 Len = 1
pcibios: PCI BIOS entry at 0x730
pnpbios: Found PnP BIOS data at 0xc00fd240
pnpbios: Entry = f0000:d270 Rev = 1.0
pnpbios: OEM ID cd041
Other BIOS signatures found:
ACPI: 000f8070
Preloaded elf kernel "kernel.GENERIC" at 0xc0436000.
Pentium Pro MTRR support enabled
md0: Malloc disk
Creating DISK md0
Math emulator present
pci_open(1): mode 1 addr port (0x0cf8) is 0x80010048
pci_open(1a): mode1res=0x80000000 (0x80000000)
pci_cfgcheck: device 0 [class=060000] [hdr=00] is there (id=71908086)
npx0: <math processor> on motherboard
npx0: INT 16 interface
pci_open(1): mode 1 addr port (0x0cf8) is 0x00000000
pci_open(1a): mode1res=0x80000000 (0x80000000)
pci_cfgcheck: device 0 [class=060000] [hdr=00] is there (id=71908086)
pcib0: <Intel 82443BX (440 BX) host to PCI bridge> on motherboard
found-> vendor=0x8086, dev=0x7190, revid=0x02
class=06-00-00, hdrtype=0x00, mfdev=0
subordinatebus=0 secondarybus=0
map[10]: type 1, range 32, base e4000000, size 26
found-> vendor=0x8086, dev=0x7191, revid=0x02
class=06-04-00, hdrtype=0x01, mfdev=0
subordinatebus=1 secondarybus=1
found-> vendor=0x8086, dev=0x7110, revid=0x02
class=06-01-00, hdrtype=0x00, mfdev=1
subordinatebus=0 secondarybus=0
found-> vendor=0x8086, dev=0x7111, revid=0x01
class=01-01-80, hdrtype=0x00, mfdev=0
subordinatebus=0 secondarybus=0
map[20]: type 1, range 32, base 0000d800, size 4
found-> vendor=0x8086, dev=0x7112, revid=0x01
class=0c-03-00, hdrtype=0x00, mfdev=0
subordinatebus=0 secondarybus=0
intpin=d, irq=9
map[20]: type 1, range 32, base 0000d400, size 5
found-> vendor=0x8086, dev=0x7113, revid=0x02
class=06-80-00, hdrtype=0x00, mfdev=0
subordinatebus=0 secondarybus=0
map[90]: type 1, range 32, base 0000e800, size 4
found-> vendor=0x8086, dev=0x1229, revid=0x05
class=02-00-00, hdrtype=0x00, mfdev=0
subordinatebus=0 secondarybus=0
intpin=a, irq=9
map[10]: type 1, range 32, base e2000000, size 12
map[14]: type 1, range 32, base 0000d000, size 5
map[18]: type 1, range 32, base e0000000, size 20
found-> vendor=0x1000, dev=0x000f, revid=0x03
class=01-00-00, hdrtype=0x00, mfdev=0
subordinatebus=0 secondarybus=0
intpin=a, irq=5
map[10]: type 1, range 32, base 0000b800, size 8
map[14]: type 1, range 32, base df800000, size 8
map[18]: type 1, range 32, base df000000, size 12
found-> vendor=0x1105, dev=0x8300, revid=0x01
class=04-80-00, hdrtype=0x00, mfdev=0
subordinatebus=0 secondarybus=0
intpin=a, irq=11
map[10]: type 1, range 32, base de800000, size 20
pci0: <PCI bus> on pcib0
pcib1: <Intel 82443BX (440 BX) PCI-PCI (AGP) bridge> at device 1.0 on pci0
found-> vendor=0x102b, dev=0x0521, revid=0x01
class=03-00-00, hdrtype=0x00, mfdev=0
subordinatebus=0 secondarybus=0
intpin=a, irq=11
map[10]: type 1, range 32, base e3000000, size 24
map[14]: type 1, range 32, base e1000000, size 14
map[18]: type 1, range 32, base e0800000, size 23
pci1: <PCI bus> on pcib1
pci1: <Matrox MGA G200 AGP graphics accelerator> (vendor=0x102b, dev=0x0521) at 0.0 irq 11
isab0: <Intel 82371AB PCI to ISA bridge> at device 4.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel PIIX4 ATA33 controller> port 0xd800-0xd80f at device 4.1 on pci0
ata0: iobase=0x01f0 altiobase=0x03f6 bmaddr=0xd800
ata0: mask=03 status0=50 status1=00
ata0: mask=03 ostat0=50 ostat2=00
ata0-master: ATAPI probe a=00 b=00
ata0-slave: ATAPI probe a=00 b=00
ata0: mask=03 status0=50 status1=00
ata0-master: ATA probe a=01 b=a5
ata0: devices=01
ata0: at 0x1f0 irq 14 on atapci0
ata1: iobase=0x0170 altiobase=0x0376 bmaddr=0xd808
ata1: mask=03 status0=50 status1=7f
ata1: mask=03 ostat0=50 ostat2=7f
ata1-master: ATAPI probe a=14 b=eb
----------> Waits here for about 35 seconds. <---------
ata1: mask=01 status0=00 status1=ff
ata1: devices=04
ata1: at 0x170 irq 15 on atapci0
uhci0: <Intel 82371AB/EB (PIIX4) USB controller> port 0xd400-0xd41f irq 9 at device 4.2 on pci0
usb0: <Intel 82371AB/EB (PIIX4) USB controller> on uhci0
usb0: USB revision 1.0
uhub0: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub0: 2 ports with 2 removable, self powered
chip1: <Intel 82371AB Power management controller> port 0xe800-0xe80f at device 4.3 on pci0
fxp0: <Intel Pro 10/100B/100+ Ethernet> port 0xd000-0xd01f mem 0xe0000000-0xe00fffff,0xe2000000-0xe2000fff irq 9 at device 10.0 on pci0
using shared irq9.
fxp0: Ethernet address 00:a0:c9:c9:f7:87
bpf: fxp0 attached
sym0: <875> port 0xb800-0xb8ff mem 0xdf000000-0xdf000fff,0xdf800000-0xdf8000ff irq 5 at device 11.0 on pci0
sym0: Tekram NVRAM, ID 7, Fast-20, SE, parity checking
sym0: open drain IRQ line driver, using on-chip SRAM
sym0: using LOAD/STORE-based firmware.
pci0: <unknown card> (vendor=0x1105, dev=0x8300) at 12.0 irq 11
ata-: ata0 exists, using next available unit number
ata-: ata1 exists, using next available unit number
Trying Read_Port at 203
CTL0043: start dependant
CTL0043: adding irq mask 0x20
CTL0043: adding dma mask 0x2
CTL0043: adding dma mask 0x8
CTL0043: adding io range 0x220-0x22f, size=0x10, align=0x1
CTL0043: adding io range 0x330-0x331, size=0x2, align=0x1
CTL0043: adding io range 0x388-0x38b, size=0x4, align=0x1
CTL0043: start dependant
CTL0043: adding irq mask 0x6a0
CTL0043: adding dma mask 0xb
CTL0043: adding dma mask 0xb
CTL0043: adding io range 0x220-0x28f, size=0x10, align=0x20
CTL0043: adding io range 0x300-0x331, size=0x2, align=0x30
CTL0043: adding io range 0x388-0x38b, size=0x4, align=0x1
CTL0043: start dependant
CTL0043: adding irq mask 0x6a0
CTL0043: adding dma mask 0xb
CTL0043: adding dma mask 0xb
CTL0043: adding io range 0x220-0x28f, size=0x10, align=0x20
CTL0043: adding io range 0x300-0x331, size=0x2, align=0x30
CTL0043: start dependant
CTL0043: adding irq mask 0x6a0
CTL0043: adding dma mask 0xb
CTL0043: adding dma mask 0xb
CTL0043: adding io range 0x220-0x28f, size=0x10, align=0x20
CTL0043: start dependant
CTL0043: adding irq mask 0x6a0
CTL0043: adding dma mask 0xb
CTL0043: adding io range 0x220-0x28f, size=0x10, align=0x20
CTL0043: adding io range 0x300-0x331, size=0x2, align=0x10
CTL0043: adding io range 0x388-0x397, size=0x4, align=0x4
CTL0043: start dependant
CTL0043: adding irq mask 0x6a0
CTL0043: adding dma mask 0xb
CTL0043: adding io range 0x220-0x28f, size=0x10, align=0x20
CTL0043: end dependant
CTL7005: start dependant
CTL7005: adding io range 0x201-0x201, size=0x1, align=0x1
CTL7005: start dependant
CTL7005: adding io range 0x200-0x20f, size=0x1, align=0x1
CTL7005: end dependant
isa_probe_children: disabling PnP devices
isa_probe_children: probing non-PnP devices
fdc0: <NEC 72065B or clone> at port 0x3f0-0x3f5,0x3f7 irq 6 drq 2 on isa0
fdc0: FIFO enabled, 8 bytes threshold
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
ata2 failed to probe at port 0x1f0 irq 14 on isa0
ata3 failed to probe at port 0x170 irq 15 on isa0
adv0 failed to probe at port 0x330 on isa0
bt0: Failed Status Reg Test - ff
bt_isa_probe: Probe failed at 0x330
bt0: Failed Status Reg Test - ff
bt_isa_probe: Probe failed at 0x334
bt0: Failed Status Reg Test - ff
bt_isa_probe: Probe failed at 0x230
bt0: Failed Status Reg Test - ff
bt_isa_probe: Probe failed at 0x234
bt0: Failed Status Reg Test - ff
bt_isa_probe: Probe failed at 0x130
bt0: Failed Status Reg Test - ff
bt_isa_probe: Probe failed at 0x134
bt0 failed to probe at port 0x134-0x137 on isa0
aha0: status reg test failed ff
aha0: status reg test failed ff
aha0: status reg test failed ff
aha0: status reg test failed ff
aha0: status reg test failed ff
aha0: status reg test failed ff
aha0 failed to probe at port 0x134-0x137 on isa0
aic0 failed to probe at port 0x140-0x15f on isa0
atkbdc0: <Keyboard controller (i8042)> at port 0x60,0x64 on isa0
atkbd0: <AT Keyboard> flags 0x1 irq 1 on atkbdc0
atkbd: the current kbd controller command byte 0047
atkbd: keyboard ID 0x41ab (2)
kbdc: RESET_KBD return code:00fa
kbdc: RESET_KBD status:00aa
kbd0 at atkbd0
kbd0: atkbd0, AT 101/102 (2), config:0x1, flags:0x3d0000
psm0: current command byte:0047
kbdc: TEST_AUX_PORT status:0000
kbdc: RESET_AUX return code:00fa
kbdc: RESET_AUX status:00aa
kbdc: RESET_AUX ID:0000
psm: status 00 02 64
psm: status 90 03 3c
psm: status 90 03 3c
psm: status 90 03 3c
psm: data 08 00 00
psm: status 00 00 3c
psm: status 00 02 64
psm: data 08 00 00
psm: status 00 02 64
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: model Generic PS/2 mouse, device ID 0-00, 3 buttons
psm0: config:00000000, flags:00000000, packet size:3
psm0: syncmask:c0, syncbits:00
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
fb0: vga0, vga, type:VGA (5), flags:0x7007f
fb0: port:0x3c0-0x3df, crtc:0x3d4, mem:0xa0000 0x20000
fb0: init mode:24, bios mode:3, current mode:24
fb0: window:0xc00b8000 size:32k gran:32k, buf:0 size:32k
VGA parameters upon power-up
50 18 10 00 00 00 03 00 02 67 5f 4f 50 82 55 81
bf 1f 00 4f 0d 0e 00 00 07 80 9c 8e 8f 28 1f 96
b9 a3 ff 00 01 02 03 04 05 14 07 38 39 3a 3b 3c
3d 3e 3f 0c 00 0f 08 00 00 00 00 00 10 0e 00 ff
VGA parameters in BIOS for mode 24
50 18 10 00 10 00 03 00 02 67 5f 4f 50 82 55 81
bf 1f 00 4f 0d 0e 00 00 00 00 9c 8e 8f 28 1f 96
b9 a3 ff 00 01 02 03 04 05 14 07 38 39 3a 3b 3c
3d 3e 3f 0c 00 0f 08 00 00 00 00 00 10 0e 00 ff
EGA/VGA parameters to be used for mode 24
50 18 10 00 10 00 03 00 02 67 5f 4f 50 82 55 81
bf 1f 00 4f 0d 0e 00 00 00 00 9c 8e 8f 28 1f 96
b9 a3 ff 00 01 02 03 04 05 14 07 38 39 3a 3b 3c
3d 3e 3f 0c 00 0f 08 00 00 00 00 00 10 0e 00 ff
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
sc0: fb0, kbd0, terminal emulator: sc (syscons terminal)
pcic0 failed to probe at port 0x3e0 iomem 0xd0000 on isa0
pcic1: not probed (disabled)
sio0: irq maps: 0x441 0x451 0x441 0x441
sio0 at port 0x3f8-0x3ff irq 4 flags 0x10 on isa0
sio0: type 16550A
sio1: irq maps: 0x441 0x449 0x441 0x441
sio1 at port 0x2f8-0x2ff irq 3 on isa0
sio1: type 16550A
sio2: not probed (disabled)
sio3: not probed (disabled)
ppc0: parallel port found at 0x378
ppc0: using extended I/O port range
ppc0: ECP SPP ECP+EPP SPP
ppc0: <Parallel port> at port 0x378-0x37f irq 7 on isa0
ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode
ppc0: FIFO with 16/16/9 bytes threshold
plip0: <PLIP network interface> on ppbus0
bpf: lp0 attached
lpt0: <Printer> on ppbus0
lpt0: Interrupt-driven port
ppi0: <Parallel I/O> on ppbus0
ed0 failed to probe at port 0x280-0x29f iomem 0xd8000 irq 10 on isa0
fe0 failed to probe at port 0x300-0x31f on isa0
ie0 failed to probe at port 0x300 iomem 0xd0000 irq 10 on isa0
lnc0 failed to probe at port 0x280 irq 10 drq 0 on isa0
cs0 failed to probe at port 0x300 on isa0
sn0 failed to probe at port 0x300 irq 10 on isa0
isa_probe_children: probing PnP devices
adv1: Invalid baseport of 0x220 specified. Nearest valid baseport is 0x230. Failing probe.
unknown: <Audio> failed to probe at port 0x220-0x22f,0x300-0x301,0x388-0x38b irq 10 drq 0,1 on isa0
adv1: Invalid baseport of 0x201 specified. Nearest valid baseport is 0x210. Failing probe.
unknown: <Game> failed to probe at port 0x201-0x210 on isa0
BIOS Geometries:
0:03fffe3f 0..1023=1024 cylinders, 0..254=255 heads, 1..63=63 sectors
0 accounted for
Device configuration finished.
bpf: faith0 attached
bpf: gif0 attached
bpf: gif1 attached
bpf: gif2 attached
bpf: gif3 attached
bpf: lo0 attached
bpf: ppp0 attached
new masks: bio 68c240, tty 63109a, net 67129a
bpf: sl0 attached
ata0-master: success setting UDMA2 on PIIX4 chip
Creating DISK ad0
Creating DISK wd0
ad0: <IBM-DTTA-351010/T56OA73A> ATA-4 disk at ata0-master
ad0: 9671MB (19807200 sectors), 19650 cyls, 16 heads, 63 S/T, 512 B/S
ad0: 16 secs/int, 1 depth queue, UDMA33
ad0: piomode=4 dmamode=2 udmamode=2 cblid=0
ad0: 9671MB <IBM-DTTA-351010> [19650/16/63] at ata0-master UDMA33
ata1-master: piomode=4 dmamode=2 udmamode=-1 dmaflag=1
ata1-master: success setting PIO4 on generic chip
acd0: <PHILIPS PCA424D DVD-ROM/A011> DVD-ROM drive at ata1 as master
acd0: read 1722KB/s (4134KB/s), 512KB buffer, PIO4
acd0: Reads: CD-R, CD-RW, CD-DA stream, DVD-ROM, DVD-R, packet
acd0: Audio: play, 256 volume levels
acd0: Mechanism: ejectable tray
acd0: Medium: CD-ROM 120mm data/audio disc loaded, unlocked
Waiting 15 seconds for SCSI devices to settle
(noperiph:sym0:0:-1:-1): SCSI BUS reset delivered.
Creating DISK da0
pass0 at sym0 bus 0 target 6 lun 0
pass0: <IBM DNES-309170W SA30> Fixed Direct Access SCSI-3 device
pass0: Serial Number AJL5E590
pass0: 40.000MB/s transfers (20.000MHz, offset 16, 16bit), Tagged Queueing Enabled
Mounting root from ufs:/dev/ad0s3a
ad0s1: type 0x6, start 63, end = 1028159, size 1028097 : OK
ad0s2: type 0x5, start 1028160, end = 5237189, size 4209030 : OK
ad0s3: type 0xa5, start 5237190, end = 13623119, size 8385930 : OK
ad0s4: type 0xa5, start 13623120, end = 19792079, size 6168960 : OK
ad0s5: type 0xb, start 1028223, end = 5237189, size 4208967 : OK
da0 at sym0 bus 0 target 6 lun 0
da0: <IBM DNES-309170W SA30> Fixed Direct Access SCSI-3 device
da0: Serial Number AJL5E590
da0: 40.000MB/s transfers (20.000MHz, offset 16, 16bit), Tagged Queueing Enabled
da0: 8748MB (17916240 512 byte sectors: 255H 63S/T 1115C)
start_init: trying /sbin/init
da0s1: type 0xa5, start 63, end = 8385929, size 8385867 : OK
da0s2: type 0xf, start 8385930, end = 12594959, size 4209030 : OK
da0<extended>: type 0x5, start 8386560, end = 12593151, size 4206592 : OK
da0s5: type 0x7, start 8386592, end = 12593151, size 4206560
da0s5: C/H/S start 1023/1/1 (16434558) != start 8386592: invalid
da0s5: C/H/S end 1023/63/32 (16438495) != end 12593151: invalid
--
Mikael Hybsch Email: mhybsch@rsasecurity.com
RSA Security AB Phone: +46-8-7250900
Box 10704 Fax: +46-8-6494970
S-121 29 STOCKHOLM, SWEDEN
To Unsubscribe: send mail to majordomo@FreeBSD.org
with "unsubscribe freebsd-hackers" in the body of the message
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?Pine.GSO.4.30.0011241006130.15577-100000>