#define COMPLAINED_READ (1 << 0)\r
#define COMPLAINED_WRITE (1 << 1)\r
#define COMPLAINED_ROTATE (1 << 2)\r
+#define TIMESTAMP_FORMAT "%04u-%02u-%02u %02u:%02u:%02u.%03u: "\r
+#define TIMESTAMP_LEN 25\r
\r
static int dup_handle(HANDLE source_handle, HANDLE *dest_handle_ptr, TCHAR *source_description, TCHAR *dest_description, unsigned long flags) {\r
if (! dest_handle_ptr) return 1;\r
return dup_handle(source_handle, dest_handle_ptr, source_description, dest_description, DUPLICATE_SAME_ACCESS);\r
}\r
\r
-static HANDLE create_logging_thread(TCHAR *service_name, TCHAR *path, unsigned long sharing, unsigned long disposition, unsigned long flags, HANDLE *read_handle_ptr, HANDLE *pipe_handle_ptr, HANDLE *write_handle_ptr, unsigned long rotate_bytes_low, unsigned long rotate_bytes_high, unsigned long rotate_delay, unsigned long *tid_ptr, unsigned long *rotate_online, bool copy_and_truncate) {\r
+/*\r
+ read_handle: read from application\r
+ pipe_handle: stdout of application\r
+ write_handle: to file\r
+*/\r
+static HANDLE create_logging_thread(TCHAR *service_name, TCHAR *path, unsigned long sharing, unsigned long disposition, unsigned long flags, HANDLE *read_handle_ptr, HANDLE *pipe_handle_ptr, HANDLE *write_handle_ptr, unsigned long rotate_bytes_low, unsigned long rotate_bytes_high, unsigned long rotate_delay, unsigned long *tid_ptr, unsigned long *rotate_online, bool timestamp_log, bool copy_and_truncate) {\r
*tid_ptr = 0;\r
\r
/* Pipe between application's stdout/stderr and our logging handle. */\r
logger->write_handle = *write_handle_ptr;\r
logger->size = (__int64) size.QuadPart;\r
logger->tid_ptr = tid_ptr;\r
+ logger->timestamp_log = timestamp_log;\r
+ logger->line_length = 0;\r
logger->rotate_online = rotate_online;\r
logger->rotate_delay = rotate_delay;\r
logger->copy_and_truncate = copy_and_truncate;\r
}\r
}\r
\r
+void close_handle(HANDLE *handle, HANDLE *remember) {\r
+ if (remember) *remember = INVALID_HANDLE_VALUE;\r
+ if (! handle) return;\r
+ if (! *handle) return;\r
+ CloseHandle(*handle);\r
+ if (remember) *remember = *handle;\r
+ *handle = 0;\r
+}\r
+\r
+void close_handle(HANDLE *handle) {\r
+ close_handle(handle, NULL);\r
+}\r
+\r
/* Get path, share mode, creation disposition and flags for a stream. */\r
int get_createfile_parameters(HKEY key, TCHAR *prefix, TCHAR *path, unsigned long *sharing, unsigned long default_sharing, unsigned long *disposition, unsigned long default_disposition, unsigned long *flags, unsigned long default_flags, bool *copy_and_truncate) {\r
TCHAR value[NSSM_STDIO_LENGTH];\r
}\r
\r
HANDLE write_to_file(TCHAR *path, unsigned long sharing, SECURITY_ATTRIBUTES *attributes, unsigned long disposition, unsigned long flags) {\r
+ static LARGE_INTEGER offset = { 0 };\r
HANDLE ret = CreateFile(path, FILE_WRITE_DATA, sharing, attributes, disposition, flags, 0);\r
- if (ret!= INVALID_HANDLE_VALUE) {\r
- if (SetFilePointer(ret, 0, 0, FILE_END) != INVALID_SET_FILE_POINTER) SetEndOfFile(ret);\r
+ if (ret != INVALID_HANDLE_VALUE) {\r
+ if (SetFilePointerEx(ret, offset, 0, FILE_END)) SetEndOfFile(ret);\r
return ret;\r
}\r
\r
if (service->rotate_files) rotate_file(service->name, service->stdout_path, service->rotate_seconds, service->rotate_bytes_low, service->rotate_bytes_high, service->rotate_delay, service->stdout_copy_and_truncate);\r
HANDLE stdout_handle = write_to_file(service->stdout_path, service->stdout_sharing, 0, service->stdout_disposition, service->stdout_flags);\r
if (stdout_handle == INVALID_HANDLE_VALUE) return 4;\r
+ service->stdout_si = 0;\r
\r
- if (service->rotate_files && service->rotate_stdout_online) {\r
+ if (service->use_stdout_pipe) {\r
service->stdout_pipe = si->hStdOutput = 0;\r
- service->stdout_thread = create_logging_thread(service->name, service->stdout_path, service->stdout_sharing, service->stdout_disposition, service->stdout_flags, &service->stdout_pipe, &si->hStdOutput, &stdout_handle, service->rotate_bytes_low, service->rotate_bytes_high, service->rotate_delay, &service->stdout_tid, &service->rotate_stdout_online, service->stdout_copy_and_truncate);\r
+ service->stdout_thread = create_logging_thread(service->name, service->stdout_path, service->stdout_sharing, service->stdout_disposition, service->stdout_flags, &service->stdout_pipe, &service->stdout_si, &stdout_handle, service->rotate_bytes_low, service->rotate_bytes_high, service->rotate_delay, &service->stdout_tid, &service->rotate_stdout_online, service->timestamp_log, service->stdout_copy_and_truncate);\r
if (! service->stdout_thread) {\r
CloseHandle(service->stdout_pipe);\r
- CloseHandle(si->hStdOutput);\r
+ CloseHandle(service->stdout_si);\r
}\r
}\r
else service->stdout_thread = 0;\r
\r
if (! service->stdout_thread) {\r
- if (dup_handle(stdout_handle, &si->hStdOutput, NSSM_REG_STDOUT, _T("stdout"), DUPLICATE_CLOSE_SOURCE | DUPLICATE_SAME_ACCESS)) return 4;\r
+ if (dup_handle(stdout_handle, &service->stdout_si, NSSM_REG_STDOUT, _T("stdout"), DUPLICATE_CLOSE_SOURCE | DUPLICATE_SAME_ACCESS)) return 4;\r
service->rotate_stdout_online = NSSM_ROTATE_OFFLINE;\r
}\r
+\r
+ if (dup_handle(service->stdout_si, &si->hStdOutput, _T("stdout_si"), _T("stdout"))) close_handle(&service->stdout_thread);\r
}\r
\r
/* stderr */\r
service->rotate_stderr_online = NSSM_ROTATE_OFFLINE;\r
\r
/* Two handles to the same file will create a race. */\r
- if (dup_handle(si->hStdOutput, &si->hStdError, _T("stdout"), _T("stderr"))) return 6;\r
+ /* XXX: Here we assume that either both or neither handle must be a pipe. */\r
+ if (dup_handle(service->stdout_si, &service->stderr_si, _T("stdout"), _T("stderr"))) return 6;\r
}\r
else {\r
if (service->rotate_files) rotate_file(service->name, service->stderr_path, service->rotate_seconds, service->rotate_bytes_low, service->rotate_bytes_high, service->rotate_delay, service->stderr_copy_and_truncate);\r
HANDLE stderr_handle = write_to_file(service->stderr_path, service->stderr_sharing, 0, service->stderr_disposition, service->stderr_flags);\r
if (stderr_handle == INVALID_HANDLE_VALUE) return 7;\r
+ service->stderr_si = 0;\r
\r
- if (service->rotate_files && service->rotate_stderr_online) {\r
+ if (service->use_stderr_pipe) {\r
service->stderr_pipe = si->hStdError = 0;\r
- service->stderr_thread = create_logging_thread(service->name, service->stderr_path, service->stderr_sharing, service->stderr_disposition, service->stderr_flags, &service->stderr_pipe, &si->hStdError, &stderr_handle, service->rotate_bytes_low, service->rotate_bytes_high, service->rotate_delay, &service->stderr_tid, &service->rotate_stderr_online, service->stderr_copy_and_truncate);\r
+ service->stderr_thread = create_logging_thread(service->name, service->stderr_path, service->stderr_sharing, service->stderr_disposition, service->stderr_flags, &service->stderr_pipe, &service->stderr_si, &stderr_handle, service->rotate_bytes_low, service->rotate_bytes_high, service->rotate_delay, &service->stderr_tid, &service->rotate_stderr_online, service->timestamp_log, service->stderr_copy_and_truncate);\r
if (! service->stderr_thread) {\r
CloseHandle(service->stderr_pipe);\r
- CloseHandle(si->hStdError);\r
+ CloseHandle(service->stderr_si);\r
}\r
}\r
else service->stderr_thread = 0;\r
\r
if (! service->stderr_thread) {\r
- if (dup_handle(stderr_handle, &si->hStdError, NSSM_REG_STDERR, _T("stderr"), DUPLICATE_CLOSE_SOURCE | DUPLICATE_SAME_ACCESS)) return 7;\r
+ if (dup_handle(stderr_handle, &service->stderr_si, NSSM_REG_STDERR, _T("stderr"), DUPLICATE_CLOSE_SOURCE | DUPLICATE_SAME_ACCESS)) return 7;\r
service->rotate_stderr_online = NSSM_ROTATE_OFFLINE;\r
}\r
}\r
+\r
+ if (dup_handle(service->stderr_si, &si->hStdError, _T("stderr_si"), _T("stderr"))) close_handle(&service->stderr_thread);\r
}\r
\r
/*\r
}\r
if (! si->hStdError) {\r
if (dup_handle(GetStdHandle(STD_ERROR_HANDLE), &si->hStdError, _T("STD_ERROR_HANDLE"), _T("stderr"))) return 10;\r
+ }\r
+\r
+ return 0;\r
+}\r
+\r
+/* Reuse output handles for a hook. */\r
+int use_output_handles(nssm_service_t *service, STARTUPINFO *si) {\r
+ si->dwFlags &= ~STARTF_USESTDHANDLES;\r
+\r
+ if (service->stdout_si) {\r
+ if (dup_handle(service->stdout_si, &si->hStdOutput, _T("stdout_pipe"), _T("hStdOutput"))) return 1;\r
+ si->dwFlags |= STARTF_USESTDHANDLES;\r
+ }\r
+\r
+ if (service->stderr_si) {\r
+ if (dup_handle(service->stderr_si, &si->hStdError, _T("stderr_pipe"), _T("hStdError"))) {\r
+ if (si->hStdOutput) {\r
+ si->dwFlags &= ~STARTF_USESTDHANDLES;\r
+ CloseHandle(si->hStdOutput);\r
+ }\r
+ return 2;\r
}\r
+ si->dwFlags |= STARTF_USESTDHANDLES;\r
}\r
\r
return 0;\r
if (si->hStdError) CloseHandle(si->hStdError);\r
}\r
\r
+void cleanup_loggers(nssm_service_t *service) {\r
+ unsigned long interval = NSSM_CLEANUP_LOGGERS_DEADLINE;\r
+ HANDLE thread_handle = INVALID_HANDLE_VALUE;\r
+\r
+ close_handle(&service->stdout_thread, &thread_handle);\r
+ /* Close write end of the data pipe so logging thread can finalise read. */\r
+ close_handle(&service->stdout_si);\r
+ /* Await logging thread then close read end. */\r
+ if (thread_handle != INVALID_HANDLE_VALUE) WaitForSingleObject(thread_handle, interval);\r
+ close_handle(&service->stdout_pipe);\r
+\r
+ thread_handle = INVALID_HANDLE_VALUE;\r
+ close_handle(&service->stderr_thread, &thread_handle);\r
+ close_handle(&service->stderr_si);\r
+ if (thread_handle != INVALID_HANDLE_VALUE) WaitForSingleObject(thread_handle, interval);\r
+ close_handle(&service->stderr_pipe);\r
+}\r
+\r
/*\r
Try multiple times to read from a file.\r
Returns: 0 on success.\r
return ret;\r
}\r
\r
+/* Note that the timestamp is created in UTF-8. */\r
+static inline int write_timestamp(logger_t *logger, unsigned long charsize, unsigned long *out, int *complained) {\r
+ char timestamp[TIMESTAMP_LEN + 1];\r
+\r
+ SYSTEMTIME now;\r
+ GetSystemTime(&now);\r
+ _snprintf_s(timestamp, _countof(timestamp), _TRUNCATE, TIMESTAMP_FORMAT, now.wYear, now.wMonth, now.wDay, now.wHour, now.wMinute, now.wSecond, now.wMilliseconds);\r
+\r
+ if (charsize == sizeof(char)) return try_write(logger, (void *) timestamp, TIMESTAMP_LEN, out, complained);\r
+\r
+ wchar_t *utf16;\r
+ unsigned long utf16len;\r
+ if (to_utf16(timestamp, &utf16, &utf16len)) return -1;\r
+ int ret = try_write(logger, (void *) *utf16, utf16len * sizeof(wchar_t), out, complained);\r
+ HeapFree(GetProcessHeap(), 0, utf16);\r
+ return ret;\r
+}\r
+\r
+static int write_with_timestamp(logger_t *logger, void *address, unsigned long bufsize, unsigned long *out, int *complained, unsigned long charsize) {\r
+ if (logger->timestamp_log) {\r
+ unsigned long log_out;\r
+ int log_complained;\r
+ unsigned long timestamp_out = 0;\r
+ int timestamp_complained;\r
+ if (! logger->line_length) {\r
+ write_timestamp(logger, charsize, ×tamp_out, ×tamp_complained);\r
+ logger->line_length += (__int64) timestamp_out;\r
+ *out += timestamp_out;\r
+ *complained |= timestamp_complained;\r
+ }\r
+\r
+ unsigned long i;\r
+ void *line = address;\r
+ unsigned long offset = 0;\r
+ int ret;\r
+ for (i = 0; i < bufsize; i++) {\r
+ if (((char *) address)[i] == '\n') {\r
+ ret = try_write(logger, line, i - offset + 1, &log_out, &log_complained);\r
+ line = (void *) ((char *) line + i - offset + 1);\r
+ logger->line_length = 0LL;\r
+ *out += log_out;\r
+ *complained |= log_complained;\r
+ offset = i + 1;\r
+ if (offset < bufsize) {\r
+ write_timestamp(logger, charsize, ×tamp_out, ×tamp_complained);\r
+ logger->line_length += (__int64) timestamp_out;\r
+ *out += timestamp_out;\r
+ *complained |= timestamp_complained;\r
+ }\r
+ }\r
+ }\r
+\r
+ if (offset < bufsize) {\r
+ ret = try_write(logger, line, bufsize - offset, &log_out, &log_complained);\r
+ *out += log_out;\r
+ *complained |= log_complained;\r
+ }\r
+\r
+ return ret;\r
+ }\r
+ else return try_write(logger, address, bufsize, out, complained);\r
+}\r
+\r
/* Wrapper to be called in a new thread for logging. */\r
unsigned long WINAPI log_and_rotate(void *arg) {\r
logger_t *logger = (logger_t *) arg;\r
address = &buffer;\r
ret = try_read(logger, address, sizeof(buffer), &in, &complained);\r
if (ret < 0) {\r
- CloseHandle(logger->read_handle);\r
- CloseHandle(logger->write_handle);\r
+ close_handle(&logger->read_handle);\r
+ close_handle(&logger->write_handle);\r
HeapFree(GetProcessHeap(), 0, logger);\r
return 2;\r
}\r
/* Write up to the newline. */\r
ret = try_write(logger, address, i, &out, &complained);\r
if (ret < 0) {\r
- CloseHandle(logger->read_handle);\r
- CloseHandle(logger->write_handle);\r
+ close_handle(&logger->read_handle);\r
+ close_handle(&logger->write_handle);\r
HeapFree(GetProcessHeap(), 0, logger);\r
return 3;\r
}\r
risk losing everything.\r
*/\r
if (logger->copy_and_truncate) FlushFileBuffers(logger->write_handle);\r
- CloseHandle(logger->write_handle);\r
+ close_handle(&logger->write_handle);\r
bool ok = true;\r
TCHAR *function;\r
if (logger->copy_and_truncate) {\r
error = GetLastError();\r
log_event(EVENTLOG_ERROR_TYPE, NSSM_EVENT_CREATEFILE_FAILED, logger->path, error_string(error), 0);\r
/* Oh dear. Now we can't log anything further. */\r
- CloseHandle(logger->read_handle);\r
- CloseHandle(logger->write_handle);\r
+ close_handle(&logger->read_handle);\r
+ close_handle(&logger->write_handle);\r
HeapFree(GetProcessHeap(), 0, logger);\r
return 4;\r
}\r
}\r
}\r
\r
+ if (! size || logger->timestamp_log) if (! charsize) charsize = guess_charsize(address, in);\r
if (! size) {\r
/* Write a BOM to the new file. */\r
- if (! charsize) charsize = guess_charsize(address, in);\r
if (charsize == sizeof(wchar_t)) write_bom(logger, &out);\r
size += (__int64) out;\r
}\r
/* Write the data, if any. */\r
if (! in) continue;\r
\r
- ret = try_write(logger, address, in, &out, &complained);\r
+ ret = write_with_timestamp(logger, address, in, &out, &complained, charsize);\r
size += (__int64) out;\r
if (ret < 0) {\r
- CloseHandle(logger->read_handle);\r
- CloseHandle(logger->write_handle);\r
+ close_handle(&logger->read_handle);\r
+ close_handle(&logger->write_handle);\r
HeapFree(GetProcessHeap(), 0, logger);\r
return 3;\r
}\r
}\r
\r
- CloseHandle(logger->read_handle);\r
- CloseHandle(logger->write_handle);\r
+ close_handle(&logger->read_handle);\r
+ close_handle(&logger->write_handle);\r
HeapFree(GetProcessHeap(), 0, logger);\r
return 0;\r
}\r