From mboxrd@z Thu Jan 1 00:00:00 1970 Path: news.gmane.org!not-for-mail From: "Sebastien Vauban" Newsgroups: gmane.emacs.bugs Subject: bug#14987: Where is the time spent? Date: Tue, 30 Jul 2013 17:05:20 +0200 Organization: Sebastien Vauban Message-ID: <86bo5kt7f3.fsf@somewhere.org> NNTP-Posting-Host: plane.gmane.org Mime-Version: 1.0 Content-Type: text/plain X-Trace: ger.gmane.org 1375196847 519 80.91.229.3 (30 Jul 2013 15:07:27 GMT) X-Complaints-To: usenet@ger.gmane.org NNTP-Posting-Date: Tue, 30 Jul 2013 15:07:27 +0000 (UTC) To: 14987-ubl+/3LiMTaZdePnXv/OxA@public.gmane.org Original-X-From: bug-gnu-emacs-bounces+geb-bug-gnu-emacs=m.gmane.org-mXXj517/zsQ@public.gmane.org Tue Jul 30 17:07:28 2013 Return-path: Envelope-to: geb-bug-gnu-emacs@m.gmane.org Original-Received: from lists.gnu.org ([208.118.235.17]) by plane.gmane.org with esmtp (Exim 4.69) (envelope-from ) id 1V4BWG-0004aY-37 for geb-bug-gnu-emacs@m.gmane.org; Tue, 30 Jul 2013 17:07:24 +0200 Original-Received: from localhost ([::1]:39932 helo=lists.gnu.org) by lists.gnu.org with esmtp (Exim 4.71) (envelope-from ) id 1V4BWF-00054G-Hi for geb-bug-gnu-emacs@m.gmane.org; Tue, 30 Jul 2013 11:07:23 -0400 Original-Received: from eggs.gnu.org ([2001:4830:134:3::10]:35878) by lists.gnu.org with esmtp (Exim 4.71) (envelope-from ) id 1V4BW4-000532-Bv for bug-gnu-emacs-mXXj517/zsQ@public.gmane.org; Tue, 30 Jul 2013 11:07:20 -0400 Original-Received: from Debian-exim by eggs.gnu.org with spam-scanned (Exim 4.71) (envelope-from ) id 1V4BVu-0008C0-PA for bug-gnu-emacs-mXXj517/zsQ@public.gmane.org; Tue, 30 Jul 2013 11:07:12 -0400 Original-Received: from debbugs.gnu.org ([140.186.70.43]:36005) by eggs.gnu.org with esmtp (Exim 4.71) (envelope-from ) id 1V4BVu-0008Bw-M3 for bug-gnu-emacs-mXXj517/zsQ@public.gmane.org; Tue, 30 Jul 2013 11:07:02 -0400 Original-Received: from Debian-debbugs by debbugs.gnu.org with local (Exim 4.80) (envelope-from ) id 1V4BVu-0005lE-2D for bug-gnu-emacs-mXXj517/zsQ@public.gmane.org; Tue, 30 Jul 2013 11:07:02 -0400 X-Loop: help-debbugs-mXXj517/zsQ@public.gmane.org Resent-From: "Sebastien Vauban" Original-Sender: "Debbugs-submit" Resent-CC: bug-gnu-emacs-mXXj517/zsQ@public.gmane.org Resent-Date: Tue, 30 Jul 2013 15:07:01 +0000 Resent-Message-ID: Resent-Sender: help-debbugs-mXXj517/zsQ@public.gmane.org X-GNU-PR-Message: report 14987 X-GNU-PR-Package: emacs X-GNU-PR-Keywords: X-Debbugs-Original-To: bug-gnu-emacs-mXXj517/zsQ@public.gmane.org Original-Received: via spool by submit-ubl+/3LiMTaZdePnXv/OxA@public.gmane.org id=B.137519676522019 (code B ref -1); Tue, 30 Jul 2013 15:07:01 +0000 Original-Received: (at submit) by debbugs.gnu.org; 30 Jul 2013 15:06:05 +0000 Original-Received: from localhost ([127.0.0.1]:58554 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.80) (envelope-from ) id 1V4BUz-0005j4-3z for submit-ubl+/3LiMTaZdePnXv/OxA@public.gmane.org; Tue, 30 Jul 2013 11:06:05 -0400 Original-Received: from eggs.gnu.org ([208.118.235.92]:33734) by debbugs.gnu.org with esmtp (Exim 4.80) (envelope-from ) id 1V4BUx-0005iU-Fh for submit-ubl+/3LiMTaZdePnXv/OxA@public.gmane.org; Tue, 30 Jul 2013 11:06:03 -0400 Original-Received: from Debian-exim by eggs.gnu.org with spam-scanned (Exim 4.71) (envelope-from ) id 1V4BUj-0007ui-Sz for submit-ubl+/3LiMTaZdePnXv/OxA@public.gmane.org; Tue, 30 Jul 2013 11:05:58 -0400 Original-Received: from lists.gnu.org ([2001:4830:134:3::11]:38462) by eggs.gnu.org with esmtp (Exim 4.71) (envelope-from ) id 1V4BUj-0007uZ-PL for submit-ubl+/3LiMTaZdePnXv/OxA@public.gmane.org; Tue, 30 Jul 2013 11:05:49 -0400 Original-Received: from eggs.gnu.org ([2001:4830:134:3::10]:35336) by lists.gnu.org with esmtp (Exim 4.71) (envelope-from ) id 1V4BUd-00040O-N4 for bug-gnu-emacs-mXXj517/zsQ@public.gmane.org; Tue, 30 Jul 2013 11:05:49 -0400 Original-Received: from Debian-exim by eggs.gnu.org with spam-scanned (Exim 4.71) (envelope-from ) id 1V4BUW-0007r4-Gj for bug-gnu-emacs-mXXj517/zsQ@public.gmane.org; Tue, 30 Jul 2013 11:05:43 -0400 Original-Received: from dd5e0353a.access.telenet.be ([213.224.53.58]:18531 helo=mail.missioncriticalit.com) by eggs.gnu.org with esmtp (Exim 4.71) (envelope-from ) id 1V4BUV-0007qx-Vf for bug-gnu-emacs-mXXj517/zsQ@public.gmane.org; Tue, 30 Jul 2013 11:05:36 -0400 Original-Received: from MUNDANEUM. (unknown [10.10.10.51]) by mail.missioncriticalit.com (Postfix) with ESMTPS id EFFC0500929 for ; Tue, 30 Jul 2013 17:05:34 +0200 (CEST) X-Url: Under construction... X-Archive: encrypt User-Agent: Gnus/5.130006 (Ma Gnus v0.6) Emacs/24.3 (windows-nt) X-detected-operating-system: by eggs.gnu.org: GNU/Linux 3.x X-detected-operating-system: by eggs.gnu.org: Error: Malformed IPv6 address (bad octet value). X-BeenThere: debbugs-submit-ubl+/3LiMTaZdePnXv/OxA@public.gmane.org X-Mailman-Version: 2.1.15 Precedence: list X-detected-operating-system: by eggs.gnu.org: GNU/Linux 3.x X-Received-From: 140.186.70.43 X-BeenThere: bug-gnu-emacs-mXXj517/zsQ@public.gmane.org List-Id: "Bug reports for GNU Emacs, the Swiss army knife of text editors" List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: bug-gnu-emacs-bounces+geb-bug-gnu-emacs=m.gmane.org-mXXj517/zsQ@public.gmane.org Original-Sender: bug-gnu-emacs-bounces+geb-bug-gnu-emacs=m.gmane.org-mXXj517/zsQ@public.gmane.org Xref: news.gmane.org gmane.emacs.bugs:76811 Archived-At: Hello, With the minimal .emacs file given previously: --8<---------------cut here---------------start------------->8--- (defconst em/emacs-load-time-start (float-time)) (defadvice message (before leuven-when-was-that activate) "Add timestamps to `message' output." (ad-set-arg 0 (concat (format-time-string "[%Y-%m-%d %T.") (substring (format-time-string "%N") 0 3) (format-time-string "] ") (ad-get-arg 0)))) (dolist (i '(1 2 3 4 5 6 7 8 9 10)) (setq org-ellipsis (if (char-displayable-p ?\u25B7) ;; white right-pointing triangle " \u25B7" ;; string 'org-ellipsis)) (message "Call nr %s" i)) (message "Loading Minimal Emacs... Done (in %.2f s)" (- (float-time) em/emacs-load-time-start)) --8<---------------cut here---------------end--------------->8--- I have timing information I don't understand in the *Messages* buffer: --8<---------------cut here---------------start------------->8--- For information about GNU Emacs and the GNU system, type C-h C-a. [2013-07-29 10:08:14.866] Call nr 1 [2013-07-29 10:08:14.869] Call nr 2 [2013-07-29 10:08:14.870] Call nr 3 [2013-07-29 10:08:14.870] Call nr 4 [2013-07-29 10:08:14.871] Call nr 5 [2013-07-29 10:08:14.872] Call nr 6 [2013-07-29 10:08:14.872] Call nr 7 [2013-07-29 10:08:14.873] Call nr 8 [2013-07-29 10:08:14.873] Call nr 9 [2013-07-29 10:08:14.874] Call nr 10 [2013-07-29 10:08:14.874] Loading Minimal Emacs... Done (in 0.58 s) --8<---------------cut here---------------end--------------->8--- 14.874 (after last call) - 14.866 (after first call) is 0.008 s, not really 0.580 s!??? Something really escapes me here. I can understand that some time is spent AFTER having read my .emacs file. But that's NOT the time I report here. So, where is the time spent? Or do I have a bug in the way I report timing information? Best regards, Seb -- Sebastien Vauban