| // Copyright 2009 Google Inc. All Rights Reserved. |
| |
| // Licensed under the Apache License, Version 2.0 (the "License"); |
| // you may not use this file except in compliance with the License. |
| // You may obtain a copy of the License at |
| |
| // http://www.apache.org/licenses/LICENSE-2.0 |
| |
| // Unless required by applicable law or agreed to in writing, software |
| // distributed under the License is distributed on an "AS IS" BASIS, |
| // WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied. |
| // See the License for the specific language governing permissions and |
| // limitations under the License. |
| |
| #include "logger.h" |
| |
| #include <pthread.h> |
| #include <stdarg.h> |
| #include <stdio.h> |
| #include <time.h> |
| #include <unistd.h> |
| |
| #include <string> |
| #include <vector> |
| |
| // This file must work with autoconf on its public version, |
| // so these includes are correct. |
| #include "sattypes.h" |
| |
| |
| Logger *Logger::GlobalLogger() { |
| static Logger logger; |
| return &logger; |
| } |
| |
| void Logger::VLogF(int priority, const char *format, va_list args) { |
| if (priority > verbosity_) { |
| return; |
| } |
| char buffer[4096]; |
| size_t length = 0; |
| if (log_timestamps_) { |
| time_t raw_time; |
| time(&raw_time); |
| struct tm time_struct; |
| localtime_r(&raw_time, &time_struct); |
| length = strftime(buffer, sizeof(buffer), "%Y/%m/%d-%H:%M:%S(%Z) ", |
| &time_struct); |
| LOGGER_ASSERT(length); // Catch if the buffer is set too small. |
| } |
| length += vsnprintf(buffer + length, sizeof(buffer) - length, format, args); |
| if (length >= sizeof(buffer)) { |
| length = sizeof(buffer); |
| buffer[sizeof(buffer) - 1] = '\n'; |
| } |
| QueueLogLine(new string(buffer, length)); |
| } |
| |
| void Logger::StartThread() { |
| LOGGER_ASSERT(!thread_running_); |
| thread_running_ = true; |
| LOGGER_ASSERT(0 == pthread_create(&thread_, NULL, &StartRoutine, this)); |
| } |
| |
| void Logger::StopThread() { |
| // Allow this to be called before the thread has started. |
| if (!thread_running_) { |
| return; |
| } |
| thread_running_ = false; |
| int retval = pthread_mutex_lock(&queued_lines_mutex_); |
| LOGGER_ASSERT(0 == retval); |
| bool need_cond_signal = queued_lines_.empty(); |
| queued_lines_.push_back(NULL); |
| retval = pthread_mutex_unlock(&queued_lines_mutex_); |
| LOGGER_ASSERT(0 == retval); |
| if (need_cond_signal) { |
| retval = pthread_cond_signal(&queued_lines_cond_); |
| LOGGER_ASSERT(0 == retval); |
| } |
| retval = pthread_join(thread_, NULL); |
| LOGGER_ASSERT(0 == retval); |
| } |
| |
| Logger::Logger() |
| : verbosity_(20), |
| log_fd_(-1), |
| thread_running_(false), |
| log_timestamps_(true) { |
| LOGGER_ASSERT(0 == pthread_mutex_init(&queued_lines_mutex_, NULL)); |
| LOGGER_ASSERT(0 == pthread_cond_init(&queued_lines_cond_, NULL)); |
| LOGGER_ASSERT(0 == pthread_cond_init(&full_queue_cond_, NULL)); |
| } |
| |
| Logger::~Logger() { |
| LOGGER_ASSERT(0 == pthread_mutex_destroy(&queued_lines_mutex_)); |
| LOGGER_ASSERT(0 == pthread_cond_destroy(&queued_lines_cond_)); |
| LOGGER_ASSERT(0 == pthread_cond_destroy(&full_queue_cond_)); |
| } |
| |
| void Logger::QueueLogLine(string *line) { |
| LOGGER_ASSERT(line != NULL); |
| LOGGER_ASSERT(0 == pthread_mutex_lock(&queued_lines_mutex_)); |
| if (thread_running_) { |
| if (queued_lines_.size() >= kMaxQueueSize) { |
| LOGGER_ASSERT(0 == pthread_cond_wait(&full_queue_cond_, |
| &queued_lines_mutex_)); |
| } |
| if (queued_lines_.empty()) { |
| LOGGER_ASSERT(0 == pthread_cond_signal(&queued_lines_cond_)); |
| } |
| queued_lines_.push_back(line); |
| } else { |
| WriteAndDeleteLogLine(line); |
| } |
| LOGGER_ASSERT(0 == pthread_mutex_unlock(&queued_lines_mutex_)); |
| } |
| |
| void Logger::WriteAndDeleteLogLine(string *line) { |
| LOGGER_ASSERT(line != NULL); |
| ssize_t bytes_written; |
| if (log_fd_ >= 0) { |
| bytes_written = write(log_fd_, line->data(), line->size()); |
| LOGGER_ASSERT(bytes_written == static_cast<ssize_t>(line->size())); |
| } |
| bytes_written = write(STDOUT_FILENO, line->data(), line->size()); |
| LOGGER_ASSERT(bytes_written == static_cast<ssize_t>(line->size())); |
| delete line; |
| } |
| |
| void *Logger::StartRoutine(void *ptr) { |
| Logger *self = static_cast<Logger*>(ptr); |
| self->ThreadMain(); |
| return NULL; |
| } |
| |
| void Logger::ThreadMain() { |
| vector<string*> local_queue; |
| LOGGER_ASSERT(0 == pthread_mutex_lock(&queued_lines_mutex_)); |
| |
| for (;;) { |
| if (queued_lines_.empty()) { |
| LOGGER_ASSERT(0 == pthread_cond_wait(&queued_lines_cond_, |
| &queued_lines_mutex_)); |
| continue; |
| } |
| |
| // We move the log lines into a local queue so we can release the lock |
| // while writing them to disk, preventing other threads from blocking on |
| // our writes. |
| local_queue.swap(queued_lines_); |
| if (local_queue.size() >= kMaxQueueSize) { |
| LOGGER_ASSERT(0 == pthread_cond_broadcast(&full_queue_cond_)); |
| } |
| |
| // Unlock while we process our local queue. |
| LOGGER_ASSERT(0 == pthread_mutex_unlock(&queued_lines_mutex_)); |
| for (vector<string*>::const_iterator it = local_queue.begin(); |
| it != local_queue.end(); ++it) { |
| if (*it == NULL) { |
| // NULL is guaranteed to be at the end. |
| return; |
| } |
| WriteAndDeleteLogLine(*it); |
| } |
| local_queue.clear(); |
| // We must hold the lock at the start of each iteration of this for loop. |
| LOGGER_ASSERT(0 == pthread_mutex_lock(&queued_lines_mutex_)); |
| } |
| } |