Bug #4178

test/rubygems/gemutilities.rb で、よくわからない ArgumentError

Added by metanest (Makoto Kishimoto) over 1 year ago. Updated about 1 year ago.

[ruby-dev:42832]
Status:Closed Start date:12/21/2010
Priority:Low Due date:
Assignee:- % Done:

100%

Category:core
Target version:-
ruby -v:ruby 1.9.3dev (2010-12-21 trunk 30285) [x86_64-freebsd8.2]

Description

(bisectによれば)trunk の r29064 以降、test-all でたまに発生しているのですが、
(たとえば http://mrkn.jp/chkbuild/ruby-trunk/log/20100925T163004Z.diff.html.gz
  <n>) Error:
+test_install_ignore_dependencies(TestGemDependencyInstaller):
+ArgumentError: wrong number of arguments (0 for 1)
+    /Users/chkbuild/build/ruby-trunk/<buildtime>/ruby/test/rubygems/gemutilities.rb:276:in `block (2 levels) in util_build_gem'
+    /Users/chkbuild/build/ruby-trunk/<buildtime>/ruby/test/rubygems/gemutilities.rb:275:in `block in util_build_gem'
+    /Users/chkbuild/build/ruby-trunk/<buildtime>/ruby/test/rubygems/gemutilities.rb:268:in `chdir'
+    /Users/chkbuild/build/ruby-trunk/<buildtime>/ruby/test/rubygems/gemutilities.rb:268:in `util_build_gem'
+    /Users/chkbuild/build/ruby-trunk/<buildtime>/ruby/test/rubygems/gemutilities.rb:293:in `util_gem'
+    /Users/chkbuild/build/ruby-trunk/<buildtime>/ruby/test/rubygems/test_gem_dependency_installer.rb:50:in `setup'
+    ./test/runner.rb:17:in `<main>'
)
こんな感じで、test/rubygems/gemutilities.rb で、よくわからない ArgumentError が起きます。
TESTS に --gc-stress を付けたり、以下のようなスクリプトでほぼ100%再現します。

require_relative "test/rubygems/gemutilities"
class TestHoge < RubyGemTestCase
  def setup
    super
    GC.stress = true
    util_setup_fake_fetcher
  end

  def test_hoge
  end
end

Related issues

related to ruby-trunk - Bug #3136: reuse of singleton method definition causes SEGV Closed 04/12/2010
related to ruby-trunk - Bug #5762: Bug#4178 が sparc Solaris 10 + Oracle Solaris Studio 12.2 で再発 Closed 12/15/2011

Associated revisions

Revision 30432
Added by wanabe (_ wanabe) over 1 year ago

* vm.c (vm_define_method): guard iseq from GC while method definition. [ruby-dev:42832]

Revision 34071
Added by ngoto (Naohisa Goto) 5 months ago

* vm.c (vm_define_method): improve guard of iseq from GC. Fix failure or segmentation fault in test_singleton_method(TestGc) on sparc Solaris10 compiled with Oracle Solaris Studio 12.2. [Bug #5762] [ruby-dev:45000] [Bug #4178]

History

Updated by metanest (Makoto Kishimoto) over 1 year ago

もうちょっと小さくしてみました。以下のようなスクリプトで手元では再現します。

require "rubygems/package.rb"

open("/dev/null", "wb"){|io|
  Gem::Package::TarOutput.open(io){}
  GC.stress = true
  Gem::Package::TarOutput.open(io){}
}

手元の ruby 1.9 trunk で実行すると以下のようになります。

/usr/local/lib/ruby/1.9.1/rubygems/package/tar_output.rb:70:in `metadata=': wrong number of arguments (0 for 1) (ArgumentError)
        from /usr/local/lib/ruby/1.9.1/rubygems/package/tar_output.rb:75:in `block (3 levels) in add_gem_contents'
        from /usr/local/lib/ruby/1.9.1/rubygems/package/tar_writer.rb:83:in `new'
        from /usr/local/lib/ruby/1.9.1/rubygems/package/tar_output.rb:67:in `block (2 levels) in add_gem_contents'
        from /usr/local/lib/ruby/1.9.1/rubygems/package/tar_output.rb:65:in `wrap'
        from /usr/local/lib/ruby/1.9.1/rubygems/package/tar_output.rb:65:in `block in add_gem_contents'
        from /usr/local/lib/ruby/1.9.1/rubygems/package/tar_writer.rb:113:in `add_file'
        from /usr/local/lib/ruby/1.9.1/rubygems/package/tar_output.rb:63:in `add_gem_contents'
        from /usr/local/lib/ruby/1.9.1/rubygems/package/tar_output.rb:31:in `open'
        from foo.rb:6:in `block in <main>'
        from foo.rb:3:in `open'
        from foo.rb:3:in `<main>'

tar_output.rb の 75 行目にあるのは reader の "metadata" メソッドの呼び出しの
はずなのに、なぜか writer の "metadata=" メソッドの呼び出しにすりかわっています。

Updated by wanabe (_ wanabe) over 1 year ago

miniruby でも確認できるように縮めました。
:foo が 5 回表示されるはずが、:foo が 2 回表示された後は :bar と :foo が交互に表示されました。

5.times do 
    GC.stress = !GC.stress
    obj = Object.new
    def obj.foo() p :foo end
    def obj.bar() p :bar end
    obj.foo
end

r29063 をリバートするとこの現象は起きなくなりました。なぜかはわかりませんでした。
また、i386-mingw32 および i686-linux では再現せず x64-mingw64 で再現しました。
なにか 64-bit 環境に関係があるのかもしれません。

Updated by wanabe (_ wanabe) over 1 year ago

すみません、下記のパッチで症状が治まるか確認して頂けないでしょうか。
rb_iseq_clone で作られた iseqval が最適化でスタックに置かれず、GC されてしまっているのではないかと思います。

diff --git a/vm.c b/vm.c
index 015456b..4a80f5d 100644
--- a/vm.c
+++ b/vm.c
@@ -1838,6 +1838,7 @@ vm_define_method(rb_thread_t *th, VALUE obj, ID id, VALUE iseqval,

     if (miseq->klass) {
        iseqval = rb_iseq_clone(iseqval, 0);
+	RB_GC_GUARD(iseqval);
        GetISeqPtr(iseqval, miseq);
     }

Updated by metanest (Makoto Kishimoto) over 1 year ago

きしもとです

このエントリに登録したスクリプトで試しましたが、治まりました。

make test-all TESTS='--gc-stress -v rubygems' の実行を待っているのですが
終わりそうにないので、とりあえず報告まで。

On Wed, 29 Dec 2010 19:31:05 +0900
_ wanabe <redmine@ruby-lang.org> wrote:
> チケット #4178 が更新されました。 (by _ wanabe)
> 
> 
> すみません、下記のパッチで症状が治まるか確認して頂けないでしょうか。
> rb_iseq_clone で作られた iseqval が最適化でスタックに置かれず、GC されてしまっているのではないかと思います。
> 
> diff --git a/vm.c b/vm.c
> index 015456b..4a80f5d 100644
> --- a/vm.c
> +++ b/vm.c
> @@ -1838,6 +1838,7 @@ vm_define_method(rb_thread_t *th, VALUE obj, ID id, VALUE iseqval,
>  
>      if (miseq->klass) {
>         iseqval = rb_iseq_clone(iseqval, 0);
> +	RB_GC_GUARD(iseqval);
>         GetISeqPtr(iseqval, miseq);
>      }
>  
> 
> ----------------------------------------
> http://redmine.ruby-lang.org/issues/show/4178

Updated by wanabe (_ wanabe) over 1 year ago

  • Status changed from Open to Closed
  • % Done changed from 0 to 100
This issue was solved with changeset r30432.
Makoto, thank you for reporting this issue.
Your contribution to Ruby is greatly appreciated.
May Ruby be with you.

Also available in: Atom PDF