git.sesse.net Git - casparcg/blob - common/log.cpp

   1 /*\r
   2 * Copyright (c) 2011 Sveriges Television AB <info@casparcg.com>\r
   3 *\r
   4 * This file is part of CasparCG (www.casparcg.com).\r
   5 *\r
   6 * CasparCG is free software: you can redistribute it and/or modify\r
   7 * it under the terms of the GNU General Public License as published by\r
   8 * the Free Software Foundation, either version 3 of the License, or\r
   9 * (at your option) any later version.\r
  10 *\r
  11 * CasparCG is distributed in the hope that it will be useful,\r
  12 * but WITHOUT ANY WARRANTY; without even the implied warranty of\r
  13 * MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the\r
  14 * GNU General Public License for more details.\r
  15 *\r
  16 * You should have received a copy of the GNU General Public License\r
  17 * along with CasparCG. If not, see <http://www.gnu.org/licenses/>.\r
  18 *\r
  19 * Author: Robert Nagy, ronag89@gmail.com\r
  20 */\r
  21 \r
  22 #include "stdafx.h"\r
  23 \r
  24 #if defined(_MSC_VER)\r
  25 #pragma warning (disable : 4100) // 'identifier' : unreferenced formal parameter\r
  26 #pragma warning (disable : 4512) // 'class' : assignment operator could not be generated\r
  27 #endif\r
  28 \r
  29 #include "log.h"\r
  30 \r
  31 #include "except.h"\r
  32 #include "utf.h"\r
  33 #include "compiler/vs/stack_walker.h"\r
  34 \r
  35 #include <ios>\r
  36 #include <string>\r
  37 #include <ostream>\r
  38 \r
  39 #include <boost/bind.hpp>\r
  40 #include <boost/shared_ptr.hpp>\r
  41 #include <boost/make_shared.hpp>\r
  42 #include <boost/filesystem/convenience.hpp>\r
  43 #include <boost/date_time/posix_time/posix_time.hpp>\r
  44 #include <boost/algorithm/string.hpp>\r
  45 \r
  46 #include <boost/log/core/core.hpp>\r
  47 \r
  48 #include <boost/log/formatters/stream.hpp>\r
  49 #include <boost/log/formatters/attr.hpp>\r
  50 #include <boost/log/formatters/date_time.hpp>\r
  51 #include <boost/log/formatters/message.hpp>\r
  52 \r
  53 #include <boost/log/filters/attr.hpp>\r
  54 \r
  55 #include <boost/log/sinks/text_file_backend.hpp>\r
  56 \r
  57 #include <boost/log/detail/universal_path.hpp>\r
  58 \r
  59 #include <boost/log/sinks/text_file_backend.hpp>\r
  60 #include <boost/log/sinks/text_ostream_backend.hpp>\r
  61 #include <boost/log/sinks/sync_frontend.hpp>\r
  62 #include <boost/log/sinks/async_frontend.hpp>\r
  63 #include <boost/log/core/record.hpp>\r
  64 #include <boost/log/utility/attribute_value_extractor.hpp>\r
  65 \r
  66 #include <boost/log/utility/init/common_attributes.hpp>\r
  67 #include <boost/log/utility/empty_deleter.hpp>\r
  68 #include <boost/lambda/lambda.hpp>\r
  69 \r
  70 #include <tbb/enumerable_thread_specific.h>\r
  71 \r
  72 namespace caspar { namespace log {\r
  73 \r
  74 using namespace boost;\r
  75 \r
  76 void my_formatter(bool print_all_characters, std::wostream& strm, boost::log::basic_record<wchar_t> const& rec)\r
  77 {\r
  78     namespace lambda = boost::lambda;\r
  79         \r
  80         #pragma warning(disable : 4996)\r
  81         time_t rawtime;\r
  82         struct tm* timeinfo;\r
  83         time(&rawtime );\r
  84         timeinfo = localtime ( &rawtime );\r
  85         char buffer [80];\r
  86         strftime (buffer,80, "%c", timeinfo);\r
  87         strm << L"[" << buffer << L"] ";\r
  88                 \r
  89     boost::log::attributes::current_thread_id::held_type thread_id;\r
  90     if(boost::log::extract<boost::log::attributes::current_thread_id::held_type>(L"ThreadID", rec.attribute_values(), lambda::var(thread_id) = lambda::_1))\r
  91         strm << L"[" << thread_id << L"] ";\r
  92         \r
  93     severity_level severity;\r
  94     if(boost::log::extract<severity_level>(boost::log::sources::aux::severity_attribute_name<wchar_t>::get(), rec.attribute_values(), lambda::var(severity) = lambda::_1))\r
  95         {\r
  96                 std::stringstream ss;\r
  97                 ss << severity;\r
  98         strm << L"[" << severity << L"] ";\r
  99                 for(int n = 0; n < 7-static_cast<int>(ss.str().size()); ++n)\r
 100                         strm << L" ";\r
 101         }\r
 102 \r
 103         if (print_all_characters)\r
 104         {\r
 105                 strm << rec.message();\r
 106         }\r
 107         else\r
 108         {\r
 109             strm << replace_nonprintable_copy(rec.message(), L'?');\r
 110         }\r
 111 }\r
 112 \r
 113 namespace internal{\r
 114         \r
 115 void init()\r
 116 {       \r
 117         boost::log::add_common_attributes<wchar_t>();\r
 118         typedef boost::log::aux::add_common_attributes_constants<wchar_t> traits_t;\r
 119 \r
 120         typedef boost::log::sinks::synchronous_sink<boost::log::sinks::wtext_file_backend> file_sink_type;\r
 121 \r
 122         typedef boost::log::sinks::asynchronous_sink<boost::log::sinks::wtext_ostream_backend> stream_sink_type;\r
 123 \r
 124         auto stream_backend = boost::make_shared<boost::log::sinks::wtext_ostream_backend>();\r
 125         stream_backend->add_stream(boost::shared_ptr<std::wostream>(&std::wcout, boost::log::empty_deleter()));\r
 126         stream_backend->auto_flush(true);\r
 127 \r
 128         auto stream_sink = boost::make_shared<stream_sink_type>(stream_backend);\r
 129         \r
 130 //#ifdef NDEBUG\r
 131 //      stream_sink->set_filter(boost::log::filters::attr<severity_level>(boost::log::sources::aux::severity_attribute_name<wchar_t>::get()) >= debug);\r
 132 //#else\r
 133 //      stream_sink->set_filter(boost::log::filters::attr<severity_level>(boost::log::sources::aux::severity_attribute_name<wchar_t>::get()) >= debug);\r
 134 //#endif\r
 135 \r
 136         stream_sink->locked_backend()->set_formatter(boost::bind(my_formatter, false, _1, _2));\r
 137 \r
 138         boost::log::wcore::get()->add_sink(stream_sink);\r
 139 }\r
 140 \r
 141 std::wstring get_call_stack()\r
 142 {\r
 143         class log_call_stack_walker : public stack_walker\r
 144         {\r
 145                 std::string str_;\r
 146         public:\r
 147                 log_call_stack_walker() : stack_walker() {}\r
 148 \r
 149                 std::string flush()\r
 150                 {\r
 151                         return std::move(str_);\r
 152                 }\r
 153         protected:              \r
 154                 virtual void OnSymInit(LPCSTR szSearchPath, DWORD symOptions, LPCSTR szUserName)\r
 155                 {\r
 156                 }\r
 157                 virtual void OnLoadModule(LPCSTR img, LPCSTR mod, DWORD64 baseAddr, DWORD size, DWORD result, LPCSTR symType, LPCSTR pdbName, ULONGLONG fileVersion)\r
 158                 {\r
 159                 }\r
 160                 virtual void OnDbgHelpErr(LPCSTR szFuncName, DWORD gle, DWORD64 addr)\r
 161                 {\r
 162                 }\r
 163                 virtual void OnOutput(LPCSTR szText)\r
 164                 {\r
 165                         std::string str = szText;\r
 166 \r
 167                         if(str.find("internal::get_call_stack") == std::string::npos && str.find("stack_walker::ShowCallstack") == std::string::npos)\r
 168                                 str_ += std::move(str);\r
 169                 }\r
 170         };\r
 171 \r
 172         static tbb::enumerable_thread_specific<log_call_stack_walker> walkers;\r
 173         try\r
 174         {\r
 175                 auto& walker = walkers.local();\r
 176                 walker.ShowCallstack();\r
 177                 return u16(walker.flush());\r
 178         }\r
 179         catch(...)\r
 180         {\r
 181                 return L"!!!";\r
 182         }\r
 183 }\r
 184 \r
 185 }\r
 186 \r
 187 void add_file_sink(const std::wstring& folder)\r
 188 {       \r
 189         boost::log::add_common_attributes<wchar_t>();\r
 190         typedef boost::log::aux::add_common_attributes_constants<wchar_t> traits_t;\r
 191 \r
 192         typedef boost::log::sinks::synchronous_sink<boost::log::sinks::wtext_file_backend> file_sink_type;\r
 193 \r
 194         try\r
 195         {\r
 196                 if(!boost::filesystem::is_directory(folder))\r
 197                         CASPAR_THROW_EXCEPTION(directory_not_found());\r
 198 \r
 199                 auto file_sink = boost::make_shared<file_sink_type>(\r
 200                         boost::log::keywords::file_name = (folder + L"caspar_%Y-%m-%d.log"),\r
 201                         boost::log::keywords::time_based_rotation = boost::log::sinks::file::rotation_at_time_point(0, 0, 0),\r
 202                         boost::log::keywords::auto_flush = true,\r
 203                         boost::log::keywords::open_mode = std::ios::app\r
 204                 );\r
 205                 \r
 206                 file_sink->locked_backend()->set_formatter(boost::bind(my_formatter, true, _1, _2));\r
 207 \r
 208 //#ifdef NDEBUG\r
 209 //              file_sink->set_filter(boost::log::filters::attr<severity_level>(boost::log::sources::aux::severity_attribute_name<wchar_t>::get()) >= debug);\r
 210 //#else\r
 211 //              file_sink->set_filter(boost::log::filters::attr<severity_level>(boost::log::sources::aux::severity_attribute_name<wchar_t>::get()) >= debug);\r
 212 //#endif\r
 213                 boost::log::wcore::get()->add_sink(file_sink);\r
 214         }\r
 215         catch(...)\r
 216         {\r
 217                 std::wcerr << L"Failed to Setup File Logging Sink" << std::endl << std::endl;\r
 218         }\r
 219 }\r
 220 \r
 221 void set_log_level(const std::wstring& lvl)\r
 222 {       \r
 223         if(boost::iequals(lvl, L"trace"))\r
 224                 boost::log::wcore::get()->set_filter(boost::log::filters::attr<severity_level>(boost::log::sources::aux::severity_attribute_name<wchar_t>::get()) >= trace);\r
 225         else if(boost::iequals(lvl, L"debug"))\r
 226                 boost::log::wcore::get()->set_filter(boost::log::filters::attr<severity_level>(boost::log::sources::aux::severity_attribute_name<wchar_t>::get()) >= debug);\r
 227         else if(boost::iequals(lvl, L"info"))\r
 228                 boost::log::wcore::get()->set_filter(boost::log::filters::attr<severity_level>(boost::log::sources::aux::severity_attribute_name<wchar_t>::get()) >= info);\r
 229         else if(boost::iequals(lvl, L"warning"))\r
 230                 boost::log::wcore::get()->set_filter(boost::log::filters::attr<severity_level>(boost::log::sources::aux::severity_attribute_name<wchar_t>::get()) >= warning);\r
 231         else if(boost::iequals(lvl, L"error"))\r
 232                 boost::log::wcore::get()->set_filter(boost::log::filters::attr<severity_level>(boost::log::sources::aux::severity_attribute_name<wchar_t>::get()) >= error);\r
 233         else if(boost::iequals(lvl, L"fatal"))\r
 234                 boost::log::wcore::get()->set_filter(boost::log::filters::attr<severity_level>(boost::log::sources::aux::severity_attribute_name<wchar_t>::get()) >= fatal);\r
 235 }\r
 236 \r
 237 }}