Base solution for your next web application
Starts in:
01 DAYS
01 HRS
01 MIN
01 SEC
Open Closed

Application lost response sometimes #5267

User avatar
0
trendline created

It is a very odd behavior, and confused me recent days. The application will lost response sometimes in a random chance, the client cannot get any response from the server.

First, the Tenancy specified access lost response from the server, then if clean the client cookie, it could access to the Host dashboard, but cannot impersonation to any Tenant, if try to impersonation a Tenant, then it will blocked on the redirect screen.

After several tries, the Host dashboard also will lost response from the server, by this time, all site lost response from the server.

The odd thing is all of these behaviors without exception logs to record, the server level or the application level both are looks work good, and the w3p.exe, application dotnet.exe both are working fine.

During the no response time, the application logs recorded request are received by the application, but cannot return any result to client. It will stop after redirect the route to login action of the account controller.

Every time encountered this behavior only restart the application pool could let the application run normally, no other approach can fix it.

Anybody have this issue too?

It is running under Zero v5.5.0, host on IIS 10 on Windows Server 2012 with SQL Server 2017 as the DB server.

Go to accepted answer
38 Answer(s)
  • User Avatar
    0
    trendline created

    I deployed one copy on another server, if don't use it, it works fine, once visit the application it is online.

    I will send to you my code and the logs on the server.

  • User Avatar
    0
    trendline created

    Any suggestion? It still happening, tenant site lost response, but host site works, then if clean the browser cookie Host site will also lost response.

    I think this is related to ABP tenancy, maybe cookie related?

  • User Avatar
    0
    maliming created
    Support Team

    The point is that you need to reproduce this error. Otherwise it is difficult to figure out what happened.

    You can reply to me in Chinese.

  • User Avatar
    0
    trendline created

    我已经加你的QQ了，看到的时候通过一下，下次再发生这种情况的时候，麻烦你在线指导一下，咱们都在一个时区与工作时间内，比较容易沟通，非常感谢。

  • User Avatar
    0
    trendline created

    As below code mentioned, from 16:30 the services work fine, but at 16:38 the services lost response, it keep the status until 16:49 then I restarted the application.

    INFO  2018-07-13 16:30:57,819 [76   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action method Viewtance.SRP.Web.Areas.App.Controllers.DashboardController.Index (Viewtance.SRP.Web.Mvc), returned result Microsoft.AspNetCore.Mvc.ViewResult in 0.0131ms.
INFO  2018-07-13 16:30:57,826 [76   ] Core.Mvc.ViewFeatures.ViewResultExecutor - Executing ViewResult, running view Index.
DEBUG 2018-07-13 16:31:04,956 [102  ] Viewtance.SRP.Web.Chat.SignalR.ChatHub   - A client is disconnected: ty0wZ_0U61OgP8MWpq4dLw
INFO  2018-07-13 16:31:04,957 [102  ] soft.AspNetCore.Hosting.Internal.WebHost - Request finished in 5128581.7246ms 101 
INFO  2018-07-13 16:31:05,618 [102  ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 GET http://jnyk.viewtance.tech/School/Students  
INFO  2018-07-13 16:31:05,679 [102  ] ore.Mvc.Internal.ControllerActionInvoker - Route matched with {area = "School", action = "Index", controller = "Students"}. Executing action Viewtance.SRP.Web.Areas.School.Controllers.StudentsController.Index (Viewtance.SRP.Web.Mvc)
INFO  2018-07-13 16:31:05,700 [102  ] uthorization.DefaultAuthorizationService - Authorization was successful.
INFO  2018-07-13 16:31:05,713 [102  ] ore.Mvc.Internal.ControllerActionInvoker - Executing action method Viewtance.SRP.Web.Areas.School.Controllers.StudentsController.Index (Viewtance.SRP.Web.Mvc) - Validation state: Valid
INFO  2018-07-13 16:31:15,641 [107  ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 GET http://jnyk.viewtance.tech/School/Students  
INFO  2018-07-13 16:31:15,667 [107  ] ore.Mvc.Internal.ControllerActionInvoker - Route matched with {area = "School", action = "Index", controller = "Students"}. Executing action Viewtance.SRP.Web.Areas.School.Controllers.StudentsController.Index (Viewtance.SRP.Web.Mvc)
INFO  2018-07-13 16:31:15,668 [107  ] uthorization.DefaultAuthorizationService - Authorization was successful.
INFO  2018-07-13 16:31:15,680 [107  ] ore.Mvc.Internal.ControllerActionInvoker - Executing action method Viewtance.SRP.Web.Areas.School.Controllers.StudentsController.Index (Viewtance.SRP.Web.Mvc) - Validation state: Valid
INFO  2018-07-13 16:31:18,139 [103  ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 GET http://www.viewtance.tech/  
INFO  2018-07-13 16:31:18,147 [103  ] ore.Mvc.Internal.ControllerActionInvoker - Route matched with {action = "Index", controller = "Home", area = ""}. Executing action Viewtance.SRP.Web.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc)
INFO  2018-07-13 16:31:18,148 [103  ] ore.Mvc.Internal.ControllerActionInvoker - Executing action method Viewtance.SRP.Web.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc) with arguments (, False) - Validation state: Valid
INFO  2018-07-13 16:31:18,148 [103  ] ore.Mvc.Internal.ControllerActionInvoker - Executed action method Viewtance.SRP.Web.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc), returned result Microsoft.AspNetCore.Mvc.RedirectToActionResult in 0.0241ms.
INFO  2018-07-13 16:31:18,165 [103  ] ft.AspNetCore.Mvc.RedirectToActionResult - Executing RedirectResult, redirecting to /App.
INFO  2018-07-13 16:31:18,166 [103  ] ore.Mvc.Internal.ControllerActionInvoker - Executed action Viewtance.SRP.Web.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc) in 18.9194ms
INFO  2018-07-13 16:31:18,166 [103  ] soft.AspNetCore.Hosting.Internal.WebHost - Request finished in 26.3525ms 302 
INFO  2018-07-13 16:31:18,219 [103  ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 GET http://www.viewtance.tech/App  
INFO  2018-07-13 16:31:18,226 [103  ] ore.Mvc.Internal.ControllerActionInvoker - Route matched with {area = "App", action = "Index", controller = "Home"}. Executing action Viewtance.SRP.Web.Areas.App.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc)
INFO  2018-07-13 16:31:18,227 [103  ] uthorization.DefaultAuthorizationService - Authorization was successful.
INFO  2018-07-13 16:31:18,227 [103  ] ore.Mvc.Internal.ControllerActionInvoker - Executing action method Viewtance.SRP.Web.Areas.App.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc) - Validation state: Valid
INFO  2018-07-13 16:31:18,228 [103  ] ore.Mvc.Internal.ControllerActionInvoker - Executed action method Viewtance.SRP.Web.Areas.App.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc), returned result Microsoft.AspNetCore.Mvc.RedirectToActionResult in 0.2199ms.
INFO  2018-07-13 16:31:18,237 [103  ] ft.AspNetCore.Mvc.RedirectToActionResult - Executing RedirectResult, redirecting to /App/HostDashboard.
INFO  2018-07-13 16:31:18,237 [103  ] ore.Mvc.Internal.ControllerActionInvoker - Executed action Viewtance.SRP.Web.Areas.App.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc) in 10.8484ms
INFO  2018-07-13 16:31:18,237 [103  ] soft.AspNetCore.Hosting.Internal.WebHost - Request finished in 17.9599ms 302 
INFO  2018-07-13 16:31:38,301 [91   ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 GET http://www.viewtance.tech/App/HostDashboard  
INFO  2018-07-13 16:31:38,309 [91   ] ore.Mvc.Internal.ControllerActionInvoker - Route matched with {area = "App", action = "Index", controller = "HostDashboard"}. Executing action Viewtance.SRP.Web.Areas.App.Controllers.HostDashboardController.Index (Viewtance.SRP.Web.Mvc)
INFO  2018-07-13 16:31:38,310 [91   ] uthorization.DefaultAuthorizationService - Authorization was successful.
INFO  2018-07-13 16:31:38,311 [91   ] ore.Mvc.Internal.ControllerActionInvoker - Executing action method Viewtance.SRP.Web.Areas.App.Controllers.HostDashboardController.Index (Viewtance.SRP.Web.Mvc) - Validation state: Valid
INFO  2018-07-13 16:31:38,311 [91   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action method Viewtance.SRP.Web.Areas.App.Controllers.HostDashboardController.Index (Viewtance.SRP.Web.Mvc), returned result Microsoft.AspNetCore.Mvc.ViewResult in 0.0255ms.
INFO  2018-07-13 16:31:38,325 [41   ] Core.Mvc.ViewFeatures.ViewResultExecutor - Executing ViewResult, running view Index.
INFO  2018-07-13 16:32:36,239 [91   ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 GET http://jnyk.viewtance.tech/School/Students  
INFO  2018-07-13 16:32:36,267 [91   ] ore.Mvc.Internal.ControllerActionInvoker - Route matched with {area = "School", action = "Index", controller = "Students"}. Executing action Viewtance.SRP.Web.Areas.School.Controllers.StudentsController.Index (Viewtance.SRP.Web.Mvc)
INFO  2018-07-13 16:32:36,267 [91   ] uthorization.DefaultAuthorizationService - Authorization was successful.
INFO  2018-07-13 16:32:36,278 [91   ] ore.Mvc.Internal.ControllerActionInvoker - Executing action method Viewtance.SRP.Web.Areas.School.Controllers.StudentsController.Index (Viewtance.SRP.Web.Mvc) - Validation state: Valid
DEBUG 2018-07-13 16:34:22,933 [86   ] Viewtance.SRP.Web.Chat.SignalR.ChatHub   - A client is disconnected: 4MtZEIi9Fc-9_TuXdgreeA
INFO  2018-07-13 16:34:22,934 [86   ] soft.AspNetCore.Hosting.Internal.WebHost - Request finished in 377446.8228ms 101 
INFO  2018-07-13 16:34:49,451 [86   ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 POST http://jnyk.viewtance.tech/signalr-chat/negotiate text/plain;charset=UTF-8 0
INFO  2018-07-13 16:34:49,458 [86   ] soft.AspNetCore.Hosting.Internal.WebHost - Request finished in 6.671ms 200 application/json
INFO  2018-07-13 16:34:52,557 [96   ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 GET http://jnyk.viewtance.tech/signalr-chat?id=Mx5FtDHAZ57kY5iMr4RhMQ  
INFO  2018-07-13 16:34:52,600 [96   ] .AspNetCore.SignalR.HubConnectionContext - Completed connection handshake. Using HubProtocol 'json'.
DEBUG 2018-07-13 16:34:52,612 [96   ] Viewtance.SRP.Web.Chat.SignalR.ChatHub   - A client is connected: {"ConnectionId":"Mx5FtDHAZ57kY5iMr4RhMQ","IpAddress":"1.198.22.195","TenantId":2,"UserId":1,"ConnectTime":"2018-07-13T16:34:52.606903+08:00","Properties":{}}
DEBUG 2018-07-13 16:38:32,942 [78   ] Viewtance.SRP.Web.Chat.SignalR.ChatHub   - A client is disconnected: 1l3J5pKOKsR6WPJ4R0n4WQ
INFO  2018-07-13 16:38:33,916 [78   ] soft.AspNetCore.Hosting.Internal.WebHost - Request finished in 977908.6658ms 101 
DEBUG 2018-07-13 16:38:37,944 [23   ] Viewtance.SRP.Web.Chat.SignalR.ChatHub   - A client is disconnected: Mx5FtDHAZ57kY5iMr4RhMQ
INFO  2018-07-13 16:38:37,945 [23   ] soft.AspNetCore.Hosting.Internal.WebHost - Request finished in 225382.1786ms 101 
INFO  2018-07-13 16:44:36,182 [82   ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 GET http://jnyk.viewtance.tech/  
INFO  2018-07-13 16:44:36,191 [82   ] ore.Mvc.Internal.ControllerActionInvoker - Route matched with {action = "Index", controller = "Home", area = ""}. Executing action Viewtance.SRP.Web.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc)
INFO  2018-07-13 16:44:36,192 [82   ] ore.Mvc.Internal.ControllerActionInvoker - Executing action method Viewtance.SRP.Web.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc) with arguments (, False) - Validation state: Valid
INFO  2018-07-13 16:44:36,192 [82   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action method Viewtance.SRP.Web.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc), returned result Microsoft.AspNetCore.Mvc.RedirectToActionResult in 0.0268ms.
INFO  2018-07-13 16:44:36,209 [82   ] ft.AspNetCore.Mvc.RedirectToActionResult - Executing RedirectResult, redirecting to /App.
INFO  2018-07-13 16:44:36,209 [82   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action Viewtance.SRP.Web.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc) in 18.1895ms
INFO  2018-07-13 16:44:36,209 [82   ] soft.AspNetCore.Hosting.Internal.WebHost - Request finished in 27.0485ms 302 
INFO  2018-07-13 16:44:36,247 [65   ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 GET http://jnyk.viewtance.tech/App  
INFO  2018-07-13 16:44:36,255 [65   ] ore.Mvc.Internal.ControllerActionInvoker - Route matched with {area = "App", action = "Index", controller = "Home"}. Executing action Viewtance.SRP.Web.Areas.App.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc)
INFO  2018-07-13 16:44:36,255 [65   ] uthorization.DefaultAuthorizationService - Authorization was successful.
INFO  2018-07-13 16:44:36,256 [65   ] ore.Mvc.Internal.ControllerActionInvoker - Executing action method Viewtance.SRP.Web.Areas.App.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc) - Validation state: Valid
INFO  2018-07-13 16:44:36,256 [65   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action method Viewtance.SRP.Web.Areas.App.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc), returned result Microsoft.AspNetCore.Mvc.RedirectToActionResult in 0.2366ms.
INFO  2018-07-13 16:44:36,264 [82   ] ft.AspNetCore.Mvc.RedirectToActionResult - Executing RedirectResult, redirecting to /App/Dashboard.
INFO  2018-07-13 16:44:36,265 [82   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action Viewtance.SRP.Web.Areas.App.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc) in 9.7314ms
INFO  2018-07-13 16:44:36,265 [82   ] soft.AspNetCore.Hosting.Internal.WebHost - Request finished in 17.6801ms 302 
INFO  2018-07-13 16:44:39,695 [65   ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 GET http://jnyk.viewtance.tech/  
INFO  2018-07-13 16:44:39,703 [65   ] ore.Mvc.Internal.ControllerActionInvoker - Route matched with {action = "Index", controller = "Home", area = ""}. Executing action Viewtance.SRP.Web.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc)
INFO  2018-07-13 16:44:39,705 [65   ] ore.Mvc.Internal.ControllerActionInvoker - Executing action method Viewtance.SRP.Web.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc) with arguments (, False) - Validation state: Valid
INFO  2018-07-13 16:44:39,705 [65   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action method Viewtance.SRP.Web.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc), returned result Microsoft.AspNetCore.Mvc.RedirectToActionResult in 0.0263ms.
INFO  2018-07-13 16:44:39,716 [65   ] ft.AspNetCore.Mvc.RedirectToActionResult - Executing RedirectResult, redirecting to /App.
INFO  2018-07-13 16:44:39,716 [65   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action Viewtance.SRP.Web.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc) in 12.603ms
INFO  2018-07-13 16:44:39,716 [65   ] soft.AspNetCore.Hosting.Internal.WebHost - Request finished in 21.6656ms 302 
INFO  2018-07-13 16:44:40,421 [65   ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 GET http://jnyk.viewtance.tech/App  
INFO  2018-07-13 16:44:40,429 [65   ] ore.Mvc.Internal.ControllerActionInvoker - Route matched with {area = "App", action = "Index", controller = "Home"}. Executing action Viewtance.SRP.Web.Areas.App.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc)
INFO  2018-07-13 16:44:40,429 [65   ] uthorization.DefaultAuthorizationService - Authorization was successful.
INFO  2018-07-13 16:44:40,430 [65   ] ore.Mvc.Internal.ControllerActionInvoker - Executing action method Viewtance.SRP.Web.Areas.App.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc) - Validation state: Valid
INFO  2018-07-13 16:44:40,431 [65   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action method Viewtance.SRP.Web.Areas.App.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc), returned result Microsoft.AspNetCore.Mvc.RedirectToActionResult in 0.2476ms.
INFO  2018-07-13 16:44:40,440 [65   ] ft.AspNetCore.Mvc.RedirectToActionResult - Executing RedirectResult, redirecting to /App/Dashboard.
INFO  2018-07-13 16:44:40,440 [65   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action Viewtance.SRP.Web.Areas.App.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc) in 11.4837ms
INFO  2018-07-13 16:44:40,441 [65   ] soft.AspNetCore.Hosting.Internal.WebHost - Request finished in 20.0444ms 302 
INFO  2018-07-13 16:44:56,318 [65   ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 GET http://jnyk.viewtance.tech/App/Dashboard  
INFO  2018-07-13 16:44:56,326 [65   ] ore.Mvc.Internal.ControllerActionInvoker - Route matched with {area = "App", action = "Index", controller = "Dashboard"}. Executing action Viewtance.SRP.Web.Areas.App.Controllers.DashboardController.Index (Viewtance.SRP.Web.Mvc)
INFO  2018-07-13 16:44:56,326 [65   ] uthorization.DefaultAuthorizationService - Authorization was successful.
INFO  2018-07-13 16:44:56,327 [65   ] ore.Mvc.Internal.ControllerActionInvoker - Executing action method Viewtance.SRP.Web.Areas.App.Controllers.DashboardController.Index (Viewtance.SRP.Web.Mvc) - Validation state: Valid
INFO  2018-07-13 16:44:56,327 [65   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action method Viewtance.SRP.Web.Areas.App.Controllers.DashboardController.Index (Viewtance.SRP.Web.Mvc), returned result Microsoft.AspNetCore.Mvc.ViewResult in 0.0131ms.
INFO  2018-07-13 16:44:56,340 [65   ] Core.Mvc.ViewFeatures.ViewResultExecutor - Executing ViewResult, running view Index.
INFO  2018-07-13 16:45:01,111 [82   ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 GET http://jnyk.viewtance.tech/App/Dashboard  
INFO  2018-07-13 16:45:01,119 [82   ] ore.Mvc.Internal.ControllerActionInvoker - Route matched with {area = "App", action = "Index", controller = "Dashboard"}. Executing action Viewtance.SRP.Web.Areas.App.Controllers.DashboardController.Index (Viewtance.SRP.Web.Mvc)
INFO  2018-07-13 16:45:01,119 [82   ] uthorization.DefaultAuthorizationService - Authorization was successful.
INFO  2018-07-13 16:45:01,120 [82   ] ore.Mvc.Internal.ControllerActionInvoker - Executing action method Viewtance.SRP.Web.Areas.App.Controllers.DashboardController.Index (Viewtance.SRP.Web.Mvc) - Validation state: Valid
INFO  2018-07-13 16:45:01,120 [82   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action method Viewtance.SRP.Web.Areas.App.Controllers.DashboardController.Index (Viewtance.SRP.Web.Mvc), returned result Microsoft.AspNetCore.Mvc.ViewResult in 0.0131ms.
INFO  2018-07-13 16:45:01,129 [82   ] Core.Mvc.ViewFeatures.ViewResultExecutor - Executing ViewResult, running view Index.
INFO  2018-07-13 16:47:42,857 [15   ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 GET http://jnyk.viewtance.tech/  
INFO  2018-07-13 16:47:42,864 [15   ] ore.Mvc.Internal.ControllerActionInvoker - Route matched with {action = "Index", controller = "Home", area = ""}. Executing action Viewtance.SRP.Web.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc)
INFO  2018-07-13 16:47:42,865 [15   ] ore.Mvc.Internal.ControllerActionInvoker - Executing action method Viewtance.SRP.Web.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc) with arguments (, False) - Validation state: Valid
INFO  2018-07-13 16:47:42,865 [15   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action method Viewtance.SRP.Web.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc), returned result Microsoft.AspNetCore.Mvc.RedirectToActionResult in 0.0237ms.
INFO  2018-07-13 16:47:42,876 [15   ] ft.AspNetCore.Mvc.RedirectToActionResult - Executing RedirectResult, redirecting to /App.
INFO  2018-07-13 16:47:42,876 [15   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action Viewtance.SRP.Web.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc) in 11.4195ms
INFO  2018-07-13 16:47:42,876 [15   ] soft.AspNetCore.Hosting.Internal.WebHost - Request finished in 19.4126ms 302 
INFO  2018-07-13 16:47:42,915 [12   ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 GET http://jnyk.viewtance.tech/App  
INFO  2018-07-13 16:47:42,924 [12   ] ore.Mvc.Internal.ControllerActionInvoker - Route matched with {area = "App", action = "Index", controller = "Home"}. Executing action Viewtance.SRP.Web.Areas.App.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc)
INFO  2018-07-13 16:47:42,924 [12   ] uthorization.DefaultAuthorizationService - Authorization was successful.
INFO  2018-07-13 16:47:42,925 [12   ] ore.Mvc.Internal.ControllerActionInvoker - Executing action method Viewtance.SRP.Web.Areas.App.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc) - Validation state: Valid
INFO  2018-07-13 16:47:42,926 [12   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action method Viewtance.SRP.Web.Areas.App.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc), returned result Microsoft.AspNetCore.Mvc.RedirectToActionResult in 0.2802ms.
INFO  2018-07-13 16:47:42,935 [15   ] ft.AspNetCore.Mvc.RedirectToActionResult - Executing RedirectResult, redirecting to /App/Dashboard.
INFO  2018-07-13 16:47:42,935 [15   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action Viewtance.SRP.Web.Areas.App.Controllers.HomeController.Index (Viewtance.SRP.Web.Mvc) in 11.3618ms
INFO  2018-07-13 16:47:42,936 [15   ] soft.AspNetCore.Hosting.Internal.WebHost - Request finished in 20.3603ms 302 
INFO  2018-07-13 16:47:42,976 [12   ] soft.AspNetCore.Hosting.Internal.WebHost - Request starting HTTP/1.1 GET http://jnyk.viewtance.tech/App/Dashboard  
INFO  2018-07-13 16:47:42,984 [12   ] ore.Mvc.Internal.ControllerActionInvoker - Route matched with {area = "App", action = "Index", controller = "Dashboard"}. Executing action Viewtance.SRP.Web.Areas.App.Controllers.DashboardController.Index (Viewtance.SRP.Web.Mvc)
INFO  2018-07-13 16:47:42,985 [12   ] uthorization.DefaultAuthorizationService - Authorization was successful.
INFO  2018-07-13 16:47:42,986 [12   ] ore.Mvc.Internal.ControllerActionInvoker - Executing action method Viewtance.SRP.Web.Areas.App.Controllers.DashboardController.Index (Viewtance.SRP.Web.Mvc) - Validation state: Valid
INFO  2018-07-13 16:47:42,986 [12   ] ore.Mvc.Internal.ControllerActionInvoker - Executed action method Viewtance.SRP.Web.Areas.App.Controllers.DashboardController.Index (Viewtance.SRP.Web.Mvc), returned result Microsoft.AspNetCore.Mvc.ViewResult in 0.0272ms.
INFO  2018-07-13 16:47:42,995 [10   ] Core.Mvc.ViewFeatures.ViewResultExecutor - Executing ViewResult, running view Index.
DEBUG 2018-07-13 16:49:33,140 [1    ] Abp.Modules.AbpModuleManager             - Loading Abp modules...
DEBUG 2018-07-13 16:49:33,178 [1    ] Abp.Modules.AbpModuleManager             - Found 34 ABP modules in total.
DEBUG 2018-07-13 16:49:33,251 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Viewtance.SRP.Web.Startup.SRPWebMvcModule, Viewtance.SRP.Web.Mvc, Version=5.0.0.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,256 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Viewtance.SRP.Web.SRPWebCoreModule, Viewtance.SRP.Web.Core, Version=5.0.0.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,257 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Viewtance.FM.FMApplicationModule, Viewtance.FM.Application, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,257 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Viewtance.FM.FMCoreModule, Viewtance.FM.Core, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,257 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.Zero.AbpZeroCoreModule, Abp.ZeroCore, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,258 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.Zero.AbpZeroCommonModule, Abp.Zero.Common, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,258 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.AbpKernelModule, Abp, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,258 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.Zero.Ldap.AbpZeroLdapModule, Abp.Zero.Ldap, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,259 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.AutoMapper.AbpAutoMapperModule, Abp.AutoMapper, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,259 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.AspNetZeroCore.AbpAspNetZeroCoreModule, Abp.AspNetZeroCore, Version=1.1.3.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,260 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.MailKit.AbpMailKitModule, Abp.MailKit, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,260 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Viewtance.CRM.CRMApplicationModule, Viewtance.CRM.Application, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,260 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Viewtance.CRM.CRMCoreModule, Viewtance.CRM.Core, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,260 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Viewtance.School.SchoolApplicationModule, Viewtance.School.Application, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,261 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Viewtance.School.SchoolCoreModule, Viewtance.School.Core, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,261 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Viewtance.GA.GAApplicationModule, Viewtance.GA.Application, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,261 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Viewtance.GA.GACoreModule, Viewtance.GA.Core, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,262 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Viewtance.Teaching.TeachingApplicationModule, Viewtance.Teaching.Application, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,262 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Viewtance.Teaching.TeachingCoreModule, Viewtance.Teaching.Core, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,262 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Viewtance.SRP.SRPApplicationModule, Viewtance.SRP.Application, Version=5.0.0.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,263 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Viewtance.SRP.SRPCoreModule, Viewtance.SRP.Core, Version=5.0.0.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,263 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Viewtance.SRP.EntityFrameworkCore.SRPEntityFrameworkCoreModule, Viewtance.SRP.EntityFrameworkCore, Version=5.0.0.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,263 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.Zero.EntityFrameworkCore.AbpZeroCoreEntityFrameworkCoreModule, Abp.ZeroCore.EntityFrameworkCore, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,264 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.EntityFrameworkCore.AbpEntityFrameworkCoreModule, Abp.EntityFrameworkCore, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,264 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.EntityFramework.AbpEntityFrameworkCommonModule, Abp.EntityFramework.Common, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,265 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.Dapper.AbpDapperModule, Abp.Dapper, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,265 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.IdentityServer4.AbpZeroCoreIdentityServerEntityFrameworkCoreModule, Abp.ZeroCore.IdentityServer4.EntityFrameworkCore, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,266 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.IdentityServer4.AbpZeroCoreIdentityServerModule, Abp.ZeroCore.IdentityServer4, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,266 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.AspNetZeroCore.Web.AbpAspNetZeroCoreWebModule, Abp.AspNetZeroCore.Web, Version=1.1.3.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,266 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.AspNetCore.AbpAspNetCoreModule, Abp.AspNetCore, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,266 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.Web.AbpWebCommonModule, Abp.Web.Common, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,267 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.AspNetCore.SignalR.AbpAspNetCoreSignalRModule, Abp.AspNetCore.SignalR, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,267 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.Runtime.Caching.Redis.AbpRedisCacheModule, Abp.RedisCache, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,267 [1    ] Abp.Modules.AbpModuleManager             - Loaded module: Abp.Hangfire.AbpHangfireAspNetCoreModule, Abp.HangFire.AspNetCore, Version=3.7.2.0, Culture=neutral, PublicKeyToken=null
DEBUG 2018-07-13 16:49:33,272 [1    ] Abp.Modules.AbpModuleManager             - 34 modules loaded.
  • User Avatar
    0
    maliming created
    Support Team

    Turning Microsoft's log level to Debug or Trace may reveal more information.

  • User Avatar
    0
    trendline created

    Log settings on IIS?

  • User Avatar
    0
    maliming created
    Support Team

    [https://docs.microsoft.com/zh-cn/aspnet/core/fundamentals/logging/?view=aspnetcore-2.1#settings-file-configuration])

    "System": "Information" "Microsoft": "Information"

    Modify the System and Microsoft log levels.

  • User Avatar
    0
    trendline created

    Is this logging setting could make ABP logs more detail? BTW, I have sent a request to your QQ, please check.

  • User Avatar
    0
    maliming created
    Support Team

    Can make information about Microsoft related components very rich to help debug. My qq answer can be filled in at will.

  • User Avatar
    0
    trendline created

    我还没有看到你的回复，或者你可以加一下我的QQ，我的qq是286285280

  • User Avatar
    0
    trendline created

    It seems related to User login expired, every time the logs record below exception, the application start lose response

    Abp.Authorization.AbpAuthorizationException: [Current user did not login to the application]
   at Abp.Authorization.AuthorizationHelper.AuthorizeAsync(IEnumerable`1 authorizeAttributes)
   at Abp.Authorization.AuthorizationHelper.CheckPermissions(MethodInfo methodInfo, Type type)
   at Abp.Authorization.AuthorizationHelper.AuthorizeAsync(MethodInfo methodInfo, Type type)
   at Abp.AspNetCore.Mvc.Authorization.AbpAuthorizationFilter.OnAuthorizationAsync(AuthorizationFilterContext context)
Abp.Authorization.AbpAuthorizationException: [Current user did not login to the application]
   at Abp.Authorization.AuthorizationHelper.AuthorizeAsync(IEnumerable`1 authorizeAttributes)
   at Abp.Authorization.AuthorizationHelper.CheckPermissions(MethodInfo methodInfo, Type type)
   at Abp.Authorization.AuthorizationHelper.AuthorizeAsync(MethodInfo methodInfo, Type type)
   at Abp.AspNetCore.Mvc.Authorization.AbpAuthorizationFilter.OnAuthorizationAsync(AuthorizationFilterContext context)
  • User Avatar
    0
    trendline created

    Any suggestion? Anybody who can Help me? It seems caused by AbpSession timeout, then no redirect login could reach.

Assignee
No one
Labels
None yet