[BUG-826] Error handling route. route=/hello/:project_name/:tab_id, route-group=perspective, route-path=/hello

PRAMANJ · October 12, 2020, 4:50pm

I am continuously getting above error in log file every 10 Seconds! How to stop it? Why is it coming? Restart Ignition is the only solution?

INFO | jvm 1 | 2020/10/12 22:14:24 | W [g.D.Route ] [16:44:24]: Error handling route. route=/hello/:project_name/:tab_id, route-group=perspective, route-path=/hello/:project_name/:tab_id
INFO | jvm 1 | 2020/10/12 22:14:24 | java.lang.IllegalStateException: Could not find the web session
INFO | jvm 1 | 2020/10/12 22:14:24 | at com.inductiveautomation.perspective.gateway.comm.Routes.getOrCreateSession(Routes.java:985)
INFO | jvm 1 | 2020/10/12 22:14:24 | at com.inductiveautomation.perspective.gateway.comm.Routes$PerspectiveRouteHandlerAdapter.handle(Routes.java:598)
INFO | jvm 1 | 2020/10/12 22:14:24 | at com.inductiveautomation.ignition.gateway.dataroutes.Route.service(Route.java:252)
INFO | jvm 1 | 2020/10/12 22:14:24 | at com.inductiveautomation.ignition.gateway.dataroutes.RouteGroupImpl.service(RouteGroupImpl.java:61)
INFO | jvm 1 | 2020/10/12 22:14:24 | at com.inductiveautomation.ignition.gateway.dataroutes.RouteGroupCollectionServlet.serviceInternal(RouteGroupCollectionServlet.java:54)
INFO | jvm 1 | 2020/10/12 22:14:24 | at com.inductiveautomation.ignition.gateway.dataroutes.AbstractRouteGroupServlet.service(AbstractRouteGroupServlet.java:38)
INFO | jvm 1 | 2020/10/12 22:14:24 | at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.servlet.ServletHolder$NotAsyncServlet.service(ServletHolder.java:1391)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:760)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:547)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:590)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1607)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1297)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1577)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1212)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.handler.HandlerList.handle(HandlerList.java:59)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:146)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.Server.handle(Server.java:500)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:270)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:313)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:129)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:388)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)
INFO | jvm 1 | 2020/10/12 22:14:24 | at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)
INFO | jvm 1 | 2020/10/12 22:14:24 | at java.base/java.lang.Thread.run(Unknown Source)

PGriffith · October 12, 2020, 4:57pm

Something is trying create a new Perspective session (ie, a tab open in a browser somewhere) and failing to do so. Restarting Perspective or Ignition itself might be warranted; there's not much detail in the stacktrace about the actual error.

PRAMANJ · October 12, 2020, 5:06pm

I haven’t opened any perspective session at all! Just the designer is open, where I am just editing some gateway scripts. Restarting Ignition is the only thing that is stopping this error! The log file is growing by ~6kb every 10 seconds! I have deleted the initial log file, which might have had the cause of the error. May be next time I will try to save and upload here.

But when I close the designer, the log file stops growing!

PGriffith · October 12, 2020, 5:07pm

The designer opens a session automatically on startup to retrieve session properties and to be able to open each view instance.

andrea.tidwell · October 12, 2020, 8:24pm

@PGriffith I’m getting the same exact error and a very similar stack trace with the “Could not find the web session” as the main error. Any ideas how to stop this from happening other than just restarting? It seems restarts only temporarily solve the issue. I’m running Ignition 8.1 RC2. TIA for your help.

PGriffith · October 12, 2020, 8:29pm

No, I’m not sure what the error is without further troubleshooting. You should get in contact with support, if you aren’t already.

Carl.Gould · October 12, 2020, 8:39pm

Does this error only occur while designers are running? Stopping the designer should stop the error. Please confirm.
If you close all running designers and then start a new designer session, does the error re-occur immediately? My suspicion is that this error is stemming from a running Designer that lost its session on the back-end, either because it lost connection for a while or maybe was on a laptop that was put to sleep and then resumed.

PRAMANJ · October 13, 2020, 5:20am

Yes it happens only when designer in ON and it stops when we close the designer

and doesn't re-appear when we start the designer again.

You have hit the nail on the head! That's exactly what happens! Even if laptop goes to sleep automatically due to no activity or manually putting it to sleep.

In fact I have also observed that the whole laptop reboots when we wake up the laptop after a long hibernation (sleep) while Ignition gateway running ! I wanted to raise a new topic for this, as its a big problem when every time (well most of the time), we have to wait for 10 mins for system to reboot (its doesn't have an ssd, its a 3 year old laptop).

I thought its due to leaving designer on while putting laptop to sleep, so I used to close designer but leaving gateway on. The frequency of reboot reduced without designer being ON, but still it used to reboot when gateway is left on (irrespective of the trial period either expired or running state).

dmalan · October 15, 2020, 6:11pm

We have been able to recreate this error and have created a bug for it.

PRAMANJ · October 16, 2020, 4:12am

It will also be nice if you can explore why the system most often reboots after wake up from a log sleep, when Ignition gateway server is left ON. This used to happen less frequently with older versions of Ignition such as 7.9 or even 8 earlier versions. With the latest version of Ignition 8, it happens almost every time after wake up from sleep. If we shutdown the server and close the designer before sleep, system doesn’t reboot.

Is there a way you can halt the server from running when system is put to sleep or it automatically goes to sleep after no activity. I know its difficult to implement and is not relevant for a production server ! But just a wish , it will save lot of time for development servers!