A pending request occurred while testing
- Dominant language
- C#
- Stars
- 10.9k
- Forks
- 2.1k
- Avg merge
- 15h 1m
- Merged PRs (30d)
- 345
Description
I did a cluster test. After running several more times, a pending request will appear, but I don't think I have a circular reference or deadlock reference, because the pending request does not appear every time. Please help to see what causes it. Thank you very much
use .net5 framework
pending request logs:
dbug: Orleans.Runtime.MessageFactory[0]
Creating NewPlacement Request S192.168.3.108:20000:371741975*grn/LibTest.Actors.BusinessMemberApplyEduClass/90a680c4-ee99-4469-add1-c26bdb4ac539@df8e729e->S192.168.3.108:30000:371741975*grn/LibTest.Actors.BusinessProcessApplyEduClass/38def660-7ad7-433f-b96a-9c1600177ba0@3a4f0aa4 InvokeMethodRequest LibTest.Actors.IBusinessProcessApplyEduClass:ExecuteWork #244 status update with diagnostics [Activation: S192.168.3.108:30000:371741975*grn/LibTest.Actors.BusinessProcessApplyEduClass/38def660-7ad7-433f-b96a-9c1600177ba0-0x8E2363CC@3a4f0aa4 #GrainType=LibTest.Actors.BusinessProcessApplyEduClass Placement=RandomPlacement State=Valid NonReentrancyQueueSize=0 EnqueuedOnDispatcher=0 InFlightCount=1 NumRunning=1 IdlenessTimeSpan=738074.13:40:02.6224585 CollectionAgeLimit=02:00:00], TaskScheduler status: WorkItemGroup:Name=[Activation: S192.168.3.108:30000:371741975*grn/LibTest.Actors.BusinessProcessApplyEduClass/38def660-7ad7-433f-b96a-9c1600177ba0@3a4f0aa4 #GrainType=LibTest.Actors.BusinessProcessApplyEduClass Placement=RandomPlacement State=Valid],WorkGroupStatus=Waiting. Currently QueuedWorkItems=0; Total Enqueued=14; Total processed=14; Quantum expirations=0; TaskRunner=ActivationTaskScheduler-108:Queued=0; Detailed context=<[Activation: S192.168.3.108:30000:371741975*grn/LibTest.Actors.BusinessProcessApplyEduClass/38def660-7ad7-433f-b96a-9c1600177ba0-0x8E2363CC@3a4f0aa4 #GrainType=LibTest.Actors.BusinessProcessApplyEduClass Placement=RandomPlacement State=Valid NonReentrancyQueueSize=0 EnqueuedOnDispatcher=0 InFlightCount=1 NumRunning=1 IdlenessTimeSpan=738074.13:40:02.6225195 CollectionAgeLimit=02:00:00 CurrentlyExecuting=NewPlacement Request S192.168.3.108:20000:371741975*grn/LibTest.Actors.BusinessMemberApplyEduClass/90a680c4-ee99-4469-add1-c26bdb4ac539@df8e729e->S192.168.3.108:30000:371741975*grn/LibTest.Actors.BusinessProcessApplyEduClass/38def660-7ad7-433f-b96a-9c1600177ba0@3a4f0aa4 InvokeMethodRequest LibTest.Actors.IBusinessProcessApplyEduClass:ExecuteWork #244]>, Message NewPlacement Request S192.168.3.108:20000:371741975*grn/LibTest.Actors.BusinessMemberApplyEduClass/90a680c4-ee99-4469-add1-c26bdb4ac539@df8e729e->S192.168.3.108:30000:371741975*grn/LibTest.Actors.BusinessProcessApplyEduClass/38def660-7ad7-433f-b96a-9c1600177ba0@3a4f0aa4 InvokeMethodRequest LibTest.Actors.IBusinessProcessApplyEduClass:ExecuteWork #244 was enqueued 00:00:14.6943259 ago and has now been executing for 00:00:14.6917922.
info: Orleans.Runtime.InsideRuntimeClient[0]
Received status update for pending request, Request: NewPlacement Request S192.168.3.108:20000:371741975*grn/LibTest.Actors.BusinessMemberApplyEduClass/90a680c4-ee99-4469-add1-c26bdb4ac539@df8e729e->S192.168.3.108:30000:371741975*grn/LibTest.Actors.BusinessProcessApplyEduClass/38def660-7ad7-433f-b96a-9c1600177ba0@3a4f0aa4 InvokeMethodRequest LibTest.Actors.IBusinessProcessApplyEduClass:ExecuteWork #244. Status: [Activation: S192.168.3.108:30000:371741975*grn/LibTest.Actors.BusinessProcessApplyEduClass/38def660-7ad7-433f-b96a-9c1600177ba0-0x8E2363CC@3a4f0aa4 #GrainType=LibTest.Actors.BusinessProcessApplyEduClass Placement=RandomPlacement State=Valid NonReentrancyQueueSize=0 EnqueuedOnDispatcher=0 InFlightCount=1 NumRunning=1 IdlenessTimeSpan=738074.13:40:02.6224585 CollectionAgeLimit=02:00:00]
TaskScheduler status: WorkItemGroup:Name=[Activation: S192.168.3.108:30000:371741975*grn/LibTest.Actors.BusinessProcessApplyEduClass/38def660-7ad7-433f-b96a-9c1600177ba0@3a4f0aa4 #GrainType=LibTest.Actors.BusinessProcessApplyEduClass Placement=RandomPlacement State=Valid],WorkGroupStatus=Waiting. Currently QueuedWorkItems=0; Total Enqueued=14; Total processed=14; Quantum expirations=0; TaskRunner=ActivationTaskScheduler-108:Queued=0; Detailed context=<[Activation: S192.168.3.108:30000:371741975*grn/LibTest.Actors.BusinessProcessApplyEduClass/38def660-7ad7-433f-b96a-9c1600177ba0-0x8E2363CC@3a4f0aa4 #GrainType=LibTest.Actors.BusinessProcessApplyEduClass Placement=RandomPlacement State=Valid NonReentrancyQueueSize=0 EnqueuedOnDispatcher=0 InFlightCount=1 NumRunning=1 IdlenessTimeSpan=738074.13:40:02.6225195 CollectionAgeLimit=02:00:00 CurrentlyExecuting=NewPlacement Request S192.168.3.108:20000:371741975*grn/LibTest.Actors.BusinessMemberApplyEduClass/90a680c4-ee99-4469-add1-c26bdb4ac539@df8e729e->S192.168.3.108:30000:371741975*grn/LibTest.Actors.BusinessProcessApplyEduClass/38def660-7ad7-433f-b96a-9c1600177ba0@3a4f0aa4 InvokeMethodRequest LibTest.Actors.IBusinessProcessApplyEduClass:ExecuteWork #244]>
Message NewPlacement Request S192.168.3.108:20000:371741975*grn/LibTest.Actors.BusinessMemberApplyEduClass/90a680c4-ee99-4469-add1-c26bdb4ac539@df8e729e->S192.168.3.108:30000:371741975*grn/LibTest.Actors.BusinessProcessApplyEduClass/38def660-7ad7-433f-b96a-9c1600177ba0@3a4f0aa4 InvokeMethodRequest LibTest.Actors.IBusinessProcessApplyEduClass:ExecuteWork #244 was enqueued 00:00:14.6943259 ago and has now been executing for 00:00:14.6917922.
sourcecode:[LibTest.zip](https://github.com/dotnet/orleans/files/7380642/LibTest.zip)
Contributor guide
Assessment
This issue has not been assessed yet.