[jboss-jira] [JBoss JIRA] Commented: (JBMESSAGING-693) Starting two node clusters simultaneously may end in failed messaging server node boot
Juha Lindfors (JIRA)
jira-events at lists.jboss.org
Mon Feb 26 06:13:37 EST 2007
[ http://jira.jboss.com/jira/browse/JBMESSAGING-693?page=comments#action_12354298 ]
Juha Lindfors commented on JBMESSAGING-693:
-------------------------------------------
It still exists in CR1, although is fairly difficult to reproduce with full server startup sequence:
11:07:59,504 INFO [ServerPeer] JBoss Messaging 1.2.0.CR1 server [2] started
11:08:17,570 INFO [STDOUT]
-------------------------------------------------------
GMS: address is 127.0.0.1:2840
-------------------------------------------------------
11:08:22,867 WARN [GMS] join(127.0.0.1:2840) sent to 127.0.0.1:2825 timed out, retrying
11:08:26,923 INFO [DefaultClusteredPostOffice] ClusteredPostOffice[2:Clustered JMS:127.0.0.1:2840] got new view [127.0.
0.1:2840|0] [127.0.0.1:2840]
11:08:27,554 INFO [STDOUT]
-------------------------------------------------------
GMS: address is 127.0.0.1:2846
-------------------------------------------------------
11:08:44,929 INFO [ConnectionFactory] Connector socket://10.11.14.93:4657 has leasing enabled, lease period 20000 milli
seconds
11:08:44,929 INFO [ConnectionFactory] [/ConnectionFactory, /XAConnectionFactory, java:/ConnectionFactory, java:/XAConne
ctionFactory] started
11:08:44,979 INFO [ConnectionFactory] Connector socket://10.11.14.93:4657 has leasing enabled, lease period 20000 milli
seconds
11:08:44,979 INFO [ConnectionFactory] [/NonClusteredConnectionFactory, /NonClusteredXAConnectionFactory, java:/NonClust
eredConnectionFactory, java:/NonClusteredXAConnectionFactory] started
11:08:46,882 INFO [QueueService] Queue[/queue/DLQ] started, fullSize=75000, pageSize=2000, downCacheSize=2000
11:08:48,645 INFO [QueueService] Queue[/queue/ExpiryQueue] started, fullSize=75000, pageSize=2000, downCacheSize=2000
11:08:48,645 INFO [TopicService] Topic[/topic/testTopic] started, fullSize=75000, pageSize=2000, downCacheSize=2000
11:08:48,655 INFO [TopicService] Topic[/topic/securedTopic] started, fullSize=75000, pageSize=2000, downCacheSize=2000
11:08:48,655 INFO [TopicService] Topic[/topic/testDurableTopic] started, fullSize=75000, pageSize=2000, downCacheSize=2
000
11:08:49,255 INFO [QueueService] Queue[/queue/testQueue] started, fullSize=75000, pageSize=2000, downCacheSize=2000
11:08:49,836 INFO [QueueService] Queue[/queue/A] started, fullSize=75000, pageSize=2000, downCacheSize=2000
11:08:50,617 INFO [QueueService] Queue[/queue/B] started, fullSize=75000, pageSize=2000, downCacheSize=2000
11:08:51,018 INFO [QueueService] Queue[/queue/C] started, fullSize=75000, pageSize=2000, downCacheSize=2000
11:08:51,739 INFO [QueueService] Queue[/queue/D] started, fullSize=75000, pageSize=2000, downCacheSize=2000
11:08:53,231 INFO [QueueService] Queue[/queue/ex] started, fullSize=75000, pageSize=2000, downCacheSize=2000
11:08:54,303 INFO [QueueService] Queue[/queue/PrivateDLQ] started, fullSize=75000, pageSize=2000, downCacheSize=2000
11:08:54,984 INFO [QueueService] Queue[/queue/PrivateExpiryQueue] started, fullSize=75000, pageSize=2000, downCacheSize
=2000
11:08:56,426 INFO [QueueService] Queue[/queue/QueueWithOwnDLQAndExpiryQueue] started, fullSize=75000, pageSize=2000, do
wnCacheSize=2000
11:08:56,426 INFO [TopicService] Topic[/topic/TopicWithOwnDLQAndExpiryQueue] started, fullSize=75000, pageSize=2000, do
wnCacheSize=2000
11:08:57,267 INFO [QueueService] Queue[/queue/QueueWithOwnRedeliveryDelay] started, fullSize=75000, pageSize=2000, down
CacheSize=2000
11:08:57,267 INFO [TopicService] Topic[/topic/TopicWithOwnRedeliveryDelay] started, fullSize=75000, pageSize=2000, down
CacheSize=2000
11:08:57,778 INFO [QueueService] Queue[/queue/testDistributedQueue] started, fullSize=75000, pageSize=2000, downCacheSi
ze=2000
11:08:57,788 INFO [TopicService] Topic[/topic/testDistributedTopic] started, fullSize=75000, pageSize=2000, downCacheSi
ze=2000
11:08:58,198 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=ConnectionFactoryBinding
,name=JmsXA' to JNDI name 'java:JmsXA'
11:09:05,329 INFO [TomcatDeployer] deploy, ctxPath=/jmx-console, warUrl=.../deploy/jmx-console.war/
11:10:08,299 INFO [Http11BaseProtocol] Starting Coyote HTTP/1.1 on http-0.0.0.0-8280
11:10:12,265 INFO [ChannelSocket] JK: ajp13 listening on /0.0.0.0:8209
11:10:12,986 INFO [JkMain] Jk running ID=0 time=0/2364 config=null
11:10:13,026 INFO [Server] JBoss (MX MicroKernel) [4.0.5.GA (build: CVSTag=Branch_4_0 date=200610162339)] Started in 9m
:34s:66ms
11:14:18,389 WARN [NAKACK] 127.0.0.1:2846] discarded message from non-member 127.0.0.1:2828, my view is [127.0.0.1:2846
|0] [127.0.0.1:2846]
11:14:20,442 WARN [NAKACK] 127.0.0.1:2846] discarded message from non-member 127.0.0.1:2828, my view is [127.0.0.1:2846
|0] [127.0.0.1:2846]
11:14:30,045 WARN [NAKACK] 127.0.0.1:2840] discarded message from non-member 127.0.0.1:2825, my view is [127.0.0.1:2840
|0] [127.0.0.1:2840]
11:14:30,075 WARN [NAKACK] 127.0.0.1:2846] discarded message from non-member 127.0.0.1:2828, my view is [127.0.0.1:2846
|0] [127.0.0.1:2846]
11:14:31,407 INFO [DefaultClusteredPostOffice] ClusteredPostOffice[2:Clustered JMS:127.0.0.1:2840] got new view MergeVi
ew::[127.0.0.1:2825|2] [127.0.0.1:2825, 127.0.0.1:2840], subgroups=[[127.0.0.1:2825|1] [127.0.0.1:2825, 127.0.0.1:2840],
[127.0.0.1:2840|0] [127.0.0.1:2840]]
11:14:32,048 WARN [GMS] failed to collect all ACKs (1) for view MergeView::[127.0.0.1:2825|2] [127.0.0.1:2825, 127.0.0.
1:2840], subgroups=[[127.0.0.1:2825|1] [127.0.0.1:2825, 127.0.0.1:2840], [127.0.0.1:2840|0] [127.0.0.1:2840]] after 2000
ms, missing ACKs from [127.0.0.1:2840] (received=[]), local_addr=127.0.0.1:2840
11:14:32,138 WARN [GMS] failed to collect all ACKs (1) for view MergeView::[127.0.0.1:2828|1] [127.0.0.1:2828, 127.0.0.
1:2846], subgroups=[[127.0.0.1:2828|0] [127.0.0.1:2828], [127.0.0.1:2846|0] [127.0.0.1:2846]] after 2000ms, missing ACKs
from [127.0.0.1:2846] (received=[]), local_addr=127.0.0.1:2846
11:14:33,661 INFO [DefaultClusteredPostOffice] ClusteredPostOffice[2:Clustered JMS:127.0.0.1:2840] got new view [127.0.
0.1:2825|3] [127.0.0.1:2825, 127.0.0.1:2840, 127.0.0.1:2940]
11:14:34,672 INFO [DefaultClusteredPostOffice] ClusteredPostOffice[2:Clustered JMS:127.0.0.1:2840] got new view [127.0.
0.1:2825|4] [127.0.0.1:2825, 127.0.0.1:2840, 127.0.0.1:2940, 127.0.0.1:2925]
11:15:07,920 ERROR [DefaultClusteredPostOffice] Caught Exception in RequestHandler
java.lang.IllegalStateException: Don't know about node id: 1
at org.jboss.messaging.core.plugin.postoffice.cluster.DefaultClusteredPostOffice.addBindingFromCluster(DefaultCl
usteredPostOffice.java:524)
at org.jboss.messaging.core.plugin.postoffice.cluster.BindRequest.execute(BindRequest.java:55)
at org.jboss.messaging.core.plugin.postoffice.cluster.DefaultClusteredPostOffice$PostOfficeRequestHandler.handle
(DefaultClusteredPostOffice.java:2519)
at org.jgroups.blocks.MessageDispatcher.handle(MessageDispatcher.java:608)
at org.jgroups.blocks.RequestCorrelator.handleRequest(RequestCorrelator.java:650)
at org.jgroups.blocks.RequestCorrelator.access$100(RequestCorrelator.java:38)
at org.jgroups.blocks.RequestCorrelator$Request.run(RequestCorrelator.java:939)
at org.jgroups.util.ReusableThread.run(ReusableThread.java:220)
at java.lang.Thread.run(Thread.java:595)
11:15:08,340 ERROR [RequestCorrelator] error invoking method
java.lang.IllegalStateException: Don't know about node id: 1
at org.jboss.messaging.core.plugin.postoffice.cluster.DefaultClusteredPostOffice.addBindingFromCluster(DefaultCl
usteredPostOffice.java:524)
at org.jboss.messaging.core.plugin.postoffice.cluster.BindRequest.execute(BindRequest.java:55)
at org.jboss.messaging.core.plugin.postoffice.cluster.DefaultClusteredPostOffice$PostOfficeRequestHandler.handle
(DefaultClusteredPostOffice.java:2519)
at org.jgroups.blocks.MessageDispatcher.handle(MessageDispatcher.java:608)
at org.jgroups.blocks.RequestCorrelator.handleRequest(RequestCorrelator.java:650)
at org.jgroups.blocks.RequestCorrelator.access$100(RequestCorrelator.java:38)
at org.jgroups.blocks.RequestCorrelator$Request.run(RequestCorrelator.java:939)
at org.jgroups.util.ReusableThread.run(ReusableThread.java:220)
at java.lang.Thread.run(Thread.java:595)
> Starting two node clusters simultaneously may end in failed messaging server node boot
> --------------------------------------------------------------------------------------
>
> Key: JBMESSAGING-693
> URL: http://jira.jboss.com/jira/browse/JBMESSAGING-693
> Project: JBoss Messaging
> Issue Type: Bug
> Reporter: Ovidiu Feodorov
> Assigned To: Tim Fox
> Priority: Critical
> Fix For: 1.2.0.CR1
>
>
> Tim Fox: 10:24:05,296 ERROR [DefaultClusteredPostOffice] Caught Exception in RequestHandler
> java.lang.IllegalStateException: Don't know about node id: 1
> at org.jboss.messaging.core.plugin.postoffice.cluster.DefaultClusteredPostOffice.addBindingFromCluster(DefaultClusteredPostOffice.java:597)
> at org.jboss.messaging.core.plugin.postoffice.cluster.BindRequest.execute(BindRequest.java:55)
> at org.jboss.messaging.core.plugin.postoffice.cluster.DefaultClusteredPostOffice$PostOfficeRequestHandler.handle(DefaultClusteredPostOffice.java:2526)
> at org.jgroups.blocks.MessageDispatcher.handle(MessageDispatcher.java:602)
> at org.jgroups.blocks.RequestCorrelator.handleRequest(RequestCorrelator.java:623)
> at org.jgroups.blocks.RequestCorrelator.access
--
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators: http://jira.jboss.com/jira/secure/Administrators.jspa
-
For more information on JIRA, see: http://www.atlassian.com/software/jira
More information about the jboss-jira
mailing list