OProfile usage [2]

5.3   Instance analysis

In this phase, some sample output after a short session profiling on JK2410 board.

 

# opreport

CPU: CPU with timer interrupt, speed 0 MHz (estimated)

Profiling through timer interrupt

          TIMER:0|

  samples|      %|

------------------

    30939 45.3684 cg

                  TIMER:0|

          samples|      %|

        ------------------

            28797 93.0767 libc-2.3.3.so

             1646  5.3201 cg

              495  1.5999 no-vmlinux

                1  0.0032 ld-linux.so.2

    22118 32.4335 multi-threads

                  TIMER:0|

          samples|      %|

        ------------------

            22076 99.8101 multi-threads

               41  0.1854 no-vmlinux

                1  0.0045 ld-linux.so.2

    14672 21.5148 no-vmlinux

      161  0.2361 busybox

                  TIMER:0|

          samples|      %|

        ------------------

              151 93.7888 no-vmlinux

                6  3.7267 ld-linux.so.2

                3  1.8634 libc-2.3.3.so

                1  0.6211 busybox

      155  0.2273 oprofiled

                  TIMER:0|

          samples|      %|

        ------------------

               91 58.7097 oprofiled

               63 40.6452 no-vmlinux

                1  0.6452 libc-2.3.3.so

      137  0.2009 multiple

                  TIMER:0|

          samples|      %|

        ------------------

              110 80.2920 multiple

               25 18.2482 no-vmlinux

                2  1.4599 ld-linux.so.2

       13  0.0191 hello

                  TIMER:0|

          samples|      %|

        ------------------

               13 100.000 no-vmlinux

 

 

# opreport -l

warning: /no-vmlinux could not be found.

CPU: CPU with timer interrupt, speed 0 MHz (estimated)

Profiling through timer interrupt

samples  %        image name               app name                 symbol name

22004    32.2663  multi-threads            multi-threads            slow_multiply

16408    24.0604  libc-2.3.3.so            cg                       __umodsi3

14672    21.5148  no-vmlinux               no-vmlinux               (no symbols)

3686      5.4051  libc-2.3.3.so            cg                       random_r

2651      3.8874  libc-2.3.3.so            cg                       strfry

1662      2.4371  libc-2.3.3.so            cg                       msort_with_tmp

1615      2.3682  libc-2.3.3.so            cg                       strcmp

1116      1.6365  cg                       cg                       compare

665       0.9751  libc-2.3.3.so            cg                       Laligned

562       0.8241  libc-2.3.3.so            cg                       _int_malloc

503       0.7376  libc-2.3.3.so            cg                       _wordcopy_fwd_aligned

495       0.7259  no-vmlinux               cg                       (no symbols)

429       0.6291  libc-2.3.3.so            cg                       memcpy

371       0.5440  libc-2.3.3.so            cg                       malloc

360       0.5279  cg                       cg                       .plt

170       0.2493  cg                       cg                       repeat

151       0.2214  no-vmlinux               busybox                  (no symbols)

133       0.1950  libc-2.3.3.so            cg                       strdup

108       0.1584  multiple                 multiple                 slow_multiply

63        0.0924  no-vmlinux               oprofiled                (no symbols)

49        0.0719  libc-2.3.3.so            cg                       Llastword

41        0.0601  no-vmlinux               multi-threads            (no symbols)

40        0.0587  libc-2.3.3.so            cg                       strlen

32        0.0469  multi-threads            multi-threads            main

25        0.0367  no-vmlinux               multiple                 (no symbols)

22        0.0323  libc-2.3.3.so            cg                       .plt

21        0.0308  multi-threads            multi-threads            fast_multiply

19        0.0279  multi-threads            multi-threads            thread_proc

15        0.0220  oprofiled                oprofiled                pop_buffer_value

# opreport -l  --merge=lib

warning: /no-vmlinux could not be found.

CPU: CPU with timer interrupt, speed 0 MHz (estimated)

Profiling through timer interrupt

samples  %        app name                 symbol name

47477    47.3317  no-vmlinux               (no symbols)

22004    21.9367  multi-threads            slow_multiply

16408    16.3578  libc-2.3.3.so            __umodsi3

3686      3.6747  libc-2.3.3.so            random_r

2651      2.6429  libc-2.3.3.so            strfry

1662      1.6569  libc-2.3.3.so            msort_with_tmp

1615      1.6101  libc-2.3.3.so            strcmp

1116      1.1126  cg                       compare

665       0.6630  libc-2.3.3.so            Laligned

563       0.5613  libc-2.3.3.so            _int_malloc

503       0.5015  libc-2.3.3.so            _wordcopy_fwd_aligned

429       0.4277  libc-2.3.3.so            memcpy

371       0.3699  libc-2.3.3.so            malloc

360       0.3589  cg                       .plt

170       0.1695  cg                       repeat

133       0.1326  libc-2.3.3.so            strdup

108       0.1077  multiple                 slow_multiply

49        0.0489  libc-2.3.3.so            Llastword

40        0.0399  libc-2.3.3.so            strlen

33        0.0329  oprofiled                pop_buffer_value

32        0.0319  multi-threads            main

22        0.0219  libc-2.3.3.so            .plt

21        0.0209  multi-threads            fast_multiply

20        0.0199  oprofiled                odb_update_node

19        0.0189  multi-threads            thread_proc

17        0.0169  oprofiled                opd_put_sample

15        0.0150  oprofiled                sfile_log_sample

13        0.0130  oprofiled                opd_process_samples

12        0.0120  oprofiled                odb_open_count

11        0.0110  oprofiled                sfile_find

9         0.0090  oprofiled                get_file

9         0.0090  oprofiled                is_escape_code

# opreport -l  --merge=lib 'image:/work/apps/cg'

warning: /no-vmlinux could not be found.

CPU: CPU with timer interrupt, speed 0 MHz (estimated)

Profiling through timer interrupt

samples  %        app name                 symbol name

16408    53.0334  libc-2.3.3.so            __umodsi3

3686     11.9138  libc-2.3.3.so            random_r

2651      8.5685  libc-2.3.3.so            strfry

1662      5.3719  libc-2.3.3.so            msort_with_tmp

1615      5.2199  libc-2.3.3.so            strcmp

1116      3.6071  cg                       compare

665       2.1494  libc-2.3.3.so            Laligned

562       1.8165  libc-2.3.3.so            _int_malloc

503       1.6258  libc-2.3.3.so            _wordcopy_fwd_aligned

495       1.5999  no-vmlinux               (no symbols)

429       1.3866  libc-2.3.3.so            memcpy

371       1.1991  libc-2.3.3.so            malloc

360       1.1636  cg                       .plt

170       0.5495  cg                       repeat

133       0.4299  libc-2.3.3.so            strdup

49        0.1584  libc-2.3.3.so            Llastword

40        0.1293  libc-2.3.3.so            strlen

22        0.0711  libc-2.3.3.so            .plt

1         0.0032  ld-linux.so.2            _dl_relocate_object

1         0.0032  libc-2.3.3.so            _IO_file_stat

# opreport -l  'image:/work/apps/cg'           

warning: /no-vmlinux could not be found.

CPU: CPU with timer interrupt, speed 0 MHz (estimated)

Profiling through timer interrupt

samples  %        image name               symbol name

16408    53.0334  libc-2.3.3.so            __umodsi3

3686     11.9138  libc-2.3.3.so            random_r

2651      8.5685  libc-2.3.3.so            strfry

1662      5.3719  libc-2.3.3.so            msort_with_tmp

1615      5.2199  libc-2.3.3.so            strcmp

1116      3.6071  cg                       compare

665       2.1494  libc-2.3.3.so            Laligned

# opreport  'image:/work/apps/cg'  

CPU: CPU with timer interrupt, speed 0 MHz (estimated)

Profiling through timer interrupt

          TIMER:0|

  samples|      %|

------------------

    30939 100.000 cg

                  TIMER:0|

          samples|      %|

        ------------------

            28797 93.0767 libc-2.3.3.so

             1646  5.3201 cg

              495  1.5999 no-vmlinux

                1  0.0032 ld-linux.so.2

 

# opreport  'image:/work/apps/*'

CPU: CPU with timer interrupt, speed 0 MHz (estimated)

Profiling through timer interrupt

          TIMER:0|

  samples|      %|

------------------

    30939 58.1484 cg

                  TIMER:0|

          samples|      %|

        ------------------

            28797 93.0767 libc-2.3.3.so

             1646  5.3201 cg

              495  1.5999 no-vmlinux

                1  0.0032 ld-linux.so.2

    22118 41.5697 multi-threads

                  TIMER:0|

          samples|      %|

        ------------------

            22076 99.8101 multi-threads

               41  0.1854 no-vmlinux

                1  0.0045 ld-linux.so.2

      137  0.2575 multiple

                  TIMER:0|

          samples|      %|

        ------------------

              110 80.2920 multiple

               25 18.2482 no-vmlinux

                2  1.4599 ld-linux.so.2

       13  0.0244 hello

                  TIMER:0|

          samples|      %|

        ------------------

               13 100.000 no-vmlinux

 

 

# opreport -l 'image:/work/apps/*'

warning: /no-vmlinux could not be found.

CPU: CPU with timer interrupt, speed 0 MHz (estimated)

Profiling through timer interrupt

samples  %        image name               app name                 symbol name

22004    41.3555  multi-threads            multi-threads            slow_multiply

16408    30.8380  libc-2.3.3.so            cg                       __umodsi3

3686      6.9277  libc-2.3.3.so            cg                       random_r

2651      4.9824  libc-2.3.3.so            cg                       strfry

1662      3.1236  libc-2.3.3.so            cg                       msort_with_tmp

1615      3.0353  libc-2.3.3.so            cg                       strcmp

1116      2.0975  cg                       cg                       compare

665       1.2498  libc-2.3.3.so            cg                       Laligned

562       1.0563  libc-2.3.3.so            cg                       _int_malloc

503       0.9454  libc-2.3.3.so            cg                       _wordcopy_fwd_aligned

495       0.9303  no-vmlinux               cg                       (no symbols)

429       0.8063  libc-2.3.3.so            cg                       memcpy

371       0.6973  libc-2.3.3.so            cg                       malloc

360       0.6766  cg                       cg                       .plt

170       0.3195  cg                       cg                       repeat

133       0.2500  libc-2.3.3.so            cg                       strdup

108       0.2030  multiple                 multiple                 slow_multiply

49        0.0921  libc-2.3.3.so            cg                       Llastword

41        0.0771  no-vmlinux               multi-threads            (no symbols)

40        0.0752  libc-2.3.3.so            cg                       strlen

32        0.0601  multi-threads            multi-threads            main

25        0.0470  no-vmlinux               multiple                 (no symbols)

22        0.0413  libc-2.3.3.so            cg                       .plt

21        0.0395  multi-threads            multi-threads            fast_multiply

19        0.0357  multi-threads            multi-threads            thread_proc

13        0.0244  no-vmlinux               hello                    (no symbols)

2         0.0038  ld-linux.so.2            multiple                 _dl_relocate_object

2         0.0038  multiple                 multiple                 main

1         0.0019  ld-linux.so.2            cg                       _dl_relocate_object

1         0.0019  ld-linux.so.2            multi-threads            do_lookup_versioned

1         0.0019  libc-2.3.3.so            cg                       _IO_file_stat

 

# opreport -l --merge=lib 'image:/work/apps/*'

warning: /no-vmlinux could not be found.

CPU: CPU with timer interrupt, speed 0 MHz (estimated)

Profiling through timer interrupt

samples  %        app name                 symbol name

22004    41.3555  multi-threads            slow_multiply

16408    30.8380  libc-2.3.3.so            __umodsi3

3686      6.9277  libc-2.3.3.so            random_r

2651      4.9824  libc-2.3.3.so            strfry

1662      3.1236  libc-2.3.3.so            msort_with_tmp

1615      3.0353  libc-2.3.3.so            strcmp

1116      2.0975  cg                       compare

665       1.2498  libc-2.3.3.so            Laligned

574       1.0788  no-vmlinux               (no symbols)

562       1.0563  libc-2.3.3.so            _int_malloc

503       0.9454  libc-2.3.3.so            _wordcopy_fwd_aligned

429       0.8063  libc-2.3.3.so            memcpy

371       0.6973  libc-2.3.3.so            malloc

360       0.6766  cg                       .plt

170       0.3195  cg                       repeat

133       0.2500  libc-2.3.3.so            strdup

108       0.2030  multiple                 slow_multiply

49        0.0921  libc-2.3.3.so            Llastword

40        0.0752  libc-2.3.3.so            strlen

32        0.0601  multi-threads            main

22        0.0413  libc-2.3.3.so            .plt

21        0.0395  multi-threads            fast_multiply

19        0.0357  multi-threads            thread_proc

3         0.0056  ld-linux.so.2            _dl_relocate_object

2         0.0038  multiple                 main

1         0.0019  ld-linux.so.2            do_lookup_versioned

1         0.0019  libc-2.3.3.so            _IO_file_stat

#

#

# opreport -callgraph  'image:/work/apps/cg'  

h isn't a valid directory

opreport error: invalid --image-path= options

# opreport --callgraph  'image:/work/apps/cg'

warning: /no-vmlinux could not be found.

CPU: CPU with timer interrupt, speed 0 MHz (estimated)

Profiling through timer interrupt

samples  %        image name               symbol name

-------------------------------------------------------------------------------

16408    53.0334  libc-2.3.3.so            __umodsi3

  16408    100.000  libc-2.3.3.so            __umodsi3 [self]

-------------------------------------------------------------------------------

3686     11.9138  libc-2.3.3.so            random_r

  3686     100.000  libc-2.3.3.so            random_r [self]

-------------------------------------------------------------------------------

2651      8.5685  libc-2.3.3.so            strfry

  2651     100.000  libc-2.3.3.so            strfry [self]

-------------------------------------------------------------------------------

1662      5.3719  libc-2.3.3.so            msort_with_tmp

  1662     100.000  libc-2.3.3.so            msort_with_tmp [self]

-------------------------------------------------------------------------------

1615      5.2199  libc-2.3.3.so            strcmp

  1615     100.000  libc-2.3.3.so            strcmp [self]

-------------------------------------------------------------------------------

1116      3.6071  cg                       compare

  1116     100.000  cg                       compare [self]

-------------------------------------------------------------------------------

665       2.1494  libc-2.3.3.so            Laligned

  665      100.000  libc-2.3.3.so            Laligned [self]

-------------------------------------------------------------------------------

562       1.8165  libc-2.3.3.so            _int_malloc

  562      100.000  libc-2.3.3.so            _int_malloc [self]

-------------------------------------------------------------------------------

503       1.6258  libc-2.3.3.so            _wordcopy_fwd_aligned

  503      100.000  libc-2.3.3.so            _wordcopy_fwd_aligned [self]

-------------------------------------------------------------------------------

495       1.5999  no-vmlinux               (no symbols)

  495      100.000  no-vmlinux               (no symbols) [self]

-------------------------------------------------------------------------------

429       1.3866  libc-2.3.3.so            memcpy

  429      100.000  libc-2.3.3.so            memcpy [self]

-------------------------------------------------------------------------------

371       1.1991  libc-2.3.3.so            malloc

  371      100.000  libc-2.3.3.so            malloc [self]

-------------------------------------------------------------------------------

360       1.1636  cg                       .plt

  360      100.000  cg                       .plt [self]

-------------------------------------------------------------------------------

170       0.5495  cg                       repeat

  170      100.000  cg                       repeat [self]

-------------------------------------------------------------------------------

133       0.4299  libc-2.3.3.so            strdup

  133      100.000  libc-2.3.3.so            strdup [self]

-------------------------------------------------------------------------------

49        0.1584  libc-2.3.3.so            Llastword

  49       100.000  libc-2.3.3.so            Llastword [self]

-------------------------------------------------------------------------------

40        0.1293  libc-2.3.3.so            strlen

  40       100.000  libc-2.3.3.so            strlen [self]

-------------------------------------------------------------------------------

22        0.0711  libc-2.3.3.so            .plt

  22       100.000  libc-2.3.3.so            .plt [self]

-------------------------------------------------------------------------------

1         0.0032  ld-linux.so.2            _dl_relocate_object

  1        100.000  ld-linux.so.2            _dl_relocate_object [self]

-------------------------------------------------------------------------------

1         0.0032  libc-2.3.3.so            _IO_file_stat

  1        100.000  libc-2.3.3.so            _IO_file_stat [self]

-------------------------------------------------------------------------------

 

 

 

 

# opreport --callgraph  'image:/work/apps/multiple'

warning: /no-vmlinux could not be found.

CPU: CPU with timer interrupt, speed 0 MHz (estimated)

Profiling through timer interrupt

samples  %        image name               symbol name

-------------------------------------------------------------------------------

108      78.8321  multiple                 slow_multiply

  108      100.000  multiple                 slow_multiply [self]

-------------------------------------------------------------------------------

25       18.2482  no-vmlinux               (no symbols)

  25       100.000  no-vmlinux               (no symbols) [self]

-------------------------------------------------------------------------------

2         1.4599  ld-linux.so.2            _dl_relocate_object

  2        100.000  ld-linux.so.2            _dl_relocate_object [self]

-------------------------------------------------------------------------------

2         1.4599  multiple                 main

  2        100.000  multiple                 main [self]

-------------------------------------------------------------------------------