Mercurial > hg > xemacs-beta

diff src/event-stream.c @ 1292:f3437b56874d

Find changesets by keywords (author, files, the commit message), revision number or hash, or revset expression.
[xemacs-hg @ 2003-02-13 09:57:04 by ben] profile updates profile.c: Major reworking. Keep track of new information -- total function timing (includes descendants), GC usage, total GC usage (includes descendants). New functions to be called appropriately from eval.c, alloc.c to keep track of this information. Keep track of when we're actually in a function vs. in its profile, for more accurate timing counts. Track profile overhead separately. Create new mechanism for specifying "internal sections" that are tracked just like regular Lisp functions and even appear in the backtrace if `backtrace-with-internal-sections' is non-nil (t by default for error-checking builds). Add some KKCC information for the straight (non-Elisp) hash table used by profile, which contains Lisp objects in its keys -- but not used yet. Remove old ad-hoc methods for tracking garbage collection, redisplay (which was incorrect anyway when Lisp was called within these sections). Don't record any tick info when blocking under MS Windows, since the timer there is in real time rather than in process time. Make `start-profiling', `stop-profiling' interactive. Be consistent wrt. recursive functions and functions currently on the stack when starting or stopping -- together these make implementing the `total' values extremely difficult. When we start profiling, we act as if we just entered all the functions currently on the stack. Likewise when exiting. Create vars in_profile for tracking time spent inside of profiling, and profiling_lock for setting exclusive access to the main hash table when reading from it or modifying it. (protects against getting screwed up by the signal handle going off at the same time. profile.h: New file. Create macros for declaring internal profiling sections. lisp.h: Move profile-related stuff to profile.h. alloc.c: Keep track of total consing, for profile. Tell profile when we are consing. Use new profile-section method for noting garbage-collection. alloc.c: Abort if we attempt to call the allocator reentrantly. backtrace.h, eval.c: Add info for use by profile in the backtrace frame and transfer PUSH_BACKTRACE/POP_BACKTRACE from eval.c, for use with profile. elhash.c: Author comment. eval.c, lisp.h: New Lisp var `backtrace-with-internal-sections'. Set to t when error-checking is on. eval.c: When unwinding, eval.c: Report to profile when we are about-to-call and just-called wrt. a function. alloc.c, eval.c: Allow for "fake" backtrace frames, for internal sections (used by profile and `backtrace-with-internal-sections'. event-Xt.c, event-gtk.c, event-msw.c, event-tty.c: Record when we are actually blocking on an event, for profile's sake. event-stream.c: Record internal profiling sections for getting, dispatching events. extents.c: Record internal profiling sections for map_extents. hash.c, hash.h: Add pregrow_hash_table_if_necessary(). (Used in profile code since the signal handler is the main grower but can't allow a realloc(). We make sure, at critical points, that the table is large enough.) lread.c: Create internal profiling sections for `load' (which may be triggered internally by autoload, etc.). redisplay.c: Remove old profile_redisplay_flag. Use new macros to declare internal profiling section for redisplay. text.c: Use new macros to declare internal profiling sections for char-byte conversion and internal-external conversion. SEMI-UNRELATED CHANGES: ----------------------- text.c: Update the long comments.
author ben
date Thu, 13 Feb 2003 09:57:08 +0000
parents cd0abfdb9e9d
children 70921960b980
line wrap: on
line diff
--- a/src/event-stream.c	Wed Feb 12 22:52:33 2003 +0000
+++ b/src/event-stream.c	Thu Feb 13 09:57:08 2003 +0000
@@ -90,6 +90,7 @@
 #include "macros.h"		/* for defining_keyboard_macro */
 #include "menubar.h"            /* #### for evil kludges. */
 #include "process.h"
+#include "profile.h"
 #include "window-impl.h"
 
 #include "sysdep.h"		/* init_poll_for_quit() */
@@ -249,6 +250,9 @@
 /* the number of keyboard characters read.  callint.c wants this. */
 Charcount num_input_chars;
 
+static Lisp_Object Qnext_event, Qdispatch_event, QSnext_event_internal;
+static Lisp_Object QSexecute_internal_event;
+
 #ifdef DEBUG_XEMACS
 Fixnum debug_emacs_events;
 
@@ -2103,8 +2107,12 @@
 next_event_internal (Lisp_Object target_event, int allow_queued)
 {
   struct gcpro gcpro1;
+  PROFILE_DECLARE ();
+
   QUIT;
 
+  PROFILE_RECORD_ENTERING_SECTION (QSnext_event_internal);
+
   assert (NILP (XEVENT_NEXT (target_event)));
 
   GCPRO1 (target_event);
@@ -2158,6 +2166,8 @@
     }
 
   UNGCPRO;
+
+  PROFILE_RECORD_EXITING_SECTION (QSnext_event_internal);
 }
 
 void
@@ -2228,6 +2238,7 @@
   int store_this_key = 0;
   struct gcpro gcpro1;
   int depth;
+  PROFILE_DECLARE ();
 
   GCPRO1 (event);
 
@@ -2252,6 +2263,8 @@
     invalid_operation ("Attempt to call next-event inside menu callback",
 		       Qunbound);
 
+  PROFILE_RECORD_ENTERING_SECTION (Qnext_event);
+
   depth = begin_dont_check_for_quit ();
 
   if (NILP (event))
@@ -2477,6 +2490,8 @@
   Vquit_flag = Qnil; /* see begin_dont_check_for_quit() */
   unbind_to (depth);
 
+  PROFILE_RECORD_EXITING_SECTION (Qnext_event);
+
   UNGCPRO;
 
   return event;
@@ -3022,35 +3037,39 @@
 static void
 execute_internal_event (Lisp_Object event)
 {
+  PROFILE_DECLARE ();
+
   /* events on dead channels get silently eaten */
   if (object_dead_p (XEVENT (event)->channel))
     return;
 
+  PROFILE_RECORD_ENTERING_SECTION (QSexecute_internal_event);
+
   /* This function can GC */
   switch (XEVENT_TYPE (event))
     {
     case empty_event:
-      return;
+      goto done;
 
     case eval_event:
       {
 	call1 (XEVENT_EVAL_FUNCTION (event),
 	       XEVENT_EVAL_OBJECT (event));
-	return;
+	goto done;
       }
 
     case magic_eval_event:
       {
 	XEVENT_MAGIC_EVAL_INTERNAL_FUNCTION (event)
 	  XEVENT_MAGIC_EVAL_OBJECT (event);
-	return;
+	goto done;
       }
 
     case pointer_motion_event:
       {
 	if (!NILP (Vmouse_motion_handler))
 	  call1 (Vmouse_motion_handler, event);
-	return;
+	goto done;
       }
 
     case process_event:
@@ -3142,7 +3161,7 @@
 	    */
 	    status_notify ();
 	  }
-	return;
+	goto done;
       }
 
     case timeout_event:
@@ -3152,14 +3171,17 @@
 	if (!NILP (EVENT_TIMEOUT_FUNCTION (e)))
 	  call1 (EVENT_TIMEOUT_FUNCTION (e),
                  EVENT_TIMEOUT_OBJECT (e));
-	return;
+	goto done;
       }
     case magic_event:
 	event_stream_handle_magic_event (XEVENT (event));
-	return;
+	goto done;
     default:
       abort ();
     }
+
+ done:
+  PROFILE_RECORD_EXITING_SECTION (QSexecute_internal_event);
 }
 
 
@@ -4341,6 +4363,7 @@
   Lisp_Event *ev;
   Lisp_Object console;
   Lisp_Object channel;
+  PROFILE_DECLARE ();
 
   CHECK_LIVE_EVENT (event);
   ev = XEVENT (event);
@@ -4350,6 +4373,8 @@
   if (object_dead_p (channel))
     return Qnil;
 
+  PROFILE_RECORD_ENTERING_SECTION (Qdispatch_event);
+
   /* Some events don't have channels (e.g. eval events). */
   console = CDFW_CONSOLE (channel);
   if (NILP (console))
@@ -4548,6 +4573,8 @@
 	execute_internal_event (event);
 	break;
     }
+
+  PROFILE_RECORD_EXITING_SECTION (Qdispatch_event);
   return Qnil;
 }
 
@@ -4818,6 +4845,9 @@
 
   DEFSYMBOL (Qself_insert_defer_undo);
   DEFSYMBOL (Qcancel_mode_internal);
+
+  DEFSYMBOL (Qnext_event);
+  DEFSYMBOL (Qdispatch_event);
 }
 
 void
@@ -4874,6 +4904,11 @@
   last_point_position_buffer = Qnil;
   staticpro (&last_point_position_buffer);
 
+  QSnext_event_internal = build_string ("next_event_internal()");
+  staticpro (&QSnext_event_internal);
+  QSexecute_internal_event = build_string ("execute_internal_event()");
+  staticpro (&QSexecute_internal_event);
+
   DEFVAR_LISP ("echo-keystrokes", &Vecho_keystrokes /*
 *Nonzero means echo unfinished commands after this many seconds of pause.
 */ );