Ruby-prof çıkışını anlama

Question

Programlarımdan birinde ruby-profiler çalıştırdım. Her alanın ne anlama geldiğini anlamaya çalışıyorum. Sanırım her şey CPU zamanı (ve duvar saati değil), bu harika bir şey. "---" nin ne anlama geldiğini anlamak istiyorum. Orada bir çeşit yığın bilgisi var mı? A/b'nin anlamı nedir?

Thread ID: 81980260 
Total Time: 0.28 

    %total %self  total  self  wait  child   calls Name 
-------------------------------------------------------------------------------- 
         0.28  0.00  0.00  0.28    5/6  FrameParser#receive_data 
100.00% 0.00%  0.28  0.00  0.00  0.28    6  FrameParser#read_frames 
         0.28  0.00  0.00  0.28    4/4  ChatServerClient#receive_frame 
         0.00  0.00  0.00  0.00    5/47  Fixnum#+ 
         0.00  0.00  0.00  0.00    1/2  DebugServer#receive_frame 
         0.00  0.00  0.00  0.00   10/29  String#[] 
         0.00  0.00  0.00  0.00   10/21  <Class::Range>#allocate 
         0.00  0.00  0.00  0.00   10/71  String#index 
-------------------------------------------------------------------------------- 
100.00% 0.00%  0.28  0.00  0.00  0.28    5  FrameParser#receive_data 
         0.28  0.00  0.00  0.28    5/6  FrameParser#read_frames 
         0.00  0.00  0.00  0.00    5/16  ActiveSupport::CoreExtensions::String::OutputSafety#add_with_safety 
-------------------------------------------------------------------------------- 
         0.28  0.00  0.00  0.28    4/4  FrameParser#read_frames 
100.00% 0.00%  0.28  0.00  0.00  0.28    4  ChatServerClient#receive_frame 
         0.28  0.00  0.00  0.28    4/6  <Class::Lal>#safe_call 
-------------------------------------------------------------------------------- 
         0.00  0.00  0.00  0.00    1/6  <Class::Lal>#safe_call 
         0.00  0.00  0.00  0.00    1/6  DebugServer#receive_frame 
         0.28  0.00  0.00  0.28    4/6  ChatServerClient#receive_frame 
100.00% 0.00%  0.28  0.00  0.00  0.28    6  <Class::Lal>#safe_call 
         0.21  0.00  0.00  0.21    2/4  ChatUserFunction#register 
         0.06  0.00  0.00  0.06    2/2  ChatUserFunction#packet 
         0.01  0.00  0.00  0.01   4/130  Class#new 
         0.00  0.00  0.00  0.00    1/1  DebugServer#profile_stop 
         0.00  0.00  0.00  0.00    1/33  String#== 
         0.00  0.00  0.00  0.00    1/6  <Class::Lal>#safe_call 
         0.00  0.00  0.00  0.00    5/5  JSON#parse 
         0.00  0.00  0.00  0.00    5/8  <Class::Log>#log 
         0.00  0.00  0.00  0.00    5/5  String#strip! 
-------------------------------------------------------------------------------- 

Answer 1

Ruby-prof çıkışının her bölümü, belirli bir işlevin incelemesine ayrılmıştır. örneğin, çıktınızın ilk bölümüne bakın. FrameParser üzerinde read_frames yöntem odak noktası ve temelde şunu söylemektedir; FrameParser # read_frames

FrameParser # read_frames içinde harcandı profilli edildi icra zaman

• 100% 6 kez çağrıldı.
• read_frames öğelerine yapılan 6 çağrıdan 5'i FrameParser # alma_tadı'ndan geldi ve bu, yürütme süresinin% 100'ünü oluşturdu (bu, read_frames satırının üstündeki satırdır).
• read_frames (ancak bu ilk bölümün içinde) yönteminin altındaki çizgiler, FrameParser # read_frames çağrılarının (bu kodunuzun kodunuz gibi göründüğünden haberdar olmanız gerekir) yöntemlerinin tümüdür, bu yöntemlerden kaçının read_frames toplam sayısıdır (a/b çağrıları sütunu) sorumludur ve bu çağrıların ne kadar sürdüğü. En çok idam zamanlarını aldıklarından emirler. Durumunuzda, bu ChatServer sınıfında rece_frame yöntemidir.
• Daha sonra, rece_frames (2 aşağı ve alma_frame üzerinde '% 100' çizgisiyle ortalanmış) üzerine odaklanan bölüme bakabilirsiniz ve performansın nasıl bozulduğunu görebilirsiniz. Her bölüm aynı şekilde ayarlanır ve genellikle en fazla zaman alan sonraki işlev çağrısı, sonraki bölümün odak noktasıdır. ruby-prof bunu tam çağrı yığını boyunca yapmaya devam edecektir. Çözmek istediğiniz darboğazı bulana kadar istediğiniz kadar ilerleyebilirsiniz.