Result of trunk-asserts@phosphorus-docker at 2022-08-27 10:15:56 +0900

NG (d6f21b308b)[LOG]
test-all: 22294 tests, 5987604 assertions, 0 failures, 0 errors, 148 skips
test-spec:

commands:

 "make update-unicode  DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 0.
"make update-download DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 0.
"make update-rubyspec DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 0.
"make update-src      DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 0.
"make after-update    DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 512.
make[1]: *** No rule to make target 'id.h', needed by 'ripper.y'.  Stop.
make: *** [uncommon.mk:1202: /tmp/ruby/v3/src/trunk-asserts/ext/ripper/ripper.c] Error 2
"make miniruby DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 0.
"make ruby DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 0.
"make all DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 0.
"make install DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 0.
"make yes-btest TESTOPTS='-q -j6'" exit with 0.
"make yes-test-basic TESTS='-j6'" exit with 0.
make yes-test-all TESTOPTS='--stderr-on-failure' TESTS='-j6'
/tmp/ruby/v3/src/trunk-asserts/test/-ext-/econv/test_append.rb: cannot load such file -- -test-/econv (Test::Unit::ProxyError)
$$$ #<Timeout::Error: execution expired>
### enter analyzing mode for stuck processes
$$$ ps jx
 PPID   PID  PGID   SID TTY      TPGID STAT   UID   TIME COMMAND
   10    11    11    11 ?           -1 Ss    1001   0:00 sh build-loop.sh trunk-asserts --incremental --process-num=6
    1  1751    11    11 ?           -1 Sl    1001   0:04 /tmp/ruby/v3/build/trunk-asserts/ruby /tmp/ruby/v3/src/trunk-asserts/test/drb/ut_drb_drbssl.rb drbssl://localhost:35027 ut_drb_drbssl.rb
    1  1780    11    11 ?           -1 Sl    1001   0:03 /tmp/ruby/v3/build/trunk-asserts/ruby -rdrb/drb -rdrb/eq -rrinda/ring -rrinda/tuplespace -e       uri = ARGV[0]       DRb.start_service("druby://localhost:0")       ro = DRbObject.new_with_uri(uri)       ts = Rinda::TupleSpaceProxy.new(ro)       th = Thread.new do         ts.take([:test_take, nil])       rescue Interrupt         # Expected       end       Kernel.sleep(0.1)       th.raise(Interrupt) # causes loss of the taken tuple       ts.write([:barrier, :continue])       Kernel.sleep  druby://localhost:43519
    1  6826    11    11 ?           -1 Sl    1001   0:03 /tmp/ruby/v3/build/trunk-asserts/ruby -rdrb/drb -rdrb/eq -rrinda/ring -rrinda/tuplespace -e       uri = ARGV[0]       DRb.start_service("druby://localhost:0")       ro = DRbObject.new_with_uri(uri)       ts = Rinda::TupleSpaceProxy.new(ro)       th = Thread.new do         ts.take([:test_take, nil])       rescue Interrupt         # Expected       end       Kernel.sleep(0.1)       th.raise(Interrupt) # causes loss of the taken tuple       ts.write([:barrier, :continue])       Kernel.sleep  druby://localhost:35093
27412  8369    11    11 ?           -1 R     1001   0:00 ps jx
    1 11628    11    11 ?           -1 Sl    1001   0:03 /tmp/ruby/v3/build/trunk-asserts/ruby /tmp/ruby/v3/src/trunk-asserts/test/drb/ut_drb_drbunix.rb drbunix:/tmp/druby1993.0 ut_drb_drbunix.rb
    1 19652    11    11 ?           -1 Sl    1001   0:03 /tmp/ruby/v3/build/trunk-asserts/ruby -rdrb/drb -rdrb/eq -rrinda/ring -rrinda/tuplespace -e       uri = ARGV[0]       DRb.start_service("druby://localhost:0")       ro = DRbObject.new_with_uri(uri)       ts = Rinda::TupleSpaceProxy.new(ro)       th = Thread.new do         ts.take([:test_take, nil])       rescue Interrupt         # Expected       end       Kernel.sleep(0.1)       th.raise(Interrupt) # causes loss of the taken tuple       ts.write([:barrier, :continue])       Kernel.sleep  druby://localhost:34715
   11 27408    11    11 ?           -1 Sl    1001   0:00 ruby ./br.rb build_report trunk-asserts --incremental --process-num=6
27408 27411    11    11 ?           -1 S     1001   0:00 sh -c ruby /home/ko1/ruby/build-ruby/build-ruby.rb --target_name=trunk-asserts --logfile=/home/ko1/ruby/logs/brlog.trunk-asserts.20220826-190228 --root_dir=/tmp/ruby --timeout=21600 --build_opts='DEFS="-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline"'   --add-env=RUBY_TEST_SUBPROCESS_TIMEOUT_SCALE=50 --incremental --process-num=6
27411 27412    11    11 ?           -1 Sl    1001   0:00 ruby /home/ko1/ruby/build-ruby/build-ruby.rb --target_name=trunk-asserts --logfile=/home/ko1/ruby/logs/brlog.trunk-asserts.20220826-190228 --root_dir=/tmp/ruby --timeout=21600 --build_opts=DEFS="-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline" --add-env=RUBY_TEST_SUBPROCESS_TIMEOUT_SCALE=50 --incremental --process-num=6
    1 27462    11    11 ?           -1 Sl    1001   0:03 /tmp/ruby/v3/build/trunk-asserts/ruby -rdrb/drb -rdrb/eq -rrinda/ring -rrinda/tuplespace -e       uri = ARGV[0]       DRb.start_service("druby://localhost:0")       ro = DRbObject.new_with_uri(uri)       ts = Rinda::TupleSpaceProxy.new(ro)       th = Thread.new do         ts.take([:test_take, nil])       rescue Interrupt         # Expected       end       Kernel.sleep(0.1)       th.raise(Interrupt) # causes loss of the taken tuple       ts.write([:barrier, :continue])       Kernel.sleep  druby://localhost:36349
    1 28199    11    11 ?           -1 Sl    1001   0:03 /tmp/ruby/v3/build/trunk-asserts/ruby -rdrb/drb -rdrb/eq -rrinda/ring -rrinda/tuplespace -e       uri = ARGV[0]       DRb.start_service("druby://localhost:0")       ro = DRbObject.new_with_uri(uri)       ts = Rinda::TupleSpaceProxy.new(ro)       th = Thread.new do         ts.take([:test_take, nil])       rescue Interrupt         # Expected       end       Kernel.sleep(0.1)       th.raise(Interrupt) # causes loss of the taken tuple       ts.write([:barrier, :continue])       Kernel.sleep  druby://localhost:45991
27412 30442    11    11 ?           -1 Z     1001   0:00 [sh] <defunct>
{10=>[11], 1=>[1751, 1780, 6826, 11628, 19652, 27462, 28199], 27412=>[8369, 30442], 11=>[27408], 27408=>[27411], 27411=>[27412]}
$ timeout 60 gdb -p 8369 -x /home/ko1/ruby/build-ruby/gdbscript -batch -quiet 2> /dev/null
>>> Threads
No threads.
>>> Machine level backtrace
>>> Dump Ruby level backtrace (if possible)
#<Errno::ESRCH: No such process>
$ timeout 60 gdb -p 30442 -x /home/ko1/ruby/build-ruby/gdbscript -batch -quiet 2> /dev/null
>>> Threads
No threads.
>>> Machine level backtrace
>>> Dump Ruby level backtrace (if possible)

Linux phosphorus-docker 4.9.0-19-amd64 #1 SMP Debian 4.9.320-2 (2022-06-30) x86_64 x86_64 x86_64 GNU/Linux

Logfile: /home/ko1/ruby/logs/brlog.trunk-asserts.20220826-190228
#<BuildRuby:0x000056334d9b0e20
 @BUILD_DIR="/tmp/ruby/v3/build",
 @INSTALL_DIR="/tmp/ruby/v3/install",
 @REPOSITORY="https://github.com/ruby/ruby.git",
 @REPOSITORY_TYPE=:git,
 @SRC_DIR="/tmp/ruby/v3/src",
 @TARGET_BUILD_DIR="/tmp/ruby/v3/build/trunk-asserts",
 @TARGET_INSTALL_DIR="/tmp/ruby/v3/install/trunk-asserts",
 @TARGET_NAME="trunk-asserts",
 @TARGET_SRC_DIR="/tmp/ruby/v3/src/trunk-asserts",
 @btest_opts="-j6",
 @build_opts="DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"",
 @configure_opts=["--enable-shared"],
 @gist=false,
 @git_branch=nil,
 @git_worktree=nil,
 @incremental=true,
 @logfile="/home/ko1/ruby/logs/brlog.trunk-asserts.20220826-190228",
 @make="make",
 @quiet=false,
 @ruby_env=
  [["RUBY_DEBUG", "ci"], ["RUBY_TEST_SUBPROCESS_TIMEOUT_SCALE", "50"]],
 @steps=
  ["checkout",
   "autoconf",
   "configure",
   "build_up",
   "build_miniruby",
   "build_ruby",
   "build_all",
   "build_install",
   "test_btest",
   "test_basic",
   "test_all",
   "test_rubyspec"],
 @svn_revision=nil,
 @test_opts="TESTS='-j6'",
 @timeout=21600>
                           user     system      total        real
checkout               0.000000   0.000000   0.000000 (  0.000030)
autoconf               0.000000   0.000000   0.000000 (  0.000058)
configure              0.000000   0.000000   0.000000 (  0.000049)
build_up               0.004000   0.012000   1.944000 (  2.826967)
build_miniruby         0.000000   0.000000   0.144000 (  0.158464)
build_ruby             0.004000   0.000000   0.164000 (  0.173404)
build_all              0.016000   0.000000  30.840000 ( 31.555945)
build_install          0.024000   0.012000  50.456000 ( 50.986920)
test_btest             0.000000   0.000000 2298.012000 (704.034779)
test_basic             0.008000   0.012000  16.888000 ( 17.132979)
test_all             #<Thread:0x000056334da614f0 /home/ko1/ruby/build-ruby/build-ruby.rb:209 run> terminated with exception (report_on_exception is true):
/home/ko1/ruby/build-ruby/build-ruby.rb:209:in `each_line': stream closed in another thread (IOError)
	from /home/ko1/ruby/build-ruby/build-ruby.rb:209:in `block (2 levels) in cmd'
  0.008000   0.004000 49592.908000 (21600.371369)
/home/ko1/ruby/build-ruby/build-ruby.rb:214:in `join': execution expired (Timeout::Error)
	from /home/ko1/ruby/build-ruby/build-ruby.rb:214:in `each'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:214:in `block (2 levels) in cmd'
	from /usr/lib/ruby/2.7.0/timeout.rb:110:in `timeout'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:213:in `block in cmd'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:205:in `popen'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:205:in `cmd'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:380:in `block in test_all'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:300:in `block in builddir'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:299:in `chdir'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:299:in `builddir'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:378:in `test_all'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:442:in `block (4 levels) in run'
	from /usr/lib/ruby/2.7.0/benchmark.rb:293:in `measure'
	from /usr/lib/ruby/2.7.0/benchmark.rb:375:in `item'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:440:in `block (3 levels) in run'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:439:in `each'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:439:in `block (2 levels) in run'
	from /usr/lib/ruby/2.7.0/benchmark.rb:173:in `benchmark'
	from /usr/lib/ruby/2.7.0/benchmark.rb:205:in `bm'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:438:in `block in run'
	from /usr/lib/ruby/2.7.0/benchmark.rb:293:in `measure'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:437:in `run'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:631:in `<main>'
total: 22407.24 sec

	"make update-unicode DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 0.
	"make update-download DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 0.
	"make update-rubyspec DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 0.
	"make update-src DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 0.
	"make after-update DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 512.
	make[1]: *** No rule to make target 'id.h', needed by 'ripper.y'. Stop.
	make: *** [uncommon.mk:1202: /tmp/ruby/v3/src/trunk-asserts/ext/ripper/ripper.c] Error 2
	"make miniruby DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 0.
	"make ruby DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 0.
	"make all DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 0.
	"make install DEFS=\"-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline\"" exit with 0.
	"make yes-btest TESTOPTS='-q -j6'" exit with 0.
	"make yes-test-basic TESTS='-j6'" exit with 0.
	make yes-test-all TESTOPTS='--stderr-on-failure' TESTS='-j6'
	/tmp/ruby/v3/src/trunk-asserts/test/-ext-/econv/test_append.rb: cannot load such file -- -test-/econv (Test::Unit::ProxyError)
	$$$ #<Timeout::Error: execution expired>
	### enter analyzing mode for stuck processes
	$$$ ps jx
	PPID PID PGID SID TTY TPGID STAT UID TIME COMMAND
	10 11 11 11 ? -1 Ss 1001 0:00 sh build-loop.sh trunk-asserts --incremental --process-num=6
	1 1751 11 11 ? -1 Sl 1001 0:04 /tmp/ruby/v3/build/trunk-asserts/ruby /tmp/ruby/v3/src/trunk-asserts/test/drb/ut_drb_drbssl.rb drbssl://localhost:35027 ut_drb_drbssl.rb
	1 1780 11 11 ? -1 Sl 1001 0:03 /tmp/ruby/v3/build/trunk-asserts/ruby -rdrb/drb -rdrb/eq -rrinda/ring -rrinda/tuplespace -e uri = ARGV[0] DRb.start_service("druby://localhost:0") ro = DRbObject.new_with_uri(uri) ts = Rinda::TupleSpaceProxy.new(ro) th = Thread.new do ts.take([:test_take, nil]) rescue Interrupt # Expected end Kernel.sleep(0.1) th.raise(Interrupt) # causes loss of the taken tuple ts.write([:barrier, :continue]) Kernel.sleep druby://localhost:43519
	1 6826 11 11 ? -1 Sl 1001 0:03 /tmp/ruby/v3/build/trunk-asserts/ruby -rdrb/drb -rdrb/eq -rrinda/ring -rrinda/tuplespace -e uri = ARGV[0] DRb.start_service("druby://localhost:0") ro = DRbObject.new_with_uri(uri) ts = Rinda::TupleSpaceProxy.new(ro) th = Thread.new do ts.take([:test_take, nil]) rescue Interrupt # Expected end Kernel.sleep(0.1) th.raise(Interrupt) # causes loss of the taken tuple ts.write([:barrier, :continue]) Kernel.sleep druby://localhost:35093
	27412 8369 11 11 ? -1 R 1001 0:00 ps jx
	1 11628 11 11 ? -1 Sl 1001 0:03 /tmp/ruby/v3/build/trunk-asserts/ruby /tmp/ruby/v3/src/trunk-asserts/test/drb/ut_drb_drbunix.rb drbunix:/tmp/druby1993.0 ut_drb_drbunix.rb
	1 19652 11 11 ? -1 Sl 1001 0:03 /tmp/ruby/v3/build/trunk-asserts/ruby -rdrb/drb -rdrb/eq -rrinda/ring -rrinda/tuplespace -e uri = ARGV[0] DRb.start_service("druby://localhost:0") ro = DRbObject.new_with_uri(uri) ts = Rinda::TupleSpaceProxy.new(ro) th = Thread.new do ts.take([:test_take, nil]) rescue Interrupt # Expected end Kernel.sleep(0.1) th.raise(Interrupt) # causes loss of the taken tuple ts.write([:barrier, :continue]) Kernel.sleep druby://localhost:34715
	11 27408 11 11 ? -1 Sl 1001 0:00 ruby ./br.rb build_report trunk-asserts --incremental --process-num=6
	27408 27411 11 11 ? -1 S 1001 0:00 sh -c ruby /home/ko1/ruby/build-ruby/build-ruby.rb --target_name=trunk-asserts --logfile=/home/ko1/ruby/logs/brlog.trunk-asserts.20220826-190228 --root_dir=/tmp/ruby --timeout=21600 --build_opts='DEFS="-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline"' --add-env=RUBY_TEST_SUBPROCESS_TIMEOUT_SCALE=50 --incremental --process-num=6
	27411 27412 11 11 ? -1 Sl 1001 0:00 ruby /home/ko1/ruby/build-ruby/build-ruby.rb --target_name=trunk-asserts --logfile=/home/ko1/ruby/logs/brlog.trunk-asserts.20220826-190228 --root_dir=/tmp/ruby --timeout=21600 --build_opts=DEFS="-DRGENGC_CHECK_MODE=2 -DRUBY_DEBUG=1 -fno-inline" --add-env=RUBY_TEST_SUBPROCESS_TIMEOUT_SCALE=50 --incremental --process-num=6
	1 27462 11 11 ? -1 Sl 1001 0:03 /tmp/ruby/v3/build/trunk-asserts/ruby -rdrb/drb -rdrb/eq -rrinda/ring -rrinda/tuplespace -e uri = ARGV[0] DRb.start_service("druby://localhost:0") ro = DRbObject.new_with_uri(uri) ts = Rinda::TupleSpaceProxy.new(ro) th = Thread.new do ts.take([:test_take, nil]) rescue Interrupt # Expected end Kernel.sleep(0.1) th.raise(Interrupt) # causes loss of the taken tuple ts.write([:barrier, :continue]) Kernel.sleep druby://localhost:36349
	1 28199 11 11 ? -1 Sl 1001 0:03 /tmp/ruby/v3/build/trunk-asserts/ruby -rdrb/drb -rdrb/eq -rrinda/ring -rrinda/tuplespace -e uri = ARGV[0] DRb.start_service("druby://localhost:0") ro = DRbObject.new_with_uri(uri) ts = Rinda::TupleSpaceProxy.new(ro) th = Thread.new do ts.take([:test_take, nil]) rescue Interrupt # Expected end Kernel.sleep(0.1) th.raise(Interrupt) # causes loss of the taken tuple ts.write([:barrier, :continue]) Kernel.sleep druby://localhost:45991
	27412 30442 11 11 ? -1 Z 1001 0:00 [sh] <defunct>
	{10=>[11], 1=>[1751, 1780, 6826, 11628, 19652, 27462, 28199], 27412=>[8369, 30442], 11=>[27408], 27408=>[27411], 27411=>[27412]}
	$ timeout 60 gdb -p 8369 -x /home/ko1/ruby/build-ruby/gdbscript -batch -quiet 2> /dev/null
	>>> Threads
	No threads.
	>>> Machine level backtrace
	>>> Dump Ruby level backtrace (if possible)
	#<Errno::ESRCH: No such process>
	$ timeout 60 gdb -p 30442 -x /home/ko1/ruby/build-ruby/gdbscript -batch -quiet 2> /dev/null
	>>> Threads
	No threads.
	>>> Machine level backtrace
	>>> Dump Ruby level backtrace (if possible)