Result of trunk-mjit@phosphorus-docker at 2020-12-10 15:40:56 +0900

• NG (bd47a8d660) [LOG]

• Linux phosphorus-docker 4.9.0-14-amd64 #1 SMP Debian 4.9.240-2 (2020-10-30) x86_64 x86_64 x86_64 GNU/Linux
  

• rev: bd47a8d660
  test-all : 
  test-spec: 
  exit statuses:
    "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 0.
    "make miniruby -j6" exit with 0.
    "make ruby -j6" exit with 0.
    "make all -j6" exit with 0.
    "make install -j6" exit with 0.
  Logfile: /home/ko1/ruby/logs/brlog.trunk-mjit.20201208-070357
  #<BuildRuby:0x0000559bff4945f8
   @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",
   @TARGET_INSTALL_DIR="/tmp/ruby/v3/install/trunk-mjit",
   @TARGET_NAME="trunk-mjit",
   @TARGET_SRC_DIR="/tmp/ruby/v3/src/trunk-mjit",
   @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.20201208-070357",
   @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='-j6'",
   @timeout=10800>
                             user     system      total        real
  checkout               0.000000   0.000000   0.000000 (  0.000027)
  autoconf               0.000000   0.000000   0.000000 (  0.000053)
  configure              0.000000   0.000000   0.000000 (  0.000058)
  build_up             From https://github.com/ruby/ruby
     b7dc04e..bd47a8d  master     -> origin/master
    0.004000   0.008000  14.188000 ( 11.817733)
  build_miniruby         0.000000   0.000000   0.156000 (  0.182011)
  build_ruby             0.004000   0.000000   4.020000 (  3.670837)
  build_all              0.016000   0.000000  58.868000 ( 19.433695)
  build_install          0.020000   0.004000  52.636000 ( 20.880631)
  test_btest           test_attr.rb             ..
  test_autoload.rb         ........
  test_block.rb            ..........................................................
  test_class.rb            ................................................
  test_env.rb              ..
  test_eval.rb             .....................................
  test_exception.rb        ..................................
  test_fiber.rb            .....
  test_finalizer.rb        .
  test_flip.rb             .
  test_flow.rb             ..............................................................
  test_fork.rb             ....
  test_gc.rb               ..
  test_insns.rb            ...............................................................................................................................................................................................................................................................................................................................................................................................
  test_io.rb               .........
  test_jump.rb             .............................
  test_literal.rb          ............................................................................................................................................................
  test_literal_suffix.rb   ................................................
  test_load.rb             ..
  test_marshal.rb          .
  test_massign.rb          ..................................
  test_method.rb           ...............................................................................................................................................................................................................................
  test_objectspace.rb      ......
  test_proc.rb             .....................................
  test_ractor.rb           .....................................................................$$$ #<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 --incremental --process-num=6
     11 17745    11    11 ?           -1 S     1001   0:00 ruby ./br.rb build_report trunk-mjit --incremental --process-num=6
  17745 17746    11    11 ?           -1 S     1001   0:00 sh -c ruby /home/ko1/ruby/build-ruby/build-ruby.rb --target_name=trunk-mjit --logfile=/home/ko1/ruby/logs/brlog.trunk-mjit.20201208-070357 --root_dir=/tmp/ruby --timeout=10800 --configure_opts=cppflags="-DMJIT_FORCE_ENABLE"                          --add-env=RUBY_TEST_SUBPROCESS_TIMEOUT_SCALE=30 --incremental --process-num=6
  17746 17747    11    11 ?           -1 Sl    1001   0:00 ruby /home/ko1/ruby/build-ruby/build-ruby.rb --target_name=trunk-mjit --logfile=/home/ko1/ruby/logs/brlog.trunk-mjit.20201208-070357 --root_dir=/tmp/ruby --timeout=10800 --configure_opts=cppflags=-DMJIT_FORCE_ENABLE --add-env=RUBY_TEST_SUBPROCESS_TIMEOUT_SCALE=30 --incremental --process-num=6
  17747 18944    11    11 ?           -1 S     1001   0:00 sh -c make yes-btest TESTS='-j6'
  18944 18945    11    11 ?           -1 S     1001   0:00 make yes-btest TESTS=-j6
  18945 18951    11    11 ?           -1 Sl    1001   0:01 /usr/bin/ruby --disable=gems /tmp/ruby/v3/src/trunk-mjit/bootstraptest/runner.rb --ruby=./miniruby -I/tmp/ruby/v3/src/trunk-mjit/lib -I. -I.ext/common   --disable-gems
  18951 24248    11    11 ?           -1 Sl    1001 168:26 /tmp/ruby/v3/build/trunk-mjit/miniruby -I/tmp/ruby/v3/src/trunk-mjit/lib -I/tmp/ruby/v3/build/trunk-mjit -I/tmp/ruby/v3/build/trunk-mjit/.ext/common --disable-gems -W0 bootstraptest.tmp.rb
  17747 24254    11    11 ?           -1 R     1001   0:00 ps jx
  {10=>[11], 11=>[17745], 17745=>[17746], 17746=>[17747], 17747=>[18944, 24254], 18944=>[18945], 18945=>[18951], 18951=>[24248]}
  "gdb -p 24248 -x /home/ko1/ruby/build-ruby/gdbscript -batch -quiet"
  [New LWP 24249]
  [New LWP 24252]
  [New LWP 24253]
  [Thread debugging using libthread_db enabled]
  Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
  52	lowlevellock.c: No such file or directory.
  __lll_lock_wait (futex=futex@entry=0x55aa5ee4d970, private=0) at lowlevellock.c:52
  >>> Threads  Id   Target Id                                           Frame 
  * 1    Thread 0x7f3466bf5b80 (LWP 24248) "miniruby"        __lll_lock_wait (futex=futex@entry=0x55aa5ee4d970, private=0) at lowlevellock.c:52
    2    Thread 0x7f3463649700 (LWP 24249) "ruby-mjitworker" list_node_to_off_ (off=32, node=0x7f345800e890) at /tmp/ruby/v3/src/trunk-mjit/ccan/list/list.h:761
    3    Thread 0x7f3462e48700 (LWP 24252) "miniruby"        __lll_lock_wait (futex=futex@entry=0x55aa5ee4d970, private=0) at lowlevellock.c:52
    4    Thread 0x7f3462c47700 (LWP 24253) "miniruby"        __lll_lock_wait (futex=futex@entry=0x55aa5d6ddc00 <mjit_engine_mutex>, private=0) at lowlevellock.c:52
  >>> Machine level backtrace
  
  Thread 4 (Thread 0x7f3462c47700 (LWP 24253)):
  #0  __lll_lock_wait (futex=futex@entry=0x55aa5d6ddc00 <mjit_engine_mutex>, private=0) at lowlevellock.c:52
  #1  0x00007f34670160a3 in __GI___pthread_mutex_lock (mutex=mutex@entry=0x55aa5d6ddc00 <mjit_engine_mutex>) at ../nptl/pthread_mutex_lock.c:80
  #2  0x000055aa5d5520cd in rb_native_mutex_lock (lock=lock@entry=0x55aa5d6ddc00 <mjit_engine_mutex>) at /tmp/ruby/v3/src/trunk-mjit/thread_pthread.c:397
  #3  0x000055aa5d432af9 in CRITICAL_SECTION_START (msg=0x55aa5d5d9646 "mjit_mark rb_gc_mark", level=4) at /tmp/ruby/v3/src/trunk-mjit/mjit_worker.c:441
  #4  mjit_mark () at /tmp/ruby/v3/src/trunk-mjit/mjit.c:929
  #5  0x000055aa5d5b15a6 in rb_vm_mark (ptr=ptr@entry=0x55aa5ee4d940) at /tmp/ruby/v3/src/trunk-mjit/vm.c:2588
  #6  0x000055aa5d3dc126 in gc_mark_roots (objspace=objspace@entry=0x55aa5ee4dfd0, categoryp=categoryp@entry=0x0) at /tmp/ruby/v3/src/trunk-mjit/gc.c:6529
  #7  0x000055aa5d3ddc28 in gc_marks_start (full_mark=<optimized out>, objspace=0x55aa5ee4dfd0) at /tmp/ruby/v3/src/trunk-mjit/gc.c:7624
  #8  gc_marks (full_mark=<optimized out>, objspace=0x55aa5ee4dfd0) at /tmp/ruby/v3/src/trunk-mjit/gc.c:7444
  #9  gc_start (objspace=0x55aa5ee4dfd0, reason=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/gc.c:8283
  #10 0x000055aa5d3e2a91 in heap_prepare (heap=<optimized out>, objspace=0x55aa5ee4dfd0) at /tmp/ruby/v3/src/trunk-mjit/gc.c:2008
  #11 heap_next_freepage (heap=<optimized out>, objspace=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/gc.c:2179
  #12 ractor_cache_slots (cr=<optimized out>, cr=<optimized out>, objspace=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/gc.c:2198
  #13 newobj_slowpath (wb_protected=1, cr=0x55aa5ef74c30, objspace=0x55aa5ee4dfd0, flags=5, klass=94190225598480) at /tmp/ruby/v3/src/trunk-mjit/gc.c:2234
  #14 newobj_slowpath_wb_protected (klass=94190225598480, flags=5, objspace=0x55aa5ee4dfd0, cr=0x55aa5ef74c30) at /tmp/ruby/v3/src/trunk-mjit/gc.c:2253
  #15 0x000055aa5d3e302c in newobj_of0 (cr=<optimized out>, wb_protected=1, flags=5, klass=94190225598480) at /tmp/ruby/v3/src/trunk-mjit/gc.c:2291
  #16 newobj_of_cr (wb_protected=1, v3=0, v2=0, v1=0, flags=5, klass=94190225598480, cr=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/gc.c:2319
  #17 rb_ec_wb_protected_newobj_of (ec=<optimized out>, klass=klass@entry=94190225598480, flags=flags@entry=5) at /tmp/ruby/v3/src/trunk-mjit/gc.c:2341
  #18 0x000055aa5d52c243 in ec_str_alloc (klass=94190225598480, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/string.c:1594
  #19 ec_str_duplicate (str=94190225007240, klass=94190225598480, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/string.c:1594
  #20 rb_ec_str_resurrect (ec=<optimized out>, str=94190225007240) at /tmp/ruby/v3/src/trunk-mjit/string.c:1622
  #21 0x000055aa5d5a80e2 in vm_exec_core (ec=0x55aa5ef73710, initial=128) at ../../src/trunk-mjit/insns.def:366
  #22 0x000055aa5d59bd54 in rb_vm_exec (ec=0x55aa5ef73710, mjit_enable_p=1) at /tmp/ruby/v3/src/trunk-mjit/vm.c:2165
  #23 0x000055aa5d59f88f in invoke_block (captured=<optimized out>, opt_pc=<optimized out>, type=<optimized out>, cref=0x0, self=94190225361280, iseq=0x55aa5ee72328, ec=0x55aa5ef73710) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1265
  #24 invoke_iseq_block_from_c (me=0x0, is_lambda=<optimized out>, cref=0x0, passed_block_handler=0, kw_splat=0, argv=0x7f3462b468d8, argc=1, self=94190225361280, captured=<optimized out>, ec=0x55aa5ef73710) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1337
  #25 invoke_block_from_c_bh (argc=<optimized out>, kw_splat=<optimized out>, passed_block_handler=<optimized out>, cref=<optimized out>, is_lambda=<optimized out>, force_blockarg=<optimized out>, argv=<optimized out>, block_handler=<optimized out>, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1355
  #26 vm_yield (argc=1, kw_splat=0, argv=0x7f3462b468d8, ec=0x55aa5ef73710) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1400
  #27 rb_yield_0 (argv=0x7f3462b468d8, argc=1) at /tmp/ruby/v3/src/trunk-mjit/vm_eval.c:1143
  #28 rb_yield_1 (val=<optimized out>, val@entry=10157) at /tmp/ruby/v3/src/trunk-mjit/vm_eval.c:1149
  #29 0x000055aa5d4601bc in int_dotimes (num=100001) at /tmp/ruby/v3/src/trunk-mjit/include/ruby/internal/arithmetic/long.h:95
  #30 0x000055aa5d5919a3 in vm_call_cfunc_with_frame (ec=0x55aa5ef73710, reg_cfp=0x7f3462c46c90, calling=<optimized out>, cd=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:2821
  #31 0x000055aa5d5a77f1 in vm_sendish (method_explorer=<optimized out>, block_handler=<optimized out>, cd=<optimized out>, reg_cfp=<optimized out>, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_callinfo.h:337
  #32 vm_exec_core (ec=0x55aa5ef73710, initial=128) at ../../src/trunk-mjit/insns.def:770
  #33 0x000055aa5d59bd54 in rb_vm_exec (ec=0x55aa5ef73710, mjit_enable_p=1) at /tmp/ruby/v3/src/trunk-mjit/vm.c:2165
  #34 0x000055aa5d554edc in thread_do_start_proc (th=0x55aa5ef6fa10) at /tmp/ruby/v3/src/trunk-mjit/thread.c:725
  #35 0x000055aa5d557bbf in thread_do_start (th=0x55aa5ef6fa10) at /tmp/ruby/v3/src/trunk-mjit/thread.c:766
  #36 thread_start_func_2 (th=<optimized out>, stack_start=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/thread.c:822
  #37 0x000055aa5d55841b in thread_start_func_1 (th_ptr=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/thread_pthread.c:994
  #38 0x00007f3467013609 in start_thread (arg=<optimized out>) at pthread_create.c:477
  #39 0x00007f3466d19293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
  
  Thread 3 (Thread 0x7f3462e48700 (LWP 24252)):
  #0  __lll_lock_wait (futex=futex@entry=0x55aa5ee4d970, private=0) at lowlevellock.c:52
  #1  0x00007f346701807b in __pthread_mutex_cond_lock (mutex=mutex@entry=0x55aa5ee4d970) at ../nptl/pthread_mutex_lock.c:80
  #2  0x00007f346701a260 in __pthread_cond_wait_common (abstime=0x0, clockid=0, mutex=0x55aa5ee4d970, cond=0x55aa5ef71e10) at pthread_cond_wait.c:627
  #3  __pthread_cond_wait (cond=cond@entry=0x55aa5ef71e10, mutex=mutex@entry=0x55aa5ee4d970) at pthread_cond_wait.c:638
  #4  0x000055aa5d552add in rb_native_cond_wait (cond=cond@entry=0x55aa5ef71e10, mutex=mutex@entry=0x55aa5ee4d970) at /tmp/ruby/v3/src/trunk-mjit/thread_pthread.c:503
  #5  0x000055aa5d5b90aa in vm_lock_enter (cr=0x55aa5ef71d50, vm=0x55aa5ee4d940, locked=<optimized out>, no_barrier=<optimized out>, lev=0x7f3462d4743c) at /tmp/ruby/v3/src/trunk-mjit/vm_sync.c:89
  #6  0x000055aa5d54c1aa in rb_vm_lock_enter (file=<synthetic pointer>, line=653, lev=0x7f3462d4743c) at /tmp/ruby/v3/src/trunk-mjit/vm_sync.h:71
  #7  lookup_str_id (str=139862791521120) at /tmp/ruby/v3/src/trunk-mjit/symbol.c:653
  #8  rb_check_id (namep=namep@entry=0x7f3462d47478) at /tmp/ruby/v3/src/trunk-mjit/symbol.c:1098
  #9  0x000055aa5d471824 in check_setter_id (message_len=50, message=<synthetic pointer>, valid_name_p=<optimized out>, valid_id_p=<optimized out>, pname=0x7f3462d47478, obj=139862791521160) at /tmp/ruby/v3/src/trunk-mjit/object.c:2226
  #10 rb_obj_ivar_set (obj=139862791521160, iv=<optimized out>, val=3) at /tmp/ruby/v3/src/trunk-mjit/object.c:2888
  #11 0x000055aa5d5919a3 in vm_call_cfunc_with_frame (ec=0x55aa5ef72460, reg_cfp=0x7f3462e47c20, calling=<optimized out>, cd=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:2821
  #12 0x000055aa5d5a76ea 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/vm_callinfo.h:337
  #13 vm_exec_core (ec=0x55aa5ef72460, initial=128) at ../../src/trunk-mjit/insns.def:789
  #14 0x000055aa5d59bd54 in rb_vm_exec (ec=0x55aa5ef72460, mjit_enable_p=1) at /tmp/ruby/v3/src/trunk-mjit/vm.c:2165
  #15 0x000055aa5d59f88f in invoke_block (captured=<optimized out>, opt_pc=<optimized out>, type=<optimized out>, cref=0x0, self=94190225005640, iseq=0x55aa5ee72328, ec=0x55aa5ef72460) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1265
  #16 invoke_iseq_block_from_c (me=0x0, is_lambda=<optimized out>, cref=0x0, passed_block_handler=0, kw_splat=0, argv=0x7f3462d477b8, argc=1, self=94190225005640, captured=<optimized out>, ec=0x55aa5ef72460) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1337
  #17 invoke_block_from_c_bh (argc=<optimized out>, kw_splat=<optimized out>, passed_block_handler=<optimized out>, cref=<optimized out>, is_lambda=<optimized out>, force_blockarg=<optimized out>, argv=<optimized out>, block_handler=<optimized out>, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1355
  #18 vm_yield (argc=1, kw_splat=0, argv=0x7f3462d477b8, ec=0x55aa5ef72460) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1400
  #19 rb_yield_0 (argv=0x7f3462d477b8, argc=1) at /tmp/ruby/v3/src/trunk-mjit/vm_eval.c:1143
  #20 rb_yield_1 (val=<optimized out>, val@entry=10465) at /tmp/ruby/v3/src/trunk-mjit/vm_eval.c:1149
  #21 0x000055aa5d4601bc in int_dotimes (num=100001) at /tmp/ruby/v3/src/trunk-mjit/include/ruby/internal/arithmetic/long.h:95
  #22 0x000055aa5d5919a3 in vm_call_cfunc_with_frame (ec=0x55aa5ef72460, reg_cfp=0x7f3462e47c90, calling=<optimized out>, cd=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:2821
  #23 0x000055aa5d59d12c in vm_call_method_each_type (ec=0x55aa5ef72460, cfp=0x7f3462e47c90, calling=0x7f3462d47a70, cd=0x55aa5ef71950) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:3324
  #24 0x000055aa5d59d8b7 in vm_call_method (ec=0x55aa5ef72460, cfp=0x7f3462e47c90, calling=<optimized out>, cd=0x55aa5ef71950) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:3428
  #25 0x000055aa5d5a77f1 in vm_sendish (method_explorer=<optimized out>, block_handler=<optimized out>, cd=<optimized out>, reg_cfp=<optimized out>, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_callinfo.h:337
  #26 vm_exec_core (ec=0x55aa5ef72460, initial=128) at ../../src/trunk-mjit/insns.def:770
  #27 0x000055aa5d59bd54 in rb_vm_exec (ec=0x55aa5ef72460, mjit_enable_p=1) at /tmp/ruby/v3/src/trunk-mjit/vm.c:2165
  #28 0x000055aa5d554edc in thread_do_start_proc (th=0x55aa5ef72270) at /tmp/ruby/v3/src/trunk-mjit/thread.c:725
  #29 0x000055aa5d557bbf in thread_do_start (th=0x55aa5ef72270) at /tmp/ruby/v3/src/trunk-mjit/thread.c:766
  #30 thread_start_func_2 (th=<optimized out>, stack_start=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/thread.c:822
  #31 0x000055aa5d55841b in thread_start_func_1 (th_ptr=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/thread_pthread.c:994
  #32 0x00007f3467013609 in start_thread (arg=<optimized out>) at pthread_create.c:477
  #33 0x00007f3466d19293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
  
  Thread 2 (Thread 0x7f3463649700 (LWP 24249)):
  #0  list_node_to_off_ (off=32, node=0x7f345800e890) at /tmp/ruby/v3/src/trunk-mjit/ccan/list/list.h:761
  #1  get_from_list (list=0x55aa5d6d1180 <unit_queue>) at /tmp/ruby/v3/src/trunk-mjit/mjit_worker.c:506
  #2  mjit_worker () at /tmp/ruby/v3/src/trunk-mjit/mjit_worker.c:1378
  #3  0x000055aa5d54d74e in mjit_worker (arg=0x55aa5d4305d0 <mjit_worker>) at /tmp/ruby/v3/src/trunk-mjit/thread_pthread.c:1941
  #4  0x00007f3467013609 in start_thread (arg=<optimized out>) at pthread_create.c:477
  #5  0x00007f3466d19293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
  
  Thread 1 (Thread 0x7f3466bf5b80 (LWP 24248)):
  #0  __lll_lock_wait (futex=futex@entry=0x55aa5ee4d970, private=0) at lowlevellock.c:52
  #1  0x00007f34670160a3 in __GI___pthread_mutex_lock (mutex=mutex@entry=0x55aa5ee4d970) at ../nptl/pthread_mutex_lock.c:80
  #2  0x000055aa5d5520cd in rb_native_mutex_lock (lock=lock@entry=0x55aa5ee4d970) at /tmp/ruby/v3/src/trunk-mjit/thread_pthread.c:397
  #3  0x000055aa5d5b8ff6 in vm_lock_enter (cr=0x55aa5ee4e6a0, vm=0x55aa5ee4d940, locked=<optimized out>, no_barrier=<optimized out>, lev=0x7ffd06489594) at /tmp/ruby/v3/src/trunk-mjit/vm_sync.c:56
  #4  0x000055aa5d4c2225 in rb_vm_lock_enter (file=0x55aa5d5fe9c0 "/tmp/ruby/v3/src/trunk-mjit/ractor.c", line=1573, lev=0x7ffd06489594) at /tmp/ruby/v3/src/trunk-mjit/vm_sync.h:71
  #5  rb_ractor_blocking_threads_dec (cr=0x55aa5ee4e6a0, file=file@entry=0x55aa5d608478 "/tmp/ruby/v3/src/trunk-mjit/thread.c", line=line@entry=1639) at /tmp/ruby/v3/src/trunk-mjit/ractor.c:1573
  #6  0x000055aa5d559fce in blocking_region_end (region=0x7ffd06489610, th=0x55aa5ee4de30) at /tmp/ruby/v3/src/trunk-mjit/thread.c:1639
  #7  rb_nogvl (func=func@entry=0x55aa5d4bcf90 <ractor_sleep_wo_gvl>, data1=data1@entry=0x55aa5ee4e6a0, ubf=<optimized out>, ubf@entry=0x55aa5d4bcec0 <ractor_sleep_interrupt>, data2=<optimized out>, data2@entry=0x55aa5ee4e6a0, flags=flags@entry=2) at /tmp/ruby/v3/src/trunk-mjit/thread.c:1669
  #8  0x000055aa5d4c0ceb in ractor_sleep (ec=0x55aa5ee4e490, cr=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/ractor.c:494
  #9  ractor_select (ec=<optimized out>, rs=<optimized out>, alen=1, yielded_value=<optimized out>, move=<optimized out>, ret_r=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/ractor.c:947
  #10 0x000055aa5d4c0db8 in ractor_take (r=<optimized out>, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/ractor.c:1047
  #11 builtin_inline_class_132 (ec=<optimized out>, self=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/ractor.rb:133
  #12 0x000055aa5d5a9242 in invoke_bf (reg_cfp=<optimized out>, bf=<optimized out>, bf=<optimized out>, argv=<optimized out>, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:5462
  #13 vm_invoke_builtin_delegate (start_index=<optimized out>, bf=<optimized out>, cfp=<optimized out>, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:5462
  #14 vm_invoke_builtin_delegate (start_index=<optimized out>, bf=<optimized out>, cfp=<optimized out>, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:5450
  #15 vm_exec_core (ec=0x55aa5ee4e490, initial=128) at ../../src/trunk-mjit/insns.def:1497
  #16 0x000055aa5d59bd54 in rb_vm_exec (ec=0x55aa5ee4e490, mjit_enable_p=1) at /tmp/ruby/v3/src/trunk-mjit/vm.c:2165
  #17 0x000055aa5d59fbbe in invoke_block (captured=<optimized out>, opt_pc=<optimized out>, type=<optimized out>, cref=0x0, self=94190225622080, iseq=0x55aa5ee72558, ec=0x55aa5ee4e490) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1265
  #18 invoke_iseq_block_from_c (me=0x0, is_lambda=<optimized out>, cref=0x0, passed_block_handler=0, kw_splat=0, argv=0x7ffd064899f0, argc=1, self=94190225622080, captured=<optimized out>, ec=0x55aa5ee4e490) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1337
  #19 invoke_block_from_c_bh (argc=<optimized out>, kw_splat=<optimized out>, passed_block_handler=<optimized out>, cref=<optimized out>, is_lambda=<optimized out>, force_blockarg=<optimized out>, argv=<optimized out>, block_handler=<optimized out>, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1355
  #20 vm_yield (argc=1, kw_splat=0, argv=0x7ffd064899f0, ec=0x55aa5ee4e490) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1400
  #21 rb_yield_0 (argv=0x7ffd064899f0, argc=1) at /tmp/ruby/v3/src/trunk-mjit/vm_eval.c:1143
  #22 rb_yield_1 (val=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_eval.c:1149
  #23 rb_yield (val=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_eval.c:1159
  #24 0x000055aa5d3238b6 in rb_ary_collect (ary=94190225006360) at /tmp/ruby/v3/src/trunk-mjit/include/ruby/internal/core/rarray.h:172
  #25 0x000055aa5d5919a3 in vm_call_cfunc_with_frame (ec=0x55aa5ee4e490, reg_cfp=0x7f3466bf3fa0, calling=<optimized out>, cd=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:2821
  #26 0x000055aa5d59d12c in vm_call_method_each_type (ec=0x55aa5ee4e490, cfp=0x7f3466bf3fa0, calling=0x7ffd06489cd0, cd=0x55aa5ef77dd0) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:3324
  #27 0x000055aa5d59d8b7 in vm_call_method (ec=0x55aa5ee4e490, cfp=0x7f3466bf3fa0, calling=<optimized out>, cd=0x55aa5ef77dd0) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:3428
  #28 0x000055aa5d5a77f1 in vm_sendish (method_explorer=<optimized out>, block_handler=<optimized out>, cd=<optimized out>, reg_cfp=<optimized out>, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_callinfo.h:337
  #29 vm_exec_core (ec=0x55aa5ee4e490, initial=128) at ../../src/trunk-mjit/insns.def:770
  #30 0x000055aa5d59bd54 in rb_vm_exec (ec=0x55aa5ee4e490, mjit_enable_p=1) at /tmp/ruby/v3/src/trunk-mjit/vm.c:2165
  #31 0x000055aa5d3b9d5d in rb_ec_exec_node (ec=ec@entry=0x55aa5ee4e490, n=n@entry=0x55aa5ee72620) at /tmp/ruby/v3/src/trunk-mjit/eval.c:317
  #32 0x000055aa5d3be96f in ruby_run_node (n=0x55aa5ee72620) at /tmp/ruby/v3/src/trunk-mjit/eval.c:375
  #33 0x000055aa5d319153 in main (argc=<optimized out>, argv=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/main.c:50
  >>> Dump Ruby level backtrace
  Exception ignored in: <gdb._GdbOutputFile object at 0x7f2676675610>[Inferior 1 (process 24248) detached]
  
  Traceback (most recent call last):
    File "/usr/share/gdb/python/gdb/__init__.py", line 43, in flush
      def flush(self):
  KeyboardInterrupt: 
  [nil, 24248]
  "gdb -p 18951 -x /home/ko1/ruby/build-ruby/gdbscript -batch -quiet"
  Fstderr output is not empty
     th: 0x55aa5ee4de30, native_id: 0x7f3466bf5b80
     -- Control frame information -----------------------------------------------
     c:0005 p:0003 s:0019 e:000018 METHOD <internal:ractor>:132
       me:
  
  test_string.rb           [New LWP 18953]
  [New LWP 24566]
  [Thread debugging using libthread_db enabled]
  Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
  44	../sysdeps/unix/sysv/linux/ppoll.c: No such file or directory.
  0x00007f75d3dcbbf6 in __ppoll (fds=0x7ffd75bc1dc8, nfds=1, timeout=<optimized out>, sigmask=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:44
  >>> Threads  Id   Target Id                                         Frame 
  * 1    Thread 0x7f75d3a6f740 (LWP 18951) "ruby"          0x00007f75d3dcbbf6 in __ppoll (fds=0x7ffd75bc1dc8, nfds=1, timeout=<optimized out>, sigmask=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:44
    2    Thread 0x7f75d0515700 (LWP 18953) "runner.rb:476" __GI___libc_read (nbytes=8192, buf=0x7f75cc005540, fd=5) at ../sysdeps/unix/sysv/linux/read.c:26
    3    Thread 0x7f75d0314700 (LWP 24566) "runner.rb:346" __GI___libc_read (nbytes=8192, buf=0x7f75c4004ba0, fd=33) at ../sysdeps/unix/sysv/linux/read.c:26
  >>> Machine level backtrace
  
  Thread 3 (Thread 0x7f75d0314700 (LWP 24566)):
  #0  __GI___libc_read (nbytes=8192, buf=0x7f75c4004ba0, fd=33) at ../sysdeps/unix/sysv/linux/read.c:26
  #1  __GI___libc_read (fd=33, buf=0x7f75c4004ba0, nbytes=8192) at ../sysdeps/unix/sysv/linux/read.c:24
  #2  0x00007f75d3f7a799 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #3  0x00007f75d40873ff in rb_thread_io_blocking_region () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #4  0x00007f75d3f813ea in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #5  0x00007f75d3f54797 in rb_ensure () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #6  0x00007f75d3f7c1ac in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #7  0x00007f75d3f80b66 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #8  0x00007f75d3f83bbb in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #9  0x00007f75d40b6026 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #10 0x00007f75d40a9406 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #11 0x00007f75d40ba0ba in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #12 0x00007f75d40c0870 in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #13 0x00007f75d40c4336 in rb_vm_invoke_proc () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #14 0x00007f75d4085ac3 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #15 0x00007f75d4087c10 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #16 0x00007f75d408804d in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #17 0x00007f75d3c9c609 in start_thread (arg=<optimized out>) at pthread_create.c:477
  #18 0x00007f75d3dd8293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
  
  Thread 2 (Thread 0x7f75d0515700 (LWP 18953)):
  #0  __GI___libc_read (nbytes=8192, buf=0x7f75cc005540, fd=5) at ../sysdeps/unix/sysv/linux/read.c:26
  #1  __GI___libc_read (fd=5, buf=0x7f75cc005540, nbytes=8192) at ../sysdeps/unix/sysv/linux/read.c:24
  #2  0x00007f75d3f7a799 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #3  0x00007f75d40873ff in rb_thread_io_blocking_region () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #4  0x00007f75d3f813ea in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #5  0x00007f75d3f54797 in rb_ensure () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #6  0x00007f75d3f7c1ac in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #7  0x00007f75d3f80b66 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #8  0x00007f75d3f83bbb in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #9  0x00007f75d40b6026 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #10 0x00007f75d40a9406 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #11 0x00007f75d40ba0ba in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #12 0x00007f75d40c0870 in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #13 0x00007f75d40c4336 in rb_vm_invoke_proc () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #14 0x00007f75d4085ac3 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #15 0x00007f75d4087c10 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #16 0x00007f75d408804d in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #17 0x00007f75d3c9c609 in start_thread (arg=<optimized out>) at pthread_create.c:477
  #18 0x00007f75d3dd8293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
  
  Thread 1 (Thread 0x7f75d3a6f740 (LWP 18951)):
  #0  0x00007f75d3dcbbf6 in __ppoll (fds=0x7ffd75bc1dc8, nfds=1, timeout=<optimized out>, sigmask=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:44
  #1  0x00007f75d4083d18 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #2  0x00007f75d40844c8 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #3  0x00007f75d40867b7 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #4  0x00007f75d3f54797 in rb_ensure () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #5  0x00007f75d408005d in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #6  0x00007f75d4080828 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #7  0x00007f75d40b6026 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #8  0x00007f75d40a9406 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #9  0x00007f75d40ba0ba in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #10 0x00007f75d40c0870 in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #11 0x00007f75d3f9a663 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #12 0x00007f75d3f9a80b in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #13 0x00007f75d40b6026 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #14 0x00007f75d40a9406 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #15 0x00007f75d40ba0ba in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #16 0x00007f75d40c0870 in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #17 0x00007f75d40cd9f1 in rb_yield () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #18 0x00007f75d3ed545c in rb_ary_each () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #19 0x00007f75d40b6026 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #20 0x00007f75d40c2dab in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #21 0x00007f75d40a9406 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #22 0x00007f75d40ba131 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #23 0x00007f75d40c0870 in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #24 0x00007f75d40cd9f1 in rb_yield () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #25 0x00007f75d3f54797 in rb_ensure () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #26 0x00007f75d3f34ebf in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #27 0x00007f75d40b6026 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #28 0x00007f75d40c2dab in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #29 0x00007f75d40a9406 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #30 0x00007f75d40ba131 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #31 0x00007f75d40c0870 in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #32 0x00007f75d3f51491 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #33 0x00007f75d3f562ee in ruby_run_node () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #34 0x0000558d048cd17f in ?? ()
  #35 0x00007f75d3cdd0b3 in __libc_start_main (main=0x558d048cd120, argc=4, argv=0x7ffd75bc3288, init=<optimized out>, fini=<optimized out>, rtld_fini=<optimized out>, stack_end=0x7ffd75bc3278) at ../csu/libc-start.c:308
  #36 0x0000558d048cd1ce in _start ()
  >>> Dump Ruby level backtrace
  /home/ko1/ruby/build-ruby/gdbscript:12: Error in sourced command file:
  No symbol "rb_vmdebug_stack_dump_all_threads" in current context.
  [Inferior 1 (process 18951) detached]
  .
  test_struct.rb           [nil, 18951]
  "gdb -p 18945 -x /home/ko1/ruby/build-ruby/gdbscript -batch -quiet"
  make: *** [uncommon.mk:761: yes-btest] Killed
  ptrace: No such process.
  >>> ThreadsNo threads.
  >>> Machine level backtrace
  >>> Dump Ruby level backtrace
  /home/ko1/ruby/build-ruby/gdbscript:12: Error in sourced command file:
  No symbol table is loaded.  Use the "file" command.
  [nil, 18945]
  #<Errno::ESRCH: No such process>
  "gdb -p 18944 -x /home/ko1/ruby/build-ruby/gdbscript -batch -quiet"
  Could not attach to process.  If your uid matches the uid of the target
  process, check the setting of /proc/sys/kernel/yama/ptrace_scope, or try
  again as the root user.  For more details, see /etc/sysctl.d/10-ptrace.conf
  warning: process 18944 is a zombie - the process has already terminated
  ptrace: Operation not permitted.
  >>> ThreadsNo threads.
  >>> Machine level backtrace
  >>> Dump Ruby level backtrace
  /home/ko1/ruby/build-ruby/gdbscript:12: Error in sourced command file:
  No symbol table is loaded.  Use the "file" command.
  [nil, 18944]
  "gdb -p 24254 -x /home/ko1/ruby/build-ruby/gdbscript -batch -quiet"
  ptrace: No such process.
  >>> ThreadsNo threads.
  >>> Machine level backtrace
  >>> Dump Ruby level backtrace
  /home/ko1/ruby/build-ruby/gdbscript:12: Error in sourced command file:
  No symbol table is loaded.  Use the "file" command.
  [nil, 24254]
  #<Errno::ESRCH: No such process>
    0.012000   0.000000 171262.664000 (171362.674806)
  /home/ko1/ruby/build-ruby/build-ruby.rb:174:in `each_line': execution expired (Timeout::Error)
  	from /home/ko1/ruby/build-ruby/build-ruby.rb:174: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:173:in `block in cmd'
  	from /home/ko1/ruby/build-ruby/build-ruby.rb:170:in `popen'
  	from /home/ko1/ruby/build-ruby/build-ruby.rb:170:in `cmd'
  	from /home/ko1/ruby/build-ruby/build-ruby.rb:308:in `block in test_btest'
  	from /home/ko1/ruby/build-ruby/build-ruby.rb:243:in `block in builddir'
  	from /home/ko1/ruby/build-ruby/build-ruby.rb:242:in `chdir'
  	from /home/ko1/ruby/build-ruby/build-ruby.rb:242:in `builddir'
  	from /home/ko1/ruby/build-ruby/build-ruby.rb:307:in `test_btest'
  	from /home/ko1/ruby/build-ruby/build-ruby.rb:382: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:380:in `block (3 levels) in run'
  	from /home/ko1/ruby/build-ruby/build-ruby.rb:379:in `each'
  	from /home/ko1/ruby/build-ruby/build-ruby.rb:379: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:378: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:377:in `run'
  	from /home/ko1/ruby/build-ruby/build-ruby.rb:551:in `<main>'
  total: 171418.66 sec