We're currently just using the default BoneCP configuration, and see this about 2-4 times daily on each of our Play servers. Traffic is low, only about 1000 requests per day for database driven requests at least. Looking at graphs across monitoring, there doesn't seem to be any correlation in terms of a load spike or a network event or a system event like log rotation etc.
Looking at the monitoring, this event is positively correlated with a GC event. Of three events we saw today, all three occurred at the same time as GC.
Can confirm that we're seeing this on PostgreSQL 9.2.7 with BoneCP 0.8.0.RELEASE on Play 2.2.3 Scala 2.10.
2014-07-02 09:11:17,764 [^[[37minfo^[[0m] [play-akka. actor.default- dispatcher- 5] - *** ERROR *** Failed with un-trapped internal exception (p1) org.postgresql. jdbc2.AbstractJ dbc2Connection. checkClosed( AbstractJdbc2Co nnection. java:714) org.postgresql. jdbc2.AbstractJ dbc2Connection. setAutoCommit( AbstractJdbc2Co nnection. java:661) bonecp. ConnectionHandl e.setAutoCommit (ConnectionHand le.java: 1292)play. api.db. BoneCPApi$ $anon$1. onCheckOut( DB.scala: 329)com. jolbox. bonecp. AbstractConnect ionStrategy. postConnection( AbstractConnect ionStrategy. java:75) bonecp. AbstractConnect ionStrategy. getConnection( AbstractConnect ionStrategy. java:92) com.jolbox. bonecp. BoneCP. getConnection( BoneCP. java:553) com.jolbox. bonecp. BoneCPDataSourc e.getConnection (BoneCPDataSour ce.java: 131)scala. slick.jdbc. PlayDatabase. createConnectio n(PlayDatabase. scala:8) jdbc.JdbcBacken d$BaseSession. conn$lzycompute (JdbcBackend. scala:302) jdbc.JdbcBacken d$BaseSession. conn(JdbcBacken d.scala: 302)scala. slick.jdbc. JdbcBackend$ SessionDef$ class.prepareSt atement( JdbcBackend. scala:123) scala.slick. jdbc.JdbcBacken d$BaseSession. prepareStatemen t(JdbcBackend. scala:297) scala.slick. jdbc.StatementI nvoker. results( StatementInvoke r.scala: 28) jdbc.StatementI nvoker. iteratorTo( StatementInvoke r.scala: 16) jdbc.Invoker$ class.foreach( Invoker. scala:97) jdbc.StatementI nvoker. foreach( StatementInvoke r.scala: 9) jdbc.Invoker$ class.firstOpti on(Invoker. scala:44) jdbc.StatementI nvoker. firstOption( StatementInvoke r.scala: 9) jdbc.UnitInvoke r$class. firstOption( Invoker. scala:155) driver. JdbcInvokerComp onent$UnitQuery Invoker. firstOption( JdbcInvokerComp onent.scala: 50) sql.NpUserSlick DAOService$ $anonfun$ findById$ 1.apply( NpUserSlickDAOS ervice. scala:22) sql.NpUserSlick DAOService$ $anonfun$ findById$ 1.apply( NpUserSlickDAOS ervice. scala:22) sql.Environment DrivenPlaySlick SessionService$ $anonfun$ withSession$ 2.apply( DBSessionServic e.scala: 47) sql.Environment DrivenPlaySlick SessionService$ $anonfun$ withSession$ 2.apply( DBSessionServic e.scala: 45) backend. DatabaseCompone nt$DatabaseDef$ class.withSessi on(DatabaseComp onent.scala: 31) jdbc.PlayDataba se.withSession( PlayDatabase. scala:6)
com.jolbox.
com.jolbox.
scala.slick.
scala.slick.
scala.slick.
scala.slick.
scala.slick.
scala.slick.
scala.slick.
scala.slick.
scala.slick.
services.
services.
services.
services.
scala.slick.
scala.slick.
... (more internal stuff)
We're currently just using the default BoneCP configuration, and see this about 2-4 times daily on each of our Play servers. Traffic is low, only about 1000 requests per day for database driven requests at least. Looking at graphs across monitoring, there doesn't seem to be any correlation in terms of a load spike or a network event or a system event like log rotation etc.
Looking at the monitoring, this event is positively correlated with a GC event. Of three events we saw today, all three occurred at the same time as GC.