X-Git-Url: http://git.indexdata.com/?a=blobdiff_plain;f=src%2Ffilter_log.cpp;h=4142be7ed88eb23a364bccb37801cc93bd38934e;hb=63ea8aedbfd7dc56ab28dbc82c38b4458dcecb68;hp=a1f0ad7ab8061e3def2ec20d8e19fbd772447280;hpb=ced440c14eaf1e9408f994ef1e7be2deb8a7db0b;p=metaproxy-moved-to-github.git

diff --git a/src/filter_log.cpp b/src/filter_log.cpp
index a1f0ad7..4142be7 100644
--- a/src/filter_log.cpp
+++ b/src/filter_log.cpp
@@ -1,54 +1,114 @@
-/* $Id: filter_log.cpp,v 1.4 2005-10-16 16:05:44 adam Exp $
+/* $Id: filter_log.cpp,v 1.10 2006-01-04 11:19:04 adam Exp $
    Copyright (c) 2005, Index Data.
 
 %LICENSE%
  */
 
-
 #include "config.hpp"
 
 #include "filter.hpp"
 #include "router.hpp"
 #include "package.hpp"
 
+#include <string>
+#include <boost/thread/mutex.hpp>
+#include <boost/date_time/posix_time/posix_time.hpp>
+
+#include "util.hpp"
 #include "filter_log.hpp"
 
 #include <yaz/zgdu.h>
-#include <yaz/log.h>
 
-#include <iostream>
+namespace yf = yp2::filter;
+
+namespace yp2 {
+    namespace filter {
+        class Log::Rep {
+            friend class Log;
+            static boost::mutex m_log_mutex;
+            std::string m_msg;
+        };
+    }
+}
 
-yp2::filter::Log::Log() {}
+boost::mutex yf::Log::Rep::m_log_mutex;
 
-void yp2::filter::Log::process(Package &package) const {
+yf::Log::Log(const std::string &x) : m_p(new Rep)
+{
+    m_p->m_msg = x;
+}
 
-    Z_GDU *gdu;
+yf::Log::Log() : m_p(new Rep)
+{
+}
 
-    std::cout << "---- req id=" << package.session().id();
+yf::Log::~Log() {}
 
-    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);
+void yf::Log::process(Package &package) const
+{
+    Z_GDU *gdu;
+
+    // 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(Rep::m_log_mutex);
+        std::cout << receive_time << " " << m_p->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)
+        {
+            yp2::odr odr(ODR_PRINT);
+            z_GDU(odr, &gdu, 0, 0);
+        }
     }
+
+    // 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(Rep::m_log_mutex);
+        std::cout << send_time << " " << m_p->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)
+        {
+            yp2::odr odr(ODR_PRINT);
+            z_GDU(odr, &gdu, 0, 0);
+        }
+    }
+}
 
-    std::cout << "---- res id=" << package.session().id();
+static yp2::filter::Base* filter_creator()
+{
+    return new yp2::filter::Log;
+}
 
-    std::cout << " close=" << (package.session().is_closed() ? "yes" : "no")
-              << "\n";
-    gdu = package.response().get();
-    if (gdu)
-    {
-	ODR odr = odr_createmem(ODR_PRINT);
-	z_GDU(odr, &gdu, 0, 0);
-	odr_destroy(odr);
-    }
+extern "C" {
+    const struct yp2_filter_struct yp2_filter_log = {
+        0,
+        "log",
+        filter_creator
+    };
 }