NebulOuS

[sal] Sal sometimes fails to grant a sessionid, complaining about invalid username/password

Bug #2055335 reported by Andreas Tsagkaropoulos
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
NebulOuS
Fix Released
Medium
Ankica Barisic

Bug Description

While providing the username and password, I get the error below. I Tried to get the logs, using the command

kubectl -n nebulous logs sal-all-6b8bbc54cf-t692h --all-containers

However, the only existing logs were several hours old. The error I get while making the {{protocol}}://{{sal_host}}:{{sal_port}}/sal/pagateway/connect request is the following

<!doctype html><html lang="en"><head><title>HTTP Status 500 – Internal Server Error</title><style type="text/css">body {font-family:Tahoma,Arial,sans-serif;} h1, h2, h3, b {color:white;background-color:#525D76;} h1 {font-size:22px;} h2 {font-size:16px;} h3 {font-size:14px;} p {font-size:12px;} a {color:black;} .line {height:1px;background-color:#525D76;border:none;}</style></head><body><h1>HTTP Status 500 – Internal Server Error</h1><hr class="line" /><p><b>Type</b> Exception Report</p><p><b>Message</b> Request processing failed; nested exception is javax.security.auth.login.LoginException: Incorrect Username&#47;Password</p><p><b>Description</b> The server encountered an unexpected condition that prevented it from fulfilling the request.</p><p><b>Exception</b></p><pre>org.springframework.web.util.NestedServletException: Request processing failed; nested exception is javax.security.auth.login.LoginException: Incorrect Username&#47;Password
 org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:982)
 org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:872)
 javax.servlet.http.HttpServlet.service(HttpServlet.java:696)
 org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:846)
 javax.servlet.http.HttpServlet.service(HttpServlet.java:779)
 org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:53)
 org.apache.logging.log4j.web.Log4jServletFilter.doFilter(Log4jServletFilter.java:71)
</pre><p><b>Root Cause</b></p><pre>javax.security.auth.login.LoginException: Incorrect Username&#47;Password
 org.ow2.proactive.authentication.AuthenticationImpl.authenticate(AuthenticationImpl.java:175)
 org.ow2.proactive.resourcemanager.authentication.RMAuthenticationImpl.login(RMAuthenticationImpl.java:83)
 sun.reflect.GeneratedMethodAccessor1266.invoke(null)
 sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
 java.lang.reflect.Method.invoke(Method.java:498)
 org.objectweb.proactive.core.mop.MethodCall.execute(MethodCall.java:243)
 org.objectweb.proactive.core.body.request.RequestImpl.serveInternal(RequestImpl.java:207)
 org.objectweb.proactive.core.body.request.RequestImpl.serve(RequestImpl.java:153)
 org.objectweb.proactive.core.body.BodyImpl$ActiveLocalBodyStrategy.serveInternal(BodyImpl.java:561)
 org.objectweb.proactive.core.body.BodyImpl$ActiveLocalBodyStrategy.serve(BodyImpl.java:486)
 org.objectweb.proactive.core.body.AbstractBody.serve(AbstractBody.java:417)
 org.objectweb.proactive.Service.serve(Service.java:119)
 org.ow2.proactive.authentication.AuthenticationImpl.runActivity(AuthenticationImpl.java:253)
 org.objectweb.proactive.core.body.ActiveBody.run(ActiveBody.java:167)
 java.lang.Thread.run(Thread.java:750)
 org.ow2.proactive.resourcemanager.authentication.RMAuthentication.login([pamr:&#47;&#47;0&#47;ActiveObject_org.ow2.proactive.resourcemanager.authentication.RMAuthenticationImpl_4f20f2ed-18ddc35889d--7ffe--c96fabee6fee5682-4f20f2ed-18ddc35889d--8000, pamr:&#47;&#47;0&#47;RMAUTHENTICATION])
 (...)(null)
 org.objectweb.proactive.core.body.proxy.AbstractBodyProxy.reifyAsSynchronous(AbstractBodyProxy.java:340)
 org.objectweb.proactive.core.body.proxy.AbstractBodyProxy.invokeOnBody(AbstractBodyProxy.java:159)
 org.objectweb.proactive.core.body.proxy.AbstractBodyProxy.reify(AbstractBodyProxy.java:113)
 pa.stub.org.ow2.proactive.resourcemanager.authentication._StubRMAuthentication.login(_StubRMAuthentication.java)
 org.ow2.proactive.resourcemanager.common.util.RMListenerProxy.init(RMListenerProxy.java:118)
 org.ow2.proactive.resourcemanager.common.util.RMListenerProxy.init(RMListenerProxy.java:111)
 sun.reflect.GeneratedMethodAccessor1301.invoke(null)
 sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
 java.lang.reflect.Method.invoke(Method.java:498)
 org.objectweb.proactive.core.mop.MethodCall.execute(MethodCall.java:243)
 org.objectweb.proactive.core.body.request.RequestImpl.serveInternal(RequestImpl.java:207)
 org.objectweb.proactive.core.body.request.RequestImpl.serve(RequestImpl.java:153)
 org.objectweb.proactive.core.body.BodyImpl$ActiveLocalBodyStrategy.serveInternal(BodyImpl.java:561)
 org.objectweb.proactive.core.body.BodyImpl$ActiveLocalBodyStrategy.serve(BodyImpl.java:486)
 org.objectweb.proactive.core.body.AbstractBody.serve(AbstractBody.java:417)
 org.objectweb.proactive.Service.serve(Service.java:119)
 org.objectweb.proactive.Service.blockingServeOldest(Service.java:203)
 org.objectweb.proactive.Service.blockingServeOldest(Service.java:178)
 org.objectweb.proactive.Service.fifoServing(Service.java:141)
 org.objectweb.proactive.core.body.ActiveBody$FIFORunActive.runActivity(ActiveBody.java:329)
 org.objectweb.proactive.core.body.ActiveBody.run(ActiveBody.java:167)
 java.lang.Thread.run(Thread.java:750)
 org.ow2.proactive.resourcemanager.common.util.RMListenerProxy.init(pamr:&#47;&#47;0&#47;ActiveObject_org.ow2.proactive.resourcemanager.common.util.RMProxyUserInterface_4f20f2ed-18df0d43f89--7567--c96fabee6fee5682-4f20f2ed-18ddc35889d--8000)
 (...)(null)
 org.objectweb.proactive.core.body.proxy.AbstractBodyProxy.reifyAsSynchronous(AbstractBodyProxy.java:340)
 org.objectweb.proactive.core.body.proxy.AbstractBodyProxy.invokeOnBody(AbstractBodyProxy.java:159)
 org.objectweb.proactive.core.body.proxy.AbstractBodyProxy.reify(AbstractBodyProxy.java:113)
 pa.stub.org.ow2.proactive.resourcemanager.common.util._StubRMProxyUserInterface.init(_StubRMProxyUserInterface.java)
 org.ow2.proactive_grid_cloud_portal.common.SchedulerRMProxyFactory.connectToRM(SchedulerRMProxyFactory.java:50)
 org.ow2.proactive_grid_cloud_portal.common.Session.connectToRM(Session.java:162)
 org.ow2.proactive_grid_cloud_portal.rm.RMRest.rmConnect(RMRest.java:180)
 sun.reflect.GeneratedMethodAccessor1407.invoke(null)
 sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
 java.lang.reflect.Method.invoke(Method.java:498)
 org.jboss.resteasy.core.MethodInjectorImpl.invoke(MethodInjectorImpl.java:138)
 org.jboss.resteasy.core.ResourceMethodInvoker.internalInvokeOnTarget(ResourceMethodInvoker.java:546)
 org.jboss.resteasy.core.ResourceMethodInvoker.invokeOnTargetAfterFilter(ResourceMethodInvoker.java:435)
 org.jboss.resteasy.core.ResourceMethodInvoker.lambda$invokeOnTarget$0(ResourceMethodInvoker.java:396)
 org.jboss.resteasy.core.interception.PreMatchContainerRequestContext.filter(PreMatchContainerRequestContext.java:358)
 org.jboss.resteasy.core.ResourceMethodInvoker.invokeOnTarget(ResourceMethodInvoker.java:398)
 org.jboss.resteasy.core.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:365)
 org.jboss.resteasy.core.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:338)
 org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:440)
 org.jboss.resteasy.core.SynchronousDispatcher.lambda$invoke$4(SynchronousDispatcher.java:229)
 org.jboss.resteasy.core.SynchronousDispatcher.lambda$preprocess$0(SynchronousDispatcher.java:135)
 org.jboss.resteasy.core.interception.PreMatchContainerRequestContext.filter(PreMatchContainerRequestContext.java:358)
 org.jboss.resteasy.core.SynchronousDispatcher.preprocess(SynchronousDispatcher.java:138)
 org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:215)
 org.jboss.resteasy.plugins.server.servlet.ServletContainerDispatcher.service(ServletContainerDispatcher.java:245)
 org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.service(HttpServletDispatcher.java:61)
 org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.service(HttpServletDispatcher.java:56)
 javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
 org.eclipse.jetty.servlet.ServletHolder$NotAsync.service(ServletHolder.java:1450)
 org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799)
 org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1656)
 org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:552)
 org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)
 org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600)
 org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
 org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235)
 org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624)
 org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
 org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1440)
 org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
 org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:505)
 org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594)
 org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
 org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1355)
 org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
 org.eclipse.jetty.server.handler.HandlerList.handle(HandlerList.java:59)
 org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
 org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
 org.eclipse.jetty.server.handler.HandlerList.handle(HandlerList.java:59)
 org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
 org.eclipse.jetty.server.Server.handle(Server.java:516)
 org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:487)
 org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:732)
 org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:479)
 org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277)
 org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
 org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
 org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
 org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338)
 org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315)
 org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173)
 org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
 org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409)
 org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883)
 org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034)
 java.lang.Thread.run(Thread.java:750)
</pre><p><b>Note</b> The full stack trace of the root cause is available in the server logs.</p><hr class="line" /><h3>Apache Tomcat/9.0.68</h3></body></html>

Tags: sal
Joanna Chmielewska (joannach)
Changed in nebulous:
assignee: nobody → Ankica Barisic (akki55)
tags: added: sal
Revision history for this message
Ankica Barisic (akki55) wrote :

Hi, we couldn't reproduce this error. If this repeats with the latest image please report it again including the steps used to connect, and how the username and password are transmitted.

Changed in nebulous:
status: New → Incomplete
Revision history for this message
Andreas Tsagkaropoulos (antsag) wrote (last edit ):

The bug was observed in our last meeting with Ali today, Ali saw it and we restarted the SAL in response. if you would like me to report this to a different bug and close this bug I could, but I am not sure if it is fixed.

Revision history for this message
Ankica Barisic (akki55) wrote :

Hi yes, but this is due to an error reported on the board: https://158.39.75.54/projects/nebulous-collaboration-hub/work_packages/434/activity

I reopend it

Revision history for this message
Ankica Barisic (akki55) wrote :

I believe this was fixed, at least not mentioned for a month but we can keep it so if someone again experiences this to:

1. open ProActive and try if the problem comes from there - it is not possible to log in probably because ProActive needs to be restarted

2. if (1) do not solve it take the SAL logs: and mention the environment which was used and how the connection was to be established (e.g. over the postman with port forwarding, or another component)

Is registered as common error: https://openproject.nebulouscloud.eu/projects/nebulous-collaboration-hub/wiki/error-handling

Ankica Barisic (akki55)
Changed in nebulous:
status: Incomplete → Fix Committed
Joanna Chmielewska (joannach)
Changed in nebulous:
status: Fix Committed → Fix Released
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.
Subscribing...

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.