|
1
|
1 /**
|
|
|
2 * Orthanc - A Lightweight, RESTful DICOM Store
|
|
|
3 * Copyright (C) 2012-2016 Sebastien Jodogne, Medical Physics
|
|
|
4 * Department, University Hospital of Liege, Belgium
|
|
|
5 *
|
|
|
6 * This program is free software: you can redistribute it and/or
|
|
|
7 * modify it under the terms of the GNU General Public License as
|
|
|
8 * published by the Free Software Foundation, either version 3 of the
|
|
|
9 * License, or (at your option) any later version.
|
|
|
10 *
|
|
|
11 * In addition, as a special exception, the copyright holders of this
|
|
|
12 * program give permission to link the code of its release with the
|
|
|
13 * OpenSSL project's "OpenSSL" library (or with modified versions of it
|
|
|
14 * that use the same license as the "OpenSSL" library), and distribute
|
|
|
15 * the linked executables. You must obey the GNU General Public License
|
|
|
16 * in all respects for all of the code used other than "OpenSSL". If you
|
|
|
17 * modify file(s) with this exception, you may extend this exception to
|
|
|
18 * your version of the file(s), but you are not obligated to do so. If
|
|
|
19 * you do not wish to do so, delete this exception statement from your
|
|
|
20 * version. If you delete this exception statement from all source files
|
|
|
21 * in the program, then also delete it here.
|
|
|
22 *
|
|
|
23 * This program is distributed in the hope that it will be useful, but
|
|
|
24 * WITHOUT ANY WARRANTY; without even the implied warranty of
|
|
|
25 * MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the GNU
|
|
|
26 * General Public License for more details.
|
|
|
27 *
|
|
|
28 * You should have received a copy of the GNU General Public License
|
|
|
29 * along with this program. If not, see <http://www.gnu.org/licenses/>.
|
|
|
30 **/
|
|
|
31
|
|
|
32
|
|
|
33 #include "PrecompiledHeaders.h"
|
|
|
34 #include "Logging.h"
|
|
|
35
|
|
|
36 #if ORTHANC_ENABLE_LOGGING != 1
|
|
|
37
|
|
|
38 namespace Orthanc
|
|
|
39 {
|
|
|
40 namespace Logging
|
|
|
41 {
|
|
|
42 void Initialize()
|
|
|
43 {
|
|
|
44 }
|
|
|
45
|
|
|
46 void Finalize()
|
|
|
47 {
|
|
|
48 }
|
|
|
49
|
|
|
50 void Reset()
|
|
|
51 {
|
|
|
52 }
|
|
|
53
|
|
|
54 void Flush()
|
|
|
55 {
|
|
|
56 }
|
|
|
57
|
|
|
58 void EnableInfoLevel(bool enabled)
|
|
|
59 {
|
|
|
60 }
|
|
|
61
|
|
|
62 void EnableTraceLevel(bool enabled)
|
|
|
63 {
|
|
|
64 }
|
|
|
65
|
|
|
66 void SetTargetFile(const std::string& path)
|
|
|
67 {
|
|
|
68 }
|
|
|
69
|
|
|
70 void SetTargetFolder(const std::string& path)
|
|
|
71 {
|
|
|
72 }
|
|
|
73 }
|
|
|
74 }
|
|
|
75
|
|
|
76 #else
|
|
|
77
|
|
|
78 /*********************************************************
|
|
|
79 * Internal logger of Orthanc, that mimics some
|
|
|
80 * behavior from Google Log.
|
|
|
81 *********************************************************/
|
|
|
82
|
|
|
83 #include "OrthancException.h"
|
|
|
84 #include "Enumerations.h"
|
|
|
85 #include "Toolbox.h"
|
|
|
86
|
|
|
87 #include <fstream>
|
|
|
88 #include <boost/filesystem.hpp>
|
|
|
89 #include <boost/thread.hpp>
|
|
|
90
|
|
|
91 #if BOOST_HAS_DATE_TIME == 1
|
|
|
92 # include <boost/date_time/posix_time/posix_time.hpp>
|
|
|
93 #else
|
|
|
94 # error Boost::date_time is required
|
|
|
95 #endif
|
|
|
96
|
|
|
97
|
|
|
98 namespace
|
|
|
99 {
|
|
|
100 struct LoggingContext
|
|
|
101 {
|
|
|
102 bool infoEnabled_;
|
|
|
103 bool traceEnabled_;
|
|
|
104 std::string targetFile_;
|
|
|
105 std::string targetFolder_;
|
|
|
106
|
|
|
107 std::ostream* error_;
|
|
|
108 std::ostream* warning_;
|
|
|
109 std::ostream* info_;
|
|
|
110
|
|
|
111 std::auto_ptr<std::ofstream> file_;
|
|
|
112
|
|
|
113 LoggingContext() :
|
|
|
114 infoEnabled_(false),
|
|
|
115 traceEnabled_(false),
|
|
|
116 error_(&std::cerr),
|
|
|
117 warning_(&std::cerr),
|
|
|
118 info_(&std::cerr)
|
|
|
119 {
|
|
|
120 }
|
|
|
121 };
|
|
|
122 }
|
|
|
123
|
|
|
124
|
|
|
125
|
|
|
126 static std::auto_ptr<LoggingContext> loggingContext_;
|
|
|
127 static boost::mutex loggingMutex_;
|
|
|
128
|
|
|
129
|
|
|
130
|
|
|
131 namespace Orthanc
|
|
|
132 {
|
|
|
133 namespace Logging
|
|
|
134 {
|
|
|
135 static void GetLogPath(boost::filesystem::path& log,
|
|
|
136 boost::filesystem::path& link,
|
|
|
137 const std::string& suffix,
|
|
|
138 const std::string& directory)
|
|
|
139 {
|
|
|
140 /**
|
|
|
141 From Google Log documentation:
|
|
|
142
|
|
|
143 Unless otherwise specified, logs will be written to the filename
|
|
|
144 "<program name>.<hostname>.<user name>.log<suffix>.",
|
|
|
145 followed by the date, time, and pid (you can't prevent the date,
|
|
|
146 time, and pid from being in the filename).
|
|
|
147
|
|
|
148 In this implementation : "hostname" and "username" are not used
|
|
|
149 **/
|
|
|
150
|
|
|
151 boost::posix_time::ptime now = boost::posix_time::second_clock::local_time();
|
|
|
152 boost::filesystem::path root(directory);
|
|
|
153 boost::filesystem::path exe(Toolbox::GetPathToExecutable());
|
|
|
154
|
|
|
155 if (!boost::filesystem::exists(root) ||
|
|
|
156 !boost::filesystem::is_directory(root))
|
|
|
157 {
|
|
|
158 throw OrthancException(ErrorCode_CannotWriteFile);
|
|
|
159 }
|
|
|
160
|
|
|
161 char date[64];
|
|
|
162 sprintf(date, "%04d%02d%02d-%02d%02d%02d.%d",
|
|
|
163 static_cast<int>(now.date().year()),
|
|
|
164 now.date().month().as_number(),
|
|
|
165 now.date().day().as_number(),
|
|
|
166 now.time_of_day().hours(),
|
|
|
167 now.time_of_day().minutes(),
|
|
|
168 now.time_of_day().seconds(),
|
|
|
169 Toolbox::GetProcessId());
|
|
|
170
|
|
|
171 std::string programName = exe.filename().replace_extension("").string();
|
|
|
172
|
|
|
173 log = (root / (programName + ".log" + suffix + "." + std::string(date)));
|
|
|
174 link = (root / (programName + ".log" + suffix));
|
|
|
175 }
|
|
|
176
|
|
|
177
|
|
|
178 static void PrepareLogFolder(std::auto_ptr<std::ofstream>& file,
|
|
|
179 const std::string& suffix,
|
|
|
180 const std::string& directory)
|
|
|
181 {
|
|
|
182 boost::filesystem::path log, link;
|
|
|
183 GetLogPath(log, link, suffix, directory);
|
|
|
184
|
|
|
185 #if !defined(_WIN32) && (defined(__unix__) || defined(__unix) || (defined(__APPLE__) && defined(__MACH__)))
|
|
|
186 boost::filesystem::remove(link);
|
|
|
187 boost::filesystem::create_symlink(log.filename(), link);
|
|
|
188 #endif
|
|
|
189
|
|
|
190 file.reset(new std::ofstream(log.string().c_str()));
|
|
|
191 }
|
|
|
192
|
|
|
193
|
|
|
194 void Initialize()
|
|
|
195 {
|
|
|
196 boost::mutex::scoped_lock lock(loggingMutex_);
|
|
|
197 loggingContext_.reset(new LoggingContext);
|
|
|
198 }
|
|
|
199
|
|
|
200 void Finalize()
|
|
|
201 {
|
|
|
202 boost::mutex::scoped_lock lock(loggingMutex_);
|
|
|
203 loggingContext_.reset(NULL);
|
|
|
204 }
|
|
|
205
|
|
|
206 void Reset()
|
|
|
207 {
|
|
|
208 // Recover the old logging context
|
|
|
209 std::auto_ptr<LoggingContext> old;
|
|
|
210
|
|
|
211 {
|
|
|
212 boost::mutex::scoped_lock lock(loggingMutex_);
|
|
|
213 if (loggingContext_.get() == NULL)
|
|
|
214 {
|
|
|
215 return;
|
|
|
216 }
|
|
|
217 else
|
|
|
218 {
|
|
|
219 old = loggingContext_;
|
|
|
220
|
|
|
221 // Create a new logging context,
|
|
|
222 loggingContext_.reset(new LoggingContext);
|
|
|
223 }
|
|
|
224 }
|
|
|
225
|
|
|
226 EnableInfoLevel(old->infoEnabled_);
|
|
|
227 EnableTraceLevel(old->traceEnabled_);
|
|
|
228
|
|
|
229 if (!old->targetFolder_.empty())
|
|
|
230 {
|
|
|
231 SetTargetFolder(old->targetFolder_);
|
|
|
232 }
|
|
|
233 else if (!old->targetFile_.empty())
|
|
|
234 {
|
|
|
235 SetTargetFile(old->targetFile_);
|
|
|
236 }
|
|
|
237 }
|
|
|
238
|
|
|
239 void EnableInfoLevel(bool enabled)
|
|
|
240 {
|
|
|
241 boost::mutex::scoped_lock lock(loggingMutex_);
|
|
|
242 assert(loggingContext_.get() != NULL);
|
|
|
243
|
|
|
244 loggingContext_->infoEnabled_ = enabled;
|
|
|
245
|
|
|
246 if (!enabled)
|
|
|
247 {
|
|
|
248 // Also disable the "TRACE" level when info-level debugging is disabled
|
|
|
249 loggingContext_->traceEnabled_ = false;
|
|
|
250 }
|
|
|
251 }
|
|
|
252
|
|
|
253 void EnableTraceLevel(bool enabled)
|
|
|
254 {
|
|
|
255 boost::mutex::scoped_lock lock(loggingMutex_);
|
|
|
256 assert(loggingContext_.get() != NULL);
|
|
|
257
|
|
|
258 loggingContext_->traceEnabled_ = enabled;
|
|
|
259
|
|
|
260 if (enabled)
|
|
|
261 {
|
|
|
262 // Also enable the "INFO" level when trace-level debugging is enabled
|
|
|
263 loggingContext_->infoEnabled_ = true;
|
|
|
264 }
|
|
|
265 }
|
|
|
266
|
|
|
267
|
|
|
268 static void CheckFile(std::auto_ptr<std::ofstream>& f)
|
|
|
269 {
|
|
|
270 if (loggingContext_->file_.get() == NULL ||
|
|
|
271 !loggingContext_->file_->is_open())
|
|
|
272 {
|
|
|
273 throw OrthancException(ErrorCode_CannotWriteFile);
|
|
|
274 }
|
|
|
275 }
|
|
|
276
|
|
|
277 void SetTargetFolder(const std::string& path)
|
|
|
278 {
|
|
|
279 boost::mutex::scoped_lock lock(loggingMutex_);
|
|
|
280 assert(loggingContext_.get() != NULL);
|
|
|
281
|
|
|
282 PrepareLogFolder(loggingContext_->file_, "" /* no suffix */, path);
|
|
|
283 CheckFile(loggingContext_->file_);
|
|
|
284
|
|
|
285 loggingContext_->targetFile_.clear();
|
|
|
286 loggingContext_->targetFolder_ = path;
|
|
|
287 loggingContext_->warning_ = loggingContext_->file_.get();
|
|
|
288 loggingContext_->error_ = loggingContext_->file_.get();
|
|
|
289 loggingContext_->info_ = loggingContext_->file_.get();
|
|
|
290 }
|
|
|
291
|
|
|
292
|
|
|
293 void SetTargetFile(const std::string& path)
|
|
|
294 {
|
|
|
295 boost::mutex::scoped_lock lock(loggingMutex_);
|
|
|
296 assert(loggingContext_.get() != NULL);
|
|
|
297
|
|
|
298 loggingContext_->file_.reset(new std::ofstream(path.c_str(), std::fstream::app));
|
|
|
299 CheckFile(loggingContext_->file_);
|
|
|
300
|
|
|
301 loggingContext_->targetFile_ = path;
|
|
|
302 loggingContext_->targetFolder_.clear();
|
|
|
303 loggingContext_->warning_ = loggingContext_->file_.get();
|
|
|
304 loggingContext_->error_ = loggingContext_->file_.get();
|
|
|
305 loggingContext_->info_ = loggingContext_->file_.get();
|
|
|
306 }
|
|
|
307
|
|
|
308
|
|
|
309 InternalLogger::InternalLogger(const char* level,
|
|
|
310 const char* file,
|
|
|
311 int line) :
|
|
|
312 lock_(loggingMutex_),
|
|
|
313 stream_(&null_) // By default, logging to "/dev/null" is simulated
|
|
|
314 {
|
|
|
315 if (loggingContext_.get() == NULL)
|
|
|
316 {
|
|
|
317 fprintf(stderr, "ERROR: Trying to log a message after the finalization of the logging engine\n");
|
|
|
318 return;
|
|
|
319 }
|
|
|
320
|
|
12
|
321 try
|
|
1
|
322 {
|
|
12
|
323 LogLevel l = StringToLogLevel(level);
|
|
|
324
|
|
|
325 if ((l == LogLevel_Info && !loggingContext_->infoEnabled_) ||
|
|
|
326 (l == LogLevel_Trace && !loggingContext_->traceEnabled_))
|
|
|
327 {
|
|
|
328 // This logging level is disabled, directly exit and unlock
|
|
|
329 // the mutex to speed-up things. The stream is set to "/dev/null"
|
|
|
330 lock_.unlock();
|
|
|
331 return;
|
|
|
332 }
|
|
1
|
333
|
|
12
|
334 // Compute the header of the line, temporary release the lock as
|
|
|
335 // this is a time-consuming operation
|
|
|
336 lock_.unlock();
|
|
|
337 std::string header;
|
|
1
|
338
|
|
12
|
339 {
|
|
|
340 boost::filesystem::path path(file);
|
|
|
341 boost::posix_time::ptime now = boost::posix_time::microsec_clock::local_time();
|
|
|
342 boost::posix_time::time_duration duration = now.time_of_day();
|
|
1
|
343
|
|
12
|
344 /**
|
|
|
345 From Google Log documentation:
|
|
1
|
346
|
|
12
|
347 "Log lines have this form:
|
|
1
|
348
|
|
12
|
349 Lmmdd hh:mm:ss.uuuuuu threadid file:line] msg...
|
|
1
|
350
|
|
12
|
351 where the fields are defined as follows:
|
|
1
|
352
|
|
12
|
353 L A single character, representing the log level (eg 'I' for INFO)
|
|
|
354 mm The month (zero padded; ie May is '05')
|
|
|
355 dd The day (zero padded)
|
|
|
356 hh:mm:ss.uuuuuu Time in hours, minutes and fractional seconds
|
|
|
357 threadid The space-padded thread ID as returned by GetTID() (this matches the PID on Linux)
|
|
|
358 file The file name
|
|
|
359 line The line number
|
|
|
360 msg The user-supplied message"
|
|
1
|
361
|
|
12
|
362 In this implementation, "threadid" is not printed.
|
|
|
363 **/
|
|
1
|
364
|
|
12
|
365 char date[32];
|
|
|
366 sprintf(date, "%c%02d%02d %02d:%02d:%02d.%06d ",
|
|
|
367 level[0],
|
|
|
368 now.date().month().as_number(),
|
|
|
369 now.date().day().as_number(),
|
|
|
370 duration.hours(),
|
|
|
371 duration.minutes(),
|
|
|
372 duration.seconds(),
|
|
|
373 static_cast<int>(duration.fractional_seconds()));
|
|
1
|
374
|
|
12
|
375 header = std::string(date) + path.filename().string() + ":" + boost::lexical_cast<std::string>(line) + "] ";
|
|
|
376 }
|
|
1
|
377
|
|
|
378
|
|
12
|
379 // The header is computed, we now re-lock the mutex to access
|
|
|
380 // the stream objects. Pay attention that "loggingContext_",
|
|
|
381 // "infoEnabled_" or "traceEnabled_" might have changed while
|
|
|
382 // the mutex was unlocked.
|
|
|
383 lock_.lock();
|
|
|
384
|
|
|
385 if (loggingContext_.get() == NULL)
|
|
|
386 {
|
|
|
387 fprintf(stderr, "ERROR: Trying to log a message after the finalization of the logging engine\n");
|
|
|
388 return;
|
|
|
389 }
|
|
1
|
390
|
|
12
|
391 switch (l)
|
|
|
392 {
|
|
|
393 case LogLevel_Error:
|
|
|
394 stream_ = loggingContext_->error_;
|
|
|
395 break;
|
|
1
|
396
|
|
12
|
397 case LogLevel_Warning:
|
|
|
398 stream_ = loggingContext_->warning_;
|
|
|
399 break;
|
|
1
|
400
|
|
12
|
401 case LogLevel_Info:
|
|
|
402 if (loggingContext_->infoEnabled_)
|
|
|
403 {
|
|
|
404 stream_ = loggingContext_->info_;
|
|
|
405 }
|
|
|
406
|
|
|
407 break;
|
|
1
|
408
|
|
12
|
409 case LogLevel_Trace:
|
|
|
410 if (loggingContext_->traceEnabled_)
|
|
|
411 {
|
|
|
412 stream_ = loggingContext_->info_;
|
|
|
413 }
|
|
1
|
414
|
|
12
|
415 break;
|
|
1
|
416
|
|
12
|
417 default:
|
|
|
418 throw OrthancException(ErrorCode_InternalError);
|
|
|
419 }
|
|
1
|
420
|
|
12
|
421 if (stream_ == &null_)
|
|
|
422 {
|
|
|
423 // The logging is disabled for this level. The stream is the
|
|
|
424 // "null_" member of this object, so we can release the global
|
|
|
425 // mutex.
|
|
|
426 lock_.unlock();
|
|
|
427 }
|
|
1
|
428
|
|
12
|
429 (*stream_) << header;
|
|
1
|
430 }
|
|
12
|
431 catch (...)
|
|
|
432 {
|
|
|
433 // Something is going really wrong, probably running out of
|
|
|
434 // memory. Fallback to a degraded mode.
|
|
|
435 stream_ = loggingContext_->error_;
|
|
|
436 (*stream_) << "E???? ??:??:??.?????? ] ";
|
|
|
437 }
|
|
1
|
438 }
|
|
|
439
|
|
|
440
|
|
|
441 InternalLogger::~InternalLogger()
|
|
|
442 {
|
|
|
443 if (stream_ != &null_)
|
|
|
444 {
|
|
|
445 #if defined(_WIN32)
|
|
|
446 *stream_ << "\r\n";
|
|
|
447 #else
|
|
|
448 *stream_ << "\n";
|
|
|
449 #endif
|
|
|
450
|
|
|
451 stream_->flush();
|
|
|
452 }
|
|
|
453 }
|
|
|
454
|
|
|
455
|
|
|
456 void Flush()
|
|
|
457 {
|
|
|
458 boost::mutex::scoped_lock lock(loggingMutex_);
|
|
|
459
|
|
|
460 if (loggingContext_.get() != NULL &&
|
|
|
461 loggingContext_->file_.get() != NULL)
|
|
|
462 {
|
|
|
463 loggingContext_->file_->flush();
|
|
|
464 }
|
|
|
465 }
|
|
|
466 }
|
|
|
467 }
|
|
|
468
|
|
|
469 #endif // ORTHANC_ENABLE_LOGGING
|
