Result of master@oci-aarch64 at 2025-05-09 11:38:31 +0900

• NG (7a660d7c69)[LOG]
• test-all:
• test-spec:
• commands:

  "make update-download -j3" exit with 0.
  "make update-src      -j3" exit with 0.
  "make after-update    -j3" exit with 0.
  "make miniruby -j3" exit with 0.
  "make ruby -j3" exit with 0.
  "make all -j3" exit with 0.
  "make install -j3" exit with 0.
  make yes-btest TESTOPTS='-q -j3'
  $$$ #<Timeout::Error: execution expired>
  ### enter analyzing mode for stuck processes
  $$$ ps jx
     PPID     PID    PGID     SID TTY        TPGID STAT   UID   TIME COMMAND
        1    2547    2547    2547 ?             -1 Ss    1000   7:16 /usr/lib/systemd/systemd --user
     2547    2550    2547    2547 ?             -1 S     1000   0:00 (sd-pam)
        1    3030    3030    3030 ?             -1 Ss    1000   8:05 tmux
     3030    3031    3031    3031 pts/1     550734 Ss    1000   0:00 -bash
        1   51080  550734    3031 pts/1     550734 S+    1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestIOBuffer#test_shared
        1  110879  550734    3031 pts/1     550734 S+    1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestBeginEndBlock#test_internal_errinfo_at_exit
        1  178643  550734    3031 pts/1     550734 S+    1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestIOBuffer#test_shared
        1  504419  550734    3031 pts/1     550734 S+    1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestTimeout#test_fork
     3031  550734  550734    3031 pts/1     550734 S+    1000   1:52 sh build-loop.sh master --incremental
        1  570281  550734    3031 pts/1     550734 S+    1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestProcess#test_daemon_detached
        1  583958  550734    3031 pts/1     550734 S+    1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestTimeout#test_fork
        1  805450  550734    3031 pts/1     550734 S+    1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestProcess#test_daemon_noclose
        1  968356  550734    3031 pts/1     550734 S+    1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: JSONGeneratorTest#test_broken_bignum
   550734 1165290  550734    3031 pts/1     550734 Sl+   1000   0:00 ruby ./br.rb build_report master --incremental
  1165290 1165293  550734    3031 pts/1     550734 Sl+   1000   0:00 ruby /home/opc/build-ruby/build-ruby.rb --target_name=master --logfile=/home/opc/ruby/logs/brlog.master.20250508-233819 --root_dir=/home/opc/ruby --timeout=10800 --configure_opts=--disable-dtrace --disable-yjit --incremental
  1165293 1166177  550734    3031 pts/1     550734 S+    1000   0:00 make yes-btest TESTOPTS=-q -j3
  1166177 1166192  550734    3031 pts/1     550734 Sl+   1000   0:02 /usr/local/bin/ruby --disable=gems /home/opc/ruby/src/master/bootstraptest/runner.rb --ruby=./miniruby -I/home/opc/ruby/src/master/lib -I. -I.ext/common  -r./aarch64-linux-fake --disable-gems -q -j3
  1166192 1168945  550734    3031 pts/1     550734 Z+    1000   0:00 [miniruby] <defunct>
        1 1168951  550734    3031 pts/1     550734 Sl+   1000   0:00 /home/opc/ruby/build/master/miniruby -I/home/opc/ruby/src/master/lib -I/home/opc/ruby/build/master -I/home/opc/ruby/build/master/.ext/common -r/home/opc/ruby/build/master/aarch64-linux-fake --disable-gems -W0 bootstraptest.test_ractor.rb_2226_1406.rb
  1165293 1244928  550734    3031 pts/1     550734 R+    1000   0:00 ps jx
        1 1279056  550734    3031 pts/1     550734 S+    1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestIOBuffer#test_shared
        1 1499050  550734    3031 pts/1     550734 S+    1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestTimeout#test_fork
        1 1904634 2286718    3031 pts/1     550734 S     1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: JSONGeneratorTest#test_broken_bignum
        1 2058937  550734    3031 pts/1     550734 S+    1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestFiberProcess#test_fork
        1 2813263  550734    3031 pts/1     550734 S+    1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestAutoload#test_autoload_fork
        1 2964324 2286718    3031 pts/1     550734 S     1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestIOBuffer#test_shared
        1 2977397  550734    3031 pts/1     550734 S+    1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestSyslog#test_log
        1 3028846  550734    3031 pts/1     550734 S+    1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestIOBuffer#test_shared
        1 3195009 2286718    3031 pts/1     550734 S     1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestSyslog#test_log
        1 3524312  550734    3031 pts/1     550734 S+    1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestSyslog#test_log
        1 3700981  550734    3031 pts/1     550734 S+    1000   0:00 /home/opc/ruby/src/master/tool/lib/test/unit/parallel.rb: TestSyslog#test_log
  {1=>[2547, 3030, 51080, 110879, 178643, 504419, 570281, 583958, 805450, 968356, 1168951, 1279056, 1499050, 1904634, 2058937, 2813263, 2964324, 2977397, 3028846, 3195009, 3524312, 3700981], 2547=>[2550], 3030=>[3031], 3031=>[550734], 550734=>[1165290], 1165290=>[1165293], 1165293=>[1166177, 1244928], 1166177=>[1166192], 1166192=>[1168945]}
  $ timeout 60 gdb -p 1168945 -x /home/opc/build-ruby/gdbscript -batch -quiet 2> /dev/null
  >>> Threads
  No threads.
  >>> Machine level backtrace
  >>> Dump Ruby level backtrace (if possible)
  $ timeout 60 gdb -p 1166192 -x /home/opc/build-ruby/gdbscript -batch -quiet 2> /dev/null
  [New LWP 1166197]
  [New LWP 1166198]
  [New LWP 1166202]
  [Thread debugging using libthread_db enabled]
  Using host libthread_db library "/lib64/libthread_db.so.1".
  0x0000fffbcd59ed20 in ppoll () from /lib64/libc.so.6
  >>> Threads
    Id   Target Id                                           Frame 
  * 1    Thread 0xfffbcded6140 (LWP 1166192) "ruby"          0x0000fffbcd59ed20 in ppoll () from /lib64/libc.so.6
    2    Thread 0xfffbc7a0f140 (LWP 1166197) "runner.rb:334" 0x0000fffbcd66dd80 in pthread_cond_wait@@GLIBC_2.17 () from /lib64/libpthread.so.0
    3    Thread 0xfffbc77ff140 (LWP 1166198) "runner.rb:546" 0x0000fffbcd59ec04 in poll () from /lib64/libc.so.6
    4    Thread 0xfffbc71cf140 (LWP 1166202) "runner.rb:636" 0x0000fffbcd59ec04 in poll () from /lib64/libc.so.6
  >>> Machine level backtrace
  Thread 4 (Thread 0xfffbc71cf140 (LWP 1166202)):
  #0  0x0000fffbcd59ec04 in poll () from /lib64/libc.so.6
  No symbol table info available.
  Thread 3 (Thread 0xfffbc77ff140 (LWP 1166198)):
  #0  0x0000fffbcd59ec04 in poll () from /lib64/libc.so.6
  No symbol table info available.
  Thread 2 (Thread 0xfffbc7a0f140 (LWP 1166197)):
  #0  0x0000fffbcd66dd80 in pthread_cond_wait@@GLIBC_2.17 () from /lib64/libpthread.so.0
  No symbol table info available.
  Thread 1 (Thread 0xfffbcded6140 (LWP 1166192)):
  #0  0x0000fffbcd59ed20 in ppoll () from /lib64/libc.so.6
  No symbol table info available.
  >>> Dump Ruby level backtrace (if possible)
   th: 0x2e501150, native_id: 0xfffbcded6140
  -- Control frame information -----------------------------------------------
  c:0012 p:0023 s:0074 e:000073 METHOD <internal:thread_sync>:18
  c:0011 p:0062 s:0067 E:0016e0 METHOD /home/opc/ruby/src/master/bootstraptest/runner.rb:356
  c:0010 p:0052 s:0057 e:000056 METHOD /home/opc/ruby/src/master/bootstraptest/runner.rb:438
  c:0009 p:0005 s:0049 e:000048 BLOCK  /home/opc/ruby/src/master/bootstraptest/runner.rb:311
  c:0008 p:0002 s:0046 e:000045 BLOCK  /home/opc/ruby/src/master/bootstraptest/runner.rb:855 [FINISH]
  c:0007 p:---- s:0043 e:000042 CFUNC  :chdir
  c:0006 p:0007 s:0038 e:000037 BLOCK  /home/opc/ruby/src/master/bootstraptest/runner.rb:854
  c:0005 p:0047 s:0034 e:000033 METHOD /usr/local/lib/ruby/3.2.0/tmpdir.rb:94
  c:0004 p:0030 s:0024 e:000023 METHOD /home/opc/ruby/src/master/bootstraptest/runner.rb:853
  c:0003 p:0624 s:0019 e:000018 METHOD /home/opc/ruby/src/master/bootstraptest/runner.rb:310
  c:0002 p:0217 s:0008 E:001668 EVAL   /home/opc/ruby/src/master/bootstraptest/runner.rb:894 [FINISH]
  c:0001 p:0000 s:0003 E:002290 DUMMY  [FINISH]
  th: 0x2e7003e0, native_id: 0xfffbc7a0f140
  -- Control frame information -----------------------------------------------
  c:0009 p:---- s:0056 e:000055 CFUNC  :value
  c:0008 p:0042 s:0052 E:000dc8 METHOD /home/opc/ruby/src/master/bootstraptest/runner.rb:552
  c:0007 p:0130 s:0043 E:0004c8 METHOD /home/opc/ruby/src/master/bootstraptest/runner.rb:580
  c:0006 p:0010 s:0034 e:000033 METHOD /home/opc/ruby/src/master/bootstraptest/runner.rb:384
  c:0005 p:0014 s:0022 E:001338 METHOD /home/opc/ruby/src/master/bootstraptest/runner.rb:536
  c:0004 p:0011 s:0015 E:000178 BLOCK  /home/opc/ruby/src/master/bootstraptest/runner.rb:678
  c:0003 p:0006 s:0011 e:000010 METHOD /home/opc/ruby/src/master/bootstraptest/runner.rb:532
  c:0002 p:0011 s:0007 e:000006 BLOCK  /home/opc/ruby/src/master/bootstraptest/runner.rb:336 [FINISH]
  c:0001 p:---- s:0003 e:000002 DUMMY  [FINISH]
  th: 0xfffbbc02f160, native_id: 0xfffbc77ff140
  -- Control frame information -----------------------------------------------
  c:0003 p:---- s:0010 e:000009 CFUNC  :read
  c:0002 p:0004 s:0006 e:000005 BLOCK  /home/opc/ruby/src/master/bootstraptest/runner.rb:546 [FINISH]
  c:0001 p:---- s:0003 e:000002 DUMMY  [FINISH]
  th: 0xfffbbc0c9710, native_id: 0xfffbc71cf140
  -- Control frame information -----------------------------------------------
  c:0003 p:---- s:0010 e:000009 CFUNC  :read
  c:0002 p:0004 s:0006 e:000005 BLOCK  /home/opc/ruby/src/master/bootstraptest/runner.rb:636 [FINISH]
  c:0001 p:---- s:0003 e:000002 DUMMY  [FINISH]
  [Inferior 1 (process 1166192) detached]
  $ timeout 60 gdb -p 1166177 -x /home/opc/build-ruby/gdbscript -batch -quiet 2> /dev/null
  make: *** [uncommon.mk:902: yes-btest] Killed
  >>> Threads
  No threads.
  >>> Machine level backtrace
  >>> Dump Ruby level backtrace (if possible)
  $ timeout 60 gdb -p 1244928 -x /home/opc/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 oci-aarch64 5.4.17-2136.314.6.2.el8uek.aarch64 #2 SMP Fri Dec 9 16:22:23 PST 2022 aarch64 aarch64 aarch64 GNU/Linux
  

• Logfile: /home/opc/ruby/logs/brlog.master.20250508-233819
  #<BuildRuby:0x0000fffd1b73fdc0
   @BUILD_DIR="/home/opc/ruby/build",
   @INSTALL_DIR="/home/opc/ruby/install",
   @REPOSITORY="https://github.com/ruby/ruby.git",
   @REPOSITORY_TYPE=:git,
   @SRC_DIR="/home/opc/ruby/src",
   @TARGET_BUILD_DIR="/home/opc/ruby/build/master",
   @TARGET_INSTALL_DIR="/home/opc/ruby/install/master",
   @TARGET_NAME="master",
   @TARGET_SRC_DIR="/home/opc/ruby/src/master",
   @btest_opts="-j3",
   @build_opts="-j3",
   @configure_opts=["--disable-dtrace --disable-yjit"],
   @gist=false,
   @git_branch=nil,
   @git_worktree=nil,
   @incremental=true,
   @logfile="/home/opc/ruby/logs/brlog.master.20250508-233819",
   @make="make",
   @no_timeout_error=nil,
   @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='-j3'",
   @timeout=10800>
                             user     system      total        real
  checkout               0.000009   0.000002   0.000011 (  0.000011)
  autoconf               0.000016   0.000004   0.000020 (  0.000020)
  configure              0.000009   0.000002   0.000011 (  0.000012)
  build_up               0.004337   0.000089   1.221697 (  1.876065)
  build_miniruby         0.001058   0.000249   1.178800 (  1.178660)
  build_ruby             0.001304   0.000307   1.786821 (  3.709166)
  build_all              0.007537   0.000000   3.022651 (  1.513076)
  build_install          0.009507   0.005328   3.975256 (  2.424312)
  test_btest             0.008270   0.000942  32.048205 (10800.975552)
  /home/opc/build-ruby/build-ruby.rb:219:in `join': execution expired (Timeout::Error)
  	from /home/opc/build-ruby/build-ruby.rb:219:in `each'
  	from /home/opc/build-ruby/build-ruby.rb:219:in `block (2 levels) in cmd'
  	from /usr/local/lib/ruby/3.2.0/timeout.rb:189:in `block in timeout'
  	from /usr/local/lib/ruby/3.2.0/timeout.rb:36:in `block in catch'
  	from /usr/local/lib/ruby/3.2.0/timeout.rb:36:in `catch'
  	from /usr/local/lib/ruby/3.2.0/timeout.rb:36:in `catch'
  	from /usr/local/lib/ruby/3.2.0/timeout.rb:198:in `timeout'
  	from /home/opc/build-ruby/build-ruby.rb:218:in `block in cmd'
  	from /home/opc/build-ruby/build-ruby.rb:210:in `popen'
  	from /home/opc/build-ruby/build-ruby.rb:210:in `cmd'
  	from /home/opc/build-ruby/build-ruby.rb:382:in `block in test_btest'
  	from /home/opc/build-ruby/build-ruby.rb:315:in `block in builddir'
  	from /home/opc/build-ruby/build-ruby.rb:314:in `chdir'
  	from /home/opc/build-ruby/build-ruby.rb:314:in `builddir'
  	from /home/opc/build-ruby/build-ruby.rb:381:in `test_btest'
  	from /home/opc/build-ruby/build-ruby.rb:457:in `block (4 levels) in run'
  	from /usr/local/lib/ruby/3.2.0/benchmark.rb:296:in `measure'
  	from /usr/local/lib/ruby/3.2.0/benchmark.rb:378:in `item'
  	from /home/opc/build-ruby/build-ruby.rb:455:in `block (3 levels) in run'
  	from /home/opc/build-ruby/build-ruby.rb:454:in `each'
  	from /home/opc/build-ruby/build-ruby.rb:454:in `block (2 levels) in run'
  	from /usr/local/lib/ruby/3.2.0/benchmark.rb:176:in `benchmark'
  	from /usr/local/lib/ruby/3.2.0/benchmark.rb:208:in `bm'
  	from /home/opc/build-ruby/build-ruby.rb:453:in `block in run'
  	from /usr/local/lib/ruby/3.2.0/benchmark.rb:296:in `measure'
  	from /home/opc/build-ruby/build-ruby.rb:452:in `run'
  	from /home/opc/build-ruby/build-ruby.rb:649:in `<main>'
  total: 10811.68 sec