[8.0] Long Running Test: System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest

[carlossanlop]

Error Blob

{
  "ErrorMessage": "[Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest'",
  "BuildRetry": false,
  "ErrorPattern": "",
  "ExcludeConsoleLog": false
}

Reproduction Steps

• PR: [release/8.0] JIT: ensure AVX512 ternary operands aren't used twice #91883
• Queue: Libraries Test Run checked coreclr linux_musl arm Release
• Build: https://dev.azure.com/dnceng-public/public/_build/results?buildId=401815&view=logs&j=fd49b2ea-3ee3-5298-d793-bdb9fd631e7e&t=26d44400-a1b8-5ed1-d719-0cc35b38251b&l=55
• Log file: https://helixre8s23ayyeko0k025g8.blob.core.windows.net/dotnet-runtime-refs-pull-91883-merge-1261102f76974c76a0/System.Threading.Tasks.Tests/1/console.dd8797d3.log?helixlogtype=result
• Output:

===========================================================================================================
/root/helix/work/workitem/e /root/helix/work/workitem/e
  Discovering: System.Threading.Tasks.Tests (method display = ClassAndMethod, method display options = None)
  Discovered:  System.Threading.Tasks.Tests (found 597 of 777 test cases)
  Starting:    System.Threading.Tasks.Tests (parallel test collections = on, max threads = 2)
    System.Threading.Tasks.Tests.TaskSchedulerTests.GetTaskSchedulersForDebugger_DebuggerAttached_ReturnsAllSchedulers [SKIP]
      Condition(s) not met: "DebuggerIsAttached"
    System.Threading.Tasks.Tests.TaskSchedulerTests.GetScheduledTasksForDebugger_DebuggerAttached_ReturnsTasksFromCustomSchedulers [SKIP]
      Condition(s) not met: "DebuggerIsAttached"
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:02:09
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:04:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:06:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:08:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:10:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:12:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:14:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:16:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:18:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:20:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:22:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:24:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:26:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:28:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:30:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:32:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:34:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:36:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:38:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:40:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:42:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:44:10

...
[EXECUTION TIMED OUT]
Exit Code:-3Executor timed out after 2700 seconds and was killed
['System.Threading.Tasks.Tests' END OF WORK ITEM LOG: Command timed out, and was killed]

Known issue validation

Build: 🔎 https://dev.azure.com/dnceng-public/public/_build/results?buildId=401815
Error message validated: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest'
Result validation: ❌ Known issue did not match with the provided build.
Validation performed at: 9/11/2023 11:34:25 PM UTC

Report

Summary

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

Tagging subscribers to this area: @dotnet/area-system-threading-tasks
See info in area-owners.md if you want to be subscribed.

Issue Details

---

Error Blob

{
  "ErrorMessage": "[Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest'",
  "BuildRetry": false,
  "ErrorPattern": "",
  "ExcludeConsoleLog": true
}

Reproduction Steps

• PR: [release/8.0] JIT: ensure AVX512 ternary operands aren't used twice #91883
• Queue: Libraries Test Run checked coreclr linux_musl arm Release
• Build: https://dev.azure.com/dnceng-public/public/_build/results?buildId=401815&view=logs&j=fd49b2ea-3ee3-5298-d793-bdb9fd631e7e&t=26d44400-a1b8-5ed1-d719-0cc35b38251b&l=55
• Log file: https://helixre8s23ayyeko0k025g8.blob.core.windows.net/dotnet-runtime-refs-pull-91883-merge-1261102f76974c76a0/System.Threading.Tasks.Tests/1/console.dd8797d3.log?helixlogtype=result
• Output:

===========================================================================================================
/root/helix/work/workitem/e /root/helix/work/workitem/e
  Discovering: System.Threading.Tasks.Tests (method display = ClassAndMethod, method display options = None)
  Discovered:  System.Threading.Tasks.Tests (found 597 of 777 test cases)
  Starting:    System.Threading.Tasks.Tests (parallel test collections = on, max threads = 2)
    System.Threading.Tasks.Tests.TaskSchedulerTests.GetTaskSchedulersForDebugger_DebuggerAttached_ReturnsAllSchedulers [SKIP]
      Condition(s) not met: "DebuggerIsAttached"
    System.Threading.Tasks.Tests.TaskSchedulerTests.GetScheduledTasksForDebugger_DebuggerAttached_ReturnsTasksFromCustomSchedulers [SKIP]
      Condition(s) not met: "DebuggerIsAttached"
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:02:09
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:04:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:06:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:08:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:10:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:12:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:14:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:16:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:18:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:20:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:22:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:24:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:26:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:28:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:30:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:32:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:34:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:36:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:38:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:40:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:42:10
   System.Threading.Tasks.Tests: [Long Running Test] 'System.Threading.Tasks.Tests.TaskPropertiesTests.TaskIDTest', Elapsed: 00:44:10

...
[EXECUTION TIMED OUT]
Exit Code:-3Executor timed out after 2700 seconds and was killed
['System.Threading.Tasks.Tests' END OF WORK ITEM LOG: Command timed out, and was killed]

Author:	carlossanlop
Assignees:	-
Labels:	area-System.Threading.Tasks, blocking-clean-ci, untriaged, Known Build Error
Milestone:	-

[ericstj]

I updated the log query here since it would never actually capture the linked issue.

I'm not sure why this task would run for 40+ minutes, unless something was hung. Thoughts @stephentoub?

runtime/src/libraries/System.Threading.Tasks/tests/Task/TaskPropertiesTests.cs

Line 25 in 20a3350

public static void TaskIDTest()

[stephentoub]

Is this happening on any specific architecture or operating system?

This isn't a great test, either in purpose or implementation. But all the same I don't see anything that should cause it to be particularly problematic (it does potentially block the current thread while waiting for another thread to run something, but that shouldn't hang unless for some reason the pool has been limited to a single thread for some reason).

[jeffschwMSFT]

removing blocking-clean-ci as it has not failed in 30 days

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