From owner-freebsd-stable@FreeBSD.ORG Tue Apr 15 12:59:52 2008 Return-Path: Delivered-To: freebsd-stable@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id 974111065673 for ; Tue, 15 Apr 2008 12:59:52 +0000 (UTC) (envelope-from ler@lerctr.org) Received: from thebighonker.lerctr.org (thebighonker.lerctr.org [192.147.25.65]) by mx1.freebsd.org (Postfix) with ESMTP id 7456E8FC1F for ; Tue, 15 Apr 2008 12:59:52 +0000 (UTC) (envelope-from ler@lerctr.org) Received: from 64.3.1.253.ptr.us.xo.net ([64.3.1.253]:26297 helo=LROSENMAN) by thebighonker.lerctr.org with esmtpa (Exim 4.69 (FreeBSD)) (envelope-from ) id 1JlklK-0006ht-I7 for freebsd-stable@freebsd.org; Tue, 15 Apr 2008 07:59:52 -0500 From: "Larry Rosenman" To: Date: Tue, 15 Apr 2008 07:59:40 -0500 Message-ID: <00df01c89ef8$9733c6d0$c59b5470$@org> MIME-Version: 1.0 Content-Type: text/plain; charset="US-ASCII" Content-Transfer-Encoding: 7bit X-Mailer: Microsoft Office Outlook 12.0 Thread-Index: Acie+JELOFhPEooLSduBKTjr9AQvCw== Content-Language: en-us X-Spam-Score: -2.4 (--) X-LERCTR-Spam-Score: -2.4 (--) X-Spam-Report: SpamScore (-2.4/5.0) ALL_TRUSTED=-1.8, BAYES_00=-2.599, TVD_RCVD_IP=1.931, TW_UH=0.077 X-LERCTR-Spam-Report: SpamScore (-2.4/5.0) ALL_TRUSTED=-1.8, BAYES_00=-2.599, TVD_RCVD_IP=1.931, TW_UH=0.077 DomainKey-Status: no signature Subject: calcru: time went backwards X-BeenThere: freebsd-stable@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: Production branch of FreeBSD source code List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Tue, 15 Apr 2008 12:59:52 -0000 I thought this was gone, but on a kernel from Saturday I'm seeing a bunch of these: calcru: runtime went backwards from 65109085931 usec to 61451418084 usec for pid 1384 (FahCore_78.exe) calcru: runtime went backwards from 65061446064 usec to 61427333429 usec for pid 1400 (FahCore_a0.exe) calcru: runtime went backwards from 65098513979 usec to 61461484768 usec for pid 1397 (FahCore_a0.exe) calcru: runtime went backwards from 7583892 usec to 7395604 usec for pid 1377 (FahCore_a0.exe) calcru: runtime went backwards from 7715084 usec to 7585681 usec for pid 1375 (FahCore_a0.exe) calcru: runtime went backwards from 1259 usec to 1189 usec for pid 1372 (FAH504-3-Linux.exe) calcru: runtime went backwards from 1295 usec to 1222 usec for pid 1369 (FAH504-2-Linux.exe) calcru: runtime went backwards from 5679294 usec to 5617155 usec for pid 1367 (FahCore_78.exe) calcru: runtime went backwards from 1846 usec to 1742 usec for pid 1364 (FAH504-Linux.exe) calcru: runtime went backwards from 3564 usec to 3363 usec for pid 1362 (getty) calcru: runtime went backwards from 2834 usec to 2674 usec for pid 1361 (getty) calcru: runtime went backwards from 2453 usec to 2315 usec for pid 1360 (getty) calcru: runtime went backwards from 4438 usec to 4188 usec for pid 1359 (getty) calcru: runtime went backwards from 3337 usec to 3149 usec for pid 1358 (getty) calcru: runtime went backwards from 3351 usec to 3163 usec for pid 1357 (getty) calcru: runtime went backwards from 3590 usec to 3387 usec for pid 1356 (getty) calcru: runtime went backwards from 5498 usec to 5188 usec for pid 1334 (inetd) calcru: runtime went backwards from 156 usec to 147 usec for pid 1307 (moused) calcru: runtime went backwards from 4315 usec to 4072 usec for pid 1292 (p4web) calcru: runtime went backwards from 1019 usec to 961 usec for pid 1236 (bacula-sd) calcru: runtime went backwards from 6084684 usec to 5742177 usec for pid 1180 (FAH504-Linux.exe) calcru: runtime went backwards from 12073757 usec to 11394126 usec for pid 1168 (FAH504-2-Linux.exe) calcru: runtime went backwards from 12053145 usec to 11374675 usec for pid 1156 (FAH504-3-Linux.exe) calcru: runtime went backwards from 28433 usec to 26833 usec for pid 822 (nfsd) calcru: runtime went backwards from 1880 usec to 1774 usec for pid 820 (mountd) calcru: runtime went backwards from 328751 usec to 310246 usec for pid 759 (java) calcru: runtime went backwards from 872 usec to 823 usec for pid 659 (devd) calcru: runtime went backwards from 14250 usec to 13448 usec for pid 659 (devd) calcru: runtime went backwards from 332 usec to 314 usec for pid 352 (adjkerntz) calcru: runtime went backwards from 1239633054 usec to 1183677040 usec for pid 190 (spa_zio_intr_1) calcru: runtime went backwards from 1234748390 usec to 1178920371 usec for pid 189 (spa_zio_intr_1) calcru: runtime went backwards from 1235958582 usec to 1180229129 usec for pid 188 (spa_zio_intr_1) calcru: runtime went backwards from 1235221753 usec to 1179466662 usec for pid 187 (spa_zio_intr_1) calcru: runtime went backwards from 10474 usec to 10311 usec for pid 44 (irq1: atkbd0) calcru: runtime went backwards from 280 usec to 265 usec for pid 42 (irq14: ata0) calcru: runtime went backwards from 284 usec to 268 usec for pid 33 (irq17: uhci0 ehci0) calcru: runtime went backwards from 1536 usec to 1450 usec for pid 29 (swi2: cambio) calcru: runtime went backwards from 6263 usec to 6175 usec for pid 26 (swi6: Giant taskq) calcru: runtime went backwards from 11857 usec to 11189 usec for pid 24 (swi5: +) calcru: runtime went backwards from 627055449 usec to 592838089 usec for pid 1 (init) calcru: runtime went backwards from 7763 usec to 7326 usec for pid 0 (swapper) How can I help debug? This is on a amd64 kernel, with Dual Xeon 5120's. -- Larry Rosenman http://www.lerctr.org/~ler Phone: +1 512-248-2683 E-Mail: ler@lerctr.org US Mail: 430 Valona Loop, Round Rock, TX 78681-3893