orleans icon indicating copy to clipboard operation
orleans copied to clipboard

A pending request occurred while testing

Open jruinetz opened this issue 2 years ago • 1 comments

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:371741975grn/LibTest.Actors.BusinessMemberApplyEduClass/90a680c4-ee99-4469-add1-c26bdb4ac539@df8e729e->S192.168.3.108:30000:371741975grn/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:371741975grn/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:371741975grn/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:371741975grn/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:371741975grn/LibTest.Actors.BusinessMemberApplyEduClass/90a680c4-ee99-4469-add1-c26bdb4ac539@df8e729e->S192.168.3.108:30000:371741975grn/LibTest.Actors.BusinessProcessApplyEduClass/38def660-7ad7-433f-b96a-9c1600177ba0@3a4f0aa4 InvokeMethodRequest LibTest.Actors.IBusinessProcessApplyEduClass:ExecuteWork #244]>, Message NewPlacement Request S192.168.3.108:20000:371741975grn/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:371741975grn/LibTest.Actors.BusinessMemberApplyEduClass/90a680c4-ee99-4469-add1-c26bdb4ac539@df8e729e->S192.168.3.108:30000:371741975grn/LibTest.Actors.BusinessProcessApplyEduClass/38def660-7ad7-433f-b96a-9c1600177ba0@3a4f0aa4 InvokeMethodRequest LibTest.Actors.IBusinessProcessApplyEduClass:ExecuteWork #244. Status: [Activation: S192.168.3.108:30000:371741975grn/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:371741975grn/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:371741975grn/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:371741975grn/LibTest.Actors.BusinessMemberApplyEduClass/90a680c4-ee99-4469-add1-c26bdb4ac539@df8e729e->S192.168.3.108:30000:371741975grn/LibTest.Actors.BusinessProcessApplyEduClass/38def660-7ad7-433f-b96a-9c1600177ba0@3a4f0aa4 InvokeMethodRequest LibTest.Actors.IBusinessProcessApplyEduClass:ExecuteWork #244]> Message NewPlacement Request S192.168.3.108:20000:371741975grn/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

jruinetz avatar Oct 20 '21 11:10 jruinetz

We've moved this issue to the Backlog. This means that it is not going to be worked on for the coming release. We review items in the backlog at the end of each milestone/release and depending on the team's priority we may reconsider this issue for the following milestone.

ghost avatar Jul 28 '22 23:07 ghost