1234567891011121314151617181920212223242526272829303132333435363738394041424344454647484950515253545556575859606162636465666768697071727374757677787980818283848586878889909192939495969798991001011021031041051061071081091101111121131141151161171181191201211221231241251261271281291301311321331341351361371381391401411421431441451461471481491501511521531541551561571581591601611621631641651661671681691701711721731741751761771781791801811821831841851861871881891901911921931941951961971981992002012022032042052062072082092102112122132142152162172182192202212222232242252262272282292302312322332342352362372382392402412422432442452462472482492502512522532542552562572582592602612622632642652662672682692702712722732742752762772782792802812822832842852862872882892902912922932942952962972982993003013023033043053063073083093103113123133143153163173183193203213223233243253263273283293303313323333343353363373383393403413423433443453463473483493503513523533543553563573583593603613623633643653663673683693703713723733743753763773783793803813823833843853863873883893903913923933943953963973983994004014024034044054064074084094104114124134144154164174184194204214224234244254264274284294304314324334344354364374384394404414424434444454464474484494504514524534544554564574584594604614624634644654664674684694704714724734744754764774784794804814824834844854864874884894904914924934944954964974984995005015025035045055065075085095105115125135145155165175185195205215225235245255265275285295305315325335345355365375385395405415425435445455465475485495505515525535545555565575585595605615625635645655665675685695705715725735745755765775785795805815825835845855865875885895905915925935945955965975985996006016026036046056066076086096106116126136146156166176186196206216226236246256266276286296306316326336346356366376386396406416426436446456466476486496506516526536546556566576586596606616626636646656666676686696706716726736746756766776786796806816826836846856866876886896906916926936946956966976986997007017027037047057067077087097107117127137147157167177187197207217227237247257267277287297307317327337347357367377387397407417427437447457467477487497507517527537547557567577587597607617627637647657667677687697707717727737747757767777787797807817827837847857867877887897907917927937947957967977987998008018028038048058068078088098108118128138148158168178188198208218228238248258268278288298308318328338348358368378388398408418428438448458468478488498508518528538548558568578588598608618628638648658668678688698708718728738748758768778788798808818828838848858868878888898908918928938948958968978988999009019029039049059069079089099109119129139149159169179189199209219229239249259269279289299309319329339349359369379389399409419429439449459469479489499509519529539549559569579589599609619629639649659669679689699709719729739749759769779789799809819829839849859869879889899909919929939949959969979989991000100110021003100410051006100710081009101010111012101310141015101610171018101910201021102210231024102510261027102810291030103110321033103410351036103710381039104010411042104310441045104610471048104910501051105210531054105510561057105810591060106110621063106410651066106710681069107010711072107310741075107610771078107910801081108210831084108510861087108810891090109110921093109410951096109710981099110011011102110311041105110611071108110911101111111211131114111511161117111811191120112111221123112411251126112711281129113011311132113311341135113611371138113911401141114211431144114511461147114811491150115111521153115411551156115711581159116011611162116311641165116611671168116911701171117211731174117511761177117811791180118111821183118411851186118711881189119011911192119311941195119611971198119912001201120212031204120512061207120812091210121112121213121412151216121712181219122012211222122312241225122612271228122912301231123212331234123512361237123812391240124112421243124412451246124712481249125012511252125312541255125612571258125912601261126212631264126512661267126812691270127112721273127412751276127712781279128012811282128312841285128612871288128912901291129212931294129512961297129812991300130113021303130413051306130713081309131013111312131313141315131613171318131913201321132213231324132513261327132813291330133113321333133413351336133713381339134013411342134313441345134613471348134913501351135213531354135513561357135813591360136113621363136413651366136713681369137013711372137313741375137613771378137913801381138213831384138513861387138813891390139113921393139413951396139713981399140014011402140314041405140614071408140914101411141214131414141514161417141814191420142114221423142414251426142714281429143014311432143314341435143614371438143914401441144214431444144514461447144814491450145114521453145414551456145714581459146014611462146314641465146614671468146914701471147214731474147514761477147814791480148114821483148414851486148714881489149014911492149314941495149614971498149915001501150215031504150515061507150815091510151115121513151415151516151715181519152015211522152315241525152615271528152915301531153215331534153515361537153815391540154115421543154415451546154715481549155015511552155315541555155615571558155915601561156215631564156515661567156815691570157115721573157415751576157715781579158015811582158315841585158615871588158915901591159215931594159515961597159815991600160116021603160416051606160716081609161016111612161316141615161616171618161916201621162216231624162516261627162816291630163116321633163416351636163716381639164016411642164316441645164616471648164916501651165216531654165516561657165816591660166116621663166416651666166716681669167016711672167316741675167616771678167916801681168216831684168516861687168816891690169116921693169416951696169716981699170017011702170317041705170617071708170917101711171217131714171517161717171817191720172117221723172417251726172717281729173017311732173317341735173617371738173917401741174217431744174517461747174817491750175117521753175417551756175717581759176017611762176317641765176617671768176917701771177217731774177517761777177817791780178117821783178417851786178717881789179017911792179317941795179617971798179918001801180218031804180518061807180818091810181118121813181418151816181718181819182018211822182318241825182618271828182918301831183218331834183518361837183818391840184118421843184418451846184718481849185018511852185318541855185618571858185918601861186218631864186518661867186818691870187118721873187418751876187718781879188018811882188318841885188618871888188918901891189218931894189518961897189818991900190119021903190419051906190719081909191019111912191319141915191619171918191919201921192219231924192519261927192819291930193119321933193419351936193719381939194019411942194319441945194619471948194919501951 |
- // snfLOGmgr.cpp
- //
- // (C) Copyright 2006 - 2009 ARM Research Labs, LLC.
- // See www.armresearch.com for the copyright terms.
- //
- // Log Manager implementations see snfLOGmgr.hpp for details.
-
- #include "snfLOGmgr.hpp"
- #include "../CodeDweller/threading.hpp"
- #include "../CodeDweller/timing.hpp"
- #include <unistd.h>
-
- using namespace std;
-
- //// DiscLogger ////////////////////////////////////////////////////////////////
-
- const ThreadType DiscLogger::Type("DiscLogger"); // The thread's type.
-
- const ThreadState DiscLogger::DiscLogger_Flush("Flushing"); // Flushing state.
- const ThreadState DiscLogger::DiscLogger_Wait("Waiting"); // Waiting state.
-
- DiscLogger::DiscLogger(string N) : // When it is time to start...
- Thread(DiscLogger::Type, N), // DiscLogger Type and Name.
- UseANotB(true), // Set all of the flags to their
- isDirty(false), // appropriate initial state
- isBad(false), // then start the thread.
- isTimeToStop(false),
- inAppendMode(true),
- isEnabled(true) {
- run();
- }
-
- DiscLogger::~DiscLogger() { // When it is time to go away...
- isTimeToStop = true; // Set the stop flag.
- flush(); // Flush if we should.
- join(); // Wait for the thread to stop.
- }
-
- void DiscLogger::post(const string Input, const string NewPath) { // Post Input to log.
- if(!isEnabled) return; // If we're not enabled, eat it.
- ScopeMutex PostingNewDataNobodyMove(BufferControlMutex); // Keep things static while posting.
- if(0 < NewPath.length()) { myPath = NewPath; } // Reset the path if provided.
- string& Buffer = PostingBuffer(); // Grab the posting buffer.
- if(!inAppendMode) Buffer.clear(); // If overwriting, clear the old.
- Buffer.append(Input); // Add the new data.
- isDirty = true; // We're dirty now.
- }
-
- // The DiscLogger flush() method is designed to lose data if it is unable to
- // write the data to the file. The alternative would allow an unlimited amount
- // of data to build up in the buffers and that would be bad.
-
- void DiscLogger::flush() { // Flush right now!
- string FilePath; // Local copy of the path.
- ScopeMutex FlushingNow(FlushMutex); // We're flushing.
- if(isDirty) { // Nothing to do if not dirty.
- BufferControlMutex.lock(); // Lock the buffer controls.
- FlushingBuffer().clear(); // Clear the old flushing buffer.
- UseANotB = (!UseANotB); // Flip the buffers.
- isDirty = false; // We are not dirty anymore.
- string& Buffer = FlushingBuffer(); // Grab the new flushing buffer.
- FilePath = myPath; // Grab the current file path.
- BufferControlMutex.unlock(); // Unlock the buffer controls.
- try { // Insulate against exceptions.
- ofstream File( // Open the file.
- FilePath.c_str(), // Use the path we have.
- (inAppendMode) ? (ios::app) : (ios::trunc) // Append or Truncate appropriately.
- );
- File << Buffer; // Write the buffer.
- if(File.bad()) throw false; // If someting went wrong, throw.
- File.close(); // Close the file.
- }
- catch(...) { // If we had an exception then
- isBad = true; // make sure the bad bit is on and
- return; // we are done.
- }
- isBad = false; // If nothing went wrong we're good!
- }
- }
-
- void DiscLogger::myTask() { // Main thread task
- Sleeper WaitForIt(500); // How to wait a half second.
- while(!isTimeToStop) { // Until it is time to stop:
- CurrentThreadState(DiscLogger_Wait); // post our waiting and
- WaitForIt(); // we wait a second, then
- CurrentThreadState(DiscLogger_Flush); // post our activity and
- flush(); // flush our data to disc
- } // then do it again.
- }
-
- //// snfScanData ///////////////////////////////////////////////////////////////
-
- snfScanData::snfScanData(int ScanHorizon) : // How to create.
- ReadyToClear(true), // Make sure initial clear will happen.
- FilteredData(ScanHorizon, 0) { // Allocate the Filtered Data buffer.
- clear(); // Start with no data.
- }
-
- snfScanData::~snfScanData() { // Cleans up lists etc.
- ReadyToClear = true; // Make sure the clear will happen.
- clear(); // Say we have no data.
- }
-
- void snfScanData::clear() { // Clear the data for the next message.
-
- if(!ReadyToClear) return; // Don't double clear.
- ReadyToClear = false; // No clearing now until logged.
-
- // Clear the IP scan data
-
- memset(MyIPScanData, 0, sizeof(MyIPScanData)); // Clear the IP scan data and
- MyIPCount = 0; // the count of scanned IPs.
- memset(DrillDownFlags, 0, sizeof(DrillDownFlags)); // Clear all DrillDown flags.
-
- SourceIPOrdinal = -1; // There is no source IP because it
- SourceIPFoundFlag = false; // has not yet been found.
- SourceIPRangeFlag = Unknown; // Range flag is not yet known.
- SourceIPEvaluation.clear(); // No eval yet.
-
- myHeaderDirectiveSourceIP = 0UL; // Header directive source is empty now.
- myCallerForcedSourceIP = 0UL; // Caller forced source is empty now.
-
- // Clear basic message stats & id info.
-
- StartOfJobUTC = 0; // Clear the start of job clock.
- SetupTime = 0; // Time in ms spent setting up to scan.
- ScanName.clear(); // Identifying name or message file name.
-
- /** The Timer ScanTime is explicitely NOT reset during clear() operations.
- *** Instead, the ScanTime Timer is controlled as a ScopeTimer() during the
- *** scan() operation exclusively.
- **/
-
- ScanSize = 0; // Scan size is zero.
- ScanDepth = 0; // Scan depth is zero.
-
- // Log entries and X- headers
-
- ClassicLogText.clear(); // Classic log entry text if any.
- XMLLogText.clear(); // XML log entry text if any.
-
- XHDRsText.clear(); // XHeaders text if any.
- XHeaderInjectOn = false; // True if injecting headers is on.
- XHeaderFileOn = false; // True if creating .xhdr file is on.
-
- // Clear the GBUdb Event Tracking Flags
-
- GBUdbNormalTriggered = false; // True if GBUdb indeterminate IP source.
-
- GBUdbWhiteTriggered = false; // True if GBUdb found source IP white.
- GBUdbWhiteSymbolForced = false; // True if white was on and symbol was set.
- GBUdbPatternSourceConflict = false; // True if a pattern was found with a white IP.
- GBUdbAutoPanicTriggered = false; // True if autopanic was triggered.
- GBUdbAutoPanicExecuted = false; // True if an autopanic was added.
-
- GBUdbBlackTriggered = false; // True if GBUdb found source IP black.
- GBUdbBlackSymbolForced = false; // True if black was on and symbol was set.
- GBUdbTruncateTriggered = false; // True if Truncate was possible.
- GBUdbPeekTriggered = false; // True if we could peek.
- GBUdbSampleTriggered = false; // True if we could sample.
-
- GBUdbTruncateExecuted = false; // True if we actually did truncate.
- GBUdbPeekExecuted = false; // True if we peeked instead of truncating.
- GBUdbSampleExecuted = false; // True if we sampled.
-
-
- GBUdbCautionTriggered = false; // True if GBUdb found source IP suspicous.
- GBUdbCautionSymbolForced = false; // True if caution was on and symbol was set.
-
- // Clear the rule panic tracking list
-
- RulePanics.clear(); // Remove all entries.
-
- // Pattern Engine Scan Result Data
-
- HeaderDirectiveFlags = 0UL; // Flags set by header directives.
-
- PatternWasFound = false; // True if a pattern was found.
- PatternID = 0; // Rule ID of the pattern.
- PatternSymbol = 0; // Symbol of the pattern.
-
- MatchRecords.clear(); // Clear the match records list.
- MatchRecordsCursor = MatchRecords.end(); // Init the cursor to nowhere.
-
- CompositeFinalResult = -1;
-
- }
-
- //// snfCounterPack ////////////////////////////////////////////////////////////
-
- snfCounterPack::snfCounterPack() { // When constructing a counter pack
- reset(); // reset it.
- }
-
- void snfCounterPack::reset() { // When asked to reset we do this:
- memset(&Events, 0, sizeof(Events)); // Fill them with zeros.
- }
-
- //// IntervalTimer /////////////////////////////////////////////////////////////
-
- Timer& IntervalTimer::Active() { // Return the active timer.
- return ((ANotB)?A:B); // If A is active, return A
- } // otherwise return B.
-
- Timer& IntervalTimer::Inactive() { // Return the inactive timer.
- return ((ANotB)?B:A); // If A is active, return B
- } // otherwise return A.
-
- msclock IntervalTimer::hack() { // Chop off a new interval & return it.
- Inactive().start(Active().stop()); // Stop the active clock and reference
- ANotB = ~ANotB; // it to start the new Active clock.
- return Interval(); // Flip the bit and return the Interval.
- }
-
- msclock IntervalTimer::Interval() { // Return the last interval.
- return Inactive().getElapsedTime(); // Return the Inactive elapsed time.
- }
-
- msclock IntervalTimer::Elapsed() { // Return the time since last hack.
- return Active().getElapsedTime(); // Return the Active elapsed time.
- }
-
- //// snfLOGmgr /////////////////////////////////////////////////////////////////
-
- const ThreadType snfLOGmgr::Type("snfLOGmgr"); // The thread's type.
-
- snfLOGmgr::snfLOGmgr() : // Constructor for the LOG manager
- Thread(snfLOGmgr::Type, "Log Manager"), // snfLOGmgr Type and Name.
- Configured(false), // Not yet configured.
- TimeToDie(false), // Not yet time to die.
- PeekEnableCounter(0), // No peeking yet.
- SampleEnableCounter(0), // No sampling yet.
- myNETmgr(NULL), // No NET mgr yet.
- myGBUdb(NULL), // No GBUdb yet.
- NewerRulebaseIsAvailable(false), // No newer rulebase yet.
- SecondStatusLogger("Second Status Logger"), // Lazy writer for Second status.
- MinuteStatusLogger("Minute Status Logger"), // Lazy writer for Minute status.
- HourStatusLogger("Hour Status Logger"), // Lazy writer for Hour status.
- XMLScanLogger("XML Scan Logger"), // Lazy writer for XML Scan log.
- ClassicScanLogger("Classic Scan Logger") { // Lazy writer for Classic Scan log.
- StartupTime = Timestamp(); // Record when did we start.
- CurrentCounters = &CounterPackA; // Assign the active CounterPack.
- ReportingCounters = &CounterPackB; // Assign the reporting CounterPack.
- Timestamp(SecondReportTimestamp); // Set the basetime for the Second,
- Timestamp(MinuteReportTimestamp); // Minute, and Hour status reports.
- Timestamp(HourReportTimestamp);
- run(); // Run the thread.
- }
-
- snfLOGmgr::~snfLOGmgr() { // When we go away we must
- stop(); // stop if we haven't already.
- Status.store(PersistentFileName); // and store our persistent data.
- }
-
- void snfLOGmgr::linkNETmgr(snfNETmgr& N) { myNETmgr = &N; } // Link in my NETmgr
-
- void snfLOGmgr::linkGBUdb(GBUdb& G) { myGBUdb = &G; } // Link in my GBUdb
-
- void snfLOGmgr::stop() { // When we want to stop
- if(!TimeToDie) { // check that we didn't already, then
- TimeToDie = true; // we set time to die and
- join(); // join the thread;
- }
- }
-
- //// Here are some functions for creating status reports.
-
- // Handy subroutine to encapsulate status log posting logic.
-
- void snfLOGmgr::postStatusLog( // Post a Status log if required.
- const string& LogData, // Here's the log entry's data.
- const string& LogFileName, // Here is where it should go.
- const bool LogEnabled, // This is true if we should write it.
- const bool AppendNotOverwrite, // True=Append, False=Overwrite.
- DiscLogger& Logger // The logger to use.
- ) {
- if(!LogEnabled) return; // If we're not supposed to, don't!
- string TFN = LogFileName; // Tagged File Name
-
- if(AppendNotOverwrite) { // If we're appending, rotate per day.
- TFN.append("."); // Put a timestamp on the file name.
- if(Rotate_LocalTime) { LocalTimestamp(TFN); } // Use either the local timestamp or
- else { Timestamp(TFN); } // the utc timestamp as configured.
- TFN = TFN.substr(0, TFN.length() - 6); // Stip off the Hours, Minutes, Seconds.
- }
-
- TFN.append(".log.xml"); // Tack on the extension.
-
- // Now feed this to the lazy logger.
-
- Logger.Enabled(LogEnabled); // Set the enable flag.
- Logger.AppendMode(AppendNotOverwrite); // Set the append mode.
- Logger.post(LogData, TFN); // Post data and update the name.
-
- }
-
- // Handy function to normalize data to a base.
- // Also, a collection of handy bases to normalize to ;-)
-
- const int MillisecondsInASecond = 1000; // Second
- const int MillisecondsInAMinute = MillisecondsInASecond * 60; // Minute
- const int MillisecondsInAnHour = MillisecondsInAMinute * 60; // Hour
- const int MillisecondsInADay = MillisecondsInAnHour * 24; // Day
-
- double snf_rationalize(double Count, double Base, double NewBase) { // Cross multiply to convert bases.
- if(0.0 == Base) return 0.0; // Handle division by zero like this.
- return (Count * NewBase) / Base; // Do the math.
- }
-
- double snf_AveragePerSecond(snf_SMHDMY_Counter& D, snf_SMHDMY_Counter& T) { // Avg Data/sec given Data & Time Counters.
- return snf_rationalize(
- D.SEC6SUM, T.SEC6SUM, MillisecondsInASecond);
- }
-
- double snf_AveragePerMinute(snf_SMHDMY_Counter& D, snf_SMHDMY_Counter& T) { // Avg Data/min given Data & Time Counters.
- return snf_rationalize(
- D.SumThru1Minute(), T.SumThru1Minute(), MillisecondsInAMinute);
- }
-
- double snf_AveragePerHour(snf_SMHDMY_Counter& D, snf_SMHDMY_Counter& T) { // Avg Data/min given Data & Time Counters.
- return snf_rationalize(
- D.SumThru1Hour(), T.SumThru1Hour(), MillisecondsInAnHour);
- }
-
- double snf_AveragePerDay(snf_SMHDMY_Counter& D, snf_SMHDMY_Counter& T) { // Avg Data/min given Data & Time Counters.
- return snf_rationalize(
- D.SumThru1Day(), T.SumThru1Day(), MillisecondsInADay);
- }
-
- void AppendRatesElement( // Format & output a rates element.
- const char* Name, // The name of the element (usually 1 char).
- snf_SMHDMY_Counter& D, // Data counter
- snf_SMHDMY_Counter& T, // Time counter
- ostringstream& S) { // Where to append the formatted output.
- S << "\t\t<" << Name << " "
- << "s=\'" << snf_AveragePerSecond(D, T) << "\' "
- << "m=\'" << snf_AveragePerMinute(D, T) << "\' "
- << "h=\'" << snf_AveragePerHour(D, T) << "\' "
- << "d=\'" << snf_AveragePerDay(D, T) << "\'/>"
- << endl;
- }
-
- void AppendHistogramElements(Histogram& H, ostringstream& S) { // Format & output a histogram.
- if(0 < H.size()) { // Don't output empty histograms.
- S << "\t\t<histogram hits=\'" << H.Hits() << "\'>" << endl; // Open tag w/ hits count.
- set<HistogramRecord>::iterator iH; // Use an iterator to
- for(iH = H.begin(); iH != H.end(); iH++) { // loop through all of the groups.
- S << "\t\t\t<g k=\'" // For each group in the histogram
- << (*iH).Key << "\' c=\'" // output the key value and
- << (*iH).Count << "\'/>" << endl; // the count of hits.
- }
- S << "\t\t</histogram>" << endl; // Close tag.
- }
- }
-
- // This gets called once per second. It is responsible for inputting the base
- // data into all of the histograms and counter mechnisms. After that, the minute
- // and hour reports are triggered when a full cycle of lower order data has
- // been collected.
-
- bool snfLOGmgr::do_SecondReport() { // Send our 1 second status report.
-
- // First thing - we always update the summaries for everybody :-)
-
- snfCounterPack& Counters = (*(getSnapshot())); // Get and swap the counters.
- TimeCounter.input(Counters.ActiveTime.getElapsedTime()); // Add the time interval.
- MessageCounter.input(Counters.Events.Scans); // Add the number of scans (messages).
- SpamCounter.input(Counters.Events.Spam); // Add the number of Spam events.
- HamCounter.input(Counters.Events.Ham); // Add the number of Ham events.
- WhiteCounter.input(Counters.Events.GBUdbWhiteSymbolForced); // Add the number of White events.
- CautionCounter.input(Counters.Events.GBUdbCautionSymbolForced); // Add the number of Caution events.
- BlackCounter.input(Counters.Events.GBUdbBlackSymbolForced); // Add the number of Black events.
- TruncateCounter.input(Counters.Events.GBUdbTruncateExecuted); // Add the number of Truncate events.
- SampleCounter.input(Counters.Events.GBUdbSampleExecuted); // Add the number of Sample events.
- AutoPanicCounter.input(Counters.Events.GBUdbAutoPanicTriggered); // Add the number of AutoPanic events.
- RulePanicCounter.input(Counters.Events.RulePanicFound); // Add the number of RulePanic events.
-
- // Next we produce our "Second" status report.
-
- ostringstream Report;
-
- //-- Report the stats element --
-
- Report << "<stats nodeid=\'" << NodeId << "\' "
- << "basetime=\'" << SecondReportTimestamp << "\' "
- << "elapsed=\'" << Counters.ActiveTime.getElapsedTime() << "\' "
- << "class=\'second\'>" << endl;
-
- SecondReportTimestamp = ""; Timestamp(SecondReportTimestamp); // Reset the timestamp for next time.
-
- //-- Version data --
-
- Report << "\t<version>" << endl
- << "\t\t<engine>" << SNF_ENGINE_VERSION << "</engine>" << endl;
-
- if(0 < myPlatformVersion.length()) {
- Report << "\t\t<platform>" << myPlatformVersion << "</platform>" << endl;
- }
-
- Report << "\t</version>" << endl;
-
- //-- Timers section --
-
- Report << "\t<timers>" << endl;
- Report << "\t\t<run started=\'" << Timestamp(StartupTime) << "\' "
- << "elapsed=\'" << (int) difftime(Timestamp(), StartupTime) << "\'/>" << endl;
-
- Report << "\t\t<sync latest=\'" << Timestamp(Status.LastSyncTime) << "\' "
- << "elapsed=\'" << SecsSinceLastSync() << "\'/>" << endl;
-
- Report << "\t\t<save latest=\'" << Timestamp(Status.LastSaveTime) << "\' "
- << "elapsed=\'" << SecsSinceLastSave() << "\'/>" << endl;
-
- Report << "\t\t<condense latest=\'" << Timestamp(Status.LastCondenseTime) << "\' "
- << "elapsed=\'" << SecsSinceLastCondense() << "\'/>" << endl;
-
- Report << "\t</timers>" << endl;
-
- //-- GBUdb section --
-
- Report << "\t<gbudb>" << endl;
-
- Report << "\t\t<size bytes=\'" << (*myGBUdb).Size() << "\'/>" << endl;
- Report << "\t\t<records count=\'" << (*myGBUdb).IPCount() << "\'/>" << endl;
- Report << "\t\t<utilization percent=\'" << (*myGBUdb).Utilization() << "\'/>" << endl;
-
- Report << "\t</gbudb>" << endl;
-
- //-- Counters --
-
- Report << "\t<counters>" << endl;
-
- if(0 < Counters.Events.Scans) {
- Report << "\t\t<m c=\'" << Counters.Events.Scans << "\'/>" << endl;
- }
-
- if(0 < Counters.Events.Spam) {
- Report << "\t\t<s c=\'" << Counters.Events.Spam << "\'/>" << endl;
- }
-
- if(0 < Counters.Events.Ham) {
- Report << "\t\t<h c=\'" << Counters.Events.Ham << "\'/>" << endl;
- }
-
- if(0 < Counters.Events.GBUdbTruncateExecuted) {
- Report << "\t\t<t c=\'" << Counters.Events.GBUdbTruncateExecuted << "\'/>" << endl;
- }
-
- if(0 < Counters.Events.GBUdbBlackSymbolForced) {
- Report << "\t\t<b c=\'" << Counters.Events.GBUdbBlackSymbolForced << "\'/>" << endl;
- }
-
- if(0 < Counters.Events.GBUdbCautionSymbolForced) {
- Report << "\t\t<c c=\'" << Counters.Events.GBUdbCautionSymbolForced << "\'/>" << endl;
- }
-
- if(0 < Counters.Events.GBUdbWhiteSymbolForced) {
- Report << "\t\t<w c=\'" << Counters.Events.GBUdbWhiteSymbolForced << "\'/>" << endl;
- }
-
- if(0 < Counters.Events.GBUdbAutoPanicExecuted) {
- Report << "\t\t<a c=\'" << Counters.Events.GBUdbAutoPanicExecuted << "\'/>" << endl;
- }
-
- if(0 < Counters.Events.RulePanicFound) {
- Report << "\t\t<r c=\'" << Counters.Events.RulePanicFound << "\'/>" << endl;
- }
-
- Report << "\t</counters>" << endl;
- Counters.reset(); // When done, clear the counters.
-
- //-- Rates ---
-
- Report << "\t<rates>" << endl;
- AppendRatesElement("m", MessageCounter, TimeCounter, Report);
- AppendRatesElement("s", SpamCounter, TimeCounter, Report);
- AppendRatesElement("h", HamCounter, TimeCounter, Report);
- AppendRatesElement("w", WhiteCounter, TimeCounter, Report);
- AppendRatesElement("c", CautionCounter, TimeCounter, Report);
- AppendRatesElement("b", BlackCounter, TimeCounter, Report);
- AppendRatesElement("t", TruncateCounter, TimeCounter, Report);
- AppendRatesElement("a", AutoPanicCounter, TimeCounter, Report);
- AppendRatesElement("r", RulePanicCounter, TimeCounter, Report);
- Report << "\t</rates>" << endl;
-
- //-- Results ---
-
- Report << "\t<results>" << endl;
- AppendHistogramElements(ResultsSecond, Report);
- ResultsSecond.reset();
- Report << "\t</results>" << endl;
-
- //-- Rules ---
-
- Report << "\t<rules>" << endl;
- string RBUTC;
- Report << "\t\t<rulebase utc=\'" << (*myNETmgr).RulebaseUTC(RBUTC) << "\'/>" << endl;
- Report << "\t\t<active utc=\'" << ActiveRulebaseUTC << "\'/>" << endl;
- Report << "\t\t<update ready=\'" << ((NewerRulebaseIsAvailable)?"yes":"no")
- << "\' utc=\'" << AvailableRulebaseUTC << "\'/>" << endl;
- Report << "\t\t<latest rule=\'" << LatestRuleID() << "\'/>" << endl;
- AppendHistogramElements(RulesSecond, Report);
- RulesSecond.reset();
- Report << "\t</rules>" << endl;
-
- //-- Panics ---
-
- Report << "\t<panics>" << endl;
- AppendHistogramElements(PanicsSecond, Report);
- PanicsSecond.reset();
- Report << "\t</panics>" << endl;
-
- //-- Close the stats element --
-
- Report << "</stats>" << endl;
-
- // Now that we've built the report we need to store it and send it to
- // the network manager.
-
- ConfigMutex.lock(); // Freeze while we get our settings.
- bool LogEnabled = SecondReport_Log_OnOff; // To log or not to log?
- bool AppendNotOverwrite = SecondReport_Append_OnOff; // To append or overwrite?
- string LogFileName = SecondReport_Log_Filename; // What file name?
- ConfigMutex.unlock(); // Ok, done with that.
-
- postStatusLog( // Post a Status log if required.
- Report.str(), // Here's the log entry's data.
- LogFileName, // Here is where it should go.
- LogEnabled, // This is true if we should write it.
- AppendNotOverwrite, // True=Append, False=Overwrite.
- SecondStatusLogger // Lazy log writer to use.
- );
-
- // Just before we go we save our stat for others to see.
-
- ScopeMutex HoldForStatusUpdate(StatusReportMutex); // Hold the mutex just long enough
- SecondReportText = Report.str(); // to post our status and return
-
- // Finally we return the test - Do we have a complete cycle in Seconds?
-
- return(TimeCounter.Cycled60Seconds()); // True on a full cycle of seconds.
- }
-
- bool snfLOGmgr::do_MinuteReport() { // Send our 1 minute status report.
-
- // Produce our "Minute" status report.
-
- ostringstream Report;
-
- //-- Report the stats element --
-
- Report << "<stats nodeid=\'" << NodeId << "\' "
- << "basetime=\'" << MinuteReportTimestamp << "\' "
- << "elapsed=\'" << TimeCounter.Sum60Seconds() << "\' "
- << "class=\'minute\'>" << endl;
-
- MinuteReportTimestamp = ""; Timestamp(MinuteReportTimestamp); // Reset the timestamp for next time.
-
- //-- Version data --
-
- Report << "\t<version>" << endl
- << "\t\t<engine>" << SNF_ENGINE_VERSION << "</engine>" << endl;
-
- if(0 < myPlatformVersion.length()) {
- Report << "\t\t<platform>" << myPlatformVersion << "</platform>" << endl;
- }
-
- Report << "\t</version>" << endl;
-
- //-- Timers section --
-
- Report << "\t<timers>" << endl;
- Report << "\t\t<run started=\'" << Timestamp(StartupTime) << "\' "
- << "elapsed=\'" << (int) difftime(Timestamp(), StartupTime) << "\'/>" << endl;
-
- Report << "\t\t<sync latest=\'" << Timestamp(Status.LastSyncTime) << "\' "
- << "elapsed=\'" << SecsSinceLastSync() << "\'/>" << endl;
-
- Report << "\t\t<save latest=\'" << Timestamp(Status.LastSaveTime) << "\' "
- << "elapsed=\'" << SecsSinceLastSave() << "\'/>" << endl;
-
- Report << "\t\t<condense latest=\'" << Timestamp(Status.LastCondenseTime) << "\' "
- << "elapsed=\'" << SecsSinceLastCondense() << "\'/>" << endl;
-
- Report << "\t</timers>" << endl;
-
- //-- GBUdb section --
-
- Report << "\t<gbudb>" << endl;
-
- Report << "\t\t<size bytes=\'" << (*myGBUdb).Size() << "\'/>" << endl;
- Report << "\t\t<records count=\'" << (*myGBUdb).IPCount() << "\'/>" << endl;
- Report << "\t\t<utilization percent=\'" << (*myGBUdb).Utilization() << "\'/>" << endl;
-
- Report << "\t</gbudb>" << endl;
-
- //-- Counters --
-
- Report << "\t<counters>" << endl;
-
- if(0 < MessageCounter.Sum60Seconds()) {
- Report << "\t\t<m c=\'" << MessageCounter.Sum60Seconds() << "\'/>" << endl;
- }
-
- if(0 < SpamCounter.Sum60Seconds()) {
- Report << "\t\t<s c=\'" << SpamCounter.Sum60Seconds() << "\'/>" << endl;
- }
-
- if(0 < HamCounter.Sum60Seconds()) {
- Report << "\t\t<h c=\'" << HamCounter.Sum60Seconds() << "\'/>" << endl;
- }
-
- if(0 < TruncateCounter.Sum60Seconds()) {
- Report << "\t\t<t c=\'" << TruncateCounter.Sum60Seconds() << "\'/>" << endl;
- }
-
- if(0 < BlackCounter.Sum60Seconds()) {
- Report << "\t\t<b c=\'" << BlackCounter.Sum60Seconds() << "\'/>" << endl;
- }
-
- if(0 < CautionCounter.Sum60Seconds()) {
- Report << "\t\t<c c=\'" << CautionCounter.Sum60Seconds() << "\'/>" << endl;
- }
-
- if(0 < WhiteCounter.Sum60Seconds()) {
- Report << "\t\t<w c=\'" << WhiteCounter.Sum60Seconds() << "\'/>" << endl;
- }
-
- if(0 < AutoPanicCounter.Sum60Seconds()) {
- Report << "\t\t<a c=\'" << AutoPanicCounter.Sum60Seconds() << "\'/>" << endl;
- }
-
- if(0 < RulePanicCounter.Sum60Seconds()) {
- Report << "\t\t<a c=\'" << RulePanicCounter.Sum60Seconds() << "\'/>" << endl;
- }
-
- Report << "\t</counters>" << endl;
-
- //-- Rates ---
-
- Report << "\t<rates>" << endl;
- AppendRatesElement("m", MessageCounter, TimeCounter, Report);
- AppendRatesElement("s", SpamCounter, TimeCounter, Report);
- AppendRatesElement("h", HamCounter, TimeCounter, Report);
- AppendRatesElement("w", WhiteCounter, TimeCounter, Report);
- AppendRatesElement("c", CautionCounter, TimeCounter, Report);
- AppendRatesElement("b", BlackCounter, TimeCounter, Report);
- AppendRatesElement("t", TruncateCounter, TimeCounter, Report);
- AppendRatesElement("a", AutoPanicCounter, TimeCounter, Report);
- AppendRatesElement("r", RulePanicCounter, TimeCounter, Report);
- Report << "\t</rates>" << endl;
-
- //-- Results ---
-
- Report << "\t<results>" << endl;
- AppendHistogramElements(ResultsMinute, Report);
- ResultsMinute.reset();
- Report << "\t</results>" << endl;
-
- //-- Rules ---
-
- Report << "\t<rules>" << endl;
- string RBUTC;
- Report << "\t\t<rulebase utc=\'" << (*myNETmgr).RulebaseUTC(RBUTC) << "\'/>" << endl;
- Report << "\t\t<active utc=\'" << ActiveRulebaseUTC << "\'/>" << endl;
- Report << "\t\t<update ready=\'" << ((NewerRulebaseIsAvailable)?"yes":"no")
- << "\' utc=\'" << AvailableRulebaseUTC << "\'/>" << endl;
- Report << "\t\t<latest rule=\'" << LatestRuleID() << "\'/>" << endl;
- AppendHistogramElements(RulesMinute, Report);
- RulesMinute.reset();
- Report << "\t</rules>" << endl;
-
- //-- Panics ---
-
- Report << "\t<panics>" << endl;
- AppendHistogramElements(PanicsMinute, Report);
- PanicsMinute.reset();
- Report << "\t</panics>" << endl;
-
- //-- Close the stats element --
-
- Report << "</stats>" << endl;
-
- // Now that we've built the report we need to store it and send it to
- // the network manager.
-
- ConfigMutex.lock(); // Freeze while we get our settings.
- bool LogEnabled = MinuteReport_Log_OnOff; // To log or not to log?
- bool AppendNotOverwrite = MinuteReport_Append_OnOff; // To append or overwrite?
- string LogFileName = MinuteReport_Log_Filename; // What file name?
- ConfigMutex.unlock(); // Ok, done with that.
-
- postStatusLog( // Post a Status log if required.
- Report.str(), // Here's the log entry's data.
- LogFileName, // Here is where it should go.
- LogEnabled, // This is true if we should write it.
- AppendNotOverwrite, // True=Append, False=Overwrite.
- MinuteStatusLogger // Lazy log writer to use.
- );
-
- (*myNETmgr).sendReport(Report.str()); // Send the status report to the net.
-
- // Just before we go we save our stat for others to see.
-
- ScopeMutex HoldForStatusUpdate(StatusReportMutex); // Hold the mutex just long enough
- MinuteReportText = Report.str(); // to post our status and return
-
- return(TimeCounter.Cycled60Minutes()); // True at a full cycle of minutes.
- }
-
- bool snfLOGmgr::do_HourReport() { // Send our 1 hour status report.
-
- // Produce our "Hour" status report.
-
- ostringstream Report;
-
- //-- Report the stats element --
-
- Report << "<stats nodeid=\'" << NodeId << "\' "
- << "basetime=\'" << HourReportTimestamp << "\' "
- << "elapsed=\'" << TimeCounter.Sum60Minutes() << "\' "
- << "class=\'hour\'>" << endl;
-
- HourReportTimestamp = ""; Timestamp(HourReportTimestamp); // Reset the timestamp for next time.
-
- //-- Version data --
-
- Report << "\t<version>" << endl
- << "\t\t<engine>" << SNF_ENGINE_VERSION << "</engine>" << endl;
-
- if(0 < myPlatformVersion.length()) {
- Report << "\t\t<platform>" << myPlatformVersion << "</platform>" << endl;
- }
-
- Report << "\t</version>" << endl;
-
- //-- Timers section --
-
- Report << "\t<timers>" << endl;
- Report << "\t\t<run started=\'" << Timestamp(StartupTime) << "\' "
- << "elapsed=\'" << (int) difftime(Timestamp(), StartupTime) << "\'/>" << endl;
-
- Report << "\t\t<sync latest=\'" << Timestamp(Status.LastSyncTime) << "\' "
- << "elapsed=\'" << SecsSinceLastSync() << "\'/>" << endl;
-
- Report << "\t\t<save latest=\'" << Timestamp(Status.LastSaveTime) << "\' "
- << "elapsed=\'" << SecsSinceLastSave() << "\'/>" << endl;
-
- Report << "\t\t<condense latest=\'" << Timestamp(Status.LastCondenseTime) << "\' "
- << "elapsed=\'" << SecsSinceLastCondense() << "\'/>" << endl;
-
- Report << "\t</timers>" << endl;
-
- //-- GBUdb section --
-
- Report << "\t<gbudb>" << endl;
-
- Report << "\t\t<size bytes=\'" << (*myGBUdb).Size() << "\'/>" << endl;
- Report << "\t\t<records count=\'" << (*myGBUdb).IPCount() << "\'/>" << endl;
- Report << "\t\t<utilization percent=\'" << (*myGBUdb).Utilization() << "\'/>" << endl;
-
- Report << "\t</gbudb>" << endl;
-
- //-- Counters --
-
- Report << "\t<counters>" << endl;
-
- if(0 < MessageCounter.Sum60Minutes()) {
- Report << "\t\t<m c=\'" << MessageCounter.Sum60Minutes() << "\'/>" << endl;
- }
-
- if(0 < SpamCounter.Sum60Minutes()) {
- Report << "\t\t<s c=\'" << SpamCounter.Sum60Minutes() << "\'/>" << endl;
- }
-
- if(0 < HamCounter.Sum60Minutes()) {
- Report << "\t\t<h c=\'" << HamCounter.Sum60Minutes() << "\'/>" << endl;
- }
-
- if(0 < TruncateCounter.Sum60Minutes()) {
- Report << "\t\t<t c=\'" << TruncateCounter.Sum60Minutes() << "\'/>" << endl;
- }
-
- if(0 < BlackCounter.Sum60Minutes()) {
- Report << "\t\t<b c=\'" << BlackCounter.Sum60Minutes() << "\'/>" << endl;
- }
-
- if(0 < CautionCounter.Sum60Minutes()) {
- Report << "\t\t<c c=\'" << CautionCounter.Sum60Minutes() << "\'/>" << endl;
- }
-
- if(0 < WhiteCounter.Sum60Minutes()) {
- Report << "\t\t<w c=\'" << WhiteCounter.Sum60Minutes() << "\'/>" << endl;
- }
-
- if(0 < AutoPanicCounter.Sum60Minutes()) {
- Report << "\t\t<a c=\'" << AutoPanicCounter.Sum60Minutes() << "\'/>" << endl;
- }
-
- if(0 < RulePanicCounter.Sum60Minutes()) {
- Report << "\t\t<a c=\'" << RulePanicCounter.Sum60Minutes() << "\'/>" << endl;
- }
-
- Report << "\t</counters>" << endl;
-
- //-- Rates ---
-
- Report << "\t<rates>" << endl;
- AppendRatesElement("m", MessageCounter, TimeCounter, Report);
- AppendRatesElement("s", SpamCounter, TimeCounter, Report);
- AppendRatesElement("h", HamCounter, TimeCounter, Report);
- AppendRatesElement("w", WhiteCounter, TimeCounter, Report);
- AppendRatesElement("c", CautionCounter, TimeCounter, Report);
- AppendRatesElement("b", BlackCounter, TimeCounter, Report);
- AppendRatesElement("t", TruncateCounter, TimeCounter, Report);
- AppendRatesElement("a", AutoPanicCounter, TimeCounter, Report);
- AppendRatesElement("r", RulePanicCounter, TimeCounter, Report);
- Report << "\t</rates>" << endl;
-
- //-- Results ---
-
- Report << "\t<results>" << endl;
- AppendHistogramElements(ResultsHour, Report);
- ResultsHour.reset();
- Report << "\t</results>" << endl;
-
- //-- Rules ---
-
- Report << "\t<rules>" << endl;
- string RBUTC;
- Report << "\t\t<rulebase utc=\'" << (*myNETmgr).RulebaseUTC(RBUTC) << "\'/>" << endl;
- Report << "\t\t<active utc=\'" << ActiveRulebaseUTC << "\'/>" << endl;
- Report << "\t\t<update ready=\'" << ((NewerRulebaseIsAvailable)?"yes":"no")
- << "\' utc=\'" << AvailableRulebaseUTC << "\'/>" << endl;
- Report << "\t\t<latest rule=\'" << LatestRuleID() << "\'/>" << endl;
- AppendHistogramElements(RulesHour, Report);
- RulesHour.reset();
- Report << "\t</rules>" << endl;
-
- //-- Panics ---
-
- Report << "\t<panics>" << endl;
- AppendHistogramElements(PanicsHour, Report);
- PanicsHour.reset();
- Report << "\t</panics>" << endl;
-
- //-- Close the stats element --
-
- Report << "</stats>" << endl;
-
- // Now that we've built the report we need to store it and send it to
- // the network manager.
-
- ConfigMutex.lock(); // Freeze while we get our settings.
- bool LogEnabled = HourReport_Log_OnOff; // To log or not to log?
- bool AppendNotOverwrite = HourReport_Append_OnOff; // To append or overwrite?
- string LogFileName = HourReport_Log_Filename; // What file name?
- ConfigMutex.unlock(); // Ok, done with that.
-
- postStatusLog( // Post a Status log if required.
- Report.str(), // Here's the log entry's data.
- LogFileName, // Here is where it should go.
- LogEnabled, // This is true if we should write it.
- AppendNotOverwrite, // True=Append, False=Overwrite.
- HourStatusLogger // Lazy log writer to use.
- );
-
- // Just before we go we save our stat for others to see.
-
- ScopeMutex HoldForStatusUpdate(StatusReportMutex); // Hold the mutex just long enough
- HourReportText = Report.str(); // to post our status and return
-
- return(TimeCounter.Cycled24Hours()); // True at a full cycle of hours.
- }
-
- void snfLOGmgr::do_StatusReports() { // Do the status reports.
- ScopeMutex PauseWhileITotalThis(MyMutex); // Everybody stop for a bit. Each report
- if(do_SecondReport()) // returns true if it has cycled so
- if(do_MinuteReport()) // that the next report can be checked
- do_HourReport(); // to see if it has cycled.
- }
-
- void snfLOGmgr::myTask() { // Thread: Live stats & reports.
- Sleeper WaitATic(MillisecondsInASecond); // One second sleeper.
- while(!TimeToDie) { // Do this until it's time to die.
- if(Configured) { // If we are configured do our work.
- do_StatusReports(); // Status reports are chained.
- }
- WaitATic();
- }
- }
-
- // Note about snfLOGmgr::configure() and Status.restore()
- // store() and restore() watch the Status.Ready flag. If
- // Status is ready, restore() won't. If Status is not ready
- // store() won't. Also, if restore() doesn't find a file, it
- // just clears everything and goes ready, assuming that this
- // is a new persistent state.
-
- void snfLOGmgr::configure(snfCFGData& CFGData) { // Update the configuration.
- ScopeMutex HoldOnWhileITweakThisThing(ConfigMutex);
- PersistentFileName = CFGData.paths_workspace_path + ".state"; // Build the persistent state path.
- Status.restore(PersistentFileName); // Load our persistent state.
- NodeId = CFGData.node_licenseid; // Grab the node id for reports.
-
- // Copy the logging configuration info from CFGData
-
- Rotate_LocalTime = CFGData.Logs_Rotation_LocalTime_OnOff; // Rotate using local time?
-
- XML_Log_Mode = CFGData.Scan_XML_Mode; // What is the XML log mode.
- Classic_Log_Mode = CFGData.Scan_Classic_Mode; // What is the Classic log mode.
-
- LogsPath = CFGData.paths_log_path; // Path to logs directory.
- ClassicLogRotate = CFGData.Scan_Classic_Rotate; // True = Rotate Classic Log.
- XMLLogRotate = CFGData.Scan_XML_Rotate; // True = Rotate XML Log.
-
- SecondReport_Log_OnOff = CFGData.Status_SecondReport_Log_OnOff;
- SecondReport_Append_OnOff = CFGData.Status_SecondReport_Append_OnOff;
- SecondReport_Log_Filename = CFGData.paths_log_path + NodeId + ".status.second";
- MinuteReport_Log_OnOff = CFGData.Status_MinuteReport_Log_OnOff;
- MinuteReport_Append_OnOff = CFGData.Status_MinuteReport_Append_OnOff;
- MinuteReport_Log_Filename = CFGData.paths_log_path + NodeId + ".status.minute";
- HourReport_Log_OnOff = CFGData.Status_HourReport_Log_OnOff;
- HourReport_Append_OnOff = CFGData.Status_HourReport_Append_OnOff;
- HourReport_Log_Filename = CFGData.paths_log_path + NodeId + ".status.hour";
-
- // Sanity check
-
- if(NULL != myNETmgr && NULL != myGBUdb) { // If we are fully linked then
- Configured = true; // we are now configured.
- }
- }
-
- void snfLOGmgr::doXHDRs(snfCFGData& CFGData, snfScanData& ScanData) { // XHDR sub routine for LogThisScan()
- ostringstream O; // Setup the output stream.
-
- const string SMTPENDL = "\r\n"; // Make a hard-coded SMTP endline.
-
- // Version header
-
- if(CFGData.XHDRVersion_OnOff) { // If the Version XHDR is on then
- O << CFGData.XHDRVersion_Header << ": " // emit the Version XHDR.
- << SMTPENDL << "\t" << SNF_ENGINE_VERSION;
-
- if(0 < myPlatformVersion.length()) { // Optional Platform Version.
- O << SMTPENDL << "\t" << myPlatformVersion;
- }
-
- O << SMTPENDL;
- }
-
- // License header
-
- if(CFGData.XHDRLicense_OnOff) { // If the license XHDR is on then
- O << CFGData.XHDRLicense_Header << ": " // emit the License XHRD.
- << CFGData.node_licenseid
- << SMTPENDL;
- }
-
- // Rulebase header
-
- if(CFGData.XHDRRulebase_OnOff) { // If the rulebase utc XHDR is on
- string RBUTC; // then make a string to capture it
- O << CFGData.XHDRRulebase_Header << ": " // then emit the rulebase utc XHDR.
- << (*myNETmgr).RulebaseUTC(RBUTC)
- << SMTPENDL;
- }
-
- // Identifier header
-
- if(CFGData.XHDRIdentifier_OnOff) { // If the identifier XHDR is on
- O << CFGData.XHDRIdentifier_Header << ": " // then emit the scan identifier.
- << ScanData.ScanName
- << SMTPENDL;
- }
-
- // GBUdb header
-
- if(CFGData.XHDRGBUdb_OnOff) { // If the GBUdb XHDR is on then
- O << CFGData.XHDRGBUdb_Header << ": "; // then emit the GBUdb XHDR.
- if(!ScanData.FoundSourceIP()) { // If no source was identified
- O << "Unknown" // then we emit "Unknown".
- << SMTPENDL;
- } else { // If the source was identified
- O << ScanData.SourceIPRecord().Ordinal << ", " // then we emit the ordial,
- << (string) IP4Address(ScanData.SourceIPRecord().IP) << ", " // the IP, and then
- << ScanData.SourceIPEvaluation // the IP evaluation that was
- << SMTPENDL; // sent to the scanner.
- }
- }
-
- // Result header
-
- if(CFGData.XHDRResult_OnOff) { // If the Result XHDR is on then
- O << CFGData.XHDRResult_Header << ": " // emit the scan result code XHDR.
- << ScanData.CompositeFinalResult
- << SMTPENDL;
- }
-
- // Matches header
-
- if(CFGData.XHDRMatches_OnOff) { // If the Matches XHDR is on then
- O << CFGData.XHDRMatches_Header << ":" << SMTPENDL; // emit the Matches XHDR with matches.
- for(
- list<snf_match>::iterator iM = ScanData.MatchRecords.begin(); // Loop through the match list
- iM != ScanData.MatchRecords.end(); iM++ // one by one.
- ) {
- snf_match& M = (*iM); // Emit each match record as a
- O << "\t" // folded entry in the header.
- << M.symbol << "-" // symbol-ruleid-index-endex-flag
- << M.ruleid << "-"
- << M.index << "-"
- << M.endex << "-"
- << M.flag
- << SMTPENDL;
- }
- }
-
- // Black header
-
- if( // If the Black XHDR is on and
- CFGData.XHDRBlack_OnOff && // We have a nonzero result
- 0 < ScanData.CompositeFinalResult && // that is not found in the training
- !CFGData.TrainingWhiteRuleHandler.isListed( // white list then...
- ScanData.CompositeFinalResult)
- ) {
- O << CFGData.XHDRBlack_Header // Emit the Black XHDR.
- << SMTPENDL;
- }
-
- // White header
-
- if( // If the White XHDR is on and
- CFGData.XHDRWhite_OnOff && // the result was listed in the
- CFGData.TrainingWhiteRuleHandler.isListed( // training white list then...
- ScanData.CompositeFinalResult)
- ) {
- O << CFGData.XHDRWhite_Header // Emit the White XHDR.
- << SMTPENDL;
- }
-
- // Clean header
-
- if(
- CFGData.XHDRClean_OnOff && // If the Clean XHDR is on and
- !ScanData.PatternWasFound && // no pattern was found and
- 0 == ScanData.CompositeFinalResult // the scan result is zero then...
- ) {
- O << CFGData.XHDRClean_Header // Emit the Clean XHDR
- << SMTPENDL;
- }
-
- // Symbol headers
-
- string SH = CFGData.XHDRSymbolHeaders.HeaderForSymbol( // Check for a Symbol XHDR
- ScanData.CompositeFinalResult // matching this result.
- );
-
- if(0 < SH.length()) { // If we have an XHDR for this
- O << SH // result then emit the header.
- << SMTPENDL;
- }
-
- ScanData.XHDRsText = O.str(); // Save the formatted XHeaders.
- }
-
- void snfLOGmgr::doXMLLogs(snfCFGData& CFGData, snfScanData& ScanData) { // XML sub routine for LogThisScan()
-
- ostringstream O; // Set up the output stream.
- bool WeHaveSubordinates = false; // Expect no subordinates at first.
-
- // We do the match sections together because it allows us to scan through
- // the match results once and pick up the final result for the <s/> section.
- // This way, even if no <m/>atch elements are emitted we will have the
- // final result without having to scan the data twice.
-
- // <s/> -- Scan Log Entry (always on if modes is not none)
- // <m/> -- Scan Match Entry (controlled by matches attribute)
- ostringstream R; // Match (R)ecords output.
-
- set<int> Duplicates; // Set up to track duplicates.
- list<snf_match>::iterator MatchRecordsCursor; // Set up a cursor to the results.
- for(
- MatchRecordsCursor = ScanData.MatchRecords.begin(); // Loop through the scan results
- MatchRecordsCursor != ScanData.MatchRecords.end();
- MatchRecordsCursor++
- ) {
- snf_match& M = (*MatchRecordsCursor); // Grab the current match record.
- if(
- ScanLogMatches_None != CFGData.Scan_XML_Matches && // If match records are requested
- ('f' != M.flag && 'c' != M.flag) // and this record is not the (f)inal
- ) { // then evaluate it and log (??) it.
- if(ScanLogMatches_Unique == CFGData.Scan_Classic_Matches) { // If only unique matches are required
- if(Duplicates.end() != Duplicates.find(M.ruleid)) { // check for a duplicate rule id.
- continue; // If the rule id was found then skip.
- } else { // If the rule id was not found then
- Duplicates.insert(M.ruleid); // remember it for next time.
- }
- }
-
- // At this point we've skipped duplicate match records, but we
- // still want to emit some (all?) of them so it's time to do it.
-
- // <m s='48' r='1234567' i='2394' e='2409' f='m'/>
-
- R << "\t<m "
- << "s=\'" << M.symbol << "\' "
- << "r=\'" << M.ruleid << "\' "
- << "i=\'" << M.index << "\' "
- << "e=\'" << M.endex << "\' "
- << "f=\'" << M.flag << "\'/>"
- << endl;
-
- } else
- if('f' == M.flag || 'c' == M.flag) { // If this record is final, emit it!
-
- WeHaveSubordinates = (
- ((ScanLogMatches_None != CFGData.Scan_XML_Matches)
- && ('f' == M.flag)) ||
- (true == CFGData.Scan_XML_GBUdb) ||
- (true == CFGData.Scan_XML_Performance)
- );
-
- // <s u='20070508012349' m='msg0123456789.msg' s='48' r='1234567' >
-
- O << "<s " // Format the <s>can element
- << "u=\'" << Timestamp(ScanData.StartOfJobUTC) << "\' "
- << "m=\'" << ScanData.ScanName << "\' "
- << "s=\'" << M.symbol << "\' "
- << "r=\'" << M.ruleid << "\'"
- << ((WeHaveSubordinates)? ">" : "/>")
- << endl;
- }
- }
-
- if(ScanLogMatches_None != CFGData.Scan_XML_Matches) O << R.str(); // Emit the match records if any.
-
- // <p/> -- Scan Performance Monitoring (performance='yes')
- // <p s='10' t='8' l='3294' d='84'/>
-
- if(CFGData.Scan_XML_Performance) { // Post performance data if needed.
- O << "\t<p "
- << "s=\'" << ScanData.SetupTime << "\' "
- << "t=\'" << ScanData.ScanTime.getElapsedTime() << "\' "
- << "l=\'" << ScanData.ScanSize << "\' "
- << "d=\'" << ScanData.ScanDepth << "\'/>"
- << endl;
- }
-
- // <g/> -- GBUdb Activity For This Scan (gbudb='yes')
- // <g o='1' i='101.201.31.04' t='u' c='0.12345' p='0.3342983' r='Caution'/>
-
- if(CFGData.Scan_XML_GBUdb && ScanData.FoundSourceIP()) { // Post gbudb data if needed & ready.
- O << "\t<g "
- << "o=\'" << ScanData.SourceIPRecord().Ordinal << "\' "
- << "i=\'" << (string) IP4Address(ScanData.SourceIPRecord().IP) << "\' "
- << "t=\'" <<
- ((Ugly == ScanData.SourceIPRecord().GBUdbData.Flag())? "u" :
- ((Good == ScanData.SourceIPRecord().GBUdbData.Flag())? "g" :
- ((Bad == ScanData.SourceIPRecord().GBUdbData.Flag())? "b" : "i")))
- << "\' "
- << "c=\'" << ScanData.SourceIPRecord().GBUdbData.Confidence() << "\' "
- << "p=\'" << ScanData.SourceIPRecord().GBUdbData.Probability() << "\' "
- << "r=\'" <<
- ((Unknown == ScanData.SourceIPRange())? "Unknown" :
- ((White == ScanData.SourceIPRange())? "White" :
- ((Normal == ScanData.SourceIPRange())? "Normal" :
- ((New == ScanData.SourceIPRange())? "New" :
- ((Caution == ScanData.SourceIPRange())? "Caution" :
- ((Black == ScanData.SourceIPRange())? "Black" :
- ((Truncate == ScanData.SourceIPRange())? "Truncate" :
- "Fault")))))))
- << "\'/>"
- << endl;
- }
-
- // If this wasn't a one-liner then we need to close the <s/> element
-
- if(WeHaveSubordinates) O << "</s>" << endl; // If <s> was open then close it.
-
- ScanData.XMLLogText = O.str(); // Save the formatted log text.
-
- // Now that we've produced the appropriate log entries let's send them
- // out to the log file.
-
- if(LogOutputMode_File == CFGData.Scan_XML_Mode) { // If we are writing to file,
- string TFN = CFGData.paths_log_path; // build an appropriate log file
- TFN.append(CFGData.node_licenseid); // name.
- if(CFGData.Scan_XML_Rotate) { // If we're rotating per day:
- TFN.append("."); // Put a timestamp on the file name.
- if(Rotate_LocalTime) { LocalTimestamp(TFN); } // Use either the local timestamp or
- else { Timestamp(TFN); } // the utc timestamp as configured.
- TFN = TFN.substr(0, TFN.length() - 6); // Stip off the Hours, Minutes, Seconds.
- }
- TFN.append(".log.xml"); // Tack on the extension.
-
- XMLScanLogger.post(ScanData.XMLLogText, TFN); // Post the log with the file name.
- }
- }
-
- void snfLOGmgr::doClassicLogs(snfCFGData& CFGData, snfScanData& ScanData) { // Classic sub routine for LogThisScan()
- ostringstream O; // Set up the output stream.
-
- set<int> Duplicates; // Set up to track duplicates.
- list<snf_match>::iterator MatchRecordsCursor; // Set up a cursor to the results.
- for(
- MatchRecordsCursor = ScanData.MatchRecords.begin(); // Loop through the scan results
- MatchRecordsCursor != ScanData.MatchRecords.end();
- MatchRecordsCursor++
- ) {
- snf_match& M = (*MatchRecordsCursor); // Grab the current match record.
- if(
- ScanLogMatches_None != CFGData.Scan_Classic_Matches && // If match records are requested
- ('f' != M.flag && 'c' != M.flag) // and this record is not the (f)inal
- ) { // then evaluate it and log (??) it.
- if(ScanLogMatches_Unique == CFGData.Scan_Classic_Matches) { // If only unique matches are required
- if(Duplicates.end() != Duplicates.find(M.ruleid)) { // check for a duplicate rule id.
- continue; // If the rule id was found then skip.
- } else { // If the rule id was not found then
- Duplicates.insert(M.ruleid); // remember it for next time.
- }
- }
-
- // At this point we've skipped duplicate match records, but we
- // still want to emit some (all?) of them so it's time to do it.
-
- O << CFGData.node_licenseid << "\t"
- << Timestamp(ScanData.StartOfJobUTC) << "\t"
- << ScanData.ScanName << "\t"
- << ScanData.SetupTime << "\t"
- << ScanData.ScanTime.getElapsedTime() << "\t"
- << (('m' == M.flag) ? "Match\t" :
- (('w' == M.flag) ? "White\t" :
- (('p' == M.flag) ? "Panic\t" : "Fault\t")))
- << M.ruleid << "\t"
- << M.symbol << "\t"
- << M.index << "\t"
- << M.endex << "\t"
- << ScanData.ScanDepth
- << endl;
-
- } else { // If this record is final, emit it!
- O << CFGData.node_licenseid << "\t"
- << Timestamp(ScanData.StartOfJobUTC) << "\t"
- << ScanData.ScanName << "\t"
- << ScanData.SetupTime << "\t"
- << ScanData.ScanTime.getElapsedTime() << "\t"
- << (('f' == M.flag) ? "Final\t" :
- (('c' == M.flag) ? "Clean\t" : "Fault\t"))
- << M.ruleid << "\t"
- << M.symbol << "\t"
- << M.index << "\t"
- << M.endex << "\t"
- << ScanData.ScanDepth
- << endl;
- }
- }
-
- ScanData.ClassicLogText = O.str(); // Save the formatted log text.
-
- // Now that we've produced the appropriate log entries let's send them
- // out to the log file.
-
- if(LogOutputMode_File == CFGData.Scan_Classic_Mode) { // If we are writing to file,
- string TFN = CFGData.paths_log_path; // build an appropriate log file
- TFN.append(CFGData.node_licenseid); // name.
- if(CFGData.Scan_Classic_Rotate) { // If we're rotating per day:
- TFN.append("."); // Put a timestamp on the file name.
- if(Rotate_LocalTime) { LocalTimestamp(TFN); } // Use either the local timestamp or
- else { Timestamp(TFN); } // the utc timestamp as configured.
- TFN = TFN.substr(0, TFN.length() - 6); // Stip off the Hours, Minutes, Seconds.
- }
- TFN.append(".log"); // Tack on the extension.
-
- ClassicScanLogger.post(ScanData.ClassicLogText, TFN); // Post the log with the file name.
- }
- }
-
- //// LogThisScan() is broken into two sections. The first section happens with
- //// the snfLOGmgr mutex locked and must happen as quickly as possible so that
- //// the chance for contention over those resources is minimized. This amounts
- //// to some raw CPU work adjusting counters and so forth.
- ////
- //// The second section handles any additional work such as formatting log
- //// entries. Essentially, anything that doesn't NEED to happen while the mutex
- //// is locked should happen in the second section.
- ////
- //// The two subroutines for LogThisScan are:
- //// captureLTSMetrics() -- Mutex locked, first section
- //// performLTSLogging() -- Mutex NOT locked, second section
-
- void snfLOGmgr::captureLTSMetrics(snfCFGData& CFGData, snfScanData& ScanData) { // LogThisScan section 1
- ScopeMutex FreezeRightThereWhileITakeThisPicture(MyMutex); // Lock the object for this update.
-
- if(Status.LatestRuleID < ScanData.PatternID) { // If we have a new latest rule id
- Status.LatestRuleID = ScanData.PatternID; // then capture it.
- }
-
- // keep histograms---
-
- // Second, Minute, and Hour histograms are reset() by the appropriate
- // reporting functions. That is, when a Second report is made, the Second
- // histograms are reset; likewise Minutes and Hours. As a result we simply
- // hit them all the same way when we log a scan.
-
- ResultsSecond.hit(ScanData.CompositeFinalResult); // Histogram the result codes.
- ResultsMinute.hit(ScanData.CompositeFinalResult);
- ResultsHour.hit(ScanData.CompositeFinalResult);
-
- if(ScanData.PatternWasFound) { // Histogram pattern IDs.
- RulesSecond.hit(ScanData.PatternID);
- RulesMinute.hit(ScanData.PatternID);
- RulesHour.hit(ScanData.PatternID);
- }
-
- if(0 < ScanData.RulePanics.size()) { // Histogram any rule panic hits.
- set<int>::iterator iP;
- for( // Dump the set of rule panic
- iP = ScanData.RulePanics.begin(); // IDs from beginning to end
- iP != ScanData.RulePanics.end(); // into our histogram set.
- iP++) {
- PanicsSecond.hit((*iP));
- PanicsMinute.hit((*iP));
- PanicsHour.hit((*iP));
- }
- }
-
- // count up these things---
-
- ++(*CurrentCounters).Events.Scans; // Number of messages scanned.
-
- const int HamResult = 0; // A result of 0 is "clean" or "white"
- if( // If we have identified Ham
- HamResult == ScanData.CompositeFinalResult || // either by the conventional result or
- CFGData.TrainingWhiteRuleHandler.isListed(ScanData.CompositeFinalResult) // some known white-rule symbol
- ) {
- ++(*CurrentCounters).Events.Ham; // then we will bump the Ham counter.
- } else { // For any other result we will bump
- ++(*CurrentCounters).Events.Spam; // the spam counter.
- }
-
- if(ScanData.GBUdbNormalTriggered) { // Count of GBUdb indetermineta IPs.
- ++(*CurrentCounters).Events.GBUdbNormalTriggered;
- }
-
- if(ScanData.GBUdbWhiteTriggered) { // Count of GBUdb found source IP white.
- ++(*CurrentCounters).Events.GBUdbWhiteTriggered;
- }
- if(ScanData.GBUdbWhiteSymbolForced) { // Count of white was on and symbol was set.
- ++(*CurrentCounters).Events.GBUdbWhiteSymbolForced;
- }
- if(ScanData.GBUdbPatternSourceConflict) { // Count of pattern was found with white IP.
- ++(*CurrentCounters).Events.GBUdbPatternSourceConflict;
- }
- if(ScanData.GBUdbAutoPanicTriggered) { // Count of autopanic was triggered.
- ++(*CurrentCounters).Events.GBUdbAutoPanicTriggered;
- }
- if(ScanData.GBUdbAutoPanicExecuted) { // Count of an autopanic was added.
- ++(*CurrentCounters).Events.GBUdbAutoPanicExecuted;
- }
-
- if(ScanData.GBUdbBlackTriggered) { // Count of GBUdb found source IP black.
- ++(*CurrentCounters).Events.GBUdbBlackTriggered;
- }
- if(ScanData.GBUdbBlackSymbolForced) { // Count of black was on and symbol was set.
- ++(*CurrentCounters).Events.GBUdbBlackSymbolForced;
- }
- if(ScanData.GBUdbTruncateTriggered) { // Count of Truncate was possible.
- ++(*CurrentCounters).Events.GBUdbTruncateTriggered;
- }
- if(ScanData.GBUdbPeekTriggered) { // Count of we could peek.
- ++(*CurrentCounters).Events.GBUdbPeekTriggered;
- }
- if(ScanData.GBUdbSampleTriggered) { // Count of we could sample.
- ++(*CurrentCounters).Events.GBUdbSampleTriggered;
- }
- if(ScanData.GBUdbTruncateExecuted) { // Count of if we actually did truncate.
- ++(*CurrentCounters).Events.GBUdbTruncateExecuted;
- }
- if(ScanData.GBUdbPeekExecuted) { // Count of we peeked instead of truncating.
- ++(*CurrentCounters).Events.GBUdbPeekExecuted;
- }
- if(ScanData.GBUdbSampleExecuted) { // Count of we sampled.
- ++(*CurrentCounters).Events.GBUdbSampleExecuted;
- }
-
- if(ScanData.GBUdbCautionTriggered) { // Count of GBUdb found source IP suspicous.
- ++(*CurrentCounters).Events.GBUdbCautionTriggered;
- }
- if(ScanData.GBUdbCautionSymbolForced) { // Count of caution was on and symbol was set.
- ++(*CurrentCounters).Events.GBUdbCautionSymbolForced;
- }
-
- if(ScanData.PatternWasFound) { // Count of scanner matches.
- ++(*CurrentCounters).Events.PatternWasFound;
- }
-
- if(0 < ScanData.RulePanics.size()) { // Count of rule panics.
- ++(*CurrentCounters).Events.RulePanicFound;
- }
- }
-
- void snfLOGmgr::performLTSLogging(snfCFGData& CFGData, snfScanData& ScanData) { // LogThisScan section 2
- // Build X- Headers, XML Log, and Classic Log as needed...
-
- if(LogOutputMode_None != CFGData.XHDROutput_Mode) { // If XHeaders are turned on then
- doXHDRs(CFGData, ScanData); // call the XHeaders subroutine.
- }
-
- if(LogOutputMode_None != CFGData.Scan_XML_Mode) { // If XML scan logs are turned on then
- doXMLLogs(CFGData, ScanData); // call the XML scan log subroutine.
- }
-
- if(LogOutputMode_None != CFGData.Scan_Classic_Mode) { // If Classic scan logs are turned on
- doClassicLogs(CFGData, ScanData); // then call the Classic log subroutine.
- }
- }
-
- void snfLOGmgr::logThisScan(snfCFGData& CFGData, snfScanData& ScanData) { // How to log a scan event...
- captureLTSMetrics(CFGData, ScanData); // Lock the mutex and do the math.
- performLTSLogging(CFGData, ScanData); // Unlock the mutex and write it down.
- if(0 < ScanData.XHDRsText.length()) { // If we have XHeader data then
- switch(CFGData.XHDROutput_Mode) { // set the appropriate output mode.
- case LogOutputMode_Inject: ScanData.XHeaderInjectOn = true; break; // We will either inject the headers
- case LogOutputMode_File: ScanData.XHeaderFileOn = true; break; // or we will create a .xhdr file.
- } // The actual inject/file operation
- } // happens in ScanMessageFile().
- ScanData.ReadyToClear = true; // This can be cleared now once
- } // the ScanData has been consumed.
-
- // logThisError(CFGData, ScanData) - for scanning errors (message context)
-
- void snfLOGmgr::logThisError(snfScanData& ScanData, const string ContextName, // Inject an error log entry for this
- const int Code, const string Text // scan using this number & message.
- ) {
-
- // Check for each type of log and create an appropriate entry for
- // each log type that is activated in the configuration.
-
- //// Handle XML Log Scan Error Reports
-
- if(LogOutputMode_None != XML_Log_Mode) { // If XML logs are on emit the error.
-
- // Produce Error Log Entry and store in XMLLogText
-
- stringstream O; // Stringstream for formatting.
-
- O << "<e " // Format the <s>can error element
- << "u=\'" << Timestamp(ScanData.StartOfJobUTC) << "\' "
- << "context=\'" << ContextName << "\' "
- << "m=\'" << ScanData.ScanName << "\' "
- << "code=\'" << Code << "\' "
- << "text=\'" << Text << "\'"
- << "/>"
- << endl;
-
- ScanData.XMLLogText = O.str(); // Save the formatted log text.
-
- // Now that we've produced the appropriate log entries let's send them
- // out to the log file.
-
- if(LogOutputMode_File == XML_Log_Mode) { // If we are writing to file,
- string TFN = LogsPath; // build an appropriate log file
- TFN.append(NodeId); // name.
- if(XMLLogRotate) { // If we're rotating per day:
- TFN.append("."); // Put a timestamp on the file name.
- if(Rotate_LocalTime) { LocalTimestamp(TFN); } // Use either the local timestamp or
- else { Timestamp(TFN); } // the utc timestamp as configured.
- TFN = TFN.substr(0, TFN.length() - 6); // Stip off the Hours, Minutes, Seconds.
- }
- TFN.append(".log.xml"); // Tack on the extension.
-
- XMLScanLogger.post(ScanData.XMLLogText, TFN); // Post the log with the file name.
- }
- }
-
- //// Handle Clasic Log Scan Error Reports
-
- if(LogOutputMode_None != Classic_Log_Mode) { // If Classic logs are on emit the error.
-
- // Produce an appropriate Error log entry in ClassicLogText
-
- stringstream O; // Stringstream for formatting.
-
- O << NodeId << "\t" // Format the scan error entry.
- << Timestamp(ScanData.StartOfJobUTC) << "\t"
- << ScanData.ScanName << "\t"
- << ScanData.SetupTime << "\t"
- << ScanData.ScanTime.getElapsedTime() << "\t"
- << Text << "\t"
- << "0\t"
- << Code << "\t"
- << "0\t"
- << ScanData.ScanSize << "\t"
- << ScanData.ScanDepth
- << endl;
-
- ScanData.ClassicLogText = O.str(); // Save the formatted log text.
-
- // Now that we've produced the appropriate log entries let's send them
- // out to the log file.
-
- if(LogOutputMode_File == Classic_Log_Mode) { // If we are writing to file,
- string TFN = LogsPath; // build an appropriate log file
- TFN.append(NodeId); // name.
- if(ClassicLogRotate) { // If we're rotating per day:
- TFN.append("."); // Put a timestamp on the file name.
- if(Rotate_LocalTime) { LocalTimestamp(TFN); } // Use either the local timestamp or
- else { Timestamp(TFN); } // the utc timestamp as configured.
- TFN = TFN.substr(0, TFN.length() - 6); // Stip off the Hours, Minutes, Seconds.
- }
- TFN.append(".log"); // Tack on the extension.
-
- ClassicScanLogger.post(ScanData.ClassicLogText, TFN); // Post the log with the file name.
- }
- }
- }
-
- // logThisIPTest w/ Action
-
- void snfLOGmgr::logThisIPTest(IPTestRecord& I, string Action) { // Log an IPTest and action.
- if(!Configured) return; // Do nothing if not configured.
- stringstream O; // Stringstream to format the entry.
- string tmp; // String for use getting timestamp.
-
- O << "<t "
- << "u=\'" << Timestamp(tmp) << "\' "
- << "ip=\'" << (string) I.IP << "\' "
- << "t=\'" <<
- ((Ugly == I.G.Flag())? "u" :
- ((Good == I.G.Flag())? "g" :
- ((Bad == I.G.Flag())? "b" : "i")))
- << "\' "
- << "g=\'" << I.G.Good() << "\' "
- << "b=\'" << I.G.Bad() << "\' "
- << "c=\'" << I.G.Confidence() << "\' "
- << "p=\'" << I.G.Probability() << "\' "
- << "r=\'" <<
- ((Unknown == I.R)? "Unknown" :
- ((White == I.R)? "White" :
- ((Normal == I.R)? "Normal" :
- ((New == I.R)? "New" :
- ((Caution == I.R)? "Caution" :
- ((Black == I.R)? "Black" :
- ((Truncate == I.R)? "Truncate" :
- "Fault")))))))
- << "\' "
- << "a=\'" << Action << "\'"
- << "/>" << endl;
-
- XMLScanLogger.post(O.str()); // Post the log, use existing path.
- }
-
- // logThisError(Context, Code, Text) - for non-message errors.
-
- void snfLOGmgr::logThisError(string ContextName, int Code, string Text) { // Log an error message.
- if(!Configured) return; // Do nothing if not configured.
- ScopeMutex LockCFG(MyMutex); // Don't change CFG. I'm using it!
- if(LogOutputMode_File == XML_Log_Mode) { // If XML logs are turned on:
- stringstream O; // Stringstream to format the entry.
- string tmp; // String for use getting timestamp.
-
- O << "<e " // Format an <e/>rror element.
- << "u=\'" << Timestamp(tmp) << "\' "
- << "context=\'" << ContextName << "\' "
- << "code=\'" << Code << "\' "
- << "text=\'" << Text << "\'/>"
- << endl;
-
- // Now that we've produced the appropriate log entries let's send them
- // out to the log file.
-
- string TFN = LogsPath; // build an appropriate log file
- TFN.append(NodeId); // name.
- if(XMLLogRotate) { // If we're rotating per day:
- TFN.append("."); // Put a timestamp on the file name.
- if(Rotate_LocalTime) { LocalTimestamp(TFN); } // Use either the local timestamp or
- else { Timestamp(TFN); } // the utc timestamp as configured.
- TFN = TFN.substr(0, TFN.length() - 6); // Stip off the Hours, Minutes, Seconds.
- }
- TFN.append(".log.xml"); // Tack on the extension.
-
- XMLScanLogger.post(O.str(), TFN); // Post the log with the file name.
- }
- if(LogOutputMode_File == Classic_Log_Mode) { // If Classic logs are turned on:
- stringstream O; // Stringstream to format the entry.
- string tmp; // String for use getting timestamp.
-
- O << NodeId << "\t" // Format the error entry.
- << Timestamp(tmp) << "\t"
- << ContextName << "\t"
- << "0\t"
- << "0\t"
- << Text << "\t"
- << "0\t"
- << Code << "\t"
- << "0\t"
- << "0\t"
- << "0\t"
- << endl;
-
- // Now that we've produced the appropriate log entries let's send them
- // out to the log file.
-
- string TFN = LogsPath; // build an appropriate log file
- TFN.append(NodeId); // name.
- if(ClassicLogRotate) { // If we're rotating per day:
- TFN.append("."); // Put a timestamp on the file name.
- if(Rotate_LocalTime) { LocalTimestamp(TFN); } // Use either the local timestamp or
- else { Timestamp(TFN); } // the utc timestamp as configured.
- TFN = TFN.substr(0, TFN.length() - 6); // Stip off the Hours, Minutes, Seconds.
- }
- TFN.append(".log"); // Tack on the extension.
-
- ClassicScanLogger.post(O.str(), TFN); // Post the log with the file name.
- }
- }
-
- // logThisInfo(Context, Code, Text) - for generic non-errors.
-
- void snfLOGmgr::logThisInfo(string ContextName, int Code, string Text) { // Log an informational message.
- if(!Configured) return; // Do nothing if not configured.
- ScopeMutex LockCFG(MyMutex); // Don't change CFG. I'm using it!
- if(LogOutputMode_File == XML_Log_Mode) { // If XML logs are turned on:
- stringstream O; // Stringstream to format the entry.
- string tmp; // String for use getting timestamp.
-
- O << "<i " // Format an <i/>nfo element.
- << "u=\'" << Timestamp(tmp) << "\' "
- << "context=\'" << ContextName << "\' "
- << "code=\'" << Code << "\' "
- << "text=\'" << Text << "\'/>"
- << endl;
-
- // Now that we've produced the appropriate log entries let's send them
- // out to the log file.
-
- string TFN = LogsPath; // build an appropriate log file
- TFN.append(NodeId); // name.
- if(XMLLogRotate) { // If we're rotating per day:
- TFN.append("."); // Put a timestamp on the file name.
- if(Rotate_LocalTime) { LocalTimestamp(TFN); } // Use either the local timestamp or
- else { Timestamp(TFN); } // the utc timestamp as configured.
- TFN = TFN.substr(0, TFN.length() - 6); // Stip off the Hours, Minutes, Seconds.
- }
- TFN.append(".log.xml"); // Tack on the extension.
-
- XMLScanLogger.post(O.str(), TFN); // Post the log with the file name.
- }
- if(LogOutputMode_File == Classic_Log_Mode) { // If Classic logs are turned on:
- stringstream O; // Stringstream to format the entry.
- string tmp; // String for use getting timestamp.
-
- O << NodeId << "\t" // Format the informational entry.
- << Timestamp(tmp) << "\t"
- << ContextName << "\t"
- << "0\t"
- << "0\t"
- << Text << "\t"
- << "0\t"
- << Code << "\t"
- << "0\t"
- << "0\t"
- << "0\t"
- << endl;
-
- // Now that we've produced the appropriate log entries let's send them
- // out to the log file.
-
- string TFN = LogsPath; // build an appropriate log file
- TFN.append(NodeId); // name.
- if(ClassicLogRotate) { // If we're rotating per day:
- TFN.append("."); // Put a timestamp on the file name.
- if(Rotate_LocalTime) { LocalTimestamp(TFN); } // Use either the local timestamp or
- else { Timestamp(TFN); } // the utc timestamp as configured.
- TFN = TFN.substr(0, TFN.length() - 6); // Stip off the Hours, Minutes, Seconds.
- }
- TFN.append(".log"); // Tack on the extension.
-
- ClassicScanLogger.post(O.str(), TFN); // Post the log with the file name.
- }
- }
-
- string snfLOGmgr::PlatformVersion(string NewPlatformVersion) { // Set platform version info.
- ScopeMutex FreezeForNewData(MyMutex); // Get the ball and
- myPlatformVersion = NewPlatformVersion; // set the data.
- return myPlatformVersion; // return the new data.
- }
-
- string snfLOGmgr::PlatformVersion() { // Get platform version info.
- ScopeMutex DontChangeOnMe(MyMutex); // Get the ball and
- return myPlatformVersion; // get the data.
- }
-
- string snfLOGmgr::EngineVersion() { // Get engine version info.
- return string(SNF_ENGINE_VERSION); // Return the engine version string.
- }
-
- //// The caller to getSpamshot promises to reset() the counters when it is done
- //// with the data and before getSnapshot() gets called again.
-
- snfCounterPack* snfLOGmgr::getSnapshot() { // Get a copy of the current counters.
- snfCounterPack* Snapshot = CurrentCounters; // Grab the active counters.
- CurrentCounters = ReportingCounters; // Swap the reporting counters in.
- CurrentCounters->ActiveTime.start(Snapshot->ActiveTime.stop()); // (De)Activate the activity timers.
- ReportingCounters = Snapshot; // Put the old ActiveCounters into
- return Snapshot; // reporting mode and return a ptr.
- }
-
- bool snfLOGmgr::OkToPeek(int PeekOneInX) { // Test to see if it's ok to peek.
- ScopeMutex JustMe(PeekMutex); // Protect the peek enable counter.
- ++PeekEnableCounter; // Bump the counter by one.
- if(PeekEnableCounter >= PeekOneInX) { // If we've made the threshold then
- PeekEnableCounter = 0; // reset the counter and
- return true; // return true.
- } // If not then
- return false; // return false.
- }
-
- bool snfLOGmgr::OkToSample(int SampleOneInX) { // Test to see if it's ok to sample.
- ScopeMutex JustMe(SampleMutex); // Protect the sample enable counter.
- ++SampleEnableCounter; // Bump the counter by one.
- if(SampleEnableCounter >= SampleOneInX) { // If we've made the threshold then
- SampleEnableCounter = 0; // reset the counter and
- return true; // return true.
- } // If not then return
- return false; // false.
- }
-
- time_t snfLOGmgr::Timestamp() { // Get an ordinary timestamp.
- time_t rawtime;
- time(&rawtime);
- return rawtime;
- }
-
- string snfLOGmgr::Timestamp(time_t t) { // Convert time_t to a timestamp s.
- char TimestampBfr[20]; // Create a small buffer.
- tm* gmt; // Get a ptr to a tm structure.
- gmt = gmtime(&t); // Fill it with UTC.
- sprintf(TimestampBfr,"%04d%02d%02d%02d%02d%02d", // Format yyyymmddhhmmss
- gmt->tm_year+1900,
- gmt->tm_mon+1,
- gmt->tm_mday,
- gmt->tm_hour,
- gmt->tm_min,
- gmt->tm_sec
- );
- return string(TimestampBfr); // Return a string.
- }
-
-
- string& snfLOGmgr::Timestamp(string& s) { // Returns a current timestamp in s.
- s.append(Timestamp(Timestamp())); // Append the timestamp to s and
- return s; // return it.
- }
-
- string snfLOGmgr::LocalTimestamp(time_t t) { // Convert time_t to a local timestamp s.
- char TimestampBfr[20]; // Create a small buffer.
- tm* localt; // Get a ptr to a tm structure.
- localt = localtime(&t); // Fill it with local time.
- sprintf(TimestampBfr,"%04d%02d%02d%02d%02d%02d", // Format yyyymmddhhmmss
- localt->tm_year+1900,
- localt->tm_mon+1,
- localt->tm_mday,
- localt->tm_hour,
- localt->tm_min,
- localt->tm_sec
- );
- return string(TimestampBfr); // Return a string.
- }
-
- string& snfLOGmgr::LocalTimestamp(string& s) { // Returns a local timestamp in s.
- s.append(LocalTimestamp(Timestamp())); // Append the timestamp to s and
- return s; // return it.
- }
-
- unsigned int snfLOGmgr::SerialNumber() { // Returns the next serial number.
- ScopeMutex AtomicOperation(SerialNumberMutex); // Lock the serial number mutex.
- ++Status.SerialNumberCounter; // Increment the serial number.
- unsigned int result = Status.SerialNumberCounter; // Capture the new value.
- return result; // Return the unique result.
- }
-
- string& snfLOGmgr::SerialNumber(string& s) { // Appends the next serial number.
- char SerialNumberBuffer[9]; // 8 hex digits and a null terminator.
- sprintf( // Format the serial number and
- SerialNumberBuffer, // place it into the buffer.
- "%08X", SerialNumber()
- );
- s.append(SerialNumberBuffer); // Append the buffer to s.
- return s; // Return s.
- }
-
- // Persistent State Functions
- // The Persistent State object is slightly smart. It won't try to restore
- // itself from disk if it has already been restored. It won't write itself
- // to disk unless it was first loaded or there was no original file to load.
- // This leaves open the possibility that a disk problem when opening the
- // engine could cause a persistent state problem - but the alternative is to
- // have the persistent state unprotected. If there is a problem, then clearing
- // the problem and re-opening the engine will load the persistent state. If
- // that's not the best solution then it's always possible to destroy the
- // existing persistent state and start over -- at least then it will be a
- // conscious decision and not a "side effect"
-
- void snfLOGPersistentState::store(string& FileNameToStore) { // Write the whole thing to a file.
- if(Ready) { // If it is safe to overwrite then
- try { // Try... to
- ofstream P(FileNameToStore.c_str(), ios::trunc | ios::binary); // Open and overwrite the file and
- P.write((char*)this, sizeof(snfLOGPersistentState)); // store our bytes in binary format.
- P.close();
- } catch (...) {} // Ignore any errors for now.
- }
- }
-
- void snfLOGPersistentState::restore(string& FileNameToRestore) { // Read the whole thing from a file.
- if(!Ready) { // If we have not already loaded...
- if(0 > access(FileNameToRestore.c_str(), R_OK)) { // If the file cannot be read we
- Ready = true; // will assume it doesn't exist and
- } // set our Ready flag to allow writing.
- else { // If the file does exist we load it.
- try { // Try this...
- ifstream P(FileNameToRestore.c_str(), ios::binary); // Create a binary input stream and
- P.read((char*)this, sizeof(snfLOGPersistentState)); // read the bytes into this object.
- P.close(); // Then, close the file.
- Ready = true; // Set the Ready flag to allow writes.
- } catch(...) {} // Ignore any errors for now.
- }
- }
- }
-
- void snfLOGmgr::RecordSyncEvent() { // Sets timestamp of latest Sync.
- Status.LastSyncTime = Timestamp();
- }
-
- int snfLOGmgr::SecsSinceLastSync() { // Gets seconds since latest Sync.
- return (int) difftime(Timestamp(), Status.LastSyncTime);
- }
-
- void snfLOGmgr::RecordSaveEvent() { // Sets timestamp of latest Save.
- Status.LastSaveTime = Timestamp();
- }
-
- int snfLOGmgr::SecsSinceLastSave() { // Gets seconds since latest Save.
- return (int) difftime(Timestamp(), Status.LastSaveTime);
- }
-
- void snfLOGmgr::RecordCondenseEvent() { // Sets timestamp of latest Condense.
- Status.LastCondenseTime = Timestamp();
- }
-
- int snfLOGmgr::SecsSinceLastCondense() { // Gets seconds since latest Condense.
- return (int) difftime(Timestamp(), Status.LastCondenseTime);
- }
-
- // Data in the multi-range sliding window is tracked in circular buffers.
- // The snf_SMHDMY_Counter::do_input() function encapsuates the input operation.
-
- bool snf_SMHDMY_Counter::do_input( // Subroutine for new data input.
- int X, // Given new data X,
- int& SUM, // the SUM being tracked,
- int* DATA, // the array of DATA,
- int& ORDINAL, // the current ORDINAL,
- int SIZE) { // and the SIZE of the array...
-
- SUM -= DATA[ORDINAL]; // Subtract the old data from the
- SUM += (DATA[ORDINAL] = X); // SUM and replace it with X.
- ++ORDINAL; // Move to the next slot and
- if(SIZE <= ORDINAL) ORDINAL = 0; // rotate back around if needed.
-
- return (0 == ORDINAL); // Return true if time to carry.
- }
-
- void snf_SMHDMY_Counter::input(int X) {
-
- if(do_input(X, SEC6SUM, SEC6DATA, SEC6ORDINAL, 6)) // 6 second sum
- if(do_input(SEC6SUM, SEC10SUM, SEC10DATA, SEC10ORDINAL, 10)) // 60 second sum
- if(do_input(SEC10SUM, MIN6SUM, MIN6DATA, MIN6ORDINAL, 6)) // 6 minute sum
- if(do_input(MIN6SUM, MIN10SUM, MIN10DATA, MIN10ORDINAL, 10)) // 60 minute sum
- if(do_input(MIN10SUM, HOUR4SUM, HOUR4DATA, HOUR4ORDINAL, 4)) // 4 hour sum
- if(do_input(HOUR4SUM, HOUR6SUM, HOUR6DATA, HOUR6ORDINAL, 6)) { // 24 hour sum
- do_input(HOUR6SUM, WEEK7SUM, WEEK7DATA, WEEK7ORDINAL, 7); // 7 day sum
- do_input(HOUR6SUM, YEAR365SUM, YEAR365DATA, YEAR365ORDINAL, 365); // 365 day sum
- if(do_input(HOUR6SUM, MONTH5SUM, MONTH5DATA, MONTH5ORDINAL, 5)) // 5 day sum
- if(do_input(MONTH5SUM, MONTH6SUM, MONTH6DATA, MONTH6ORDINAL, 6)) // 30 day sum
- if(do_input(MONTH6SUM, YEAR3SUM, YEAR3DATA, YEAR3ORDINAL, 3)) // 3 month sum
- do_input(YEAR3SUM, YEAR4SUM, YEAR4DATA, YEAR4ORDINAL, 4); // 12 month sum
-
- }
- }
-
- double snf_SMHDMY_AveragePerMinute( // Get mornalized minute avg of X
- snf_SMHDMY_Counter& X, // Input the Units in the period.
- snf_SMHDMY_Counter& M) { // Input the Milliseconds in the period.
- int Units = X.SEC6SUM + X.SEC10SUM; // Get the count.
- int Milliseconds = M.SEC6SUM + M.SEC10SUM; // Get the time.
- return snf_rationalize(Units, Milliseconds, MillisecondsInAMinute); // Normalize to a minute.
- }
-
- double snfLOGmgr::MessagesPerMinute() { // Avg Msgs/Minute.
- return snf_SMHDMY_AveragePerMinute(MessageCounter, TimeCounter);
- }
-
- double snfLOGmgr::HamPerMinute() { // Avg Ham/Minute.
- return snf_SMHDMY_AveragePerMinute(HamCounter, TimeCounter);
- }
-
- double snfLOGmgr::SpamPerMinute() { // Avg Spam/Minute.
- return snf_SMHDMY_AveragePerMinute(SpamCounter, TimeCounter);
- }
-
- double snfLOGmgr::WhitePerMinute() { // Avg White/Minute.
- return snf_SMHDMY_AveragePerMinute(WhiteCounter, TimeCounter);
- }
-
- double snfLOGmgr::CautionPerMinute() { // Avg Caution/Minute.
- return snf_SMHDMY_AveragePerMinute(CautionCounter, TimeCounter);
- }
-
- double snfLOGmgr::BlackPerMinute() { // Avg Black/Minute.
- return snf_SMHDMY_AveragePerMinute(BlackCounter, TimeCounter);
- }
-
- double snfLOGmgr::TruncatePerMinute() { // Avg Truncate/Minute.
- return snf_SMHDMY_AveragePerMinute(TruncateCounter, TimeCounter);
- }
-
- double snfLOGmgr::SamplePerMinute() { // Avg Sample/Minute.
- return snf_SMHDMY_AveragePerMinute(SampleCounter, TimeCounter);
- }
-
- const string EmptyStatusSecondReport = "<stats class=\'second\'/>"; // Empty Status.Second looks like this.
- string snfLOGmgr::getStatusSecondReport() { // Get latest status.second report.
- ScopeMutex FlashBulb(StatusReportMutex); // Take a safe snapshot of the report.
- if(0 < SecondReportText.length()) return SecondReportText; // If it's posted then send it. If not
- return EmptyStatusSecondReport; // then send the empty version.
- }
-
- const string EmptyStatusMinuteReport = "<stats class=\'minute\'/>"; // Empty Status.Minute looks like this.
- string snfLOGmgr::getStatusMinuteReport() { // Get latest status.minute report.
- ScopeMutex FlashBulb(StatusReportMutex); // Take a safe snapshot of the report.
- if(0 < MinuteReportText.length()) return MinuteReportText; // If it's posted then send it. If not
- return EmptyStatusMinuteReport; // then send the empty version.
- }
-
- const string EmptyStatusHourReport = "<stats class=\'hour\'/>"; // Empty Status.Hour looks like this.
- string snfLOGmgr::getStatusHourReport() { // Get latest status.hour report.
- ScopeMutex FlashBulb(StatusReportMutex); // Take a safe snapshot of the report.
- if(0 < HourReportText.length()) return HourReportText; // If it's posted then send it. If not
- return EmptyStatusHourReport; // then send the empty version.
- }
|