Return-Path: Delivered-To: apmail-activemq-dev-archive@www.apache.org Received: (qmail 87157 invoked from network); 26 Apr 2007 07:09:58 -0000 Received: from hermes.apache.org (HELO mail.apache.org) (140.211.11.2) by minotaur.apache.org with SMTP; 26 Apr 2007 07:09:58 -0000 Received: (qmail 89392 invoked by uid 500); 26 Apr 2007 07:10:05 -0000 Delivered-To: apmail-activemq-dev-archive@activemq.apache.org Received: (qmail 89372 invoked by uid 500); 26 Apr 2007 07:10:04 -0000 Mailing-List: contact dev-help@activemq.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@activemq.apache.org Delivered-To: mailing list dev@activemq.apache.org Received: (qmail 89362 invoked by uid 99); 26 Apr 2007 07:10:04 -0000 Received: from herse.apache.org (HELO herse.apache.org) (140.211.11.133) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 26 Apr 2007 00:10:04 -0700 X-ASF-Spam-Status: No, hits=-100.0 required=10.0 tests=ALL_TRUSTED X-Spam-Check-By: apache.org Received: from [140.211.11.4] (HELO brutus.apache.org) (140.211.11.4) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 26 Apr 2007 00:09:55 -0700 Received: from brutus (localhost [127.0.0.1]) by brutus.apache.org (Postfix) with ESMTP id 9B08E714079 for ; Thu, 26 Apr 2007 00:09:34 -0700 (PDT) Message-ID: <12892648.1177571374631.JavaMail.jira@brutus> Date: Thu, 26 Apr 2007 00:09:34 -0700 (PDT) From: "Mykola Paliyenko (JIRA)" To: dev@activemq.apache.org Subject: [jira] Created: (AMQ-1236) Failing to acquire exclusive lock - MySQL and other strange errors MIME-Version: 1.0 Content-Type: text/plain; charset=ISO-8859-1 Content-Transfer-Encoding: 7bit X-Virus-Checked: Checked by ClamAV on apache.org Failing to acquire exclusive lock - MySQL and other strange errors ------------------------------------------------------------------ Key: AMQ-1236 URL: https://issues.apache.org/activemq/browse/AMQ-1236 Project: ActiveMQ Issue Type: Bug Components: Message Store Affects Versions: 4.1.1 Environment: AMQ 4.1.1 MySQL 5.0.24 mysql-connector-java-5.0.4 under Windows XP Reporter: Mykola Paliyenko Priority: Critical The issue prevents us from using AMQ discussion http://www.nabble.com/Failing-to-acquire-exclusive-lock---MySQL-t3598830s2354.html#a10051767 I'm new to ActiveMQ so probably I misconfigure it somehow. Problem: I'm trying to setup jdbcPersistenceAdapter in AMQ 4.1.1 to store journal into the MySQL 5.0.24 with Connector mysql-connector-java-5.0.4 under Windows XP My config file is very simple ------------------------CONFIG------------------------------- ------------------------ END CONFIG------------------------------- I've also set profileSQL=true to be able to debug the reason of the problem As a result I have following error log in ActiveMQ window console ---------------------------- LOG --------------------------------- D:\java\tools\apache-activemq-4.1.1\bin>activemq.bat ACTIVEMQ_HOME: D:\java\tools\apache-activemq-4.1.1\bin\.. ACTIVEMQ_BASE: D:\java\tools\apache-activemq-4.1.1\bin\.. Loading message broker from: xbean:activemq.xml INFO BrokerService - ActiveMQ 4.1.1 JMS Message Broker (localhost) is starting INFO BrokerService - For help or more information please see: http://incubator.apache.org/activemq/ INFO ManagementContext - JMX consoles can connect to service:jmx:rmi:///jndi/rmi://localhost:1099/jmxrmi Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 999, resultset-id: 0, message: SET NAMES latin1 Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 47 ms, connection-id: 0, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 999, resultset-id: 0, message: SET character_set_results = NULL Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 1, resultset-id: 1, message: SHOW VARIABLES Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 1, resultset-id: 1 Wed Apr 25 14:38:19 EEST 2007 WARN: Profiler Event: [WARN] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 1, resultset-id: 1, message: The following query was executed with a bad index, use 'EXPLAIN' for more deta ils: SHOW VARIABLES Full stack trace of location where event occurred: ** BEGIN NESTED EXCEPTION ** java.lang.Throwable STACKTRACE: java.lang.Throwable at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:1785) at com.mysql.jdbc.Connection.execSQL(Connection.java:3170) at com.mysql.jdbc.Connection.execSQL(Connection.java:3099) at com.mysql.jdbc.Statement.executeQuery(Statement.java:1169) at com.mysql.jdbc.Connection.loadServerVariables(Connection.java:4317) at com.mysql.jdbc.Connection.initializePropsFromServer(Connection.java:3936) at com.mysql.jdbc.Connection.createNewIO(Connection.java:2683) at com.mysql.jdbc.Connection.(Connection.java:1531) at com.mysql.jdbc.NonRegisteringDriver.connect(NonRegisteringDriver.java:266) at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java:37) at org.apache.commons.dbcp.PoolableConnectionFactory.makeObject(PoolableConnectionFactory.java:290) at org.apache.commons.dbcp.BasicDataSource.validateConnectionFactory(BasicDataSource.java:881) at org.apache.commons.dbcp.BasicDataSource.createDataSource(BasicDataSource.java:855) at org.apache.commons.dbcp.BasicDataSource.getConnection(BasicDataSource.java:544) at org.apache.activemq.store.jdbc.TransactionContext.getConnection(TransactionContext.java:55) at org.apache.activemq.store.jdbc.JDBCPersistenceAdapter.createAdapter(JDBCPersistenceAdapter.java:297) at org.apache.activemq.store.jdbc.JDBCPersistenceAdapter.getAdapter(JDBCPersistenceAdapter.java:251) at org.apache.activemq.store.jdbc.JDBCPersistenceAdapter.start(JDBCPersistenceAdapter.java:152) at org.apache.activemq.broker.BrokerService.createRegionBroker(BrokerService.java:1251) at org.apache.activemq.broker.BrokerService.createBroker(BrokerService.java:1209) at org.apache.activemq.broker.BrokerService.getBroker(BrokerService.java:508) at org.apache.activemq.broker.BrokerService.start(BrokerService.java:394) at org.apache.activemq.xbean.XBeanBrokerService.afterPropertiesSet(XBeanBrokerService.java:47) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1062) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1029) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:420) at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:245) at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:141) at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:242) at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:156) at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:290) at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:348) at org.apache.xbean.spring.context.ClassPathXmlApplicationContext.(ClassPathXmlApplicationContext.java:161) at org.apache.xbean.spring.context.ClassPathXmlApplicationContext.(ClassPathXmlApplicationContext.java:51) at org.apache.activemq.xbean.XBeanBrokerFactory.createBroker(XBeanBrokerFactory.java:41) at org.apache.activemq.broker.BrokerFactory.createBroker(BrokerFactory.java:57) at org.apache.activemq.console.command.StartCommand.startBroker(StartCommand.java:82) at org.apache.activemq.console.command.StartCommand.runTask(StartCommand.java:47) at org.apache.activemq.console.command.AbstractCommand.execute(AbstractCommand.java:50) at org.apache.activemq.console.command.ShellCommand.runTask(ShellCommand.java:65) at org.apache.activemq.console.command.AbstractCommand.execute(AbstractCommand.java:50) at org.apache.activemq.console.command.ShellCommand.main(ShellCommand.java:46) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.apache.activemq.console.Main.runTaskClass(Main.java:202) at org.apache.activemq.console.Main.main(Main.java:91) ** END NESTED EXCEPTION ** Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 2, resultset-id: 2, message: SHOW COLLATION Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 2, resultset-id: 2 Wed Apr 25 14:38:19 EEST 2007 WARN: Profiler Event: [WARN] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 2, resultset-id: 2, message: The following query was executed with a bad index, use 'EXPLAIN' for more deta ils: SHOW COLLATION Full stack trace of location where event occurred: ** BEGIN NESTED EXCEPTION ** java.lang.Throwable STACKTRACE: java.lang.Throwable at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:1785) at com.mysql.jdbc.Connection.execSQL(Connection.java:3170) at com.mysql.jdbc.Connection.execSQL(Connection.java:3099) at com.mysql.jdbc.Statement.executeQuery(Statement.java:1169) at com.mysql.jdbc.Connection.buildCollationMapping(Connection.java:1645) at com.mysql.jdbc.Connection.initializePropsFromServer(Connection.java:3938) at com.mysql.jdbc.Connection.createNewIO(Connection.java:2683) at com.mysql.jdbc.Connection.(Connection.java:1531) at com.mysql.jdbc.NonRegisteringDriver.connect(NonRegisteringDriver.java:266) at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java:37) at org.apache.commons.dbcp.PoolableConnectionFactory.makeObject(PoolableConnectionFactory.java:290) at org.apache.commons.dbcp.BasicDataSource.validateConnectionFactory(BasicDataSource.java:881) at org.apache.commons.dbcp.BasicDataSource.createDataSource(BasicDataSource.java:855) at org.apache.commons.dbcp.BasicDataSource.getConnection(BasicDataSource.java:544) at org.apache.activemq.store.jdbc.TransactionContext.getConnection(TransactionContext.java:55) at org.apache.activemq.store.jdbc.JDBCPersistenceAdapter.createAdapter(JDBCPersistenceAdapter.java:297) at org.apache.activemq.store.jdbc.JDBCPersistenceAdapter.getAdapter(JDBCPersistenceAdapter.java:251) at org.apache.activemq.store.jdbc.JDBCPersistenceAdapter.start(JDBCPersistenceAdapter.java:152) at org.apache.activemq.broker.BrokerService.createRegionBroker(BrokerService.java:1251) at org.apache.activemq.broker.BrokerService.createBroker(BrokerService.java:1209) at org.apache.activemq.broker.BrokerService.getBroker(BrokerService.java:508) at org.apache.activemq.broker.BrokerService.start(BrokerService.java:394) at org.apache.activemq.xbean.XBeanBrokerService.afterPropertiesSet(XBeanBrokerService.java:47) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1062) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1029) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:420) at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:245) at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:141) at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:242) at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:156) at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:290) at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:348) at org.apache.xbean.spring.context.ClassPathXmlApplicationContext.(ClassPathXmlApplicationContext.java:161) at org.apache.xbean.spring.context.ClassPathXmlApplicationContext.(ClassPathXmlApplicationContext.java:51) at org.apache.activemq.xbean.XBeanBrokerFactory.createBroker(XBeanBrokerFactory.java:41) at org.apache.activemq.broker.BrokerFactory.createBroker(BrokerFactory.java:57) at org.apache.activemq.console.command.StartCommand.startBroker(StartCommand.java:82) at org.apache.activemq.console.command.StartCommand.runTask(StartCommand.java:47) at org.apache.activemq.console.command.AbstractCommand.execute(AbstractCommand.java:50) at org.apache.activemq.console.command.ShellCommand.runTask(ShellCommand.java:65) at org.apache.activemq.console.command.AbstractCommand.execute(AbstractCommand.java:50) at org.apache.activemq.console.command.ShellCommand.main(ShellCommand.java:46) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.apache.activemq.console.Main.runTaskClass(Main.java:202) at org.apache.activemq.console.Main.main(Main.java:91) ** END NESTED EXCEPTION ** Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 999, resultset-id: 0, message: SET autocommit=1 Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 312, statement-id: 999, resultset-id: 0, message: SET autocommit=1 Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 312, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 312, statement-id: 999, resultset-id: 0, message: SET autocommit=1 Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 16 ms, connection-id: 312, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 999, resultset-id: 0, message: SET NAMES latin1 Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 999, resultset-id: 0, message: SET character_set_results = NULL Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 3, resultset-id: 3, message: SHOW VARIABLES Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 3, resultset-id: 3 Wed Apr 25 14:38:19 EEST 2007 WARN: Profiler Event: [WARN] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 3, resultset-id: 3, message: The following query was executed with a bad index, use 'EXPLAIN' for more deta ils: SHOW VARIABLES Full stack trace of location where event occurred: ** BEGIN NESTED EXCEPTION ** java.lang.Throwable STACKTRACE: java.lang.Throwable at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:1785) at com.mysql.jdbc.Connection.execSQL(Connection.java:3170) at com.mysql.jdbc.Connection.execSQL(Connection.java:3099) at com.mysql.jdbc.Statement.executeQuery(Statement.java:1169) at com.mysql.jdbc.Connection.loadServerVariables(Connection.java:4317) at com.mysql.jdbc.Connection.initializePropsFromServer(Connection.java:3936) at com.mysql.jdbc.Connection.createNewIO(Connection.java:2683) at com.mysql.jdbc.Connection.(Connection.java:1531) at com.mysql.jdbc.NonRegisteringDriver.connect(NonRegisteringDriver.java:266) at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java:37) at org.apache.commons.dbcp.PoolableConnectionFactory.makeObject(PoolableConnectionFactory.java:290) at org.apache.commons.pool.impl.GenericObjectPool.borrowObject(GenericObjectPool.java:771) at org.apache.commons.dbcp.PoolingDataSource.getConnection(PoolingDataSource.java:95) at org.apache.commons.dbcp.BasicDataSource.getConnection(BasicDataSource.java:544) at org.apache.activemq.store.jdbc.TransactionContext.getConnection(TransactionContext.java:55) at org.apache.activemq.store.jdbc.JDBCPersistenceAdapter.createAdapter(JDBCPersistenceAdapter.java:297) at org.apache.activemq.store.jdbc.JDBCPersistenceAdapter.getAdapter(JDBCPersistenceAdapter.java:251) at org.apache.activemq.store.jdbc.JDBCPersistenceAdapter.start(JDBCPersistenceAdapter.java:152) at org.apache.activemq.broker.BrokerService.createRegionBroker(BrokerService.java:1251) at org.apache.activemq.broker.BrokerService.createBroker(BrokerService.java:1209) at org.apache.activemq.broker.BrokerService.getBroker(BrokerService.java:508) at org.apache.activemq.broker.BrokerService.start(BrokerService.java:394) at org.apache.activemq.xbean.XBeanBrokerService.afterPropertiesSet(XBeanBrokerService.java:47) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1062) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1029) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:420) at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:245) at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:141) at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:242) at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:156) at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:290) at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:348) at org.apache.xbean.spring.context.ClassPathXmlApplicationContext.(ClassPathXmlApplicationContext.java:161) at org.apache.xbean.spring.context.ClassPathXmlApplicationContext.(ClassPathXmlApplicationContext.java:51) at org.apache.activemq.xbean.XBeanBrokerFactory.createBroker(XBeanBrokerFactory.java:41) at org.apache.activemq.broker.BrokerFactory.createBroker(BrokerFactory.java:57) at org.apache.activemq.console.command.StartCommand.startBroker(StartCommand.java:82) at org.apache.activemq.console.command.StartCommand.runTask(StartCommand.java:47) at org.apache.activemq.console.command.AbstractCommand.execute(AbstractCommand.java:50) at org.apache.activemq.console.command.ShellCommand.runTask(ShellCommand.java:65) at org.apache.activemq.console.command.AbstractCommand.execute(AbstractCommand.java:50) at org.apache.activemq.console.command.ShellCommand.main(ShellCommand.java:46) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.apache.activemq.console.Main.runTaskClass(Main.java:202) at org.apache.activemq.console.Main.main(Main.java:91) ** END NESTED EXCEPTION ** Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 4, resultset-id: 4, message: SHOW COLLATION Wed Apr 25 14:38:19 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 4, resultset-id: 4 Wed Apr 25 14:38:19 EEST 2007 WARN: Profiler Event: [WARN] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 4, resultset-id: 4, message: The following query was executed with a bad index, use 'EXPLAIN' for more deta ils: SHOW COLLATION Full stack trace of location where event occurred: ** BEGIN NESTED EXCEPTION ** java.lang.Throwable STACKTRACE: java.lang.Throwable at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:1785) at com.mysql.jdbc.Connection.execSQL(Connection.java:3170) at com.mysql.jdbc.Connection.execSQL(Connection.java:3099) at com.mysql.jdbc.Statement.executeQuery(Statement.java:1169) at com.mysql.jdbc.Connection.buildCollationMapping(Connection.java:1645) at com.mysql.jdbc.Connection.initializePropsFromServer(Connection.java:3938) at com.mysql.jdbc.Connection.createNewIO(Connection.java:2683) at com.mysql.jdbc.Connection.(Connection.java:1531) at com.mysql.jdbc.NonRegisteringDriver.connect(NonRegisteringDriver.java:266) at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java:37) at org.apache.commons.dbcp.PoolableConnectionFactory.makeObject(PoolableConnectionFactory.java:290) at org.apache.commons.pool.impl.GenericObjectPool.borrowObject(GenericObjectPool.java:771) at org.apache.commons.dbcp.PoolingDataSource.getConnection(PoolingDataSource.java:95) at org.apache.commons.dbcp.BasicDataSource.getConnection(BasicDataSource.java:544) at org.apache.activemq.store.jdbc.TransactionContext.getConnection(TransactionContext.java:55) at org.apache.activemq.store.jdbc.JDBCPersistenceAdapter.createAdapter(JDBCPersistenceAdapter.java:297) at org.apache.activemq.store.jdbc.JDBCPersistenceAdapter.getAdapter(JDBCPersistenceAdapter.java:251) at org.apache.activemq.store.jdbc.JDBCPersistenceAdapter.start(JDBCPersistenceAdapter.java:152) at org.apache.activemq.broker.BrokerService.createRegionBroker(BrokerService.java:1251) at org.apache.activemq.broker.BrokerService.createBroker(BrokerService.java:1209) at org.apache.activemq.broker.BrokerService.getBroker(BrokerService.java:508) at org.apache.activemq.broker.BrokerService.start(BrokerService.java:394) at org.apache.activemq.xbean.XBeanBrokerService.afterPropertiesSet(XBeanBrokerService.java:47) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1062) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1029) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:420) at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:245) at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:141) at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:242) at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:156) at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:290) at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:348) at org.apache.xbean.spring.context.ClassPathXmlApplicationContext.(ClassPathXmlApplicationContext.java:161) at org.apache.xbean.spring.context.ClassPathXmlApplicationContext.(ClassPathXmlApplicationContext.java:51) at org.apache.activemq.xbean.XBeanBrokerFactory.createBroker(XBeanBrokerFactory.java:41) at org.apache.activemq.broker.BrokerFactory.createBroker(BrokerFactory.java:57) at org.apache.activemq.console.command.StartCommand.startBroker(StartCommand.java:82) at org.apache.activemq.console.command.StartCommand.runTask(StartCommand.java:47) at org.apache.activemq.console.command.AbstractCommand.execute(AbstractCommand.java:50) at org.apache.activemq.console.command.ShellCommand.runTask(ShellCommand.java:65) at org.apache.activemq.console.command.AbstractCommand.execute(AbstractCommand.java:50) at org.apache.activemq.console.command.ShellCommand.main(ShellCommand.java:46) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.apache.activemq.console.Main.runTaskClass(Main.java:202) at org.apache.activemq.console.Main.main(Main.java:91) ** END NESTED EXCEPTION ** Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 999, resultset-id: 0, message: SET autocommit=1 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DriverConnectionFactory.createConnection(DriverConnectionFactory.java :37) duration: 0 ms, connection-id: 0, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0, message: SET autocommit=1 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingConnection.setTransactionIsolation(DelegatingConnection.jav a:224) duration: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0, message: SET SESSION TRANSACTION ISOLATION LEVEL READ UNCOMMITTED Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingConnection.setTransactionIsolation(DelegatingConnection.jav a:224) duration: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0 INFO JDBCPersistenceAdapter - Database driver recognized: [mysql-ab_jdbc_driver] Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0, message: SET autocommit=1 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0, message: SET autocommit=1 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0, message: SET autocommit=0 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingConnection.setTransactionIsolation(DelegatingConnection.jav a:224) duration: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0, message: SET SESSION TRANSACTION ISOLATION LEVEL READ UNCOMMITTED Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingConnection.setTransactionIsolation(DelegatingConnection.jav a:224) duration: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.activemq.store.jdbc.adapter.DefaultJDBCAdapter.doCreateTables(DefaultJDBCAdapter.j ava:72) duration: 0 ms, connection-id: 313, statement-id: 5, resultset-id: 5, message: SHOW FULL TABLES FROM `sonoportal` LIKE 'ACTIVEMQ_MSGS' Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.activemq.store.jdbc.adapter.DefaultJDBCAdapter.doCreateTables(DefaultJDBCAdapter.j ava:72) duration: 0 ms, connection-id: 313, statement-id: 5, resultset-id: 5 Wed Apr 25 14:38:20 EEST 2007 WARN: Profiler Event: [WARN] at org.apache.activemq.store.jdbc.adapter.DefaultJDBCAdapter.doCreateTables(DefaultJDBCAdapter.j ava:72) duration: 0 ms, connection-id: 313, statement-id: 5, resultset-id: 5, message: The following query was executed with a bad index, use 'EXPLAIN' for more details: SHOW FULL TABLES FROM `sonoportal` LIKE 'ACTIVEMQ_MSGS' Full stack trace of location where event occurred: ** BEGIN NESTED EXCEPTION ** java.lang.Throwable STACKTRACE: java.lang.Throwable at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:1785) at com.mysql.jdbc.Connection.execSQL(Connection.java:3170) at com.mysql.jdbc.Connection.execSQL(Connection.java:3099) at com.mysql.jdbc.Statement.executeQuery(Statement.java:1169) at com.mysql.jdbc.DatabaseMetaData$9.forEach(DatabaseMetaData.java:4379) at com.mysql.jdbc.DatabaseMetaData$IterateBlock.doForAll(DatabaseMetaData.java:76) at com.mysql.jdbc.DatabaseMetaData.getTables(DatabaseMetaData.java:4357) at org.apache.activemq.store.jdbc.adapter.DefaultJDBCAdapter.doCreateTables(DefaultJDBCAdapter.java:72) at org.apache.activemq.store.jdbc.JDBCPersistenceAdapter.start(JDBCPersistenceAdapter.java:159) at org.apache.activemq.broker.BrokerService.createRegionBroker(BrokerService.java:1251) at org.apache.activemq.broker.BrokerService.createBroker(BrokerService.java:1209) at org.apache.activemq.broker.BrokerService.getBroker(BrokerService.java:508) at org.apache.activemq.broker.BrokerService.start(BrokerService.java:394) at org.apache.activemq.xbean.XBeanBrokerService.afterPropertiesSet(XBeanBrokerService.java:47) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1062) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1029) at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:420) at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:245) at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:141) at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:242) at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:156) at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:290) at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:348) at org.apache.xbean.spring.context.ClassPathXmlApplicationContext.(ClassPathXmlApplicationContext.java:161) at org.apache.xbean.spring.context.ClassPathXmlApplicationContext.(ClassPathXmlApplicationContext.java:51) at org.apache.activemq.xbean.XBeanBrokerFactory.createBroker(XBeanBrokerFactory.java:41) at org.apache.activemq.broker.BrokerFactory.createBroker(BrokerFactory.java:57) at org.apache.activemq.console.command.StartCommand.startBroker(StartCommand.java:82) at org.apache.activemq.console.command.StartCommand.runTask(StartCommand.java:47) at org.apache.activemq.console.command.AbstractCommand.execute(AbstractCommand.java:50) at org.apache.activemq.console.command.ShellCommand.runTask(ShellCommand.java:65) at org.apache.activemq.console.command.AbstractCommand.execute(AbstractCommand.java:50) at org.apache.activemq.console.command.ShellCommand.main(ShellCommand.java:46) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.apache.activemq.console.Main.runTaskClass(Main.java:202) at org.apache.activemq.console.Main.main(Main.java:91) ** END NESTED EXCEPTION ** Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingConnection.commit(DelegatingConnection.java:203) duration: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0, message: commit Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingConnection.commit(DelegatingConnection.java:203) duration: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingConnection.commit(DelegatingConnection.java:203) duration: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0, message: commit Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingConnection.commit(DelegatingConnection.java:203) duration: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingConnection.commit(DelegatingConnection.java:203) duration: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0, message: commit Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingConnection.commit(DelegatingConnection.java:203) duration: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingConnection.rollback(DelegatingConnection.java:220) duration : 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0, message: rollback Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingConnection.rollback(DelegatingConnection.java:220) duration : 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0, message: SET autocommit=1 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0, message: SET autocommit=1 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0, message: SET autocommit=0 Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingConnection.setAutoCommit(DelegatingConnection.java:221) dur ation: 0 ms, connection-id: 313, statement-id: 999, resultset-id: 0 INFO DefaultDatabaseLocker - Attempting to acquire the exclusive lock to become the Master broker Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingPreparedStatement.execute(DelegatingPreparedStatement.java: 117) duration: 0 ms, connection-id: 313, statement-id: 8, resultset-id: 0, message: LOCK TABLE ACTIVEMQ_LOCK WRITE Wed Apr 25 14:38:20 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingPreparedStatement.execute(DelegatingPreparedStatement.java: 117) duration: 0 ms, connection-id: 313, statement-id: 8, resultset-id: 0 INFO DefaultDatabaseLocker - Attempting to acquire the exclusive lock to become the Master broker Wed Apr 25 14:38:21 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingPreparedStatement.execute(DelegatingPreparedStatement.java: 117) duration: 0 ms, connection-id: 313, statement-id: 8, resultset-id: 0, message: LOCK TABLE ACTIVEMQ_LOCK WRITE Wed Apr 25 14:38:21 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingPreparedStatement.execute(DelegatingPreparedStatement.java: 117) duration: 0 ms, connection-id: 313, statement-id: 8, resultset-id: 0 INFO DefaultDatabaseLocker - Attempting to acquire the exclusive lock to become the Master broker Wed Apr 25 14:38:22 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingPreparedStatement.execute(DelegatingPreparedStatement.java: 117) duration: 0 ms, connection-id: 313, statement-id: 8, resultset-id: 0, message: LOCK TABLE ACTIVEMQ_LOCK WRITE Wed Apr 25 14:38:22 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingPreparedStatement.execute(DelegatingPreparedStatement.java: 117) duration: 0 ms, connection-id: 313, statement-id: 8, resultset-id: 0 INFO DefaultDatabaseLocker - Attempting to acquire the exclusive lock to become the Master broker Wed Apr 25 14:38:23 EEST 2007 INFO: Profiler Event: [QUERY] at org.apache.commons.dbcp.DelegatingPreparedStatement.execute(DelegatingPreparedStatement.java: 117) duration: 0 ms, connection-id: 313, statement-id: 8, resultset-id: 0, message: LOCK TABLE ACTIVEMQ_LOCK WRITE Wed Apr 25 14:38:23 EEST 2007 INFO: Profiler Event: [FETCH] at org.apache.commons.dbcp.DelegatingPreparedStatement.execute(DelegatingPreparedStatement.java: 117) duration: 0 ms, connection-id: 313, statement-id: 8, resultset-id: 0 ---------------------------- END LOG --------------------------------- All mentioned in errors commands are easily executed from MySQL Console. Tables are get created in the schema, I've no idea about what problem is. Has anyone tried to run it with MySQL persistence? Thanks in advance for any help. -- This message is automatically generated by JIRA. - You can reply to this email to add a comment to the issue online.