Hi Manuel,
It looks to me as if Sequoia is taking the backend(s) off-line shortly after
your application starts. Have you looked in the log files to see what errors
appear at this time? In particular, you should look at log/full_cluster.log on
all your controllers. I would expect to see one or more error messages that
explain why the backends went off-line.
Cheers, Robert
On 11/7/08 11:51 AM, "Manuel Rorarius - mediaskill OHG" <[EMAIL PROTECTED]>
wrote:
Hello,
I am currently trying to install Sequoia with Hibernate/C3P0 in a
Tomcat 6 environment. I sadly keep having connection losses after a
few selects or sometimes even at the first select. After that no more
selects seem to work.
Generally it seems to work when I use direct JDBC requests in
a small sample Application outside of Tomcat.
I did not find any clues to what's going wrong in the Server log or on
the net. The Server is up and running and the virtual DB is working
fine with the regular JDBC-Approach, so I guess it's a client side
problem. I can see the connections from the Client in the Server log,
but no errors. Any help with this Problem would be very much appreciated.
The Error from the JDBC states:
"Controller /000.000.000.000:25322 down - shutting down connected sockets
I/O Error while trying to disable autocommit
socket closed"
as well as
"Exception.org.continuent.sequoia.common.exceptions.driver.
VirtualDatabaseUnavailableException: Virtual database vdating2 not found
on any of the controllers"
I attached the log from the JDBC (IP and Username/Password anonymized):
[INFO] MLog clients using com.mchange.v2.log.FallbackMLog logging.
[WARNING] Unknown c3p0-config property: idle_test_period
[WARNING] Unknown c3p0-config property: timeout
[INFO] Initializing c3p0-0.9.1 [built 16-January-2007 14:46:42; debug? true;
trace: 10]
[INFO] Initializing c3p0 pool... [EMAIL PROTECTED] [ connectionPoolDataSource
-> [EMAIL PROTECTED] [ acquireIncrement -> 3, acquireRetryAttempts -> 5,
acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable ->
null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0,
connectionCustomizerClassName -> null, connectionTesterClassName ->
com.mchange.v2.c3p0.impl.DefaultConnectionTester,
debugUnreturnedConnectionStackTraces -> false, factoryClassLocation -> null,
forceIgnoreUnresolvedTransactions -> false, identityToken ->
1hgf02m7xxa6mkl9k1rrd|3f4a21, idleConnectionTestPeriod -> 3000, initialPoolSize
-> 5, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 600, maxIdleTime ->
300, maxIdleTimeExcessConnections -> 0, maxPoolSize -> 20, maxStatements -> 0,
maxStatementsPerConnection -> 0, minPoolSize -> 5, nestedDataSource -> [EMAIL
PROTECTED] [ desc!
ription -> null, driverClass -> null, factoryClassLocation -> null,
identityToken -> 1hgf02m7xxa6mkl9k1rrd|15a142f, jdbcUrl ->
jdbc:sequoia://000.000.000.000/vdating2;debugLevel=debug, properties ->
{user=******, password=******} ], preferredTestQuery -> null, propertyCycle ->
0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false,
unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false;
userOverrides: {} ], dataSourceName -> null, factoryClassLocation -> null,
identityToken -> 1hgf02m7xxa6mkl9k1rrd|258c74, numHelperThreads -> 3 ]
Fri Nov 07 18:16:08 CET 2008 Thread[ControllerWatcher,5,[EMAIL PROTECTED]
{/000.000.000.000:25322} started.
Fri Nov 07 18:16:08 CET 2008 Thread[ControllerWatcher,5,[EMAIL PROTECTED]
{/000.000.000.000:25322} started.
Fri Nov 07 18:16:08 CET 2008 Thread[ControllerPingSender,5,[EMAIL PROTECTED]
{/000.000.000.000:25322} started.
Fri Nov 07 18:16:08 CET 2008 Thread[ControllerPingSender,5,[EMAIL PROTECTED]
{/000.000.000.000:25322} started.
Authenticating with controller /000.000.000.000:25322
Authenticating with controller /000.000.000.000:25322
Authenticating with controller /000.000.000.000:25322
New connection:url:jdbc:sequoia://000.000.000.000/vdating2;debugLevel=debug
Socket[addr=/000.000.000.000,port=25322,localport=55798]
properties:{debugLevel=debug, password=xxxxxxx, user=xxxxx}
user:dating
connection pooling:true
escape backslash:true
escape char:'
escape single quote:true
New connection:url:jdbc:sequoia://000.000.000.000/vdating2;debugLevel=debug
Socket[addr=/000.000.000.000,port=25322,localport=55797]
properties:{debugLevel=debug, password=xxxxx, user=xxxxxx}
user:dating
connection pooling:true
escape backslash:true
escape char:'
escape single quote:true
New connection:url:jdbc:sequoia://000.000.000.000/vdating2;debugLevel=debug
Socket[addr=/000.000.000.000,port=25322,localport=55796]
properties:{debugLevel=debug, password=xxxxx, user=xxxxx}
user:dating
connection pooling:true
escape backslash:true
escape char:'
escape single quote:true
Executing getCatalog
Executing getCatalog
Executing getCatalog
getDatabaseProductName
Authenticating with controller /000.000.000.000:25322
Authenticating with controller /000.000.000.000:25322
Getting getDatabaseMajorVersion metadata
New connection:url:jdbc:sequoia://000.000.000.000/vdating2;debugLevel=debug
Socket[addr=/000.000.000.000,port=25322,localport=55799]
properties:{debugLevel=debug, password=xxxxx, user=xxxxx}
user:dating
connection pooling:true
escape backslash:true
escape char:'
escape single quote:true
New connection:url:jdbc:sequoia://000.000.000.000/vdating2;debugLevel=debug
Socket[addr=/000.000.000.000,port=25322,localport=55800]
properties:{debugLevel=debug, password=xxxxx, user=xxxxx}
user:dating
connection pooling:true
escape backslash:true
escape char:'
escape single quote:true
Executing getControllerVersionNumber
Executing getCatalog
Executing getCatalog
Getting supportsResultSetType(int=1004) metadata
Getting supportsBatchUpdates metadata
Getting dataDefinitionCausesTransactionCommit metadata
Getting dataDefinitionIgnoredInTransactions metadata
Getting supportsGetGeneratedKeys metadata
Transaction 8 has been started
Executing statementExecuteQuery with request [EMAIL PROTECTED]
Transaction 9 has been started
Executing statementExecuteQuery with request [EMAIL PROTECTED]
Transaction 10 has been started
Executing statementExecuteQuery with request [EMAIL PROTECTED]
07.11.2008 18:16:34 org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8081
07.11.2008 18:16:34 org.apache.jk.common.ChannelSocket init
INFO: JK: ajp13 listening on /0.0.0.0:8010
07.11.2008 18:16:34 org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=0/34 config=null
07.11.2008 18:16:34 org.apache.catalina.startup.Catalina start
INFO: Server startup in 30578 ms
Fri Nov 07 18:16:34 CET 2008 Controller /000.000.000.000:25322 down - shutting
down connected sockets
I/O Error while trying to disable autocommit
socket closed
Resetting connection and adding it to the pool
[INFO] [c3p0] Exceptions occurred while trying to close a PooledConnection's
resources normally.
[INFO] [c3p0] NewPooledConnection close
Exception.org.continuent.sequoia.common.exceptions.driver.VirtualDatabaseUnavailableException:
Virtual database vdating2 not found on any of the controllers
at
org.continuent.sequoia.driver.Driver.getConnectionToNewController(Driver.java:425)
at
org.continuent.sequoia.driver.Connection.reconnect(Connection.java:2629)
at org.continuent.sequoia.driver.Connection.close(Connection.java:576)
at
com.mchange.v2.c3p0.impl.NewPooledConnection.close(NewPooledConnection.java:474)
at
com.mchange.v2.c3p0.impl.NewPooledConnection.close(NewPooledConnection.java:191)
at
com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager.destroyResource(C3P0PooledConnectionPool.java:470)
at
com.mchange.v2.resourcepool.BasicResourcePool$1DestroyResourceTask.run(BasicResourcePool.java:964)
at
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:547)
[WARNING] Failed to destroy resource: [EMAIL PROTECTED]: Some resources failed
to close properly while closing [EMAIL PROTECTED]
at
com.mchange.v2.c3p0.impl.NewPooledConnection.close(NewPooledConnection.java:496)
at
com.mchange.v2.c3p0.impl.NewPooledConnection.close(NewPooledConnection.java:191)
at
com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager.destroyResource(C3P0PooledConnectionPool.java:470)
at
com.mchange.v2.resourcepool.BasicResourcePool$1DestroyResourceTask.run(BasicResourcePool.java:964)
at
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:547)
2008-11-07 18:16:34,947 ERROR - DefaultQuartzScheduler_Worker-1 -
[de.mediaskill.service.helper.BaseDBHelper] - getList() Hibernate Exception:
<<org.hibernate.exception.GenericJDBCException begin>>
org.hibernate.exception.GenericJDBCException: could not execute query
at
org.hibernate.exception.SQLStateConverter.handledNonSpecificException(SQLStateConverter.java:103)
at
org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:91)
at
org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)
at org.hibernate.loader.Loader.doList(Loader.java:2223)
at org.hibernate.loader.Loader.listIgnoreQueryCache(Loader.java:2104)
at org.hibernate.loader.Loader.list(Loader.java:2099)
at org.hibernate.loader.hql.QueryLoader.list(QueryLoader.java:378)
at
org.hibernate.hql.ast.QueryTranslatorImpl.list(QueryTranslatorImpl.java:338)
at
org.hibernate.engine.query.HQLQueryPlan.performList(HQLQueryPlan.java:172)
at org.hibernate.impl.SessionImpl.list(SessionImpl.java:1121)
at org.hibernate.impl.QueryImpl.list(QueryImpl.java:79)
at
de.mediaskill.service.helper.BaseDBHelper.getList(BaseDBHelper.java:664)
at de.mediaskill.cron.EmailSender.checkEmailQueue(EmailSender.java:59)
at de.mediaskill.cron.EmailSender.execute(EmailSender.java:115)
at org.quartz.core.JobRunShell.run(JobRunShell.java:202)
at
org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529)
Caused by:
org.continuent.sequoia.common.exceptions.driver.VirtualDatabaseUnavailableException:
Virtual database vdating2 not found on any of the controllers
at
org.continuent.sequoia.driver.Driver.getConnectionToNewController(Driver.java:425)
at
org.continuent.sequoia.driver.Connection.reconnect(Connection.java:2629)
at org.continuent.sequoia.driver.Connection.begin(Connection.java:1561)
at
org.continuent.sequoia.driver.Connection.beginTransactionIfNeeded(Connection.java:1963)
at
org.continuent.sequoia.driver.Connection.statementExecuteQuery(Connection.java:2781)
at
org.continuent.sequoia.driver.Statement.executeQuery(Statement.java:528)
at
org.continuent.sequoia.driver.PreparedStatement.executeQuery(PreparedStatement.java:168)
at
com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeQuery(NewProxyPreparedStatement.java:76)
at
org.hibernate.jdbc.AbstractBatcher.getResultSet(AbstractBatcher.java:186)
at org.hibernate.loader.Loader.getResultSet(Loader.java:1787)
at org.hibernate.loader.Loader.doQuery(Loader.java:674)
at
org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:236)
at org.hibernate.loader.Loader.doList(Loader.java:2220)
... 12 more
<<org.hibernate.exception.GenericJDBCException end>>
Best regards
Manuel Rorarius
------------------------------------
mediaskill OHG
Kaiserdamm 100
14057 Berlin
Fon +49 (0)30 - 39 83 572 - 5
Fax +49 (0)30 - 39 83 572 - 3
[EMAIL PROTECTED]
www.mediaskill.de
------------------------------------
Handelsregister: AG Charlottenburg
Nummer: HRA 31638
Ust-IdNr.: DE 238 456 737
------------------------------------
_______________________________________________
Sequoia mailing list
[email protected]
https://forge.continuent.org/mailman/listinfo/sequoia
--
Robert Hodges, CTO, Continuent, Inc.
Email: [EMAIL PROTECTED]
Mobile: +1-510-501-3728 Skype: hodgesrm
_______________________________________________
Sequoia mailing list
[email protected]
https://forge.continuent.org/mailman/listinfo/sequoia
