291 lines
		
	
	
		
			8.3 KiB
		
	
	
	
		
			C
		
	
	
			
		
		
	
	
			291 lines
		
	
	
		
			8.3 KiB
		
	
	
	
		
			C
		
	
	
| #ifndef TRACE_H
 | |
| #define TRACE_H
 | |
| 
 | |
| #include "git-compat-util.h"
 | |
| #include "strbuf.h"
 | |
| 
 | |
| /**
 | |
|  * The trace API can be used to print debug messages to stderr or a file. Trace
 | |
|  * code is inactive unless explicitly enabled by setting `GIT_TRACE*` environment
 | |
|  * variables.
 | |
|  *
 | |
|  * The trace implementation automatically adds `timestamp file:line ... \n` to
 | |
|  * all trace messages. E.g.:
 | |
|  *
 | |
|  * ------------
 | |
|  * 23:59:59.123456 git.c:312               trace: built-in: git 'foo'
 | |
|  * 00:00:00.000001 builtin/foo.c:99        foo: some message
 | |
|  * ------------
 | |
|  *
 | |
|  * Bugs & Caveats
 | |
|  * --------------
 | |
|  *
 | |
|  * GIT_TRACE_* environment variables can be used to tell Git to show
 | |
|  * trace output to its standard error stream. Git can often spawn a pager
 | |
|  * internally to run its subcommand and send its standard output and
 | |
|  * standard error to it.
 | |
|  *
 | |
|  * Because GIT_TRACE_PERFORMANCE trace is generated only at the very end
 | |
|  * of the program with atexit(), which happens after the pager exits, it
 | |
|  * would not work well if you send its log to the standard error output
 | |
|  * and let Git spawn the pager at the same time.
 | |
|  *
 | |
|  * As a work around, you can for example use '--no-pager', or set
 | |
|  * GIT_TRACE_PERFORMANCE to another file descriptor which is redirected
 | |
|  * to stderr, or set GIT_TRACE_PERFORMANCE to a file specified by its
 | |
|  * absolute path.
 | |
|  *
 | |
|  * For example instead of the following command which by default may not
 | |
|  * print any performance information:
 | |
|  *
 | |
|  * ------------
 | |
|  * GIT_TRACE_PERFORMANCE=2 git log -1
 | |
|  * ------------
 | |
|  *
 | |
|  * you may want to use:
 | |
|  *
 | |
|  * ------------
 | |
|  * GIT_TRACE_PERFORMANCE=2 git --no-pager log -1
 | |
|  * ------------
 | |
|  *
 | |
|  * or:
 | |
|  *
 | |
|  * ------------
 | |
|  * GIT_TRACE_PERFORMANCE=3 3>&2 git log -1
 | |
|  * ------------
 | |
|  *
 | |
|  * or:
 | |
|  *
 | |
|  * ------------
 | |
|  * GIT_TRACE_PERFORMANCE=/path/to/log/file git log -1
 | |
|  * ------------
 | |
|  *
 | |
|  */
 | |
| 
 | |
| /**
 | |
|  * Defines a trace key (or category). The default (for API functions that
 | |
|  * don't take a key) is `GIT_TRACE`.
 | |
|  *
 | |
|  * E.g. to define a trace key controlled by environment variable `GIT_TRACE_FOO`:
 | |
|  *
 | |
|  * ------------
 | |
|  * static struct trace_key trace_foo = TRACE_KEY_INIT(FOO);
 | |
|  *
 | |
|  * static void trace_print_foo(const char *message)
 | |
|  * {
 | |
|  * 	trace_printf_key(&trace_foo, "%s", message);
 | |
|  * }
 | |
|  * ------------
 | |
|  *
 | |
|  * Note: don't use `const` as the trace implementation stores internal state in
 | |
|  * the `trace_key` structure.
 | |
|  */
 | |
| struct trace_key {
 | |
| 	const char * const key;
 | |
| 	int fd;
 | |
| 	unsigned int initialized : 1;
 | |
| 	unsigned int  need_close : 1;
 | |
| };
 | |
| 
 | |
| extern struct trace_key trace_default_key;
 | |
| 
 | |
| #define TRACE_KEY_INIT(name) { "GIT_TRACE_" #name, 0, 0, 0 }
 | |
| extern struct trace_key trace_perf_key;
 | |
| extern struct trace_key trace_setup_key;
 | |
| 
 | |
| void trace_repo_setup(const char *prefix);
 | |
| 
 | |
| /**
 | |
|  * Checks whether the trace key is enabled. Used to prevent expensive
 | |
|  * string formatting before calling one of the printing APIs.
 | |
|  */
 | |
| int trace_want(struct trace_key *key);
 | |
| 
 | |
| /**
 | |
|  * Enables or disables tracing for the specified key, as if the environment
 | |
|  * variable was set to the given value.
 | |
|  */
 | |
| void trace_override_envvar(struct trace_key *key, const char *value);
 | |
| 
 | |
| /**
 | |
|  * Disables tracing for the specified key, even if the environment variable
 | |
|  * was set.
 | |
|  */
 | |
| void trace_disable(struct trace_key *key);
 | |
| 
 | |
| /**
 | |
|  * Returns nanoseconds since the epoch (01/01/1970), typically used
 | |
|  * for performance measurements.
 | |
|  * Currently there are high precision timer implementations for Linux (using
 | |
|  * `clock_gettime(CLOCK_MONOTONIC)`) and Windows (`QueryPerformanceCounter`).
 | |
|  * Other platforms use `gettimeofday` as time source.
 | |
|  */
 | |
| uint64_t getnanotime(void);
 | |
| 
 | |
| void trace_command_performance(const char **argv);
 | |
| void trace_verbatim(struct trace_key *key, const void *buf, unsigned len);
 | |
| uint64_t trace_performance_enter(void);
 | |
| 
 | |
| #ifndef HAVE_VARIADIC_MACROS
 | |
| 
 | |
| /**
 | |
|  * Prints a formatted message, similar to printf.
 | |
|  */
 | |
| __attribute__((format (printf, 1, 2)))
 | |
| void trace_printf(const char *format, ...);
 | |
| 
 | |
| __attribute__((format (printf, 2, 3)))
 | |
| void trace_printf_key(struct trace_key *key, const char *format, ...);
 | |
| 
 | |
| /**
 | |
|  * Prints a formatted message, followed by a quoted list of arguments.
 | |
|  */
 | |
| __attribute__((format (printf, 2, 3)))
 | |
| void trace_argv_printf(const char **argv, const char *format, ...);
 | |
| 
 | |
| /**
 | |
|  * Prints the strbuf, without additional formatting (i.e. doesn't
 | |
|  * choke on `%` or even `\0`).
 | |
|  */
 | |
| void trace_strbuf(struct trace_key *key, const struct strbuf *data);
 | |
| 
 | |
| /**
 | |
|  * Prints elapsed time (in nanoseconds) if GIT_TRACE_PERFORMANCE is enabled.
 | |
|  *
 | |
|  * Example:
 | |
|  * ------------
 | |
|  * uint64_t t = 0;
 | |
|  * for (;;) {
 | |
|  * 	// ignore
 | |
|  * t -= getnanotime();
 | |
|  * // code section to measure
 | |
|  * t += getnanotime();
 | |
|  * // ignore
 | |
|  * }
 | |
|  * trace_performance(t, "frotz");
 | |
|  * ------------
 | |
|  */
 | |
| __attribute__((format (printf, 2, 3)))
 | |
| void trace_performance(uint64_t nanos, const char *format, ...);
 | |
| 
 | |
| /**
 | |
|  * Prints elapsed time since 'start' if GIT_TRACE_PERFORMANCE is enabled.
 | |
|  *
 | |
|  * Example:
 | |
|  * ------------
 | |
|  * uint64_t start = getnanotime();
 | |
|  * // code section to measure
 | |
|  * trace_performance_since(start, "foobar");
 | |
|  * ------------
 | |
|  */
 | |
| __attribute__((format (printf, 2, 3)))
 | |
| void trace_performance_since(uint64_t start, const char *format, ...);
 | |
| 
 | |
| __attribute__((format (printf, 1, 2)))
 | |
| void trace_performance_leave(const char *format, ...);
 | |
| 
 | |
| #else
 | |
| 
 | |
| /*
 | |
|  * Macros to add file:line - see above for C-style declarations of how these
 | |
|  * should be used.
 | |
|  */
 | |
| 
 | |
| /*
 | |
|  * TRACE_CONTEXT may be set to __FUNCTION__ if the compiler supports it. The
 | |
|  * default is __FILE__, as it is consistent with assert(), and static function
 | |
|  * names are not necessarily unique.
 | |
|  *
 | |
|  * __FILE__ ":" __FUNCTION__ doesn't work with GNUC, as __FILE__ is supplied
 | |
|  * by the preprocessor as a string literal, and __FUNCTION__ is filled in by
 | |
|  * the compiler as a string constant.
 | |
|  */
 | |
| #ifndef TRACE_CONTEXT
 | |
| # define TRACE_CONTEXT __FILE__
 | |
| #endif
 | |
| 
 | |
| /*
 | |
|  * Note: with C99 variadic macros, __VA_ARGS__ must include the last fixed
 | |
|  * parameter ('format' in this case). Otherwise, a call without variable
 | |
|  * arguments will have a surplus ','. E.g.:
 | |
|  *
 | |
|  *  #define foo(format, ...) bar(format, __VA_ARGS__)
 | |
|  *  foo("test");
 | |
|  *
 | |
|  * will expand to
 | |
|  *
 | |
|  *  bar("test",);
 | |
|  *
 | |
|  * which is invalid (note the ',)'). With GNUC, '##__VA_ARGS__' drops the
 | |
|  * comma, but this is non-standard.
 | |
|  */
 | |
| 
 | |
| #define trace_printf_key(key, ...)					    \
 | |
| 	do {								    \
 | |
| 		if (trace_pass_fl(key))					    \
 | |
| 			trace_printf_key_fl(TRACE_CONTEXT, __LINE__, key,   \
 | |
| 					    __VA_ARGS__);		    \
 | |
| 	} while (0)
 | |
| 
 | |
| #define trace_printf(...) trace_printf_key(&trace_default_key, __VA_ARGS__)
 | |
| 
 | |
| #define trace_argv_printf(argv, ...)					    \
 | |
| 	do {								    \
 | |
| 		if (trace_pass_fl(&trace_default_key))			    \
 | |
| 			trace_argv_printf_fl(TRACE_CONTEXT, __LINE__,	    \
 | |
| 					    argv, __VA_ARGS__);		    \
 | |
| 	} while (0)
 | |
| 
 | |
| #define trace_strbuf(key, data)						    \
 | |
| 	do {								    \
 | |
| 		if (trace_pass_fl(key))					    \
 | |
| 			trace_strbuf_fl(TRACE_CONTEXT, __LINE__, key, data);\
 | |
| 	} while (0)
 | |
| 
 | |
| #define trace_performance(nanos, ...)					    \
 | |
| 	do {								    \
 | |
| 		if (trace_pass_fl(&trace_perf_key))			    \
 | |
| 			trace_performance_fl(TRACE_CONTEXT, __LINE__, nanos,\
 | |
| 					     __VA_ARGS__);		    \
 | |
| 	} while (0)
 | |
| 
 | |
| #define trace_performance_since(start, ...)				    \
 | |
| 	do {								    \
 | |
| 		if (trace_pass_fl(&trace_perf_key))			    \
 | |
| 			trace_performance_fl(TRACE_CONTEXT, __LINE__,       \
 | |
| 					     getnanotime() - (start),	    \
 | |
| 					     __VA_ARGS__);		    \
 | |
| 	} while (0)
 | |
| 
 | |
| #define trace_performance_leave(...)					    \
 | |
| 	do {								    \
 | |
| 		if (trace_pass_fl(&trace_perf_key))			    \
 | |
| 			trace_performance_leave_fl(TRACE_CONTEXT, __LINE__, \
 | |
| 						   getnanotime(),	    \
 | |
| 						   __VA_ARGS__);	    \
 | |
| 	} while (0)
 | |
| 
 | |
| /* backend functions, use non-*fl macros instead */
 | |
| __attribute__((format (printf, 4, 5)))
 | |
| void trace_printf_key_fl(const char *file, int line, struct trace_key *key,
 | |
| 			 const char *format, ...);
 | |
| __attribute__((format (printf, 4, 5)))
 | |
| void trace_argv_printf_fl(const char *file, int line, const char **argv,
 | |
| 			  const char *format, ...);
 | |
| void trace_strbuf_fl(const char *file, int line, struct trace_key *key,
 | |
| 		     const struct strbuf *data);
 | |
| __attribute__((format (printf, 4, 5)))
 | |
| void trace_performance_fl(const char *file, int line,
 | |
| 			  uint64_t nanos, const char *fmt, ...);
 | |
| __attribute__((format (printf, 4, 5)))
 | |
| void trace_performance_leave_fl(const char *file, int line,
 | |
| 				uint64_t nanos, const char *fmt, ...);
 | |
| static inline int trace_pass_fl(struct trace_key *key)
 | |
| {
 | |
| 	return key->fd || !key->initialized;
 | |
| }
 | |
| 
 | |
| #endif /* HAVE_VARIADIC_MACROS */
 | |
| 
 | |
| #endif /* TRACE_H */
 |