Bug #5039: Re: socket related errors on chkbuild

• Status: Closed
• Assignee: nahi (Hiroshi Nakamura)
• Target version: 1.9.3
• ruby -v: -

[akr (Akira Tanaka)]

2011年7月16日23:09 Tanaka Akira [e-mail]:

1. Error:
   test_write_nonblock(OpenSSL::TestPair):

これは原因が違うようで、直っていません。

これが起き始めたのはそんなに古くないのですね。
http://www.rubyist.net/~akr/chkbuild/debian/ruby-trunk/log/20110624T071200Z.diff.html.gz
からなんですが、

+Fri Jun 24 15:54:14 2011 Hiroshi Nakamura [e-mail]
+

•   * ext/openssl/ossl_ssl.c (ossl_ssl_shutdown): Try to shutdown SSL
  

•     connection more gracefully. Call SSL_shutdown() max 4 times until it
  

•     returns 1 (success). Bi-directional SSL close has several states but
  

•     SSL_shutdown() kicks only 1 transition per call. Max 4 is from
  

•     mod_ssl.c of Apache httpd that says 'max 2x pending + 2x data = 4'.
  

•     See #4237.
  

というコミット以来のようです。

[田中 哲][たなか あきら][Tanaka Akira]

[kosaki (Motohiro KOSAKI)]

• Category set to core
• Status changed from Open to Assigned
• Assignee set to nahi (Hiroshi Nakamura)
• Target version set to 1.9.3

[kosaki (Motohiro KOSAKI)]

1. Error:
   test_write_nonblock(OpenSSL::TestPair):

これは原因が違うようで、直っていません。

これが起き始めたのはそんなに古くないのですね。
http://www.rubyist.net/~akr/chkbuild/debian/ruby-trunk/log/20110624T071200Z.diff.html.gz
からなんですが、

+Fri Jun 24 15:54:14 2011 Hiroshi Nakamura [e-mail]
+

•   * ext/openssl/ossl_ssl.c (ossl_ssl_shutdown): Try to shutdown SSL
  

•     connection more gracefully. Call SSL_shutdown() max 4 times until it
  

•     returns 1 (success). Bi-directional SSL close has several states but
  

•     SSL_shutdown() kicks only 1 transition per call. Max 4 is from
  

•     mod_ssl.c of Apache httpd that says 'max 2x pending + 2x data = 4'.
  

•     See #4237.
  

というコミット以来のようです。

ということなら regressionなので特別な理由が無い限り1.9.3 releaseまでに修正要と
思います。

[kosaki (Motohiro KOSAKI)]

1. Error:
   test_write_nonblock(OpenSSL::TestPair):

これは原因が違うようで、直っていません。

これが起き始めたのはそんなに古くないのですね。
http://www.rubyist.net/~akr/chkbuild/debian/ruby-trunk/log/20110624T071200Z.diff.html.gz
からなんですが、

+Fri Jun 24 15:54:14 2011 Hiroshi Nakamura [e-mail]
+

•   * ext/openssl/ossl_ssl.c (ossl_ssl_shutdown): Try to shutdown SSL
  

•     connection more gracefully. Call SSL_shutdown() max 4 times until it
  

•     returns 1 (success). Bi-directional SSL close has several states but
  

•     SSL_shutdown() kicks only 1 transition per call. Max 4 is from
  

•     mod_ssl.c of Apache httpd that says 'max 2x pending + 2x data = 4'.
  

•     See #4237.
  

というコミット以来のようです。

ということなら regressionなので特別な理由が無い限り1.9.3 releaseまでに修正要と
思います。

[nahi (Hiroshi Nakamura)]

水曜日に見ます。が、再現させるのに時間がかかるかもしれません。手元では出てないんですが、他にこのエラー出てる方が居たら情報提供お願いします。

akrさんに聞いたところによると、boronはDebian lennyで、Debian lennyは0.9.8gなんですね。それほど古いわけでもないなあ。

[akr (Akira Tanaka)]

2011年7月18日7:15 Hiroshi Nakamura [e-mail]:

水曜日に見ます。が、再現させるのに時間がかかるかもしれません。手元では出てないんですが、他にこのエラー出てる方が居たら情報提供お願いします。

boron でない、手元の Debian lenny でも起きますね。

また、検索すると、
http://redmine.ruby-lang.org/issues/4923 では
Ubuntu 10.04 with OpenSSL 0.9.8k でも起きているという報告がありますね。
(なひさんのコメントもあります。)

akrさんに聞いたところによると、boronはDebian lennyで、Debian lennyは0.9.8gなんですね。それほど古いわけでもないなあ。

boron% ldd .ext/i686-linux/openssl.so
linux-gate.so.1 => (0xb7fe8000)
libssl.so.0.9.8 => /usr/lib/i686/cmov/libssl.so.0.9.8 (0xb7f4c000)
libcrypto.so.0.9.8 => /usr/lib/i686/cmov/libcrypto.so.0.9.8 (0xb7df9000)
libpthread.so.0 => /lib/i686/nosegneg/libpthread.so.0 (0xb7de0000)
librt.so.1 => /lib/i686/nosegneg/librt.so.1 (0xb7dd6000)
libdl.so.2 => /lib/i686/nosegneg/libdl.so.2 (0xb7dd2000)
libcrypt.so.1 => /lib/i686/nosegneg/libcrypt.so.1 (0xb7da0000)
libm.so.6 => /lib/i686/nosegneg/libm.so.6 (0xb7d7a000)
libc.so.6 => /lib/i686/nosegneg/libc.so.6 (0xb7c1c000)
libz.so.1 => /usr/lib/libz.so.1 (0xb7c07000)
/lib/ld-linux.so.2 (0x80000000)
boron% dpkg -l|grep libssl
ii libssl-dev 0.9.8g-15+lenny11
SSL development libraries, header files and
ii libssl0.9.7 0.9.7k-3.1etch5
SSL shared libraries
ii libssl0.9.8 0.9.8g-15+lenny11
SSL shared libraries

[田中 哲][たなか あきら][Tanaka Akira]

[akr (Akira Tanaka)]

2011年7月18日7:15 Hiroshi Nakamura [e-mail]:

水曜日に見ます。が、再現させるのに時間がかかるかもしれません。手元では出てないんですが、他にこのエラー出てる方が居たら情報提供お願いします。

boron でない、手元の Debian lenny でも起きますね。

また、検索すると、
http://redmine.ruby-lang.org/issues/4923 では
Ubuntu 10.04 with OpenSSL 0.9.8k でも起きているという報告がありますね。
(なひさんのコメントもあります。)

akrさんに聞いたところによると、boronはDebian lennyで、Debian lennyは0.9.8gなんですね。それほど古いわけでもないなあ。

boron% ldd .ext/i686-linux/openssl.so
linux-gate.so.1 => (0xb7fe8000)
libssl.so.0.9.8 => /usr/lib/i686/cmov/libssl.so.0.9.8 (0xb7f4c000)
libcrypto.so.0.9.8 => /usr/lib/i686/cmov/libcrypto.so.0.9.8 (0xb7df9000)
libpthread.so.0 => /lib/i686/nosegneg/libpthread.so.0 (0xb7de0000)
librt.so.1 => /lib/i686/nosegneg/librt.so.1 (0xb7dd6000)
libdl.so.2 => /lib/i686/nosegneg/libdl.so.2 (0xb7dd2000)
libcrypt.so.1 => /lib/i686/nosegneg/libcrypt.so.1 (0xb7da0000)
libm.so.6 => /lib/i686/nosegneg/libm.so.6 (0xb7d7a000)
libc.so.6 => /lib/i686/nosegneg/libc.so.6 (0xb7c1c000)
libz.so.1 => /usr/lib/libz.so.1 (0xb7c07000)
/lib/ld-linux.so.2 (0x80000000)
boron% dpkg -l|grep libssl
ii libssl-dev 0.9.8g-15+lenny11
SSL development libraries, header files and
ii libssl0.9.7 0.9.7k-3.1etch5
SSL shared libraries
ii libssl0.9.8 0.9.8g-15+lenny11
SSL shared libraries

[田中 哲][たなか あきら][Tanaka Akira]

[kosaki (Motohiro KOSAKI)]

• Priority changed from Normal to 5

[nahi (Hiroshi Nakamura)]

• Status changed from Assigned to Closed

田中さんの書かれているように、#4923の件と同じでした（#4923は、最初に登録されていたエラーと、途中から議論していたエラーがすり替わっており、その後者と、今回boronで発生するようになったエラーが同じでした）。

SSLSocket#closeでOpenSSLのSSL_shutdown()を呼んでいるんですが、返ってきた後のエラーチェック（およびOpenSSL側エラースタックのクリア）をしていませんでした。使う理由もないので、trunkはr32685、ruby_1_9_3はr32660で、エラースタックをクリアするようにしました。r32219でSSL_shutdownを呼ぶ回数が増えたので、このエラーが発言し易くなったものと考えています。

boronのchkbuildでエラーがなくなったので、Closeします。