On Thu, Sep 24, 2015 at 2:46 PM, Eli Zaretskii <eliz@gnu.org> wrote:

> > In this run, I recorded over 601000 bytes being allocated; about 60% of
> that (~364000) was done from things called by parse_single_submenu; about
> half of that (~185000), in turn, was via larger_vector called from
> ensure_menu_items. The largest of those was a single allocation of 62000
> bytes. There were several more of these calls, with smaller sizes, and
> varying in the nesting depth of single_menu_item and friends.
>
> Can you show the backtrace from the 62000-byte allocation?
>


Hardware watchpoint 3: consing_since_gc

Old value = 236408
New value = 298408
allocate_vectorlike (len=7749) at ../../src/alloc.c:3129
3129      vector_cells_consed += len;
#0  allocate_vectorlike (len=7749) at ../../src/alloc.c:3129
#1  0x000000000053c358 in XUNTAG (type=<optimized out>, a=<optimized out>)
at ../../src/lisp.h:839
#2  XVECTOR (a=<optimized out>) at ../../src/lisp.h:969
#3  allocate_vectorlike (len=7749) at ../../src/alloc.c:3097
#4  allocate_vector (len=7749) at ../../src/alloc.c:3148
#5  0x000000000055febd in larger_vector (vec=20730845, incr_min=<optimized
out>, nitems_max=<optimized out>) at ../../src/fns.c:3619
#6  0x0000000000463c18 in ensure_menu_items (items=<optimized out>) at
../../src/menu.c:172
#7  0x0000000000463dbe in push_menu_item (name=8891140, enable=44016,
key=7152208, def=4131552, equiv=0, type=0, selected=0, help=0) at
../../src/menu.c:242
#8  0x00000000004640ae in single_menu_item (skp_v=0x7fffffffcbf0,
key=7152208, item=<optimized out>, dummy=<optimized out>) at
../../src/menu.c:424
#9  single_menu_item (key=7152208, item=<optimized out>, dummy=<optimized
out>, skp_v=0x7fffffffcbf0) at ../../src/menu.c:322
#10 0x00000000004f468a in map_keymap_item (data=0x7fffffffcbf0,
val=<optimized out>, key=<optimized out>, args=0, fun=0x463ea0
<single_menu_item>) at ../../src/keymap.c:547
#11 map_keymap_internal (map=19459395, fun=0x463ea0 <single_menu_item>,
args=0, data=0x7fffffffcbf0) at ../../src/keymap.c:584
#12 0x0000000000463d18 in single_keymap_panes (keymap=16111731,
pane_name=<optimized out>, prefix=<optimized out>, maxdepth=8) at
../../src/menu.c:298
#13 0x0000000000464124 in single_menu_item (skp_v=0x7fffffffcd50,
key=5159696, item=<optimized out>, dummy=<optimized out>) at
../../src/menu.c:437
#14 single_menu_item (key=5159696, item=<optimized out>, dummy=<optimized
out>, skp_v=0x7fffffffcd50) at ../../src/menu.c:322
#15 0x00000000004f468a in map_keymap_item (data=0x7fffffffcd50,
val=<optimized out>, key=<optimized out>, args=0, fun=0x463ea0
<single_menu_item>) at ../../src/keymap.c:547
#16 map_keymap_internal (map=19467347, fun=0x463ea0 <single_menu_item>,
args=0, data=0x7fffffffcd50) at ../../src/keymap.c:584
#17 0x0000000000463d18 in single_keymap_panes (keymap=19716755,
pane_name=<optimized out>, prefix=<optimized out>, maxdepth=9) at
../../src/menu.c:298
#18 0x0000000000464124 in single_menu_item (skp_v=0x7fffffffceb0,
key=8138816, item=<optimized out>, dummy=<optimized out>) at
../../src/menu.c:437
#19 single_menu_item (key=8138816, item=<optimized out>, dummy=<optimized
out>, skp_v=0x7fffffffceb0) at ../../src/menu.c:322
#20 0x00000000004f468a in map_keymap_item (data=0x7fffffffceb0,
val=<optimized out>, key=<optimized out>, args=0, fun=0x463ea0
<single_menu_item>) at ../../src/keymap.c:547
#21 map_keymap_internal (map=19488483, fun=0x463ea0 <single_menu_item>,
args=0, data=0x7fffffffceb0) at ../../src/keymap.c:584
#22 0x0000000000463d18 in single_keymap_panes (keymap=19750115,
pane_name=<optimized out>, prefix=<optimized out>, maxdepth=10) at
../../src/menu.c:298
#23 0x00000000004652c8 in parse_single_submenu (item_key=8191056,
item_name=9330724, maps=<optimized out>) at ../../src/menu.c:566
#24 0x0000000000466a9f in set_frame_menubar (f=0x122a940,
first_time=<optimized out>, deep_p=<optimized out>) at ../../src/xmenu.c:797
#25 0x00000000004d0038 in Fx_create_frame (parms=13618675) at
../../src/xfns.c:3307
#26 0x0000000000555600 in Ffuncall (nargs=<optimized out>,
args=0x7fffffffd2e8) at ../../src/eval.c:2650
#27 0x0000000000589d4d in exec_byte_code (bytestr=13263568, vector=48,
maxdepth=0, args_template=140737488343792, nargs=2, args=0x2000000) at
../../src/bytecode.c:880
#28 0x000000000055501a in funcall_lambda (fun=9182501, nargs=<optimized
out>, arg_vector=0x7fffffffd4e8) at ../../src/eval.c:2876
#29 0x0000000000555364 in Ffuncall (nargs=<optimized out>,
args=0x7fffffffd4e0) at ../../src/eval.c:2711
#30 0x0000000000589d4d in exec_byte_code (bytestr=13263568, vector=48,
maxdepth=0, args_template=1030, nargs=2, args=0x2000000) at
../../src/bytecode.c:880
#31 0x0000000000555364 in Ffuncall (nargs=<optimized out>,
args=0x7fffffffd7a0) at ../../src/eval.c:2711
#32 0x0000000000556527 in Fapply (nargs=2, args=0x7fffffffd7a0) at
../../src/eval.c:2235
#33 0x0000000000555462 in Ffuncall (nargs=<optimized out>,
args=0x7fffffffd798) at ../../src/eval.c:2630
#34 0x0000000000589d4d in exec_byte_code (bytestr=13263568, vector=48,
maxdepth=0, args_template=41, nargs=3, args=0x2000000) at
../../src/bytecode.c:880
#35 0x0000000000555364 in Ffuncall (nargs=<optimized out>,
args=0x7fffffffd998) at ../../src/eval.c:2711
#36 0x0000000000589d4d in exec_byte_code (bytestr=13263568, vector=48,
maxdepth=0, args_template=158, nargs=2, args=0x2000000) at
../../src/bytecode.c:880
#37 0x0000000000555364 in Ffuncall (nargs=<optimized out>,
args=0x7fffffffdb38) at ../../src/eval.c:2711
#38 0x0000000000589d4d in exec_byte_code (bytestr=13263568, vector=48,
maxdepth=0, args_template=8, nargs=1, args=0x2000000) at
../../src/bytecode.c:880
#39 0x0000000000555364 in Ffuncall (nargs=<optimized out>,
args=0x7fffffffdea8) at ../../src/eval.c:2711
#40 0x0000000000551229 in Ffuncall_interactively (nargs=1,
args=0x7fffffffdea8) at ../../src/callint.c:250
#41 0x0000000000555462 in Ffuncall (nargs=<optimized out>,
args=0x7fffffffdea0) at ../../src/eval.c:2630
#42 0x00000000005563fd in Fapply (nargs=3, args=0x7fffffffdea0) at
../../src/eval.c:2231
#43 0x00000000005515c7 in Fcall_interactively (function=7970864,
record_flag=0, keys=12382133) at ../../src/callint.c:387
#44 0x00000000005555dc in Ffuncall (nargs=<optimized out>,
args=0x7fffffffdfe8) at ../../src/eval.c:2657
#45 0x0000000000589d4d in exec_byte_code (bytestr=13263568, vector=48,
maxdepth=0, args_template=115, nargs=4, args=0x2000000) at
../../src/bytecode.c:880
#46 0x0000000000555364 in Ffuncall (nargs=<optimized out>,
args=0x7fffffffe180) at ../../src/eval.c:2711
#47 0x00000000005557ba in call1 (fn=<optimized out>, arg1=<optimized out>)
at ../../src/eval.c:2509
#48 0x00000000004f013b in command_loop_1 () at ../../src/keyboard.c:1460
#49 0x0000000000553a14 in internal_condition_case (bfun=0x4efda0
<command_loop_1>, handlers=<optimized out>, hfun=0x4e6030 <cmd_error>) at
../../src/eval.c:1309
#50 0x00000000004e463c in command_loop_2 (ignore=<optimized out>) at
../../src/keyboard.c:1088
#51 0x0000000000553928 in internal_catch (tag=0, func=0x4e4620
<command_loop_2>, arg=0) at ../../src/eval.c:1073
#52 0x00000000004e458c in command_loop () at ../../src/keyboard.c:1067
#53 0x00000000004e5c16 in recursive_edit_1 () at ../../src/keyboard.c:673
#54 0x00000000004e5f55 in Frecursive_edit () at ../../src/keyboard.c:744
#55 0x00000000004123a3 in main (argc=2, argv=0x7fffffffe5a8) at
../../src/emacs.c:1643

Lisp Backtrace:
"x-create-frame" (0xffffd2f0)
"x-create-frame-with-faces" (0xffffd4e8)
0x121eac8 PVEC_COMPILED
"apply" (0xffffd7a0)
"frame-creation-function" (0xffffd9a0)
"make-frame" (0xffffdb40)
"make-frame-command" (0xffffdeb0)
"funcall-interactively" (0xffffdea8)
"call-interactively" (0xffffdff0)
"command-execute" (0xffffe188)

Anyway, I think this is a separate issue, unrelated to the one that is
> the subject of this bug report.
>

Agreed. Nothing about this is specific to remote frames or slow
connections. Should I open a new ticket, or follow up on emacs-devel?

I'm still seeing no display problems with the test branch; I'm fine with
closing out this ticket now.

Ken