2 Unix SMB/CIFS implementation.
3 Samba utility functions
4 Copyright (C) Andrew Tridgell 1992-1998
5 Copyright (C) Elrond 2002
6 Copyright (C) Simo Sorce 2002
8 This program is free software; you can redistribute it and/or modify
9 it under the terms of the GNU General Public License as published by
10 the Free Software Foundation; either version 2 of the License, or
11 (at your option) any later version.
13 This program is distributed in the hope that it will be useful,
14 but WITHOUT ANY WARRANTY; without even the implied warranty of
15 MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the
16 GNU General Public License for more details.
18 You should have received a copy of the GNU General Public License
19 along with this program; if not, write to the Free Software
20 Foundation, Inc., 675 Mass Ave, Cambridge, MA 02139, USA.
25 /* -------------------------------------------------------------------------- **
28 * FORMAT_BUFR_MAX - Index of the last byte of the format buffer;
29 * format_bufr[FORMAT_BUFR_MAX] should always be reserved
30 * for a terminating null byte.
33 #define FORMAT_BUFR_MAX ( sizeof( format_bufr ) - 1 )
35 /* -------------------------------------------------------------------------- **
36 * This module implements Samba's debugging utility.
38 * The syntax of a debugging log file is represented as:
40 * <debugfile> :== { <debugmsg> }
42 * <debugmsg> :== <debughdr> '\n' <debugtext>
44 * <debughdr> :== '[' TIME ',' LEVEL ']' [ [FILENAME ':'] [FUNCTION '()'] ]
46 * <debugtext> :== { <debugline> }
48 * <debugline> :== TEXT '\n'
50 * TEXT is a string of characters excluding the newline character.
51 * LEVEL is the DEBUG level of the message (an integer in the range 0..10).
52 * TIME is a timestamp.
53 * FILENAME is the name of the file from which the debug message was generated.
54 * FUNCTION is the function from which the debug message was generated.
56 * Basically, what that all means is:
58 * - A debugging log file is made up of debug messages.
60 * - Each debug message is made up of a header and text. The header is
61 * separated from the text by a newline.
63 * - The header begins with the timestamp and debug level of the message
64 * enclosed in brackets. The filename and function from which the
65 * message was generated may follow. The filename is terminated by a
66 * colon, and the function name is terminated by parenthesis.
68 * - The message text is made up of zero or more lines, each terminated by
72 /* -------------------------------------------------------------------------- **
75 * dbf - Global debug file handle.
76 * debugf - Debug file name.
77 * append_log - If True, then the output file will be opened in append
79 * DEBUGLEVEL - System-wide debug message limit. Messages with message-
80 * levels higher than DEBUGLEVEL will not be processed.
85 BOOL append_log
= False
;
86 BOOL debug_warn_unknown_class
= True
;
87 BOOL debug_auto_add_unknown_class
= True
;
88 BOOL AllowDebugChange
= True
;
91 * This is to allow assignment to DEBUGLEVEL before the debug
92 * system has been initialised.
94 static int debug_all_class_hack
= 1;
95 static BOOL debug_all_class_isset_hack
= True
;
97 static int debug_num_classes
= 0;
98 int *DEBUGLEVEL_CLASS
= &debug_all_class_hack
;
99 BOOL
*DEBUGLEVEL_CLASS_ISSET
= &debug_all_class_isset_hack
;
101 /* DEBUGLEVEL is #defined to *debug_level */
102 int DEBUGLEVEL
= &debug_all_class_hack
;
105 /* -------------------------------------------------------------------------- **
106 * Internal variables.
108 * stdout_logging - Default False, if set to True then dbf will be set to
109 * stdout and debug output will go to dbf only, and not
110 * to syslog. Set in setup_logging() and read in Debug1().
112 * debug_count - Number of debug messages that have been output.
113 * Used to check log size.
115 * syslog_level - Internal copy of the message debug level. Written by
116 * dbghdr() and read by Debug1().
118 * format_bufr - Used to format debug messages. The dbgtext() function
119 * prints debug messages to a string, and then passes the
120 * string to format_debug_text(), which uses format_bufr
121 * to build the formatted output.
123 * format_pos - Marks the first free byte of the format_bufr.
126 * log_overflow - When this variable is True, never attempt to check the
127 * size of the log. This is a hack, so that we can write
128 * a message using DEBUG, from open_logs() when we
129 * are unable to open a new log file for some reason.
132 static BOOL stdout_logging
= False
;
133 static int debug_count
= 0;
135 static int syslog_level
= 0;
137 static pstring format_bufr
= { '\0' };
138 static size_t format_pos
= 0;
139 static BOOL log_overflow
= False
;
142 * Define all the debug class selection names here. Names *MUST NOT* contain
143 * white space. There must be one name for each DBGC_<class name>, and they
144 * must be in the table in the order of DBGC_<class name>..
146 static const char *default_classname_table
[] = {
147 "all", /* DBGC_ALL; index refs traditional DEBUGLEVEL */
148 "tdb", /* DBGC_TDB */
149 "printdrivers", /* DBGC_PRINTDRIVERS */
150 "lanman", /* DBGC_LANMAN */
151 "smb", /* DBGC_SMB */
152 "rpc_parse", /* DBGC_RPC_PARSE */
153 "rpc_srv", /* DBGC_RPC_SRV */
154 "rpc_cli", /* DBGC_RPC_CLI */
155 "passdb", /* DBGC_PASSDB */
156 "sam", /* DBGC_SAM */
157 "auth", /* DBGC_AUTH */
158 "winbind", /* DBGC_WINBIND */
159 "vfs", /* DBGC_VFS */
163 static char **classname_table
= NULL
;
166 /* -------------------------------------------------------------------------- **
171 /****************************************************************************
172 utility lists registered debug class names's
173 ****************************************************************************/
175 #define MAX_CLASS_NAME_SIZE 1024
177 static char *debug_list_class_names_and_levels(void)
185 if (DEBUGLEVEL_CLASS
== &debug_all_class_hack
)
188 list
= calloc(debug_num_classes
+ 1, sizeof(char *));
192 /* prepare strings */
193 for (i
= 0, dim
= 0; i
< debug_num_classes
; i
++) {
194 int l
= asprintf(&list
[i
],
197 DEBUGLEVEL_CLASS_ISSET
[i
]?DEBUGLEVEL_CLASS
[i
]:DEBUGLEVEL
);
198 if (l
< 0 || l
> MAX_CLASS_NAME_SIZE
) {
205 /* create single string list */
206 b
= buf
= malloc(dim
);
211 for (i
= 0; i
< debug_num_classes
; i
++) {
212 int l
= strlen(list
[i
]);
213 strncpy(b
, list
[i
], l
);
219 /* free strings list */
220 for (i
= 0; i
< debug_num_classes
; i
++)
221 if (list
[i
]) free(list
[i
]);
233 /****************************************************************************
234 utility access to debug class names's
235 ****************************************************************************/
236 const char *debug_classname_from_index(int ndx
)
238 if (ndx
< 0 || ndx
>= debug_num_classes
)
241 return classname_table
[ndx
];
244 /****************************************************************************
245 utility to translate names to debug class index's (internal version)
246 ****************************************************************************/
247 static int debug_lookup_classname_int(const char* classname
)
251 if (!classname
) return -1;
253 for (i
=0; i
< debug_num_classes
; i
++) {
254 if (strcmp(classname
, classname_table
[i
])==0)
260 /****************************************************************************
261 Add a new debug class to the system
262 ****************************************************************************/
263 int debug_add_class(const char *classname
)
271 /* check the init has yet been called */
274 ndx
= debug_lookup_classname_int(classname
);
277 ndx
= debug_num_classes
;
279 new_ptr
= DEBUGLEVEL_CLASS
;
280 if (DEBUGLEVEL_CLASS
== &debug_all_class_hack
)
282 /* Initial loading... */
285 new_ptr
= Realloc(new_ptr
,
286 sizeof(int) * (debug_num_classes
+ 1));
289 DEBUGLEVEL_CLASS
= new_ptr
;
290 DEBUGLEVEL_CLASS
[ndx
] = 0;
292 /* debug_level is the pointer used for the DEBUGLEVEL-thingy */
295 /* Transfer the initial level from debug_all_class_hack */
296 DEBUGLEVEL_CLASS
[ndx
] = DEBUGLEVEL
;
298 debug_level
= DEBUGLEVEL_CLASS
;
300 new_ptr
= DEBUGLEVEL_CLASS_ISSET
;
301 if (new_ptr
== &debug_all_class_isset_hack
)
305 new_ptr
= Realloc(new_ptr
,
306 sizeof(BOOL
) * (debug_num_classes
+ 1));
309 DEBUGLEVEL_CLASS_ISSET
= new_ptr
;
310 DEBUGLEVEL_CLASS_ISSET
[ndx
] = False
;
312 new_ptr
= Realloc(classname_table
,
313 sizeof(char *) * (debug_num_classes
+ 1));
316 classname_table
= new_ptr
;
318 classname_table
[ndx
] = strdup(classname
);
319 if (! classname_table
[ndx
])
327 /****************************************************************************
328 utility to translate names to debug class index's (public version)
329 ****************************************************************************/
330 int debug_lookup_classname(const char *classname
)
334 if (!classname
|| !*classname
) return -1;
336 ndx
= debug_lookup_classname_int(classname
);
341 if (debug_warn_unknown_class
)
343 DEBUG(0, ("debug_lookup_classname(%s): Unknown class\n",
346 if (debug_auto_add_unknown_class
)
348 return debug_add_class(classname
);
354 /****************************************************************************
355 dump the current registered debug levels
356 ****************************************************************************/
357 static void debug_dump_status(int level
)
361 DEBUG(level
, ("INFO: Current debug levels:\n"));
362 for (q
= 0; q
< debug_num_classes
; q
++)
364 DEBUGADD(level
, (" %s: %s/%d\n",
366 (DEBUGLEVEL_CLASS_ISSET
[q
]
368 DEBUGLEVEL_CLASS
[q
]));
372 /****************************************************************************
373 parse the debug levels from smbcontrol. Example debug level parameter:
375 ****************************************************************************/
376 static BOOL
debug_parse_params(char **params
)
385 /* Allow DBGC_ALL to be specified w/o requiring its class name e.g."10"
386 * v.s. "all:10", this is the traditional way to set DEBUGLEVEL
388 if (isdigit((int)params
[0][0])) {
389 DEBUGLEVEL_CLASS
[DBGC_ALL
] = atoi(params
[0]);
390 DEBUGLEVEL_CLASS_ISSET
[DBGC_ALL
] = True
;
391 i
= 1; /* start processing at the next params */
394 i
= 0; /* DBGC_ALL not specified OR class name was included */
396 /* Fill in new debug class levels */
397 for (; i
< debug_num_classes
&& params
[i
]; i
++) {
398 if ((class_name
=strtok(params
[i
],":")) &&
399 (class_level
=strtok(NULL
, "\0")) &&
400 ((ndx
= debug_lookup_classname(class_name
)) != -1)) {
401 DEBUGLEVEL_CLASS
[ndx
] = atoi(class_level
);
402 DEBUGLEVEL_CLASS_ISSET
[ndx
] = True
;
404 DEBUG(0,("debug_parse_params: unrecognized debug class name or format [%s]\n", params
[i
]));
412 /****************************************************************************
413 parse the debug levels from smb.conf. Example debug level string:
414 3 tdb:5 printdrivers:7
415 Note: the 1st param has no "name:" preceeding it.
416 ****************************************************************************/
417 BOOL
debug_parse_levels(const char *params_str
)
424 if (AllowDebugChange
== False
)
427 params
= str_list_make(params_str
, NULL
);
429 if (debug_parse_params(params
))
431 debug_dump_status(5);
432 str_list_free(¶ms
);
435 str_list_free(¶ms
);
440 /****************************************************************************
441 receive a "set debug level" message
442 ****************************************************************************/
443 static void debug_message(int msg_type
, pid_t src
, void *buf
, size_t len
)
445 const char *params_str
= buf
;
447 /* Check, it's a proper string! */
448 if (params_str
[len
-1] != '\0')
450 DEBUG(1, ("Invalid debug message from pid %u to pid %u\n",
451 (unsigned int)src
, (unsigned int)getpid()));
455 DEBUG(3, ("INFO: Remote set of debug to `%s' (pid %u from pid %u)\n",
456 params_str
, (unsigned int)getpid(), (unsigned int)src
));
458 debug_parse_levels(params_str
);
462 /****************************************************************************
463 send a "set debug level" message
464 ****************************************************************************/
465 void debug_message_send(pid_t pid
, const char *params_str
)
469 message_send_pid(pid
, MSG_DEBUG
, params_str
, strlen(params_str
) + 1,
474 /****************************************************************************
475 Return current debug level.
476 ****************************************************************************/
478 static void debuglevel_message(int msg_type
, pid_t src
, void *buf
, size_t len
)
480 char *debug_level_classes
;
481 DEBUG(1,("INFO: Received REQ_DEBUGLEVEL message from PID %u\n",(unsigned int)src
));
483 if ((debug_level_classes
= debug_list_class_names_and_levels())) {
484 /*{ debug_level_classes = "test:1000";*/
485 message_send_pid(src
, MSG_DEBUGLEVEL
, debug_level_classes
, strlen(debug_level_classes
) + 1, True
);
486 SAFE_FREE(debug_level_classes
);
488 DEBUG(0, ("debuglevel_message: error retrieving class levels!\n"));
492 /****************************************************************************
493 Init debugging (one time stuff)
494 ****************************************************************************/
495 void debug_init(void)
497 static BOOL initialised
= False
;
505 message_register(MSG_DEBUG
, debug_message
);
506 message_register(MSG_REQ_DEBUGLEVEL
, debuglevel_message
);
508 for(p
= default_classname_table
; *p
; p
++)
515 /* ************************************************************************** **
516 * get ready for syslog stuff
517 * ************************************************************************** **
519 void setup_logging(const char *pname
, BOOL interactive
)
523 /* reset to allow multiple setup calls, going from interactive to
525 stdout_logging
= False
;
529 stdout_logging
= True
;
534 const char *p
= strrchr_m( pname
,'/' );
538 openlog( pname
, LOG_PID
, SYSLOG_FACILITY
);
540 /* for old systems that have no facility codes. */
541 openlog( pname
, LOG_PID
);
545 } /* setup_logging */
547 /* ************************************************************************** **
548 * reopen the log files
549 * note that we now do this unconditionally
550 * We attempt to open the new debug fp before closing the old. This means
551 * if we run out of fd's we just keep using the old fd rather than aborting.
552 * Fix from dgibson@linuxcare.com.
553 * ************************************************************************** **
556 BOOL
reopen_logs( void )
560 XFILE
*new_dbf
= NULL
;
566 oldumask
= umask( 022 );
568 pstrcpy(fname
, debugf
);
573 logfname
= lp_logfile();
575 pstrcpy(fname
, logfname
);
578 pstrcpy( debugf
, fname
);
580 new_dbf
= x_fopen( debugf
, O_WRONLY
|O_APPEND
|O_CREAT
, 0644);
582 new_dbf
= x_fopen( debugf
, O_WRONLY
|O_CREAT
|O_TRUNC
, 0644 );
586 DEBUG(0, ("Unable to open new log file %s: %s\n", debugf
, strerror(errno
)));
587 log_overflow
= False
;
592 x_setbuf(new_dbf
, NULL
);
594 (void) x_fclose(dbf
);
598 /* Fix from klausr@ITAP.Physik.Uni-Stuttgart.De
599 * to fix problem where smbd's that generate less
600 * than 100 messages keep growing the log.
602 force_check_log_size();
603 (void)umask(oldumask
);
605 /* Take over stderr to catch ouput into logs */
606 if (dbf
&& sys_dup2(dbf
->fd
, 2) == -1) {
607 close_low_fds(True
); /* Close stderr too, if dup2 can't point it
614 /* ************************************************************************** **
615 * Force a check of the log size.
616 * ************************************************************************** **
618 void force_check_log_size( void )
623 /***************************************************************************
624 Check to see if there is any need to check if the logfile has grown too big.
625 **************************************************************************/
627 BOOL
need_to_check_log_size( void )
631 if( debug_count
++ < 100 )
634 maxlog
= lp_max_log_size() * 1024;
635 if( !dbf
|| maxlog
<= 0 ) {
642 /* ************************************************************************** **
643 * Check to see if the log has grown to be too big.
644 * ************************************************************************** **
647 void check_log_size( void )
653 * We need to be root to check/change log-file, skip this and let the main
654 * loop check do a new check as root.
660 if(log_overflow
|| !need_to_check_log_size() )
663 maxlog
= lp_max_log_size() * 1024;
665 if( sys_fstat( x_fileno( dbf
), &st
) == 0 && st
.st_size
> maxlog
) {
667 if( dbf
&& get_file_size( debugf
) > maxlog
) {
670 slprintf( name
, sizeof(name
)-1, "%s.old", debugf
);
671 (void)rename( debugf
, name
);
673 if (!reopen_logs()) {
674 /* We failed to reopen a log - continue using the old name. */
675 (void)rename(name
, debugf
);
681 * Here's where we need to panic if dbf == NULL..
685 /* This code should only be reached in very strange
686 * circumstances. If we merely fail to open the new log we
687 * should stick with the old one. ergo this should only be
688 * reached when opening the logs for the first time: at
689 * startup or when the log level is increased from zero.
692 dbf
= x_fopen( "/dev/console", O_WRONLY
, 0);
694 DEBUG(0,("check_log_size: open of debug file %s failed - using console.\n",
698 * We cannot continue without a debug file handle.
704 } /* check_log_size */
706 /* ************************************************************************** **
707 * Write an debug message on the debugfile.
708 * This is called by dbghdr() and format_debug_text().
709 * ************************************************************************** **
711 int Debug1( char *format_str
, ... )
714 int old_errno
= errno
;
718 va_start( ap
, format_str
);
720 (void)x_vfprintf( dbf
, format_str
, ap
);
727 if( !lp_syslog_only() )
732 mode_t oldumask
= umask( 022 );
735 dbf
= x_fopen( debugf
, O_WRONLY
|O_APPEND
|O_CREAT
, 0644 );
737 dbf
= x_fopen( debugf
, O_WRONLY
|O_CREAT
|O_TRUNC
, 0644 );
738 (void)umask( oldumask
);
741 x_setbuf( dbf
, NULL
);
752 if( syslog_level
< lp_syslog() )
754 /* map debug levels to syslog() priorities
755 * note that not all DEBUG(0, ...) calls are
758 static int priority_map
[] = {
767 if( syslog_level
>= ( sizeof(priority_map
) / sizeof(priority_map
[0]) )
769 priority
= LOG_DEBUG
;
771 priority
= priority_map
[syslog_level
];
773 va_start( ap
, format_str
);
774 vslprintf( msgbuf
, sizeof(msgbuf
)-1, format_str
, ap
);
778 syslog( priority
, "%s", msgbuf
);
785 if( !lp_syslog_only() )
788 va_start( ap
, format_str
);
790 (void)x_vfprintf( dbf
, format_str
, ap
);
793 (void)x_fflush( dbf
);
802 /* ************************************************************************** **
803 * Print the buffer content via Debug1(), then reset the buffer.
808 * ************************************************************************** **
810 static void bufr_print( void )
812 format_bufr
[format_pos
] = '\0';
813 (void)Debug1( "%s", format_bufr
);
817 /* ************************************************************************** **
818 * Format the debug message text.
820 * Input: msg - Text to be added to the "current" debug message text.
824 * Notes: The purpose of this is two-fold. First, each call to syslog()
825 * (used by Debug1(), see above) generates a new line of syslog
826 * output. This is fixed by storing the partial lines until the
827 * newline character is encountered. Second, printing the debug
828 * message lines when a newline is encountered allows us to add
829 * spaces, thus indenting the body of the message and making it
832 * ************************************************************************** **
834 static void format_debug_text( char *msg
)
837 BOOL timestamp
= (!stdout_logging
&& (lp_timestamp_logs() ||
840 for( i
= 0; msg
[i
]; i
++ )
842 /* Indent two spaces at each new line. */
843 if(timestamp
&& 0 == format_pos
)
845 format_bufr
[0] = format_bufr
[1] = ' ';
849 /* If there's room, copy the character to the format buffer. */
850 if( format_pos
< FORMAT_BUFR_MAX
)
851 format_bufr
[format_pos
++] = msg
[i
];
853 /* If a newline is encountered, print & restart. */
857 /* If the buffer is full dump it out, reset it, and put out a line
858 * continuation indicator.
860 if( format_pos
>= FORMAT_BUFR_MAX
)
863 (void)Debug1( " +>\n" );
867 /* Just to be safe... */
868 format_bufr
[format_pos
] = '\0';
869 } /* format_debug_text */
871 /* ************************************************************************** **
872 * Flush debug output, including the format buffer content.
877 * ************************************************************************** **
879 void dbgflush( void )
883 (void)x_fflush( dbf
);
886 /* ************************************************************************** **
887 * Print a Debug Header.
889 * Input: level - Debug level of the message (not the system-wide debug
891 * file - Pointer to a string containing the name of the file
892 * from which this function was called, or an empty string
893 * if the __FILE__ macro is not implemented.
894 * func - Pointer to a string containing the name of the function
895 * from which this function was called, or an empty string
896 * if the __FUNCTION__ macro is not implemented.
897 * line - line number of the call to dbghdr, assuming __LINE__
900 * Output: Always True. This makes it easy to fudge a call to dbghdr()
901 * in a macro, since the function can be called as part of a test.
902 * Eg: ( (level <= DEBUGLEVEL) && (dbghdr(level,"",line)) )
904 * Notes: This function takes care of setting syslog_level.
906 * ************************************************************************** **
909 BOOL
dbghdr( int level
, char *file
, char *func
, int line
)
911 /* Ensure we don't lose any real errno value. */
912 int old_errno
= errno
;
915 /* This is a fudge. If there is stuff sitting in the format_bufr, then
916 * the *right* thing to do is to call
917 * format_debug_text( "\n" );
918 * to write the remainder, and then proceed with the new header.
919 * Unfortunately, there are several places in the code at which
920 * the DEBUG() macro is used to build partial lines. That in mind,
921 * we'll work under the assumption that an incomplete line indicates
922 * that a new header is *not* desired.
928 /* Set syslog_level. */
929 syslog_level
= level
;
932 /* Don't print a header if we're logging to stdout. */
936 /* Print the header if timestamps are turned on. If parameters are
937 * not yet loaded, then default to timestamps on.
939 if( lp_timestamp_logs() || !(lp_loaded()) ) {
940 char header_str
[200];
942 header_str
[0] = '\0';
945 slprintf(header_str
,sizeof(header_str
)-1,", pid=%u",(unsigned int)sys_getpid());
947 if( lp_debug_uid()) {
948 size_t hs_len
= strlen(header_str
);
949 slprintf(header_str
+ hs_len
,
950 sizeof(header_str
) - 1 - hs_len
,
951 ", effective(%u, %u), real(%u, %u)",
952 (unsigned int)geteuid(), (unsigned int)getegid(),
953 (unsigned int)getuid(), (unsigned int)getgid());
956 /* Print it all out at once to prevent split syslog output. */
957 (void)Debug1( "[%s, %d%s] %s:%s(%d)\n",
958 timestring(lp_debug_hires_timestamp()), level
,
959 header_str
, file
, func
, line
);
966 /* ************************************************************************** **
967 * Add text to the body of the "current" debug message via the format buffer.
969 * Input: format_str - Format string, as used in printf(), et. al.
970 * ... - Variable argument list.
972 * ..or.. va_alist - Old style variable parameter list starting point.
974 * Output: Always True. See dbghdr() for more info, though this is not
975 * likely to be used in the same way.
977 * ************************************************************************** **
979 BOOL
dbgtext( char *format_str
, ... )
984 va_start( ap
, format_str
);
985 vslprintf( msgbuf
, sizeof(msgbuf
)-1, format_str
, ap
);
988 format_debug_text( msgbuf
);
994 /* ************************************************************************** */