Result of trunk-mjit-wait@phosphorus-docker at 2022-01-31 19:17:40 +0900

NG (b01657c470)[LOG]
test-all:
test-spec:

commands:

 "make update-unicode  -j6" exit with 0.
"make update-download -j6" exit with 0.
"make update-rubyspec -j6" exit with 0.
"make update-src      -j6" exit with 0.
"make after-update    -j6" exit with 512.
make[1]: *** No rule to make target 'id.h', needed by 'ripper.y'.  Stop.
make: *** [uncommon.mk:1198: /tmp/ruby/v3/src/trunk-mjit-wait/ext/ripper/ripper.c] Error 2
make: *** Waiting for unfinished jobs....
"make miniruby -j6" exit with 0.
"make ruby -j6" exit with 0.
"make all -j6" exit with 0.
"make install -j6" exit with 0.
"make yes-btest TESTOPTS=-q RUN_OPTS=\"--disable-gems --mjit-debug=-ggdb3 --mjit-wait --mjit-warnings --mjit-min-calls=5\"" exit with 0.
"make yes-test-basic RUN_OPTS=\"--disable-gems --mjit-debug=-ggdb3 --mjit-wait --mjit-warnings --mjit-min-calls=5\"" exit with 0.
make yes-test-all TESTOPTS='--stderr-on-failure' RUN_OPTS="--disable-gems --mjit-debug=-ggdb3 --mjit-wait --mjit-warnings --mjit-min-calls=5"
MJIT warning: Skipped to compile unsupported instruction: defineclass
MJIT warning: Skipped to compile unsupported instruction: defineclass
MJIT warning: Skipped to compile unsupported instruction: defineclass
#<Thread:0x00007f0dd914eba8 /tmp/ruby/v3/src/trunk-mjit-wait/test/net/http/test_https.rb:274 run> terminated with exception (report_on_exception is true):
/tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit/assertions.rb:109:in `assert': [Net::OpenTimeout] exception expected, not #<Errno::ECONNREFUSED: Failed to open TCP connection to localhost:33413 (Connection refused - connect(2) for 127.0.0.1:33413)>. (Test::Unit::AssertionFailedError)
	from /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/core_assertions.rb:485:in `assert'
	from /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit/assertions.rb:352:in `flunk'
	from /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/core_assertions.rb:399:in `block in assert_raise'
	from /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit/assertions.rb:108:in `assert'
	from /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/core_assertions.rb:485:in `assert'
	from /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/core_assertions.rb:398:in `rescue in assert_raise'
	from /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/core_assertions.rb:386:in `assert_raise'
	from /tmp/ruby/v3/src/trunk-mjit-wait/test/net/http/test_https.rb:275:in `block (2 levels) in test_timeout_during_SSL_handshake'
/tmp/ruby/v3/build/trunk-mjit-wait/.ext/common/socket.rb:1214:in `__connect_nonblock': Failed to open TCP connection to localhost:33413 (Connection refused - connect(2) for 127.0.0.1:33413) (Errno::ECONNREFUSED)
	from /tmp/ruby/v3/build/trunk-mjit-wait/.ext/common/socket.rb:1214:in `connect_nonblock'
	from /tmp/ruby/v3/build/trunk-mjit-wait/.ext/common/socket.rb:56:in `connect_internal'
	from /tmp/ruby/v3/build/trunk-mjit-wait/.ext/common/socket.rb:137:in `connect'
	from /tmp/ruby/v3/build/trunk-mjit-wait/.ext/common/socket.rb:642:in `block in tcp'
	from /tmp/ruby/v3/build/trunk-mjit-wait/.ext/common/socket.rb:227:in `each'
	from /tmp/ruby/v3/build/trunk-mjit-wait/.ext/common/socket.rb:227:in `foreach'
	from /tmp/ruby/v3/build/trunk-mjit-wait/.ext/common/socket.rb:632:in `tcp'
	from /tmp/ruby/v3/src/trunk-mjit-wait/lib/net/http.rb:998:in `connect'
	from /tmp/ruby/v3/src/trunk-mjit-wait/lib/net/http.rb:976:in `do_start'
	from /tmp/ruby/v3/src/trunk-mjit-wait/lib/net/http.rb:965:in `start'
	from /tmp/ruby/v3/src/trunk-mjit-wait/lib/net/http.rb:1530:in `request'
	from /tmp/ruby/v3/src/trunk-mjit-wait/lib/net/http.rb:1288:in `get'
	from /tmp/ruby/v3/src/trunk-mjit-wait/test/net/http/test_https.rb:276:in `block (3 levels) in test_timeout_during_SSL_handshake'
	from /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/core_assertions.rb:385:in `assert_raise'
	from /tmp/ruby/v3/src/trunk-mjit-wait/test/net/http/test_https.rb:275:in `block (2 levels) in test_timeout_during_SSL_handshake'
/tmp/ruby/v3/build/trunk-mjit-wait/.ext/common/socket.rb:1214:in `__connect_nonblock': Connection refused - connect(2) for 127.0.0.1:33413 (Errno::ECONNREFUSED)
	from /tmp/ruby/v3/build/trunk-mjit-wait/.ext/common/socket.rb:1214:in `connect_nonblock'
	from /tmp/ruby/v3/build/trunk-mjit-wait/.ext/common/socket.rb:56:in `connect_internal'
	from /tmp/ruby/v3/build/trunk-mjit-wait/.ext/common/socket.rb:137:in `connect'
	from /tmp/ruby/v3/build/trunk-mjit-wait/.ext/common/socket.rb:642:in `block in tcp'
	from /tmp/ruby/v3/build/trunk-mjit-wait/.ext/common/socket.rb:227:in `each'
	from /tmp/ruby/v3/build/trunk-mjit-wait/.ext/common/socket.rb:227:in `foreach'
	from /tmp/ruby/v3/build/trunk-mjit-wait/.ext/common/socket.rb:632:in `tcp'
	from /tmp/ruby/v3/src/trunk-mjit-wait/lib/net/http.rb:998:in `connect'
	from /tmp/ruby/v3/src/trunk-mjit-wait/lib/net/http.rb:976:in `do_start'
	from /tmp/ruby/v3/src/trunk-mjit-wait/lib/net/http.rb:965:in `start'
	from /tmp/ruby/v3/src/trunk-mjit-wait/lib/net/http.rb:1530:in `request'
	from /tmp/ruby/v3/src/trunk-mjit-wait/lib/net/http.rb:1288:in `get'
	from /tmp/ruby/v3/src/trunk-mjit-wait/test/net/http/test_https.rb:276:in `block (3 levels) in test_timeout_during_SSL_handshake'
	from /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/core_assertions.rb:385:in `assert_raise'
	from /tmp/ruby/v3/src/trunk-mjit-wait/test/net/http/test_https.rb:275:in `block (2 levels) in test_timeout_during_SSL_handshake'
MJIT warning: Skipped to compile unsupported instruction: defineclass
MJIT warning: Skipped to compile unsupported instruction: defineclass
$$$ #<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-mjit-wait --incremental --process-num=6
   11 22551    11    11 ?           -1 Sl    1001   0:00 ruby ./br.rb build_report trunk-mjit-wait --incremental --process-num=6
22551 22554    11    11 ?           -1 S     1001   0:00 sh -c ruby /home/ko1/ruby/build-ruby/build-ruby.rb --target_name=trunk-mjit-wait --logfile=/home/ko1/ruby/logs/brlog.trunk-mjit-wait.20220131-032104 --root_dir=/tmp/ruby --timeout=18000 --configure_opts=cppflags="-DMJIT_FORCE_ENABLE" --add-env=RUBY_TEST_SUBPROCESS_TIMEOUT_SCALE=30 --test_opts='RUN_OPTS="--disable-gems --mjit-debug=-ggdb3 --mjit-wait --mjit-warnings --mjit-min-calls=5"' --incremental --process-num=6
22554 22555    11    11 ?           -1 Sl    1001   0:00 ruby /home/ko1/ruby/build-ruby/build-ruby.rb --target_name=trunk-mjit-wait --logfile=/home/ko1/ruby/logs/brlog.trunk-mjit-wait.20220131-032104 --root_dir=/tmp/ruby --timeout=18000 --configure_opts=cppflags=-DMJIT_FORCE_ENABLE --add-env=RUBY_TEST_SUBPROCESS_TIMEOUT_SCALE=30 --test_opts=RUN_OPTS="--disable-gems --mjit-debug=-ggdb3 --mjit-wait --mjit-warnings --mjit-min-calls=5" --incremental --process-num=6
22555 25447    11    11 ?           -1 S     1001   0:00 sh -c make yes-test-all TESTOPTS='--stderr-on-failure' RUN_OPTS="--disable-gems --mjit-debug=-ggdb3 --mjit-wait --mjit-warnings --mjit-min-calls=5"
25447 25448    11    11 ?           -1 S     1001   0:00 make yes-test-all TESTOPTS=--stderr-on-failure RUN_OPTS=--disable-gems --mjit-debug=-ggdb3 --mjit-wait --mjit-warnings --mjit-min-calls=5
25448 25456    11    11 ?           -1 Sl    1001   3:54 /tmp/ruby/v3/src/trunk-mjit-wait/test/runner.rb: TestFiberMutex#test_condition_variable
22555 28807    11    11 ?           -1 R     1001   0:00 ps jx
{10=>[11], 11=>[22551], 22551=>[22554], 22554=>[22555], 22555=>[25447, 28807], 25447=>[25448], 25448=>[25456]}
$ timeout 60 gdb -p 25456 -x /home/ko1/ruby/build-ruby/gdbscript -batch -quiet 2> /dev/null
[New LWP 919]
[New LWP 28666]
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
0x00007f0de4a32bf6 in __ppoll (fds=fds@entry=0x7fff93b2eaa8, nfds=nfds@entry=1, timeout=<optimized out>, sigmask=sigmask@entry=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:44
>>> Threads  Id   Target Id                                           Frame 
* 1    Thread 0x7f0de491bb80 (LWP 25456) "ruby"            0x00007f0de4a32bf6 in __ppoll (fds=fds@entry=0x7fff93b2eaa8, nfds=nfds@entry=1, timeout=<optimized out>, sigmask=sigmask@entry=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:44
  2    Thread 0x7f0de1eef700 (LWP 919) "ruby-mjitworker"   futex_wait_cancelable (private=<optimized out>, expected=0, futex_word=0x562322cda68c <mjit_worker_wakeup+44>) at ../sysdeps/nptl/futex-internal.h:183
  3    Thread 0x7f0dd7a11700 (LWP 28666) "test_mutex.rb:*" 0x00007f0de4a3512b in __GI___select (nfds=nfds@entry=8, readfds=readfds@entry=0x7f0dbc0096b0, writefds=writefds@entry=0x7f0dbc008bc0, exceptfds=exceptfds@entry=0x7f0dbc000ee0, timeout=0x0) at ../sysdeps/unix/sysv/linux/select.c:41
>>> Machine level backtrace
Thread 3 (Thread 0x7f0dd7a11700 (LWP 28666)):
#0  0x00007f0de4a3512b in __GI___select (nfds=nfds@entry=8, readfds=readfds@entry=0x7f0dbc0096b0, writefds=writefds@entry=0x7f0dbc008bc0, exceptfds=exceptfds@entry=0x7f0dbc000ee0, timeout=0x0) at ../sysdeps/unix/sysv/linux/select.c:41
#1  0x0000562322a77a9b in rb_fd_select (timeout=<optimized out>, exceptfds=0x7f0dbc000ee0, writefds=0x7f0dbc008bc0, readfds=0x7f0dbc0096b0, n=8) at /tmp/ruby/v3/src/trunk-mjit-wait/thread.c:4057
#2  native_fd_select (th=<optimized out>, timeout=<optimized out>, exceptfds=0x7f0dbc000ee0, writefds=0x7f0dbc008bc0, readfds=0x7f0dbc0096b0, n=8) at /tmp/ruby/v3/src/trunk-mjit-wait/thread_pthread.c:1246
#3  do_select (p=p@entry=139697427908032) at /tmp/ruby/v3/src/trunk-mjit-wait/thread.c:4231
#4  0x00005623228dae74 in rb_ensure (b_proc=b_proc@entry=0x562322a777b0 <do_select>, data1=data1@entry=139697427908032, e_proc=e_proc@entry=0x562322a6bcf0 <select_set_free>, data2=data2@entry=139697427908032) at /tmp/ruby/v3/src/trunk-mjit-wait/eval.c:993
#5  0x0000562322a7a39e in rb_thread_fd_select (max=max@entry=8, read=read@entry=0x7f0dd7910880, write=write@entry=0x7f0dd7910890, except=except@entry=0x7f0dd79108a0, timeout=timeout@entry=0x0) at /tmp/ruby/v3/src/trunk-mjit-wait/thread.c:4322
#6  0x000056232291f28d in select_internal (fds=<optimized out>, tp=0x0, except=<optimized out>, write=<optimized out>, read=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit-wait/io.c:9917
#7  select_call (arg=arg@entry=139697427908704) at /tmp/ruby/v3/src/trunk-mjit-wait/io.c:9987
#8  0x00005623228dae74 in rb_ensure (b_proc=b_proc@entry=0x56232291f000 <select_call>, data1=data1@entry=139697427908704, e_proc=e_proc@entry=0x562322918a50 <select_end>, data2=data2@entry=139697427908704) at /tmp/ruby/v3/src/trunk-mjit-wait/eval.c:993
#9  0x0000562322918991 in rb_f_select (argc=<optimized out>, argv=<optimized out>, obj=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit-wait/io.c:10335
#10 0x0000562322ab19eb in vm_call_cfunc_with_frame (ec=0x56232a465180, reg_cfp=0x7f0dd7a10b90, calling=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit-wait/vm_insnhelper.c:3037
#11 0x0000562322acc131 in vm_sendish (block_handler=<optimized out>, method_explorer=<optimized out>, cd=<optimized out>, reg_cfp=<optimized out>, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit-wait/vm_callinfo.h:349
#12 vm_exec_core (ec=0x8, initial=139696965457584) at ../../src/trunk-mjit-wait/insns.def:778
#13 0x0000562322abd463 in rb_vm_exec (ec=0x56232a465180, mjit_enable_p=true) at /tmp/ruby/v3/src/trunk-mjit-wait/vm.c:2211
#14 0x0000562322ac4df3 in rb_vm_invoke_proc (ec=<optimized out>, proc=proc@entry=0x56232a4653b0, argc=argc@entry=0, argv=argv@entry=0x7f0dd7910bf0, kw_splat=<optimized out>, passed_block_handler=passed_block_handler@entry=0) at /tmp/ruby/v3/src/trunk-mjit-wait/vm.c:1521
#15 0x0000562322a7352d in thread_do_start_proc (th=0x56232a469ce0) at /tmp/ruby/v3/src/trunk-mjit-wait/thread.c:716
#16 0x0000562322a763d4 in thread_do_start (th=0x56232a469ce0) at /tmp/ruby/v3/src/trunk-mjit-wait/thread.c:735
#17 thread_start_func_2 (th=<optimized out>, stack_start=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit-wait/thread.c:809
#18 0x0000562322a76d4b in thread_start_func_1 (th_ptr=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit-wait/thread_pthread.c:1054
#19 0x00007f0de4d39609 in start_thread (arg=<optimized out>) at pthread_create.c:477
#20 0x00007f0de4a3f293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 2 (Thread 0x7f0de1eef700 (LWP 919)):
#0  futex_wait_cancelable (private=<optimized out>, expected=0, futex_word=0x562322cda68c <mjit_worker_wakeup+44>) at ../sysdeps/nptl/futex-internal.h:183
#1  __pthread_cond_wait_common (abstime=0x0, clockid=0, mutex=0x562322cda720 <mjit_engine_mutex>, cond=0x562322cda660 <mjit_worker_wakeup>) at pthread_cond_wait.c:508
#2  __pthread_cond_wait (cond=cond@entry=0x562322cda660 <mjit_worker_wakeup>, mutex=mutex@entry=0x562322cda720 <mjit_engine_mutex>) at pthread_cond_wait.c:638
#3  0x0000562322a710cd in rb_native_cond_wait (cond=cond@entry=0x562322cda660 <mjit_worker_wakeup>, mutex=mutex@entry=0x562322cda720 <mjit_engine_mutex>) at /tmp/ruby/v3/src/trunk-mjit-wait/thread_pthread.c:552
#4  0x000056232295394b in mjit_worker () at /tmp/ruby/v3/src/trunk-mjit-wait/mjit_worker.c:1445
#5  0x0000562322a6b84e in mjit_worker (arg=0x562322953830 <mjit_worker>) at /tmp/ruby/v3/src/trunk-mjit-wait/thread_pthread.c:2022
#6  0x00007f0de4d39609 in start_thread (arg=<optimized out>) at pthread_create.c:477
#7  0x00007f0de4a3f293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 1 (Thread 0x7f0de491bb80 (LWP 25456)):
#0  0x00007f0de4a32bf6 in __ppoll (fds=fds@entry=0x7fff93b2eaa8, nfds=nfds@entry=1, timeout=<optimized out>, sigmask=sigmask@entry=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:44
#1  0x0000562322a72160 in rb_sigwait_sleep (th=th@entry=0x5623238f5e30, sigwait_fd=sigwait_fd@entry=3, rel=rel@entry=0x0) at /tmp/ruby/v3/src/trunk-mjit-wait/hrtime.h:148
#2  0x0000562322a74a3f in native_sleep (th=th@entry=0x5623238f5e30, rel=0x0) at /tmp/ruby/v3/src/trunk-mjit-wait/thread_pthread.c:2236
#3  0x0000562322a770de in thread_join_sleep (arg=arg@entry=140735671364768) at /tmp/ruby/v3/src/trunk-mjit-wait/thread.c:1176
#4  0x00005623228dae74 in rb_ensure (b_proc=b_proc@entry=0x562322a77030 <thread_join_sleep>, data1=data1@entry=140735671364768, e_proc=e_proc@entry=0x562322a6b470 <remove_from_join_list>, data2=data2@entry=140735671364768) at /tmp/ruby/v3/src/trunk-mjit-wait/eval.c:993
#5  0x0000562322a6fcff in thread_join (limit=0x0, timeout=8, target_th=0x56232a469ce0) at /tmp/ruby/v3/src/trunk-mjit-wait/thread.c:1227
#6  thread_join_m (argc=<optimized out>, argv=<optimized out>, self=139697451608280) at /tmp/ruby/v3/src/trunk-mjit-wait/thread.c:1326
#7  0x0000562322ab19eb in vm_call_cfunc_with_frame (ec=0x5623238f6990, reg_cfp=0x7f0de4919950, calling=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit-wait/vm_insnhelper.c:3037
#8  0x0000562322acc131 in vm_sendish (block_handler=<optimized out>, method_explorer=<optimized out>, cd=<optimized out>, reg_cfp=<optimized out>, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit-wait/vm_callinfo.h:349
#9  vm_exec_core (ec=0x7fff93b2eaa8, initial=1) at ../../src/trunk-mjit-wait/insns.def:778
#10 0x0000562322abd463 in rb_vm_exec (ec=0x5623238f6990, mjit_enable_p=false) at /tmp/ruby/v3/src/trunk-mjit-wait/vm.c:2211
#11 0x00007f0dd869ea92 in ?? ()
#12 0x00007fff93b2ee40 in ?? ()
#13 0x00007fff93b2ee70 in ?? ()
#14 0x0000000000000000 in ?? ()
>>> Dump Ruby level backtrace
th: 0x5623238f5e30, native_id: 0x7f0de491bb80
-- Control frame information -----------------------------------------------
c:0028 p:---- s:0162 e:000161 CFUNC  :join
  me:
    called_id: join, type: cfunc
    owner class: 0x00007f0de27330d8 [3LM    ] T_CLASS Thread
  self: 0x00007f0dd8faa8d8 [0    U ] VM/thread (Thread)VM/thread
c:0027 p:0051 s:0158 E:000c18 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/test/fiber/test_mutex.rb:135 [FINISH]
  me:
    called_id: test_condition_variable, type: iseq
    owner class: 0x00007f0de0dba8b8 [3LM R  ] T_CLASS TestFiberMutex
  self: 0x00007f0dd8faadb0 [0      ] T_OBJECT (TestFiberMutex)len:10 ptr:0x00007f0de44939e8
  lvars:
    mutex: 0x00007f0dd8faa978 [0      ] mutex (Thread::Mutex)mutex
    condition: 0x00007f0dd8faa928 [0      ] condvar (Thread::ConditionVariable)condvar
    signalled: T_FIXNUM 2
c:0026 p:0041 s:0151 e:000150 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit/testcase.rb:200 [FINISH]
  me:
    called_id: run_test, type: iseq
    owner class: 0x00007f0de16437a0 [3LM R  ] T_CLASS Test::Unit::TestCase
  self: 0x00007f0dd8faadb0 [0      ] T_OBJECT (TestFiberMutex)len:10 ptr:0x00007f0de44939e8
  lvars:
    name: T_SYMBOL test_condition_variable
    progname: 0x00007f0dd8dc59a0 [3LM    ] T_STRING (String) [embed] len: 47, capa: 55 "/tmp/ruby/v3/src/trunk-mjit-wait/test/runner.rb"
c:0025 p:0059 s:0145 e:000144 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit/testcase.rb:168 [FINISH]
  me:
    called_id: run, type: iseq
    owner class: 0x00007f0de16437a0 [3LM R  ] T_CLASS Test::Unit::TestCase
  self: 0x00007f0dd8faadb0 [0      ] T_OBJECT (TestFiberMutex)len:10 ptr:0x00007f0de44939e8
  lvars:
    runner: 0x00007f0de15dc550 [3LM    ] T_OBJECT (Test::Unit::AutoRunner::Runner)len:28 ptr:0x000056232a25b7b0
    start_time: 0x00007f0dd8faac98 [0    U ] time (Time)time
    result: 0x00007f0de47ea448 [3LM    ] T_STRING (String) [embed] len: 0, capa: 15 ""
    time: T_NIL
    e: T_NIL
c:0024 p:0089 s:0136 e:000135 BLOCK  /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:1534 [FINISH]
  me:
    called_id: _run_suite, type: iseq
    owner class: 0x00007f0de1664298 [3LM    ] T_CLASS Test::Unit::Runner
  self: 0x00007f0de15dc550 [3LM    ] T_OBJECT (Test::Unit::AutoRunner::Runner)len:28 ptr:0x000056232a25b7b0
  lvars:
    method: T_SYMBOL test_condition_variable
    inst: 0x00007f0dd8faadb0 [0      ] T_OBJECT (TestFiberMutex)len:10 ptr:0x00007f0de44939e8
    start_time: T_NIL
    result: T_NIL
c:0023 p:---- s:0129 e:000128 CFUNC  :map
  me:
    called_id: map, type: cfunc
    owner class: 0x00007f0de273d420 [3LM R  ] T_CLASS Array
  self: 0x00007f0dd8fa4780 [0      ] T_ARRAY (Array)[   ] len: 8, capa:16 ptr:0x00005623278e0010
c:0022 p:0117 s:0125 e:000124 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:1521
  me:
    called_id: _run_suite, type: iseq
    owner class: 0x00007f0de1664298 [3LM    ] T_CLASS Test::Unit::Runner
  self: 0x00007f0de15dc550 [3LM    ] T_OBJECT (Test::Unit::AutoRunner::Runner)len:28 ptr:0x000056232a25b7b0
  lvars:
    suite: 0x00007f0de0dba8b8 [3LM R  ] T_CLASS TestFiberMutex
    type: T_SYMBOL test
    header: 0x00007f0dd8fa4c30 [0      ] T_STRING (String) len: 17, capa: 31 "test_suite_header"
    filter: 0x00007f0de158fd18 [3LM    ] T_REGEXP (Regexp)
    all_test_methods: 0x00007f0dd8fa4780 [0      ] T_ARRAY (Array)[   ] len: 8, capa:16 ptr:0x00005623278e0010
    leakchecker: 0x00007f0dd8fa4690 [0      ] T_OBJECT (LeakChecker)len:7 ptr:0x00007f0de44934a0
    trace: T_NIL
    assertions: T_NIL
c:0021 p:0042 s:0113 e:000112 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:1314
  me:
    called_id: _run_suite, type: iseq
    owner class: 0x00007f0de1664838 [3LM    ] T_MODULE (Module)Test::Unit::ExcludesOption
    defined_class: 0x00007f0de16772a8 [3LM    ] T_ICLASS src:Test::Unit::ExcludesOption
  self: 0x00007f0de15dc550 [3LM    ] T_OBJECT (Test::Unit::AutoRunner::Runner)len:28 ptr:0x000056232a25b7b0
  lvars:
    suite: 0x00007f0de0dba8b8 [3LM R  ] T_CLASS TestFiberMutex
    type: T_SYMBOL test
    ex: T_NIL
c:0020 p:0010 s:0106 e:000104 BLOCK  /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:802 [FINISH]
  me:
    called_id: _run_suites, type: iseq
    owner class: 0x00007f0de1665c38 [3LM    ] T_MODULE (Module)Test::Unit::Parallel
    defined_class: 0x00007f0de16777a8 [3LM    ] T_ICLASS src:Test::Unit::Parallel
  self: 0x00007f0de15dc550 [3LM    ] T_OBJECT (Test::Unit::AutoRunner::Runner)len:28 ptr:0x000056232a25b7b0
  lvars:
    suite: 0x00007f0de0dba8b8 [3LM R  ] T_CLASS TestFiberMutex
    e: T_NIL
c:0019 p:---- s:0100 e:000099 CFUNC  :each
  me:
    called_id: each, type: cfunc
    owner class: 0x00007f0de273d420 [3LM R  ] T_CLASS Array
  self: 0x00007f0dd8e6eca8 [3LM    ] T_ARRAY (Array)[   ] len: 961, capa:961 ptr:0x000056232a1098f0
c:0018 p:0054 s:0096 E:000468 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:800
  me:
    called_id: _run_suites, type: iseq
    owner class: 0x00007f0de1665c38 [3LM    ] T_MODULE (Module)Test::Unit::Parallel
    defined_class: 0x00007f0de16777a8 [3LM    ] T_ICLASS src:Test::Unit::Parallel
  self: 0x00007f0de15dc550 [3LM    ] T_OBJECT (Test::Unit::AutoRunner::Runner)len:28 ptr:0x000056232a25b7b0
  lvars:
    suites: 0x00007f0dd8e6eca8 [3LM    ] T_ARRAY (Array)[   ] len: 961, capa:961 ptr:0x000056232a1098f0
    type: T_SYMBOL test
    result: 0x00007f0dd8f6e810 [3LM R  ] T_ARRAY (Array)[   ] len: 77, capa:85 ptr:0x000056232a04aa40
c:0017 p:0008 s:0089 E:0004e8 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:837
  me:
    called_id: _run_suites, type: iseq
    owner class: 0x00007f0de1664fb8 [3LM    ] T_MODULE (Module)Test::Unit::Skipping
    defined_class: 0x00007f0de1677668 [3LM    ] T_ICLASS src:Test::Unit::Skipping
  self: 0x00007f0de15dc550 [3LM    ] T_OBJECT (Test::Unit::AutoRunner::Runner)len:28 ptr:0x000056232a25b7b0
  lvars:
    suites: 0x00007f0dd8e6eca8 [3LM    ] T_ARRAY (Array)[   ] len: 961, capa:961 ptr:0x000056232a1098f0
    type: T_SYMBOL test
    result: T_NIL
c:0016 p:0153 s:0082 E:000558 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:1467
  me:
    called_id: _run_anything, type: iseq
    owner class: 0x00007f0de1664298 [3LM    ] T_CLASS Test::Unit::Runner
  self: 0x00007f0de15dc550 [3LM    ] T_OBJECT (Test::Unit::AutoRunner::Runner)len:28 ptr:0x000056232a25b7b0
  lvars:
    type: T_SYMBOL test
    suites: 0x00007f0dd8e6eca8 [3LM    ] T_ARRAY (Array)[   ] len: 961, capa:961 ptr:0x000056232a1098f0
    test_count: T_FIXNUM 0
    assertion_count: T_FIXNUM 0
    sync: T_TRUE
    old_sync: T_FALSE
    start: 0x00007f0dd8e8b830 [0LM  U ] time (Time)time
    results: T_NIL
    t: T_NIL
c:0015 p:0016 s:0069 E:000588 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:1251
  me:
    called_id: _run_anything, type: iseq
    owner class: 0x00007f0de16648d8 [3LM    ] T_MODULE (Module)Test::Unit::RepeatOption
    defined_class: 0x00007f0de1677528 [3LM    ] T_ICLASS src:Test::Unit::RepeatOption
  self: 0x00007f0de15dc550 [3LM    ] T_OBJECT (Test::Unit::AutoRunner::Runner)len:28 ptr:0x000056232a25b7b0
  lvars:
    type: T_SYMBOL test
c:0014 p:0005 s:0064 E:000690 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:1642
  me:
    called_id: run_tests, type: iseq
    owner class: 0x00007f0de1664298 [3LM    ] T_CLASS Test::Unit::Runner
  self: 0x00007f0de15dc550 [3LM    ] T_OBJECT (Test::Unit::AutoRunner::Runner)len:28 ptr:0x000056232a25b7b0
c:0013 p:0006 s:0060 E:0006f8 BLOCK  /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:1629 [FINISH]
  me:
    called_id: _run, type: iseq
    owner class: 0x00007f0de1664298 [3LM    ] T_CLASS Test::Unit::Runner
  self: 0x00007f0de15dc550 [3LM    ] T_OBJECT (Test::Unit::AutoRunner::Runner)len:28 ptr:0x000056232a25b7b0
  lvars:
    plugin: 0x00007f0de1646900 [3LM    ] T_STRING (String) [embed] len: 9, capa: 15 "run_tests"
c:0012 p:---- s:0056 e:000055 CFUNC  :each
  me:
    called_id: each, type: cfunc
    owner class: 0x00007f0de273d420 [3LM R  ] T_CLASS Array
  self: 0x00007f0dd8e6eeb0 [3LM    ] T_ARRAY (Array)[E ] len: 1 (embed)
c:0011 p:0042 s:0052 E:0006c8 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:1628
  me:
    called_id: _run, type: iseq
    owner class: 0x00007f0de1664298 [3LM    ] T_CLASS Test::Unit::Runner
  self: 0x00007f0de15dc550 [3LM    ] T_OBJECT (Test::Unit::AutoRunner::Runner)len:28 ptr:0x000056232a25b7b0
  lvars:
    args: 0x00007f0de15ede40 [3LM    ] T_HASH (Hash)[S ] 9
c:0010 p:0013 s:0047 E:000728 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:1670
  me:
    called_id: run, type: iseq
    owner class: 0x00007f0de1664298 [3LM    ] T_CLASS Test::Unit::Runner
  self: 0x00007f0de15dc550 [3LM    ] T_OBJECT (Test::Unit::AutoRunner::Runner)len:28 ptr:0x000056232a25b7b0
  lvars:
    argv: 0x00007f0de47ccd58 [3LM    ] T_ARRAY shared -> 0x00007f0de1575120 [3LM    ] T_ARRAY (Array)[   ] len: 838, capa:2 ptr:0x0000562323bd9a30
c:0009 p:0008 s:0042 E:000760 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:1022
  me:
    called_id: run, type: iseq
    owner class: 0x00007f0de1664e78 [3LM    ] T_MODULE (Module)Test::Unit::StatusLine
    defined_class: 0x00007f0de1677848 [3LM    ] T_ICLASS src:Test::Unit::StatusLine
  self: 0x00007f0de15dc550 [3LM    ] T_OBJECT (Test::Unit::AutoRunner::Runner)len:28 ptr:0x000056232a25b7b0
  lvars:
    args: 0x00007f0dd8e6f428 [3LM    ] T_ARRAY (Array)[E ] len: 1 (embed)
    result: T_NIL
c:0008 p:0008 s:0036 E:0007a0 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:870
  me:
    called_id: run, type: iseq
    owner class: 0x00007f0de1664f18 [3LM    ] T_MODULE (Module)Test::Unit::Statistics
    defined_class: 0x00007f0de1677708 [3LM    ] T_ICLASS src:Test::Unit::Statistics
  self: 0x00007f0de15dc550 [3LM    ] T_OBJECT (Test::Unit::AutoRunner::Runner)len:28 ptr:0x000056232a25b7b0
  lvars:
    args: 0x00007f0dd8e6f4c8 [3LM    ] T_ARRAY (Array)[E ] len: 1 (embed)
    result: T_NIL
c:0007 p:0017 s:0030 E:0007d8 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:154
  me:
    called_id: run, type: iseq
    owner class: 0x00007f0de1665e18 [3LM    ] T_MODULE Test::Unit::RunCount
    defined_class: 0x00007f0de1677168 [3LM    ] T_ICLASS src:Test::Unit::RunCount
  self: 0x00007f0de15dc550 [3LM    ] T_OBJECT (Test::Unit::AutoRunner::Runner)len:28 ptr:0x000056232a25b7b0
  lvars:
    *: 0x00007f0dd8e6f540 [3LM    ] T_ARRAY (Array)[E ] len: 1 (embed)
c:0006 p:0032 s:0025 E:000440 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:1749
  me:
    called_id: run, type: iseq
    owner class: 0x00007f0de16770c8 [3LM    ] T_CLASS Test::Unit::AutoRunner
  self: 0x00007f0de15dc618 [3LM    ] T_OBJECT (Test::Unit::AutoRunner)len:5 ptr:0x0000562323bbe3c0
c:0005 p:0009 s:0021 E:000478 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/tool/lib/test/unit.rb:1753
  me:
    called_id: run, type: iseq
    owner class: 0x00007f0de1677028 [3LM    ] T_CLASS (annon)
  self: 0x00007f0de16770c8 [3LM    ] T_CLASS Test::Unit::AutoRunner
  lvars:
    args: 0x00007f0de15dc690 [3LM    ] T_ARRAY (Array)[E ] len: 2 (embed)
c:0004 p:0174 s:0016 E:0004a8 TOP    /tmp/ruby/v3/src/trunk-mjit-wait/tool/test/runner.rb:23 [FINISH]
  self: 0x00007f0de47b8e20 [3LM    ] T_OBJECT (embed) len:3
  lvars:
    dir: 0x00007f0de15dc708 [3LM    ] T_STRING (String) len: 37, capa: 37 "/tmp/ruby/v3/src/trunk-mjit-wait/test"
c:0003 p:---- s:0011 e:000010 CFUNC  :require_relative
  me:
    called_id: require_relative, type: cfunc
    owner class: 0x00007f0de274b8e0 [3LM    ] T_MODULE Kernel
    defined_class: 0x00007f0de274b840 [3LM R  ] T_ICLASS src:Kernel
  self: 0x00007f0de47b8e20 [3LM    ] T_OBJECT (embed) len:3
c:0002 p:0115 s:0006 E:0004d0 EVAL   /tmp/ruby/v3/src/trunk-mjit-wait/test/runner.rb:14 [FINISH]
  self: 0x00007f0de47b8e20 [3LM    ] T_OBJECT (embed) len:3
c:0001 p:0000 s:0003 E:0018a0 (none) [FINISH]
  self: 0x00007f0de47b8e20 [3LM    ] T_OBJECT (embed) len:3
th: 0x56232a469ce0, native_id: 0x7f0dd7a11700
-- Control frame information -----------------------------------------------
c:0004 p:---- s:0025 e:000024 CFUNC  :select
  me:
    called_id: select, type: cfunc
    owner class: 0x00007f0de273bdf0 [3LM    ] T_CLASS (annon)
  self: 0x00007f0de273be90 [3LM R  ] T_CLASS IO
c:0003 p:0041 s:0017 e:000016 METHOD /tmp/ruby/v3/src/trunk-mjit-wait/test/fiber/scheduler.rb:62
  me:
    called_id: run, type: iseq
    owner class: 0x00007f0de0d566d8 [3LM R  ] T_CLASS Scheduler
  self: 0x00007f0dd8faa770 [0      ] T_OBJECT (Scheduler)len:8 ptr:0x00007f0de4493a48
  lvars:
    readable: 0x00007f0dd8fa9ac8 [0      ] T_ARRAY (Array)[E ] len: 1 (embed)
    writable: 0x00007f0dd8fa9aa0 [0      ] T_ARRAY (Array)[E ] len: 0 (embed)
    selected: 0x00007f0dd8fa9a50 [0      ] T_HASH (Hash)[A ] 0
    time: T_FLOAT
    waiting: 0x00007f0dd8fa9cd0 [0      ] T_HASH (Hash)[AT] 1
    ready: 0x00007f0dd8fa9eb0 [0      ] T_ARRAY (Array)[E ] len: 1 (embed)
c:0002 p:0057 s:0007 E:0012c8 BLOCK  /tmp/ruby/v3/src/trunk-mjit-wait/test/fiber/test_mutex.rb:134 [FINISH]
  me:
    called_id: test_condition_variable, type: iseq
    owner class: 0x00007f0de0dba8b8 [3LM R  ] T_CLASS TestFiberMutex
  self: 0x00007f0dd8faadb0 [0      ] T_OBJECT (TestFiberMutex)len:10 ptr:0x00007f0de44939e8
  lvars:
    scheduler: 0x00007f0dd8faa770 [0      ] T_OBJECT (Scheduler)len:8 ptr:0x00007f0de4493a48
c:0001 p:---- s:0003 e:000002 (none) [FINISH]
  self: T_NIL
$1 = 0
">>> Finish
"[Inferior 1 (process 25456) detached]
$ timeout 60 gdb -p 25448 -x /home/ko1/ruby/build-ruby/gdbscript -batch -quiet 2> /dev/null
0x00007f41362aedba in __GI___wait4 (pid=-1, stat_loc=0x7ffe09e35778, options=0, usage=0x0) at ../sysdeps/unix/sysv/linux/wait4.c:27
>>> Threads  Id   Target Id            Frame 
* 1    process 25448 "make" 0x00007f41362aedba in __GI___wait4 (pid=-1, stat_loc=0x7ffe09e35778, options=0, usage=0x0) at ../sysdeps/unix/sysv/linux/wait4.c:27
>>> Machine level backtrace
Thread 1 (process 25448):
#0  0x00007f41362aedba in __GI___wait4 (pid=-1, stat_loc=0x7ffe09e35778, options=0, usage=0x0) at ../sysdeps/unix/sysv/linux/wait4.c:27
#1  0x00005579efe78488 in reap_children ()
#2  0x00005579efe78e64 in new_job ()
#3  0x00005579efe84d47 in ?? ()
#4  0x00005579efe856df in update_goal_chain ()
#5  0x00005579efe69f3f in main ()
>>> Dump Ruby level backtrace
make: *** [uncommon.mk:821: yes-test-all] Killed
[Inferior 1 (process 25448) detached]
#<Errno::ESRCH: No such process>
$ timeout 60 gdb -p 25447 -x /home/ko1/ruby/build-ruby/gdbscript -batch -quiet 2> /dev/null
>>> ThreadsNo threads.
>>> Machine level backtrace
>>> Dump Ruby level backtrace
$ timeout 60 gdb -p 28807 -x /home/ko1/ruby/build-ruby/gdbscript -batch -quiet 2> /dev/null
>>> ThreadsNo threads.
>>> Machine level backtrace
>>> Dump Ruby level backtrace
#<Errno::ESRCH: No such process>

Linux phosphorus-docker 4.9.0-17-amd64 #1 SMP Debian 4.9.290-1 (2021-12-12) x86_64 x86_64 x86_64 GNU/Linux

Logfile: /home/ko1/ruby/logs/brlog.trunk-mjit-wait.20220131-032104
#<BuildRuby:0x00005603932f1538
 @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-mjit-wait",
 @TARGET_INSTALL_DIR="/tmp/ruby/v3/install/trunk-mjit-wait",
 @TARGET_NAME="trunk-mjit-wait",
 @TARGET_SRC_DIR="/tmp/ruby/v3/src/trunk-mjit-wait",
 @build_opts="-j6",
 @configure_opts=["cppflags=-DMJIT_FORCE_ENABLE"],
 @gist=false,
 @git_branch=nil,
 @git_worktree=nil,
 @incremental=true,
 @logfile="/home/ko1/ruby/logs/brlog.trunk-mjit-wait.20220131-032104",
 @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=
  "RUN_OPTS=\"--disable-gems --mjit-debug=-ggdb3 --mjit-wait --mjit-warnings --mjit-min-calls=5\"",
 @timeout=18000>
                           user     system      total        real
checkout               0.000000   0.000000   0.000000 (  0.000029)
autoconf               0.000000   0.000000   0.000000 (  0.000063)
configure              0.000000   0.000000   0.000000 (  0.000054)
build_up               0.008000   0.004000   1.916000 (  1.793035)
build_miniruby         0.004000   0.000000   0.192000 (  0.203558)
build_ruby             0.004000   0.000000   0.188000 (  0.197825)
build_all              0.008000   0.004000  38.884000 ( 11.788206)
build_install          0.020000   0.008000  53.352000 ( 19.291718)
test_btest             0.000000   0.000000 6926.912000 (6953.030047)
test_basic             0.004000   0.012000   7.552000 (  6.113324)
test_all               0.020000   0.004000 6855.284000 (18003.084855)
/home/ko1/ruby/build-ruby/build-ruby.rb:212:in `join': execution expired (Timeout::Error)
	from /home/ko1/ruby/build-ruby/build-ruby.rb:212:in `each'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:212: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:211:in `block in cmd'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:203:in `popen'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:203:in `cmd'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:378:in `block in test_all'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:298:in `block in builddir'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:297:in `chdir'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:297:in `builddir'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:376:in `test_all'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:440: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:438:in `block (3 levels) in run'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:437:in `each'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:437: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:436: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:435:in `run'
	from /home/ko1/ruby/build-ruby/build-ruby.rb:629:in `<main>'
total: 24995.50 sec