Logo AND Algorithmique Numérique Distribuée

Public GIT Repository
Do append the right buffer to the message when we switched to dynamic logs; be more...
[simgrid.git] / src / xbt / xbt_log_layout_format.c
index 0b33f77..8aef6f5 100644 (file)
 
 #include "portable.h" /* execinfo when available */
 #include "xbt/sysdep.h"
-#include "xbt/log.h"
-#include "gras/virtu.h"
+#include "xbt/strbuff.h"
+#include "xbt/log_private.h"
+#include "gras/virtu.h" /* gras_os_myname (KILLME) */
+#include "xbt/synchro.h" /* xbt_thread_self_name */
 #include <stdio.h>
 
 extern const char *xbt_log_priority_names[7];
 
+static double begin_of_time = -1;
 
-static char *xbt_log_layout_format_doit(xbt_log_layout_t l,
-                                       xbt_log_event_t ev, 
-                                       const char *msg_fmt) {
-  static char res[2048];
-  static double begin_of_time = -1;
+#define append1(fmt,fmt2,elm)                                         \
+   do {                                                               \
+       if (precision == -1) {                                        \
+          xbt_strbuff_append(buff, tmp=bprintf(fmt,elm));            \
+          free(tmp);                                                 \
+        } else {                                                      \
+          xbt_strbuff_append(buff, tmp=bprintf(fmt2,precision,elm)); \
+          free(tmp);                                                 \
+          precision = -1;                                            \
+       }                                                             \
+   } while (0)
+#define append2(fmt,elm,elm2)                                         \
+   do {                                                               \
+       xbt_strbuff_append(buff, tmp=bprintf(fmt,elm,elm2));          \
+       free(tmp);                                                    \
+       precision = -1;                                               \
+   } while (0)
+
+static void xbt_log_layout_format_dynamic(xbt_log_layout_t l,
+                                         xbt_log_event_t ev,
+                                         const char*fmt,
+                                         xbt_log_appender_t app) {
+  xbt_strbuff_t buff = xbt_strbuff_new();   
+  int precision=-1;
+  char *q = l->data;
+  char *tmp;
+  char *tmp2;
+
+  while (*q != '\0') {
+    if (*q == '%') {
+      q++;
+       handle_modifier:
+      switch (*q) {
+      case '\0':
+       fprintf(stderr,"Layout format (%s) ending with %%\n",(char*)l->data);
+       abort();
+      case '%':
+       xbt_strbuff_append(buff,"%");
+       break;
+      case 'n': /* platform-dependant line separator (LOG4J compliant) */
+       xbt_strbuff_append(buff,"\n");
+       break;
+      case 'e': /* plain space (SimGrid extension) */
+       xbt_strbuff_append(buff," ");
+       break;
+        
+      case '.': /* precision specifyier */
+       q++;
+       q += sscanf(q,"%d",&precision);
+       goto handle_modifier;
+
+      case 'c': /* category name; LOG4J compliant
+                  should accept a precision postfix to show the hierarchy */
+       append1("%s","%.*s",ev->cat->name);
+       break;
+      case 'p': /* priority name; LOG4J compliant */   
+       append1("%s","%.*s",xbt_log_priority_names[ev->priority]);
+       break;
+
+      case 'h': /* host name; SimGrid extension */
+       append1("%s","%.*s",gras_os_myname());
+       break;
+      case 't': /* thread name; LOG4J compliant */
+       append1("%s","%.*s",xbt_thread_self_name());
+       break;
+      case 'P': /* process name; SimGrid extension */
+       append1("%s","%.*s",xbt_procname());
+       break;
+      case 'i': /* process PID name; SimGrid extension */
+       append1("%d","%.*d",(*xbt_getpid)());
+       break;
+   
+      case 'F': /* file name; LOG4J compliant */
+       append1("%s","%.*s",ev->fileName);
+       break;
+      case 'l': /* location; LOG4J compliant */
+       append2("%s:%d",ev->fileName,ev->lineNum);
+       precision = -1; /* Ignored */
+       break;
+      case 'L': /* line number; LOG4J compliant */
+       append1("%d","%.*d",ev->lineNum);
+       break;
+      case 'M': /* method (ie, function) name; LOG4J compliant */
+       append1("%s","%.*s",ev->functionName);
+       break;
+      case 'b': /* backtrace; called %throwable in LOG4J */
+      case 'B': /* short backtrace; called %throwable{short} in LOG4J */
+#if defined(HAVE_EXECINFO_H) && defined(HAVE_POPEN) && defined(ADDR2LINE)
+       {
+         xbt_ex_t e;
+         int i;
+         
+         e.used     = backtrace((void**)e.bt,XBT_BACKTRACE_SIZE);
+         e.bt_strings = NULL;
+         e.msg=NULL;
+         e.remote=0;
+         xbt_backtrace_current(&e);
+         if (*q=='B') {
+            append1("%s","%.*s",e.bt_strings[2]+8);
+         } else {
+           for (i=2; i<e.used; i++)
+             append1("%s\n","%.*s\n",e.bt_strings[i]+8);
+         }
+          
+         xbt_ex_free(e);
+       }
+#else
+       append1("%s","%.*s","(no backtrace on this arch)");
+#endif
+       break;
+
+      case 'd': /* date; LOG4J compliant */
+       append1("%f","%.*f", gras_os_time());
+       break;
+      case 'r': /* application age; LOG4J compliant */
+       append1("%f","%.*f", gras_os_time()-begin_of_time);
+       break;
+       
+      case 'm': /* user-provided message; LOG4J compliant */
+       vasprintf(&tmp2, fmt, ev->ap_copy);
+       append1("%s","%.*s",tmp2);
+       free(tmp2);
+       break;
+
+      default:
+       fprintf(stderr,"Unknown %%%c sequence in layout format (%s)\n",
+               *q,(char*)l->data);
+       abort();
+      }
+      q++;
+    } else {
+      char tmp2[2];
+      tmp2[0] = *(q++);
+      tmp2[1] = '\0';
+      xbt_strbuff_append(buff,tmp2);
+    }
+  }
+  app->do_append(app,buff->data);
+  xbt_strbuff_free(buff);
+}
+
+#define check_overflow \
+  if (p-ev->buffer > XBT_LOG_BUFF_SIZE) { /* buffer overflow */ \
+     xbt_log_layout_format_dynamic(l,ev,msg_fmt,app); \
+     return;\
+  } 
+static void xbt_log_layout_format_doit(xbt_log_layout_t l,
+                                      xbt_log_event_t ev, 
+                                      const char *msg_fmt,
+                                      xbt_log_appender_t app) {
   char *p,*q;
   int precision=-1;
 
   if (begin_of_time<0) 
     begin_of_time=gras_os_time();
 
-  p = res;
+  p = ev->buffer;
   q = l->data;
 
   while (*q != '\0') {
@@ -42,10 +190,12 @@ static char *xbt_log_layout_format_doit(xbt_log_layout_t l,
        *p++ = '%';
        break;
       case 'n': /* platform-dependant line separator (LOG4J compliant) */
-       p += sprintf(p,"\n");
+       p += snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer),"\n");
+       check_overflow;
        break;
       case 'e': /* plain space (SimGrid extension) */
-       p += sprintf(p," ");
+       p += snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer)," ");
+       check_overflow;
        break;
         
       case '.': /* precision specifyier */
@@ -55,76 +205,104 @@ static char *xbt_log_layout_format_doit(xbt_log_layout_t l,
 
       case 'c': /* category name; LOG4J compliant
                   should accept a precision postfix to show the hierarchy */
-       if (precision == -1)
-          p += sprintf(p,"%s",ev->cat->name);
-        else {       
-          p += sprintf(p,"%.*s",precision,ev->cat->name);
+       if (precision == -1) {
+          p += snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer),"%s",ev->cat->name);
+          check_overflow;         
+        } else {             
+          p += sprintf(p,"%.*s",(int)MIN(XBT_LOG_BUFF_SIZE-(p-ev->buffer),precision),ev->cat->name);
+          check_overflow;         
           precision = -1;
        }        
        break;
       case 'p': /* priority name; LOG4J compliant */   
-       if (precision == -1)
-          p += sprintf(p, "%s", xbt_log_priority_names[ev->priority] );
-        else {       
-          p += sprintf(p, "%.*s", precision, xbt_log_priority_names[ev->priority] );
+       if (precision == -1) {
+          p += snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer), "%s", xbt_log_priority_names[ev->priority] );
+          check_overflow;         
+        } else {             
+          p += sprintf(p, "%.*s", (int)MIN(XBT_LOG_BUFF_SIZE-(p-ev->buffer),precision), xbt_log_priority_names[ev->priority] );
+          check_overflow;         
           precision = -1;
        }        
        break;
 
       case 'h': /* host name; SimGrid extension */
-       if (precision == -1)
-          p += sprintf(p, "%s", gras_os_myname());
-        else {       
-          p += sprintf(p, "%.*s", precision, gras_os_myname());
+       if (precision == -1) {
+          p += snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer), "%s", gras_os_myname());
+          check_overflow;         
+        } else {             
+          p += sprintf(p, "%.*s", (int)MIN(XBT_LOG_BUFF_SIZE-(p-ev->buffer),precision), gras_os_myname());
+          check_overflow;         
           precision = -1;
        }        
        break;
-      case 't': /* process name; LOG4J compliant (thread name) */
-       if (precision == -1)
-          p += sprintf(p, "%s", xbt_procname());
-        else {       
-          p += sprintf(p, "%.*s", precision,xbt_procname());
+      case 't': /* thread name; LOG4J compliant */
+       if (precision == -1) {
+          p += snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer), "%s", xbt_thread_self_name());
+          check_overflow;         
+        } else {             
+          p += sprintf(p, "%.*s", (int)MIN(XBT_LOG_BUFF_SIZE-(p-ev->buffer),precision), xbt_thread_self_name());
+          check_overflow;         
+          precision = -1;
+       }        
+       break;
+      case 'P': /* process name; SimGrid extension */
+       if (precision == -1) {
+          p += snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer), "%s", xbt_procname());
+          check_overflow;         
+        } else {             
+          p += sprintf(p, "%.*s", (int)MIN(XBT_LOG_BUFF_SIZE-(p-ev->buffer),precision),xbt_procname());
+          check_overflow;         
           precision = -1;
        }        
        break;
       case 'i': /* process PID name; SimGrid extension */
-       if (precision == -1)
-          p += sprintf(p, "%d", (*xbt_getpid)());
-        else {       
-          p += sprintf(p, "%.*d", precision, (*xbt_getpid)());
+       if (precision == -1) {
+          p += snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer), "%d", (*xbt_getpid)());
+          check_overflow;         
+        } else {             
+          p += sprintf(p, "%.*d", (int)MIN(XBT_LOG_BUFF_SIZE-(p-ev->buffer),precision), (*xbt_getpid)());
+          check_overflow;         
           precision = -1;
        }        
        break;
 
       case 'F': /* file name; LOG4J compliant */
-       if (precision == -1)
-          p += sprintf(p,"%s",ev->fileName);
-        else {       
-          p += sprintf(p,"%.*s",precision, ev->fileName);
+       if (precision == -1) {
+          p += snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer),"%s",ev->fileName);
+          check_overflow;         
+        } else {             
+          p += sprintf(p,"%.*s",(int)MIN(XBT_LOG_BUFF_SIZE-(p-ev->buffer),precision), ev->fileName);
+          check_overflow;         
           precision = -1;
        }        
        break;
       case 'l': /* location; LOG4J compliant */
-       if (precision == -1)
-          p += sprintf(p, "%s:%d", ev->fileName, ev->lineNum);
-        else {       
-          p += snprintf(p, precision, "%s:%d", ev->fileName, ev->lineNum);
+       if (precision == -1) {
+          p += snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer), "%s:%d", ev->fileName, ev->lineNum);
+          check_overflow;         
+        } else {             
+          p += snprintf(p, (int)MIN(XBT_LOG_BUFF_SIZE-(p-ev->buffer),precision), "%s:%d", ev->fileName, ev->lineNum);
+          check_overflow;         
           precision = -1;
        }        
        break;
       case 'L': /* line number; LOG4J compliant */
-       if (precision == -1)
-          p += sprintf(p, "%d", ev->lineNum);
-        else {       
-          p += sprintf(p, "%.*d", precision, ev->lineNum);
+       if (precision == -1) {
+          p += snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer), "%d", ev->lineNum);
+          check_overflow;         
+        } else {             
+          p += sprintf(p, "%.*d", (int)MIN(XBT_LOG_BUFF_SIZE-(p-ev->buffer),precision), ev->lineNum);
+          check_overflow;         
           precision = -1;
        }        
        break;
       case 'M': /* method (ie, function) name; LOG4J compliant */
-       if (precision == -1)
-          p += sprintf(p, "%s", ev->functionName);
-        else {       
-          p += sprintf(p, "%.*s", precision, ev->functionName);
+       if (precision == -1) {
+          p += snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer), "%s", ev->functionName);
+          check_overflow;         
+        } else {             
+          p += sprintf(p, "%.*s", (int)MIN(XBT_LOG_BUFF_SIZE-(p-ev->buffer),precision), ev->functionName);
+          check_overflow;         
           precision = -1;
        }        
        break;
@@ -141,51 +319,62 @@ static char *xbt_log_layout_format_doit(xbt_log_layout_t l,
          e.remote=0;
          xbt_backtrace_current(&e);
          if (*q=='B') {
-            if (precision == -1)
-              p += sprintf(p,"%s",e.bt_strings[2]+8);
-            else {           
-               p += sprintf(p,"%.*s",precision, e.bt_strings[2]+8);
+            if (precision == -1) {
+              p += snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer),"%s",e.bt_strings[2]+8);
+              check_overflow;     
+            } else {         
+               p += sprintf(p,"%.*s",(int)MIN(XBT_LOG_BUFF_SIZE-(p-ev->buffer),precision), e.bt_strings[2]+8);
+               check_overflow;    
                precision = -1;
             }   
          } else {
            for (i=2; i<e.used; i++)
-              if (precision == -1)
-                p += sprintf(p,"%s\n",e.bt_strings[i]+8);
-            else {           
-                p += sprintf(p,"%.*s\n",precision,e.bt_strings[i]+8);
-               precision = -1;
+              if (precision == -1) {
+                p += snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer),"%s\n",e.bt_strings[i]+8);
+                check_overflow;           
+              } else {       
+                p += sprintf(p,"%.*s\n",(int)MIN(XBT_LOG_BUFF_SIZE-(p-ev->buffer),precision),e.bt_strings[i]+8);
+                check_overflow;           
+                precision = -1;
             }   
          }
           
          xbt_ex_free(e);
        }
 #else
-       p+=sprintf(p,"(no backtrace on this arch)");
+       p+=snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer),"(no backtrace on this arch)");
+       check_overflow;    
 #endif
        break;
 
       case 'd': /* date; LOG4J compliant */
-       if (precision == -1)
-          p += sprintf(p,"%f", gras_os_time());
-        else {       
-          p += sprintf(p,"%.*f", precision, gras_os_time());
+       if (precision == -1) {
+          p += snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer),"%f", gras_os_time());
+          check_overflow;         
+        } else {             
+          p += sprintf(p,"%.*f", (int)MIN(XBT_LOG_BUFF_SIZE-(p-ev->buffer),precision), gras_os_time());
+          check_overflow;         
           precision = -1;
        }        
        break;
       case 'r': /* application age; LOG4J compliant */
-       if (precision == -1)
-          p += sprintf(p,"%f", gras_os_time()-begin_of_time);
-        else {       
-          p += sprintf(p,"%.*f", precision, gras_os_time()-begin_of_time);
+       if (precision == -1) {
+          p += snprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer),"%f", gras_os_time()-begin_of_time);
+          check_overflow;         
+        } else {             
+          p += sprintf(p,"%.*f", (int)MIN(XBT_LOG_BUFF_SIZE-(p-ev->buffer),precision), gras_os_time()-begin_of_time);
+          check_overflow;         
           precision = -1;
        }        
        break;
        
       case 'm': /* user-provided message; LOG4J compliant */
-       if (precision == -1)
-          p += vsprintf(p, msg_fmt, ev->ap);
-        else {       
-          p += vsnprintf(p, precision, msg_fmt, ev->ap);
+       if (precision == -1) {
+          p += vsnprintf(p,XBT_LOG_BUFF_SIZE-(p-ev->buffer), msg_fmt, ev->ap);
+          check_overflow;         
+        } else {             
+          p += vsnprintf(p, (int)MIN(XBT_LOG_BUFF_SIZE-(p-ev->buffer),precision), msg_fmt, ev->ap);
+          check_overflow;         
           precision = -1;
        }        
        break;
@@ -198,10 +387,11 @@ static char *xbt_log_layout_format_doit(xbt_log_layout_t l,
       q++;
     } else {
       *(p++) = *(q++);
+      check_overflow;     
     }
   }
   *p = '\0';
-  return res;
+  app->do_append(app,ev->buffer);
 }
 
 static void xbt_log_layout_format_free(xbt_log_layout_t lay) {