activemq-users mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Eric-AWL <eric.vinc...@atosorigin.com>
Subject Re: 5.3.2 source tests : AMQ2149Test is blocked during broker stop.
Date Fri, 09 Jul 2010 09:14:08 GMT

I have another error with

Running org.apache.activemq.usecases.ExpiredMessagesWithNoConsumerTest
Tests run: 7, Failures: 2, Errors: 0, Skipped: 0, Time elapsed: 138.842 sec
<<< FAILURE!





2010-07-09 11:01:45,548 [main           ] INFO  BrokerService                 
- ActiveMQ JMS Message Broker (localhost,
ID:ytsvmx01s.priv.atos.fr-48579-12786
66094137-10:1) started
2010-07-09 11:01:45,771 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 100 @ 1m/ms
2010-07-09 11:01:45,944 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 200 @ 1m/ms
2010-07-09 11:01:46,066 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 300 @ 1m/ms
2010-07-09 11:01:46,180 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 400 @ 1m/ms
2010-07-09 11:01:46,308 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 500 @ 1m/ms
2010-07-09 11:01:46,450 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 600 @ 1m/ms
2010-07-09 11:01:46,564 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 700 @ 1m/ms
2010-07-09 11:01:46,702 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 800 @ 1m/ms
2010-07-09 11:01:46,851 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 900 @ 1m/ms
2010-07-09 11:01:46,972 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 1000 @ 1m/ms
2010-07-09 11:01:47,088 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 1100 @ 1m/ms
2010-07-09 11:01:47,202 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 1200 @ 1m/ms
2010-07-09 11:01:48,742 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 1300 @ 15m/ms
2010-07-09 11:01:48,957 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 1400 @ 2m/ms
2010-07-09 11:01:49,134 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 1500 @ 1m/ms
2010-07-09 11:01:49,303 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 1600 @ 1m/ms
2010-07-09 11:01:49,403 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 1700 @ 1m/ms
2010-07-09 11:01:49,515 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 1800 @ 1m/ms
2010-07-09 11:01:49,633 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 1900 @ 1m/ms
2010-07-09 11:01:49,742 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 2000 @ 1m/ms
2010-07-09 11:01:49,743 [main           ] INFO 
iredMessagesWithNoConsumerTest - enqueue=2000, dequeue=1219, inflight=0,
expired= 1219, size= 781
2010-07-09 11:01:50,747 [main           ] INFO 
iredMessagesWithNoConsumerTest - enqueue=2000, dequeue=1653, inflight=0,
expired= 1653, size= 347
2010-07-09 11:01:51,751 [main           ] INFO 
iredMessagesWithNoConsumerTest - enqueue=2000, dequeue=2000, inflight=0,
expired= 2000, size= 0
2010-07-09 11:01:51,752 [main           ] INFO 
iredMessagesWithNoConsumerTest - enqueue=2000, dequeue=2000, inflight=0,
expired= 2000, size= 0

then ...

2010-07-09 11:02:36,899 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 1700 @ 0m/ms
2010-07-09 11:02:37,012 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 1800 @ 1m/ms
2010-07-09 11:02:37,125 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 1900 @ 1m/ms
2010-07-09 11:02:37,254 [roducing Thread] INFO 
iredMessagesWithNoConsumerTest - sent: 2000 @ 1m/ms
2010-07-09 11:02:38,105 [main           ] INFO 
iredMessagesWithNoConsumerTest - enqueue=2000, dequeue=1350, inflight=0,
expired= 1350, size= 650
2010-07-09 11:02:39,107 [main           ] INFO 
iredMessagesWithNoConsumerTest - enqueue=2000, dequeue=1999, inflight=0,
expired= 1999, size= 1
2010-07-09 11:02:40,111 [main           ] INFO 
iredMessagesWithNoConsumerTest - enqueue=2000, dequeue=1999, inflight=0,
expired= 1999, size= 1
2010-07-09 11:02:41,115 [main           ] INFO 
iredMessagesWithNoConsumerTest - enqueue=2000, dequeue=1999, inflight=0,
expired= 1999, size= 1
2010-07-09 11:02:42,120 [main           ] INFO 
iredMessagesWithNoConsumerTest - enqueue=2000, dequeue=1999, inflight=0,
expired= 1999, size= 1
2010-07-09 11:02:43,124 [main           ] INFO 
iredMessagesWithNoConsumerTest - enqueue=2000, dequeue=1999, inflight=0,
expired= 1999, size= 1
2010-07-09 11:02:44,128 [main           ] INFO 
iredMessagesWithNoConsumerTest - enqueue=2000, dequeue=1999, inflight=0,
expired= 1999, size= 1
2010-07-09 11:02:45,132 [main           ] INFO 
iredMessagesWithNoConsumerTest - enqueue=2000, dequeue=1999, inflight=0,
expired= 1999, size= 1
2010-07-09 11:02:46,136 [main           ] INFO 
iredMessagesWithNoConsumerTest - enqueue=2000, dequeue=1999, inflight=0,
expired= 1999, size= 1
2010-07-09 11:02:47,140 [main           ] INFO 
iredMessagesWithNoConsumerTest - enqueue=2000, dequeue=1999, inflight=0,
expired= 1999, size= 1

Eric-AWL



Gary Tully wrote:
> 
> That particular test is under investigation on trunk at the moment as
> it seems to fail reproducible now rather than intermittently.
> It may makes sense for you to add a local exclude the the surefire
> target in the activemq-core pom.xml for that test so that it does not
> hamper further testing on your end.
> 
> On 9 July 2010 10:01, Eric-AWL <eric.vincent@atosorigin.com> wrote:
>>
>> I experienced a "File system full" on the current file system during the
>> test. I don't know if that can have an impact on it .... But ....
>>
>> Eric-AWL
>>
>>
>> Eric-AWL wrote:
>>>
>>> Hi
>>>
>>> I try to compile and test ActiveMQ 5.3.2 source using Maven. Nothing
>>> more.
>>>
>>> The AMQ2149Test is blocked with this log traces.
>>>
>>> 2010-07-09 09:15:17,164 [127.0.0.1:61617] WARN  FailoverTransport
>>> - Transport failed to tcp://localhost:61617 , attempting to
>>> automatically
>>> recon
>>> nect due to: java.io.EOFException
>>> 2010-07-09 09:15:17,174 [127.0.0.1:61617] WARN  FailoverTransport
>>> - Transport failed to tcp://localhost:61617 , attempting to
>>> automatically
>>> recon
>>> nect due to: java.io.EOFException
>>> 2010-07-09 09:15:17,194 [127.0.0.1:61617] WARN  FailoverTransport
>>> - Transport failed to tcp://localhost:61617 , attempting to
>>> automatically
>>> recon
>>> nect due to: java.io.EOFException
>>> 2010-07-09 09:15:17,201 [Timer-0        ] INFO  TransportConnector
>>> - Connector tcp://localhost:61617 Stopped
>>> 2010-07-09 09:15:17,361 [Timer-0        ] INFO  BrokerService
>>> - ActiveMQ JMS Message Broker (testOrderWithRestart,
>>> ID:ytsvmx01s.priv.atos.fr-
>>> 45541-1278659663829-10:1) stopped
>>> 2010-07-09 09:15:17,363 [Timer-0        ] INFO  AMQ2149Test
>>> - restarting broker
>>> 2010-07-09 09:15:17,386 [Timer-0        ] INFO  BrokerService
>>> - Using Persistence Adapter:
>>> AMQPersistenceAdapter(target/testOrderWithRestart)
>>> 2010-07-09 09:15:17,387 [Timer-0        ] INFO  AMQPersistenceAdapter
>>> - AMQStore starting using directory: target/testOrderWithRestart
>>> 2010-07-09 09:15:17,414 [Timer-0        ] INFO  KahaStore
>>> - Kaha Store using data directory
>>> target/testOrderWithRestart/kr-store/state
>>> 2010-07-09 09:15:17,428 [Timer-0        ] INFO  AMQPersistenceAdapter
>>> - Active data files: [14]
>>> 2010-07-09 09:15:17,451 [JMX connector  ] INFO  ManagementContext
>>> - JMX consoles can connect to
>>> service:jmx:rmi:///jndi/rmi://localhost:1099/jmxr
>>> mi
>>> 2010-07-09 09:15:17,479 [Timer-0        ] INFO  BrokerService
>>> - ActiveMQ null JMS Message Broker (testOrderWithRestart) is starting
>>> 2010-07-09 09:15:17,479 [Timer-0        ] INFO  BrokerService
>>> - For help or more information please see: http://activemq.apache.org/
>>> 2010-07-09 09:15:17,480 [Timer-0        ] INFO  KahaStore
>>> - Kaha Store using data directory
>>> target/testOrderWithRestart/kr-store/data
>>> 2010-07-09 09:15:17,495 [Timer-0        ] INFO  BrokerService
>>> - Successfully deleted temporary storage
>>> 2010-07-09 09:15:17,582 [Timer-0        ] INFO
>>> TransportServerThreadSupport   - Listening for connections at:
>>> tcp://localhost:61617
>>> 2010-07-09 09:15:17,583 [Timer-0        ] INFO  TransportConnector
>>> - Connector tcp://localhost:61617 Started
>>> 2010-07-09 09:15:17,583 [Timer-0        ] INFO  BrokerService
>>> - ActiveMQ JMS Message Broker (testOrderWithRestart,
>>> ID:ytsvmx01s.priv.atos.fr-
>>> 45541-1278659663829-10:2) started
>>> 2010-07-09 09:15:17,599 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,600 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,662 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,663 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,665 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,690 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,695 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,724 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,732 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,741 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,761 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,767 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,783 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,787 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,787 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,791 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,814 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,815 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,818 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:17,909 [ActiveMQ Task  ] INFO  FailoverTransport
>>> - Successfully reconnected to tcp://localhost:61617
>>> 2010-07-09 09:15:37,588 [Timer-0        ] INFO  AMQ2149Test
>>> - stopping broker..
>>> 2010-07-09 09:15:37,588 [Timer-0        ] INFO  BrokerService
>>> - ActiveMQ Message Broker (testOrderWithRestart,
>>> ID:ytsvmx01s.priv.atos.fr-4554
>>> 1-1278659663829-10:2) is shutting down
>>> 2010-07-09 09:15:38,500 [ActiveMQ Task  ] INFO  KahaStore
>>> - Kaha Store using data directory
>>> target/testOrderWithRestart/testOrderWithRest
>>> art/tmp_storage
>>> 2010-07-09 09:15:38,501 [127.0.0.1:61617] WARN  FailoverTransport
>>> - Transport failed to tcp://localhost:61617 , attempting to
>>> automatically
>>> recon
>>> nect due to: java.io.EOFException
>>> 2010-07-09 09:15:43,507 [Timer-0        ] INFO  TransportConnection
>>> - The connection to '/127.0.0.1:41781' is taking a long time to
>>> shutdown.
>>> 2010-07-09 09:15:48,512 [Timer-0        ] INFO  TransportConnection
>>> - The connection to '/127.0.0.1:41781' is taking a long time to
>>> shutdown.
>>> 2010-07-09 09:15:53,516 [Timer-0        ] INFO  TransportConnection
>>> - The connection to '/127.0.0.1:41781' is taking a long time to
>>> shutdown.
>>> 2010-07-09 09:15:58,520 [Timer-0        ] INFO  TransportConnection
>>> - The connection to '/127.0.0.1:41781' is taking a long time to
>>> shutdown.
>>> 2010-07-09 09:16:03,525 [Timer-0        ] INFO  TransportConnection
>>> - The connection to '/127.0.0.1:41781' is taking a long time to
>>> shutdown.
>>> 2010-07-09 09:16:08,529 [Timer-0        ] INFO  TransportConnection
>>> - The connection to '/127.0.0.1:41781' is taking a long time to
>>> shutdown.
>>> 2010-07-09 09:16:13,533 [Timer-0        ] INFO  TransportConnection
>>> - The connection to '/127.0.0.1:41781' is taking a long time to
>>> shutdown.
>>>
>>> Eric-AWL
>>>
>>>
>>
>> --
>> View this message in context:
>> http://old.nabble.com/5.3.2-source-tests-%3A-AMQ2149Test-is-blocked-during-broker-stop.-tp29114882p29115644.html
>> Sent from the ActiveMQ - User mailing list archive at Nabble.com.
>>
>>
> 
> 
> 
> -- 
> http://blog.garytully.com
> 
> Open Source Integration
> http://fusesource.com
> 
> 

-- 
View this message in context: http://old.nabble.com/5.3.2-source-tests-%3A-AMQ2149Test-is-blocked-during-broker-stop.-tp29114882p29115745.html
Sent from the ActiveMQ - User mailing list archive at Nabble.com.


Mime
View raw message