src/smpi/internals/smpi_bench.cpp

   1 /* Copyright (c) 2007, 2009-2017. The SimGrid Team. All rights reserved.    */
   2
   3 /* This program is free software; you can redistribute it and/or modify it
   4  * under the terms of the license (GNU LGPL) which comes with this package. */
   5
   6 #include "private.h"
   7 #include "private.hpp"
   8 #include "simgrid/modelchecker.h"
   9 #include "smpi_comm.hpp"
  10 #include "smpi_process.hpp"
  11 #include "src/internal_config.h"
  12 #include "src/mc/mc_replay.h"
  13 #include <unordered_map>
  14
  15 #ifndef WIN32
  16 #include <sys/mman.h>
  17 #endif
  18 #include <cmath>
  19
  20 #if HAVE_PAPI
  21 #include <papi.h>
  22 #endif
  23
  24 XBT_LOG_NEW_DEFAULT_SUBCATEGORY(smpi_bench, smpi, "Logging specific to SMPI (benchmarking)");
  25
  26 double smpi_cpu_threshold = -1;
  27 double smpi_host_speed;
  28
  29 shared_malloc_type smpi_cfg_shared_malloc = shmalloc_global;
  30 double smpi_total_benched_time = 0;
  31 smpi_privatization_region_t smpi_privatization_regions;
  32
  33 extern "C" XBT_PUBLIC(void) smpi_execute_flops_(double *flops);
  34 void smpi_execute_flops_(double *flops)
  35 {
  36   smpi_execute_flops(*flops);
  37 }
  38
  39 extern "C" XBT_PUBLIC(void) smpi_execute_(double *duration);
  40 void smpi_execute_(double *duration)
  41 {
  42   smpi_execute(*duration);
  43 }
  44
  45 void smpi_execute_flops(double flops) {
  46   XBT_DEBUG("Handle real computation time: %f flops", flops);
  47   smx_activity_t action = simcall_execution_start("computation", flops, 1, 0);
  48   simcall_set_category (action, TRACE_internal_smpi_get_category());
  49   simcall_execution_wait(action);
  50   smpi_switch_data_segment(smpi_process()->index());
  51 }
  52
  53 void smpi_execute(double duration)
  54 {
  55   if (duration >= smpi_cpu_threshold) {
  56     XBT_DEBUG("Sleep for %g to handle real computation time", duration);
  57     double flops = duration * smpi_host_speed;
  58     int rank = smpi_process()->index();
  59     instr_extra_data extra = xbt_new0(s_instr_extra_data_t,1);
  60     extra->type=TRACING_COMPUTING;
  61     extra->comp_size=flops;
  62     TRACE_smpi_computing_in(rank, extra);
  63
  64     smpi_execute_flops(flops);
  65
  66     TRACE_smpi_computing_out(rank);
  67
  68   } else {
  69     XBT_DEBUG("Real computation took %g while option smpi/cpu-threshold is set to %g => ignore it", duration,
  70               smpi_cpu_threshold);
  71   }
  72 }
  73
  74 void smpi_execute_benched(double duration)
  75 {
  76   smpi_bench_end();
  77   smpi_execute(duration);
  78   smpi_bench_begin();
  79 }
  80
  81 void smpi_bench_begin()
  82 {
  83   if (smpi_privatize_global_variables == SMPI_PRIVATIZE_MMAP) {
  84     smpi_switch_data_segment(smpi_process()->index());
  85   }
  86
  87   if (MC_is_active() || MC_record_replay_is_active())
  88     return;
  89
  90 #if HAVE_PAPI
  91   if (xbt_cfg_get_string("smpi/papi-events")[0] != '\0') {
  92     int event_set = smpi_process()->papi_event_set();
  93     // PAPI_start sets everything to 0! See man(3) PAPI_start
  94     if (PAPI_LOW_LEVEL_INITED == PAPI_is_initialized()) {
  95       if (PAPI_start(event_set) != PAPI_OK) {
  96         // TODO This needs some proper handling.
  97         XBT_CRITICAL("Could not start PAPI counters.\n");
  98         xbt_die("Error.");
  99       }
 100     }
 101   }
 102 #endif
 103   xbt_os_threadtimer_start(smpi_process()->timer());
 104 }
 105
 106 void smpi_bench_end()
 107 {
 108   if (MC_is_active() || MC_record_replay_is_active())
 109     return;
 110
 111   double speedup = 1;
 112   xbt_os_timer_t timer = smpi_process()->timer();
 113   xbt_os_threadtimer_stop(timer);
 114
 115 #if HAVE_PAPI
 116   /**
 117    * An MPI function has been called and now is the right time to update
 118    * our PAPI counters for this process.
 119    */
 120   if (xbt_cfg_get_string("smpi/papi-events")[0] != '\0') {
 121     papi_counter_t& counter_data        = smpi_process()->papi_counters();
 122     int event_set                       = smpi_process()->papi_event_set();
 123     std::vector<long long> event_values = std::vector<long long>(counter_data.size());
 124
 125     if (PAPI_stop(event_set, &event_values[0]) != PAPI_OK) { // Error
 126       XBT_CRITICAL("Could not stop PAPI counters.\n");
 127       xbt_die("Error.");
 128     } else {
 129       for (unsigned int i = 0; i < counter_data.size(); i++) {
 130         counter_data[i].second += event_values[i];
 131         // XBT_DEBUG("[%i] PAPI: Counter %s: Value is now %lli (got increment by %lli\n", smpi_process()->index(),
 132         // counter_data[i].first.c_str(), counter_data[i].second, event_values[i]);
 133       }
 134     }
 135   }
 136 #endif
 137
 138   if (smpi_process()->sampling()) {
 139     XBT_CRITICAL("Cannot do recursive benchmarks.");
 140     XBT_CRITICAL("Are you trying to make a call to MPI within a SMPI_SAMPLE_ block?");
 141     xbt_backtrace_display_current();
 142     xbt_die("Aborting.");
 143   }
 144
 145   if (xbt_cfg_get_string("smpi/comp-adjustment-file")[0] != '\0') { // Maybe we need to artificially speed up or slow
 146     // down our computation based on our statistical analysis.
 147
 148     smpi_trace_call_location_t* loc                            = smpi_process()->call_location();
 149     std::string key                                            = loc->get_composed_key();
 150     std::unordered_map<std::string, double>::const_iterator it = location2speedup.find(key);
 151     if (it != location2speedup.end()) {
 152       speedup = it->second;
 153     }
 154   }
 155
 156   // Simulate the benchmarked computation unless disabled via command-line argument
 157   if (xbt_cfg_get_boolean("smpi/simulate-computation")) {
 158     smpi_execute(xbt_os_timer_elapsed(timer)/speedup);
 159   }
 160
 161 #if HAVE_PAPI
 162   if (xbt_cfg_get_string("smpi/papi-events")[0] != '\0' && TRACE_smpi_is_enabled()) {
 163     char container_name[INSTR_DEFAULT_STR_SIZE];
 164     smpi_container(smpi_process()->index(), container_name, INSTR_DEFAULT_STR_SIZE);
 165     container_t container        = PJ_container_get(container_name);
 166     papi_counter_t& counter_data = smpi_process()->papi_counters();
 167
 168     for (auto& pair : counter_data) {
 169       new_pajeSetVariable(surf_get_clock(), container,
 170                           PJ_type_get(/* countername */ pair.first.c_str(), container->type), pair.second);
 171     }
 172   }
 173 #endif
 174
 175   smpi_total_benched_time += xbt_os_timer_elapsed(timer);
 176 }
 177
 178 /* Private sleep function used by smpi_sleep() and smpi_usleep() */
 179 static unsigned int private_sleep(double secs)
 180 {
 181   smpi_bench_end();
 182
 183   XBT_DEBUG("Sleep for: %lf secs", secs);
 184   int rank = MPI_COMM_WORLD->rank();
 185   instr_extra_data extra = xbt_new0(s_instr_extra_data_t,1);
 186   extra->type=TRACING_SLEEPING;
 187   extra->sleep_duration=secs;
 188   TRACE_smpi_sleeping_in(rank, extra);
 189
 190   simcall_process_sleep(secs);
 191
 192   TRACE_smpi_sleeping_out(rank);
 193
 194   smpi_bench_begin();
 195   return 0;
 196 }
 197
 198 unsigned int smpi_sleep(unsigned int secs)
 199 {
 200   return private_sleep(static_cast<double>(secs));
 201 }
 202
 203 int smpi_usleep(useconds_t usecs)
 204 {
 205   return static_cast<int>(private_sleep(static_cast<double>(usecs) / 1000000.0));
 206 }
 207
 208 #if _POSIX_TIMERS > 0
 209 int smpi_nanosleep(const struct timespec *tp, struct timespec * t)
 210 {
 211   return static_cast<int>(private_sleep(static_cast<double>(tp->tv_sec + tp->tv_nsec / 1000000000.0)));
 212 }
 213 #endif
 214
 215 int smpi_gettimeofday(struct timeval *tv, void* tz)
 216 {
 217   smpi_bench_end();
 218   double now = SIMIX_get_clock();
 219   if (tv) {
 220     tv->tv_sec = static_cast<time_t>(now);
 221 #ifdef WIN32
 222     tv->tv_usec = static_cast<useconds_t>((now - tv->tv_sec) * 1e6);
 223 #else
 224     tv->tv_usec = static_cast<suseconds_t>((now - tv->tv_sec) * 1e6);
 225 #endif
 226   }
 227   smpi_bench_begin();
 228   return 0;
 229 }
 230
 231 #if _POSIX_TIMERS > 0
 232 int smpi_clock_gettime(clockid_t clk_id, struct timespec *tp)
 233 {
 234   //there is only one time in SMPI, so clk_id is ignored.
 235   smpi_bench_end();
 236   double now = SIMIX_get_clock();
 237   if (tp) {
 238     tp->tv_sec = static_cast<time_t>(now);
 239     tp->tv_nsec = static_cast<long int>((now - tp->tv_sec) * 1e9);
 240   }
 241   smpi_bench_begin();
 242   return 0;
 243 }
 244 #endif
 245
 246 extern double sg_surf_precision;
 247 unsigned long long smpi_rastro_resolution ()
 248 {
 249   smpi_bench_end();
 250   double resolution = (1/sg_surf_precision);
 251   smpi_bench_begin();
 252   return static_cast<unsigned long long>(resolution);
 253 }
 254
 255 unsigned long long smpi_rastro_timestamp ()
 256 {
 257   smpi_bench_end();
 258   double now = SIMIX_get_clock();
 259
 260   unsigned long long sec = static_cast<unsigned long long>(now);
 261   unsigned long long pre = (now - sec) * smpi_rastro_resolution();
 262   smpi_bench_begin();
 263   return static_cast<unsigned long long>(sec) * smpi_rastro_resolution() + pre;
 264 }
 265
 266 /* ****************************** Functions related to the SMPI_SAMPLE_ macros ************************************/
 267 typedef struct {
 268   double threshold; /* maximal stderr requested (if positive) */
 269   double relstderr; /* observed stderr so far */
 270   double mean;      /* mean of benched times, to be used if the block is disabled */
 271   double sum;       /* sum of benched times (to compute the mean and stderr) */
 272   double sum_pow2;  /* sum of the square of the benched times (to compute the stderr) */
 273   int iters;        /* amount of requested iterations */
 274   int count;        /* amount of iterations done so far */
 275   int benching;     /* 1: we are benchmarking; 0: we have enough data, no bench anymore */
 276 } local_data_t;
 277
 278 std::unordered_map<std::string, local_data_t*> samples; /* Allocated on first use */
 279
 280 static std::string sample_location(int global, const char* file, int line)
 281 {
 282   if (global) {
 283     return std::string(file) + ":" + std::to_string(line);
 284   } else {
 285     return std::string(file) + ":" + std::to_string(line) + ":" + std::to_string(smpi_process()->index());
 286   }
 287 }
 288
 289 static int sample_enough_benchs(local_data_t *data) {
 290   int res = data->count >= data->iters;
 291   if (data->threshold>0.0) {
 292     if (data->count <2)
 293       res = 0; // not enough data
 294     if (data->relstderr > data->threshold)
 295       res = 0; // stderr too high yet
 296   }
 297   XBT_DEBUG("%s (count:%d iter:%d stderr:%f thres:%f mean:%fs)",
 298       (res?"enough benchs":"need more data"), data->count, data->iters, data->relstderr, data->threshold, data->mean);
 299   return res;
 300 }
 301
 302 void smpi_sample_1(int global, const char *file, int line, int iters, double threshold)
 303 {
 304   std::string loc = sample_location(global, file, line);
 305
 306   smpi_bench_end();     /* Take time from previous, unrelated computation into account */
 307   smpi_process()->set_sampling(1);
 308
 309   auto ld = samples.find(loc);
 310   local_data_t* data;
 311   if (ld == samples.end()) {
 312     xbt_assert(threshold>0 || iters>0,
 313         "You should provide either a positive amount of iterations to bench, or a positive maximal stderr (or both)");
 314     data            = static_cast<local_data_t*>(xbt_new(local_data_t, 1));
 315     data->count = 0;
 316     data->sum = 0.0;
 317     data->sum_pow2 = 0.0;
 318     data->iters = iters;
 319     data->threshold = threshold;
 320     data->benching = 1; // If we have no data, we need at least one
 321     data->mean = 0;
 322     samples[loc]    = data;
 323     XBT_DEBUG("XXXXX First time ever on benched nest %s.", loc.c_str());
 324   } else {
 325     data = ld->second;
 326     if (data->iters != iters || data->threshold != threshold) {
 327       XBT_ERROR("Asked to bench block %s with different settings %d, %f is not %d, %f. "
 328                 "How did you manage to give two numbers at the same line??",
 329                 loc.c_str(), data->iters, data->threshold, iters, threshold);
 330       THROW_IMPOSSIBLE;
 331     }
 332
 333     // if we already have some data, check whether sample_2 should get one more bench or whether it should emulate
 334     // the computation instead
 335     data->benching = (sample_enough_benchs(data) == 0);
 336     XBT_DEBUG("XXXX Re-entering the benched nest %s. %s", loc.c_str(),
 337               (data->benching ? "more benching needed" : "we have enough data, skip computes"));
 338   }
 339 }
 340
 341 int smpi_sample_2(int global, const char *file, int line)
 342 {
 343   std::string loc = sample_location(global, file, line);
 344   int res;
 345
 346   xbt_assert(not samples.empty(),
 347              "Y U NO use SMPI_SAMPLE_* macros? Stop messing directly with smpi_sample_* functions!");
 348   local_data_t* data = samples.at(loc);
 349   XBT_DEBUG("sample2 %s", loc.c_str());
 350
 351   if (data->benching==1) {
 352     // we need to run a new bench
 353     XBT_DEBUG("benchmarking: count:%d iter:%d stderr:%f thres:%f; mean:%f",
 354         data->count, data->iters, data->relstderr, data->threshold, data->mean);
 355     res = 1;
 356   } else {
 357     // Enough data, no more bench (either we got enough data from previous visits to this benched nest, or we just
 358     //ran one bench and need to bail out now that our job is done). Just sleep instead
 359     XBT_DEBUG("No benchmark (either no need, or just ran one): count >= iter (%d >= %d) or stderr<thres (%f<=%f)."
 360               " apply the %fs delay instead",
 361               data->count, data->iters, data->relstderr, data->threshold, data->mean);
 362     smpi_execute(data->mean);
 363     smpi_process()->set_sampling(0);
 364     res = 0; // prepare to capture future, unrelated computations
 365   }
 366   smpi_bench_begin();
 367   return res;
 368 }
 369
 370 void smpi_sample_3(int global, const char *file, int line)
 371 {
 372   std::string loc = sample_location(global, file, line);
 373
 374   xbt_assert(not samples.empty(),
 375              "Y U NO use SMPI_SAMPLE_* macros? Stop messing directly with smpi_sample_* functions!");
 376   local_data_t* data = samples.at(loc);
 377   XBT_DEBUG("sample3 %s", loc.c_str());
 378
 379   if (data->benching==0)
 380     THROW_IMPOSSIBLE;
 381
 382   // ok, benchmarking this loop is over
 383   xbt_os_threadtimer_stop(smpi_process()->timer());
 384
 385   // update the stats
 386   data->count++;
 387   double sample = xbt_os_timer_elapsed(smpi_process()->timer());
 388   data->sum += sample;
 389   data->sum_pow2 += sample * sample;
 390   double n = static_cast<double>(data->count);
 391   data->mean = data->sum / n;
 392   data->relstderr = sqrt((data->sum_pow2 / n - data->mean * data->mean) / n) / data->mean;
 393   if (sample_enough_benchs(data)==0) {
 394     data->mean = sample; // Still in benching process; We want sample_2 to simulate the exact time of this loop
 395     // occurrence before leaving, not the mean over the history
 396   }
 397   XBT_DEBUG("Average mean after %d steps is %f, relative standard error is %f (sample was %f)", data->count,
 398       data->mean, data->relstderr, sample);
 399
 400   // That's enough for now, prevent sample_2 to run the same code over and over
 401   data->benching = 0;
 402 }
 403
 404 extern "C" { /** These functions will be called from the user code **/
 405 smpi_trace_call_location_t* smpi_trace_get_call_location()
 406 {
 407   return smpi_process()->call_location();
 408 }
 409
 410 void smpi_trace_set_call_location(const char* file, const int line)
 411 {
 412   smpi_trace_call_location_t* loc = smpi_process()->call_location();
 413
 414   loc->previous_filename   = loc->filename;
 415   loc->previous_linenumber = loc->linenumber;
 416   loc->filename            = file;
 417   loc->linenumber          = line;
 418 }
 419
 420 /** Required for Fortran bindings */
 421 void smpi_trace_set_call_location_(const char* file, int* line)
 422 {
 423   smpi_trace_set_call_location(file, *line);
 424 }
 425
 426 /** Required for Fortran if -fsecond-underscore is activated */
 427 void smpi_trace_set_call_location__(const char* file, int* line)
 428 {
 429   smpi_trace_set_call_location(file, *line);
 430 }
 431 }
 432
 433 void smpi_bench_destroy()
 434 {
 435   for (auto elm : samples)
 436     xbt_free(elm.second);
 437 }