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 Sun, 11 Jul 2010 07:48:58 GMT

Hi Gary

Does it mean that all provided Maven Tests with a broker.stop() call can
fail ?

I want to try to change the RemoteBrokerNameknownLatch behaviour (by forcing
a countdown at the end of the stop() method, and by testing the disposed
AtomicBoolean value, to free blocked threads)  in
DemandForwardingBridgeSupport class and my first tests seem to fail on some
broker.stop() conditions (example network/NetworkReconnectTest.java).

Eric-AWL

 

Eric-AWL wrote:
> 
> 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.-tp29114882p29130076.html
Sent from the ActiveMQ - User mailing list archive at Nabble.com.


Mime
View raw message