You are viewing a plain text version of this content. The canonical link for it is here.
Posted to commits@qpid.apache.org by rg...@apache.org on 2007/03/30 13:51:10 UTC

svn commit: r524050 - in /incubator/qpid/branches/M2/java/perftests/src: main/java/org/apache/qpid/ping/PingDurableClient.java main/java/org/apache/qpid/requestreply/PingPongProducer.java test/java/org/apache/qpid/ping/PingAsyncTestPerf.java

Author: rgreig
Date: Fri Mar 30 04:51:09 2007
New Revision: 524050

URL: http://svn.apache.org/viewvc?view=rev&rev=524050
Log:
Removed excess logging to optimize performance.

Modified:
    incubator/qpid/branches/M2/java/perftests/src/main/java/org/apache/qpid/ping/PingDurableClient.java
    incubator/qpid/branches/M2/java/perftests/src/main/java/org/apache/qpid/requestreply/PingPongProducer.java
    incubator/qpid/branches/M2/java/perftests/src/test/java/org/apache/qpid/ping/PingAsyncTestPerf.java

Modified: incubator/qpid/branches/M2/java/perftests/src/main/java/org/apache/qpid/ping/PingDurableClient.java
URL: http://svn.apache.org/viewvc/incubator/qpid/branches/M2/java/perftests/src/main/java/org/apache/qpid/ping/PingDurableClient.java?view=diff&rev=524050&r1=524049&r2=524050
==============================================================================
--- incubator/qpid/branches/M2/java/perftests/src/main/java/org/apache/qpid/ping/PingDurableClient.java (original)
+++ incubator/qpid/branches/M2/java/perftests/src/main/java/org/apache/qpid/ping/PingDurableClient.java Fri Mar 30 04:51:09 2007
@@ -284,6 +284,9 @@
         }
         catch (JMSException e)
         {
+            log.debug("There was an error whilst closing the connection: " + e, e);
+            System.out.println("There was an error whilst closing the connection.");
+
             // Ignore as did best could manage to clean up.
         }
 
@@ -326,6 +329,32 @@
             {
                 System.out.println("Got all messages.");
                 endCondition = true;
+            }
+        }
+
+        // Ensure messages received are committed.
+        if (_transacted)
+        {
+            try
+            {
+                _consumerSession.commit();
+                System.out.println("Committed for all messages received.");
+            }
+            catch (JMSException e)
+            {
+                log.debug("Error during commit: " + e, e);
+                System.out.println("Error during commit.");
+                try
+                {
+                    _consumerSession.rollback();
+                    System.out.println("Rolled back on all messages received.");
+                }
+                catch (JMSException e2)
+                {
+                    log.debug("Error during rollback: " + e, e);
+                    System.out.println("Error on roll back of all messages received.");
+                }
+
             }
         }
 

Modified: incubator/qpid/branches/M2/java/perftests/src/main/java/org/apache/qpid/requestreply/PingPongProducer.java
URL: http://svn.apache.org/viewvc/incubator/qpid/branches/M2/java/perftests/src/main/java/org/apache/qpid/requestreply/PingPongProducer.java?view=diff&rev=524050&r1=524049&r2=524050
==============================================================================
--- incubator/qpid/branches/M2/java/perftests/src/main/java/org/apache/qpid/requestreply/PingPongProducer.java (original)
+++ incubator/qpid/branches/M2/java/perftests/src/main/java/org/apache/qpid/requestreply/PingPongProducer.java Fri Mar 30 04:51:09 2007
@@ -118,7 +118,7 @@
  *       by the PPP that it is atteched to.
  *
  * @todo Use read/write lock in the onmessage, not for reading writing but to make use of a shared and exlcusive lock pair.
- *       Obtian read lock on all messages, before decrementing the message count. At the end of the on message method add a
+ *       Obtain read lock on all messages, before decrementing the message count. At the end of the on message method add a
  *       block that obtains the write lock for the very last message, releases any waiting producer. Means that the last
  *       message waits until all other messages have been handled before releasing producers but allows messages to be
  *       processed concurrently, unlike the current synchronized block.
@@ -737,6 +737,8 @@
         log.debug("public void createReplyConsumers(Collection<Destination> destinations = " + destinations
             + ", String selector = " + selector + "): called");
 
+        log.debug("Creating " + destinations.size() + " reply consumers.");
+
         for (Destination destination : destinations)
         {
             // Create a consumer for the destination and set this pinger to listen to its messages.
@@ -744,6 +746,8 @@
                 _consumerSession.createConsumer(destination, PREFETCH_DEFAULT, NO_LOCAL_DEFAULT, EXCLUSIVE_DEFAULT,
                     selector);
             _consumer.setMessageListener(this);
+
+            log.debug("Set this to listen to replies sent to destination: " + destination);
         }
     }
 
@@ -755,13 +759,13 @@
      */
     public void onMessage(Message message)
     {
-        log.debug("public void onMessage(Message message): called");
+        // log.debug("public void onMessage(Message message): called");
 
         try
         {
             // Extract the messages correlation id.
             String correlationID = message.getJMSCorrelationID();
-            log.debug("correlationID = " + correlationID);
+            // log.debug("correlationID = " + correlationID);
 
             // Countdown on the traffic light if there is one for the matching correlation id.
             PerCorrelationId perCorrelationId = perCorrelationIds.get(correlationID);
@@ -773,7 +777,7 @@
                 // Restart the timeout timer on every message.
                 perCorrelationId.timeOutStart = System.nanoTime();
 
-                log.debug("Reply was expected, decrementing the latch for the id, " + correlationID);
+                // log.debug("Reply was expected, decrementing the latch for the id, " + correlationID);
 
                 // Decrement the countdown latch. Before this point, it is possible that two threads might enter this
                 // method simultanesouly with the same correlation id. Decrementing the latch in a synchronized block
@@ -788,8 +792,8 @@
                     trueCount = trafficLight.getCount();
                     remainingCount = trueCount - 1;
 
-                    log.debug("remainingCount = " + remainingCount);
-                    log.debug("trueCount = " + trueCount);
+                    // log.debug("remainingCount = " + remainingCount);
+                    // log.debug("trueCount = " + trueCount);
 
                     // Commit on transaction batch size boundaries. At this point in time the waiting producer remains
                     // blocked, even on the last message.
@@ -818,23 +822,23 @@
             }
 
             // Print out ping times for every message in verbose mode only.
-            if (_verbose)
+            /*if (_verbose)
             {
                 Long timestamp = message.getLongProperty(MESSAGE_TIMESTAMP_PROPNAME);
 
                 if (timestamp != null)
                 {
                     long diff = System.nanoTime() - timestamp;
-                    log.trace("Time for round trip (nanos): " + diff);
+                    //log.trace("Time for round trip (nanos): " + diff);
                 }
-            }
+            }*/
         }
         catch (JMSException e)
         {
             log.warn("There was a JMSException: " + e.getMessage(), e);
         }
 
-        log.debug("public void onMessage(Message message): ending");
+        // log.debug("public void onMessage(Message message): ending");
     }
 
     /**
@@ -1188,6 +1192,7 @@
             if (_connection != null)
             {
                 _connection.close();
+                log.debug("Close connection.");
             }
         }
         finally
@@ -1225,20 +1230,20 @@
      */
     protected boolean commitTx(Session session) throws JMSException
     {
-        log.debug("protected void commitTx(Session session): called");
+        // log.debug("protected void commitTx(Session session): called");
 
         boolean committed = false;
 
-        log.trace("Batch time reached");
+        // log.trace("Batch time reached");
         if (_failAfterSend)
         {
-            log.trace("Batch size reached");
+            // log.trace("Batch size reached");
             if (_failOnce)
             {
                 _failAfterSend = false;
             }
 
-            log.trace("Failing After Send");
+            // log.trace("Failing After Send");
             waitForUser(KILL_BROKER_PROMPT);
         }
 
@@ -1253,14 +1258,14 @@
                         _failBeforeCommit = false;
                     }
 
-                    log.trace("Failing Before Commit");
+                    // log.trace("Failing Before Commit");
                     waitForUser(KILL_BROKER_PROMPT);
                 }
 
-                long l = System.nanoTime();
+                // long l = System.nanoTime();
                 session.commit();
                 committed = true;
-                log.debug("Time taken to commit :" + ((System.nanoTime() - l) / 1000000f) + " ms");
+                // log.debug("Time taken to commit :" + ((System.nanoTime() - l) / 1000000f) + " ms");
 
                 if (_failAfterCommit)
                 {
@@ -1269,15 +1274,15 @@
                         _failAfterCommit = false;
                     }
 
-                    log.trace("Failing After Commit");
+                    // log.trace("Failing After Commit");
                     waitForUser(KILL_BROKER_PROMPT);
                 }
 
-                log.trace("Session Commited.");
+                // log.trace("Session Commited.");
             }
             catch (JMSException e)
             {
-                log.trace("JMSException on commit:" + e.getMessage(), e);
+                log.debug("JMSException on commit:" + e.getMessage(), e);
 
                 // Warn that the bounce back client is not available.
                 if (e.getLinkedException() instanceof AMQNoConsumersException)
@@ -1288,11 +1293,11 @@
                 try
                 {
                     session.rollback();
-                    log.trace("Message rolled back.");
+                    log.debug("Message rolled back.");
                 }
                 catch (JMSException jmse)
                 {
-                    log.trace("JMSE on rollback:" + jmse.getMessage(), jmse);
+                    log.debug("JMSE on rollback:" + jmse.getMessage(), jmse);
 
                     // Both commit and rollback failed. Throw the rollback exception.
                     throw jmse;

Modified: incubator/qpid/branches/M2/java/perftests/src/test/java/org/apache/qpid/ping/PingAsyncTestPerf.java
URL: http://svn.apache.org/viewvc/incubator/qpid/branches/M2/java/perftests/src/test/java/org/apache/qpid/ping/PingAsyncTestPerf.java?view=diff&rev=524050&r1=524049&r2=524050
==============================================================================
--- incubator/qpid/branches/M2/java/perftests/src/test/java/org/apache/qpid/ping/PingAsyncTestPerf.java (original)
+++ incubator/qpid/branches/M2/java/perftests/src/test/java/org/apache/qpid/ping/PingAsyncTestPerf.java Fri Mar 30 04:51:09 2007
@@ -246,9 +246,9 @@
                 // Extract the correlation id from the message.
                 String correlationId = message.getJMSCorrelationID();
 
-                _logger.debug("public void onMessage(Message message, int remainingCount = " + remainingCount
+                /*_logger.debug("public void onMessage(Message message, int remainingCount = " + remainingCount
                     + "): called on batch boundary for message id: " + correlationId + " with thread id: "
-                    + Thread.currentThread().getId());
+                    + Thread.currentThread().getId());*/
 
                 // Get the details for the correlation id and check that they are not null. They can become null
                 // if a test times out.