crypto/trace.c

   1 /*
   2  * Copyright 2019 The OpenSSL Project Authors. All Rights Reserved.
   3  *
   4  * Licensed under the Apache License 2.0 (the "License").  You may not use
   5  * this file except in compliance with the License.  You can obtain a copy
   6  * in the file LICENSE in the source distribution or at
   7  * https://www.openssl.org/source/license.html
   8  */
   9
  10 #include <stdio.h>
  11 #include <string.h>
  12
  13 #include <openssl/bio.h>
  14 #include <openssl/crypto.h>
  15 #include <openssl/trace.h>
  16 #include "internal/bio.h"
  17 #include "internal/nelem.h"
  18 #include "internal/cryptlib_int.h"
  19
  20 #include "e_os.h"                /* strcasecmp for Windows */
  21
  22 #ifndef OPENSSL_NO_TRACE
  23
  24 static CRYPTO_RWLOCK *trace_lock = NULL;
  25
  26 static const BIO  *current_channel = NULL;
  27
  28 /*-
  29  * INTERNAL TRACE CHANNEL IMPLEMENTATION
  30  *
  31  * For our own flexibility, all trace categories are associated with a
  32  * BIO sink object, also called the trace channel. Instead of a BIO object,
  33  * the application can also provide a callback function, in which case an
  34  * internal trace channel is attached, which simply calls the registered
  35  * callback function.
  36  */
  37 static int trace_write(BIO *b, const char *buf,
  38                                size_t num, size_t *written);
  39 static int trace_puts(BIO *b, const char *str);
  40 static long trace_ctrl(BIO *channel, int cmd, long argl, void *argp);
  41 static int trace_free(BIO *b);
  42
  43 static const BIO_METHOD trace_method = {
  44     BIO_TYPE_SOURCE_SINK,
  45     "trace",
  46     trace_write,
  47     NULL,                        /* old write */
  48     NULL,                        /* read_ex */
  49     NULL,                        /* read */
  50     trace_puts,
  51     NULL,                        /* gets */
  52     trace_ctrl,                  /* ctrl */
  53     NULL,                        /* create */
  54     trace_free,                  /* free */
  55     NULL,                        /* callback_ctrl */
  56 };
  57
  58 struct trace_data_st {
  59     OSSL_trace_cb callback;
  60     int category;
  61     void *data;
  62 };
  63
  64 static int trace_write(BIO *channel,
  65                        const char *buf, size_t num, size_t *written)
  66 {
  67     struct trace_data_st *ctx = BIO_get_data(channel);
  68     size_t cnt = ctx->callback(buf, num, ctx->category, OSSL_TRACE_CTRL_WRITE,
  69                                ctx->data);
  70
  71     *written = cnt;
  72     return cnt != 0;
  73 }
  74
  75 static int trace_puts(BIO *channel, const char *str)
  76 {
  77     size_t written;
  78
  79     if (trace_write(channel, str, strlen(str), &written))
  80         return (int)written;
  81
  82     return EOF;
  83 }
  84
  85 static long trace_ctrl(BIO *channel, int cmd, long argl, void *argp)
  86 {
  87     struct trace_data_st *ctx = BIO_get_data(channel);
  88
  89     switch (cmd) {
  90     case OSSL_TRACE_CTRL_BEGIN:
  91     case OSSL_TRACE_CTRL_END:
  92         /* We know that the callback is likely to return 0 here */
  93         ctx->callback("", 0, ctx->category, cmd, ctx->data);
  94         return 1;
  95     default:
  96         break;
  97     }
  98     return -2;                   /* Unsupported */
  99 }
 100
 101 static int trace_free(BIO *channel)
 102 {
 103     if (channel == NULL)
 104         return 0;
 105     OPENSSL_free(BIO_get_data(channel));
 106     return 1;
 107 }
 108 #endif
 109
 110 /*-
 111  * TRACE
 112  */
 113
 114 /* Helper struct and macro to get name string to number mapping */
 115 struct trace_category_st {
 116     const char * const name;
 117     const int num;
 118 };
 119 #define TRACE_CATEGORY_(name)       { #name, OSSL_TRACE_CATEGORY_##name }
 120
 121 static const struct trace_category_st trace_categories[] = {
 122     TRACE_CATEGORY_(ALL),
 123     TRACE_CATEGORY_(TRACE),
 124     TRACE_CATEGORY_(INIT),
 125     TRACE_CATEGORY_(TLS),
 126     TRACE_CATEGORY_(TLS_CIPHER),
 127     TRACE_CATEGORY_(CONF),
 128     TRACE_CATEGORY_(ENGINE_TABLE),
 129     TRACE_CATEGORY_(ENGINE_REF_COUNT),
 130     TRACE_CATEGORY_(PKCS5V2),
 131     TRACE_CATEGORY_(PKCS12_KEYGEN),
 132     TRACE_CATEGORY_(PKCS12_DECRYPT),
 133     TRACE_CATEGORY_(X509V3_POLICY),
 134     TRACE_CATEGORY_(BN_CTX),
 135 };
 136
 137 const char *OSSL_trace_get_category_name(int num)
 138 {
 139     size_t i;
 140
 141     for (i = 0; i < OSSL_NELEM(trace_categories); i++)
 142         if (trace_categories[i].num == num)
 143             return trace_categories[i].name;
 144     return NULL; /* not found */
 145 }
 146
 147 int OSSL_trace_get_category_num(const char *name)
 148 {
 149     size_t i;
 150
 151     for (i = 0; i < OSSL_NELEM(trace_categories); i++)
 152         if (strcasecmp(name, trace_categories[i].name) == 0)
 153             return trace_categories[i].num;
 154     return -1; /* not found */
 155 }
 156
 157 #ifndef OPENSSL_NO_TRACE
 158
 159 /* We use one trace channel for each trace category */
 160 static struct {
 161     enum { SIMPLE_CHANNEL, CALLBACK_CHANNEL } type;
 162     BIO *bio;
 163     char *prefix;
 164     char *suffix;
 165 } trace_channels[OSSL_TRACE_CATEGORY_NUM] = {
 166     { 0, NULL, NULL, NULL },
 167 };
 168
 169 #endif
 170
 171 #ifndef OPENSSL_NO_TRACE
 172
 173 enum {
 174     CHANNEL,
 175     PREFIX,
 176     SUFFIX
 177 };
 178
 179 static int trace_attach_cb(int category, int type, const void *data)
 180 {
 181     switch (type) {
 182     case CHANNEL:
 183         OSSL_TRACE2(TRACE, "Attach channel %p to category '%s'\n",
 184                     data, trace_categories[category].name);
 185         break;
 186     case PREFIX:
 187         OSSL_TRACE2(TRACE, "Attach prefix \"%s\" to category '%s'\n",
 188                     (const char *)data, trace_categories[category].name);
 189         break;
 190     case SUFFIX:
 191         OSSL_TRACE2(TRACE, "Attach suffix \"%s\" to category '%s'\n",
 192                     (const char *)data, trace_categories[category].name);
 193         break;
 194     default:                     /* No clue */
 195         break;
 196     }
 197     return 1;
 198 }
 199
 200 static int trace_detach_cb(int category, int type, const void *data)
 201 {
 202     switch (type) {
 203     case CHANNEL:
 204         OSSL_TRACE2(TRACE, "Detach channel %p from category '%s'\n",
 205                     data, trace_categories[category].name);
 206         break;
 207     case PREFIX:
 208         OSSL_TRACE2(TRACE, "Detach prefix \"%s\" from category '%s'\n",
 209                     (const char *)data, trace_categories[category].name);
 210         break;
 211     case SUFFIX:
 212         OSSL_TRACE2(TRACE, "Detach suffix \"%s\" from category '%s'\n",
 213                     (const char *)data, trace_categories[category].name);
 214         break;
 215     default:                     /* No clue */
 216         break;
 217     }
 218     return 1;
 219 }
 220
 221 static int set_trace_data(int category, int type, BIO **channel,
 222                           const char **prefix, const char **suffix,
 223                           int (*attach_cb)(int, int, const void *),
 224                           int (*detach_cb)(int, int, const void *))
 225 {
 226     BIO *curr_channel = NULL;
 227     char *curr_prefix = NULL;
 228     char *curr_suffix = NULL;
 229
 230     /* Ensure ossl_trace_init() is called */
 231     OPENSSL_init_crypto(0, NULL);
 232
 233     curr_channel = trace_channels[category].bio;
 234     curr_prefix = trace_channels[category].prefix;
 235     curr_suffix = trace_channels[category].suffix;
 236
 237     /* Make sure to run the detach callback first on all data */
 238     if (prefix != NULL && curr_prefix != NULL) {
 239         detach_cb(category, PREFIX, curr_prefix);
 240     }
 241
 242     if (suffix != NULL && curr_suffix != NULL) {
 243         detach_cb(category, SUFFIX, curr_suffix);
 244     }
 245
 246     if (channel != NULL && curr_channel != NULL) {
 247         detach_cb(category, CHANNEL, curr_channel);
 248     }
 249
 250     /* After detach callbacks are done, clear data where appropriate */
 251     if (prefix != NULL && curr_prefix != NULL) {
 252         OPENSSL_free(curr_prefix);
 253         trace_channels[category].prefix = NULL;
 254     }
 255
 256     if (suffix != NULL && curr_suffix != NULL) {
 257         OPENSSL_free(curr_suffix);
 258         trace_channels[category].suffix = NULL;
 259     }
 260
 261     if (channel != NULL && curr_channel != NULL) {
 262         BIO_free(curr_channel);
 263         trace_channels[category].type = 0;
 264         trace_channels[category].bio = NULL;
 265     }
 266
 267     /* Before running callbacks are done, set new data where appropriate */
 268     if (channel != NULL && *channel != NULL) {
 269         trace_channels[category].type = type;
 270         trace_channels[category].bio = *channel;
 271     }
 272
 273     if (prefix != NULL && *prefix != NULL) {
 274         if ((curr_prefix = OPENSSL_strdup(*prefix)) == NULL)
 275             return 0;
 276         trace_channels[category].prefix = curr_prefix;
 277     }
 278
 279     if (suffix != NULL && *suffix != NULL) {
 280         if ((curr_suffix = OPENSSL_strdup(*suffix)) == NULL)
 281             return 0;
 282         trace_channels[category].suffix = curr_suffix;
 283     }
 284
 285     /* Finally, run the attach callback on the new data */
 286     if (channel != NULL && *channel != NULL) {
 287         attach_cb(category, CHANNEL, *channel);
 288     }
 289
 290     if (prefix != NULL && *prefix != NULL) {
 291         attach_cb(category, PREFIX, *prefix);
 292     }
 293
 294     if (suffix != NULL && *suffix != NULL) {
 295         attach_cb(category, SUFFIX, *suffix);
 296     }
 297
 298     return 1;
 299 }
 300 #endif
 301
 302 int ossl_trace_init(void)
 303 {
 304 #ifndef OPENSSL_NO_TRACE
 305     trace_lock = CRYPTO_THREAD_lock_new();
 306     if (trace_lock == NULL)
 307         return 0;
 308 #endif
 309
 310     return 1;
 311 }
 312
 313 void ossl_trace_cleanup(void)
 314 {
 315 #ifndef OPENSSL_NO_TRACE
 316     int category;
 317     BIO *channel = NULL;
 318     const char *prefix = NULL;
 319     const char *suffix = NULL;
 320
 321     for (category = 0; category < OSSL_TRACE_CATEGORY_NUM; category++) {
 322         /* We force the TRACE category to be treated last */
 323         if (category == OSSL_TRACE_CATEGORY_TRACE)
 324             continue;
 325         set_trace_data(category, 0, &channel, &prefix, &suffix,
 326                        trace_attach_cb, trace_detach_cb);
 327     }
 328     set_trace_data(OSSL_TRACE_CATEGORY_TRACE, 0, &channel,
 329                    &prefix, &suffix,
 330                    trace_attach_cb, trace_detach_cb);
 331     CRYPTO_THREAD_lock_free(trace_lock);
 332 #endif
 333 }
 334
 335 int OSSL_trace_set_channel(int category, BIO *channel)
 336 {
 337 #ifndef OPENSSL_NO_TRACE
 338     if (category >= 0 && category < OSSL_TRACE_CATEGORY_NUM)
 339         return set_trace_data(category, SIMPLE_CHANNEL, &channel, NULL, NULL,
 340                               trace_attach_cb, trace_detach_cb);
 341 #endif
 342     return 0;
 343 }
 344
 345 #ifndef OPENSSL_NO_TRACE
 346 static int trace_attach_w_callback_cb(int category, int type, const void *data)
 347 {
 348     switch (type) {
 349     case CHANNEL:
 350         OSSL_TRACE2(TRACE,
 351                     "Attach channel %p to category '%s' (with callback)\n",
 352                     data, trace_categories[category].name);
 353         break;
 354     case PREFIX:
 355         OSSL_TRACE2(TRACE, "Attach prefix \"%s\" to category '%s'\n",
 356                     (const char *)data, trace_categories[category].name);
 357         break;
 358     case SUFFIX:
 359         OSSL_TRACE2(TRACE, "Attach suffix \"%s\" to category '%s'\n",
 360                     (const char *)data, trace_categories[category].name);
 361         break;
 362     default:                     /* No clue */
 363         break;
 364     }
 365     return 1;
 366 }
 367 #endif
 368
 369 int OSSL_trace_set_callback(int category, OSSL_trace_cb callback, void *data)
 370 {
 371 #ifndef OPENSSL_NO_TRACE
 372     BIO *channel = NULL;
 373     struct trace_data_st *trace_data = NULL;
 374
 375     if (category < 0 || category >= OSSL_TRACE_CATEGORY_NUM)
 376         return 0;
 377
 378     if (callback != NULL) {
 379         if ((channel = BIO_new(&trace_method)) == NULL
 380             || (trace_data =
 381                 OPENSSL_zalloc(sizeof(struct trace_data_st))) == NULL)
 382             goto err;
 383
 384         trace_data->callback = callback;
 385         trace_data->category = category;
 386         trace_data->data = data;
 387
 388         BIO_set_data(channel, trace_data);
 389     }
 390
 391     if (!set_trace_data(category, CALLBACK_CHANNEL, &channel, NULL, NULL,
 392                         trace_attach_w_callback_cb, trace_detach_cb))
 393         goto err;
 394
 395     return 1;
 396
 397  err:
 398     BIO_free(channel);
 399     OPENSSL_free(trace_data);
 400 #endif
 401
 402     return 0;
 403 }
 404
 405 int OSSL_trace_set_prefix(int category, const char *prefix)
 406 {
 407 #ifndef OPENSSL_NO_TRACE
 408     if (category >= 0 && category < OSSL_TRACE_CATEGORY_NUM)
 409         return set_trace_data(category, 0, NULL, &prefix, NULL,
 410                               trace_attach_cb, trace_detach_cb);
 411 #endif
 412     return 0;
 413 }
 414
 415 int OSSL_trace_set_suffix(int category, const char *suffix)
 416 {
 417 #ifndef OPENSSL_NO_TRACE
 418     if (category >= 0 && category < OSSL_TRACE_CATEGORY_NUM)
 419         return set_trace_data(category, 0, NULL, NULL, &suffix,
 420                               trace_attach_cb, trace_detach_cb);
 421 #endif
 422     return 0;
 423 }
 424
 425 #ifndef OPENSSL_NO_TRACE
 426 static int ossl_trace_get_category(int category)
 427 {
 428     if (category < 0 || category >= OSSL_TRACE_CATEGORY_NUM)
 429         return -1;
 430     if (trace_channels[category].bio != NULL)
 431         return category;
 432     return OSSL_TRACE_CATEGORY_ALL;
 433 }
 434 #endif
 435
 436 int OSSL_trace_enabled(int category)
 437 {
 438     int ret = 0;
 439 #ifndef OPENSSL_NO_TRACE
 440     category = ossl_trace_get_category(category);
 441     if (category >= 0)
 442         ret = trace_channels[category].bio != NULL;
 443 #endif
 444     return ret;
 445 }
 446
 447 BIO *OSSL_trace_begin(int category)
 448 {
 449     BIO *channel = NULL;
 450 #ifndef OPENSSL_NO_TRACE
 451     char *prefix = NULL;
 452
 453     category = ossl_trace_get_category(category);
 454     if (category < 0)
 455         return NULL;
 456
 457     channel = trace_channels[category].bio;
 458     prefix = trace_channels[category].prefix;
 459
 460     if (channel != NULL) {
 461         CRYPTO_THREAD_write_lock(trace_lock);
 462         current_channel = channel;
 463         switch (trace_channels[category].type) {
 464         case SIMPLE_CHANNEL:
 465             if (prefix != NULL) {
 466                 (void)BIO_puts(channel, prefix);
 467                 (void)BIO_puts(channel, "\n");
 468             }
 469             break;
 470         case CALLBACK_CHANNEL:
 471             (void)BIO_ctrl(channel, OSSL_TRACE_CTRL_BEGIN,
 472                            prefix == NULL ? 0 : strlen(prefix), prefix);
 473             break;
 474         }
 475     }
 476 #endif
 477     return channel;
 478 }
 479
 480 void OSSL_trace_end(int category, BIO * channel)
 481 {
 482 #ifndef OPENSSL_NO_TRACE
 483     char *suffix = NULL;
 484
 485     category = ossl_trace_get_category(category);
 486     suffix = trace_channels[category].suffix;
 487     if (channel != NULL
 488         && ossl_assert(channel == current_channel)) {
 489         (void)BIO_flush(channel);
 490         switch (trace_channels[category].type) {
 491         case SIMPLE_CHANNEL:
 492             if (suffix != NULL) {
 493                 (void)BIO_puts(channel, suffix);
 494                 (void)BIO_puts(channel, "\n");
 495             }
 496             break;
 497         case CALLBACK_CHANNEL:
 498             (void)BIO_ctrl(channel, OSSL_TRACE_CTRL_END,
 499                            suffix == NULL ? 0 : strlen(suffix), suffix);
 500             break;
 501         }
 502         current_channel = NULL;
 503         CRYPTO_THREAD_unlock(trace_lock);
 504     }
 505 #endif
 506 }