Result of trunk-gc-asserts@ruby-sp2-docker at 2023-07-31 22:03:59 +0900

• NG (a3c0e9d90d)[LOG]
• test-all: 25088 tests, 9222223 assertions, 0 failures, 0 errors, 144 skips
• test-spec:
• commands:

  "make update-unicode  DEFS=\"-DRGENGC_CHECK_MODE=2\"" exit with 0.
  "make update-download DEFS=\"-DRGENGC_CHECK_MODE=2\"" exit with 0.
  From https://github.com/ruby/typeprof
   * branch            aabc019684d8b4a1ed66c2a1ca48da7bbb18dcc0 -> FETCH_HEAD
  HEAD is now at aabc019 Skip VM_CALL_BLOCKISEQ for Ruby < 3.3
  "make update-rubyspec DEFS=\"-DRGENGC_CHECK_MODE=2\"" exit with 0.
  "make update-src      DEFS=\"-DRGENGC_CHECK_MODE=2\"" exit with 0.
  From https://github.com/ruby/ruby
     60ac719..a3c0e9d  master     -> origin/master
  "make after-update    DEFS=\"-DRGENGC_CHECK_MODE=2\"" exit with 0.
  From https://github.com/ruby/typeprof
   * branch            aabc019684d8b4a1ed66c2a1ca48da7bbb18dcc0 -> FETCH_HEAD
  HEAD is now at aabc019 Skip VM_CALL_BLOCKISEQ for Ruby < 3.3
  "make miniruby DEFS=\"-DRGENGC_CHECK_MODE=2\"" exit with 0.
  "make ruby DEFS=\"-DRGENGC_CHECK_MODE=2\"" exit with 0.
  "make all DEFS=\"-DRGENGC_CHECK_MODE=2\"" exit with 0.
  "make install DEFS=\"-DRGENGC_CHECK_MODE=2\"" exit with 0.
  From https://github.com/ruby/typeprof
   * branch            aabc019684d8b4a1ed66c2a1ca48da7bbb18dcc0 -> FETCH_HEAD
  HEAD is now at aabc019 Skip VM_CALL_BLOCKISEQ for Ruby < 3.3
  "make yes-btest TESTOPTS='-q -j4'" exit with 0.
   
  "make yes-test-basic TESTS='-j4'" exit with 0.
  make yes-test-all TESTOPTS='--stderr-on-failure' TESTS='-j4'
  $$$ #<Timeout::Error: execution expired>
  ### enter analyzing mode for stuck processes
  $$$ ps jx
     PPID     PID    PGID     SID TTY        TPGID STAT   UID   TIME COMMAND
       11      12      12      12 ?             -1 Ss    1001   0:00 sh build-loop.sh trunk-gc-asserts --incremental --process-num=4
        1 1111922      12      12 ?             -1 Sl    1001   0:00 /tmp/ruby/build/trunk-gc-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:45021
        1 1133200      12      12 ?             -1 Sl    1001   0:00 /tmp/ruby/build/trunk-gc-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:35229
        1 1261812      12      12 ?             -1 Sl    1001   0:00 /tmp/ruby/build/trunk-gc-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:39967
        1 1284301      12      12 ?             -1 Sl    1001   0:00 /tmp/ruby/build/trunk-gc-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:35659
        1 1296972      12      12 ?             -1 Sl    1001   0:00 /tmp/ruby/build/trunk-gc-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:35149
        1 2349941      12      12 ?             -1 Sl    1001   0:00 /tmp/ruby/build/trunk-gc-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:42009
        1 2456238      12      12 ?             -1 Sl    1001   0:00 /tmp/ruby/build/trunk-gc-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:38853
        1 2491655      12      12 ?             -1 Sl    1001   0:00 /tmp/ruby/build/trunk-gc-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:44211
        1 2534895      12      12 ?             -1 Sl    1001   0:00 /tmp/ruby/build/trunk-gc-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:45911
       12 2639511      12      12 ?             -1 Sl    1001   0:00 ruby ./br.rb build_report trunk-gc-asserts --incremental --process-num=4
  2639511 2639514      12      12 ?             -1 S     1001   0:00 sh -c ruby /home/ko1/build-ruby/build-ruby.rb --target_name=trunk-gc-asserts --logfile=/home/ko1/ruby/logs/brlog.trunk-gc-asserts.20230731-065824 --root_dir=/tmp/ruby --timeout=21600 --build_opts='DEFS="-DRGENGC_CHECK_MODE=2"'                              --add-env=RUBY_TEST_SUBPROCESS_TIMEOUT_SCALE=30 --incremental --process-num=4
  2639514 2639515      12      12 ?             -1 Sl    1001   0:00 ruby /home/ko1/build-ruby/build-ruby.rb --target_name=trunk-gc-asserts --logfile=/home/ko1/ruby/logs/brlog.trunk-gc-asserts.20230731-065824 --root_dir=/tmp/ruby --timeout=21600 --build_opts=DEFS="-DRGENGC_CHECK_MODE=2" --add-env=RUBY_TEST_SUBPROCESS_TIMEOUT_SCALE=30 --incremental --process-num=4
  2639515 2643639      12      12 ?             -1 Z     1001   0:00 [sh] <defunct>
        1 2646452      12      12 ?             -1 Sl    1001   0:00 /tmp/ruby/build/trunk-gc-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:42775
  2639515 2650979      12      12 ?             -1 R     1001   0:00 ps jx
  {11=>[12], 1=>[1111922, 1133200, 1261812, 1284301, 1296972, 2349941, 2456238, 2491655, 2534895, 2646452], 12=>[2639511], 2639511=>[2639514], 2639514=>[2639515], 2639515=>[2643639, 2650979]}
  $ timeout 60 gdb -p 2643639 -x /home/ko1/build-ruby/gdbscript -batch -quiet 2> /dev/null
  >>> Threads
  No threads.
  >>> Machine level backtrace
  >>> Dump Ruby level backtrace (if possible)
  $ timeout 60 gdb -p 2650979 -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>
  

• Linux ruby-sp2-docker 5.15.0-72-generic #79-Ubuntu SMP Wed Apr 19 08:22:18 UTC 2023 x86_64 x86_64 x86_64 GNU/Linux
  

• Logfile: /home/ko1/ruby/logs/brlog.trunk-gc-asserts.20230731-065824
  #<BuildRuby:0x000055ae54d1cc68
   @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-gc-asserts",
   @TARGET_INSTALL_DIR="/tmp/ruby/install/trunk-gc-asserts",
   @TARGET_NAME="trunk-gc-asserts",
   @TARGET_SRC_DIR="/tmp/ruby/src/trunk-gc-asserts",
   @btest_opts="-j4",
   @build_opts="DEFS=\"-DRGENGC_CHECK_MODE=2\"",
   @configure_opts=["--enable-shared"],
   @gist=false,
   @git_branch=nil,
   @git_worktree=nil,
   @incremental=true,
   @logfile="/home/ko1/ruby/logs/brlog.trunk-gc-asserts.20230731-065824",
   @make="make",
   @quiet=false,
   @ruby_env=
    [["RUBY_DEBUG", "ci"], ["RUBY_TEST_SUBPROCESS_TIMEOUT_SCALE", "30"]],
   @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='-j4'",
   @timeout=21600>
                             user     system      total        real
  checkout               0.000015   0.000001   0.000016 (  0.000019)
  autoconf               0.000029   0.000003   0.000032 (  0.000031)
  configure              0.000021   0.000002   0.000023 (  0.000024)
  build_up               0.006256   0.000605   2.371263 (  4.399745)
  build_miniruby         0.002302   0.000223  43.115338 ( 43.588482)
  build_ruby             0.001898   0.000183   9.553297 ( 10.197045)
  build_all              0.001131   0.014084  17.138294 ( 19.793520)
  build_install          0.021486   0.000374  10.968319 ( 12.323206)
  test_btest             0.001254   0.000219 536.585269 (239.478830)
  test_basic             0.006453   0.003679   4.662908 (  4.914002)
  test_all             #<Thread:0x000055ae54dcbfd8 /home/ko1/build-ruby/build-ruby.rb:209 run> terminated with exception (report_on_exception is true):
  /home/ko1/build-ruby/build-ruby.rb:209:in `each_line': stream closed in another thread (IOError)
  	from /home/ko1/build-ruby/build-ruby.rb:209:in `block (2 levels) in cmd'
    0.005219   0.000994 18835.914350 (21600.347995)
  /home/ko1/build-ruby/build-ruby.rb:214:in `join': execution expired (Timeout::Error)
  	from /home/ko1/build-ruby/build-ruby.rb:214:in `each'
  	from /home/ko1/build-ruby/build-ruby.rb:214: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:213:in `block in cmd'
  	from /home/ko1/build-ruby/build-ruby.rb:205:in `popen'
  	from /home/ko1/build-ruby/build-ruby.rb:205:in `cmd'
  	from /home/ko1/build-ruby/build-ruby.rb:381:in `block in test_all'
  	from /home/ko1/build-ruby/build-ruby.rb:301:in `block in builddir'
  	from /home/ko1/build-ruby/build-ruby.rb:300:in `chdir'
  	from /home/ko1/build-ruby/build-ruby.rb:300:in `builddir'
  	from /home/ko1/build-ruby/build-ruby.rb:379:in `test_all'
  	from /home/ko1/build-ruby/build-ruby.rb:443: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:441:in `block (3 levels) in run'
  	from /home/ko1/build-ruby/build-ruby.rb:440:in `each'
  	from /home/ko1/build-ruby/build-ruby.rb:440: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:439:in `block in run'
  	from /usr/lib/ruby/3.0.0/benchmark.rb:293:in `measure'
  	from /home/ko1/build-ruby/build-ruby.rb:438:in `run'
  	from /home/ko1/build-ruby/build-ruby.rb:632:in `<main>'
  total: 21935.04 sec