瞭解ruby-prof輸出

Question

我在我的一個程序上運行了ruby-profiler。我試圖弄清楚每個字段的含義。我猜一切都是CPU時間（而不是掛鐘時間），這太棒了。我想了解「---」代表什麼。那裏有一些堆棧信息。呼叫a/b是什麼意思？

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輸出的每個部分被分解成特定函數的檢查。例如，查看輸出的第一部分。在FrameParser的read_frames方法是重點，它基本上是說以下內容：這是異形FrameParser＃read_frames

FrameParser＃read_frames內花在執行時間

• 100％被稱爲6次。
• 對read_frames的6次調用中有5次來自FrameParser＃receive_data，這佔到了執行時間的100％（這是read_frames行上面​​的行）。
• read_frames（但在第一節中）下方的行是FrameParser＃read_frames調用的所有方法（您應該知道，因爲這看起來像是您的代碼），有多少個方法總共調用read_frames負責（a/b呼叫欄），以及這些呼叫花費多少時間。他們是由哪個人執行時間最多的命令。在你的情況下，這是ChatServer類的receive_frame方法。
• 然後，您可以看看側重於receive_frames的部分（2個向下並以receive_frame上的'100％'行爲中心），並查看其性能如何分解。每個部分的設置方式相同，通常後續功能調用佔用最多的時間是下一部分的重點。 ruby-prof將繼續通過完整的調用堆棧來執行此操作。你可以儘可能深入地找到你想要解決的瓶頸問題。