bug#30731: Slower startup using March (27.0.50) build in windows

bug#30731: Slower startup using March (27.0.50) build in windows

[Shuguang Sun]

[-- Attachment #1: Type: text/plain, Size: 1858 bytes --]

Hi,

I built emacs 27 on 4th March, however, the startup gets slower than the
build on 4th Feb (which is camparable with the build in
https://alpha.gnu.org/gnu/emacs/pretest/windows/emacs-27/).
I'm sorry I don't know how to repeat it using "-q".

System: Windows 7

The same .emacs file;

Here is the message from 4th Feb build (GNU Emacs 27.0.50 (build 6,
x86_64-w64-mingw32) of 2018-02-04):
>>> start load .emacs: 0.140 sec <<<
>>> before custom-set-variables : 0.359 sec <<<
Loading image-file...done
Loading time...done
Loading completion...done
Loading elec-pair...done
Loading icomplete...done
Loading ido...done
Loading avoid...done
Loading savehist...done
Loading paren...done
>>> custom-set-variables time: 2.248 sec <<<
>>> frame-setting time: 2.263 sec <<<
‘epa-file’ already enabled
epa-file loaded
elpa initialized
>>> elpa time: 4.464 sec <<<

Below is the message from 4th March build.
1. It has a warning at the very begining. If start with -q, there will be
no such warning.
2. Actually there is nothing before " >>> start load .emacs: 2.575 sec <<<
", however, it still takes 2.574 sec.
3. The load time is longer than the 4th Feb build.

Symbol's value as variable is void: Info-default-directory-list [14 times]
>>> start load .emacs: 2.575 sec <<<
>>> before custom-set-variables : 2.903 sec <<<
Loading image-file...done
Loading time...done
Loading completion...done
Loading elec-pair...done
Loading icomplete...done
Loading ido...done
Loading avoid...done
Loading savehist...done
Loading paren...done
>>> custom-set-variables time: 8.333 sec <<<
>>> frame-setting time: 8.333 sec <<<
‘epa-file’ already enabled
epa-file loaded
elpa initialized
>>> elpa time: 9.394 sec <<<

The value of Info-default-directory-list at the very begining is
("%emacs_dir%/share/info/").

[-- Attachment #2: Type: text/html, Size: 2482 bytes --]

bug#30731: Slower startup using March (27.0.50) build in windows

[Noam Postavsky]

Shuguang Sun <shuguang@gmail.com> writes:

> I built emacs 27 on 4th March, however, the startup gets slower than
> the build on 4th Feb (which is camparable with the build in https://
> alpha.gnu.org/gnu/emacs/pretest/windows/emacs-27/).
> I'm sorry I don't know how to repeat it using "-q".
>
> System: Windows 7

> Below is the message from 4th March build.
> 1. It has a warning at the very begining. If start with -q, there
> will be no such warning.
> 2. Actually there is nothing before " >>> start load .emacs: 2.575
> sec <<< ", however, it still takes 2.574 sec.
> 3. The load time is longer than the 4th Feb build.
>
> Symbol's value as variable is void: Info-default-directory-list [14
> times]

Could you try out the patch posted to emacs-devel?

https://lists.gnu.org/archive/html/emacs-devel/2018-03/msg00040.html

bug#30731: Slower startup using March (27.0.50) build in windows

[Shuguang Sun]

[-- Attachment #1: Type: text/plain, Size: 2233 bytes --]

I patched the startup.el and package.el in the installed emacs. They seems
not work. I'll try to patch the git source prior to the complilation later
when I get time.


1. Even I put the (setq package-enable-at-startup nil) in the top of the
.emacs, it takes 3.692 sec before loading others of the init file. I have a
site-start.el file, which is suposed to run before .emacs is loaded. Woud
it impact the the load process? NB. the 3.692 does not count the site-start
in. It is from
(message ">>> start load .emacs: %.3f sec <<<"
                     (float-time (time-subtract after-init-time
before-init-time)))

2. No mather whether I set package-enable-at-startup, it takes about 8 sec
to load the custom-set-variable. However, it takes only 2.5 sec in the Feb
version.

Symbol's value as variable is void: Info-default-directory-list [14 times]
>>> start load .emacs: 3.692 sec <<<
Loading Sun Shuguang’s .emacs file
>>> before custom-set-variables : 4.176 sec <<<
Loading image-file...done
Loading time...done
Loading completion...done
Loading elec-pair...done
Loading icomplete...done
Loading ido...done
Loading avoid...done
Loading savehist...done
Loading paren...done
>>> custom-set-variables time: 12.387 sec <<<


On Fri, Mar 9, 2018 at 9:35 AM, Noam Postavsky <npostavs@gmail.com> wrote:

> Shuguang Sun <shuguang@gmail.com> writes:
>
> > I built emacs 27 on 4th March, however, the startup gets slower than
> > the build on 4th Feb (which is camparable with the build in https://
> > alpha.gnu.org/gnu/emacs/pretest/windows/emacs-27/).
> > I'm sorry I don't know how to repeat it using "-q".
> >
> > System: Windows 7
>
> > Below is the message from 4th March build.
> > 1. It has a warning at the very begining. If start with -q, there
> > will be no such warning.
> > 2. Actually there is nothing before " >>> start load .emacs: 2.575
> > sec <<< ", however, it still takes 2.574 sec.
> > 3. The load time is longer than the 4th Feb build.
> >
> > Symbol's value as variable is void: Info-default-directory-list [14
> > times]
>
> Could you try out the patch posted to emacs-devel?
>
> https://lists.gnu.org/archive/html/emacs-devel/2018-03/msg00040.html
>

[-- Attachment #2: Type: text/html, Size: 3179 bytes --]

bug#30731: Slower startup using March (27.0.50) build in windows

[Shuguang Sun]

[-- Attachment #1: Type: text/plain, Size: 3700 bytes --]

 Hi Noam,

I read the disussions and patches, and I find out the 3.692 sec at the
beigining seems package initialization (according to the patch in Feb 17).
However, I still don't get
1. What is an early init file?
2. Why custom-set-variable  get slower?
3. If i want to disable package initialization prior to any thin in the
.emacs, but after custom-set-variable and some environment variables
setting (e.g. PATH, exec-path), how to do it?

Could you help provide an example using my situation?
1) I use a Windows 7 box, and have HOME env variables, and .emacs.d folder
under it
2) I have a site-start.el for environment variables setting (e.g. PATH,
exec-path)  <= I thinks this it not critical because it has no speed effect
after I delete the site-start.el
3) I have .emacs under HOME, and compile it to .emacs.elc to speed it up
4) I load dired.el and then custom-set-variable, then some frame settings ,
then initialize package using
(setq package-enable-at-startup nil)
(package-initialize)
and then packages customizations (hooks etc)
5) I don't have default.el or init.el

How could I adapt the change in package.el and startup.el? e.g. to disable
package initialization at the very begining but start it at some point of
the initialization of emacs.



On Sun, Mar 11, 2018 at 11:25 AM, Shuguang Sun <shuguang@gmail.com> wrote:

> I patched the startup.el and package.el in the installed emacs. They seems
> not work. I'll try to patch the git source prior to the complilation later
> when I get time.
>
>
> 1. Even I put the (setq package-enable-at-startup nil) in the top of the
> .emacs, it takes 3.692 sec before loading others of the init file. I have a
> site-start.el file, which is suposed to run before .emacs is loaded. Woud
> it impact the the load process? NB. the 3.692 does not count the site-start
> in. It is from
> (message ">>> start load .emacs: %.3f sec <<<"
>                      (float-time (time-subtract after-init-time
> before-init-time)))
>
> 2. No mather whether I set package-enable-at-startup, it takes about 8 sec
> to load the custom-set-variable. However, it takes only 2.5 sec in the Feb
> version.
>
> Symbol's value as variable is void: Info-default-directory-list [14 times]
> >>> start load .emacs: 3.692 sec <<<
> Loading Sun Shuguang’s .emacs file
> >>> before custom-set-variables : 4.176 sec <<<
> Loading image-file...done
> Loading time...done
> Loading completion...done
> Loading elec-pair...done
> Loading icomplete...done
> Loading ido...done
> Loading avoid...done
> Loading savehist...done
> Loading paren...done
> >>> custom-set-variables time: 12.387 sec <<<
>
>
> On Fri, Mar 9, 2018 at 9:35 AM, Noam Postavsky <npostavs@gmail.com> wrote:
>
>> Shuguang Sun <shuguang@gmail.com> writes:
>>
>> > I built emacs 27 on 4th March, however, the startup gets slower than
>> > the build on 4th Feb (which is camparable with the build in https://
>> > alpha.gnu.org/gnu/emacs/pretest/windows/emacs-27/).
>> > I'm sorry I don't know how to repeat it using "-q".
>> >
>> > System: Windows 7
>>
>> > Below is the message from 4th March build.
>> > 1. It has a warning at the very begining. If start with -q, there
>> > will be no such warning.
>> > 2. Actually there is nothing before " >>> start load .emacs: 2.575
>> > sec <<< ", however, it still takes 2.574 sec.
>> > 3. The load time is longer than the 4th Feb build.
>> >
>> > Symbol's value as variable is void: Info-default-directory-list [14
>> > times]
>>
>> Could you try out the patch posted to emacs-devel?
>>
>> https://lists.gnu.org/archive/html/emacs-devel/2018-03/msg00040.html
>>
>
>

[-- Attachment #2: Type: text/html, Size: 5170 bytes --]

bug#30731: Slower startup using March (27.0.50) build in windows

[Noam Postavsky]

Shuguang Sun <shuguang@gmail.com> writes:

> I read the disussions and patches, and I find out the 3.692 sec at
> the beigining seems package initialization (according to the patch in
> Feb 17).
> However, I still don't get
> 1. What is an early init file?

It should be explained in `(emacs) Early Init File' and `(elisp) Init
File'.

> 2. Why custom-set-variable  get slower?

Not sure, we need to see some more detail to find out.  Can you figure
out if it's all variables getting slower, or is there some specific
variable setting that causes the slowdown?

> 3. If i want to disable package initialization prior to any thin in
> the .emacs, but after custom-set-variable and some environment
> variables setting (e.g. PATH, exec-path), how to do it?
>
> Could you help provide an example using my situation?
> 1) I use a Windows 7 box, and have HOME env variables, and .emacs.d
> folder under it
> 2) I have a site-start.el for environment variables setting (e.g.
> PATH, exec-path)  <= I thinks this it not critical because it has no
> speed effect after I delete the site-start.el
> 3) I have .emacs under HOME, and compile it to .emacs.elc to speed it
> up
> 4) I load dired.el and then custom-set-variable, then some frame
> settings , then initialize package using
> (setq package-enable-at-startup nil)
> (package-initialize)

The package-enable-at-startup setting should be moved to
~/.emacs.d/early-init.el.

Possibly also the settings in site-start.el (since the early-init.el
runs before site-start.el, as described in `(elisp) Startup Summary').

bug#30731: Slower startup using March (27.0.50) build in windows

[Shuguang Sun]

[-- Attachment #1: Type: text/plain, Size: 1900 bytes --]

Hi Noam,

Thank you for your patient explaination. I put the (setq
package-enable-at-startup nil) in the early-init.el and the load time of
.emacs is the same to the emacs Feb version.

On Mon, Mar 12, 2018 at 1:39 AM, Noam Postavsky <npostavs@gmail.com> wrote:

> Shuguang Sun <shuguang@gmail.com> writes:
>
> > I read the disussions and patches, and I find out the 3.692 sec at
> > the beigining seems package initialization (according to the patch in
> > Feb 17).
> > However, I still don't get
> > 1. What is an early init file?
>
> It should be explained in `(emacs) Early Init File' and `(elisp) Init
> File'.
>
> > 2. Why custom-set-variable  get slower?
>
> Not sure, we need to see some more detail to find out.  Can you figure
> out if it's all variables getting slower, or is there some specific
> variable setting that causes the slowdown?
>
> > 3. If i want to disable package initialization prior to any thin in
> > the .emacs, but after custom-set-variable and some environment
> > variables setting (e.g. PATH, exec-path), how to do it?
> >
> > Could you help provide an example using my situation?
> > 1) I use a Windows 7 box, and have HOME env variables, and .emacs.d
> > folder under it
> > 2) I have a site-start.el for environment variables setting (e.g.
> > PATH, exec-path)  <= I thinks this it not critical because it has no
> > speed effect after I delete the site-start.el
> > 3) I have .emacs under HOME, and compile it to .emacs.elc to speed it
> > up
> > 4) I load dired.el and then custom-set-variable, then some frame
> > settings , then initialize package using
> > (setq package-enable-at-startup nil)
> > (package-initialize)
>
> The package-enable-at-startup setting should be moved to
> ~/.emacs.d/early-init.el.
>
> Possibly also the settings in site-start.el (since the early-init.el
> runs before site-start.el, as described in `(elisp) Startup Summary').
>
>

[-- Attachment #2: Type: text/html, Size: 2563 bytes --]

bug#30731: Slower startup using March (27.0.50) build in windows

[Noam Postavsky]

Shuguang Sun <shuguang@gmail.com> writes:

> Thank you for your patient explaination. I put the (setq
> package-enable-at-startup nil) in the early-init.el and the load time
> of .emacs is the same to the emacs Feb version.

Oh, interesting.  I guess the slowdown of the custom-set-variables must
have been caused by autoloads getting triggered then?

bug#30731: Slower startup using March (27.0.50) build in windows

[Shuguang Sun]

[-- Attachment #1: Type: text/plain, Size: 889 bytes --]

FYI. If put (package-initialize) before custom-set-variables,
>>> before custom-set-variables : 3.637 sec <<<
Loading image-file...done
Loading time...done
Loading completion...done
Loading elec-pair...done
Loading icomplete...done
Loading ido...done
Loading avoid...done
Loading savehist...done
Loading paren...done
>>> custom-set-variables time: 10.080 sec <<<

Don't know whether autoloads being triggered. It is hard to test one by one.




On Mon, Mar 12, 2018 at 9:52 AM, Noam Postavsky <npostavs@gmail.com> wrote:

> Shuguang Sun <shuguang@gmail.com> writes:
>
> > Thank you for your patient explaination. I put the (setq
> > package-enable-at-startup nil) in the early-init.el and the load time
> > of .emacs is the same to the emacs Feb version.
>
> Oh, interesting.  I guess the slowdown of the custom-set-variables must
> have been caused by autoloads getting triggered then?
>

[-- Attachment #2: Type: text/html, Size: 1440 bytes --]

bug#30731: Slower startup using March (27.0.50) build in windows

[Noam Postavsky]

On Mon, Mar 12, 2018 at 12:08 PM, Shuguang Sun <shuguang@gmail.com> wrote:
> FYI. If put (package-initialize) before custom-set-variables,
>>>> before custom-set-variables : 3.637 sec <<<
> Loading image-file...done
> Loading time...done
> Loading completion...done
> Loading elec-pair...done
> Loading icomplete...done
> Loading ido...done
> Loading avoid...done
> Loading savehist...done
> Loading paren...done
>>>> custom-set-variables time: 10.080 sec <<<
>
> Don't know whether autoloads being triggered. It is hard to test one by one.

Try

...
(require 'trace)
(trace-function 'do-after-load-evaluation)
(custom-set-variables ...)
(untrace-function 'do-after-load-evaluation)

and see if there is a difference in the *trace-output* buffer when you
call package-initialize first.

Maybe also do (mapc #'require '(image-file time completion elec-pair
ido avoid savehist paren)) to get the known loads (which I see listed
in your output above) out of the way.

bug#30731: Slower startup using March (27.0.50) build in windows

[Shuguang Sun]

[-- Attachment #1.1: Type: text/plain, Size: 1670 bytes --]

Hi Noam,

Thanks for the tips. One (package-earlyload) is to put package-initialize
before custom-set-variables, another one (package-lateload) is to put
package-initialize after then custom-set-variables. I attahed the diff of
the trace-log.
The trace-log from package-earlyload has less do-after-load-evaluation
items which are supposed to be loaded during the package-initialize.
However, it still takes longer time for custom-set-variables  during early
package initialization.
The two items, mail-prsvr.elc and mm-util.elc are in earlyload but not in
lateload.



On Tue, Mar 13, 2018 at 1:09 AM, Noam Postavsky <npostavs@gmail.com> wrote:

> On Mon, Mar 12, 2018 at 12:08 PM, Shuguang Sun <shuguang@gmail.com> wrote:
> > FYI. If put (package-initialize) before custom-set-variables,
> >>>> before custom-set-variables : 3.637 sec <<<
> > Loading image-file...done
> > Loading time...done
> > Loading completion...done
> > Loading elec-pair...done
> > Loading icomplete...done
> > Loading ido...done
> > Loading avoid...done
> > Loading savehist...done
> > Loading paren...done
> >>>> custom-set-variables time: 10.080 sec <<<
> >
> > Don't know whether autoloads being triggered. It is hard to test one by
> one.
>
> Try
>
> ...
> (require 'trace)
> (trace-function 'do-after-load-evaluation)
> (custom-set-variables ...)
> (untrace-function 'do-after-load-evaluation)
>
> and see if there is a difference in the *trace-output* buffer when you
> call package-initialize first.
>
> Maybe also do (mapc #'require '(image-file time completion elec-pair
> ido avoid savehist paren)) to get the known loads (which I see listed
> in your output above) out of the way.
>

[-- Attachment #1.2: Type: text/html, Size: 2340 bytes --]

[-- Attachment #2: package-e-l.diff --]
[-- Type: application/octet-stream, Size: 5179 bytes --]

--- c:/Users/username/package-lateload.txt	2018-03-13 17:47:47.440568800 +0800
+++ c:/Users/username/package-earlyload.txt	2018-03-13 17:47:55.920568800 +0800
@@ -20,27 +20,6 @@
 1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/icomplete.elc")
 1 <- do-after-load-evaluation: nil
 ======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/emacs-lisp/cl-loaddefs.el")
-1 <- do-after-load-evaluation: nil
-======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/emacs-lisp/cl-lib.elc")
-1 <- do-after-load-evaluation: nil
-======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/emacs-lisp/cconv.elc")
-1 <- do-after-load-evaluation: nil
-======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/emacs-lisp/bytecomp.elc")
-1 <- do-after-load-evaluation: nil
-======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/emacs-lisp/gv.elc")
-1 <- do-after-load-evaluation: nil
-======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/emacs-lisp/byte-opt.elc")
-1 <- do-after-load-evaluation: nil
-======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/emacs-lisp/seq.elc")
-1 <- do-after-load-evaluation: nil
-======================================================================
 1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/ido.elc")
 1 <- do-after-load-evaluation: nil
 ======================================================================
@@ -53,37 +32,16 @@
 1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/paren.elc")
 1 <- do-after-load-evaluation: nil
 ======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/calendar/time-date.elc")
-1 <- do-after-load-evaluation: nil
-======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/HOME/.emacs.d/history")
-1 <- do-after-load-evaluation: nil
-======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/emacs-lisp/map.elc")
-1 <- do-after-load-evaluation: nil
-======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/json.elc")
-1 <- do-after-load-evaluation: nil
-======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/password-cache.elc")
+1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/mail/mail-prsvr.elc")
 1 <- do-after-load-evaluation: nil
 ======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/emacs-lisp/eieio-loaddefs.el")
+1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/gnus/mm-util.elc")
 1 <- do-after-load-evaluation: nil
 ======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/emacs-lisp/cl-macs.elc")
-1 <- do-after-load-evaluation: nil
-======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/emacs-lisp/eieio-core.elc")
-1 <- do-after-load-evaluation: nil
-======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/emacs-lisp/eieio.elc")
-1 <- do-after-load-evaluation: nil
-======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/emacs-lisp/cl-seq.elc")
+1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/calendar/time-date.elc")
 1 <- do-after-load-evaluation: nil
 ======================================================================
-1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/auth-source.elc")
+1 -> (do-after-load-evaluation "c:/Users/username/HOME/.emacs.d/history")
 1 <- do-after-load-evaluation: nil
 ======================================================================
 1 -> (do-after-load-evaluation "c:/Users/username/emacs/share/emacs/27.0.50/lisp/emacs-lisp/advice.elc")

bug#30731: Slower startup using March (27.0.50) build in windows

[Noam Postavsky]

Shuguang Sun <shuguang@gmail.com> writes:

> The trace-log from package-earlyload has less
> do-after-load-evaluation items which are supposed to be loaded during
> the package-initialize. However, it still takes longer time for
> custom-set-variables  during early package initialization.
> The two items, mail-prsvr.elc and mm-util.elc are in earlyload but
> not in lateload.

Hmm, somehow that was not so informative as I'd have hoped.  Maybe
measuring each setting individually would give more info, try this:

(require 'pcase)

(defmacro split-custom-set-variables (cset-form)
  (pcase cset-form
    (`(custom-set-variables . ,entries)
     `(progn
        ,@(mapcar
           (pcase-lambda (`(quote (,sym . ,args)))
             `(let ((t0 (current-time))
                    (tf nil))
                (custom-set-variables (quote (,sym . ,args)))
                (setq tf (current-time))
                (message "setting `%s': %.3fs" ',sym
                         (float-time (time-subtract tf t0)))))
           entries)))))

(split-custom-set-variables
 (custom-set-variables
  ...))

bug#30731: Slower startup using March (27.0.50) build in windows

[Shuguang Sun]

[-- Attachment #1.1: Type: text/plain, Size: 1364 bytes --]

The diff files compares the loading time given package initialized early or
late. And I attached a list of packages I installed.

On Wed, Mar 14, 2018 at 9:38 AM, Noam Postavsky <npostavs@gmail.com> wrote:

> Shuguang Sun <shuguang@gmail.com> writes:
>
> > The trace-log from package-earlyload has less
> > do-after-load-evaluation items which are supposed to be loaded during
> > the package-initialize. However, it still takes longer time for
> > custom-set-variables  during early package initialization.
> > The two items, mail-prsvr.elc and mm-util.elc are in earlyload but
> > not in lateload.
>
> Hmm, somehow that was not so informative as I'd have hoped.  Maybe
> measuring each setting individually would give more info, try this:
>
> (require 'pcase)
>
> (defmacro split-custom-set-variables (cset-form)
>   (pcase cset-form
>     (`(custom-set-variables . ,entries)
>      `(progn
>         ,@(mapcar
>            (pcase-lambda (`(quote (,sym . ,args)))
>              `(let ((t0 (current-time))
>                     (tf nil))
>                 (custom-set-variables (quote (,sym . ,args)))
>                 (setq tf (current-time))
>                 (message "setting `%s': %.3fs" ',sym
>                          (float-time (time-subtract tf t0)))))
>            entries)))))
>
> (split-custom-set-variables
>  (custom-set-variables
>   ...))
>
>

[-- Attachment #1.2: Type: text/html, Size: 1953 bytes --]

[-- Attachment #2: pkg-list.txt --]
[-- Type: text/plain, Size: 7658 bytes --]

  ace-window         20180123.1111 installed             Quickly switch windows.
  ag                 20180225.240  installed             A front-end for ag ('the silver searcher'), the C ack replacement.
  anaconda-mode      20180209.1147 installed             Code navigation, documentation lookup and completion for Python
  annotate           20171110.2336 installed             annotate files without changing them
  auctex             12.1.0        installed             Integrated environment for *TeX*
  auto-complete      20170124.1845 installed             Auto Completion for GNU Emacs
  avy                20171230.220  installed             Jump to arbitrary positions in visible text and select text quickly.
  bm                 20170815.1609 installed             Visible bookmarks in buffer.
  boxquote           20170802.417  installed             Quote text with a semi-box.
  browse-kill-ring   20171219.1108 installed             interactively insert items from kill-ring
  cal-china-x        20180211.1101 installed             Chinese localization, lunar/horoscope/zodiac info and more...
  company-lsp        20180216.2325 installed             Company completion backend for lsp-mode.
  dash               20180310.1317 installed             A modern list library for Emacs
  diff-hl            20180201.355  installed             Highlight uncommitted changes using VC
  edit-indirect      20170928.430  installed             Edit regions in separate buffers
  edit-server        20180120.752  installed             server that responds to edit requests from Chrome
  elpy               20180302.2001 installed             Emacs Python Development Environment
  emms               20180217.1219 installed             The Emacs Multimedia System
  ess                20180309.1235 installed             Emacs Speaks Statistics
  ess-smart-under... 20170222.1715 installed             Ess Smart Underscore
  f                  20180106.122  installed             Modern API for working with files and directories
  find-file-in-pr... 20180224.2103 installed             Find file/directory and review Diff/Patch/Commit efficiently everywhere
  flycheck           20180311.1014 installed             On-the-fly syntax checking
  forecast           20180106.920  installed             Weather forecasts
  fuzzy              20150729.2037 installed             Fuzzy Matching
  header2            20170223.729  installed             Support for creation and update of file headers.
  highlight-thing    20170919.704  installed             Minimalistic minor mode to highlight current thing under point.
  hydra              20180226.1116 installed             Make bindings that stick around.
  ivy                20180305.1340 installed             Incremental Vertical completYon
  jedi               20160425.2156 installed             a Python auto-completion for Emacs
  jedi-direx         20140310.236  installed             Tree style source code viewer for Python buffer
  langtool           20170917.2154 installed             Grammar check utility using LanguageTool
  lsp-python         20171021.254  installed             Python support for lsp-mode
  lsp-ui             20180310.453  installed             UI modules for lsp-mode
  markdown-mode      20180124.138  installed             Major mode for Markdown-formatted text
  matlab-mode        20180125.1010 installed             Major mode for MATLAB(R) dot-m files
  multiple-cursors   20170908.1452 installed             Multiple cursors for Emacs.
  neotree            20170522.758  installed             A tree plugin like NerdTree for Vim
  pandoc-mode        20180122.108  installed             Minor mode for interacting with Pandoc
  paredit            20171126.1805 installed             minor mode for editing parentheses
  quickrun           20170222.1715 installed             Run commands quickly
  rainbow-mode       0.13          installed             Colorize color names in buffers
  ranger             20170817.857  installed             Make dired more like ranger
  rg                 20180218.1129 installed             A search tool based on ripgrep.
  s                  20180227.51   installed             The long lost Emacs string manipulation library.
  smartparens        20180308.1042 installed             Automatic insertion, wrapping and paredit-like navigation with user defined pairs.
  stan-mode          20180110.1441 installed             Major mode for editing Stan files
  swiper             20180306.953  installed             Isearch with an overview. Oh, man!
  undo-tree          20170706.246  installed             Treat undo history as a tree
  vdiff              20170525.1231 installed             A diff tool similar to  vimdiff
  visual-regexp      20170228.1716 installed             A regexp/replace command for Emacs with interactive visual feedback
  vlf                20180201.1454 installed             View Large Files
  yaml-mode          20180212.1556 installed             Major mode for editing YAML files
  ztree              20180215.1021 installed             Text mode directory tree
  async              20180119.533  dependency            Asynchronous processing in Emacs
  company            20180310.1143 dependency            Modular text completion framework
  concurrent         20161228.1930 dependency            Concurrent utility functions for emacs lisp
  ctable             20171005.1711 dependency            Table component for Emacs Lisp
  dash-functional    20180107.818  dependency            Collection of useful combinators for Emacs Lisp
  deferred           20170901.630  dependency            Simple asynchronous functions for emacs lisp
  direx              20170422.627  dependency            Simple Directory Explorer
  epc                20140609.2234 dependency            A RPC stack for the Emacs Lisp
  epl                20180205.1249 dependency            Emacs Package Library
  ghub               20180312.1611 dependency            minuscule client library for the Github API
  git-commit         20180202.321  dependency            Edit Git commit messages
  highlight-inden... 20171218.137  dependency            Minor modes for highlighting indentation
  jedi-core          20170121.610  dependency            Common code of jedi.el and company-jedi.el
  julia-mode         20171116.642  dependency            Major mode for editing Julia source code
  lsp-mode           20180311.908  dependency            Minor mode for interacting with Language Servers
  magit              20180307.631  dependency            A Git porcelain inside Emacs.
  magit-popup        20180217.910  dependency            Define prefix-infix-suffix command combos
  pkg-info           20150517.1943 dependency            Information about packages
  popup              20160709.729  dependency            Visual Popup User Interface
  projectile         20180227.1743 dependency            Manage and navigate projects in Emacs easily
  python-environment 20150310.153  dependency            virtualenv API for Emacs Lisp
  pythonic           20180208.214  dependency            Utility functions for writing pythonic emacs package.
  pyvenv             20180304.712  dependency            Python virtual environment interface
  web-mode           20180310.416  dependency            major mode for editing web templates
  with-editor        20180217.548  dependency            Use the Emacsclient as $EDITOR
  yasnippet          20180310.1614 dependency            Yet another snippet extension for Emacs.

[-- Attachment #3: pkg-e-l.diff --]
[-- Type: application/octet-stream, Size: 3717 bytes --]

--- c:/Users/username/Documents/base/pkg-late.txt	2018-03-14 12:54:08.671294000 +0800
+++ c:/Users/username/Documents/base/pkg-early.txt	2018-03-14 12:53:19.455859000 +0800
@@ -1,4 +1,4 @@
->>> before custom-set-variables : 0.500 sec <<<
+>>> before custom-set-variables : 5.532 sec <<<
 setting 'abbrev-mode': 0.000s
 setting 'ac-dictionary-files': 0.000s
 setting 'ac-modes': 0.000s
@@ -7,7 +7,7 @@
 setting 'ansi-color-for-comint-mode': 0.000s
 setting 'appt-audible': 0.000s
 Loading image-file...done
-setting 'auto-image-file-mode': 0.095s
+setting 'auto-image-file-mode': 0.745s
 setting 'auto-save-timeout': 0.000s
 setting 'backup-by-copying': 0.000s
 setting 'backup-directory-alist': 0.000s
@@ -23,7 +23,7 @@
 setting 'comment-auto-fill-only-comments': 0.000s
 setting 'comment-style': 0.000s
 setting 'compilation-window-height': 0.000s
-setting 'current-language-environment': 0.000s
+setting 'current-language-environment': 0.473s
 setting 'default-input-method': 0.000s
 setting 'default-major-mode': 0.000s
 setting 'delete-by-moving-to-trash': 0.000s
@@ -41,12 +41,12 @@
 setting 'display-time-24hr-format': 0.000s
 setting 'display-time-day-and-date': 0.000s
 Loading time...done
-setting 'display-time-mode': 0.048s
+setting 'display-time-mode': 0.260s
 setting 'doc-view-ghostscript-program': 0.000s
 Loading completion...done
-setting 'dynamic-completion-mode': 0.032s
+setting 'dynamic-completion-mode': 0.283s
 Loading elec-pair...done
-setting 'electric-pair-mode': 0.031s
+setting 'electric-pair-mode': 0.268s
 setting 'emms-player-mpg321-command-name': 0.000s
 setting 'ess-ask-for-ess-directory': 0.000s
 setting 'ess-default-style': 0.000s
@@ -70,12 +70,12 @@
 setting 'history-delete-duplicates': 0.000s
 setting 'horizontal-scroll-bar-mode': 0.000s
 Loading icomplete...done
-setting 'icomplete-mode': 0.010s
+setting 'icomplete-mode': 0.252s
 setting 'ido-everywhere': 0.000s
 setting 'ido-ignore-buffers': 0.000s
 setting 'ido-ignore-files': 0.000s
 Loading ido...done
-setting 'ido-mode': 0.762s
+setting 'ido-mode': 0.253s
 setting 'ido-use-virtual-buffers': 0.000s
 setting 'image-dired-cmd-create-thumbnail-program': 0.000s
 setting 'indent-tabs-mode': 0.000s
@@ -101,7 +101,7 @@
 setting 'maxima-command': 0.000s
 setting 'menu-bar-mode': 0.000s
 Loading avoid...done
-setting 'mouse-avoidance-mode': 0.128s
+setting 'mouse-avoidance-mode': 0.554s
 setting 'org-agenda-files': 0.000s
 setting 'org-agenda-start-on-weekday': 0.000s
 setting 'org-alphabetical-lists': 0.000s
@@ -135,13 +135,13 @@
 setting 'sas-program': 0.000s
 setting 'save-interprogram-paste-before-kill': 0.000s
 Loading savehist...done
-setting 'savehist-mode': 0.047s
+setting 'savehist-mode': 0.261s
 setting 'scalable-fonts-allowed': 0.000s
 setting 'scroll-bar-mode': 0.000s
 setting 'scroll-conservatively': 0.000s
 setting 'sentence-end-double-space': 0.000s
 Loading paren...done
-setting 'show-paren-mode': 0.031s
+setting 'show-paren-mode': 0.371s
 setting 'show-paren-style': 0.000s
 setting 'size-indication-mode': 0.000s
 setting 'srecode-map-save-file': 0.000s
@@ -152,7 +152,7 @@
 setting 'text-scale-mode-step': 0.000s
 setting 'tool-bar-mode': 0.000s
 setting 'track-eol': 0.000s
-setting 'tramp-syntax': 1.622s
+setting 'tramp-syntax': 3.775s
 setting 'undo-limit': 0.000s
 setting 'undo-strong-limit': 0.000s
 setting 'uniquify-buffer-name-style': 0.000s
@@ -168,4 +168,4 @@
 setting 'yas-also-auto-indent-first-line': 0.000s
 setting 'yas-prompt-functions': 0.000s
 setting 'yas-snippet-dirs': 0.000s
->>> custom-set-variables time: 3.337 sec <<<
+>>> custom-set-variables time: 13.096 sec <<<

[-- Attachment #4: pkg-late.txt --]
[-- Type: text/plain, Size: 6737 bytes --]

>>> before custom-set-variables : 0.500 sec <<<
setting 'abbrev-mode': 0.000s
setting 'ac-dictionary-files': 0.000s
setting 'ac-modes': 0.000s
setting 'ac-use-quick-help': 0.000s
setting 'adaptive-fill-regexp': 0.000s
setting 'ansi-color-for-comint-mode': 0.000s
setting 'appt-audible': 0.000s
Loading image-file...done
setting 'auto-image-file-mode': 0.095s
setting 'auto-save-timeout': 0.000s
setting 'backup-by-copying': 0.000s
setting 'backup-directory-alist': 0.000s
setting 'bookmark-default-file': 0.000s
setting 'bookmark-save-flag': 0.000s
setting 'bookmark-version-control': 0.000s
setting 'calendar-chinese-celestial-stem': 0.000s
setting 'calendar-chinese-terrestrial-branch': 0.000s
setting 'calendar-time-zone': 0.000s
setting 'case-fold-search': 0.000s
setting 'column-number-mode': 0.000s
setting 'comint-input-ignoredups': 0.000s
setting 'comment-auto-fill-only-comments': 0.000s
setting 'comment-style': 0.000s
setting 'compilation-window-height': 0.000s
setting 'current-language-environment': 0.000s
setting 'default-input-method': 0.000s
setting 'default-major-mode': 0.000s
setting 'delete-by-moving-to-trash': 0.000s
setting 'delete-old-versions': 0.000s
setting 'desktop-auto-save-timeout': 0.000s
setting 'desktop-base-file-name': 0.000s
setting 'desktop-base-lock-name': 0.000s
setting 'desktop-buffers-not-to-save': 0.000s
setting 'desktop-files-not-to-save': 0.000s
setting 'desktop-modes-not-to-save': 0.000s
setting 'desktop-path': 0.000s
setting 'diary-file': 0.000s
setting 'dired-guess-shell-case-fold-search': 0.000s
setting 'dired-isearch-filenames': 0.000s
setting 'display-time-24hr-format': 0.000s
setting 'display-time-day-and-date': 0.000s
Loading time...done
setting 'display-time-mode': 0.048s
setting 'doc-view-ghostscript-program': 0.000s
Loading completion...done
setting 'dynamic-completion-mode': 0.032s
Loading elec-pair...done
setting 'electric-pair-mode': 0.031s
setting 'emms-player-mpg321-command-name': 0.000s
setting 'ess-ask-for-ess-directory': 0.000s
setting 'ess-default-style': 0.000s
setting 'ess-directory-containing-R': 0.000s
setting 'ess-funcmenu-use-p': 0.000s
setting 'ess-function-template': 0.000s
setting 'ess-indent-with-fancy-comments': 0.000s
setting 'ess-language': 0.000s
setting 'ess-program-files': 0.000s
setting 'ess-sas-data-view-submit-options': 0.000s
setting 'ess-sas-submit-command': 0.000s
setting 'ess-sas-submit-command-options': 0.000s
setting 'ess-sas-submit-post-command': 0.000s
setting 'ess-sas-submit-pre-command': 0.000s
setting 'ess-source-directory': 0.000s
setting 'ffap-machine-p-known': 0.000s
setting 'fill-column': 0.000s
setting 'font-latex-fontify-script': 0.000s
setting 'garbage-collection-messages': 0.000s
setting 'highlight-nonselected-windows': 0.000s
setting 'history-delete-duplicates': 0.000s
setting 'horizontal-scroll-bar-mode': 0.000s
Loading icomplete...done
setting 'icomplete-mode': 0.010s
setting 'ido-everywhere': 0.000s
setting 'ido-ignore-buffers': 0.000s
setting 'ido-ignore-files': 0.000s
Loading ido...done
setting 'ido-mode': 0.762s
setting 'ido-use-virtual-buffers': 0.000s
setting 'image-dired-cmd-create-thumbnail-program': 0.000s
setting 'indent-tabs-mode': 0.000s
setting 'indicate-empty-lines': 0.000s
setting 'inferior-R-args': 0.000s
setting 'inferior-julia-args': 0.000s
setting 'inferior-julia-program-name': 0.000s
setting 'inhibit-default-init': 0.000s
setting 'inhibit-startup-screen': 0.000s
setting 'initial-scratch-message': 0.000s
setting 'isearch-allow-scroll': 0.000s
setting 'ivy-use-virtual-buffers': 0.000s
setting 'julia-arguments': 0.000s
setting 'julia-program': 0.000s
setting 'kept-new-versions': 0.000s
setting 'kept-old-versions': 0.000s
setting 'kill-do-not-save-duplicates': 0.000s
setting 'kill-ring-max': 0.000s
setting 'kill-whole-line': 0.000s
setting 'ls-lisp-emulation': 0.000s
setting 'ls-lisp-verbosity': 0.000s
setting 'magit-git-executable': 0.000s
setting 'maxima-command': 0.000s
setting 'menu-bar-mode': 0.000s
Loading avoid...done
setting 'mouse-avoidance-mode': 0.128s
setting 'org-agenda-files': 0.000s
setting 'org-agenda-start-on-weekday': 0.000s
setting 'org-alphabetical-lists': 0.000s
setting 'org-babel-load-languages': 0.000s
setting 'org-capture-templates': 0.000s
setting 'org-directory': 0.000s
setting 'org-insert-mode-line-in-empty-file': 0.000s
setting 'org-latex-to-pdf-process': 0.000s
setting 'org-list-allow-alphabetical': 0.000s
setting 'org-log-done': 0.000s
setting 'org-modules': 0.000s
setting 'org-src-fontify-natively': 0.000s
setting 'org-startup-folded': 0.000s
setting 'org-startup-truncated': 0.000s
setting 'org-todo-keywords': 0.000s
setting 'package-archives': 0.000s
setting 'package-selected-packages': 0.000s
setting 'paragraph-start': 0.000s
setting 'password-cache-expiry': 0.000s
setting 'pop3-leave-mail-on-server': 0.000s
setting 'pr-faces-p': 0.000s
setting 'pr-gs-command': 0.000s
setting 'pr-gv-command': 0.000s
setting 'preview-auto-cache-preamble': 0.000s
setting 'preview-gs-command': 0.000s
setting 'preview-image-type': 0.000s
setting 'remote-file-name-inhibit-cache': 0.000s
setting 'require-final-newline': 0.000s
setting 'safe-local-variable-values': 0.000s
setting 'sas-indent-width': 0.000s
setting 'sas-program': 0.000s
setting 'save-interprogram-paste-before-kill': 0.000s
Loading savehist...done
setting 'savehist-mode': 0.047s
setting 'scalable-fonts-allowed': 0.000s
setting 'scroll-bar-mode': 0.000s
setting 'scroll-conservatively': 0.000s
setting 'sentence-end-double-space': 0.000s
Loading paren...done
setting 'show-paren-mode': 0.031s
setting 'show-paren-style': 0.000s
setting 'size-indication-mode': 0.000s
setting 'srecode-map-save-file': 0.000s
setting 'svn-status-svn-environment-var-list': 0.000s
setting 'system-time-locale': 0.000s
setting 'tab-always-indent': 0.000s
setting 'tab-width': 0.000s
setting 'text-scale-mode-step': 0.000s
setting 'tool-bar-mode': 0.000s
setting 'track-eol': 0.000s
setting 'tramp-syntax': 1.622s
setting 'undo-limit': 0.000s
setting 'undo-strong-limit': 0.000s
setting 'uniquify-buffer-name-style': 0.000s
setting 'user-full-name': 0.000s
setting 'user-mail-address': 0.000s
setting 'vc-handled-backends': 0.000s
setting 'vc-make-backup-files': 0.000s
setting 'vc-svn-diff-switches': 0.000s
setting 'vc-svn-use-edit': 0.000s
setting 'version-control': 0.000s
setting 'visible-bell': 0.000s
setting 'whitespace-action': 0.000s
setting 'yas-also-auto-indent-first-line': 0.000s
setting 'yas-prompt-functions': 0.000s
setting 'yas-snippet-dirs': 0.000s
>>> custom-set-variables time: 3.337 sec <<<

bug#30731: Slower startup using March (27.0.50) build in windows

[Noam Postavsky]

forwarded 30731 https://lists.gnu.org/archive/html/emacs-devel/2018-03/msg00040.html
close 30731
quit

Shuguang Sun <shuguang@gmail.com> writes:

> The diff files compares the loading time given package initialized
> early or late. And I attached a list of packages I installed.

The variables which take longer seem to have custom setters; those don't
activate when the library is not loaded.  So basically, I think this
difference is to be expected.

Since the patches mention in
https://lists.gnu.org/archive/html/emacs-devel/2018-03/msg00040.html
have now been merged, I think we can consider this bug resolved.

[1: 2db57579b0]: 2018-03-19 10:44:40 +0200
  Various follow-ups for early init file changes
  https://git.savannah.gnu.org/cgit/emacs.git/commit/?id=2db57579b08ac99c464b6d3698648b3167fc5d55