profiler.py 38.2 KB
Newer Older
C
chenjian 已提交
1
# Copyright (c) 2022 PaddlePaddle Authors. All Rights Reserved.
C
chenjian 已提交
2
#
C
chenjian 已提交
3 4 5
# 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
C
chenjian 已提交
6
#
C
chenjian 已提交
7
#     http://www.apache.org/licenses/LICENSE-2.0
C
chenjian 已提交
8
#
C
chenjian 已提交
9 10 11 12 13 14
# 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 17
import datetime
import importlib
import json
C
chenjian 已提交
18 19 20 21 22 23 24
import os
import socket
from enum import Enum
from typing import Any, Callable, Iterable, Optional, Union
from warnings import warn

import paddle
25 26 27
from paddle.fluid.core import (
    ProfilerOptions,
    TracerEventType,
28
    _Profiler,
29
    disable_memory_recorder,
30
    disable_op_info_recorder,
31 32
    enable_memory_recorder,
    enable_op_info_recorder,
33
)
34 35 36 37 38 39 40 41

from .utils import RecordEvent, wrap_optimizers
from .profiler_statistic import (
    StatisticData,
    _build_table,
    SortedKeys,
    gen_layer_flops,
)
42
from paddle.profiler import utils
43 44

from .profiler_statistic import SortedKeys, StatisticData, _build_table
Z
Zhang Ting 已提交
45
from .timer import benchmark
46
from .utils import RecordEvent, wrap_optimizers
C
chenjian 已提交
47 48


49 50 51 52
class SummaryView(Enum):
    r"""
    SummaryView define the summary view of different contents.

53 54 55 56 57 58 59 60 61 62 63 64 65 66 67 68 69
    - **SummaryView.DeviceView** : The device summary view.

    - **SummaryView.OverView** : The overview summary view.

    - **SummaryView.ModelView** : The model summary view.

    - **SummaryView.DistributedView** : The distributed summary view.

    - **SummaryView.KernelView** : The kernel summary view.

    - **SummaryView.OperatorView** : The operator summary view.

    - **SummaryView.MemoryView** : The memory summary view.

    - **SummaryView.MemoryManipulationView** : The meomory manipulation summary view.

    - **SummaryView.UDFView** : The user defined summary view.
70 71 72 73 74 75 76 77 78 79 80 81
    """
    DeviceView = 0
    OverView = 1
    ModelView = 2
    DistributedView = 3
    KernelView = 4
    OperatorView = 5
    MemoryView = 6
    MemoryManipulationView = 7
    UDFView = 8


C
chenjian 已提交
82 83
class ProfilerState(Enum):
    r"""
C
chenjian 已提交
84
    ProfilerState is used to present the state of :ref:`Profiler <api_paddle_profiler_Profiler>` .
C
chenjian 已提交
85

C
chenjian 已提交
86
    The meaning of each ProfilerState is as following
C
chenjian 已提交
87

C
chenjian 已提交
88
    - **ProfilerState.CLOSED** : The profiler is closed, and no profiling data will be recorded.
C
chenjian 已提交
89

C
chenjian 已提交
90
    - **ProfilerState.READY** : The profiler is open, but the data will not be recorded. This state is used for reducing overhead influence when profiler starts.
C
chenjian 已提交
91

C
chenjian 已提交
92 93 94
    - **ProfilerState.RECORD** : The profiler is open, and the data will be recorded.

    - **ProfilerState.RECORD_AND_RETURN** : The profiler is open, and this state stands for the last batch of "RECORD" state in current profiling period. The collected data will be returned in this state.
C
chenjian 已提交
95 96 97 98
    """
    CLOSED = 0
    READY = 1
    RECORD = 2
C
chenjian 已提交
99
    RECORD_AND_RETURN = 3  # the last step of RECORD
C
chenjian 已提交
100 101 102 103


class ProfilerTarget(Enum):
    r"""
F
fwenguang 已提交
104
    ProfilerTarget is used to specify target device for :ref:`profiling <api_paddle_profiler_Profiler>` . Only CPU, GPU and MLU are supported currently.
C
chenjian 已提交
105

C
chenjian 已提交
106 107 108 109 110
    The meaning of each ProfilerState is as following

    - **ProfilerTarget.CPU** : Profile events on CPU.

    - **ProfilerTarget.GPU** : Profile events on GPU.
F
fwenguang 已提交
111 112

    - **ProfilerTarget.MLU** : Profile events on MLU.
C
chenjian 已提交
113 114 115
    """
    CPU = 0
    GPU = 1
F
fwenguang 已提交
116
    MLU = 2
117
    CUSTOM_DEVICE = 3
C
chenjian 已提交
118 119


120 121 122 123 124 125
def make_scheduler(
    *,
    closed: int,
    ready: int,
    record: int,
    repeat: int = 0,
126
    skip_first: int = 0,
127
) -> Callable:
C
chenjian 已提交
128
    r"""
C
chenjian 已提交
129
    Return a scheduler function, which scheduler the :ref:`state <api_paddle_profiler_ProfilerState>` according to the setting.
C
chenjian 已提交
130 131
    The state transform confirms to:

C
chenjian 已提交
132 133 134 135 136 137 138 139
    .. code-block:: text

        (CLOSED)  (CLOSED)    (CLOSED)  (READY)    (RECORD,last RETURN)      (CLOSED)
        START -> skip_first -> closed -> ready    ->    record       ->      END
                                |                        |
                                |                        | (if has_repeated < repeat)
                                - - - - - - - - - - - -
        Note that repeat <= 0 means the cycle will continue until the profiler exits.
C
chenjian 已提交
140

C
chenjian 已提交
141
    Args:
C
chenjian 已提交
142
        closed(int): The number of steps in state ProfilerState.CLOSED.
C
chenjian 已提交
143
        ready(int):  The number of steps in state ProfilerState.READY.
C
chenjian 已提交
144 145 146
        record(int): The number of steps in state ProfilerState.RECORD, and the state in last step will be set as ProfilerState.RECORD_AND_RETURN.
        repeat(int, optional): The number of cycles to repeat above state transform. Default value is 0, which means it will repeat this cycle until profiler exits.
        skip_first(int, optional): The number of first steps to drop, not participate in the state transform, and at ProfilerState.CLOSED state. Default value is 0.
C
chenjian 已提交
147 148

    Returns:
149
        A scheduler function, conforms to above state transform setting. The function will takes one parameter `step_num`, and returns corresponding ProfilerState.
C
chenjian 已提交
150 151

    Examples:
152
        1. profiling range [2, 5].
C
chenjian 已提交
153

154
        Assume batch 0: closed, batch 1: ready, batch [2, 5] record.
C
chenjian 已提交
155 156

            .. code-block:: python
C
chenjian 已提交
157
                :name: code-example1
C
chenjian 已提交
158 159 160 161 162

                import paddle.profiler as profiler
                profiler.make_scheduler(closed=1, ready=1, record=4, repeat=1)


163
        2. profiling range [3,6], [9,12], [15,18].
C
chenjian 已提交
164

165
        Assume batch 0: skiped, batch 1: closed, batch 2: ready, batch [3,6]: record, repeat.
C
chenjian 已提交
166 167

            .. code-block:: python
C
chenjian 已提交
168
                :name: code-example2
C
chenjian 已提交
169 170 171

                import paddle.profiler as profiler
                profiler.make_scheduler(closed=1, ready=1, record=4, skip_first=1)
C
chenjian 已提交
172 173 174 175 176 177 178 179 180
    """

    def getScheduleState(step: int) -> ProfilerState:
        assert step >= 0
        if step < skip_first:  # within skip_first, just skip
            return ProfilerState.CLOSED
        step = step - skip_first
        period_steps = closed + ready + record
        has_repeated = step // period_steps
181 182 183
        if (
            repeat > 0 and has_repeated >= repeat
        ):  # the period has repeated repeat times, return CLOSED state
C
chenjian 已提交
184 185 186 187 188 189 190 191 192 193 194
            return ProfilerState.CLOSED
        mod_step = step % period_steps
        if mod_step < closed:
            return ProfilerState.CLOSED
        elif mod_step >= closed and mod_step < closed + ready:
            return ProfilerState.READY
        else:
            if mod_step < period_steps - 1:
                return ProfilerState.RECORD
            else:
                return ProfilerState.RECORD_AND_RETURN
195 196 197 198 199 200 201 202

    assert (
        closed >= 0
        and ready >= 0
        and record > 0
        and repeat >= 0
        and skip_first >= 0
    ), "Invalid profiler scheduler arguments"
C
chenjian 已提交
203
    if ready == 0:
204 205
        warn(
            "Profiler will record data after enabling profiler immediately, \
C
chenjian 已提交
206
          some data collected at the beginning of profiling may be 'noisy' because of overhead."
207
        )
C
chenjian 已提交
208 209 210 211 212
    return getScheduleState


def _default_state_scheduler(step: int):
    r"""
213
    A default state scheduler, keep recording from the beginning of the profiler until ending.
C
chenjian 已提交
214 215 216 217
    """
    return ProfilerState.RECORD


218 219 220
def export_chrome_tracing(
    dir_name: str, worker_name: Optional[str] = None
) -> Callable:
C
chenjian 已提交
221 222
    r"""
    Return a callable, used for outputing tracing data to chrome tracing format file.
223 224
    The output file will be saved in directory ``dir_name``, and file name will be set as `worker_name`.
    if `worker_name` is not set, the default name is `[hostname]_[pid]`.
C
chenjian 已提交
225

C
chenjian 已提交
226
    Args:
C
chenjian 已提交
227
        dir_name(str): Directory to save profiling data.
228
        worker_name(str, optional): Prefix of the file name saved, default is `[hostname]_[pid]`.
229

C
chenjian 已提交
230 231
    Returns:
        A callable, which takes a Profiler object as parameter and calls its export method to save data to chrome tracing format file.
C
chenjian 已提交
232 233

    Examples:
C
chenjian 已提交
234 235
        The return value can be used as parameter ``on_trace_ready`` in :ref:`Profiler <api_paddle_profiler_Profiler>` .

C
chenjian 已提交
236
        .. code-block:: python
C
chenjian 已提交
237 238 239 240 241 242 243 244 245 246

            # required: gpu
            import paddle.profiler as profiler
            with profiler.Profiler(
                    targets=[profiler.ProfilerTarget.CPU, profiler.ProfilerTarget.GPU],
                    scheduler = (3, 10),
                    on_trace_ready=profiler.export_protobuf('./log')) as p:
                for iter in range(10):
                    #train()
                    p.step()
C
chenjian 已提交
247 248 249 250 251 252
    """
    if not os.path.exists(dir_name):
        try:
            os.makedirs(dir_name, exist_ok=True)
        except Exception:
            raise RuntimeError(
253 254 255 256
                "Can not create directory '{}' for saving profiling results.".format(
                    dir_name
                )
            )
C
chenjian 已提交
257 258 259 260

    def handle_fn(prof):
        nonlocal worker_name
        if not worker_name:
261 262 263
            worker_name = "host_{}pid_{}".format(
                socket.gethostname(), str(os.getpid())
            )
C
chenjian 已提交
264 265
        now = datetime.datetime.now()
        filename = '{}_time_{}.paddle_trace.json'.format(
266 267
            worker_name, now.strftime('%Y_%m_%d_%H_%M_%S_%f')
        )
C
chenjian 已提交
268 269 270 271 272
        prof.export(os.path.join(dir_name, filename), "json")

    return handle_fn


273 274 275
def export_protobuf(
    dir_name: str, worker_name: Optional[str] = None
) -> Callable:
C
chenjian 已提交
276 277
    r"""
    Return a callable, used for outputing tracing data to protobuf file.
278 279
    The output file will be saved in directory ``dir_name``, and file name will be set as ``worker_name``.
    if ``worker_name`` is not set, the default name is `[hostname]_[pid]`.
C
chenjian 已提交
280

C
chenjian 已提交
281
    Args:
C
chenjian 已提交
282
        dir_name(str): Directory to save profiling data.
283
        worker_name(str, optional): Prefix of the file name saved, default is `[hostname]_[pid]`.
C
chenjian 已提交
284 285 286

    Returns:
        A callable, which takes a Profiler object as parameter and calls its export method to save data to protobuf file.
C
chenjian 已提交
287 288

    Examples:
C
chenjian 已提交
289 290
        The return value can be used as parameter ``on_trace_ready`` in :ref:`Profiler <api_paddle_profiler_Profiler>` .

C
chenjian 已提交
291
        .. code-block:: python
C
chenjian 已提交
292 293 294 295 296 297 298 299 300 301

            # required: gpu
            import paddle.profiler as profiler
            with profiler.Profiler(
                    targets=[profiler.ProfilerTarget.CPU, profiler.ProfilerTarget.GPU],
                    scheduler = (3, 10),
                    on_trace_ready = profiler.export_protobuf('./log')) as p:
                for iter in range(10):
                    #train()
                    p.step()
C
chenjian 已提交
302 303 304 305 306 307
    """
    if not os.path.exists(dir_name):
        try:
            os.makedirs(dir_name, exist_ok=True)
        except Exception:
            raise RuntimeError(
308 309 310 311
                "Can not create directory '{}' for saving profiling results.".format(
                    dir_name
                )
            )
C
chenjian 已提交
312 313 314 315

    def handle_fn(prof):
        nonlocal worker_name
        if not worker_name:
316 317 318
            worker_name = "host_{}pid_{}".format(
                socket.gethostname(), str(os.getpid())
            )
C
chenjian 已提交
319 320
        now = datetime.datetime.now()
        filename = '{}_time_{}.paddle_trace.pb'.format(
321 322
            worker_name, now.strftime('%Y_%m_%d_%H_%M_%S_%f')
        )
C
chenjian 已提交
323 324 325 326 327 328 329 330 331
        prof.export(os.path.join(dir_name, filename), "pb")

    return handle_fn


def _get_supported_targets() -> Iterable[ProfilerTarget]:
    r"""
    Get the current supported profiler target in the system.
    """
C
chenjian 已提交
332
    if _Profiler.is_cupti_supported():
333
        return [
334 335 336
            ProfilerTarget.CPU,
            ProfilerTarget.GPU,
            ProfilerTarget.CUSTOM_DEVICE,
337
        ]
F
fwenguang 已提交
338
    if _Profiler.is_cnpapi_supported():
339
        return [
340 341 342
            ProfilerTarget.CPU,
            ProfilerTarget.MLU,
            ProfilerTarget.CUSTOM_DEVICE,
343 344
        ]
    return [ProfilerTarget.CPU, ProfilerTarget.CUSTOM_DEVICE]
C
chenjian 已提交
345 346 347 348


class Profiler:
    r"""
C
chenjian 已提交
349
    Profiler context manager, user interface to manage profiling process to start, stop, export profiling data and print summary table.
C
chenjian 已提交
350

C
chenjian 已提交
351
    Args:
F
fwenguang 已提交
352
        targets (list, optional): specify target devices to profile, and all existing and supported devices will be chosen by default. Currently supported values, :ref:`ProfilerTarget.CPU <api_paddle_profiler_ProfilerTarget>` , :ref:`ProfilerTarget.GPU <api_paddle_profiler_ProfilerTarget>` and :ref:`ProfilerTarget.MLU <api_paddle_profiler_ProfilerTarget>` .
C
chenjian 已提交
353 354
        scheduler (Callable|tuple, optional): If it is a callable object, it takes a step number as parameter and return the corresponding :ref:`ProfilerState <api_paddle_profiler_ProfilerState>`. This callable object can be generated by :ref:`make_scheduler <api_paddle_profiler_make_scheduler>` function.
            If not provided (None), the default scheduler will keep tracing until the profiler exits. If it is a tuple, it has two values start_batch and end_batch,
C
chenjian 已提交
355
            which means profiling range [start_batch, end_batch).
C
chenjian 已提交
356
        on_trace_ready (Callable, optional): Callable object, serves as callback function, and takes the Profiler object as parameter, which provides a way for users to do post-processing.
357
            This callable object will be called when ``scheduler`` returns ``ProfilerState.RECORD_AND_RETURN``. The default value is :ref:`export_chrome_tracing <api_paddle_profiler_export_chrome_tracing>`.
Z
Zhang Ting 已提交
358 359
        timer_only (bool, optional): If it is True, the cost of Dataloader and every step of the model will be count without profiling. Otherwise, the model will
            be timed and profiled. Default: False.
360 361
        record_shapes (bool, optional): If it is True, collect op's input shape information. Default: False.
        profile_memory (bool, optional): If it is True, collect tensor memory allocation and release information. Default: False.
362
        with_flops (bool, optional): If it is True, the flops of the op will be calculated. Default: False.
C
chenjian 已提交
363

C
chenjian 已提交
364
    Examples:
C
chenjian 已提交
365
        1. profiling range [2, 5).
C
chenjian 已提交
366 367

            .. code-block:: python
C
chenjian 已提交
368
                :name: code-example1
C
chenjian 已提交
369 370 371 372 373 374 375 376 377 378 379

                # required: gpu
                import paddle.profiler as profiler
                with profiler.Profiler(
                        targets=[profiler.ProfilerTarget.CPU, profiler.ProfilerTarget.GPU],
                        scheduler = (2, 5),
                        on_trace_ready = profiler.export_chrome_tracing('./log')) as p:
                    for iter in range(10):
                        #train()
                        p.step()

380
        2. profiling range [2,4], [7, 9], [11,13].
C
chenjian 已提交
381 382

            .. code-block:: python
C
chenjian 已提交
383
                :name: code-example2
C
chenjian 已提交
384 385 386 387 388 389 390 391 392 393 394

                # required: gpu
                import paddle.profiler as profiler
                with profiler.Profiler(
                        targets=[profiler.ProfilerTarget.CPU, profiler.ProfilerTarget.GPU],
                        scheduler = profiler.make_scheduler(closed=1, ready=1, record=3, repeat=3),
                        on_trace_ready = profiler.export_chrome_tracing('./log')) as p:
                    for iter in range(10):
                        #train()
                        p.step()

395
        3. Use profiler without context manager, and use default parameters.
C
chenjian 已提交
396 397

            .. code-block:: python
C
chenjian 已提交
398
                :name: code-example3
C
chenjian 已提交
399 400 401 402 403 404 405 406 407 408 409

                # required: gpu
                import paddle.profiler as profiler
                p = profiler.Profiler()
                p.start()
                for iter in range(10):
                    #train()
                    p.step()
                p.stop()
                p.summary()

410
        4. Use profiler to get throughput and cost of the model.
Z
Zhang Ting 已提交
411 412 413 414 415 416

            .. code-block:: python
                :name: code-example-timer1

                import paddle
                import paddle.profiler as profiler
417

Z
Zhang Ting 已提交
418 419 420
                class RandomDataset(paddle.io.Dataset):
                    def __init__(self, num_samples):
                        self.num_samples = num_samples
421

Z
Zhang Ting 已提交
422 423 424 425
                    def __getitem__(self, idx):
                        image = paddle.rand(shape=[100], dtype='float32')
                        label = paddle.randint(0, 10, shape=[1], dtype='int64')
                        return image, label
426

Z
Zhang Ting 已提交
427 428
                    def __len__(self):
                        return self.num_samples
429

Z
Zhang Ting 已提交
430 431
                class SimpleNet(paddle.nn.Layer):
                    def __init__(self):
432
                        super().__init__()
Z
Zhang Ting 已提交
433
                        self.fc = paddle.nn.Linear(100, 10)
434

Z
Zhang Ting 已提交
435 436
                    def forward(self, image, label=None):
                        return self.fc(image)
437

Z
Zhang Ting 已提交
438 439
                dataset = RandomDataset(20 * 4)
                simple_net = SimpleNet()
440
                opt = paddle.optimizer.SGD(learning_rate=1e-3, parameters=simple_net.parameters())
Z
Zhang Ting 已提交
441 442 443 444 445 446 447 448 449 450 451 452 453 454 455 456 457 458 459 460 461 462 463 464 465 466 467 468 469 470
                BATCH_SIZE = 4
                loader = paddle.io.DataLoader(
                    dataset,
                    batch_size=BATCH_SIZE)
                p = profiler.Profiler(timer_only=True)
                p.start()
                for i, (image, label) in enumerate(loader()):
                    out = simple_net(image)
                    loss = paddle.nn.functional.cross_entropy(out, label)
                    avg_loss = paddle.mean(loss)
                    avg_loss.backward()
                    opt.minimize(avg_loss)
                    simple_net.clear_gradients()
                    p.step(num_samples=BATCH_SIZE)
                    if i % 10 == 0:
                        step_info = p.step_info(unit='images')
                        print("Iter {}: {}".format(i, step_info))
                        # The average statistics for 10 steps between the last and this call will be
                        # printed when the "step_info" is called at 10 iteration intervals.
                        # The values you get may be different from the following.
                        # Iter 0:  reader_cost: 0.51946 s batch_cost: 0.66077 s ips: 6.054 images/s
                        # Iter 10:  reader_cost: 0.00014 s batch_cost: 0.00441 s ips: 907.009 images/s
                p.stop()
                # The performance summary will be automatically printed when the "stop" is called.
                # Reader Ratio: 2.658%
                # Time Unit: s, IPS Unit: images/s
                # |                 |       avg       |       max       |       min       |
                # |   reader_cost   |     0.00011     |     0.00013     |     0.00007     |
                # |    batch_cost   |     0.00405     |     0.00434     |     0.00326     |
                # |       ips       |    1086.42904   |    1227.30604   |    959.92796    |
C
chenjian 已提交
471 472
    """

473 474 475 476 477 478 479
    def __init__(
        self,
        *,
        targets: Optional[Iterable[ProfilerTarget]] = None,
        scheduler: Union[Callable[[int], ProfilerState], tuple, None] = None,
        on_trace_ready: Optional[Callable[..., Any]] = None,
        record_shapes: Optional[bool] = False,
480
        profile_memory: Optional[bool] = False,
481 482
        timer_only: Optional[bool] = False,
        emit_nvtx: Optional[bool] = False,
483 484
        custom_device_types: Optional[list] = [],
        with_flops: Optional[bool] = False,
485
    ):
C
chenjian 已提交
486 487 488 489 490 491
        supported_targets = _get_supported_targets()
        if targets:
            self.targets = set(targets)
            for target in targets:
                if target not in supported_targets:
                    self.targets.remove(target)
492 493 494 495 496
                    warn(
                        "Profiling {} is not supported in current context.".format(
                            target
                        )
                    )
C
chenjian 已提交
497 498 499 500 501 502
        else:
            self.targets = supported_targets
        profileoption = ProfilerOptions()
        if ProfilerTarget.CPU in self.targets:
            profileoption.trace_switch |= 1
        if ProfilerTarget.GPU in self.targets:
503
            profileoption.trace_switch |= 1 << 1
F
fwenguang 已提交
504
        if ProfilerTarget.MLU in self.targets:
505
            profileoption.trace_switch |= 1 << 2
506
        if ProfilerTarget.CUSTOM_DEVICE in self.targets:
507
            profileoption.trace_switch |= 1 << 3
508 509
            if not custom_device_types:
                custom_device_types = paddle.device.get_all_custom_device_type()
C
chenjian 已提交
510
        wrap_optimizers()
511
        self.profiler = _Profiler.create(profileoption, custom_device_types)
C
chenjian 已提交
512 513 514 515 516 517 518
        if callable(scheduler):
            self.scheduler = scheduler
        elif isinstance(scheduler, (tuple, list)):
            assert len(scheduler) == 2 and scheduler[1] > scheduler[0]
            start_batch, end_batch = scheduler
            start_batch = max(start_batch, 0)
            if start_batch >= 1:
519 520 521 522 523 524
                self.scheduler = make_scheduler(
                    closed=max(start_batch - 1, 0),
                    ready=1,
                    record=(end_batch - start_batch),
                    repeat=1,
                )
C
chenjian 已提交
525
            else:
526 527 528 529 530 531
                self.scheduler = make_scheduler(
                    closed=0,
                    ready=0,
                    record=(end_batch - start_batch),
                    repeat=1,
                )
C
chenjian 已提交
532 533 534
        else:
            self.scheduler = _default_state_scheduler

535
        if on_trace_ready is None:
C
chenjian 已提交
536 537 538 539 540 541 542 543
            self.on_trace_ready = export_chrome_tracing('./profiler_log/')
        else:
            self.on_trace_ready = on_trace_ready
        self.step_num = 0
        self.previous_state = ProfilerState.CLOSED
        self.current_state = self.scheduler(self.step_num)
        self.record_event = None
        self.profiler_result = None
Z
Zhang Ting 已提交
544
        self.timer_only = timer_only
545 546
        self.record_shapes = record_shapes
        self.profile_memory = profile_memory
547
        self.with_flops = with_flops
548
        self.emit_nvtx = emit_nvtx
C
chenjian 已提交
549 550 551 552 553 554 555 556 557 558 559

    def __enter__(self):
        self.start()
        return self

    def __exit__(self, exc_type, exc_val, exc_tb):
        self.stop()

    def start(self):
        r'''
        Start profiler and enter the first profiler step(0).
C
chenjian 已提交
560 561 562 563
        State transformed from CLOSED to self.current_state and trigger corresponding action.

        Examples:
            .. code-block:: python
C
chenjian 已提交
564
                :name: code-example4
C
chenjian 已提交
565 566 567 568 569 570 571 572 573 574 575 576

                # required: gpu
                import paddle.profiler as profiler
                prof = profiler.Profiler(
                    targets=[profiler.ProfilerTarget.CPU, profiler.ProfilerTarget.GPU],
                    scheduler = (1, 9),
                    on_trace_ready = profiler.export_chrome_tracing('./log'))
                prof.start()
                for iter in range(10):
                    #train()
                    prof.step()
                prof.stop()
Z
Zhang Ting 已提交
577

C
chenjian 已提交
578
        '''
579
        # Timing only without profiling.
Z
Zhang Ting 已提交
580
        benchmark().begin()
581 582
        if not self.timer_only or self.emit_nvtx:
            utils._is_profiler_used = True
Z
Zhang Ting 已提交
583 584
        if self.timer_only:
            return
585 586
        if self.record_shapes or self.with_flops:
            enable_op_info_recorder()
587 588
        if self.profile_memory:
            enable_memory_recorder()
C
chenjian 已提交
589 590 591 592 593 594 595 596 597
        # CLOSED -> self.current_state
        if self.current_state == ProfilerState.READY:
            self.profiler.prepare()
        elif self.current_state == ProfilerState.RECORD:
            self.profiler.prepare()
            self.profiler.start()
        elif self.current_state == ProfilerState.RECORD_AND_RETURN:
            self.profiler.prepare()
            self.profiler.start()
598 599 600 601
        self.record_event = RecordEvent(
            name="ProfileStep#{}".format(self.step_num),
            event_type=TracerEventType.ProfileStep,
        )
C
chenjian 已提交
602 603 604 605 606 607
        self.record_event.begin()

    def stop(self):
        r'''
        Stop profiler and State transformed from self.current_state to CLOSED.
        Trigger corresponding action and post-process profiler result using self.on_trace_ready if result exists.
C
chenjian 已提交
608 609 610

        Examples:
            .. code-block:: python
C
chenjian 已提交
611
                :name: code-example5
C
chenjian 已提交
612 613 614 615 616 617 618 619 620 621 622 623

                # required: gpu
                import paddle.profiler as profiler
                prof = profiler.Profiler(
                    targets=[profiler.ProfilerTarget.CPU, profiler.ProfilerTarget.GPU],
                    scheduler = (1, 7),
                    on_trace_ready = profiler.export_chrome_tracing('./log'))
                prof.start()
                for iter in range(10):
                    #train()
                    prof.step()
                prof.stop()
C
chenjian 已提交
624
        '''
Z
Zhang Ting 已提交
625 626 627
        benchmark().end()
        if self.timer_only:
            return
628 629
        if self.record_shapes or self.with_flops:
            disable_op_info_recorder()
630 631
        if self.profile_memory:
            disable_memory_recorder()
C
chenjian 已提交
632
        # self.current_state -> CLOSED
633
        # In this situation, RECORD state is regarded as RECORD_AND_RETURN.
C
chenjian 已提交
634 635 636 637 638 639 640 641 642
        if self.record_event:
            self.record_event.end()
            self.record_event = None
        if self.current_state == ProfilerState.READY:
            warn(
                "Inproper Profiler state transform: READY->CLOSED, profiler will start and stop without saving data"
            )
            self.profiler.start()
            self.profiler.stop()
643 644 645 646
        if (
            self.current_state == ProfilerState.RECORD
            or self.current_state == ProfilerState.RECORD_AND_RETURN
        ):
C
chenjian 已提交
647 648 649
            self.profiler_result = self.profiler.stop()
            if self.on_trace_ready:
                self.on_trace_ready(self)
650
        utils._is_profiler_used = False
C
chenjian 已提交
651

652
    def step(self, num_samples: Optional[int] = None):
C
chenjian 已提交
653 654 655
        r"""
        Signals the profiler that the next profiling step has started.
        Get the new ProfilerState and trigger corresponding action.
C
chenjian 已提交
656

Z
Zhang Ting 已提交
657 658
        Args:
            num_samples (int|None, optional): Specifies the batch size of every step of the model
659
                that is used to compute throughput when `timer_only` is True. Default: None.
Z
Zhang Ting 已提交
660

C
chenjian 已提交
661 662
        Examples:
            .. code-block:: python
C
chenjian 已提交
663
                :name: code-example6
C
chenjian 已提交
664 665 666 667 668 669 670 671 672 673 674 675 676

                # required: gpu
                import paddle.profiler as profiler
                prof = profiler.Profiler(
                    targets=[profiler.ProfilerTarget.CPU, profiler.ProfilerTarget.GPU],
                    scheduler = (3, 7),
                    on_trace_ready = profiler.export_chrome_tracing('./log'))

                prof.start()
                for iter in range(10):
                    #train()
                    prof.step()
                prof.stop()
C
chenjian 已提交
677
        """
Z
Zhang Ting 已提交
678 679 680
        benchmark().step(num_samples)
        if self.timer_only:
            return
C
chenjian 已提交
681 682 683 684 685 686 687
        if self.record_event:
            self.record_event.end()
            self.record_event = None
        self.previous_state = self.current_state
        self.step_num += 1
        self.current_state = self.scheduler(self.step_num)
        self._trigger_action()
688 689 690 691
        self.record_event = RecordEvent(
            name="ProfileStep#{}".format(self.step_num),
            event_type=TracerEventType.ProfileStep,
        )
C
chenjian 已提交
692 693
        self.record_event.begin()

Z
Zhang Ting 已提交
694 695 696 697
    def step_info(self, unit=None):
        r"""
        Get statistics for current step. If the function is called at certain iteration
        intervals, the result is the average of all steps between the previous call and
698
        this call. Statistics are as follows:
Z
Zhang Ting 已提交
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

        1. reader_cost: the cost of loading data measured in seconds.

        2. batch_cost: the cost of step measured in seconds.

        3. ips(Instance Per Second): the throughput of the model measured in `samples/s`
        or others depends on the `unit`. When `num_samples` of `step()` is None, it is
        measured in `steps/s`.

        Args:
            unit (string, optional): The unit of input data is only used When `num_samples`
                of `step()` is specified as a number. For example, when it is `images`, the unit
                of throughput is `images/s`. Default: None, the unit of throughput is `samples/s`.

        Returns:
            string: A string representing the statistic.

        Examples:
            .. code-block:: python
                :name: code-example-timer2

                import paddle.profiler as profiler
                prof = profiler.Profiler(timer_only=True)
                prof.start()
                for iter in range(20):
                    #train()
                    prof.step()
                    if iter % 10 == 0:
                        print("Iter {}: {}".format(iter, prof.step_info()))
                        # The example does not call the DataLoader, so there is no "reader_cost".
                        # Iter 0:  batch_cost: 0.00001 s ips: 86216.623 steps/s
                        # Iter 10:  batch_cost: 0.00001 s ips: 103645.034 steps/s
                prof.stop()
                # Time Unit: s, IPS Unit: steps/s
                # |                 |       avg       |       max       |       min       |
                # |    batch_cost   |     0.00000     |     0.00002     |     0.00000     |
                # |       ips       |   267846.19437  |   712030.38727  |   45134.16662   |
        """
        if unit is None:
            unit = 'samples'
        return benchmark().step_info(unit)

C
chenjian 已提交
741 742 743 744 745 746 747
    def _trigger_action(self):
        if self.previous_state == ProfilerState.CLOSED:
            if self.current_state == ProfilerState.READY:  # CLOSED -> READY
                self.profiler.prepare()
            if self.current_state == ProfilerState.RECORD:  # CLOSED -> RECORD
                self.profiler.prepare()
                self.profiler.start()
748 749 750
            if (
                self.current_state == ProfilerState.RECORD_AND_RETURN
            ):  # CLOSED -> RECORD_AND_RETURN
C
chenjian 已提交
751 752 753 754 755 756 757 758 759 760 761 762
                self.profiler.prepare()
                self.profiler.start()

        elif self.previous_state == ProfilerState.READY:
            if self.current_state == ProfilerState.CLOSED:  # READY -> CLOSED
                warn(
                    "Improper schedule: READY->CLOSED, profiler will start and stop without saving data"
                )
                self.profiler.start()
                self.profiler.stop()
            if self.current_state == ProfilerState.RECORD:  # READY -> RECORD
                self.profiler.start()
763 764 765
            if (
                self.current_state == ProfilerState.RECORD_AND_RETURN
            ):  # READY -> RECORD_AND_RETURN
C
chenjian 已提交
766 767 768 769 770 771 772 773 774 775 776 777 778 779 780
                self.profiler.start()

        elif self.previous_state == ProfilerState.RECORD:
            if self.current_state == ProfilerState.CLOSED:  # RECORD -> CLOSED
                warn(
                    "Improper schedule: RECORD->CLOSED, profiler will not saving data"
                )
                self.profiler.stop()

            if self.current_state == ProfilerState.READY:  # RECORD -> READY
                warn(
                    "Improper schedule: RECORD->READY, profiler will stop and re-prepare"
                )
                self.profiler.stop()
                self.profiler.prepare()
781 782 783
            if (
                self.current_state == ProfilerState.RECORD_AND_RETURN
            ):  # RECORD -> RECORD_AND_RETURN
C
chenjian 已提交
784 785 786 787
                pass

        else:
            assert self.previous_state == ProfilerState.RECORD_AND_RETURN
788 789 790
            if (
                self.current_state == ProfilerState.CLOSED
            ):  # RECORD_AND_RETURN -> CLOSED
C
chenjian 已提交
791
                self.profiler_result = self.profiler.stop()
792 793 794
            if (
                self.current_state == ProfilerState.READY
            ):  # RECORD_AND_RETURN -> READY
C
chenjian 已提交
795 796
                self.profiler_result = self.profiler.stop()
                self.profiler.prepare()
797 798 799
            if (
                self.current_state == ProfilerState.RECORD
            ):  # RECORD_AND_RETURN -> RECORD
C
chenjian 已提交
800 801 802
                self.profiler_result = self.profiler.stop()
                self.profiler.prepare()
                self.profiler.start()
803 804 805
            if (
                self.current_state == ProfilerState.RECORD_AND_RETURN
            ):  # RECORD_AND_RETURN -> RECORD_AND_RETURN
C
chenjian 已提交
806 807 808 809 810 811 812 813
                self.profiler_result = self.profiler.stop()
                self.profiler.prepare()
                self.profiler.start()
            if self.on_trace_ready:
                self.on_trace_ready(self)

    def export(self, path="", format="json"):
        r"""
C
chenjian 已提交
814 815 816 817
        Exports the tracing data to file.

        Args:
            path(str): file path of the output.
818
            format(str, optional): output format, can be chosen from ['json', 'pb'], 'json' for chrome tracing and 'pb' for protobuf, default value is 'json'.
C
chenjian 已提交
819

C
chenjian 已提交
820 821 822

        Examples:
            .. code-block:: python
C
chenjian 已提交
823
                :name: code-example7
C
chenjian 已提交
824 825 826 827 828 829 830 831 832 833 834 835

                # required: gpu
                import paddle.profiler as profiler
                prof = profiler.Profiler(
                    targets=[profiler.ProfilerTarget.CPU, profiler.ProfilerTarget.GPU],
                    scheduler = (3, 7))
                prof.start()
                for iter in range(10):
                    #train()
                    prof.step()
                prof.stop()
                prof.export(path="./profiler_data.json", format="json")
C
chenjian 已提交
836 837 838 839
        """
        if self.profiler_result:
            self.profiler_result.save(path, format)

840 841 842 843 844 845 846 847
    def summary(
        self,
        sorted_by=SortedKeys.CPUTotal,
        op_detail=True,
        thread_sep=False,
        time_unit='ms',
        views=None,
    ):
C
chenjian 已提交
848
        r"""
C
chenjian 已提交
849
        Print the Summary table. Currently support overview, model, distributed, operator, memory manipulation and userdefined summary.
C
chenjian 已提交
850

C
chenjian 已提交
851 852 853 854 855
        Args:
            sorted_by( :ref:`SortedKeys <api_paddle_profiler_SortedKeys>` , optional): how to rank the op table items, default value is SortedKeys.CPUTotal.
            op_detail(bool, optional): expand each operator detail information, default value is True.
            thread_sep(bool, optional): print op table each thread, default value is False.
            time_unit(str, optional): time unit for display, can be chosen form ['s', 'ms', 'us', 'ns'], default value is 'ms'.
856
            views(SummaryView|list[SummaryView], optional): summary tables to print, default to None means all views to be printed.
C
chenjian 已提交
857 858 859

        Examples:
            .. code-block:: python
C
chenjian 已提交
860
                :name: code-example8
C
chenjian 已提交
861 862 863 864 865 866 867 868 869 870 871 872 873

                # required: gpu
                import paddle.profiler as profiler
                prof = profiler.Profiler(
                    targets=[profiler.ProfilerTarget.CPU, profiler.ProfilerTarget.GPU],
                    scheduler = (3, 7),
                    on_trace_ready = profiler.export_chrome_tracing('./log'))
                prof.start()
                for iter in range(10):
                    #train()
                    prof.step()
                prof.stop()
                prof.summary(sorted_by=profiler.SortedKeys.CPUTotal, op_detail=True, thread_sep=False, time_unit='ms')
C
chenjian 已提交
874
        """
875 876 877
        if isinstance(views, SummaryView):
            views = [views]

C
chenjian 已提交
878 879 880
        if self.profiler_result:
            statistic_data = StatisticData(
                self.profiler_result.get_data(),
881 882
                self.profiler_result.get_extra_info(),
            )
C
chenjian 已提交
883
            print(
884 885 886 887 888 889 890 891 892
                _build_table(
                    statistic_data,
                    sorted_by=sorted_by,
                    op_detail=op_detail,
                    thread_sep=thread_sep,
                    time_unit=time_unit,
                    views=views,
                )
            )
C
chenjian 已提交
893

894 895 896 897 898 899 900 901 902 903 904 905
        if self.with_flops:
            self._print_flops()

    def _print_flops(self, repeat=1):
        if not self.with_flops:
            print('ERROR: with_flops disabled.')
            return

        print(" Flops Profiler Begin ".center(100, "-"))
        print(gen_layer_flops(self.profiler_result.get_data(), repeat))
        print("- Flops Profiler End -".center(100, "-"))

C
chenjian 已提交
906 907 908 909 910 911 912 913 914 915 916 917 918 919 920 921 922 923 924 925 926 927 928 929 930 931 932 933 934 935 936

def get_profiler(config_path):
    try:
        with open(config_path, 'r') as filehandle:
            config_dict = json.load(filehandle)
    except Exception as e:
        print('Load config file for profiler error: {}'.format(e))
        print('Use default parameters instead.')
        return Profiler()
    translated_config_dict = {}
    if "targets" in config_dict:
        try:
            translated_config_dict['targets'] = []
            for target in config_dict['targets']:
                if target.lower() == "cpu":
                    translated_config_dict['targets'].append(ProfilerTarget.CPU)
                elif target.lower() == 'gpu':
                    translated_config_dict['targets'].append(ProfilerTarget.GPU)
        except:
            print('Set targets parameter error, use default parameter instead.')
            translated_config_dict['targets'] = None
    if "scheduler" in config_dict:
        try:
            if isinstance(config_dict['scheduler'], dict):
                for key, value in config_dict['scheduler'].items():
                    module_path = value['module']
                    use_direct = value['use_direct']
                    module = importlib.import_module(module_path)
                    method = getattr(module, key)
                    if not use_direct:
                        translated_config_dict['scheduler'] = method(
937 938
                            *value['args'], **value['kwargs']
                        )
C
chenjian 已提交
939 940 941 942
                    else:
                        translated_config_dict['scheduler'] = method
            else:
                translated_config_dict['scheduler'] = [
943 944
                    config_dict['scheduler'][0],
                    config_dict['scheduler'][1],
C
chenjian 已提交
945 946 947 948
                ]

        except:
            print(
949 950
                'Set scheduler parameter error, use default parameter instead.'
            )
C
chenjian 已提交
951 952 953 954 955 956 957 958 959 960 961
            translated_config_dict['scheduler'] = None
    if "on_trace_ready" in config_dict:
        try:
            if isinstance(config_dict['on_trace_ready'], dict):
                for key, value in config_dict['on_trace_ready'].items():
                    module_path = value['module']
                    use_direct = value['use_direct']
                    module = importlib.import_module(module_path)
                    method = getattr(module, key)
                    if not use_direct:
                        translated_config_dict['on_trace_ready'] = method(
962 963
                            *value['args'], **value['kwargs']
                        )
C
chenjian 已提交
964 965 966 967 968 969 970 971 972 973 974 975
                    else:
                        translated_config_dict['on_trace_ready'] = method
        except:
            print(
                'Set on_trace_ready parameter error, use default parameter instead.'
            )
            translated_config_dict['on_trace_ready'] = None
    if "timer_only" in config_dict:
        if isinstance(config_dict['timer_only'], bool):
            translated_config_dict['timer_only'] = config_dict['timer_only']
        else:
            print(
976 977
                'Set timer_only parameter error, use default parameter instead.'
            )
C
chenjian 已提交
978 979

    return Profiler(**translated_config_dict)