Subject: kern/26354: Negative process running time
To: None <gnats-bugs@gnats.NetBSD.org>
From: None <p@ppires.org>
List: netbsd-bugs
Date: 07/17/2004 19:10:46
>Number: 26354
>Category: kern
>Synopsis: negative running time for process in ps(1) and top(1) output
>Confidential: no
>Severity: serious
>Priority: medium
>Responsible: kern-bug-people
>State: open
>Class: sw-bug
>Submitter-Id: net
>Arrival-Date: Sat Jul 17 22:45:00 UTC 2004
>Closed-Date:
>Last-Modified:
>Originator: Paulo Alexandre Pinto Pires
>Release: NetBSD 2.0G (as of 2004/07/15)
>Organization:
>Environment:
System: NetBSD mateus.ap.ppires.org 2.0G NetBSD 2.0G (MATEUS-20040715) #0: Thu Jul 15 12:45:51 BRT 2004 pappires@mateus.ap.ppires.org:/usr/src/sys/arch/i386/compile/MATEUS-20040715 i386
Architecture: i386
Machine: i386
>Description:
From a few weeks to now, I have noticed some processes in ps(1)
output to show negative running times (see How-To-Repeat fo sample).
I first noticed this problem in 2.0F as of mid-June. Watching the
lists, I saw some messages regarding changes in isa/clock.c. So
I updated on 2004/07/15, but problem is still there.
Problem can be seen with other program, such as top(1).
My system runs a mostly trimmed GENERIC with ACPI, MPBIOS,
NEW_BUFQ_STRATEGY, PERFCTRS, NO_TSC_TIME enabled. I can send
detailed kernel conf file, if needed. Output from dmesg(8)
follows.
>How-To-Repeat:
pappires@mateus:/ [28]: ps -ax | grep ':-'
10 ?? DK 0:-1.98 [usb2]
96 ?? Is 0:-1.99 nfsd: master
199 ?? S 0:-1.07 kdeinit: kded
351 ?? Ss 0:-1.98 /usr/sbin/ypserv -d
432 ?? Is 0:-1.99 sendmail: Queue runner@00:01:00 for /var/spool/clientmque
471 ?? DK 0:-1.99 [acctwatch]
865 ?? S 0:-1.99 /usr/common/netscape/netscape-bin
946 ?? Is 0:-1.99 /usr/sbin/lpd
1007 ?? I 0:-1.50 xterm
1491 ?? S 0:-1.75 xterm
11390 p2 RV 0:00.00 grep :- (tcsh)
729 E4 Is+ 0:-1.99 /usr/libexec/getty Pc ttyE4
pappires@mateus:/ [29]: demsg
NetBSD 2.0G (MATEUS-20040715) #0: Thu Jul 15 12:45:51 BRT 2004
pappires@mateus.ap.ppires.org:/usr/src/sys/arch/i386/compile/MATEUS-20040715
total memory = 511 MB
avail memory = 497 MB
BIOS32 rev. 0 found at 0xfdaf0
mainbus0 (root)
cpu0 at mainbus0: apid 0 (boot processor)
cpu0: AMD Athlon XP 2400+ (686-class), 2004.65 MHz, id 0x681
cpu0: features c3c3fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR>
cpu0: features c3c3fbff<PGE,MCA,CMOV,PAT,PSE36,MMXX,MMX>
cpu0: features c3c3fbff<FXSR,SSE,3DNOW2,3DNOW>
cpu0: "AMD Athlon(tm) XP 2400+"
cpu0: I-cache 64 KB 64B/line 2-way, D-cache 64 KB 64B/line 2-way
cpu0: L2 cache 256 KB 64B/line 16-way
cpu0: ITLB 16 4 KB entries fully associative, 8 4 MB entries fully associative
cpu0: DTLB 32 4 KB entries fully associative, 8 4 MB entries 4-way
cpu0: calibrating local timer
cpu0: apic clock running at 267 MHz
cpu0: 8 page colors
ioapic0 at mainbus0 apid 2 (I/O APIC)
ioapic0: pa 0xfec00000, version 3, 24 pins
acpi0 at mainbus0
acpi0: using Intel ACPI CA subsystem version 20040211
acpi0: X/RSDT: OemId <AMIINT,VIA_K7 ,00000010>, AslId <MSFT,00000097>
acpi0: SCI interrupting at int 9
acpi0: fixed-feature power button present
ACPI Object Type 'Processor' (0x0c) at acpi0 not configured
acpibut0 at acpi0 (PNP0C0C): ACPI Power Button
PNP0A03 at acpi0 not configured
PNP0C02 at acpi0 not configured
PNP0200 at acpi0 not configured
PNP0100 at acpi0 not configured
PNP0B00 at acpi0 not configured
PNP0800 at acpi0 not configured
npx0 at acpi0 (PNP0C04)
npx0: io 0xf0-0xff irq 13
npx0: using exception 16
pckbc0 at acpi0 (PNP0303): kbd port
pckbc0: io 0x60,0x64 irq 1
fdc0 at acpi0 (PNP0700)
fdc0: io 0x3f2-0x3f5,0x3f7 irq 6 drq 2
com0 at acpi0 (PNP0501-1)
com0: io 0x3f8-0x3ff irq 4
com0: ns16550a, working fifo
com1 at acpi0 (PNP0501-2)
com1: io 0x2f8-0x2ff irq 3
com1: ns16550a, working fifo
lpt0 at acpi0 (PNP0400)
lpt0: io 0x378-0x37f irq 7
ACPI Object Type 'Power' (0x0b) at acpi0 not configured
ACPI Object Type 'Power' (0x0b) at acpi0 not configured
ACPI Object Type 'Power' (0x0b) at acpi0 not configured
ACPI Object Type 'Power' (0x0b) at acpi0 not configured
PNP0C0F at acpi0 not configured
PNP0C0F at acpi0 not configured
PNP0C0F at acpi0 not configured
PNP0C0F at acpi0 not configured
acpibut1 at acpi0 (PNP0C0E): ACPI Sleep Button
pckbd0 at pckbc0 (kbd slot)
pckbc0: using irq 1 for kbd slot
wskbd0 at pckbd0: console keyboard
pci0 at mainbus0 bus 0: configuration mode 1
pci0: i/o space, memory space enabled, rd/line, rd/mult, wr/inv ok
pchb0 at pci0 dev 0 function 0
pchb0: vendor 0x1106 product 0x3189 (rev. 0x80)
agp0 at pchb0: aperture at 0xe0000000, size 0xf900000
ppb0 at pci0 dev 1 function 0: vendor 0x1106 product 0xb198 (rev. 0x00)
pci1 at ppb0 bus 1
pci1: i/o space, memory space enabled
vga0 at pci1 dev 0 function 0: vendor 0x1002 product 0x5159 (rev. 0x00)
wsdisplay0 at vga0 kbdmux 1: console (80x25, vt100 emulation), using wskbd0
wsmux1: connecting to wsdisplay0
wsdisplay0: screen 1-7 added (80x25, vt100 emulation)
eap0 at pci0 dev 7 function 0: vendor 0x1274 product 0x1371 ES1373-8(rev. 0x08)
eap0: interrupting at ioapic0 pin 19 (irq 10)
eap0: ac97: Crystal CS4297A codec; headphone, 20 bit DAC, 18 bit ADC, Crystal Semi 3D
eap0: ac97: ext id 200<AMAP>
audio0 at eap0: full duplex, mmap, independent
midi0 at eap0: AudioPCI MIDI UART
puc0 at pci0 dev 8 function 0: US Robotics (3Com) 3CP5609 PCI 16550 Modem (com)
com2 at puc0 port 0: interrupting at ioapic0 pin 18 (irq 12)
com2: ns16550a, working fifo
rtk0 at pci0 dev 10 function 0: Realtek 8139 10/100BaseTX
rtk0: interrupting at ioapic0 pin 17 (irq 10)
rtk0: Ethernet address 00:e0:7d:fa:2e:8c
ukphy0 at rtk0 phy 7: Generic IEEE 802.3u media interface
ukphy0: OUI 0x000000, model 0x0000, rev. 0
ukphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
bce0 at pci0 dev 11 function 0: Broadcom BCM4401 10/100 Ethernet
bce0: interrupting at ioapic0 pin 18 (irq 12)
ukphy1 at bce0 phy 1: Generic IEEE 802.3u media interface
ukphy1: OUI 0x000818, model 0x0036, rev. 0
ukphy1: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
bce0: Ethernet address 00:0c:76:2a:1a:eb
viaide0 at pci0 dev 15 function 0
viaide0: VIA Technologies VT8237 SATA Controller (rev. 0x80)
viaide0: bus-master DMA support present
viaide0: primary channel wired to native-PCI mode
viaide0: using ioapic0 pin 20 (irq 11) for native-PCI interrupt
atabus0 at viaide0 channel 0
viaide0: secondary channel wired to native-PCI mode
atabus1 at viaide0 channel 1
viaide1 at pci0 dev 15 function 1
viaide1: VIA Technologies VT8237 ATA133 controller
viaide1: bus-master DMA support present
viaide1: primary channel configured to compatibility mode
viaide1: primary channel interrupting at ioapic0 pin 14 (irq 14)
atabus2 at viaide1 channel 0
viaide1: secondary channel configured to compatibility mode
viaide1: secondary channel interrupting at ioapic0 pin 15 (irq 15)
atabus3 at viaide1 channel 1
uhci0 at pci0 dev 16 function 0: vendor 0x1106 product 0x3038 (rev. 0x81)
uhci0: interrupting at ioapic0 pin 21 (irq 11)
usb0 at uhci0: USB revision 1.0
uhub0 at usb0
uhub0: vendor 0x1106 UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub0: 2 ports with 2 removable, self powered
uhci1 at pci0 dev 16 function 1: vendor 0x1106 product 0x3038 (rev. 0x81)
uhci1: interrupting at ioapic0 pin 21 (irq 11)
usb1 at uhci1: USB revision 1.0
uhub1 at usb1
uhub1: vendor 0x1106 UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub1: 2 ports with 2 removable, self powered
uhci2 at pci0 dev 16 function 2: vendor 0x1106 product 0x3038 (rev. 0x81)
uhci2: interrupting at ioapic0 pin 21 (irq 10)
usb2 at uhci2: USB revision 1.0
uhub2 at usb2
uhub2: vendor 0x1106 UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub2: 2 ports with 2 removable, self powered
uhci3 at pci0 dev 16 function 3: vendor 0x1106 product 0x3038 (rev. 0x81)
uhci3: interrupting at ioapic0 pin 21 (irq 10)
usb3 at uhci3: USB revision 1.0
uhub3 at usb3
uhub3: vendor 0x1106 UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub3: 2 ports with 2 removable, self powered
ehci0 at pci0 dev 16 function 4: vendor 0x1106 product 0x3104 (rev. 0x86)
ehci0: interrupting at ioapic0 pin 21 (irq 12)
ehci0: EHCI version 1.0
ehci0: companion controllers, 2 ports each: uhci0 uhci1 uhci2 uhci3
usb4 at ehci0: USB revision 2.0
uhub4 at usb4
uhub4: vendor 0x1106 EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
uhub4: 8 ports with 8 removable, self powered
pcib0 at pci0 dev 17 function 0
pcib0: vendor 0x1106 product 0x3227 (rev. 0x00)
auvia0 at pci0 dev 17 function 5: VIA Technologies VT8235 AC'97 Audio (rev 0x60)
auvia0: interrupting at ioapic0 pin 22 (irq 12)
auvia0: ac97: CMI97 codec; no 3D stereo
auvia0: ac97: ext id 5c6<AC97_22,LDAC,SDAC,CDAC,SPDIF,DRA>
audio1 at auvia0: full duplex, independent
isa0 at pcib0
lm0 at isa0 port 0x290-0x297: W83697HF
pcppi0 at isa0 port 0x61
midi1 at pcppi0: PC speaker
spkr0 at pcppi0
sysbeep0 at pcppi0
ioapic0: enabling
fd0 at fdc0 drive 0: 1.44MB, 80 cyl, 2 head, 18 sec
wd0 at atabus2 drive 0: <ST360021A>
wd0: drive supports 16-sector PIO transfers, LBA addressing
wd0: 57241 MB, 116301 cyl, 16 head, 63 sec, 512 bytes/sect x 117231408 sectors
wd0: 32-bit data port
wd0: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 5 (Ultra/100)
wd1 at atabus2 drive 1: <MAXTOR 6L040J2>
wd1: drive supports 16-sector PIO transfers, LBA addressing
wd1: 38172 MB, 77557 cyl, 16 head, 63 sec, 512 bytes/sect x 78177792 sectors
wd1: 32-bit data port
wd1: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 6 (Ultra/133)
wd0(viaide1:0:0): using PIO mode 4, Ultra-DMA mode 5 (Ultra/100) (using DMA data transfers)
wd1(viaide1:0:1): using PIO mode 4, Ultra-DMA mode 6 (Ultra/133) (using DMA data transfers)
atapibus0 at atabus3: 2 targets
cd0 at atapibus0 drive 0: <CSI-1480, CSI-1480 Ver2.2C, VER-2.2> cdrom removable
cd0: 32-bit data port
cd0: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 2 (Ultra/33)
cd1 at atapibus0 drive 1: <HL-DT-ST RW/DVD GCC-4320B, , 1.00> cdrom removable
cd1: 32-bit data port
cd1: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 2 (Ultra/33)
cd0(viaide1:1:0): using PIO mode 4, Ultra-DMA mode 2 (Ultra/33) (using DMA data transfers)
cd1(viaide1:1:1): using PIO mode 4, Ultra-DMA mode 2 (Ultra/33) (using DMA data transfers)
boot device: wd0
root on wd0a dumps on wd0b
root file system type: ffs
uhidev0 at uhub1 port 2 configuration 1 interface 0
uhidev0: Microsoft Microsoft IntelliMouseM-. Explorer, rev 1.10/1.21, addr 2, iclass 3/1
ums0 at uhidev0: 5 buttons and Z dir.
wsmouse0 at ums0 mux 0
Accounting started
LKM 'linuxrtc': kernel version mismatch - LKM 200060000, kernel 200070000
LKM 'vmmon': kernel version mismatch - LKM 200060000, kernel 200070000
LKM 'vmnet': kernel version mismatch - LKM 200060000, kernel 200070000
set{u,g}id pid 10168 (netstat) was invoked by uid 1001 ppid 924 (netscape-bin) with fd 0 closed
>Fix:
>Release-Note:
>Audit-Trail:
>Unformatted: