chrometracing_logger.cc 25.8 KB
Newer Older
1 2 3 4 5 6 7 8 9 10 11 12 13 14
/* Copyright (c) 2022 PaddlePaddle Authors. All Rights Reserved.

licensed under the Apache License, Version 2.0 (the "License");
you may not use this file except in compliance with the License.
You may obtain a copy of the License at

    http://www.apache.org/licenses/LICENSE-2.0

Unless required by applicable law or agreed to in writing, software
distributed under the License is distributed on an "AS IS" BASIS,
WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
See the License for the specific language governing permissions and
limitations under the License. */

15 16
#include "paddle/fluid/platform/profiler/chrometracing_logger.h"

17 18
#include <cstdio>
#include <ctime>
19
#include <limits>
20
#include <regex>
21 22 23

#include "glog/logging.h"
#include "paddle/fluid/platform/device/gpu/gpu_info.h"
C
chenjian 已提交
24
#include "paddle/fluid/platform/enforce.h"
25
#include "paddle/fluid/platform/profiler/event_node.h"
C
chenjian 已提交
26
#include "paddle/fluid/platform/profiler/utils.h"
27 28 29 30

namespace paddle {
namespace platform {

C
chenjian 已提交
31
static const char* kSchemaVersion = "1.0.1";
32
static const char* kDefaultFilename = "pid_%s_time_%s.paddle_trace.json";
C
chenjian 已提交
33
static uint32_t span_indx = 0;
34 35 36

static std::string DefaultFileName() {
  auto pid = GetProcessId();
37 38
  return string_format(
      std::string(kDefaultFilename), pid, GetStringFormatLocalTime().c_str());
39 40 41 42 43 44
}

void ChromeTracingLogger::OpenFile() {
  output_file_stream_.open(filename_,
                           std::ofstream::out | std::ofstream::trunc);
  if (!output_file_stream_) {
C
chenjian 已提交
45 46
    LOG(WARNING) << "Unable to open file for writing profiling data."
                 << std::endl;
47
  } else {
C
chenjian 已提交
48
    LOG(INFO) << "writing profiling data to " << filename_ << std::endl;
49 50 51 52 53 54 55 56 57 58 59 60 61 62 63 64 65 66 67 68 69 70 71 72 73
  }
}

ChromeTracingLogger::ChromeTracingLogger(const std::string& filename) {
  filename_ = filename.empty() ? DefaultFileName() : filename;
  OpenFile();
  StartLog();
}

ChromeTracingLogger::ChromeTracingLogger(const char* filename_cstr) {
  std::string filename(filename_cstr);
  filename_ = filename.empty() ? DefaultFileName() : filename;
  OpenFile();
  StartLog();
}

ChromeTracingLogger::~ChromeTracingLogger() {
  EndLog();
  output_file_stream_.close();
}

void ChromeTracingLogger::LogNodeTrees(const NodeTrees& node_trees) {
  // log all nodes except root node, root node is a helper node.
  const std::map<uint64_t, std::vector<HostTraceEventNode*>>
      thread2host_event_nodes = node_trees.Traverse(true);
74 75 76
  // find the earliest time in current timeline
  start_time_ = std::numeric_limits<uint64_t>::max();
  for (auto it = thread2host_event_nodes.begin();
77 78
       it != thread2host_event_nodes.end();
       ++it) {
79 80 81 82 83 84 85 86 87 88 89 90 91 92 93 94
    if (it->second.begin() + 1 != it->second.end()) {
      if ((*(it->second.begin() + 1))->StartNs() < start_time_) {
        start_time_ = (*(it->second.begin() + 1))->StartNs();
      }
    } else {
      auto runtimenode =
          (*(it->second.begin()))->GetRuntimeTraceEventNodes().begin();
      if (runtimenode !=
          (*(it->second.begin()))->GetRuntimeTraceEventNodes().end()) {
        if ((*runtimenode)->StartNs() < start_time_) {
          start_time_ = (*runtimenode)->StartNs();
        }
      }
    }
  }

95
  for (auto it = thread2host_event_nodes.begin();
96 97
       it != thread2host_event_nodes.end();
       ++it) {
98 99 100 101 102 103 104 105 106 107 108 109 110 111 112 113
    for (auto hostnode = it->second.begin(); hostnode != it->second.end();
         ++hostnode) {
      if (hostnode != it->second.begin()) {  // skip root node
        (*hostnode)->LogMe(this);
      }
      for (auto runtimenode = (*hostnode)->GetRuntimeTraceEventNodes().begin();
           runtimenode != (*hostnode)->GetRuntimeTraceEventNodes().end();
           ++runtimenode) {
        (*runtimenode)->LogMe(this);
        for (auto devicenode =
                 (*runtimenode)->GetDeviceTraceEventNodes().begin();
             devicenode != (*runtimenode)->GetDeviceTraceEventNodes().end();
             ++devicenode) {
          (*devicenode)->LogMe(this);
        }
      }
C
chenjian 已提交
114
      for (auto memnode = (*hostnode)->GetMemTraceEventNodes().begin();
115 116
           memnode != (*hostnode)->GetMemTraceEventNodes().end();
           ++memnode) {
C
chenjian 已提交
117 118
        (*memnode)->LogMe(this);
      }
119 120 121 122
    }
  }
}

C
chenjian 已提交
123 124 125 126 127 128 129 130 131
void ChromeTracingLogger::LogMemTraceEventNode(
    const MemTraceEventNode& mem_node) {
  if (!output_file_stream_) {
    return;
  }
  output_file_stream_ << string_format(
      std::string(
          R"JSON(
  { 
132
    "name": "[memory]", "pid": %lld, "tid": "%lld(C++)",
C
chenjian 已提交
133 134 135 136 137
    "ts": %lld, 
    "ph": "i", "cat": "%s", 
    "args": {
      "place": "%s",
      "addr": "%llu",
138
      "increase_bytes": %lld,
C
chenjian 已提交
139 140
      "current_allocated": %llu,
      "current_reserved": %llu,
141 142
      "peak_allocated": %llu,
      "peak_reserved": %llu
C
chenjian 已提交
143 144 145
    }
  },
  )JSON"),
146 147 148 149 150 151 152 153 154 155
      mem_node.ProcessId(),
      mem_node.ThreadId(),
      nsToUs(mem_node.TimeStampNs()),
      StringTracerMemEventType(mem_node.Type()),
      mem_node.Place().c_str(),
      mem_node.Addr(),
      mem_node.IncreaseBytes(),
      mem_node.CurrentAllocated(),
      mem_node.CurrentReserved(),
      mem_node.PeakAllocated(),
156 157
      mem_node.PeakReserved());
  pid_tid_set_.insert({mem_node.ProcessId(), mem_node.ThreadId()});
C
chenjian 已提交
158 159
}

160 161 162 163 164
void ChromeTracingLogger::LogHostTraceEventNode(
    const HostTraceEventNode& host_node) {
  if (!output_file_stream_) {
    return;
  }
165 166 167 168 169 170 171
  std::string dur_display;
  float dur = nsToMsFloat(host_node.Duration());
  if (dur > 1.0) {
    dur_display = string_format(std::string("%.3f ms"), dur);
  } else {
    dur_display = string_format(std::string("%.3f us"), dur * 1000);
  }
C
chenjian 已提交
172 173 174 175 176 177 178 179 180
  std::map<std::string, std::vector<std::vector<int64_t>>> input_shapes;
  std::map<std::string, std::vector<std::string>> input_dtypes;
  std::string callstack;
  OperatorSupplementEventNode* op_supplement_node =
      host_node.GetOperatorSupplementEventNode();
  if (op_supplement_node != nullptr) {
    input_shapes = op_supplement_node->InputShapes();
    input_dtypes = op_supplement_node->Dtypes();
    callstack = op_supplement_node->CallStack();
181 182
    callstack = std::regex_replace(callstack, std::regex("\""), "\'");
    callstack = std::regex_replace(callstack, std::regex("\n"), "\\n");
C
chenjian 已提交
183
  }
C
chenjian 已提交
184 185 186 187 188 189 190 191
  switch (host_node.Type()) {
    case TracerEventType::ProfileStep:
    case TracerEventType::Forward:
    case TracerEventType::Backward:
    case TracerEventType::Dataloader:
    case TracerEventType::Optimization:
    case TracerEventType::PythonOp:
    case TracerEventType::PythonUserDefined:
192 193
      // cname value comes from tracing.js reservedColorsByName variable

C
chenjian 已提交
194 195 196
      output_file_stream_ << string_format(
          std::string(
              R"JSON(
197
  { 
198 199
    "name": "%s[%s]", "pid": %lld, "tid": "%lld(Python)",
    "ts": %lld, "dur": %.3f,
200
    "ph": "X", "cat": "%s", 
201
    "cname": "thread_state_runnable",
202
    "args": {
203 204
      "start_time": "%.3f us",
      "end_time": "%.3f us"
205 206 207
    }
  },
  )JSON"),
208 209 210 211 212
          host_node.Name().c_str(),
          dur_display.c_str(),
          host_node.ProcessId(),
          host_node.ThreadId(),
          nsToUs(host_node.StartNs()),
213
          nsToUsFloat(host_node.Duration()),
C
chenjian 已提交
214
          StringTracerEventType(host_node.Type()),
215 216
          nsToUsFloat(host_node.StartNs(), start_time_),
          nsToUsFloat(host_node.EndNs(), start_time_));
C
chenjian 已提交
217
      break;
C
chenjian 已提交
218 219 220 221 222 223 224 225 226 227 228 229 230 231 232 233 234 235 236 237

    case TracerEventType::Operator:

      output_file_stream_ << string_format(
          std::string(
              R"JSON(
  { 
    "name": "%s[%s]", "pid": %lld, "tid": "%lld(C++)",
    "ts": %lld, "dur": %.3f,
    "ph": "X", "cat": "%s", 
    "cname": "thread_state_runnable",
    "args": {
      "start_time": "%.3f us",
      "end_time": "%.3f us",
      "input_shapes": %s,
      "input_dtypes": %s,
      "callstack": "%s"
    }
  },
  )JSON"),
238 239 240 241 242
          host_node.Name().c_str(),
          dur_display.c_str(),
          host_node.ProcessId(),
          host_node.ThreadId(),
          nsToUs(host_node.StartNs()),
C
chenjian 已提交
243 244 245 246
          nsToUsFloat(host_node.Duration()),
          StringTracerEventType(host_node.Type()),
          nsToUsFloat(host_node.StartNs(), start_time_),
          nsToUsFloat(host_node.EndNs(), start_time_),
247 248
          json_dict(input_shapes).c_str(),
          json_dict(input_dtypes).c_str(),
C
chenjian 已提交
249 250 251 252 253 254 255 256 257 258 259
          callstack.c_str());
      break;
    case TracerEventType::CudaRuntime:
    case TracerEventType::Kernel:
    case TracerEventType::Memcpy:
    case TracerEventType::Memset:
    case TracerEventType::UserDefined:
    case TracerEventType::OperatorInner:
    case TracerEventType::Communication:
    case TracerEventType::MluRuntime:
    case TracerEventType::NumTypes:
C
chenjian 已提交
260 261 262 263 264
    default:
      output_file_stream_ << string_format(
          std::string(
              R"JSON(
  { 
265 266
    "name": "%s[%s]", "pid": %lld, "tid": "%lld(C++)",
    "ts": %lld, "dur": %.3f,
C
chenjian 已提交
267
    "ph": "X", "cat": "%s", 
268
    "cname": "thread_state_runnable",
C
chenjian 已提交
269
    "args": {
270 271
      "start_time": "%.3f us",
      "end_time": "%.3f us"
C
chenjian 已提交
272 273 274
    }
  },
  )JSON"),
275 276 277 278 279
          host_node.Name().c_str(),
          dur_display.c_str(),
          host_node.ProcessId(),
          host_node.ThreadId(),
          nsToUs(host_node.StartNs()),
280
          nsToUsFloat(host_node.Duration()),
C
chenjian 已提交
281
          StringTracerEventType(host_node.Type()),
282 283
          nsToUsFloat(host_node.StartNs(), start_time_),
          nsToUsFloat(host_node.EndNs(), start_time_));
C
chenjian 已提交
284 285 286 287
      break;
  }

  pid_tid_set_.insert({host_node.ProcessId(), host_node.ThreadId()});
288 289 290 291 292 293 294
}

void ChromeTracingLogger::LogRuntimeTraceEventNode(
    const CudaRuntimeTraceEventNode& runtime_node) {
  if (!output_file_stream_) {
    return;
  }
295 296 297 298 299 300 301
  float dur = nsToMsFloat(runtime_node.Duration());
  std::string dur_display;
  if (dur > 1.0) {
    dur_display = string_format(std::string("%.3f ms"), dur);
  } else {
    dur_display = string_format(std::string("%.3f us"), dur * 1000);
  }
302 303 304 305
  output_file_stream_ << string_format(
      std::string(
          R"JSON(
  { 
306 307
    "name": "%s[%s]", "pid": %lld, "tid": "%lld(C++)",
    "ts": %lld, "dur": %.3f,
308
    "ph": "X", "cat": "%s", 
309
    "cname": "thread_state_running",
310
    "args": {
C
chenjian 已提交
311
      "correlation id": %d,
312 313
      "start_time": "%.3f us",
      "end_time": "%.3f us"
314 315 316
    }
  },
  )JSON"),
317 318 319 320 321 322 323 324
      runtime_node.Name().c_str(),
      dur_display.c_str(),
      runtime_node.ProcessId(),
      runtime_node.ThreadId(),
      nsToUs(runtime_node.StartNs()),
      nsToUsFloat(runtime_node.Duration()),
      StringTracerEventType(runtime_node.Type()),
      runtime_node.CorrelationId(),
325 326
      nsToUsFloat(runtime_node.StartNs(), start_time_),
      nsToUsFloat(runtime_node.EndNs(), start_time_));
C
chenjian 已提交
327 328 329 330 331 332 333 334 335 336 337
  pid_tid_set_.insert({runtime_node.ProcessId(), runtime_node.ThreadId()});

  output_file_stream_ << string_format(
      std::string(
          R"JSON(
  { 
    "name": "launch", "id": %d, "pid": %lld, "tid": "%lld(C++)",
    "ts": %lld, 
    "ph": "s", "cat": "async"
  },
  )JSON"),
338 339
      runtime_node.CorrelationId(),
      runtime_node.ProcessId(),
C
chenjian 已提交
340 341 342
      runtime_node.ThreadId(),
      nsToUs((runtime_node.StartNs() + runtime_node.EndNs()) >> 1));
  pid_tid_set_.insert({runtime_node.ProcessId(), runtime_node.ThreadId()});
343 344 345 346 347 348 349
}

void ChromeTracingLogger::LogDeviceTraceEventNode(
    const DeviceTraceEventNode& device_node) {
  if (!output_file_stream_) {
    return;
  }
350

351 352 353 354 355 356 357 358 359 360 361 362
  switch (device_node.Type()) {
    case TracerEventType::Kernel:
      HandleTypeKernel(device_node);
      break;
    case TracerEventType::Memcpy:
      HandleTypeMemcpy(device_node);
      break;
    case TracerEventType::Memset:
      HandleTypeMemset(device_node);
    default:
      break;
  }
C
chenjian 已提交
363
  if (nsToUs(device_node.Duration()) == 0) {
364 365
    output_file_stream_ << string_format(std::string(
                                             R"JSON(
C
chenjian 已提交
366 367 368 369 370 371
  { 
    "name": "launch", "id": %d, "pid": %lld, "tid": %lld,
    "ts": %lld, 
    "ph": "f", "cat": "async"
  },
  )JSON"),
372 373 374 375
                                         device_node.CorrelationId(),
                                         device_node.DeviceId(),
                                         device_node.StreamId(),
                                         nsToUs(device_node.StartNs()));
C
chenjian 已提交
376 377 378 379 380 381 382 383 384 385 386 387
    deviceid_streamid_set_.insert(
        {device_node.DeviceId(), device_node.StreamId()});
  } else {
    output_file_stream_ << string_format(
        std::string(
            R"JSON(
  { 
    "name": "launch", "id": %d, "pid": %lld, "tid": %lld,
    "ts": %lld, 
    "ph": "f", "cat": "async", "bp": "e"
  },
  )JSON"),
388 389
        device_node.CorrelationId(),
        device_node.DeviceId(),
C
chenjian 已提交
390 391 392 393 394
        device_node.StreamId(),
        nsToUs((device_node.StartNs() + device_node.EndNs()) >> 1));
    deviceid_streamid_set_.insert(
        {device_node.DeviceId(), device_node.StreamId()});
  }
395 396 397 398 399 400 401 402
}

void ChromeTracingLogger::HandleTypeKernel(
    const DeviceTraceEventNode& device_node) {
  KernelEventInfo kernel_info = device_node.KernelInfo();
  float blocks_per_sm = 0.0;
  float warps_per_sm = 0.0;
  float occupancy = 0.0;
C
chenjian 已提交
403
#if defined(PADDLE_WITH_CUPTI)
404 405 406
#ifdef PADDLE_WITH_HIP
  constexpr int threads_per_warp = 64;
#else
407
  constexpr int threads_per_warp = 32;
408
#endif
409 410
  const gpuDeviceProp& device_property =
      GetDeviceProperties(device_node.DeviceId());
C
chenjian 已提交
411 412 413
  blocks_per_sm = static_cast<float>(kernel_info.grid_x * kernel_info.grid_y *
                                     kernel_info.grid_z) /
                  device_property.multiProcessorCount;
414 415 416 417
  warps_per_sm =
      blocks_per_sm *
      (kernel_info.block_x * kernel_info.block_y * kernel_info.block_z) /
      threads_per_warp;
418 419 420 421 422 423 424 425 426
#ifdef PADDLE_WITH_HIP
  occupancy = CalculateEstOccupancy(device_node.DeviceId(),
                                    kernel_info.dynamic_shared_memory,
                                    kernel_info.block_x,
                                    kernel_info.block_y,
                                    kernel_info.block_z,
                                    kernel_info.kernelFunc,
                                    kernel_info.launchType);
#else
427 428 429 430 431 432 433 434
  occupancy = CalculateEstOccupancy(device_node.DeviceId(),
                                    kernel_info.registers_per_thread,
                                    kernel_info.static_shared_memory,
                                    kernel_info.dynamic_shared_memory,
                                    kernel_info.block_x,
                                    kernel_info.block_y,
                                    kernel_info.block_z,
                                    blocks_per_sm);
435 436
#endif  // PADDLE_WITH_HIP

437
#endif
438 439 440 441 442 443 444
  float dur = nsToMsFloat(device_node.Duration());
  std::string dur_display;
  if (dur > 1.0) {
    dur_display = string_format(std::string("%.3f ms"), dur);
  } else {
    dur_display = string_format(std::string("%.3f us"), dur * 1000);
  }
445 446 447 448
  output_file_stream_ << string_format(
      std::string(
          R"JSON(
  { 
449 450
    "name": "%s[%s]", "pid": %lld, "tid": %lld,
    "ts": %lld, "dur": %.3f,
451
    "ph": "X", "cat": "%s", 
452
    "cname": "cq_build_failed",
453
    "args": {
454 455
      "start_time": "%.3f us",
      "end_time": "%.3f us",
456 457 458
      "device": %d, "context": %d,
      "stream": %d, "correlation id": %d,
      "registers per thread": %d,
C
chenjian 已提交
459
      "shared memory": %d,
460 461 462 463
      "blocks per SM": %f,
      "warps per SM": %f,
      "grid": [%d, %d, %d],
      "block": [%d, %d, %d],
464
      "theoretical achieved occupancy %%": %.3f
465 466 467
    }
  },
  )JSON"),
468 469 470 471 472
      device_node.Name().c_str(),
      dur_display.c_str(),
      device_node.DeviceId(),
      device_node.StreamId(),
      nsToUs(device_node.StartNs()),
473
      nsToUsFloat(device_node.Duration()),
C
chenjian 已提交
474
      StringTracerEventType(device_node.Type()),
475
      nsToUsFloat(device_node.StartNs(), start_time_),
476 477 478 479 480 481
      nsToUsFloat(device_node.EndNs(), start_time_),
      device_node.DeviceId(),
      device_node.ContextId(),
      device_node.StreamId(),
      device_node.CorrelationId(),
      kernel_info.registers_per_thread,
482
      kernel_info.static_shared_memory + kernel_info.dynamic_shared_memory,
483 484 485 486 487 488 489 490 491
      blocks_per_sm,
      warps_per_sm,
      kernel_info.grid_x,
      kernel_info.grid_y,
      kernel_info.grid_z,
      kernel_info.block_x,
      kernel_info.block_y,
      kernel_info.block_z,
      occupancy * 100);
492 493 494 495 496 497 498 499 500
}

void ChromeTracingLogger::HandleTypeMemcpy(
    const DeviceTraceEventNode& device_node) {
  MemcpyEventInfo memcpy_info = device_node.MemcpyInfo();
  float memory_bandwidth = 0;
  if (device_node.Duration() > 0) {
    memory_bandwidth = memcpy_info.num_bytes * 1.0 / device_node.Duration();
  }
501 502 503 504 505 506 507
  float dur = nsToMsFloat(device_node.Duration());
  std::string dur_display;
  if (dur > 1.0) {
    dur_display = string_format(std::string("%.3f ms"), dur);
  } else {
    dur_display = string_format(std::string("%.3f us"), dur * 1000);
  }
508 509 510 511
  output_file_stream_ << string_format(
      std::string(
          R"JSON(
  {
512 513
    "name": "%s[%s]", "pid": %lld, "tid": %lld,
    "ts": %lld, "dur": %.3f,
514
    "ph": "X", "cat": "%s", 
515
    "cname": "cq_build_failed",
516
    "args": {
517 518
      "start_time": "%.3f us",
      "end_time": "%.3f us",
519
      "stream": %d, "correlation id": %d,
520
      "bytes": %d, "memory bandwidth (GB/s)": %.3f
521 522 523
    }
  },
  )JSON"),
524 525 526 527 528
      device_node.Name().c_str(),
      dur_display.c_str(),
      device_node.DeviceId(),
      device_node.StreamId(),
      nsToUs(device_node.StartNs()),
529
      nsToUsFloat(device_node.Duration()),
C
chenjian 已提交
530
      StringTracerEventType(device_node.Type()),
531
      nsToUsFloat(device_node.StartNs(), start_time_),
532 533 534 535 536
      nsToUsFloat(device_node.EndNs(), start_time_),
      device_node.StreamId(),
      device_node.CorrelationId(),
      memcpy_info.num_bytes,
      memory_bandwidth);
537 538 539 540 541
}

void ChromeTracingLogger::HandleTypeMemset(
    const DeviceTraceEventNode& device_node) {
  MemsetEventInfo memset_info = device_node.MemsetInfo();
542 543 544 545 546 547 548
  float dur = nsToMsFloat(device_node.Duration());
  std::string dur_display;
  if (dur > 1.0) {
    dur_display = string_format(std::string("%.3f ms"), dur);
  } else {
    dur_display = string_format(std::string("%.3f us"), dur * 1000);
  }
549 550 551 552
  output_file_stream_ << string_format(
      std::string(
          R"JSON(
  {
553 554
    "name": "%s[%s]", "pid": %lld, "tid": %lld,
    "ts": %lld, "dur": %.3f,
555
    "ph": "X", "cat": "%s", 
556
    "cname": "cq_build_failed",
557
    "args": {
558 559
      "start_time": "%.3f us",
      "end_time": "%.3f us",
560 561 562 563 564 565
      "device": %d, "context": %d,
      "stream": %d, "correlation id": %d,
      "bytes": %d, "value": %d
    }
  },
  )JSON"),
566 567 568 569 570
      device_node.Name().c_str(),
      dur_display.c_str(),
      device_node.DeviceId(),
      device_node.StreamId(),
      nsToUs(device_node.StartNs()),
571
      nsToUsFloat(device_node.Duration()),
C
chenjian 已提交
572
      StringTracerEventType(device_node.Type()),
573
      nsToUsFloat(device_node.StartNs(), start_time_),
574 575 576 577 578 579 580
      nsToUsFloat(device_node.EndNs(), start_time_),
      device_node.DeviceId(),
      device_node.ContextId(),
      device_node.StreamId(),
      device_node.CorrelationId(),
      memset_info.num_bytes,
      memset_info.value);
581 582 583 584 585 586 587
}

void ChromeTracingLogger::StartLog() {
  output_file_stream_ << string_format(std::string(
                                           R"JSON(
  { 
    "schemaVersion": "%s",
C
chenjian 已提交
588 589
    "displayTimeUnit": "ms",
    "span_indx": "%d",
590
  )JSON"),
591 592
                                       kSchemaVersion,
                                       span_indx++);
593 594 595 596 597 598 599 600 601 602 603 604 605 606 607 608 609 610 611 612 613
// add device property information
#if defined(PADDLE_WITH_CUDA)
  output_file_stream_ << std::string(R"JSON(
    "deviceProperties": [
  )JSON");
  std::vector<int> device_ids = GetSelectedDevices();
  for (auto index = 0u; index < device_ids.size() - 1; index++) {
    const gpuDeviceProp& device_property =
        GetDeviceProperties(device_ids[index]);
    output_file_stream_ << string_format(
        std::string(
            R"JSON(
    {
       "id": %d, "name": "%s", "totalGlobalMem": %u,
      "computeMajor": %d, "computeMinor": %d,
      "maxThreadsPerBlock": %d, "maxThreadsPerMultiprocessor": %d,
      "regsPerBlock": %d, "regsPerMultiprocessor": %d, "warpSize": %d,
      "sharedMemPerBlock": %d, "sharedMemPerMultiprocessor": %d,
      "smCount": %d, "sharedMemPerBlockOptin": %d
    },
  )JSON"),
614 615 616 617 618
        device_ids[index],
        device_property.name,
        device_property.totalGlobalMem,
        device_property.major,
        device_property.minor,
619 620
        device_property.maxThreadsPerBlock,
        device_property.maxThreadsPerMultiProcessor,
621 622 623 624
        device_property.regsPerBlock,
        device_property.regsPerMultiprocessor,
        device_property.warpSize,
        device_property.sharedMemPerBlock,
625 626 627 628 629 630 631 632 633 634 635 636 637 638 639 640 641 642 643
        device_property.sharedMemPerMultiprocessor,
        device_property.multiProcessorCount,
        device_property.sharedMemPerBlockOptin);
  }
  if (device_ids.size() > 0) {
    const gpuDeviceProp& device_property =
        GetDeviceProperties(device_ids[device_ids.size() - 1]);
    output_file_stream_ << string_format(
        std::string(
            R"JSON(
    {
       "id": %d, "name": "%s", "totalGlobalMem": %u,
      "computeMajor": %d, "computeMinor": %d,
      "maxThreadsPerBlock": %d, "maxThreadsPerMultiprocessor": %d,
      "regsPerBlock": %d, "regsPerMultiprocessor": %d, "warpSize": %d,
      "sharedMemPerBlock": %d, "sharedMemPerMultiprocessor": %d,
      "smCount": %d, "sharedMemPerBlockOptin": %d
    }],
  )JSON"),
644 645 646 647 648 649
        device_ids[device_ids.size() - 1],
        device_property.name,
        device_property.totalGlobalMem,
        device_property.major,
        device_property.minor,
        device_property.maxThreadsPerBlock,
650
        device_property.maxThreadsPerMultiProcessor,
651 652 653 654
        device_property.regsPerBlock,
        device_property.regsPerMultiprocessor,
        device_property.warpSize,
        device_property.sharedMemPerBlock,
655 656 657 658 659 660 661 662 663 664 665 666
        device_property.sharedMemPerMultiprocessor,
        device_property.multiProcessorCount,
        device_property.sharedMemPerBlockOptin);
  }
#endif

  output_file_stream_ << std::string(
      R"JSON(
    "traceEvents": [
  )JSON");
}

C
chenjian 已提交
667 668 669
void ChromeTracingLogger::LogMetaInfo(
    const std::unordered_map<std::string, std::string> extra_info) {
  RefineDisplayName(extra_info);
670 671 672
  output_file_stream_ << std::string(
      R"JSON(
  {}
C
chenjian 已提交
673 674 675 676 677 678 679 680 681 682
  ],
  )JSON");
  output_file_stream_ << std::string(R"JSON(
  "ExtraInfo": {)JSON");
  size_t count = extra_info.size();
  for (const auto& kv : extra_info) {
    if (count > 1) {
      output_file_stream_ << string_format(std::string(R"JSON(
     "%s": "%s",
   )JSON"),
683 684
                                           kv.first.c_str(),
                                           kv.second.c_str());
C
chenjian 已提交
685 686 687 688
    } else {
      output_file_stream_ << string_format(std::string(R"JSON(
     "%s": "%s"
   )JSON"),
689 690
                                           kv.first.c_str(),
                                           kv.second.c_str());
C
chenjian 已提交
691 692 693 694 695 696 697 698 699 700 701 702 703 704 705 706 707 708 709 710 711 712 713 714 715 716 717 718 719 720 721 722 723 724 725 726 727 728 729 730 731 732 733 734 735 736 737 738 739 740 741 742 743 744 745 746 747 748 749 750 751 752 753
    }
    count--;
  }
  output_file_stream_ << std::string(R"JSON(
  })JSON");
}

void ChromeTracingLogger::RefineDisplayName(
    std::unordered_map<std::string, std::string> extra_info) {
  for (auto it = pid_tid_set_.begin(); it != pid_tid_set_.end(); ++it) {
    output_file_stream_ << string_format(
        std::string(
            R"JSON(
  {
    "name": "process_name", "pid": %lld, "tid": "%lld(Python)",
    "ph": "M", 
    "args": {
      "name": "Process %lld (CPU)"
    }
  },
  {
    "name": "process_name", "pid": %lld, "tid": "%lld(C++)",
    "ph": "M", 
    "args": {
      "name": "Process %lld (CPU)"
    }
  },
   {
    "name": "thread_name", "pid": %lld, "tid": "%lld(Python)",
    "ph": "M", 
    "args": {
      "name": "thread %lld:%s(Python)"
    }
  },
  {
    "name": "thread_name", "pid": %lld, "tid": "%lld(C++)",
    "ph": "M", 
    "args": {
      "name": "thread %lld:%s(C++)"
    }
  },
  {
    "name": "process_sort_index", "pid": %lld, "tid": %lld,
    "ph": "M", 
    "args": {
      "sort_index": %lld
    }
  },  
  {
    "name": "thread_sort_index", "pid": %lld, "tid": "%lld(Python)",
    "ph": "M", 
    "args": {
      "sort_index": %lld
    }
  },
  {
    "name": "thread_sort_index", "pid": %lld, "tid": "%lld(C++)",
    "ph": "M", 
    "args": {
      "sort_index": %lld
    }
  },
  )JSON"),
754 755 756 757 758 759 760 761 762
        (*it).first,
        (*it).second,
        (*it).first,
        (*it).first,
        (*it).second,
        (*it).first,
        (*it).first,
        (*it).second,
        (*it).second,
C
chenjian 已提交
763
        extra_info[string_format(std::string("%lld"), (*it).second)].c_str(),
764 765 766
        (*it).first,
        (*it).second,
        (*it).second,
C
chenjian 已提交
767
        extra_info[string_format(std::string("%lld"), (*it).second)].c_str(),
768 769 770 771 772 773 774 775 776
        (*it).first,
        (*it).second,
        (*it).first,
        (*it).first,
        (*it).second,
        (*it).second * 2,
        (*it).first,
        (*it).second,
        (*it).second * 2 + 1);
C
chenjian 已提交
777 778
  }

F
fwenguang 已提交
779 780 781 782 783 784
#ifdef PADDLE_WITH_MLU
  static std::string device_type("MLU");
#else
  static std::string device_type("GPU");
#endif

C
chenjian 已提交
785
  for (auto it = deviceid_streamid_set_.begin();
786 787 788 789
       it != deviceid_streamid_set_.end();
       ++it) {
    output_file_stream_ << string_format(std::string(
                                             R"JSON(
C
chenjian 已提交
790 791 792 793
  {
    "name": "process_name", "pid": %lld, "tid": %lld,
    "ph": "M", 
    "args": {
F
fwenguang 已提交
794
      "name": "Deivce %lld (%s)"
C
chenjian 已提交
795 796 797 798 799 800 801 802 803 804 805 806 807 808 809 810 811 812 813 814 815 816 817 818
    }
  },
   {
    "name": "thread_name", "pid": %lld, "tid": %lld,
    "ph": "M", 
    "args": {
      "name": "stream %lld"
    }
  },
  {
    "name": "process_sort_index", "pid": %lld, "tid": %lld,
    "ph": "M", 
    "args": {
      "sort_index": %lld
    }
  },  
  {
    "name": "thread_sort_index", "pid": %lld, "tid": %lld,
    "ph": "M", 
    "args": {
      "sort_index": %lld
    }
  },  
  )JSON"),
819 820 821 822 823 824 825 826 827 828 829 830 831
                                         (*it).first,
                                         (*it).second,
                                         (*it).first,
                                         device_type.c_str(),
                                         (*it).first,
                                         (*it).second,
                                         (*it).second,
                                         (*it).first,
                                         (*it).second,
                                         (*it).first + 0x10000000,
                                         (*it).first,
                                         (*it).second,
                                         (*it).second);
C
chenjian 已提交
832 833 834 835 836 837
  }
}

void ChromeTracingLogger::EndLog() {
  output_file_stream_ << std::string(
      R"JSON(
838 839 840 841 842 843
  }
  )JSON");
}

}  // namespace platform
}  // namespace paddle