Result of trunk@ruby-sp1 at 2024-02-15 07:46:27 +0900

NG (1d3b306753)[LOG]
test-all: 25211 tests, 9481088 assertions, 0 failures, 0 errors, 232 skips
test-spec:

commands:

 "make update-unicode  -j16" exit with 0.
"make update-download -j16" exit with 0.
"make update-rubyspec -j16" exit with 0.
"make update-src      -j16" exit with 0.
"make after-update    -j16" exit with 0.
"make miniruby -j16" exit with 0.
"make ruby -j16" exit with 0.
"make all -j16" exit with 0.
"make install -j16" exit with 0.
"make yes-btest TESTOPTS='-q -j16'" exit with 0.
 
"make yes-test-basic TESTS='-j16'" exit with 0.
make yes-test-all TESTOPTS='--stderr-on-failure' TESTS='-j16'
$$$ #<Timeout::Error: execution expired>
### enter analyzing mode for stuck processes
$$$ ps jx
   PPID     PID    PGID     SID TTY        TPGID STAT   UID   TIME COMMAND
      1     830     830     830 ?             -1 Ss    1000   0:09 SCREEN -d -m dash -c cd build-ruby; BUILD_RUBY_WORKING_DIR=/tmp/ruby sh build-loop.sh trunk --incremental
    830     831     831     831 pts/0        831 Ss+   1000   0:00 dash -c cd build-ruby; BUILD_RUBY_WORKING_DIR=/tmp/ruby sh build-loop.sh trunk --incremental
    831     832     831     831 pts/0        831 S+    1000   0:03 sh build-loop.sh trunk --incremental
      1   42857     831     831 pts/0        831 S+    1000   0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestRand#test_fork_shuffle
      1  263517     831     831 pts/0        831 S+    1000   0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestThread#test_fork_while_parent_locked
      1  415679     831     831 pts/0        831 S+    1000   0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestProcess#test_wait_and_sigchild
      1  424379     831     831 pts/0        831 S+    1000   0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestThread#test_fork_while_parent_locked
      1  680574     831     831 pts/0        831 S+    1000   0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestThreadQueue#test_fork_while_queue_waiting
      1  927265     831     831 pts/0        831 S+    1000   0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestThread#test_fork_while_mutex_locked_by_forker
      1 1186739     831     831 pts/0        831 S+    1000   0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: JSONGeneratorTest#test_broken_bignum
      1 1229483     831     831 pts/0        831 S+    1000   0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestProcess#test_concurrent_group_and_pid_wait
      1 1229692     831     831 pts/0        831 Sl+   1000   0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestProcess#test_concurrent_group_and_pid_wait
      1 1461775     831     831 pts/0        831 S+    1000   0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestProcess#test_daemon_no_threads
    832 1496613     831     831 pts/0        831 Sl+   1000   0:00 ruby ./br.rb build_report trunk --incremental
1496613 1607657     831     831 pts/0        831 Sl+   1000   0:00 ruby /home/ko1/build-ruby/build-ruby.rb --target_name=trunk --logfile=/home/ko1/ruby/logs/brlog.trunk.20240214-194607 --root_dir=/tmp/ruby --timeout=10800 --incremental
      1 1727305     831     831 pts/0        831 Sl+   1000 15978:10 /tmp/ruby/build/trunk/ruby -W0
      1 1753508     831     831 pts/0        831 S+    1000   0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestIO#test_fcntl_lock_linux
1607657 1886045     831     831 pts/0        831 R+    1000   0:00 ps jx
      1 2500830     831     831 pts/0        831 Rl+   1000 29284:57 /tmp/ruby/build/trunk/ruby -W0
      1 2965286     831     831 pts/0        831 Rl+   1000 14708:21 /tmp/ruby/build/trunk/ruby -W0
1607657 3400558     831     831 pts/0        831 Z+    1000   0:00 [sh] <defunct>
      1 4182592     831     831 pts/0        831 S+    1000   0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestProcess#test_daemon_default
{1=>[830, 42857, 263517, 415679, 424379, 680574, 927265, 1186739, 1229483, 1229692, 1461775, 1727305, 1753508, 2500830, 2965286, 4182592], 830=>[831], 831=>[832], 832=>[1496613], 1496613=>[1607657], 1607657=>[1886045, 3400558]}
$ timeout 60 gdb -p 1886045 -x /home/ko1/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 3400558 -x /home/ko1/build-ruby/gdbscript -batch -quiet 2> /dev/null
>>> Threads
No threads.
>>> Machine level backtrace
>>> Dump Ruby level backtrace (if possible)

Linux ruby-sp1 5.15.0-91-generic #101-Ubuntu SMP Tue Nov 14 13:30:08 UTC 2023 x86_64 x86_64 x86_64 GNU/Linux

Logfile: /home/ko1/ruby/logs/brlog.trunk.20240214-194607
#<BuildRuby:0x000056428b80f970
 @BUILD_DIR="/tmp/ruby/build",
 @INSTALL_DIR="/tmp/ruby/install",
 @REPOSITORY="https://github.com/ruby/ruby.git",
 @REPOSITORY_TYPE=:git,
 @SRC_DIR="/tmp/ruby/src",
 @TARGET_BUILD_DIR="/tmp/ruby/build/trunk",
 @TARGET_INSTALL_DIR="/tmp/ruby/install/trunk",
 @TARGET_NAME="trunk",
 @TARGET_SRC_DIR="/tmp/ruby/src/trunk",
 @btest_opts="-j16",
 @build_opts="-j16",
 @configure_opts=["--enable-shared"],
 @gist=false,
 @git_branch=nil,
 @git_worktree=nil,
 @incremental=true,
 @logfile="/home/ko1/ruby/logs/brlog.trunk.20240214-194607",
 @make="make",
 @quiet=false,
 @ruby_env=[["RUBY_DEBUG", "ci"]],
 @spec_opts="-j",
 @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='-j16'",
 @timeout=10800>
                           user     system      total        real
checkout               0.000005   0.000002   0.000007 (  0.000007)
autoconf               0.000011   0.000004   0.000015 (  0.000015)
configure              0.000007   0.000002   0.000009 (  0.000010)
build_up               0.004909   0.000000   1.040404 (  1.436458)
build_miniruby         0.000000   0.001024   1.146943 (  1.149704)
build_ruby             0.000000   0.001407   3.182847 (  3.188053)
build_all              0.011446   0.000290  17.663027 (  1.752184)
build_install          0.001839   0.010520   5.968332 (  1.452033)
test_btest             0.000469   0.000221  95.379085 ( 10.465797)
test_basic             0.004767   0.000493   0.494730 (  0.568442)
test_all             #<Thread:0x000056428b8ad670 /home/ko1/build-ruby/build-ruby.rb:211 run> terminated with exception (report_on_exception is true):
/home/ko1/build-ruby/build-ruby.rb:211:in `each_line': stream closed in another thread (IOError)
	from /home/ko1/build-ruby/build-ruby.rb:211:in `block (2 levels) in cmd'
  0.134910   0.038734 588.031642 (10800.118515)
/home/ko1/build-ruby/build-ruby.rb:216:in `join': execution expired (Timeout::Error)
	from /home/ko1/build-ruby/build-ruby.rb:216:in `each'
	from /home/ko1/build-ruby/build-ruby.rb:216:in `block (2 levels) in cmd'
	from /usr/lib/ruby/3.0.0/timeout.rb:112:in `timeout'
	from /home/ko1/build-ruby/build-ruby.rb:215:in `block in cmd'
	from /home/ko1/build-ruby/build-ruby.rb:207:in `popen'
	from /home/ko1/build-ruby/build-ruby.rb:207:in `cmd'
	from /home/ko1/build-ruby/build-ruby.rb:383:in `block in test_all'
	from /home/ko1/build-ruby/build-ruby.rb:303:in `block in builddir'
	from /home/ko1/build-ruby/build-ruby.rb:302:in `chdir'
	from /home/ko1/build-ruby/build-ruby.rb:302:in `builddir'
	from /home/ko1/build-ruby/build-ruby.rb:381:in `test_all'
	from /home/ko1/build-ruby/build-ruby.rb:445:in `block (4 levels) in run'
	from /usr/lib/ruby/3.0.0/benchmark.rb:293:in `measure'
	from /usr/lib/ruby/3.0.0/benchmark.rb:375:in `item'
	from /home/ko1/build-ruby/build-ruby.rb:443:in `block (3 levels) in run'
	from /home/ko1/build-ruby/build-ruby.rb:442:in `each'
	from /home/ko1/build-ruby/build-ruby.rb:442:in `block (2 levels) in run'
	from /usr/lib/ruby/3.0.0/benchmark.rb:173:in `benchmark'
	from /usr/lib/ruby/3.0.0/benchmark.rb:205:in `bm'
	from /home/ko1/build-ruby/build-ruby.rb:441:in `block in run'
	from /usr/lib/ruby/3.0.0/benchmark.rb:293:in `measure'
	from /home/ko1/build-ruby/build-ruby.rb:440:in `run'
	from /home/ko1/build-ruby/build-ruby.rb:634:in `<main>'
total: 10820.13 sec

	"make update-unicode -j16" exit with 0.
	"make update-download -j16" exit with 0.
	"make update-rubyspec -j16" exit with 0.
	"make update-src -j16" exit with 0.
	"make after-update -j16" exit with 0.
	"make miniruby -j16" exit with 0.
	"make ruby -j16" exit with 0.
	"make all -j16" exit with 0.
	"make install -j16" exit with 0.
	"make yes-btest TESTOPTS='-q -j16'" exit with 0.

	"make yes-test-basic TESTS='-j16'" exit with 0.
	make yes-test-all TESTOPTS='--stderr-on-failure' TESTS='-j16'
	$$$ #<Timeout::Error: execution expired>
	### enter analyzing mode for stuck processes
	$$$ ps jx
	PPID PID PGID SID TTY TPGID STAT UID TIME COMMAND
	1 830 830 830 ? -1 Ss 1000 0:09 SCREEN -d -m dash -c cd build-ruby; BUILD_RUBY_WORKING_DIR=/tmp/ruby sh build-loop.sh trunk --incremental
	830 831 831 831 pts/0 831 Ss+ 1000 0:00 dash -c cd build-ruby; BUILD_RUBY_WORKING_DIR=/tmp/ruby sh build-loop.sh trunk --incremental
	831 832 831 831 pts/0 831 S+ 1000 0:03 sh build-loop.sh trunk --incremental
	1 42857 831 831 pts/0 831 S+ 1000 0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestRand#test_fork_shuffle
	1 263517 831 831 pts/0 831 S+ 1000 0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestThread#test_fork_while_parent_locked
	1 415679 831 831 pts/0 831 S+ 1000 0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestProcess#test_wait_and_sigchild
	1 424379 831 831 pts/0 831 S+ 1000 0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestThread#test_fork_while_parent_locked
	1 680574 831 831 pts/0 831 S+ 1000 0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestThreadQueue#test_fork_while_queue_waiting
	1 927265 831 831 pts/0 831 S+ 1000 0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestThread#test_fork_while_mutex_locked_by_forker
	1 1186739 831 831 pts/0 831 S+ 1000 0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: JSONGeneratorTest#test_broken_bignum
	1 1229483 831 831 pts/0 831 S+ 1000 0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestProcess#test_concurrent_group_and_pid_wait
	1 1229692 831 831 pts/0 831 Sl+ 1000 0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestProcess#test_concurrent_group_and_pid_wait
	1 1461775 831 831 pts/0 831 S+ 1000 0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestProcess#test_daemon_no_threads
	832 1496613 831 831 pts/0 831 Sl+ 1000 0:00 ruby ./br.rb build_report trunk --incremental
	1496613 1607657 831 831 pts/0 831 Sl+ 1000 0:00 ruby /home/ko1/build-ruby/build-ruby.rb --target_name=trunk --logfile=/home/ko1/ruby/logs/brlog.trunk.20240214-194607 --root_dir=/tmp/ruby --timeout=10800 --incremental
	1 1727305 831 831 pts/0 831 Sl+ 1000 15978:10 /tmp/ruby/build/trunk/ruby -W0
	1 1753508 831 831 pts/0 831 S+ 1000 0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestIO#test_fcntl_lock_linux
	1607657 1886045 831 831 pts/0 831 R+ 1000 0:00 ps jx
	1 2500830 831 831 pts/0 831 Rl+ 1000 29284:57 /tmp/ruby/build/trunk/ruby -W0
	1 2965286 831 831 pts/0 831 Rl+ 1000 14708:21 /tmp/ruby/build/trunk/ruby -W0
	1607657 3400558 831 831 pts/0 831 Z+ 1000 0:00 [sh] <defunct>
	1 4182592 831 831 pts/0 831 S+ 1000 0:00 /tmp/ruby/src/trunk/tool/lib/test/unit/parallel.rb: TestProcess#test_daemon_default
	{1=>[830, 42857, 263517, 415679, 424379, 680574, 927265, 1186739, 1229483, 1229692, 1461775, 1727305, 1753508, 2500830, 2965286, 4182592], 830=>[831], 831=>[832], 832=>[1496613], 1496613=>[1607657], 1607657=>[1886045, 3400558]}
	$ timeout 60 gdb -p 1886045 -x /home/ko1/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 3400558 -x /home/ko1/build-ruby/gdbscript -batch -quiet 2> /dev/null
	>>> Threads
	No threads.
	>>> Machine level backtrace
	>>> Dump Ruby level backtrace (if possible)