[JAVA-1972] SocketException handling request, closing client connection: 9001 socket exception [SEND_ERROR] server after re-primary back and forth Created: 21/Sep/15  Updated: 05/Nov/15  Resolved: 05/Nov/15

Status: Closed
Project: Java Driver
Component/s: Cluster Management, Connection Management
Affects Version/s: 2.13.0
Fix Version/s: None

Type: Bug Priority: Major - P3
Reporter: Eyal Sorek Assignee: Unassigned
Resolution: Cannot Reproduce Votes: 0
Labels: SocketException, connections, driver, replicaset
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified
Environment:

App Servers: Scala code,in jetty app server on on linux debian 7.8
DB: mongo 2.6.10 on primary, 2.6.8 on secondary that got elected as primary when original primary rebooted.



 Description   

After a primary db machine did a reboot, a secondary node, next in priority, got elected as primary, and when the original primary came up and got re-elected as primary, the connections from the app servers, that moved first to the secondary node, that became a primary, did not move back to the original primary.
The application got - NullPointerExceptions from all the related DB code, we saw in the secondary node log file errors as below, and new connections were not established on the original primary (which could be accessed by mongo CLI).
Only a re-start of all app servers, caused the app severs to re-connect to the original primary:
Errors from the secondary log file, after it stepped down:

2015-09-21T08:22:23.186+0000 [conn2782255] 172.16.209.10:27017 is now closest at 55ffbe3e:3
2015-09-21T08:22:23.186+0000 [conn2782255] replSet info stepping down as primary secs=1
2015-09-21T08:22:23.187+0000 [conn2782255] replSet relinquishing primary state
2015-09-21T08:22:23.187+0000 [conn2782255] replSet SECONDARY
2015-09-21T08:22:23.187+0000 [conn2782255] replSet closing client sockets after relinquishing primary
2015-09-21T08:22:23.189+0000 [conn2782140] SocketException handling request, closing client connection: 9001 socket exception [SEND_ERROR] server [172.16.213.148:36694]
2015-09-21T08:22:24.261+0000 [rsMgr] not electing self, 172.16.210.10:27017 would veto with '172.16.144.20:27017 has lower priority than 172.16.209.10:27017'
2015-09-21T08:22:27.259+0000 [conn2782065] SocketException handling request, closing client connection: 9001 socket exception [SEND_ERROR] server [172.16.144.188:33313]
2015-09-21T08:22:27.259+0000 [conn2782059] SocketException handling request, closing client connection: 9001 socket exception [SEND_ERROR] server [172.16.22.10:63550]
2015-09-21T08:22:27.259+0000 [conn2782061] SocketException handling request, closing client connection: 9001 socket exception [SEND_ERROR] server [172.16.210.154:62381]
2015-09-21T08:22:27.259+0000 [conn2782247] SocketException handling request, closing client connection: 9001 socket exception [SEND_ERROR] server [172.16.209.76:40453]
2015-09-21T08:22:27.285+0000 [conn2782057] SocketException handling request, closing client connection: 9001 socket exception [SEND_ERROR] server [10.14.6.10:60497]
2015-09-21T08:22:27.486+0000 [conn2782072] SocketException handling request, closing client connection: 9001 socket exception [SEND_ERROR] server [10.12.103.1:52824]
2015-09-21T08:22:29.384+0000 [conn2782256] replSet info voting yea for 172.16.209.10:27017 (8)
2015-09-21T08:22:30.089+0000 [rsMgr] not electing self, 172.16.210.10:27017 would veto with '172.16.144.20:27017 has lower priority than 172.16.209.10:27017'
2015-09-21T08:22:34.324+0000 [rsMgr] not electing self, 172.16.210.10:27017 would veto with '172.16.144.20:27017 is trying to elect itself but 172.16.209.10:27017 is already primary and more up-to-date'
2015-09-21T08:22:34.585+0000 [rsHealthPoll] replSet member 172.16.209.10:27017 is now in state PRIMARY
2015-09-21T08:22:34.688+0000 [rsBackgroundSync] replSet syncing to: 172.16.209.10:27017
2015-09-21T08:22:34.689+0000 [rsBackgroundSync] replset setting syncSourceFeedback to 172.16.209.10:27017



 Comments   
Comment by Jeffrey Yemin [ 05/Nov/15 ]

Closing as "Cannot Reproduce", but if anyone has more information please comment and I'll re-open

Comment by Jeff Mitchell [ 18/Oct/15 ]

It appears that our problem was solved by restarting the primary. But this doesn't seem to be some Java-client-specific issue.

Comment by Jeff Mitchell [ 18/Oct/15 ]

We're seeing this too. All of our clients are Go or EmberJS.

Mongo is 2.6.5 on primary and both secondaries.

An example of logs, on the primary node:

2015-10-18T00:32:35.879+0000 [conn37701] query images.original.files query: { $query: { filename: "5709_5573ad893c6a159105366d337e439ff9.png" }, $orderby: { uploadDate: -1 } } planSummary: COLLSCAN, COLLSCAN ntoskip:0 nscanned:502205 nscannedObjects:502205 keyUpdates:0 numYields:3020 locks(micros) r:3075415750 nreturned:1 reslen:187 1663220ms
2015-10-18T00:32:35.879+0000 [conn37701] SocketException handling request, closing client connection: 9001 socket exception [SEND_ERROR] server [10.20.30.10:38950] 
2015-10-18T00:32:35.881+0000 [conn37537] query images.original.files query: { $query: { filename: "43368_24fcaa0fa5a71227357d81afcfd2af9b.jpeg" }, $orderby: { uploadDate: -1 } } planSummary: COLLSCAN, COLLSCAN ntoskip:0 nscanned:502205 nscannedObjects:502205 keyUpdates:0 numYields:2964 locks(micros) r:3081212449 nreturned:1 reslen:189 1666221ms
2015-10-18T00:32:35.882+0000 [conn37537] SocketException handling request, closing client connection: 9001 socket exception [SEND_ERROR] server [10.20.30.10:38615] 
2015-10-18T00:32:35.882+0000 [conn38011] query images.original.files query: { $query: { filename: "42656_43e81282286410960c0db167ca826598.jpeg" }, $orderby: { uploadDate: -1 } } planSummary: COLLSCAN, COLLSCAN ntoskip:0 nscanned:502205 nscannedObjects:502205 keyUpdates:0 numYields:2984 locks(micros) r:3038899587 nreturned:1 reslen:189 1645839ms
2015-10-18T00:32:35.882+0000 [conn38011] SocketException handling request, closing client connection: 9001 socket exception [SEND_ERROR] server [10.20.30.10:40109] 
2015-10-18T00:32:35.882+0000 [conn38054] query images.original.files query: { $query: { filename: "44001_68a83ffd13b17d891a57b3006fe7a5de.jpeg" }, $orderby: { uploadDate: -1 } } planSummary: COLLSCAN, COLLSCAN ntoskip:0 nscanned:502205 nscannedObjects:502205 keyUpdates:0 numYields:3016 locks(micros) r:3035100441 nreturned:1 reslen:189 1641716ms
2015-10-18T00:32:35.882+0000 [conn38054] SocketException handling request, closing client connection: 9001 socket exception [SEND_ERROR] server [10.20.30.10:40214] 
2015-10-18T00:32:35.883+0000 [conn38038] query images.original.files query: { $query: { filename: "44001_68a83ffd13b17d891a57b3006fe7a5de.jpeg" }, $orderby: { uploadDate: -1 } } planSummary: COLLSCAN, COLLSCAN ntoskip:0 nscanned:502205 nscannedObjects:502205 keyUpdates:0 numYields:3007 locks(micros) r:3034475326 nreturned:1 reslen:189 1643177ms
2015-10-18T00:32:35.883+0000 [conn38038] SocketException handling request, closing client connection: 9001 socket exception [SEND_ERROR] server [10.20.30.10:40177] 
2015-10-18T00:32:35.886+0000 [conn37768] query images.original.files query: { $query: { filename: "1658_709ae92fbd2eadbacb2bf7b1238ce9ab.jpeg" }, $orderby: { uploadDate: -1 } } planSummary: COLLSCAN, COLLSCAN ntoskip:0 nscanned:502205 nscannedObjects:502205 keyUpdates:0 numYields:3003 locks(micros) r:3062726379 nreturned:1 reslen:188 1658971ms
2015-10-18T00:32:35.886+0000 [conn37768] SocketException handling request, closing client connection: 9001 socket exception [SEND_ERROR] server [10.20.30.10:39278] 
2015-10-18T00:32:35.886+0000 [conn38047] query images.original.files query: { $query: { filename: "4217_4aa9341262aae669cbf680b81e83ef3a.jpeg" }, $orderby: { uploadDate: -1 } } planSummary: COLLSCAN, COLLSCAN ntoskip:0 nscanned:502205 nscannedObjects:502205 keyUpdates:0 numYields:2954 locks(micros) r:3035947748 nreturned:1 reslen:188 1642053ms
2015-10-18T00:32:35.886+0000 [conn38047] SocketException handling request, closing client connection: 9001 socket exception [SEND_ERROR] server [10.20.30.10:40199] 

Comment by Jeffrey Yemin [ 08/Oct/15 ]

Hi Eyal,

We're still looking into this. At the moment, based on code examination of the 2.12.0 release, I don't have a theory as to how there could be a NullPointerException on line 551 of DBCursor. The only field referenced on that line is '_it', and the _check() method, called previously in the same method, ensures that _it is initialized before any normal exit. It does this by calling DBCollectionImpl#find(com.mongodb.DBObject, com.mongodb.DBObject, int, int, int, int, com.mongodb.ReadPreference, com.mongodb.DBDecoder), which never returns a null value.

Is it possible that your application is using a subclass of DBCollection or DBCursor that don't offer the same guarantees? That would be very unusual, but it's possible.

Comment by Eyal Sorek [ 24/Sep/15 ]

Hi,
It seems that on the node that was tested now, the driver has still not upgraded to 2.13 and it is still 2.12.
See below the stack trace. We can not upload the log file, since it contains personal information, we can not expose.
I hope the stack trace will be enough for investigation:

 java.lang.NullPointerException: null
	at com.mongodb.DBCursor._hasNext(DBCursor.java:551) ~[mongo-java-driver-2.12.0.jar:na]
	at com.mongodb.DBCursor.hasNext(DBCursor.java:571) ~[mongo-java-driver-2.12.0.jar:na]
	at scala.collection.convert.Wrappers$JIteratorWrapper.hasNext(Wrappers.scala:42) ~[scala-library-2.11.7.jar:na]
	at scala.collection.Iterator$class.foreach(Iterator.scala:742) ~[scala-library-2.11.7.jar:na]
	at scala.collection.AbstractIterator.foreach(Iterator.scala:1194) ~[scala-library-2.11.7.jar:na]
	at scala.collection.IterableLike$class.foreach(IterableLike.scala:72) ~[scala-library-2.11.7.jar:na]
	at scala.collection.AbstractIterable.foreach(Iterable.scala:54) ~[scala-library-2.11.7.jar:na]
	at scala.collection.TraversableLike$class.map(TraversableLike.scala:245) ~[scala-library-2.11.7.jar:na]
	at scala.collection.AbstractTraversable.map(Traversable.scala:104) ~[scala-library-2.11.7.jar:na]
[33m	at com.wixpress.contacts.lists.MongoListsObjectService.query(MongoListsObjectService.scala:294) ~[wix-contacts-server-core-1.514.0-20150921.071006-91.jar:1.514.0-SNAPSHOT] [39m
[33m	at com.wixpress.contacts.lists.DefaultListsFacade.com$wixpress$contacts$lists$DefaultListsFacade$$loadContactsBy(ListsFacade.scala:531) ~[wix-contacts-server-core-1.514.0-20150921.071006-91.jar:1.514.0-SNAPSHOT] [39m
[33m	at com.wixpress.contacts.lists.DefaultListsFacade$$anonfun$loadContact$1.apply(ListsFacade.scala:294) ~[wix-contacts-server-core-1.514.0-20150921.071006-91.jar:1.514.0-SNAPSHOT] [39m
[33m	at com.wixpress.contacts.lists.DefaultListsFacade$$anonfun$loadContact$1.apply(ListsFacade.scala:290) ~[wix-contacts-server-core-1.514.0-20150921.071006-91.jar:1.514.0-SNAPSHOT] [39m
[33m	at com.wixpress.contacts.common.metering.RelicMetrics$class.track(RelicMetrics.scala:13) ~[wix-contacts-server-core-1.514.0-20150921.071006-91.jar:1.514.0-SNAPSHOT] [39m
[33m	at com.wixpress.contacts.lists.DefaultListsFacade.track(ListsFacade.scala:220) ~[wix-contacts-server-core-1.514.0-20150921.071006-91.jar:1.514.0-SNAPSHOT] [39m
[33m	at com.wixpress.contacts.lists.DefaultListsFacade.loadContact(ListsFacade.scala:290) ~[wix-contacts-server-core-1.514.0-20150921.071006-91.jar:1.514.0-SNAPSHOT] [39m
[33m	at com.wixpress.contacts.lists.DefaultListsFacade.loadContact(ListsFacade.scala:287) ~[wix-contacts-server-core-1.514.0-20150921.071006-91.jar:1.514.0-SNAPSHOT] [39m
[33m	at com.wixpress.contacts.hive.service.TPAApiService$$anonfun$contactById$1.apply(TPAApiService.scala:39) ~[wix-contacts-hive-1.514.0-20150921.071352-93.jar:1.514.0-SNAPSHOT] [39m
[33m	at com.wixpress.contacts.hive.service.TPAApiService$$anonfun$contactById$1.apply(TPAApiService.scala:37) ~[wix-contacts-hive-1.514.0-20150921.071352-93.jar:1.514.0-SNAPSHOT] [39m
[35m	at com.wixpress.framework.monitoring.metering.SyncMetering$class.tracking(Metering.scala:58) ~[wix-framework-2.803.0-20150917.094031-8.jar:2.803.0-SNAPSHOT] [39m
[33m	at com.wixpress.contacts.hive.service.TPAApiService.tracking(TPAApiService.scala:21) ~[wix-contacts-hive-1.514.0-20150921.071352-93.jar:1.514.0-SNAPSHOT] [39m
[33m	at com.wixpress.contacts.hive.service.TPAApiService.contactById(TPAApiService.scala:37) ~[wix-contacts-hive-1.514.0-20150921.071352-93.jar:1.514.0-SNAPSHOT] [39m
[33m	at com.wixpress.contacts.hive.service.ContactsServerManagerImpl.contactById(ContactsServerManagerImpl.scala:49) ~[wix-contacts-hive-1.514.0-20150921.071352-93.jar:1.514.0-SNAPSHOT] [39m
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_40]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_40]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_40]
	at java.lang.reflect.Method.invoke(Method.java:497) ~[na:1.8.0_40]
	at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) ~[spring-aop-4.1.1.RELEASE.jar:4.1.1.RELEASE]
	at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:190) ~[spring-aop-4.1.1.RELEASE.jar:4.1.1.RELEASE]
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:157) ~[spring-aop-4.1.1.RELEASE.jar:4.1.1.RELEASE]
[35m	at com.wixpress.framework.validation.ValidatingMethodInterceptor.invoke(ValidatingMethodInterceptor.java:44) ~[wix-framework-2.803.0-20150917.094031-8.jar:2.803.0-SNAPSHOT] [39m
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179) ~[spring-aop-4.1.1.RELEASE.jar:4.1.1.RELEASE]
	at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:207) ~[spring-aop-4.1.1.RELEASE.jar:4.1.1.RELEASE]
	at com.sun.proxy.$Proxy116.contactById(Unknown Source) ~[na:na]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_40]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_40]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_40]
	at java.lang.reflect.Method.invoke(Method.java:497) ~[na:1.8.0_40]
[35m	at com.wixpress.framework.rpc.server.RpcInvocation.invoke(RpcInvocation.java:49) ~[hoopoe-rpc-2.803.0-20150917.093719-6.jar:2.803.0-SNAPSHOT] [39m
[35m	at com.wixpress.framework.rpc.server.RpcOverHttpServer2$$anonfun$com$wixpress$framework$rpc$server$RpcOverHttpServer2$$invokeMethod$2.apply(RpcOverHttpServer2.scala:136) [hoopoe-rpc-2.803.0-20150917.093719-6.jar:2.803.0-SNAPSHOT] [39m
[35m	at com.wixpress.framework.rpc.server.RpcOverHttpServer2$$anonfun$com$wixpress$framework$rpc$server$RpcOverHttpServer2$$invokeMethod$2.apply(RpcOverHttpServer2.scala:133) [hoopoe-rpc-2.803.0-20150917.093719-6.jar:2.803.0-SNAPSHOT] [39m
	at scala.collection.mutable.ResizableArray$class.foreach(ResizableArray.scala:59) [scala-library-2.11.7.jar:na]
	at scala.collection.mutable.ArrayBuffer.foreach(ArrayBuffer.scala:48) [scala-library-2.11.7.jar:na]
[35m	at com.wixpress.framework.rpc.server.RpcOverHttpServer2.com$wixpress$framework$rpc$server$RpcOverHttpServer2$$invokeMethod(RpcOverHttpServer2.scala:133) [hoopoe-rpc-2.803.0-20150917.093719-6.jar:2.803.0-SNAPSHOT] [39m
[35m	at com.wixpress.framework.rpc.server.RpcOverHttpServer2$$anonfun$handleRequest$2$$anonfun$apply$3.apply(RpcOverHttpServer2.scala:63) [hoopoe-rpc-2.803.0-20150917.093719-6.jar:2.803.0-SNAPSHOT] [39m
[35m	at com.wixpress.framework.rpc.server.RpcOverHttpServer2$$anonfun$handleRequest$2$$anonfun$apply$3.apply(RpcOverHttpServer2.scala:63) [hoopoe-rpc-2.803.0-20150917.093719-6.jar:2.803.0-SNAPSHOT] [39m
	at scala.Function1$$anonfun$andThen$1.apply(Function1.scala:52) [scala-library-2.11.7.jar:na]
	at scala.Function1$$anonfun$andThen$1.apply(Function1.scala:52) [scala-library-2.11.7.jar:na]
	at scala.Function1$$anonfun$andThen$1.apply(Function1.scala:52) [scala-library-2.11.7.jar:na]
[35m	at com.wixpress.framework.rpc.server.RpcOverHttpServer2$$anonfun$handleRequest$2.apply(RpcOverHttpServer2.scala:64) [hoopoe-rpc-2.803.0-20150917.093719-6.jar:2.803.0-SNAPSHOT] [39m
	at scala.util.control.Exception$Catch.apply(Exception.scala:103) [scala-library-2.11.7.jar:na]
[35m	at com.wixpress.framework.rpc.server.RpcOverHttpServer2.handleRequest(RpcOverHttpServer2.scala:60) [hoopoe-rpc-2.803.0-20150917.093719-6.jar:2.803.0-SNAPSHOT] [39m
[35m	at com.wixpress.framework.rpc.spring.RpcOverHttpServerExporter.handleRequest(RpcOverHttpServerExporter.java:30) [hoopoe-rpc-2.803.0-20150917.093719-6.jar:2.803.0-SNAPSHOT] [39m
[35m	at com.wixpress.framework.rpc.server.RpcServiceHandlerAdapter.handle(RpcServiceHandlerAdapter.scala:28) [wix-framework-2.803.0-20150917.094031-8.jar:2.803.0-SNAPSHOT] [39m
	at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:943) [spring-webmvc-4.1.1.RELEASE.jar:4.1.1.RELEASE]
	at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:877) [spring-webmvc-4.1.1.RELEASE.jar:4.1.1.RELEASE]
	at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:966) [spring-webmvc-4.1.1.RELEASE.jar:4.1.1.RELEASE]
	at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:868) [spring-webmvc-4.1.1.RELEASE.jar:4.1.1.RELEASE]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:641) [servlet-api-3.0.jar:na]
	at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:842) [spring-webmvc-4.1.1.RELEASE.jar:4.1.1.RELEASE]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:722) [servlet-api-3.0.jar:na]
	at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:598) [jetty8-servlet-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1367) [jetty8-servlet-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:88) [spring-web-4.1.1.RELEASE.jar:4.1.1.RELEASE]
	at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) [spring-web-4.1.1.RELEASE.jar:4.1.1.RELEASE]
	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1338) [jetty8-servlet-8.1.3.v20120416.jar:8.1.3.v20120416]
[35m	at com.wixpress.framework.web.filter.AcceptHeaderFilter.doFilter(AcceptHeaderFilter.java:66) [wix-framework-2.803.0-20150917.094031-8.jar:2.803.0-SNAPSHOT] [39m
	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1338) [jetty8-servlet-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:484) [jetty8-servlet-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:119) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:499) [jetty8-security-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:233) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:119) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:111) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.handler.RequestLogHandler.handle(RequestLogHandler.java:62) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1067) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:413) [jetty8-servlet-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:192) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:999) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:117) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:250) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:149) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:111) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.Server.handle(Server.java:350) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:454) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:900) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:954) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:851) [jetty8-http-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235) [jetty8-http-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:77) [jetty8-server-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:606) [jetty8-io-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:46) [jetty8-io-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:603) [jetty8-util-8.1.3.v20120416.jar:8.1.3.v20120416]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:538) [jetty8-util-8.1.3.v20120416.jar:8.1.3.v20120416]
	at java.lang.Thread.run(Thread.java:745) [na:1.8.0_40]

Comment by Jeffrey Yemin [ 21/Sep/15 ]

Hi Eyal,

Please post a comment or upload an attachment with any application server logs you have, including, in particular, samples of all relevant stack traces of exceptions thrown from the driver.

Generated at Thu Feb 08 08:56:00 UTC 2024 using Jira 9.7.1#970001-sha1:2222b88b221c4928ef0de3161136cc90c8356a66.