activemq-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From ajitgirish <ajit_gir...@infosys.com>
Subject Active Mq logs shows repeted disconnection .
Date Wed, 07 Oct 2009 08:45:39 GMT

Hi ,

I am getting the following error in the ActiveMq logs.

2009-09-25 15:35:20,414 [iveMQ Scheduler] DEBUG AMQPersistenceAdapter         
- dataFilesInProgress.values: (0) []
2009-09-25 15:35:20,415 [iveMQ Scheduler] DEBUG AMQPersistenceAdapter         
- lastDataFile: 1
2009-09-25 15:35:20,416 [iveMQ Scheduler] DEBUG AsyncDataManager              
- lastFileId=0, purgeList: (0) []
2009-09-25 15:35:20,422 [eckpoint Worker] DEBUG AMQPersistenceAdapter         
- Checkpoint started.
2009-09-25 15:35:20,422 [eckpoint Worker] DEBUG AMQPersistenceAdapter         
- Checkpoint done.
2009-09-25 15:35:21,311 [1.211.234:50029] DEBUG Transport      
java.io.EOFException
        at java.io.DataInputStream.readByte(DataInputStream.java:250)
        at
org.apache.activemq.transport.stomp.StompWireFormat.readLine(StompWireFormat.java:186)
        at
org.apache.activemq.transport.stomp.StompWireFormat.unmarshal(StompWireFormat.java:94)
        at
org.apache.activemq.transport.tcp.TcpTransport.readCommand(TcpTransport.java:210)
        at
org.apache.activemq.transport.tcp.TcpTransport.doRun(TcpTransport.java:202)
        at
org.apache.activemq.transport.tcp.TcpTransport.run(TcpTransport.java:185)
        at java.lang.Thread.run(Thread.java:619)
2009-09-25 15:35:21,358 [115.12.62:60205] DEBUG Transport                     
- Transport failed: java.io.EOFException
java.io.EOFException
        at java.io.DataInputStream.readByte(DataInputStream.java:250)
        at
org.apache.activemq.transport.stomp.StompWireFormat.readLine(StompWireFormat.java:186)
        at
org.apache.activemq.transport.stomp.StompWireFormat.unmarshal(StompWireFormat.java:94)
        at
org.apache.activemq.transport.tcp.TcpTransport.readCommand(TcpTransport.java:210)
        at
org.apache.activemq.transport.tcp.TcpTransport.doRun(TcpTransport.java:202)
        at
org.apache.activemq.transport.tcp.TcpTransport.run(TcpTransport.java:185)
        at java.lang.Thread.run(Thread.java:619)
2009-09-25 15:35:21,584 [1.211.231:36383] DEBUG TransportConnection           
- Setting up new connection: /xxxxx:36383
2009-09-25 15:35:21,597 [115.12.63:41433] DEBUG Transport                     
- Transport failed: java.io.EOFException
java.io.EOFException
        at java.io.DataInputStream.readByte(DataInputStream.java:250)
        at
org.apache.activemq.transport.stomp.StompWireFormat.readLine(StompWireFormat.java:186)
        at
org.apache.activemq.transport.stomp.StompWireFormat.unmarshal(StompWireFormat.java:94)
        at
org.apache.activemq.transport.tcp.TcpTransport.readCommand(TcpTransport.java:210)
        at
org.apache.activemq.transport.tcp.TcpTransport.doRun(TcpTransport.java:202)
        at
org.apache.activemq.transport.tcp.TcpTransport.run(TcpTransport.java:185)
        at java.lang.Thread.run(Thread.java:619)
2009-09-25 15:35:22,636 [iveMQ Scheduler] DEBUG AMQPersistenceAdapter         
- dataFilesInProgress.values: (0) []
2009-09-25 15:35:22,636 [iveMQ Scheduler] DEBUG AMQPersistenceAdapter         
- lastDataFile: 1
2009-09-25 15:35:22,637 [iveMQ Scheduler] DEBUG AsyncDataManager              
- lastFileId=0, purgeList: (0) []
2009-09-25 15:35:22,638 [115.12.62:60205] DEBUG TransportConnection           
- Stopping connection: /yyyyyy:60205
2009-09-25 15:35:22,638 [115.12.62:60205] DEBUG TcpTransport                  
- Stopping transport tcp:///zzzzzzz:60205
2009-09-25 15:35:22,672 [1.211.234:50029] DEBUG TransportConnection           
- Stopping connection: /qqqqqqq:50029
2009-09-25 15:35:22,672 [1.211.234:50029] DEBUG TcpTransport                  
- Stopping transport tcp:///rrrrrrr:50029
2009-09-25 15:35:22,775 [1.211.234:33435] DEBUG TransportConnection           
- Setting up new connection: /xxxxxx:33435
2009-09-25 15:35:22,837 [115.12.62:48216] DEBUG TransportConnection           
- Setting up new connection: /xxxxxx:48216
2009-09-25 15:35:23,787 [1.211.231:48547] DEBUG Transport                     
- Transport failed: java.io.EOFException
java.io.EOFException
        at java.io.DataInputStream.readByte(DataInputStream.java:250)
        at
org.apache.activemq.transport.stomp.StompWireFormat.readLine(StompWireFormat.java:186)
        at
org.apache.activemq.transport.stomp.StompWireFormat.unmarshal(StompWireFormat.java:94)
        at
org.apache.activemq.transport.tcp.TcpTransport.readCommand(TcpTransport.java:210)
        at
org.apache.activemq.transport.tcp.TcpTransport.doRun(TcpTransport.java:202)
        at
org.apache.activemq.transport.tcp.TcpTransport.run(TcpTransport.java:185)
        at java.lang.Thread.run(Thread.java:619)
        2009-09-25 15:35:30,672 [115.12.63:53051] DEBUG TransportConnection           
- Setting up new connection: /XXXXXX:53051
        2009-09-25 15:35:30,745 [115.12.62:60205] DEBUG TransportConnection           
- Stopped transport: /YYYY:60205
        2009-09-25 15:35:30,753 [115.12.62:60205] DEBUG TransportConnection           
- Cleaning up connection resources: /YYYY:60205
        2009-09-25 15:35:30,754 [115.12.62:60205] DEBUG TransportConnection           
- Connection Stopped: /YYYY:60205
        2009-09-25 15:35:34,188 [1.211.234:50029] DEBUG TransportConnection           
- Stopped transport: /ZZZZ:50029
        2009-09-25 15:35:34,210 [115.12.63:41433] DEBUG TransportConnection           
- Stopping connection: /XXXXXX:41433
        2009-09-25 15:35:34,418 [115.12.63:41433] DEBUG TcpTransport                  
- Stopping transport tcp:///XXXXXX:41433
        2009-09-25 15:35:34,418 [1.211.231:48547] DEBUG TransportConnection           
- Stopping connection: /WWWWW:48547
        2009-09-25 15:35:34,418 [1.211.231:48547] DEBUG TcpTransport                  
- Stopping transport tcp:///WWWWW:48547
        2009-09-25 15:35:34,418 [115.12.63:41433] DEBUG TransportConnection           
- Stopped transport: /XXXXXX:41433
        2009-09-25 15:35:34,419 [1.211.231:48547] DEBUG TransportConnection           
- Stopped transport: /WWWWW:48547
        2009-09-25 15:35:34,471 [eckpoint Worker] DEBUG
AMQPersistenceAdapter          - Checkpoint started.
        2009-09-25 15:35:34,472 [eckpoint Worker] DEBUG
AMQPersistenceAdapter          - Checkpoint done.
        2009-09-25 15:35:34,492 [1.211.231:48547] DEBUG TransportConnection           
- Cleaning up connection resources: /WWWWW:48547
        2009-09-25 15:35:34,493 [1.211.231:48547] DEBUG TransportConnection           
- Connection Stopped: /WWWWW:48547
        2009-09-25 15:35:34,493 [1.211.234:50029] DEBUG TransportConnection           
- Cleaning up connection resources: /ZZZZ:50029
        2009-09-25 15:35:34,493 [1.211.234:50029] DEBUG TransportConnection           
- Connection Stopped: /ZZZZ:50029
        2009-09-25 15:36:53,967 [iveMQ Scheduler] DEBUG
AMQPersistenceAdapter          - dataFilesInProgress.values: (0) []
        2009-09-25 15:36:53,973 [iveMQ Scheduler] DEBUG
AMQPersistenceAdapter          - lastDataFile: 1
        2009-09-25 15:36:54,146 [115.12.63:41433] DEBUG TransportConnection           
- Cleaning up connection resources: /XXXXXX:41433
        2009-09-25 15:37:05,214 [115.12.63:41433] DEBUG TransportConnection           
- Connection Stopped: /XXXXXX:41433
        2009-09-25 15:37:05,213 [eckpoint Worker] DEBUG
AMQPersistenceAdapter          - Checkpoint started.
        2009-09-25 15:37:01,326 [iveMQ Scheduler] DEBUG AsyncDataManager              
- lastFileId=0, purgeList: (0) []
        2009-09-25 15:37:05,273 [iveMQ Scheduler] DEBUG
AMQPersistenceAdapter          - dataFilesInProgress.values: (0) []
        2009-09-25 15:37:05,274 [iveMQ Scheduler] DEBUG
AMQPersistenceAdapter          - lastDataFile: 1
        2009-09-25 15:37:05,274 [iveMQ Scheduler] DEBUG AsyncDataManager              
- lastFileId=0, purgeList: (0) []
        2009-09-25 15:37:05,274 [iveMQ Scheduler] DEBUG
AMQPersistenceAdapter          - dataFilesInProgress.values: (0) []
        2009-09-25 15:37:05,274 [iveMQ Scheduler] DEBUG
AMQPersistenceAdapter          - lastDataFile: 1
        2009-09-25 15:37:05,274 [iveMQ Scheduler] DEBUG AsyncDataManager              
- lastFileId=0, purgeList: (0) []
        2009-09-25 15:37:05,276 [eckpoint Worker] DEBUG
AMQPersistenceAdapter          - Checkpoint done.
        2009-09-25 15:37:05,276 [eckpoint Worker] DEBUG
AMQPersistenceAdapter          - Checkpoint started.
2009-09-25 15:37:05,276 [eckpoint Worker] DEBUG AMQPersistenceAdapter         
- Checkpoint done.
2009-09-25 15:37:44,873 [iveMQ Scheduler] DEBUG AMQPersistenceAdapter         
- dataFilesInProgress.values: (0) []
2009-09-25 15:37:44,945 [iveMQ Scheduler] DEBUG AMQPersistenceAdapter         
- lastDataFile: 1
2009-09-25 15:37:44,946 [iveMQ Scheduler] DEBUG AsyncDataManager              
- lastFileId=0, purgeList: (0) []
2009-09-25 15:37:44,945 [eckpoint Worker] DEBUG AMQPersistenceAdapter         
- Checkpoint started.
2009-09-25 15:37:45,024 [eckpoint Worker] DEBUG AMQPersistenceAdapter         
- Checkpoint done.
2009-09-25 15:37:45,025 [eckpoint Worker] DEBUG AMQPersistenceAdapter         
- Checkpoint started.
2009-09-25 15:37:45,025 [eckpoint Worker] DEBUG AMQPersistenceAdapter         
- Checkpoint done.
2009-09-25 15:37:52,402 [iveMQ Scheduler] DEBUG AMQPersistenceAdapter         
- dataFilesInProgress.values: (0) []
2009-09-25 15:38:18,424 [iveMQ Scheduler] DEBUG AMQPersistenceAdapter         
- lastDataFile: 1
2009-09-25 15:38:18,524 [iveMQ Scheduler] DEBUG AsyncDataManager              
- lastFileId=0, purgeList: (0) []
2009-09-25 15:37:52,710 [eckpoint Worker] DEBUG AMQPersistenceAdapter         
- Checkpoint started.
2009-09-25 15:38:18,524 [eckpoint Worker] DEBUG AMQPersistenceAdapter         
- Checkpoint done.
2009-09-25 15:38:18,525 [eckpoint Worker] DEBUG AMQPersistenceAdapter         
- Checkpoint started.
2009-09-25 15:38:18,525 [eckpoint Worker] DEBUG AMQPersistenceAdapter         
- Checkpoint done.
2009-09-25 15:38:23,171 [iveMQ Scheduler] DEBUG AMQPersistenceAdapter         
- dataFilesInProgress.values: (0) []
2009-09-25 15:38:27,644 [iveMQ Scheduler] DEBUG AMQPersistenceAdapter         
- lastDataFile: 1
2009-09-25 15:38:27,648 [iveMQ Scheduler] DEBUG AsyncDataManager              
- lastFileId=0, purgeList: (0) []
2009-09-25 15:38:32,373 [eckpoint Worker] DEBUG AMQPersistenceAdapter         
- Checkpoint started.
2009-09-25 15:38:32,374 [eckpoint Worker] DEBUG AMQPersistenceAdapter         
- Checkpoint done.
2009-09-25 15:40:54,506 [erSimpleAppMain] DEBUG XBeanBrokerFactory            
- Now attempting to figure out the type of resource:
file:/home/dg/sorter/activemq/conf/sorter.xml
2009-09-25 15:40:58,292 [erSimpleAppMain] ERROR BrokerService                 
- Broker Name: %HOSTNAME% contained illegal characters - replaced with
_HOSTNAME_
2009-09-25 15:40:58,582 [erSimpleAppMain] INFO  BrokerService                 
- Using Persistence Adapter:
AMQPersistenceAdapter(/home/dg/sorter/activemq/data/_HOSTNAME_)
2009-09-25 15:40:58,747 [erSimpleAppMain] INFO  AMQPersistenceAdapter         
- AMQStore starting using directory:
/home/dg/sorter/activemq/data/_HOSTNAME_
2009-09-25 15:40:58,936 [erSimpleAppMain] INFO  KahaStore                     
- Kaha Store using data directory
/home/dg/sorter/activemq/data/_HOSTNAME_/kr-store/state
2009-09-25 15:40:59,849 [erSimpleAppMain] INFO  AMQPersistenceAdapter         
- Active data files: []
2009-09-25 15:40:59,854 [erSimpleAppMain] WARN  AMQPersistenceAdapter         
- The ReferenceStore is not valid - recovering ...
2009-09-25 15:40:59,863 [erSimpleAppMain] INFO  KahaStore                     
- Kaha Store successfully deleted data directory
/home/dg/sorter/activemq/data/_HOSTNAME_/kr-store/data
2009-09-25 15:40:59,868 [erSimpleAppMain] INFO  AMQPersistenceAdapter         
- Journal Recovery Started from: DataManager:(data-)
2009-09-25 15:40:59,871 [erSimpleAppMain] DEBUG AMQPersistenceAdapter         
- TRACE Entry: RECOVERED Tue Sep 22 01:50:52 EST 2009
2009-09-25 15:40:59,879 [erSimpleAppMain] INFO  AMQPersistenceAdapter         
- Recovered 0 operations from redo log in 0.01 seconds.
2009-09-25 15:40:59,879 [erSimpleAppMain] INFO  AMQPersistenceAdapter         
- Finished recovering the ReferenceStore
2009-09-25 15:40:59,884 [eckpoint Worker] DEBUG AMQPersistenceAdapter         
- Checkpoint started.
2009-09-25 15:40:59,886 [eckpoint Worker] DEBUG AMQPersistenceAdapter         
- Checkpoint done.
2009-09-25 15:41:00,434 [erSimpleAppMain] DEBUG ManagementContext             
- Probably not using JRE 1.4: mx4j.tools.naming.NamingService
2009-09-25 15:41:00,495 [erSimpleAppMain] DEBUG ManagementContext             
- Failed to create local registry
java.rmi.server.ExportException: internal error: ObjID already in use
        at sun.rmi.transport.ObjectTable.putTarget(ObjectTable.java:169)
        at sun.rmi.transport.Transport.exportObject(Transport.java:74)
        at
sun.rmi.transport.tcp.TCPTransport.exportObject(TCPTransport.java:229)
        at
sun.rmi.transport.tcp.TCPEndpoint.exportObject(TCPEndpoint.java:393)
        at sun.rmi.transport.LiveRef.exportObject(LiveRef.java:129)
        at
sun.rmi.server.UnicastServerRef.exportObject(UnicastServerRef.java:190)
        at sun.rmi.registry.RegistryImpl.setup(RegistryImpl.java:92)
        at sun.rmi.registry.RegistryImpl.<init>(RegistryImpl.java:78)
        at
java.rmi.registry.LocateRegistry.createRegistry(LocateRegistry.java:186)
        at
org.apache.activemq.broker.jmx.ManagementContext.createConnector(ManagementContext.java:365)
        at
org.apache.activemq.broker.jmx.ManagementContext.findMBeanServer(ManagementContext.java:293)
        at
org.apache.activemq.broker.jmx.ManagementContext.getMBeanServer(ManagementContext.java:148)
        at
org.apache.activemq.broker.jmx.ManagementContext.start(ManagementContext.java:77)
        at
org.apache.activemq.broker.BrokerService.start(BrokerService.java:465)
        at
org.apache.activemq.xbean.XBeanBrokerService.afterPropertiesSet(XBeanBrokerService.java:52)
        at
org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1368)
        at
org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1334)
        at
org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:473)
        at
org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory$1.run(AbstractAutowireCapableBeanFactory.java:409)
        at java.security.AccessController.doPrivileged(Native Method)
        at
org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:380)
        at
org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:264)
        at
org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:221)
        at
org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:261)
        at
org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:185)
        at
org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:164)
        at
org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:429)
        at
org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:729)
        at
org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:381)
        at
org.apache.xbean.spring.context.ResourceXmlApplicationContext.<init>(ResourceXmlApplicationContext.java:64)
        at
org.apache.xbean.spring.context.ResourceXmlApplicationContext.<init>(ResourceXmlApplicationContext.java:52)
        at
org.apache.activemq.xbean.XBeanBrokerFactory.createApplicationContext(XBeanBrokerFactory.java:96)
        at
org.apache.activemq.xbean.XBeanBrokerFactory.createBroker(XBeanBrokerFactory.java:52)
        at
org.apache.activemq.broker.BrokerFactory.createBroker(BrokerFactory.java:71)
        at
org.apache.activemq.broker.BrokerFactory.createBroker(BrokerFactory.java:54)
        at
org.apache.activemq.console.command.StartCommand.startBroker(StartCommand.java:115)
        at
org.apache.activemq.console.command.StartCommand.runTask(StartCommand.java:91)
        at
org.apache.activemq.console.command.AbstractCommand.execute(AbstractCommand.java:57)
        at
org.apache.activemq.console.command.ShellCommand.runTask(ShellCommand.java:129)
        at
org.apache.activemq.console.command.AbstractCommand.execute(AbstractCommand.java:57)
        at
org.apache.activemq.console.command.ShellCommand.main(ShellCommand.java:79)
        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:597)
        at org.apache.activemq.console.Main.runTaskClass(Main.java:225)
        at org.apache.activemq.console.Main.main(Main.java:106)
        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:597)
        at
org.tanukisoftware.wrapper.WrapperSimpleApp.run(WrapperSimpleApp.java:240)
        at java.lang.Thread.run(Thread.java:619)
2009-09-25 15:41:00,550 [erSimpleAppMain] INFO  BrokerService                 
- ActiveMQ 5.2.0 JMS Message Broker (_HOSTNAME_) is starting
2009-09-25 15:41:00,552 [erSimpleAppMain] INFO  BrokerService                 
- For help or more information please see: http://activemq.apache.org/
2009-09-25 15:41:01,399 [erSimpleAppMain] INFO  KahaStore                     
- Kaha Store using data directory
/home/dg/sorter/activemq/data/_HOSTNAME_/kr-store/data
2009-09-25 15:41:01,606 [JMX connector  ] INFO  ManagementContext             
- JMX consoles can connect to
service:jmx:rmi:///jndi/rmi://localhost:1099/jmxrmi
2009-09-25 15:41:01,608 [erSimpleAppMain] INFO  TransportServerThreadSupport  
- Listening for connections at:
stomp://dgce-ora-00.networks.in.telstra.com.au:61613
2009-09-25 15:41:01,610 [erSimpleAppMain] INFO  TransportConnector            
- Connector stomp Started
2009-09-25 15:41:01,662 [erSimpleAppMain] INFO  TransportServerThreadSupport  
- Listening for connections at:
tcp://dgce-ora-00.networks.in.telstra.com.au:61616?wireFormat.maxInactivityDuration=-1
2009-09-25 15:41:01,663 [erSimpleAppMain] INFO  TransportConnector            
- Connector openwire Started






This is happening again and again . After a while we have a scenario our
server crashed.

Any one has any idea how to resolve such issues. I am using Active Mq for my
message flow within the servers.

Thanks in Advance
Ajit.

-- 
View this message in context: http://www.nabble.com/Active-Mq-logs-shows-repeted-disconnection-.-tp25782767p25782767.html
Sent from the ActiveMQ - Dev mailing list archive at Nabble.com.


Mime
View raw message