dotnet / dotnet/orleans

A pending request occurred while testing

Open
#7,342 1 comment 0 reactions 1 assignee Claimed by @ReubenBond View on GitHub
Needs: investigation 🔍 question
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

Open the contributing guide

Assessment

This issue has not been assessed yet.

Get new issues in your inbox

A short digest of beginner-friendly GitHub issues.