Home | History | Annotate | Line # | Download | only in test
      1 /*
      2  * Copyright 2024 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 "internal/qlog.h"
     11 #include "testutil.h"
     12 
     13 /*
     14  * Unfortunately, this must be expressed as an array and not a string literal as
     15  * ANSI C only guarantees support for up to 509 characters in a string literal.
     16  */
     17 static const char expected[] = {
     18     30, '{', '"', 'q', 'l', 'o', 'g', '_', 'v', 'e', 'r', 's', 'i', 'o', 'n',
     19     '"', ':', '"', '0', '.', '3', '"', ',', '"', 'q', 'l', 'o', 'g', '_', 'f',
     20     'o', 'r', 'm', 'a', 't', '"', ':', '"', 'J', 'S', 'O', 'N', '-', 'S', 'E',
     21     'Q', '"', ',', '"', 't', 'i', 't', 'l', 'e', '"', ':', '"', 't', 'e', 's',
     22     't', ' ', 't', 'i', 't', 'l', 'e', '"', ',', '"', 'd', 'e', 's', 'c', 'r',
     23     'i', 'p', 't', 'i', 'o', 'n', '"', ':', '"', 't', 'e', 's', 't', ' ', 'd',
     24     'e', 's', 'c', 'r', 'i', 'p', 't', 'i', 'o', 'n', '"', ',', '"', 't', 'r',
     25     'a', 'c', 'e', '"', ':', '{', '"', 'c', 'o', 'm', 'm', 'o', 'n', '_', 'f',
     26     'i', 'e', 'l', 'd', 's', '"', ':', '{', '"', 't', 'i', 'm', 'e', '_', 'f',
     27     'o', 'r', 'm', 'a', 't', '"', ':', '"', 'd', 'e', 'l', 't', 'a', '"', ',',
     28     '"', 'p', 'r', 'o', 't', 'o', 'c', 'o', 'l', '_', 't', 'y', 'p', 'e', '"',
     29     ':', '[', '"', 'Q', 'U', 'I', 'C', '"', ']', ',', '"', 'g', 'r', 'o', 'u',
     30     'p', '_', 'i', 'd', '"', ':', '"', 't', 'e', 's', 't', ' ', 'g', 'r', 'o',
     31     'u', 'p', ' ', 'I', 'D', '"', ',', '"', 's', 'y', 's', 't', 'e', 'm', '_',
     32     'i', 'n', 'f', 'o', '"', ':', '{', '"', 'p', 'r', 'o', 'c', 'e', 's', 's',
     33     '_', 'i', 'd', '"', ':', '1', '2', '3', '}', '}', ',', '"', 'v', 'a', 'n',
     34     't', 'a', 'g', 'e', '_', 'p', 'o', 'i', 'n', 't', '"', ':', '{', '"', 't',
     35     'y', 'p', 'e', '"', ':', '"', 'c', 'l', 'i', 'e', 'n', 't', '"', ',', '"',
     36     'n', 'a', 'm', 'e', '"', ':', '"', 'O', 'p', 'e', 'n', 'S', 'S', 'L', '/',
     37     'x', '.', 'y', '.', 'z', '"', '}', '}', '}', 10, 30, '{', '"', 'n', 'a',
     38     'm', 'e', '"', ':', '"', 't', 'r', 'a', 'n', 's', 'p', 'o', 'r', 't', ':',
     39     'p', 'a', 'c', 'k', 'e', 't', '_', 's', 'e', 'n', 't', '"', ',', '"', 'd',
     40     'a', 't', 'a', '"', ':', '{', '"', 'f', 'i', 'e', 'l', 'd', '1', '"', ':',
     41     '"', 'f', 'o', 'o', '"', ',', '"', 'f', 'i', 'e', 'l', 'd', '2', '"', ':',
     42     '"', 'b', 'a', 'r', '"', ',', '"', 'f', 'i', 'e', 'l', 'd', '3', '"', ':',
     43     '4', '2', ',', '"', 'f', 'i', 'e', 'l', 'd', '4', '"', ':', '"', '1', '1',
     44     '5', '2', '9', '2', '1', '5', '0', '4', '6', '0', '6', '8', '4', '6', '9',
     45     '7', '6', '"', ',', '"', 'f', 'i', 'e', 'l', 'd', '5', '"', ':', '"', '1',
     46     '8', '4', '4', '6', '7', '4', '4', '0', '7', '3', '7', '0', '9', '5', '5',
     47     '1', '6', '1', '5', '"', ',', '"', 'f', 'i', 'e', 'l', 'd', '6', '"', ':',
     48     'f', 'a', 'l', 's', 'e', ',', '"', 'f', 'i', 'e', 'l', 'd', '7', '"', ':',
     49     't', 'r', 'u', 'e', ',', '"', 'f', 'i', 'e', 'l', 'd', '8', '"', ':', '"',
     50     '0', '1', 'a', 'f', '"', ',', '"', 'f', 'i', 'e', 'l', 'd', '9', '"', ':',
     51     '"', '5', '5', '"', ',', '"', 's', 'u', 'b', 'g', 'r', 'o', 'u', 'p', '"',
     52     ':', '{', '"', 'f', 'i', 'e', 'l', 'd', '1', '0', '"', ':', '"', 'b', 'a',
     53     'z', '"', '}', ',', '"', 'a', 'r', 'r', 'a', 'y', '"', ':', '[', '"', 'a',
     54     '"', ',', '"', 'b', '"', ']', '}', ',', '"', 't', 'i', 'm', 'e', '"', ':',
     55     '1', '7', '0', '6', '5', '3', '1', '1', '7', '0', '0', '0', '}', 10, 30,
     56     '{', '"', 'n', 'a', 'm', 'e', '"', ':', '"', 't', 'r', 'a', 'n', 's', 'p',
     57     'o', 'r', 't', ':', 'p', 'a', 'c', 'k', 'e', 't', '_', 's', 'e', 'n', 't',
     58     '"', ',', '"', 'd', 'a', 't', 'a', '"', ':', '{', '"', 'f', 'i', 'e', 'l',
     59     'd', '1', '"', ':', '"', 'b', 'a', 'r', '"', '}', ',', '"', 't', 'i', 'm',
     60     'e', '"', ':', '1', '0', '0', '0', '}', 10
     61 };
     62 
     63 static const unsigned char bin_buf[] = {
     64     0x01, 0xaf
     65 };
     66 
     67 static OSSL_TIME last_time;
     68 
     69 static OSSL_TIME now(void *arg)
     70 {
     71     OSSL_TIME t = last_time;
     72 
     73     last_time = ossl_time_add(t, ossl_ms2time(1000));
     74     return t;
     75 }
     76 
     77 static int test_qlog(void)
     78 {
     79     int testresult = 0;
     80     QLOG_TRACE_INFO qti = { 0 };
     81     QLOG *qlog;
     82     BIO *bio;
     83     char *buf = NULL;
     84     size_t buf_len = 0;
     85 
     86     last_time = ossl_time_from_time_t(170653117);
     87 
     88     qti.odcid.id_len = 1;
     89     qti.odcid.id[0] = 0x55;
     90     qti.title = "test title";
     91     qti.description = "test description";
     92     qti.group_id = "test group ID";
     93     qti.override_process_id = 123;
     94     qti.now_cb = now;
     95     qti.override_impl_name = "OpenSSL/x.y.z";
     96 
     97     if (!TEST_ptr(qlog = ossl_qlog_new(&qti)))
     98         goto err;
     99 
    100     if (!TEST_true(ossl_qlog_set_event_type_enabled(qlog, QLOG_EVENT_TYPE_transport_packet_sent, 1)))
    101         goto err;
    102 
    103     if (!TEST_ptr(bio = BIO_new(BIO_s_mem())))
    104         goto err;
    105 
    106     if (!TEST_true(ossl_qlog_set_sink_bio(qlog, bio)))
    107         goto err;
    108 
    109     QLOG_EVENT_BEGIN(qlog, transport, packet_sent)
    110     QLOG_STR("field1", "foo");
    111     QLOG_STR_LEN("field2", "bar", 3);
    112     QLOG_I64("field3", 42);
    113     QLOG_I64("field4", 1ULL << 60);
    114     QLOG_U64("field5", UINT64_MAX);
    115     QLOG_BOOL("field6", 0);
    116     QLOG_BOOL("field7", 1);
    117     QLOG_BIN("field8", bin_buf, sizeof(bin_buf));
    118     QLOG_CID("field9", &qti.odcid);
    119     QLOG_BEGIN("subgroup")
    120     QLOG_STR("field10", "baz");
    121     QLOG_END()
    122     QLOG_BEGIN_ARRAY("array")
    123     QLOG_STR(NULL, "a");
    124     QLOG_STR(NULL, "b");
    125     QLOG_END_ARRAY()
    126     QLOG_EVENT_END()
    127 
    128     /* not enabled */
    129     QLOG_EVENT_BEGIN(qlog, transport, packet_received)
    130     QLOG_STR("field1", "foo");
    131     QLOG_EVENT_END()
    132 
    133     /* test delta time calculation */
    134     QLOG_EVENT_BEGIN(qlog, transport, packet_sent)
    135     QLOG_STR("field1", "bar");
    136     QLOG_EVENT_END()
    137 
    138     if (!TEST_true(ossl_qlog_flush(qlog)))
    139         goto err;
    140 
    141     buf_len = BIO_get_mem_data(bio, &buf);
    142     if (!TEST_size_t_gt(buf_len, 0))
    143         goto err;
    144 
    145     if (!TEST_mem_eq(buf, buf_len, expected, sizeof(expected)))
    146         goto err;
    147 
    148     testresult = 1;
    149 err:
    150     ossl_qlog_free(qlog);
    151     return testresult;
    152 }
    153 
    154 struct filter_spec {
    155     const char *filter;
    156     int expect_ok;
    157     uint32_t expect_event_type;
    158     int expect_event_enable;
    159 };
    160 
    161 static const struct filter_spec filters[] = {
    162     { "*", 1,
    163         QLOG_EVENT_TYPE_transport_packet_sent, 1 },
    164     { "-*", 1,
    165         QLOG_EVENT_TYPE_transport_packet_sent, 0 },
    166     { "+*", 1,
    167         QLOG_EVENT_TYPE_transport_packet_sent, 1 },
    168     { "* *", 1,
    169         QLOG_EVENT_TYPE_transport_packet_received, 1 },
    170     { "-* +*", 1,
    171         QLOG_EVENT_TYPE_transport_packet_received, 1 },
    172     { "-* +* -*", 1,
    173         QLOG_EVENT_TYPE_transport_packet_received, 0 },
    174     { "  *", 1,
    175         QLOG_EVENT_TYPE_transport_packet_sent, 1 },
    176     { " ", 1,
    177         QLOG_EVENT_TYPE_transport_packet_sent, 0 },
    178     { "", 1,
    179         QLOG_EVENT_TYPE_transport_packet_sent, 0 },
    180     { "transport:packet_sent", 1,
    181         QLOG_EVENT_TYPE_transport_packet_sent, 1 },
    182     { "transport:packet_sent", 1,
    183         QLOG_EVENT_TYPE_transport_packet_received, 0 },
    184     { "* -transport:packet_sent", 1,
    185         QLOG_EVENT_TYPE_transport_packet_received, 1 },
    186     { "* -transport:packet_sent", 1,
    187         QLOG_EVENT_TYPE_transport_packet_sent, 0 },
    188     { "unknown:event", 1,
    189         QLOG_EVENT_TYPE_transport_packet_sent, 0 },
    190     { "unknown:event +transport:packet_sent", 1,
    191         QLOG_EVENT_TYPE_transport_packet_sent, 1 },
    192     { "unknown:event transport:*", 1,
    193         QLOG_EVENT_TYPE_transport_packet_sent, 1 },
    194     { "unknown:event +transport:* -transport:packet_sent", 1,
    195         QLOG_EVENT_TYPE_transport_packet_received, 1 },
    196     { "unknown:event transport:* -transport:packet_sent", 1,
    197         QLOG_EVENT_TYPE_transport_packet_sent, 0 },
    198     { "* -transport:*", 1,
    199         QLOG_EVENT_TYPE_connectivity_connection_started, 1 },
    200     { "* -transport:*", 1,
    201         QLOG_EVENT_TYPE_transport_parameters_set, 0 },
    202     { "&", 0 },
    203     { "event_name_without_category", 0 },
    204     { "event_name_with_@badchar:foo", 0 },
    205     { "event_name_with_badchar:f@oo", 0 },
    206     { "category:", 0 },
    207     { ":name", 0 },
    208     { ":", 0 },
    209     { "**", 0 },
    210     { "foo:bar*", 0 },
    211     { "foo:*bar", 0 },
    212     { "foo*:bar", 0 },
    213     { "*foo:bar", 0 },
    214 };
    215 
    216 static int test_qlog_filter(int idx)
    217 {
    218     int testresult = 0;
    219     QLOG_TRACE_INFO qti = { 0 };
    220     QLOG *qlog;
    221 
    222     qti.odcid.id_len = 1;
    223     qti.odcid.id[0] = 0x55;
    224 
    225     if (!TEST_ptr(qlog = ossl_qlog_new(&qti)))
    226         goto err;
    227 
    228     if (!TEST_int_eq(ossl_qlog_set_filter(qlog, filters[idx].filter),
    229             filters[idx].expect_ok))
    230         goto err;
    231 
    232     if (filters[idx].expect_event_type != QLOG_EVENT_TYPE_NONE)
    233         if (!TEST_int_eq(ossl_qlog_enabled(qlog, filters[idx].expect_event_type),
    234                 filters[idx].expect_event_enable))
    235             goto err;
    236 
    237     testresult = 1;
    238 err:
    239     ossl_qlog_free(qlog);
    240     return testresult;
    241 }
    242 
    243 int setup_tests(void)
    244 {
    245     ADD_TEST(test_qlog);
    246     ADD_ALL_TESTS(test_qlog_filter, OSSL_NELEM(filters));
    247     return 1;
    248 }
    249