X-Git-Url: http://lists.indexdata.com/cgi-bin?a=blobdiff_plain;f=src%2Ffilter_log.cpp;h=2cc54a42efc0e384c902a5bab642ea4fa8e3a277;hb=cf09b1981338f07c333e43e0a2c907012c841c5d;hp=f652c3baa338dc2146af06cd86a4aff64dafd460;hpb=b84218609efb976d5b5270bd9e95a3c421af3a13;p=metaproxy-moved-to-github.git

diff --git a/src/filter_log.cpp b/src/filter_log.cpp
index f652c3b..2cc54a4 100644
--- a/src/filter_log.cpp
+++ b/src/filter_log.cpp
@@ -1,3 +1,8 @@
+/* $Id: filter_log.cpp,v 1.6 2005-10-25 11:48:30 adam Exp $
+   Copyright (c) 2005, Index Data.
+
+%LICENSE%
+ */
 
 
 #include "config.hpp"
@@ -11,23 +16,60 @@
 #include <yaz/zgdu.h>
 #include <yaz/log.h>
 
+#include <boost/date_time/posix_time/posix_time.hpp>
 #include <iostream>
 
+
 yp2::filter::Log::Log() {}
 
 void yp2::filter::Log::process(Package &package) const {
 
     Z_GDU *gdu;
 
-    gdu = package.request().get();
-    if (gdu)
-    {
-	ODR odr = odr_createmem(ODR_PRINT);
-	z_GDU(odr, &gdu, 0, 0);
-	odr_destroy(odr);
+    // getting timestamp for receiving of package
+    boost::posix_time::ptime receive_time
+        = boost::posix_time::microsec_clock::local_time();
+
+    // scope for locking Ostream 
+    { 
+        boost::mutex::scoped_lock scoped_lock(m_log_mutex);
+        std::cout << receive_time << " " << m_msg;
+        std::cout << " request id=" << package.session().id();
+        std::cout << " close=" 
+                  << (package.session().is_closed() ? "yes" : "no")
+                  << "\n";
+        gdu = package.request().get();
+        if (gdu)
+        {
+            ODR odr = odr_createmem(ODR_PRINT);
+            z_GDU(odr, &gdu, 0, 0);
+            odr_destroy(odr);
+        }
     }
+
+    // unlocked during move
     package.move();
 
+    // getting timestamp for sending of package
+    boost::posix_time::ptime send_time
+        = boost::posix_time::microsec_clock::local_time();
+
+    boost::posix_time::time_duration duration = send_time - receive_time;
+
+    // scope for locking Ostream 
+    { 
+        boost::mutex::scoped_lock scoped_lock(m_log_mutex);
+        std::cout << send_time << " " << m_msg;
+        std::cout << " response id=" << package.session().id();
+        std::cout << " close=" 
+                  << (package.session().is_closed() ? "yes " : "no ")
+                  << "duration=" << duration      
+                  << "\n";
+            //<< "duration=" << duration.total_seconds() 
+            //    << "." << duration.fractional_seconds()
+            //      << "\n";
+    }
+    
     gdu = package.response().get();
     if (gdu)
     {
@@ -37,4 +79,19 @@ void yp2::filter::Log::process(Package &package) const {
     }
 }
 
+void yp2::filter::Log::set_prefix(const std::string &msg)
+{
+    m_msg = msg;
+}
+
+// defining and initializing static members
+boost::mutex yp2::filter::Log::m_log_mutex;
 
+/*
+ * Local variables:
+ * c-basic-offset: 4
+ * indent-tabs-mode: nil
+ * c-file-style: "stroustrup"
+ * End:
+ * vim: shiftwidth=4 tabstop=8 expandtab
+ */