X-Git-Url: http://git.megacz.com/?a=blobdiff_plain;f=rts%2Feventlog%2FEventLog.c;h=fec34b9647ece0f4b7122a84a6d039182bcf1c24;hb=a201afd3d2c4bef07e4e7682b129f60dbdde0e8a;hp=178dfb3fd7653bc0d3d52787ccf4e61fdf477870;hpb=d71615757d0462b1721ee3664c5fa9f79325d666;p=ghc-hetmet.git diff --git a/rts/eventlog/EventLog.c b/rts/eventlog/EventLog.c index 178dfb3..fec34b9 100644 --- a/rts/eventlog/EventLog.c +++ b/rts/eventlog/EventLog.c @@ -13,13 +13,21 @@ #include "Trace.h" #include "Capability.h" -#include "Trace.h" #include "RtsUtils.h" #include "Stats.h" #include "EventLog.h" -#include +#include #include +#ifdef HAVE_SYS_TYPES_H +#include +#endif +#ifdef HAVE_UNISTD_H +#include +#endif + +// PID of the process that writes to event_log_filename (#4512) +static pid_t event_log_pid = -1; static char *event_log_filename = NULL; @@ -36,6 +44,7 @@ typedef struct _EventsBuf { StgInt8 *pos; StgInt8 *marker; StgWord64 size; + EventCapNo capno; // which capability this buffer belongs to, or -1 } EventsBuf; EventsBuf *capEventBuf; // one EventsBuf for each Capability @@ -60,9 +69,12 @@ char *EventDesc[] = { [EVENT_REQUEST_SEQ_GC] = "Request sequential GC", [EVENT_REQUEST_PAR_GC] = "Request parallel GC", [EVENT_CREATE_SPARK_THREAD] = "Create spark thread", - [EVENT_LOG_CAP_MSG] = "Log Capability message", [EVENT_LOG_MSG] = "Log message", + [EVENT_USER_MSG] = "User message", [EVENT_STARTUP] = "Startup", + [EVENT_GC_IDLE] = "GC idle", + [EVENT_GC_WORK] = "GC working", + [EVENT_GC_DONE] = "GC done", [EVENT_BLOCK_MARKER] = "Block marker" }; @@ -76,12 +88,14 @@ typedef struct _EventType { EventType eventTypes[NUM_EVENT_TAGS]; -static void initEventsBuf(EventsBuf* eb, StgWord64 size); +static void initEventsBuf(EventsBuf* eb, StgWord64 size, EventCapNo capno); static void resetEventsBuf(EventsBuf* eb); static void printAndClearEventBuf (EventsBuf *eventsBuf); static void postEventType(EventsBuf *eb, EventType *et); +static void postLogMsg(EventsBuf *eb, EventTypeNum type, char *msg, va_list ap); + static void postBlockMarker(EventsBuf *eb); static void closeBlockMarker(EventsBuf *ebuf); @@ -160,18 +174,29 @@ initEventLogging(void) StgWord8 t, c; nat n_caps; - event_log_filename = stgMallocBytes(strlen(prog_name) + 10, + event_log_filename = stgMallocBytes(strlen(prog_name) + + 10 /* .%d */ + + 10 /* .eventlog */, "initEventLogging"); if (sizeof(EventDesc) / sizeof(char*) != NUM_EVENT_TAGS) { barf("EventDesc array has the wrong number of elements"); } - - sprintf(event_log_filename, "%s.eventlog", prog_name); - + + if (event_log_pid == -1) { // #4512 + // Single process + sprintf(event_log_filename, "%s.eventlog", prog_name); + event_log_pid = getpid(); + } else { + // Forked process, eventlog already started by the parent + // before fork + event_log_pid = getpid(); + sprintf(event_log_filename, "%s.%d.eventlog", prog_name, event_log_pid); + } + /* Open event log file for writing. */ if ((event_log_file = fopen(event_log_filename, "wb")) == NULL) { - sysErrorBelch("initEventLoggin: can't open %s", event_log_filename); + sysErrorBelch("initEventLogging: can't open %s", event_log_filename); stg_exit(EXIT_FAILURE); } @@ -195,9 +220,9 @@ initEventLogging(void) for (c = 0; c < n_caps; ++c) { // Init buffer for events. - initEventsBuf(&capEventBuf[c], EVENT_LOG_SIZE); + initEventsBuf(&capEventBuf[c], EVENT_LOG_SIZE, c); } - initEventsBuf(&eventBuf, EVENT_LOG_SIZE); + initEventsBuf(&eventBuf, EVENT_LOG_SIZE, (EventCapNo)(-1)); // Write in buffer: the header begin marker. postInt32(&eventBuf, EVENT_HEADER_BEGIN); @@ -215,19 +240,23 @@ initEventLogging(void) case EVENT_THREAD_RUNNABLE: // (cap, thread) case EVENT_RUN_SPARK: // (cap, thread) case EVENT_CREATE_SPARK_THREAD: // (cap, spark_thread) - eventTypes[t].size = sizeof(EventCapNo) + sizeof(EventThreadID); + eventTypes[t].size = sizeof(EventThreadID); break; case EVENT_MIGRATE_THREAD: // (cap, thread, new_cap) case EVENT_STEAL_SPARK: // (cap, thread, victim_cap) case EVENT_THREAD_WAKEUP: // (cap, thread, other_cap) eventTypes[t].size = - sizeof(EventCapNo) + sizeof(EventThreadID) + sizeof(EventCapNo); + sizeof(EventThreadID) + sizeof(EventCapNo); break; case EVENT_STOP_THREAD: // (cap, thread, status) eventTypes[t].size = - sizeof(EventCapNo) + sizeof(EventThreadID) + sizeof(StgWord16); + sizeof(EventThreadID) + sizeof(StgWord16); + break; + + case EVENT_STARTUP: // (cap count) + eventTypes[t].size = sizeof(EventCapNo); break; case EVENT_SHUTDOWN: // (cap) @@ -235,20 +264,20 @@ initEventLogging(void) case EVENT_REQUEST_PAR_GC: // (cap) case EVENT_GC_START: // (cap) case EVENT_GC_END: // (cap) - eventTypes[t].size = sizeof(EventCapNo); + case EVENT_GC_IDLE: + case EVENT_GC_WORK: + case EVENT_GC_DONE: + eventTypes[t].size = 0; break; case EVENT_LOG_MSG: // (msg) - case EVENT_LOG_CAP_MSG: // (cap,msg) + case EVENT_USER_MSG: // (msg) eventTypes[t].size = 0xffff; break; - case EVENT_STARTUP: - eventTypes[t].size = sizeof(EventCapNo); - break; - case EVENT_BLOCK_MARKER: - eventTypes[t].size = sizeof(StgWord32) + sizeof(EventTimestamp); + eventTypes[t].size = sizeof(StgWord32) + sizeof(EventTimestamp) + + sizeof(EventCapNo); break; default: @@ -268,6 +297,10 @@ initEventLogging(void) // Prepare event buffer for events (data). postInt32(&eventBuf, EVENT_DATA_BEGIN); + // Post a STARTUP event with the number of capabilities + postEventHeader(&eventBuf, EVENT_STARTUP); + postCapNo(&eventBuf, n_caps); + // Flush capEventBuf with header. /* * Flush header and data begin marker to the file, thus preparing the @@ -278,6 +311,10 @@ initEventLogging(void) for (c = 0; c < n_caps; ++c) { postBlockMarker(&capEventBuf[c]); } + +#ifdef THREADED_RTS + initMutex(&eventBufMutex); +#endif } void @@ -290,6 +327,7 @@ endEventLogging(void) printAndClearEventBuf(&capEventBuf[c]); } printAndClearEventBuf(&eventBuf); + resetEventsBuf(&eventBuf); // we don't want the block marker // Mark end of events (data). postEventTypeNum(&eventBuf, EVENT_DATA_END); @@ -320,6 +358,22 @@ freeEventLogging(void) } } +void +flushEventLog(void) +{ + if (event_log_file != NULL) { + fflush(event_log_file); + } +} + +void +abortEventLogging(void) +{ + freeEventLogging(); + if (event_log_file != NULL) { + fclose(event_log_file); + } +} /* * Post an event message to the capability's eventlog buffer. * If the buffer is full, prints out the buffer and clears it. @@ -340,7 +394,6 @@ postSchedEvent (Capability *cap, } postEventHeader(eb, tag); - postCapNo(eb, cap->no); switch (tag) { case EVENT_CREATE_THREAD: // (cap, thread) @@ -388,38 +441,25 @@ postSchedEvent (Capability *cap, } } -#define BUF 512 - -void postMsg(char *msg, va_list ap) +void +postEvent (Capability *cap, EventTypeNum tag) { EventsBuf *eb; - char buf[BUF]; - nat size; - size = vsnprintf(buf,BUF,msg,ap); - if (size > BUF) { - buf[BUF-1] = '\0'; - size = BUF; - } - - ACQUIRE_LOCK(&eventBufMutex); - eb = &eventBuf; + eb = &capEventBuf[cap->no]; - if (!hasRoomForVariableEvent(eb, size)) { + if (!hasRoomForEvent(eb, tag)) { // Flush event buffer to make room for new event. printAndClearEventBuf(eb); } - postEventHeader(eb, EVENT_LOG_MSG); - postPayloadSize(eb, size); - postBuf(eb,(StgWord8*)buf,size); - - RELEASE_LOCK(&eventBufMutex); + postEventHeader(eb, tag); } -void postCapMsg(Capability *cap, char *msg, va_list ap) +#define BUF 512 + +void postLogMsg(EventsBuf *eb, EventTypeNum type, char *msg, va_list ap) { - EventsBuf *eb; char buf[BUF]; nat size; @@ -429,26 +469,47 @@ void postCapMsg(Capability *cap, char *msg, va_list ap) size = BUF; } - eb = &capEventBuf[cap->no]; - if (!hasRoomForVariableEvent(eb, size)) { // Flush event buffer to make room for new event. printAndClearEventBuf(eb); } - postEventHeader(eb, EVENT_LOG_MSG); - postPayloadSize(eb, size + sizeof(EventCapNo)); - postCapNo(eb, cap->no); + postEventHeader(eb, type); + postPayloadSize(eb, size); postBuf(eb,(StgWord8*)buf,size); } +void postMsg(char *msg, va_list ap) +{ + ACQUIRE_LOCK(&eventBufMutex); + postLogMsg(&eventBuf, EVENT_LOG_MSG, msg, ap); + RELEASE_LOCK(&eventBufMutex); +} + +void postCapMsg(Capability *cap, char *msg, va_list ap) +{ + postLogMsg(&capEventBuf[cap->no], EVENT_LOG_MSG, msg, ap); +} + +void postUserMsg(Capability *cap, char *msg, va_list ap) +{ + postLogMsg(&capEventBuf[cap->no], EVENT_USER_MSG, msg, ap); +} + void closeBlockMarker (EventsBuf *ebuf) { + StgInt8* save_pos; + if (ebuf->marker) { // (type:16, time:64, size:32, end_time:64) - *(StgWord32 *)(ebuf->marker + 10) = ebuf->pos - ebuf->marker; - *(StgWord64 *)(ebuf->marker + 14) = time_ns(); + + save_pos = ebuf->pos; + ebuf->pos = ebuf->marker + sizeof(EventTypeNum) + + sizeof(EventTimestamp); + postWord32(ebuf, save_pos - ebuf->marker); + postTimestamp(ebuf); + ebuf->pos = save_pos; ebuf->marker = NULL; } } @@ -466,6 +527,7 @@ void postBlockMarker (EventsBuf *eb) postEventHeader(eb, EVENT_BLOCK_MARKER); postWord32(eb,0); // these get filled in later by closeBlockMarker(); postWord64(eb,0); + postCapNo(eb, eb->capno); } void printAndClearEventBuf (EventsBuf *ebuf) @@ -493,16 +555,18 @@ void printAndClearEventBuf (EventsBuf *ebuf) } } -void initEventsBuf(EventsBuf* eb, StgWord64 size) +void initEventsBuf(EventsBuf* eb, StgWord64 size, EventCapNo capno) { eb->begin = eb->pos = stgMallocBytes(size, "initEventsBuf"); eb->size = size; eb->marker = NULL; + eb->capno = capno; } void resetEventsBuf(EventsBuf* eb) { eb->pos = eb->begin; + eb->marker = NULL; } StgBool hasRoomForEvent(EventsBuf *eb, EventTypeNum eNum)