From owner-freebsd-hackers Fri Nov 24 1:32: 8 2000 Delivered-To: freebsd-hackers@freebsd.org Received: from karon.dynas.se (karon.dynas.se [192.71.43.4]) by hub.freebsd.org (Postfix) with SMTP id 82F3A37B479 for ; Fri, 24 Nov 2000 01:31:57 -0800 (PST) Received: (qmail 2362 invoked from network); 24 Nov 2000 09:31:52 -0000 Received: from spirit.sto.dynas.se (HELO spirit.dynas.se) (172.16.1.10) by karon.sto.dynas.se with SMTP; 24 Nov 2000 09:31:52 -0000 Received: (qmail 18522 invoked from network); 24 Nov 2000 09:32:19 -0000 Received: from localhost (127.0.0.1) by localhost with SMTP; 24 Nov 2000 09:32:19 -0000 Date: Fri, 24 Nov 2000 10:32:19 +0100 (MET) From: Mikael Hybsch X-Sender: To: Subject: 4.2-STABLE takes 35 seconds to probe ata1-master. Message-ID: MIME-Version: 1.0 Content-Type: TEXT/PLAIN; charset=US-ASCII Sender: owner-freebsd-hackers@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.ORG 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 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: 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: 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: on pcib0 pcib1: 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: on pcib1 pci1: (vendor=0x102b, dev=0x0521) at 0.0 irq 11 isab0: at device 4.0 on pci0 isa0: on isab0 atapci0: 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: port 0xd400-0xd41f irq 9 at device 4.2 on pci0 usb0: 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: port 0xe800-0xe80f at device 4.3 on pci0 fxp0: 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: (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: 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: at port 0x60,0x64 on isa0 atkbd0: 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: 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: 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: 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: 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: on ppbus0 bpf: lp0 attached lpt0: on ppbus0 lpt0: Interrupt-driven port ppi0: 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: