Result of trunk-mjit@phosphorus-docker at 2021-01-10 22:14:55 +0900

• NG (34d02631e7) [LOG]

• Linux phosphorus-docker 4.9.0-14-amd64 #1 SMP Debian 4.9.246-2 (2020-12-17) x86_64 x86_64 x86_64 GNU/Linux
  

• rev: 34d02631e7
  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.20210110-101304
  #<BuildRuby:0x00005603744c00e0
   @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.20210110-101304",
   @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 --jit-debug=-ggdb3\"",
   @timeout=10800>
                             user     system      total        real
  checkout               0.000000   0.000000   0.000000 (  0.000024)
  autoconf               0.000000   0.000000   0.000000 (  0.000055)
  configure              0.000000   0.000000   0.000000 (  0.000048)
  build_up               0.008000   0.004000  11.076000 (  8.165667)
  build_miniruby         0.000000   0.004000   0.132000 (  0.142417)
  build_ruby             0.000000   0.000000   0.168000 (  0.194614)
  build_all              0.012000   0.004000  48.676000 ( 18.852624)
  build_install          0.024000   0.004000  54.644000 ( 21.913244)
  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  2698    11    11 ?           -1 Sl    1001   0:00 ruby ./br.rb build_report trunk-mjit --incremental --process-num=6
   2698  2700    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.20210110-101304 --root_dir=/tmp/ruby --timeout=10800 --configure_opts=cppflags="-DMJIT_FORCE_ENABLE" --add-env=RUBY_TEST_SUBPROCESS_TIMEOUT_SCALE=30 --test_opts='RUN_OPTS="--disable-gems --jit-debug=-ggdb3"' --incremental --process-num=6
   2700  2701    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.20210110-101304 --root_dir=/tmp/ruby --timeout=10800 --configure_opts=cppflags=-DMJIT_FORCE_ENABLE --add-env=RUBY_TEST_SUBPROCESS_TIMEOUT_SCALE=30 --test_opts=RUN_OPTS="--disable-gems --jit-debug=-ggdb3" --incremental --process-num=6
   2701  3751    11    11 ?           -1 S     1001   0:00 sh -c make yes-btest RUN_OPTS="--disable-gems --jit-debug=-ggdb3"
   3751  3752    11    11 ?           -1 S     1001   0:00 make yes-btest RUN_OPTS=--disable-gems --jit-debug=-ggdb3
   3752  3758    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 --jit-debug=-ggdb3
   3758  9129    11    11 ?           -1 Sl    1001 165:09 /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 --jit-debug=-ggdb3 -W0 bootstraptest.tmp.rb
   2701  9135    11    11 ?           -1 R     1001   0:00 ps jx
  {10=>[11], 11=>[2698], 2698=>[2700], 2700=>[2701], 2701=>[3751, 9135], 3751=>[3752], 3752=>[3758], 3758=>[9129]}
  "timeout 60 gdb -p 9129 -x /home/ko1/ruby/build-ruby/gdbscript -batch -quiet"
  [New LWP 9130]
  [New LWP 9133]
  [New LWP 9134]
  [Thread debugging using libthread_db enabled]
  Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
  183	../sysdeps/nptl/futex-internal.h: No such file or directory.
  futex_wait_cancelable (private=<optimized out>, expected=0, futex_word=0x55f548ed790c) at ../sysdeps/nptl/futex-internal.h:183
  >>> Threads  Id   Target Id                                          Frame 
  * 1    Thread 0x7f3d0f94eb80 (LWP 9129) "miniruby"        futex_wait_cancelable (private=<optimized out>, expected=0, futex_word=0x55f548ed790c) at ../sysdeps/nptl/futex-internal.h:183
    2    Thread 0x7f3d0c3a2700 (LWP 9130) "ruby-mjitworker" 0x000055f547617a6a in get_from_list (list=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/ccan/list/list.h:761
    3    Thread 0x7f3d0bba1700 (LWP 9133) "miniruby"        __lll_lock_wait (futex=futex@entry=0x55f5478cab60 <mjit_engine_mutex>, private=0) at lowlevellock.c:52
    4    Thread 0x7f3d0b9a0700 (LWP 9134) "miniruby"        __lll_lock_wait (futex=futex@entry=0x55f548ed6970, private=0) at lowlevellock.c:52
  >>> Machine level backtrace
  
  Thread 4 (Thread 0x7f3d0b9a0700 (LWP 9134)):
  #0  __lll_lock_wait (futex=futex@entry=0x55f548ed6970, private=0) at lowlevellock.c:52
  #1  0x00007f3d0fd6f0a3 in __GI___pthread_mutex_lock (mutex=mutex@entry=0x55f548ed6970) at ../nptl/pthread_mutex_lock.c:80
  #2  0x000055f54773acad in rb_native_mutex_lock (lock=lock@entry=0x55f548ed6970) at /tmp/ruby/v3/src/trunk-mjit/thread_pthread.c:397
  #3  0x000055f5477a2e46 in vm_lock_enter (cr=0x55f548ffbf40, vm=0x55f548ed6940, locked=<optimized out>, no_barrier=<optimized out>, lev=0x7f3d0b89f484) at /tmp/ruby/v3/src/trunk-mjit/vm_sync.c:56
  #4  0x000055f54776790f in rb_vm_lock_enter (file=<synthetic pointer>, line=1341, lev=0x7f3d0b89f484) at /tmp/ruby/v3/src/trunk-mjit/vm_sync.h:76
  #5  generic_ivar_set (val=3, id=31091, obj=139899902919600) at /tmp/ruby/v3/src/trunk-mjit/variable.c:1341
  #6  ivar_set (obj=obj@entry=139899902919600, id=31091, val=val@entry=3) at /tmp/ruby/v3/src/trunk-mjit/variable.c:1487
  #7  0x000055f54776d230 in rb_ivar_set (obj=obj@entry=139899902919600, id=id@entry=31091, val=val@entry=3) at /tmp/ruby/v3/src/trunk-mjit/variable.c:1496
  #8  0x000055f54765821b in rb_obj_ivar_set (obj=139899902919600, iv=<optimized out>, val=3) at /tmp/ruby/v3/src/trunk-mjit/object.c:2909
  #9  0x000055f54777b15b in vm_call_cfunc_with_frame (ec=0x55f549009ac0, reg_cfp=0x7f3d0b99fc20, calling=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:2896
  #10 0x000055f547791410 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:336
  #11 vm_exec_core (ec=0x55f549009ac0, initial=128) at ../../src/trunk-mjit/insns.def:789
  #12 0x000055f547785a4e in rb_vm_exec (ec=<optimized out>, mjit_enable_p=true) at /tmp/ruby/v3/src/trunk-mjit/vm.c:2162
  #13 0x000055f547788f8f in invoke_block (captured=<optimized out>, opt_pc=<optimized out>, type=<optimized out>, cref=0x0, self=94511980232320, iseq=0x55f548ee8840, ec=0x55f549009ac0) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1262
  #14 invoke_iseq_block_from_c (me=0x0, is_lambda=<optimized out>, cref=0x0, passed_block_handler=0, kw_splat=0, argv=0x7f3d0b89f858, argc=1, self=94511980232320, captured=<optimized out>, ec=0x55f549009ac0) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1334
  #15 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:1352
  #16 vm_yield (argc=1, kw_splat=0, argv=0x7f3d0b89f858, ec=0x55f549009ac0) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1397
  #17 rb_yield_0 (argv=0x7f3d0b89f858, argc=1) at /tmp/ruby/v3/src/trunk-mjit/vm_eval.c:1171
  #18 rb_yield_1 (val=<optimized out>, val@entry=77083) at /tmp/ruby/v3/src/trunk-mjit/vm_eval.c:1177
  #19 0x000055f547646fcc in int_dotimes (num=100001) at /tmp/ruby/v3/src/trunk-mjit/include/ruby/internal/arithmetic/long.h:95
  #20 0x000055f54777b15b in vm_call_cfunc_with_frame (ec=0x55f549009ac0, reg_cfp=0x7f3d0b99fc90, calling=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:2896
  #21 0x000055f54777e530 in vm_sendish (ec=0x55f549009ac0, reg_cfp=0x7f3d0b99fc90, cd=0x55f548ffa870, block_handler=<optimized out>, method_explorer=mexp_search_method) at /tmp/ruby/v3/src/trunk-mjit/vm_callinfo.h:336
  #22 0x000055f547791488 in vm_exec_core (ec=0x55f549009ac0, initial=128) at ../../src/trunk-mjit/insns.def:770
  #23 0x000055f547785a4e in rb_vm_exec (ec=<optimized out>, mjit_enable_p=true) at /tmp/ruby/v3/src/trunk-mjit/vm.c:2162
  #24 0x000055f54773daa7 in thread_do_start_proc (th=0x55f548ffc640) at /tmp/ruby/v3/src/trunk-mjit/thread.c:725
  #25 0x000055f54773fe6f in thread_do_start (th=0x55f548ffc640) at /tmp/ruby/v3/src/trunk-mjit/thread.c:766
  #26 thread_start_func_2 (th=<optimized out>, stack_start=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/thread.c:822
  #27 0x000055f5477406cb in thread_start_func_1 (th_ptr=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/thread_pthread.c:994
  #28 0x00007f3d0fd6c609 in start_thread (arg=<optimized out>) at pthread_create.c:477
  #29 0x00007f3d0fa72293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
  
  Thread 3 (Thread 0x7f3d0bba1700 (LWP 9133)):
  #0  __lll_lock_wait (futex=futex@entry=0x55f5478cab60 <mjit_engine_mutex>, private=0) at lowlevellock.c:52
  #1  0x00007f3d0fd6f0a3 in __GI___pthread_mutex_lock (mutex=mutex@entry=0x55f5478cab60 <mjit_engine_mutex>) at ../nptl/pthread_mutex_lock.c:80
  #2  0x000055f54773acad in rb_native_mutex_lock (lock=lock@entry=0x55f5478cab60 <mjit_engine_mutex>) at /tmp/ruby/v3/src/trunk-mjit/thread_pthread.c:397
  #3  0x000055f547618337 in CRITICAL_SECTION_START (msg=0x55f5477c390d "mjit_gc_start_hook", level=4) at /tmp/ruby/v3/src/trunk-mjit/mjit_worker.c:442
  #4  mjit_gc_start_hook () at /tmp/ruby/v3/src/trunk-mjit/mjit.c:58
  #5  0x000055f5475c93a6 in gc_enter (lock_lev=0x7f3d0baa0494, event=gc_enter_event_sweep_continue, objspace=0x55f548ed6fc0) at /tmp/ruby/v3/src/trunk-mjit/gc.c:8494
  #6  gc_sweep_continue (heap=<optimized out>, objspace=0x55f548ed6fc0) at /tmp/ruby/v3/src/trunk-mjit/gc.c:5215
  #7  heap_prepare (heap=<optimized out>, objspace=0x55f548ed6fc0) at /tmp/ruby/v3/src/trunk-mjit/gc.c:2038
  #8  heap_next_freepage (heap=<optimized out>, objspace=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/gc.c:2194
  #9  ractor_cache_slots (cr=<optimized out>, cr=<optimized out>, objspace=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/gc.c:2213
  #10 newobj_slowpath (wb_protected=1, cr=0x55f548ff8d10, objspace=0x55f548ed6fc0, flags=5, klass=94511979604000) at /tmp/ruby/v3/src/trunk-mjit/gc.c:2249
  #11 newobj_slowpath_wb_protected (klass=94511979604000, flags=5, objspace=0x55f548ed6fc0, cr=0x55f548ff8d10) at /tmp/ruby/v3/src/trunk-mjit/gc.c:2268
  #12 0x000055f5475c9884 in newobj_of0 (cr=<optimized out>, wb_protected=1, flags=5, klass=94511979604000) at /tmp/ruby/v3/src/trunk-mjit/gc.c:2306
  #13 newobj_of_cr (wb_protected=1, v3=0, v2=0, v1=0, flags=5, klass=94511979604000, cr=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/gc.c:2334
  #14 rb_ec_wb_protected_newobj_of (ec=<optimized out>, klass=klass@entry=94511979604000, flags=flags@entry=5) at /tmp/ruby/v3/src/trunk-mjit/gc.c:2356
  #15 0x000055f547714b33 in ec_str_alloc (klass=94511979604000, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/string.c:1594
  #16 ec_str_duplicate (str=94511978940360, klass=94511979604000, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/string.c:1594
  #17 rb_ec_str_resurrect (ec=<optimized out>, str=94511978940360) at /tmp/ruby/v3/src/trunk-mjit/string.c:1622
  #18 0x000055f547791d72 in vm_exec_core (ec=0x55f548efc060, initial=128) at ../../src/trunk-mjit/insns.def:366
  #19 0x000055f547785a4e in rb_vm_exec (ec=<optimized out>, mjit_enable_p=true) at /tmp/ruby/v3/src/trunk-mjit/vm.c:2162
  #20 0x000055f547788f8f in invoke_block (captured=<optimized out>, opt_pc=<optimized out>, type=<optimized out>, cref=0x0, self=94511978938680, iseq=0x55f548ee8840, ec=0x55f548efc060) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1262
  #21 invoke_iseq_block_from_c (me=0x0, is_lambda=<optimized out>, cref=0x0, passed_block_handler=0, kw_splat=0, argv=0x7f3d0baa0778, argc=1, self=94511978938680, captured=<optimized out>, ec=0x55f548efc060) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1334
  #22 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:1352
  #23 vm_yield (argc=1, kw_splat=0, argv=0x7f3d0baa0778, ec=0x55f548efc060) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1397
  #24 rb_yield_0 (argv=0x7f3d0baa0778, argc=1) at /tmp/ruby/v3/src/trunk-mjit/vm_eval.c:1171
  #25 rb_yield_1 (val=<optimized out>, val@entry=77737) at /tmp/ruby/v3/src/trunk-mjit/vm_eval.c:1177
  #26 0x000055f547646fcc in int_dotimes (num=100001) at /tmp/ruby/v3/src/trunk-mjit/include/ruby/internal/arithmetic/long.h:95
  #27 0x000055f54777b15b in vm_call_cfunc_with_frame (ec=0x55f548efc060, reg_cfp=0x7f3d0bba0c90, calling=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:2896
  #28 0x000055f547786d59 in vm_call_method_each_type (ec=0x55f548efc060, cfp=0x7f3d0bba0c90, calling=0x7f3d0baa09a0) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:3386
  #29 0x000055f547787434 in vm_call_method (ec=0x55f548efc060, cfp=0x7f3d0bba0c90, calling=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:3504
  #30 0x000055f54777e530 in vm_sendish (ec=0x55f548efc060, reg_cfp=0x7f3d0bba0c90, cd=0x55f548ffa870, block_handler=<optimized out>, method_explorer=mexp_search_method) at /tmp/ruby/v3/src/trunk-mjit/vm_callinfo.h:336
  #31 0x000055f547791488 in vm_exec_core (ec=0x55f548efc060, initial=128) at ../../src/trunk-mjit/insns.def:770
  #32 0x000055f547785a4e in rb_vm_exec (ec=<optimized out>, mjit_enable_p=true) at /tmp/ruby/v3/src/trunk-mjit/vm.c:2162
  #33 0x000055f54773daa7 in thread_do_start_proc (th=0x55f548ffaac0) at /tmp/ruby/v3/src/trunk-mjit/thread.c:725
  #34 0x000055f54773fe6f in thread_do_start (th=0x55f548ffaac0) at /tmp/ruby/v3/src/trunk-mjit/thread.c:766
  #35 thread_start_func_2 (th=<optimized out>, stack_start=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/thread.c:822
  #36 0x000055f5477406cb in thread_start_func_1 (th_ptr=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/thread_pthread.c:994
  #37 0x00007f3d0fd6c609 in start_thread (arg=<optimized out>) at pthread_create.c:477
  #38 0x00007f3d0fa72293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
  
  Thread 2 (Thread 0x7f3d0c3a2700 (LWP 9130)):
  #0  0x000055f547617a6a in get_from_list (list=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/ccan/list/list.h:761
  #1  mjit_worker () at /tmp/ruby/v3/src/trunk-mjit/mjit_worker.c:1408
  #2  0x000055f5477360de in mjit_worker (arg=0x55f5476175f0 <mjit_worker>) at /tmp/ruby/v3/src/trunk-mjit/thread_pthread.c:1941
  #3  0x00007f3d0fd6c609 in start_thread (arg=<optimized out>) at pthread_create.c:477
  #4  0x00007f3d0fa72293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
  
  Thread 1 (Thread 0x7f3d0f94eb80 (LWP 9129)):
  #0  futex_wait_cancelable (private=<optimized out>, expected=0, futex_word=0x55f548ed790c) at ../sysdeps/nptl/futex-internal.h:183
  #1  __pthread_cond_wait_common (abstime=0x0, clockid=0, mutex=0x55f548ed78b8, cond=0x55f548ed78e0) at pthread_cond_wait.c:508
  #2  __pthread_cond_wait (cond=cond@entry=0x55f548ed78e0, mutex=mutex@entry=0x55f548ed78b8) at pthread_cond_wait.c:638
  #3  0x000055f54773b6bd in rb_native_cond_wait (cond=cond@entry=0x55f548ed78e0, mutex=mutex@entry=0x55f548ed78b8) at /tmp/ruby/v3/src/trunk-mjit/thread_pthread.c:503
  #4  0x000055f5476a431c in ractor_cond_wait (r=0x55f548ed7890) at /tmp/ruby/v3/src/trunk-mjit/ractor.c:502
  #5  ractor_sleep_wo_gvl (ptr=0x55f548ed7890) at /tmp/ruby/v3/src/trunk-mjit/ractor.c:502
  #6  0x000055f547742a8a in rb_nogvl (func=func@entry=0x55f5476a42d0 <ractor_sleep_wo_gvl>, data1=data1@entry=0x55f548ed7890, ubf=<optimized out>, ubf@entry=0x55f5476a41e0 <ractor_sleep_interrupt>, data2=<optimized out>, data2@entry=0x55f548ed7890, flags=flags@entry=3) at /tmp/ruby/v3/src/trunk-mjit/thread.c:1669
  #7  0x000055f5476a8c2e in ractor_sleep (ec=0x55f548ed7680, cr=0x55f548ed7890) at /tmp/ruby/v3/src/trunk-mjit/ractor.c:566
  #8  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:1194
  #9  0x000055f5476a8d41 in ractor_take (r=<optimized out>, ec=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/ractor.c:1294
  #10 builtin_inline_class_694 (ec=<optimized out>, self=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/ractor.rb:695
  #11 0x000055f5477933ba 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:5574
  #12 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:5574
  #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:5562
  #14 vm_exec_core (ec=0x55f548ed7680, initial=128) at ../../src/trunk-mjit/insns.def:1493
  #15 0x000055f547785a4e in rb_vm_exec (ec=<optimized out>, mjit_enable_p=true) at /tmp/ruby/v3/src/trunk-mjit/vm.c:2162
  #16 0x000055f54778962e in invoke_block (captured=<optimized out>, opt_pc=<optimized out>, type=<optimized out>, cref=0x0, self=94511979627720, iseq=0x55f548ee8a70, ec=0x55f548ed7680) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1262
  #17 invoke_iseq_block_from_c (me=0x0, is_lambda=<optimized out>, cref=0x0, passed_block_handler=0, kw_splat=0, argv=0x7ffd6049a0d0, argc=1, self=94511979627720, captured=<optimized out>, ec=0x55f548ed7680) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1334
  #18 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:1352
  #19 vm_yield (argc=1, kw_splat=0, argv=0x7ffd6049a0d0, ec=0x55f548ed7680) at /tmp/ruby/v3/src/trunk-mjit/vm.c:1397
  #20 rb_yield_0 (argv=0x7ffd6049a0d0, argc=1) at /tmp/ruby/v3/src/trunk-mjit/vm_eval.c:1171
  #21 rb_yield_1 (val=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_eval.c:1177
  #22 rb_yield (val=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_eval.c:1187
  #23 0x000055f547508916 in rb_ary_collect (ary=94511978939440) at /tmp/ruby/v3/src/trunk-mjit/include/ruby/internal/core/rarray.h:172
  #24 0x000055f54777b15b in vm_call_cfunc_with_frame (ec=0x55f548ed7680, reg_cfp=0x7f3d0f94cfa0, calling=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:2896
  #25 0x000055f547786d59 in vm_call_method_each_type (ec=0x55f548ed7680, cfp=0x7f3d0f94cfa0, calling=0x7ffd6049a320) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:3386
  #26 0x000055f547787434 in vm_call_method (ec=0x55f548ed7680, cfp=0x7f3d0f94cfa0, calling=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/vm_insnhelper.c:3504
  #27 0x000055f54777e530 in vm_sendish (ec=0x55f548ed7680, reg_cfp=0x7f3d0f94cfa0, cd=0x55f54900a320, block_handler=<optimized out>, method_explorer=mexp_search_method) at /tmp/ruby/v3/src/trunk-mjit/vm_callinfo.h:336
  #28 0x000055f547791488 in vm_exec_core (ec=0x55f548ed7680, initial=128) at ../../src/trunk-mjit/insns.def:770
  #29 0x000055f547785a4e in rb_vm_exec (ec=<optimized out>, mjit_enable_p=true) at /tmp/ruby/v3/src/trunk-mjit/vm.c:2162
  #30 0x000055f5475a11cd in rb_ec_exec_node (ec=ec@entry=0x55f548ed7680, n=n@entry=0x55f548ee8b38) at /tmp/ruby/v3/src/trunk-mjit/eval.c:317
  #31 0x000055f5475a5e3f in ruby_run_node (n=0x55f548ee8b38) at /tmp/ruby/v3/src/trunk-mjit/eval.c:375
  #32 0x000055f5474fe103 in main (argc=<optimized out>, argv=<optimized out>) at /tmp/ruby/v3/src/trunk-mjit/main.c:47
  >>> Dump Ruby level backtrace
  Exception ignored in: [Inferior 1 (process 9129) detached]
  <gdb._GdbOutputFile object at 0x7fc91007c610>
  Traceback (most recent call last):
    File "/usr/share/gdb/python/gdb/__init__.py", line 43, in flush
      def flush(self):
  KeyboardInterrupt: 
  [nil, 9129]
  "timeout 60 gdb -p 3758 -x /home/ko1/ruby/build-ruby/gdbscript -batch -quiet"
  Fstderr output is not empty
     th: 0x55f548ed6e20, native_id: 0x7f3d0f94eb80
     -- Control frame information -----------------------------------------------
     c:0005 p:0003 s:0019 e:000018 METHOD <internal:ractor>:694
       me:
  
  test_string.rb           [New LWP 3760]
  [New LWP 9145]
  [Thread debugging using libthread_db enabled]
  Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
  0x00007f0133029bf6 in __ppoll (fds=0x7ffc4eb8db18, nfds=1, timeout=<optimized out>, sigmask=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:44
  44	../sysdeps/unix/sysv/linux/ppoll.c: No such file or directory.
  >>> Threads  Id   Target Id                                        Frame 
  * 1    Thread 0x7f0132ccd740 (LWP 3758) "ruby"          0x00007f0133029bf6 in __ppoll (fds=0x7ffc4eb8db18, nfds=1, timeout=<optimized out>, sigmask=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:44
    2    Thread 0x7f012f773700 (LWP 3760) "runner.rb:476" __GI___libc_read (nbytes=8192, buf=0x7f0128005bc0, fd=5) at ../sysdeps/unix/sysv/linux/read.c:26
    3    Thread 0x7f012f572700 (LWP 9145) "runner.rb:346" __GI___libc_read (nbytes=8192, buf=0x7f0120004ba0, fd=43) at ../sysdeps/unix/sysv/linux/read.c:26
  >>> Machine level backtrace
  
  Thread 3 (Thread 0x7f012f572700 (LWP 9145)):
  #0  __GI___libc_read (nbytes=8192, buf=0x7f0120004ba0, fd=43) at ../sysdeps/unix/sysv/linux/read.c:26
  #1  __GI___libc_read (fd=43, buf=0x7f0120004ba0, nbytes=8192) at ../sysdeps/unix/sysv/linux/read.c:24
  #2  0x00007f01331d8799 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #3  0x00007f01332e53ff in rb_thread_io_blocking_region () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #4  0x00007f01331df3ea in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #5  0x00007f01331b2797 in rb_ensure () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #6  0x00007f01331da1ac in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #7  0x00007f01331deb66 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #8  0x00007f01331e1bbb in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #9  0x00007f0133314026 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #10 0x00007f0133307406 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #11 0x00007f01333180ba in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #12 0x00007f013331e870 in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #13 0x00007f0133322336 in rb_vm_invoke_proc () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #14 0x00007f01332e3ac3 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #15 0x00007f01332e5c10 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #16 0x00007f01332e604d in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #17 0x00007f0132efa609 in start_thread (arg=<optimized out>) at pthread_create.c:477
  #18 0x00007f0133036293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
  
  Thread 2 (Thread 0x7f012f773700 (LWP 3760)):
  #0  __GI___libc_read (nbytes=8192, buf=0x7f0128005bc0, fd=5) at ../sysdeps/unix/sysv/linux/read.c:26
  #1  __GI___libc_read (fd=5, buf=0x7f0128005bc0, nbytes=8192) at ../sysdeps/unix/sysv/linux/read.c:24
  #2  0x00007f01331d8799 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #3  0x00007f01332e53ff in rb_thread_io_blocking_region () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #4  0x00007f01331df3ea in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #5  0x00007f01331b2797 in rb_ensure () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #6  0x00007f01331da1ac in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #7  0x00007f01331deb66 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #8  0x00007f01331e1bbb in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #9  0x00007f0133314026 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #10 0x00007f0133307406 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #11 0x00007f01333180ba in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #12 0x00007f013331e870 in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #13 0x00007f0133322336 in rb_vm_invoke_proc () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #14 0x00007f01332e3ac3 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #15 0x00007f01332e5c10 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #16 0x00007f01332e604d in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #17 0x00007f0132efa609 in start_thread (arg=<optimized out>) at pthread_create.c:477
  #18 0x00007f0133036293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
  
  Thread 1 (Thread 0x7f0132ccd740 (LWP 3758)):
  #0  0x00007f0133029bf6 in __ppoll (fds=0x7ffc4eb8db18, nfds=1, timeout=<optimized out>, sigmask=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:44
  #1  0x00007f01332e1d18 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #2  0x00007f01332e24c8 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #3  0x00007f01332e47b7 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #4  0x00007f01331b2797 in rb_ensure () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #5  0x00007f01332de05d in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #6  0x00007f01332de828 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #7  0x00007f0133314026 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #8  0x00007f0133307406 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #9  0x00007f01333180ba in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #10 0x00007f013331e870 in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #11 0x00007f01331f8663 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #12 0x00007f01331f880b in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #13 0x00007f0133314026 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #14 0x00007f0133307406 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #15 0x00007f01333180ba in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #16 0x00007f013331e870 in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #17 0x00007f013332b9f1 in rb_yield () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #18 0x00007f013313345c in rb_ary_each () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #19 0x00007f0133314026 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #20 0x00007f0133320dab in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #21 0x00007f0133307406 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #22 0x00007f0133318131 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #23 0x00007f013331e870 in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #24 0x00007f013332b9f1 in rb_yield () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #25 0x00007f01331b2797 in rb_ensure () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #26 0x00007f0133192ebf in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #27 0x00007f0133314026 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #28 0x00007f0133320dab in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #29 0x00007f0133307406 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #30 0x00007f0133318131 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #31 0x00007f013331e870 in rb_vm_exec () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #32 0x00007f01331af491 in ?? () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #33 0x00007f01331b42ee in ruby_run_node () from /lib/x86_64-linux-gnu/libruby-2.7.so.2.7
  #34 0x000056534f55a17f in ?? ()
  #35 0x00007f0132f3b0b3 in __libc_start_main (main=0x56534f55a120, argc=4, argv=0x7ffc4eb8efd8, init=<optimized out>, fini=<optimized out>, rtld_fini=<optimized out>, stack_end=0x7ffc4eb8efc8) at ../csu/libc-start.c:308
  #36 0x000056534f55a1ce 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 3758) detached]
  [nil, 3758]
  "timeout 60 gdb -p 3752 -x /home/ko1/ruby/build-ruby/gdbscript -batch -quiet"
  make: *** [uncommon.mk:763: 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, 3752]
  #<Errno::ESRCH: No such process>
  "timeout 60 gdb -p 3751 -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 3751 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, 3751]
  "timeout 60 gdb -p 9135 -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, 9135]
  #<Errno::ESRCH: No such process>
    0.008000   0.000000 10854.856000 (10861.148306)
  /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: 10910.42 sec