Tomcat Http session failover is not working in 1808.

[former_member641200]

Hi Experts,

We have recently made cluster configuration in our project. As part of this we followed the below steps to configure the session failover .

https://help.sap.com/viewer/d0224eca81e249cb821f2cdf45a82ace/1808/en-US/8be54e7686691014866af4629843...

We made all the changes as mentioned in the document . We are also seeing the success messages at the time of server startup as mentioned in the document.

But we are seeing the below error and as a result the session failover is not working as expected .

Could any one of you help me in resolving the issue.

INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | Jun 26, 2019 11:59:49 PM org.apache.catalina.ha.session.DeltaManager requestCompleted INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | SEVERE: Unable to serialize delta request for sessionid [BB968D1738B0A3674B7C090DD202F606] INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | java.io.NotSerializableException: de.hybris.platform.sap.core.runtime.SAPHybrisSession INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1184) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.io.ObjectOutputStream.writeObject(ObjectOutputStream.java:348) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.util.concurrent.ConcurrentHashMap.writeObject(ConcurrentHashMap.java:1413) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at sun.reflect.GeneratedMethodAccessor1421.invoke(Unknown Source) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.lang.reflect.Method.invoke(Method.java:498) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.io.ObjectStreamClass.invokeWriteObject(ObjectStreamClass.java:1140) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.io.ObjectOutputStream.writeSerialData(ObjectOutputStream.java:1496) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.io.ObjectOutputStream.writeOrdinaryObject(ObjectOutputStream.java:1432) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1178) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.io.ObjectOutputStream.defaultWriteFields(ObjectOutputStream.java:1548) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.io.ObjectOutputStream.writeSerialData(ObjectOutputStream.java:1509) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.io.ObjectOutputStream.writeOrdinaryObject(ObjectOutputStream.java:1432) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1178) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.io.ObjectOutputStream.defaultWriteFields(ObjectOutputStream.java:1548) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.io.ObjectOutputStream.writeSerialData(ObjectOutputStream.java:1509) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.io.ObjectOutputStream.writeOrdinaryObject(ObjectOutputStream.java:1432) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1178) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.io.ObjectOutputStream.writeObject(ObjectOutputStream.java:348) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.catalina.ha.session.DeltaRequest$AttributeInfo.writeExternal(DeltaRequest.java:401) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.catalina.ha.session.DeltaRequest.writeExternal(DeltaRequest.java:294) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.catalina.ha.session.DeltaRequest.serialize(DeltaRequest.java:308) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.catalina.ha.session.DeltaManager.serializeDeltaRequest(DeltaManager.java:594) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.catalina.ha.session.DeltaManager.requestCompleted(DeltaManager.java:979) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.catalina.ha.session.DeltaManager.requestCompleted(DeltaManager.java:946) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.catalina.ha.tcp.ReplicationValve.send(ReplicationValve.java:523) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.catalina.ha.tcp.ReplicationValve.sendMessage(ReplicationValve.java:511) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.catalina.ha.tcp.ReplicationValve.sendSessionReplicationMessage(ReplicationValve.java:493) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.catalina.ha.tcp.ReplicationValve.sendReplicationMessage(ReplicationValve.java:404) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.catalina.ha.tcp.ReplicationValve.invoke(ReplicationValve.java:333) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:650) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:342) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.coyote.ajp.AjpProcessor.service(AjpProcessor.java:486) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:66) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:868) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1459) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | at java.lang.Thread.run(Thread.java:748) INFO | jvm 1 | srvmain | 2019/06/26 23:59:49.960 | INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | Jun 26, 2019 11:59:54 PM org.apache.catalina.ha.session.DeltaManager requestCompleted INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | SEVERE: Unable to serialize delta request for sessionid [BB968D1738B0A3674B7C090DD202F606] INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | java.io.NotSerializableException: de.hybris.platform.sap.core.runtime.SAPHybrisSession INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1184) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.io.ObjectOutputStream.writeObject(ObjectOutputStream.java:348) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.util.concurrent.ConcurrentHashMap.writeObject(ConcurrentHashMap.java:1413) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at sun.reflect.GeneratedMethodAccessor1421.invoke(Unknown Source) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.lang.reflect.Method.invoke(Method.java:498) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.io.ObjectStreamClass.invokeWriteObject(ObjectStreamClass.java:1140) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.io.ObjectOutputStream.writeSerialData(ObjectOutputStream.java:1496) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.io.ObjectOutputStream.writeOrdinaryObject(ObjectOutputStream.java:1432) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1178) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.io.ObjectOutputStream.defaultWriteFields(ObjectOutputStream.java:1548) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.io.ObjectOutputStream.writeSerialData(ObjectOutputStream.java:1509) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.io.ObjectOutputStream.writeOrdinaryObject(ObjectOutputStream.java:1432) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1178) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.io.ObjectOutputStream.defaultWriteFields(ObjectOutputStream.java:1548) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.io.ObjectOutputStream.writeSerialData(ObjectOutputStream.java:1509) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.io.ObjectOutputStream.writeOrdinaryObject(ObjectOutputStream.java:1432) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.io.ObjectOutputStream.writeObject0(ObjectOutputStream.java:1178) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.io.ObjectOutputStream.writeObject(ObjectOutputStream.java:348) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.catalina.ha.session.DeltaRequest$AttributeInfo.writeExternal(DeltaRequest.java:401) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.catalina.ha.session.DeltaRequest.writeExternal(DeltaRequest.java:294) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.catalina.ha.session.DeltaRequest.serialize(DeltaRequest.java:308) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.catalina.ha.session.DeltaManager.serializeDeltaRequest(DeltaManager.java:594) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.catalina.ha.session.DeltaManager.requestCompleted(DeltaManager.java:979) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.catalina.ha.session.DeltaManager.requestCompleted(DeltaManager.java:946) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.catalina.ha.tcp.ReplicationValve.send(ReplicationValve.java:523) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.catalina.ha.tcp.ReplicationValve.sendMessage(ReplicationValve.java:511) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.catalina.ha.tcp.ReplicationValve.sendSessionReplicationMessage(ReplicationValve.java:493) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.catalina.ha.tcp.ReplicationValve.sendReplicationMessage(ReplicationValve.java:404) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.catalina.ha.tcp.ReplicationValve.invoke(ReplicationValve.java:333) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:650) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:342) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.coyote.ajp.AjpProcessor.service(AjpProcessor.java:486) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:66) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:868) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1459) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 | at java.lang.Thread.run(Thread.java:748) INFO | jvm 1 | srvmain | 2019/06/26 23:59:54.440 |

[arvind-kumar_avinash]

Generally, it happens when the applications deployed on the nodes of the cluster are not in sync. Please redeploy the application on each node and try again.

[former_member641200]

Hi Arvind , I appreciate your interest in helping me , I have done the deployment on both nodes with the same build and I still see the same error .

[Former Member]

Hi Sreekar,

It is happening because somewhere in your code, you are storing a SAPHybrisSession (is non Serializable) in the session (or you are storing an object that has a reference to a SAPHybrisSession). Tomcat tries to serialize the full object graph of all active sessions when you shut it down, and then it tries to restore them when you start it back up. The crux of this is that Tomcat uses "normal" java object serialization, which requires all objects to be Serializable.

Could you please test by making SAPHybrisSession as Serializable. if it works you can raise ticket with sap.