Debugging event emission

Author: mdh1418

src/tests/tracing/eventpipe/userevents/dotnet-common.script

@@ -1,2 +1,2 @@
 let Microsoft_Windows_DotNETRuntime_flags = new_dotnet_provider_flags();
-record_dotnet_provider("Microsoft-Windows-DotNETRuntime", 0x80000000000, 4, Microsoft_Windows_DotNETRuntime_flags);
+record_dotnet_provider("Microsoft-Windows-DotNETRuntime", 0x80000000001, 4, Microsoft_Windows_DotNETRuntime_flags);

src/tests/tracing/eventpipe/userevents/userevents.cs

@@ -52,9 +52,14 @@ public static int TestEntryPoint()
                 return -1;
             }
 
-            string traceFilePath = Path.GetTempFileName();
-            File.Delete(traceFilePath); // record-trace requires the output file to not exist
-            traceFilePath = Path.ChangeExtension(traceFilePath, ".nettrace");
+            string traceFilePath = Path.Combine(appBaseDir, "trace.nettrace"); // Path.GetTempFileName();
+            string helixWorkItemDirectory = Environment.GetEnvironmentVariable("HELIX_WORKITEM_UPLOAD_ROOT");
+            if (helixWorkItemDirectory != null && Directory.Exists(helixWorkItemDirectory))
+            {
+                traceFilePath = Path.Combine(helixWorkItemDirectory, "trace.nettrace");
+            }
+            // File.Delete(traceFilePath); // record-trace requires the output file to not exist
+            // traceFilePath = Path.ChangeExtension(traceFilePath, ".nettrace");
 
             ProcessStartInfo recordTraceStartInfo = new();
             recordTraceStartInfo.FileName = "sudo";
@@ -112,7 +117,7 @@ public static int TestEntryPoint()
             traceeProcess.BeginErrorReadLine();
 
             Console.WriteLine($"Waiting for tracee process to exit...");
-            if (!traceeProcess.HasExited && !traceeProcess.WaitForExit(5000))
+            if (!traceeProcess.HasExited && !traceeProcess.WaitForExit(15000))
             {
                 Console.WriteLine($"Tracee process did not exit within the 5s timeout, killing it.");
                 traceeProcess.Kill();
@@ -125,7 +130,7 @@ public static int TestEntryPoint()
                 Console.WriteLine($"Stopping record-trace with SIGINT.");
                 Kill(recordTraceProcess.Id, SIGINT);
                 Console.WriteLine($"Waiting for record-trace to exit...");
-                if (!recordTraceProcess.WaitForExit(20000))
+                if (!recordTraceProcess.WaitForExit(30000))
                 {
                     // record-trace needs to stop gracefully to generate the trace file
                     Console.WriteLine($"record-trace did not exit within the 20s timeout, killing it.");
@@ -147,7 +152,7 @@ public static int TestEntryPoint()
             if (!ValidateTraceeEvents(traceFilePath))
             {
                 Console.Error.WriteLine($"Trace file `{traceFilePath}` does not contain expected events.");
-                UploadTraceFile(traceFilePath);
+                // UploadTraceFile(traceFilePath);
                 return -1;
             }
 
@@ -157,21 +162,32 @@ public static int TestEntryPoint()
         private static bool ValidateTraceeEvents(string traceFilePath)
         {
             using EventPipeEventSource source = new EventPipeEventSource(traceFilePath);
+            bool startEventFound = false;
+            bool stopEventFound = false;
             bool allocationSampledEventFound = false;
 
             source.Dynamic.All += (TraceEvent e) =>
             {
                 if (e.ProviderName == "Microsoft-Windows-DotNETRuntime")
                 {
-                    if (e.EventName == "AllocationSampled" || (e.ID == (TraceEventID)303 && e.EventName.StartsWith("Unknown")))
+                    Console.WriteLine($"Event: {e.ProviderName} - {e.EventName} (ID: {e.ID})");
+                    if (e.EventName == "GC/Start" || (e.ID == (TraceEventID)1 && e.EventName.StartsWith("Unknown")))
+                    {
+                        startEventFound = true;
+                    }
+                    else if (e.EventName == "GC/Stop" || (e.ID == (TraceEventID)2 && e.EventName.StartsWith("Unknown")))
+                    {
+                        stopEventFound = true;
+                    }
+                    else if (e.EventName == "AllocationSampled" || (e.ID == (TraceEventID)303 && e.EventName.StartsWith("Unknown")))
                     {
                         allocationSampledEventFound = true;
                     }
                 }
             };
 
             source.Process();
-            return allocationSampledEventFound;
+            return startEventFound && stopEventFound && allocationSampledEventFound;
         }
 
         private static void UploadTraceFile(string traceFilePath)

src/tests/tracing/eventpipe/userevents/usereventstracee.cs

@@ -14,11 +14,12 @@ public class UserEventsTracee
         public static void Run()
         {
             long startTimestamp = Stopwatch.GetTimestamp();
-            long targetTicks = Stopwatch.Frequency; // 1s
+            long targetTicks = Stopwatch.Frequency * 10; // 10s
 
             while (Stopwatch.GetTimestamp() - startTimestamp < targetTicks)
             {
                 s_array = new byte[1024 * 100];
+                GC.Collect(2);
                 Thread.Sleep(100);
             }
         }