5.3.2 source tests : AMQ2149Test is blocked during broker stop.

Previous Topic Next Topic
 
classic Classic list List threaded Threaded
6 messages Options
Reply | Threaded
Open this post in threaded view
|

5.3.2 source tests : AMQ2149Test is blocked during broker stop.

Eric-AWL
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
Reply | Threaded
Open this post in threaded view
|

Re: 5.3.2 source tests : AMQ2149Test is blocked during broker stop.

Eric-AWL
Some new traces now ...

2010-07-09 09:44:24,986 [Timer-0        ] INFO  TransportConnection            - The connection to '/127.0.0.1:41781' is taking a long time to shutdown.
2010-07-09 09:44:29,990 [Timer-0        ] INFO  TransportConnection            - The connection to '/127.0.0.1:41781' is taking a long time to shutdown.
2010-07-09 09:44:34,994 [Timer-0        ] INFO  TransportConnection            - The connection to '/127.0.0.1:41781' is taking a long time to shutdown.

2010-07-09 09:44:36,487 [main           ] INFO  AMQ2149Test                    - senders done...

2010-07-09 09:44:39,999 [Timer-0        ] INFO  TransportConnection            - The connection to '/127.0.0.1:41781' is taking a long time to shutdown.
2010-07-09 09:44:45,003 [Timer-0        ] INFO  TransportConnection            - The connection to '/127.0.0.1:41781' is taking a long time to shutdown.
2010-07-09 09:44:50,007 [Timer-0        ] INFO  TransportConnection            - The connection to '/127.0.0.1:41781' is taking a long time to shutdown.

....
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
Reply | Threaded
Open this post in threaded view
|

Re: 5.3.2 source tests : AMQ2149Test is blocked during broker stop.

Eric-AWL
In reply to this post by Eric-AWL
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
Reply | Threaded
Open this post in threaded view
|

Re: 5.3.2 source tests : AMQ2149Test is blocked during broker stop.

gtully
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 <[hidden email]> 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
Reply | Threaded
Open this post in threaded view
|

Re: 5.3.2 source tests : AMQ2149Test is blocked during broker stop.

Eric-AWL
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
Reply | Threaded
Open this post in threaded view
|

Re: 5.3.2 source tests : AMQ2149Test is blocked during broker stop.

Eric-AWL
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