;;; trace.el --- tracing facility for Emacs Lisp functions -*- lexical-binding: t -*- ;; Copyright (C) 1993, 1998, 2000-2017 Free Software Foundation, Inc. ;; Author: Hans Chalupsky ;; Maintainer: emacs-devel@gnu.org ;; Created: 15 Dec 1992 ;; Keywords: tools, lisp ;; This file is part of GNU Emacs. ;; GNU Emacs is free software: you can redistribute it and/or modify ;; it under the terms of the GNU General Public License as published by ;; the Free Software Foundation, either version 3 of the License, or ;; (at your option) any later version. ;; GNU Emacs is distributed in the hope that it will be useful, ;; but WITHOUT ANY WARRANTY; without even the implied warranty of ;; MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the ;; GNU General Public License for more details. ;; You should have received a copy of the GNU General Public License ;; along with GNU Emacs. If not, see . ;; LCD Archive Entry: ;; trace|Hans Chalupsky|hans@cs.buffalo.edu| ;; Tracing facility for Emacs Lisp functions| ;; 1993/05/18 00:41:16|2.0|~/packages/trace.el.Z| ;;; Commentary: ;; Introduction: ;; ============= ;; A simple trace package that utilizes nadvice.el. It generates trace ;; information in a Lisp-style fashion and inserts it into a trace output ;; buffer. Tracing can be done in the background (or silently) so that ;; generation of trace output won't interfere with what you are currently ;; doing. ;; Restrictions: ;; ============= ;; - Traced subrs when called interactively will always show nil as the ;; value of their arguments. ;; - Only functions/macros/subrs that are called via their function cell will ;; generate trace output, hence, you won't get trace output for: ;; + Subrs called directly from other subrs/C-code ;; + Compiled calls to subrs that have special byte-codes associated ;; with them (e.g., car, cdr, ...) ;; + Macros that were expanded during compilation ;; - All the restrictions that apply to nadvice.el ;; Usage: ;; ====== ;; - To trace a function say `M-x trace-function', which will ask you for the ;; name of the function/subr/macro to trace. ;; - If you want to trace a function that switches buffers or does other ;; display oriented stuff use `M-x trace-function-background', which will ;; generate the trace output silently in the background without popping ;; up windows and doing other irritating stuff. ;; - To untrace a function say `M-x untrace-function'. ;; - To untrace all currently traced functions say `M-x untrace-all'. ;; Examples: ;; ========= ;; ;; (defun fact (n) ;; (if (= n 0) 1 ;; (* n (fact (1- n))))) ;; fact ;; ;; (trace-function 'fact) ;; fact ;; ;; Now, evaluating this... ;; ;; (fact 4) ;; 24 ;; ;; ...will generate the following in *trace-buffer*: ;; ;; 1 -> fact: n=4 ;; | 2 -> fact: n=3 ;; | | 3 -> fact: n=2 ;; | | | 4 -> fact: n=1 ;; | | | | 5 -> fact: n=0 ;; | | | | 5 <- fact: 1 ;; | | | 4 <- fact: 1 ;; | | 3 <- fact: 2 ;; | 2 <- fact: 6 ;; 1 <- fact: 24 ;; ;; ;; (defun ack (x y z) ;; (if (= x 0) ;; (+ y z) ;; (if (and (<= x 2) (= z 0)) ;; (1- x) ;; (if (and (> x 2) (= z 0)) ;; y ;; (ack (1- x) y (ack x y (1- z))))))) ;; ack ;; ;; (trace-function 'ack) ;; ack ;; ;; Try this for some interesting trace output: ;; ;; (ack 3 3 1) ;; 27 ;; ;; ;; The following does something similar to the functionality of the package ;; log-message.el by Robert Potter, which is giving you a chance to look at ;; messages that might have whizzed by too quickly (you won't see subr ;; generated messages though): ;; ;; (trace-function-background 'message "*Message Log*") ;;; Change Log: ;; Revision 2.0 1993/05/18 00:41:16 hans ;; * Adapted for advice.el 2.0; it now also works ;; for GNU Emacs-19 and Lemacs ;; * Separate function `trace-function-background' ;; * Separate pieces of advice for foreground and background tracing ;; * Less insane handling of interactive trace buffer specification ;; * String arguments and values are now printed properly ;; ;; Revision 1.1 1992/12/15 22:45:15 hans ;; * Created, first public release ;;; Code: (defgroup trace nil "Tracing facility for Emacs Lisp functions." :prefix "trace-" :group 'lisp) ;;;###autoload (defcustom trace-buffer "*trace-output*" "Trace output will by default go to that buffer." :type 'string) ;; Current level of traced function invocation: (defvar trace-level 0) ;; Semi-cryptic name used for a piece of trace advice: (defvar trace-advice-name 'trace-function\ ) ;; Used to separate new trace output from previous traced runs: (defvar trace-separator (format "%s\n" (make-string 70 ?=))) (defvar inhibit-trace nil "If non-nil, all tracing is temporarily inhibited.") ;;;###autoload (defun trace-values (&rest values) "Helper function to get internal values. You can call this function to add internal values in the trace buffer." (unless inhibit-trace (with-current-buffer trace-buffer (goto-char (point-max)) (insert (trace-entry-message 'trace-values trace-level values ""))))) (defun trace-entry-message (function level args context) "Generate a string that describes that FUNCTION has been entered. LEVEL is the trace level, ARGS is the list of arguments passed to FUNCTION, and CONTEXT is a string describing the dynamic context (e.g. values of some global variables)." (let ((print-circle t)) (format "%s%s%d -> %S%s\n" (mapconcat 'char-to-string (make-string (1- level) ?|) " ") (if (> level 1) " " "") level ;; FIXME: Make it so we can click the function name to jump to its ;; definition and/or untrace it. (cons function args) context))) (defun trace-exit-message (function level value context) "Generate a string that describes that FUNCTION has exited. LEVEL is the trace level, VALUE value returned by FUNCTION, and CONTEXT is a string describing the dynamic context (e.g. values of some global variables)." (let ((print-circle t)) (format "%s%s%d <- %s: %S%s\n" (mapconcat 'char-to-string (make-string (1- level) ?|) " ") (if (> level 1) " " "") level function ;; Do this so we'll see strings: value context))) (defvar trace--timer nil) (defun trace--display-buffer (buf) (unless (or trace--timer (get-buffer-window buf 'visible)) (setq trace--timer ;; Postpone the display to some later time, in case we ;; can't actually do it now. (run-with-timer 0 nil (lambda () (setq trace--timer nil) (display-buffer buf nil 0)))))) (defun trace-make-advice (function buffer background context) "Build the piece of advice to be added to trace FUNCTION. FUNCTION is the name of the traced function. BUFFER is the buffer where the trace should be printed. BACKGROUND if nil means to display BUFFER. CONTEXT if non-nil should be a function that returns extra info that should be printed along with the arguments in the trace." (lambda (body &rest args) (let ((trace-level (1+ trace-level)) (trace-buffer (get-buffer-create buffer)) (deactivate-mark nil) ;Protect deactivate-mark. (ctx (funcall context))) (unless inhibit-trace (with-current-buffer trace-buffer (set (make-local-variable 'window-point-insertion-type) t) (unless background (trace--display-buffer trace-buffer)) (goto-char (point-max)) ;; Insert a separator from previous trace output: (if (= trace-level 1) (insert trace-separator)) (insert (trace-entry-message function trace-level args ctx)))) (let ((result)) (unwind-protect (setq result (list (apply body args))) (unless inhibit-trace (let ((ctx (funcall context))) (with-current-buffer trace-buffer (unless background (trace--display-buffer trace-buffer)) (goto-char (point-max)) (insert (trace-exit-message function trace-level (if result (car result) '\!non-local\ exit\!) ctx)))))) (car result))))) (defun trace-function-internal (function buffer background context) "Add trace advice for FUNCTION." (advice-add function :around (trace-make-advice function (or buffer trace-buffer) background (or context (lambda () ""))) `((name . ,trace-advice-name) (depth . -100)))) (defun trace-is-traceable-p (sym) "Whether the given symbol is a traceable function." (or (functionp sym) (macrop sym))) (defun trace-is-traced (function) "Whether FUNCTION is currently traced." (advice-member-p trace-advice-name function)) (defun trace--read-function-args (prompt) "Read a function name, prompting with string PROMPT. If `current-prefix-arg' is non-nil, also read a buffer and a \"context\" \(Lisp expression). Return (FUNCTION BUFFER FUNCTION-CONTEXT)." (cons (let ((default (function-called-at-point)) (beg (string-match ":[ \t]*\\'" prompt))) (intern (completing-read (if default (format "%s (default %s)%s" (substring prompt 0 beg) default (if beg (substring prompt beg) ": ")) prompt) obarray 'trace-is-traceable-p t nil nil (if default (symbol-name default))))) (when current-prefix-arg (trace--read-extra-args)))) (defun trace--read-extra-args () "Read a buffer and a \"context\" (Lisp expression). Return (BUFFER FUNCTION-CONTEXT)." (list (read-buffer "Output to buffer: " trace-buffer) (let ((exp (let ((minibuffer-completing-symbol t)) (read-from-minibuffer "Context expression: " nil read-expression-map t 'read-expression-history "nil")))) (and exp (lambda () (let ((print-circle t)) (concat " [" (prin1-to-string (eval exp t)) "]"))))))) ;;;###autoload (defun trace-function-foreground (function &optional buffer context) "Trace calls to function FUNCTION. With a prefix argument, also prompt for the trace buffer (default `trace-buffer'), and a Lisp expression CONTEXT. Tracing a function causes every call to that function to insert into BUFFER Lisp-style trace messages that display the function's arguments and return values. It also evaluates CONTEXT, if that is non-nil, and inserts its value too. For example, you can use this to track the current buffer, or position of point. This function creates BUFFER if it does not exist. This buffer will popup whenever FUNCTION is called. Do not use this function to trace functions that switch buffers, or do any other display-oriented stuff - use `trace-function-background' instead. To stop tracing a function, use `untrace-function' or `untrace-all'." (interactive (trace--read-function-args "Trace function: ")) (trace-function-internal function buffer nil context)) ;;;###autoload (defun trace-function-background (function &optional buffer context) "Trace calls to function FUNCTION, quietly. This is like `trace-function-foreground', but without popping up the output buffer or changing the window configuration." (interactive (trace--read-function-args "Trace function in background: ")) (trace-function-internal function buffer t context)) ;;;###autoload (defalias 'trace-function 'trace-function-foreground) (defun untrace-function (function) "Remove trace from FUNCTION. If FUNCTION was not traced this is a noop." (interactive (list (intern (completing-read "Untrace function: " obarray #'trace-is-traced t)))) (advice-remove function trace-advice-name)) ;;;###autoload (defun trace-package (prefix &optional buffer context) "Trace all functions with names starting with PREFIX. For example, to trace all diff functions, do the following: \\[trace-package] RET diff- RET Background tracing is used. Switch to the trace output buffer to view the results. See also `untrace-package'." ;; Derived in part from `elp-instrument-package'. (interactive (cons (completing-read "Prefix of package to trace: " obarray #'trace-is-traceable-p) (and current-prefix-arg (trace--read-extra-args)))) (when (zerop (length prefix)) (error "Tracing all Emacs functions would render Emacs unusable")) (mapc (lambda (name) (trace-function-background (intern name) buffer context)) (all-completions prefix obarray #'trace-is-traceable-p)) (message "Tracing to %s. Use %s to untrace a package, or %s to remove all traces." (or buffer trace-buffer) (substitute-command-keys "\\[untrace-package]") (substitute-command-keys "\\[untrace-all]"))) (defun untrace-package (prefix) "Remove traces from all functions with names starting with PREFIX. See also `trace-package'." (interactive (list (completing-read "Prefix of package to untrace: " obarray #'trace-is-traced))) (if (and (zerop (length prefix)) (y-or-n-p "Remove all function traces?")) (untrace-all) (mapc (lambda (name) (untrace-function (intern name))) (all-completions prefix obarray #'trace-is-traceable-p)))) ;;;###autoload (defun trace-regexp (regexp &optional buffer context) "Trace all functions with names matching REGEXP. For example, to trace indentation-related functions, you could try: \\[trace-regexp] RET indent\\|offset RET Warning: Do not attempt to trace all functions. Tracing too many functions at one time will render Emacs unusable. Background tracing is used. Switch to the trace output buffer to view the results. See also `untrace-regexp'." (interactive (cons (read-regexp "Regexp matching functions to trace: ") (and current-prefix-arg (trace--read-extra-args)))) (when (member regexp '("" "." ".+" ".*")) ;; Not comprehensive, but it catches the most likely attempts. (error "Tracing all Emacs functions would render Emacs unusable")) (mapatoms (lambda (sym) (and (trace-is-traceable-p sym) (string-match-p regexp (symbol-name sym)) (trace-function-background sym buffer context)))) (message "Tracing to %s. Use %s to untrace by regexp, or %s to remove all traces." (or buffer trace-buffer) (substitute-command-keys "\\[untrace-regexp]") (substitute-command-keys "\\[untrace-all]"))) (defun untrace-regexp (regexp) "Remove traces from all functions with names matching REGEXP. See also `trace-regexp'." (interactive (list (read-regexp "Regexp matching functions to untrace: "))) (if (and (zerop (length regexp)) (y-or-n-p "Remove all function traces?")) (untrace-all) (mapatoms (lambda (sym) (and (trace-is-traceable-p sym) (string-match-p regexp (symbol-name sym)) (untrace-function sym)))))) (defun untrace-all () "Remove traces from all currently traced functions." (interactive) (mapatoms #'untrace-function)) (provide 'trace) ;;; trace.el ends here