Показывает ли `perf stat` (и` time`!) Неверные результаты при запуске `perf trace -s`? - PullRequest
Новая
       13

Показывает ли `perf stat` (и` time`!) Неверные результаты при запуске `perf trace -s`?

1 голос
/ 02 мая 2019

Я использую kernel-5.0.9-200.fc29.x86_64 (и пакет perf с тем же номером версии).

В приведенной ниже команде сообщаемое msec task-clock намного большечем сумма seconds user и seconds sys.

Это реальные цифры или это результат ограничения или ошибки?

Если все они реальные цифры, точто означает task-clock?Это не время процессора (user + sys).Кроме того, я хотел бы знать, почему вторая команда, приведенная ниже, занимает на 10 секунд меньше реального времени, но 50 секунд больше времени ЦП: -).

РЕДАКТИРОВАТЬ: в течение первых нескольких секунд,первая команда показывает много сообщений красным цветом: LOST 5 events!

$ sudo time perf stat perf trace -s dnf -y --releasever=30 --installroot=$HOME/nspawn/fedora-30 --disablerepo='*' --enablerepo=fedora --enablerepo=updates install systemd passwd dnf fedora-release vim-minimal

... 

         91,686.29 msec task-clock                #    0.463 CPUs utilized          
           535,179      context-switches          #    0.006 M/sec                  
             5,244      cpu-migrations            #    0.057 K/sec                  
           399,484      page-faults               #    0.004 M/sec                  
   238,663,625,948      cycles                    #    2.603 GHz                    
   348,081,456,496      instructions              #    1.46  insn per cycle         
    51,406,362,901      branches                  #  560.677 M/sec                  
     1,207,307,303      branch-misses             #    2.35% of all branches        

     198.215217338 seconds time elapsed

       5.570430000 seconds user
      11.160145000 seconds sys


5.59user 11.36system 3:18.45elapsed 8%CPU (0avgtext+0avgdata 28212maxresident)k
2760inputs+0outputs (16major+6641minor)pagefaults 0swaps

Сравнение:

$ sudo time perf stat dnf -y --releasever=30 --installroot=/home/alan-sysop/nspawn/fedora-30 --disablerepo=* --enablerepo=fedora --enablerepo=updates install systemd passwd dnf fedora-release vim-minimal

... 

         69,277.16 msec task-clock                #    0.371 CPUs utilized          
            22,223      context-switches          #    0.321 K/sec                  
               858      cpu-migrations            #    0.012 K/sec                  
           380,117      page-faults               #    0.005 M/sec                  
   187,946,998,307      cycles                    #    2.713 GHz                    
   312,329,248,251      instructions              #    1.66  insn per cycle         
    44,516,425,061      branches                  #  642.584 M/sec                  
     1,020,032,328      branch-misses             #    2.29% of all branches        

     186.623370416 seconds time elapsed

      62.278802000 seconds user
       6.379658000 seconds sys


62.31user 6.57system 3:06.86elapsed 36%CPU (0avgtext+0avgdata 423700maxresident)k
0inputs+2562096outputs (23major+379286minor)pagefaults 0swaps

1 Ответ

3 голосов
/ 02 мая 2019

Фактического несоответствия нет.

seconds user / seconds sys отображает статистику rusage только для прямого потомка (все потоки в процессе), где счетчики по умолчанию наследовать до всех дочерних и внучатых процессов.

То есть меньшее время ЦП относится только к утилите perf trace, тогда как больше время включает фактический dnf процесс.

Вы также можете подтвердить, что, запустив 

perf stat --no-inherit perf trace ...

Вы не можете включить внуков в сводку об использовании ресурса.

PS Выне следует предполагать, что поведение сводной информации об использовании ресурсов остается одинаковым для различных perf версий.

...