SampleProfilerSampleType test timing out

[MichalStrehovsky]

12:26:02.827 Running test: tracing/eventpipe/eventsvalidation/SampleProfilerSampleType/SampleProfilerSampleType.cmd
Collecting diagnostic information...
Snapshot of processes currently running:
	ID     ProcessName
	1      helix_docker_wo
	8      execute.sh
	12     bash
	15     bash
	18     tracing
	348    bash
	351    bash
	354    SampleProfilerSampleType
	364    .NET EventPipe
Finding all child processes of 'bash' (ID: 348) with name 'corerun'
Checking child process: 'bash' (ID: 351)
Checking child process: 'SampleProfilerSampleType' (ID: 354)
DOTNET_DbgEnableMiniDump is set and the createdump binary does not exist: /root/helix/work/workitem/e/tracing/eventpipe/eventsvalidation/SampleProfilerSampleType//native/createdump

cmdLine:/root/helix/work/workitem/e/tracing/tracing/native/../../eventpipe/eventsvalidation/SampleProfilerSampleType/SampleProfilerSampleType.sh Timed Out (timeout in milliseconds: 3600000 from variable __TestTimeout, start: 5/7/2026 12:26:02 PM, end: 5/7/2026 1:26:02 PM)

Return code:      -100
Raw output file:      /root/helix/work/workitem/uploads/eventpipe/eventsvalidation/SampleProfilerSampleType/output.txt
Raw output:
BEGIN EXECUTION
/root/helix/work/correlation/nativeaottest.sh /root/helix/work/workitem/e/tracing/eventpipe/eventsvalidation/SampleProfilerSampleType/ SampleProfilerSampleType.dll ''
  0.0s: ==TEST STARTING==
  0.0s: Started sending sentinel events...
  0.0s: Connecting to EventPipe...
  0.0s: Creating EventPipeEventSource...
  0.0s: EventPipeEventSource created
  0.0s: Dynamic.All callback registered
  0.0s: Running optional trace validator
  0.0s: Finished running optional trace validator
  0.0s: Starting stream processing...
  0.0s: Saw new provider 'Microsoft-DotNETCore-SampleProfiler'
  0.1s: Saw sentinel event
  0.1s: Stopped sending sentinel events
  0.1s: Starting event generating action...

cmdLine:/root/helix/work/workitem/e/tracing/tracing/native/../../eventpipe/eventsvalidation/SampleProfilerSampleType/SampleProfilerSampleType.sh Timed Out (timeout in milliseconds: 3600000 from variable __TestTimeout, start: 5/7/2026 12:26:02 PM, end: 5/7/2026 1:26:02 PM)
Test Harness Exitcode is : -100
To run the test:
Set up CORE_ROOT and run.
> /root/helix/work/workitem/e/tracing/tracing/native/../../eventpipe/eventsvalidation/SampleProfilerSampleType/SampleProfilerSampleType.sh
Xunit.Sdk.TrueException: DOTNET_DbgEnableMiniDump is set and the createdump binary does not exist: /root/helix/work/workitem/e/tracing/eventpipe/eventsvalidation/SampleProfilerSampleType//native/createdump

cmdLine:/root/helix/work/workitem/e/tracing/tracing/native/../../eventpipe/eventsvalidation/SampleProfilerSampleType/SampleProfilerSampleType.sh Timed Out (timeout in milliseconds: 3600000 from variable __TestTimeout, start: 5/7/2026 12:26:02 PM, end: 5/7/2026 1:26:02 PM)

Return code:      -100
Raw output file:      /root/helix/work/workitem/uploads/eventpipe/eventsvalidation/SampleProfilerSampleType/output.txt
Raw output:
BEGIN EXECUTION
/root/helix/work/correlation/nativeaottest.sh /root/helix/work/workitem/e/tracing/eventpipe/eventsvalidation/SampleProfilerSampleType/ SampleProfilerSampleType.dll ''
  0.0s: ==TEST STARTING==
  0.0s: Started sending sentinel events...
  0.0s: Connecting to EventPipe...
  0.0s: Creating EventPipeEventSource...
  0.0s: EventPipeEventSource created
  0.0s: Dynamic.All callback registered
  0.0s: Running optional trace validator
  0.0s: Finished running optional trace validator
  0.0s: Starting stream processing...
  0.0s: Saw new provider 'Microsoft-DotNETCore-SampleProfiler'
  0.1s: Saw sentinel event
  0.1s: Stopped sending sentinel events
  0.1s: Starting event generating action...

cmdLine:/root/helix/work/workitem/e/tracing/tracing/native/../../eventpipe/eventsvalidation/SampleProfilerSampleType/SampleProfilerSampleType.sh Timed Out (timeout in milliseconds: 3600000 from variable __TestTimeout, start: 5/7/2026 12:26:02 PM, end: 5/7/2026 1:26:02 PM)
Test Harness Exitcode is : -100
To run the test:
Set up CORE_ROOT and run.
> /root/helix/work/workitem/e/tracing/tracing/native/../../eventpipe/eventsvalidation/SampleProfilerSampleType/SampleProfilerSampleType.sh
   at Xunit.Assert.True(Nullable`1, String)
   at TestLibrary.OutOfProcessTest.RunOutOfProcessTest(String, String)
   at Program.<<Main>$>g__TestExecutor22|0_23(StreamWriter, StreamWriter, Program.<>c__DisplayClass0_0&)
13:26:02.862 Failed test: tracing/eventpipe/eventsvalidation/SampleProfilerSampleType/SampleProfilerSampleType.cmd

Build Information

Build: https://dev.azure.com/dnceng-public/cbb18261-c48f-4abb-8651-8cdcb5474649/_build/results?buildId=1411843
Build error leg or test failing: tracing
Pull request:

Error Message

{
  "ErrorMessage": "SampleProfilerSampleType.sh Timed Out",
  "ErrorPattern": "",
  "BuildRetry": false,
  "ExcludeConsoleLog": false
}

Report

Summary

24-Hour Hit Count	7-Day Hit Count	1-Month Count
0	0	0

Known issue validation

Build: 🔎 https://dev.azure.com/dnceng-public/public/_build/results?buildId=1411843
Error message validated: [SampleProfilerSampleType.sh Timed Out]
Result validation: ✅ Known issue matched with the provided build.
Validation performed at: 5/13/2026 1:49:34 AM UTC

Report

Summary

24-Hour Hit Count	7-Day Hit Count	1-Month Count
0	0	0

Known issue validation

Build: 🔎 https://dev.azure.com/dnceng-public/public/_build/results?buildId=1411843
Error message validated: [SampleProfilerSampleType.sh Timed Out]
Result validation: ✅ Known issue matched with the provided build.
Validation performed at: 5/13/2026 1:49:34 AM UTC

[dotnet-policy-service]

Tagging subscribers to this area: @steveisok, @dotnet/area-system-diagnostics-tracing
See info in area-owners.md if you want to be subscribed.

[MichalStrehovsky]

The latest hit is now a lot more interesting:

Raw output file:      /datadisks/disk1/work/BD6B0A3E/w/BEAA0A1D/uploads/eventpipe/eventsvalidation/SampleProfilerSampleType/output.txt
Raw output:
BEGIN EXECUTION
/datadisks/disk1/work/BD6B0A3E/p/nativeaottest.sh /datadisks/disk1/work/BD6B0A3E/w/BEAA0A1D/e/tracing/eventpipe/eventsvalidation/SampleProfilerSampleType/ SampleProfilerSampleType.dll ''
  0.0s: ==TEST STARTING==
  0.0s: Connecting to EventPipe...
  0.0s: Started sending sentinel events...
  0.0s: Creating EventPipeEventSource...
  0.0s: EventPipeEventSource created
  0.0s: Dynamic.All callback registered
  0.0s: Running optional trace validator
  0.0s: Finished running optional trace validator
  0.0s: Starting stream processing...
  0.0s: Saw new provider 'Microsoft-DotNETCore-SampleProfiler'
  0.1s: Saw sentinel event
  0.1s: Stopped sending sentinel events
  0.1s: Starting event generating action...
--------------------------------------------------
Debug Assertion Violation

Expression: '(EventPipeBufferState)buffer->state == EP_BUFFER_STATE_WRITABLE'

File: /__w/1/s/src/native/eventpipe/ep-buffer.c, Line: 83
--------------------------------------------------

cmdLine:/datadisks/disk1/work/BD6B0A3E/w/BEAA0A1D/e/tracing/tracing/native/../../eventpipe/eventsvalidation/SampleProfilerSampleType/SampleProfilerSampleType.sh Timed Out (timeout in milliseconds: 3600000 from variable __TestTimeout, start: 6/10/2026 11:03:00 PM, end: 6/11/2026 12:03:00 AM)
Test Harness Exitcode is : -100
To run the test:
Set up CORE_ROOT and run.
> /datadisks/disk1/work/BD6B0A3E/w/BEAA0A1D/e/tracing/tracing/native/../../eventpipe/eventsvalidation/SampleProfilerSampleType/SampleProfilerSampleType.sh
Xunit.Sdk.TrueException: DOTNET_DbgEnableMiniDump is set and the createdump binary does not exist: /datadisks/disk1/work/BD6B0A3E/w/BEAA0A1D/e/tracing/eventpipe/eventsvalidation/SampleProfilerSampleType//native/createdump

cmdLine:/datadisks/disk1/work/BD6B0A3E/w/BEAA0A1D/e/tracing/tracing/native/../../eventpipe/eventsvalidation/SampleProfilerSampleType/SampleProfilerSampleType.sh Timed Out (timeout in milliseconds: 3600000 from variable __TestTimeout, start: 6/10/2026 11:03:00 PM, end: 6/11/2026 12:03:00 AM)

Don't really understand why hitting an assert still let the test time out instead of killing the process and creating a dump (there is no dump).

[steveisok]

@mdh1418 the timeout is weird. Is there something we can do to better surface the error?