;; Tracer ;; Bruno Haible 13.2.1990, 15.3.1991, 4.4.1991 ;; German comments translated into English: Stefan Kain 2001-12-26 ;; Sam Steingold 2001-2009, 2011 (in-package "COMMON-LISP") (export '(trace untrace)) (export '(custom::*trace-indent*) "CUSTOM") (in-package "EXT") (export '(*trace-function* *trace-args* *trace-form* *trace-values* custom::*trace-indent*)) (in-package "SYSTEM") (defvar *trace-indent* nil "Use indentation in addition to numbering to indicate the trace level.") (proclaim '(special *trace-function* *trace-args* *trace-form* *trace-values*)) (defvar *traced-functions* nil) ; list of currently traced function-names ; ABI ;; So long as a function-name funname [resp. more exactly: the Symbol ;; symbol = (get-funname-symbol funname)] are traced, the Property ;; sys::traced-definition contains the old content of the function-cell, the ;; Property sys::tracing-definition contains the new content of the ;; function-cell, and the function-name is element of the list ;; *traced-functions*. ;; Meanwhile the content of the function-cell can change, however! ;; At all events the following is true: ;; (and (fboundp symbol) ;; (eq (symbol-function symbol) (get symbol 'sys::tracing-definition))) ;; ===> (member funname *traced-functions* :test #'equal) ;; <==> (get symbol 'sys::traced-definition) (defvar *trace-level* 0) ; nesting depth for Trace-Output (labels ((subclosure-pos (closure name) (do* ((length (sys::%record-length closure)) ;; SYMBOL-SUFFIX is defined in compiler.lisp (nm (symbol-suffix (closure-name closure) name)) (nm-traced (symbol-suffix nm "TRACED")) (pos 0 (1+ pos)) obj) ((= pos length) (error (TEXT "~S: no local name ~S in ~S") 'local name closure)) (setq obj (sys::closure-const closure pos)) (when (and (closurep obj) (or (eq (closure-name obj) nm) (eq (closure-name obj) nm-traced))) (return pos)))) (force-cclosure (name) (let ((closure (fdefinition name))) (unless (closurep closure) (error-of-type 'type-error :datum closure :expected-type 'closure (TEXT "~S: ~S does not name a closure") 'local name)) (if (sys::%compiled-function-p closure) closure (fdefinition (compile name closure))))) (local-helper (spec) (do* ((spe (cdr spec) (cdr spe)) (clo (force-cclosure (car spec)) (sys::%record-ref clo pos)) (pos (subclosure-pos clo (car spe)) (subclosure-pos clo (car spe)))) ((endp (cdr spe)) (values clo pos))))) (defun %local-get (spec) ; ABI (multiple-value-bind (clo pos) (local-helper spec) (sys::closure-const clo pos))) (defun %local-set (new-def spec) ; ABI (unless (closurep new-def) (error-of-type 'type-error :datum new-def :expected-type 'closure (TEXT "~S: ~S is not a closure") `(setf (local ,@spec)) new-def)) (multiple-value-bind (clo pos) (local-helper spec) (setf (sys::closure-const clo pos) (if (sys::%compiled-function-p new-def) new-def (fdefinition (compile (closure-name (sys::%record-ref clo pos)) new-def))))))) (defmacro local (&rest spec) "Return the closure defined locally with LABELS or FLET. SPEC is a list of (CLOSURE SUB-CLOSURE SUB-SUB-CLOSURE ...) CLOSURE must be compiled." (%local-get spec)) (define-setf-expander local (&rest spec) "Modify the local definition (LABELS or FLET). This will not work with closures that use lexical variables!" (let ((store (gensym "LOCAL-"))) (values nil nil `(,store) `(%LOCAL-SET ,store ',spec) `(%LOCAL-GET ,spec)))) ;; check whether the object might name a local (LABELS or FLET) function (defun local-function-name-p (obj) (and (consp obj) (eq 'local (car obj)))) ;; Structure containing all trace options for a given function. ;; make-tracer is ABI (defstruct (tracer (:type vector)) name symb cur-def local-p suppress-if max-depth step-if pre post pre-break-if post-break-if bindings pre-print post-print print) ;; install the new function definition (defun tracer-set-fdef (trr new-fdef) (if (tracer-local-p trr) (%local-set new-fdef (rest (tracer-name trr))) (setf (symbol-function (tracer-symb trr)) new-fdef))) (defmacro trace (&rest funs) "Trace function execution. \(TRACE) returns the list of all traced functions. \(TRACE fun ...) additionally traces the functions fun, ... . Format for fun: Either a function name or a list made of a function-cell and a few keyword arguments (pairwise!) (function-name [:suppress-if form] ; no trace output, as long as form is true [:max-depth form] ; no trace output, as long as (> *trace-level* form) [:step-if form] ; trace moves into the stepper, if form is true [:bindings ((variable form)...)] ; binds variables around the following forms [:pre form] ; executes form before function call [:post form] ; executes form after function call [:pre-break-if form] ; trace moves into break-loop before function call, ; if form is true [:post-break-if form] ; trace moves into break-loop after function call, ; if form is true [:pre-print form] ; prints the values of form before function call [:post-print form] ; prints the values of form after function call [:print form] ; prints the values of form before ; and after the function call ) In all these forms *TRACE-FUNCTION* (the function itself), *TRACE-ARGS* (the function arguments), *TRACE-FORM* (the function-/macro-call as form), and after function call also *TRACE-VALUES* (the list of values of the function call) can be accessed, and the function can be left with RETURN with given values. TRACE and UNTRACE are also applicable to functions (SETF symbol) and macros, however not applicable to locally defined functions and macros." (if (null funs) '*TRACED-FUNCTIONS* `(APPEND ,@(mapcar #'(lambda (fun) `(TRACE1 ,(if (or (function-name-p fun) (local-function-name-p fun)) `(MAKE-TRACER :NAME ',fun) `(APPLY #'MAKE-TRACER :NAME ',fun)))) funs)))) ;; check whether the FUNNAME can be traced, ;; fill SYMB, CUR-DEF and LOCAL-P slots of TRR and return TRR (defun check-traceable (funname trr caller) (tagbody restart (cond ((function-name-p funname) (let ((sym (get-funname-symbol funname))) (unless (fboundp sym) (error (TEXT "~S: undefined function ~S") caller funname)) (when (special-operator-p sym) (error (TEXT "~S: cannot trace special operator ~S") caller funname)) (setf (tracer-symb trr) sym (tracer-cur-def trr) (symbol-function sym) (tracer-local-p trr) nil))) ((local-function-name-p funname) (setf (tracer-cur-def trr) (%local-get (rest funname)) (tracer-symb trr) (closure-name (tracer-cur-def trr)) (tracer-local-p trr) t) (when (get (tracer-symb trr) 'sys::untraced-name) (setf (tracer-symb trr) (get (tracer-symb trr) 'sys::untraced-name)))) (t (setq funname (check-function-name funname caller)) (go restart)))) (check-redefinition funname caller (TEXT "function")) trr) (defun trace1 (trr) ; ABI (check-traceable (tracer-name trr) trr 'trace) (let ((macro-flag (macrop (tracer-cur-def trr))) (sig (when (tracer-local-p trr) (sig-to-list (get-signature (tracer-cur-def trr)))))) (unless (eq (tracer-cur-def trr) ; already traced? (get (tracer-symb trr) 'sys::tracing-definition)) (setf (get (tracer-symb trr) 'sys::traced-definition) (tracer-cur-def trr)) (pushnew (tracer-name trr) *traced-functions* :test #'equal)) (fresh-line) (format t (TEXT ";; Tracing ~:[function~;macro~] ~S.") macro-flag (tracer-name trr)) (setf (get (tracer-symb trr) 'sys::tracing-definition) ;; new function, that replaces the original one: (let ((newname (symbol-suffix (tracer-symb trr) "TRACED")) (body `((declare (inline car cdr cons apply values-list)) (let ((*trace-level* (1+ *trace-level*)) ,@(tracer-bindings trr)) (block nil (unless (or ,(tracer-suppress-if trr) ,(if (tracer-max-depth trr) `(> *trace-level* ,(tracer-max-depth trr)) 'nil)) (trace-pre-output)) ,@(when (tracer-pre-print trr) `((trace-print (multiple-value-list ,(tracer-pre-print trr))))) ,@(when (tracer-print trr) `((trace-print (multiple-value-list ,(tracer-print trr))))) ,(tracer-pre trr) ,@(when (tracer-pre-break-if trr) `((when ,(tracer-pre-break-if trr) (sys::break-loop t)))) (let ((*trace-values* (multiple-value-list ,(if (tracer-local-p trr) `(funcall ,(tracer-cur-def trr) ,@sig) `(if ,(tracer-step-if trr) (trace-step-apply) (apply *trace-function* *trace-args*)))))) ,@(when (tracer-post-break-if trr) `((when ,(tracer-post-break-if trr) (sys::break-loop t)))) ,(tracer-post trr) ,@(when (tracer-print trr) `((trace-print (multiple-value-list ,(tracer-print trr))))) ,@(when (tracer-post-print trr) `((trace-print (multiple-value-list ,(tracer-post-print trr))))) (unless (or ,(tracer-suppress-if trr) ,(if (tracer-max-depth trr) `(> *trace-level* ,(tracer-max-depth trr)) 'nil)) (trace-post-output)) (values-list *trace-values*))))))) (setf (get newname 'sys::untraced-name) (tracer-symb trr)) (macrolet ((f (def) `(fdefinition (compile newname ,def)))) (cond (macro-flag (make-macro (f `(lambda (&rest *trace-args* &aux (*trace-form* (car *trace-args*)) (*trace-function* (macro-expander (get-traced-definition ',(tracer-symb trr))))) ,@body)) (macro-lambda-list (tracer-cur-def trr)))) ((tracer-local-p trr) (f `(lambda ,sig (let* ((*trace-args* (list ,@sig)) (*trace-form* (make-apply-form ',(tracer-name trr) *trace-args*)) (*trace-function* (get-traced-definition ',(tracer-symb trr)))) ,@body)))) (t (f `(lambda (&rest *trace-args* &aux (*trace-form* (make-apply-form ',(tracer-name trr) *trace-args*)) (*trace-function* (get-traced-definition ',(tracer-symb trr)))) ,@body))))))) ;; install the new definition (tracer-set-fdef trr (get (tracer-symb trr) 'sys::tracing-definition)) ;; return the name (list (tracer-name trr)))) ;; auxiliary functions: ;; fetch original function definition: (defun get-traced-definition (symbol) (get symbol 'sys::traced-definition)) ;; apply, but step by step: (defun trace-step-apply () (eval `(step (apply ',*trace-function* ',*trace-args*)))) ;; build Eval-Form, that corresponds to an Apply (approximately) : (defun make-apply-form (funname args) (declare (inline cons mapcar)) (cons funname (mapcar #'(lambda (arg) ;; (list 'quote arg) (cons 'quote (cons arg nil))) args))) ;; Output before call, uses *trace-level* and *trace-form* (defun trace-output () ; common for pre & post (fresh-line *trace-output*) (when *trace-indent* (write-spaces *trace-level* *trace-output*)) (write *trace-level* :stream *trace-output* :base 10 :radix t) (write-string " Trace: " *trace-output*)) (defun trace-pre-output () (trace-output) (prin1 *trace-form* *trace-output*) (elastic-newline *trace-output*)) ;; Output after call, uses *trace-level*, *trace-form* and *trace-values* (defun trace-post-output () (declare (inline car cdr consp atom)) (trace-output) (write (car *trace-form*) :stream *trace-output*) (write-string " ==> " *trace-output*) (trace-print *trace-values* nil)) ;; Output of a list of values: (defun trace-print (vals &optional (nl-flag t)) (when nl-flag (fresh-line *trace-output*)) (when (consp vals) (loop (prin1 (pop vals) *trace-output*) (when (atom vals) (return)) (write-string ", " *trace-output*))) (elastic-newline *trace-output*)) (defmacro untrace (&rest funs) "(UNTRACE) returns the list of traced functions, stops tracing all of them. \(UNTRACE symbol ...) removes symbol, ... from the list of traced functions." `(MAPCAN #'UNTRACE1 ,(if (null funs) `(COPY-LIST *TRACED-FUNCTIONS*) `',funs))) (defun untrace1 (funname) ; ABI (let* ((trr (check-traceable funname (make-tracer :name funname) 'untrace)) (symbol (tracer-symb trr)) (old-definition (get symbol 'sys::traced-definition))) (prog1 (if old-definition ;; symbol was traced (progn (if (eq (tracer-cur-def trr) (get symbol 'sys::tracing-definition)) (tracer-set-fdef trr old-definition) (warn (TEXT "~S: ~S was traced and has been redefined!") 'untrace funname)) `(,funname)) ;; funname was not traced '()) (untrace2 trr)))) (defun untrace2 (funname) ;; funname can be either a tracer (from untrace1) ;; or a function name (from remove-old-definitions) (let ((symbol (if (vectorp funname) (tracer-symb funname) (get-funname-symbol funname)))) (remprop symbol 'sys::traced-definition) (remprop symbol 'sys::tracing-definition)) (setq *traced-functions* (delete (if (vectorp funname) (tracer-name funname) funname) *traced-functions* :test #'equal)))