lttng-xenomai/LinuxTraceToolkitViewer-0.8.61-xenoltt/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   LttFacility *facility;
 302   LttEventType *event_type;
 303   int err;
 304   gchar mod_name[PATH_MAX];
 305
 306   /* start_count is always initialized in this function _if_ there is always
 307    * a block_start before a block_end.
 308    */
 309   long long unsigned cycle_count, start_count=0, delta_cycle;
 310
 311
 312   filename = g_string_new("");
 313   strcpy(mod_name, g_quark_to_string(ltt_tracefile_name(tracefile)));
 314
 315   sanitize_name(mod_name);
 316
 317   g_warning("test %s test", g_quark_to_string(ltt_tracefile_name(tracefile)));
 318   g_string_printf(filename, "%s.%s.%u.trace", a_dump_tracefiles,
 319       mod_name, ltt_tracefile_cpu(tracefile));
 320   fp = fopen(filename->str, "w");
 321   if(fp == NULL) g_error("Cannot open %s", filename->str);
 322   g_string_free(filename, TRUE);
 323   err = ltt_tracefile_seek_time(tracefile, ltt_time_zero);
 324   if(err) goto close;
 325
 326   previous_time = ltt_time_zero;
 327   nb_equal = 0;
 328
 329   do {
 330     LttTracefile *tf_pos;
 331     facility = ltt_event_facility(event);
 332     event_type = ltt_event_eventtype(event);
 333     time = ltt_event_time(event);
 334     ltt_event_position(event, a_event_position);
 335     ltt_event_position_get(a_event_position, &tf_pos, &nb_block, &offset, &tsc);
 336     //fprintf(fp,"%s.%s: %llu %lu.%09lu position %u/%u\n",
 337     fprintf(fp, "%s.%s: %llu %lu.%09lu position %u/%u, tracefile %s\n",
 338         g_quark_to_string(ltt_facility_name(facility)),
 339         g_quark_to_string(ltt_eventtype_name(event_type)),
 340         tsc, (unsigned long)time.tv_sec,
 341         (unsigned long)time.tv_nsec,
 342         nb_block, offset,
 343         g_quark_to_string(ltt_tracefile_name(tracefile)));
 344
 345     if(ltt_time_compare(time, previous_time) < 0) {
 346       g_warning("Time decreasing trace %s tracefile %s cpu %u position %u/0x%x",
 347     g_quark_to_string(ltt_trace_name(ltt_tracefile_get_trace(tracefile))),
 348     g_quark_to_string(ltt_tracefile_name(tracefile)),
 349     ltt_tracefile_cpu(tracefile), nb_block, offset);
 350       g_warning("last time %lu.%lu vs current %lu.%lu",
 351     previous_time.tv_sec, previous_time.tv_nsec,
 352     time.tv_sec, time.tv_nsec);
 353     }
 354
 355 #if 0 //FIXME
 356     if(ltt_eventtype_name(event_type) == QUARK_BLOCK_START) {
 357       start_count = cycle_count;
 358       start_time = time;
 359     }
 360     else if(ltt_eventtype_name(event_type) == QUARK_BLOCK_END) {
 361       delta_cycle = cycle_count - start_count;
 362       end_nsec_sec = (long long unsigned)time.tv_sec * (long long unsigned)1000000000;
 363       end_nsec_nsec = time.tv_nsec;
 364       end_nsec = end_nsec_sec + end_nsec_nsec;
 365       start_nsec = (long long unsigned)start_time.tv_sec * (long long unsigned)1000000000 + (long long unsigned)start_time.tv_nsec;
 366       delta_nsec = end_nsec - start_nsec;
 367       cycle_per_nsec = (double)delta_cycle / (double)delta_nsec;
 368       nsec_per_cycle = (double)delta_nsec / (double)delta_cycle;
 369       added_nsec = (double)delta_cycle * nsec_per_cycle;
 370       interpolated_nsec = start_nsec + added_nsec;
 371       added_nsec2 = (double)delta_cycle / cycle_per_nsec;
 372       interpolated_nsec2 = start_nsec + added_nsec2;
 373
 374       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);
 375     }
 376     else {
 377 #endif //0
 378       if(ltt_time_compare(time, previous_time) == 0) nb_equal++;
 379       else if(nb_equal > 0) {
 380         g_warning("Consecutive %d events with time %lu.%09lu",
 381                    nb_equal + 1, previous_time.tv_sec, previous_time.tv_nsec);
 382         nb_equal = 0;
 383       }
 384       previous_time = time;
 385     //}
 386   } while((!ltt_tracefile_read(tracefile)));
 387
 388 close:
 389   fclose(fp);
 390 }
 391
 392 static gboolean process_traceset(void __UNUSED__ *hook_data,
 393                                  void __UNUSED__ *call_data)
 394 {
 395   GString *filename;
 396   LttvTracesetStats *tscs;
 397
 398   LttvTracesetState *ts;
 399
 400   LttvTracesetContext *tc;
 401
 402   FILE *fp;
 403
 404   double t;
 405
 406   //guint count, nb_control, nb_tracefile, nb_block, nb_event;
 407   //guint i, j, count, nb_control, nb_tracefile, nb_block, nb_event, nb_equal;
 408   guint i, j, count;
 409
 410   LttTrace *trace;
 411
 412   long long unsigned start_nsec, end_nsec, delta_nsec, added_nsec, added_nsec2;
 413
 414   double cycle_per_nsec, nsec_per_cycle;
 415
 416   long long interpolated_nsec, interpolated_nsec2, end_nsec_sec, end_nsec_nsec;
 417
 418   LttTime start_time;
 419
 420   LttTime max_time = { G_MAXULONG, G_MAXULONG };
 421
 422   a_event_position = ltt_event_position_new();
 423
 424   GData **tracefiles_groups;
 425
 426   struct compute_tracefile_group_args args;
 427
 428   args.func = compute_tracefile;
 429   args.func_args = NULL;
 430
 431   if(a_dump_tracefiles != NULL) {
 432     for(i = 0 ; i < lttv_traceset_number(traceset) ; i++) {
 433       trace = lttv_trace(lttv_traceset_get(traceset, i));
 434       tracefiles_groups = ltt_trace_get_tracefiles_groups(trace);
 435
 436       g_datalist_foreach(tracefiles_groups,
 437                             (GDataForeachFunc)compute_tracefile_group,
 438                             &args);
 439
 440     }
 441   }
 442
 443   tscs = g_object_new(LTTV_TRACESET_STATS_TYPE, NULL);
 444   ts = &tscs->parent;
 445   tc = &tscs->parent.parent;
 446
 447   lttv_context_init(tc, traceset);
 448
 449   /* For each case compute and print the elapsed time.
 450      The first case is simply to run through all events with a
 451      simple counter. */
 452
 453   if(a_test1 || a_test_all) {
 454     count = 0;
 455     lttv_hooks_add(event_hook, count_event, &count, LTTV_PRIO_DEFAULT);
 456     t = run_one_test(ts, ltt_time_zero, max_time);
 457     lttv_hooks_remove_data(event_hook, count_event, &count);
 458     g_message(
 459         "Processing trace while counting events (%u events in %g seconds)",
 460         count, t);
 461   }
 462
 463   /* Run through all events computing the state. */
 464
 465   if(a_test2 || a_test_all) {
 466     lttv_state_add_event_hooks(ts);
 467     t = run_one_test(ts, ltt_time_zero, max_time);
 468     lttv_state_remove_event_hooks(ts);
 469     g_message("Processing trace while updating state (%g seconds)", t);
 470   }
 471
 472   /* Run through all events computing the state and writing it out
 473      periodically. */
 474
 475   SaveState save_state;
 476
 477   save_state.interval = a_sample_interval;
 478   save_state.size = a_sample_number;
 479   save_state.fp = stderr;
 480   save_state.write_time = g_new(LttTime, a_sample_number);
 481
 482
 483   if(a_test3 || a_test_all) {
 484     for(i = 0 ; i < 2 ; i++) {
 485       save_state.count = 0;
 486       save_state.position = 0;
 487       save_state.version = i;
 488       lttv_state_add_event_hooks(ts);
 489       lttv_hooks_add(event_hook, save_state_event, &save_state,
 490                         LTTV_PRIO_DEFAULT);
 491       t = run_one_test(ts, ltt_time_zero, max_time);
 492       lttv_state_remove_event_hooks(ts);
 493       lttv_hooks_remove_data(event_hook, save_state_event, &save_state);
 494       g_warning("Processing while updating/writing state (%g seconds)", t);
 495     }
 496   }
 497
 498   /* Run through all events computing the stats. */
 499
 500   if(a_test4 || a_test_all) {
 501     if(lttv_profile_memory) {
 502       g_message("Memory summary before computing stats");
 503       g_mem_profile();
 504     }
 505
 506     lttv_stats_add_event_hooks(tscs);
 507     t = run_one_test(ts, ltt_time_zero, max_time);
 508     lttv_stats_remove_event_hooks(tscs);
 509     g_message("Processing trace while counting stats (%g seconds)", t);
 510
 511     if(lttv_profile_memory) {
 512       g_message("Memory summary after computing stats");
 513       g_mem_profile();
 514     }
 515
 516     lttv_stats_sum_traceset(tscs);
 517
 518     if(lttv_profile_memory) {
 519       g_message("Memory summary after summing stats");
 520       g_mem_profile();
 521     }
 522
 523     lttv_context_fini(tc);
 524     lttv_context_init(tc, traceset);
 525
 526     if(lttv_profile_memory) {
 527       g_message("Memory summary after cleaning up the stats");
 528       g_mem_profile();
 529     }
 530   }
 531
 532   /* Run through all events computing the state and stats. */
 533
 534   if(a_test5 || a_test_all) {
 535     if(lttv_profile_memory) {
 536       g_message("Memory summary before computing state and stats");
 537       g_mem_profile();
 538     }
 539
 540     lttv_state_add_event_hooks(ts);
 541     lttv_stats_add_event_hooks(tscs);
 542     t = run_one_test(ts, ltt_time_zero, max_time);
 543     lttv_state_remove_event_hooks(ts);
 544     lttv_stats_remove_event_hooks(tscs);
 545     g_message(
 546         "Processing trace while counting state and stats (%g seconds)", t);
 547
 548     if(lttv_profile_memory) {
 549       g_message("Memory summary after computing and state and stats");
 550       g_mem_profile();
 551     }
 552
 553     lttv_context_fini(tc);
 554     lttv_context_init(tc, traceset);
 555
 556     if(lttv_profile_memory) {
 557       g_message("Memory summary after cleaning up the stats");
 558       g_mem_profile();
 559     }
 560   }
 561
 562   /* Run through all events computing and saving the state. */
 563
 564   if(a_trace_event) lttv_hooks_add(event_hook, trace_event, NULL,
 565                                       LTTV_PRIO_DEFAULT);
 566
 567   if(a_test6 || a_test_all) {
 568     if(lttv_profile_memory) {
 569       g_message("Memory summary before computing and saving state");
 570       g_mem_profile();
 571     }
 572
 573     lttv_state_add_event_hooks(ts);
 574     lttv_state_save_add_event_hooks(ts);
 575     if(a_save_state_copy)
 576         lttv_hooks_add(event_hook, save_state_copy_event, &save_state,
 577                           LTTV_PRIO_DEFAULT);
 578     t = run_one_test(ts, ltt_time_zero, max_time);
 579     lttv_state_remove_event_hooks(ts);
 580     lttv_state_save_remove_event_hooks(ts);
 581     if(a_save_state_copy)
 582         lttv_hooks_remove_data(event_hook,save_state_copy_event, &save_state);
 583
 584     g_message("Processing trace while updating/saving state (%g seconds)", t);
 585
 586     if(lttv_profile_memory) {
 587       g_message("Memory summary after computing/saving state");
 588       g_mem_profile();
 589     }
 590   }
 591
 592   /* Seek a few times to each saved position */
 593
 594   if((a_test7 && a_test3) || a_test_all) {
 595     g_assert(a_seek_number >= 0);
 596     for(i = 0 ; i < (guint)a_seek_number ; i++) {
 597       gint reverse_j; /* just to make sure j is unsigned */
 598       for(reverse_j = save_state.position - 1 ; reverse_j >= 0 ; reverse_j--) {
 599         j = (guint)reverse_j;
 600         lttv_state_add_event_hooks(ts);
 601         t = run_one_test(ts, save_state.write_time[j],
 602             save_state.write_time[j]);
 603         lttv_state_remove_event_hooks(ts);
 604         g_message("Seeking to %lu.%lu (%g seconds)",
 605             save_state.write_time[j].tv_sec, save_state.write_time[j].tv_nsec,
 606             t);
 607
 608         if(a_save_sample != NULL) {
 609           filename = g_string_new("");
 610           g_string_printf(filename, "%s.%d.xml.bak%d", a_save_sample, j, i);
 611           fp = fopen(filename->str, "w");
 612           if(fp == NULL) g_error("Cannot open %s", filename->str);
 613           g_string_free(filename, TRUE);
 614           lttv_state_write((LttvTraceState *)tc->traces[0],
 615               save_state.write_time[j], fp);
 616           fclose(fp);
 617         }
 618         //else lttv_state_write((LttvTraceState *)tc->traces[0],
 619         //    save_state.write_time[j], save_state.fp);
 620       }
 621     }
 622   }
 623
 624   /* Seek at specified interval, using states computed in 6, making
 625    * sure that there is no more than the number of events between
 626    * state save interval to read before getting there.
 627    */
 628
 629   if((a_test8 && a_test6) || a_test_all) {
 630     g_message("Running test 8 : check save interval");
 631     LttTime time = tc->time_span.start_time;
 632     LttTime interval;
 633     interval.tv_sec = 0;
 634     interval.tv_nsec = 175674987;
 635     guint count;
 636
 637     while(ltt_time_compare(time, tc->time_span.end_time) < 0) {
 638       //g_message("Seeking at time %u.%u", time.tv_sec, time.tv_nsec);
 639       lttv_process_traceset_seek_time(&ts->parent, ltt_time_zero);
 640       lttv_state_traceset_seek_time_closest(ts, time);
 641       /* We add no hook to the traceset, not necessary */
 642       count = lttv_process_traceset_middle(&ts->parent,
 643           time, G_MAXUINT, NULL);
 644       g_info("Number of events to jump over : %u", count);
 645
 646       if(count > LTTV_STATE_SAVE_INTERVAL)
 647         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.",
 648             LTTV_STATE_SAVE_INTERVAL, count, time.tv_sec, time.tv_nsec);
 649       time = ltt_time_add(time, interval);
 650     }
 651
 652   }
 653
 654   if(a_test9 || a_test_all) {
 655     double t0, t1;
 656     /* Run seek_forward and seek_backward test */
 657     guint count;
 658     LttvTracesetContext *tsc = &ts->parent;
 659     LttvTracesetContextPosition *saved_pos =
 660       lttv_traceset_context_position_new(tsc);
 661     g_message("Running test 9 : seek_forward and seek_backward");
 662     lttv_process_traceset_seek_time(tsc, ltt_time_zero);
 663
 664     count = lttv_process_traceset_seek_n_forward(tsc, 500, NULL, NULL, NULL);
 665     g_assert(count == 500);
 666     lttv_traceset_context_position_save(tsc, saved_pos);
 667     t0 = get_time();
 668     count = lttv_process_traceset_seek_n_forward(tsc, 150000, NULL, NULL, NULL);
 669     t1 = get_time();
 670     g_message("Seek forward 150000 events in %g seconds", t1 - t0);
 671     g_assert(count == 150000);
 672     t0 = get_time();
 673     count = lttv_process_traceset_seek_n_backward(tsc, 150000,
 674         seek_back_default_offset, lttv_process_traceset_seek_time, NULL,
 675         NULL, NULL);
 676     t1 = get_time();
 677     g_message("Seek backward 150000 events in %g seconds", t1 - t0);
 678     g_assert(count == 150000);
 679     if(lttv_traceset_context_ctx_pos_compare(tsc, saved_pos)) {
 680       g_warning("Problem with seek_n ! Positions differ. (1)");
 681     }
 682
 683     lttv_process_traceset_seek_n_forward(tsc, 500, NULL, NULL, NULL);
 684     lttv_traceset_context_position_save(tsc, saved_pos);
 685     lttv_process_traceset_seek_n_forward(tsc, 15000, NULL, NULL, NULL);
 686     lttv_process_traceset_seek_n_backward(tsc, 15005,
 687         seek_back_default_offset, lttv_process_traceset_seek_time, NULL,
 688         NULL, NULL);
 689     lttv_process_traceset_seek_n_forward(tsc, 5, NULL, NULL, NULL);
 690     if(lttv_traceset_context_ctx_pos_compare(tsc, saved_pos)) {
 691       g_warning("Problem with seek_n ! Positions differ. (2)");
 692     }
 693
 694     lttv_process_traceset_seek_time(tsc, ltt_time_infinite);
 695
 696     count = lttv_process_traceset_seek_n_forward(tsc, 15000, NULL, NULL, NULL);
 697     if(count > 0)
 698       g_warning("Problem with seek_n ! Forward at end of traceset.");
 699
 700     lttv_process_traceset_seek_time(tsc, ltt_time_infinite);
 701
 702     lttv_traceset_context_position_save(tsc, saved_pos);
 703     t0 = get_time();
 704     lttv_process_traceset_seek_n_backward(tsc, 300,
 705         seek_back_default_offset, lttv_process_traceset_seek_time, NULL,
 706         NULL, NULL);
 707     t1 = get_time();
 708     g_message("Seek backward 300 events in %g seconds", t1 - t0);
 709     count = lttv_process_traceset_seek_n_forward(tsc, 299, NULL, NULL, NULL);
 710     count = lttv_process_traceset_seek_n_forward(tsc, 1, NULL, NULL, NULL);
 711
 712     if(lttv_traceset_context_ctx_pos_compare(tsc, saved_pos)) {
 713       g_warning("Problem with seek_n ! Positions differ. (4)");
 714     }
 715
 716     lttv_traceset_context_position_save(tsc, saved_pos);
 717     t0 = get_time();
 718     lttv_process_traceset_seek_n_backward(tsc, 10,
 719         seek_back_default_offset, lttv_process_traceset_seek_time, NULL,
 720         NULL, NULL);
 721     t1 = get_time();
 722     g_message("Seek backward 10 events in %g seconds", t1 - t0);
 723     t0 = get_time();
 724     count = lttv_process_traceset_seek_n_forward(tsc, 10, NULL, NULL, NULL);
 725     t1 = get_time();
 726     g_message("Seek forward 10 events in %g seconds", t1 - t0);
 727
 728
 729     /* try a volountary error */
 730     lttv_process_traceset_seek_time(tsc, ltt_time_infinite);
 731
 732     lttv_traceset_context_position_save(tsc, saved_pos);
 733     lttv_process_traceset_seek_n_backward(tsc, 301,
 734         seek_back_default_offset, lttv_process_traceset_seek_time, NULL,
 735         NULL, NULL);
 736     count = lttv_process_traceset_seek_n_forward(tsc, 299, NULL, NULL, NULL);
 737     count = lttv_process_traceset_seek_n_forward(tsc, 1, NULL, NULL, NULL);
 738
 739     if(lttv_traceset_context_ctx_pos_compare(tsc, saved_pos) == 0) {
 740       g_warning("Problem with seek_n ! Positions _should_ differ. (5)");
 741     }
 742
 743     /* Try a seek by closest time : Hint : try this one with and without states
 744      * computed. */
 745     lttv_process_traceset_seek_time(tsc, ltt_time_zero);
 746     count = lttv_process_traceset_seek_n_forward(tsc, 200000, NULL, NULL, NULL);
 747     lttv_traceset_context_position_save(tsc, saved_pos);
 748     t0 = get_time();
 749     lttv_process_traceset_seek_n_backward(tsc, 100301,
 750         seek_back_default_offset,
 751         (seek_time_fct)lttv_state_traceset_seek_time_closest, NULL,
 752         NULL, NULL);
 753     t1 = get_time();
 754     g_message("Seek backward 100301 events (with seek closest) in %g seconds",
 755                 t1 - t0);
 756     count = lttv_process_traceset_seek_n_forward(tsc, 100301, NULL, NULL, NULL);
 757
 758     if(lttv_traceset_context_ctx_pos_compare(tsc, saved_pos)) {
 759       g_warning("Problem with seek_n with state seek time! Positions differ. (6)");
 760     }
 761
 762     lttv_traceset_context_position_destroy(saved_pos);
 763   }
 764
 765   if(a_test10 || a_test_all) {
 766     g_message("Running test 10 : check seek traceset context position");
 767     LttvTracesetContext *tsc = &ts->parent;
 768     LttvTracesetContextPosition *saved_pos =
 769       lttv_traceset_context_position_new(tsc);
 770
 771     lttv_process_traceset_seek_time(tsc, ltt_time_zero);
 772     lttv_process_traceset_seek_n_forward(tsc, 200000, NULL, NULL, NULL);
 773     lttv_traceset_context_position_save(tsc, saved_pos);
 774     if(lttv_traceset_context_ctx_pos_compare(tsc, saved_pos) != 0)
 775       g_critical("Error in seek position. (1)");
 776
 777     lttv_process_traceset_seek_time(tsc, ltt_time_infinite);
 778     lttv_process_traceset_seek_n_backward(tsc, 500,
 779         seek_back_default_offset, lttv_process_traceset_seek_time, NULL,
 780         NULL, NULL);
 781     lttv_traceset_context_position_save(tsc, saved_pos);
 782
 783     if(lttv_traceset_context_ctx_pos_compare(tsc, saved_pos) != 0)
 784       g_critical("Error in seek position. (2)");
 785
 786     lttv_traceset_context_position_destroy(saved_pos);
 787   }
 788
 789   if(a_trace_event) lttv_hooks_remove_data(event_hook, trace_event, NULL);
 790
 791   g_free(save_state.write_time);
 792   g_free(a_event_position);
 793   lttv_context_fini(tc);
 794   g_object_unref(tscs);
 795
 796   if(lttv_profile_memory) {
 797     g_message("Memory summary at the end of batchtest");
 798     g_mem_profile();
 799   }
 800
 801   g_info("BatchTest end process traceset");
 802   return 0;
 803 }
 804
 805
 806 static void init()
 807 {
 808   LttvAttributeValue value;
 809
 810   LttvIAttribute *attributes = LTTV_IATTRIBUTE(lttv_global_attributes());
 811
 812   g_info("Init batchtest.c");
 813
 814   /* Init GQuarks */
 815   QUARK_BLOCK_START = g_quark_from_string("block_start");
 816   QUARK_BLOCK_END = g_quark_from_string("block_end");
 817
 818
 819   lttv_option_add("trace", 't',
 820       "add a trace to the trace set to analyse",
 821       "pathname of the directory containing the trace",
 822       LTTV_OPT_STRING, &a_trace, lttv_trace_option, NULL);
 823
 824   a_trace_event = FALSE;
 825
 826   a_dump_tracefiles = NULL;
 827   lttv_option_add("dump-tracefiles", 'D',
 828       "Write event by event the content of tracefiles",
 829       "basename for the files where to dump events",
 830       LTTV_OPT_STRING, &a_dump_tracefiles, NULL, NULL);
 831
 832   a_save_sample = NULL;
 833   lttv_option_add("save-sample", 's',
 834       "Save state samples to multiple files",
 835       "basename for the files containing the state samples",
 836       LTTV_OPT_STRING, &a_save_sample, NULL, NULL);
 837
 838   a_save_state_copy = FALSE;
 839   lttv_option_add("save-state-copy", 'S', "Write the state saved for seeking",
 840       "", LTTV_OPT_NONE, &a_save_state_copy, NULL, NULL);
 841
 842   a_save_interval = 100000;
 843   lttv_option_add("save-interval", 'i',
 844       "Interval between saving state",
 845       "number of events before a block start triggers saving state",
 846       LTTV_OPT_INT, &a_save_interval, NULL, NULL);
 847
 848   a_sample_interval = 100000;
 849   lttv_option_add("sample-interval", 'S',
 850       "Interval between sampling state",
 851       "number of events before sampling and writing state",
 852       LTTV_OPT_INT, &a_sample_interval, NULL, NULL);
 853
 854   a_sample_number = 20;
 855   lttv_option_add("sample-number", 'N',
 856       "Number of state samples",
 857       "maximum number",
 858       LTTV_OPT_INT, &a_sample_number, NULL, NULL);
 859
 860   a_seek_number = 200;
 861   lttv_option_add("seek-number", 'K',
 862       "Number of seek",
 863       "number",
 864       LTTV_OPT_INT, &a_seek_number, NULL, NULL);
 865
 866   a_test1 = FALSE;
 867   lttv_option_add("test1", '1', "Test just counting events", "",
 868       LTTV_OPT_NONE, &a_test1, NULL, NULL);
 869
 870   a_test2 = FALSE;
 871   lttv_option_add("test2", '2', "Test computing the state", "",
 872       LTTV_OPT_NONE, &a_test2, NULL, NULL);
 873
 874   a_test3 = FALSE;
 875   lttv_option_add("test3", '3', "Test computing the state, writing out a few",
 876       "", LTTV_OPT_NONE, &a_test3, NULL, NULL);
 877
 878   a_test4 = FALSE;
 879   lttv_option_add("test4", '4', "Test computing the stats", "",
 880       LTTV_OPT_NONE, &a_test4, NULL, NULL);
 881
 882   a_test5 = FALSE;
 883   lttv_option_add("test5", '5', "Test computing the state and stats", "",
 884       LTTV_OPT_NONE, &a_test5, NULL, NULL);
 885
 886   a_test6 = FALSE;
 887   lttv_option_add("test6", '6', "Test computing and saving the state", "",
 888       LTTV_OPT_NONE, &a_test6, NULL, NULL);
 889
 890   a_test7 = FALSE;
 891   lttv_option_add("test7", '7', "Test seeking to positions written out in 3",
 892       "", LTTV_OPT_NONE, &a_test7, NULL, NULL);
 893
 894   a_test8 = FALSE;
 895   lttv_option_add("test8", '8', "Test seeking to positions using saved states computed at 6 : check if number of events fits",
 896       "", LTTV_OPT_NONE, &a_test8, NULL, NULL);
 897
 898   a_test9 = FALSE;
 899   lttv_option_add("test9", '9', "Test seeking backward/forward positions",
 900       "", LTTV_OPT_NONE, &a_test9, NULL, NULL);
 901
 902   a_test10 = FALSE;
 903   lttv_option_add("test10", ' ', "Test seeking traceset by position",
 904       "", LTTV_OPT_NONE, &a_test10, NULL, NULL);
 905
 906
 907
 908   a_test_all = FALSE;
 909   lttv_option_add("testall", 'a', "Run all tests ", "",
 910       LTTV_OPT_NONE, &a_test_all, NULL, NULL);
 911
 912   traceset = lttv_traceset_new();
 913
 914   before_traceset = lttv_hooks_new();
 915   after_traceset = lttv_hooks_new();
 916   before_trace = lttv_hooks_new();
 917   after_trace = lttv_hooks_new();
 918   before_tracefile = lttv_hooks_new();
 919   after_tracefile = lttv_hooks_new();
 920   //before_event = lttv_hooks_new();
 921   //after_event = lttv_hooks_new();
 922   event_hook = lttv_hooks_new();
 923
 924
 925   g_assert(lttv_iattribute_find_by_path(attributes, "hooks/traceset/before",
 926       LTTV_POINTER, &value));
 927   *(value.v_pointer) = before_traceset;
 928   g_assert(lttv_iattribute_find_by_path(attributes, "hooks/traceset/after",
 929       LTTV_POINTER, &value));
 930   *(value.v_pointer) = after_traceset;
 931   g_assert(lttv_iattribute_find_by_path(attributes, "hooks/trace/before",
 932       LTTV_POINTER, &value));
 933   *(value.v_pointer) = before_trace;
 934   g_assert(lttv_iattribute_find_by_path(attributes, "hooks/trace/after",
 935       LTTV_POINTER, &value));
 936   *(value.v_pointer) = after_trace;
 937   g_assert(lttv_iattribute_find_by_path(attributes, "hooks/tracefile/before",
 938       LTTV_POINTER, &value));
 939   *(value.v_pointer) = before_tracefile;
 940   g_assert(lttv_iattribute_find_by_path(attributes, "hooks/tracefile/after",
 941       LTTV_POINTER, &value));
 942   *(value.v_pointer) = after_tracefile;
 943   //g_assert(lttv_iattribute_find_by_path(attributes, "hooks/event/before",
 944   //    LTTV_POINTER, &value));
 945   //*(value.v_pointer) = before_event;
 946   //g_assert(lttv_iattribute_find_by_path(attributes, "hooks/event/after",
 947   //    LTTV_POINTER, &value));
 948   //*(value.v_pointer) = after_event;
 949   g_assert(lttv_iattribute_find_by_path(attributes, "hooks/event",
 950       LTTV_POINTER, &value));
 951   *(value.v_pointer) = event_hook;
 952   g_assert(lttv_iattribute_find_by_path(attributes, "hooks/main/before",
 953       LTTV_POINTER, &value));
 954   g_assert((main_hooks = *(value.v_pointer)) != NULL);
 955   lttv_hooks_add(main_hooks, process_traceset, NULL, LTTV_PRIO_DEFAULT);
 956 }
 957
 958
 959 static void destroy()
 960 {
 961   guint i, nb;
 962
 963   LttvTrace *trace;
 964
 965   g_info("Destroy batchAnalysis.c");
 966
 967   lttv_option_remove("trace");
 968   lttv_option_remove("dump-tracefiles");
 969   lttv_option_remove("save-sample");
 970   lttv_option_remove("save-state-copy");
 971   lttv_option_remove("sample-interval");
 972   lttv_option_remove("sample-number");
 973   lttv_option_remove("seek-number");
 974   lttv_option_remove("save-interval");
 975   lttv_option_remove("test1");
 976   lttv_option_remove("test2");
 977   lttv_option_remove("test3");
 978   lttv_option_remove("test4");
 979   lttv_option_remove("test5");
 980   lttv_option_remove("test6");
 981   lttv_option_remove("test7");
 982   lttv_option_remove("test8");
 983   lttv_option_remove("test9");
 984   lttv_option_remove("test10");
 985   lttv_option_remove("testall");
 986
 987   lttv_hooks_destroy(before_traceset);
 988   lttv_hooks_destroy(after_traceset);
 989   lttv_hooks_destroy(before_trace);
 990   lttv_hooks_destroy(after_trace);
 991   lttv_hooks_destroy(before_tracefile);
 992   lttv_hooks_destroy(after_tracefile);
 993   //lttv_hooks_destroy(before_event);
 994   //lttv_hooks_destroy(after_event);
 995   lttv_hooks_destroy(event_hook);
 996   lttv_hooks_remove_data(main_hooks, process_traceset, NULL);
 997
 998   nb = lttv_traceset_number(traceset);
 999   for(i = 0 ; i < nb ; i++) {
1000     trace = lttv_traceset_get(traceset, i);
1001     lttv_traceset_remove(traceset,i);
1002     ltt_trace_close(lttv_trace(trace));
1003     lttv_trace_destroy(trace);
1004   }
1005
1006   lttv_traceset_destroy(traceset);
1007 }
1008
1009
1010 LTTV_MODULE("batchtest", "Batch processing of a trace for tests", \
1011     "Run through a trace calling all the registered hooks for tests", \
1012     init, destroy, "state", "stats", "option" )