libcfs/libcfs/posix/posix-debug.c

   1 /* -*- mode: c; c-basic-offset: 8; indent-tabs-mode: nil; -*-
   2  * vim:expandtab:shiftwidth=8:tabstop=8:
   3  *
   4  * GPL HEADER START
   5  *
   6  * DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER.
   7  *
   8  * This program is free software; you can redistribute it and/or modify
   9  * it under the terms of the GNU General Public License version 2 only,
  10  * as published by the Free Software Foundation.
  11  *
  12  * This program is distributed in the hope that it will be useful, but
  13  * WITHOUT ANY WARRANTY; without even the implied warranty of
  14  * MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the GNU
  15  * General Public License version 2 for more details (a copy is included
  16  * in the LICENSE file that accompanied this code).
  17  *
  18  * You should have received a copy of the GNU General Public License
  19  * version 2 along with this program; If not, see
  20  * http://www.sun.com/software/products/lustre/docs/GPLv2.pdf
  21  *
  22  * Please contact Sun Microsystems, Inc., 4150 Network Circle, Santa Clara,
  23  * CA 95054 USA or visit www.sun.com if you need additional information or
  24  * have any questions.
  25  *
  26  * GPL HEADER END
  27  */
  28 /*
  29  * Copyright (c) 2008, 2010, Oracle and/or its affiliates. All rights reserved.
  30  * Use is subject to license terms.
  31  */
  32 /*
  33  * This file is part of Lustre, http://www.lustre.org/
  34  * Lustre is a trademark of Sun Microsystems, Inc.
  35  *
  36  * libcfs/libcfs/posix/posix_debug.c
  37  *
  38  * Userspace debugging.
  39  *
  40  */
  41
  42 # define DEBUG_SUBSYSTEM S_LNET
  43
  44 #include <libcfs/libcfs.h>
  45
  46 static char debug_file_name[1024];
  47
  48 #ifdef HAVE_CATAMOUNT_DATA_H
  49 #include <catamount/data.h>
  50 #include <catamount/lputs.h>
  51
  52 static char source_nid[16];
  53 /* 0 indicates no messages to console, 1 is errors, > 1 is all debug messages */
  54 static int toconsole = 1;
  55 unsigned int libcfs_console_ratelimit = 1;
  56 cfs_duration_t libcfs_console_max_delay;
  57 cfs_duration_t libcfs_console_min_delay;
  58 unsigned int libcfs_console_backoff = CDEBUG_DEFAULT_BACKOFF;
  59 #else /* !HAVE_CATAMOUNT_DATA_H */
  60 #ifdef HAVE_NETDB_H
  61 #include <sys/utsname.h>
  62 #endif /* HAVE_NETDB_H */
  63 struct utsname *tmp_utsname;
  64 static char source_nid[sizeof(tmp_utsname->nodename)];
  65 #endif /* HAVE_CATAMOUNT_DATA_H */
  66
  67 static int source_pid;
  68 int cfs_smp_processor_id = 1;
  69 char libcfs_debug_file_path[1024];
  70 FILE *debug_file_fd;
  71
  72 int portals_do_debug_dumplog(void *arg)
  73 {
  74         printf("Look in %s\n", debug_file_name);
  75         return 0;
  76 }
  77
  78
  79 void portals_debug_print(void)
  80 {
  81         return;
  82 }
  83
  84
  85 void libcfs_debug_dumplog(void)
  86 {
  87         printf("Look in %s\n", debug_file_name);
  88         return;
  89 }
  90
  91 int libcfs_debug_init(unsigned long bufsize)
  92 {
  93         char *debug_mask = NULL;
  94         char *debug_subsys = NULL;
  95         char *debug_filename;
  96
  97 #ifdef HAVE_CATAMOUNT_DATA_H
  98         char *debug_console = NULL;
  99         char *debug_ratelimit = NULL;
 100         char *debug_max_delay = NULL;
 101         char *debug_min_delay = NULL;
 102         char *debug_backoff = NULL;
 103
 104         libcfs_console_max_delay = CDEBUG_DEFAULT_MAX_DELAY;
 105         libcfs_console_min_delay = CDEBUG_DEFAULT_MIN_DELAY;
 106
 107         snprintf(source_nid, sizeof(source_nid) - 1, "%u", _my_pnid);
 108         source_pid = _my_pid;
 109
 110         debug_console = getenv("LIBLUSTRE_DEBUG_CONSOLE");
 111         if (debug_console != NULL) {
 112                 toconsole = strtoul(debug_console, NULL, 0);
 113                 CDEBUG(D_INFO, "set liblustre toconsole to %u\n", toconsole);
 114         }
 115         debug_ratelimit = getenv("LIBLUSTRE_DEBUG_CONSOLE_RATELIMIT");
 116         if (debug_ratelimit != NULL) {
 117                 libcfs_console_ratelimit = strtoul(debug_ratelimit, NULL, 0);
 118                 CDEBUG(D_INFO, "set liblustre console ratelimit to %u\n",
 119                                 libcfs_console_ratelimit);
 120         }
 121         debug_max_delay = getenv("LIBLUSTRE_DEBUG_CONSOLE_MAX_DELAY");
 122         if (debug_max_delay != NULL)
 123                 libcfs_console_max_delay =
 124                             cfs_time_seconds(strtoul(debug_max_delay, NULL, 0));
 125         debug_min_delay = getenv("LIBLUSTRE_DEBUG_CONSOLE_MIN_DELAY");
 126         if (debug_min_delay != NULL)
 127                 libcfs_console_min_delay =
 128                             cfs_time_seconds(strtoul(debug_min_delay, NULL, 0));
 129         if (debug_min_delay || debug_max_delay) {
 130                 if (!libcfs_console_max_delay || !libcfs_console_min_delay ||
 131                     libcfs_console_max_delay < libcfs_console_min_delay) {
 132                         libcfs_console_max_delay = CDEBUG_DEFAULT_MAX_DELAY;
 133                         libcfs_console_min_delay = CDEBUG_DEFAULT_MIN_DELAY;
 134                         CDEBUG(D_INFO, "LIBLUSTRE_DEBUG_CONSOLE_MAX_DELAY "
 135                                        "should be greater than "
 136                                        "LIBLUSTRE_DEBUG_CONSOLE_MIN_DELAY "
 137                                        "and both parameters should be non-null"
 138                                        ": restore default values\n");
 139                 } else {
 140                         CDEBUG(D_INFO, "set liblustre console max delay to %lus"
 141                                        " and min delay to %lus\n",
 142                                (cfs_duration_t)
 143                                      cfs_duration_sec(libcfs_console_max_delay),
 144                                (cfs_duration_t)
 145                                     cfs_duration_sec(libcfs_console_min_delay));
 146                 }
 147         }
 148         debug_backoff = getenv("LIBLUSTRE_DEBUG_CONSOLE_BACKOFF");
 149         if (debug_backoff != NULL) {
 150                 libcfs_console_backoff = strtoul(debug_backoff, NULL, 0);
 151                 if (libcfs_console_backoff <= 0) {
 152                         libcfs_console_backoff = CDEBUG_DEFAULT_BACKOFF;
 153                         CDEBUG(D_INFO, "LIBLUSTRE_DEBUG_CONSOLE_BACKOFF <= 0: "
 154                                        "restore default value\n");
 155                 } else {
 156                         CDEBUG(D_INFO, "set liblustre console backoff to %u\n",
 157                                libcfs_console_backoff);
 158                 }
 159         }
 160 #else
 161         struct utsname myname;
 162
 163         if (uname(&myname) == 0)
 164                 strcpy(source_nid, myname.nodename);
 165         source_pid = getpid();
 166 #endif
 167         /* debug masks */
 168         debug_mask = getenv("LIBLUSTRE_DEBUG_MASK");
 169         if (debug_mask)
 170                 libcfs_debug = (unsigned int) strtol(debug_mask, NULL, 0);
 171
 172         debug_subsys = getenv("LIBLUSTRE_DEBUG_SUBSYS");
 173         if (debug_subsys)
 174                 libcfs_subsystem_debug =
 175                                 (unsigned int) strtol(debug_subsys, NULL, 0);
 176
 177         debug_filename = getenv("LIBLUSTRE_DEBUG_BASE");
 178         if (debug_filename)
 179                 strncpy(libcfs_debug_file_path, debug_filename,
 180                         sizeof(libcfs_debug_file_path));
 181
 182         debug_filename = getenv("LIBLUSTRE_DEBUG_FILE");
 183         if (debug_filename)
 184                 strncpy(debug_file_name,debug_filename,sizeof(debug_file_name));
 185
 186         if (debug_file_name[0] == '\0' && libcfs_debug_file_path[0] != '\0')
 187                 snprintf(debug_file_name, sizeof(debug_file_name) - 1,
 188                          "%s-%s-"CFS_TIME_T".log", libcfs_debug_file_path,
 189                          source_nid, time(0));
 190
 191         if (strcmp(debug_file_name, "stdout") == 0 ||
 192             strcmp(debug_file_name, "-") == 0) {
 193                 debug_file_fd = stdout;
 194         } else if (strcmp(debug_file_name, "stderr") == 0) {
 195                 debug_file_fd = stderr;
 196         } else if (debug_file_name[0] != '\0') {
 197                 debug_file_fd = fopen(debug_file_name, "w");
 198                 if (debug_file_fd == NULL)
 199                         fprintf(stderr, "%s: unable to open '%s': %s\n",
 200                                 source_nid, debug_file_name, strerror(errno));
 201         }
 202
 203         if (debug_file_fd == NULL)
 204                 debug_file_fd = stdout;
 205
 206         return 0;
 207 }
 208
 209 int libcfs_debug_cleanup(void)
 210 {
 211         if (debug_file_fd != stdout && debug_file_fd != stderr)
 212                 fclose(debug_file_fd);
 213         return 0;
 214 }
 215
 216 int libcfs_debug_clear_buffer(void)
 217 {
 218         return 0;
 219 }
 220
 221 int libcfs_debug_mark_buffer(const char *text)
 222 {
 223
 224         fprintf(debug_file_fd, "*******************************************************************************\n");
 225         fprintf(debug_file_fd, "DEBUG MARKER: %s\n", text);
 226         fprintf(debug_file_fd, "*******************************************************************************\n");
 227
 228         return 0;
 229 }
 230
 231 #ifdef HAVE_CATAMOUNT_DATA_H
 232 #define CATAMOUNT_MAXLINE (256-4)
 233 void catamount_printline(char *buf, size_t size)
 234 {
 235     char *pos = buf;
 236     int prsize = size;
 237
 238     while (prsize > 0){
 239         lputs(pos);
 240         pos += CATAMOUNT_MAXLINE;
 241         prsize -= CATAMOUNT_MAXLINE;
 242     }
 243 }
 244 #endif
 245
 246 int
 247 libcfs_debug_vmsg2(cfs_debug_limit_state_t *cdls,
 248                    int subsys, int mask,
 249                    const char *file, const char *fn, const int line,
 250                    const char *format1, va_list args,
 251                    const char *format2, ...)
 252 {
 253         struct timeval tv;
 254         int            nob;
 255         int            remain;
 256         va_list        ap;
 257         char           buf[CFS_PAGE_SIZE]; /* size 4096 used for compatimble
 258                                             * with linux, where message can`t
 259                                             * be exceed PAGE_SIZE */
 260         int            console = 0;
 261         char *prefix = "Lustre";
 262
 263 #ifdef HAVE_CATAMOUNT_DATA_H
 264         /* toconsole == 0 - all messages to debug_file_fd
 265          * toconsole == 1 - warnings to console, all to debug_file_fd
 266          * toconsole >  1 - all debug to console */
 267         if (((mask & libcfs_printk) && toconsole == 1) || toconsole > 1)
 268                 console = 1;
 269 #endif
 270
 271         if ((!console) && (!debug_file_fd)) {
 272                 return 0;
 273         }
 274
 275         if (mask & (D_EMERG | D_ERROR))
 276                prefix = "LustreError";
 277
 278         nob = snprintf(buf, sizeof(buf), "%s: %u-%s:(%s:%d:%s()): ", prefix,
 279                        source_pid, source_nid, file, line, fn);
 280
 281         remain = sizeof(buf) - nob;
 282         if (format1) {
 283                 nob += vsnprintf(&buf[nob], remain, format1, args);
 284         }
 285
 286         remain = sizeof(buf) - nob;
 287         if ((format2) && (remain > 0)) {
 288                 va_start(ap, format2);
 289                 nob += vsnprintf(&buf[nob], remain, format2, ap);
 290                 va_end(ap);
 291         }
 292
 293 #ifdef HAVE_CATAMOUNT_DATA_H
 294         if (console) {
 295                 /* check rate limit for console */
 296                 if (cdls != NULL) {
 297                         if (libcfs_console_ratelimit &&
 298                                 cdls->cdls_next != 0 &&     /* not first time ever */
 299                                 !cfs_time_after(cfs_time_current(), cdls->cdls_next)) {
 300
 301                                 /* skipping a console message */
 302                                 cdls->cdls_count++;
 303                                 goto out_file;
 304                         }
 305
 306                         if (cfs_time_after(cfs_time_current(), cdls->cdls_next +
 307                                            libcfs_console_max_delay +
 308                                            cfs_time_seconds(10))) {
 309                                 /* last timeout was a long time ago */
 310                                 cdls->cdls_delay /= libcfs_console_backoff * 4;
 311                         } else {
 312                                 cdls->cdls_delay *= libcfs_console_backoff;
 313
 314                                 if (cdls->cdls_delay <
 315                                                 libcfs_console_min_delay)
 316                                         cdls->cdls_delay =
 317                                                 libcfs_console_min_delay;
 318                                 else if (cdls->cdls_delay >
 319                                                 libcfs_console_max_delay)
 320                                         cdls->cdls_delay =
 321                                                 libcfs_console_max_delay;
 322                         }
 323
 324                         /* ensure cdls_next is never zero after it's been seen */
 325                         cdls->cdls_next = (cfs_time_current() + cdls->cdls_delay) | 1;
 326                 }
 327
 328                 if (cdls != NULL && cdls->cdls_count != 0) {
 329                         char buf2[100];
 330
 331                         nob = snprintf(buf2, sizeof(buf2),
 332                                        "Skipped %d previous similar message%s\n",
 333                                        cdls->cdls_count, (cdls->cdls_count > 1) ? "s" : "");
 334
 335                         catamount_printline(buf2, nob);
 336                         cdls->cdls_count = 0;
 337                         goto out_file;
 338                 }
 339                 catamount_printline(buf, nob);
 340        }
 341 out_file:
 342         /* return on toconsole > 1, as we don't want the user getting
 343         * spammed by the debug data */
 344         if (toconsole > 1)
 345                 return 0;
 346 #endif
 347         if (debug_file_fd == NULL)
 348                 return 0;
 349
 350         gettimeofday(&tv, NULL);
 351
 352         fprintf(debug_file_fd, CFS_TIME_T".%06lu:%u:%s:(%s:%d:%s()): %s",
 353                 tv.tv_sec, tv.tv_usec, source_pid, source_nid,
 354                 file, line, fn, buf);
 355
 356         return 0;
 357 }
 358
 359 void
 360 libcfs_assertion_failed(const char *expr, const char *file, const char *func,
 361                         const int line)
 362 {
 363         libcfs_debug_msg(NULL, 0, D_EMERG, file, func, line,
 364                          "ASSERTION(%s) failed\n", expr);
 365         abort();
 366 }