Date: Tue, 26 Feb 2002 22:22:45 +0100 (CET) From: Frode Nordahl <frode@nordahl.net> To: Poul-Henning Kamp <phk@critter.freebsd.dk> Cc: freebsd-current@FreeBSD.ORG Subject: Re: calcru: negative time of XXX Message-ID: <20020226221930.A2098-100000@gandalf.xu.nordahl.net> In-Reply-To: <42947.1014740358@critter.freebsd.dk>
next in thread | previous in thread | raw e-mail | index | archive | help
On Tue, 26 Feb 2002, Poul-Henning Kamp wrote:
> please send me /var/run/dmesg.boot from a "boot -v" on a current kernel
> and output from "sysctl kern.timecounter" please ?
$ sysctl kern.timecounter
kern.timecounter.nmicrotime: 3838
kern.timecounter.nnanotime: 3
kern.timecounter.nmicrouptime: 1
kern.timecounter.nnanouptime: 4
kern.timecounter.ngetmicrotime: 13562
kern.timecounter.ngetnanotime: 1
kern.timecounter.ngetmicrouptime: 25811
kern.timecounter.ngetnanouptime: 19
kern.timecounter.hardware: ACPI
$ cat /var/run/dmesg.boot
Copyright (c) 1992-2002 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 5.0-CURRENT #1: Sun Feb 24 22:06:53 CET 2002
root@gandalf.xu.nordahl.net:/usr/obj/usr/src/sys/GENERIC
Preloaded elf kernel "/boot/kernel/kernel" at 0xc0535000.
Preloaded elf module "/boot/kernel/acpi.ko" at 0xc05350b4.
Calibrating clock(s) ... TSC clock: 400916162 Hz, i8254 clock: 1193202 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
Timecounter "TSC" frequency 400911616 Hz
CPU: Pentium II/Pentium II Xeon/Celeron (400.91-MHz 686-class CPU)
Origin = "GenuineIntel" Id = 0x652 Stepping = 2
Features=0x183f9ff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR>
real memory = 134152192 (131008K bytes)
Physical memory chunk(s):
0x00001000 - 0x0009efff, 647168 bytes (158 pages)
0x0055c000 - 0x07fe7fff, 128499712 bytes (31372 pages)
avail memory = 125100032 (122168K bytes)
bios32: Found BIOS32 Service Directory header at 0xc00fad90
bios32: Entry = 0xfb200 (c00fb200) Rev = 0 Len = 1
pcibios: PCI BIOS entry at 0xf0000+0xb230
pnpbios: Found PnP BIOS data at 0xc00fbe90
pnpbios: Entry = f0000:bec0 Rev = 1.0
Other BIOS signatures found:
null: <null device, zero device>
random: <entropy source>
mem: <memory & I/O>
Pentium Pro MTRR support enabled
pci_open(1): mode 1 addr port (0x0cf8) is 0x8000005c
pci_open(1a): mode1res=0x80000000 (0x80000000)
pci_cfgcheck: device 0 [class=060000] [hdr=00] is there (id=71908086)
Using $PIR table, 7 entries at 0xc00fdf00
npx0: <math processor> on motherboard
npx0: INT 16 interface
acpi0: <HP HPCCD HW> on motherboard
acpi0: power button is handled as a fixed feature programming model.
Timecounter "ACPI" frequency 3579545 Hz
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x4008-0x400b on acpi0
acpi_cpu0: <CPU> on acpi0
acpi_button0: <Power Button> on acpi0
acpi_pcib0: <Host-PCI bridge> port 0x5000-0x500f,0x4000-0x4041,0xcf8-0xcff on acpi0
pci0: physical bus=0
map[10]: type 3, range 32, base e0000000, size 26, enabled
found-> vendor=0x8086, dev=0x7190, revid=0x03
bus=0, slot=0, func=0
class=06-00-00, hdrtype=0x00, mfdev=0
found-> vendor=0x8086, dev=0x7191, revid=0x03
bus=0, slot=1, func=0
class=06-04-00, hdrtype=0x01, mfdev=0
map[10]: type 1, range 32, base e8102000, size 12, enabled
map[14]: type 1, range 32, base e8000000, size 20, enabled
found-> vendor=0x1013, dev=0x6003, revid=0x01
bus=0, slot=4, func=0
class=04-01-00, hdrtype=0x00, mfdev=0
intpin=a, irq=9
powerspec 2 supports D0 D1 D2 D3 current D0
found-> vendor=0x8086, dev=0x7110, revid=0x02
bus=0, slot=7, func=0
class=06-01-00, hdrtype=0x00, mfdev=1
map[20]: type 4, range 32, base 0000f000, size 4, enabled
found-> vendor=0x8086, dev=0x7111, revid=0x01
bus=0, slot=7, func=1
class=01-01-80, hdrtype=0x00, mfdev=0
map[20]: type 4, range 32, base 0000e000, size 5, enabled
found-> vendor=0x8086, dev=0x7112, revid=0x01
bus=0, slot=7, func=2
class=0c-03-00, hdrtype=0x00, mfdev=0
intpin=d, irq=3
map[90]: type 4, range 32, base 00005000, size 4, enabled
found-> vendor=0x8086, dev=0x7113, revid=0x02
bus=0, slot=7, func=3
class=06-80-00, hdrtype=0x00, mfdev=0
map[10]: type 1, range 32, base e8100000, size 12, enabled
map[14]: type 4, range 32, base 0000e400, size 8, enabled
found-> vendor=0x0675, dev=0x1702, revid=0x00
bus=0, slot=15, func=0
class=02-80-00, hdrtype=0x00, mfdev=0
intpin=a, irq=5
map[10]: type 4, range 32, base 0000e800, size 7, enabled
map[14]: type 1, range 32, base e8101100, size 7, enabled
found-> vendor=0x10b7, dev=0x9055, revid=0x24
bus=0, slot=16, func=0
class=02-00-00, hdrtype=0x00, mfdev=0
intpin=a, irq=9
powerspec 1 supports D0 D1 D2 D3 current D0
pci0: <PCI bus> on acpi_pcib0
pcib1: <PCI-PCI bridge> at device 1.0 on pci0
pcib1: secondary bus 1
pcib1: subordinate bus 1
pcib1: I/O decode 0xd000-0xdfff
pcib1: memory decode 0xe4000000-0xe5ffffff
pcib1: prefetched decode 0xe6000000-0xe6ffffff
pci1: physical bus=1
map[10]: type 3, range 32, base e6000000, size 24, enabled
map[14]: type 1, range 32, base e4000000, size 14, enabled
map[18]: type 1, range 32, base e5000000, size 23, enabled
found-> vendor=0x102b, dev=0x0521, revid=0x03
bus=1, slot=0, func=0
class=03-00-00, hdrtype=0x00, mfdev=0
intpin=a, irq=11
powerspec 1 supports D0 D3 current D0
pci1: <PCI bus> on pcib1
pci1: <display, VGA> at device 0.0 (no driver attached)
pci0: <multimedia, audio> at device 4.0 (no driver attached)
isab0: <PCI-ISA bridge> at device 7.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel PIIX4 ATA33 controller> port 0xf000-0xf00f at device 7.1 on pci0
ata0: iobase=0x01f0 altiobase=0x03f6 bmaddr=0xf000
ata0: mask=03 ostat0=50 ostat2=00
ata0-master: ATAPI 00 00
ata0-slave: ATAPI 00 00
ata0: mask=03 stat0=50 stat1=00
ata0-master: ATA 01 a5
ata0: devices=01
ata0: at 0x1f0 irq 14 on atapci0
ata1: iobase=0x0170 altiobase=0x0376 bmaddr=0xf008
ata1: mask=03 ostat0=50 ostat2=50
ata1-master: ATAPI 00 00
ata1-slave: ATAPI 14 eb
ata1: mask=03 stat0=50 stat1=00
ata1-master: ATA 01 a5
ata1: devices=09
ata1: at 0x170 irq 15 on atapci0
uhci0: <Intel 82371AB/EB (PIIX4) USB controller> port 0xe000-0xe01f irq 3 at device 7.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
pci0: <bridge, PCI-unknown> at device 7.3 (no driver attached)
pci0: <network> at device 15.0 (no driver attached)
xl0: <3Com 3c905B-TX Fast Etherlink XL> port 0xe800-0xe87f mem 0xe8101100-0xe810117f irq 9 at device 16.0 on pci0
xl0: Ethernet address: 00:50:04:4c:22:77
xl0: media options word: a
xl0: found MII/AUTO
miibus0: <MII bus> on xl0
xlphy0: <3Com internal media interface> on miibus0
xlphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
bpf: xl0 attached
fdc0: <enhanced floppy controller (i82077, NE72065 or clone)> port 0x3f7,0x3f2-0x3f5 irq 6 on acpi0
fdc0: FIFO enabled, 8 bytes threshold
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
sio0: irq maps: 0x1 0x11 0x1 0x1
sio0 port 0x3f8-0x3ff irq 4 on acpi0
sio0: type 16550A
unknown: not probed (disabled)
ppc0: using extended I/O port range
ppc0: EPP SPP
ppc0 port 0x378-0x37f irq 7 on acpi0
ppc0: Generic chipset (EPP/NIBBLE) in COMPATIBLE mode
plip0: <PLIP network interface> on ppbus0
bpf: lp0 attached
lpt0: <Printer> on ppbus0
lpt0: Interrupt-driven port
ppi0: <Parallel I/O> on ppbus0
unknown: not probed (disabled)
psmcpnp0 irq 12 on acpi0
atkbdc0: <Keyboard controller (i8042)> port 0x64,0x60 irq 1 on acpi0
atkbd0: <AT Keyboard> flags 0x1 irq 1 on atkbdc0
atkbd: the current kbd controller command byte 0047
atkbd: keyboard ID 0x41ab (2)
kbd0 at atkbd0
kbd0: atkbd0, AT 101/102 (2), config:0x1, flags:0x3d0000
psm0: current command byte:0047
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: model IntelliMouse, device ID 3-00, 3 buttons
psm0: config:00000000, flags:00000000, packet size:4
psm0: syncmask:08, syncbits:08
unknown: not probed (disabled)
unknown: not probed (disabled)
ata: ata0 already exists; skipping it
ata: ata1 already exists; skipping it
atkbdc: atkbdc0 already exists; skipping it
fdc: fdc0 already exists; skipping it
ppc: ppc0 already exists; skipping it
sio: sio0 already exists; skipping it
Trying Read_Port at 203
Trying Read_Port at 243
Trying Read_Port at 283
Trying Read_Port at 2c3
Trying Read_Port at 303
Trying Read_Port at 343
Trying Read_Port at 383
Trying Read_Port at 3c3
isa0: if_ep: <3Com 3C509-Combo EtherLink III> at port 0x210-0x220 irq 10
ex_isa_identify()
sc: sc0 already exists; skipping it
vga: vga0 already exists; skipping it
isa_probe_children: disabling PnP devices
isa_probe_children: probing non-PnP devices
orm0: <Option ROM> at iomem 0xc0000-0xc7fff on isa0
adv0 failed to probe 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
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
cs0 failed to probe at port 0x300-0x31f on isa0
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
le0 failed to probe at port 0x300 iomem 0xd0000 irq 5 on isa0
lnc0 failed to probe at port 0x280 irq 10 drq 0 on isa0
pcic0 failed to probe at port 0x3e0 iomem 0xd0000 on isa0
pcic1: not probed (disabled)
pmtimer0 on isa0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
sc0: fb0, kbd0, terminal emulator: sc (syscons terminal)
sio1: configured irq 3 not in bitmap of probed irqs 0
sio1: port may not be enabled
sio1: irq maps: 0x1 0x1 0x1 0x1
sio1: probe failed test(s): 0 1 2 4 6 7 9
sio1 failed to probe at port 0x2f8-0x2ff irq 3 on isa0
sio2: not probed (disabled)
sio3: not probed (disabled)
sn0 failed to probe at port 0x300-0x30f irq 10 on isa0
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 0e 0f 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
vt0 failed to probe on isa0
ep0: <3Com 3C509-Combo EtherLink III> at port 0x210-0x21f irq 10 on isa0
ep0: Ethernet address 00:20:af:e8:55:57
bpf: ep0 attached
isa_probe_children: probing PnP devices
BIOS Geometries:
0:03feef3f 0..1022=1023 cylinders, 0..239=240 heads, 1..63=63 sectors
1:0209fe3f 0..521=522 cylinders, 0..254=255 heads, 1..63=63 sectors
0 accounted for
Device configuration finished.
procfs registered
bpf: lo0 attached
bpf: ppp0 attached
acpi_cpu0: set speed to 100.0%
acpi_cpu: CPU throttling enabled, 2 steps from 100% to 50.0%
ad0: success setting UDMA2 on Intel chip
Creating DISK ad0
ad0: <IBM-DTTA-371440/T71OA73A> ATA-4 disk at ata0-master
ad0: 13783MB (28229040 sectors), 28005 C, 16 H, 63 S, 512 B
ad0: 16 secs/int, 1 depth queue, UDMA33
ad0: piomode=4 dmamode=2 udmamode=2 cblid=0
ad2: success setting UDMA2 on Intel chip
Creating DISK ad2
ad2: <Maxtor 90430D2/GAS64812> ATA-4 disk at ata1-master
ad2: 4104MB (8405775 sectors), 8895 C, 15 H, 63 S, 512 B
ad2: 16 secs/int, 1 depth queue, UDMA33
ad2: piomode=4 dmamode=2 udmamode=2 cblid=0
ata1-slave: piomode=4 dmamode=2 udmamode=-1 dmaflag=1
ata1-slave: success setting PIO4 on generic chip
acd0: <CD-ROM CDU601-25/1.0p> CDROM drive at ata1 as slave
acd0: read 1723KB/s (57255KB/s), 128KB buffer, PIO4
acd0: Reads: CD-R, CD-RW, CD-DA stream, packet
acd0: Writes:
acd0: Audio: play, 256 volume levels
acd0: Mechanism: ejectable tray, unlocked
acd0: Medium: CD-ROM 120mm data disc
Mounting root from ufs:/dev/ad0s2a
ad0s1: type 0x7, start 63, end = 8436959, size 8436897 : OK
ad0s2: type 0xa5, start 8436960, end = 28229039, size 19792080 : OK
start_init: trying /sbin/init
ad2s1: type 0xc, start 63, end = 8401994, size 8401932 : OK
Rgds,
Frode Nordahl
> In message <1014737827.12305.7.camel@ws24.ns5.powertech.no>, Frode Nordahl writ
> es:
> >Hey,
> >
> >I've had the microuptime problem some time, and I have somewhat followed
> >the discussion about this on -current.
> >
> >It seems like the patch committed removed the messages, but they are now
> >replaced by messages like:
> >Feb 24 17:28:26 gandalf kernel: calcru: negative time of -680109 usec
> >for pid 92704 (sed)
> >Feb 25 10:25:05 gandalf kernel: calcru: negative time of -487 usec for
> >pid 59222 (rm)
> >Feb 25 19:42:23 gandalf kernel: calcru: negative time of -680904 usec
> >for pid 48076 (sed)
> >Feb 26 00:26:45 gandalf kernel: calcru: negative time of -666072 usec
> >for pid 438 (gmake)
> >
> >
> >Also, I have seen strange things reported by PS. Crond had negative CPU
> >usage time.
> >
> >I am also unable to compile libgtop at the moment, (which makes it
> >impossible to compile the GNOME port w/o changes).
> >
> >proctime.c: In function `calcru':
> >proctime.c:88: aggregate value used where an integer was expected
> >proctime.c:69: warning: unused variable `tv'
> >proctime.c: In function `glibtop_get_proc_time_p':
> >proctime.c:130: warning: unused variable `pstats'
> >proctime.c:128: warning: unused variable `u_addr'
> >proctime.c: At top level:
> >proctime.c:62: warning: `calcru' defined but not used
> >gmake[3]: *** [proctime.lo] Error 1
> >
> ># uname -a
> >FreeBSD gandalf.xu.nordahl.net 5.0-CURRENT FreeBSD 5.0-CURRENT #0: Sun
> >Feb 24 15:58:24 CET 2002
> >root@gandalf.xu.nordahl.net:/usr/obj/usr/src/sys/GENERIC i386
> >
> >Last make world: Feb 24 20:00
> >
> >Cheers!
> >
> >Regds,
> >Frode Nordahl
> >
> >
> >To Unsubscribe: send mail to majordomo@FreeBSD.org
> >with "unsubscribe freebsd-current" in the body of the message
> >
>
> --
> Poul-Henning Kamp | UNIX since Zilog Zeus 3.20
> phk@FreeBSD.ORG | TCP/IP since RFC 956
> FreeBSD committer | BSD since 4.3-tahoe
> Never attribute to malice what can adequately be explained by incompetence.
>
To Unsubscribe: send mail to majordomo@FreeBSD.org
with "unsubscribe freebsd-current" in the body of the message
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20020226221930.A2098-100000>