From unknown Thu Sep 11 11:55:12 2025 Content-Disposition: inline Content-Transfer-Encoding: quoted-printable MIME-Version: 1.0 X-Mailer: MIME-tools 5.509 (Entity 5.509) Content-Type: text/plain; charset=utf-8 From: bug#14987 <14987@debbugs.gnu.org> To: bug#14987 <14987@debbugs.gnu.org> Subject: Status: Where is the time spent? Reply-To: bug#14987 <14987@debbugs.gnu.org> Date: Thu, 11 Sep 2025 18:55:12 +0000 retitle 14987 Where is the time spent? reassign 14987 emacs submitter 14987 "Sebastien Vauban" severity 14987 normal tag 14987 notabug thanks From debbugs-submit-bounces@debbugs.gnu.org Tue Jul 30 11:06:05 2013 Received: (at submit) by debbugs.gnu.org; 30 Jul 2013 15:06:05 +0000 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@debbugs.gnu.org; Tue, 30 Jul 2013 11:06:05 -0400 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@debbugs.gnu.org; Tue, 30 Jul 2013 11:06:03 -0400 Received: from Debian-exim by eggs.gnu.org with spam-scanned (Exim 4.71) (envelope-from ) id 1V4BUj-0007ui-Sz for submit@debbugs.gnu.org; Tue, 30 Jul 2013 11:05:58 -0400 X-Spam-Checker-Version: SpamAssassin 3.3.2 (2011-06-06) on eggs.gnu.org X-Spam-Level: X-Spam-Status: No, score=-0.0 required=5.0 tests=BAYES_20 autolearn=disabled version=3.3.2 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@debbugs.gnu.org; Tue, 30 Jul 2013 11:05:49 -0400 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@gnu.org; Tue, 30 Jul 2013 11:05:49 -0400 Received: from Debian-exim by eggs.gnu.org with spam-scanned (Exim 4.71) (envelope-from ) id 1V4BUW-0007r4-Gj for bug-gnu-emacs@gnu.org; Tue, 30 Jul 2013 11:05:43 -0400 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@gnu.org; Tue, 30 Jul 2013 11:05:36 -0400 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) From: "Sebastien Vauban" To: bug-gnu-emacs@gnu.org Subject: Where is the time spent? Organization: Sebastien Vauban X-Url: Under construction... X-Archive: encrypt Date: Tue, 30 Jul 2013 17:05:20 +0200 Message-ID: <86bo5kt7f3.fsf@somewhere.org> User-Agent: Gnus/5.130006 (Ma Gnus v0.6) Emacs/24.3 (windows-nt) MIME-Version: 1.0 Content-Type: text/plain 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-Received-From: 2001:4830:134:3::11 X-Spam-Score: -2.7 (--) X-Debbugs-Envelope-To: submit X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.15 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -2.7 (--) 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 From debbugs-submit-bounces@debbugs.gnu.org Tue Jul 30 11:38:49 2013 Received: (at 14987) by debbugs.gnu.org; 30 Jul 2013 15:38:49 +0000 Received: from localhost ([127.0.0.1]:58610 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.80) (envelope-from ) id 1V4C0f-00089M-3q for submit@debbugs.gnu.org; Tue, 30 Jul 2013 11:38:49 -0400 Received: from ironport2-out.teksavvy.com ([206.248.154.182]:60078) by debbugs.gnu.org with esmtp (Exim 4.80) (envelope-from ) id 1V4C0c-00088w-Hv for 14987@debbugs.gnu.org; Tue, 30 Jul 2013 11:38:46 -0400 X-IronPort-Anti-Spam-Filtered: true X-IronPort-Anti-Spam-Result: Av8EABK/CFFFxJpK/2dsb2JhbABEuzWDWRdzgh4BAQQBViMFCwsOJhIUGA0kiB4GsR+QDpEKA6R6gV6DEw X-IPAS-Result: Av8EABK/CFFFxJpK/2dsb2JhbABEuzWDWRdzgh4BAQQBViMFCwsOJhIUGA0kiB4GsR+QDpEKA6R6gV6DEw X-IronPort-AV: E=Sophos;i="4.84,565,1355115600"; d="scan'208";a="20168148" Received: from 69-196-154-74.dsl.teksavvy.com (HELO pastel.home) ([69.196.154.74]) by ironport2-out.teksavvy.com with ESMTP/TLS/ADH-AES256-SHA; 30 Jul 2013 11:38:34 -0400 Received: by pastel.home (Postfix, from userid 20848) id 6E80A62DAF; Tue, 30 Jul 2013 11:38:40 -0400 (EDT) From: Stefan Monnier To: "Sebastien Vauban" Subject: Re: bug#14987: Where is the time spent? Message-ID: References: <86bo5kt7f3.fsf@somewhere.org> Date: Tue, 30 Jul 2013 11:38:40 -0400 In-Reply-To: <86bo5kt7f3.fsf@somewhere.org> (Sebastien Vauban's message of "Tue, 30 Jul 2013 17:05:20 +0200") User-Agent: Gnus/5.13 (Gnus v5.13) Emacs/24.3.50 (gnu/linux) MIME-Version: 1.0 Content-Type: text/plain X-Spam-Score: 0.3 (/) X-Debbugs-Envelope-To: 14987 Cc: 14987@debbugs.gnu.org X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.15 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: 0.3 (/) > Something really escapes me here. em/emacs-load-time-start is the time at the very beginning of reading your .emacs. [2013-07-29 10:08:14.866] is the time at which `message' is called for the first time. Between the two, (char-displayable-p ?\u25B7) was called. Stefan From debbugs-submit-bounces@debbugs.gnu.org Tue Jul 30 11:46:15 2013 Received: (at control) by debbugs.gnu.org; 30 Jul 2013 15:46:15 +0000 Received: from localhost ([127.0.0.1]:58619 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.80) (envelope-from ) id 1V4C7r-0008UO-6H for submit@debbugs.gnu.org; Tue, 30 Jul 2013 11:46:15 -0400 Received: from fencepost.gnu.org ([208.118.235.10]:53984 ident=Debian-exim) by debbugs.gnu.org with esmtp (Exim 4.80) (envelope-from ) id 1V4C7o-0008UF-8d for control@debbugs.gnu.org; Tue, 30 Jul 2013 11:46:12 -0400 Received: from rgm by fencepost.gnu.org with local (Exim 4.71) (envelope-from ) id 1V4C7n-0006Gu-K6 for control@debbugs.gnu.org; Tue, 30 Jul 2013 11:46:11 -0400 Date: Tue, 30 Jul 2013 11:46:11 -0400 Message-Id: Subject: control message for bug 14987 To: X-Mailer: mail (GNU Mailutils 2.1) From: Glenn Morris X-Spam-Score: -6.5 (------) X-Debbugs-Envelope-To: control X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.15 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -6.5 (------) tag 14987 notabug close 14987 From debbugs-submit-bounces@debbugs.gnu.org Tue Jul 30 12:05:38 2013 Received: (at 14987) by debbugs.gnu.org; 30 Jul 2013 16:05:38 +0000 Received: from localhost ([127.0.0.1]:58634 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.80) (envelope-from ) id 1V4CQa-0001tv-SS for submit@debbugs.gnu.org; Tue, 30 Jul 2013 12:05:37 -0400 Received: from plane.gmane.org ([80.91.229.3]:54045) by debbugs.gnu.org with esmtp (Exim 4.80) (envelope-from ) id 1V4CQX-0001th-2H for 14987@debbugs.gnu.org; Tue, 30 Jul 2013 12:05:34 -0400 Received: from public by plane.gmane.org with local (Exim 4.69) (envelope-from ) id 1V4CQT-0000XL-9N for 14987@debbugs.gnu.org; Tue, 30 Jul 2013 18:05:29 +0200 Received: from mxin.ulb.ac.be ([164.15.128.112]) by plane.gmane.org with esmtp (Exim 4.69) (envelope-from ) id 1V4CQC-0000Mv-5J; Tue, 30 Jul 2013 18:05:12 +0200 X-IronPort-Anti-Spam-Filtered: true X-IronPort-Anti-Spam-Result: Ap4EAIfj91GkD4Nx/2dsb2JhbABbwh6BM3SCJQEFJ1IQCAMhJQ8BBEkth2MBEqdYh1gBiTWPfgeECQOXX4YQizyDFjo Received: from geodiff-mac3.ulb.ac.be (HELO geodiff-mac3) ([164.15.131.113]) by smtp.ulb.ac.be with ESMTP; 30 Jul 2013 18:05:09 +0200 From: Nicolas Richard To: "Sebastien Vauban" Subject: Re: bug#14987: Where is the time spent? References: <86bo5kt7f3.fsf@somewhere.org> Date: Tue, 30 Jul 2013 18:05:34 +0200 In-Reply-To: <86bo5kt7f3.fsf@somewhere.org> (Sebastien Vauban's message of "Tue, 30 Jul 2013 17:05:20 +0200") Message-ID: <87a9l4rq29.fsf@yahoo.fr> User-Agent: Gnus/5.13 (Gnus v5.13) Emacs/24.3 (gnu/linux) MIME-Version: 1.0 Content-Type: text/plain X-Spam-Score: -1.5 (-) X-Debbugs-Envelope-To: 14987 Cc: public-14987-ubl+/3LiMTaZdePnXv/OxA@plane.gmane.org X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.15 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -1.5 (-) Hi, "Sebastien Vauban" writes: > With the minimal .emacs file given previously: Based on Stefan's answer, here's the .emacs file I used to do some tests for myself : --8<---------------cut here---------------start------------->8--- (defconst em/emacs-load-time-start (float-time)) (message "%s Beginning" (format-time-string "[%Y-%m-%d %T.%3N] ")) (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.%3N] ") (ad-get-arg 0)))) (message "Advice set") (dotimes (i 10) (setq org-ellipsis (if (char-displayable-p ?\u25B7) ;; white right-pointing triangle " \u25B7" ;; string 'org-ellipsis)) (message "Call nr %s" (1+ i))) (message "Loading Minimal Emacs... Done (in %.3f s)" (- (float-time) em/emacs-load-time-start)) --8<---------------cut here---------------start------------->8--- (I also did some unnecessary modifications.) Now it seems to match perfectly (defadvice takes some time here, too) -- Nico. From debbugs-submit-bounces@debbugs.gnu.org Tue Jul 30 12:24:01 2013 Received: (at 14987) by debbugs.gnu.org; 30 Jul 2013 16:24:02 +0000 Received: from localhost ([127.0.0.1]:58655 helo=debbugs.gnu.org) by debbugs.gnu.org with esmtp (Exim 4.80) (envelope-from ) id 1V4CiP-0002eb-C8 for submit@debbugs.gnu.org; Tue, 30 Jul 2013 12:24:01 -0400 Received: from fencepost.gnu.org ([208.118.235.10]:54909 ident=Debian-exim) by debbugs.gnu.org with esmtp (Exim 4.80) (envelope-from ) id 1V4CiN-0002eT-4C for 14987@debbugs.gnu.org; Tue, 30 Jul 2013 12:23:59 -0400 Received: from rgm by fencepost.gnu.org with local (Exim 4.71) (envelope-from ) id 1V4CiM-00080g-2w; Tue, 30 Jul 2013 12:23:58 -0400 From: Glenn Morris To: Nicolas Richard Subject: Re: bug#14987: Where is the time spent? References: <86bo5kt7f3.fsf@somewhere.org> <87a9l4rq29.fsf@yahoo.fr> X-Spook: CID Chobetsu FIPS140 tempest Cohiba Cocaine IDEA ASLET X-Ran: se2?V.<2~nI,?A6uLCR2((E5K,_TG'1eY (Nicolas Richard's message of "Tue, 30 Jul 2013 18:05:34 +0200") Message-ID: User-Agent: Gnus (www.gnus.org), GNU Emacs (www.gnu.org/software/emacs/) MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii X-Spam-Score: -6.5 (------) X-Debbugs-Envelope-To: 14987 Cc: 14987@debbugs.gnu.org X-BeenThere: debbugs-submit@debbugs.gnu.org X-Mailman-Version: 2.1.15 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: debbugs-submit-bounces@debbugs.gnu.org Sender: "Debbugs-submit" X-Spam-Score: -6.5 (------) Nicolas Richard wrote: > (defconst em/emacs-load-time-start (float-time)) = before-init-time > (message "Loading Minimal Emacs... Done (in %.3f s)" > (- (float-time) em/emacs-load-time-start)) = (emacs-init-time) From unknown Thu Sep 11 11:55:12 2025 Received: (at fakecontrol) by fakecontrolmessage; To: internal_control@debbugs.gnu.org From: Debbugs Internal Request Subject: Internal Control Message-Id: bug archived. Date: Wed, 28 Aug 2013 11:24:05 +0000 User-Agent: Fakemail v42.6.9 # This is a fake control message. # # The action: # bug archived. thanks # This fakemail brought to you by your local debbugs # administrator