I'm seeing these requests error every 5 minutes. Any suggestions?
- ABP Framework version: v0.7.7
- UI Type: MVC
- Database System: EF Core (SQL Server Azure). Also Azure Cache for Redis
- Tiered (for MVC) or Auth Server Separated (for Angular): Yes
- Exception message and full stack trace:
2024-08-20T17:40:15.7455704Z [17:40:15 INF] Request starting HTTP/1.1 GET http://athletes-dev-api.azurewebsites.net/api/abp/application-configuration?IncludeLocalizationResources=False&api-version=1.0 - null null
2024-08-20T17:40:15.7487798Z [17:40:15 INF] Executing endpoint 'Volo.Abp.AspNetCore.Mvc.ApplicationConfigurations.AbpApplicationConfigurationController.GetAsync (Volo.Abp.AspNetCore.Mvc)'
2024-08-20T17:40:15.7503502Z [17:40:15 INF] Route matched with {area = "abp", action = "Get", controller = "AbpApplicationConfiguration", page = ""}. Executing controller action with signature System.Threading.Tasks.Task1[Volo.Abp.AspNetCore.Mvc.ApplicationConfigurations.ApplicationConfigurationDto] GetAsync(Volo.Abp.AspNetCore.Mvc.ApplicationConfigurations.ApplicationConfigurationRequestOptions) on controller Volo.Abp.AspNetCore.Mvc.ApplicationConfigurations.AbpApplicationConfigurationController (Volo.Abp.AspNetCore.Mvc). 2024-08-20T17:40:15.7590797Z [17:40:15 INF] Executing action method Volo.Abp.AspNetCore.Mvc.ApplicationConfigurations.AbpApplicationConfigurationController.GetAsync (Volo.Abp.AspNetCore.Mvc) - Validation state: Valid 2024-08-20T17:40:16.0304774Z [17:40:16 WRN] The operation was canceled. 2024-08-20T17:40:16.0305783Z System.OperationCanceledException: The operation was canceled. 2024-08-20T17:40:16.0305840Z at System.Threading.CancellationToken.ThrowOperationCanceledException() 2024-08-20T17:40:16.0325149Z at Microsoft.Extensions.Caching.StackExchangeRedis.RedisCache.GetAsync(String key, CancellationToken token) 2024-08-20T17:40:16.0325558Z at Volo.Abp.Caching.DistributedCache
2.GetAsync(TCacheKey key, Nullable1 hideErrors, Boolean considerUow, CancellationToken token) 2024-08-20T17:40:16.0338380Z [17:40:16 ERR] An error occurred using the connection to database 'AthletesSocial-Dev' on server 'tcp:athletessocial-dev.database.windows.net,1433'. 2024-08-20T17:40:16.0422971Z [17:40:16 ERR] ---------- RemoteServiceErrorInfo ---------- 2024-08-20T17:40:16.0423644Z { 2024-08-20T17:40:16.0423687Z "code": null, 2024-08-20T17:40:16.0423726Z "message": "An internal error occurred during your request!", 2024-08-20T17:40:16.0423761Z "details": null, 2024-08-20T17:40:16.0423796Z "data": {}, 2024-08-20T17:40:16.0423831Z "validationErrors": null 2024-08-20T17:40:16.0423862Z } 2024-08-20T17:40:16.0423896Z 2024-08-20T17:40:16.0485509Z [17:40:16 ERR] A task was canceled. 2024-08-20T17:40:16.0486060Z System.Threading.Tasks.TaskCanceledException: A task was canceled. 2024-08-20T17:40:16.0486116Z at Microsoft.EntityFrameworkCore.Storage.RelationalConnection.OpenInternalAsync(Boolean errorsExpected, CancellationToken cancellationToken) 2024-08-20T17:40:16.0486159Z at Microsoft.EntityFrameworkCore.Storage.RelationalConnection.OpenInternalAsync(Boolean errorsExpected, CancellationToken cancellationToken) 2024-08-20T17:40:16.0486205Z at Microsoft.EntityFrameworkCore.Storage.RelationalConnection.OpenAsync(CancellationToken cancellationToken, Boolean errorsExpected) 2024-08-20T17:40:16.0486252Z at Microsoft.EntityFrameworkCore.Storage.RelationalCommand.ExecuteReaderAsync(RelationalCommandParameterObject parameterObject, CancellationToken cancellationToken) 2024-08-20T17:40:16.0486333Z at Microsoft.EntityFrameworkCore.Query.Internal.SplitQueryingEnumerable
1.AsyncEnumerator.InitializeReaderAsync(AsyncEnumerator enumerator, CancellationToken cancellationToken)
2024-08-20T17:40:16.0486385Z at Microsoft.EntityFrameworkCore.SqlServer.Storage.Internal.SqlServerExecutionStrategy.ExecuteAsync[TState,TResult](TState state, Func4 operation, Func
4 verifySucceeded, CancellationToken cancellationToken)
2024-08-20T17:40:16.0486433Z at Microsoft.EntityFrameworkCore.Query.Internal.SplitQueryingEnumerable1.AsyncEnumerator.MoveNextAsync() 2024-08-20T17:40:16.0486477Z at Microsoft.EntityFrameworkCore.EntityFrameworkQueryableExtensions.ToListAsync[TSource](IQueryable
1 source, CancellationToken cancellationToken)
2024-08-20T17:40:16.0486521Z at Microsoft.EntityFrameworkCore.EntityFrameworkQueryableExtensions.ToListAsync[TSource](IQueryable1 source, CancellationToken cancellationToken) 2024-08-20T17:40:16.0486567Z at Volo.Abp.FeatureManagement.EntityFrameworkCore.EfCoreFeatureValueRepository.GetListAsync(String providerName, String providerKey, CancellationToken cancellationToken) 2024-08-20T17:40:16.0486635Z at Castle.DynamicProxy.AsyncInterceptorBase.ProceedAsynchronous[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo) 2024-08-20T17:40:16.0486680Z at Volo.Abp.Castle.DynamicProxy.CastleAbpMethodInvocationAdapterWithReturnValue
1.ProceedAsync()
2024-08-20T17:40:16.0486724Z at Volo.Abp.Uow.UnitOfWorkInterceptor.InterceptAsync(IAbpMethodInvocation invocation)
2024-08-20T17:40:16.0486773Z at Volo.Abp.Castle.DynamicProxy.CastleAsyncAbpInterceptorAdapter1.InterceptAsync[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo, Func
3 proceed)
2024-08-20T17:40:16.0486832Z at Volo.Abp.FeatureManagement.FeatureManagementStore.SetCacheItemsAsync(String providerName, String providerKey, String currentName, FeatureValueCacheItem currentCacheItem)
2024-08-20T17:40:16.0486884Z at Volo.Abp.FeatureManagement.FeatureManagementStore.GetCacheItemAsync(String name, String providerName, String providerKey)
2024-08-20T17:40:16.0486936Z at Volo.Abp.FeatureManagement.FeatureManagementStore.GetOrNullAsync(String name, String providerName, String providerKey)
2024-08-20T17:40:16.0487007Z at Castle.DynamicProxy.AsyncInterceptorBase.ProceedAsynchronous[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo)
2024-08-20T17:40:16.0487054Z at Volo.Abp.Castle.DynamicProxy.CastleAbpMethodInvocationAdapterWithReturnValue1.ProceedAsync() 2024-08-20T17:40:16.0487100Z at Volo.Abp.Uow.UnitOfWorkInterceptor.InterceptAsync(IAbpMethodInvocation invocation) 2024-08-20T17:40:16.0487151Z at Volo.Abp.Castle.DynamicProxy.CastleAsyncAbpInterceptorAdapter
1.InterceptAsync[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo, Func3 proceed) 2024-08-20T17:40:16.0487197Z at Volo.Abp.Features.TenantFeatureValueProvider.GetOrNullAsync(FeatureDefinition feature) 2024-08-20T17:40:16.0487244Z at Volo.Abp.Features.FeatureChecker.GetOrNullValueFromProvidersAsync(IEnumerable
1 providers, FeatureDefinition feature)
2024-08-20T17:40:16.0487287Z at Volo.Abp.Features.FeatureChecker.GetOrNullAsync(String name)
2024-08-20T17:40:16.0487358Z at Volo.Abp.AspNetCore.Mvc.ApplicationConfigurations.AbpApplicationConfigurationAppService.GetFeaturesConfigAsync()
2024-08-20T17:40:16.0487408Z at Volo.Abp.AspNetCore.Mvc.ApplicationConfigurations.AbpApplicationConfigurationAppService.GetAsync(ApplicationConfigurationRequestOptions options)
2024-08-20T17:40:16.0487457Z at Castle.DynamicProxy.AsyncInterceptorBase.ProceedAsynchronous[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo)
2024-08-20T17:40:16.0487503Z at Volo.Abp.Castle.DynamicProxy.CastleAbpMethodInvocationAdapterWithReturnValue1.ProceedAsync() 2024-08-20T17:40:16.0487549Z at Volo.Abp.GlobalFeatures.GlobalFeatureInterceptor.InterceptAsync(IAbpMethodInvocation invocation) 2024-08-20T17:40:16.0495117Z at Volo.Abp.Castle.DynamicProxy.CastleAsyncAbpInterceptorAdapter
1.InterceptAsync[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo, Func3 proceed) 2024-08-20T17:40:16.0495598Z at Castle.DynamicProxy.AsyncInterceptorBase.ProceedAsynchronous[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo) 2024-08-20T17:40:16.0495649Z at Volo.Abp.Castle.DynamicProxy.CastleAbpMethodInvocationAdapterWithReturnValue
1.ProceedAsync()
2024-08-20T17:40:16.0495694Z at Volo.Abp.Auditing.AuditingInterceptor.ProceedByLoggingAsync(IAbpMethodInvocation invocation, AbpAuditingOptions options, IAuditingHelper auditingHelper, IAuditLogScope auditLogScope)
2024-08-20T17:40:16.0495732Z at Volo.Abp.Auditing.AuditingInterceptor.InterceptAsync(IAbpMethodInvocation invocation)
2024-08-20T17:40:16.0495782Z at Volo.Abp.Castle.DynamicProxy.CastleAsyncAbpInterceptorAdapter1.InterceptAsync[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo, Func
3 proceed)
2024-08-20T17:40:16.0495832Z at Castle.DynamicProxy.AsyncInterceptorBase.ProceedAsynchronous[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo)
2024-08-20T17:40:16.0495893Z at Volo.Abp.Castle.DynamicProxy.CastleAbpMethodInvocationAdapterWithReturnValue1.ProceedAsync() 2024-08-20T17:40:16.0495939Z at Volo.Abp.Validation.ValidationInterceptor.InterceptAsync(IAbpMethodInvocation invocation) 2024-08-20T17:40:16.0495983Z at Volo.Abp.Castle.DynamicProxy.CastleAsyncAbpInterceptorAdapter
1.InterceptAsync[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo, Func3 proceed) 2024-08-20T17:40:16.0496024Z at Castle.DynamicProxy.AsyncInterceptorBase.ProceedAsynchronous[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo) 2024-08-20T17:40:16.0496067Z at Volo.Abp.Castle.DynamicProxy.CastleAbpMethodInvocationAdapterWithReturnValue
1.ProceedAsync()
2024-08-20T17:40:16.0496109Z at Volo.Abp.Uow.UnitOfWorkInterceptor.InterceptAsync(IAbpMethodInvocation invocation)
2024-08-20T17:40:16.0496159Z at Volo.Abp.Castle.DynamicProxy.CastleAsyncAbpInterceptorAdapter1.InterceptAsync[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo, Func
3 proceed)
2024-08-20T17:40:16.0496226Z at Volo.Abp.AspNetCore.Mvc.ApplicationConfigurations.AbpApplicationConfigurationController.GetAsync(ApplicationConfigurationRequestOptions options)
2024-08-20T17:40:16.0496270Z at lambda_method2773(Closure, Object)
2024-08-20T17:40:16.0496327Z at Microsoft.AspNetCore.Mvc.Infrastructure.ActionMethodExecutor.AwaitableObjectResultExecutor.Execute(ActionContext actionContext, IActionResultTypeMapper mapper, ObjectMethodExecutor executor, Object controller, Object[] arguments)
2024-08-20T17:40:16.0496377Z at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.<InvokeActionMethodAsync>g__Logged|12_1(ControllerActionInvoker invoker)
2024-08-20T17:40:16.0496445Z at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.<InvokeNextActionFilterAsync>g__Awaited|10_0(ControllerActionInvoker invoker, Task lastTask, State next, Scope scope, Object state, Boolean isCompleted)
2024-08-20T17:40:16.0496494Z at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.Rethrow(ActionExecutedContextSealed context)
2024-08-20T17:40:16.0496564Z at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.Next(State& next, Scope& scope, Object& state, Boolean& isCompleted)
2024-08-20T17:40:16.0500601Z at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.<InvokeInnerFilterAsync>g__Awaited|13_0(ControllerActionInvoker invoker, Task lastTask, State next, Scope scope, Object state, Boolean isCompleted)
2024-08-20T17:40:16.0500902Z at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.<InvokeNextExceptionFilterAsync>g__Awaited|26_0(ResourceInvoker invoker, Task lastTask, State next, Scope scope, Object state, Boolean isCompleted)
2024-08-20T17:40:16.0500957Z [17:40:16 INF] Executing ObjectResult, writing value of type 'Volo.Abp.Http.RemoteServiceErrorResponse'.
2024-08-20T17:40:16.0501000Z [17:40:16 INF] Executed action Volo.Abp.AspNetCore.Mvc.ApplicationConfigurations.AbpApplicationConfigurationController.GetAsync (Volo.Abp.AspNetCore.Mvc) in 292.4057ms
2024-08-20T17:40:16.0501043Z [17:40:16 INF] Executed endpoint 'Volo.Abp.AspNetCore.Mvc.ApplicationConfigurations.AbpApplicationConfigurationController.GetAsync (Volo.Abp.AspNetCore.Mvc)'
2024-08-20T17:40:16.0831419Z [17:40:16 INF] Request finished HTTP/1.1 GET https://athletes-dev-api.azurewebsites.net/api/abp/application-configuration?IncludeLocalizationResources=False&api-version=1.0 - 499 null application/json; charset=utf-8 337.9846ms
Will post second stack trace in comment.
- Steps to reproduce the issue:
Wait 5 minutes, check log.
4 Answer(s)
-
0
2024-08-20T17:40:22.7843968Z [17:40:22 INF] Request starting HTTP/1.1 GET http://athletes-dev-api.azurewebsites.net/api/abp/application-localization?CultureName=en&OnlyDynamics=True&api-version=1.0 - null null 2024-08-20T17:40:22.7868161Z [17:40:22 INF] Executing endpoint 'Volo.Abp.AspNetCore.Mvc.ApplicationConfigurations.AbpApplicationLocalizationController.GetAsync (Volo.Abp.AspNetCore.Mvc)' 2024-08-20T17:40:22.7872464Z [17:40:22 INF] Route matched with {area = "abp", action = "Get", controller = "AbpApplicationLocalization", page = ""}. Executing controller action with signature System.Threading.Tasks.Task
1[Volo.Abp.AspNetCore.Mvc.ApplicationConfigurations.ApplicationLocalizationDto] GetAsync(Volo.Abp.AspNetCore.Mvc.ApplicationConfigurations.ApplicationLocalizationRequestDto) on controller Volo.Abp.AspNetCore.Mvc.ApplicationConfigurations.AbpApplicationLocalizationController (Volo.Abp.AspNetCore.Mvc). 2024-08-20T17:40:22.7917352Z [17:40:22 INF] Executing action method Volo.Abp.AspNetCore.Mvc.ApplicationConfigurations.AbpApplicationLocalizationController.GetAsync (Volo.Abp.AspNetCore.Mvc) - Validation state: Valid 2024-08-20T17:40:22.9678591Z [17:40:22 WRN] The operation was canceled. 2024-08-20T17:40:22.9679189Z System.OperationCanceledException: The operation was canceled. 2024-08-20T17:40:22.9679294Z at System.Threading.CancellationToken.ThrowOperationCanceledException() 2024-08-20T17:40:22.9697635Z at Microsoft.Extensions.Caching.StackExchangeRedis.RedisCache.RefreshAsync(IDatabase cache, String key, Nullable
1 absExpr, Nullable1 sldExpr, CancellationToken token) 2024-08-20T17:40:22.9698105Z at Microsoft.Extensions.Caching.StackExchangeRedis.RedisCache.GetAndRefreshAsync(String key, Boolean getData, CancellationToken token) 2024-08-20T17:40:22.9732985Z at Microsoft.Extensions.Caching.StackExchangeRedis.RedisCache.GetAsync(String key, CancellationToken token) 2024-08-20T17:40:22.9733336Z at Volo.Abp.Caching.DistributedCache
2.GetAsync(TCacheKey key, Nullable1 hideErrors, Boolean considerUow, CancellationToken token) 2024-08-20T17:40:23.0045311Z [17:40:23 ERR] ---------- RemoteServiceErrorInfo ---------- 2024-08-20T17:40:23.0046032Z { 2024-08-20T17:40:23.0046082Z "code": null, 2024-08-20T17:40:23.0046118Z "message": "An internal error occurred during your request!", 2024-08-20T17:40:23.0046152Z "details": null, 2024-08-20T17:40:23.0046184Z "data": {}, 2024-08-20T17:40:23.0046219Z "validationErrors": null 2024-08-20T17:40:23.0046258Z } 2024-08-20T17:40:23.0046291Z 2024-08-20T17:40:23.0063118Z [17:40:23 ERR] A task was canceled. 2024-08-20T17:40:23.0063549Z System.Threading.Tasks.TaskCanceledException: A task was canceled. 2024-08-20T17:40:23.0063596Z at Volo.Abp.Threading.SemaphoreSlimExtensions.LockAsync(SemaphoreSlim semaphoreSlim, CancellationToken cancellationToken) 2024-08-20T17:40:23.0063640Z at Volo.Abp.Caching.DistributedCache
2.GetOrAddAsync(TCacheKey key, Func1 factory, Func
1 optionsFactory, Nullable1 hideErrors, Boolean considerUow, CancellationToken token) 2024-08-20T17:40:23.0063680Z at Volo.Abp.LanguageManagement.DynamicResourceLocalizer.FillAsync(LocalizationResourceBase resource, String cultureName, Dictionary
2 dictionary) 2024-08-20T17:40:23.0063724Z at Volo.Abp.Localization.LocalizationResourceContributorList.FillAsync(String cultureName, Dictionary2 dictionary, Boolean includeDynamicContributors) 2024-08-20T17:40:23.0063801Z at Volo.Abp.Localization.AbpDictionaryBasedStringLocalizer.GetAllStringsAsync(String cultureName, Boolean includeParentCultures, Boolean includeBaseLocalizers, Boolean includeDynamicContributors) 2024-08-20T17:40:23.0063851Z at Volo.Abp.Localization.AbpDictionaryBasedStringLocalizer.GetAllStringsAsync(Boolean includeParentCultures, Boolean includeBaseLocalizers, Boolean includeDynamicContributors) 2024-08-20T17:40:23.0063901Z at Volo.Abp.Localization.AbpStringLocalizerExtensions.GetAllStringsAsync(IStringLocalizer stringLocalizer, Boolean includeParentCultures, Boolean includeBaseLocalizers, Boolean includeDynamicContributors) 2024-08-20T17:40:23.0063948Z at Volo.Abp.AspNetCore.Mvc.ApplicationConfigurations.AbpApplicationLocalizationAppService.GetAsync(ApplicationLocalizationRequestDto input) 2024-08-20T17:40:23.0063993Z at Castle.DynamicProxy.AsyncInterceptorBase.ProceedAsynchronous[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo) 2024-08-20T17:40:23.0064032Z at Volo.Abp.Castle.DynamicProxy.CastleAbpMethodInvocationAdapterWithReturnValue
1.ProceedAsync() 2024-08-20T17:40:23.0064090Z at Volo.Abp.GlobalFeatures.GlobalFeatureInterceptor.InterceptAsync(IAbpMethodInvocation invocation) 2024-08-20T17:40:23.0064143Z at Volo.Abp.Castle.DynamicProxy.CastleAsyncAbpInterceptorAdapter1.InterceptAsync[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo, Func
3 proceed) 2024-08-20T17:40:23.0064182Z at Castle.DynamicProxy.AsyncInterceptorBase.ProceedAsynchronous[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo) 2024-08-20T17:40:23.0064223Z at Volo.Abp.Castle.DynamicProxy.CastleAbpMethodInvocationAdapterWithReturnValue1.ProceedAsync() 2024-08-20T17:40:23.0064270Z at Volo.Abp.Auditing.AuditingInterceptor.ProceedByLoggingAsync(IAbpMethodInvocation invocation, AbpAuditingOptions options, IAuditingHelper auditingHelper, IAuditLogScope auditLogScope) 2024-08-20T17:40:23.0064312Z at Volo.Abp.Auditing.AuditingInterceptor.InterceptAsync(IAbpMethodInvocation invocation) 2024-08-20T17:40:23.0064361Z at Volo.Abp.Castle.DynamicProxy.CastleAsyncAbpInterceptorAdapter
1.InterceptAsync[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo, Func3 proceed) 2024-08-20T17:40:23.0064431Z at Castle.DynamicProxy.AsyncInterceptorBase.ProceedAsynchronous[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo) 2024-08-20T17:40:23.0064480Z at Volo.Abp.Castle.DynamicProxy.CastleAbpMethodInvocationAdapterWithReturnValue
1.ProceedAsync() 2024-08-20T17:40:23.0064528Z at Volo.Abp.Validation.ValidationInterceptor.InterceptAsync(IAbpMethodInvocation invocation) 2024-08-20T17:40:23.0064575Z at Volo.Abp.Castle.DynamicProxy.CastleAsyncAbpInterceptorAdapter1.InterceptAsync[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo, Func
3 proceed) 2024-08-20T17:40:23.0064624Z at Castle.DynamicProxy.AsyncInterceptorBase.ProceedAsynchronous[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo) 2024-08-20T17:40:23.0064674Z at Volo.Abp.Castle.DynamicProxy.CastleAbpMethodInvocationAdapterWithReturnValue1.ProceedAsync() 2024-08-20T17:40:23.0064724Z at Volo.Abp.Uow.UnitOfWorkInterceptor.InterceptAsync(IAbpMethodInvocation invocation) 2024-08-20T17:40:23.0064794Z at Volo.Abp.Castle.DynamicProxy.CastleAsyncAbpInterceptorAdapter
1.InterceptAsync[TResult](IInvocation invocation, IInvocationProceedInfo proceedInfo, Func`3 proceed) -
0
A task was canceled.
seems like the request was canceled
-
0
It's a recurring task - something system related. How do I debug what it could be?
-
0
It's a recurring task - something system related
Here is the option to control it https://github.com/abpframework/abp/blob/dev/framework/src/Volo.Abp.AspNetCore.Mvc.Client/Volo/Abp/AspNetCore/Mvc/Client/AbpAspNetCoreMvcClientCacheOptions.cs#L11