[JBoss JIRA] Created: (JBMESSAGING-1112) Highly concurrent clients lock a JBM server node and apparently cause persistent messages to "dissapear"
by Ovidiu Feodorov (JIRA)
Highly concurrent clients lock a JBM server node and apparently cause persistent messages to "dissapear"
--------------------------------------------------------------------------------------------------------
Key: JBMESSAGING-1112
URL: http://jira.jboss.com/jira/browse/JBMESSAGING-1112
Project: JBoss Messaging
Issue Type: Bug
Affects Versions: 1.4.0.GA
Reporter: Ovidiu Feodorov
Assigned To: Tim Fox
Priority: Critical
The log of the experiment.
* Empty database
mysql> CONNECT ovidiu
Connection id: 5288
Current database: ovidiu
mysql> SHOW TABLES;
Empty set (0.00 sec)
mysql>
* Both cluster nodes down.
* Start node 39
2007-10-13 20:08:19,259 INFO [org.jboss.system.server.Server] JBoss (MX MicroKernel) [4.2.0.GA (build: SVNTag=JBPAPP_4_2_0_GA date=200706281411)] Started in 23s:774ms
mysql> SELECT * FROM JBM_MSG_REF;
Empty set (0.00 sec)
mysql> SELECT * FROM JBM_POSTOFFICE;
+-----------------+---------+--------------------------+--------------------------------+----------+------------+-----------+-----------+
| POSTOFFICE_NAME | NODE_ID | QUEUE_NAME | COND | SELECTOR | CHANNEL_ID | CLUSTERED | ALL_NODES |
+-----------------+---------+--------------------------+--------------------------------+----------+------------+-----------+-----------+
| JMS post office | 39 | bounceQueue | queue.bounceQueue | NULL | 7 | Y | N |
| JMS post office | 39 | DLQ | queue.DLQ | NULL | 1 | N | N |
| JMS post office | 39 | ExpiryQueue | queue.ExpiryQueue | NULL | 2 | N | N |
...
| JMS post office | 39 | ovidiu | queue.ovidiu | NULL | 0 | Y | N |
...
| JMS post office | 39 | testDistributedQueue | queue.testDistributedQueue | NULL | 8 | Y | N |
+-----------------+---------+--------------------------+--------------------------------+----------+------------+-----------+-----------+
17 rows in set (0.00 sec)
* Start node 38
2007-10-13 20:11:58,280 INFO [org.jboss.system.server.Server] JBoss (MX MicroKernel) [4.2.0.GA (build: SVNTag=JBPAPP_4_2_0_GA date=200706281411)] Started in 27s:214ms
mysql> SELECT * FROM JBM_MSG_REF;
Empty set (0.00 sec)
mysql> SELECT * FROM JBM_POSTOFFICE;
+-----------------+---------+--------------------------+--------------------------------+----------+------------+-----------+-----------+
| POSTOFFICE_NAME | NODE_ID | QUEUE_NAME | COND | SELECTOR | CHANNEL_ID | CLUSTERED | ALL_NODES |
+-----------------+---------+--------------------------+--------------------------------+----------+------------+-----------+-----------+
| JMS post office | 38 | DLQ | queue.DLQ | NULL | 21 | N | N |
| JMS post office | 38 | ExpiryQueue | queue.ExpiryQueue | NULL | 22 | N | N |
...
| JMS post office | 38 | ovidiu | queue.ovidiu | NULL | 20 | Y | N |
| JMS post office | 38 | testDistributedQueue | queue.testDistributedQueue | NULL | 28 | Y | N |
| JMS post office | 39 | DLQ | queue.DLQ | NULL | 1 | N | N |
| JMS post office | 39 | ExpiryQueue | queue.ExpiryQueue | NULL | 2 | N | N |
| JMS post office | 39 | ovidiu | queue.ovidiu | NULL | 0 | Y | N |
...
| JMS post office | 39 | testDistributedQueue | queue.testDistributedQueue | NULL | 8 | Y | N |
+-----------------+---------+--------------------------+--------------------------------+----------+------------+-----------+-----------+
34 rows in set (0.00 sec)
* Make sure I look up a clustered but non load-balanced connection factory from node 39
ofeodorov@yamato$ mess ping
ConnectionFactory: JBossConnectionFactory->ClusteredConnectionFactoryDelegate[SIDs={38,39}]
Destination: JBossQueue[ovidiu]
node 39 alive
closing connection JBossConnection->ConnectionDelegate[1542500044, ID=t2-w06lzq7f-1-qvc9zq7f-g0vqau-31184a, SID=39] on exit ...
* Sending 50,000 10K persistent text messages to "ovidiu" on node 39.
ofeodorov@yamato$ mess -stat send -size 10240 50000
ConnectionFactory: JBossConnectionFactory->ClusteredConnectionFactoryDelegate[SIDs={38,39}]
Destination: JBossQueue[ovidiu]
sent 38 message(s) with a rate of 37.9 messages/sec, 38 message(s) sent so far
sent 32 message(s) with a rate of 32.0 messages/sec, 70 message(s) sent so far
sent 33 message(s) with a rate of 33.0 messages/sec, 103 message(s) sent so far
.....
sent 58 message(s) with a rate of 58.0 messages/sec, 49907 message(s) sent so far
sent 41 message(s) with a rate of 41.0 messages/sec, 49948 message(s) sent so far
50000 message(s) sent non-transactionally at a rate of 59.1 messages/sec
closing connection JBossConnection->ConnectionDelegate[1839586123, ID=03-t8gmzq7f-1-qvc9zq7f-g0vqau-31184a, SID=39] on exit ...
* Database content after send:
mysql> SELECT COUNT(MESSAGE_ID) FROM JBM_MSG_REF;
+-------------------+
| COUNT(MESSAGE_ID) |
+-------------------+
| 50000 |
+-------------------+
mysql> SELECT MIN(MESSAGE_ID) FROM JBM_MSG_REF;
+-----------------+
| MIN(MESSAGE_ID) |
+-----------------+
| 0 |
+-----------------+
mysql> SELECT * FROM JBM_MSG_REF WHERE MESSAGE_ID = 0;
+------------+------------+----------------+-------+-------------------+----------+----------------+----------------+
| CHANNEL_ID | MESSAGE_ID | TRANSACTION_ID | STATE | ORD | PAGE_ORD | DELIVERY_COUNT | SCHED_DELIVERY |
+------------+------------+----------------+-------+-------------------+----------+----------------+----------------+
| 0 | 0 | NULL | C | 39070331482505216 | NULL | 0 | 0 |
+------------+------------+----------------+-------+-------------------+----------+----------------+----------------+
mysql> SELECT MAX(MESSAGE_ID) FROM JBM_MSG_REF;
+-----------------+
| MAX(MESSAGE_ID) |
+-----------------+
| 54192 |
+-----------------+
mysql> SELECT * FROM JBM_MSG_REF WHERE MESSAGE_ID = 54192;
+------------+------------+----------------+-------+-------------------+----------+----------------+----------------+
| CHANNEL_ID | MESSAGE_ID | TRANSACTION_ID | STATE | ORD | PAGE_ORD | DELIVERY_COUNT | SCHED_DELIVERY |
+------------+------------+----------------+-------+-------------------+----------+----------------+----------------+
| 0 | 54192 | NULL | C | 39070359199138639 | NULL | 0 | 0 |
+------------+------------+----------------+-------+-------------------+----------+----------------+----------------+
mysql> SELECT * FROM JBM_POSTOFFICE;
+-----------------+---------+--------------------------+--------------------------------+----------+------------+-----------+-----------+
| POSTOFFICE_NAME | NODE_ID | QUEUE_NAME | COND | SELECTOR | CHANNEL_ID | CLUSTERED | ALL_NODES |
+-----------------+---------+--------------------------+--------------------------------+----------+------------+-----------+-----------+
| JMS post office | 38 | DLQ | queue.DLQ | NULL | 21 | N | N |
| JMS post office | 38 | ExpiryQueue | queue.ExpiryQueue | NULL | 22 | N | N |
...
| JMS post office | 38 | ovidiu | queue.ovidiu | NULL | 20 | Y | N |
...
| JMS post office | 38 | testDistributedQueue | queue.testDistributedQueue | NULL | 28 | Y | N |
...
| JMS post office | 39 | DLQ | queue.DLQ | NULL | 1 | N | N |
| JMS post office | 39 | ExpiryQueue | queue.ExpiryQueue | NULL | 2 | N | N |
...
| JMS post office | 39 | ovidiu | queue.ovidiu | NULL | 0 | Y | N |
...
| JMS post office | 39 | testDistributedQueue | queue.testDistributedQueue | NULL | 8 | Y | N |
+-----------------+---------+--------------------------+--------------------------------+----------+------------+-----------+-----------+
34 rows in set (0.00 sec)
* Receiving on 50 concurrent threads, from node 39
ofeodorov@yamato$ mess -stat -sessions 50 receive all
ConnectionFactory: JBossConnectionFactory->ClusteredConnectionFactoryDelegate[SIDs={38,39}]
Destination: JBossQueue[ovidiu]
Consumer43 starting to consume, 1 active consumers ...
Consumer06 starting to consume, 2 active consumers ...
Consumer38 starting to consume, 3 active consumers ...
Consumer13 starting to consume, 4 active consumers ...
Consumer37 starting to consume, 5 active consumers ...
Consumer10 starting to consume, 6 active consumers ...
Consumer11 starting to consume, 7 active consumers ...
Consumer25 starting to consume, 8 active consumers ...
Consumer12 starting to consume, 9 active consumers ...
Consumer04 starting to consume, 10 active consumers ...
Consumer48 starting to consume, 11 active consumers ...
Consumer32 starting to consume, 12 active consumers ...
Consumer40 starting to consume, 13 active consumers ...
Consumer39 starting to consume, 14 active consumers ...
Consumer05 starting to consume, 15 active consumers ...
Consumer09 starting to consume, 16 active consumers ...
Consumer03 starting to consume, 17 active consumers ...
Consumer41 starting to consume, 18 active consumers ...
Consumer19 starting to consume, 19 active consumers ...
Consumer07 starting to consume, 20 active consumers ...
Consumer22 starting to consume, 21 active consumers ...
Consumer36 starting to consume, 22 active consumers ...
Consumer01 starting to consume, 23 active consumers ...
Consumer46 starting to consume, 24 active consumers ...
Consumer16 starting to consume, 25 active consumers ...
Consumer18 starting to consume, 26 active consumers ...
Consumer23 starting to consume, 28 active consumers ...
Consumer33 starting to consume, 27 active consumers ...
Consumer02 starting to consume, 29 active consumers ...
Consumer00 starting to consume, 30 active consumers ...
Consumer17 starting to consume, 31 active consumers ...
Consumer15 starting to consume, 32 active consumers ...
Consumer47 starting to consume, 33 active consumers ...
Consumer31 starting to consume, 34 active consumers ...
Consumer27 starting to consume, 35 active consumers ...
Consumer49 starting to consume, 36 active consumers ...
Consumer44 starting to consume, 37 active consumers ...
Consumer26 starting to consume, 38 active consumers ...
Consumer24 starting to consume, 39 active consumers ...
Consumer42 starting to consume, 40 active consumers ...
Consumer28 starting to consume, 41 active consumers ...
Consumer20 starting to consume, 42 active consumers ...
Consumer29 starting to consume, 43 active consumers ...
Consumer35 starting to consume, 44 active consumers ...
Consumer45 starting to consume, 45 active consumers ...
Consumer30 starting to consume, 47 active consumers ...
Consumer08 starting to consume, 46 active consumers ...
received 240 message(s) with a rate of 239.3 messages/sec, 240 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 240 message(s) received so far
received 1378 message(s) with a rate of 1,378.0 messages/sec, 1618 message(s) received so far
received 451 message(s) with a rate of 451.0 messages/sec, 2069 message(s) received so far
received 61 message(s) with a rate of 61.0 messages/sec, 2130 message(s) received so far
received 348 message(s) with a rate of 348.0 messages/sec, 2478 message(s) received so far
received 62 message(s) with a rate of 62.0 messages/sec, 2540 message(s) received so far
received 958 message(s) with a rate of 957.0 messages/sec, 3498 message(s) received so far
received 88 message(s) with a rate of 88.2 messages/sec, 3586 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 3586 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 3586 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 3586 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 3586 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 3586 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 3586 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 3586 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 3586 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 3586 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 3586 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 3586 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 3586 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 3586 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 3586 message(s) received so far
received 312 message(s) with a rate of 312.3 messages/sec, 3898 message(s) received so far
received 681 message(s) with a rate of 681.0 messages/sec, 4579 message(s) received so far
received 273 message(s) with a rate of 273.3 messages/sec, 4852 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 4852 message(s) received so far
received 2 message(s) with a rate of 2.0 messages/sec, 4854 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 4854 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 4854 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 4854 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 4854 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 4854 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 4854 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 4854 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 4854 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 4854 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 4854 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 4854 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 4854 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 4854 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 4854 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 4854 message(s) received so far
received 153 message(s) with a rate of 153.0 messages/sec, 5007 message(s) received so far
received 1126 message(s) with a rate of 1,124.9 messages/sec, 6133 message(s) received so far
received 864 message(s) with a rate of 864.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 6997 message(s) received so far
received 42 message(s) with a rate of 42.0 messages/sec, 7039 message(s) received so far
received 441 message(s) with a rate of 441.4 messages/sec, 7480 message(s) received so far
received 1803 message(s) with a rate of 1,803.0 messages/sec, 9283 message(s) received so far
received 505 message(s) with a rate of 505.0 messages/sec, 9788 message(s) received so far
received 974 message(s) with a rate of 975.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 10762 message(s) received so far
received 597 message(s) with a rate of 597.6 messages/sec, 11359 message(s) received so far
received 447 message(s) with a rate of 447.4 messages/sec, 11806 message(s) received so far
received 783 message(s) with a rate of 783.0 messages/sec, 12589 message(s) received so far
received 262 message(s) with a rate of 261.7 messages/sec, 12851 message(s) received so far
received 267 message(s) with a rate of 267.0 messages/sec, 13118 message(s) received so far
received 293 message(s) with a rate of 293.0 messages/sec, 13411 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13411 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13411 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13411 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13411 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13411 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13411 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13411 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13411 message(s) received so far
received 197 message(s) with a rate of 196.8 messages/sec, 13608 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13608 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13608 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13608 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13608 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13608 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13608 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13608 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13608 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13608 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13608 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13608 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13608 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13608 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 13608 message(s) received so far
received 201 message(s) with a rate of 200.6 messages/sec, 13809 message(s) received so far
received 757 message(s) with a rate of 757.8 messages/sec, 14566 message(s) received so far
received 580 message(s) with a rate of 580.0 messages/sec, 15146 message(s) received so far
received 683 message(s) with a rate of 683.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
closing connection JBossConnection->ConnectionDelegate[375652924, ID=83-8dse0r7f-1-qvc9zq7f-g0vqau-31184a, SID=39] on exit ...
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15829 message(s) received so far
received 40 message(s) with a rate of 40.0 messages/sec, 15869 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15869 message(s) received so far
received 0 message(s) with a rate of 0.0 messages/sec, 15869 message(s) received so far
Could not just close() connection, I had to kill the java process
SELECT COUNT(MESSAGE_ID) FROM JBM_MSG_REF;
+-------------------+
| COUNT(MESSAGE_ID) |
+-------------------+
| 34091 |
+-------------------+
50000 - 34091 - 15869 = 40 messages unnacounted for (probably the client send the acknowledgment, but it did not actually receive it)
* Tried to create a new connection to the server, the JNDI invocation hung.
* Killed both servers, restarted.
Database situation:
mysql> SELECT COUNT(MESSAGE_ID) FROM JBM_MSG;
+-------------------+
| COUNT(MESSAGE_ID) |
+-------------------+
| 34091 |
+-------------------+
mysql> SELECT MIN(MESSAGE_ID) FROM JBM_MSG_REF;
+-----------------+
| MIN(MESSAGE_ID) |
+-----------------+
| 8957 |
+-----------------+
mysql> SELECT * FROM JBM_MSG_REF WHERE MESSAGE_ID = 8957;
+------------+------------+----------------+-------+-------------------+----------+----------------+----------------+
| CHANNEL_ID | MESSAGE_ID | TRANSACTION_ID | STATE | ORD | PAGE_ORD | DELIVERY_COUNT | SCHED_DELIVERY |
+------------+------------+----------------+-------+-------------------+----------+----------------+----------------+
| 0 | 8957 | NULL | C | 39070335505044212 | NULL | 0 | 0 |
+------------+------------+----------------+-------+-------------------+----------+----------------+----------------+
mysql> SELECT MAX(MESSAGE_ID) FROM JBM_MSG_REF;
+-----------------+
| MAX(MESSAGE_ID) |
+-----------------+
| 54192 |
+-----------------+
mysql> SELECT * FROM JBM_MSG_REF WHERE MESSAGE_ID = 54192;
+------------+------------+----------------+-------+-------------------+----------+----------------+----------------+
| CHANNEL_ID | MESSAGE_ID | TRANSACTION_ID | STATE | ORD | PAGE_ORD | DELIVERY_COUNT | SCHED_DELIVERY |
+------------+------------+----------------+-------+-------------------+----------+----------------+----------------+
| 0 | 54192 | NULL | C | 39070359199138639 | NULL | 0 | 0 |
+------------+------------+----------------+-------+-------------------+----------+----------------+----------------+
mysql> SELECT * FROM JBM_POSTOFFICE;
+-----------------+---------+--------------------------+--------------------------------+----------+------------+-----------+-----------+
| POSTOFFICE_NAME | NODE_ID | QUEUE_NAME | COND | SELECTOR | CHANNEL_ID | CLUSTERED | ALL_NODES |
+-----------------+---------+--------------------------+--------------------------------+----------+------------+-----------+-----------+
| JMS post office | 38 | DLQ | queue.DLQ | NULL | 21 | N | N |
| JMS post office | 38 | ExpiryQueue | queue.ExpiryQueue | NULL | 22 | N | N |
...
| JMS post office | 38 | ovidiu | queue.ovidiu | NULL | 20 | Y | N |
...
| JMS post office | 38 | testDistributedQueue | queue.testDistributedQueue | NULL | 28 | Y | N |
...
| JMS post office | 39 | DLQ | queue.DLQ | NULL | 1 | N | N |
| JMS post office | 39 | ExpiryQueue | queue.ExpiryQueue | NULL | 2 | N | N |
...
| JMS post office | 39 | ovidiu | queue.ovidiu | NULL | 40 | Y | N |
...
| JMS post office | 39 | testDistributedQueue | queue.testDistributedQueue | NULL | 8 | Y | N |
+-----------------+---------+--------------------------+--------------------------------+----------+------------+-----------+-----------+
34 rows in set (0.00 sec)
* Pinged 39
ofeodorov@yamato$ mess ping
ConnectionFactory: JBossConnectionFactory->ClusteredConnectionFactoryDelegate[SIDs={38,39}]
Destination: JBossQueue[ovidiu]
node 39 alive
closing connection JBossConnection->ConnectionDelegate[735699586, ID=t2-b4k51r7f-1-h2w31r7f-q9dxny-31184a, SID=39] on exit ...
* Browsed the queue (I don't expect any, since channel 0 does not exist anymore)
ofeodorov@yamato$ mess browse
ConnectionFactory: JBossConnectionFactory->ClusteredConnectionFactoryDelegate[SIDs={38,39}]
Destination: JBossQueue[ovidiu]
NO messages in queue
closing connection JBossConnection->ConnectionDelegate[2002021820, ID=03-fah61r7f-1-h2w31r7f-q9dxny-31184a, SID=39] on exit ...
--
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
16 years, 11 months
[JBoss JIRA] Created: (JBMESSAGING-1110) Client incorrectly initiates client-side fail over on high concurrency
by Ovidiu Feodorov (JIRA)
Client incorrectly initiates client-side fail over on high concurrency
----------------------------------------------------------------------
Key: JBMESSAGING-1110
URL: http://jira.jboss.com/jira/browse/JBMESSAGING-1110
Project: JBoss Messaging
Issue Type: Bug
Components: JMS Facade
Affects Versions: 1.4.0.GA
Reporter: Ovidiu Feodorov
Assigned To: Tim Fox
Dump of a faulty test run:
ofeodorov@yamato$ mess -sessions 50 receive all
ConnectionFactory: JBossConnectionFactory->ClusteredConnectionFactoryDelegate[SIDs={38,39}]
Destination: JBossQueue[ovidiu]
Consumer21 starting to consume ...
Consumer43 starting to consume ...
Consumer23 starting to consume ...
Consumer44 starting to consume ...
Consumer11 starting to consume ...
Consumer29 starting to consume ...
Consumer26 starting to consume ...
Consumer39 starting to consume ...
Consumer40 starting to consume ...
Consumer01 starting to consume ...
Consumer17 starting to consume ...
Consumer14 starting to consume ...
Consumer04 starting to consume ...
Consumer16 starting to consume ...
Consumer49 starting to consume ...
Consumer42 starting to consume ...
Consumer41 starting to consume ...
Consumer34 starting to consume ...
Consumer18 starting to consume ...
Consumer46 starting to consume ...
Consumer20 starting to consume ...
Consumer02 starting to consume ...
Consumer06 starting to consume ...
Consumer32 starting to consume ...
Consumer07 starting to consume ...
Consumer19 starting to consume ...
Consumer35 starting to consume ...
Consumer48 starting to consume ...
Consumer24 starting to consume ...
Consumer30 starting to consume ...
Consumer08 starting to consume ...
Consumer12 starting to consume ...
Consumer36 starting to consume ...
Consumer09 starting to consume ...
Consumer47 starting to consume ...
Consumer00 starting to consume ...
Consumer13 starting to consume ...
Consumer31 starting to consume ...
Consumer27 starting to consume ...
Consumer33 starting to consume ...
Consumer10 starting to consume ...
Consumer05 starting to consume ...
Consumer45 starting to consume ...
Consumer15 starting to consume ...
Consumer03 starting to consume ...
Consumer37 starting to consume ...
Consumer28 starting to consume ...
Consumer38 starting to consume ...
Consumer22 starting to consume ...
Consumer30 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer33 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer22 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer32 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer31 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer10 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer09 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer34 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer12 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer38 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer24 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer15 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer13 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer29 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer36 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer16 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer41 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer14 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer26 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer19 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer08 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer07 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer23 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer11 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer18 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer00 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer03 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer02 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer27 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer05 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer20 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer04 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer45 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer48 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer06 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer47 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer43 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer46 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer44 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer42 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer17 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer39 doesn't get any more messages, exiting after a timeout of 3000 ms ...
Consumer49 doesn't get any more messages, exiting after a timeout of 3000 ms ...
@Consumer25 18:11:31,474 WARN [ClusteringAspect] Client attempted failover, but no failover attempt has been detected on the server side. We will now try again on the original server in case there was a temporary glitch on the client--server network
closing connection JBossConnection->ConnectionDelegate[1572973638, ID=yc-2wk3vq7f-1-6ii5qq7f-onm5l5-31184a, SID=39] on exit ...
@Timer-1 18:16:58,969 WARN [BisocketServerInvoker] org.jboss.remoting.transport.bisocket.BisocketServerInvoker$ControlMonitorTimerTask@11dba9f9: detected failure on control connection Thread[control: Socket[addr=/10.152.1.39,port=3309,localport=50826],5,main] (a4812d-tafka6-f7qv26rw-1-f7qv3kvq-o: requesting new control connection
--
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
16 years, 11 months
[JBoss JIRA] Created: (JBAS-4361) java.util.logging.FileHandler.formatter cannot be changed to java.util.logging.SimpleFormatter
by Wolfgang Knauf (JIRA)
java.util.logging.FileHandler.formatter cannot be changed to java.util.logging.SimpleFormatter
----------------------------------------------------------------------------------------------
Key: JBAS-4361
URL: http://jira.jboss.com/jira/browse/JBAS-4361
Project: JBoss Application Server
Issue Type: Bug
Security Level: Public (Everyone can see)
Components: Logging
Affects Versions: JBossAS-4.2.0.CR2
Environment: JDK is 1.5.0_11-b03
Reporter: Wolfgang Knauf
Assigned To: Scott M Stark
Priority: Minor
I tried to create a plain text log output with this logging.properties file:
handlers= java.util.logging.FileHandler, java.util.logging.ConsoleHandler
.level= INFO
java.util.logging.FileHandler.pattern = c:/temp/logfile.log
java.util.logging.FileHandler.level = INFO
java.util.logging.FileHandler.limit = 50000
java.util.logging.FileHandler.count = 1
java.util.logging.FileHandler.formatter = java.util.logging.SimpleFormatter
java.util.logging.ConsoleHandler.level = INFO
java.util.logging.ConsoleHandler.formatter = java.util.logging.SimpleFormatter
I startup the server with this commandline:
run.bat -c default -Djava.util.logging.config.file=c:/temp/logging.properties
The log file is placed in c:\temp\logfile.log and contains my output, but it is in XML, not in Simple format. Console output is in SimpleFormat as expected.
--
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
16 years, 11 months
[JBoss JIRA] Created: (JBPORTAL-1759) Entering urls containing German Umlaute (e.g. ü = %FC) throws an ArrayIndexOutOfBoundsException in FastURLDecoder
by Johannes Fiala (JIRA)
Entering urls containing German Umlaute (e.g. ü = %FC) throws an ArrayIndexOutOfBoundsException in FastURLDecoder
-----------------------------------------------------------------------------------------------------------------
Key: JBPORTAL-1759
URL: http://jira.jboss.com/jira/browse/JBPORTAL-1759
Project: JBoss Portal
Issue Type: Bug
Security Level: Public (Everyone can see)
Components: Portal Server
Affects Versions: 2.6.2 Final
Environment: Jboss AS 4.0.5 GA on JDK 1.4.2_14
Tested using the browsers: Mozilla Firefox 2.0.0.7 and MS Internet Explorer 6.0
Reporter: Johannes Fiala
Assigned To: Julien Viet
Entering urls containing German Umlaute (e.g. ü = %FC) throws an ArrayIndexOutOfBoundsException in FastURLDecoder.
E.g. Entering "http://localhost:8080/portal/?test=%FC"
throws the following exception:
HTTP Status 500 -
type Exception report
message description The server encountered an internal error () that prevented it from fulfilling this request.
exception java.lang.ArrayIndexOutOfBoundsException: 4
org.jboss.portal.common.text.FastURLDecoder.encodeNoCheck(FastURLDecoder.java:268)
org.jboss.portal.common.text.FastURLDecoder.encode(FastURLDecoder.java:226)
org.jboss.portal.common.http.QueryStringParser.parseQueryString(QueryStringParser.java:233)
org.jboss.portal.server.servlet.PortalServlet.service(PortalServlet.java:291)
javax.servlet.http.HttpServlet.service(HttpServlet.java:810)
org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:96)
Prior versions of Jboss Portal (2.2.1 SP3, 2.6.1 GA) handle this correctly, so this bug is newly introduced with Jboss Portal 2.6.2 GA.
The issue does not seem to be browser specific, as it occurs with Mozilla Firefox 2.0.0.7 and MS Internet Explorer 6.0.
--
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
16 years, 11 months
[JBoss JIRA] Created: (JASSIST-37) CtField.getConstantValue() for just added fileds
by Vlad Skarzhevskyy (JIRA)
CtField.getConstantValue() for just added fileds
------------------------------------------------
Key: JASSIST-37
URL: http://jira.jboss.com/jira/browse/JASSIST-37
Project: Javassist
Issue Type: Bug
Environment: Java 5, javassist 3.4.ga
Reporter: Vlad Skarzhevskyy
Assigned To: Shigeru Chiba
I'm creating tool to verify Java API compatibility
I'm converting class files to XML and then XML to classes creating CtClass objects that have empty implementation.
Then target is to compare new lib version to API described in XML.
project site: http://pyx4me.com/snapshot/jour/signature.html
This is what causing the problem:
CtField field = new CtField(CtClass.longType, "test", klass);
CtField.Initializer initializer = CtField.Initializer.constant(1);
klass.addField(field, initializer);
// Then later when
Object value = fields.getConstantValue();
//value is always null and can't be used for comparison.
when I call klass.writeFile(); The .class is created correctly and when decompiled I see that int filed is properly initialized .
--
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
16 years, 11 months
[JBoss JIRA] Created: (JBCACHE-1198) putForExternalRead not silent on remote node
by Brian Stansberry (JIRA)
putForExternalRead not silent on remote node
--------------------------------------------
Key: JBCACHE-1198
URL: http://jira.jboss.com/jira/browse/JBCACHE-1198
Project: JBoss Cache
Issue Type: Bug
Security Level: Public (Everyone can see)
Components: Clustering
Affects Versions: 2.1.0.BETA1
Reporter: Brian Stansberry
Assigned To: Manik Surtani
Seeing WARN logging like this when using PFER with a replicating cache:
22:16:37,931 WARN pessimistic-shared:2839 - replication failure with method_call MethodName: _putForExternalRead; MethodIdInteger: 45; Args: (null, /test/com/foo/test/ENTITY/KEY, item, VALUE1) exception
org.jboss.cache.lock.TimeoutException: failure acquiring lock: fqn=/test/com/foo/test/ENTITY/KEY, caller=Thread[Multiplexer-2,5,MultiplexerThreads], lock=write owner=GlobalTransaction:<192.168.1.145:35562>:1 (activeReaders=0, activeWriter=Thread[Thread-7,5,main], waitingReaders=1, waitingWriters=0, waitingUpgrader=0)
at org.jboss.cache.lock.IdentityLock.acquire(IdentityLock.java:528)
at org.jboss.cache.interceptors.PessimisticLockInterceptor$LockManager.acquire(PessimisticLockInterceptor.java:598)
at org.jboss.cache.interceptors.PessimisticLockInterceptor.acquireNodeLock(PessimisticLockInterceptor.java:412)
at org.jboss.cache.interceptors.PessimisticLockInterceptor.lock(PessimisticLockInterceptor.java:348)
at org.jboss.cache.interceptors.PessimisticLockInterceptor.invoke(PessimisticLockInterceptor.java:185)
at org.jboss.cache.interceptors.Interceptor.invoke(Interceptor.java:76)
at org.jboss.cache.interceptors.ReplicationInterceptor.invoke(ReplicationInterceptor.java:34)
at org.jboss.cache.interceptors.Interceptor.invoke(Interceptor.java:76)
at org.jboss.cache.interceptors.NotificationInterceptor.invoke(NotificationInterceptor.java:32)
at org.jboss.cache.interceptors.Interceptor.invoke(Interceptor.java:76)
at org.jboss.cache.interceptors.TxInterceptor.handleNonTxMethod(TxInterceptor.java:299)
at org.jboss.cache.interceptors.TxInterceptor.invoke(TxInterceptor.java:131)
at org.jboss.cache.interceptors.Interceptor.invoke(Interceptor.java:76)
at org.jboss.cache.interceptors.CacheMgmtInterceptor.invoke(CacheMgmtInterceptor.java:97)
at org.jboss.cache.interceptors.Interceptor.invoke(Interceptor.java:76)
at org.jboss.cache.interceptors.InvocationContextInterceptor.invoke(InvocationContextInterceptor.java:62)
at org.jboss.cache.CacheImpl.invokeMethod(CacheImpl.java:3958)
at org.jboss.cache.CacheImpl._replicate(CacheImpl.java:2826)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:585)
at org.jgroups.blocks.MethodCall.invoke(MethodCall.java:330)
at org.jboss.cache.marshall.InactiveRegionAwareRpcDispatcher.handle(InactiveRegionAwareRpcDispatcher.java:77)
at org.jgroups.blocks.RequestCorrelator.handleRequest(RequestCorrelator.java:624)
at org.jgroups.blocks.RequestCorrelator.receiveMessage(RequestCorrelator.java:533)
at org.jgroups.blocks.RequestCorrelator.receive(RequestCorrelator.java:365)
at org.jgroups.blocks.MessageDispatcher$ProtocolAdapter.up(MessageDispatcher.java:744)
at org.jgroups.JChannel.up(JChannel.java:1137)
at org.jgroups.mux.Multiplexer$Task.run(Multiplexer.java:999)
at org.jgroups.mux.Multiplexer$ExecuteTask.run(Multiplexer.java:1023)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
at java.lang.Thread.run(Thread.java:595)
Caused by: org.jboss.cache.lock.TimeoutException: write lock for /test/com/foo/test/ENTITY/KEY could not be acquired after 0 ms. Locks: Read lock owners: []
Write lock owner: GlobalTransaction:<192.168.1.145:35562>:1
(caller=Thread[Multiplexer-2,5,MultiplexerThreads], lock info: write owner=GlobalTransaction:<192.168.1.145:35562>:1 (activeReaders=0, activeWriter=Thread[Thread-7,5,main], waitingReaders=1, waitingWriters=0, waitingUpgrader=0))
at org.jboss.cache.lock.IdentityLock.acquireWriteLock0(IdentityLock.java:244)
at org.jboss.cache.lock.IdentityLock.acquireWriteLock(IdentityLock.java:167)
at org.jboss.cache.lock.IdentityLock.acquire(IdentityLock.java:497)
... 33 more
Issue seems to be that the logic in CacheImpl.putForExternalRead that makes the call a no-op if the node exists and sets the failSilently option otherwise never gets executed on the remote node. Instead the call gets handled by _replicate which just passes the MethodCall into the interceptor chain without setting any options.
--
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
16 years, 11 months
[JBoss JIRA] Created: (JBCACHE-1202) State transfer does not include optimistic node's DataVersion
by Brian Stansberry (JIRA)
State transfer does not include optimistic node's DataVersion
-------------------------------------------------------------
Key: JBCACHE-1202
URL: http://jira.jboss.com/jira/browse/JBCACHE-1202
Project: JBoss Cache
Issue Type: Bug
Security Level: Public (Everyone can see)
Affects Versions: 1.4.1.SP5, 1.4.1.SP4, 1.4.1.SP3, 1.4.1.SP2, 1.4.1.SP1, 2.0.0.GA, 1.4.1.GA, 1.4.0.SP1, 1.4.0.GA, 1.3.0.SP4, 1.3.0.SP3, 1.3.0.SP2, 1.3.0.SP1, 1.3.0.GA, 2.1.0.BETA1
Reporter: Brian Stansberry
Assigned To: Manik Surtani
Here's the object that's transferred; doesn't include the DataVersion
public class NodeData implements Externalizable
{
private Fqn<?> fqn = null;
private Map<?, ?> attrs = null;
}
A hack solution that wouldn't affect wire format compatibility would be to store the version in the attrs map.
--
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
16 years, 11 months