From: SASADA Koichi <ko1@...>
Date: 2010-10-08T12:38:19+09:00
Subject: [ruby-dev:42346] Re: [Ruby 1.9-Feature#3917][Open] [proposal] called_from() which is much faster than caller()

　ささだです。

　基本的に賛成なんですが、alternative なアイデアということで。


(1) caller の拡張案

  (a) 配列の要素数を指定するオプショナル引数を加える

    caller(n, m) => n 個上の caller から、m 個だけ情報を取り出す
    caller(0, 1) => ['...']
    caller(0, 2) => ['...', '...']

　＊一個しかほしくない時に、1要素の配列になってしまうのが嬉しくない。
　＊1 を指定したら配列じゃ無くす、というのは、きっと良くないんだろうな。

　(b) 配列の要素をFrameInfo オブジェクトとかいうものをでっちあげる

   caller => [frameinfo1, frameinfo2, ...]

    FrameInfo#type     # iseq->type 相当（でいいのか？）
    FrameInfo#name     # iseq->name 相当、メソッド名とか
    FrameInfo#line_no  # これまでと同じ行番号
    FrameInfo#filename # これまでと同じファイル名

  FrameInfo#to_str を定義することで、文字列を期待していた今までのコード
もそのまま動く（ほんとかな？）。

　iseq を持たせるような実装になるのかしらん。

　FrameInfo#binding とかやると、caller_binding みたいな話になるんだけ
ど、これはやらない。

　＊CallerEntry とかの名前のほうがいいのかしらん。


(2) called_from の返値を FrameInfo みたいなのにする

　FrameInfo の定義は上記の通り。


　以上です。


　vm_backtrace_each の順番は変えちゃうかも。でも、今 iseq 周りの実装をそ
う取っ替えしているので、この変更はもうちょっと待ってほしいかも。

# 日本語が混ざってると、つい日本語を追っちゃって、
# 意外と読みづらいという現象を発見しました...。


(2010/10/08 0:46), makoto kuwata wrote:
> Feature #3917: [proposal] called_from() which is much faster than caller()
> http://redmine.ruby-lang.org/issues/show/3917
> 
> 起票者: makoto kuwata
> ステータス: Open, 優先度: Normal
> カテゴリ: core, Target version: 1.9.x
> 
> I propose to introduce Kernel#called_from() which is similar to caller()
> but much faster than it.
> 
> 
> Background
> ----------
> 
> There are some cases to want to know from where current method is called.
> In this case, Kernel#caller() is used.
> 
> But Kernel#caller() has performance issues for these cases.
> 
> * caller() retrieves entire stack frame. It is too heavy.
> * caller() returns an array of "filename:linenum in `method'" string.
>   User must parse it and retrieve filename and linenum by rexp.
>   It is also very heavy weight task.
> 
> Therefore I propose Kernel#called_from() which is very light weight
> compared to caller(). A certain benchmark shows that called_from()
> is more than 20 times faster tan caller().
> 
> 現のメソッドがどこから呼び出されたかを知りたい場合がときどきある。
> こういう場合は通常 Kernel#caller() が使われる。
> 
> しかし Kernel#caller() は、こういった用途ではパフォーマンスが非常に悪い。
> 
> * caller() はスタックフレームをすべて取り醜。これは非常に重い操作。
> * caller() は "ファイル名:行番号 in `メソド名'" という文字列の配列を返す。
>   ユーザは正規表を使ってこの文字列をわざわざパースしなければならない。
>   これも修操作。
> 
> そため、Kernel#called_from() を追加することを提案する。
> このメソドは caller() と比べて非常に動作が軽く、ベンチマークでは
> called_from() は caller() と比べて20倍以上高速。
> 
> 
> Spec
> -----
> 
> call-seq:
>    called_from(start=    -> array or nil
> 
> Returns file name, line number, and method name of the stack.
> The optional _start_ parameter represents the number of stack
> entries to skip.
> 
> Returns +nil+ if _start_ is greater than the size of
> current execution stack.
> 
> Raises ArgumentError if _start_ is negative value.
> 
> 
> Example code
> ------------
> 
>   # example.rb
>    1:  def f1()
>    2:    f2()
>    3:  end
>    4:  def f2()
>    5:    f3()
>    6:  end
>    7:  def f3()
>    8:    p called_from()    #=["example.rb", 5, "f2"]
>    9:    p called_from(0)   #=["example.rb", 9, "f3"]
>   10:    p called_from(1)   #=["example.rb", 5, "f2"]
>   11:    p called_from(2)   #=["example.rb", 2, "f1"]
>   12:    p called_from(3)   #=["example.rb", 15, "<main>"]
>   13:    p called_from(4)   #=nil
>   14:  end
>   15:  f1()
> 
> 
> Use Case
> --------
> 
> Case 1: logging method
> 
>   def log_info(message)
>     filename, linenum, _ =alled_from()   # !!!
>     @logger.info "#{filename}:#{linenum}: #{message}"
>   end
> 
> 
> Case 2: debug print
> 
>   def debug(message)
>     filename, linenum, _ =alled_from()   # !!!
>     $stderr.puts "*** DEBUG: #{filename}:#{linenum}: #{message}"
>   end
> 
> 
> Case 3: deprecation message
> 
>   def send(*args)
>     filename, linenum, _ =alled_from()   # !!!
>     msg =`send()' is deprecated. use `__send__()' instead."
>     msg << " (file: #{filename}, line: #{linenum})"
>     $stderr.puts "*** warning: #{msg}"
>     __send__(*args)
>   end
> 
> 
> Case 4: ActiveSupport::Testing::Pending
> 
>   module ActiveSupport::Testing::Peding
>     def pending(description =", &block)
>         :
>       #caller[0] =(/(.*):(.*):in `(.*)'/)             # original
>       #@@pending_cases << "#{$3} at #{$1}, line #{$2}"  # original
>       #print "P"                                        # original
>       filenemae, linenum, method =alled_from()    # !!!
>       @@pending_cases << "#{method} at #{filename}, line #{linenum}"
>       print "P"
>         :
>     end
>   end
> 
> 
> Case 5: activesupport/lib/active_support/core_ext/module/delegation.rb
> 
>   class Module
>     def delegate(*methods)
>         :
>       #file, line =aller.first.split(':', 2)  # original
>       #line =ine.to_i                         # original
>       file, line, _ =alled_from()             # !!!
>         :
>       module_eval(<<-EOS, file, line - 5)
>         :
>     end
>   end
> 
> 
> Case 6: caching helper for template system
> 
>   def cache_with(key)
>     data, created_at =_cache_store.get(key)
>     filename, =alled_from()   # !!!
>     ## if template file is newer than cached data then clear cache.
>     ## (performance is very important in this case.)
>     if created_at < File.mtime(filename)
>       data =il
>       @_cache_store.del(key)
>     end
>     ##
>     if data.nil?
>       len =_buf.length
>       yield
>       data =_buf[len..-1]
>       @_cache_store.set(key, data)
>     else
>       @_buf << data
>     end
>     nil
>   end
>   
>   ## in template file
>   <% cache_with("orders/#{@order.id}") do %>
>     <p>Order ID: <%=@order.id %></p>
>     <p>Customer: <%=@order.customer.name %></p>
>   <% end %>
> 
> 
> Benchmark
> ---------
> 
> Attached benchmark shows that called_from() is much faster than caller().
> This is very important for logging or template timestamp check.
> 
>     $ ./ruby -s bench.rb -N0000
>                                         user     system      total        real
>     caller()[0]                     1.890000   0.010000   1.900000 (  1.941812)
>     caller()[0] (retrieve)          2.190000   0.010000   2.200000 (  2.225966)
>     called_from()                   0.100000   0.000000   0.100000 (  0.102810)
>     called_from() (retrieve)        0.100000   0.000000   0.100000 (  0.102133)
> 
> 
> Another Solutions
> -----------------
> 
> Adding new gobal function may be refused.
> The followings are another solutions instead of new global function.
> 
> * Extend caller() to take 'count' parameter.
>   For example:
> 
>     start =
>     count =
>     caller(start, count)  #=["filename:linenum in `method'"]
> 
> * Extend caller() to take 'conbine' flag.
>   For example:
> 
>     start =
>     count =il
>     conbine =alse
>     caller(start, count, conbine)
>                          #=[["filename", linenum, "method"],
> 			 #    ["filename", linenum, "method"],
> 			 #    .... ]
> 
> * Add new standard library 'called_from.so' instead of Kernel#called_from().
> 
> 新しいグローバル関数を導入するのは拒絶される可能が高い。
> そ場合は、caller()を拡張してcalled_from()相当のことができるように
> してもらえるとうれしい。
> あるいは Kernel#called_from() ではなくても called_from.so を標什付
> する方針でもいい。
> 
> 
> Note
> ----
> 
> * I tried to implement the above solutions, but failed because
>   vm_backtrace_each() seems to search stack frames in the reverse
>   order of what called_from() requires.
> 
> * I can implement called_from() as user library in Ruby 1.8.
>   http://rubygems.org/gems/called_from
>   It is allowed to access to stack frame in Ruby 1.8, but no in 1.9.
>   This is why I submit this propose.
> 
> * 実は上記のanother solutionsを実装しようとしたが、called_from() では
>   直近のスタックフレームから辿りたいのに対し、vm_backtrace_each() は
>   逆の瞬で辿ることしかできないようなので、実装を諦めた。
> 
> * Ruby 1.8 では拡張モジュールからスタックフレームにアクセスできるので
>   ライブラリとして実装した。
>   http://rubygems.org/gems/called_from
>   けど1.9ではスタックフレームへのアクセスができないので、ライブラリが
>   作れない。そため今回このような提案をしてみた。
> 
> 
> ----------------------------------------
> http://redmine.ruby-lang.org


-- 
// SASADA Koichi at atdot dot net