[APLO-197] Negative queue metrics (queue size: -1.838 / enqueued: 1.000 / dequeued: 2.838)

• Type: Bug
• Status: Resolved
• Priority: Major
• Resolution: Fixed
• Fix Version/s: 1.5
• Component/s: apollo-broker
• Environment: 1.2 release, 99-trunk-20120421.031307-21
• Assignee: Hiram R. Chirino
• Reporter: Torben Fitschen
• Created: 23/Apr/12 08:07
• Updated: 28/Sep/12 17:07
• Resolved: 28/Sep/12 17:07

Description

Metrics:

{
"id" : "Foo",
"state" : "STARTED",
"state_since" : 1335163351974,
"config" :

{ "full_policy" : "block", "other" : [], "auto_delete_after" : 10 }

,
"binding" :

{ "@class" : "queue_destination", "name" : "Foo", "path" : [] }

,
"metrics" :

{ "current_time" : 1335168324251, "enqueue_item_counter" : 1000, "enqueue_size_counter" : 228770, "enqueue_ts" : 1335164951528, "dequeue_item_counter" : 2838, "dequeue_size_counter" : 649441, "dequeue_ts" : 1335168238561, "producer_counter" : 1, "consumer_counter" : 5830, "producer_count" : 0, "consumer_count" : 0, "expired_item_counter" : 0, "expired_size_counter" : 0, "expired_ts" : 1335163351972, "nack_item_counter" : 486, "nack_size_counter" : 111233, "nack_ts" : 1335166069831, "queue_size" : -420671, "queue_items" : -1838, "swapped_in_size_max" : 655360, "swapped_in_size" : 0, "swapped_in_items" : 0, "swapping_in_size" : 0, "swapping_out_size" : 0, "swap_out_item_counter" : 251, "swap_out_size_counter" : 57388, "swap_in_item_counter" : 1195318, "swap_in_size_counter" : 273567320 }

,
"entries" : [],
"producers" : [],
"consumers" : []
}
---------------------------------------------------------------------------------
Log:

WARN | java.lang.AssertionError: Node is not linked | org.apache.activemq.apollo.broker.Broker | hawtdispatch-DEFAULT-1
DEBUG | java.lang.AssertionError: Node is not linked | org.apache.activemq.apollo.broker.Broker | hawtdispatch-DEFAULT-1
java.lang.AssertionError: Node is not linked
at org.apache.activemq.apollo.util.list.LinkedNode.assertLinked(LinkedNode.java:64)
at org.apache.activemq.apollo.util.list.LinkedNode.isTailNode(LinkedNode.java:73)
at org.apache.activemq.apollo.util.list.LinkedNode.getNext(LinkedNode.java:48)
at org.apache.activemq.apollo.broker.QueueEntry.nextOrTail(Queue.scala:1333)
at org.apache.activemq.apollo.broker.Subscription$AcquiredQueueEntry.ack(Queue.scala:2427)
at org.apache.activemq.apollo.broker.Queue$$anonfun$drain_acks$1$$anonfun$apply$mcV$sp$8.apply(Queue.scala:1019)
at org.apache.activemq.apollo.broker.Queue$$anonfun$drain_acks$1$$anonfun$apply$mcV$sp$8.apply(Queue.scala:1014)
at scala.collection.LinearSeqOptimized$class.foreach(LinearSeqOptimized.scala:59)
at scala.collection.immutable.List.foreach(List.scala:45)
at scala.collection.generic.TraversableForwarder$class.foreach(TraversableForwarder.scala:30)
at scala.collection.mutable.ListBuffer.foreach(ListBuffer.scala:42)
at org.apache.activemq.apollo.broker.Queue$$anonfun$drain_acks$1.apply$mcV$sp(Queue.scala:1014)
at org.apache.activemq.apollo.broker.Queue$$anonfun$drain_acks$1.apply(Queue.scala:1014)
at org.apache.activemq.apollo.broker.Queue$$anonfun$drain_acks$1.apply(Queue.scala:1014)
at org.apache.activemq.apollo.broker.Queue.might_unfill(Queue.scala:515)
at org.apache.activemq.apollo.broker.Queue.drain_acks(Queue.scala:1013)
at org.apache.activemq.apollo.broker.Queue$$anonfun$1.apply$mcV$sp(Queue.scala:98)
at org.fusesource.hawtdispatch.package$$anon$3.run(hawtdispatch.scala:358)
at org.fusesource.hawtdispatch.internal.HawtCustomDispatchSource$1.run(HawtCustomDispatchSource.java:125)
at org.fusesource.hawtdispatch.internal.SerialDispatchQueue.run(SerialDispatchQueue.java:96)
at org.fusesource.hawtdispatch.internal.pool.SimpleThread.run(SimpleThread.java:77)