You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users@activemq.apache.org by Johnny Wang <jo...@air2web.com> on 2007/07/20 20:04:17 UTC
JBDC Master/slave , slave server blocked my application.
Hi,
I am using Jboss 4.0.4, oracle, and activemq 4.1.1 JDBC master/salve.
I am using activemq-rar-4.1.1.rar under my deploy/ folder.
broker configuration is as:
<persistenceAdapter>
<journaledJDBC dataSource="#oracle-ds" />
</persistenceAdapter>
My problem is : After master server launched successfully. My slave server
is halt when try to Attempting to acquire the exclusive lock to become the
Master broker.
It blocked all of rest work it supposed to do!!!! Is this a bug??
How can I resolve this problem? It is urgent for me to resolve this to put
applicaiton goto production.
Thanks a lot.
the log snippet is the master server. you can see a lot of thing need to do
after activemq broker started.
2007-07-20 13:46:31,422 INFO [main
org.apache.activemq.broker.BrokerService] ActiveMQ 4.1.1 JMS Message Broker
(smtpservice.broker1) is starting
2007-07-20 13:46:31,423 INFO [main
org.apache.activemq.broker.BrokerService] For help or more information
please see: http://incubator.apache.org/activemq/
2007-07-20 13:46:31,601 INFO [JMX connector
org.apache.activemq.broker.jmx.ManagementContext] JMX consoles can connect
to service:jmx:rmi:///jndi/rmi://localhost:1099/jmxrmi
2007-07-20 13:46:33,077 INFO [main
org.apache.activemq.store.jdbc.JDBCPersistenceAdapter] Database driver
recognized: [oracle_jdbc_driver]
2007-07-20 13:46:33,867 INFO [main
org.apache.activemq.store.jdbc.DefaultDatabaseLocker] Attempting to acquire
the exclusive lock to become the Master broker
*********** Slave server blocked here , all rest of jobs are halted .
****************
2007-07-20 13:46:33,976 INFO [main
org.apache.activemq.store.jdbc.DefaultDatabaseLocker] Becoming the master on
dataSource: org.apache.commons.dbcp.BasicDataSource@1e193f2
2007-07-20 13:46:35,503 INFO [main
org.apache.activemq.store.journal.JournalPersistenceAdapter] Journal
Recovery Started from: Active Journal: using 2 x 20.0 Megs at:
/u01/app/jboss/jboss-4.0.4.GA/server/emailservice-01/activemq-data/journal
2007-07-20 13:46:36,273 INFO [main
org.apache.activemq.store.journal.JournalPersistenceAdapter] Journal
Recovered: 15 message(s) in transactions recovered.
2007-07-20 13:46:37,220 INFO [main
org.apache.activemq.transport.TransportServerThreadSupport] Listening for
connections at: tcp://test-01:61616
2007-07-20 13:46:37,279 INFO [main
org.apache.activemq.broker.TransportConnector] Connector smtpservice.broker1
Started
2007-07-20 13:46:37,495 INFO [main
org.apache.activemq.network.NetworkConnector] Establishing network
connection between from vm://smtpservice.broker1?network=true to
failover:(tcp://192.168.105.50:61616)?maxReconnectDelay=1000
2007-07-20 13:46:37,770 INFO [main
org.apache.activemq.broker.TransportConnector] Connector
vm://smtpservice.broker1 Started
2007-07-20 13:46:37,974 INFO [main
org.apache.activemq.network.NetworkConnector] Establishing network
connection between from vm://smtpservice.broker1?network=true to
failover:(tcp://192.168.105.51:61616)?maxReconnectDelay=1000
2007-07-20 13:46:37,985 INFO [main
org.apache.activemq.network.NetworkConnector] Network Connector bridge
Started
2007-07-20 13:46:37,985 INFO [main
org.apache.activemq.broker.BrokerService] ActiveMQ JMS Message Broker
(smtpservice.broker1, ID:test-01-39836-1184953572196-1:0) started
2007-07-20 13:46:38,077 INFO [main
org.jboss.resource.deployment.RARDeployment] Required license terms exist,
view META-INF/ra.xml in .../deploy/jboss-ha-local-jdbc.rar
2007-07-20 13:46:38,162 INFO [main
org.jboss.resource.deployment.RARDeployment] Required license terms exist,
view META-INF/ra.xml in .../deploy/jboss-ha-xa-jdbc.rar
2007-07-20 13:46:38,257 INFO [main
org.jboss.resource.deployment.RARDeployment] Required license terms exist,
view META-INF/ra.xml in .../deploy/jboss-local-jdbc.rar
2007-07-20 13:46:38,343 INFO [main
org.jboss.resource.deployment.RARDeployment] Required license terms exist,
view META-INF/ra.xml in .../deploy/jboss-xa-jdbc.rar
2007-07-20 13:46:38,494 INFO [main
org.jboss.resource.deployment.RARDeployment] Required license terms exist,
view META-INF/ra.xml in .../deploy/jms/jms-ra.rar
2007-07-20 13:46:38,594 INFO [main
org.jboss.resource.deployment.RARDeployment] Required license terms exist,
view META-INF/ra.xml in .../deploy/mail-ra.rar
2007-07-20 13:46:39,362 INFO [main
org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] Bound
ConnectionManager
'jboss.jca:service=ConnectionFactoryBinding,name=activemq/QueueConnectionFactory'
to JNDI name 'java:activemq/QueueConnectionFactory'
2007-07-20 13:46:39,396 INFO [main
org.jboss.resource.deployment.AdminObject] Bound admin object
'org.apache.activemq.command.ActiveMQQueue' at 'activemq/queue/outbound'
[Full GC 76301K->22553K(266112K), 1.2092692 secs]
2007-07-20 13:46:43,027 INFO [main
org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] Bound
ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DefaultDS' to
JNDI name 'java:DefaultDS'
2007-07-20 13:46:44,153 INFO [main org.jboss.mq.server.jmx.Queue.A] Bound
to JNDI name: queue/A
2007-07-20 13:46:44,158 INFO [main org.jboss.mq.server.jmx.Queue.B] Bound
to JNDI name: queue/B
2007-07-20 13:46:44,163 INFO [main org.jboss.mq.server.jmx.Queue.C] Bound
to JNDI name: queue/C
2007-07-20 13:46:44,168 INFO [main org.jboss.mq.server.jmx.Queue.D] Bound
to JNDI name: queue/D
2007-07-20 13:46:44,173 INFO [main org.jboss.mq.server.jmx.Queue.ex] Bound
to JNDI name: queue/ex
2007-07-20 13:46:44,275 INFO [main org.jboss.mq.server.jmx.Topic.testTopic]
Bound to JNDI name: topic/testTopic
2007-07-20 13:46:44,282 INFO [main
org.jboss.mq.server.jmx.Topic.securedTopic] Bound to JNDI name:
topic/securedTopic
2007-07-20 13:46:44,287 INFO [main
org.jboss.mq.server.jmx.Topic.testDurableTopic] Bound to JNDI name:
topic/testDurableTopic
2007-07-20 13:46:44,300 INFO [main org.jboss.mq.server.jmx.Queue.testQueue]
Bound to JNDI name: queue/testQueue
2007-07-20 13:46:44,504 INFO [main org.jboss.mq.il.uil2.UILServerILService]
JBossMQ UIL service available at : /0.0.0.0:53002
2007-07-20 13:46:44,684 INFO [main org.jboss.mq.server.jmx.Queue.DLQ] Bound
to JNDI name: queue/DLQ
2007-07-20 13:46:45,197 INFO [main
org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] Bound
ConnectionManager 'jboss.jca:service=ConnectionFactoryBinding,name=JmsXA' to
JNDI name 'java:JmsXA'
2007-07-20 13:46:45,435 INFO [main
org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] Bound
ConnectionManager 'jboss.jca:service=DataSourceBinding,name=OracleDS' to
JNDI name 'java:OracleDS'
2007-07-20 13:46:45,622 INFO [main org.hibernate.cfg.Environment] Hibernate
3.2 cr2
2007-07-20 13:46:45,671 INFO [main org.hibernate.cfg.Environment]
hibernate.properties not found
2007-07-20 13:46:45,685 INFO [main org.hibernate.cfg.Environment] Bytecode
provider name : javassist
2007-07-20 13:46:45,734 INFO [main org.hibernate.cfg.Environment] using JDK
1.4 java.sql.Timestamp handling
2007-07-20 13:46:46,393 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59099log4j-1.2.12.jar
2007-07-20 13:46:46,401 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59102velocity-dep-1.2.jar
2007-07-20 13:46:46,410 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59094jboss-common.jar
2007-07-20 13:46:46,419 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59097jboss-system.jar
2007-07-20 13:46:46,423 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59096jboss-jmx.jar
2007-07-20 13:46:46,433 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59086commons-collections-3.0.jar
2007-07-20 13:46:46,444 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar:
tmp59087commons-configuration-2.1.jar
2007-07-20 13:46:46,447 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59091commons-pool-1.2.jar
2007-07-20 13:46:46,449 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59101ojdbc14.jar
2007-07-20 13:46:46,463 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59098junit-3.8.1.jar
2007-07-20 13:46:46,468 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59083apache-activemq-4.1.1.jar
2007-07-20 13:46:46,552 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59093hibernate3.jar
2007-07-20 13:46:46,582 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59100mail.jar
2007-07-20 13:46:46,590 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59082activation.jar
2007-07-20 13:46:46,591 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59090commons-httpclient-3.0.jar
2007-07-20 13:46:46,596 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59095jboss-j2ee.jar
2007-07-20 13:46:46,608 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59084aspectjrt-1.1.1.jar
2007-07-20 13:46:46,611 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59089commons-digester-1.5.jar
2007-07-20 13:46:46,614 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59092concurrent.jar
2007-07-20 13:46:46,618 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59085cactus-1.6.1.jar
2007-07-20 13:46:46,622 INFO [main org.hibernate.cfg.Configuration]
Searching for mapping documents in jar: tmp59088commons-dbcp-1.2.2.jar
2007-07-20 13:46:46,626 INFO [main org.hibernate.cfg.Configuration] Reading
mappings from file:
/u01/app/jboss/jboss-4.0.4.GA/server/emailservice-01/deploy/emailservice.sar/mapping/AppKey.hbm.xml
2007-07-20 13:46:47,257 INFO [main org.hibernate.cfg.HbmBinder] Mapping
class: com.air2web.smtpservice.model.AppKey -> SMTP_APPKEY
2007-07-20 13:46:47,406 INFO [main org.hibernate.cfg.Configuration] Reading
mappings from file:
/u01/app/jboss/jboss-4.0.4.GA/server/emailservice-01/deploy/emailservice.sar/mapping/AppLog.hbm.xml
2007-07-20 13:46:47,528 INFO [main org.hibernate.cfg.HbmBinder] Mapping
class: com.air2web.smtpservice.model.AppLog -> SMTP_APPLOG
2007-07-20 13:46:47,537 INFO [main org.hibernate.cfg.Configuration] Reading
mappings from file:
/u01/app/jboss/jboss-4.0.4.GA/server/emailservice-01/deploy/emailservice.sar/mapping/RouterMapping.hbm.xml
2007-07-20 13:46:47,640 INFO [main org.hibernate.cfg.HbmBinder] Mapping
class: com.air2web.smtpservice.model.RouterMapping -> SMTP_ROUTER
2007-07-20 13:46:48,256 INFO [main org.hibernate.util.NamingHelper] JNDI
InitialContext properties:{}
2007-07-20 13:46:48,264 INFO [main
org.hibernate.connection.DatasourceConnectionProvider] Using datasource:
java:OracleDS
2007-07-20 13:46:51,924 INFO [main org.hibernate.cfg.SettingsFactory]
RDBMS: Oracle, version: Oracle Database 10g Enterprise Edition Release
10.2.0.2.0 - 64bit Production
With the Partitioning, Real Application Clusters, OLAP and Data Mining
options
2007-07-20 13:46:51,926 INFO [main org.hibernate.cfg.SettingsFactory] JDBC
driver: Oracle JDBC driver, version: 10.2.0.2.0
2007-07-20 13:46:52,187 INFO [main org.hibernate.dialect.Dialect] Using
dialect: org.hibernate.dialect.OracleDialect
2007-07-20 13:46:52,244 INFO [main
org.hibernate.transaction.TransactionFactoryFactory] Transaction strategy:
org.hibernate.transaction.JTATransactionFactory
2007-07-20 13:46:52,245 INFO [main org.hibernate.util.NamingHelper] JNDI
InitialContext properties:{}
2007-07-20 13:46:52,256 INFO [main
org.hibernate.transaction.TransactionManagerLookupFactory] instantiating
TransactionManagerLookup:
org.hibernate.transaction.JBossTransactionManagerLookup
2007-07-20 13:46:52,256 INFO [main
org.hibernate.transaction.TransactionManagerLookupFactory] instantiated
TransactionManagerLookup
2007-07-20 13:46:52,257 INFO [main
org.hibernate.transaction.TransactionManagerLookupFactory] instantiating
TransactionManagerLookup:
org.hibernate.transaction.JBossTransactionManagerLookup
2007-07-20 13:46:52,258 INFO [main
org.hibernate.transaction.TransactionManagerLookupFactory] instantiated
TransactionManagerLookup
2007-07-20 13:46:52,258 INFO [main org.hibernate.cfg.SettingsFactory]
Automatic flush during beforeCompletion(): enabled
2007-07-20 13:46:52,259 INFO [main org.hibernate.cfg.SettingsFactory]
Automatic session close at end of transaction: enabled
2007-07-20 13:46:52,259 INFO [main org.hibernate.cfg.SettingsFactory] JDBC
batch size: 15
2007-07-20 13:46:52,260 INFO [main org.hibernate.cfg.SettingsFactory] JDBC
batch updates for versioned data: disabled
2007-07-20 13:46:52,267 INFO [main org.hibernate.cfg.SettingsFactory]
Scrollable result sets: enabled
2007-07-20 13:46:52,268 INFO [main org.hibernate.cfg.SettingsFactory] JDBC3
getGeneratedKeys(): disabled
2007-07-20 13:46:52,269 INFO [main org.hibernate.cfg.SettingsFactory]
Connection release mode: after_statement
2007-07-20 13:46:52,277 INFO [main org.hibernate.cfg.SettingsFactory]
Default batch fetch size: 1
2007-07-20 13:46:52,277 INFO [main org.hibernate.cfg.SettingsFactory]
Generate SQL with comments: disabled
2007-07-20 13:46:52,278 INFO [main org.hibernate.cfg.SettingsFactory] Order
SQL updates by primary key: disabled
2007-07-20 13:46:52,279 INFO [main org.hibernate.cfg.SettingsFactory] Query
translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
2007-07-20 13:46:52,306 INFO [main
org.hibernate.hql.ast.ASTQueryTranslatorFactory] Using
ASTQueryTranslatorFactory
2007-07-20 13:46:52,307 INFO [main org.hibernate.cfg.SettingsFactory] Query
language substitutions: {}
2007-07-20 13:46:52,307 INFO [main org.hibernate.cfg.SettingsFactory]
Second-level cache: enabled
2007-07-20 13:46:52,308 INFO [main org.hibernate.cfg.SettingsFactory] Query
cache: disabled
2007-07-20 13:46:52,309 INFO [main org.hibernate.cfg.SettingsFactory] Cache
provider: org.hibernate.cache.HashtableCacheProvider
2007-07-20 13:46:52,322 INFO [main org.hibernate.cfg.SettingsFactory]
Optimize cache for minimal puts: disabled
2007-07-20 13:46:52,323 INFO [main org.hibernate.cfg.SettingsFactory]
Structured second-level cache entries: disabled
2007-07-20 13:46:52,385 INFO [main org.hibernate.cfg.SettingsFactory]
Statistics: disabled
2007-07-20 13:46:52,386 INFO [main org.hibernate.cfg.SettingsFactory]
Deleted entity synthetic identifier rollback: disabled
2007-07-20 13:46:52,386 INFO [main org.hibernate.cfg.SettingsFactory]
Default entity-mode: pojo
2007-07-20 13:46:52,658 INFO [main org.hibernate.impl.SessionFactoryImpl]
building session factory
2007-07-20 13:46:54,449 INFO [main
org.hibernate.impl.SessionFactoryObjectFactory] Not binding factory to JNDI,
no JNDI name configured
2007-07-20 13:46:54,451 INFO [main org.hibernate.util.NamingHelper] JNDI
InitialContext properties:{}
2007-07-20 13:46:54,478 INFO [main org.jboss.hibernate.jmx.Hibernate]
SessionFactory successfully built and bound into JNDI
[java:/hibernate/SessionFactory]
2007-07-20 13:46:54,741 INFO [main
com.air2web.smtpservice.util.AppConfigurationManager] property file
name=file:/u01/app/jboss/jboss-4.0.4.GA/server/emailservice-01/conf/emailservice-01/app.properties
properties:
activemq.broker.name=[smtpservice.broker1]
activemq.url=[failover:(tcp://192.168.105.50:61616,tcp://192.168.105.51:61616)]
2007-07-20 13:47:10,735 INFO [main
com.air2web.smtpservice.jms.activemq.MqConsumer] ActiveMq- set up consumer
connection - queue://SMTPMessage_Queue - 1
2007-07-20 13:47:18,961 INFO [main
com.air2web.smtpservice.jms.activemq.MqConsumer] ActiveMq- set up consumer
connection - queue://SMTPMessage_Queue - 2
2007-07-20 13:47:23,116 INFO [main
com.air2web.smtpservice.jms.activemq.MqConsumer] ActiveMq- set up consumer
connection - queue://SMTPMessage_Queue - 3
2007-07-20 13:47:23,562 INFO [main
com.air2web.smtpservice.jms.activemq.MqConsumer] ActiveMq- set up consumer
connection - queue://SMTPMessage_Queue - 4
2007-07-20 13:47:24,008 INFO [main
com.air2web.smtpservice.jms.activemq.MqConsumer] ActiveMq- set up consumer
connection - queue://SMTPMessage_Queue - 5
2007-07-20 13:47:31,837 INFO [main org.jboss.web.tomcat.tc5.TomcatDeployer]
deploy, ctxPath=/emailservice, warUrl=.../deploy/emailservice.war/
2007-07-20 13:47:32,553 INFO [main org.jboss.web.tomcat.tc5.TomcatDeployer]
deploy, ctxPath=/jmx-console, warUrl=.../deploy/jmx-console.war/
2007-07-20 13:47:33,334 INFO [main
org.apache.coyote.http11.Http11BaseProtocol] Starting Coyote HTTP/1.1 on
http-0.0.0.0-7601
2007-07-20 13:47:33,679 INFO [main org.apache.jk.common.ChannelSocket] JK:
ajp13 listening on /0.0.0.0:57002
2007-07-20 13:47:33,717 INFO [main org.apache.jk.server.JkMain] Jk running
ID=0 time=0/144 config=null
2007-07-20 13:47:33,748 INFO [main org.jboss.system.server.Server] JBoss
(MX MicroKernel) [4.0.4.GA (build: CVSTag=JBoss_4_0_4_GA date=200605151000)]
Started in 2m:38s:42ms
--
View this message in context: http://www.nabble.com/JBDC-Master-slave-%2C--slave-server-blocked-my-application.-tf4118696s2354.html#a11713185
Sent from the ActiveMQ - User mailing list archive at Nabble.com.