]> git.ipfire.org Git - thirdparty/snort3.git/commitdiff
Merge pull request #1147 in SNORT/snort3 from dbg_cleanup to master
authorTom Peters (thopeter) <thopeter@cisco.com>
Thu, 22 Mar 2018 20:02:47 +0000 (16:02 -0400)
committerTom Peters (thopeter) <thopeter@cisco.com>
Thu, 22 Mar 2018 20:02:47 +0000 (16:02 -0400)
Squashed commit of the following:

commit e21086ce7aef8b7324ca12cc32aea20a94b60b59
Author: snorty <mdagon@cisco.com>
Date:   Mon Mar 19 11:15:09 2018 -0400

    Debug messages: cleanup for service inspectors.
    Removal of Debug messages from imap, pop, ssl, rpc_decode and dce_tcp.
    Conversion to trace for some of the dce_smb and dce_udp debugs.
    New traces for detection, stream.

34 files changed:
src/detection/detect_trace.h
src/detection/detection_engine.cc
src/main/snort_debug.h
src/service_inspectors/dce_rpc/dce_co.cc
src/service_inspectors/dce_rpc/dce_common.cc
src/service_inspectors/dce_rpc/dce_list.cc
src/service_inspectors/dce_rpc/dce_smb.cc
src/service_inspectors/dce_rpc/dce_smb2.cc
src/service_inspectors/dce_rpc/dce_smb_commands.cc
src/service_inspectors/dce_rpc/dce_smb_module.cc
src/service_inspectors/dce_rpc/dce_smb_module.h
src/service_inspectors/dce_rpc/dce_smb_paf.cc
src/service_inspectors/dce_rpc/dce_smb_paf.h
src/service_inspectors/dce_rpc/dce_smb_transaction.cc
src/service_inspectors/dce_rpc/dce_smb_transaction_utils.cc
src/service_inspectors/dce_rpc/dce_smb_utils.cc
src/service_inspectors/dce_rpc/dce_tcp.cc
src/service_inspectors/dce_rpc/dce_tcp_paf.cc
src/service_inspectors/dce_rpc/dce_udp.cc
src/service_inspectors/dce_rpc/dce_udp_module.cc
src/service_inspectors/dce_rpc/dce_udp_module.h
src/service_inspectors/dce_rpc/dce_udp_processing.cc
src/service_inspectors/dce_rpc/dce_utils.cc
src/service_inspectors/dce_rpc/dce_utils.h
src/service_inspectors/dce_rpc/smb_message.cc
src/service_inspectors/imap/imap.cc
src/service_inspectors/imap/imap_paf.cc
src/service_inspectors/pop/pop.cc
src/service_inspectors/pop/pop_paf.cc
src/service_inspectors/rpc_decode/rpc_decode.cc
src/service_inspectors/ssl/ssl_inspector.cc
src/stream/base/stream_module.cc
src/stream/base/stream_module.h
src/stream/stream.cc

index 877f0b34bef53b7bf418a2976e108c8ab1b33e1d..ca91df7d2f0473c518c892cc547a9ca2c9681535 100644 (file)
@@ -41,7 +41,8 @@ enum
     TRACE_BUFFER_MINIMAL = 0x4,
     TRACE_BUFFER_VERBOSE = 0x8,
     TRACE_RULE_VARS = 0x10,
-    TRACE_FP_SEARCH = 0x20
+    TRACE_FP_SEARCH = 0x20,
+    TRACE_PKT_DETECTION = 0x40,
 };
 
 void clear_trace_cursor_info();
index 666b9323dcb52d54ada3a98ce34e160d91c69df0..58d047f9bbc5501e1c98174f4dfaa1f819d5712d 100644 (file)
@@ -173,6 +173,7 @@ void DetectionEngine::disable_content(Packet* p)
 {
     if ( p->context->active_rules == IpsContext::CONTENT )
         p->context->active_rules = IpsContext::NON_CONTENT;
+    trace_logf(detection, TRACE_PKT_DETECTION, "Disabled content detect, packet %" PRIu64"\n", pc.total_from_daq);
 }
 
 void DetectionEngine::enable_content(Packet* p)
index a3f529d6dfb2fb9bde10c8cf20896f32786de385..774314c3e029c1520f549c404150498d07a2888b 100644 (file)
 #define DEBUG_STREAM          0x0000000200000000LL
 #define DEBUG_STREAM_STATE    0x0000000400000000LL
 #define DEBUG_STREAM_PAF      0x0000000800000000LL
-#define DEBUG_HTTPINSPECT     0x0000001000000000LL
 #define DEBUG_ASN1            0x0000002000000000LL
 #define DEBUG_DNS             0x0000004000000000LL
 #define DEBUG_FTPTELNET       0x0000008000000000LL
 #define DEBUG_GTP             0x0000010000000000LL
-#define DEBUG_IMAP            0x0000020000000000LL
-#define DEBUG_POP             0x0000040000000000LL
-#define DEBUG_RPC             0x0000080000000000LL
 #define DEBUG_SIP             0x0000100000000000LL
-#define DEBUG_SSL             0x0000200000000000LL
 #define DEBUG_SMTP            0x0000400000000000LL
 #define DEBUG_REPUTATION      0x0000800000000000LL
 
 #define DEBUG_MPSE            0x0010000000000000LL
 #define DEBUG_SO_RULE         0x0020000000000000LL
 #define DEBUG_LOGGER          0x0040000000000000LL
-#define DEBUG_DCE_TCP         0x0080000000000000LL
-#define DEBUG_DCE_SMB         0x0100000000000000LL
-#define DEBUG_DCE_COMMON      0x0200000000000000LL
 #define DEBUG_APPID           0x0400000000000000LL
 
 #ifdef PIGLET
 #define DEBUG_PIGLET          0x0800000000000000LL
 #endif
 
-#define DEBUG_DCE_UDP         0x1000000000000000LL
-
 typedef uint64_t Trace;
 
 bool trace_enabled(Trace mask);
index 11b06000763088ec7d4a992f3a307efa48b072f0..7a7bf73435888e489504eaf74f7c3b9369f4ca2e 100644 (file)
@@ -25,7 +25,6 @@
 
 #include "dce_co.h"
 
-#include "main/snort_debug.h"
 #include "utils/util.h"
 
 #include "dce_smb.h"
@@ -641,16 +640,6 @@ static void DCE2_CoCtxReq(DCE2_SsnData* sd, DCE2_CoTracker* cot, const DceRpcCoH
         {
             return;
         }
-        DEBUG_WRAP(char uuid_buf[DCE2_UUID_BUF_SIZE];)
-
-        DebugFormat(DEBUG_DCE_COMMON, "Added Context item to queue.\n"
-            " Context id: %hu\n"
-            " Interface: %s\n"
-            " Interface major version: %hu\n"
-            " Interface minor version: %hu\n",
-            ctx_node->ctx_id,
-            DCE2_UuidToStr(&ctx_node->iface, DCERPC_BO_FLAG__NONE, uuid_buf),
-            ctx_node->iface_vers_maj, ctx_node->iface_vers_min);
 
         switch (policy)
         {
@@ -689,30 +678,17 @@ static void dce_co_process_ctx_result(DCE2_SsnData* sd,DCE2_CoTracker* cot,
     ctx_node = (DCE2_CoCtxIdNode*)DCE2_QueueDequeue(cot->pending_ctx_ids);
     if (ctx_node == nullptr)
     {
-        DebugMessage(DEBUG_DCE_COMMON, "Failed to dequeue a context id node.\n");
         return;
     }
-    DEBUG_WRAP(char uuid_buf[DCE2_UUID_BUF_SIZE];)
-
-    DebugFormat(DEBUG_DCE_COMMON, "Adding Context item to context item list.\n"
-        " Context id: %hu\n"
-        " Interface: %s\n"
-        " Interface major version: %hu\n"
-        " Interface minor version: %hu\n",
-        ctx_node->ctx_id,
-        DCE2_UuidToStr(&ctx_node->iface, DCERPC_BO_FLAG__NONE, uuid_buf),
-        ctx_node->iface_vers_maj, ctx_node->iface_vers_min);
 
     if (result == DCERPC_CO_CONT_DEF_RESULT__ACCEPTANCE)
     {
-        DebugMessage(DEBUG_DCE_COMMON, "Server accepted context item.\n");
         ctx_node->state = DCE2_CO_CTX_STATE__ACCEPTED;
         if (DceRpcCoPduType(co_hdr) == DCERPC_PDU_TYPE__BIND_ACK)
             cot->got_bind = 1;
     }
     else
     {
-        DebugMessage(DEBUG_DCE_COMMON, "Server rejected context item.\n");
         ctx_node->state = DCE2_CO_CTX_STATE__REJECTED;
         cot->got_bind = 0;
     }
@@ -773,8 +749,6 @@ static void dce_co_process_ctx_result(DCE2_SsnData* sd,DCE2_CoTracker* cot,
         if (status != DCE2_RET__SUCCESS)
         {
             snort_free((void*)ctx_node);
-            DebugMessage(DEBUG_DCE_COMMON,
-                "Failed to add context id node to list.\n");
             return;
         }
     }
@@ -936,7 +910,7 @@ static void DCE2_CoBind(DCE2_SsnData* sd, DCE2_CoTracker* cot,
         break;
 
     default:
-        DebugFormat(DEBUG_DCE_COMMON, "Invalid policy: %d\n", policy);
+        assert(false);
         return;
     }
 
@@ -998,7 +972,7 @@ static void DCE2_CoAlterCtx(DCE2_SsnData* sd, DCE2_CoTracker* cot,
         break;
 
     default:
-        DebugFormat(DEBUG_DCE_COMMON, "Invalid policy: %d\n", policy);
+        assert(false);
         break;
     }
 
@@ -1084,7 +1058,7 @@ static DCE2_RpktType DCE2_CoGetRpktType(DCE2_SsnData* sd, DCE2_BufType btype)
             break;
 
         default:
-            DebugFormat(DEBUG_DCE_COMMON, "Invalid buffer type: %d\n", btype);
+            assert(false);
             break;
         }
         break;
@@ -1102,13 +1076,13 @@ static DCE2_RpktType DCE2_CoGetRpktType(DCE2_SsnData* sd, DCE2_BufType btype)
             break;
 
         default:
-            DebugFormat(DEBUG_DCE_COMMON, "Invalid buffer type: %d\n", btype);
+            assert(false);
             break;
         }
         break;
 
     default:
-        DebugFormat(DEBUG_DCE_COMMON, "Invalid transport type: %d", sd->trans);
+        assert(false);
         break;
     }
     return rtype;
@@ -1169,7 +1143,7 @@ static Packet* DCE2_CoGetRpkt(DCE2_SsnData* sd, DCE2_CoTracker* cot,
         break;
 
     default:
-        DebugFormat(DEBUG_DCE_COMMON, "Invalid CO rpkt type: %d\n", co_rtype);
+        assert(false);
         return nullptr;
     }
 
@@ -1246,10 +1220,9 @@ static Packet* dce_co_reassemble(DCE2_SsnData* sd, DCE2_CoTracker* cot,
     Packet* rpkt = DCE2_CoGetRpkt(sd, cot, co_rtype, &rpkt_type);
     if (rpkt == nullptr)
     {
-        DebugMessage(DEBUG_DCE_COMMON, "Could not create DCE/RPC frag reassembled buffer.\n");
         return nullptr;
     }
-    uint8_t *wrdata = const_cast<uint8_t*>(rpkt->data);
+    uint8_twrdata = const_cast<uint8_t*>(rpkt->data);
 
     switch (rpkt_type)
     {
@@ -1304,7 +1277,7 @@ static Packet* dce_co_reassemble(DCE2_SsnData* sd, DCE2_CoTracker* cot,
         return rpkt;
 
     default:
-        DebugFormat(DEBUG_DCE_COMMON, "Invalid rpkt type: %d\n", rpkt_type);
+        assert(false);
         return nullptr;
     }
 }
@@ -1328,9 +1301,6 @@ static void DCE2_CoReassemble(DCE2_SsnData* sd, DCE2_CoTracker* cot, DCE2_CoRpkt
 
     DCE2_CoSetRopts(sd, cot, co_hdr, rpkt);
 
-    DebugMessage(DEBUG_DCE_COMMON, "Reassembled CO fragmented packet:\n");
-    DCE2_PrintPktData(rpkt->data, rpkt->dsize);
-
     DCE2_Detect(sd);
     co_reassembled = 1;
 }
@@ -1554,7 +1524,6 @@ static void DCE2_CoRequest(DCE2_SsnData* sd, DCE2_CoTracker* cot,
     if (DceRpcCoFirstFrag(co_hdr) && DceRpcCoLastFrag(co_hdr))
     {
         int auth_len = DCE2_CoGetAuthLen(sd, co_hdr, frag_ptr, frag_len);
-        DebugMessage(DEBUG_DCE_COMMON, "First and last fragment.\n");
         if (auth_len == -1)
             return;
         DCE2_CoSetRopts(sd, cot, co_hdr, sd->wire_pkt);
@@ -1566,16 +1535,6 @@ static void DCE2_CoRequest(DCE2_SsnData* sd, DCE2_CoTracker* cot,
 
         dce_common_stats->co_req_fragments++;
 
-        if (DceRpcCoFirstFrag(co_hdr))
-            DebugMessage(DEBUG_DCE_COMMON, "First fragment.\n");
-        else if (DceRpcCoLastFrag(co_hdr))
-            DebugMessage(DEBUG_DCE_COMMON, "Last fragment.\n");
-        else
-        {
-            DebugMessage(DEBUG_DCE_COMMON, "Middle fragment.\n");
-        }
-        DCE2_PrintPktData(frag_ptr, frag_len);
-
         if (auth_len == -1)
             return;
 
@@ -1630,7 +1589,7 @@ static void DCE2_CoRequest(DCE2_SsnData* sd, DCE2_CoTracker* cot,
             break;
 
         default:
-            DebugFormat(DEBUG_DCE_COMMON, "Invalid policy: %d\n", policy);
+            assert(false);
             break;
         }
 
@@ -1668,7 +1627,7 @@ static void DCE2_CoRequest(DCE2_SsnData* sd, DCE2_CoTracker* cot,
             break;
 
         default:
-            DebugFormat(DEBUG_DCE_COMMON, "Invalid policy: %d\n", policy);
+            assert(false);
             break;
         }
 
@@ -1737,7 +1696,6 @@ static void DCE2_CoResponse(DCE2_SsnData* sd, DCE2_CoTracker* cot,
 
         if (ctx_node == nullptr)
         {
-            DebugMessage(DEBUG_DCE_COMMON, "Failed to dequeue a context id node.\n");
             return;
         }
 
@@ -1774,7 +1732,7 @@ static void DCE2_CoResponse(DCE2_SsnData* sd, DCE2_CoTracker* cot,
     if (DceRpcCoFirstFrag(co_hdr) && DceRpcCoLastFrag(co_hdr))
     {
         int auth_len = DCE2_CoGetAuthLen(sd, co_hdr, frag_ptr, frag_len);
-        DebugMessage(DEBUG_DCE_COMMON, "First and last fragment.\n");
+
         if (auth_len == -1)
             return;
         DCE2_CoSetRopts(sd, cot, co_hdr, sd->wire_pkt);
@@ -1820,15 +1778,12 @@ static void DCE2_CoDecode(DCE2_SsnData* sd, DCE2_CoTracker* cot,
      * start of the pdu */
     DCE2_MOVE(frag_ptr, frag_len, sizeof(DceRpcCoHdr));
 
-    DebugMessage(DEBUG_DCE_COMMON, "PDU type: ");
-
     /* Client specific pdu types - some overlap with server */
     if (DCE2_SsnFromClient(sd->wire_pkt))
     {
         switch (pdu_type)
         {
         case DCERPC_PDU_TYPE__BIND:
-            DebugMessage(DEBUG_DCE_COMMON, "Bind\n");
             dce_common_stats->co_bind++;
 
             /* Make sure context id list and queue are initialized */
@@ -1840,7 +1795,6 @@ static void DCE2_CoDecode(DCE2_SsnData* sd, DCE2_CoTracker* cot,
             break;
 
         case DCERPC_PDU_TYPE__ALTER_CONTEXT:
-            DebugMessage(DEBUG_DCE_COMMON, "Alter Context\n");
             dce_common_stats->co_alter_ctx++;
 
             if (DCE2_CoInitCtxStorage(cot) != DCE2_RET__SUCCESS)
@@ -1851,7 +1805,6 @@ static void DCE2_CoDecode(DCE2_SsnData* sd, DCE2_CoTracker* cot,
             break;
 
         case DCERPC_PDU_TYPE__REQUEST:
-            DebugMessage(DEBUG_DCE_COMMON, "Request\n");
             dce_common_stats->co_request++;
 
             if (DCE2_ListIsEmpty(cot->ctx_ids) &&
@@ -1865,27 +1818,22 @@ static void DCE2_CoDecode(DCE2_SsnData* sd, DCE2_CoTracker* cot,
             break;
 
         case DCERPC_PDU_TYPE__AUTH3:
-            DebugMessage(DEBUG_DCE_COMMON, "Auth3\n");
             dce_common_stats->co_auth3++;
             break;
 
         case DCERPC_PDU_TYPE__CO_CANCEL:
-            DebugMessage(DEBUG_DCE_COMMON, "Cancel\n");
             dce_common_stats->co_cancel++;
             break;
 
         case DCERPC_PDU_TYPE__ORPHANED:
-            DebugMessage(DEBUG_DCE_COMMON, "Orphaned\n");
             dce_common_stats->co_orphaned++;
             break;
 
         case DCERPC_PDU_TYPE__MICROSOFT_PROPRIETARY_OUTLOOK2003_RPC_OVER_HTTP:
-            DebugMessage(DEBUG_DCE_COMMON, "Microsoft Request To Send RPC over HTTP\n");
             dce_common_stats->co_ms_pdu++;
             break;
 
         default:
-            DebugFormat(DEBUG_DCE_COMMON, "Unknown (0x%02x)\n", pdu_type);
             dce_common_stats->co_other_req++;
             break;
         }
@@ -1898,12 +1846,10 @@ static void DCE2_CoDecode(DCE2_SsnData* sd, DCE2_CoTracker* cot,
         case DCERPC_PDU_TYPE__ALTER_CONTEXT_RESP:
             if (pdu_type == DCERPC_PDU_TYPE__BIND_ACK)
             {
-                DebugMessage(DEBUG_DCE_COMMON, "Bind Ack\n");
                 dce_common_stats->co_bind_ack++;
             }
             else
             {
-                DebugMessage(DEBUG_DCE_COMMON, "Alter Context Response\n");
                 dce_common_stats->co_alter_ctx_resp++;
             }
 
@@ -1920,7 +1866,6 @@ static void DCE2_CoDecode(DCE2_SsnData* sd, DCE2_CoTracker* cot,
             break;
 
         case DCERPC_PDU_TYPE__BIND_NACK:
-            DebugMessage(DEBUG_DCE_COMMON, "Bind Nack\n");
             dce_common_stats->co_bind_nack++;
 
             /* Bind nack in Windows seems to blow any previous context away */
@@ -1944,13 +1889,11 @@ static void DCE2_CoDecode(DCE2_SsnData* sd, DCE2_CoTracker* cot,
             break;
 
         case DCERPC_PDU_TYPE__RESPONSE:
-            DebugMessage(DEBUG_DCE_COMMON, "Response\n");
             dce_common_stats->co_response++;
             DCE2_CoResponse(sd, cot, co_hdr, frag_ptr, frag_len);
             break;
 
         case DCERPC_PDU_TYPE__FAULT:
-            DebugMessage(DEBUG_DCE_COMMON, "Fault\n");
             dce_common_stats->co_fault++;
 
             /* Clear out the client side */
@@ -1963,12 +1906,10 @@ static void DCE2_CoDecode(DCE2_SsnData* sd, DCE2_CoTracker* cot,
             break;
 
         case DCERPC_PDU_TYPE__SHUTDOWN:
-            DebugMessage(DEBUG_DCE_COMMON, "Shutdown\n");
             dce_common_stats->co_shutdown++;
             break;
 
         case DCERPC_PDU_TYPE__REJECT:
-            DebugMessage(DEBUG_DCE_COMMON, "Reject\n");
             dce_common_stats->co_reject++;
 
             DCE2_QueueEmpty(cot->pending_ctx_ids);
@@ -1976,12 +1917,10 @@ static void DCE2_CoDecode(DCE2_SsnData* sd, DCE2_CoTracker* cot,
             break;
 
         case DCERPC_PDU_TYPE__MICROSOFT_PROPRIETARY_OUTLOOK2003_RPC_OVER_HTTP:
-            DebugMessage(DEBUG_DCE_COMMON, "Microsoft Request To Send RPC over HTTP\n");
             dce_common_stats->co_ms_pdu++;
             break;
 
         default:
-            DebugFormat(DEBUG_DCE_COMMON, "Unknown (0x%02x)\n", pdu_type);
             dce_common_stats->co_other_resp++;
             break;
         }
@@ -2074,13 +2013,10 @@ static void DCE2_CoEarlyReassemble(DCE2_SsnData* sd, DCE2_CoTracker* cot)
         {
             if (seg_bytes == 0)
             {
-                DebugMessage(DEBUG_DCE_COMMON, "Early reassemble - DCE/RPC fragments\n");
                 DCE2_CoReassemble(sd, cot, DCE2_CO_RPKT_TYPE__FRAG);
             }
             else
             {
-                DebugMessage(DEBUG_DCE_COMMON,
-                    "Early reassemble - DCE/RPC fragments and segments\n");
                 DCE2_CoReassemble(sd, cot, DCE2_CO_RPKT_TYPE__ALL);
             }
         }
@@ -2093,13 +2029,9 @@ static void DCE2_CoEarlyReassemble(DCE2_SsnData* sd, DCE2_CoTracker* cot)
         {
             DCE2_Ret status;
 
-            DebugMessage(DEBUG_DCE_COMMON, "Early reassemble - DCE/RPC segments\n");
-
             status = DCE2_CoSegEarlyRequest(cot, DCE2_BufferData(cot->cli_seg.buf), bytes);
             if (status != DCE2_RET__SUCCESS)
             {
-                DebugMessage(DEBUG_DCE_COMMON,
-                    "Not enough data in seg buffer to set rule option data.\n");
                 return;
             }
 
@@ -2149,7 +2081,7 @@ static Packet* DCE2_CoGetSegRpkt(DCE2_SsnData* sd,
         break;
 
     default:
-        DebugFormat(DEBUG_DCE_COMMON, "Invalid transport type: %d\n", sd->trans);
+        assert(false);
         break;
     }
 
@@ -2205,17 +2137,13 @@ static void DCE2_CoSegDecode(DCE2_SsnData* sd, DCE2_CoTracker* cot, DCE2_CoSeg*
         break;
 
     default:
-        DebugFormat(DEBUG_DCE_COMMON, "Invalid transport type: %d\n",
-            sd->trans);
+        assert(false);
         return;
     }
 
     /* All is good.  Decode the pdu */
     DCE2_CoDecode(sd, cot, frag_ptr, frag_len);
 
-    DebugMessage(DEBUG_DCE_COMMON, "Reassembled CO segmented packet\n");
-    DCE2_PrintPktData(rpkt->data, rpkt->dsize);
-
     /* Call detect since this is a reassembled packet that the
      * detection engine hasn't seen yet */
     if (!co_reassembled)
@@ -2335,8 +2263,6 @@ void DCE2_CoProcess(DCE2_SsnData* sd, DCE2_CoTracker* cot,
     {
         num_frags++;
 
-        DebugFormat(DEBUG_DCE_COMMON, "DCE/RPC message number: %u\n", num_frags);
-
         /* Fast track full fragments */
         if (DCE2_BufferIsEmpty(seg->buf))
         {
@@ -2347,9 +2273,6 @@ void DCE2_CoProcess(DCE2_SsnData* sd, DCE2_CoTracker* cot,
             /* Not enough data left for a header.  Buffer it and return */
             if (data_len < sizeof(DceRpcCoHdr))
             {
-                DebugMessage(DEBUG_DCE_COMMON,
-                    "Not enough data in packet for DCE/RPC Connection-oriented header.\n");
-
                 DCE2_CoHandleSegmentation(sd, seg, data_ptr, data_len, sizeof(DceRpcCoHdr),
                     &data_used);
 
@@ -2365,9 +2288,6 @@ void DCE2_CoProcess(DCE2_SsnData* sd, DCE2_CoTracker* cot,
             /* Not enough data left for the pdu. */
             if (data_len < frag_len)
             {
-                DebugFormat(DEBUG_DCE_COMMON,
-                    "Not enough data in packet for fragment length: %hu\n", frag_len);
-
                 /* Set frag length so we don't have to check it again in seg code */
                 seg->frag_len = frag_len;
 
@@ -2394,9 +2314,6 @@ void DCE2_CoProcess(DCE2_SsnData* sd, DCE2_CoTracker* cot,
         {
             uint16_t data_used = 0;
 
-            DebugFormat(DEBUG_DCE_COMMON, "Segmentation buffer has %u bytes\n",
-                DCE2_BufferLength(seg->buf));
-
             // Need more data to get header
             if (DCE2_BufferLength(seg->buf) < sizeof(DceRpcCoHdr))
             {
@@ -2419,7 +2336,8 @@ void DCE2_CoProcess(DCE2_SsnData* sd, DCE2_CoTracker* cot,
                     data_back = -data_used;
                     DCE2_MOVE(data_ptr, data_len, data_back);
                     /*Check the original packet*/
-                    if (DCE2_CoHdrChecks(sd, cot, (const DceRpcCoHdr*)data_ptr) != DCE2_RET__SUCCESS)
+                    if (DCE2_CoHdrChecks(sd, cot, (const DceRpcCoHdr*)data_ptr) !=
+                        DCE2_RET__SUCCESS)
                         return;
                     else
                     {
index abdfadd70863b6ec663db440c2916d234dc026ce..df7ad902ee266fa1238cba7f133c73844c4e045c 100644 (file)
@@ -27,7 +27,6 @@
 #include "detection/detection_engine.h"
 #include "ips_options/extract.h"
 #include "log/messages.h"
-#include "main/snort_debug.h"
 #include "utils/safec.h"
 
 #include "dce_http_proxy_module.h"
@@ -130,56 +129,21 @@ bool dce2_paf_abort(Flow* flow, DCE2_SsnData* sd)
 {
     if (flow->get_session_flags() & SSNFLAG_MIDSTREAM)
     {
-        DebugMessage(DEBUG_DCE_COMMON,
-            "Aborting PAF because of midstream pickup.\n");
         return true;
     }
     else if (!(flow->get_session_flags() & SSNFLAG_ESTABLISHED))
     {
-        DebugMessage(DEBUG_DCE_COMMON,
-            "Aborting PAF because of unestablished session.\n");
         return true;
     }
 
     if ((sd != nullptr) && DCE2_SsnNoInspect(sd))
     {
-        DebugMessage(DEBUG_DCE_COMMON, "Aborting PAF because of session data check.\n");
         return true;
     }
 
     return false;
 }
 
-static void DCE2_PrintRoptions(DCE2_Roptions* ropts)
-{
-    DebugFormat(DEBUG_DCE_COMMON,
-        "  First frag: %s\n", ropts->first_frag == 1 ? "yes" : (ropts->first_frag == 0 ? "no" :
-        "unset"));
-    if (ropts->first_frag == DCE2_SENTINEL)
-    {
-        DebugMessage(DEBUG_DCE_COMMON, "  Iface: unset\n");
-        DebugMessage(DEBUG_DCE_COMMON, "  Iface version: unset\n");
-    }
-    else
-    {
-        DEBUG_WRAP(char uuid_buf[DCE2_UUID_BUF_SIZE];)
-        DebugFormat(DEBUG_DCE_COMMON, "  Iface: %s\n",
-            DCE2_UuidToStr(&ropts->iface, DCERPC_BO_FLAG__NONE, uuid_buf));
-        DebugFormat(DEBUG_DCE_COMMON, "  Iface version: %hu\n", ropts->iface_vers_maj);
-    }
-    if (ropts->opnum == DCE2_SENTINEL)
-        DebugMessage(DEBUG_DCE_COMMON, "  Opnum: unset\n");
-    else
-    {
-        DebugFormat(DEBUG_DCE_COMMON, "  Opnum: %d\n", ropts->opnum);
-    }
-    if (ropts->stub_data != nullptr)
-        DebugFormat(DEBUG_DCE_COMMON, "  Stub data: %p\n", ropts->stub_data);
-    else
-    {
-        DebugMessage(DEBUG_DCE_COMMON, "  Stub data: NULL\n");
-    }
-}
 
 static void dce2_protocol_detect(DCE2_SsnData* sd, snort::Packet* pkt)
 {
@@ -212,22 +176,9 @@ void DCE2_Detect(DCE2_SsnData* sd)
         return;
     }
     snort::Packet* top_pkt = DetectionEngine::get_current_packet();
-
-    DCE2_PrintRoptions(&sd->ropts);
-    DebugMessage(DEBUG_DCE_COMMON, "Payload:\n");
-    DCE2_PrintPktData(top_pkt->data, top_pkt->dsize);
-
-    if (sd->ropts.stub_data != nullptr)
-    {
-        DebugMessage(DEBUG_DCE_COMMON,"\nStub data:\n");
-        DCE2_PrintPktData(sd->ropts.stub_data,
-            top_pkt->dsize - (sd->ropts.stub_data - top_pkt->data));
-    }
-
     dce2_protocol_detect(sd, top_pkt);
     /* Always reset rule option data after detecting */
     DCE2_ResetRopts(&sd->ropts);
-    DebugMessage(DEBUG_DCE_COMMON, "----------------------------------------------------------\n");
 }
 
 DCE2_SsnData* get_dce2_session_data(snort::Packet* p)
@@ -276,37 +227,23 @@ bool DceEndianness::get_offset_endianness(int32_t offset, uint8_t& endian)
 
     if ((data_byte_order == DCE2_SENTINEL) ||
         (hdr_byte_order == DCE2_SENTINEL))
-    {
-        DebugMessage(DEBUG_DCE_COMMON,
-            "Data byte order or header byte order not set "
-            "in rule options - not evaluating.\n");
         return false;
-    }
 
     if (stub_data_offset == DCE2_SENTINEL)
     {
-        DebugMessage(DEBUG_DCE_COMMON, "Stub data is NULL.  "
-            "Setting byte order to that of the header.\n");
         byte_order = (DceRpcBoFlag)hdr_byte_order;
     }
     else if (offset < stub_data_offset)
     {
-        DebugMessage(DEBUG_DCE_COMMON,
-            "Reading data in the header.  Setting byte order "
-            "to that of the header.\n");
         byte_order = (DceRpcBoFlag)hdr_byte_order;
     }
     else
     {
-        DebugMessage(DEBUG_DCE_COMMON,
-            "Reading data in the stub.  Setting byte order "
-            "to that of the stub data.\n");
         byte_order = (DceRpcBoFlag)data_byte_order;
     }
 
     endian = (byte_order == DCERPC_BO_FLAG__BIG_ENDIAN) ? ENDIAN_BIG : ENDIAN_LITTLE;
-    DebugFormat(DEBUG_DCE_COMMON, " Byte order: %s\n",
-        endian == ENDIAN_LITTLE ? "little endian" : "big endian");
     return true;
 }
 
@@ -345,7 +282,7 @@ uint16_t DCE2_GetRpktMaxData(DCE2_SsnData* sd, DCE2_RpktType rtype)
         break;
 
     default:
-        DebugFormat(DEBUG_DCE_COMMON,"Invalid reassembly packet type: %d\n",rtype);
+        assert(false);
         return 0;
     }
     return (snort::Packet::max_dsize - overhead);
@@ -463,7 +400,6 @@ snort::Packet* DCE2_GetRpkt(snort::Packet* p,DCE2_RpktType rpkt_type,
         break;
 
     default:
-        DebugFormat(DEBUG_DCE_COMMON, "Invalid reassembly packet type: %d\n",rpkt_type);
         assert(false);
         return nullptr;
     }
@@ -473,7 +409,6 @@ snort::Packet* DCE2_GetRpkt(snort::Packet* p,DCE2_RpktType rpkt_type,
 
     if (data_len > snort::Packet::max_dsize - data_overhead)
     {
-        DebugMessage(DEBUG_DCE_COMMON, "Failed to create reassembly packet.\n");
         delete rpkt->endianness;
         rpkt->endianness = nullptr;
         return nullptr;
index 40b5b638a1d00da814c5d4f1b56e4e8a0daa6e43..4709033d892383e030b255fb4023be9c6791e3d0 100644 (file)
@@ -31,7 +31,6 @@
 
 #include "dce_list.h"
 
-#include "main/snort_debug.h"
 #include "utils/util.h"
 
 /********************************************************************
@@ -108,10 +107,8 @@ DCE2_List* DCE2_ListNew(DCE2_ListType type, DCE2_ListKeyCompare kc,
 static void DCE2_ListInsertTail(DCE2_List* list, DCE2_ListNode* n)
 {
     if ((list == nullptr) || (n == nullptr))
-    {
-        DebugMessage(DEBUG_DCE_COMMON, "List and/or list node passed in was NULL\n");
         return;
-    }
+
 
     if (list->tail == nullptr)
     {
@@ -146,10 +143,7 @@ static void DCE2_ListInsertTail(DCE2_List* list, DCE2_ListNode* n)
 static void DCE2_ListInsertHead(DCE2_List* list, DCE2_ListNode* n)
 {
     if ((list == nullptr) || (n == nullptr))
-    {
-        DebugMessage(DEBUG_DCE_COMMON, "List and/or list node passed in was NULL\n");
-        return;
-    }
+        return;   
 
     if (list->head == nullptr)
     {
@@ -187,10 +181,7 @@ static void DCE2_ListInsertHead(DCE2_List* list, DCE2_ListNode* n)
 static void DCE2_ListInsertBefore(DCE2_List* list, DCE2_ListNode* insert, DCE2_ListNode* front)
 {
     if ((list == nullptr) || (insert == nullptr) || (front == nullptr))
-    {
-        DebugMessage(DEBUG_DCE_COMMON, "List, insert node and/or front node passed in was NULL\n");
         return;
-    }
 
     if (front == list->head)
     {
index a7f820774f30501501aface95025571db93957b0..e7c861605bdf249d7c279adfe28ae6e2f459b22b 100644 (file)
@@ -385,8 +385,6 @@ void Dce2Smb::eval(snort::Packet* p)
 
     if (p->flow->get_session_flags() & SSNFLAG_MIDSTREAM)
     {
-        DebugMessage(DEBUG_DCE_SMB,
-            "Midstream - not inspecting.\n");
         return;
     }
 
index 9648306d55857471728b42e7d5df7474f1ea70b9..2dc3ae4514a4af435a9b3c5b4af915c6b5a47e68 100644 (file)
@@ -69,21 +69,18 @@ static inline void DCE2_Smb2InsertTid(DCE2_SmbSsnData* ssd, const uint32_t tid,
         || ((ssd->max_file_depth == -1) && DCE2_ScSmbFileDepth(
         (dce2SmbProtoConf*)ssd->sd.config) == -1)))
     {
-        DebugFormat(DEBUG_DCE_SMB, "Not inserting TID (%u) "
-            "because it's not IPC and not inspecting normal file "
-            "data.", tid);
+        trace_logf(dce_smb, "Not inserting TID (%u) because it's "
+            "not IPC and not inspecting normal file data.\n", tid);
         return;
     }
 
     if (is_ipc)
     {
-        DebugFormat(DEBUG_DCE_SMB, "Not inserting TID (%u) "
-            "because it's IPC and only inspecting normal file data.", tid);
+        trace_logf(dce_smb, "Not inserting TID (%u) "
+            "because it's IPC and only inspecting normal file data.\n", tid);
         return;
     }
 
-    DebugFormat(DEBUG_DCE_SMB, "Inserting Tid: %u\n", tid);
-
     if (ssd->tids == nullptr)
     {
         ssd->tids = DCE2_ListNew(DCE2_LIST_TYPE__SPLAYED, DCE2_Smb2TidCompare,
@@ -304,7 +301,7 @@ static void DCE2_Smb2CreateRequest(DCE2_SmbSsnData* ssd, const Smb2Hdr*,
     const Smb2CreateRequestHdr* smb_create_hdr,const uint8_t* end)
 {
     uint16_t name_offset = alignedNtohs(&(smb_create_hdr->name_offset));
-    DebugMessage(DEBUG_DCE_SMB, "Processing create request command!\n");
     DCE2_Smb2InitFileTracker(&ssd->ftracker, false, 0);
 
     if (name_offset > SMB2_HEADER_LENGTH)
@@ -338,14 +335,13 @@ static void DCE2_Smb2CreateResponse(DCE2_SmbSsnData* ssd, const Smb2Hdr*,
 {
     uint64_t fileId_persistent;
     uint64_t file_size = UNKNOWN_FILE_SIZE;
-    DebugMessage(DEBUG_DCE_SMB, "Processing create response command!\n");
+  
 
     fileId_persistent = alignedNtohq((const uint64_t*)(&(smb_create_hdr->fileId_persistent)));
     ssd->ftracker.fid_v2 = fileId_persistent;
     if (smb_create_hdr->end_of_file)
     {
-        file_size = alignedNtohq((const uint64_t*)(&(smb_create_hdr->end_of_file)));
-        DebugFormat(DEBUG_DCE_SMB, "Get file size %" PRIu64 "!\n", file_size);
+        file_size = alignedNtohq((const uint64_t*)(&(smb_create_hdr->end_of_file)));      
         ssd->ftracker.tracker.file.file_size = file_size;
     }
 
@@ -399,10 +395,6 @@ static void DCE2_Smb2Create(DCE2_SmbSsnData* ssd, const Smb2Hdr* smb_hdr,
             DCE2_Smb2FreeFileName(&(ssd->ftracker));
         }
     }
-    else
-    {
-        DebugMessage(DEBUG_DCE_SMB, "Wrong format for smb create command!\n");
-    }
 }
 
 /********************************************************************
@@ -464,7 +456,6 @@ static void DCE2_Smb2SetInfo(DCE2_SmbSsnData* ssd, const Smb2Hdr*,
         if (smb_set_info_hdr->file_info_class == SMB2_FILE_ENDOFFILE_INFO)
         {
             uint64_t file_size = alignedNtohq((const uint64_t*)file_data);
-            DebugFormat(DEBUG_DCE_SMB, "Get file size %" PRIu64 "!\n", file_size);
             ssd->ftracker.tracker.file.file_size = file_size;
             uint64_t fileId_persistent = alignedNtohq(&(smb_set_info_hdr->fileId_persistent));
             FileContext* file = get_file_context(ssd, fileId_persistent);
@@ -486,14 +477,13 @@ static void DCE2_Smb2ReadRequest(DCE2_SmbSsnData* ssd, const Smb2Hdr* smb_hdr,
 {
     uint64_t message_id, offset;
     uint64_t fileId_persistent;
-    DebugMessage(DEBUG_DCE_SMB, "Processing read request command!\n");
+  
     message_id = alignedNtohq((const uint64_t*)(&(smb_hdr->message_id)));
     offset = alignedNtohq((const uint64_t*)(&(smb_read_hdr->offset)));
     fileId_persistent = alignedNtohq((const uint64_t*)(&(smb_read_hdr->fileId_persistent)));
     DCE2_Smb2StoreRequest(ssd, message_id, offset, fileId_persistent);
     if (fileId_persistent && (ssd->ftracker.fid_v2 != fileId_persistent))
     {
-        DebugMessage(DEBUG_DCE_SMB, "Persistent file ID changed read request command!\n");
         ssd->ftracker.fid_v2 = fileId_persistent;
     }
     if (ssd->ftracker.tracker.file.file_size && (offset > ssd->ftracker.tracker.file.file_size))
@@ -517,8 +507,6 @@ static void DCE2_Smb2ReadResponse(DCE2_SmbSsnData* ssd, const Smb2Hdr* smb_hdr,
     uint16_t data_offset;
     Smb2Request* request;
 
-    DebugMessage(DEBUG_DCE_SMB, "Processing read response command!\n");
-
     message_id = alignedNtohq((const uint64_t*)(&(smb_hdr->message_id)));
     request = DCE2_Smb2GetRequest(ssd, message_id);
     if (!request)
@@ -573,7 +561,7 @@ static void DCE2_Smb2Read(DCE2_SmbSsnData* ssd, const Smb2Hdr* smb_hdr,
     {
         uint64_t message_id;
         Smb2Request* request;
-        DebugMessage(DEBUG_DCE_SMB, "Wrong format for smb read command!\n");
+    
         message_id = alignedNtohq((const uint64_t*)(&(smb_hdr->message_id)));
         request = DCE2_Smb2GetRequest(ssd, message_id);
         if (!request)
@@ -598,12 +586,9 @@ static void DCE2_Smb2WriteRequest(DCE2_SmbSsnData* ssd, const Smb2Hdr* smb_hdr,
     uint16_t data_offset;
     uint32_t total_data_length;
 
-    DebugMessage(DEBUG_DCE_SMB, "Processing write request command!\n");
-
     fileId_persistent = alignedNtohq((const uint64_t*)(&(smb_write_hdr->fileId_persistent)));
     if (fileId_persistent && (ssd->ftracker.fid_v2 != fileId_persistent))
     {
-        DebugMessage(DEBUG_DCE_SMB, "Persistent file ID changed read request command!\n");
         ssd->ftracker.fid_v2 = fileId_persistent;
     }
     data_offset = alignedNtohs((const uint16_t*)(&(smb_write_hdr->data_offset)));
@@ -627,16 +612,6 @@ static void DCE2_Smb2WriteRequest(DCE2_SmbSsnData* ssd, const Smb2Hdr* smb_hdr,
         ssd->pdu_state = DCE2_SMB_PDU_STATE__RAW_DATA;
 }
 
-/********************************************************************
- *
- * Process write response
- *
- ********************************************************************/
-static void DCE2_Smb2WriteResponse(DCE2_SmbSsnData*, const Smb2Hdr*,
-    const Smb2WriteResponseHdr*, const uint8_t*)
-{
-    DebugMessage(DEBUG_DCE_SMB, "Processing write response command!\n");
-}
 
 /********************************************************************
  *
@@ -657,16 +632,6 @@ static void DCE2_Smb2Write(DCE2_SmbSsnData* ssd, const Smb2Hdr* smb_hdr,
             return;
         DCE2_Smb2WriteRequest(ssd, smb_hdr, smb_write_hdr, end);
     }
-    else if (structure_size == SMB2_WRITE_RESPONSE_STRUC_SIZE)
-    {
-        if ((const uint8_t*)smb_write_hdr + SMB2_WRITE_RESPONSE_STRUC_SIZE - 1 > end)
-            return;
-        DCE2_Smb2WriteResponse(ssd,  smb_hdr, (const Smb2WriteResponseHdr*)smb_write_hdr, end);
-    }
-    else
-    {
-        DebugMessage(DEBUG_DCE_SMB, "Wrong format for smb write command!\n");
-    }
 }
 
 /********************************************************************
@@ -688,52 +653,44 @@ static void DCE2_Smb2Inspect(DCE2_SmbSsnData* ssd, const Smb2Hdr* smb_hdr, const
     switch (command)
     {
     case SMB2_COM_CREATE:
-        DebugMessage(DEBUG_DCE_SMB, "Create command.\n");
         dce2_smb_stats.smb2_create++;
         if (DCE2_Smb2FindTid(ssd, smb_hdr) != DCE2_RET__SUCCESS)
             return;
         DCE2_Smb2Create(ssd, smb_hdr, smb_data, end);
         break;
     case SMB2_COM_READ:
-        DebugMessage(DEBUG_DCE_SMB, "Read command.\n");
         dce2_smb_stats.smb2_read++;
         if (DCE2_Smb2FindTid(ssd, smb_hdr) != DCE2_RET__SUCCESS)
             return;
         DCE2_Smb2Read(ssd, smb_hdr, smb_data, end);
         break;
     case SMB2_COM_WRITE:
-        DebugMessage(DEBUG_DCE_SMB, "Write command.\n");
         dce2_smb_stats.smb2_write++;
         if (DCE2_Smb2FindTid(ssd, smb_hdr) != DCE2_RET__SUCCESS)
             return;
         DCE2_Smb2Write(ssd, smb_hdr, smb_data, end);
         break;
     case SMB2_COM_SET_INFO:
-        DebugMessage(DEBUG_DCE_SMB, "Set info command.\n");
         dce2_smb_stats.smb2_set_info++;
         if (DCE2_Smb2FindTid(ssd, smb_hdr) != DCE2_RET__SUCCESS)
             return;
         DCE2_Smb2SetInfo(ssd, smb_hdr, smb_data, end);
         break;
     case SMB2_COM_CLOSE:
-        DebugMessage(DEBUG_DCE_SMB, "Close command.\n");
         dce2_smb_stats.smb2_close++;
         if (DCE2_Smb2FindTid(ssd, smb_hdr) != DCE2_RET__SUCCESS)
             return;
         DCE2_Smb2CloseCmd(ssd, smb_hdr, smb_data, end);
         break;
     case SMB2_COM_TREE_CONNECT:
-        DebugMessage(DEBUG_DCE_SMB, "Tree connect command.\n");
         dce2_smb_stats.smb2_tree_connect++;
         DCE2_Smb2TreeConnect(ssd, smb_hdr, smb_data, end);
         break;
     case SMB2_COM_TREE_DISCONNECT:
-        DebugMessage(DEBUG_DCE_SMB, "Tree disconnect command.\n");
         dce2_smb_stats.smb2_tree_disconnect++;
         DCE2_Smb2TreeDisconnect(ssd, smb_hdr, smb_data, end);
         break;
     default:
-        DebugMessage(DEBUG_DCE_SMB, "command ignored!\n");
         break;
     }
 }
index 717c880a9afd5e949bd67ddbfc58a1959e3c0e01..46eb12410058c18d6c20709e15070c8808313234 100644 (file)
@@ -27,7 +27,6 @@
 
 #include "dce_smb_commands.h"
 
-#include "main/snort_debug.h"
 #include "utils/util.h"
 
 #include "dce_smb_module.h"
@@ -275,9 +274,6 @@ static DCE2_Ret DCE2_SmbCheckData(DCE2_SmbSsnData*,
 static DCE2_Ret DCE2_SmbWriteAndXRawRequest(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
     const DCE2_SmbComInfo* com_info, const uint8_t* nb_ptr, uint32_t nb_len)
 {
-    DebugMessage(DEBUG_DCE_SMB,
-        "Processing WriteAndX with raw mode flags\n");
-
     // Set this now for possible reassembled packet
     uint16_t fid = SmbWriteAndXReqFid((const SmbWriteAndXReq*)nb_ptr);
     DCE2_SmbFileTracker* ftracker = DCE2_SmbGetFileTracker(ssd, fid);
@@ -319,7 +315,7 @@ static DCE2_Ret DCE2_SmbWriteAndXRawRequest(DCE2_SmbSsnData* ssd, const SmbNtHdr
             // See below.
             break;
         default:
-            DebugFormat(DEBUG_DCE_SMB, "Invalid policy: %d", policy);
+            assert(false);
             break;
         }
     }
@@ -427,9 +423,6 @@ static DCE2_Ret DCE2_SmbWriteAndXRawRequest(DCE2_SmbSsnData* ssd, const SmbNtHdr
                     return DCE2_RET__ERROR;
                 }
 
-                DebugMessage(DEBUG_DCE_SMB, "Reassembled WriteAndX raw mode request\n");
-                DCE2_PrintPktData(rpkt->data, rpkt->dsize);
-
                 (void)DCE2_SmbProcessRequestData(ssd, fid, data_ptr, data_len, 0);
 
                 DCE2_BufferEmpty(ftracker->fp_writex_raw->buf);
@@ -452,7 +445,7 @@ static DCE2_Ret DCE2_SmbWriteAndXRawRequest(DCE2_SmbSsnData* ssd, const SmbNtHdr
         // if both flags are set.
         break;
     default:
-        DebugFormat(DEBUG_DCE_SMB, "Invalid policy: %d", policy);
+        assert(false);
         break;
     }
 
@@ -892,7 +885,6 @@ DCE2_Ret DCE2_SmbLockAndRead(DCE2_SmbSsnData* ssd, const SmbNtHdr*,
 
         DCE2_SmbCheckFmtData(ssd, nb_len, byte_count, fmt, com_dcnt, fmt_dcnt);
 
-        DebugFormat(DEBUG_DCE_SMB," SmbWriteLockAndRead dcnt %d\n", com_dcnt);
         if (com_dcnt == 0)
         {
             dce_alert(GID_DCE2, DCE2_SMB_DCNT_ZERO, (dce2CommonStats*)&dce2_smb_stats);
@@ -1250,9 +1242,7 @@ DCE2_Ret DCE2_SmbSessionSetupAndX(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
                     DCE2_MOVE(nb_ptr, nb_len, 1);
             }
 
-            DebugMessage(DEBUG_DCE_SMB, "Attempting to fingerprint "
-                "Client Windows/Samba version ... \n");
-
+            // Attempting to fingerprint Client Windows/Samba version.
             // Move past Account and Domain strings
             // Blob version doesn't have these as they're in the blob
             if (DCE2_ComInfoWordCount(com_info) == 13)
@@ -1284,33 +1274,9 @@ DCE2_Ret DCE2_SmbSessionSetupAndX(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
             // is kosher.  It's policy will be used when the server is
             // sending data to it.
 
-#ifdef DEBUG_MSGS
-            {
-                uint32_t k, l = 0;
-                char buf[65535];
-
-                for (k = 0; (k < nb_len) && (nb_ptr[k] != 0); k += increment, l++)
-                    buf[l] = nb_ptr[k];
-
-                buf[l] = 0;
-                DebugFormat(DEBUG_DCE_SMB, "  Client OS: %s\n", buf);
-
-                k += increment;
-
-                l = 0;
-                for (; k < nb_len && nb_ptr[k] != 0; k += increment, l++)
-                    buf[l] = nb_ptr[k];
-
-                buf[l] = 0;
-                DebugFormat(DEBUG_DCE_SMB, "  Client Lanman: %s\n", buf);
-            }
-#endif
-
             // Windows Vista and above don't put anything here
             if (*nb_ptr == '\0')
             {
-                DebugMessage(DEBUG_DCE_SMB,
-                    "Setting client policy to Windows Vista\n");
                 DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__WINVISTA);
                 return DCE2_RET__SUCCESS;
             }
@@ -1337,18 +1303,12 @@ DCE2_Ret DCE2_SmbSessionSetupAndX(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
                 switch (state)
                 {
                 case OS_WIN2000:
-                    DebugMessage(DEBUG_DCE_SMB,
-                        "Setting client policy to Windows 2000\n");
                     DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__WIN2000);
                     break;
                 case OS_WINXP:
-                    DebugMessage(DEBUG_DCE_SMB,
-                        "Setting client policy to Windows XP\n");
                     DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__WINXP);
                     break;
                 case OS_WIN2003:
-                    DebugMessage(DEBUG_DCE_SMB,
-                        "Setting client policy to Windows 2003\n");
                     DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__WIN2003);
                     break;
                 default:
@@ -1379,8 +1339,6 @@ DCE2_Ret DCE2_SmbSessionSetupAndX(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
             // Samba
             if (*nb_ptr == 'S')
             {
-                DebugMessage(DEBUG_DCE_SMB,
-                    "Setting client policy to Samba\n");
                 DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__SAMBA);
             }
         }
@@ -1437,35 +1395,10 @@ DCE2_Ret DCE2_SmbSessionSetupAndX(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
                     DCE2_MOVE(nb_ptr, nb_len, 1);
             }
 
-            DebugMessage(DEBUG_DCE_SMB, "Attempting to fingerprint "
-                "Server Windows/Samba version ... \n");
-
+            // Attempting to fingerprint Server Windows/Samba version.
             // Note the below is quick and dirty.  We're assuming the server
             // is kosher.  It's policy will be used when the client is
             // sending data to it.
-
-#ifdef DEBUG_MSGS
-            {
-                uint32_t k, l = 0;
-                char buf[65535];
-
-                for (k = 0; (k < nb_len) && (nb_ptr[k] != 0); k += increment, l++)
-                    buf[l] = nb_ptr[k];
-
-                buf[l] = 0;
-                DebugFormat(DEBUG_DCE_SMB, "  Server OS: %s\n", buf);
-
-                k += increment;
-
-                l = 0;
-                for (; k < nb_len && nb_ptr[k] != 0; k += increment, l++)
-                    buf[l] = nb_ptr[k];
-
-                buf[l] = 0;
-                DebugFormat(DEBUG_DCE_SMB, "  Server Lanman: %s\n", buf);
-            }
-#endif
-
             if ((nb_len < increment) || (*nb_ptr == '\0'))
             {
                 return DCE2_RET__SUCCESS;
@@ -1493,33 +1426,21 @@ DCE2_Ret DCE2_SmbSessionSetupAndX(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
                 switch (state)
                 {
                 case OS_WIN2000:
-                    DebugMessage(DEBUG_DCE_SMB,
-                        "Setting server policy to Windows 2000\n");
                     DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__WIN2000);
                     break;
                 case OS_WINXP:
-                    DebugMessage(DEBUG_DCE_SMB,
-                        "Setting server policy to Windows XP\n");
                     DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__WINXP);
                     break;
                 case OS_WIN2003:
-                    DebugMessage(DEBUG_DCE_SMB,
-                        "Setting server policy to Windows 2003\n");
                     DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__WIN2003);
                     break;
                 case OS_WIN2008:
-                    DebugMessage(DEBUG_DCE_SMB,
-                        "Setting server policy to Windows 2008\n");
                     DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__WIN2008);
                     break;
                 case OS_WINVISTA:
-                    DebugMessage(DEBUG_DCE_SMB,
-                        "Setting server policy to Windows Vista\n");
                     DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__WINVISTA);
                     break;
                 case OS_WIN7:
-                    DebugMessage(DEBUG_DCE_SMB,
-                        "Setting server policy to Windows 7\n");
                     DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__WIN7);
                     break;
                 default:
@@ -1568,8 +1489,6 @@ DCE2_Ret DCE2_SmbSessionSetupAndX(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
                 // If less than 3 set policy to earliest Samba policy we use
                 if ((nb_ptr[i] == '0') || (nb_ptr[i] == '1') || (nb_ptr[i] == '2'))
                 {
-                    DebugMessage(DEBUG_DCE_SMB,
-                        "Setting server policy to Samba 3.0.20\n");
                     DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__SAMBA_3_0_20);
                     return DCE2_RET__SUCCESS;
                 }
@@ -1585,8 +1504,6 @@ DCE2_Ret DCE2_SmbSessionSetupAndX(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
                 // If it's not 0, then set to latest Samba policy we use
                 if (nb_ptr[i] != '0')
                 {
-                    DebugMessage(DEBUG_DCE_SMB,
-                        "Setting server policy to current Samba\n");
                     DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__SAMBA);
                     return DCE2_RET__SUCCESS;
                 }
@@ -1597,8 +1514,6 @@ DCE2_Ret DCE2_SmbSessionSetupAndX(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
                 // First digit is 1 or no second digit or 20, Samba 3.0.20
                 if ((r1 == '1') || (r2 == '\0') || ((r1 == '2') && (r2 == '0')))
                 {
-                    DebugMessage(DEBUG_DCE_SMB,
-                        "Setting server policy to Samba 3.0.20\n");
                     DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__SAMBA_3_0_20);
                     return DCE2_RET__SUCCESS;
                 }
@@ -1606,8 +1521,6 @@ DCE2_Ret DCE2_SmbSessionSetupAndX(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
                 // 21 or 22, Samba 3.0.22
                 if ((r1 == '2') && (r2 <= '2'))
                 {
-                    DebugMessage(DEBUG_DCE_SMB,
-                        "Setting server policy to Samba 3.0.22\n");
                     DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__SAMBA_3_0_22);
                     return DCE2_RET__SUCCESS;
                 }
@@ -1615,14 +1528,10 @@ DCE2_Ret DCE2_SmbSessionSetupAndX(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
                 // 23, 24 ... 30 ... 37, Samba 3.0.37
                 if ((r1 == '2') || ((r1 == '3') && (r2 <= '7')))
                 {
-                    DebugMessage(DEBUG_DCE_SMB,
-                        "Setting server policy to Samba 3.0.37\n");
                     DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__SAMBA_3_0_37);
                     return DCE2_RET__SUCCESS;
                 }
 
-                DebugMessage(DEBUG_DCE_SMB,
-                    "Setting server policy to current Samba\n");
                 DCE2_SsnSetPolicy(&ssd->sd, DCE2_POLICY__SAMBA);
             }
         }
@@ -1780,13 +1689,9 @@ DCE2_Ret DCE2_SmbTreeConnectAndX(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
         switch (state)
         {
         case SERVICE_IPC:
-            DebugFormat(DEBUG_DCE_SMB,
-                "Tid (%hu) is an IPC tree.\n", tid);
             break;
         case SERVICE_DISK:
             is_ipc = false;
-            DebugFormat(DEBUG_DCE_SMB,
-                "Tid (%hu) is a DISK tree.\n", tid);
             break;
         default:
             return DCE2_RET__IGNORE;
@@ -1881,9 +1786,6 @@ DCE2_Ret DCE2_SmbTreeConnect(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
         // in the TreeConnect command response?
         uint16_t tid = SmbTid(smb_hdr);
         DCE2_SmbInsertTid(ssd, tid, ssd->cur_rtracker->is_ipc);
-
-        DebugFormat(DEBUG_DCE_SMB, "Tid (%hu) %s an IPC tree\n", tid,
-            (ssd->cur_rtracker->is_ipc) ? "is" : "is not");
     }
 
     return DCE2_RET__SUCCESS;
index 43fc37103944372a982e744109c8cef06c945e7d..d16dc06c787c0db5cc97ca6758519c1fc5583f15 100644 (file)
@@ -33,6 +33,8 @@
 using namespace snort;
 using namespace std;
 
+Trace TRACE_NAME(dce_smb);
+
 static const PegInfo dce2_smb_pegs[] =
 {
     { CountType::SUM, "events", "total events" },
@@ -175,7 +177,7 @@ static const RuleMap dce2_smb_rules[] =
     { 0, nullptr }
 };
 
-Dce2SmbModule::Dce2SmbModule() : Module(DCE2_SMB_NAME, DCE2_SMB_HELP, s_params)
+Dce2SmbModule::Dce2SmbModule() : Module(DCE2_SMB_NAME, DCE2_SMB_HELP, s_params, false, &TRACE_NAME(dce_smb))
 {
     memset(&config, 0, sizeof(config));
 }
@@ -424,7 +426,7 @@ static bool set_smb_invalid_shares(dce2SmbProtoConf& config, Value& v)
     return(true);
 }
 
-bool Dce2SmbModule::set(const char*, snort::Value& v, snort::SnortConfig*)
+bool Dce2SmbModule::set(const char* fqn, snort::Value& v, snort::SnortConfig* c)
 {
     if (dce2_set_co_config(v,config.common))
         return true;
@@ -445,7 +447,7 @@ bool Dce2SmbModule::set(const char*, snort::Value& v, snort::SnortConfig*)
     else if ( v.is("smb_legacy_mode"))
         config.legacy_mode = v.get_bool();
     else
-        return false;
+        return Module::set(fqn, v, c);
     return true;
 }
 
index 22a7d7c930d3c80ae41a1ab6b9cbfd3fbea6f7bd..e83d3d28b6373de0377ad8f2b890ea93cce10dc6 100644 (file)
@@ -66,6 +66,8 @@ struct dce2SmbProtoConf
     bool legacy_mode;
 };
 
+extern Trace TRACE_NAME(dce_smb);
+
 class Dce2SmbModule : public snort::Module
 {
 public:
index 5f2308af62c2d94756f77f6d6715a56c31d94ee9..d47d415c11a66ab6dd59c8737b7c9ba82ed10f6d 100644 (file)
@@ -25,8 +25,6 @@
 
 #include "dce_smb_paf.h"
 
-#include "main/snort_debug.h"
-
 #include "dce_smb.h"
 
 using namespace snort;
@@ -86,7 +84,7 @@ static inline bool DCE2_PafSmbIsValidNetbiosHdr(uint32_t nb_hdr, bool junk)
  *
  *********************************************************************/
 static StreamSplitter::Status dce2_smb_paf(DCE2_PafSmbData* ss, Flow* flow, const uint8_t* data,
-    uint32_t len, uint32_t flags, uint32_t* fp)
+    uint32_t len, uint32_t, uint32_t* fp)
 {
     uint32_t n = 0;
     StreamSplitter::Status ps = StreamSplitter::SEARCH;
@@ -94,30 +92,13 @@ static StreamSplitter::Status dce2_smb_paf(DCE2_PafSmbData* ss, Flow* flow, cons
     uint32_t nb_len;
     DCE2_SmbSsnData* sd = get_dce2_smb_session_data(flow);
 
-    DebugFormat(DEBUG_DCE_SMB, "%s\n", DCE2_DEBUG__PAF_START_MSG_SMB);
-    DebugFormat(DEBUG_DCE_SMB, "SMB: %u bytes of data\n", len);
-
-#ifdef DEBUG_MSGS
-    if (flags & PKT_FROM_CLIENT)
-        DebugMessage(DEBUG_DCE_SMB, "Packet from Client\n");
-    else
-        DebugMessage(DEBUG_DCE_SMB, "Packet from Server\n");
-#else
-    UNUSED(flags);
-#endif
-
     if (dce2_paf_abort(flow, (DCE2_SsnData*)sd))
     {
-        DebugFormat(DEBUG_DCE_SMB, "%s\n", DCE2_DEBUG__PAF_END_MSG);
         return StreamSplitter::ABORT;
     }
 
-    DebugFormat(DEBUG_DCE_SMB, "Start state: %u\n", ss->paf_state);
-
     while (n < len)
     {
-        DebugFormatNoFileLine(DEBUG_DCE_SMB, " State %d : 0x%02x\n", ss->paf_state, data[n]);
-
         switch (ss->paf_state)
         {
         case DCE2_PAF_SMB_STATES__0:
@@ -132,13 +113,9 @@ static StreamSplitter::Status dce2_smb_paf(DCE2_PafSmbData* ss, Flow* flow, cons
                 nb_len = NbssLen((const NbssHdr*)&nb_hdr);
                 *fp = (nb_len + sizeof(NbssHdr) + n) - ss->paf_state;
                 ss->paf_state = DCE2_PAF_SMB_STATES__0;
-                DebugFormat(DEBUG_DCE_SMB,
-                    "Setting flush point: %u\n", *fp);
-                DebugFormat(DEBUG_DCE_SMB, "%s\n", DCE2_DEBUG__PAF_END_MSG);
                 return StreamSplitter::FLUSH;
             }
-             DebugFormatNoFileLine(DEBUG_DCE_SMB, "%s", "Invalid NetBIOS header - "
-                "entering junk data states.\n");
+          
             ss->paf_state = (DCE2_PafSmbStates)(((int)ss->paf_state) + 1);
             break;
         case DCE2_PAF_SMB_STATES__7:
@@ -146,25 +123,19 @@ static StreamSplitter::Status dce2_smb_paf(DCE2_PafSmbData* ss, Flow* flow, cons
 
             if (!DCE2_PafSmbIsValidNetbiosHdr((uint32_t)(ss->nb_hdr >> 32), true))
             {
-                 DebugFormatNoFileLine(DEBUG_DCE_SMB, "%s", "Invalid NetBIOS header - "
-                    "staying in State 7.\n");
                 break;
             }
             if (((uint32_t)ss->nb_hdr != DCE2_SMB_ID)
                 && ((uint32_t)ss->nb_hdr != DCE2_SMB2_ID))
             {
-                 DebugFormatNoFileLine(DEBUG_DCE_SMB, "%s", "Invalid SMB ID - "
-                    "staying in State 7.\n");
                 break;
             }
 
             nb_hdr = htonl((uint32_t)(ss->nb_hdr >> 32));
             nb_len = NbssLen((const NbssHdr*)&nb_hdr);
             *fp = (nb_len + sizeof(NbssHdr) + n) - ss->paf_state;
-            DebugFormat(DEBUG_DCE_SMB,
-                "Setting flush point: %u\n", *fp);
             ss->paf_state = DCE2_PAF_SMB_STATES__0;
-            DebugFormat(DEBUG_DCE_SMB, "%s\n", DCE2_DEBUG__PAF_END_MSG);
+   
             return StreamSplitter::FLUSH;
         default:
             DCE2_SMB_PAF_SHIFT(ss->nb_hdr, data[n]);
@@ -175,7 +146,6 @@ static StreamSplitter::Status dce2_smb_paf(DCE2_PafSmbData* ss, Flow* flow, cons
         n++;
     }
 
-    DebugFormat(DEBUG_DCE_SMB, "%s\n", DCE2_DEBUG__PAF_END_MSG);
     return ps;
 }
 
index d9aa9cb15613491141aeab6e78117b01b8675900..b64c74de6bdf33d24d9a0be6f8d298af1b7f3497 100644 (file)
@@ -25,7 +25,6 @@
 #include "stream/stream_splitter.h"
 
 #define DCE2_SMB_PAF_SHIFT(x64, x8) { (x64) <<= 8; (x64) |= (uint64_t)(x8); }
-#define DCE2_DEBUG__PAF_START_MSG_SMB  "DCE/RPC over SMB PAF ====================================="
 
 // Enumerations for PAF states
 enum DCE2_PafSmbStates
index 645d72628385fb248e92134975c35244f90ae88c..5edafdc5a66e11c2c07a83d3dbdfcc19da84bc80 100644 (file)
@@ -27,8 +27,7 @@
 
 #include "dce_smb_transaction.h"
 
-#include "main/snort_debug.h"
-
+#include "dce_smb_module.h"
 #include "dce_smb_transaction_utils.h"
 
 #define DCE2_SMB_TRANS__NONE    0x00
@@ -224,10 +223,6 @@ static DCE2_Ret DCE2_SmbTransactionReq(DCE2_SmbSsnData* ssd,
             // Won't get a response
             if (DCE2_SsnIsWindowsPolicy(&ssd->sd) && ttracker->one_way)
             {
-                DebugFormat(DEBUG_DCE_SMB,
-                    "Setting pipe to %s mode\n",
-                    ttracker->pipe_byte_mode ? "byte" : "message");
-
                 ssd->cur_rtracker->ftracker->fp_byte_mode = ttracker->pipe_byte_mode;
             }
         }
@@ -436,12 +431,6 @@ static DCE2_Ret DCE2_SmbUpdateTransSecondary(DCE2_SmbSsnData* ssd,
     ttracker->dsent += dcnt;
     ttracker->psent += pcnt;
 
-    DebugFormat(DEBUG_DCE_SMB, "Data displacement: %u, "
-        "Data count: %u, Total data count: %u\n"
-        "Parameter displacement: %u, "
-        "Parameter count: %u, Total parameter count: %u\n",
-        ddisp, dcnt, tdcnt, pdisp, pcnt, tpcnt);
-
     if (data_params & DCE2_SMB_TRANS__DATA)
     {
         DCE2_MOVE(nb_ptr, nb_len, ((const uint8_t*)smb_hdr + doff) - nb_ptr);
@@ -525,12 +514,6 @@ static DCE2_Ret DCE2_SmbUpdateTransRequest(DCE2_SmbSsnData* ssd,
         pcnt = SmbTransactionReqParamCnt((const SmbTransactionReq*)nb_ptr);
         poff = SmbTransactionReqParamOff((const SmbTransactionReq*)nb_ptr);
 
-        DebugFormat(DEBUG_DCE_SMB,
-            "Transaction subcommand: %s (0x%04X)\n",
-            (sub_com < TRANS_SUBCOM_MAX)
-            ? smb_transaction_sub_command_strings[sub_com]
-            : "Unknown", sub_com);
-
         ssd->cur_rtracker->ftracker = DCE2_SmbGetFileTracker(ssd, fid);
         if (ssd->cur_rtracker->ftracker == nullptr)
             return DCE2_RET__IGNORE;
@@ -541,7 +524,7 @@ static DCE2_Ret DCE2_SmbUpdateTransRequest(DCE2_SmbSsnData* ssd,
             if (DCE2_SsnIsWindowsPolicy(&ssd->sd)
                 && ssd->cur_rtracker->ftracker->fp_byte_mode)
             {
-                DebugMessage(DEBUG_DCE_SMB, "Pipe is in byte "
+                trace_log(dce_smb, "Pipe is in byte "
                     "mode - TRANS_TRANSACT_NMPIPE won't work\n");
                 return DCE2_RET__ERROR;
             }
@@ -598,7 +581,7 @@ static DCE2_Ret DCE2_SmbUpdateTransRequest(DCE2_SmbSsnData* ssd,
             && (DCE2_SmbTransactionGetName(nb_ptr, nb_len,
             byte_count, SmbUnicode(smb_hdr)) != DCE2_RET__SUCCESS))
         {
-            DebugMessage(DEBUG_DCE_SMB, "Failed to validate "
+            trace_log(dce_smb, "Failed to validate "
                 "pipe name for Samba.\n");
             return DCE2_RET__ERROR;
         }
@@ -614,12 +597,6 @@ static DCE2_Ret DCE2_SmbUpdateTransRequest(DCE2_SmbSsnData* ssd,
         pcnt = SmbTransaction2ReqParamCnt((const SmbTransaction2Req*)nb_ptr);
         poff = SmbTransaction2ReqParamOff((const SmbTransaction2Req*)nb_ptr);
 
-        DebugFormat(DEBUG_DCE_SMB,
-            "Transaction2 subcommand: %s (0x%04X)\n",
-            (sub_com < TRANS2_SUBCOM_MAX)
-            ? smb_transaction2_sub_command_strings[sub_com]
-            : "Unknown", sub_com);
-
         switch (sub_com)
         {
         case TRANS2_OPEN2:
@@ -671,12 +648,6 @@ static DCE2_Ret DCE2_SmbUpdateTransRequest(DCE2_SmbSsnData* ssd,
         pcnt = SmbNtTransactReqParamCnt((const SmbNtTransactReq*)nb_ptr);
         poff = SmbNtTransactReqParamOff((const SmbNtTransactReq*)nb_ptr);
 
-        DebugFormat(DEBUG_DCE_SMB,
-            "Nt Transact subcommand: %s (0x%04X)\n",
-            (sub_com < NT_TRANSACT_SUBCOM_MAX)
-            ? smb_nt_transact_sub_command_strings[sub_com]
-            : "Unknown", sub_com);
-
         switch (sub_com)
         {
         case NT_TRANSACT_CREATE:
@@ -718,10 +689,6 @@ static DCE2_Ret DCE2_SmbUpdateTransRequest(DCE2_SmbSsnData* ssd,
     ttracker->tpcnt = tpcnt;
     ttracker->psent = pcnt;
 
-    DebugFormat(DEBUG_DCE_SMB, "Data count: %u, "
-        "Total data count: %u, Param count: %u, "
-        "Total param count: %u\n", dcnt, tdcnt, pcnt, tpcnt);
-
     // Testing shows that Transacts aren't processed until
     // all of the data and parameters are received, so overlapping
     // writes to the same FID can occur as long as the pid/mid are
@@ -811,12 +778,6 @@ static DCE2_Ret DCE2_SmbUpdateTransResponse(DCE2_SmbSsnData* ssd,
         poff = SmbTransactionRespParamOff((const SmbTransactionResp*)nb_ptr);
         pdisp = SmbTransactionRespParamDisp((const SmbTransactionResp*)nb_ptr);
 
-        DebugFormat(DEBUG_DCE_SMB,
-            "Transaction subcommand: %s (0x%04X)\n",
-            (sub_com < TRANS_SUBCOM_MAX)
-            ? smb_transaction_sub_command_strings[sub_com]
-            : "Unknown", sub_com);
-
         switch (sub_com)
         {
         case TRANS_TRANSACT_NMPIPE:
@@ -843,12 +804,6 @@ static DCE2_Ret DCE2_SmbUpdateTransResponse(DCE2_SmbSsnData* ssd,
         doff = SmbTransaction2RespDataOff((const SmbTransaction2Resp*)nb_ptr);
         ddisp = SmbTransaction2RespDataDisp((const SmbTransaction2Resp*)nb_ptr);
 
-        DebugFormat(DEBUG_DCE_SMB,
-            "Transaction2 subcommand: %s (0x%04X)\n",
-            (sub_com < TRANS2_SUBCOM_MAX)
-            ? smb_transaction2_sub_command_strings[sub_com]
-            : "Unknown", sub_com);
-
         switch (sub_com)
         {
         case TRANS2_OPEN2:
@@ -874,12 +829,6 @@ static DCE2_Ret DCE2_SmbUpdateTransResponse(DCE2_SmbSsnData* ssd,
         doff = SmbNtTransactRespDataOff((const SmbNtTransactResp*)nb_ptr);
         ddisp = SmbNtTransactRespDataDisp((const SmbNtTransactResp*)nb_ptr);
 
-        DebugFormat(DEBUG_DCE_SMB,
-            "Nt Transact subcommand: %s (0x%04X)\n",
-            (sub_com < NT_TRANSACT_SUBCOM_MAX)
-            ? smb_nt_transact_sub_command_strings[sub_com]
-            : "Unknown", sub_com);
-
         switch (sub_com)
         {
         case NT_TRANSACT_CREATE:
@@ -930,12 +879,6 @@ static DCE2_Ret DCE2_SmbUpdateTransResponse(DCE2_SmbSsnData* ssd,
     ttracker->dsent += dcnt;
     ttracker->psent += pcnt;
 
-    DebugFormat(DEBUG_DCE_SMB, "Data displacement: %u, "
-        "Data count: %u, Total data count: %u\n"
-        "Parameter displacement: %u, "
-        "Parameter count: %u, Total parameter count: %u\n",
-        ddisp, dcnt, tdcnt, pdisp, pcnt, tpcnt);
-
     if (data_params & DCE2_SMB_TRANS__DATA)
     {
         DCE2_MOVE(nb_ptr, nb_len, ((const uint8_t*)smb_hdr + doff) - nb_ptr);
@@ -1117,7 +1060,7 @@ DCE2_Ret DCE2_SmbTransaction(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
     if (DCE2_ComInfoIsRequest(com_info)
         && !DCE2_SmbIsTransactionComplete(ttracker))
     {
-        DebugMessage(DEBUG_DCE_SMB, "Got new transaction request "
+        trace_log(dce_smb, "Got new transaction request "
             "that matches an in progress transaction - not inspecting.\n");
         return DCE2_RET__ERROR;
     }
@@ -1126,7 +1069,7 @@ DCE2_Ret DCE2_SmbTransaction(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
     if (DCE2_ComInfoIsRequest(com_info)
         && (DCE2_ComInfoWordCount(com_info) != 16))
     {
-        DebugMessage(DEBUG_DCE_SMB, "\\PIPE\\LANMAN request - not inspecting\n");
+        trace_log(dce_smb, "\\PIPE\\LANMAN request - not inspecting\n");
         return DCE2_RET__IGNORE;
     }
 
@@ -1139,9 +1082,6 @@ DCE2_Ret DCE2_SmbTransaction(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
     if (DCE2_ComInfoIsResponse(com_info)
         && (com_size == sizeof(SmbTransactionInterimResp)))
     {
-        DebugMessage(DEBUG_DCE_SMB,
-            "  Server Transaction interim response.\n");
-
         return DCE2_RET__SUCCESS;
     }
 
@@ -1193,9 +1133,6 @@ DCE2_Ret DCE2_SmbTransaction(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
                 if (rpkt == nullptr)
                     return DCE2_RET__ERROR;
 
-                DebugMessage(DEBUG_DCE_SMB, "Reassembled Transaction response\n");
-                DCE2_PrintPktData(rpkt->data, rpkt->dsize);
-
                 status = DCE2_SmbProcessResponseData(ssd, data_ptr, data_len);
 
                 if (status != DCE2_RET__SUCCESS)
@@ -1215,8 +1152,6 @@ DCE2_Ret DCE2_SmbTransaction(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
             break;
 
         case TRANS_SET_NMPIPE_STATE:
-            DebugFormat(DEBUG_DCE_SMB, "Setting pipe "
-                "to %s mode\n", ttracker->pipe_byte_mode ? "byte" : "message");
             ssd->cur_rtracker->ftracker->fp_byte_mode = ttracker->pipe_byte_mode;
             break;
 
@@ -1246,7 +1181,7 @@ DCE2_Ret DCE2_SmbTransaction2(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
     if (DCE2_ComInfoIsRequest(com_info)
         && !DCE2_SmbIsTransactionComplete(ttracker))
     {
-        DebugMessage(DEBUG_DCE_SMB, "Got new transaction request "
+        trace_log(dce_smb, "Got new transaction request "
             "that matches an in progress transaction - not inspecting.\n");
         return DCE2_RET__ERROR;
     }
@@ -1504,7 +1439,7 @@ DCE2_Ret DCE2_SmbNtTransact(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr,
     if (DCE2_ComInfoIsRequest(com_info)
         && !DCE2_SmbIsTransactionComplete(ttracker))
     {
-        DebugMessage(DEBUG_DCE_SMB, "Got new transaction request "
+        trace_log(dce_smb, "Got new transaction request "
             "that matches an in progress transaction - not inspecting.\n");
         return DCE2_RET__ERROR;
     }
@@ -1649,10 +1584,7 @@ DCE2_Ret DCE2_SmbTransactionSecondary(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_
         snort::Packet* rpkt = DCE2_SmbGetRpkt(ssd, &data_ptr, &data_len, DCE2_RPKT_TYPE__SMB_TRANS);
 
         if (rpkt == nullptr)
-            return DCE2_RET__ERROR;
-
-        DebugMessage(DEBUG_DCE_SMB, "Reassembled Transaction request\n");
-        DCE2_PrintPktData(rpkt->data, rpkt->dsize);
+            return DCE2_RET__ERROR;    
 
         status = DCE2_SmbTransactionReq(ssd, ttracker, data_ptr, data_len,
             DCE2_BufferData(ttracker->pbuf), DCE2_BufferLength(ttracker->pbuf));
index 24a0fea176be9d58a7ab1c745df343183e519eb5..4d3f5d528b1d5d4e6082fdf9e63cd940ee6eac2b 100644 (file)
@@ -27,8 +27,6 @@
 
 #include "dce_smb_transaction_utils.h"
 
-#include "main/snort_debug.h"
-
 #define TRANS_NM_PIPE_0       (0)
 #define TRANS_NM_PIPE_1       (TRANS_NM_PIPE_0+7)
 #define TRANS_NM_PIPE_2       (TRANS_NM_PIPE_1+1)
@@ -363,8 +361,6 @@ DCE2_Ret DCE2_SmbBufferTransactionData(DCE2_SmbTransactionTracker* ttracker,
 {
     snort::Profile profile(dce2_smb_pstat_smb_req);
 
-    DebugMessage(DEBUG_DCE_SMB, "Buffering transaction data.\n");
-
     if (ttracker->dbuf == nullptr)
     {
         /* Buf size should be the total data count we need */
@@ -374,14 +370,9 @@ DCE2_Ret DCE2_SmbBufferTransactionData(DCE2_SmbTransactionTracker* ttracker,
     if (DCE2_BufferAddData(ttracker->dbuf, data_ptr, dcnt, ddisp,
         DCE2_BUFFER_MIN_ADD_FLAG__IGNORE) != DCE2_RET__SUCCESS)
     {
-        DebugMessage(DEBUG_DCE_SMB,
-            "Failed to buffer transaction data.\n");
         return DCE2_RET__ERROR;
     }
 
-    DebugMessage(DEBUG_DCE_SMB,
-        "Successfully buffered transaction data.\n");
-
     return DCE2_RET__SUCCESS;
 }
 
@@ -390,8 +381,6 @@ DCE2_Ret DCE2_SmbBufferTransactionParameters(DCE2_SmbTransactionTracker* ttracke
 {
     snort::Profile profile(dce2_smb_pstat_smb_req);
 
-    DebugMessage(DEBUG_DCE_SMB, "Buffering transaction parameters.\n");
-
     if (ttracker->pbuf == nullptr)
     {
         /* Buf size should be the total data count we need */
@@ -401,13 +390,8 @@ DCE2_Ret DCE2_SmbBufferTransactionParameters(DCE2_SmbTransactionTracker* ttracke
     if (DCE2_BufferAddData(ttracker->pbuf, param_ptr, pcnt, pdisp,
         DCE2_BUFFER_MIN_ADD_FLAG__IGNORE) != DCE2_RET__SUCCESS)
     {
-        DebugMessage(DEBUG_DCE_SMB,
-            "Failed to buffer transaction parameter data.\n");
         return DCE2_RET__ERROR;
     }
 
-    DebugMessage(DEBUG_DCE_SMB,
-        "Successfully buffered transaction parameter data.\n");
-
     return DCE2_RET__SUCCESS;
 }
index e225f21002617594e9506240060144f23608077a..7e661b3fa67ff7a23fcde9f56746032c8d6aee7a 100644 (file)
@@ -169,8 +169,6 @@ void DCE2_SmbInsertUid(DCE2_SmbSsnData* ssd, const uint16_t uid)
 {
     Profile profile(dce2_smb_pstat_smb_uid);
 
-    DebugFormat(DEBUG_DCE_SMB, "Inserting Uid: %hu\n", uid);
-
     if (ssd->uid == DCE2_SENTINEL)
     {
         ssd->uid = (int)uid;
@@ -198,8 +196,6 @@ void DCE2_SmbRemoveUid(DCE2_SmbSsnData* ssd, const uint16_t uid)
 
     Profile profile(dce2_smb_pstat_smb_uid);
 
-    DebugFormat(DEBUG_DCE_SMB,"Removing Uid: %hu\n", uid);
-
     if ((ssd->uid != DCE2_SENTINEL) && (ssd->uid == (int)uid))
         ssd->uid = DCE2_SENTINEL;
     else
@@ -252,7 +248,7 @@ void DCE2_SmbRemoveUid(DCE2_SmbSsnData* ssd, const uint16_t uid)
         break;
 
     default:
-        DebugFormat(DEBUG_DCE_SMB, "Invalid policy: %d", policy);
+        assert(false);
         break;
     }
 }
@@ -338,12 +334,6 @@ DCE2_SmbRequestTracker* DCE2_SmbNewRequestTracker(DCE2_SmbSsnData* ssd,
     if (ssd->outstanding_requests > dce2_smb_stats.smb_max_outstanding_requests)
         dce2_smb_stats.smb_max_outstanding_requests = ssd->outstanding_requests;
 
-    DebugFormat(DEBUG_DCE_SMB, "Added new request tracker => "
-        "Uid: %hu, Tid: %hu, Pid: %hu, Mid: %d\n",
-        rtracker->uid, rtracker->tid, rtracker->pid, rtracker->mid);
-    DebugFormat(DEBUG_DCE_SMB,
-        "Current outstanding requests: %hu\n", ssd->outstanding_requests);
-
     return rtracker;
 }
 
@@ -359,9 +349,6 @@ DCE2_SmbFileTracker* DCE2_SmbNewFileTracker(DCE2_SmbSsnData* ssd,
         && (DCE2_ScSmbFileDepth((dce2SmbProtoConf*)ssd->sd.config) == -1))
         return nullptr;
 
-    DebugFormat(DEBUG_DCE_SMB, "Creating new file tracker "
-        "with Uid: %hu, Tid: %hu, Fid: 0x%04X\n", uid, tid, fid);
-
     DCE2_SmbFileTracker* ftracker = nullptr;
     if (ssd->ftracker.fid_v1 == DCE2_SENTINEL)
     {
@@ -449,8 +436,6 @@ DCE2_Ret DCE2_SmbInitFileTracker(DCE2_SmbSsnData* ssd,
         ftracker->ff_bytes_queued = 0;
         if ((ssd->fapi_ftracker == nullptr) && (ssd->max_file_depth != -1))
         {
-            DebugFormat(DEBUG_DCE_SMB, "Designating file tracker "
-                "for file API processing: 0x%04X\n", (uint16_t)fid);
             ssd->fapi_ftracker = ftracker;
         }
     }
@@ -463,9 +448,6 @@ DCE2_SmbFileTracker* DCE2_SmbFindFileTracker(DCE2_SmbSsnData* ssd,
 {
     Profile profile(dce2_smb_pstat_smb_fid);
 
-    DebugFormat(DEBUG_DCE_SMB, "Finding file tracker with "
-        "Uid: %hu, Tid: %hu, Fid: 0x%04X ... ", uid, tid, fid);
-
     DCE2_SmbFileTracker* ftracker;
     if ((ssd->ftracker.fid_v1 != DCE2_SENTINEL) && (ssd->ftracker.fid_v1 == (int)fid))
     {
@@ -479,7 +461,6 @@ DCE2_SmbFileTracker* DCE2_SmbFindFileTracker(DCE2_SmbSsnData* ssd,
 
     if (ftracker == nullptr)
     {
-        DebugMessage(DEBUG_DCE_SMB, "Not found.\n");
         return nullptr;
     }
 
@@ -492,7 +473,6 @@ DCE2_SmbFileTracker* DCE2_SmbFindFileTracker(DCE2_SmbSsnData* ssd,
         // Only Uid used to open file can be used to make a request
         if (ftracker->uid_v1 != uid)
         {
-            DebugMessage(DEBUG_DCE_SMB, "Not found.\n");
             return nullptr;
         }
 
@@ -508,7 +488,6 @@ DCE2_SmbFileTracker* DCE2_SmbFindFileTracker(DCE2_SmbSsnData* ssd,
             // Check that the Uid exists
             if (DCE2_SmbFindUid(ssd, uid) != DCE2_RET__SUCCESS)
             {
-                DebugMessage(DEBUG_DCE_SMB, "Not found.\n");
                 return nullptr;
             }
 
@@ -524,20 +503,16 @@ DCE2_SmbFileTracker* DCE2_SmbFindFileTracker(DCE2_SmbSsnData* ssd,
         // Both Uid and Tid used to create file must be used to make a request
         if ((ftracker->uid_v1 != uid) || (ftracker->tid_v1 != tid))
         {
-            DebugMessage(DEBUG_DCE_SMB, "Not found.\n");
             return nullptr;
         }
 
         break;
 
     default:
-        DebugFormat(DEBUG_DCE_SMB, "Invalid policy: %d", policy);
+        assert(false);
         break;
     }
 
-    DebugFormat(DEBUG_DCE_SMB, "Found with "
-        "Uid: %hu, Tid: %hu, Fid: 0x%04X\n",
-        ftracker->uid_v1, ftracker->tid_v1, ftracker->fid_v1);
     return ftracker;
 }
 
@@ -577,9 +552,6 @@ void DCE2_SmbRemoveFileTracker(DCE2_SmbSsnData* ssd, DCE2_SmbFileTracker* ftrack
 
     Profile profile(dce2_smb_pstat_smb_fid);
 
-    DebugFormat(DEBUG_DCE_SMB,
-        "Removing file tracker with Fid: 0x%04X\n", ftracker->fid_v1);
-
     if (ssd->fapi_ftracker == ftracker)
         DCE2_SmbFinishFileAPI(ssd);
 
@@ -651,10 +623,6 @@ void DCE2_SmbFileTrackerDataFree(void* data)
     if (ftracker == nullptr)
         return;
 
-    DebugFormat(DEBUG_DCE_SMB, "Freeing file tracker: "
-        "Uid: %hu, Tid: %hu, Fid: 0x%04X\n",
-        ftracker->uid_v1, ftracker->tid_v1, ftracker->fid_v1);
-
     DCE2_SmbCleanFileTracker(ftracker);
     snort_free((void*)ftracker);
 }
@@ -731,14 +699,8 @@ void DCE2_SmbRemoveRequestTracker(DCE2_SmbSsnData* ssd,
         return;
     }
 
-    DebugFormat(DEBUG_DCE_SMB, "Removing request tracker => "
-        "Uid: %hu, Tid: %hu, Pid: %hu, Mid: %d ... ",
-        rtracker->uid, rtracker->tid, rtracker->pid, rtracker->mid);
-
     if (rtracker == &ssd->rtracker)
     {
-        DebugMessage(DEBUG_DCE_SMB, "Removed\n");
-
         DCE2_SmbCleanRequestTracker(&ssd->rtracker);
         ssd->outstanding_requests--;
         return;
@@ -751,15 +713,11 @@ void DCE2_SmbRemoveRequestTracker(DCE2_SmbSsnData* ssd,
     {
         if (tmp_node == (void*)rtracker)
         {
-            DebugMessage(DEBUG_DCE_SMB, "Removed\n");
-
             DCE2_QueueRemoveCurrent(ssd->rtrackers);
             ssd->outstanding_requests--;
             return;
         }
     }
-
-    DebugMessage(DEBUG_DCE_SMB, "Not removed.\n");
 }
 
 void DCE2_SmbRemoveFileTrackerFromRequestTrackers(DCE2_SmbSsnData* ssd,
@@ -790,7 +748,7 @@ DCE2_SmbFileTracker* DCE2_SmbDequeueTmpFileTracker(DCE2_SmbSsnData* ssd,
     DCE2_SmbRequestTracker* rtracker, const uint16_t fid)
 {
     Profile profile(dce2_smb_pstat_smb_fid);
-    DebugFormat(DEBUG_DCE_SMB, "Dequeuing file tracker and binding to fid: 0x%04X\n", fid);
+
     DCE2_SmbFileTracker* ftracker = (DCE2_SmbFileTracker*)DCE2_QueueDequeue(rtracker->ft_queue);
 
     if (ftracker == nullptr)
@@ -842,10 +800,6 @@ void DCE2_SmbRequestTrackerDataFree(void* data)
     if (rtracker == nullptr)
         return;
 
-    DebugFormat(DEBUG_DCE_SMB, "Freeing request tracker: "
-        "Uid: %hu, Tid: %hu, Pid: %hu, Mid: %d\n",
-        rtracker->uid, rtracker->tid, rtracker->pid, rtracker->mid);
-
     DCE2_SmbCleanRequestTracker(rtracker);
     snort_free((void*)rtracker);
 }
@@ -868,8 +822,6 @@ void DCE2_SmbRemoveTid(DCE2_SmbSsnData* ssd, const uint16_t tid)
 {
     Profile profile(dce2_smb_pstat_smb_tid);
 
-    DebugFormat(DEBUG_DCE_SMB, "Removing Tid: %hu\n", tid);
-
     if ((ssd->tid != DCE2_SENTINEL) && ((ssd->tid & 0x0000ffff) == (int)tid))
         ssd->tid = DCE2_SENTINEL;
     else
@@ -914,21 +866,20 @@ void DCE2_SmbInsertTid(DCE2_SmbSsnData* ssd,
         || ((ssd->max_file_depth == -1) && DCE2_ScSmbFileDepth(
         (dce2SmbProtoConf*)ssd->sd.config) == -1)))
     {
-        DebugFormat(DEBUG_DCE_SMB, "Not inserting TID (%hu) "
+        trace_logf(dce_smb, "Not inserting TID (%hu) "
             "because it's not IPC and not inspecting normal file "
-            "data.", tid);
+            "data.\n", tid);
         return;
     }
 
     if (is_ipc && DCE2_ScSmbFileInspectionOnly((dce2SmbProtoConf*)ssd->sd.config))
     {
-        DebugFormat(DEBUG_DCE_SMB, "Not inserting TID (%hu) "
+        trace_logf(dce_smb, "Not inserting TID (%hu) "
             "because it's IPC and only inspecting normal file "
-            "data.", tid);
+            "data.\n", tid);
         return;
     }
 
-    DebugFormat(DEBUG_DCE_SMB, "Inserting Tid: %hu\n", tid);
     int insert_tid = (int)tid;
     // Set a bit so as to distinguish between IPC and non-IPC TIDs
     if (!is_ipc)
@@ -1027,9 +978,6 @@ void DCE2_SmbQueueTmpFileTracker(DCE2_SmbSsnData* ssd,
 {
     Profile profile(dce2_smb_pstat_smb_fid);
 
-    DebugFormat(DEBUG_DCE_SMB, "Queuing file tracker "
-        "with Uid: %hu, Tid: %hu\n", uid, tid);
-
     DCE2_SmbFileTracker* ftracker = (DCE2_SmbFileTracker*)
         snort_calloc(sizeof(DCE2_SmbFileTracker));
 
@@ -1075,9 +1023,6 @@ DCE2_Ret DCE2_SmbProcessRequestData(DCE2_SmbSsnData* ssd,
     if (ftracker == nullptr)
         return DCE2_RET__ERROR;
 
-    DebugFormat(DEBUG_DCE_SMB,
-        "Processing request data with Fid: 0x%04X ~~~~~~~~~~~~~~~~~\n", ftracker->fid_v1);
-
     // Set this in case of chained commands or reassembled packet
     ssd->cur_rtracker->ftracker = ftracker;
 
@@ -1098,8 +1043,6 @@ DCE2_Ret DCE2_SmbProcessRequestData(DCE2_SmbSsnData* ssd,
         DCE2_SmbProcessFileData(ssd, ftracker, data_ptr, data_len, true);
     }
 
-    DebugMessage(DEBUG_DCE_SMB, "~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\n");
-
     return DCE2_RET__SUCCESS;
 }
 
@@ -1111,9 +1054,6 @@ DCE2_Ret DCE2_SmbProcessResponseData(DCE2_SmbSsnData* ssd,
     if (ftracker == nullptr)
         return DCE2_RET__ERROR;
 
-    DebugFormat(DEBUG_DCE_SMB,
-        "Processing response data with Fid: 0x%04X ~~~~~~~~~~~~~~~~\n", ftracker->fid_v1);
-
     if (ftracker->is_ipc)
     {
         // Maximum possible fragment length is 16 bit
@@ -1128,8 +1068,6 @@ DCE2_Ret DCE2_SmbProcessResponseData(DCE2_SmbSsnData* ssd,
         DCE2_SmbProcessFileData(ssd, ftracker, data_ptr, data_len, false);
     }
 
-    DebugMessage(DEBUG_DCE_SMB, "~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\n");
-
     return DCE2_RET__SUCCESS;
 }
 
@@ -1636,14 +1574,12 @@ static DCE2_Ret DCE2_SmbFileAPIProcess(DCE2_SmbSsnData* ssd,
             position = SNORT_FILE_MIDDLE;
     }
 
-    DebugMessage(DEBUG_DCE_SMB, "Sending SMB file data to file API ........\n");
-
     Profile profile(dce2_smb_pstat_smb_file_api);
     FileFlows* file_flows = FileFlows::get_file_flows(ssd->sd.wire_pkt->flow);
     if (!file_flows->file_process(data_ptr, (int)data_len, position, upload,
         DCE2_SmbIsVerdictSuspend(upload, position)))
     {
-        DebugFormat(DEBUG_DCE_SMB, "File API returned FAILURE "
+        trace_logf(dce_smb, "File API returned FAILURE "
             "for (0x%02X) %s\n", ftracker->fid_v1, upload ? "UPLOAD" : "DOWNLOAD");
 
         // Failure.  Abort tracking this file under file API
@@ -1651,9 +1587,6 @@ static DCE2_Ret DCE2_SmbFileAPIProcess(DCE2_SmbSsnData* ssd,
     }
     else
     {
-        DebugFormat(DEBUG_DCE_SMB, "File API returned SUCCESS "
-            "for (0x%02X) %s\n", ftracker->fid_v1, upload ? "UPLOAD" : "DOWNLOAD");
-
         if (((position == SNORT_FILE_START) || (position == SNORT_FILE_FULL))
             && (ftracker->file_name_size != 0))
         {
@@ -1784,9 +1717,6 @@ static DCE2_Ret DCE2_SmbHandleOutOfOrderFileData(DCE2_SmbSsnData* ssd,
         if ((ret = DCE2_ListInsert(ftracker->ff_file_chunks,
                 (void*)file_chunk, (void*)file_chunk)) != DCE2_RET__SUCCESS)
         {
-            DebugFormat(DEBUG_DCE_SMB, "Insert file chunk failed: "
-                "0x%02X.\n", ftracker->fid_v1);
-
             snort_free((void*)file_chunk->data);
             snort_free((void*)file_chunk);
 
@@ -1795,9 +1725,6 @@ static DCE2_Ret DCE2_SmbHandleOutOfOrderFileData(DCE2_SmbSsnData* ssd,
         }
     }
 
-    DebugFormat(DEBUG_DCE_SMB, "Currently buffering %u bytes "
-        "of out of order file data.\n", ftracker->ff_bytes_queued);
-
     return DCE2_RET__SUCCESS;
 }
 
@@ -1832,11 +1759,6 @@ void DCE2_SmbProcessFileData(DCE2_SmbSsnData* ssd,
 
     Profile profile(dce2_smb_pstat_smb_file);
 
-    DebugFormat(DEBUG_DCE_SMB,
-        "File size: %" PRIu64 ", File offset: %" PRIu64 ", Bytes processed: %" PRIu64 ", "
-        "Data len: %u\n", ftracker->ff_file_size, ftracker->ff_file_offset,
-        ftracker->ff_bytes_processed, data_len);
-
     // Account for wrapping.  Not likely but just in case.
     if ((ftracker->ff_bytes_processed + data_len) < ftracker->ff_bytes_processed)
     {
@@ -1855,14 +1777,9 @@ void DCE2_SmbProcessFileData(DCE2_SmbSsnData* ssd,
         if (cur_upload)
         {
             // Went from writing to reading.  Ignore the read.
-            DebugMessage(DEBUG_DCE_SMB, "Went from writing to "
-                "reading - ignoring read.\n");
             return;
         }
 
-        DebugMessage(DEBUG_DCE_SMB, "Went from reading to "
-            "writing - consider transfer done.\n");
-
         // Went from reading to writing.  Consider the transfer done
         // and remove the file tracker.
         DCE2_SmbRemoveFileTracker(ssd, ftracker);
@@ -1911,7 +1828,7 @@ void DCE2_SmbProcessFileData(DCE2_SmbSsnData* ssd,
             }
             else if (ftracker->ff_file_offset < ftracker->ff_bytes_processed)
             {
-                DebugFormat(DEBUG_DCE_SMB, "File offset %" PRIu64 " is "
+                trace_logf(dce_smb, "File offset %" PRIu64 " is "
                     "less than bytes processed %" PRIu64 " - aborting.\n",
                     ftracker->ff_file_offset, ftracker->ff_bytes_processed);
 
@@ -1954,11 +1871,8 @@ void DCE2_SmbProcessFileData(DCE2_SmbSsnData* ssd,
             || ((file_data_depth != 0)
             && (ftracker->ff_bytes_processed >= (uint64_t)file_data_depth)))
         {
-            DebugFormat(DEBUG_DCE_SMB, "Bytes processed %" PRIu64
-                "is at or beyond file data depth %" PRIi64 " - finished.\n",
-                ftracker->ff_bytes_processed, file_data_depth);
+           // Bytes processed is at or beyond file data depth - finished.
             DCE2_SmbRemoveFileTracker(ssd, ftracker);
-
             return;
         }
     }
@@ -1968,9 +1882,6 @@ void DCE2_FileDetect()
 {
     Packet* top_pkt = DetectionEngine::get_current_packet();
 
-    DebugMessage(DEBUG_DCE_SMB, "Payload:\n");
-    DCE2_PrintPktData(top_pkt->data, top_pkt->dsize);
-
     Profile profile(dce2_smb_pstat_smb_file_detect);
     DetectionEngine::detect(top_pkt);
 
@@ -1988,9 +1899,6 @@ static void DCE2_SmbSetNewFileAPIFileTracker(DCE2_SmbSsnData* ssd)
             && !ftracker->is_ipc && ftracker->ff_sequential_only
             && (ftracker->ff_bytes_processed == 0))
         {
-            DebugFormat(DEBUG_DCE_SMB, "Designating file tracker "
-                "for file API processing: \"%s\" (0x%04X)\n",
-                ftracker->file_name, (uint16_t)ftracker->fid_v1);
             break;
         }
 
index d2544a8baaf8321ca5616bde8be19c7e098540c3..441a0dcba65c15f94b93d64dcd07a836d913f3d9 100644 (file)
@@ -80,9 +80,6 @@ static DCE2_TcpSsnData* dce2_create_new_tcp_session(Packet* p, dce2TcpProtoConf*
 {
     Profile profile(dce2_tcp_pstat_new_session);
 
-    DebugMessage(DEBUG_DCE_TCP, "DCE over TCP packet detected\n");
-    DebugMessage(DEBUG_DCE_TCP, "Creating new session\n");
-
     DCE2_TcpSsnData* dce2_tcp_sess = set_new_dce2_tcp_session(p);
 
     if ( dce2_tcp_sess )
@@ -91,7 +88,6 @@ static DCE2_TcpSsnData* dce2_create_new_tcp_session(Packet* p, dce2TcpProtoConf*
         DCE2_ResetRopts(&dce2_tcp_sess->sd.ropts);
 
         dce2_tcp_stats.tcp_sessions++;
-        DebugFormat(DEBUG_DCE_TCP,"Created (%p)\n", (void*)dce2_tcp_sess);
 
         dce2_tcp_sess->sd.trans = DCE2_TRANS_TYPE__TCP;
         dce2_tcp_sess->sd.server_policy = config->common.policy;
@@ -114,8 +110,6 @@ static DCE2_TcpSsnData* dce2_handle_tcp_session(Packet* p, dce2TcpProtoConf* con
         dce2_tcp_sess = dce2_create_new_tcp_session(p, config);
     }
 
-    DebugFormat(DEBUG_DCE_TCP, "Session pointer: %p\n", (void*)dce2_tcp_sess);
-
     return dce2_tcp_sess;
 }
 
@@ -153,22 +147,12 @@ void Dce2Tcp::eval(Packet* p)
 {
     DCE2_TcpSsnData* dce2_tcp_sess;
     Profile profile(dce2_tcp_pstat_main);
-    if (DCE2_SsnFromServer(p))
-    {
-        DebugMessage(DEBUG_DCE_TCP, "Packet from Server.\n");
-    }
-    else
-    {
-        DebugMessage(DEBUG_DCE_TCP, "Packet from Client.\n");
-    }
 
     assert(p->has_tcp_data());
     assert(p->flow);
 
     if (p->flow->get_session_flags() & SSNFLAG_MIDSTREAM)
     {
-        DebugMessage(DEBUG_DCE_TCP,
-            "Midstream - not inspecting.\n");
         return;
     }
 
index 1cfcfb9de3d8f5c0bb052e28ec0659651e0da074..0d6e6d8ec7efe9643f864247117357060c2b8d16 100644 (file)
@@ -25,8 +25,6 @@
 
 #include "dce_tcp_paf.h"
 
-#include "main/snort_debug.h"
-
 #include "dce_tcp.h"
 
 using namespace snort;
@@ -53,27 +51,14 @@ static StreamSplitter::Status dce2_tcp_paf(DCE2_PafTcpData* ds, Flow* flow, cons
 
     int num_requests = 0;
 
-    DebugFormat(DEBUG_DCE_TCP, "%s\n", DCE2_DEBUG__PAF_START_MSG_TCP);
-    DebugFormat(DEBUG_DCE_TCP, "TCP: %u bytes of data\n", len);
-
-#ifdef DEBUG_MSGS
-    if (flags & PKT_FROM_CLIENT)
-        DebugMessage(DEBUG_DCE_TCP, "Packet from Client\n");
-    else
-        DebugMessage(DEBUG_DCE_TCP, "Packet from Server\n");
-#endif
-
     if (dce2_paf_abort(flow, (DCE2_SsnData*)sd))
     {
-        DebugFormat(DEBUG_DCE_TCP, "%s\n", DCE2_DEBUG__PAF_END_MSG);
         return StreamSplitter::ABORT;
     }
 
     if (sd == nullptr)
     {
         bool autodetected = false;
-        DebugMessage(DEBUG_DCE_TCP, "No session data - autodetecting\n");
-
         if (len >= sizeof(DceRpcCoHdr))
         {
             const DceRpcCoHdr* co_hdr = (const DceRpcCoHdr*)data;
@@ -87,46 +72,30 @@ static StreamSplitter::Status dce2_tcp_paf(DCE2_PafTcpData* ds, Flow* flow, cons
                 && (DceRpcCoFragLen(co_hdr) >= sizeof(DceRpcCoHdr)))
             {
                 autodetected = true;
-                DebugMessage(DEBUG_DCE_TCP, "Autodetected!\n");
             }
         }
         else if ((*data == DCERPC_PROTO_MAJOR_VERS__5) && (flags & PKT_FROM_CLIENT))
         {
             autodetected = true;
-            DebugMessage(DEBUG_DCE_TCP, "Autodetected!\n");
         }
 
         if (!autodetected)
         {
-            DebugMessage(DEBUG_DCE_TCP, "Couldn't autodetect - aborting\n");
-            DebugFormat(DEBUG_DCE_TCP, "%s\n", DCE2_DEBUG__PAF_END_MSG);
             return StreamSplitter::ABORT;
         }
     }
 
-    DebugFormat(DEBUG_DCE_TCP, "Start state: %d\n", ds->paf_state);
     start_state = (uint8_t)ds->paf_state;
 
     while (n < len)
     {
-        DebugFormatNoFileLine(DEBUG_DCE_TCP, " State %d : 0x%02x\n", ds->paf_state, data[n]);
-
         switch (ds->paf_state)
         {
         case DCE2_PAF_TCP_STATES__4:      // Get byte order
             ds->byte_order = DceRpcByteOrder(data[n]);
             ds->paf_state = (DCE2_PafTcpStates)(((int)ds->paf_state) + 1);
-            if (ds->byte_order == DCERPC_BO_FLAG__LITTLE_ENDIAN)
-            {
-                DebugFormatNoFileLine(DEBUG_DCE_TCP, "%s","Byte order: Little endian\n");
-            }
-            else
-            {
-                DebugFormatNoFileLine(DEBUG_DCE_TCP, "%s","Byte order: Big endian\n");
-            }
             break;
         case DCE2_PAF_TCP_STATES__8:
-            DebugFormatNoFileLine(DEBUG_DCE_TCP, "%s", "First byte of fragment length\n");
             if (ds->byte_order == DCERPC_BO_FLAG__LITTLE_ENDIAN)
                 ds->frag_len = data[n];
             else
@@ -134,7 +103,6 @@ static StreamSplitter::Status dce2_tcp_paf(DCE2_PafTcpData* ds, Flow* flow, cons
             ds->paf_state = (DCE2_PafTcpStates)(((int)ds->paf_state) + 1);
             break;
         case DCE2_PAF_TCP_STATES__9:
-            DebugFormatNoFileLine(DEBUG_DCE_TCP, "%s", "Second byte of fragment length\n");
             if (ds->byte_order == DCERPC_BO_FLAG__LITTLE_ENDIAN)
                 ds->frag_len |= data[n] << 8;
             else
@@ -143,12 +111,9 @@ static StreamSplitter::Status dce2_tcp_paf(DCE2_PafTcpData* ds, Flow* flow, cons
             /* If we get a bad frag length abort */
             if (ds->frag_len < sizeof(DceRpcCoHdr))
             {
-                DebugFormatNoFileLine(DEBUG_DCE_TCP, "%s\n", DCE2_DEBUG__PAF_END_MSG);
                 return StreamSplitter::ABORT;
             }
 
-            DebugFormatNoFileLine(DEBUG_DCE_TCP, "Fragment length: %hu\n", ds->frag_len);
-
             /* Increment n here so we can continue */
             n += ds->frag_len - (uint8_t)ds->paf_state;
             num_requests++;
@@ -156,7 +121,7 @@ static StreamSplitter::Status dce2_tcp_paf(DCE2_PafTcpData* ds, Flow* flow, cons
              * flush just before it */
             if ((num_requests == 1) || (n <= len))
                 tmp_fp += ds->frag_len;
-            DebugFormatNoFileLine(DEBUG_DCE_TCP, "Requests: %d\n", num_requests);
+          
             ds->paf_state = DCE2_PAF_TCP_STATES__0;
             continue;      // we incremented n already
         default:
@@ -170,12 +135,9 @@ static StreamSplitter::Status dce2_tcp_paf(DCE2_PafTcpData* ds, Flow* flow, cons
     if (tmp_fp != 0)
     {
         *fp = tmp_fp - start_state;
-        DebugFormat(DEBUG_DCE_TCP, "Setting flush point: %u\n", *fp);
-        DebugFormat(DEBUG_DCE_TCP, "%s\n", DCE2_DEBUG__PAF_END_MSG);
         return StreamSplitter::FLUSH;
     }
 
-    DebugFormat(DEBUG_DCE_TCP, "%s\n", DCE2_DEBUG__PAF_END_MSG);
     return ps;
 }
 
index 41e6802b4e32d51a898281d8c9fa3b8306370017..6065185c749f6238d5991c31fb3fcd3074fbde60 100644 (file)
@@ -92,16 +92,11 @@ static DCE2_UdpSsnData* dce2_create_new_udp_session(Packet* p, dce2UdpProtoConf*
 {
     Profile profile(dce2_udp_pstat_new_session);
 
-    DebugMessage(DEBUG_DCE_UDP, "DCE over UDP packet detected\n");
-    DebugMessage(DEBUG_DCE_UDP, "Creating new session\n");
-
     DCE2_UdpSsnData* dce2_udp_sess = set_new_dce2_udp_session(p);
 
     DCE2_ResetRopts(&dce2_udp_sess->sd.ropts);
 
     dce2_udp_stats.udp_sessions++;
-    DebugFormat(DEBUG_DCE_UDP,"Created (%p)\n", (void*)dce2_udp_sess);
-
     dce2_udp_sess->sd.trans = DCE2_TRANS_TYPE__UDP;
     dce2_udp_sess->sd.wire_pkt = p;
     dce2_udp_sess->sd.config = (void*)config;
@@ -120,8 +115,6 @@ static DCE2_UdpSsnData* dce2_handle_udp_session(Packet* p, dce2UdpProtoConf* con
         dce2_udp_sess = dce2_create_new_udp_session(p, config);
     }
 
-    DebugFormat(DEBUG_DCE_UDP, "Session pointer: %p\n", (void*)dce2_udp_sess);
-
     return dce2_udp_sess;
 }
 
@@ -150,14 +143,6 @@ void Dce2Udp::eval(Packet* p)
 {
     DCE2_UdpSsnData* dce2_udp_sess;
     Profile profile(dce2_udp_pstat_main);
-    if (DCE2_SsnFromServer(p))
-    {
-        DebugMessage(DEBUG_DCE_UDP, "Packet from Server.\n");
-    }
-    else
-    {
-        DebugMessage(DEBUG_DCE_UDP, "Packet from Client.\n");
-    }
 
     assert(p->flow);
 
index ad55eae56dcb7b91600a8acd78eda524d43495a6..1ce1cc42bd048ca232ea85faf02fbe7fe7dcd441 100644 (file)
@@ -31,6 +31,8 @@
 using namespace snort;
 using namespace std;
 
+Trace TRACE_NAME(dce_udp);
+
 static const Parameter s_params[] =
 {
     { "disable_defrag", Parameter::PT_BOOL, nullptr, "false",
@@ -77,7 +79,7 @@ static const PegInfo dce2_udp_pegs[] =
     { CountType::END, nullptr, nullptr }
 };
 
-Dce2UdpModule::Dce2UdpModule() : Module(DCE2_UDP_NAME, DCE2_UDP_HELP, s_params)
+Dce2UdpModule::Dce2UdpModule() : Module(DCE2_UDP_NAME, DCE2_UDP_HELP, s_params, false, &TRACE_NAME(dce_udp))
 {
 }
 
@@ -144,12 +146,12 @@ ProfileStats* Dce2UdpModule::get_profile(
     return nullptr;
 }
 
-bool Dce2UdpModule::set(const char*, Value& v, SnortConfig*)
+bool Dce2UdpModule::set(const char* fqn, Value& v, SnortConfig* c)
 {
     if (dce2_set_common_config(v,config.common))
         return true;
     else
-        return false;
+        return Module::set(fqn, v, c);
 }
 
 void Dce2UdpModule::get_data(dce2UdpProtoConf& dce2_udp_config)
index fbff01927a5214bd1545c34b7d3c94364fdb11b5..d5a5019dbf1940de0e8280c03ee83236a1d19e35 100644 (file)
@@ -44,6 +44,8 @@ struct dce2UdpProtoConf
     dce2CommonProtoConf common;
 };
 
+extern Trace TRACE_NAME(dce_udp);
+
 class Dce2UdpModule : public snort::Module
 {
 public:
index 37b222c2514318659f0973448acbf26e56655643..1c1233fa5c3568637624be6819ca8981fd6a3c64 100644 (file)
@@ -109,8 +109,6 @@ void DCE2_ClProcess(DCE2_SsnData* sd, DCE2_ClTracker* clt)
     const uint8_t* data_ptr = sd->wire_pkt->data;
     uint16_t data_len = sd->wire_pkt->dsize;
 
-    DebugMessage(DEBUG_DCE_UDP, "Cl processing ...\n");
-
     if (data_len < sizeof(DceRpcClHdr))
     {
         dce_alert(GID_DCE2,  DCE2_CL_DATA_LT_HDR, (dce2CommonStats*)&dce2_udp_stats);
@@ -134,34 +132,29 @@ void DCE2_ClProcess(DCE2_SsnData* sd, DCE2_ClTracker* clt)
         switch (DceRpcClPduType(cl_hdr))
         {
         case DCERPC_PDU_TYPE__REQUEST:
-            DebugMessage(DEBUG_DCE_UDP, "Request\n");
             dce2_udp_stats.cl_request++;
             DCE2_ClRequest(sd, at, cl_hdr, data_ptr, data_len);
             break;
 
         case DCERPC_PDU_TYPE__ACK:
-            DebugMessage(DEBUG_DCE_UDP, "Ack\n");
             dce2_udp_stats.cl_ack++;
             break;
 
         case DCERPC_PDU_TYPE__CL_CANCEL:
-            DebugMessage(DEBUG_DCE_UDP, "Cancel\n");
             dce2_udp_stats.cl_cancel++;
             break;
 
         case DCERPC_PDU_TYPE__FACK:
-            DebugMessage(DEBUG_DCE_UDP, "Fack\n");
             dce2_udp_stats.cl_cli_fack++;
             break;
 
         case DCERPC_PDU_TYPE__PING:
-            DebugMessage(DEBUG_DCE_UDP, "Ping\n");
             dce2_udp_stats.cl_ping++;
             break;
 
         case DCERPC_PDU_TYPE__RESPONSE:
         {
-            DebugMessage(DEBUG_DCE_UDP, "Response from client.  Changing stream direction.");
+            trace_log(dce_udp, "Response from client.  Changing stream direction.\n");
             Packet* p = sd->wire_pkt;
             ip::IpApi* ip_api = &p->ptrs.ip_api;
 
@@ -172,7 +165,6 @@ void DCE2_ClProcess(DCE2_SsnData* sd, DCE2_ClTracker* clt)
             break;
         }
         default:
-            DebugMessage(DEBUG_DCE_UDP, "Other pdu type\n");
             dce2_udp_stats.cl_other_req++;
             break;
         }
@@ -182,12 +174,10 @@ void DCE2_ClProcess(DCE2_SsnData* sd, DCE2_ClTracker* clt)
         switch (DceRpcClPduType(cl_hdr))
         {
         case DCERPC_PDU_TYPE__RESPONSE:
-            DebugMessage(DEBUG_DCE_UDP, "Response\n");
             dce2_udp_stats.cl_response++;
             break;
 
         case DCERPC_PDU_TYPE__REJECT:
-            DebugMessage(DEBUG_DCE_UDP, "Reject\n");
             dce2_udp_stats.cl_reject++;
 
             if (DceRpcClSeqNum(cl_hdr) == at->seq_num)
@@ -199,32 +189,26 @@ void DCE2_ClProcess(DCE2_SsnData* sd, DCE2_ClTracker* clt)
             break;
 
         case DCERPC_PDU_TYPE__CANCEL_ACK:
-            DebugMessage(DEBUG_DCE_UDP, "Cancel Ack\n");
             dce2_udp_stats.cl_cancel_ack++;
             break;
 
         case DCERPC_PDU_TYPE__FACK:
-            DebugMessage(DEBUG_DCE_UDP, "Fack\n");
             dce2_udp_stats.cl_srv_fack++;
             break;
 
         case DCERPC_PDU_TYPE__FAULT:
-            DebugMessage(DEBUG_DCE_UDP, "Fault\n");
             dce2_udp_stats.cl_fault++;
             break;
 
         case DCERPC_PDU_TYPE__NOCALL:
-            DebugMessage(DEBUG_DCE_UDP, "No call\n");
             dce2_udp_stats.cl_nocall++;
             break;
 
         case DCERPC_PDU_TYPE__WORKING:
-            DebugMessage(DEBUG_DCE_UDP, "Working\n");
             dce2_udp_stats.cl_working++;
             break;
 
         default:
-            DebugMessage(DEBUG_DCE_UDP, "Other pdu type\n");
             dce2_udp_stats.cl_other_resp++;
             break;
         }
@@ -310,8 +294,6 @@ static void DCE2_ClRequest(DCE2_SsnData* sd, DCE2_ClActTracker* at, const DceRpc
 {
     const uint32_t seq_num = DceRpcClSeqNum(cl_hdr);
 
-    DebugMessage(DEBUG_DCE_UDP, "Processing Request ...\n");
-
     if (seq_num > at->seq_num)
     {
         /* This is the normal case where the sequence number is incremented
@@ -573,12 +555,7 @@ static void DCE2_ClFragReassemble(
         fnode = (DCE2_ClFragNode*)DCE2_ListNext(ft->frags))
     {
         if (fnode->frag_len > rlen)
-        {
-            DebugMessage(DEBUG_DCE_UDP,
-                "Size of fragments exceeds reassembly buffer size. "
-                "Using as many fragments as will fit.");
             break;
-        }
 
         memcpy(const_cast<uint8_t*>(rdata), fnode->frag_data, fnode->frag_len);
         DCE2_MOVE(rdata, rlen, fnode->frag_len);
index fc59fedc606af68b9b6a96a4182d36436a4d12af..b8834708880ff9718aa0d70c03cae6305517384a 100644 (file)
@@ -23,7 +23,6 @@
 
 #include "dce_utils.h"
 
-#include "main/snort_debug.h"
 #include "utils/safec.h"
 #include "utils/util.h"
 
@@ -183,120 +182,6 @@ const char* DCE2_UuidToStr(
     return uuid_buf;
 }
 
-#ifdef DEBUG_MSGS
-void DCE2_PrintPktData(const uint8_t* data, const uint16_t len)
-{
-    unsigned int i, j = 0, line_len = 0;
-    uint8_t hex_buf[16];
-    uint8_t char_buf[16];
-
-    for (i = 0; i < len; i++)
-    {
-        hex_buf[j] = data[i];
-
-        if (isascii((int)data[i]) && isprint((int)data[i]))
-            char_buf[j] = data[i];
-        else
-            char_buf[j] = '.';
-
-        if (line_len == 15)
-        {
-            unsigned int k, sub_line_len = 0;
-            for (k = 0; k <= j; k++)
-            {
-                DebugFormatNoFileLine(DEBUG_DCE_COMMON,"%02x ", hex_buf[k]);
-                if (sub_line_len >= 7)
-                {
-                    DebugFormatNoFileLine(DEBUG_DCE_COMMON,"%s"," ");
-                    sub_line_len = 0;
-                }
-                else
-                {
-                    sub_line_len++;
-                }
-            }
-
-            DebugFormatNoFileLine(DEBUG_DCE_COMMON,"%s"," ");
-
-            sub_line_len = 0;
-            for (k = 0; k <= j; k++)
-            {
-                DebugFormatNoFileLine(DEBUG_DCE_COMMON,"%c", char_buf[k]);
-                if (sub_line_len >= 7)
-                {
-                    DebugFormatNoFileLine(DEBUG_DCE_COMMON,"%s"," ");
-                    sub_line_len = 0;
-                }
-                else
-                {
-                    sub_line_len++;
-                }
-            }
-
-            DebugFormatNoFileLine(DEBUG_DCE_COMMON,"%s","\n");
-
-            j = line_len = 0;
-        }
-        else
-        {
-            j++;
-            line_len++;
-        }
-    }
-
-    if (line_len > 0)
-    {
-        unsigned int k, sub_line_len = 0;
-        for (k = 0; k < j; k++)
-        {
-            DebugFormatNoFileLine(DEBUG_DCE_COMMON,"%02x ", hex_buf[k]);
-            if (sub_line_len >= 7)
-            {
-                DebugFormatNoFileLine(DEBUG_DCE_COMMON,"%s"," ");
-                sub_line_len = 0;
-            }
-            else
-            {
-                sub_line_len++;
-            }
-        }
-
-        if (k < 8)
-            DebugFormatNoFileLine(DEBUG_DCE_COMMON,"%s","   ");
-        else
-            DebugFormatNoFileLine(DEBUG_DCE_COMMON,"%s","  ");
-
-        while (k < 16)
-        {
-            DebugFormatNoFileLine(DEBUG_DCE_COMMON,"%s","   ");
-            k++;
-        }
-
-        sub_line_len = 0;
-        for (k = 0; k < j; k++)
-        {
-            DebugFormatNoFileLine(DEBUG_DCE_COMMON,"%c", char_buf[k]);
-            if (sub_line_len >= 7)
-            {
-                DebugFormatNoFileLine(DEBUG_DCE_COMMON,"%s"," ");
-                sub_line_len = 0;
-            }
-            else
-            {
-                sub_line_len++;
-            }
-        }
-    }
-
-    DebugFormatNoFileLine(DEBUG_DCE_COMMON,"%s","\n");
-}
-
-#else
-void DCE2_PrintPktData(const uint8_t*, const uint16_t)
-{
-}
-
-#endif // DEBUG_MSGS
 
 DCE2_Buffer* DCE2_BufferNew(uint32_t initial_size, uint32_t min_add_size)
 {
@@ -323,7 +208,6 @@ void* DCE2_ReAlloc(void* old_mem, uint32_t old_size, uint32_t new_size)
     }
     else if (new_size < old_size)
     {
-        DebugMessage(DEBUG_DCE_COMMON, "New size is less than old size.\n");
         return nullptr;
     }
     else if (new_size == old_size)
index bd92c25cc759f84d0a17cb839c072f063a97e0f7..05423fbbdb18cc516414f290a272dea357a258c2 100644 (file)
@@ -128,7 +128,6 @@ inline int DCE2_UuidCompare(const void*, const void*);
  * Public function prototypes
  ********************************************************************/
 DCE2_Ret DCE2_GetValue(const char*, char*, void*, int, DCE2_IntType, uint8_t);
-void DCE2_PrintPktData(const uint8_t*, const uint16_t);
 DCE2_Buffer* DCE2_BufferNew(uint32_t, uint32_t);
 void* DCE2_ReAlloc(void*, uint32_t, uint32_t);
 DCE2_Ret DCE2_BufferAddData(DCE2_Buffer*, const uint8_t*,
index 8817d84c7c1834451b970e2a3a5cdf72924d081e..f0810be768eece43db6d8d8c9134011656b9a71b 100644 (file)
@@ -445,9 +445,6 @@ static DCE2_SmbRequestTracker* DCE2_SmbFindRequestTracker(DCE2_SmbSsnData* ssd,
 
     Profile profile(dce2_smb_pstat_smb_req);
 
-    DebugFormat(DEBUG_DCE_SMB, "Find request tracker => "
-        "Uid: %hu, Tid: %hu, Pid: %hu, Mid: %hu ... ", uid, tid, pid, mid);
-
     DCE2_SmbRequestTracker* tmp_rtracker = &ssd->rtracker;
     int smb_com = SmbCom(smb_hdr);
     switch (smb_com)
@@ -505,7 +502,6 @@ static DCE2_SmbRequestTracker* DCE2_SmbFindRequestTracker(DCE2_SmbSsnData* ssd,
 
             if (ret_rtracker != nullptr)
             {
-                DebugMessage(DEBUG_DCE_SMB, "Found.\n");
                 return ret_rtracker;
             }
 
@@ -560,7 +556,7 @@ static DCE2_SmbRequestTracker* DCE2_SmbFindRequestTracker(DCE2_SmbSsnData* ssd,
             ret_rtracker = first_mid_rtracker;
         break;
     default:
-        DebugFormat(DEBUG_DCE_SMB, "Invalid policy: %d", policy);
+        assert(false);
         break;
     }
 
@@ -607,13 +603,9 @@ static void DCE2_SmbCheckCommand(DCE2_SmbSsnData* ssd,
         if ((SmbEmptyComWct(ec) == 0)
             && (SmbEmptyComBcc(ec) == 0) && SmbError(smb_hdr))
         {
-            DebugFormat(DEBUG_DCE_SMB,
-                "Response error: 0x%08X\n", SmbNtStatus(smb_hdr));
-
             // If broken pipe, clean up data associated with open named pipe
             if (SmbBrokenPipe(smb_hdr))
             {
-                DebugMessage(DEBUG_DCE_SMB, "Broken or disconnected pipe.\n");
                 DCE2_SmbRemoveFileTracker(ssd, ssd->cur_rtracker->ftracker);
             }
 
@@ -803,7 +795,7 @@ static void DCE2_SmbProcessCommand(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr
         com_info.cmd_size = 0;
         com_info.byte_count = 0;
         DCE2_SmbCheckCommand(ssd, smb_hdr, smb_com, nb_ptr, nb_len, com_info);
-        DebugFormat(DEBUG_DCE_SMB, "Processing command: %s (0x%02X)\n",
+        trace_logf(dce_smb, "Processing command: %s (0x%02X)\n",
             get_smb_com_string(smb_com), smb_com);
 
         // Note that even if the command shouldn't be processed, some of
@@ -828,7 +820,7 @@ static void DCE2_SmbProcessCommand(DCE2_SmbSsnData* ssd, const SmbNtHdr* smb_hdr
         if (smb_com2 == SMB_COM_NO_ANDX_COMMAND)
             break;
 
-        DebugFormat(DEBUG_DCE_SMB, "Chained SMB command: %s\n",
+        trace_logf(dce_smb, "Chained SMB command: %s\n",
             get_smb_com_string(smb_com2));
 
         num_chained++;
@@ -1024,13 +1016,11 @@ static DCE2_SmbRequestTracker* DCE2_SmbInspect(DCE2_SmbSsnData* ssd, const SmbNt
 {
     int smb_com = SmbCom(smb_hdr);
 
-    DebugFormat(DEBUG_DCE_SMB, "SMB command: %s (0x%02X)\n",
+    trace_logf(dce_smb, "SMB command: %s (0x%02X)\n",
         get_smb_com_string(smb_com), smb_com);
 
     if (smb_com_funcs[smb_com] == nullptr)
     {
-        DebugMessage(DEBUG_DCE_SMB, "Command isn't processed "
-            "by preprocessor.\n");
         return nullptr;
     }
 
@@ -1060,11 +1050,7 @@ static DCE2_SmbRequestTracker* DCE2_SmbInspect(DCE2_SmbSsnData* ssd, const SmbNt
             break;
         default:
             if (DCE2_SmbFindTid(ssd, SmbTid(smb_hdr)) != DCE2_RET__SUCCESS)
-            {
-                DebugFormat(DEBUG_DCE_SMB,
-                    "Couldn't find Tid (%hu)\n", SmbTid(smb_hdr));
                 return nullptr;
-            }
 
             if (DCE2_SmbIsTidIPC(ssd, SmbTid(smb_hdr)))
             {
@@ -1083,8 +1069,6 @@ static DCE2_SmbRequestTracker* DCE2_SmbInspect(DCE2_SmbSsnData* ssd, const SmbNt
                     case DCE2_POLICY__SAMBA_3_0_37:
                     case DCE2_POLICY__SAMBA_3_0_22:
                     case DCE2_POLICY__SAMBA_3_0_20:
-                        DebugMessage(DEBUG_DCE_SMB, "Samba doesn't "
-                            "process this command under an IPC tree.\n");
                         return nullptr;
                     default:
                         break;
@@ -1105,9 +1089,6 @@ static DCE2_SmbRequestTracker* DCE2_SmbInspect(DCE2_SmbSsnData* ssd, const SmbNt
                     case DCE2_POLICY__SAMBA_3_0_37:
                     case DCE2_POLICY__SAMBA_3_0_22:
                     case DCE2_POLICY__SAMBA_3_0_20:
-                        DebugMessage(DEBUG_DCE_SMB, "Samba and "
-                            "Windows Vista on don't process this "
-                            "command under an IPC tree.\n");
                         return nullptr;
                     default:
                         break;
@@ -1127,7 +1108,6 @@ static DCE2_SmbRequestTracker* DCE2_SmbInspect(DCE2_SmbSsnData* ssd, const SmbNt
                 // These commands are only used for IPC
                 case SMB_COM_TRANSACTION:
                 case SMB_COM_TRANSACTION_SECONDARY:
-                    DebugMessage(DEBUG_DCE_SMB, "secondary transaction not IPC.\n");
                     return nullptr;
                 case SMB_COM_READ_RAW:
                 case SMB_COM_WRITE_RAW:
@@ -1140,9 +1120,6 @@ static DCE2_SmbRequestTracker* DCE2_SmbInspect(DCE2_SmbSsnData* ssd, const SmbNt
                     case DCE2_POLICY__WINVISTA:
                     case DCE2_POLICY__WIN2008:
                     case DCE2_POLICY__WIN7:
-                        DebugMessage(DEBUG_DCE_SMB,
-                            "Windows Vista on don't process "
-                            "this command.\n");
                         return nullptr;
                     default:
                         break;
@@ -1199,9 +1176,6 @@ static void DCE2_SmbProcessRawData(DCE2_SmbSsnData* ssd, const uint8_t* nb_ptr,
 
     if (DCE2_SsnFromClient(ssd->sd.wire_pkt))
     {
-        DebugMessage(DEBUG_DCE_SMB, "Raw data: Write Raw\n");
-        DebugFormat(DEBUG_DCE_SMB, "Request Fid: 0x%04X\n", ftracker->fid_v1);
-
         if (nb_len > ssd->cur_rtracker->writeraw_remaining)
         {
             dce_alert(GID_DCE2, DCE2_SMB_TDCNT_LT_DSIZE, (dce2CommonStats*)&dce2_smb_stats);
@@ -1225,9 +1199,6 @@ static void DCE2_SmbProcessRawData(DCE2_SmbSsnData* ssd, const uint8_t* nb_ptr,
     }
     else
     {
-        DebugMessage(DEBUG_DCE_SMB, "Raw data: Read Raw\n");
-        DebugFormat(DEBUG_DCE_SMB, "Response Fid: 0x%04X\n", ftracker->fid_v1);
-
         remove_rtracker = true;
     }
 
@@ -1314,7 +1285,7 @@ static void DCE2_SmbDataFree(DCE2_SmbSsnData* ssd)
 Dce2SmbFlowData::Dce2SmbFlowData() : FlowData(inspector_id)
 {
     dce2_smb_stats.concurrent_sessions++;
-    if(dce2_smb_stats.max_concurrent_sessions < dce2_smb_stats.concurrent_sessions)
+    if (dce2_smb_stats.max_concurrent_sessions < dce2_smb_stats.concurrent_sessions)
         dce2_smb_stats.max_concurrent_sessions = dce2_smb_stats.concurrent_sessions;
 }
 
@@ -1345,9 +1316,6 @@ static DCE2_SmbSsnData* dce2_create_new_smb_session(Packet* p, dce2SmbProtoConf*
 {
     Profile profile(dce2_smb_pstat_new_session);
 
-    DebugMessage(DEBUG_DCE_SMB, "DCE over SMB packet detected\n");
-    DebugMessage(DEBUG_DCE_SMB, "Creating new session\n");
-
     DCE2_SmbSsnData* dce2_smb_sess = set_new_dce2_smb_session(p);
     if ( dce2_smb_sess )
     {
@@ -1365,7 +1333,6 @@ static DCE2_SmbSsnData* dce2_create_new_smb_session(Packet* p, dce2SmbProtoConf*
         DCE2_ResetRopts(&dce2_smb_sess->sd.ropts);
 
         dce2_smb_stats.smb_sessions++;
-        DebugFormat(DEBUG_DCE_SMB,"Created (%p)\n", (void*)dce2_smb_sess);
 
         dce2_smb_sess->sd.trans = DCE2_TRANS_TYPE__SMB;
         dce2_smb_sess->sd.server_policy = config->common.policy;
@@ -1407,14 +1374,10 @@ static DCE2_Ret DCE2_NbssHdrChecks(DCE2_SmbSsnData* ssd, const NbssHdr* nb_hdr)
     Packet* p = ssd->sd.wire_pkt;
     bool is_seg_buf = DCE2_SmbIsSegBuffer(ssd, (const uint8_t*)nb_hdr);
 
-    DebugMessage(DEBUG_DCE_SMB, "NetBIOS Session Service type: ");
-
     switch (NbssType(nb_hdr))
     {
     case NBSS_SESSION_TYPE__MESSAGE:
         /* Only want to look at session messages */
-        DebugMessage(DEBUG_DCE_SMB, "Session Message\n");
-
         if (!DCE2_SmbIsRawData(ssd))
         {
             uint32_t nb_len = NbssLen(nb_hdr);
@@ -1424,9 +1387,6 @@ static DCE2_Ret DCE2_NbssHdrChecks(DCE2_SmbSsnData* ssd, const NbssHdr* nb_hdr)
 
             if (nb_len < sizeof(SmbNtHdr))
             {
-                DebugFormat(DEBUG_DCE_SMB, "NetBIOS SS len(%zu) < SMB header len(%zu).\n",
-                    sizeof(SmbNtHdr), sizeof(NbssHdr) + nb_len);
-
                 if (is_seg_buf)
                     DCE2_SmbSegAlert(ssd, DCE2_SMB_NB_LT_SMBHDR);
                 else
@@ -1439,7 +1399,6 @@ static DCE2_Ret DCE2_NbssHdrChecks(DCE2_SmbSsnData* ssd, const NbssHdr* nb_hdr)
         return DCE2_RET__SUCCESS;
 
     case NBSS_SESSION_TYPE__REQUEST:
-        DebugMessage(DEBUG_DCE_SMB, "Session Request\n");
         if (DCE2_SsnFromServer(p))
         {
             if (is_seg_buf)
@@ -1464,13 +1423,9 @@ static DCE2_Ret DCE2_NbssHdrChecks(DCE2_SmbSsnData* ssd, const NbssHdr* nb_hdr)
         break;
 
     case NBSS_SESSION_TYPE__KEEP_ALIVE:
-        DebugMessage(DEBUG_DCE_SMB, "Session Keep Alive\n");
         break;
 
     default:
-        DebugFormat(DEBUG_DCE_SMB,
-            "Invalid Session Service type: 0x%02X\n", NbssType(nb_hdr));
-
         if (is_seg_buf)
             DCE2_SmbSegAlert(ssd, DCE2_SMB_BAD_NBSS_TYPE);
         else
@@ -1482,11 +1437,9 @@ static DCE2_Ret DCE2_NbssHdrChecks(DCE2_SmbSsnData* ssd, const NbssHdr* nb_hdr)
     return DCE2_RET__IGNORE;
 }
 
-
 // This is the main entry point for SMB1 processing.
 static void DCE2_Smb1Process(DCE2_SmbSsnData* ssd)
 {
-    DebugMessage(DEBUG_DCE_SMB, "Processing SMB packet.\n");
     dce2_smb_stats.smb_pkts++;
 
     const Packet* p = ssd->sd.wire_pkt;
@@ -1504,8 +1457,6 @@ static void DCE2_Smb1Process(DCE2_SmbSsnData* ssd)
         uint32_t* ignore_bytes = DCE2_SmbGetIgnorePtr(ssd);
         if (*ignore_bytes)
         {
-            DebugFormat(DEBUG_DCE_SMB, "Ignoring %u bytes\n", *ignore_bytes);
-
             if (data_len <= *ignore_bytes)
             {
                 *ignore_bytes -= data_len;
@@ -1534,9 +1485,6 @@ static void DCE2_Smb1Process(DCE2_SmbSsnData* ssd)
             // See if there is enough data to process the NetBIOS header
             if (data_len < data_need)
             {
-                DebugFormat(DEBUG_DCE_SMB, "Data len(%hu) < NetBIOS SS header(%u). "
-                    "Queuing data.\n", data_len, data_need);
-
                 if (DCE2_SmbHandleSegmentation(seg_buf, data_ptr,
                     data_len, sizeof(NbssHdr)) != DCE2_RET__SUCCESS)
                 {
@@ -1567,25 +1515,17 @@ static void DCE2_Smb1Process(DCE2_SmbSsnData* ssd)
             }
 
             uint32_t nb_len = NbssLen(nb_hdr);
-
-            DebugFormat(DEBUG_DCE_SMB, "NetBIOS PDU length: %u\n", nb_len);
-
             DCE2_Ret status = DCE2_NbssHdrChecks(ssd, nb_hdr);
             if (status != DCE2_RET__SUCCESS)
             {
-                DebugMessage(DEBUG_DCE_SMB, "Not a NetBIOS Session Message.\n");
-
                 if (status == DCE2_RET__IGNORE)
                 {
-                    DebugMessage(DEBUG_DCE_SMB, "Valid NetBIOS header "
-                        "type so ignoring NetBIOS length bytes.\n");
+                    // Valid NetBIOS header type so ignoring NetBIOS length bytes
                     *ignore_bytes = data_need + nb_len;
                 }
                 else      // nb_ret == DCE2_RET__ERROR, i.e. invalid NetBIOS type
                 {
-                    DebugMessage(DEBUG_DCE_SMB, "Not a valid NetBIOS "
-                        "header type so trying to find \\xffSMB to "
-                        "determine how many bytes to ignore.\n");
+                    // Trying to find \\xffSMB to determine how many bytes to ignore
                     *ignore_bytes = DCE2_IgnoreJunkData(data_ptr, data_len, data_need + nb_len);
                 }
 
@@ -1607,7 +1547,6 @@ static void DCE2_Smb1Process(DCE2_SmbSsnData* ssd)
                 // Continue here because of fall through below
                 continue;
             default:
-                DebugFormat(DEBUG_DCE_SMB,"Invalid SMB PDU state: %d\n", ssd->pdu_state);
                 return;
             }
         }
@@ -1627,10 +1566,6 @@ static void DCE2_Smb1Process(DCE2_SmbSsnData* ssd)
             // See if there is enough data to process the SMB header
             if (data_len < data_need)
             {
-                DebugFormat(DEBUG_DCE_SMB, "Data len (%hu) < "
-                    "NetBIOS SS header + SMB header (%u). Queuing data.\n",
-                    data_len, data_need);
-
                 if (DCE2_SmbHandleSegmentation(seg_buf, data_ptr, data_len,
                     sizeof(NbssHdr) + sizeof(SmbNtHdr)) != DCE2_RET__SUCCESS)
                 {
@@ -1675,7 +1610,7 @@ static void DCE2_Smb1Process(DCE2_SmbSsnData* ssd)
             rtracker = DCE2_SmbInspect(ssd, smb_hdr);
             if (rtracker == nullptr)
             {
-                DebugMessage(DEBUG_DCE_SMB, "Not inspecting SMB packet.\n");
+                trace_log(dce_smb, "Not inspecting SMB packet.\n");
 
                 if (DCE2_BufferIsEmpty(*seg_buf))
                 {
@@ -1696,8 +1631,6 @@ static void DCE2_Smb1Process(DCE2_SmbSsnData* ssd)
             // Check the SMB header for anomalies
             if (DCE2_SmbHdrChecks(ssd, smb_hdr) != DCE2_RET__SUCCESS)
             {
-                DebugMessage(DEBUG_DCE_SMB, "Bad SMB header.\n");
-
                 if (DCE2_BufferIsEmpty(*seg_buf))
                 {
                     *ignore_bytes = sizeof(NbssHdr) + NbssLen((const NbssHdr*)data_ptr);
@@ -1744,10 +1677,6 @@ static void DCE2_Smb1Process(DCE2_SmbSsnData* ssd)
             /* It's something we want to inspect so make sure we have the full NBSS packet */
             if (data_len < data_need)
             {
-                DebugFormat(DEBUG_DCE_SMB, "Data len(%hu) < "
-                    "NetBIOS SS header + NetBIOS len(%zu). "
-                    "Queuing data.\n", data_len, sizeof(NbssHdr) + nb_len);
-
                 if (DCE2_SmbHandleSegmentation(seg_buf, data_ptr, data_len,
                     sizeof(NbssHdr) + nb_len) != DCE2_RET__SUCCESS)
                 {
@@ -1797,17 +1726,10 @@ static void DCE2_Smb1Process(DCE2_SmbSsnData* ssd)
                 nb_ptr = DCE2_BufferData(*seg_buf);
                 nb_len = DCE2_BufferLength(*seg_buf);
 
-                DebugFormat(DEBUG_DCE_SMB,
-                    "Segmentation buffer: len: %u, size: %u\n",
-                    DCE2_BufferLength(*seg_buf), DCE2_BufferSize(*seg_buf));
-
                 if (DCE2_SsnFromClient(ssd->sd.wire_pkt))
                     dce2_smb_stats.smb_cli_seg_reassembled++;
                 else
                     dce2_smb_stats.smb_srv_seg_reassembled++;
-
-                DebugMessage(DEBUG_DCE_SMB, "TCP reassembled SMB PDU\n");
-                DCE2_PrintPktData(rpkt->data, rpkt->dsize);
             }
 
             switch (ssd->pdu_state)
@@ -1831,7 +1753,6 @@ static void DCE2_Smb1Process(DCE2_SmbSsnData* ssd)
                 ssd->pdu_state = DCE2_SMB_PDU_STATE__COMMAND;
                 break;
             default:
-                DebugFormat(DEBUG_DCE_SMB, "Invalid SMB PDU state: %d\n", ssd->pdu_state);
                 return;
             }
 
@@ -1845,7 +1766,6 @@ static void DCE2_Smb1Process(DCE2_SmbSsnData* ssd)
         }
 
         default:
-            DebugFormat(DEBUG_DCE_SMB, "Invalid SMB Data state: %d\n", *data_state);
             return;
         }
     }
@@ -2615,8 +2535,6 @@ DCE2_SmbSsnData* dce2_handle_smb_session(Packet* p, dce2SmbProtoConf* config)
         dce2_smb_sess = dce2_create_new_smb_session(p, config);
     }
 
-    DebugFormat(DEBUG_DCE_SMB, "Session pointer: %p\n", (void*)dce2_smb_sess);
-
     return dce2_smb_sess;
 }
 
@@ -2635,7 +2553,6 @@ void DCE2_SmbProcess(DCE2_SmbSsnData* ssd)
     {
         if ((ssd->sd.flags & DCE2_SSN_FLAG__SMB2))
         {
-            DebugMessage(DEBUG_DCE_SMB, "SMB1 packet detected!\n");
             ssd->sd.flags &= ~DCE2_SSN_FLAG__SMB2;
             DCE2_SmbCleanFileTracker(&(ssd->ftracker));
             ssd->ftracker.is_smb2 = false;
@@ -2645,7 +2562,6 @@ void DCE2_SmbProcess(DCE2_SmbSsnData* ssd)
     {
         if (!(ssd->sd.flags & DCE2_SSN_FLAG__SMB2))
         {
-            DebugMessage(DEBUG_DCE_SMB, "SMB2 packet detected!\n");
             DCE2_SmbCleanFileTracker(&(ssd->ftracker));
             DCE2_Smb2InitFileTracker(&(ssd->ftracker), false, 0);
             ssd->sd.flags |= DCE2_SSN_FLAG__SMB2;
index 72afc0c7f09627a114116d5cae18872ae837be67..482a218fe6494dff9ca28cbc55abf2822c027b02 100644 (file)
@@ -26,7 +26,6 @@
 
 #include "detection/detection_engine.h"
 #include "log/messages.h"
-#include "main/snort_debug.h"
 #include "profiler/profiler.h"
 #include "protocols/packet.h"
 #include "protocols/ssl.h"
@@ -178,11 +177,7 @@ static IMAPData* SetNewIMAPData(IMAP_PROTO_CONF* config, Packet* p)
     imap_ssn->mime_ssn->set_mime_stats(&(imapstats.mime_stats));
 
     if (p->packet_flags & SSNFLAG_MIDSTREAM)
-    {
-        DebugMessage(DEBUG_IMAP, "Got midstream packet - "
-            "setting state to unknown\n");
         imap_ssn->state = STATE_UNKNOWN;
-    }
 
     imap_ssn->body_read = imap_ssn->body_len = 0;
 
@@ -306,16 +301,12 @@ static int IMAP_Setup(Packet* p, IMAPData* ssn)
 
         if (ssn->session_flags & IMAP_FLAG_NEXT_STATE_UNKNOWN)
         {
-            DebugMessage(DEBUG_IMAP, "Found gap in previous reassembly buffer - "
-                "set state to unknown\n");
             ssn->state = STATE_UNKNOWN;
             ssn->session_flags &= ~IMAP_FLAG_NEXT_STATE_UNKNOWN;
         }
 
         if (missing_in_rebuilt == SSN_MISSING_BEFORE)
         {
-            DebugMessage(DEBUG_IMAP, "Found missing packets before "
-                "in reassembly buffer - set state to unknown\n");
             ssn->state = STATE_UNKNOWN;
         }
     }
@@ -370,16 +361,11 @@ static const uint8_t* IMAP_HandleCommand(Packet* p, IMAPData* imap_ssn, const ui
     {
         if (imap_ssn->state == STATE_UNKNOWN)
         {
-            DebugMessage(DEBUG_IMAP, "Command not found, but state is "
-                "unknown - checking for SSL\n");
-
             /* check for encrypted */
 
             if ((imap_ssn->session_flags & IMAP_FLAG_CHECK_SSL) &&
                 (IsSSL(ptr, end - ptr, p->packet_flags)))
             {
-                DebugMessage(DEBUG_IMAP, "Packet is SSL encrypted\n");
-
                 imap_ssn->state = STATE_TLS_DATA;
 
                 /* Ignore data */
@@ -387,7 +373,6 @@ static const uint8_t* IMAP_HandleCommand(Packet* p, IMAPData* imap_ssn, const ui
             }
             else
             {
-                DebugMessage(DEBUG_IMAP, "Not SSL - try data state\n");
                 /* don't check for ssl again in this packet */
                 if (imap_ssn->session_flags & IMAP_FLAG_CHECK_SSL)
                     imap_ssn->session_flags &= ~IMAP_FLAG_CHECK_SSL;
@@ -401,7 +386,6 @@ static const uint8_t* IMAP_HandleCommand(Packet* p, IMAPData* imap_ssn, const ui
         else
         {
             DetectionEngine::queue_event(GID_IMAP, IMAP_UNKNOWN_CMD);
-            DebugMessage(DEBUG_IMAP, "No known command found\n");
             return eol;
         }
     }
@@ -461,7 +445,6 @@ static void IMAP_ProcessServerPacket(Packet* p, IMAPData* imap_ssn)
     {
         if (imap_ssn->state == STATE_DATA)
         {
-            DebugMessage(DEBUG_IMAP, "DATA STATE ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\n");
             if ( imap_ssn->body_len > imap_ssn->body_read)
             {
                 int len = imap_ssn->body_len - imap_ssn->body_read;
@@ -552,16 +535,10 @@ static void IMAP_ProcessServerPacket(Packet* p, IMAPData* imap_ssn)
         }
         else
         {
-            DebugMessage(DEBUG_IMAP,
-                "Server response not found - see if it's SSL data\n");
-
             if ((imap_ssn->session_flags & IMAP_FLAG_CHECK_SSL) &&
                 (IsSSL(ptr, end - ptr, p->packet_flags)))
             {
-                DebugMessage(DEBUG_IMAP, "Server response is an SSL packet\n");
-
                 imap_ssn->state = STATE_TLS_DATA;
-
                 return;
             }
             else if (imap_ssn->session_flags & IMAP_FLAG_CHECK_SSL)
@@ -571,7 +548,6 @@ static void IMAP_ProcessServerPacket(Packet* p, IMAPData* imap_ssn)
             if ( (*ptr != '*') && (*ptr !='+') && (*ptr != '\r') && (*ptr != '\n') )
             {
                 DetectionEngine::queue_event(GID_IMAP, IMAP_UNKNOWN_RESP);
-                DebugMessage(DEBUG_IMAP, "Server response not found\n");
             }
         }
 
@@ -617,9 +593,6 @@ static void snort_imap(IMAP_PROTO_CONF* config, Packet* p)
         {
             if (IsTlsClientHello(p->data, p->data + p->dsize))
             {
-                DebugMessage(DEBUG_IMAP,
-                    "TLS DATA STATE ~~~~~~~~~~~~~~~~~~~~~~~~~\n");
-
                 imap_ssn->state = STATE_TLS_SERVER_PEND;
                 return;
             }
@@ -635,7 +608,6 @@ static void snort_imap(IMAP_PROTO_CONF* config, Packet* p)
             return;
         }
         IMAP_ProcessClientPacket(p, imap_ssn);
-        DebugMessage(DEBUG_IMAP, "IMAP client packet\n");
     }
     else
     {
@@ -662,7 +634,6 @@ static void snort_imap(IMAP_PROTO_CONF* config, Packet* p)
         if ( !InspectPacket(p))
         {
             /* Packet will be rebuilt, so wait for it */
-            DebugMessage(DEBUG_IMAP, "Client packet will be reassembled\n");
             return;
         }
         else if (!(p->packet_flags & PKT_REBUILT_STREAM))
@@ -682,9 +653,6 @@ static void snort_imap(IMAP_PROTO_CONF* config, Packet* p)
              * that were not rebuilt, state is going to be messed up
              * so set state to unknown. It's likely this was the
              * beginning of the conversation so reset state */
-            DebugMessage(DEBUG_IMAP, "Got non-rebuilt packets before "
-                "this rebuilt packet\n");
-
             imap_ssn->state = STATE_UNKNOWN;
             imap_ssn->session_flags &= ~IMAP_FLAG_GOT_NON_REBUILT;
         }
index bf33ccc9b695e5fef684bef9eb24fbb4990a9dbf..e7292dab9e7874bcd8cca5a5c99f3db413879477 100644 (file)
@@ -22,7 +22,6 @@
 
 #include "imap_paf.h"
 
-#include "main/snort_debug.h"
 #include "protocols/packet.h"
 #include "stream/stream.h"
 
@@ -204,18 +203,13 @@ static bool find_data_end_mime_data(const uint8_t ch, ImapPafData* pfdata)
     if (literal_complete(pfdata)
         && check_imap_data_end(&(pfdata->data_end_state), ch))
     {
-        DebugMessage(DEBUG_IMAP, "IMAP PAF: End of Data!\n");
         reset_data_states(pfdata);
         return true;
     }
 
     // check for mime flush point
     if (process_mime_paf_data(&(pfdata->mime_info), ch))
-    {
-        DebugMessage(DEBUG_IMAP, "IMAP PAF: Mime Boundary found."
-            " Flushing data!\n");
         return true;
-    }
 
     return false;
 }
@@ -432,8 +426,6 @@ static StreamSplitter::Status imap_paf_server(ImapPafData* pfdata,
 
     if (flush_len)
     {
-        DebugMessage(DEBUG_IMAP, "IMAP PAF: flushing data!\n");
-
         // flush at the final termination sequence
         *fp = flush_len;
         return StreamSplitter::FLUSH;
@@ -467,8 +459,6 @@ static StreamSplitter::Status imap_paf_client(const uint8_t* data, uint32_t len,
 
     if (pch != nullptr)
     {
-        DebugMessage(DEBUG_IMAP, "IMAP PAF: Flushing client"
-            " data!\n");
         *fp = (uint32_t)(pch - (const char*)data) + 1;
         return StreamSplitter::FLUSH;
     }
@@ -513,15 +503,9 @@ StreamSplitter::Status ImapSplitter::scan(
     ImapPafData* pfdata = &state;
 
     if (flags & PKT_FROM_SERVER)
-    {
-        DebugMessage(DEBUG_IMAP, "PAF: From server.\n");
         return imap_paf_server(pfdata, data, len, fp);
-    }
     else
-    {
-        DebugMessage(DEBUG_IMAP, "PAF: From client.\n");
         return imap_paf_client(data, len, fp);
-    }
 }
 
 bool imap_is_data_end(Flow* ssn)
index 59353b9d6e2af54eb8ba4cc026d87fc73e4d4089..861a821396f592c320ece4233727d2c87a5a12d9 100644 (file)
@@ -26,7 +26,6 @@
 
 #include "detection/detection_engine.h"
 #include "log/messages.h"
-#include "main/snort_debug.h"
 #include "profiler/profiler.h"
 #include "protocols/packet.h"
 #include "protocols/ssl.h"
@@ -138,8 +137,6 @@ static POPData* SetNewPOPData(POP_PROTO_CONF* config, Packet* p)
 
     if (p->packet_flags & SSNFLAG_MIDSTREAM)
     {
-        DebugMessage(DEBUG_POP, "Got midstream packet - "
-            "setting state to unknown\n");
         pop_ssn->state = STATE_UNKNOWN;
     }
 
@@ -263,16 +260,12 @@ static int POP_Setup(Packet* p, POPData* ssn)
 
         if (ssn->session_flags & POP_FLAG_NEXT_STATE_UNKNOWN)
         {
-            DebugMessage(DEBUG_POP, "Found gap in previous reassembly buffer - "
-                "set state to unknown\n");
             ssn->state = STATE_UNKNOWN;
             ssn->session_flags &= ~POP_FLAG_NEXT_STATE_UNKNOWN;
         }
 
         if (missing_in_rebuilt == SSN_MISSING_BEFORE)
         {
-            DebugMessage(DEBUG_POP, "Found missing packets before "
-                "in reassembly buffer - set state to unknown\n");
             ssn->state = STATE_UNKNOWN;
         }
     }
@@ -352,16 +345,10 @@ static const uint8_t* POP_HandleCommand(Packet* p, POPData* pop_ssn, const uint8
     {
         if (pop_ssn->state == STATE_UNKNOWN)
         {
-            DebugMessage(DEBUG_POP, "Command not found, but state is "
-                "unknown - checking for SSL\n");
-
             /* check for encrypted */
-
             if ((pop_ssn->session_flags & POP_FLAG_CHECK_SSL) &&
                 (IsSSL(ptr, end - ptr, p->packet_flags)))
             {
-                DebugMessage(DEBUG_POP, "Packet is SSL encrypted\n");
-
                 pop_ssn->state = STATE_TLS_DATA;
 
                 /* Ignore data */
@@ -369,7 +356,6 @@ static const uint8_t* POP_HandleCommand(Packet* p, POPData* pop_ssn, const uint8
             }
             else
             {
-                DebugMessage(DEBUG_POP, "Not SSL - try data state\n");
                 /* don't check for ssl again in this packet */
                 if (pop_ssn->session_flags & POP_FLAG_CHECK_SSL)
                     pop_ssn->session_flags &= ~POP_FLAG_CHECK_SSL;
@@ -383,7 +369,6 @@ static const uint8_t* POP_HandleCommand(Packet* p, POPData* pop_ssn, const uint8
         else
         {
             DetectionEngine::queue_event(GID_POP, POP_UNKNOWN_CMD);
-            DebugMessage(DEBUG_POP, "No known command found\n");
             return eol;
         }
     }
@@ -444,7 +429,6 @@ static void POP_ProcessServerPacket(Packet* p, POPData* pop_ssn)
     {
         if (pop_ssn->state == STATE_DATA)
         {
-            DebugMessage(DEBUG_POP, "DATA STATE ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\n");
             //ptr = POP_HandleData(p, ptr, end);
             FilePosition position = get_file_position(p);
             int len = end - ptr;
@@ -482,16 +466,10 @@ static void POP_ProcessServerPacket(Packet* p, POPData* pop_ssn)
         }
         else
         {
-            DebugMessage(DEBUG_POP,
-                "Server response not found - see if it's SSL data\n");
-
             if ((pop_ssn->session_flags & POP_FLAG_CHECK_SSL) &&
                 (IsSSL(ptr, end - ptr, p->packet_flags)))
             {
-                DebugMessage(DEBUG_POP, "Server response is an SSL packet\n");
-
                 pop_ssn->state = STATE_TLS_DATA;
-
                 return;
             }
             else if (pop_ssn->session_flags & POP_FLAG_CHECK_SSL)
@@ -509,7 +487,6 @@ static void POP_ProcessServerPacket(Packet* p, POPData* pop_ssn)
             else if (*ptr == '+')
             {
                 DetectionEngine::queue_event(GID_POP, POP_UNKNOWN_RESP);
-                DebugMessage(DEBUG_POP, "Server response not found\n");
             }
         }
 
@@ -555,9 +532,6 @@ static void snort_pop(POP_PROTO_CONF* config, Packet* p)
         {
             if (IsTlsClientHello(p->data, p->data + p->dsize))
             {
-                DebugMessage(DEBUG_POP,
-                    "TLS DATA STATE ~~~~~~~~~~~~~~~~~~~~~~~~~\n");
-
                 pop_ssn->state = STATE_TLS_SERVER_PEND;
                 return;
             }
@@ -573,7 +547,6 @@ static void snort_pop(POP_PROTO_CONF* config, Packet* p)
             return;
         }
         POP_ProcessClientPacket(p, pop_ssn);
-        DebugMessage(DEBUG_POP, "POP client packet\n");
     }
     else
     {
@@ -600,7 +573,6 @@ static void snort_pop(POP_PROTO_CONF* config, Packet* p)
         if ( !InspectPacket(p))
         {
             /* Packet will be rebuilt, so wait for it */
-            DebugMessage(DEBUG_POP, "Client packet will be reassembled\n");
             return;
         }
         else if (!(p->packet_flags & PKT_REBUILT_STREAM))
@@ -620,9 +592,6 @@ static void snort_pop(POP_PROTO_CONF* config, Packet* p)
              * that were not rebuilt, state is going to be messed up
              * so set state to unknown. It's likely this was the
              * beginning of the conversation so reset state */
-            DebugMessage(DEBUG_POP, "Got non-rebuilt packets before "
-                "this rebuilt packet\n");
-
             pop_ssn->state = STATE_UNKNOWN;
             pop_ssn->session_flags &= ~POP_FLAG_GOT_NON_REBUILT;
         }
index 0816c980def41047cfe6dbb5315b216a85abed22..746e1ef88ad39e89d99b4e0a96634033d915465c 100644 (file)
@@ -22,7 +22,6 @@
 
 #include "pop_paf.h"
 
-#include "main/snort_debug.h"
 #include "protocols/packet.h"
 #include "stream/stream.h"
 
@@ -205,7 +204,6 @@ static bool find_data_end_multi_line(PopPafData* pfdata, const uint8_t ch, bool
 
     if (check_data_end(&(pfdata->end_state), ch))
     {
-        DebugMessage(DEBUG_POP, "End of Multi-line response found\n");
         pfdata->end_of_data = true;
         pfdata->pop_state = POP_PAF_SINGLE_LINE_STATE;
         reset_data_states(pfdata);
@@ -217,7 +215,6 @@ static bool find_data_end_multi_line(PopPafData* pfdata, const uint8_t ch, bool
     {
         if (process_mime_paf_data(&(pfdata->data_info), ch))
         {
-            DebugMessage(DEBUG_POP, "Mime Boundary found.  Flushing data!\n");
             pfdata->cmd_continued = true;
             return true;
         }
@@ -246,8 +243,6 @@ static inline bool find_data_end_single_line(PopPafData* pfdata, const uint8_t c
         else
             reset_data_states(pfdata);
 
-        DebugMessage(DEBUG_POP, "End of single-line response "
-            "found.  Flushing data!\n");
         return true;
     }
 
@@ -401,15 +396,9 @@ StreamSplitter::Status PopSplitter::scan(
     PopPafData* pfdata = &state;
 
     if (flags & PKT_FROM_SERVER)
-    {
-        DebugMessage(DEBUG_POP, "PAF: From server.\n");
         return pop_paf_server(pfdata, data, len, fp);
-    }
     else
-    {
-        DebugMessage(DEBUG_POP, "PAF: From client.\n");
         return pop_paf_client(ssn, pfdata, data, len, fp);
-    }
 }
 
 bool pop_is_data_end(Flow* ssn)
index 03632c25538d9c81f5621b800d9979ac80bb9e4d..1d52596cbd8c6390103b67127e56a14b0ac7477e 100644 (file)
@@ -186,29 +186,14 @@ static RpcStatus RpcStatefulInspection(RpcDecodeConfig* rconfig,
     int need;
     RpcStatus status;
 
-    DebugMessage(DEBUG_RPC,
-        "STATEFUL: Start *******************************\n");
-    DebugFormat(DEBUG_RPC,
-        "STATEFUL: Ssn: %p\n", (void*) rsdata);
-
     if (rsdata->ignore)
     {
         if (dsize < rsdata->ignore)
         {
-            DebugFormat(DEBUG_RPC,
-                "STATEFUL: Ignoring %hu bytes\n", dsize);
-
             rsdata->ignore -= dsize;
-
-            DebugFormat(DEBUG_RPC,
-                "STATEFUL: Bytes left to ignore: %u \n", rsdata->ignore);
-
             return RPC_STATUS__SUCCESS;
         }
 
-        DebugFormat(DEBUG_RPC,
-            "STATEFUL: Ignoring %u bytes\n", rsdata->ignore);
-
         dsize -= (uint16_t)rsdata->ignore;
         data += rsdata->ignore;
         rsdata->ignore = 0;
@@ -225,10 +210,6 @@ static RpcStatus RpcStatefulInspection(RpcDecodeConfig* rconfig,
         {
             if (dsize < RPC_FRAG_HDR_SIZE)
             {
-                DebugFormat(DEBUG_RPC,
-                    "STATEFUL: Not enough data for frag header: %hu\n",
-                    dsize);
-
                 RpcPreprocEvent(rconfig, rsdata, RPC_INCOMPLETE_SEGMENT);
 
                 if (RpcBufAdd(&rsdata->seg, data, dsize) != RPC_STATUS__SUCCESS)
@@ -238,16 +219,8 @@ static RpcStatus RpcStatefulInspection(RpcDecodeConfig* rconfig,
             }
 
             rsdata->frag_len = RPC_FRAG_LEN(data);
-
-            DebugFormat(DEBUG_RPC,
-                "STATEFUL: Fragment length: %u\n", rsdata->frag_len);
-
             if (dsize < (RPC_FRAG_HDR_SIZE + rsdata->frag_len))
             {
-                DebugFormat(DEBUG_RPC,
-                    "STATEFUL: Not enough data for fragment: %hu\n",
-                    dsize);
-
                 RpcPreprocEvent(rconfig, rsdata, RPC_INCOMPLETE_SEGMENT);
 
                 if (RpcBufAdd(&rsdata->seg, data, dsize) != RPC_STATUS__SUCCESS)
@@ -265,9 +238,6 @@ static RpcStatus RpcStatefulInspection(RpcDecodeConfig* rconfig,
 
             if (status == RPC_STATUS__DEFRAG)
             {
-                DebugMessage(DEBUG_RPC,
-                    "STATEFUL: Last frag - calling detect\n");
-
                 if ((dsize != 0) || (data != p->data))
                 {
                     /* Only do this if there is more than one fragment in
@@ -291,10 +261,6 @@ static RpcStatus RpcStatefulInspection(RpcDecodeConfig* rconfig,
                 need = RPC_FRAG_HDR_SIZE - RpcBufLen(&rsdata->seg);
                 if (dsize < need)
                 {
-                    DebugFormat(DEBUG_RPC,
-                        "STATEFUL: Not enough data for frag header "
-                        "(%d): %hu\n", need, dsize);
-
                     RpcPreprocEvent(rconfig, rsdata, RPC_INCOMPLETE_SEGMENT);
 
                     if (RpcBufAdd(&rsdata->seg, data, dsize) != RPC_STATUS__SUCCESS)
@@ -310,18 +276,11 @@ static RpcStatus RpcStatefulInspection(RpcDecodeConfig* rconfig,
                 dsize -= need;
 
                 rsdata->frag_len = RPC_FRAG_LEN(RpcBufData(&rsdata->seg));
-
-                DebugFormat(DEBUG_RPC,
-                    "STATEFUL: Fragment length: %u\n", rsdata->frag_len);
             }
 
             need = rsdata->frag_len - (RpcBufLen(&rsdata->seg) - RPC_FRAG_HDR_SIZE);
             if (dsize < need)
             {
-                DebugFormat(DEBUG_RPC,
-                    "STATEFUL: Not enough data for fragment (%d): %hu\n",
-                    need, dsize);
-
                 RpcPreprocEvent(rconfig, rsdata, RPC_INCOMPLETE_SEGMENT);
 
                 if (RpcBufAdd(&rsdata->seg, data, dsize) != RPC_STATUS__SUCCESS)
@@ -354,9 +313,6 @@ static RpcStatus RpcStatefulInspection(RpcDecodeConfig* rconfig,
                         return RPC_STATUS__ERROR;
                 }
 
-                DebugMessage(DEBUG_RPC,
-                    "STATEFUL: Last frag - calling detect\n");
-
                 if ( (dsize > 0) )
                     RpcPreprocEvent(rconfig, rsdata, RPC_MULTIPLE_RECORD);
 
@@ -370,26 +326,15 @@ static RpcStatus RpcStatefulInspection(RpcDecodeConfig* rconfig,
 
     if (RpcBufLen(&rsdata->frag) != 0)
     {
-        DebugFormat(DEBUG_RPC,
-            "STATEFUL: Prepping Frag data: %u\n",
-            RpcBufLen(&rsdata->frag));
-
         if (RpcPrepFrag(rsdata, p) != RPC_STATUS__SUCCESS)
             return RPC_STATUS__ERROR;
     }
     else if (RpcBufLen(&rsdata->seg) != 0)
     {
-        DebugFormat(DEBUG_RPC,
-            "STATEFUL: Prepping Seg data: %u\n",
-            RpcBufLen(&rsdata->seg));
-
         if (RpcPrepSeg(rsdata, p) != RPC_STATUS__SUCCESS)
             return RPC_STATUS__ERROR;
     }
 
-    DebugMessage(DEBUG_RPC,
-        "STATEFUL: Success *****************************\n");
-
     return RPC_STATUS__SUCCESS;
 }
 
@@ -450,8 +395,6 @@ static RpcStatus RpcPrepSeg(RpcSsnData* rsdata, Packet* p)
     if (RpcBufLen(&rsdata->seg) > RPC_MAX_BUF_SIZE)
     {
         rsdata->ignore = (sizeof(uint32_t) + rsdata->frag_len) - RpcBufLen(&rsdata->seg);
-        DebugFormat(DEBUG_RPC,
-            "STATEFUL: Ignoring %u bytes\n", rsdata->ignore);
         RpcBufClean(&rsdata->seg);
     }
 
@@ -475,9 +418,6 @@ static RpcStatus RpcHandleFrag(RpcDecodeConfig* rconfig,
     if ((RpcBufLen(&rsdata->frag) == 0) && last_frag)
         return RPC_STATUS__DEFRAG;
 
-    DebugFormat(DEBUG_RPC,
-        "STATEFUL: Adding %u bytes to frag buffer\n", frag_len);
-
     if (RpcBufAdd(&rsdata->frag,
         fragment + sizeof(uint32_t), frag_len) != RPC_STATUS__SUCCESS)
     {
@@ -570,9 +510,6 @@ static inline void RpcSsnSetInactive(RpcSsnData* rsdata, Packet*)
     if (rsdata == nullptr)
         return;
 
-    DebugFormat(DEBUG_RPC, "STATEFUL: Deactivating session: %p\n",
-        (void*) rsdata);
-
     RpcSsnClean(rsdata);
 }
 
@@ -618,7 +555,6 @@ static RpcSsnData* RpcSsnDataNew(Packet* p)
 
     p->flow->set_flow_data(fd);
 
-    DebugFormat(DEBUG_RPC, "STATEFUL: Created new session: " "%p\n", (void*) rsdata);
     return rsdata;
 }
 
@@ -682,13 +618,10 @@ static int ConvertRPC(RpcDecodeConfig* rconfig, RpcSsnData* rsdata, Packet* p)
 
     if (psize < MIN_CALL_BODY_SZ)
     {
-        DebugFormat(DEBUG_RPC, "Not enough data to decode: %u\n",
-            psize);
         return 0;
     }
 
     /* on match, normalize the data */
-    DebugFormat(DEBUG_RPC, "Got RPC traffic (%u bytes)!\n", psize);
 
     /* cheesy alignment safe fraghdr = *(uint32_t *) data*/
     *((uint8_t*)&fraghdr)      = data[0];
@@ -704,18 +637,12 @@ static int ConvertRPC(RpcDecodeConfig* rconfig, RpcSsnData* rsdata, Packet* p)
     if (fraghdr & MSB)
     {
         /* on match, normalize the data */
-        DebugFormat(DEBUG_RPC, "Found Last Fragment: %u!\n", length);
-
         if ((length + 4 != psize) && !(p->packet_flags & PKT_REBUILT_STREAM))
         {
-            DebugFormat(DEBUG_RPC, "It's not the only thing in this buffer!"
-                " length: %u psize: %u!\n", length, psize);
             return RPC_MULTIPLE_RECORD;
         }
         else if ( length == 0 )
         {
-            DebugFormat(DEBUG_RPC, "Zero-length RPC fragment detected."
-                " length: %u psize: %u.\n", length, psize);
             return RPC_ZERO_LENGTH_FRAGMENT;
         }
         return 0;
@@ -768,7 +695,6 @@ static int ConvertRPC(RpcDecodeConfig* rconfig, RpcSsnData* rsdata, Packet* p)
 
         if (fraghdr & MSB)
         {
-            DebugMessage(DEBUG_RPC, "Last Fragment detected\n");
             last_fragment = 1;
         }
 
@@ -777,10 +703,6 @@ static int ConvertRPC(RpcDecodeConfig* rconfig, RpcSsnData* rsdata, Packet* p)
             /* don't allow integer overflow to confuse us.  Should be
              * caught by length > psize but who knows when weird
              * psize's might be allowed */
-
-            DebugFormat(DEBUG_RPC, "Integer Overflow"
-                " field(%u) exceeds packet size(%u)\n",
-                length, psize);
             return RPC_LARGE_FRAGSIZE;
         }
 
@@ -788,9 +710,6 @@ static int ConvertRPC(RpcDecodeConfig* rconfig, RpcSsnData* rsdata, Packet* p)
 
         if (length > psize)
         {
-            DebugFormat(DEBUG_RPC, "Length of"
-                " field(%u) exceeds packet size(%u)\n",
-                length, psize);
             return RPC_INCOMPLETE_SEGMENT;
         }
         else if (decoded_len > psize)
@@ -798,25 +717,15 @@ static int ConvertRPC(RpcDecodeConfig* rconfig, RpcSsnData* rsdata, Packet* p)
             /* The entire request is larger than our current packet
              *  size
              */
-            DebugFormat(DEBUG_RPC, " Decoded Length (%u)"
-                "exceeds packet size(%u)\n",
-                decoded_len, psize);
             return RPC_LARGE_FRAGSIZE;
         }
         else if ((data_index + length) > data_end)
         {
-            DebugMessage(DEBUG_RPC,
-                "returning LARGE_FRAGSIZE since we'd read past our end\n");
             return RPC_LARGE_FRAGSIZE;
         }
         else
         {
             fragcount++;
-
-            DebugFormat(DEBUG_RPC,
-                "length: %u size: %u decoded_len: %u\n",
-                length, psize, decoded_len);
-
             if (decode_buf_rem >= length)
             {
                 memcpy_s(norm_index, decode_buf_rem, data_index, length);
@@ -846,14 +755,9 @@ static int ConvertRPC(RpcDecodeConfig* rconfig, RpcSsnData* rsdata, Packet* p)
      */
     if (decoded_len + ((fragcount - 1) * 4) != psize)
     {
-        DebugFormat(DEBUG_RPC, "decoded len does not compute: %u\n",
-            decoded_len);
         return RPC_MULTIPLE_RECORD;
     }
 
-    DebugFormat(DEBUG_RPC, "New size: %u\n", decoded_len);
-        DebugMessage(DEBUG_RPC, "converted data:\n");
-
     buf.len = (uint16_t)decoded_len;
     return 0;
 }
@@ -966,9 +870,6 @@ void RpcDecode::eval(Packet* p)
         if (ret == RPC_STATUS__ERROR)
             RpcSsnSetInactive(rsdata, p);
     }
-
-    DebugMessage(DEBUG_RPC,"Stateless inspection\n");
-
     RpcPreprocEvent(&config, rsdata, ConvertRPC(&config, rsdata, p));
 }
 
index 530356b7f146373f0516ecfa289c73cce9267d68..0e0567a733f73db84857441c38d1865b023351b5 100644 (file)
@@ -182,8 +182,6 @@ static inline bool SSLPP_is_encrypted(SSL_PROTO_CONF* config, uint32_t ssl_flags
 static inline uint32_t SSLPP_process_alert(
     SSL_PROTO_CONF*, uint32_t ssn_flags, uint32_t new_flags, Packet* packet)
 {
-    DebugMessage(DEBUG_SSL, "Process Alert\n");
-
     ssn_flags |= new_flags;
 
     /* Check if we've seen a handshake, that this isn't it,
@@ -193,7 +191,6 @@ static inline uint32_t SSLPP_process_alert(
         !(new_flags & SSL_CHANGE_CIPHER_FLAG) &&
         !(new_flags & SSL_HEARTBEAT_SEEN))
     {
-        DebugMessage(DEBUG_SSL, "Disabling detect\n");
         DetectionEngine::disable_content(packet);
     }
 
@@ -210,8 +207,6 @@ static inline uint32_t SSLPP_process_alert(
 
 static inline uint32_t SSLPP_process_hs(uint32_t ssl_flags, uint32_t new_flags)
 {
-    DebugMessage(DEBUG_SSL, "Process Handshake\n");
-
     if (!SSL_BAD_HS(new_flags))
     {
         ssl_flags |= new_flags & (SSL_CLIENT_HELLO_FLAG |
@@ -230,8 +225,6 @@ static inline uint32_t SSLPP_process_hs(uint32_t ssl_flags, uint32_t new_flags)
 static inline uint32_t SSLPP_process_app(SSL_PROTO_CONF* config, uint32_t ssn_flags, uint32_t
     new_flags, Packet* packet)
 {
-    DebugMessage(DEBUG_SSL, "Process Application\n");
-
     if (SSLPP_is_encrypted(config, ssn_flags | new_flags, packet) )
     {
         ssn_flags |= SSL_ENCRYPTED_FLAG;
@@ -239,7 +232,6 @@ static inline uint32_t SSLPP_process_app(SSL_PROTO_CONF* config, uint32_t ssn_fl
         // Heartbleed check is disabled. Stop inspection on this session.
         if (!config->max_heartbeat_len)
         {
-            DebugMessage(DEBUG_SSL, "STOPPING INSPECTION (process_app)\n");
             Stream::stop_inspection(packet->flow, packet, SSN_DIR_BOTH, -1, 0);
             sslstats.stopped++;
         }
@@ -267,7 +259,6 @@ static inline void SSLPP_process_other(SSL_PROTO_CONF* config, SSLData* sd, uint
 
         if (!config->max_heartbeat_len)
         {
-            DebugMessage(DEBUG_SSL, "STOPPING INSPECTION (process_other)\n");
             Stream::stop_inspection(packet->flow, packet, SSN_DIR_BOTH, -1, 0);
         }
         else if (!(new_flags & SSL_HEARTBEAT_SEEN))
index 65d2cd1871dceebc73a1ffa77fc467152e128099..679ccef506ab7ef7692a63060bfd73b4166bf7cf 100644 (file)
 
 #include "stream_module.h"
 
+#include "main/snort_debug.h"
+
 using namespace snort;
 using namespace std;
 
 //-------------------------------------------------------------------------
 // stream module
 //-------------------------------------------------------------------------
+Trace TRACE_NAME(stream);
 
 #define CACHE_PARAMS(name, max, prune, idle, cleanup) \
 static const Parameter name[] = \
@@ -76,7 +79,7 @@ static const Parameter s_params[] =
 };
 
 StreamModule::StreamModule() :
-    Module(MOD_NAME, MOD_HELP, s_params)
+    Module(MOD_NAME, MOD_HELP, s_params, false, &TRACE_NAME(stream))
 { }
 
 const PegInfo* StreamModule::get_pegs() const
@@ -101,7 +104,7 @@ bool StreamModule::begin(const char* fqn, int, SnortConfig*)
     return true;
 }
 
-bool StreamModule::set(const char* fqn, Value& v, SnortConfig*)
+bool StreamModule::set(const char* fqn, Value& v, SnortConfig* c)
 {
     FlowConfig* fc = nullptr;
 
@@ -134,7 +137,7 @@ bool StreamModule::set(const char* fqn, Value& v, SnortConfig*)
         fc = &config.file_cfg;
 
     else
-        return false;
+        return Module::set(fqn, v, c);
 
     if ( v.is("max_sessions") )
         fc->max_sessions = v.get_long();
index 70e7e2ac6092f56dc7d34c546daa5c7c5fe03f86..1439a363d697fa40ffb0240a54f004aac67b4bac 100644 (file)
@@ -34,6 +34,7 @@ struct SnortConfig;
 //-------------------------------------------------------------------------
 // stream module
 //-------------------------------------------------------------------------
+extern Trace TRACE_NAME(stream);
 
 #define MOD_NAME "stream"
 #define MOD_HELP "common flow tracking"
index 6e47f2a0470f6ea794b5a8a3760e43905e83d1cc..4194791140843d962a182d2fe8e90135671d9296 100644 (file)
@@ -35,6 +35,7 @@
 #include "main/snort_debug.h"
 #include "packet_io/active.h"
 #include "protocols/vlan.h"
+#include "stream/base/stream_module.h"
 #include "target_based/sftarget_hostentry.h"
 #include "target_based/snort_protocols.h"
 #include "utils/util.h"
@@ -213,6 +214,10 @@ void Stream::stop_inspection(
 {
     assert(flow && flow->session);
 
+    trace_logf(stream, "stop inspection on flow, dir %s \n",
+              dir == SSN_DIR_BOTH ? "BOTH": 
+              ((dir == SSN_DIR_FROM_CLIENT) ? "FROM_CLIENT" : "FROM_SERVER"));
+
     switch (dir)
     {
     case SSN_DIR_BOTH: