public void WhenCustomFormatterThrowsAnExceptionAndUsedConfig()
{
string fileName = "FlatFileOutProcCustomFormatterHandleExceptionViaConfig.log";
File.Delete(fileName);
var logger = MockEventSourceOutProc.Logger;
MockFormatter formatter = new MockFormatter(true); //this formatter throws
TraceEventServiceConfiguration svcConfiguration = TraceEventServiceConfiguration.Load("Configurations\\CustomSink\\FlatFileCustomFormatter.xml");
TestScenario.WithConfiguration(
svcConfiguration,
() =>
{
using (InMemoryEventListener collectErrorsListener = new InMemoryEventListener())
{
try
{
collectErrorsListener.EnableEvents(SemanticLoggingEventSource.Log, EventLevel.Error, Keywords.All);
logger.LogSomeMessage("some message using formatter that throws");
collectErrorsListener.WaitEvents.Wait(5000);
StringAssert.Contains(collectErrorsListener.ToString(), "Payload : [message : System.InvalidOperationException: Operation is not valid due to the current state of the object.");
}
finally
{
collectErrorsListener.DisableEvents(SemanticLoggingEventSource.Log);
}
}
});
}
protected override void Given()
{
base.Given();
inMemoryListener = new InMemoryEventListener(new MockFormatter()
{
BeforeWriteEventAction = (f) => { throw new Exception("unhandled_exception_test"); }
});
var sink = new Lazy <IObserver <EventEntry> >(() => inMemoryListener);
sourceSettings = new EventSourceSettings(EventSource.GetName(typeof(MyCompanyEventSource)));
eventSources = new List <EventSourceSettings>()
{
{ sourceSettings }
};
sinkSettings = new List <SinkSettings>()
{
{ new SinkSettings("test", sink, eventSources) }
};
configuration = new TraceEventServiceConfiguration(sinkSettings);
this.sut = new TraceEventService(configuration);
slabListener = new InMemoryEventListener();
slabListener.EnableEvents(SemanticLoggingEventSource.Log, EventLevel.Error, SemanticLoggingEventSource.Keywords.TraceEvent);
this.sut.Start();
}
public void WhenExceptinOccursInCustomFormater1()
{
string filename = "customFormatterException.log";
File.Delete(filename);
var logger = MockEventSource.Logger;
var formatter = new CustomFormatter(true);
using (var listener = new ObservableEventListener())
using (var collectErrorsListener = new InMemoryEventListener(true))
{
try
{
collectErrorsListener.EnableEvents(SemanticLoggingEventSource.Log, System.Diagnostics.Tracing.EventLevel.LogAlways, Keywords.All);
listener.LogToFlatFile(filename, formatter);
listener.EnableEvents(logger, System.Diagnostics.Tracing.EventLevel.LogAlways, Keywords.All);
logger.LogSomeMessage("testing");
collectErrorsListener.WaitEvents.Wait(3000);
StringAssert.Contains(collectErrorsListener.ToString(), "unhandled exception from formatter");
}
finally
{
listener.DisableEvents(logger);
collectErrorsListener.DisableEvents(SemanticLoggingEventSource.Log);
}
}
}
public void WhenExceptionOccursInCustomFormatter()
{
string fileName = "FlatFileInProcCustomFormatterHandleException.log";
File.Delete(fileName);
var logger = TestEventSourceNonTransient.Logger;
using (var listener = new ObservableEventListener())
using (var collectErrorsListener = new InMemoryEventListener(true))
{
try
{
collectErrorsListener.EnableEvents(SemanticLoggingEventSource.Log, System.Diagnostics.Tracing.EventLevel.Error, Keywords.All);
listener.LogToFlatFile(fileName, new MockFormatter(true));
listener.EnableEvents(logger, System.Diagnostics.Tracing.EventLevel.LogAlways);
logger.EventWithPayload("payload1", 100);
StringAssert.Contains(collectErrorsListener.ToString(), "Payload : [message : System.InvalidOperationException: Operation is not valid due to the current state of the object.");
}
finally
{
listener.DisableEvents(logger);
collectErrorsListener.DisableEvents(SemanticLoggingEventSource.Log);
}
}
}
public void WritesDetailedOnEventLevel()
{
var formatter = new EventTextFormatter(EventTextFormatter.DashSeparator)
{
VerbosityThreshold = EventLevel.Critical
};
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(Logger, EventLevel.LogAlways, EventKeywords.All);
Logger.Failure("Summary");
formatter.VerbosityThreshold = EventLevel.Error;
Logger.Failure("Detailed");
var entries = Regex.Split(listener.ToString(), formatter.Header + "\r\n").Where(c => !string.IsNullOrWhiteSpace(c)).ToList();
StringAssert.Contains(entries[0], "EventId : 1");
StringAssert.Contains(entries[0], "Level : Error");
StringAssert.Contains(entries[0], "Payload : [message : Summary]");
StringAssert.Contains(entries[1],
@"ProviderId : 659518be-d338-564b-2759-c63c10ef82e2
EventId : 1
Keywords : 4
Level : Error
Message : Application Failure: Detailed
Opcode : Info
Task : 65533
Version : 0
Payload : [message : Detailed]
EventName : FailureInfo");
}
}
public void EventWithPayloadAndMessageAndDateTimeFormatInJson()
{
var logger = MockEventSrcForJson.Logger;
var formatter = new JsonEventTextFormatter();
formatter.DateTimeFormat = "dd/MM/yyyy";
string rawOutput = string.Empty;
using (var listener = new InMemoryEventListener()
{
Formatter = formatter
})
{
listener.EnableEvents(logger, EventLevel.LogAlways, MockEventSrcForJson.Keywords.Errors);
try
{
logger.LogUsingMessage(MockEventSrcForJson.LogMessage);
rawOutput = Encoding.Default.GetString(listener.Stream.ToArray());
}
finally
{
listener.DisableEvents(logger);
}
}
string today = System.DateTime.Today.ToString(formatter.DateTimeFormat);
string tomorrow = System.DateTime.Today.AddDays(1).ToString(formatter.DateTimeFormat);
Assert.IsTrue(rawOutput.Contains(today) || rawOutput.Contains(tomorrow));
}
public static void With1Listener(IEnumerable <EventSource> loggers, Action <ObservableEventListener, InMemoryEventListener> scenario)
{
using (var errorsListener = new InMemoryEventListener())
using (var listener = new ObservableEventListener())
{
try
{
errorsListener.EnableEvents(SemanticLoggingEventSource.Log, EventLevel.Verbose, Keywords.All);
scenario(listener, errorsListener);
}
finally
{
foreach (var logger in loggers)
{
try
{ listener.DisableEvents(logger); }
catch
{ }
}
errorsListener.DisableEvents(SemanticLoggingEventSource.Log);
}
}
}
public void TwoEventsWithPayloadsAndMessageInXml()
{
var formatter = new XmlEventTextFormatter();
var logger = MockEventSrcForXml.Logger;
string rawOutput = string.Empty;
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(logger, EventLevel.LogAlways, MockEventSrcForXml.Keywords.Errors);
try
{
logger.LogUsingMessage(MockEventSrcForXml.LogMessage);
logger.LogUsingMessage(MockEventSrcForXml.LogMessage + "2");
rawOutput = Encoding.Default.GetString(listener.Stream.ToArray());
}
finally
{
listener.DisableEvents(logger);
}
}
var entries = XDocument.Parse("<Events>" + rawOutput + "</Events>").Root.Elements();
Assert.AreEqual(2, entries.Count());
XmlFormattedEntry.Fill(entries.First());
Assert.AreEqual("message", XmlFormattedEntry.Payload.Elements().First().Attribute("Name").Value);
Assert.AreEqual(MockEventSrcForXml.LogMessage, XmlFormattedEntry.Payload.Elements().First().Value);
XmlFormattedEntry.Fill(entries.Last());
Assert.AreEqual("message", XmlFormattedEntry.Payload.Elements().Last().Attribute("Name").Value);
Assert.AreEqual(MockEventSrcForXml.LogMessage + "2", XmlFormattedEntry.Payload.Elements().First().Value);
Assert.AreEqual(MockEventSrcForXml.LogMessage, XmlFormattedEntry.Message.Elements().First().Value);
}
public void EventWithActivityIdInXml()
{
var formatter = new XmlEventTextFormatter();
var logger = MockEventSrcForXml.Logger;
var activityId = Guid.NewGuid();
var previousActivityId = Guid.Empty;
string rawOutput = string.Empty;
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(logger, EventLevel.LogAlways, MockEventSrcForXml.Keywords.Errors);
try
{
EventSource.SetCurrentThreadActivityId(activityId, out previousActivityId);
logger.LogUsingMessage(MockEventSrcForXml.LogMessage);
rawOutput = Encoding.Default.GetString(listener.Stream.ToArray());
}
finally
{
listener.DisableEvents(logger);
EventSource.SetCurrentThreadActivityId(previousActivityId);
}
}
var entries = XDocument.Parse("<Events>" + rawOutput + "</Events>").Root.Elements();
XmlFormattedEntry.Fill(entries.First());
Assert.AreEqual <Guid>(EventSource.GetGuid(typeof(MockEventSrcForXml)), Guid.Parse(XmlFormattedEntry.Provider.Attribute("Guid").Value));
Assert.AreEqual(1, XmlFormattedEntry.Payload.Elements().Count());
Assert.AreEqual("message", XmlFormattedEntry.Payload.Elements().First().Attribute("Name").Value);
Assert.AreEqual(MockEventSrcForXml.LogMessage, XmlFormattedEntry.Payload.Elements().First().Value);
Assert.AreEqual <Guid>(activityId, Guid.Parse(XmlFormattedEntry.Correlation.Attribute("ActivityID").Value));
Assert.IsNull(XmlFormattedEntry.Correlation.Attribute("RelatedActivityID"));
}
public void EventWithInformationalMessageformatDetailedInJson()
{
var logger = MockEventSourceNoTask.Logger;
string rawOutput = string.Empty;
using (var listener = new InMemoryEventListener()
{
Formatter = new JsonEventTextFormatter()
})
{
listener.EnableEvents(logger, EventLevel.LogAlways);
try
{
logger.InformationalMessageFormat("test");
rawOutput = Encoding.Default.GetString(listener.Stream.ToArray());
}
finally
{
listener.DisableEvents(logger);
}
}
var entries = JsonConvert.DeserializeObject <TestEventEntry[]>("[" + rawOutput + "]");
var entry = entries.FirstOrDefault();
Assert.IsFalse(rawOutput.StartsWith("{\r\n")); // No Formatting (Default)
Assert.AreEqual <EventLevel>(EventLevel.Informational, entry.Level);
Assert.AreEqual <EventKeywords>(EventKeywords.None, entry.EventKeywords);
Assert.AreEqual <EventOpcode>(0, entry.Opcode);
Assert.AreEqual("**test**", entry.Message);
Assert.AreEqual <byte>(0, entry.Version);
Assert.AreEqual(1, entry.Payload.Count);
StringAssert.Contains(entry.Payload.First().ToString(), "test");
}
protected override void Given()
{
listener = new InMemoryEventListener()
{
Formatter = new XmlEventTextFormatter(EventTextFormatting.None)
};
listener.EnableEvents(Logger, EventLevel.LogAlways);
}
protected override void Given()
{
formatter = new JsonEventTextFormatter();
listener = new InMemoryEventListener()
{
Formatter = formatter
};
listener.EnableEvents(logger, EventLevel.LogAlways);
}
public void WritesEventData()
{
var formatter = new EventTextFormatter(EventTextFormatter.DashSeparator)
{
VerbosityThreshold = EventLevel.Critical
};
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(Logger, EventLevel.LogAlways, Keywords.All);
Logger.Failure("Failure message");
formatter.VerbosityThreshold = EventLevel.Informational;
Logger.DBQueryStart("select * from table");
Logger.DBQueryStop();
Logger.LogColor(MyColor.Red);
var entries = Regex.Split(listener.ToString(), formatter.Header + "\r\n").Where(c => !string.IsNullOrWhiteSpace(c)).ToList();
StringAssert.Contains(entries[0], "EventId : 1");
StringAssert.Contains(entries[0], "Level : Error");
StringAssert.Contains(entries[0], "Payload : [message : Failure message]");
StringAssert.Contains(Regex.Replace(entries[1], @"\s+", string.Empty), Regex.Replace(
@"EventId : 5
Keywords : 2
Level : Informational
Message :
Opcode : Start
Task : 2
Version : 0
Payload : [sqlQuery : select * from table]", @"\s+", string.Empty));
StringAssert.Contains(Regex.Replace(entries[2], @"\s+", string.Empty), Regex.Replace(
@"EventId : 6
Keywords : 2
Level : Informational
Message :
Opcode : Stop
Task : 2
Version : 0
Payload :", @"\s+", string.Empty));
StringAssert.Contains(Regex.Replace(entries[3], @"\s+", string.Empty), Regex.Replace(
@"EventId : 8
Keywords : None
Level : Informational
Message :
Opcode : Info
Task : 65526
Version : 0
Payload : [color : 0]", @"\s+", string.Empty));
}
}
public void ShouldFilterByEventKeywords()
{
using (var listener = new InMemoryEventListener())
{
listener.EnableEvents(SemanticLoggingEventSource.Log, EventLevel.LogAlways, SemanticLoggingEventSource.Keywords.Formatting);
SemanticLoggingEventSource.Log.ConsoleSinkWriteFailed("test");
listener.DisableEvents(SemanticLoggingEventSource.Log);
Assert.AreEqual(string.Empty, listener.ToString());
}
}
public void WritesCustomHeader()
{
var formatter = new EventTextFormatter("*** header ***");
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(Logger, EventLevel.LogAlways, EventKeywords.All);
Logger.DBQueryStart("select * from table");
Assert.IsTrue(listener.ToString().Contains(formatter.Header));
}
}
protected override void Given()
{
this.mockConsole = new MockConsoleOutput();
// Will always throw
this.listener = new ObservableEventListener();
this.listener.LogToConsole(new MockFormatter()
{
BeforeWriteEventAction = (f) => { throw new InvalidOperationException(); }
});
this.listener.EnableEvents(TestEventSource.Log, EventLevel.LogAlways);
collectErrorsListener = new InMemoryEventListener();
collectErrorsListener.EnableEvents(SemanticLoggingEventSource.Log, EventLevel.Error, SemanticLoggingEventSource.Keywords.Sink);
}
public void WritesCustomFooter()
{
var formatter = new EventTextFormatter(null, "___footer___");
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(Logger, EventLevel.LogAlways, EventKeywords.All);
Logger.Startup();
Assert.IsTrue(listener.ToString().Contains(formatter.Footer));
}
}
public void EventWithPayloadAndMessageWithDateTimeFormatInXml()
{
var formatter = new XmlEventTextFormatter();
formatter.DateTimeFormat = "dd/MM/yyyy";
var logger = MockEventSrcForXml.Logger;
string rawOutput = string.Empty;
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(logger, EventLevel.LogAlways, MockEventSrcForXml.Keywords.Errors);
try
{
logger.LogUsingMessage(MockEventSrcForXml.LogMessage);
rawOutput = Encoding.Default.GetString(listener.Stream.ToArray());
}
finally
{
listener.DisableEvents(logger);
}
}
var entries = XDocument.Parse("<Events>" + rawOutput + "</Events>").Root.Elements();
XmlFormattedEntry.Fill(entries.First());
Assert.IsFalse(rawOutput.StartsWith("{\r\n")); // No Formatting (Default)
string today = System.DateTime.Today.ToString(formatter.DateTimeFormat);
string tomorrow = System.DateTime.Today.AddDays(1).ToString("dd/MM/yyyy");
Assert.IsTrue(rawOutput.Contains(today) || rawOutput.Contains(tomorrow));
Assert.AreEqual <Guid>(EventSource.GetGuid(typeof(MockEventSrcForXml)), Guid.Parse(XmlFormattedEntry.Provider.Attribute("Guid").Value));
Assert.AreEqual <int>(MockEventSrcForXml.LogUsingMessageEventID, Convert.ToInt32(XmlFormattedEntry.EventId.Value));
Assert.AreEqual <byte>(0, Convert.ToByte(XmlFormattedEntry.Version.Value));
Assert.AreEqual <int>((int)EventLevel.Informational, Int32.Parse(XmlFormattedEntry.Level.Value));
Assert.AreEqual <int>((int)MockEventSrcForXml.Tasks.Page, Int32.Parse(XmlFormattedEntry.Task.Value));
Assert.AreEqual <long>((long)EventKeywords.None, Int64.Parse(XmlFormattedEntry.Keywords.Value.Replace("0x", string.Empty)));
Assert.AreEqual <int>((int)EventOpcode.Start, Int32.Parse(XmlFormattedEntry.Opcode.Value));
Assert.AreEqual <byte>(0, Convert.ToByte(XmlFormattedEntry.Version.Value));
Assert.AreEqual <int>(System.Diagnostics.Process.GetCurrentProcess().Id, Int32.Parse(XmlFormattedEntry.ProcessId.Value));
Assert.AreEqual <int>(ThreadHelper.GetCurrentUnManagedThreadId(), Int32.Parse(XmlFormattedEntry.ThreadId.Value));
DateTime dt;
Assert.IsTrue(DateTime.TryParseExact(XmlFormattedEntry.TimeCreated.Attribute("SystemTime").Value, formatter.DateTimeFormat, CultureInfo.InvariantCulture, DateTimeStyles.None, out dt));
Assert.AreEqual(1, XmlFormattedEntry.Payload.Elements().Count());
Assert.AreEqual("message", XmlFormattedEntry.Payload.Elements().First().Attribute("Name").Value);
Assert.AreEqual(MockEventSrcForXml.LogMessage, XmlFormattedEntry.Payload.Elements().First().Value);
Assert.AreEqual(MockEventSrcForXml.LogMessage, XmlFormattedEntry.Message.Elements().First().Value);
}
public void WritesCustomHeaderAndFooter()
{
var formatter = new EventTextFormatter("---header---", "___footer___");
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(Logger, EventLevel.LogAlways, EventKeywords.All);
Logger.Startup();
var contents = listener.ToString();
Assert.IsTrue(contents.Contains(formatter.Header));
Assert.IsTrue(contents.Contains(formatter.Footer));
}
}
public void ShouldWriteWithNoFiltering()
{
using (var listener = new InMemoryEventListener())
{
listener.EnableEvents(SemanticLoggingEventSource.Log, EventLevel.LogAlways, Keywords.All);
SemanticLoggingEventSource.Log.DatabaseSinkPublishEventsFailed("test");
listener.DisableEvents(SemanticLoggingEventSource.Log);
StringAssert.Contains(listener.ToString(), "EventId : 101");
StringAssert.Contains(listener.ToString(), "Level : Error");
StringAssert.Contains(listener.ToString(), "Payload : [message : test]");
}
}
public void ShouldWriteByEventKeywords()
{
using (var listener = new InMemoryEventListener())
{
listener.EnableEvents(SemanticLoggingEventSource.Log, EventLevel.LogAlways, SemanticLoggingEventSource.Keywords.Sink);
SemanticLoggingEventSource.Log.ConsoleSinkWriteFailed("test");
listener.DisableEvents(SemanticLoggingEventSource.Log);
StringAssert.Contains(listener.ToString(), "EventId : 200");
StringAssert.Contains(listener.ToString(), "Level : Critical");
StringAssert.Contains(listener.ToString(), "Payload : [message : test]");
}
}
public void IncludesTaskName()
{
var formatter = new EventTextFormatter(EventTextFormatter.DashSeparator, verbosityThreshold: EventLevel.LogAlways);
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(Logger, EventLevel.LogAlways, Keywords.All);
Logger.DBQueryStart("select * from table");
Logger.DBQueryStop();
var entries = Regex.Split(listener.ToString(), formatter.Header + "\r\n").Where(c => !string.IsNullOrWhiteSpace(c)).ToList();
StringAssert.Matches(entries[0], new Regex("EventName : .*DBQuery"));
StringAssert.Matches(entries[1], new Regex("Task : .*" + MyCompanyEventSource.Tasks.DBQuery.ToString()));
}
}
public void WritesEventData()
{
var formatter = new EventTextFormatter(EventTextFormatter.DashSeparator) { VerbosityThreshold = EventLevel.Critical };
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(Logger, EventLevel.LogAlways, Keywords.All);
Logger.Failure("Failure message");
formatter.VerbosityThreshold = EventLevel.Informational;
Logger.DBQueryStart("select * from table");
Logger.DBQueryStop();
Logger.LogColor(MyColor.Red);
var entries = Regex.Split(listener.ToString(), formatter.Header + "\r\n").Where(c => !string.IsNullOrWhiteSpace(c)).ToList();
StringAssert.Contains(entries[0], "EventId : 1");
StringAssert.Contains(entries[0], "Level : Error");
StringAssert.Contains(entries[0], "Payload : [message : Failure message]");
StringAssert.Contains(entries[1],
@"EventId : 5
Keywords : 2
Level : Informational
Message :
Opcode : Start
Task : 2
Version : 0
Payload : [sqlQuery : select * from table]");
StringAssert.Contains(entries[2],
@"EventId : 6
Keywords : 2
Level : Informational
Message :
Opcode : Stop
Task : 2
Version : 0
Payload :");
StringAssert.Contains(entries[3],
@"EventId : 8
Keywords : None
Level : Informational
Message :
Opcode : Info
Task : 65526
Version : 0
Payload : [color : 0]");
}
}
public void IncludesTaskName()
{
var formatter = new EventTextFormatter(EventTextFormatter.DashSeparator, verbosityThreshold: EventLevel.LogAlways);
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(Logger, EventLevel.LogAlways, EventKeywords.All);
Logger.DBQueryStart("select * from table");
Logger.WithOpcodeAndNoTaskSpecfied(4);
var entries = Regex.Split(listener.ToString(), formatter.Header + "\r\n").Where(c => !string.IsNullOrWhiteSpace(c)).ToList();
StringAssert.Matches(entries[0], new Regex("EventName : .*DBQuery"));
StringAssert.Matches(entries[1], new Regex("Task : .*None"));
}
}
public void ShouldWriteTimestampWithDefaultDateTimeFormat()
{
var formatter = new EventTextFormatter(EventTextFormatter.DashSeparator);
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(Logger, EventLevel.LogAlways, EventKeywords.All);
Logger.Failure("error");
var logged = listener.ToString();
var lookup = "Timestamp : ";
var ts = logged.Substring(logged.IndexOf(lookup) + lookup.Length).Replace("\r\n", "");
DateTime dt;
Assert.IsTrue(DateTime.TryParseExact(ts, formatter.DateTimeFormat ?? EventEntry.DefaultDateTimeFormat, CultureInfo.InvariantCulture, DateTimeStyles.None, out dt));
}
}
public void ShouldWriteTimestampWithDefaultDateTimeFormat()
{
var formatter = new EventTextFormatter(EventTextFormatter.DashSeparator);
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(Logger, EventLevel.LogAlways, Keywords.All);
Logger.Failure("error");
var logged = listener.ToString();
var lookup = "Timestamp : ";
var lookupIndex = logged.IndexOf(lookup);
var dateLength = logged.IndexOf("\r\n", lookupIndex) - lookupIndex - lookup.Length;
var ts = logged.Substring(lookupIndex + lookup.Length, dateLength);
DateTime dt;
Assert.IsTrue(DateTime.TryParseExact(ts, formatter.DateTimeFormat ?? EventEntry.DefaultDateTimeFormat, CultureInfo.InvariantCulture, DateTimeStyles.None, out dt));
}
}
public void WhenLoggingErrorOCcurs()
{
InMemoryEventListener collectErrorsListener;
var mockConsole = new MockConsoleOutputInterceptor();
using (var eventListener = new ObservableEventListener())
{
eventListener.LogToConsole(new MockFormatter(true));
eventListener.EnableEvents(TestEventSource.Logger, EventLevel.LogAlways);
collectErrorsListener = new InMemoryEventListener(true);
collectErrorsListener.EnableEvents(SemanticLoggingEventSource.Log, EventLevel.Error, SemanticLoggingEventSource.Keywords.Sink);
TestEventSource.Logger.EventWithPayload("payload1", 100);
}
StringAssert.Contains(collectErrorsListener.ToString(), "System.InvalidOperationException: Operation is not valid due to the current state of the object.");
Assert.AreEqual(string.Empty, mockConsole.Ouput);
}
public void WhenSinkAddedWithErrorDoesNotRecycle()
{
var fileName = "flatfileListenerOk.log";
File.Delete(fileName);
var logger = MockEventSourceOutProc.Logger;
var configFile = Path.GetDirectoryName(Assembly.GetExecutingAssembly().Location) + "\\Configurations\\Reconfiguration\\temp\\configFile.xml";
UpdateServiceConfigurationFile("Configurations\\Reconfiguration\\NoListener.xml", configFile);
TraceEventServiceConfiguration svcConfiguration = TraceEventServiceConfiguration.Load(configFile, true);
TestScenario.WithConfiguration(
svcConfiguration,
() =>
{
using (var collectErrorsListener = new InMemoryEventListener())
{
collectErrorsListener.EnableEvents(SemanticLoggingEventSource.Log, EventLevel.LogAlways, Keywords.All);
try
{
TraceSessionHelper.WaitAndAssertCountOfSessions("ServiceReconfig", 1);
UpdateServiceConfigurationFile("Configurations\\Reconfiguration\\FlatFileListenerError.xml", configFile);
TraceSessionHelper.WaitAndAssertCountOfSessions("ServiceReconfig-flatFileListener", 0);
TraceSessionHelper.WaitAndAssertCountOfSessions("ServiceReconfig-2flatFileListener", 1);
MockEventSourceOutProc.Logger.LogSomeMessage("Some informational from a new listener.");
var entries2 = FlatFileHelper.PollUntilTextEventsAreWritten(fileName, 1, "======");
Assert.AreEqual(1, entries2.Count());
StringAssert.Contains(entries2.First(), "Some informational from a new listener.");
collectErrorsListener.WaitEvents.Wait(TimeSpan.FromSeconds(3));
StringAssert.Contains(collectErrorsListener.ToString(), "One or more errors occurred when loading the TraceEventService configuration file.");
StringAssert.Contains(collectErrorsListener.ToString(), "The given path's format is not supported.");
StringAssert.Contains(collectErrorsListener.ToString(), "The configuration was partially successfully loaded. Check logs for further error details.");
}
finally
{
File.Delete(configFile);
collectErrorsListener.DisableEvents(SemanticLoggingEventSource.Log);
}
}
});
}
public void WhenCustomFormatterThrowsAnExceptionAndUsedProgramatically()
{
string fileName = "FlatFileOutProcCustomFormatterHandleException.log";
File.Delete(fileName);
var logger = MockEventSourceOutProc.Logger;
MockFormatter formatter = new MockFormatter(true); //this formatter throws
EventSourceSettings settings = new EventSourceSettings("MockEventSourceOutProc", null, EventLevel.Informational);
var subject = new EventEntrySubject();
subject.LogToFlatFile(fileName, formatter);
SinkSettings sinkSettings = new SinkSettings("flatFileSink", subject, new List <EventSourceSettings>()
{
{ settings }
});
List <SinkSettings> sinks = new List <SinkSettings>()
{
{ sinkSettings }
};
TraceEventServiceConfiguration svcConfiguration = new TraceEventServiceConfiguration(sinks);
TestScenario.WithConfiguration(
svcConfiguration,
() =>
{
using (var collectErrorsListener = new InMemoryEventListener())
{
try
{
collectErrorsListener.EnableEvents(SemanticLoggingEventSource.Log, EventLevel.LogAlways, Keywords.All);
logger.LogSomeMessage("some message using formatter that throws");
collectErrorsListener.WaitEvents.Wait(5000);
StringAssert.Contains(collectErrorsListener.ToString(), "Payload : [message : System.InvalidOperationException: Operation is not valid due to the current state of the object.");
}
finally
{
collectErrorsListener.DisableEvents(SemanticLoggingEventSource.Log);
}
}
});
}
public void EventWithPayloadKeywrdsNoMsgInXml()
{
var logger = MockEventSrcForXml.Logger;
var formatter = new XmlEventTextFormatter();
string rawOutput = string.Empty;
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(logger, EventLevel.LogAlways, MockEventSrcForXml.Keywords.Errors);
try
{
logger.UsingKeywords(MockEventSrcForXml.LogMessage, long.MaxValue);
rawOutput = listener.ToString();
}
finally
{
listener.DisableEvents(logger);
}
}
Assert.AreEqual(-1, rawOutput.IndexOf("\r\n"));
var entries = XDocument.Parse("<Events>" + rawOutput + "</Events>").Root.Elements();
XmlFormattedEntry.Fill(entries.Single());
Assert.AreEqual <Guid>(EventSource.GetGuid(typeof(MockEventSrcForXml)), Guid.Parse(XmlFormattedEntry.Provider.Attribute("Guid").Value));
Assert.AreEqual <int>(MockEventSrcForXml.UsingKeywordsEventID, Convert.ToInt32(XmlFormattedEntry.EventId.Value));
Assert.AreEqual <byte>(0, Convert.ToByte(XmlFormattedEntry.Version.Value));
Assert.AreEqual <int>((int)EventLevel.Informational, Int32.Parse(XmlFormattedEntry.Level.Value));
Assert.AreEqual <int>((int)MockEventSrcForXml.Tasks.DBQuery, Int32.Parse(XmlFormattedEntry.Task.Value));
Assert.AreEqual <long>((long)MockEventSrcForXml.Keywords.Errors, Int64.Parse(XmlFormattedEntry.Keywords.Value.Replace("0x", string.Empty)));
Assert.AreEqual <int>((int)EventOpcode.Start, Int32.Parse(XmlFormattedEntry.Opcode.Value));
Assert.AreEqual <int>(System.Diagnostics.Process.GetCurrentProcess().Id, Int32.Parse(XmlFormattedEntry.ProcessId.Value));
Assert.AreEqual <int>(ThreadHelper.GetCurrentUnManagedThreadId(), Int32.Parse(XmlFormattedEntry.ThreadId.Value));
DateTime dt;
Assert.IsTrue(DateTime.TryParseExact(XmlFormattedEntry.TimeCreated.Attribute("SystemTime").Value, EventEntry.DefaultDateTimeFormat, CultureInfo.InvariantCulture, DateTimeStyles.None, out dt));
Assert.AreEqual(2, XmlFormattedEntry.Payload.Elements().Count());
Assert.AreEqual("message", XmlFormattedEntry.Payload.Elements().First().Attribute("Name").Value);
Assert.AreEqual(MockEventSrcForXml.LogMessage, XmlFormattedEntry.Payload.Elements().First().Value);
Assert.AreEqual("longArg", XmlFormattedEntry.Payload.Elements().Last().Attribute("Name").Value);
Assert.AreEqual(long.MaxValue.ToString(), XmlFormattedEntry.Payload.Elements().Last().Value);
}
public void EventWithPayloadAloneInXml()
{
var formatter = new XmlEventTextFormatter();
string payloadMsg = ("<?xml version=\"1.0\" encoding=\"utf-8\"?>\n<test/>");
var logger = MockEventSrcForXml.Logger;
string rawOutput = string.Empty;
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(logger, EventLevel.LogAlways, MockEventSrcForXml.Keywords.Errors);
try
{
logger.LogUsingMessage(payloadMsg);
rawOutput = Encoding.Default.GetString(listener.Stream.ToArray());
}
finally
{
listener.DisableEvents(logger);
}
}
var entries = XDocument.Parse("<Events>" + rawOutput + "</Events>").Root.Elements();
XmlFormattedEntry.Fill(entries.First());
Assert.IsFalse(rawOutput.StartsWith("{\r\n")); // No Formatting (Default)
Assert.AreEqual <Guid>(EventSource.GetGuid(typeof(MockEventSrcForXml)), Guid.Parse(XmlFormattedEntry.Provider.Attribute("Guid").Value));
Assert.AreEqual <int>(MockEventSrcForXml.LogUsingMessageEventID, Convert.ToInt32(XmlFormattedEntry.EventId.Value));
Assert.AreEqual <byte>(0, Convert.ToByte(XmlFormattedEntry.Version.Value));
Assert.AreEqual <int>((int)EventLevel.Informational, Int32.Parse(XmlFormattedEntry.Level.Value));
Assert.AreEqual <int>((int)EventTask.None, Int32.Parse(XmlFormattedEntry.Task.Value));
Assert.AreEqual <long>((long)EventKeywords.None, Int64.Parse(XmlFormattedEntry.Keywords.Value.Replace("0x", string.Empty)));
Assert.AreEqual <int>((int)EventOpcode.Start, Int32.Parse(XmlFormattedEntry.Opcode.Value));
Assert.AreEqual <byte>(0, Convert.ToByte(XmlFormattedEntry.Version.Value));
DateTime dt;
Assert.IsTrue(DateTime.TryParseExact(XmlFormattedEntry.TimeCreated.Attribute("SystemTime").Value, EventEntry.DefaultDateTimeFormat, CultureInfo.InvariantCulture, DateTimeStyles.None, out dt));
Assert.AreEqual(1, XmlFormattedEntry.Payload.Elements().Count());
Assert.AreEqual("message", XmlFormattedEntry.Payload.Elements().First().Attribute("Name").Value);
Assert.AreEqual(payloadMsg, XmlFormattedEntry.Payload.Elements().First().Value);
}
public void EventWithPayloadKeywrdsNoMessageInJson()
{
var logger = MockEventSrcForJson.Logger;
string rawOutput = string.Empty;
using (var listener = new InMemoryEventListener()
{
Formatter = new JsonEventTextFormatter()
})
{
listener.EnableEvents(logger, EventLevel.LogAlways, MockEventSrcForJson.Keywords.Errors);
try
{
logger.UsingKeywords(MockEventSrcForJson.LogMessage, long.MaxValue);
rawOutput = Encoding.Default.GetString(listener.Stream.ToArray());
}
finally
{
listener.DisableEvents(logger);
}
}
var entries = JsonConvert.DeserializeObject <TestEventEntry[]>("[" + rawOutput + "]");
var entry = entries.First();
Assert.IsFalse(rawOutput.StartsWith("{\r\n")); // No Formatting (Default)
Assert.AreEqual <Guid>(EventSource.GetGuid(typeof(MockEventSrcForJson)), entry.ProviderId);
Assert.AreEqual <int>(MockEventSrcForJson.UsingKeywordsEventID, entry.EventId);
Assert.AreEqual <EventLevel>(EventLevel.Informational, entry.Level);
Assert.AreEqual <string>("None", entry.EventKeywords.ToString());
Assert.AreEqual <EventOpcode>(EventOpcode.Start, entry.Opcode);
Assert.AreEqual <int>(System.Diagnostics.Process.GetCurrentProcess().Id, entry.ProcessId);
Assert.AreEqual <int>(ThreadHelper.GetCurrentUnManagedThreadId(), entry.ThreadId);
Assert.AreEqual <byte>(0, entry.Version);
Assert.AreEqual <EventTask>(MockEventSrcForJson.Tasks.DBQuery, entry.Task);
Assert.AreEqual(null, entry.Message);
Assert.AreEqual(2, entry.Payload.Count);
StringAssert.Contains(entry.Payload.First().ToString(), MockEventSrcForJson.LogMessage);
StringAssert.Contains(entry.Payload.Last().ToString(), long.MaxValue.ToString());
}
public void ShouldWriteTimestampWithDefaultDateTimeFormatWhenNull()
{
var formatter = new EventTextFormatter(EventTextFormatter.DashSeparator)
{
DateTimeFormat = null
};
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(Logger, EventLevel.LogAlways, Keywords.All);
Logger.Failure("error");
var logged = listener.ToString();
var lookup = "Timestamp : ";
var lookupIndex = logged.IndexOf(lookup);
var dateLength = logged.IndexOf("\r\n", lookupIndex) - lookupIndex - lookup.Length;
var ts = logged.Substring(lookupIndex + lookup.Length, dateLength);
DateTime dt;
Assert.IsTrue(DateTime.TryParseExact(ts, "o", CultureInfo.InvariantCulture, DateTimeStyles.None, out dt));
}
}
protected override void Given()
{
listener = new InMemoryEventListener() { Formatter = new XmlEventTextFormatter(EventTextFormatting.None) };
listener.EnableEvents(Logger, EventLevel.LogAlways);
}
protected override void Given()
{
this.MockConsole = new MockConsoleOutput();
// Will always throw
this.Listener = new ObservableEventListener();
this.Listener.LogToConsole(new MockFormatter() { BeforeWriteEventAction = (f) => { throw new InvalidOperationException(); } });
this.Listener.EnableEvents(TestEventSource.Log, EventLevel.LogAlways);
collectErrorsListener = new InMemoryEventListener();
collectErrorsListener.EnableEvents(SemanticLoggingEventSource.Log, EventLevel.Error, SemanticLoggingEventSource.Keywords.Sink);
}
protected override void Given()
{
formatter = new XmlEventTextFormatter(EventTextFormatting.Indented);
listener = new InMemoryEventListener() { Formatter = formatter };
listener.EnableEvents(logger, EventLevel.LogAlways);
}
protected override void Given()
{
formatter = new JsonEventTextFormatter();
listener = new InMemoryEventListener() { Formatter = formatter };
listener.EnableEvents(Logger, EventLevel.LogAlways);
}
public void ShouldWriteTimestampWithDefaultDateTimeFormatWhenNull()
{
var formatter = new EventTextFormatter(EventTextFormatter.DashSeparator) { DateTimeFormat = null };
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(Logger, EventLevel.LogAlways, Keywords.All);
Logger.Failure("error");
var logged = listener.ToString();
var lookup = "Timestamp : ";
var ts = logged.Substring(logged.IndexOf(lookup) + lookup.Length).Replace("\r\n", "");
DateTime dt;
Assert.IsTrue(DateTime.TryParseExact(ts, "o", CultureInfo.InvariantCulture, DateTimeStyles.None, out dt));
}
}
protected override void Given()
{
base.Given();
inMemoryListener = new InMemoryEventListener(new MockFormatter { BeforeWriteEventAction = f => { throw new Exception("unhandled_exception_test"); } });
var sink = new Lazy<IObserver<EventEntry>>(() => inMemoryListener);
sourceSettings = new EventSourceSettings(EventSource.GetName(typeof(MyCompanyEventSource)));
eventSources = new List<EventSourceSettings> { sourceSettings };
sinkSettings = new List<SinkSettings> { new SinkSettings("test", sink, eventSources) };
configuration = new TraceEventServiceConfiguration(sinkSettings);
this.sut = new TraceEventService(configuration);
slabListener = new InMemoryEventListener();
slabListener.EnableEvents(SemanticLoggingEventSource.Log, EventLevel.Error, SemanticLoggingEventSource.Keywords.TraceEvent);
this.sut.Start();
}
public void WritesDetailedOnEventLevel()
{
var formatter = new EventTextFormatter(EventTextFormatter.DashSeparator) { VerbosityThreshold = EventLevel.Critical };
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(Logger, EventLevel.LogAlways, Keywords.All);
Logger.Failure("Summary");
formatter.VerbosityThreshold = EventLevel.Error;
Logger.Failure("Detailed");
var entries = Regex.Split(listener.ToString(), formatter.Header + "\r\n").Where(c => !string.IsNullOrWhiteSpace(c)).ToList();
StringAssert.Contains(entries[0], "EventId : 1");
StringAssert.Contains(entries[0], "Level : Error");
StringAssert.Contains(entries[0], "Payload : [message : Summary]");
StringAssert.Contains(entries[1],
@"ProviderId : 659518be-d338-564b-2759-c63c10ef82e2
EventId : 1
Keywords : 4
Level : Error
Message : Application Failure: Detailed
Opcode : Info
Task : 65533
Version : 0
Payload : [message : Detailed]
EventName : FailureInfo");
}
}
public void WritesCustomHeaderAndFooter()
{
var formatter = new EventTextFormatter("---header---", "___footer___");
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(Logger, EventLevel.LogAlways, Keywords.All);
Logger.Startup();
var contents = listener.ToString();
Assert.IsTrue(contents.Contains(formatter.Header));
Assert.IsTrue(contents.Contains(formatter.Footer));
}
}
public void WritesCustomHeader()
{
var formatter = new EventTextFormatter("*** header ***");
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(Logger, EventLevel.LogAlways, Keywords.All);
Logger.DBQueryStart("select * from table");
Assert.IsTrue(listener.ToString().Contains(formatter.Header));
}
}
public void WritesCustomFooter()
{
var formatter = new EventTextFormatter(null, "___footer___");
using (var listener = new InMemoryEventListener(formatter))
{
listener.EnableEvents(Logger, EventLevel.LogAlways, Keywords.All);
Logger.Startup();
Assert.IsTrue(listener.ToString().Contains(formatter.Footer));
}
}
