ltt/branches/poly/lttv/lttv/batchtest.c

   1 /* This file is part of the Linux Trace Toolkit viewer
   2  * Copyright (C) 2003-2004 Michel Dagenais
   3  *
   4  * This program is free software; you can redistribute it and/or modify
   5  * it under the terms of the GNU General Public License Version 2 as
   6  * published by the Free Software Foundation;
   7  *
   8  * This program is distributed in the hope that it will be useful,
   9  * but WITHOUT ANY WARRANTY; without even the implied warranty of
  10  * MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the
  11  * GNU General Public License for more details.
  12  *
  13  * You should have received a copy of the GNU General Public License
  14  * along with this program; if not, write to the Free Software
  15  * Foundation, Inc., 59 Temple Place - Suite 330, Boston,
  16  * MA 02111-1307, USA.
  17  */
  18
  19 /* This module inserts a hook in the program main loop. This hook processes
  20    all the events in the main tracefile while testing the speed and
  21    functionality of the state and stats computations. */
  22
  23 #ifdef HAVE_CONFIG_H
  24 #include <config.h>
  25 #endif
  26
  27 #include <string.h>
  28 #include <lttv/lttv.h>
  29 #include <lttv/attribute.h>
  30 #include <lttv/hook.h>
  31 #include <lttv/option.h>
  32 #include <lttv/module.h>
  33 #include <lttv/tracecontext.h>
  34 #include <lttv/state.h>
  35 #include <lttv/stats.h>
  36 #include <ltt/trace.h>
  37 #include <ltt/event.h>
  38 #include <ltt/type.h>
  39 #include <ltt/facility.h>
  40
  41 #define __UNUSED__ __attribute__((__unused__))
  42
  43 static LttvTraceset *traceset;
  44
  45 static LttvHooks
  46   *before_traceset,
  47   *after_traceset,
  48   *before_trace,
  49   *after_trace,
  50   *before_tracefile,
  51   *after_tracefile,
  52   //*before_event,
  53   //*after_event,
  54   *event_hook,
  55   *main_hooks;
  56
  57 static char *a_trace;
  58
  59 static char *a_dump_tracefiles;
  60
  61 static char *a_save_sample;
  62
  63 static int
  64   a_sample_interval,
  65   a_sample_number,
  66   a_seek_number,
  67   a_save_interval;
  68
  69 static gboolean
  70   a_trace_event,
  71   a_save_state_copy,
  72   a_test1,
  73   a_test2,
  74   a_test3,
  75   a_test4,
  76   a_test5,
  77   a_test6,
  78   a_test7,
  79   a_test8,
  80   a_test9,
  81   a_test10,
  82   a_test_all;
  83
  84 static GQuark QUARK_BLOCK_START,
  85               QUARK_BLOCK_END;
  86
  87 LttEventPosition *a_event_position;
  88
  89 typedef struct _save_state {
  90   guint count;
  91   FILE *fp;
  92   guint interval;
  93   guint position;
  94   guint size;
  95   LttTime *write_time;
  96   guint version;
  97 } SaveState;
  98
  99
 100 static void lttv_trace_option(void __UNUSED__ *hook_data)
 101 {
 102   LttTrace *trace;
 103
 104   trace = ltt_trace_open(a_trace);
 105   if(trace == NULL) {
 106     g_critical("cannot open trace %s", a_trace);
 107   } else {
 108     lttv_traceset_add(traceset, lttv_trace_new(trace));
 109   }
 110 }
 111
 112 static double get_time()
 113 {
 114   GTimeVal gt;
 115
 116   g_get_current_time(&gt);
 117   return gt.tv_sec + (double)gt.tv_usec / (double)1000000.0;
 118 }
 119
 120 static double run_one_test(LttvTracesetState *ts, LttTime start, LttTime end)
 121 {
 122   double t0, t1;
 123
 124   unsigned int i;
 125
 126   //lttv_traceset_context_add_hooks(&ts->parent,
 127   //before_traceset, after_traceset, NULL, before_trace, after_trace,
 128   //NULL, before_tracefile, after_tracefile, NULL, before_event, after_event);
 129   lttv_process_traceset_begin(&ts->parent,
 130                               before_traceset,
 131                               before_trace,
 132                               before_tracefile,
 133                               event_hook,
 134                               NULL);
 135
 136   for(i = 0 ; i < lttv_traceset_number(traceset) ; i++) {
 137     ((LttvTraceState *)(ts->parent.traces[i]))->save_interval =a_save_interval;
 138   }
 139
 140   t0 = get_time();
 141   lttv_state_traceset_seek_time_closest(ts, start);
 142   //lttv_process_traceset(&ts->parent, end, G_MAXULONG);
 143   lttv_process_traceset_middle(&ts->parent,
 144                                end,
 145                                G_MAXULONG,
 146                                NULL);
 147   t1 = get_time();
 148
 149   //lttv_traceset_context_remove_hooks(&ts->parent,
 150   //before_traceset, after_traceset, NULL, before_trace, after_trace,
 151   //NULL, before_tracefile, after_tracefile, NULL, before_event, after_event);
 152   lttv_process_traceset_end(&ts->parent,
 153                             after_traceset,
 154                             after_trace,
 155                             after_tracefile,
 156                             event_hook,
 157                             NULL);
 158
 159   return t1 - t0;
 160 }
 161
 162
 163 gboolean trace_event(void __UNUSED__ *hook_data, void *call_data)
 164 {
 165   LttvTracefileState *tfs = (LttvTracefileState *)call_data;
 166
 167   guint nb_block, offset;
 168
 169   guint64 tsc;
 170
 171   LttTracefile *tf;
 172   LttEvent *e = ltt_tracefile_get_event(tfs->parent.tf);
 173   ltt_event_position(e, a_event_position);
 174   ltt_event_position_get(a_event_position, &tf, &nb_block, &offset, &tsc);
 175   fprintf(stderr,"Event %s %lu.%09lu [%u 0x%x tsc %llu]\n",
 176       g_quark_to_string(ltt_eventtype_name(ltt_event_eventtype(e))),
 177       tfs->parent.timestamp.tv_sec, tfs->parent.timestamp.tv_nsec,
 178       nb_block, offset, tsc);
 179   return FALSE;
 180 }
 181
 182 static LttTime count_previous_time = { 0, 0 };
 183
 184 gboolean count_event(void *hook_data, void __UNUSED__ *call_data)
 185 {
 186   LttvTracefileState *tfs = (LttvTracefileState *)call_data;
 187   LttTracefile *tracefile = tfs->parent.tf;
 188   guint nb_block, offset;
 189   LttTracefile *tf_pos;
 190   guint64 tsc;
 191   LttEvent * event = ltt_tracefile_get_event(tracefile);
 192   LttTime time;
 193   guint *pcount = (guint *)hook_data;
 194
 195   (*pcount)++;
 196
 197   time = ltt_event_time(event);
 198   ltt_event_position(event, a_event_position);
 199   ltt_event_position_get(a_event_position, &tf_pos, &nb_block, &offset, &tsc);
 200
 201   if(ltt_time_compare(time, count_previous_time) < 0) {
 202     g_warning("Time decreasing trace %s tracefile %s cpu %u position %u/0x%x",
 203   g_quark_to_string(ltt_trace_name(ltt_tracefile_get_trace(tracefile))),
 204   g_quark_to_string(ltt_tracefile_name(tracefile)),
 205   tfs->cpu, nb_block, offset);
 206     g_warning("last time %lu.%lu vs current %lu.%lu",
 207   count_previous_time.tv_sec, count_previous_time.tv_nsec,
 208   time.tv_sec, time.tv_nsec);
 209   }
 210   count_previous_time = time;
 211
 212
 213
 214   return FALSE;
 215 }
 216
 217
 218 gboolean save_state_copy_event(void *hook_data, void *call_data)
 219 {
 220   SaveState __UNUSED__ *save_state = (SaveState *)hook_data;
 221
 222   LttvTracefileState *tfs = (LttvTracefileState *)call_data;
 223
 224   LttvTraceState *ts = (LttvTraceState *)tfs->parent.t_context;
 225
 226   LttEvent *e = ltt_tracefile_get_event(tfs->parent.tf);
 227
 228   GString *filename;
 229
 230   FILE *fp;
 231
 232   if(ts->nb_event == 0 &&
 233       ltt_eventtype_name(ltt_event_eventtype(e))
 234                             == QUARK_BLOCK_START) {
 235     if(a_save_sample != NULL) {
 236       filename = g_string_new("");
 237       g_string_printf(filename, "%s.copy.%lu.%09lu.xml", a_save_sample,
 238           tfs->parent.timestamp.tv_sec, tfs->parent.timestamp.tv_nsec);
 239       fp = fopen(filename->str, "w");
 240       if(fp == NULL) g_error("Cannot open %s", filename->str);
 241       g_string_free(filename, TRUE);
 242       lttv_state_write(ts, tfs->parent.timestamp, fp);
 243       fclose(fp);
 244     } //else lttv_state_write(ts, tfs->parent.timestamp, save_state->fp);
 245   }
 246   return FALSE;
 247 }
 248
 249
 250 gboolean save_state_event(void *hook_data, void *call_data)
 251 {
 252   SaveState *save_state = (SaveState *)hook_data;
 253
 254   LttvTracefileState *tfs = (LttvTracefileState *)call_data;
 255
 256   LttvTraceState *ts = (LttvTraceState *)tfs->parent.t_context;
 257
 258   GString *filename;
 259
 260   FILE *fp;
 261
 262   (save_state->count)++;
 263   if(save_state->count % save_state->interval == 0 &&
 264      save_state->position < save_state->size) {
 265     if(a_save_sample != NULL) {
 266       filename = g_string_new("");
 267       g_string_printf(filename, "%s.%u.xml.%u", a_save_sample,
 268           save_state->position, save_state->version);
 269       fp = fopen(filename->str, "w");
 270       if(fp == NULL) g_error("Cannot open %s", filename->str);
 271       g_string_free(filename, TRUE);
 272       lttv_state_write(ts, tfs->parent.timestamp, fp);
 273       fclose(fp);
 274     } //else lttv_state_write(ts, tfs->parent.timestamp, save_state->fp);
 275
 276     save_state->write_time[save_state->position] = tfs->parent.timestamp;
 277     save_state->position++;
 278   }
 279   return FALSE;
 280 }
 281
 282
 283 static void sanitize_name(gchar *name)
 284 {
 285   while(*name != '\0') {
 286     if(*name == '/') *name = '_';
 287     name++;
 288   }
 289
 290 }
 291
 292
 293 static void compute_tracefile(LttTracefile *tracefile, void *hook_data)
 294 {
 295   GString *filename;
 296   guint nb_equal, nb_block, offset;
 297   guint64 tsc;
 298   FILE *fp;
 299   LttTime time, previous_time;
 300   LttEvent *event = ltt_tracefile_get_event(tracefile);
 301   LttEventType *event_type;
 302   int err;
 303   gchar mod_name[PATH_MAX];
 304
 305   /* start_count is always initialized in this function _if_ there is always
 306    * a block_start before a block_end.
 307    */
 308   long long unsigned cycle_count, start_count=0, delta_cycle;
 309
 310
 311   filename = g_string_new("");
 312   strcpy(mod_name, g_quark_to_string(ltt_tracefile_name(tracefile)));
 313
 314   sanitize_name(mod_name);
 315
 316   g_warning("test %s test", g_quark_to_string(ltt_tracefile_name(tracefile)));
 317   g_string_printf(filename, "%s.%s.%u.trace", a_dump_tracefiles,
 318       mod_name, ltt_tracefile_cpu(tracefile));
 319   fp = fopen(filename->str, "w");
 320   if(fp == NULL) g_error("Cannot open %s", filename->str);
 321   g_string_free(filename, TRUE);
 322   err = ltt_tracefile_seek_time(tracefile, ltt_time_zero);
 323   if(err) goto close;
 324
 325   previous_time = ltt_time_zero;
 326   nb_equal = 0;
 327
 328   do {
 329     LttTracefile *tf_pos;
 330     event_type = ltt_event_eventtype(event);
 331     time = ltt_event_time(event);
 332     ltt_event_position(event, a_event_position);
 333     ltt_event_position_get(a_event_position, &tf_pos, &nb_block, &offset, &tsc);
 334     //fprintf(fp,"%s.%s: %llu %lu.%09lu position %u/%u\n",
 335     fprintf(fp, "%s.%s: %llu %lu.%09lu position %u/%u, tracefile %s\n",
 336         g_quark_to_string(ltt_facility_name(facility)),
 337         g_quark_to_string(ltt_eventtype_name(event_type)),
 338         tsc, (unsigned long)time.tv_sec,
 339         (unsigned long)time.tv_nsec,
 340         nb_block, offset,
 341         g_quark_to_string(ltt_tracefile_name(tracefile)));
 342
 343     if(ltt_time_compare(time, previous_time) < 0) {
 344       g_warning("Time decreasing trace %s tracefile %s cpu %u position %u/0x%x",
 345     g_quark_to_string(ltt_trace_name(ltt_tracefile_get_trace(tracefile))),
 346     g_quark_to_string(ltt_tracefile_name(tracefile)),
 347     ltt_tracefile_cpu(tracefile), nb_block, offset);
 348       g_warning("last time %lu.%lu vs current %lu.%lu",
 349     previous_time.tv_sec, previous_time.tv_nsec,
 350     time.tv_sec, time.tv_nsec);
 351     }
 352
 353 #if 0 //FIXME
 354     if(ltt_eventtype_name(event_type) == QUARK_BLOCK_START) {
 355       start_count = cycle_count;
 356       start_time = time;
 357     }
 358     else if(ltt_eventtype_name(event_type) == QUARK_BLOCK_END) {
 359       delta_cycle = cycle_count - start_count;
 360       end_nsec_sec = (long long unsigned)time.tv_sec * (long long unsigned)1000000000;
 361       end_nsec_nsec = time.tv_nsec;
 362       end_nsec = end_nsec_sec + end_nsec_nsec;
 363       start_nsec = (long long unsigned)start_time.tv_sec * (long long unsigned)1000000000 + (long long unsigned)start_time.tv_nsec;
 364       delta_nsec = end_nsec - start_nsec;
 365       cycle_per_nsec = (double)delta_cycle / (double)delta_nsec;
 366       nsec_per_cycle = (double)delta_nsec / (double)delta_cycle;
 367       added_nsec = (double)delta_cycle * nsec_per_cycle;
 368       interpolated_nsec = start_nsec + added_nsec;
 369       added_nsec2 = (double)delta_cycle / cycle_per_nsec;
 370       interpolated_nsec2 = start_nsec + added_nsec2;
 371
 372       fprintf(fp,"Time: start_count %llu, end_count %llu, delta_cycle %llu, start_nsec %llu, end_nsec_sec %llu, end_nsec_nsec %llu, end_nsec %llu, delta_nsec %llu, cycle_per_nsec %.25f, nsec_per_cycle %.25f, added_nsec %llu, added_nsec2 %llu, interpolated_nsec %llu, interpolated_nsec2 %llu\n", start_count, cycle_count, delta_cycle, start_nsec, end_nsec_sec, end_nsec_nsec, end_nsec, delta_nsec, cycle_per_nsec, nsec_per_cycle, added_nsec, added_nsec2, interpolated_nsec, interpolated_nsec2);
 373     }
 374     else {
 375 #endif //0
 376       if(ltt_time_compare(time, previous_time) == 0) nb_equal++;
 377       else if(nb_equal > 0) {
 378         g_warning("Consecutive %d events with time %lu.%09lu",
 379                    nb_equal + 1, previous_time.tv_sec, previous_time.tv_nsec);
 380         nb_equal = 0;
 381       }
 382       previous_time = time;
 383     //}
 384   } while((!ltt_tracefile_read(tracefile)));
 385
 386 close:
 387   fclose(fp);
 388 }
 389
 390 static gboolean process_traceset(void __UNUSED__ *hook_data,
 391                                  void __UNUSED__ *call_data)
 392 {
 393   GString *filename;
 394   LttvTracesetStats *tscs;
 395
 396   LttvTracesetState *ts;
 397
 398   LttvTracesetContext *tc;
 399
 400   FILE *fp;
 401
 402   double t;
 403
 404   //guint count, nb_control, nb_tracefile, nb_block, nb_event;
 405   //guint i, j, count, nb_control, nb_tracefile, nb_block, nb_event, nb_equal;
 406   guint i, j, count;
 407
 408   LttTrace *trace;
 409
 410   long long unsigned start_nsec, end_nsec, delta_nsec, added_nsec, added_nsec2;
 411
 412   double cycle_per_nsec, nsec_per_cycle;
 413
 414   long long interpolated_nsec, interpolated_nsec2, end_nsec_sec, end_nsec_nsec;
 415
 416   LttTime start_time;
 417
 418   LttTime max_time = { G_MAXULONG, G_MAXULONG };
 419
 420   a_event_position = ltt_event_position_new();
 421
 422   GData **tracefiles_groups;
 423
 424   struct compute_tracefile_group_args args;
 425
 426   args.func = compute_tracefile;
 427   args.func_args = NULL;
 428
 429   if(a_dump_tracefiles != NULL) {
 430     for(i = 0 ; i < lttv_traceset_number(traceset) ; i++) {
 431       trace = lttv_trace(lttv_traceset_get(traceset, i));
 432       tracefiles_groups = ltt_trace_get_tracefiles_groups(trace);
 433
 434       g_datalist_foreach(tracefiles_groups,
 435                             (GDataForeachFunc)compute_tracefile_group,
 436                             &args);
 437
 438     }
 439   }
 440
 441   tscs = g_object_new(LTTV_TRACESET_STATS_TYPE, NULL);
 442   ts = &tscs->parent;
 443   tc = &tscs->parent.parent;
 444
 445   lttv_context_init(tc, traceset);
 446
 447   /* For each case compute and print the elapsed time.
 448      The first case is simply to run through all events with a
 449      simple counter. */
 450
 451   if(a_test1 || a_test_all) {
 452     count = 0;
 453     lttv_hooks_add(event_hook, count_event, &count, LTTV_PRIO_DEFAULT);
 454     t = run_one_test(ts, ltt_time_zero, max_time);
 455     lttv_hooks_remove_data(event_hook, count_event, &count);
 456     g_message(
 457         "Processing trace while counting events (%u events in %g seconds)",
 458         count, t);
 459   }
 460
 461   /* Run through all events computing the state. */
 462
 463   if(a_test2 || a_test_all) {
 464     lttv_state_add_event_hooks(ts);
 465     t = run_one_test(ts, ltt_time_zero, max_time);
 466     lttv_state_remove_event_hooks(ts);
 467     g_message("Processing trace while updating state (%g seconds)", t);
 468   }
 469
 470   /* Run through all events computing the state and writing it out
 471      periodically. */
 472
 473   SaveState save_state;
 474
 475   save_state.interval = a_sample_interval;
 476   save_state.size = a_sample_number;
 477   save_state.fp = stderr;
 478   save_state.write_time = g_new(LttTime, a_sample_number);
 479
 480
 481   if(a_test3 || a_test_all) {
 482     for(i = 0 ; i < 2 ; i++) {
 483       save_state.count = 0;
 484       save_state.position = 0;
 485       save_state.version = i;
 486       lttv_state_add_event_hooks(ts);
 487       lttv_hooks_add(event_hook, save_state_event, &save_state,
 488                         LTTV_PRIO_DEFAULT);
 489       t = run_one_test(ts, ltt_time_zero, max_time);
 490       lttv_state_remove_event_hooks(ts);
 491       lttv_hooks_remove_data(event_hook, save_state_event, &save_state);
 492       g_warning("Processing while updating/writing state (%g seconds)", t);
 493     }
 494   }
 495
 496   /* Run through all events computing the stats. */
 497
 498   if(a_test4 || a_test_all) {
 499     if(lttv_profile_memory) {
 500       g_message("Memory summary before computing stats");
 501       g_mem_profile();
 502     }
 503
 504     lttv_stats_add_event_hooks(tscs);
 505     t = run_one_test(ts, ltt_time_zero, max_time);
 506     lttv_stats_remove_event_hooks(tscs);
 507     g_message("Processing trace while counting stats (%g seconds)", t);
 508
 509     if(lttv_profile_memory) {
 510       g_message("Memory summary after computing stats");
 511       g_mem_profile();
 512     }
 513
 514     lttv_stats_sum_traceset(tscs, ltt_time_infinite);
 515
 516     if(lttv_profile_memory) {
 517       g_message("Memory summary after summing stats");
 518       g_mem_profile();
 519     }
 520
 521     lttv_context_fini(tc);
 522     lttv_context_init(tc, traceset);
 523
 524     if(lttv_profile_memory) {
 525       g_message("Memory summary after cleaning up the stats");
 526       g_mem_profile();
 527     }
 528   }
 529
 530   /* Run through all events computing the state and stats. */
 531
 532   if(a_test5 || a_test_all) {
 533     if(lttv_profile_memory) {
 534       g_message("Memory summary before computing state and stats");
 535       g_mem_profile();
 536     }
 537
 538     lttv_state_add_event_hooks(ts);
 539     lttv_stats_add_event_hooks(tscs);
 540     t = run_one_test(ts, ltt_time_zero, max_time);
 541     lttv_state_remove_event_hooks(ts);
 542     lttv_stats_remove_event_hooks(tscs);
 543     g_message(
 544         "Processing trace while counting state and stats (%g seconds)", t);
 545
 546     if(lttv_profile_memory) {
 547       g_message("Memory summary after computing and state and stats");
 548       g_mem_profile();
 549     }
 550
 551     lttv_context_fini(tc);
 552     lttv_context_init(tc, traceset);
 553
 554     if(lttv_profile_memory) {
 555       g_message("Memory summary after cleaning up the stats");
 556       g_mem_profile();
 557     }
 558   }
 559
 560   /* Run through all events computing and saving the state. */
 561
 562   if(a_trace_event) lttv_hooks_add(event_hook, trace_event, NULL,
 563                                       LTTV_PRIO_DEFAULT);
 564
 565   if(a_test6 || a_test_all) {
 566     if(lttv_profile_memory) {
 567       g_message("Memory summary before computing and saving state");
 568       g_mem_profile();
 569     }
 570
 571     lttv_state_add_event_hooks(ts);
 572     lttv_state_save_add_event_hooks(ts);
 573     if(a_save_state_copy)
 574         lttv_hooks_add(event_hook, save_state_copy_event, &save_state,
 575                           LTTV_PRIO_DEFAULT);
 576     t = run_one_test(ts, ltt_time_zero, max_time);
 577     lttv_state_remove_event_hooks(ts);
 578     lttv_state_save_remove_event_hooks(ts);
 579     if(a_save_state_copy)
 580         lttv_hooks_remove_data(event_hook,save_state_copy_event, &save_state);
 581
 582     g_message("Processing trace while updating/saving state (%g seconds)", t);
 583
 584     if(lttv_profile_memory) {
 585       g_message("Memory summary after computing/saving state");
 586       g_mem_profile();
 587     }
 588   }
 589
 590   /* Seek a few times to each saved position */
 591
 592   if((a_test7 && a_test3) || a_test_all) {
 593     g_assert(a_seek_number >= 0);
 594     for(i = 0 ; i < (guint)a_seek_number ; i++) {
 595       gint reverse_j; /* just to make sure j is unsigned */
 596       for(reverse_j = save_state.position - 1 ; reverse_j >= 0 ; reverse_j--) {
 597         j = (guint)reverse_j;
 598         lttv_state_add_event_hooks(ts);
 599         t = run_one_test(ts, save_state.write_time[j],
 600             save_state.write_time[j]);
 601         lttv_state_remove_event_hooks(ts);
 602         g_message("Seeking to %lu.%lu (%g seconds)",
 603             save_state.write_time[j].tv_sec, save_state.write_time[j].tv_nsec,
 604             t);
 605
 606         if(a_save_sample != NULL) {
 607           filename = g_string_new("");
 608           g_string_printf(filename, "%s.%d.xml.bak%d", a_save_sample, j, i);
 609           fp = fopen(filename->str, "w");
 610           if(fp == NULL) g_error("Cannot open %s", filename->str);
 611           g_string_free(filename, TRUE);
 612           lttv_state_write((LttvTraceState *)tc->traces[0],
 613               save_state.write_time[j], fp);
 614           fclose(fp);
 615         }
 616         //else lttv_state_write((LttvTraceState *)tc->traces[0],
 617         //    save_state.write_time[j], save_state.fp);
 618       }
 619     }
 620   }
 621
 622   /* Seek at specified interval, using states computed in 6, making
 623    * sure that there is no more than the number of events between
 624    * state save interval to read before getting there.
 625    */
 626
 627   if((a_test8 && a_test6) || a_test_all) {
 628     g_message("Running test 8 : check save interval");
 629     LttTime time = tc->time_span.start_time;
 630     LttTime interval;
 631     interval.tv_sec = 0;
 632     interval.tv_nsec = 175674987;
 633     guint count;
 634
 635     while(ltt_time_compare(time, tc->time_span.end_time) < 0) {
 636       //g_message("Seeking at time %u.%u", time.tv_sec, time.tv_nsec);
 637       lttv_process_traceset_seek_time(&ts->parent, ltt_time_zero);
 638       lttv_state_traceset_seek_time_closest(ts, time);
 639       /* We add no hook to the traceset, not necessary */
 640       count = lttv_process_traceset_middle(&ts->parent,
 641           time, G_MAXUINT, NULL);
 642       g_info("Number of events to jump over : %u", count);
 643
 644       if(count > LTTV_STATE_SAVE_INTERVAL)
 645         g_warning("Oops! Save interval is %u and it took %u events to seek to a time %lu.%lu supposed to be closer from the last saved state.",
 646             LTTV_STATE_SAVE_INTERVAL, count, time.tv_sec, time.tv_nsec);
 647       time = ltt_time_add(time, interval);
 648     }
 649
 650   }
 651
 652   if(a_test9 || a_test_all) {
 653     double t0, t1;
 654     /* Run seek_forward and seek_backward test */
 655     guint count;
 656     LttvTracesetContext *tsc = &ts->parent;
 657     LttvTracesetContextPosition *saved_pos =
 658       lttv_traceset_context_position_new(tsc);
 659     g_message("Running test 9 : seek_forward and seek_backward");
 660     lttv_process_traceset_seek_time(tsc, ltt_time_zero);
 661
 662     count = lttv_process_traceset_seek_n_forward(tsc, 500, NULL, NULL, NULL, NULL, NULL, NULL);
 663     g_assert(count == 500);
 664     lttv_traceset_context_position_save(tsc, saved_pos);
 665     t0 = get_time();
 666     count = lttv_process_traceset_seek_n_forward(tsc, 150000, NULL, NULL, NULL, NULL, NULL, NULL);
 667     t1 = get_time();
 668     g_message("Seek forward 150000 events in %g seconds", t1 - t0);
 669     g_assert(count == 150000);
 670     t0 = get_time();
 671     count = lttv_process_traceset_seek_n_backward(tsc, 150000,
 672         seek_back_default_offset, lttv_process_traceset_seek_time, NULL,
 673         NULL, NULL, NULL, NULL, NULL);
 674     t1 = get_time();
 675     g_message("Seek backward 150000 events in %g seconds", t1 - t0);
 676     g_assert(count == 150000);
 677     if(lttv_traceset_context_ctx_pos_compare(tsc, saved_pos)) {
 678       g_warning("Problem with seek_n ! Positions differ. (1)");
 679     }
 680
 681     lttv_process_traceset_seek_n_forward(tsc, 500, NULL, NULL, NULL, NULL, NULL, NULL);
 682     lttv_traceset_context_position_save(tsc, saved_pos);
 683     lttv_process_traceset_seek_n_forward(tsc, 15000, NULL, NULL, NULL, NULL, NULL, NULL);
 684     lttv_process_traceset_seek_n_backward(tsc, 15005,
 685         seek_back_default_offset, lttv_process_traceset_seek_time, NULL,
 686         NULL, NULL, NULL, NULL, NULL);
 687     lttv_process_traceset_seek_n_forward(tsc, 5, NULL, NULL, NULL, NULL, NULL, NULL);
 688     if(lttv_traceset_context_ctx_pos_compare(tsc, saved_pos)) {
 689       g_warning("Problem with seek_n ! Positions differ. (2)");
 690     }
 691
 692     lttv_process_traceset_seek_time(tsc, ltt_time_infinite);
 693
 694     count = lttv_process_traceset_seek_n_forward(tsc, 15000, NULL, NULL, NULL, NULL, NULL, NULL);
 695     if(count > 0)
 696       g_warning("Problem with seek_n ! Forward at end of traceset.");
 697
 698     lttv_process_traceset_seek_time(tsc, ltt_time_infinite);
 699
 700     lttv_traceset_context_position_save(tsc, saved_pos);
 701     t0 = get_time();
 702     lttv_process_traceset_seek_n_backward(tsc, 300,
 703         seek_back_default_offset, lttv_process_traceset_seek_time, NULL,
 704         NULL, NULL, NULL, NULL, NULL);
 705     t1 = get_time();
 706     g_message("Seek backward 300 events in %g seconds", t1 - t0);
 707     count = lttv_process_traceset_seek_n_forward(tsc, 299, NULL, NULL, NULL, NULL, NULL, NULL);
 708     count = lttv_process_traceset_seek_n_forward(tsc, 1, NULL, NULL, NULL, NULL, NULL, NULL);
 709
 710     if(lttv_traceset_context_ctx_pos_compare(tsc, saved_pos)) {
 711       g_warning("Problem with seek_n ! Positions differ. (4)");
 712     }
 713
 714     lttv_traceset_context_position_save(tsc, saved_pos);
 715     t0 = get_time();
 716     lttv_process_traceset_seek_n_backward(tsc, 10,
 717         seek_back_default_offset, lttv_process_traceset_seek_time, NULL,
 718         NULL, NULL, NULL, NULL, NULL);
 719     t1 = get_time();
 720     g_message("Seek backward 10 events in %g seconds", t1 - t0);
 721     t0 = get_time();
 722     count = lttv_process_traceset_seek_n_forward(tsc, 10, NULL, NULL, NULL, NULL, NULL, NULL);
 723     t1 = get_time();
 724     g_message("Seek forward 10 events in %g seconds", t1 - t0);
 725
 726
 727     /* try a volountary error */
 728     lttv_process_traceset_seek_time(tsc, ltt_time_infinite);
 729
 730     lttv_traceset_context_position_save(tsc, saved_pos);
 731     lttv_process_traceset_seek_n_backward(tsc, 301,
 732         seek_back_default_offset, lttv_process_traceset_seek_time, NULL,
 733         NULL, NULL, NULL, NULL, NULL);
 734     count = lttv_process_traceset_seek_n_forward(tsc, 299, NULL, NULL, NULL, NULL, NULL, NULL);
 735     count = lttv_process_traceset_seek_n_forward(tsc, 1, NULL, NULL, NULL, NULL, NULL, NULL);
 736
 737     if(lttv_traceset_context_ctx_pos_compare(tsc, saved_pos) == 0) {
 738       g_warning("Problem with seek_n ! Positions _should_ differ. (5)");
 739     }
 740
 741     /* Try a seek by closest time : Hint : try this one with and without states
 742      * computed. */
 743     lttv_process_traceset_seek_time(tsc, ltt_time_zero);
 744     count = lttv_process_traceset_seek_n_forward(tsc, 200000, NULL, NULL, NULL, NULL, NULL, NULL);
 745     lttv_traceset_context_position_save(tsc, saved_pos);
 746     t0 = get_time();
 747     lttv_process_traceset_seek_n_backward(tsc, 100301,
 748         seek_back_default_offset,
 749         (seek_time_fct)lttv_state_traceset_seek_time_closest, NULL,
 750         NULL, NULL, NULL, NULL, NULL);
 751     t1 = get_time();
 752     g_message("Seek backward 100301 events (with seek closest) in %g seconds",
 753                 t1 - t0);
 754     count = lttv_process_traceset_seek_n_forward(tsc, 100301, NULL, NULL, NULL, NULL, NULL, NULL);
 755
 756     if(lttv_traceset_context_ctx_pos_compare(tsc, saved_pos)) {
 757       g_warning("Problem with seek_n with state seek time! Positions differ. (6)");
 758     }
 759
 760     lttv_traceset_context_position_destroy(saved_pos);
 761   }
 762
 763   if(a_test10 || a_test_all) {
 764     g_message("Running test 10 : check seek traceset context position");
 765     LttvTracesetContext *tsc = &ts->parent;
 766     LttvTracesetContextPosition *saved_pos =
 767       lttv_traceset_context_position_new(tsc);
 768
 769     lttv_process_traceset_seek_time(tsc, ltt_time_zero);
 770     lttv_process_traceset_seek_n_forward(tsc, 200000, NULL, NULL, NULL, NULL, NULL, NULL);
 771     lttv_traceset_context_position_save(tsc, saved_pos);
 772     if(lttv_traceset_context_ctx_pos_compare(tsc, saved_pos) != 0)
 773       g_critical("Error in seek position. (1)");
 774
 775     lttv_process_traceset_seek_time(tsc, ltt_time_infinite);
 776     lttv_process_traceset_seek_n_backward(tsc, 500,
 777         seek_back_default_offset, lttv_process_traceset_seek_time, NULL,
 778         NULL, NULL, NULL, NULL, NULL);
 779     lttv_traceset_context_position_save(tsc, saved_pos);
 780
 781     if(lttv_traceset_context_ctx_pos_compare(tsc, saved_pos) != 0)
 782       g_critical("Error in seek position. (2)");
 783
 784     lttv_traceset_context_position_destroy(saved_pos);
 785   }
 786
 787   if(a_trace_event) lttv_hooks_remove_data(event_hook, trace_event, NULL);
 788
 789   g_free(save_state.write_time);
 790   g_free(a_event_position);
 791   lttv_context_fini(tc);
 792   g_object_unref(tscs);
 793
 794   if(lttv_profile_memory) {
 795     g_message("Memory summary at the end of batchtest");
 796     g_mem_profile();
 797   }
 798
 799   g_info("BatchTest end process traceset");
 800   return 0;
 801 }
 802
 803
 804 static void init()
 805 {
 806   LttvAttributeValue value;
 807
 808   LttvIAttribute *attributes = LTTV_IATTRIBUTE(lttv_global_attributes());
 809
 810   g_info("Init batchtest.c");
 811
 812   /* Init GQuarks */
 813   QUARK_BLOCK_START = g_quark_from_string("block_start");
 814   QUARK_BLOCK_END = g_quark_from_string("block_end");
 815
 816
 817   lttv_option_add("trace", 't',
 818       "add a trace to the trace set to analyse",
 819       "pathname of the directory containing the trace",
 820       LTTV_OPT_STRING, &a_trace, lttv_trace_option, NULL);
 821
 822   a_trace_event = FALSE;
 823
 824   a_dump_tracefiles = NULL;
 825   lttv_option_add("dump-tracefiles", 'D',
 826       "Write event by event the content of tracefiles",
 827       "basename for the files where to dump events",
 828       LTTV_OPT_STRING, &a_dump_tracefiles, NULL, NULL);
 829
 830   a_save_sample = NULL;
 831   lttv_option_add("save-sample", 's',
 832       "Save state samples to multiple files",
 833       "basename for the files containing the state samples",
 834       LTTV_OPT_STRING, &a_save_sample, NULL, NULL);
 835
 836   a_save_state_copy = FALSE;
 837   lttv_option_add("save-state-copy", 'S', "Write the state saved for seeking",
 838       "", LTTV_OPT_NONE, &a_save_state_copy, NULL, NULL);
 839
 840   a_save_interval = 100000;
 841   lttv_option_add("save-interval", 'i',
 842       "Interval between saving state",
 843       "number of events before a block start triggers saving state",
 844       LTTV_OPT_INT, &a_save_interval, NULL, NULL);
 845
 846   a_sample_interval = 100000;
 847   lttv_option_add("sample-interval", 'S',
 848       "Interval between sampling state",
 849       "number of events before sampling and writing state",
 850       LTTV_OPT_INT, &a_sample_interval, NULL, NULL);
 851
 852   a_sample_number = 20;
 853   lttv_option_add("sample-number", 'N',
 854       "Number of state samples",
 855       "maximum number",
 856       LTTV_OPT_INT, &a_sample_number, NULL, NULL);
 857
 858   a_seek_number = 200;
 859   lttv_option_add("seek-number", 'K',
 860       "Number of seek",
 861       "number",
 862       LTTV_OPT_INT, &a_seek_number, NULL, NULL);
 863
 864   a_test1 = FALSE;
 865   lttv_option_add("test1", '1', "Test just counting events", "",
 866       LTTV_OPT_NONE, &a_test1, NULL, NULL);
 867
 868   a_test2 = FALSE;
 869   lttv_option_add("test2", '2', "Test computing the state", "",
 870       LTTV_OPT_NONE, &a_test2, NULL, NULL);
 871
 872   a_test3 = FALSE;
 873   lttv_option_add("test3", '3', "Test computing the state, writing out a few",
 874       "", LTTV_OPT_NONE, &a_test3, NULL, NULL);
 875
 876   a_test4 = FALSE;
 877   lttv_option_add("test4", '4', "Test computing the stats", "",
 878       LTTV_OPT_NONE, &a_test4, NULL, NULL);
 879
 880   a_test5 = FALSE;
 881   lttv_option_add("test5", '5', "Test computing the state and stats", "",
 882       LTTV_OPT_NONE, &a_test5, NULL, NULL);
 883
 884   a_test6 = FALSE;
 885   lttv_option_add("test6", '6', "Test computing and saving the state", "",
 886       LTTV_OPT_NONE, &a_test6, NULL, NULL);
 887
 888   a_test7 = FALSE;
 889   lttv_option_add("test7", '7', "Test seeking to positions written out in 3",
 890       "", LTTV_OPT_NONE, &a_test7, NULL, NULL);
 891
 892   a_test8 = FALSE;
 893   lttv_option_add("test8", '8', "Test seeking to positions using saved states computed at 6 : check if number of events fits",
 894       "", LTTV_OPT_NONE, &a_test8, NULL, NULL);
 895
 896   a_test9 = FALSE;
 897   lttv_option_add("test9", '9', "Test seeking backward/forward positions",
 898       "", LTTV_OPT_NONE, &a_test9, NULL, NULL);
 899
 900   a_test10 = FALSE;
 901   lttv_option_add("test10", ' ', "Test seeking traceset by position",
 902       "", LTTV_OPT_NONE, &a_test10, NULL, NULL);
 903
 904
 905
 906   a_test_all = FALSE;
 907   lttv_option_add("testall", 'a', "Run all tests ", "",
 908       LTTV_OPT_NONE, &a_test_all, NULL, NULL);
 909
 910   traceset = lttv_traceset_new();
 911
 912   before_traceset = lttv_hooks_new();
 913   after_traceset = lttv_hooks_new();
 914   before_trace = lttv_hooks_new();
 915   after_trace = lttv_hooks_new();
 916   before_tracefile = lttv_hooks_new();
 917   after_tracefile = lttv_hooks_new();
 918   //before_event = lttv_hooks_new();
 919   //after_event = lttv_hooks_new();
 920   event_hook = lttv_hooks_new();
 921
 922
 923   g_assert(lttv_iattribute_find_by_path(attributes, "hooks/traceset/before",
 924       LTTV_POINTER, &value));
 925   *(value.v_pointer) = before_traceset;
 926   g_assert(lttv_iattribute_find_by_path(attributes, "hooks/traceset/after",
 927       LTTV_POINTER, &value));
 928   *(value.v_pointer) = after_traceset;
 929   g_assert(lttv_iattribute_find_by_path(attributes, "hooks/trace/before",
 930       LTTV_POINTER, &value));
 931   *(value.v_pointer) = before_trace;
 932   g_assert(lttv_iattribute_find_by_path(attributes, "hooks/trace/after",
 933       LTTV_POINTER, &value));
 934   *(value.v_pointer) = after_trace;
 935   g_assert(lttv_iattribute_find_by_path(attributes, "hooks/tracefile/before",
 936       LTTV_POINTER, &value));
 937   *(value.v_pointer) = before_tracefile;
 938   g_assert(lttv_iattribute_find_by_path(attributes, "hooks/tracefile/after",
 939       LTTV_POINTER, &value));
 940   *(value.v_pointer) = after_tracefile;
 941   //g_assert(lttv_iattribute_find_by_path(attributes, "hooks/event/before",
 942   //    LTTV_POINTER, &value));
 943   //*(value.v_pointer) = before_event;
 944   //g_assert(lttv_iattribute_find_by_path(attributes, "hooks/event/after",
 945   //    LTTV_POINTER, &value));
 946   //*(value.v_pointer) = after_event;
 947   g_assert(lttv_iattribute_find_by_path(attributes, "hooks/event",
 948       LTTV_POINTER, &value));
 949   *(value.v_pointer) = event_hook;
 950   g_assert(lttv_iattribute_find_by_path(attributes, "hooks/main/before",
 951       LTTV_POINTER, &value));
 952   g_assert((main_hooks = *(value.v_pointer)) != NULL);
 953   lttv_hooks_add(main_hooks, process_traceset, NULL, LTTV_PRIO_DEFAULT);
 954 }
 955
 956
 957 static void destroy()
 958 {
 959   guint i, nb;
 960
 961   LttvTrace *trace;
 962
 963   g_info("Destroy batchAnalysis.c");
 964
 965   lttv_option_remove("trace");
 966   lttv_option_remove("dump-tracefiles");
 967   lttv_option_remove("save-sample");
 968   lttv_option_remove("save-state-copy");
 969   lttv_option_remove("sample-interval");
 970   lttv_option_remove("sample-number");
 971   lttv_option_remove("seek-number");
 972   lttv_option_remove("save-interval");
 973   lttv_option_remove("test1");
 974   lttv_option_remove("test2");
 975   lttv_option_remove("test3");
 976   lttv_option_remove("test4");
 977   lttv_option_remove("test5");
 978   lttv_option_remove("test6");
 979   lttv_option_remove("test7");
 980   lttv_option_remove("test8");
 981   lttv_option_remove("test9");
 982   lttv_option_remove("test10");
 983   lttv_option_remove("testall");
 984
 985   lttv_hooks_destroy(before_traceset);
 986   lttv_hooks_destroy(after_traceset);
 987   lttv_hooks_destroy(before_trace);
 988   lttv_hooks_destroy(after_trace);
 989   lttv_hooks_destroy(before_tracefile);
 990   lttv_hooks_destroy(after_tracefile);
 991   //lttv_hooks_destroy(before_event);
 992   //lttv_hooks_destroy(after_event);
 993   lttv_hooks_destroy(event_hook);
 994   lttv_hooks_remove_data(main_hooks, process_traceset, NULL);
 995
 996   nb = lttv_traceset_number(traceset);
 997   for(i = 0 ; i < nb ; i++) {
 998     trace = lttv_traceset_get(traceset, i);
 999     lttv_traceset_remove(traceset,i);
1000     ltt_trace_close(lttv_trace(trace));
1001     lttv_trace_destroy(trace);
1002   }
1003
1004   lttv_traceset_destroy(traceset);
1005 }
1006
1007
1008 LTTV_MODULE("batchtest", "Batch processing of a trace for tests", \
1009     "Run through a trace calling all the registered hooks for tests", \
1010     init, destroy, "state", "stats", "option" )